-- Logs begin at Sun 2026-02-22 18:00:15 EST, end at Sun 2026-02-22 21:05:36 EST. --
Feb 22 21:04:01 volumio volumio[1183]: info: Initializing connection to go-librespot Websocket
Feb 22 21:04:01 volumio volumio[1183]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 22 21:04:03 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Feb 22 21:04:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 670905.
Feb 22 21:04:03 volumio systemd[1]: Stopped go-librespot Daemon.
Feb 22 21:04:03 volumio systemd[1]: Started go-librespot Daemon.
Feb 22 21:04:03 volumio go-librespot[6218]: go-librespot daemon starting...
Feb 22 21:04:03 volumio go-librespot[6218]: time="2026-02-22T21:04:03-05:00" level=info msg="running go-librespot 0.4.0"
Feb 22 21:04:03 volumio go-librespot[6218]: time="2026-02-22T21:04:03-05:00" level=debug msg="app state loaded"
Feb 22 21:04:03 volumio go-librespot[6218]: time="2026-02-22T21:04:03-05:00" level=debug msg="stored credentials not found"
Feb 22 21:04:03 volumio go-librespot[6218]: time="2026-02-22T21:04:03-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 22 21:04:03 volumio go-librespot[6218]: time="2026-02-22T21:04:03-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 22 21:04:03 volumio go-librespot[6218]: time="2026-02-22T21:04:03-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 22 21:04:03 volumio go-librespot[6218]: time="2026-02-22T21:04:03-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 22 21:04:03 volumio go-librespot[6218]: time="2026-02-22T21:04:03-05:00" level=info msg="zeroconf server listening on port 35277"
Feb 22 21:04:03 volumio go-librespot[6218]: time="2026-02-22T21:04:03-05:00" level=debug msg="obtained new client token: AADTG+koFlqX22hUatnveVTb2gESCRYXw6hIkG2zYhxOyse8X/4tpSsoclKo+MEo9YblHKDfZNWKWCEGEEqo1FSzN2XvkcWPxpOh+pbsuhQhidDLCGLwKtgc5gFvViQv/yWeBCYFM0qHUoeNE3+GInoMrXqPqYB2KPH/qCb82PAMWX6vGYb9KP3TRlo0eTlwMLrGJLx5x0zEGLiZfn5bWW1OWDKrYv5TpUBZpBB83B+oFv3THSs9uo1Peg=="
Feb 22 21:04:03 volumio go-librespot[6218]: time="2026-02-22T21:04:03-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Feb 22 21:04:03 volumio go-librespot[6218]: time="2026-02-22T21:04:03-05:00" level=debug msg="completed keyexchange"
Feb 22 21:04:03 volumio go-librespot[6218]: time="2026-02-22T21:04:03-05:00" level=debug msg="completed challenge"
Feb 22 21:04:03 volumio go-librespot[6218]: time="2026-02-22T21:04:03-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 22 21:04:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 22 21:04:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 22 21:04:04 volumio volumio[1183]: info: Initializing connection to go-librespot Websocket
Feb 22 21:04:04 volumio volumio[1183]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 22 21:04:06 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Feb 22 21:04:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 670906.
Feb 22 21:04:06 volumio systemd[1]: Stopped go-librespot Daemon.
Feb 22 21:04:06 volumio systemd[1]: Started go-librespot Daemon.
Feb 22 21:04:06 volumio go-librespot[6234]: go-librespot daemon starting...
Feb 22 21:04:06 volumio go-librespot[6234]: time="2026-02-22T21:04:06-05:00" level=info msg="running go-librespot 0.4.0"
Feb 22 21:04:06 volumio go-librespot[6234]: time="2026-02-22T21:04:06-05:00" level=debug msg="app state loaded"
Feb 22 21:04:06 volumio go-librespot[6234]: time="2026-02-22T21:04:06-05:00" level=debug msg="stored credentials not found"
Feb 22 21:04:06 volumio go-librespot[6234]: time="2026-02-22T21:04:06-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 22 21:04:07 volumio volumio[1183]: info: Initializing connection to go-librespot Websocket
Feb 22 21:04:07 volumio go-librespot[6234]: time="2026-02-22T21:04:07-05:00" level=debug msg="new websocket client"
Feb 22 21:04:07 volumio volumio[1183]: info: Connection to go-librespot Websocket established
Feb 22 21:04:07 volumio go-librespot[6234]: time="2026-02-22T21:04:07-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 22 21:04:07 volumio go-librespot[6234]: time="2026-02-22T21:04:07-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 22 21:04:07 volumio go-librespot[6234]: time="2026-02-22T21:04:07-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 22 21:04:07 volumio go-librespot[6234]: time="2026-02-22T21:04:07-05:00" level=info msg="zeroconf server listening on port 44439"
Feb 22 21:04:07 volumio go-librespot[6234]: time="2026-02-22T21:04:07-05:00" level=debug msg="obtained new client token: AACe1TRRBN8Po6x/0JYgexpUVFz6mPjPQx8JY6lFG/c0to+2UDin0cm+l9XyKzmqpn5g4jQ35auO7uTkdvoR6GQtPDsyYQGGoGvsJXUZjHgM56akxai+0jlwgmGtJSSwxzVIxmegXg4gd84nndEZseVQH8SBu2SKQRM4S++A7ei0JiB3qyJliELYP1OsnvRlKN7+CzPJfYrqF+YbKPI0PuWyDfJ6C9ph+mUsulJvSE1DNYaM79NLDWkOsQ=="
Feb 22 21:04:08 volumio go-librespot[6234]: time="2026-02-22T21:04:08-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Feb 22 21:04:09 volumio go-librespot[6234]: time="2026-02-22T21:04:09-05:00" level=debug msg="completed keyexchange"
Feb 22 21:04:09 volumio go-librespot[6234]: time="2026-02-22T21:04:09-05:00" level=debug msg="completed challenge"
Feb 22 21:04:10 volumio go-librespot[6234]: time="2026-02-22T21:04:10-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 22 21:04:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 22 21:04:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 22 21:04:10 volumio volumio[1183]: info: Connection to go-librespot Websocket closed
Feb 22 21:04:10 volumio volumio[1183]: info: Getting Spotify volume
Feb 22 21:04:10 volumio volumio[1183]: (node:1183) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 22 21:04:10 volumio volumio[1183]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16)
Feb 22 21:04:10 volumio volumio[1183]: (node:1183) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 30071)
Feb 22 21:04:10 volumio volumio[1183]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7
Feb 22 21:04:10 volumio volumio[1183]: info: CoreCommandRouter::volumioGetState
Feb 22 21:04:10 volumio volumio[1183]: info: CorePlayQueue::getTrack 8
Feb 22 21:04:10 volumio volumio[1183]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
Feb 22 21:04:13 volumio volumio[1183]: info: Initializing connection to go-librespot Websocket
Feb 22 21:04:13 volumio volumio[1183]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 22 21:04:13 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Feb 22 21:04:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 670907.
Feb 22 21:04:13 volumio systemd[1]: Stopped go-librespot Daemon.
Feb 22 21:04:13 volumio systemd[1]: Started go-librespot Daemon.
Feb 22 21:04:13 volumio go-librespot[6268]: go-librespot daemon starting...
Feb 22 21:04:13 volumio go-librespot[6268]: time="2026-02-22T21:04:13-05:00" level=info msg="running go-librespot 0.4.0"
Feb 22 21:04:13 volumio go-librespot[6268]: time="2026-02-22T21:04:13-05:00" level=debug msg="app state loaded"
Feb 22 21:04:13 volumio go-librespot[6268]: time="2026-02-22T21:04:13-05:00" level=debug msg="stored credentials not found"
Feb 22 21:04:13 volumio go-librespot[6268]: time="2026-02-22T21:04:13-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 22 21:04:14 volumio go-librespot[6268]: time="2026-02-22T21:04:14-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew1.spotify.com:80]"
Feb 22 21:04:14 volumio go-librespot[6268]: time="2026-02-22T21:04:14-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Feb 22 21:04:14 volumio go-librespot[6268]: time="2026-02-22T21:04:14-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Feb 22 21:04:14 volumio go-librespot[6268]: time="2026-02-22T21:04:14-05:00" level=info msg="zeroconf server listening on port 42953"
Feb 22 21:04:14 volumio go-librespot[6268]: time="2026-02-22T21:04:14-05:00" level=debug msg="obtained new client token: AAAVLM779tQId37BIg9eZ+MWnD5VH3vteTAbcXAi+4Dc0PT/GhzQJU0MxsmcGFLJxRV4TBaes5s5U1gc2e0A+QYoER9ahrVGnJTjgJu7725nsKLrysS8O6GAIImuJGtzlU2DNL5WzsAc5aryTNJo5l5A31QBt/1nopJXAvvtnAsgdCKro4+7iTii/bZqaYvJox6u1z6RPa9tXSYuoo42EvQCWMGOqesF56uzJ3x+NJY7MxEAkYx6wJmmJA=="
Feb 22 21:04:14 volumio go-librespot[6268]: time="2026-02-22T21:04:14-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Feb 22 21:04:14 volumio go-librespot[6268]: time="2026-02-22T21:04:14-05:00" level=debug msg="completed keyexchange"
Feb 22 21:04:14 volumio go-librespot[6268]: time="2026-02-22T21:04:14-05:00" level=debug msg="completed challenge"
Feb 22 21:04:14 volumio go-librespot[6268]: time="2026-02-22T21:04:14-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 22 21:04:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 22 21:04:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 22 21:04:16 volumio volumio[1183]: info: Initializing connection to go-librespot Websocket
Feb 22 21:04:16 volumio volumio[1183]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 22 21:04:17 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Feb 22 21:04:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 670908.
Feb 22 21:04:17 volumio systemd[1]: Stopped go-librespot Daemon.
Feb 22 21:04:17 volumio systemd[1]: Started go-librespot Daemon.
Feb 22 21:04:17 volumio go-librespot[6290]: go-librespot daemon starting...
Feb 22 21:04:17 volumio go-librespot[6290]: time="2026-02-22T21:04:17-05:00" level=info msg="running go-librespot 0.4.0"
Feb 22 21:04:17 volumio go-librespot[6290]: time="2026-02-22T21:04:17-05:00" level=debug msg="app state loaded"
Feb 22 21:04:17 volumio go-librespot[6290]: time="2026-02-22T21:04:17-05:00" level=debug msg="stored credentials not found"
Feb 22 21:04:17 volumio go-librespot[6290]: time="2026-02-22T21:04:17-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 22 21:04:17 volumio go-librespot[6290]: time="2026-02-22T21:04:17-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 22 21:04:17 volumio go-librespot[6290]: time="2026-02-22T21:04:17-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 22 21:04:17 volumio go-librespot[6290]: time="2026-02-22T21:04:17-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 22 21:04:17 volumio go-librespot[6290]: time="2026-02-22T21:04:17-05:00" level=info msg="zeroconf server listening on port 37345"
Feb 22 21:04:18 volumio go-librespot[6290]: time="2026-02-22T21:04:18-05:00" level=debug msg="obtained new client token: AABVWP1Pxj3gawvfJXZghi4pZRrLOFgATxh8YOshkHofjyTKnDsjEKFfIDda7HT6Si8lN2zp85HAuok+kZFt+Hm7gGVbX6oulsXssclWjpQMaWAidAkrUeXeJnGjsL8pGQZkX1rBajSUbWfdtKxX4RA5J3ePqpKrjbrGxX6wa+1kTOJ98r0YAmHTXa6w9OXkCVtrKf/yTIh7iV3xOxsGBvBXnEhXYfGzYe4I7Lw994Q8ZEt6UfDDEwc="
Feb 22 21:04:18 volumio go-librespot[6290]: time="2026-02-22T21:04:18-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Feb 22 21:04:18 volumio go-librespot[6290]: time="2026-02-22T21:04:18-05:00" level=debug msg="completed keyexchange"
Feb 22 21:04:18 volumio go-librespot[6290]: time="2026-02-22T21:04:18-05:00" level=debug msg="completed challenge"
Feb 22 21:04:18 volumio go-librespot[6290]: time="2026-02-22T21:04:18-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 22 21:04:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 22 21:04:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 22 21:04:19 volumio volumio[1183]: info: Initializing connection to go-librespot Websocket
Feb 22 21:04:19 volumio volumio[1183]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 22 21:04:21 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Feb 22 21:04:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 670909.
Feb 22 21:04:21 volumio systemd[1]: Stopped go-librespot Daemon.
Feb 22 21:04:21 volumio systemd[1]: Started go-librespot Daemon.
Feb 22 21:04:21 volumio go-librespot[6318]: go-librespot daemon starting...
Feb 22 21:04:21 volumio go-librespot[6318]: time="2026-02-22T21:04:21-05:00" level=info msg="running go-librespot 0.4.0"
Feb 22 21:04:21 volumio go-librespot[6318]: time="2026-02-22T21:04:21-05:00" level=debug msg="app state loaded"
Feb 22 21:04:21 volumio go-librespot[6318]: time="2026-02-22T21:04:21-05:00" level=debug msg="stored credentials not found"
Feb 22 21:04:21 volumio go-librespot[6318]: time="2026-02-22T21:04:21-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 22 21:04:21 volumio go-librespot[6318]: time="2026-02-22T21:04:21-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 22 21:04:21 volumio go-librespot[6318]: time="2026-02-22T21:04:21-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 22 21:04:21 volumio go-librespot[6318]: time="2026-02-22T21:04:21-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 22 21:04:21 volumio go-librespot[6318]: time="2026-02-22T21:04:21-05:00" level=info msg="zeroconf server listening on port 37125"
Feb 22 21:04:21 volumio go-librespot[6318]: time="2026-02-22T21:04:21-05:00" level=debug msg="obtained new client token: AACSVxV2rQl5mQk+qOq3xMMazIszK2SvZ6VjhBaTS33qJftbORZuG85sS/I0aCZuW5uY1F3EHkUpNqPPOclNUoOi3fJ/Qwc1LMx1H+FC/JXgYc+bIFgk0BIq6vJz8xRAcmZqNPlPgFrHynVEGUz0uC/l6loq2RidGB5gyMXYUCLSopc09OB8QXmZwKIQky45gQvayW5c85kB5yycn6yOllRRRoHmOkvUYucBDWm/t6A7HQhTfoxJ3boRNw=="
Feb 22 21:04:21 volumio go-librespot[6318]: time="2026-02-22T21:04:21-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Feb 22 21:04:21 volumio go-librespot[6318]: time="2026-02-22T21:04:21-05:00" level=debug msg="completed keyexchange"
Feb 22 21:04:21 volumio go-librespot[6318]: time="2026-02-22T21:04:21-05:00" level=debug msg="completed challenge"
Feb 22 21:04:21 volumio go-librespot[6318]: time="2026-02-22T21:04:21-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 22 21:04:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 22 21:04:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 22 21:04:22 volumio volumio[1183]: info: Initializing connection to go-librespot Websocket
Feb 22 21:04:22 volumio volumio[1183]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 22 21:04:25 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Feb 22 21:04:25 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 670910.
Feb 22 21:04:25 volumio systemd[1]: Stopped go-librespot Daemon.
Feb 22 21:04:25 volumio systemd[1]: Started go-librespot Daemon.
Feb 22 21:04:25 volumio go-librespot[6334]: go-librespot daemon starting...
Feb 22 21:04:25 volumio go-librespot[6334]: time="2026-02-22T21:04:25-05:00" level=info msg="running go-librespot 0.4.0"
Feb 22 21:04:25 volumio go-librespot[6334]: time="2026-02-22T21:04:25-05:00" level=debug msg="app state loaded"
Feb 22 21:04:25 volumio go-librespot[6334]: time="2026-02-22T21:04:25-05:00" level=debug msg="stored credentials not found"
Feb 22 21:04:25 volumio go-librespot[6334]: time="2026-02-22T21:04:25-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 22 21:04:25 volumio volumio[1183]: info: Initializing connection to go-librespot Websocket
Feb 22 21:04:25 volumio go-librespot[6334]: time="2026-02-22T21:04:25-05:00" level=debug msg="new websocket client"
Feb 22 21:04:25 volumio volumio[1183]: info: Connection to go-librespot Websocket established
Feb 22 21:04:25 volumio go-librespot[6334]: time="2026-02-22T21:04:25-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 22 21:04:25 volumio go-librespot[6334]: time="2026-02-22T21:04:25-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 22 21:04:25 volumio go-librespot[6334]: time="2026-02-22T21:04:25-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 22 21:04:25 volumio go-librespot[6334]: time="2026-02-22T21:04:25-05:00" level=info msg="zeroconf server listening on port 34591"
Feb 22 21:04:25 volumio go-librespot[6334]: time="2026-02-22T21:04:25-05:00" level=debug msg="obtained new client token: AACeEBqO9sqrUnhCz/St8PeQvyjalXrjMT3+2H554QyoxWRvKw9hqnnlmcq5CD5ADvxRmPR4MmaeOSF6zTAlimkEnuJcDqhTO0tK/EZp2rlrUXF4HSnq3Nebd6S+uQoKHhLgCaVJpOxcCFRTG+inT1wXW/P454y9h/7k0Xu31S7WlXw+UCmc/fKBEF+OdU0ZY+rp2MJY+38BZyiy/VqzVvx2Mmojw5DRpIm7fMkrIxCFLeZVMRqR6+v47Q=="
Feb 22 21:04:25 volumio go-librespot[6334]: time="2026-02-22T21:04:25-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Feb 22 21:04:25 volumio go-librespot[6334]: time="2026-02-22T21:04:25-05:00" level=debug msg="completed keyexchange"
Feb 22 21:04:25 volumio go-librespot[6334]: time="2026-02-22T21:04:25-05:00" level=debug msg="completed challenge"
Feb 22 21:04:25 volumio go-librespot[6334]: time="2026-02-22T21:04:25-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 22 21:04:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 22 21:04:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 22 21:04:25 volumio volumio[1183]: info: Connection to go-librespot Websocket closed
Feb 22 21:04:28 volumio volumio[1183]: info: Getting Spotify volume
Feb 22 21:04:28 volumio volumio[1183]: (node:1183) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 22 21:04:28 volumio volumio[1183]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16)
Feb 22 21:04:28 volumio volumio[1183]: (node:1183) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 30072)
Feb 22 21:04:28 volumio volumio[1183]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8
Feb 22 21:04:28 volumio volumio[1183]: info: CoreCommandRouter::volumioGetState
Feb 22 21:04:28 volumio volumio[1183]: info: CorePlayQueue::getTrack 8
Feb 22 21:04:28 volumio volumio[1183]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
Feb 22 21:04:28 volumio volumio[1183]: info: Initializing connection to go-librespot Websocket
Feb 22 21:04:28 volumio volumio[1183]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 22 21:04:28 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Feb 22 21:04:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 670911.
Feb 22 21:04:28 volumio systemd[1]: Stopped go-librespot Daemon.
Feb 22 21:04:28 volumio systemd[1]: Started go-librespot Daemon.
Feb 22 21:04:28 volumio go-librespot[6350]: go-librespot daemon starting...
Feb 22 21:04:28 volumio go-librespot[6350]: time="2026-02-22T21:04:28-05:00" level=info msg="running go-librespot 0.4.0"
Feb 22 21:04:28 volumio go-librespot[6350]: time="2026-02-22T21:04:28-05:00" level=debug msg="app state loaded"
Feb 22 21:04:28 volumio go-librespot[6350]: time="2026-02-22T21:04:28-05:00" level=debug msg="stored credentials not found"
Feb 22 21:04:28 volumio go-librespot[6350]: time="2026-02-22T21:04:28-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 22 21:04:29 volumio go-librespot[6350]: time="2026-02-22T21:04:29-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 22 21:04:29 volumio go-librespot[6350]: time="2026-02-22T21:04:29-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 22 21:04:29 volumio go-librespot[6350]: time="2026-02-22T21:04:29-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 22 21:04:29 volumio go-librespot[6350]: time="2026-02-22T21:04:29-05:00" level=info msg="zeroconf server listening on port 44419"
Feb 22 21:04:29 volumio go-librespot[6350]: time="2026-02-22T21:04:29-05:00" level=debug msg="obtained new client token: AADOJx159a9vyhDaR3IEu/XW7zmDdGE9zgP52PV79zpnu8ipR/28apUSKSd8RGwvnvANSUWVd0p8iL6xvphZQLU4XT3dGUEiuA+1mr969AJ1o9+M7TpC4AXr8r05U1AAINg1/1f3jUL9hUDPHNl5TgN4FIWziDdjgzJT5M/ytqKZPel+OLfebjouIQPC20zE48ox6XT9uX202d9pcmgILIlGkh6z84iLSslrxWH6zy24Rb3c/j+uO6HH1g=="
Feb 22 21:04:29 volumio go-librespot[6350]: time="2026-02-22T21:04:29-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Feb 22 21:04:29 volumio go-librespot[6350]: time="2026-02-22T21:04:29-05:00" level=debug msg="completed keyexchange"
Feb 22 21:04:29 volumio go-librespot[6350]: time="2026-02-22T21:04:29-05:00" level=debug msg="completed challenge"
Feb 22 21:04:29 volumio go-librespot[6350]: time="2026-02-22T21:04:29-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 22 21:04:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 22 21:04:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 22 21:04:31 volumio volumio[1183]: info: Initializing connection to go-librespot Websocket
Feb 22 21:04:31 volumio volumio[1183]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 22 21:04:32 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Feb 22 21:04:32 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 670912.
Feb 22 21:04:32 volumio systemd[1]: Stopped go-librespot Daemon.
Feb 22 21:04:32 volumio systemd[1]: Started go-librespot Daemon.
Feb 22 21:04:32 volumio go-librespot[6380]: go-librespot daemon starting...
Feb 22 21:04:32 volumio go-librespot[6380]: time="2026-02-22T21:04:32-05:00" level=info msg="running go-librespot 0.4.0"
Feb 22 21:04:32 volumio go-librespot[6380]: time="2026-02-22T21:04:32-05:00" level=debug msg="app state loaded"
Feb 22 21:04:32 volumio go-librespot[6380]: time="2026-02-22T21:04:32-05:00" level=debug msg="stored credentials not found"
Feb 22 21:04:32 volumio go-librespot[6380]: time="2026-02-22T21:04:32-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 22 21:04:32 volumio go-librespot[6380]: time="2026-02-22T21:04:32-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 22 21:04:32 volumio go-librespot[6380]: time="2026-02-22T21:04:32-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 22 21:04:32 volumio go-librespot[6380]: time="2026-02-22T21:04:32-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 22 21:04:32 volumio go-librespot[6380]: time="2026-02-22T21:04:32-05:00" level=info msg="zeroconf server listening on port 43129"
Feb 22 21:04:32 volumio go-librespot[6380]: time="2026-02-22T21:04:32-05:00" level=debug msg="obtained new client token: AAAcaG1KZVUb16lL1FACKBHoPa29yBealkZ+eiLjaePWsbo3HNmIhpOmAdKQBswxxFKOuPgMAc+F/VexVsUWrUivoA5Zh+5AuS7GyVitYBZU+2xPLdVIpa6plqmkSNE0vQX3/B+ww4YDx8bs2rQZooZimRjbCpN5ivsQUrXPNL6FaDvTSb5jNM0Q9HLapNBLDVmtOYce7jLTXemISSSAXAmzjqtB7tkIMVEZRQUIr/LXFWTodk6qi6FiVQ=="
Feb 22 21:04:33 volumio go-librespot[6380]: time="2026-02-22T21:04:33-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Feb 22 21:04:33 volumio go-librespot[6380]: time="2026-02-22T21:04:33-05:00" level=debug msg="completed keyexchange"
Feb 22 21:04:33 volumio go-librespot[6380]: time="2026-02-22T21:04:33-05:00" level=debug msg="completed challenge"
Feb 22 21:04:33 volumio go-librespot[6380]: time="2026-02-22T21:04:33-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 22 21:04:33 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 22 21:04:33 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 22 21:04:34 volumio volumio[1183]: info: Initializing connection to go-librespot Websocket
Feb 22 21:04:34 volumio volumio[1183]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 22 21:04:36 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Feb 22 21:04:36 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 670913.
Feb 22 21:04:36 volumio systemd[1]: Stopped go-librespot Daemon.
Feb 22 21:04:36 volumio systemd[1]: Started go-librespot Daemon.
Feb 22 21:04:36 volumio go-librespot[6395]: go-librespot daemon starting...
Feb 22 21:04:36 volumio go-librespot[6395]: time="2026-02-22T21:04:36-05:00" level=info msg="running go-librespot 0.4.0"
Feb 22 21:04:36 volumio go-librespot[6395]: time="2026-02-22T21:04:36-05:00" level=debug msg="app state loaded"
Feb 22 21:04:36 volumio go-librespot[6395]: time="2026-02-22T21:04:36-05:00" level=debug msg="stored credentials not found"
Feb 22 21:04:36 volumio go-librespot[6395]: time="2026-02-22T21:04:36-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 22 21:04:36 volumio go-librespot[6395]: time="2026-02-22T21:04:36-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew1.spotify.com:80]"
Feb 22 21:04:36 volumio go-librespot[6395]: time="2026-02-22T21:04:36-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Feb 22 21:04:36 volumio go-librespot[6395]: time="2026-02-22T21:04:36-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Feb 22 21:04:36 volumio go-librespot[6395]: time="2026-02-22T21:04:36-05:00" level=info msg="zeroconf server listening on port 34999"
Feb 22 21:04:36 volumio go-librespot[6395]: time="2026-02-22T21:04:36-05:00" level=debug msg="obtained new client token: AABGd9pAXvkNDPBkquhXOKOc3MhBCVTlpMj2Xa9WuvSF92s0SYHnCAkIm7QbauqR8LyF8WjvX+IDc7Y2bq4UhUCtJtXzRZGtoDdSXX+tssTNY74iHqIqLC3moKK5dOyYKBiP0O39TUWLCYGZSUiYupKDomg16p/CKQ7B7P6f66LrhO5tWGVPqdcmwdyBAIBNoDmtAgGpFJ+M74adOFBKHnlXT4gf36uqyq3pdvjlqZDMx4Zi2joa5mOljQ=="
Feb 22 21:04:36 volumio go-librespot[6395]: time="2026-02-22T21:04:36-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Feb 22 21:04:36 volumio go-librespot[6395]: time="2026-02-22T21:04:36-05:00" level=debug msg="completed keyexchange"
Feb 22 21:04:36 volumio go-librespot[6395]: time="2026-02-22T21:04:36-05:00" level=debug msg="completed challenge"
Feb 22 21:04:36 volumio go-librespot[6395]: time="2026-02-22T21:04:36-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 22 21:04:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 22 21:04:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 22 21:04:37 volumio volumio[1183]: info: Initializing connection to go-librespot Websocket
Feb 22 21:04:37 volumio volumio[1183]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 22 21:04:39 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Feb 22 21:04:39 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 670914.
Feb 22 21:04:39 volumio systemd[1]: Stopped go-librespot Daemon.
Feb 22 21:04:39 volumio go-librespot[6469]: go-librespot daemon starting...
Feb 22 21:04:39 volumio systemd[1]: Started go-librespot Daemon.
Feb 22 21:04:39 volumio go-librespot[6469]: time="2026-02-22T21:04:39-05:00" level=info msg="running go-librespot 0.4.0"
Feb 22 21:04:39 volumio go-librespot[6469]: time="2026-02-22T21:04:39-05:00" level=debug msg="app state loaded"
Feb 22 21:04:39 volumio go-librespot[6469]: time="2026-02-22T21:04:39-05:00" level=debug msg="stored credentials not found"
Feb 22 21:04:39 volumio go-librespot[6469]: time="2026-02-22T21:04:39-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 22 21:04:40 volumio go-librespot[6469]: time="2026-02-22T21:04:40-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 22 21:04:40 volumio go-librespot[6469]: time="2026-02-22T21:04:40-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 22 21:04:40 volumio go-librespot[6469]: time="2026-02-22T21:04:40-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 22 21:04:40 volumio go-librespot[6469]: time="2026-02-22T21:04:40-05:00" level=info msg="zeroconf server listening on port 34575"
Feb 22 21:04:40 volumio go-librespot[6469]: time="2026-02-22T21:04:40-05:00" level=debug msg="obtained new client token: AADXKVqgjB9k+x+dmsLdeWQpZt8ArStVFJQpEDrXfcZ08OIYFQq0UZxYea55Pyt8bHH8xxKmz/LKd8koLtTI8GytZUV5TMWSx1Rf6ou1A4aOE2RNr5PTj+aojkYANabUevsruDPKExxpBrM7h9vCmXoJMWumNNV2SU0EkNtParui9Wdg2qJ9x9FlyMZQdDThFJUTAU+/wmivlztbMo/qdtRUx9gfEOdLxqo29l3GWERPev6KznSjz7yTQQ=="
Feb 22 21:04:40 volumio go-librespot[6469]: time="2026-02-22T21:04:40-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Feb 22 21:04:40 volumio go-librespot[6469]: time="2026-02-22T21:04:40-05:00" level=debug msg="completed keyexchange"
Feb 22 21:04:40 volumio go-librespot[6469]: time="2026-02-22T21:04:40-05:00" level=debug msg="completed challenge"
Feb 22 21:04:40 volumio go-librespot[6469]: time="2026-02-22T21:04:40-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 22 21:04:40 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 22 21:04:40 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 22 21:04:40 volumio volumio[1183]: info: Initializing connection to go-librespot Websocket
Feb 22 21:04:40 volumio volumio[1183]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 22 21:04:43 volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Feb 22 21:04:43 volumio volumio[1183]: info: CURURI: artists://Beeson
Feb 22 21:04:43 volumio volumio[1183]: info: Initializing connection to go-librespot Websocket
Feb 22 21:04:43 volumio volumio[1183]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 22 21:04:43 volumio volumio[1183]: info: Preload queue cleared
Feb 22 21:04:43 volumio volumio[1183]: info: Preload queue cleared
Feb 22 21:04:43 volumio volumio[1183]: info: Preloading song: music-library/NAS/MusicShare/my_music/Beeson/Duplicates/Best Case Girl/01 - Beeson - Best Case Girl.flac
Feb 22 21:04:43 volumio volumio[1183]: info: Preloading song: music-library/NAS/MusicShare/my_music/Beeson/Duplicates/I Haven't Seen You Since The Summer/01 - Beeson - I Haven't Seen You Since The Summer.flac
Feb 22 21:04:43 volumio volumio[1183]: info: Preloading song: music-library/NAS/MusicShare/my_music/Beeson/Last Shot/01 - Beeson - everybody's baby.flac
Feb 22 21:04:43 volumio volumio[1183]: info: Preloading song: music-library/NAS/MusicShare/my_music/Beeson/Last Shot/02 - Beeson - I Haven't Seen You Since The Summer.flac
Feb 22 21:04:43 volumio volumio[1183]: info: Preloading song: music-library/NAS/MusicShare/my_music/Beeson/Last Shot/03 - Beeson - Best Case Girl.flac
Feb 22 21:04:43 volumio volumio[1183]: info: Preloading song: music-library/NAS/MusicShare/my_music/Beeson/Last Shot/04 - Beeson - Last Shot.flac
Feb 22 21:04:43 volumio volumio[1183]: info: Preloading song: music-library/NAS/MusicShare/my_music/Beeson/Last Shot/05 - Beeson - Love Her Better.flac
Feb 22 21:04:43 volumio volumio[1183]: info: Preloading song: music-library/NAS/MusicShare/my_music/Beeson/Luckiest/01 - Beeson - Luckiest.flac
Feb 22 21:04:43 volumio volumio[1183]: info: Preloading song: music-library/NAS/MusicShare/my_music/Beeson/Pink Light/01 - Beeson - Pink Light.flac
Feb 22 21:04:43 volumio volumio[1183]: info: Preloading song: music-library/NAS/MusicShare/my_music/Beeson/Ritual/01 - Beeson - Gap Teeth.flac
Feb 22 21:04:43 volumio volumio[1183]: info: Preloading song: music-library/NAS/MusicShare/my_music/Beeson/Ritual/02 - Beeson - Swimmer.flac
Feb 22 21:04:43 volumio volumio[1183]: info: Preloading song: music-library/NAS/MusicShare/my_music/Beeson/Ritual/03 - Beeson - Just For Now.flac
Feb 22 21:04:43 volumio volumio[1183]: info: Preloading song: music-library/NAS/MusicShare/my_music/Beeson/Ritual/04 - Beeson - Ritual.flac
Feb 22 21:04:43 volumio volumio[1183]: info: Preloading song: music-library/NAS/MusicShare/my_music/Beeson/Ritual/05 - Beeson - Pulpit.flac
Feb 22 21:04:43 volumio volumio[1183]: info: Preloading song: music-library/NAS/MusicShare/my_music/Beeson/Ritual/06 - Beeson - Keeping Score.flac
Feb 22 21:04:43 volumio volumio[1183]: info: Preloading song: music-library/NAS/MusicShare/my_music/Beeson/Tommy/01 - Beeson - Tommy.flac
Feb 22 21:04:43 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Feb 22 21:04:43 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 670915.
Feb 22 21:04:43 volumio systemd[1]: Stopped go-librespot Daemon.
Feb 22 21:04:43 volumio volumio[1183]: info: Exploding uri music-library/NAS/MusicShare/my_music/Beeson/Duplicates/Best Case Girl/01 - Beeson - Best Case Girl.flac in service mpd
Feb 22 21:04:43 volumio systemd[1]: Started go-librespot Daemon.
Feb 22 21:04:43 volumio go-librespot[6485]: go-librespot daemon starting...
Feb 22 21:04:43 volumio go-librespot[6485]: time="2026-02-22T21:04:43-05:00" level=info msg="running go-librespot 0.4.0"
Feb 22 21:04:43 volumio go-librespot[6485]: time="2026-02-22T21:04:43-05:00" level=debug msg="app state loaded"
Feb 22 21:04:43 volumio go-librespot[6485]: time="2026-02-22T21:04:43-05:00" level=debug msg="stored credentials not found"
Feb 22 21:04:43 volumio go-librespot[6485]: time="2026-02-22T21:04:43-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 22 21:04:44 volumio volumio[1183]: info: Exploding uri music-library/NAS/MusicShare/my_music/Beeson/Duplicates/I Haven't Seen You Since The Summer/01 - Beeson - I Haven't Seen You Since The Summer.flac in service mpd
Feb 22 21:04:44 volumio go-librespot[6485]: time="2026-02-22T21:04:44-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 22 21:04:44 volumio go-librespot[6485]: time="2026-02-22T21:04:44-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 22 21:04:44 volumio go-librespot[6485]: time="2026-02-22T21:04:44-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 22 21:04:44 volumio go-librespot[6485]: time="2026-02-22T21:04:44-05:00" level=info msg="zeroconf server listening on port 40627"
Feb 22 21:04:44 volumio volumio[1183]: info: Exploding uri music-library/NAS/MusicShare/my_music/Beeson/Last Shot/01 - Beeson - everybody's baby.flac in service mpd
Feb 22 21:04:44 volumio volumio[1183]: info: ALBUMART /albumart?cacheid=581&web=Beeson/Best%20Case%20Girl/extralarge&path=%2Fmnt%2FNAS%2FMusicShare%2Fmy_music%2FBeeson%2FDuplicates%2FBest%20Case%20Girl%2F01%20-%20Beeson%20-%20Best%20Case%20Girl.flac&metadata=false
Feb 22 21:04:44 volumio volumio[1183]: info: URI /mnt/NAS/MusicShare/my_music/Beeson/Duplicates/Best Case Girl/01 - Beeson - Best Case Girl.flac
Feb 22 21:04:44 volumio volumio[1183]: info: ALBUMART /albumart?cacheid=581&web=Beeson/I%20Haven't%20Seen%20You%20Since%20The%20Summer/extralarge&path=%2Fmnt%2FNAS%2FMusicShare%2Fmy_music%2FBeeson%2FDuplicates%2FI%20Haven't%20Seen%20You%20Since%20The%20Summer%2F01%20-%20Beeson%20-%20I%20Haven't%20Seen%20You%20Since%20The%20Summer.flac&metadata=false
Feb 22 21:04:44 volumio volumio[1183]: info: URI /mnt/NAS/MusicShare/my_music/Beeson/Duplicates/I Haven't Seen You Since The Summer/01 - Beeson - I Haven't Seen You Since The Summer.flac
Feb 22 21:04:44 volumio volumio[1183]: info: Exploding uri music-library/NAS/MusicShare/my_music/Beeson/Last Shot/02 - Beeson - I Haven't Seen You Since The Summer.flac in service mpd
Feb 22 21:04:44 volumio volumio[1183]: info: Exploding uri music-library/NAS/MusicShare/my_music/Beeson/Last Shot/03 - Beeson - Best Case Girl.flac in service mpd
Feb 22 21:04:44 volumio volumio[1183]: info: Exploding uri music-library/NAS/MusicShare/my_music/Beeson/Last Shot/04 - Beeson - Last Shot.flac in service mpd
Feb 22 21:04:44 volumio volumio[1183]: info: ALBUMART /albumart?cacheid=581&web=Beeson/Last%20Shot/extralarge&path=%2Fmnt%2FNAS%2FMusicShare%2Fmy_music%2FBeeson%2FLast%20Shot%2F01%20-%20Beeson%20-%20everybody's%20baby.flac&metadata=false
Feb 22 21:04:44 volumio volumio[1183]: info: URI /mnt/NAS/MusicShare/my_music/Beeson/Last Shot/01 - Beeson - everybody's baby.flac
Feb 22 21:04:44 volumio volumio[1183]: info: ALBUMART /albumart?cacheid=581&web=Beeson/Last%20Shot/extralarge&path=%2Fmnt%2FNAS%2FMusicShare%2Fmy_music%2FBeeson%2FLast%20Shot%2F02%20-%20Beeson%20-%20I%20Haven't%20Seen%20You%20Since%20The%20Summer.flac&metadata=false
Feb 22 21:04:44 volumio volumio[1183]: info: URI /mnt/NAS/MusicShare/my_music/Beeson/Last Shot/02 - Beeson - I Haven't Seen You Since The Summer.flac
Feb 22 21:04:44 volumio volumio[1183]: info: ALBUMART /albumart?cacheid=581&web=Beeson/Last%20Shot/extralarge&path=%2Fmnt%2FNAS%2FMusicShare%2Fmy_music%2FBeeson%2FLast%20Shot%2F03%20-%20Beeson%20-%20Best%20Case%20Girl.flac&metadata=false
Feb 22 21:04:44 volumio volumio[1183]: info: URI /mnt/NAS/MusicShare/my_music/Beeson/Last Shot/03 - Beeson - Best Case Girl.flac
Feb 22 21:04:44 volumio volumio[1183]: info: Exploding uri music-library/NAS/MusicShare/my_music/Beeson/Last Shot/05 - Beeson - Love Her Better.flac in service mpd
Feb 22 21:04:44 volumio go-librespot[6485]: time="2026-02-22T21:04:44-05:00" level=debug msg="obtained new client token: AADzUI3fwh7+iigVHauSo3QChxUhjZadP8jzRdJkt5ikw4sqe6OJH0JvgvUOMqVnXxxkkZuu14/yRP9G5gskH7+xLkMZictxudD1Ga0mWLwh7ZcCjoEq+8KPjdqLpuWPDCF3RjFeTIpQ05AURJHxboBPyzBqRbYG9JrUHyvtdKqWwYPEhwZvETiRRZmEJSmCNHLvM3oruhe0x/k5LVnMOQfm0lVLmRVAruxwtfXVXTVfU8M3u3F8hSk="
Feb 22 21:04:44 volumio volumio[1183]: info: ALBUMART /albumart?cacheid=581&web=Beeson/Last%20Shot/extralarge&path=%2Fmnt%2FNAS%2FMusicShare%2Fmy_music%2FBeeson%2FLast%20Shot%2F04%20-%20Beeson%20-%20Last%20Shot.flac&metadata=false
Feb 22 21:04:44 volumio volumio[1183]: info: URI /mnt/NAS/MusicShare/my_music/Beeson/Last Shot/04 - Beeson - Last Shot.flac
Feb 22 21:04:44 volumio volumio[1183]: info: Exploding uri music-library/NAS/MusicShare/my_music/Beeson/Luckiest/01 - Beeson - Luckiest.flac in service mpd
Feb 22 21:04:44 volumio go-librespot[6485]: time="2026-02-22T21:04:44-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Feb 22 21:04:44 volumio volumio[1183]: info: ALBUMART /albumart?cacheid=581&web=Beeson/Last%20Shot/extralarge&path=%2Fmnt%2FNAS%2FMusicShare%2Fmy_music%2FBeeson%2FLast%20Shot%2F05%20-%20Beeson%20-%20Love%20Her%20Better.flac&metadata=false
Feb 22 21:04:44 volumio volumio[1183]: info: URI /mnt/NAS/MusicShare/my_music/Beeson/Last Shot/05 - Beeson - Love Her Better.flac
Feb 22 21:04:44 volumio volumio[1183]: info: Exploding uri music-library/NAS/MusicShare/my_music/Beeson/Pink Light/01 - Beeson - Pink Light.flac in service mpd
Feb 22 21:04:44 volumio volumio[1183]: info: Exploding uri music-library/NAS/MusicShare/my_music/Beeson/Ritual/01 - Beeson - Gap Teeth.flac in service mpd
Feb 22 21:04:44 volumio go-librespot[6485]: time="2026-02-22T21:04:44-05:00" level=debug msg="completed keyexchange"
Feb 22 21:04:44 volumio go-librespot[6485]: time="2026-02-22T21:04:44-05:00" level=debug msg="completed challenge"
Feb 22 21:04:44 volumio volumio[1183]: info: ALBUMART /albumart?cacheid=581&web=Beeson/Luckiest/extralarge&path=%2Fmnt%2FNAS%2FMusicShare%2Fmy_music%2FBeeson%2FLuckiest%2F01%20-%20Beeson%20-%20Luckiest.flac&metadata=false
Feb 22 21:04:44 volumio volumio[1183]: info: URI /mnt/NAS/MusicShare/my_music/Beeson/Luckiest/01 - Beeson - Luckiest.flac
Feb 22 21:04:44 volumio volumio[1183]: info: ALBUMART /albumart?cacheid=581&web=Beeson/Pink%20Light/extralarge&path=%2Fmnt%2FNAS%2FMusicShare%2Fmy_music%2FBeeson%2FPink%20Light%2F01%20-%20Beeson%20-%20Pink%20Light.flac&metadata=false
Feb 22 21:04:44 volumio volumio[1183]: info: URI /mnt/NAS/MusicShare/my_music/Beeson/Pink Light/01 - Beeson - Pink Light.flac
Feb 22 21:04:44 volumio go-librespot[6485]: time="2026-02-22T21:04:44-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 22 21:04:44 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 22 21:04:44 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 22 21:04:44 volumio volumio[1183]: info: Exploding uri music-library/NAS/MusicShare/my_music/Beeson/Ritual/02 - Beeson - Swimmer.flac in service mpd
Feb 22 21:04:44 volumio volumio[1183]: info: Exploding uri music-library/NAS/MusicShare/my_music/Beeson/Ritual/03 - Beeson - Just For Now.flac in service mpd
Feb 22 21:04:44 volumio volumio[1183]: info: ALBUMART /albumart?cacheid=581&web=Beeson/Ritual/extralarge&path=%2Fmnt%2FNAS%2FMusicShare%2Fmy_music%2FBeeson%2FRitual%2F01%20-%20Beeson%20-%20Gap%20Teeth.flac&metadata=false
Feb 22 21:04:44 volumio volumio[1183]: info: URI /mnt/NAS/MusicShare/my_music/Beeson/Ritual/01 - Beeson - Gap Teeth.flac
Feb 22 21:04:44 volumio volumio[1183]: info: ALBUMART /albumart?cacheid=581&web=Beeson/Ritual/extralarge&path=%2Fmnt%2FNAS%2FMusicShare%2Fmy_music%2FBeeson%2FRitual%2F02%20-%20Beeson%20-%20Swimmer.flac&metadata=false
Feb 22 21:04:44 volumio volumio[1183]: info: URI /mnt/NAS/MusicShare/my_music/Beeson/Ritual/02 - Beeson - Swimmer.flac
Feb 22 21:04:44 volumio volumio[1183]: info: Exploding uri music-library/NAS/MusicShare/my_music/Beeson/Ritual/04 - Beeson - Ritual.flac in service mpd
Feb 22 21:04:44 volumio volumio[1183]: info: ALBUMART /albumart?cacheid=581&web=Beeson/Ritual/extralarge&path=%2Fmnt%2FNAS%2FMusicShare%2Fmy_music%2FBeeson%2FRitual%2F03%20-%20Beeson%20-%20Just%20For%20Now.flac&metadata=false
Feb 22 21:04:44 volumio volumio[1183]: info: URI /mnt/NAS/MusicShare/my_music/Beeson/Ritual/03 - Beeson - Just For Now.flac
Feb 22 21:04:44 volumio volumio[1183]: info: ALBUMART /albumart?cacheid=581&web=Beeson/Ritual/extralarge&path=%2Fmnt%2FNAS%2FMusicShare%2Fmy_music%2FBeeson%2FRitual%2F04%20-%20Beeson%20-%20Ritual.flac&metadata=false
Feb 22 21:04:44 volumio volumio[1183]: info: URI /mnt/NAS/MusicShare/my_music/Beeson/Ritual/04 - Beeson - Ritual.flac
Feb 22 21:04:44 volumio volumio[1183]: info: Exploding uri music-library/NAS/MusicShare/my_music/Beeson/Ritual/05 - Beeson - Pulpit.flac in service mpd
Feb 22 21:04:44 volumio volumio[1183]: info: ALBUMART /albumart?cacheid=581&web=Beeson/Ritual/extralarge&path=%2Fmnt%2FNAS%2FMusicShare%2Fmy_music%2FBeeson%2FRitual%2F05%20-%20Beeson%20-%20Pulpit.flac&metadata=false
Feb 22 21:04:44 volumio volumio[1183]: info: URI /mnt/NAS/MusicShare/my_music/Beeson/Ritual/05 - Beeson - Pulpit.flac
Feb 22 21:04:44 volumio volumio[1183]: info: Exploding uri music-library/NAS/MusicShare/my_music/Beeson/Ritual/06 - Beeson - Keeping Score.flac in service mpd
Feb 22 21:04:44 volumio volumio[1183]: info: ALBUMART /albumart?cacheid=581&web=Beeson/Ritual/extralarge&path=%2Fmnt%2FNAS%2FMusicShare%2Fmy_music%2FBeeson%2FRitual%2F06%20-%20Beeson%20-%20Keeping%20Score.flac&metadata=false
Feb 22 21:04:44 volumio volumio[1183]: info: URI /mnt/NAS/MusicShare/my_music/Beeson/Ritual/06 - Beeson - Keeping Score.flac
Feb 22 21:04:44 volumio volumio[1183]: info: Exploding uri music-library/NAS/MusicShare/my_music/Beeson/Tommy/01 - Beeson - Tommy.flac in service mpd
Feb 22 21:04:44 volumio volumio[1183]: info: ALBUMART /albumart?cacheid=581&web=Beeson/Tommy/extralarge&path=%2Fmnt%2FNAS%2FMusicShare%2Fmy_music%2FBeeson%2FTommy%2F01%20-%20Beeson%20-%20Tommy.flac&metadata=false
Feb 22 21:04:44 volumio volumio[1183]: info: URI /mnt/NAS/MusicShare/my_music/Beeson/Tommy/01 - Beeson - Tommy.flac
Feb 22 21:04:45 volumio volumio[1183]: info: No valid Plugin REST Endpoint
Feb 22 21:04:45 volumio volumio[1183]: info: No valid Plugin REST Endpoint
Feb 22 21:04:46 volumio volumio[1183]: info: Initializing connection to go-librespot Websocket
Feb 22 21:04:46 volumio volumio[1183]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 22 21:04:47 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Feb 22 21:04:47 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 670916.
Feb 22 21:04:47 volumio systemd[1]: Stopped go-librespot Daemon.
Feb 22 21:04:47 volumio systemd[1]: Started go-librespot Daemon.
Feb 22 21:04:47 volumio go-librespot[6499]: go-librespot daemon starting...
Feb 22 21:04:47 volumio go-librespot[6499]: time="2026-02-22T21:04:47-05:00" level=info msg="running go-librespot 0.4.0"
Feb 22 21:04:47 volumio go-librespot[6499]: time="2026-02-22T21:04:47-05:00" level=debug msg="app state loaded"
Feb 22 21:04:47 volumio go-librespot[6499]: time="2026-02-22T21:04:47-05:00" level=debug msg="stored credentials not found"
Feb 22 21:04:47 volumio go-librespot[6499]: time="2026-02-22T21:04:47-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 22 21:04:47 volumio go-librespot[6499]: time="2026-02-22T21:04:47-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 22 21:04:47 volumio go-librespot[6499]: time="2026-02-22T21:04:47-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 22 21:04:47 volumio go-librespot[6499]: time="2026-02-22T21:04:47-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 22 21:04:47 volumio go-librespot[6499]: time="2026-02-22T21:04:47-05:00" level=info msg="zeroconf server listening on port 33425"
Feb 22 21:04:48 volumio go-librespot[6499]: time="2026-02-22T21:04:48-05:00" level=debug msg="obtained new client token: AAAnSXiYWctgbKHgQf/drnKMI5DGseLL24EVc5rKrEwSbaGZb08qhV9wjRujg8agVQPI7bo0M3CLV37ve6gIaipmwM4uRt93RZh2CDBK4ivv1H/981imPy3SO/dWL43wzfcToKGzSckDQpsMFGcSBu3YO2pmBgYDWJEqFcdczEt+ojVxhq2DafeHS4WwevTWV+pMaAvopMcckjs57i7c2lTJskM1EgfG9dnlVYMT5Ym7UxUl+BDaBPY="
Feb 22 21:04:48 volumio go-librespot[6499]: time="2026-02-22T21:04:48-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Feb 22 21:04:48 volumio go-librespot[6499]: time="2026-02-22T21:04:48-05:00" level=debug msg="completed keyexchange"
Feb 22 21:04:48 volumio go-librespot[6499]: time="2026-02-22T21:04:48-05:00" level=debug msg="completed challenge"
Feb 22 21:04:48 volumio go-librespot[6499]: time="2026-02-22T21:04:48-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 22 21:04:48 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 22 21:04:48 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 22 21:04:49 volumio volumio[1183]: info: Initializing connection to go-librespot Websocket
Feb 22 21:04:49 volumio volumio[1183]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 22 21:04:51 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Feb 22 21:04:51 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 670917.
Feb 22 21:04:51 volumio systemd[1]: Stopped go-librespot Daemon.
Feb 22 21:04:51 volumio systemd[1]: Started go-librespot Daemon.
Feb 22 21:04:51 volumio go-librespot[6529]: go-librespot daemon starting...
Feb 22 21:04:51 volumio go-librespot[6529]: time="2026-02-22T21:04:51-05:00" level=info msg="running go-librespot 0.4.0"
Feb 22 21:04:51 volumio go-librespot[6529]: time="2026-02-22T21:04:51-05:00" level=debug msg="app state loaded"
Feb 22 21:04:51 volumio go-librespot[6529]: time="2026-02-22T21:04:51-05:00" level=debug msg="stored credentials not found"
Feb 22 21:04:51 volumio go-librespot[6529]: time="2026-02-22T21:04:51-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 22 21:04:51 volumio go-librespot[6529]: time="2026-02-22T21:04:51-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 22 21:04:51 volumio go-librespot[6529]: time="2026-02-22T21:04:51-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 22 21:04:51 volumio go-librespot[6529]: time="2026-02-22T21:04:51-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 22 21:04:51 volumio go-librespot[6529]: time="2026-02-22T21:04:51-05:00" level=info msg="zeroconf server listening on port 36909"
Feb 22 21:04:51 volumio go-librespot[6529]: time="2026-02-22T21:04:51-05:00" level=debug msg="obtained new client token: AABdpnAjT2GNKaGq6+m7dQsWviEP2cNyDDvzW/7kjIQQxHvdjM6Jvab51c1XTZHnLh04TE0awM+x6pqa/LJrNRWiJnci0gVXI0Ahh1Otfy6oXmDAs5jcpiYgcMmjJqt1tIh89qp8c3Q2sTU8fWzbb5Eu8nkgJgS37sy0jh0q5nNWFAuMsdhHhq+MUFfPQEb0WWqSZ9ezJhAyj35+9x4Q6fNmvYLin2WvOb247fDljrIAN7+0HH2BaL/GOg=="
Feb 22 21:04:51 volumio go-librespot[6529]: time="2026-02-22T21:04:51-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Feb 22 21:04:51 volumio go-librespot[6529]: time="2026-02-22T21:04:51-05:00" level=debug msg="completed keyexchange"
Feb 22 21:04:51 volumio go-librespot[6529]: time="2026-02-22T21:04:51-05:00" level=debug msg="completed challenge"
Feb 22 21:04:51 volumio go-librespot[6529]: time="2026-02-22T21:04:51-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 22 21:04:51 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 22 21:04:51 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 22 21:04:52 volumio volumio[1183]: info: Initializing connection to go-librespot Websocket
Feb 22 21:04:52 volumio volumio[1183]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 22 21:04:55 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Feb 22 21:04:55 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 670918.
Feb 22 21:04:55 volumio systemd[1]: Stopped go-librespot Daemon.
Feb 22 21:04:55 volumio systemd[1]: Started go-librespot Daemon.
Feb 22 21:04:55 volumio go-librespot[6546]: go-librespot daemon starting...
Feb 22 21:04:55 volumio go-librespot[6546]: time="2026-02-22T21:04:55-05:00" level=info msg="running go-librespot 0.4.0"
Feb 22 21:04:55 volumio go-librespot[6546]: time="2026-02-22T21:04:55-05:00" level=debug msg="app state loaded"
Feb 22 21:04:55 volumio go-librespot[6546]: time="2026-02-22T21:04:55-05:00" level=debug msg="stored credentials not found"
Feb 22 21:04:55 volumio go-librespot[6546]: time="2026-02-22T21:04:55-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 22 21:04:55 volumio go-librespot[6546]: time="2026-02-22T21:04:55-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 22 21:04:55 volumio go-librespot[6546]: time="2026-02-22T21:04:55-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 22 21:04:55 volumio go-librespot[6546]: time="2026-02-22T21:04:55-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 22 21:04:55 volumio go-librespot[6546]: time="2026-02-22T21:04:55-05:00" level=info msg="zeroconf server listening on port 40519"
Feb 22 21:04:55 volumio go-librespot[6546]: time="2026-02-22T21:04:55-05:00" level=debug msg="obtained new client token: AACCavLzN40rOXveYJhbXOSFww2DfcwkBy2QUS/bBtQf1zlgeCtt867+Z2IAe3FqBg+C6IcBHyzdtwcf+u0xBFDne2gn4CG/STUg+2zdFCOw75B6xFAVUeQJYO1uA/rakh1jL4C24qm7xC/ntLghBR7WJKVjtPAj6FfURBSqn4AfYHnVo7yOMVVvn9++7gcKXbD3Mlr4427/23BeVOsUr30O0JputjBAF4OZPDG3AWVzYMChodIqKm44QQ=="
Feb 22 21:04:55 volumio go-librespot[6546]: time="2026-02-22T21:04:55-05:00" level=warning msg="failed to connect to AP ap-gue1.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.255.62:4070: connect: connection refused"
Feb 22 21:04:55 volumio go-librespot[6546]: time="2026-02-22T21:04:55-05:00" level=debug msg="connected to ap-gue1.spotify.com:443"
Feb 22 21:04:55 volumio volumio[1183]: info: Initializing connection to go-librespot Websocket
Feb 22 21:04:55 volumio go-librespot[6546]: time="2026-02-22T21:04:55-05:00" level=debug msg="new websocket client"
Feb 22 21:04:55 volumio volumio[1183]: info: Connection to go-librespot Websocket established
Feb 22 21:04:55 volumio go-librespot[6546]: time="2026-02-22T21:04:55-05:00" level=debug msg="completed keyexchange"
Feb 22 21:04:55 volumio go-librespot[6546]: time="2026-02-22T21:04:55-05:00" level=debug msg="completed challenge"
Feb 22 21:04:56 volumio go-librespot[6546]: time="2026-02-22T21:04:56-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 22 21:04:56 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 22 21:04:56 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 22 21:04:56 volumio volumio[1183]: info: Connection to go-librespot Websocket closed
Feb 22 21:04:58 volumio volumio[1183]: info: Getting Spotify volume
Feb 22 21:04:58 volumio volumio[1183]: (node:1183) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 22 21:04:58 volumio volumio[1183]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16)
Feb 22 21:04:58 volumio volumio[1183]: (node:1183) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 30073)
Feb 22 21:04:58 volumio volumio[1183]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8
Feb 22 21:04:58 volumio volumio[1183]: info: CoreCommandRouter::volumioGetState
Feb 22 21:04:58 volumio volumio[1183]: info: CorePlayQueue::getTrack 8
Feb 22 21:04:58 volumio volumio[1183]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
Feb 22 21:04:59 volumio volumio[1183]: info: Initializing connection to go-librespot Websocket
Feb 22 21:04:59 volumio volumio[1183]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 22 21:04:59 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Feb 22 21:04:59 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 670919.
Feb 22 21:04:59 volumio systemd[1]: Stopped go-librespot Daemon.
Feb 22 21:04:59 volumio systemd[1]: Started go-librespot Daemon.
Feb 22 21:04:59 volumio go-librespot[6565]: go-librespot daemon starting...
Feb 22 21:04:59 volumio go-librespot[6565]: time="2026-02-22T21:04:59-05:00" level=info msg="running go-librespot 0.4.0"
Feb 22 21:04:59 volumio go-librespot[6565]: time="2026-02-22T21:04:59-05:00" level=debug msg="app state loaded"
Feb 22 21:04:59 volumio go-librespot[6565]: time="2026-02-22T21:04:59-05:00" level=debug msg="stored credentials not found"
Feb 22 21:04:59 volumio go-librespot[6565]: time="2026-02-22T21:04:59-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 22 21:04:59 volumio go-librespot[6565]: time="2026-02-22T21:04:59-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 22 21:04:59 volumio go-librespot[6565]: time="2026-02-22T21:04:59-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 22 21:04:59 volumio go-librespot[6565]: time="2026-02-22T21:04:59-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 22 21:04:59 volumio go-librespot[6565]: time="2026-02-22T21:04:59-05:00" level=info msg="zeroconf server listening on port 37179"
Feb 22 21:04:59 volumio go-librespot[6565]: time="2026-02-22T21:04:59-05:00" level=debug msg="obtained new client token: AAAuZO7mZT8BrrMMF4HdmZ//mET0iKlvdgIjnzOdGeKmZ66xwt3oWxg9cwvhkhkKvu72bAU+pjlGeuS5Gyy6JhyavUdUMmx9l3UsCFKflq+TMClmQVwFpTjQw7x8/GIFqsMCUCcffQsD5yKY33/fn7mikw7NqdixUQOi7E29gNelQSfK1zs0TLJmlH+cW9dUhpUxBtQq/ZZWylO/hB4PnoDyW8E2AUtYfAbNgPDf2HglLDUjtOMbZ0A3ZQ=="
Feb 22 21:04:59 volumio go-librespot[6565]: time="2026-02-22T21:04:59-05:00" level=warning msg="failed to connect to AP ap-gue1.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.255.62:4070: connect: connection refused"
Feb 22 21:04:59 volumio go-librespot[6565]: time="2026-02-22T21:04:59-05:00" level=debug msg="connected to ap-gue1.spotify.com:443"
Feb 22 21:04:59 volumio go-librespot[6565]: time="2026-02-22T21:04:59-05:00" level=debug msg="completed keyexchange"
Feb 22 21:04:59 volumio go-librespot[6565]: time="2026-02-22T21:04:59-05:00" level=debug msg="completed challenge"
Feb 22 21:04:59 volumio go-librespot[6565]: time="2026-02-22T21:04:59-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 22 21:04:59 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 22 21:04:59 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 22 21:05:02 volumio volumio[1183]: info: Initializing connection to go-librespot Websocket
Feb 22 21:05:02 volumio volumio[1183]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 22 21:05:02 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Feb 22 21:05:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 670920.
Feb 22 21:05:02 volumio systemd[1]: Stopped go-librespot Daemon.
Feb 22 21:05:02 volumio systemd[1]: Started go-librespot Daemon.
Feb 22 21:05:02 volumio go-librespot[6595]: go-librespot daemon starting...
Feb 22 21:05:02 volumio go-librespot[6595]: time="2026-02-22T21:05:02-05:00" level=info msg="running go-librespot 0.4.0"
Feb 22 21:05:02 volumio go-librespot[6595]: time="2026-02-22T21:05:02-05:00" level=debug msg="app state loaded"
Feb 22 21:05:02 volumio go-librespot[6595]: time="2026-02-22T21:05:02-05:00" level=debug msg="stored credentials not found"
Feb 22 21:05:02 volumio go-librespot[6595]: time="2026-02-22T21:05:02-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 22 21:05:03 volumio go-librespot[6595]: time="2026-02-22T21:05:03-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 22 21:05:03 volumio go-librespot[6595]: time="2026-02-22T21:05:03-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 22 21:05:03 volumio go-librespot[6595]: time="2026-02-22T21:05:03-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 22 21:05:03 volumio go-librespot[6595]: time="2026-02-22T21:05:03-05:00" level=info msg="zeroconf server listening on port 33443"
Feb 22 21:05:03 volumio go-librespot[6595]: time="2026-02-22T21:05:03-05:00" level=debug msg="obtained new client token: AAC5pEc/tK9utNXlIoZaD7x/ZlTDkq6xn9ErSoXbBUyynsYHkPwjR2edcCNw8najZXSsMReHe9qor+hRy0MhNrmixDFGuNgSh1NnuUS9iLfjMgsps2uN2/1gItj8KXaaBGXAfmReIxwXUuiLxbdoIU4D9BzXNLNnyKUf+0GUSgcMcC5RxSub9G6DO4qqZ6fFmIy19lcJKQSfgmnDTP/tWkFenNEts2ZngmTv5cvp8JTT6e05Cjbi57A="
Feb 22 21:05:03 volumio go-librespot[6595]: time="2026-02-22T21:05:03-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Feb 22 21:05:03 volumio go-librespot[6595]: time="2026-02-22T21:05:03-05:00" level=debug msg="completed keyexchange"
Feb 22 21:05:03 volumio go-librespot[6595]: time="2026-02-22T21:05:03-05:00" level=debug msg="completed challenge"
Feb 22 21:05:03 volumio go-librespot[6595]: time="2026-02-22T21:05:03-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 22 21:05:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 22 21:05:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 22 21:05:05 volumio volumio[1183]: info: Initializing connection to go-librespot Websocket
Feb 22 21:05:05 volumio volumio[1183]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 22 21:05:06 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Feb 22 21:05:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 670921.
Feb 22 21:05:06 volumio systemd[1]: Stopped go-librespot Daemon.
Feb 22 21:05:06 volumio systemd[1]: Started go-librespot Daemon.
Feb 22 21:05:06 volumio go-librespot[6609]: go-librespot daemon starting...
Feb 22 21:05:06 volumio go-librespot[6609]: time="2026-02-22T21:05:06-05:00" level=info msg="running go-librespot 0.4.0"
Feb 22 21:05:06 volumio go-librespot[6609]: time="2026-02-22T21:05:06-05:00" level=debug msg="app state loaded"
Feb 22 21:05:06 volumio go-librespot[6609]: time="2026-02-22T21:05:06-05:00" level=debug msg="stored credentials not found"
Feb 22 21:05:06 volumio go-librespot[6609]: time="2026-02-22T21:05:06-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 22 21:05:06 volumio go-librespot[6609]: time="2026-02-22T21:05:06-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew1.spotify.com:80]"
Feb 22 21:05:06 volumio go-librespot[6609]: time="2026-02-22T21:05:06-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Feb 22 21:05:06 volumio go-librespot[6609]: time="2026-02-22T21:05:06-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Feb 22 21:05:06 volumio go-librespot[6609]: time="2026-02-22T21:05:06-05:00" level=info msg="zeroconf server listening on port 34277"
Feb 22 21:05:07 volumio go-librespot[6609]: time="2026-02-22T21:05:07-05:00" level=debug msg="obtained new client token: AADTU/Zuf6Flg5qvNekXu2LEO76pf/hhKNfLW2U6biMUSsf8ej4rknlKgJBUPK/TWDXPaAiw9aBkxWs09XKnYhSrhzUexWfbPsysPXwIVA+mxvuLJdFcvhssqX4ewZGCyyMul1O1Sm3EIxR6zAduSxog9LDsrTQc3sBkZgIKxBquR/KP9t0R1AWd4V2FBva8ZIqKg+D8OFcgo9oxRDzY8JyLlcf6gADoCdPqgeHI5219loNrTEL4eSw="
Feb 22 21:05:07 volumio go-librespot[6609]: time="2026-02-22T21:05:07-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Feb 22 21:05:07 volumio go-librespot[6609]: time="2026-02-22T21:05:07-05:00" level=debug msg="completed keyexchange"
Feb 22 21:05:07 volumio go-librespot[6609]: time="2026-02-22T21:05:07-05:00" level=debug msg="completed challenge"
Feb 22 21:05:07 volumio go-librespot[6609]: time="2026-02-22T21:05:07-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 22 21:05:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 22 21:05:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 22 21:05:08 volumio volumio[1183]: info: Initializing connection to go-librespot Websocket
Feb 22 21:05:08 volumio volumio[1183]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 22 21:05:10 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Feb 22 21:05:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 670922.
Feb 22 21:05:10 volumio systemd[1]: Stopped go-librespot Daemon.
Feb 22 21:05:10 volumio systemd[1]: Started go-librespot Daemon.
Feb 22 21:05:10 volumio go-librespot[6639]: go-librespot daemon starting...
Feb 22 21:05:10 volumio go-librespot[6639]: time="2026-02-22T21:05:10-05:00" level=info msg="running go-librespot 0.4.0"
Feb 22 21:05:10 volumio go-librespot[6639]: time="2026-02-22T21:05:10-05:00" level=debug msg="app state loaded"
Feb 22 21:05:10 volumio go-librespot[6639]: time="2026-02-22T21:05:10-05:00" level=debug msg="stored credentials not found"
Feb 22 21:05:10 volumio go-librespot[6639]: time="2026-02-22T21:05:10-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 22 21:05:10 volumio go-librespot[6639]: time="2026-02-22T21:05:10-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew1.spotify.com:80]"
Feb 22 21:05:10 volumio go-librespot[6639]: time="2026-02-22T21:05:10-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Feb 22 21:05:10 volumio go-librespot[6639]: time="2026-02-22T21:05:10-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Feb 22 21:05:10 volumio go-librespot[6639]: time="2026-02-22T21:05:10-05:00" level=info msg="zeroconf server listening on port 32869"
Feb 22 21:05:11 volumio volumio[1183]: info: Initializing connection to go-librespot Websocket
Feb 22 21:05:11 volumio go-librespot[6639]: time="2026-02-22T21:05:11-05:00" level=debug msg="new websocket client"
Feb 22 21:05:11 volumio volumio[1183]: info: Connection to go-librespot Websocket established
Feb 22 21:05:11 volumio go-librespot[6639]: time="2026-02-22T21:05:11-05:00" level=debug msg="obtained new client token: AAAqUuqxePdvwhDZ5GkDFjRbd9Lwe0J7ug7cVIHyR7X3pyUxbD/NYg+d67518j9387qE2WaXO1MOJuOeCdK9wWtVV49NQGe3Ta4WWqSTPR+0qyrfL/hi1OfiXIOfh2KnO9drVsIGN7tAIIXFH0EzUsKPahBKsiUyCE3CPhjsR5/OhwUBmPJZbOlCcuUnvx7L3So/eNs0QyG4ppGgcIvPTvCs0s+CoFE4xXp/Y/RT7i8rrHqI7elxsYI="
Feb 22 21:05:11 volumio go-librespot[6639]: time="2026-02-22T21:05:11-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Feb 22 21:05:11 volumio go-librespot[6639]: time="2026-02-22T21:05:11-05:00" level=debug msg="completed keyexchange"
Feb 22 21:05:11 volumio go-librespot[6639]: time="2026-02-22T21:05:11-05:00" level=debug msg="completed challenge"
Feb 22 21:05:11 volumio go-librespot[6639]: time="2026-02-22T21:05:11-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 22 21:05:11 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 22 21:05:11 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 22 21:05:11 volumio volumio[1183]: info: Connection to go-librespot Websocket closed
Feb 22 21:05:14 volumio volumio[1183]: info: Getting Spotify volume
Feb 22 21:05:14 volumio volumio[1183]: (node:1183) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 22 21:05:14 volumio volumio[1183]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16)
Feb 22 21:05:14 volumio volumio[1183]: (node:1183) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 30074)
Feb 22 21:05:14 volumio volumio[1183]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8
Feb 22 21:05:14 volumio volumio[1183]: info: CoreCommandRouter::volumioGetState
Feb 22 21:05:14 volumio volumio[1183]: info: CorePlayQueue::getTrack 8
Feb 22 21:05:14 volumio volumio[1183]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
Feb 22 21:05:14 volumio volumio[1183]: info: Initializing connection to go-librespot Websocket
Feb 22 21:05:14 volumio volumio[1183]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 22 21:05:14 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Feb 22 21:05:14 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 670923.
Feb 22 21:05:14 volumio systemd[1]: Stopped go-librespot Daemon.
Feb 22 21:05:14 volumio systemd[1]: Started go-librespot Daemon.
Feb 22 21:05:14 volumio go-librespot[6656]: go-librespot daemon starting...
Feb 22 21:05:14 volumio go-librespot[6656]: time="2026-02-22T21:05:14-05:00" level=info msg="running go-librespot 0.4.0"
Feb 22 21:05:14 volumio go-librespot[6656]: time="2026-02-22T21:05:14-05:00" level=debug msg="app state loaded"
Feb 22 21:05:14 volumio go-librespot[6656]: time="2026-02-22T21:05:14-05:00" level=debug msg="stored credentials not found"
Feb 22 21:05:14 volumio go-librespot[6656]: time="2026-02-22T21:05:14-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 22 21:05:14 volumio go-librespot[6656]: time="2026-02-22T21:05:14-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 22 21:05:14 volumio go-librespot[6656]: time="2026-02-22T21:05:14-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 22 21:05:14 volumio go-librespot[6656]: time="2026-02-22T21:05:14-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 22 21:05:14 volumio go-librespot[6656]: time="2026-02-22T21:05:14-05:00" level=info msg="zeroconf server listening on port 36409"
Feb 22 21:05:14 volumio go-librespot[6656]: time="2026-02-22T21:05:14-05:00" level=debug msg="obtained new client token: AAA3O9HkZApv5zbsgKzbRXDLTu8npVyYX8LX2SWua1+CIcbeHwM3Y/nEw1WvhH2te7/+4S/qWEpJFGUe5otTW9OUA01bDNbHpJ7QkL3JkpVM0srv8tua8bhTh7s5+e8X3Rf+oE8tmjjGIYvITnmTSydXhDMCBDVUCyFqwM9KhxF4+aQLcE9KLegMh+7Xtqew71KN7d6x7hOFiDgwk+uqxRp2I/IAcXXHpSgbY7EtKhEiwJ0g86aB2Gg6+w=="
Feb 22 21:05:14 volumio go-librespot[6656]: time="2026-02-22T21:05:14-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Feb 22 21:05:14 volumio go-librespot[6656]: time="2026-02-22T21:05:14-05:00" level=debug msg="completed keyexchange"
Feb 22 21:05:14 volumio go-librespot[6656]: time="2026-02-22T21:05:14-05:00" level=debug msg="completed challenge"
Feb 22 21:05:14 volumio go-librespot[6656]: time="2026-02-22T21:05:14-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 22 21:05:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 22 21:05:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 22 21:05:17 volumio volumio[1183]: info: Initializing connection to go-librespot Websocket
Feb 22 21:05:17 volumio volumio[1183]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 22 21:05:18 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Feb 22 21:05:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 670924.
Feb 22 21:05:18 volumio systemd[1]: Stopped go-librespot Daemon.
Feb 22 21:05:18 volumio systemd[1]: Started go-librespot Daemon.
Feb 22 21:05:18 volumio go-librespot[6676]: go-librespot daemon starting...
Feb 22 21:05:18 volumio go-librespot[6676]: time="2026-02-22T21:05:18-05:00" level=info msg="running go-librespot 0.4.0"
Feb 22 21:05:18 volumio go-librespot[6676]: time="2026-02-22T21:05:18-05:00" level=debug msg="app state loaded"
Feb 22 21:05:18 volumio go-librespot[6676]: time="2026-02-22T21:05:18-05:00" level=debug msg="stored credentials not found"
Feb 22 21:05:18 volumio go-librespot[6676]: time="2026-02-22T21:05:18-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 22 21:05:18 volumio go-librespot[6676]: time="2026-02-22T21:05:18-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew1.spotify.com:80]"
Feb 22 21:05:18 volumio go-librespot[6676]: time="2026-02-22T21:05:18-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Feb 22 21:05:18 volumio go-librespot[6676]: time="2026-02-22T21:05:18-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Feb 22 21:05:18 volumio go-librespot[6676]: time="2026-02-22T21:05:18-05:00" level=info msg="zeroconf server listening on port 45551"
Feb 22 21:05:18 volumio go-librespot[6676]: time="2026-02-22T21:05:18-05:00" level=debug msg="obtained new client token: AAC2p7sCB4IKAI+MiwnfpRjE9hTUd8GYcQCpYZ6geWPLPcflh3Z8MVfcEDbs4lgz5XCUF3M9Dv18SB0MMRkVw+6aNV+u06s3LKQS8Fk8tJbU9UeknXaQD01C0PeFYhQdKsJogtVmkDRkfx7QbyFE7AH1mbTcY6a5eHz6PT8x0HDPogohhaHlRQR5mVu29Kbgj1RFsabX+07kow4r8U2gLwLV7YEuNRqOgEwZp9holl+LlFFjHqe/e71T+g=="
Feb 22 21:05:18 volumio go-librespot[6676]: time="2026-02-22T21:05:18-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Feb 22 21:05:18 volumio go-librespot[6676]: time="2026-02-22T21:05:18-05:00" level=debug msg="completed keyexchange"
Feb 22 21:05:18 volumio go-librespot[6676]: time="2026-02-22T21:05:18-05:00" level=debug msg="completed challenge"
Feb 22 21:05:18 volumio go-librespot[6676]: time="2026-02-22T21:05:18-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 22 21:05:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 22 21:05:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 22 21:05:20 volumio volumio[1183]: info: Initializing connection to go-librespot Websocket
Feb 22 21:05:20 volumio volumio[1183]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 22 21:05:21 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Feb 22 21:05:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 670925.
Feb 22 21:05:21 volumio systemd[1]: Stopped go-librespot Daemon.
Feb 22 21:05:21 volumio systemd[1]: Started go-librespot Daemon.
Feb 22 21:05:21 volumio go-librespot[6705]: go-librespot daemon starting...
Feb 22 21:05:21 volumio go-librespot[6705]: time="2026-02-22T21:05:21-05:00" level=info msg="running go-librespot 0.4.0"
Feb 22 21:05:21 volumio go-librespot[6705]: time="2026-02-22T21:05:21-05:00" level=debug msg="app state loaded"
Feb 22 21:05:21 volumio go-librespot[6705]: time="2026-02-22T21:05:21-05:00" level=debug msg="stored credentials not found"
Feb 22 21:05:21 volumio go-librespot[6705]: time="2026-02-22T21:05:21-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 22 21:05:22 volumio go-librespot[6705]: time="2026-02-22T21:05:22-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 22 21:05:22 volumio go-librespot[6705]: time="2026-02-22T21:05:22-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 22 21:05:22 volumio go-librespot[6705]: time="2026-02-22T21:05:22-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 22 21:05:22 volumio go-librespot[6705]: time="2026-02-22T21:05:22-05:00" level=info msg="zeroconf server listening on port 42499"
Feb 22 21:05:22 volumio go-librespot[6705]: time="2026-02-22T21:05:22-05:00" level=debug msg="obtained new client token: AACq+arl25NRxOkEuVPdYK4QY4++iSh/uKj/45pYQTpcY4LToPKANtKU2juJ0wzSE6qOZ93YhE+VHNfq7id1y7F0YQfGFnyTTgTrhcqiJLdaTNXrI+tH3/OEmeT5yo0dYJBK4EE4R6vt6D1f03jYt3YD6ddrzRJXLR5ADKHIJNctDAXBbIjq2xGIYRXHC9bwJOi34Sv/vujxO7nJw37ERZ2RmuvgrlGkYKDqPojv6+UMHk6HGJnBLtw="
Feb 22 21:05:22 volumio go-librespot[6705]: time="2026-02-22T21:05:22-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Feb 22 21:05:22 volumio go-librespot[6705]: time="2026-02-22T21:05:22-05:00" level=debug msg="completed keyexchange"
Feb 22 21:05:22 volumio go-librespot[6705]: time="2026-02-22T21:05:22-05:00" level=debug msg="completed challenge"
Feb 22 21:05:22 volumio go-librespot[6705]: time="2026-02-22T21:05:22-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 22 21:05:22 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 22 21:05:22 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 22 21:05:23 volumio volumio[1183]: info: Initializing connection to go-librespot Websocket
Feb 22 21:05:23 volumio volumio[1183]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 22 21:05:25 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Feb 22 21:05:25 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 670926.
Feb 22 21:05:25 volumio systemd[1]: Stopped go-librespot Daemon.
Feb 22 21:05:25 volumio systemd[1]: Started go-librespot Daemon.
Feb 22 21:05:25 volumio go-librespot[6722]: go-librespot daemon starting...
Feb 22 21:05:25 volumio go-librespot[6722]: time="2026-02-22T21:05:25-05:00" level=info msg="running go-librespot 0.4.0"
Feb 22 21:05:25 volumio go-librespot[6722]: time="2026-02-22T21:05:25-05:00" level=debug msg="app state loaded"
Feb 22 21:05:25 volumio go-librespot[6722]: time="2026-02-22T21:05:25-05:00" level=debug msg="stored credentials not found"
Feb 22 21:05:25 volumio go-librespot[6722]: time="2026-02-22T21:05:25-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 22 21:05:25 volumio go-librespot[6722]: time="2026-02-22T21:05:25-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 22 21:05:25 volumio go-librespot[6722]: time="2026-02-22T21:05:25-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 22 21:05:25 volumio go-librespot[6722]: time="2026-02-22T21:05:25-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 22 21:05:25 volumio go-librespot[6722]: time="2026-02-22T21:05:25-05:00" level=info msg="zeroconf server listening on port 39449"
Feb 22 21:05:25 volumio go-librespot[6722]: time="2026-02-22T21:05:25-05:00" level=debug msg="obtained new client token: AAB8rTXPV+EsIX1a2kQGEbrxpULEF5Oxf6pbc4Zon2OsDjfvX5+2qkFLOlxFsCCoFnavGcZIVC6yoWhTM0B9vCjPcMXczjvSHtpmdU4TaSVSRPqLX+m+G5CZXzpTriy/9qceTLkDorrvvTSvpWHa4uaYYOsGykYsnbLlJNAQZWNy0ZIi+lZz06E8yNZcJHEMAttvoE4t7liwBe8RF2V7oRp16a4WS5PPzlZtubknE+yHwsV1rVm52kyjYw=="
Feb 22 21:05:26 volumio go-librespot[6722]: time="2026-02-22T21:05:26-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Feb 22 21:05:26 volumio go-librespot[6722]: time="2026-02-22T21:05:26-05:00" level=debug msg="completed keyexchange"
Feb 22 21:05:26 volumio go-librespot[6722]: time="2026-02-22T21:05:26-05:00" level=debug msg="completed challenge"
Feb 22 21:05:26 volumio go-librespot[6722]: time="2026-02-22T21:05:26-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 22 21:05:26 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 22 21:05:26 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 22 21:05:26 volumio volumio[1183]: info: Initializing connection to go-librespot Websocket
Feb 22 21:05:26 volumio volumio[1183]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 22 21:05:29 volumio volumio[1183]: info: Initializing connection to go-librespot Websocket
Feb 22 21:05:29 volumio volumio[1183]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 22 21:05:29 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Feb 22 21:05:29 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 670927.
Feb 22 21:05:29 volumio systemd[1]: Stopped go-librespot Daemon.
Feb 22 21:05:29 volumio systemd[1]: Started go-librespot Daemon.
Feb 22 21:05:29 volumio go-librespot[6752]: go-librespot daemon starting...
Feb 22 21:05:29 volumio go-librespot[6752]: time="2026-02-22T21:05:29-05:00" level=info msg="running go-librespot 0.4.0"
Feb 22 21:05:29 volumio go-librespot[6752]: time="2026-02-22T21:05:29-05:00" level=debug msg="app state loaded"
Feb 22 21:05:29 volumio go-librespot[6752]: time="2026-02-22T21:05:29-05:00" level=debug msg="stored credentials not found"
Feb 22 21:05:29 volumio go-librespot[6752]: time="2026-02-22T21:05:29-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 22 21:05:29 volumio go-librespot[6752]: time="2026-02-22T21:05:29-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 22 21:05:29 volumio go-librespot[6752]: time="2026-02-22T21:05:29-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 22 21:05:29 volumio go-librespot[6752]: time="2026-02-22T21:05:29-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 22 21:05:29 volumio go-librespot[6752]: time="2026-02-22T21:05:29-05:00" level=info msg="zeroconf server listening on port 42767"
Feb 22 21:05:29 volumio go-librespot[6752]: time="2026-02-22T21:05:29-05:00" level=debug msg="obtained new client token: AAD5xsP86vTMY6kXurEKAs2lxm1HnryccsF+MXvel90/5AD9SoQZ4nRdIH0V3mvv093Doq/Qm6A1Grk6xKZGRK6f9kP+aqJmza0oePp4Px/Ac6Wqu5vNwG9RbB6weKo+K6af7IuYzpWvZCcukxcUfiBDGe27UCR/PqiA5Csuc1cPGXlpZlOzAh1nbm4Rx6utcTuLwxbWYKtCG/mMmFZ9SRl3H6SkcpOx85f915GtS0Lp6EAdLUN1zMyIpg=="
Feb 22 21:05:29 volumio go-librespot[6752]: time="2026-02-22T21:05:29-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Feb 22 21:05:29 volumio go-librespot[6752]: time="2026-02-22T21:05:29-05:00" level=debug msg="completed keyexchange"
Feb 22 21:05:29 volumio go-librespot[6752]: time="2026-02-22T21:05:29-05:00" level=debug msg="completed challenge"
Feb 22 21:05:29 volumio go-librespot[6752]: time="2026-02-22T21:05:29-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 22 21:05:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 22 21:05:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 22 21:05:32 volumio volumio[1183]: info: Initializing connection to go-librespot Websocket
Feb 22 21:05:32 volumio volumio[1183]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 22 21:05:33 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Feb 22 21:05:33 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 670928.
Feb 22 21:05:33 volumio systemd[1]: Stopped go-librespot Daemon.
Feb 22 21:05:33 volumio systemd[1]: Started go-librespot Daemon.
Feb 22 21:05:33 volumio go-librespot[6769]: go-librespot daemon starting...
Feb 22 21:05:33 volumio go-librespot[6769]: time="2026-02-22T21:05:33-05:00" level=info msg="running go-librespot 0.4.0"
Feb 22 21:05:33 volumio go-librespot[6769]: time="2026-02-22T21:05:33-05:00" level=debug msg="app state loaded"
Feb 22 21:05:33 volumio go-librespot[6769]: time="2026-02-22T21:05:33-05:00" level=debug msg="stored credentials not found"
Feb 22 21:05:33 volumio go-librespot[6769]: time="2026-02-22T21:05:33-05:00" level=info msg="api server listening on 127.0.0.1:9879"
Feb 22 21:05:33 volumio go-librespot[6769]: time="2026-02-22T21:05:33-05:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Feb 22 21:05:33 volumio go-librespot[6769]: time="2026-02-22T21:05:33-05:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Feb 22 21:05:33 volumio go-librespot[6769]: time="2026-02-22T21:05:33-05:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Feb 22 21:05:33 volumio go-librespot[6769]: time="2026-02-22T21:05:33-05:00" level=info msg="zeroconf server listening on port 44505"
Feb 22 21:05:33 volumio go-librespot[6769]: time="2026-02-22T21:05:33-05:00" level=debug msg="obtained new client token: AADN6++VSpriJkDx0duTMznLyNCwa3hlt63V88cFAI8/2zofM056mCIPwVOPIyj28fhEaJAs76ShpYay6huhu2USCJGgsLMrdLukfjfGCfgvgSpV607wd4zDacDBd4vVTvDuXpcjh+8aeU/uWffihDzaUP+4n/JrqlOycro2KqFoPXO70CLExgcBL8KRk5SBuZA9xyBZsdFw+JHfDRWdA26LJrL6CDADeTK2Vm2AH/0mi9IqPCm8MG4L1Q=="
Feb 22 21:05:33 volumio go-librespot[6769]: time="2026-02-22T21:05:33-05:00" level=debug msg="connected to ap-gue1.spotify.com:4070"
Feb 22 21:05:33 volumio go-librespot[6769]: time="2026-02-22T21:05:33-05:00" level=debug msg="completed keyexchange"
Feb 22 21:05:33 volumio go-librespot[6769]: time="2026-02-22T21:05:33-05:00" level=debug msg="completed challenge"
Feb 22 21:05:33 volumio go-librespot[6769]: time="2026-02-22T21:05:33-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials "
Feb 22 21:05:33 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Feb 22 21:05:33 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Feb 22 21:05:35 volumio volumio[1183]: info: Initializing connection to go-librespot Websocket
Feb 22 21:05:35 volumio volumio[1183]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Feb 22 21:05:36 volumio volumio[1183]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Feb 22 21:05:36 volumio volumio[1183]: TypeError: Cannot read property 'slice' of null
Feb 22 21:05:36 volumio volumio[1183]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41)
Feb 22 21:05:36 volumio volumio[1183]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7)
Feb 22 21:05:36 volumio volumio[1183]: at Object.onceWrapper (events.js:422:26)
Feb 22 21:05:36 volumio volumio[1183]: at ClientRequest.emit (events.js:315:20)
Feb 22 21:05:36 volumio volumio[1183]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:641:27)
Feb 22 21:05:36 volumio volumio[1183]: at HTTPParser.parserOnHeadersComplete (_http_common.js:126:17)
Feb 22 21:05:36 volumio volumio[1183]: at HTTPParser.execute ()
Feb 22 21:05:36 volumio volumio[1183]: at TLSSocket.socketOnData (_http_client.js:509:22)
Feb 22 21:05:36 volumio volumio[1183]: at TLSSocket.emit (events.js:315:20)
Feb 22 21:05:36 volumio volumio[1183]: at addChunk (internal/streams/readable.js:309:12)
Feb 22 21:05:36 volumio volumio[1183]: at readableAddChunk (internal/streams/readable.js:284:9)
Feb 22 21:05:36 volumio volumio[1183]: at TLSSocket.Readable.push (internal/streams/readable.js:223:10)
Feb 22 21:05:36 volumio volumio[1183]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:188:23)
Feb 22 21:05:36 volumio volumio[1183]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Feb 22 21:05:36 volumio sudo[6791]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-22 21:04
Feb 22 21:05:36 volumio sudo[6791]: pam_unix(sudo:session): session opened for user root by (uid=0)
PRETTY_NAME="Raspbian GNU/Linux 10 (buster)"
NAME="Raspbian GNU/Linux"
VERSION_ID="10"
VERSION="10 (buster)"
VERSION_CODENAME=buster
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="b43c85cc0006d3f1efecba101311ec96e334d1fd"
VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd"
VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2"
VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 05:43:24 PM CET"
VOLUMIO_VERSION="3.886"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="15d0241b0cd805792809f3ddf4a0fc4e"