Dec 08 07:40:01 volumio volumio[5143]: info: Initializing connection to go-librespot Websocket Dec 08 07:40:01 volumio volumio[5143]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 08 07:40:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Dec 08 07:40:01 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 08 07:40:01 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 08 07:40:01 volumio go-librespot[5683]: go-librespot daemon starting... Dec 08 07:40:01 volumio go-librespot[5684]: time="2025-12-08T07:40:01-06:00" level=info msg="running go-librespot 0.4.0" Dec 08 07:40:01 volumio go-librespot[5684]: time="2025-12-08T07:40:01-06:00" level=debug msg="app state loaded" Dec 08 07:40:01 volumio go-librespot[5684]: time="2025-12-08T07:40:01-06:00" level=debug msg="stored credentials not found" Dec 08 07:40:01 volumio go-librespot[5684]: time="2025-12-08T07:40:01-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 08 07:40:01 volumio go-librespot[5684]: time="2025-12-08T07:40:01-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 08 07:40:01 volumio go-librespot[5684]: time="2025-12-08T07:40:01-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 08 07:40:01 volumio go-librespot[5684]: time="2025-12-08T07:40:01-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 08 07:40:01 volumio go-librespot[5684]: time="2025-12-08T07:40:01-06:00" level=info msg="zeroconf server listening on port 39707" Dec 08 07:40:01 volumio go-librespot[5684]: time="2025-12-08T07:40:01-06:00" level=debug msg="obtained new client token: AABnaUftAhkS/BkiQO9/nZ5Xhf1FHKhQWkQFAr0gn9x+jUbztPp8XfESeQfZK1GiqeLqZqy0fW0p/YA0a74Mj6IDxF1dRRmwk41vmrTrD0nMvo4IM9aOSMD5ueAn+D0PFDYRipD4pBvTZURtA6KGCJ0q+rYf7o/muKmWDt+1yolQc7lDLJq+cg+F91nb5ucfZ8lwQO7Str2khC1XzE9MY74JeDVFvoR1jz6D1uRtxE+JQFpW4WJlA670Rg==" Dec 08 07:40:01 volumio go-librespot[5684]: time="2025-12-08T07:40:01-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 08 07:40:01 volumio go-librespot[5684]: time="2025-12-08T07:40:01-06:00" level=debug msg="completed keyexchange" Dec 08 07:40:01 volumio go-librespot[5684]: time="2025-12-08T07:40:01-06:00" level=debug msg="completed challenge" Dec 08 07:40:01 volumio go-librespot[5684]: time="2025-12-08T07:40:01-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 08 07:40:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 08 07:40:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 08 07:40:02 volumio volumio[5143]: info: MYVOLUMIO: Adding device Dec 08 07:40:02 volumio volumio[5143]: info: MYVOLUMIO: Evaluating Server Dec 08 07:40:03 volumio volumio[5143]: info: Setting Geolocation for MyVolumio to us4 Dec 08 07:40:03 volumio volumio[5143]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 08 07:40:03 volumio volumio[5143]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 08 07:40:03 volumio volumio[5143]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 08 07:40:03 volumio volumio[5143]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Dec 08 07:40:04 volumio volumio[5143]: info: Initializing connection to go-librespot Websocket Dec 08 07:40:04 volumio volumio[5143]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 08 07:40:04 volumio volumio[5143]: info: Updating MyVolumio device info Dec 08 07:40:04 volumio volumio[5143]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 08 07:40:04 volumio volumio[5143]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 08 07:40:04 volumio volumio[5143]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 08 07:40:04 volumio volumio[5143]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Dec 08 07:40:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Dec 08 07:40:04 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 08 07:40:04 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 08 07:40:04 volumio go-librespot[5709]: go-librespot daemon starting... Dec 08 07:40:05 volumio go-librespot[5710]: time="2025-12-08T07:40:05-06:00" level=info msg="running go-librespot 0.4.0" Dec 08 07:40:05 volumio go-librespot[5710]: time="2025-12-08T07:40:05-06:00" level=debug msg="app state loaded" Dec 08 07:40:05 volumio go-librespot[5710]: time="2025-12-08T07:40:05-06:00" level=debug msg="stored credentials not found" Dec 08 07:40:05 volumio go-librespot[5710]: time="2025-12-08T07:40:05-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 08 07:40:05 volumio go-librespot[5710]: time="2025-12-08T07:40:05-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 08 07:40:05 volumio go-librespot[5710]: time="2025-12-08T07:40:05-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 08 07:40:05 volumio go-librespot[5710]: time="2025-12-08T07:40:05-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 08 07:40:05 volumio go-librespot[5710]: time="2025-12-08T07:40:05-06:00" level=info msg="zeroconf server listening on port 43171" Dec 08 07:40:05 volumio go-librespot[5710]: time="2025-12-08T07:40:05-06:00" level=debug msg="obtained new client token: AACdSlUpwTIJkFJ5h4/GZ2qrplTkcBzEyHly8OwIqVBdNPLhkBHalCk1woVJcIt+pxx9vjy2sKbwwvbdnPHfs0sYN/SdNtvJYSpf3087aANbb97MslM9A/t5VbllpWqJNttJjBdnXW2Qe52pXna1fTz7iRIq2WXkh6hwwLdAql2fk3x576XYhhOhYPhmGOsdhllL+XUKiT/EUgVx68maT2S8v16lss9SwATuyavseVnVKdlO53cj5SOvbw==" Dec 08 07:40:05 volumio go-librespot[5710]: time="2025-12-08T07:40:05-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 08 07:40:05 volumio go-librespot[5710]: time="2025-12-08T07:40:05-06:00" level=debug msg="completed keyexchange" Dec 08 07:40:05 volumio go-librespot[5710]: time="2025-12-08T07:40:05-06:00" level=debug msg="completed challenge" Dec 08 07:40:05 volumio go-librespot[5710]: time="2025-12-08T07:40:05-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 08 07:40:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 08 07:40:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 08 07:40:07 volumio volumio[5143]: info: Initializing connection to go-librespot Websocket Dec 08 07:40:07 volumio volumio[5143]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 08 07:40:08 volumio volumio[5143]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 08 07:40:08 volumio volumio[5143]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 08 07:40:08 volumio volumio[5143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 08 07:40:08 volumio volumio-remote-updater[9752]: Test mode disabled Dec 08 07:40:08 volumio volumio-remote-updater[9752]: Alpha mode disabled Dec 08 07:40:08 volumio volumio-remote-updater[9752]: Alpha legacy test mode disabled Dec 08 07:40:08 volumio volumio[5143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Dec 08 07:40:08 volumio volumio[5143]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

ANNOUNCEMENT

\n
    \n
  • Production line
    • \n
\n

FIX

\n
    \n
  • Nodejs 20.5.1 version pinning with static package fallback
    • \n
\n","title":"Update v4.073","updateavailable":true} Dec 08 07:40:08 volumio volumio[5143]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 08 07:40:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Dec 08 07:40:08 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 08 07:40:08 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 08 07:40:08 volumio go-librespot[5739]: go-librespot daemon starting... Dec 08 07:40:08 volumio go-librespot[5740]: time="2025-12-08T07:40:08-06:00" level=info msg="running go-librespot 0.4.0" Dec 08 07:40:08 volumio go-librespot[5740]: time="2025-12-08T07:40:08-06:00" level=debug msg="app state loaded" Dec 08 07:40:08 volumio go-librespot[5740]: time="2025-12-08T07:40:08-06:00" level=debug msg="stored credentials not found" Dec 08 07:40:08 volumio go-librespot[5740]: time="2025-12-08T07:40:08-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 08 07:40:08 volumio go-librespot[5740]: time="2025-12-08T07:40:08-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 08 07:40:08 volumio go-librespot[5740]: time="2025-12-08T07:40:08-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 08 07:40:08 volumio go-librespot[5740]: time="2025-12-08T07:40:08-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 08 07:40:08 volumio go-librespot[5740]: time="2025-12-08T07:40:08-06:00" level=info msg="zeroconf server listening on port 46515" Dec 08 07:40:09 volumio go-librespot[5740]: time="2025-12-08T07:40:09-06:00" level=debug msg="obtained new client token: AAD/Rof8vreXEPYo8HJJRu9nkbuw57ysZD5GW/gaZuvtWnCE/2e7alThmljnstRGURCWaDzpXlkEnJRSo8EfjsfPU+KJbl5SnkZp7v3vKGIHkVSrZdfy49L3YxbcXFNjTO8Vd5DVTIrhIlr9YeZ5wfAh0E9MU3F768ygE9zNHNpJ6Crg+dJyHaB0Dba4bJaqbLGfbDqL99BKJ8rYcxeGaCbm/RA1XF827E/2FxgRcvhL8mSEwEII+BA=" Dec 08 07:40:09 volumio go-librespot[5740]: time="2025-12-08T07:40:09-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 08 07:40:09 volumio go-librespot[5740]: time="2025-12-08T07:40:09-06:00" level=debug msg="completed keyexchange" Dec 08 07:40:09 volumio go-librespot[5740]: time="2025-12-08T07:40:09-06:00" level=debug msg="completed challenge" Dec 08 07:40:09 volumio go-librespot[5740]: time="2025-12-08T07:40:09-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Dec 08 07:40:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 08 07:40:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 08 07:40:10 volumio volumio[5143]: info: Initializing connection to go-librespot Websocket Dec 08 07:40:10 volumio volumio[5143]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 08 07:40:11 volumio volumio[5143]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 08 07:40:11 volumio volumio[5143]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Dec 08 07:40:11 volumio volumio[5143]: info: CoreCommandRouter::volumioGetState Dec 08 07:40:11 volumio volumio[5143]: info: CorePlayQueue::getTrack 0 Dec 08 07:40:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Dec 08 07:40:12 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 08 07:40:12 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 08 07:40:12 volumio go-librespot[5747]: go-librespot daemon starting... Dec 08 07:40:12 volumio go-librespot[5748]: time="2025-12-08T07:40:12-06:00" level=info msg="running go-librespot 0.4.0" Dec 08 07:40:12 volumio go-librespot[5748]: time="2025-12-08T07:40:12-06:00" level=debug msg="app state loaded" Dec 08 07:40:12 volumio go-librespot[5748]: time="2025-12-08T07:40:12-06:00" level=debug msg="stored credentials not found" Dec 08 07:40:12 volumio go-librespot[5748]: time="2025-12-08T07:40:12-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 08 07:40:12 volumio go-librespot[5748]: time="2025-12-08T07:40:12-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 08 07:40:12 volumio go-librespot[5748]: time="2025-12-08T07:40:12-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 08 07:40:12 volumio go-librespot[5748]: time="2025-12-08T07:40:12-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 08 07:40:12 volumio go-librespot[5748]: time="2025-12-08T07:40:12-06:00" level=info msg="zeroconf server listening on port 41597" Dec 08 07:40:12 volumio go-librespot[5748]: time="2025-12-08T07:40:12-06:00" level=debug msg="obtained new client token: AAAky7IBcs+kDQ1UH6/jGE+8XosnTc6RCndUFJMUQ3PVv+TvPls6D1KzvvirxKwgZcW3I1p9Di7x3hovUOqF8cX3qFvIYsvhD6eqC9bDcLwpJhfa6EWRjLmZpNOWJZIU53wNmlM1EbHLxOYT5JnSMJYAUkc2jLGejGbN4dpYf0jy/I6ETX0O2+SNkA5zHA4GTGJIr1f1vKabbduCEV5/AwaWHKzWCeqDV5Jlj5boNqyMwlzU68z4N97BaQ==" Dec 08 07:40:12 volumio go-librespot[5748]: time="2025-12-08T07:40:12-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 08 07:40:12 volumio go-librespot[5748]: time="2025-12-08T07:40:12-06:00" level=debug msg="completed keyexchange" Dec 08 07:40:12 volumio go-librespot[5748]: time="2025-12-08T07:40:12-06:00" level=debug msg="completed challenge" Dec 08 07:40:12 volumio go-librespot[5748]: time="2025-12-08T07:40:12-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 08 07:40:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 08 07:40:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 08 07:40:13 volumio volumio[5143]: info: Initializing connection to go-librespot Websocket Dec 08 07:40:13 volumio volumio[5143]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 08 07:40:16 volumio volumio[5143]: info: Initializing connection to go-librespot Websocket Dec 08 07:40:16 volumio volumio[5143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 07:40:16 volumio volumio[5143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 07:40:16 volumio volumio[5143]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 08 07:40:16 volumio volumio[5143]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 08 07:40:16 volumio volumio[5143]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 08 07:40:16 volumio volumio[5143]: info: CoreCommandRouter::volumioGetBrowseSources Dec 08 07:40:16 volumio volumio[5143]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 08 07:40:16 volumio volumio[5143]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 08 07:40:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Dec 08 07:40:16 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 08 07:40:16 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 08 07:40:16 volumio go-librespot[5770]: go-librespot daemon starting... Dec 08 07:40:16 volumio go-librespot[5771]: time="2025-12-08T07:40:16-06:00" level=info msg="running go-librespot 0.4.0" Dec 08 07:40:16 volumio go-librespot[5771]: time="2025-12-08T07:40:16-06:00" level=debug msg="app state loaded" Dec 08 07:40:16 volumio go-librespot[5771]: time="2025-12-08T07:40:16-06:00" level=debug msg="stored credentials not found" Dec 08 07:40:16 volumio go-librespot[5771]: time="2025-12-08T07:40:16-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 08 07:40:16 volumio go-librespot[5771]: time="2025-12-08T07:40:16-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 08 07:40:16 volumio go-librespot[5771]: time="2025-12-08T07:40:16-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 08 07:40:16 volumio go-librespot[5771]: time="2025-12-08T07:40:16-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 08 07:40:16 volumio go-librespot[5771]: time="2025-12-08T07:40:16-06:00" level=info msg="zeroconf server listening on port 38065" Dec 08 07:40:16 volumio go-librespot[5771]: time="2025-12-08T07:40:16-06:00" level=debug msg="obtained new client token: AAAtSkoY7YMmP79Xmftl6uORHvYnHf1ioaJBrim3WsjowEXzyxqeZFoPJq1JTZ4PvYF2Pz+ekPitvyYfLcaasN1aKTmx8Z5qu/jyqZ711k5AD78Wd6LwXVUgTk0jSIYP63KmLwwtOZBR+9H0RSafFCPDo/tMgPJdaSODrf+GRZsJm9i3kdXZ5wiHAzgZHF5zjFZTTPISNcxRdM+5rlCp3MFqWyQ0JS1zDr4+/AfFHGzVFpfNf31/XPAOkA==" Dec 08 07:40:16 volumio go-librespot[5771]: time="2025-12-08T07:40:16-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 08 07:40:16 volumio go-librespot[5771]: time="2025-12-08T07:40:16-06:00" level=debug msg="completed keyexchange" Dec 08 07:40:16 volumio go-librespot[5771]: time="2025-12-08T07:40:16-06:00" level=debug msg="completed challenge" Dec 08 07:40:16 volumio go-librespot[5771]: time="2025-12-08T07:40:16-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 08 07:40:16 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 08 07:40:16 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 08 07:40:17 volumio volumio[5143]: error: MyVolumio Plugin failed to authenticate in a timely fashion Dec 08 07:40:17 volumio volumio[5143]: info: Completed starting MyVolumio Plugin Dec 08 07:40:17 volumio volumio[5143]: [Metrics] CommandRouter: 54s 312.31ms Dec 08 07:40:17 volumio volumio[5143]: info: CoreCommandRouter::volumiosetStartupVolume Dec 08 07:40:17 volumio volumio[5143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 07:40:17 volumio volumio[5143]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 08 07:40:17 volumio volumio[5143]: info: CoreCommandRouter::Close All Modals sent Dec 08 07:40:17 volumio volumio[5143]: info: CoreCommandRouter::Close All Modals sent Dec 08 07:40:18 volumio volumio[5143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 08 07:40:18 volumio volumio[5143]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 08 07:40:18 volumio volumio[5143]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 08 07:40:19 volumio volumio[5143]: info: Initializing connection to go-librespot Websocket Dec 08 07:40:19 volumio volumio[5143]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 08 07:40:19 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11. Dec 08 07:40:19 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 08 07:40:19 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 08 07:40:19 volumio go-librespot[5785]: go-librespot daemon starting... Dec 08 07:40:20 volumio go-librespot[5786]: time="2025-12-08T07:40:20-06:00" level=info msg="running go-librespot 0.4.0" Dec 08 07:40:20 volumio go-librespot[5786]: time="2025-12-08T07:40:20-06:00" level=debug msg="app state loaded" Dec 08 07:40:20 volumio go-librespot[5786]: time="2025-12-08T07:40:20-06:00" level=debug msg="stored credentials not found" Dec 08 07:40:20 volumio go-librespot[5786]: time="2025-12-08T07:40:20-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 08 07:40:20 volumio go-librespot[5786]: time="2025-12-08T07:40:20-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 08 07:40:20 volumio go-librespot[5786]: time="2025-12-08T07:40:20-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 08 07:40:20 volumio go-librespot[5786]: time="2025-12-08T07:40:20-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 08 07:40:20 volumio go-librespot[5786]: time="2025-12-08T07:40:20-06:00" level=info msg="zeroconf server listening on port 41439" Dec 08 07:40:20 volumio go-librespot[5786]: time="2025-12-08T07:40:20-06:00" level=debug msg="obtained new client token: AAAUwyY1sBgnEBOSMhJkRTeaPSpaPHccW/NK5wFSdW5xim0xb8ZDj6C1zAvn5C5ACebWBwiygJrf16ypUdfCsBZvpZbnwP70ebp5w4Z9p4Eux9nU5HFB5Ka0MeHfLk4YHRYkKG4FwnQ7NqsalVqQDBOjkXDuWwAOw4kdjw7ax2UbfGoZZ8U6IMLqzLpadnfGHHRWSydKPxKpEBM7C62rkn4e2Qn3bPBWe210RHziCSpQjqmu8Kv7o22KPg==" Dec 08 07:40:20 volumio go-librespot[5786]: time="2025-12-08T07:40:20-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 08 07:40:20 volumio go-librespot[5786]: time="2025-12-08T07:40:20-06:00" level=debug msg="completed keyexchange" Dec 08 07:40:20 volumio go-librespot[5786]: time="2025-12-08T07:40:20-06:00" level=debug msg="completed challenge" Dec 08 07:40:20 volumio go-librespot[5786]: time="2025-12-08T07:40:20-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 08 07:40:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 08 07:40:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 08 07:40:22 volumio volumio[5143]: info: Initializing connection to go-librespot Websocket Dec 08 07:40:22 volumio volumio[5143]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 08 07:40:23 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12. Dec 08 07:40:23 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 08 07:40:23 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 08 07:40:23 volumio go-librespot[5793]: go-librespot daemon starting... Dec 08 07:40:23 volumio go-librespot[5794]: time="2025-12-08T07:40:23-06:00" level=info msg="running go-librespot 0.4.0" Dec 08 07:40:23 volumio go-librespot[5794]: time="2025-12-08T07:40:23-06:00" level=debug msg="app state loaded" Dec 08 07:40:23 volumio go-librespot[5794]: time="2025-12-08T07:40:23-06:00" level=debug msg="stored credentials not found" Dec 08 07:40:23 volumio go-librespot[5794]: time="2025-12-08T07:40:23-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 08 07:40:24 volumio go-librespot[5794]: time="2025-12-08T07:40:23-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 08 07:40:24 volumio go-librespot[5794]: time="2025-12-08T07:40:24-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 08 07:40:24 volumio go-librespot[5794]: time="2025-12-08T07:40:24-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 08 07:40:24 volumio go-librespot[5794]: time="2025-12-08T07:40:24-06:00" level=info msg="zeroconf server listening on port 34721" Dec 08 07:40:24 volumio volumio[5143]: info: BOOT COMPLETED Dec 08 07:40:24 volumio go-librespot[5794]: time="2025-12-08T07:40:24-06:00" level=debug msg="obtained new client token: AAAnTm/Q28i4hI4xcxow5venvnk28KSTYWtetPT0pzCDxfgxRZ0GCZof9wM5u+ZUVaz5WhIbsC8Fvas3Y3t/aRFTToD5OmoNjTU7/7Aqbux4sExBQitFV22TNExRzXsouvIAfscCJSOn9fm3TzDhtBqKezQ+RZckq0iynlVJshdIPzibxwl4D/cWxeUbRYXmTZr64wYbUkWHCqRIrZ8b2m/ST9maBpsWYru5XhpTWbmfBbufSr0IssY=" Dec 08 07:40:24 volumio go-librespot[5794]: time="2025-12-08T07:40:24-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 08 07:40:24 volumio go-librespot[5794]: time="2025-12-08T07:40:24-06:00" level=debug msg="completed keyexchange" Dec 08 07:40:24 volumio go-librespot[5794]: time="2025-12-08T07:40:24-06:00" level=debug msg="completed challenge" Dec 08 07:40:24 volumio go-librespot[5794]: time="2025-12-08T07:40:24-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Dec 08 07:40:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 08 07:40:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 08 07:40:25 volumio volumio[5143]: info: Initializing connection to go-librespot Websocket Dec 08 07:40:25 volumio volumio[5143]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 08 07:40:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13. Dec 08 07:40:27 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 08 07:40:27 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 08 07:40:27 volumio go-librespot[5818]: go-librespot daemon starting... Dec 08 07:40:27 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Dec 08 07:40:27 volumio go-librespot[5819]: time="2025-12-08T07:40:27-06:00" level=info msg="running go-librespot 0.4.0" Dec 08 07:40:27 volumio go-librespot[5819]: time="2025-12-08T07:40:27-06:00" level=debug msg="app state loaded" Dec 08 07:40:27 volumio go-librespot[5819]: time="2025-12-08T07:40:27-06:00" level=debug msg="stored credentials not found" Dec 08 07:40:27 volumio go-librespot[5819]: time="2025-12-08T07:40:27-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 08 07:40:27 volumio go-librespot[5819]: time="2025-12-08T07:40:27-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 08 07:40:27 volumio go-librespot[5819]: time="2025-12-08T07:40:27-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 08 07:40:27 volumio go-librespot[5819]: time="2025-12-08T07:40:27-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 08 07:40:27 volumio go-librespot[5819]: time="2025-12-08T07:40:27-06:00" level=info msg="zeroconf server listening on port 43655" Dec 08 07:40:27 volumio go-librespot[5819]: time="2025-12-08T07:40:27-06:00" level=debug msg="obtained new client token: AACwnrdXPQTUzCHRangEPyaHmWXl6I179xEWqjpCQI4rGH/CFnENNaE//yWp/GxfLcgxZSljWvjYRs0OqLoADjIcfJ3VVGQg4N3V7AYjO6+4qbhVpeZMGMT/DnjHv46Y1B0/npAX2/anfCYWX2U/QImw84kWM6tEejAloLqSEHiFowUc+eP994sMDO7qbXJvL8yA+DfnqnXPbvmz7Wnw7e6qrnqe0uwbqZlrKlP1bkY5K8MJo1GnqkgWlQ==" Dec 08 07:40:27 volumio go-librespot[5819]: time="2025-12-08T07:40:27-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 08 07:40:28 volumio go-librespot[5819]: time="2025-12-08T07:40:28-06:00" level=debug msg="completed keyexchange" Dec 08 07:40:28 volumio go-librespot[5819]: time="2025-12-08T07:40:28-06:00" level=debug msg="completed challenge" Dec 08 07:40:28 volumio go-librespot[5819]: time="2025-12-08T07:40:28-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Dec 08 07:40:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 08 07:40:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 08 07:40:28 volumio volumio[5143]: info: Initializing connection to go-librespot Websocket Dec 08 07:40:28 volumio volumio[5143]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 08 07:40:28 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Dec 08 07:40:28 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Dec 08 07:40:31 volumio volumio[5143]: info: Initializing connection to go-librespot Websocket Dec 08 07:40:31 volumio volumio[5143]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 08 07:40:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14. Dec 08 07:40:31 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 08 07:40:31 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 08 07:40:31 volumio go-librespot[5852]: go-librespot daemon starting... Dec 08 07:40:31 volumio go-librespot[5853]: time="2025-12-08T07:40:31-06:00" level=info msg="running go-librespot 0.4.0" Dec 08 07:40:31 volumio go-librespot[5853]: time="2025-12-08T07:40:31-06:00" level=debug msg="app state loaded" Dec 08 07:40:31 volumio go-librespot[5853]: time="2025-12-08T07:40:31-06:00" level=debug msg="stored credentials not found" Dec 08 07:40:31 volumio go-librespot[5853]: time="2025-12-08T07:40:31-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 08 07:40:31 volumio go-librespot[5853]: time="2025-12-08T07:40:31-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 08 07:40:31 volumio go-librespot[5853]: time="2025-12-08T07:40:31-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 08 07:40:31 volumio go-librespot[5853]: time="2025-12-08T07:40:31-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 08 07:40:31 volumio go-librespot[5853]: time="2025-12-08T07:40:31-06:00" level=info msg="zeroconf server listening on port 41441" Dec 08 07:40:31 volumio go-librespot[5853]: time="2025-12-08T07:40:31-06:00" level=debug msg="obtained new client token: AAAyAgOxZ5SPU1jLIUyaT9pUiPeDS4DMr9p9UbUTmLFt014S78wTOUb7wgIJa300HketT214cYwwLm+3mHX0I809XL2g5b3N569cFzOTl69XxEwNWP1pluuzyXxpy1Z0V6owB7ZvprOec3B2U+7PMOooimUXczBKs+CibFrgeYNS6hlHemGsPzv18ZD4AI29687+3yC65lDbI2waclSuhCnQCHsiXQ0kF3R2arBVRLIzskdjKBgXyHgo2Q==" Dec 08 07:40:31 volumio go-librespot[5853]: time="2025-12-08T07:40:31-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 08 07:40:31 volumio go-librespot[5853]: time="2025-12-08T07:40:31-06:00" level=debug msg="completed keyexchange" Dec 08 07:40:31 volumio go-librespot[5853]: time="2025-12-08T07:40:31-06:00" level=debug msg="completed challenge" Dec 08 07:40:31 volumio go-librespot[5853]: time="2025-12-08T07:40:31-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 08 07:40:31 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 08 07:40:31 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 08 07:40:34 volumio volumio[5143]: info: Initializing connection to go-librespot Websocket Dec 08 07:40:34 volumio volumio[5143]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 08 07:40:34 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15. Dec 08 07:40:34 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 08 07:40:34 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 08 07:40:34 volumio go-librespot[5860]: go-librespot daemon starting... Dec 08 07:40:35 volumio go-librespot[5861]: time="2025-12-08T07:40:35-06:00" level=info msg="running go-librespot 0.4.0" Dec 08 07:40:35 volumio go-librespot[5861]: time="2025-12-08T07:40:35-06:00" level=debug msg="app state loaded" Dec 08 07:40:35 volumio go-librespot[5861]: time="2025-12-08T07:40:35-06:00" level=debug msg="stored credentials not found" Dec 08 07:40:35 volumio go-librespot[5861]: time="2025-12-08T07:40:35-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 08 07:40:35 volumio go-librespot[5861]: time="2025-12-08T07:40:35-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Dec 08 07:40:35 volumio go-librespot[5861]: time="2025-12-08T07:40:35-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 08 07:40:35 volumio go-librespot[5861]: time="2025-12-08T07:40:35-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 08 07:40:35 volumio go-librespot[5861]: time="2025-12-08T07:40:35-06:00" level=info msg="zeroconf server listening on port 45397" Dec 08 07:40:35 volumio go-librespot[5861]: time="2025-12-08T07:40:35-06:00" level=debug msg="obtained new client token: AAAIhten+P47QbMJYaY+d4C17FlLlG7GI/ZFlVMny+gS/JJZbqQvjLJ5EZcWns8HdkvI0vqyaPu2SVIWokHgrdasFJZmOirQF1Pwg1FhrgIMRfkWrKfiR0UVIvTU3807UKsEix7Y2WCSOiqv2Qa7Hdd2uyrB0z/54dPvhPMMupnURBMxGcfroloAOXn9jewH0lrwGHdpsRVaA3oFfL7Ks35xCeAF2cOks+JKmebRvXWR86x1ScP/7XJ3aw==" Dec 08 07:40:35 volumio go-librespot[5861]: time="2025-12-08T07:40:35-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 08 07:40:35 volumio go-librespot[5861]: time="2025-12-08T07:40:35-06:00" level=debug msg="completed keyexchange" Dec 08 07:40:35 volumio go-librespot[5861]: time="2025-12-08T07:40:35-06:00" level=debug msg="completed challenge" Dec 08 07:40:35 volumio go-librespot[5861]: time="2025-12-08T07:40:35-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 08 07:40:35 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 08 07:40:35 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 08 07:40:37 volumio volumio[5143]: info: Initializing connection to go-librespot Websocket Dec 08 07:40:37 volumio volumio[5143]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 08 07:40:38 volumio volumio[5143]: info: UPDATER: Scheduling automatic update Dec 08 07:40:38 volumio volumio[5143]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStartTime Dec 08 07:40:38 volumio volumio[5143]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStopTime Dec 08 07:40:38 volumio volumio[5143]: info: UPDATER: Auto update will take place at: Tue Dec 09 2025 04:14:54 GMT-0600 (Central Standard Time) Dec 08 07:40:38 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16. Dec 08 07:40:38 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 08 07:40:38 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 08 07:40:38 volumio go-librespot[5883]: go-librespot daemon starting... Dec 08 07:40:38 volumio go-librespot[5884]: time="2025-12-08T07:40:38-06:00" level=info msg="running go-librespot 0.4.0" Dec 08 07:40:38 volumio go-librespot[5884]: time="2025-12-08T07:40:38-06:00" level=debug msg="app state loaded" Dec 08 07:40:38 volumio go-librespot[5884]: time="2025-12-08T07:40:38-06:00" level=debug msg="stored credentials not found" Dec 08 07:40:38 volumio go-librespot[5884]: time="2025-12-08T07:40:38-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 08 07:40:38 volumio go-librespot[5884]: time="2025-12-08T07:40:38-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 08 07:40:38 volumio go-librespot[5884]: time="2025-12-08T07:40:38-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 08 07:40:38 volumio go-librespot[5884]: time="2025-12-08T07:40:38-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 08 07:40:39 volumio go-librespot[5884]: time="2025-12-08T07:40:39-06:00" level=info msg="zeroconf server listening on port 46103" Dec 08 07:40:39 volumio go-librespot[5884]: time="2025-12-08T07:40:39-06:00" level=debug msg="obtained new client token: AAAgIYQJy99KwxE4DtwDV1D9IIxajciLP1gHF0QfmLhoaUbIol/stfkF0HA17+DR7YOnsdET+C2A9BiRHhD/63p6m/zdlAxwiw3T20w4nmznFpmd0XTGnqsV56DaARaOOSX7OUX0628bdv0B6qTCSms4bcNgMr72kxTy1EANVQhxW8S6XWnlrSwZG8a2q9kXfjTItvlL438JBOsrXn4ecZ2twi8/gUMNtSdqJ3iYZqIrYuvZlSpc17s=" Dec 08 07:40:39 volumio go-librespot[5884]: time="2025-12-08T07:40:39-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 08 07:40:39 volumio go-librespot[5884]: time="2025-12-08T07:40:39-06:00" level=debug msg="completed keyexchange" Dec 08 07:40:39 volumio go-librespot[5884]: time="2025-12-08T07:40:39-06:00" level=debug msg="completed challenge" Dec 08 07:40:39 volumio go-librespot[5884]: time="2025-12-08T07:40:39-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Dec 08 07:40:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 08 07:40:39 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 08 07:40:40 volumio volumio[5143]: info: Initializing connection to go-librespot Websocket Dec 08 07:40:40 volumio volumio[5143]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 08 07:40:42 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17. Dec 08 07:40:42 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 08 07:40:42 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 08 07:40:42 volumio go-librespot[5891]: go-librespot daemon starting... Dec 08 07:40:42 volumio go-librespot[5892]: time="2025-12-08T07:40:42-06:00" level=info msg="running go-librespot 0.4.0" Dec 08 07:40:42 volumio go-librespot[5892]: time="2025-12-08T07:40:42-06:00" level=debug msg="app state loaded" Dec 08 07:40:42 volumio go-librespot[5892]: time="2025-12-08T07:40:42-06:00" level=debug msg="stored credentials not found" Dec 08 07:40:42 volumio go-librespot[5892]: time="2025-12-08T07:40:42-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 08 07:40:42 volumio go-librespot[5892]: time="2025-12-08T07:40:42-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 08 07:40:42 volumio go-librespot[5892]: time="2025-12-08T07:40:42-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 08 07:40:42 volumio go-librespot[5892]: time="2025-12-08T07:40:42-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 08 07:40:42 volumio go-librespot[5892]: time="2025-12-08T07:40:42-06:00" level=info msg="zeroconf server listening on port 39317" Dec 08 07:40:42 volumio go-librespot[5892]: time="2025-12-08T07:40:42-06:00" level=debug msg="obtained new client token: AAA5kNIor0VvzSYSaZ8NSwL+gYH6wQVyOZUay/RioRkkcUVwiAQ7F7V7natx0HGCbta7B54LX4wPgACdkwPdpgT4BZFpr+kL4M8l0KZSgugJD3NpL/7GTBUANZEuDhuXQxcWwV8zfUza6KfnwCdxCPOxZ0i9qdCi5u6xi+IG4LNtkhL5mzsXptSC06OPF6PV0eTw/BulCf+iMANDZP4ccMFwOpFGXhIGDu03mGOSOBSOOFRvajp0MrCITQ==" Dec 08 07:40:42 volumio go-librespot[5892]: time="2025-12-08T07:40:42-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 08 07:40:42 volumio go-librespot[5892]: time="2025-12-08T07:40:42-06:00" level=debug msg="completed keyexchange" Dec 08 07:40:42 volumio go-librespot[5892]: time="2025-12-08T07:40:42-06:00" level=debug msg="completed challenge" Dec 08 07:40:43 volumio go-librespot[5892]: time="2025-12-08T07:40:43-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Dec 08 07:40:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 08 07:40:43 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 08 07:40:43 volumio volumio[5143]: info: Initializing connection to go-librespot Websocket Dec 08 07:40:43 volumio volumio[5143]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 08 07:40:46 volumio volumio[5143]: info: Initializing connection to go-librespot Websocket Dec 08 07:40:46 volumio volumio[5143]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 08 07:40:46 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 18. Dec 08 07:40:46 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 08 07:40:46 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 08 07:40:46 volumio go-librespot[5913]: go-librespot daemon starting... Dec 08 07:40:46 volumio go-librespot[5914]: time="2025-12-08T07:40:46-06:00" level=info msg="running go-librespot 0.4.0" Dec 08 07:40:46 volumio go-librespot[5914]: time="2025-12-08T07:40:46-06:00" level=debug msg="app state loaded" Dec 08 07:40:46 volumio go-librespot[5914]: time="2025-12-08T07:40:46-06:00" level=debug msg="stored credentials not found" Dec 08 07:40:46 volumio go-librespot[5914]: time="2025-12-08T07:40:46-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 08 07:40:46 volumio go-librespot[5914]: time="2025-12-08T07:40:46-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 08 07:40:46 volumio go-librespot[5914]: time="2025-12-08T07:40:46-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 08 07:40:46 volumio go-librespot[5914]: time="2025-12-08T07:40:46-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 08 07:40:46 volumio go-librespot[5914]: time="2025-12-08T07:40:46-06:00" level=info msg="zeroconf server listening on port 45227" Dec 08 07:40:46 volumio go-librespot[5914]: time="2025-12-08T07:40:46-06:00" level=debug msg="obtained new client token: AACYPnjmsDlzHyQ5RpjV/lOGwmIpmcJ16VD1W/X81OuCz8R3tpcwuXQdJj/FgCymx5CSS+TvmDr7s4t07q8yVV/aJwN15OVOG512mYoc1qDZ/PEUHo46vohtxJqRUvFjZ4UPnsCeEy21GD2+rUFd2fEH2YrOjEo2DQrSzNBI4zP2j2xUPdml47hRWjyquHSKqLFmrJ7C06jYNZEVIAAl6IlXsyG0Q121+hOpzbFhTka0AbR15T2riE3iHg==" Dec 08 07:40:46 volumio go-librespot[5914]: time="2025-12-08T07:40:46-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 08 07:40:46 volumio go-librespot[5914]: time="2025-12-08T07:40:46-06:00" level=debug msg="completed keyexchange" Dec 08 07:40:46 volumio go-librespot[5914]: time="2025-12-08T07:40:46-06:00" level=debug msg="completed challenge" Dec 08 07:40:46 volumio go-librespot[5914]: time="2025-12-08T07:40:46-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 08 07:40:46 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 08 07:40:46 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 08 07:40:49 volumio volumio[5143]: info: Initializing connection to go-librespot Websocket Dec 08 07:40:49 volumio volumio[5143]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 08 07:40:49 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 19. Dec 08 07:40:49 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 08 07:40:49 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 08 07:40:49 volumio go-librespot[5924]: go-librespot daemon starting... Dec 08 07:40:50 volumio go-librespot[5925]: time="2025-12-08T07:40:50-06:00" level=info msg="running go-librespot 0.4.0" Dec 08 07:40:50 volumio go-librespot[5925]: time="2025-12-08T07:40:50-06:00" level=debug msg="app state loaded" Dec 08 07:40:50 volumio go-librespot[5925]: time="2025-12-08T07:40:50-06:00" level=debug msg="stored credentials not found" Dec 08 07:40:50 volumio go-librespot[5925]: time="2025-12-08T07:40:50-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 08 07:40:50 volumio go-librespot[5925]: time="2025-12-08T07:40:50-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 08 07:40:50 volumio go-librespot[5925]: time="2025-12-08T07:40:50-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 08 07:40:50 volumio go-librespot[5925]: time="2025-12-08T07:40:50-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 08 07:40:50 volumio go-librespot[5925]: time="2025-12-08T07:40:50-06:00" level=info msg="zeroconf server listening on port 41921" Dec 08 07:40:50 volumio go-librespot[5925]: time="2025-12-08T07:40:50-06:00" level=debug msg="obtained new client token: AABuIyBMkKkRSCEVaHhrHRwSt9G+Exail02v5KgibIvlplwix2JzWu45IlVh5QRwUtkUqoun4ldmBHqMqnXTiUQAeaQi2F7vzbqSEQ4YpsuI7Pp73RFPMgrQR26Gc759aX99VXK4FCnrBUDrPJhWqwjR1CMhY8BeGWmRTg7Fm1Quw6bVX9wUq1tePHDqFD0ySWT0ewuaHyXZdv15QQvxoNGgO9ywKQFVCVqJ/5kQXuiBgnsLj3Hgccckaw==" Dec 08 07:40:50 volumio go-librespot[5925]: time="2025-12-08T07:40:50-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 08 07:40:50 volumio go-librespot[5925]: time="2025-12-08T07:40:50-06:00" level=debug msg="completed keyexchange" Dec 08 07:40:50 volumio go-librespot[5925]: time="2025-12-08T07:40:50-06:00" level=debug msg="completed challenge" Dec 08 07:40:50 volumio go-librespot[5925]: time="2025-12-08T07:40:50-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 08 07:40:50 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 08 07:40:50 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 08 07:40:52 volumio volumio[5143]: info: Initializing connection to go-librespot Websocket Dec 08 07:40:52 volumio volumio[5143]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 08 07:40:53 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 20. Dec 08 07:40:53 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 08 07:40:53 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 08 07:40:53 volumio go-librespot[5932]: go-librespot daemon starting... Dec 08 07:40:53 volumio go-librespot[5933]: time="2025-12-08T07:40:53-06:00" level=info msg="running go-librespot 0.4.0" Dec 08 07:40:53 volumio go-librespot[5933]: time="2025-12-08T07:40:53-06:00" level=debug msg="app state loaded" Dec 08 07:40:53 volumio go-librespot[5933]: time="2025-12-08T07:40:53-06:00" level=debug msg="stored credentials not found" Dec 08 07:40:53 volumio go-librespot[5933]: time="2025-12-08T07:40:53-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 08 07:40:53 volumio go-librespot[5933]: time="2025-12-08T07:40: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 08 07:40:53 volumio go-librespot[5933]: time="2025-12-08T07:40: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 08 07:40:53 volumio go-librespot[5933]: time="2025-12-08T07:40: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 08 07:40:53 volumio go-librespot[5933]: time="2025-12-08T07:40:53-06:00" level=info msg="zeroconf server listening on port 41425" Dec 08 07:40:54 volumio go-librespot[5933]: time="2025-12-08T07:40:54-06:00" level=debug msg="obtained new client token: AAA/oWtHNCGuQrX+W+rCoUlaoelExjPyrMZqrDJyPoT5QgX5HobVryTTk8TqIHZNfcw3UJAbLDm9BRitsYk52sCmUPTb+xxCQMp2vST+cnfM44AxwfpqolW1sX0PAlxwUiKWieuXZGJ4uzCCtI0BZQHwrrfm+JJEz6B+0irRXaG/nNGRqOv9W7kVjVaBUUpcEDNJ6wr7g+Fo3aEnIG85wUIm/9C3r/W9z27QsJ/+4V7v3K0HmhIND0M=" Dec 08 07:40:54 volumio go-librespot[5933]: time="2025-12-08T07:40:54-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 08 07:40:54 volumio go-librespot[5933]: time="2025-12-08T07:40:54-06:00" level=debug msg="completed keyexchange" Dec 08 07:40:54 volumio go-librespot[5933]: time="2025-12-08T07:40:54-06:00" level=debug msg="completed challenge" Dec 08 07:40:54 volumio go-librespot[5933]: time="2025-12-08T07:40:54-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Dec 08 07:40:54 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 08 07:40:54 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 08 07:40:55 volumio volumio[5143]: info: Initializing connection to go-librespot Websocket Dec 08 07:40:55 volumio volumio[5143]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 08 07:40:57 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 21. Dec 08 07:40:57 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 08 07:40:57 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 08 07:40:57 volumio go-librespot[5954]: go-librespot daemon starting... Dec 08 07:40:57 volumio go-librespot[5955]: time="2025-12-08T07:40:57-06:00" level=info msg="running go-librespot 0.4.0" Dec 08 07:40:57 volumio go-librespot[5955]: time="2025-12-08T07:40:57-06:00" level=debug msg="app state loaded" Dec 08 07:40:57 volumio go-librespot[5955]: time="2025-12-08T07:40:57-06:00" level=debug msg="stored credentials not found" Dec 08 07:40:57 volumio go-librespot[5955]: time="2025-12-08T07:40:57-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 08 07:40:57 volumio go-librespot[5955]: time="2025-12-08T07:40: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-gew4.spotify.com:80]" Dec 08 07:40:57 volumio go-librespot[5955]: time="2025-12-08T07:40:57-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 08 07:40:57 volumio go-librespot[5955]: time="2025-12-08T07:40:57-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 08 07:40:57 volumio go-librespot[5955]: time="2025-12-08T07:40:57-06:00" level=info msg="zeroconf server listening on port 44813" Dec 08 07:40:58 volumio go-librespot[5955]: time="2025-12-08T07:40:58-06:00" level=debug msg="obtained new client token: AABTRUTYWPlaaHAYZ+lUiZ5ygvcqXcQzg7B5RnKWb89K7HpxGBm/VFIo6Dhi4Ki8GC4EFzH6QeqeWd1hZSJlYPxrdL7HFIZwyWrTEvCNiWv0Z1c0R0UalYDB5Em2TGGB7YOA+jxHpd9vhHh27/esOr/vwjE1EIgeJRKLZrZN4nK+aa+98neZt3YBYSj79lggDfpTkjFkAX8xAJn51owTplwRq0UUFE39rB2q3j46Q2atm3eg2a5amSs=" Dec 08 07:40:58 volumio go-librespot[5955]: time="2025-12-08T07:40:58-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 08 07:40:58 volumio go-librespot[5955]: time="2025-12-08T07:40:58-06:00" level=debug msg="completed keyexchange" Dec 08 07:40:58 volumio go-librespot[5955]: time="2025-12-08T07:40:58-06:00" level=debug msg="completed challenge" Dec 08 07:40:58 volumio volumio[5143]: info: Initializing connection to go-librespot Websocket Dec 08 07:40:58 volumio go-librespot[5955]: time="2025-12-08T07:40:58-06:00" level=debug msg="new websocket client" Dec 08 07:40:58 volumio volumio[5143]: info: Connection to go-librespot Websocket established Dec 08 07:40:58 volumio go-librespot[5955]: time="2025-12-08T07:40:58-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Dec 08 07:40:58 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 08 07:40:58 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 08 07:40:58 volumio volumio[5143]: info: Connection to go-librespot Websocket closed Dec 08 07:41:01 volumio volumio[5143]: info: Getting Spotify volume Dec 08 07:41:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22. Dec 08 07:41:01 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 08 07:41:01 volumio volumio[5143]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 08 07:41:01 volumio volumio[5143]: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 08 07:41:01 volumio volumio[5143]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Dec 08 07:41:01 volumio volumio[5143]: errno: -111, Dec 08 07:41:01 volumio volumio[5143]: code: 'ECONNREFUSED', Dec 08 07:41:01 volumio volumio[5143]: syscall: 'connect', Dec 08 07:41:01 volumio volumio[5143]: address: '127.0.0.1', Dec 08 07:41:01 volumio volumio[5143]: port: 9879, Dec 08 07:41:01 volumio volumio[5143]: response: undefined Dec 08 07:41:01 volumio volumio[5143]: } Dec 08 07:41:01 volumio volumio[5143]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 08 07:41:01 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 08 07:41:01 volumio go-librespot[5963]: go-librespot daemon starting... Dec 08 07:41:01 volumio go-librespot[5967]: time="2025-12-08T07:41:01-06:00" level=info msg="running go-librespot 0.4.0" Dec 08 07:41:01 volumio go-librespot[5967]: time="2025-12-08T07:41:01-06:00" level=debug msg="app state loaded" Dec 08 07:41:01 volumio go-librespot[5967]: time="2025-12-08T07:41:01-06:00" level=debug msg="stored credentials not found" Dec 08 07:41:01 volumio go-librespot[5967]: time="2025-12-08T07:41:01-06:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 08 07:41:01 volumio go-librespot[5967]: time="2025-12-08T07:41:01-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 08 07:41:01 volumio go-librespot[5967]: time="2025-12-08T07:41:01-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 08 07:41:01 volumio go-librespot[5967]: time="2025-12-08T07:41:01-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 08 07:41:01 volumio go-librespot[5967]: time="2025-12-08T07:41:01-06:00" level=info msg="zeroconf server listening on port 43599" Dec 08 07:41:01 volumio go-librespot[5967]: time="2025-12-08T07:41:01-06:00" level=debug msg="obtained new client token: AAD7lC3+QrorYJksiAXibKV2Itf87aLzfdF3JM40LCAV3gcoWYYAnucCtbBBTkv6VGxk5iNLWVekzKb6QpW258NPoQNs49nVZ/FisInicQBUcaeyZ4orWJkLiE0RVMtMM8I3fymnFxOpqfdQ/agDc59YLwyMBsVR22LE2GS+lTLTTXnf3Kdec20wG+7gXBEdufsyqPI7PXEFi0qnV7qxJEkld0h6FTLfBVVSe8yuiMQDf23+hpfedQ1ncg==" Dec 08 07:41:01 volumio go-librespot[5967]: time="2025-12-08T07:41:01-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 08 07:41:01 volumio go-librespot[5967]: time="2025-12-08T07:41:01-06:00" level=debug msg="completed keyexchange" Dec 08 07:41:01 volumio go-librespot[5967]: time="2025-12-08T07:41:01-06:00" level=debug msg="completed challenge" Dec 08 07:41:01 volumio go-librespot[5967]: time="2025-12-08T07:41:01-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 08 07:41:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 08 07:41:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 08 07:41:02 volumio sudo[5985]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-08 07:40' Dec 08 07:41:02 volumio sudo[5985]: 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"