-- Logs begin at Mon 2026-06-01 15:14:43 CEST, end at Mon 2026-06-01 17:49:21 CEST. -- Jun 01 17:48:00 volumio systemd[1]: Started go-librespot Daemon. Jun 01 17:48:00 volumio go-librespot[21756]: go-librespot daemon starting... Jun 01 17:48:00 volumio go-librespot[21756]: time="2026-06-01T17:48:00+02:00" level=info msg="running go-librespot 0.2.0" Jun 01 17:48:00 volumio go-librespot[21756]: time="2026-06-01T17:48:00+02:00" level=debug msg="app state loaded" Jun 01 17:48:00 volumio go-librespot[21756]: time="2026-06-01T17:48:00+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 01 17:48:00 volumio go-librespot[21756]: time="2026-06-01T17:48:00+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Jun 01 17:48:00 volumio go-librespot[21756]: time="2026-06-01T17:48:00+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Jun 01 17:48:00 volumio go-librespot[21756]: time="2026-06-01T17:48:00+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Jun 01 17:48:00 volumio go-librespot[21756]: time="2026-06-01T17:48:00+02:00" level=info msg="zeroconf server listening on port 34517" Jun 01 17:48:00 volumio go-librespot[21756]: time="2026-06-01T17:48:00+02:00" level=debug msg="obtained new client token: AAA5T18pE09Px0oyiOfclymc6sz6ouL2SyhJpaA8Pnod3LdCSq6eh8a+awKSPOiK1y43VWXUI6/BqBoJF1Z1+WdQ3ZBioBsGWlSh+iOayO5vppAhBnEYUAhCONRIj0qyxHLj6TqKMU9xGTKPyjyNkWYq3N9dsJiaVgaN0/X7eW7odgCBYgKR91GZZPzYnRXu9xVa6E9LYV9HWOYEA8hsvccQr+ssMTXEgPpITucCRuZzLDV7KTmX9hxcWA==" Jun 01 17:48:00 volumio go-librespot[21756]: time="2026-06-01T17:48:00+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Jun 01 17:48:00 volumio volumio[1887]: info: Initializing connection to go-librespot Websocket Jun 01 17:48:00 volumio go-librespot[21756]: time="2026-06-01T17:48:00+02:00" level=debug msg="new websocket client" Jun 01 17:48:00 volumio volumio[1887]: info: Connection to go-librespot Websocket established Jun 01 17:48:00 volumio go-librespot[21756]: time="2026-06-01T17:48:00+02:00" level=debug msg="completed keyexchange" Jun 01 17:48:00 volumio go-librespot[21756]: time="2026-06-01T17:48:00+02:00" level=debug msg="completed challenge" Jun 01 17:48:00 volumio go-librespot[21756]: time="2026-06-01T17:48:00+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Jun 01 17:48:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 01 17:48:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 01 17:48:00 volumio volumio[1887]: info: Connection to go-librespot Websocket closed Jun 01 17:48:02 volumio volumio[1887]: info: CoreCommandRouter::volumioGetState Jun 01 17:48:02 volumio volumio[1887]: info: CorePlayQueue::getTrack 0 Jun 01 17:48:03 volumio volumio[1887]: info: Getting Spotify volume Jun 01 17:48:03 volumio volumio[1887]: (node:1887) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 01 17:48:03 volumio volumio[1887]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Jun 01 17:48:03 volumio volumio[1887]: (node:1887) 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: 354) Jun 01 17:48:03 volumio volumio[1887]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jun 01 17:48:03 volumio volumio[1887]: info: CoreCommandRouter::volumioGetState Jun 01 17:48:03 volumio volumio[1887]: info: CorePlayQueue::getTrack 0 Jun 01 17:48:03 volumio volumio[1887]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 Jun 01 17:48:03 volumio volumio[1887]: info: Initializing connection to go-librespot Websocket Jun 01 17:48:03 volumio volumio[1887]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 01 17:48:03 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 01 17:48:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2466. Jun 01 17:48:03 volumio systemd[1]: Stopped go-librespot Daemon. Jun 01 17:48:03 volumio systemd[1]: Started go-librespot Daemon. Jun 01 17:48:03 volumio go-librespot[21764]: go-librespot daemon starting... Jun 01 17:48:03 volumio go-librespot[21764]: time="2026-06-01T17:48:03+02:00" level=info msg="running go-librespot 0.2.0" Jun 01 17:48:03 volumio go-librespot[21764]: time="2026-06-01T17:48:03+02:00" level=debug msg="app state loaded" Jun 01 17:48:03 volumio go-librespot[21764]: time="2026-06-01T17:48:03+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 01 17:48:04 volumio go-librespot[21764]: time="2026-06-01T17:48:04+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Jun 01 17:48:04 volumio go-librespot[21764]: time="2026-06-01T17:48:04+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jun 01 17:48:04 volumio go-librespot[21764]: time="2026-06-01T17:48:04+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jun 01 17:48:04 volumio go-librespot[21764]: time="2026-06-01T17:48:04+02:00" level=info msg="zeroconf server listening on port 41725" Jun 01 17:48:04 volumio go-librespot[21764]: time="2026-06-01T17:48:04+02:00" level=debug msg="obtained new client token: AADHCiwI2UCQu0XHD50hqIz6Par2ix3Zv8+AgtyEwIh0aiWJvnSMQ+4Q8dysE3nicV8WKqf+g5YTTEsQ03hl3js/X5WqaxgbcmGVrVaSLUNVvgI3q2Exi9zf1qku8JCxG68+O6dUEphHbbJd6kfzUYPRnv+pzO3/71FHRR/BkxsyhgQEcotO3Ix8q1sXZ7diFwh2Lvm3j5BCRuA89wZ1APhbBUWy4VbLyZ0+rS9GjYVnXVZpHyvkq8g=" Jun 01 17:48:04 volumio go-librespot[21764]: time="2026-06-01T17:48:04+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Jun 01 17:48:04 volumio go-librespot[21764]: time="2026-06-01T17:48:04+02:00" level=debug msg="completed keyexchange" Jun 01 17:48:04 volumio go-librespot[21764]: time="2026-06-01T17:48:04+02:00" level=debug msg="completed challenge" Jun 01 17:48:04 volumio go-librespot[21764]: time="2026-06-01T17:48:04+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Jun 01 17:48:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 01 17:48:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 01 17:48:06 volumio volumio[1887]: info: Initializing connection to go-librespot Websocket Jun 01 17:48:06 volumio volumio[1887]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 01 17:48:07 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 01 17:48:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2467. Jun 01 17:48:07 volumio systemd[1]: Stopped go-librespot Daemon. Jun 01 17:48:07 volumio systemd[1]: Started go-librespot Daemon. Jun 01 17:48:07 volumio go-librespot[21789]: go-librespot daemon starting... Jun 01 17:48:07 volumio go-librespot[21789]: time="2026-06-01T17:48:07+02:00" level=info msg="running go-librespot 0.2.0" Jun 01 17:48:07 volumio go-librespot[21789]: time="2026-06-01T17:48:07+02:00" level=debug msg="app state loaded" Jun 01 17:48:07 volumio go-librespot[21789]: time="2026-06-01T17:48:07+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 01 17:48:07 volumio go-librespot[21789]: time="2026-06-01T17:48:07+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Jun 01 17:48:07 volumio go-librespot[21789]: time="2026-06-01T17:48:07+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jun 01 17:48:07 volumio go-librespot[21789]: time="2026-06-01T17:48:07+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jun 01 17:48:07 volumio go-librespot[21789]: time="2026-06-01T17:48:07+02:00" level=info msg="zeroconf server listening on port 41945" Jun 01 17:48:07 volumio go-librespot[21789]: time="2026-06-01T17:48:07+02:00" level=debug msg="obtained new client token: AADjYMdqE/S3V1F7RAyFwxoC2e85+1FN/3JCRW17tL14NXCNj86+IARzWtrCfxuP0/gzderEopsh7gonmNvIyYjkhsVkgdcy7142SzB66VH3JsK1Tbw6gOIE+zyxjZGwJnR5LceI97u2fhFKlgyLqOai6JfoX4jDoff1Yb6PEfTvgs5+m+EFt3AvK5yfxRxWwz6/aivJpXHh3rayPNThEgRxXgswcgSJ4AXFug6lESCUg8TvzUu2gHj4WQ==" Jun 01 17:48:07 volumio go-librespot[21789]: time="2026-06-01T17:48:07+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Jun 01 17:48:08 volumio go-librespot[21789]: time="2026-06-01T17:48:08+02:00" level=debug msg="completed keyexchange" Jun 01 17:48:08 volumio go-librespot[21789]: time="2026-06-01T17:48:08+02:00" level=debug msg="completed challenge" Jun 01 17:48:08 volumio go-librespot[21789]: time="2026-06-01T17:48:08+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Jun 01 17:48:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 01 17:48:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 01 17:48:09 volumio volumio[1887]: info: Initializing connection to go-librespot Websocket Jun 01 17:48:09 volumio volumio[1887]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 01 17:48:11 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 01 17:48:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2468. Jun 01 17:48:11 volumio systemd[1]: Stopped go-librespot Daemon. Jun 01 17:48:11 volumio systemd[1]: Started go-librespot Daemon. Jun 01 17:48:11 volumio go-librespot[21797]: go-librespot daemon starting... Jun 01 17:48:11 volumio go-librespot[21797]: time="2026-06-01T17:48:11+02:00" level=info msg="running go-librespot 0.2.0" Jun 01 17:48:11 volumio go-librespot[21797]: time="2026-06-01T17:48:11+02:00" level=debug msg="app state loaded" Jun 01 17:48:11 volumio go-librespot[21797]: time="2026-06-01T17:48:11+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 01 17:48:11 volumio go-librespot[21797]: time="2026-06-01T17:48:11+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Jun 01 17:48:11 volumio go-librespot[21797]: time="2026-06-01T17:48:11+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jun 01 17:48:11 volumio go-librespot[21797]: time="2026-06-01T17:48:11+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jun 01 17:48:11 volumio go-librespot[21797]: time="2026-06-01T17:48:11+02:00" level=info msg="zeroconf server listening on port 45655" Jun 01 17:48:11 volumio go-librespot[21797]: time="2026-06-01T17:48:11+02:00" level=debug msg="obtained new client token: AAAWTLPPsiAKb02MnkQJvrTkDxcoDGpCx919cs4nGTO70i9u25Tl0CYOK/LnXMBR9nFR31rcKqDvf1IYIW8l/zFGQlho6YvNCj2s37kbc0SBBIXv5UKbn8f91G3tzLNa3U9y5RnlBX9Mi/LCaZOIo/kSXJJyOG7CY7hbBFC2iZKoJ2QVa5K/PNxz/k4RXivSMksz53LWfBZcGoEkyfYsatiPJGvHr7s1fFOrjgLvZK5r6/MBvlTbbRjQJg==" Jun 01 17:48:11 volumio go-librespot[21797]: time="2026-06-01T17:48:11+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Jun 01 17:48:11 volumio go-librespot[21797]: time="2026-06-01T17:48:11+02:00" level=debug msg="completed keyexchange" Jun 01 17:48:11 volumio go-librespot[21797]: time="2026-06-01T17:48:11+02:00" level=debug msg="completed challenge" Jun 01 17:48:11 volumio go-librespot[21797]: time="2026-06-01T17:48:11+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Jun 01 17:48:11 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 01 17:48:11 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 01 17:48:12 volumio volumio[1887]: info: CoreCommandRouter::volumioGetState Jun 01 17:48:12 volumio volumio[1887]: info: CorePlayQueue::getTrack 0 Jun 01 17:48:12 volumio volumio[1887]: info: Listing playlists Jun 01 17:48:12 volumio volumio[1887]: info: Listing playlists Jun 01 17:48:12 volumio volumio[1887]: info: Initializing connection to go-librespot Websocket Jun 01 17:48:12 volumio volumio[1887]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 01 17:48:14 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 01 17:48:14 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2469. Jun 01 17:48:14 volumio systemd[1]: Stopped go-librespot Daemon. Jun 01 17:48:15 volumio systemd[1]: Started go-librespot Daemon. Jun 01 17:48:15 volumio go-librespot[21820]: go-librespot daemon starting... Jun 01 17:48:15 volumio go-librespot[21820]: time="2026-06-01T17:48:15+02:00" level=info msg="running go-librespot 0.2.0" Jun 01 17:48:15 volumio go-librespot[21820]: time="2026-06-01T17:48:15+02:00" level=debug msg="app state loaded" Jun 01 17:48:15 volumio go-librespot[21820]: time="2026-06-01T17:48:15+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 01 17:48:15 volumio go-librespot[21820]: time="2026-06-01T17:48:15+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Jun 01 17:48:15 volumio go-librespot[21820]: time="2026-06-01T17:48:15+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jun 01 17:48:15 volumio go-librespot[21820]: time="2026-06-01T17:48:15+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jun 01 17:48:15 volumio go-librespot[21820]: time="2026-06-01T17:48:15+02:00" level=info msg="zeroconf server listening on port 42067" Jun 01 17:48:15 volumio go-librespot[21820]: time="2026-06-01T17:48:15+02:00" level=debug msg="obtained new client token: AADpXJ6gzUUZQ/WEpEhHHZ5IjIDeFruoUxev4uuvewAZaGZNOBlUN+7wYtrHwLGKTG0Lf5vuGe0wHu54GB0P0hDiIjFUUqIklglLfBWNC8jICbRBFAR/mmlBY6SPKH4qEMRg4kdf+t9MTBxgLJYES3EQuSQMhf2biWqVgXzEzKhSQsiGNGFpykHoako723jlozpSK8Cguq7ELGolkLL5Qqdhvnk0uhVkfnnEYV7+UMaqQ4EJJMbNGZJZpA==" Jun 01 17:48:15 volumio go-librespot[21820]: time="2026-06-01T17:48:15+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Jun 01 17:48:15 volumio go-librespot[21820]: time="2026-06-01T17:48:15+02:00" level=debug msg="completed keyexchange" Jun 01 17:48:15 volumio go-librespot[21820]: time="2026-06-01T17:48:15+02:00" level=debug msg="completed challenge" Jun 01 17:48:15 volumio volumio[1887]: info: Initializing connection to go-librespot Websocket Jun 01 17:48:15 volumio go-librespot[21820]: time="2026-06-01T17:48:15+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Jun 01 17:48:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 01 17:48:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 01 17:48:15 volumio volumio[1887]: info: Error connecting to go-librespot Websocket: Error: write ECONNRESET Jun 01 17:48:18 volumio volumio[1887]: info: Initializing connection to go-librespot Websocket Jun 01 17:48:18 volumio volumio[1887]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 01 17:48:18 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 01 17:48:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2470. Jun 01 17:48:18 volumio systemd[1]: Stopped go-librespot Daemon. Jun 01 17:48:18 volumio systemd[1]: Started go-librespot Daemon. Jun 01 17:48:18 volumio go-librespot[21828]: go-librespot daemon starting... Jun 01 17:48:18 volumio go-librespot[21828]: time="2026-06-01T17:48:18+02:00" level=info msg="running go-librespot 0.2.0" Jun 01 17:48:18 volumio go-librespot[21828]: time="2026-06-01T17:48:18+02:00" level=debug msg="app state loaded" Jun 01 17:48:18 volumio go-librespot[21828]: time="2026-06-01T17:48:18+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 01 17:48:19 volumio go-librespot[21828]: time="2026-06-01T17:48:19+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Jun 01 17:48:19 volumio go-librespot[21828]: time="2026-06-01T17:48:19+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jun 01 17:48:19 volumio go-librespot[21828]: time="2026-06-01T17:48:19+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jun 01 17:48:19 volumio go-librespot[21828]: time="2026-06-01T17:48:19+02:00" level=info msg="zeroconf server listening on port 35243" Jun 01 17:48:21 volumio volumio[1887]: info: Initializing connection to go-librespot Websocket Jun 01 17:48:21 volumio go-librespot[21828]: time="2026-06-01T17:48:21+02:00" level=debug msg="new websocket client" Jun 01 17:48:21 volumio volumio[1887]: info: Connection to go-librespot Websocket established Jun 01 17:48:22 volumio volumio[1887]: info: CoreCommandRouter::volumioGetState Jun 01 17:48:22 volumio volumio[1887]: info: CorePlayQueue::getTrack 0 Jun 01 17:48:24 volumio go-librespot[21828]: time="2026-06-01T17:48:24+02:00" level=debug msg="obtained new client token: AABwK8Y/ht+InDi7dWFsurfYhpeihfmTVTH8ssWe3rwR42mK0FZcD5GFUl5uOcWiziqEROiSZw1EAjnwOP6MKXrqx9j/tpbZGH86MOdjR7PeziVbFfQiYPI+dy12H4xfcJJGB/qb1iGGjXOnPZ5t/7MLNFhO76HXrsGadyPPWbfAVMPoxLsXpo8kE5LQxPOKgBRjDipwg0VfEdtTD9f4PqlUPd6LSriKSrAhQWuGE0gnOhT2faB/Awk=" Jun 01 17:48:24 volumio go-librespot[21828]: time="2026-06-01T17:48:24+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Jun 01 17:48:24 volumio go-librespot[21828]: time="2026-06-01T17:48:24+02:00" level=debug msg="completed keyexchange" Jun 01 17:48:24 volumio go-librespot[21828]: time="2026-06-01T17:48:24+02:00" level=debug msg="completed challenge" Jun 01 17:48:24 volumio go-librespot[21828]: time="2026-06-01T17:48:24+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Jun 01 17:48:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 01 17:48:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 01 17:48:24 volumio volumio[1887]: info: Connection to go-librespot Websocket closed Jun 01 17:48:24 volumio volumio[1887]: info: Getting Spotify volume Jun 01 17:48:24 volumio volumio[1887]: (node:1887) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 01 17:48:24 volumio volumio[1887]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Jun 01 17:48:24 volumio volumio[1887]: (node:1887) 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: 355) Jun 01 17:48:24 volumio volumio[1887]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jun 01 17:48:24 volumio volumio[1887]: info: CoreCommandRouter::volumioGetState Jun 01 17:48:24 volumio volumio[1887]: info: CorePlayQueue::getTrack 0 Jun 01 17:48:24 volumio volumio[1887]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 Jun 01 17:48:27 volumio volumio[1887]: info: Initializing connection to go-librespot Websocket Jun 01 17:48:27 volumio volumio[1887]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 01 17:48:27 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 01 17:48:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2471. Jun 01 17:48:27 volumio systemd[1]: Stopped go-librespot Daemon. Jun 01 17:48:27 volumio systemd[1]: Started go-librespot Daemon. Jun 01 17:48:27 volumio go-librespot[21853]: go-librespot daemon starting... Jun 01 17:48:27 volumio go-librespot[21853]: time="2026-06-01T17:48:27+02:00" level=info msg="running go-librespot 0.2.0" Jun 01 17:48:27 volumio go-librespot[21853]: time="2026-06-01T17:48:27+02:00" level=debug msg="app state loaded" Jun 01 17:48:27 volumio go-librespot[21853]: time="2026-06-01T17:48:27+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 01 17:48:27 volumio go-librespot[21853]: time="2026-06-01T17:48:27+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Jun 01 17:48:27 volumio go-librespot[21853]: time="2026-06-01T17:48:27+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jun 01 17:48:27 volumio go-librespot[21853]: time="2026-06-01T17:48:27+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jun 01 17:48:27 volumio go-librespot[21853]: time="2026-06-01T17:48:27+02:00" level=info msg="zeroconf server listening on port 33339" Jun 01 17:48:27 volumio go-librespot[21853]: time="2026-06-01T17:48:27+02:00" level=debug msg="obtained new client token: AADJ91OgjlhBBU7SuSQXm3d0Jl1wke/fyTDq8dsic07JcZSLiSFQ7Z02UamQNTKmZDrwG0ImSlmqHRJvoonWauEHqcjXZNr4+ymPvx3dHTk55dttoongJjbYPaMFvy5VYSd6ISErLiF4Me7K3lZ6d/FmVLfH0uF4nXpbSUwynQLE+gtlSzXbP6NbZhrptJlaVRi1aGHq2cKOZn9tinq3bXGgb0Yw+0q8BKk1hDOiEmkEHpE8ufKMfC0P6Q==" Jun 01 17:48:28 volumio go-librespot[21853]: time="2026-06-01T17:48:28+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Jun 01 17:48:28 volumio go-librespot[21853]: time="2026-06-01T17:48:28+02:00" level=debug msg="completed keyexchange" Jun 01 17:48:28 volumio go-librespot[21853]: time="2026-06-01T17:48:28+02:00" level=debug msg="completed challenge" Jun 01 17:48:28 volumio go-librespot[21853]: time="2026-06-01T17:48:28+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Jun 01 17:48:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 01 17:48:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 01 17:48:30 volumio volumio[1887]: info: Initializing connection to go-librespot Websocket Jun 01 17:48:30 volumio volumio[1887]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 01 17:48:31 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 01 17:48:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2472. Jun 01 17:48:31 volumio systemd[1]: Stopped go-librespot Daemon. Jun 01 17:48:31 volumio systemd[1]: Started go-librespot Daemon. Jun 01 17:48:31 volumio go-librespot[21861]: go-librespot daemon starting... Jun 01 17:48:31 volumio go-librespot[21861]: time="2026-06-01T17:48:31+02:00" level=info msg="running go-librespot 0.2.0" Jun 01 17:48:31 volumio go-librespot[21861]: time="2026-06-01T17:48:31+02:00" level=debug msg="app state loaded" Jun 01 17:48:31 volumio go-librespot[21861]: time="2026-06-01T17:48:31+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 01 17:48:31 volumio go-librespot[21861]: time="2026-06-01T17:48:31+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Jun 01 17:48:31 volumio go-librespot[21861]: time="2026-06-01T17:48:31+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jun 01 17:48:31 volumio go-librespot[21861]: time="2026-06-01T17:48:31+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jun 01 17:48:31 volumio go-librespot[21861]: time="2026-06-01T17:48:31+02:00" level=info msg="zeroconf server listening on port 44491" Jun 01 17:48:31 volumio go-librespot[21861]: time="2026-06-01T17:48:31+02:00" level=debug msg="obtained new client token: AACaNkZxfPQ8xNslkd6rb3I3+FQ8JrQOM8VYCup4m1851PtaGbh3GfX5issL0xAQmY7ClYZwPkqeTPvBVW3mbmHSpIhJM7X0H380m48CaT3n2/t1qBLcsVv6ievB7u5BQSWbebRga6Gf+FPbpNXtpwymVTosM7G58qADpneymPp50S3NG6T/W+Zi0mqj9RWEbaUjhhqWnPqGySnB8yeiKl8Ji8hirS/S/mtdQpLOWm51UYv1jbK4i3icfQ==" Jun 01 17:48:31 volumio go-librespot[21861]: time="2026-06-01T17:48:31+02:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.1.133:4070: connect: connection refused" Jun 01 17:48:31 volumio go-librespot[21861]: time="2026-06-01T17:48:31+02:00" level=debug msg="connected to ap-gew4.spotify.com:443" Jun 01 17:48:32 volumio volumio[1887]: info: CoreCommandRouter::volumioGetState Jun 01 17:48:32 volumio volumio[1887]: info: CorePlayQueue::getTrack 0 Jun 01 17:48:32 volumio volumio[1887]: info: Listing playlists Jun 01 17:48:32 volumio volumio[1887]: info: Listing playlists Jun 01 17:48:33 volumio volumio[1887]: info: Initializing connection to go-librespot Websocket Jun 01 17:48:33 volumio go-librespot[21861]: time="2026-06-01T17:48:33+02:00" level=debug msg="new websocket client" Jun 01 17:48:33 volumio volumio[1887]: info: Connection to go-librespot Websocket established Jun 01 17:48:36 volumio volumio[1887]: info: Getting Spotify volume Jun 01 17:48:36 volumio volumio[1887]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jun 01 17:48:36 volumio volumio[1887]: info: CoreCommandRouter::volumioGetState Jun 01 17:48:36 volumio volumio[1887]: info: CorePlayQueue::getTrack 0 Jun 01 17:48:36 volumio volumio[1887]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 Jun 01 17:48:42 volumio volumio[1887]: info: CoreCommandRouter::volumioGetState Jun 01 17:48:42 volumio volumio[1887]: info: CorePlayQueue::getTrack 0 Jun 01 17:48:52 volumio volumio[1887]: info: CoreCommandRouter::volumioGetState Jun 01 17:48:52 volumio volumio[1887]: info: CorePlayQueue::getTrack 0 Jun 01 17:48:52 volumio volumio[1887]: info: Listing playlists Jun 01 17:48:52 volumio volumio[1887]: info: Listing playlists Jun 01 17:49:02 volumio volumio[1887]: info: CoreCommandRouter::volumioGetState Jun 01 17:49:02 volumio volumio[1887]: info: CorePlayQueue::getTrack 0 Jun 01 17:49:13 volumio wpa_supplicant[751]: wlan0: CTRL-EVENT-DISCONNECTED bssid=80:af:ca:5f:a8:7c reason=0 locally_generated=1 Jun 01 17:49:13 volumio wpa_supplicant[751]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Jun 01 17:49:13 volumio wpa_supplicant[751]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=DE Jun 01 17:49:13 volumio dhcpcd[762]: wlan0: carrier lost Jun 01 17:49:13 volumio avahi-daemon[532]: Withdrawing address record for 192.168.178.45 on wlan0. Jun 01 17:49:13 volumio avahi-daemon[532]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.178.45. Jun 01 17:49:13 volumio avahi-daemon[532]: Interface wlan0.IPv4 no longer relevant for mDNS. Jun 01 17:49:13 volumio dhcpcd[762]: wlan0: deleting route to 192.168.178.0/24 Jun 01 17:49:13 volumio dhcpcd[762]: wlan0: deleting default route via 192.168.178.1 Jun 01 17:49:13 volumio volumio[1887]: info: Discovery: A device disappeared from network Jun 01 17:49:13 volumio volumio[1887]: info: Discovery: Device volumio disappeared from network Jun 01 17:49:14 volumio volumio[1887]: info: Volumio Network Manager: Network status updated: 0 Jun 01 17:49:14 volumio wpa_supplicant[751]: wlan0: Trying to associate with SSID 'SCTRA2' Jun 01 17:49:15 volumio ntpd[647]: Deleting interface #4 wlan0, 192.168.178.45#123, interface stats: received=857, sent=865, dropped=0, active_time=14440 secs Jun 01 17:49:15 volumio ntpd[647]: 91.212.242.20 local addr 192.168.178.45 -> Jun 01 17:49:15 volumio ntpd[647]: 85.115.212.254 local addr 192.168.178.45 -> Jun 01 17:49:15 volumio ntpd[647]: 194.146.251.102 local addr 192.168.178.45 -> Jun 01 17:49:15 volumio ntpd[647]: 46.175.224.7 local addr 192.168.178.45 -> Jun 01 17:49:15 volumio ntpd[647]: 162.159.200.1 local addr 192.168.178.45 -> Jun 01 17:49:15 volumio ntpd[647]: 193.70.94.182 local addr 192.168.178.45 -> Jun 01 17:49:15 volumio ntpd[647]: 77.252.226.67 local addr 192.168.178.45 -> Jun 01 17:49:15 volumio ntpd[647]: 162.159.200.123 local addr 192.168.178.45 -> Jun 01 17:49:15 volumio ntpd[647]: 194.146.251.101 local addr 192.168.178.45 -> Jun 01 17:49:15 volumio ntpd[647]: 194.146.251.100 local addr 192.168.178.45 -> Jun 01 17:49:16 volumio wpa_supplicant[751]: wlan0: Associated with 80:af:ca:5f:a8:7c Jun 01 17:49:16 volumio wpa_supplicant[751]: wlan0: CTRL-EVENT-CONNECTED - Connection to 80:af:ca:5f:a8:7c completed [id=0 id_str=] Jun 01 17:49:16 volumio dhcpcd[762]: wlan0: carrier acquired Jun 01 17:49:16 volumio wpa_supplicant[751]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jun 01 17:49:16 volumio wpa_supplicant[751]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE Jun 01 17:49:16 volumio dhcpcd[762]: wlan0: IAID dd:d4:d8:94 Jun 01 17:49:16 volumio dhcpcd[762]: wlan0: soliciting a DHCP lease Jun 01 17:49:16 volumio dhcpcd[762]: wlan0: offered 192.168.178.45 from 192.168.178.1 Jun 01 17:49:16 volumio dhcpcd[762]: wlan0: probing address 192.168.178.45/24 Jun 01 17:49:17 volumio dhcpcd[762]: wlan0: soliciting an IPv6 router Jun 01 17:49:20 volumio volumio[1887]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 01 17:49:20 volumio volumio[1887]: Error: send ENETUNREACH 255.255.255.255:3483 Jun 01 17:49:20 volumio volumio[1887]: at doSend (dgram.js:692:16) Jun 01 17:49:20 volumio volumio[1887]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Jun 01 17:49:20 volumio volumio[1887]: at afterDns (dgram.js:638:5) Jun 01 17:49:20 volumio volumio[1887]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Jun 01 17:49:20 volumio volumio[1887]: errno: -101, Jun 01 17:49:20 volumio volumio[1887]: code: 'ENETUNREACH', Jun 01 17:49:20 volumio volumio[1887]: syscall: 'send', Jun 01 17:49:20 volumio volumio[1887]: address: '255.255.255.255', Jun 01 17:49:20 volumio volumio[1887]: port: 3483 Jun 01 17:49:20 volumio volumio[1887]: } Jun 01 17:49:20 volumio volumio[1887]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 01 17:49:21 volumio dhcpcd[762]: wlan0: leased 192.168.178.45 for 2080974 seconds Jun 01 17:49:21 volumio avahi-daemon[532]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.178.45. Jun 01 17:49:21 volumio dhcpcd[762]: wlan0: adding route to 192.168.178.0/24 Jun 01 17:49:21 volumio dhcpcd[762]: wlan0: adding default route via 192.168.178.1 Jun 01 17:49:21 volumio avahi-daemon[532]: New relevant interface wlan0.IPv4 for mDNS. Jun 01 17:49:21 volumio avahi-daemon[532]: Registering new address record for 192.168.178.45 on wlan0.IPv4. Jun 01 17:49:21 volumio sudo[22047]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-06-01 17:48 Jun 01 17:49:21 volumio sudo[22047]: 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="cc50ad4a2058d01de272214eb33827883bd2b7d8" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="502750e5d54ecf269d25125b622bfabc030fb553" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 12 Nov 2025 06:58:02 PM CET" VOLUMIO_VERSION="3.874" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4ae6504ed6f22e44c01a6e93b16badbd"