-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Sun 2026-02-22 19:06:27 CET. -- Feb 22 19:05:00 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 22 19:05:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 55. Feb 22 19:05:00 volumio systemd[1]: Stopped go-librespot Daemon. Feb 22 19:05:00 volumio systemd[1]: Started go-librespot Daemon. Feb 22 19:05:00 volumio go-librespot[2269]: go-librespot daemon starting... Feb 22 19:05:00 volumio go-librespot[2269]: time="2026-02-22T19:05:00+01:00" level=info msg="running go-librespot 0.2.0" Feb 22 19:05:00 volumio go-librespot[2269]: time="2026-02-22T19:05:00+01:00" level=debug msg="app state loaded" Feb 22 19:05:00 volumio go-librespot[2269]: time="2026-02-22T19:05:00+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 22 19:05:01 volumio go-librespot[2269]: time="2026-02-22T19:05:01+01: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]" Feb 22 19:05:01 volumio go-librespot[2269]: time="2026-02-22T19:05:01+01: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]" Feb 22 19:05:01 volumio go-librespot[2269]: time="2026-02-22T19:05:01+01: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]" Feb 22 19:05:01 volumio go-librespot[2269]: time="2026-02-22T19:05:01+01:00" level=info msg="zeroconf server listening on port 34905" Feb 22 19:05:01 volumio go-librespot[2269]: time="2026-02-22T19:05:01+01:00" level=debug msg="obtained new client token: AAAnv8aIuR0U/8na/2aerg3oiUqyWbTrW7a8KKdzBOKG/hNZdXQXNeXsGncB+jU1j49NBAMmnQKEECzNtgXNCClQBKhXXL2HLgCGjF/nspFT0u2TTQbeoXK2YylVdRzVvqy9dN5drlG+NNKAC/VEWEyISCuPiRokXPZ0J3OV2+l0T1Q/VMaaUghytS6bsmPNu3Zl3wWF1pka468vj5NhgYdbFaDC9m9/XoZwhFrljLhN7Q2+eL6Bsqs=" Feb 22 19:05:01 volumio go-librespot[2269]: time="2026-02-22T19:05:01+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 22 19:05:01 volumio go-librespot[2269]: time="2026-02-22T19:05:01+01:00" level=debug msg="completed keyexchange" Feb 22 19:05:01 volumio go-librespot[2269]: time="2026-02-22T19:05:01+01:00" level=debug msg="completed challenge" Feb 22 19:05:01 volumio go-librespot[2269]: time="2026-02-22T19:05:01+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 22 19:05:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 22 19:05:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 22 19:05:02 volumio volumio[880]: info: Initializing connection to go-librespot Websocket Feb 22 19:05:02 volumio volumio[880]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 22 19:05:04 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 22 19:05:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 56. Feb 22 19:05:04 volumio systemd[1]: Stopped go-librespot Daemon. Feb 22 19:05:04 volumio systemd[1]: Started go-librespot Daemon. Feb 22 19:05:04 volumio go-librespot[2277]: go-librespot daemon starting... Feb 22 19:05:04 volumio go-librespot[2277]: time="2026-02-22T19:05:04+01:00" level=info msg="running go-librespot 0.2.0" Feb 22 19:05:04 volumio go-librespot[2277]: time="2026-02-22T19:05:04+01:00" level=debug msg="app state loaded" Feb 22 19:05:04 volumio go-librespot[2277]: time="2026-02-22T19:05:04+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 22 19:05:04 volumio go-librespot[2277]: time="2026-02-22T19:05:04+01: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]" Feb 22 19:05:04 volumio go-librespot[2277]: time="2026-02-22T19:05:04+01: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]" Feb 22 19:05:04 volumio go-librespot[2277]: time="2026-02-22T19:05:04+01: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]" Feb 22 19:05:04 volumio go-librespot[2277]: time="2026-02-22T19:05:04+01:00" level=info msg="zeroconf server listening on port 35405" Feb 22 19:05:04 volumio go-librespot[2277]: time="2026-02-22T19:05:04+01:00" level=debug msg="obtained new client token: AABGVhx8FerINLgoXw94gD+DKPNVVJ9YyBBpvIcxatZJByXu8xVm8U+rdu8Iniwtsi663TvFSL0YSOPJaRn90thkEaylX5Fz/X9rkUWJ9aRjDyneM99hNze2v8g9BH9c4TBQjw6d1TW9VyJDSFMHQq0Hh+AGSnQ0ziEujOeSWgKRrDnR9UFGKpmZuWpMb3Indu42F52ILYH9xc0Vg3o/ct1Q1QyGORKdyzXX44VFd+Qt3FYBMN/Cuey49g==" Feb 22 19:05:05 volumio go-librespot[2277]: time="2026-02-22T19:05:05+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 22 19:05:05 volumio go-librespot[2277]: time="2026-02-22T19:05:05+01:00" level=debug msg="completed keyexchange" Feb 22 19:05:05 volumio go-librespot[2277]: time="2026-02-22T19:05:05+01:00" level=debug msg="completed challenge" Feb 22 19:05:05 volumio go-librespot[2277]: time="2026-02-22T19:05:05+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 22 19:05:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 22 19:05:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 22 19:05:05 volumio volumio[880]: info: Initializing connection to go-librespot Websocket Feb 22 19:05:05 volumio volumio[880]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 22 19:05:08 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 22 19:05:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 57. Feb 22 19:05:08 volumio systemd[1]: Stopped go-librespot Daemon. Feb 22 19:05:08 volumio systemd[1]: Started go-librespot Daemon. Feb 22 19:05:08 volumio go-librespot[2299]: go-librespot daemon starting... Feb 22 19:05:08 volumio go-librespot[2299]: time="2026-02-22T19:05:08+01:00" level=info msg="running go-librespot 0.2.0" Feb 22 19:05:08 volumio go-librespot[2299]: time="2026-02-22T19:05:08+01:00" level=debug msg="app state loaded" Feb 22 19:05:08 volumio go-librespot[2299]: time="2026-02-22T19:05:08+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 22 19:05:08 volumio go-librespot[2299]: time="2026-02-22T19:05:08+01: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]" Feb 22 19:05:08 volumio go-librespot[2299]: time="2026-02-22T19:05:08+01: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]" Feb 22 19:05:08 volumio go-librespot[2299]: time="2026-02-22T19:05:08+01: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]" Feb 22 19:05:08 volumio go-librespot[2299]: time="2026-02-22T19:05:08+01:00" level=info msg="zeroconf server listening on port 38043" Feb 22 19:05:08 volumio go-librespot[2299]: time="2026-02-22T19:05:08+01:00" level=debug msg="obtained new client token: AABwEPlWNsqYS2eLUFLtf9+zMmaJTBlJ3aH2WqDqJo15s6uM0CqBCNSg/CjnyKTAhrcp0wAYwiL+Zlsoz40ENWzSsqLqCRc2nudEG4JCvUF8M1EJp7735Mf+8BBISRJbjMkycX3Cx9cGKYrsSEWK1qfbI/zAEV5ryrqZkbG1nFGaa+PIBb4Cjgiq92DwgZsWoZI3RgFcfNsUL3ooQvilzeli5Esg4bk+HU+6dLjZwzyzdF/mm2zNe4iiew==" Feb 22 19:05:08 volumio go-librespot[2299]: time="2026-02-22T19:05:08+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 22 19:05:08 volumio go-librespot[2299]: time="2026-02-22T19:05:08+01:00" level=debug msg="completed keyexchange" Feb 22 19:05:08 volumio go-librespot[2299]: time="2026-02-22T19:05:08+01:00" level=debug msg="completed challenge" Feb 22 19:05:08 volumio go-librespot[2299]: time="2026-02-22T19:05:08+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 22 19:05:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 22 19:05:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 22 19:05:08 volumio volumio[880]: info: Initializing connection to go-librespot Websocket Feb 22 19:05:08 volumio volumio[880]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 22 19:05:09 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Feb 22 19:05:09 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Feb 22 19:05:11 volumio volumio[880]: info: Initializing connection to go-librespot Websocket Feb 22 19:05:11 volumio volumio[880]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 22 19:05:11 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 22 19:05:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 58. Feb 22 19:05:12 volumio systemd[1]: Stopped go-librespot Daemon. Feb 22 19:05:12 volumio systemd[1]: Started go-librespot Daemon. Feb 22 19:05:12 volumio go-librespot[2307]: go-librespot daemon starting... Feb 22 19:05:12 volumio go-librespot[2307]: time="2026-02-22T19:05:12+01:00" level=info msg="running go-librespot 0.2.0" Feb 22 19:05:12 volumio go-librespot[2307]: time="2026-02-22T19:05:12+01:00" level=debug msg="app state loaded" Feb 22 19:05:12 volumio go-librespot[2307]: time="2026-02-22T19:05:12+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 22 19:05:12 volumio go-librespot[2307]: time="2026-02-22T19:05:12+01: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]" Feb 22 19:05:12 volumio go-librespot[2307]: time="2026-02-22T19:05:12+01: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]" Feb 22 19:05:12 volumio go-librespot[2307]: time="2026-02-22T19:05:12+01: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]" Feb 22 19:05:12 volumio go-librespot[2307]: time="2026-02-22T19:05:12+01:00" level=info msg="zeroconf server listening on port 37703" Feb 22 19:05:12 volumio go-librespot[2307]: time="2026-02-22T19:05:12+01:00" level=debug msg="obtained new client token: AABEsJPpAjg9Z8V5TfeG75t6TWnej+AyitZFjh6IbEIwfbpv+vltVW3s3S+nzK60xKmnS7CJFmSklQw4pNZkRJJRnbQ4jqt0JSkyjrHUqbiwsofhj1EPkkdi9F7BpPUOnHlvFR2w2BanqwFaY2In75wpJA82uM+7r4+pTZatyJ6lonYMWwr4yj5IYEO6eL1kEJ7OITg6xiz8DTKAJXhFuVMosudSKlhsyZXw/ShCXUbCN9XkdKvcYpdPgA==" Feb 22 19:05:12 volumio go-librespot[2307]: time="2026-02-22T19:05:12+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 22 19:05:12 volumio go-librespot[2307]: time="2026-02-22T19:05:12+01:00" level=debug msg="completed keyexchange" Feb 22 19:05:12 volumio go-librespot[2307]: time="2026-02-22T19:05:12+01:00" level=debug msg="completed challenge" Feb 22 19:05:12 volumio go-librespot[2307]: time="2026-02-22T19:05:12+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 22 19:05:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 22 19:05:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 22 19:05:14 volumio volumio[880]: info: Initializing connection to go-librespot Websocket Feb 22 19:05:14 volumio volumio[880]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 22 19:05:15 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 22 19:05:15 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 59. Feb 22 19:05:15 volumio systemd[1]: Stopped go-librespot Daemon. Feb 22 19:05:15 volumio systemd[1]: Started go-librespot Daemon. Feb 22 19:05:15 volumio go-librespot[2367]: go-librespot daemon starting... Feb 22 19:05:15 volumio go-librespot[2367]: time="2026-02-22T19:05:15+01:00" level=info msg="running go-librespot 0.2.0" Feb 22 19:05:15 volumio go-librespot[2367]: time="2026-02-22T19:05:15+01:00" level=debug msg="app state loaded" Feb 22 19:05:15 volumio go-librespot[2367]: time="2026-02-22T19:05:15+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 22 19:05:16 volumio go-librespot[2367]: time="2026-02-22T19:05:16+01: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]" Feb 22 19:05:16 volumio go-librespot[2367]: time="2026-02-22T19:05:16+01: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]" Feb 22 19:05:16 volumio go-librespot[2367]: time="2026-02-22T19:05:16+01: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]" Feb 22 19:05:16 volumio go-librespot[2367]: time="2026-02-22T19:05:16+01:00" level=info msg="zeroconf server listening on port 46259" Feb 22 19:05:16 volumio go-librespot[2367]: time="2026-02-22T19:05:16+01:00" level=debug msg="obtained new client token: AABau7w2HmS5myvdusJD/pSUGSFT32lWrUOBGTkLZvYOIyYz1wEdaUn9to4rCMVVGDHfxQgc7kFwCWqAaxOjAhkatv7aWa6wKYUOsmvlB6tjHpg+RTxVTM57HTK4MUWOQgpH7+wzDk6ptj9aGDYeO24VkcC2ubU5JIa7oi6uIs4hlzMlmZ0mJ8Hy+NI8H42zeEsquo6Kilcc+i1ihPJLLHaEkMGbdYYtSY3h6BkUqOXD+YzIcva5cpQ=" Feb 22 19:05:16 volumio go-librespot[2367]: time="2026-02-22T19:05:16+01: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" Feb 22 19:05:16 volumio go-librespot[2367]: time="2026-02-22T19:05:16+01:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:443, retrying with a different AP" error="dial tcp 34.158.1.133:443: connect: connection refused" Feb 22 19:05:16 volumio go-librespot[2367]: time="2026-02-22T19:05:16+01:00" level=debug msg="connected to ap-gew4.spotify.com:80" Feb 22 19:05:16 volumio go-librespot[2367]: time="2026-02-22T19:05:16+01:00" level=debug msg="completed keyexchange" Feb 22 19:05:16 volumio go-librespot[2367]: time="2026-02-22T19:05:16+01:00" level=debug msg="completed challenge" Feb 22 19:05:16 volumio go-librespot[2367]: time="2026-02-22T19:05:16+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 22 19:05:16 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 22 19:05:16 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 22 19:05:17 volumio volumio[880]: info: Initializing connection to go-librespot Websocket Feb 22 19:05:17 volumio volumio[880]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 22 19:05:19 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 22 19:05:19 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 60. Feb 22 19:05:19 volumio systemd[1]: Stopped go-librespot Daemon. Feb 22 19:05:19 volumio systemd[1]: Started go-librespot Daemon. Feb 22 19:05:19 volumio go-librespot[2380]: go-librespot daemon starting... Feb 22 19:05:19 volumio go-librespot[2380]: time="2026-02-22T19:05:19+01:00" level=info msg="running go-librespot 0.2.0" Feb 22 19:05:19 volumio go-librespot[2380]: time="2026-02-22T19:05:19+01:00" level=debug msg="app state loaded" Feb 22 19:05:19 volumio go-librespot[2380]: time="2026-02-22T19:05:19+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 22 19:05:19 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Feb 22 19:05:19 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Feb 22 19:05:19 volumio volumio[880]: info: Listing playlists Feb 22 19:05:19 volumio volumio[880]: info: Listing playlists Feb 22 19:05:20 volumio go-librespot[2380]: time="2026-02-22T19:05:20+01: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]" Feb 22 19:05:20 volumio go-librespot[2380]: time="2026-02-22T19:05:20+01: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]" Feb 22 19:05:20 volumio go-librespot[2380]: time="2026-02-22T19:05:20+01: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]" Feb 22 19:05:20 volumio go-librespot[2380]: time="2026-02-22T19:05:20+01:00" level=info msg="zeroconf server listening on port 35539" Feb 22 19:05:20 volumio go-librespot[2380]: time="2026-02-22T19:05:20+01:00" level=debug msg="obtained new client token: AACjPiasBMW6+yygYMg9m0LHjRVkEsvIvYYnSbZhKwlI+9QrsRo+v5fHTWkTjUCeUl+K4Ylz6SVrrCl4ujAnTUTnuFBYYeUzRKFrzNmQD2T6dkhE5m5md95qGA5JJopMuvFtYQCk36+WFFNG/OQjccDh4BQf+hDuQNgqWcT0nwbSEMGS8cS+wu/CqsCtLBdb4aGmz7iAo3vjQrdlzYANkMyKI69gDE7Q6lOBHpkl1H6AUtCQPGyicX4=" Feb 22 19:05:20 volumio go-librespot[2380]: time="2026-02-22T19:05:20+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 22 19:05:20 volumio go-librespot[2380]: time="2026-02-22T19:05:20+01:00" level=debug msg="completed keyexchange" Feb 22 19:05:20 volumio go-librespot[2380]: time="2026-02-22T19:05:20+01:00" level=debug msg="completed challenge" Feb 22 19:05:20 volumio go-librespot[2380]: time="2026-02-22T19:05:20+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 22 19:05:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 22 19:05:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 22 19:05:20 volumio volumio[880]: info: Initializing connection to go-librespot Websocket Feb 22 19:05:21 volumio volumio[880]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 22 19:05:23 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 22 19:05:23 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 61. Feb 22 19:05:23 volumio systemd[1]: Stopped go-librespot Daemon. Feb 22 19:05:23 volumio systemd[1]: Started go-librespot Daemon. Feb 22 19:05:23 volumio go-librespot[2388]: go-librespot daemon starting... Feb 22 19:05:23 volumio go-librespot[2388]: time="2026-02-22T19:05:23+01:00" level=info msg="running go-librespot 0.2.0" Feb 22 19:05:23 volumio go-librespot[2388]: time="2026-02-22T19:05:23+01:00" level=debug msg="app state loaded" Feb 22 19:05:23 volumio go-librespot[2388]: time="2026-02-22T19:05:23+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 22 19:05:23 volumio go-librespot[2388]: time="2026-02-22T19:05:23+01: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]" Feb 22 19:05:23 volumio go-librespot[2388]: time="2026-02-22T19:05:23+01: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]" Feb 22 19:05:23 volumio go-librespot[2388]: time="2026-02-22T19:05:23+01: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]" Feb 22 19:05:23 volumio go-librespot[2388]: time="2026-02-22T19:05:23+01:00" level=info msg="zeroconf server listening on port 34627" Feb 22 19:05:23 volumio go-librespot[2388]: time="2026-02-22T19:05:23+01:00" level=debug msg="obtained new client token: AACi9TanYwKzkTyhaFNJUul8f2JhpTT23hqDcEPjEdso7v1rib6PlCVPC6VX31G8/d3DH0e5h9JJdtP1yRW+uyMbXXSAFoQssLHdl7s5LRy5R+W0O2gRFG0tiDjI2iGDsV1BTxasVyfboNjyfPm4AJ4FsIeCrIN7z6pvkUmc8jDf4J10DXd/aQpw9/aNDRhXNbP3UNP3OFUSmPsqqE9KTMinArXnn2D+IHA1f7APhbyj0SGGriN3dJWGBg==" Feb 22 19:05:24 volumio volumio[880]: info: Initializing connection to go-librespot Websocket Feb 22 19:05:24 volumio go-librespot[2388]: time="2026-02-22T19:05:24+01:00" level=debug msg="new websocket client" Feb 22 19:05:24 volumio volumio[880]: info: Connection to go-librespot Websocket established Feb 22 19:05:24 volumio go-librespot[2388]: time="2026-02-22T19:05:24+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 22 19:05:24 volumio go-librespot[2388]: time="2026-02-22T19:05:24+01:00" level=debug msg="completed keyexchange" Feb 22 19:05:24 volumio go-librespot[2388]: time="2026-02-22T19:05:24+01:00" level=debug msg="completed challenge" Feb 22 19:05:24 volumio go-librespot[2388]: time="2026-02-22T19:05:24+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 22 19:05:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 22 19:05:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 22 19:05:24 volumio volumio[880]: info: Connection to go-librespot Websocket closed Feb 22 19:05:27 volumio volumio[880]: info: Getting Spotify volume Feb 22 19:05:27 volumio volumio[880]: (node:880) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 22 19:05:27 volumio volumio[880]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Feb 22 19:05:27 volumio volumio[880]: (node:880) 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: 7) Feb 22 19:05:27 volumio volumio[880]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Feb 22 19:05:27 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Feb 22 19:05:27 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Feb 22 19:05:27 volumio volumio[880]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 22 19:05:27 volumio volumio[880]: info: Initializing connection to go-librespot Websocket Feb 22 19:05:27 volumio volumio[880]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 22 19:05:27 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 22 19:05:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 62. Feb 22 19:05:27 volumio systemd[1]: Stopped go-librespot Daemon. Feb 22 19:05:27 volumio systemd[1]: Started go-librespot Daemon. Feb 22 19:05:27 volumio go-librespot[2410]: go-librespot daemon starting... Feb 22 19:05:27 volumio go-librespot[2410]: time="2026-02-22T19:05:27+01:00" level=info msg="running go-librespot 0.2.0" Feb 22 19:05:27 volumio go-librespot[2410]: time="2026-02-22T19:05:27+01:00" level=debug msg="app state loaded" Feb 22 19:05:27 volumio go-librespot[2410]: time="2026-02-22T19:05:27+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 22 19:05:27 volumio go-librespot[2410]: time="2026-02-22T19:05:27+01: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]" Feb 22 19:05:27 volumio go-librespot[2410]: time="2026-02-22T19:05:27+01: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]" Feb 22 19:05:27 volumio go-librespot[2410]: time="2026-02-22T19:05:27+01: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]" Feb 22 19:05:27 volumio go-librespot[2410]: time="2026-02-22T19:05:27+01:00" level=info msg="zeroconf server listening on port 34329" Feb 22 19:05:27 volumio go-librespot[2410]: time="2026-02-22T19:05:27+01:00" level=debug msg="obtained new client token: AAANLiuieDDTA67wKL3THNy3VfsXojxsVPm7SVbZtCtmk1rGQTDAEKV95rJiscNIHWJxVRp2XESssitsLK7nb+4sPBUXd7KHwIONxbiaXE1E/QcK6lw4RH0usLyRQexcGwMNvjsTBZproqVXoDF0HUikpbX+szatTlrl9T9x/67MNKjT4BAutqrS4dpBlXQ0CO5BYIktUdV5bbdXtxcXH2hcCeBthaQW/lzj1rTuJAvW/rZoZT1p9FQZbw==" Feb 22 19:05:27 volumio go-librespot[2410]: time="2026-02-22T19:05:27+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 22 19:05:27 volumio go-librespot[2410]: time="2026-02-22T19:05:27+01:00" level=debug msg="completed keyexchange" Feb 22 19:05:27 volumio go-librespot[2410]: time="2026-02-22T19:05:27+01:00" level=debug msg="completed challenge" Feb 22 19:05:27 volumio go-librespot[2410]: time="2026-02-22T19:05:27+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 22 19:05:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 22 19:05:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 22 19:05:29 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Feb 22 19:05:29 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Feb 22 19:05:30 volumio volumio[880]: info: Initializing connection to go-librespot Websocket Feb 22 19:05:30 volumio volumio[880]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 22 19:05:30 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 22 19:05:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 63. Feb 22 19:05:31 volumio systemd[1]: Stopped go-librespot Daemon. Feb 22 19:05:31 volumio systemd[1]: Started go-librespot Daemon. Feb 22 19:05:31 volumio go-librespot[2418]: go-librespot daemon starting... Feb 22 19:05:31 volumio go-librespot[2418]: time="2026-02-22T19:05:31+01:00" level=info msg="running go-librespot 0.2.0" Feb 22 19:05:31 volumio go-librespot[2418]: time="2026-02-22T19:05:31+01:00" level=debug msg="app state loaded" Feb 22 19:05:31 volumio go-librespot[2418]: time="2026-02-22T19:05:31+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 22 19:05:31 volumio go-librespot[2418]: time="2026-02-22T19:05:31+01: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]" Feb 22 19:05:31 volumio go-librespot[2418]: time="2026-02-22T19:05:31+01: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]" Feb 22 19:05:31 volumio go-librespot[2418]: time="2026-02-22T19:05:31+01: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]" Feb 22 19:05:31 volumio go-librespot[2418]: time="2026-02-22T19:05:31+01:00" level=info msg="zeroconf server listening on port 38513" Feb 22 19:05:31 volumio go-librespot[2418]: time="2026-02-22T19:05:31+01:00" level=debug msg="obtained new client token: AAB2lyjvQSJNd3gTSDsEToP54+dp9LYBMVS5P3QafBTcL8yjIWbXjID6YH2pNHDlIKEQDeH2ZBvsrNH3n/jdGSep9q6bDW2cOVHxer/fjoNeH3h8aImLCucHUdvigeAvzSTdJMtJbMRTTrrc8fTuSlcOS/5sNRQLUcvaIZf9WScq48Zr95THb8TOeo+mAH7qghrw11Wlh6XWhDrseVimSpXBKYYhUHygPziR/mkRMWr0A/QzFxsLsO2/vg==" Feb 22 19:05:31 volumio go-librespot[2418]: time="2026-02-22T19:05:31+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 22 19:05:31 volumio go-librespot[2418]: time="2026-02-22T19:05:31+01:00" level=debug msg="completed keyexchange" Feb 22 19:05:31 volumio go-librespot[2418]: time="2026-02-22T19:05:31+01:00" level=debug msg="completed challenge" Feb 22 19:05:31 volumio go-librespot[2418]: time="2026-02-22T19:05:31+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 22 19:05:31 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 22 19:05:31 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 22 19:05:33 volumio volumio[880]: info: Initializing connection to go-librespot Websocket Feb 22 19:05:33 volumio volumio[880]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 22 19:05:34 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 22 19:05:34 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 64. Feb 22 19:05:34 volumio systemd[1]: Stopped go-librespot Daemon. Feb 22 19:05:34 volumio systemd[1]: Started go-librespot Daemon. Feb 22 19:05:34 volumio go-librespot[2426]: go-librespot daemon starting... Feb 22 19:05:34 volumio go-librespot[2426]: time="2026-02-22T19:05:34+01:00" level=info msg="running go-librespot 0.2.0" Feb 22 19:05:34 volumio go-librespot[2426]: time="2026-02-22T19:05:34+01:00" level=debug msg="app state loaded" Feb 22 19:05:34 volumio go-librespot[2426]: time="2026-02-22T19:05:34+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 22 19:05:35 volumio go-librespot[2426]: time="2026-02-22T19:05:35+01: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]" Feb 22 19:05:35 volumio go-librespot[2426]: time="2026-02-22T19:05:35+01: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]" Feb 22 19:05:35 volumio go-librespot[2426]: time="2026-02-22T19:05:35+01: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]" Feb 22 19:05:35 volumio go-librespot[2426]: time="2026-02-22T19:05:35+01:00" level=info msg="zeroconf server listening on port 35921" Feb 22 19:05:35 volumio go-librespot[2426]: time="2026-02-22T19:05:35+01:00" level=debug msg="obtained new client token: AACojJ2/HufL8IHyyiERyREBr4y3g/c2jlS9pt6to2nRFMH7MnEqsRgalkpFKIZnn9VPvRY1NJ7ccISEOiL7OtrlZEDid+62ScXrMxlQyJPFhtNP3czsn1NX7+o0TRsxbdANAdQinj8EqRns1J5Uai3fRvMPN24z96QwHNr9vBLLvUrNfAvgY5FHP3I7iZPGeX5ZPgk4L+Z1EvKdrtqmBH3UQbnLuCkglIKm9pQsbiwQJoaOT5ftvso=" Feb 22 19:05:35 volumio go-librespot[2426]: time="2026-02-22T19:05:35+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 22 19:05:35 volumio go-librespot[2426]: time="2026-02-22T19:05:35+01:00" level=debug msg="completed keyexchange" Feb 22 19:05:35 volumio go-librespot[2426]: time="2026-02-22T19:05:35+01:00" level=debug msg="completed challenge" Feb 22 19:05:35 volumio go-librespot[2426]: time="2026-02-22T19:05:35+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 22 19:05:35 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 22 19:05:35 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 22 19:05:36 volumio volumio[880]: info: Initializing connection to go-librespot Websocket Feb 22 19:05:36 volumio volumio[880]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 22 19:05:38 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 22 19:05:38 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 65. Feb 22 19:05:38 volumio systemd[1]: Stopped go-librespot Daemon. Feb 22 19:05:38 volumio systemd[1]: Started go-librespot Daemon. Feb 22 19:05:38 volumio go-librespot[2452]: go-librespot daemon starting... Feb 22 19:05:38 volumio go-librespot[2452]: time="2026-02-22T19:05:38+01:00" level=info msg="running go-librespot 0.2.0" Feb 22 19:05:38 volumio go-librespot[2452]: time="2026-02-22T19:05:38+01:00" level=debug msg="app state loaded" Feb 22 19:05:38 volumio go-librespot[2452]: time="2026-02-22T19:05:38+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 22 19:05:38 volumio go-librespot[2452]: time="2026-02-22T19:05:38+01: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]" Feb 22 19:05:38 volumio go-librespot[2452]: time="2026-02-22T19:05:38+01: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]" Feb 22 19:05:38 volumio go-librespot[2452]: time="2026-02-22T19:05:38+01: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]" Feb 22 19:05:38 volumio go-librespot[2452]: time="2026-02-22T19:05:38+01:00" level=info msg="zeroconf server listening on port 38435" Feb 22 19:05:38 volumio go-librespot[2452]: time="2026-02-22T19:05:38+01:00" level=debug msg="obtained new client token: AADmUdlCzRvBdn9/TzVl0ae3uktXt7D2uxzHz2ElIdl7Tn4WRnYgEnDJc0+zpzNMg7I2fAt848zgNOuS6HVZuYhB7LD7n/3NSgS6R+DGeatnoMeKUjZ+0JiZwyYdAQ2Rq5E3BdAAB/D+5ZttqmLWtFTkhyuoZnWvxTny4IpAquRMAIMgz7nHK0QjcERocvYwyvZOqMbhPl7WPc0zE6nkzEDojXmQg3F3S1PYVM3eFcr8o2cQP1VDjHkkKQ==" Feb 22 19:05:39 volumio go-librespot[2452]: time="2026-02-22T19:05:39+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 22 19:05:39 volumio go-librespot[2452]: time="2026-02-22T19:05:39+01:00" level=debug msg="completed keyexchange" Feb 22 19:05:39 volumio go-librespot[2452]: time="2026-02-22T19:05:39+01:00" level=debug msg="completed challenge" Feb 22 19:05:39 volumio go-librespot[2452]: time="2026-02-22T19:05:39+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 22 19:05:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 22 19:05:39 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 22 19:05:39 volumio volumio[880]: info: Initializing connection to go-librespot Websocket Feb 22 19:05:39 volumio volumio[880]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 22 19:05:39 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Feb 22 19:05:39 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Feb 22 19:05:39 volumio volumio[880]: info: Listing playlists Feb 22 19:05:39 volumio volumio[880]: info: Listing playlists Feb 22 19:05:42 volumio volumio[880]: info: Initializing connection to go-librespot Websocket Feb 22 19:05:42 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 22 19:05:42 volumio volumio[880]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 22 19:05:42 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 66. Feb 22 19:05:42 volumio systemd[1]: Stopped go-librespot Daemon. Feb 22 19:05:42 volumio systemd[1]: Started go-librespot Daemon. Feb 22 19:05:42 volumio go-librespot[2460]: go-librespot daemon starting... Feb 22 19:05:42 volumio go-librespot[2460]: time="2026-02-22T19:05:42+01:00" level=info msg="running go-librespot 0.2.0" Feb 22 19:05:42 volumio go-librespot[2460]: time="2026-02-22T19:05:42+01:00" level=debug msg="app state loaded" Feb 22 19:05:42 volumio go-librespot[2460]: time="2026-02-22T19:05:42+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 22 19:05:42 volumio go-librespot[2460]: time="2026-02-22T19:05:42+01: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]" Feb 22 19:05:42 volumio go-librespot[2460]: time="2026-02-22T19:05:42+01: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]" Feb 22 19:05:42 volumio go-librespot[2460]: time="2026-02-22T19:05:42+01: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]" Feb 22 19:05:42 volumio go-librespot[2460]: time="2026-02-22T19:05:42+01:00" level=info msg="zeroconf server listening on port 38701" Feb 22 19:05:42 volumio go-librespot[2460]: time="2026-02-22T19:05:42+01:00" level=debug msg="obtained new client token: AADdg1oo3aumfnyhY0tzxSjynvjw9M4jr17paJv77sPf+7a4EdCLIaM7VPMJdOhXmH1r6zJI97qdduWR3lY/0BB4eh7qbQCnZ19BGLiy4LSLS9CQ5Pv4hVFLniqXumKrf+QBDHXWsR89H76SQETrnsekG2MENwfqP85rxxATXmBEF+J8phF4Fa1a/kKV4/96BhdtyefVPeEp0fcHd5f9u4U7TWu/Y/d2/xknPYz3BW89BQzaQt9vwAOcIg==" Feb 22 19:05:42 volumio go-librespot[2460]: time="2026-02-22T19:05:42+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 22 19:05:42 volumio go-librespot[2460]: time="2026-02-22T19:05:42+01:00" level=debug msg="completed keyexchange" Feb 22 19:05:42 volumio go-librespot[2460]: time="2026-02-22T19:05:42+01:00" level=debug msg="completed challenge" Feb 22 19:05:42 volumio go-librespot[2460]: time="2026-02-22T19:05:42+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 22 19:05:42 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 22 19:05:42 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 22 19:05:43 volumio sudo[2470]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 22 19:05:43 volumio sudo[2470]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 19:05:43 volumio sudo[2470]: pam_unix(sudo:session): session closed for user root Feb 22 19:05:43 volumio sudo[2472]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 22 19:05:43 volumio sudo[2472]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 19:05:43 volumio sudo[2472]: pam_unix(sudo:session): session closed for user root Feb 22 19:05:43 volumio volumio[880]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.116 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/145.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Feb 22 19:05:43 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 22 19:05:43 volumio sudo[2476]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 22 19:05:43 volumio sudo[2476]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 19:05:43 volumio sudo[2476]: pam_unix(sudo:session): session closed for user root Feb 22 19:05:43 volumio sudo[2478]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 22 19:05:43 volumio sudo[2478]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 19:05:43 volumio sudo[2478]: pam_unix(sudo:session): session closed for user root Feb 22 19:05:43 volumio volumio[880]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.116 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/145.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Feb 22 19:05:43 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 22 19:05:43 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 22 19:05:43 volumio volumio[880]: info: CoreCommandRouter::volumioGetVisibleSources Feb 22 19:05:43 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 19:05:43 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Feb 22 19:05:43 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Feb 22 19:05:43 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 22 19:05:43 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 22 19:05:43 volumio volumio[880]: info: Received Get System Info Feb 22 19:05:43 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 19:05:43 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 19:05:43 volumio volumio[880]: info: Discovery: Getting this device information Feb 22 19:05:43 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Feb 22 19:05:43 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Feb 22 19:05:43 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 19:05:43 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Feb 22 19:05:43 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Feb 22 19:05:43 volumio volumio[880]: info: Listing playlists Feb 22 19:05:43 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 22 19:05:43 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 22 19:05:43 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 22 19:05:44 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Feb 22 19:05:44 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Feb 22 19:05:45 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Feb 22 19:05:45 volumio volumio[880]: info: Initializing connection to go-librespot Websocket Feb 22 19:05:45 volumio volumio[880]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 22 19:05:45 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 22 19:05:45 volumio volumio[880]: info: Received Get System Info Feb 22 19:05:45 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 19:05:45 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 19:05:45 volumio volumio[880]: info: Discovery: Getting this device information Feb 22 19:05:45 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Feb 22 19:05:45 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Feb 22 19:05:45 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 19:05:45 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 22 19:05:46 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 67. Feb 22 19:05:46 volumio systemd[1]: Stopped go-librespot Daemon. Feb 22 19:05:46 volumio systemd[1]: Started go-librespot Daemon. Feb 22 19:05:46 volumio go-librespot[2495]: go-librespot daemon starting... Feb 22 19:05:46 volumio go-librespot[2495]: time="2026-02-22T19:05:46+01:00" level=info msg="running go-librespot 0.2.0" Feb 22 19:05:46 volumio go-librespot[2495]: time="2026-02-22T19:05:46+01:00" level=debug msg="app state loaded" Feb 22 19:05:46 volumio go-librespot[2495]: time="2026-02-22T19:05:46+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 22 19:05:46 volumio go-librespot[2495]: time="2026-02-22T19:05:46+01: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]" Feb 22 19:05:46 volumio go-librespot[2495]: time="2026-02-22T19:05:46+01: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]" Feb 22 19:05:46 volumio go-librespot[2495]: time="2026-02-22T19:05:46+01: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]" Feb 22 19:05:46 volumio go-librespot[2495]: time="2026-02-22T19:05:46+01:00" level=info msg="zeroconf server listening on port 42827" Feb 22 19:05:46 volumio go-librespot[2495]: time="2026-02-22T19:05:46+01:00" level=debug msg="obtained new client token: AABAEHed7UBlBYgKzN6bLhKcwo5Mq6K6ABl+LzGrm57+6kHjgkQl0+phTm74ld0oau2VG8TjM6Ns/mz0ULroKAF1YN8CBsdE/3uOaT6dOCQQEUgv6i9Zp45nB0BNgqICo2LIoRjCqQ/H+KP7vTj2Tk2aOx0ZfxItmhzvB2xFcB+K3l46kHCDu3xZVJo2fizgUT6d/Qlr6XzOwZZUkvuJKljUQkvLIUVT4UKUrdektvbsIr93OADTYrxg6Q==" Feb 22 19:05:46 volumio go-librespot[2495]: time="2026-02-22T19:05:46+01: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" Feb 22 19:05:46 volumio go-librespot[2495]: time="2026-02-22T19:05:46+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Feb 22 19:05:46 volumio go-librespot[2495]: time="2026-02-22T19:05:46+01:00" level=debug msg="completed keyexchange" Feb 22 19:05:46 volumio go-librespot[2495]: time="2026-02-22T19:05:46+01:00" level=debug msg="completed challenge" Feb 22 19:05:46 volumio go-librespot[2495]: time="2026-02-22T19:05:46+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 22 19:05:46 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 22 19:05:46 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 22 19:05:46 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 22 19:05:46 volumio volumio[880]: info: Received Get System Info Feb 22 19:05:46 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 19:05:46 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 19:05:46 volumio volumio[880]: info: Discovery: Getting this device information Feb 22 19:05:46 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Feb 22 19:05:46 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Feb 22 19:05:46 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 19:05:48 volumio volumio[880]: info: Initializing connection to go-librespot Websocket Feb 22 19:05:48 volumio volumio[880]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 22 19:05:49 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 22 19:05:49 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 68. Feb 22 19:05:49 volumio systemd[1]: Stopped go-librespot Daemon. Feb 22 19:05:49 volumio systemd[1]: Started go-librespot Daemon. Feb 22 19:05:49 volumio go-librespot[2503]: go-librespot daemon starting... Feb 22 19:05:49 volumio go-librespot[2503]: time="2026-02-22T19:05:49+01:00" level=info msg="running go-librespot 0.2.0" Feb 22 19:05:49 volumio go-librespot[2503]: time="2026-02-22T19:05:49+01:00" level=debug msg="app state loaded" Feb 22 19:05:49 volumio go-librespot[2503]: time="2026-02-22T19:05:49+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 22 19:05:49 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Feb 22 19:05:49 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Feb 22 19:05:50 volumio go-librespot[2503]: time="2026-02-22T19:05:50+01: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]" Feb 22 19:05:50 volumio go-librespot[2503]: time="2026-02-22T19:05:50+01: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]" Feb 22 19:05:50 volumio go-librespot[2503]: time="2026-02-22T19:05:50+01: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]" Feb 22 19:05:50 volumio go-librespot[2503]: time="2026-02-22T19:05:50+01:00" level=info msg="zeroconf server listening on port 39751" Feb 22 19:05:50 volumio go-librespot[2503]: time="2026-02-22T19:05:50+01:00" level=debug msg="obtained new client token: AACQ8b1igsEwW2F2FPfcim+P3g//lw7nzS8VzEyzo5awb7Qy+vs7ZfODXByWHWdCFybk8jSvu5S/I9SVrqaWnlElw8U8+3XSglIDQG9o4nlkQE2yG5g03d01E9UaUEhicOixa1PgqwQCHUq/jMgtVwKUDFViGF2+PdUsSoACrApDVv2aG+C9sF7g39m/TJ5HuAuDtEukMU4vq1M4Zoy7DytS2DUGwM1uiU2VPSRCcd1GRtGWoyeyudI=" Feb 22 19:05:50 volumio go-librespot[2503]: time="2026-02-22T19:05:50+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 22 19:05:50 volumio go-librespot[2503]: time="2026-02-22T19:05:50+01:00" level=debug msg="completed keyexchange" Feb 22 19:05:50 volumio go-librespot[2503]: time="2026-02-22T19:05:50+01:00" level=debug msg="completed challenge" Feb 22 19:05:50 volumio go-librespot[2503]: time="2026-02-22T19:05:50+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 22 19:05:50 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 22 19:05:50 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 22 19:05:51 volumio volumio[880]: info: Initializing connection to go-librespot Websocket Feb 22 19:05:51 volumio volumio[880]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 22 19:05:53 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 22 19:05:53 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 22 19:05:53 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 69. Feb 22 19:05:53 volumio systemd[1]: Stopped go-librespot Daemon. Feb 22 19:05:53 volumio systemd[1]: Started go-librespot Daemon. Feb 22 19:05:53 volumio go-librespot[2511]: go-librespot daemon starting... Feb 22 19:05:53 volumio go-librespot[2511]: time="2026-02-22T19:05:53+01:00" level=info msg="running go-librespot 0.2.0" Feb 22 19:05:53 volumio go-librespot[2511]: time="2026-02-22T19:05:53+01:00" level=debug msg="app state loaded" Feb 22 19:05:53 volumio go-librespot[2511]: time="2026-02-22T19:05:53+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 22 19:05:53 volumio go-librespot[2511]: time="2026-02-22T19:05:53+01: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]" Feb 22 19:05:53 volumio go-librespot[2511]: time="2026-02-22T19:05:53+01: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]" Feb 22 19:05:53 volumio go-librespot[2511]: time="2026-02-22T19:05:53+01: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]" Feb 22 19:05:53 volumio go-librespot[2511]: time="2026-02-22T19:05:53+01:00" level=info msg="zeroconf server listening on port 43383" Feb 22 19:05:53 volumio go-librespot[2511]: time="2026-02-22T19:05:53+01:00" level=debug msg="obtained new client token: AAA/Y106xkzUOY3wNjCG0TZgETAHAMoWaGFFW5WJb9N4RQkVbQSmDQ9kj4wQhbeoUxQUUoMYh20zk9Nh1T6+Ga8dhZlWaBGbmazbx23J3Kiknlc/k/wIuAnt4SI22TOsEGqcw4VR5RWD6HAr+S8ULemkCkslSJ0ec4j6D2kgZs5/MPYHLVNEidBZoFJPM9Op/8kZlO2zVMOHgxHzdutuVJUO6faeMw3R3d+TTQAduic36bPsRM16YzT0cw==" Feb 22 19:05:54 volumio go-librespot[2511]: time="2026-02-22T19:05:54+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 22 19:05:54 volumio go-librespot[2511]: time="2026-02-22T19:05:54+01:00" level=debug msg="completed keyexchange" Feb 22 19:05:54 volumio go-librespot[2511]: time="2026-02-22T19:05:54+01:00" level=debug msg="completed challenge" Feb 22 19:05:54 volumio go-librespot[2511]: time="2026-02-22T19:05:54+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 22 19:05:54 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 22 19:05:54 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 22 19:05:54 volumio volumio[880]: info: Initializing connection to go-librespot Websocket Feb 22 19:05:54 volumio volumio[880]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 22 19:05:57 volumio volumio[880]: info: Initializing connection to go-librespot Websocket Feb 22 19:05:57 volumio volumio[880]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 22 19:05:57 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 22 19:05:57 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 70. Feb 22 19:05:57 volumio systemd[1]: Stopped go-librespot Daemon. Feb 22 19:05:57 volumio systemd[1]: Started go-librespot Daemon. Feb 22 19:05:57 volumio go-librespot[2534]: go-librespot daemon starting... Feb 22 19:05:57 volumio go-librespot[2534]: time="2026-02-22T19:05:57+01:00" level=info msg="running go-librespot 0.2.0" Feb 22 19:05:57 volumio go-librespot[2534]: time="2026-02-22T19:05:57+01:00" level=debug msg="app state loaded" Feb 22 19:05:57 volumio go-librespot[2534]: time="2026-02-22T19:05:57+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 22 19:05:57 volumio go-librespot[2534]: time="2026-02-22T19:05:57+01: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]" Feb 22 19:05:57 volumio go-librespot[2534]: time="2026-02-22T19:05:57+01: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]" Feb 22 19:05:57 volumio go-librespot[2534]: time="2026-02-22T19:05:57+01: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]" Feb 22 19:05:57 volumio go-librespot[2534]: time="2026-02-22T19:05:57+01:00" level=info msg="zeroconf server listening on port 43797" Feb 22 19:05:57 volumio go-librespot[2534]: time="2026-02-22T19:05:57+01:00" level=debug msg="obtained new client token: AACPiLKW/2wkbJ9xpb6C9m7I/e1U89wc+B62oUUvYIdNUnd291yie7WUnA+lg9UQIvX/Ko69QC/7PoHR7LSwXPcVK6qtW0m4Gv0em00ec+WvKkk8RDUISCwkEjZGnqOV+wj94Ic8gjRUUPozfvY6K66d7C2/NEfvVC8fAysOPYvw4TkH387V/e8ipWd9ybtFQcXMBW2gIa+udaVyS9+UKBU2+A8SXwjNtSN7LyhqaffCqvatcg1sHaZ1kA==" Feb 22 19:05:57 volumio go-librespot[2534]: time="2026-02-22T19:05:57+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 22 19:05:57 volumio go-librespot[2534]: time="2026-02-22T19:05:57+01:00" level=debug msg="completed keyexchange" Feb 22 19:05:57 volumio go-librespot[2534]: time="2026-02-22T19:05:57+01:00" level=debug msg="completed challenge" Feb 22 19:05:57 volumio go-librespot[2534]: time="2026-02-22T19:05:57+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 22 19:05:57 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 22 19:05:57 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 22 19:05:59 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Feb 22 19:05:59 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Feb 22 19:05:59 volumio volumio[880]: info: Listing playlists Feb 22 19:05:59 volumio volumio[880]: info: Listing playlists Feb 22 19:06:00 volumio volumio[880]: info: Initializing connection to go-librespot Websocket Feb 22 19:06:00 volumio volumio[880]: info: CoreCommandRouter::volumioPlay Feb 22 19:06:00 volumio volumio[880]: info: CoreStateMachine::play index undefined Feb 22 19:06:00 volumio volumio[880]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 22 19:06:00 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Feb 22 19:06:00 volumio volumio[880]: info: CoreStateMachine::startPlaybackTimer Feb 22 19:06:00 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Feb 22 19:06:00 volumio volumio[880]: verbose: ControllerMpd::clearAddPlayTracks NAS/Media/Martin Wind, Kenny Barron, Anat Cohen, Matt Wilson - Stars (2026) [24-96]/01. Passing Thoughts.flac Feb 22 19:06:00 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand stop Feb 22 19:06:00 volumio volumio[880]: info: sendMpdCommand stop took 3 milliseconds Feb 22 19:06:00 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand clear Feb 22 19:06:00 volumio volumio[880]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 22 19:06:00 volumio volumio[880]: info: Feb 22 19:06:00 volumio volumio[880]: ---------------------------- MPD announces system playlist update Feb 22 19:06:00 volumio volumio[880]: info: Ignoring MPD Status Update Feb 22 19:06:00 volumio volumio[880]: info: sendMpdCommand clear took 4 milliseconds Feb 22 19:06:00 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand add "NAS/Media/Martin Wind, Kenny Barron, Anat Cohen, Matt Wilson - Stars (2026) [24-96]/01. Passing Thoughts.flac" Feb 22 19:06:00 volumio volumio[880]: info: Feb 22 19:06:00 volumio volumio[880]: ---------------------------- MPD announces system playlist update Feb 22 19:06:00 volumio volumio[880]: info: Ignoring MPD Status Update Feb 22 19:06:00 volumio volumio[880]: info: Feb 22 19:06:00 volumio volumio[880]: ---------------------------- MPD announces system playlist update Feb 22 19:06:00 volumio volumio[880]: info: Ignoring MPD Status Update Feb 22 19:06:00 volumio volumio[880]: error: updateQueue error: null Feb 22 19:06:00 volumio volumio[880]: info: Feb 22 19:06:00 volumio volumio[880]: ---------------------------- MPD announces system playlist update Feb 22 19:06:00 volumio volumio[880]: info: Ignoring MPD Status Update Feb 22 19:06:00 volumio volumio[880]: info: ------------------------------ 11ms Feb 22 19:06:00 volumio volumio[880]: info: sendMpdCommand add "NAS/Media/Martin Wind, Kenny Barron, Anat Cohen, Matt Wilson - Stars (2026) [24-96]/01. Passing Thoughts.flac" took 9 milliseconds Feb 22 19:06:00 volumio volumio[880]: info: ------------------------------ 7ms Feb 22 19:06:00 volumio volumio[880]: info: ------------------------------ 7ms Feb 22 19:06:00 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand play Feb 22 19:06:00 volumio volumio[880]: info: Feb 22 19:06:00 volumio volumio[880]: ---------------------------- MPD announces system playlist update Feb 22 19:06:00 volumio volumio[880]: info: Ignoring MPD Status Update Feb 22 19:06:00 volumio volumio[880]: info: Feb 22 19:06:00 volumio volumio[880]: ---------------------------- MPD announces system playlist update Feb 22 19:06:00 volumio volumio[880]: info: Ignoring MPD Status Update Feb 22 19:06:00 volumio volumio[880]: error: Upnp client error: Error: This socket has been ended by the other party Feb 22 19:06:00 volumio volumio[880]: info: ------------------------------ 11ms Feb 22 19:06:00 volumio volumio[880]: info: sendMpdCommand play took 8 milliseconds Feb 22 19:06:00 volumio volumio[880]: info: ------------------------------ 7ms Feb 22 19:06:00 volumio volumio[880]: info: ------------------------------ 5ms Feb 22 19:06:00 volumio volumio[880]: info: Feb 22 19:06:00 volumio volumio[880]: ---------------------------- MPD announces state update: player Feb 22 19:06:00 volumio volumio[880]: info: ControllerMpd::getState Feb 22 19:06:00 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Feb 22 19:06:00 volumio volumio[880]: info: Feb 22 19:06:00 volumio volumio[880]: ---------------------------- MPD announces state update: player Feb 22 19:06:00 volumio volumio[880]: info: ControllerMpd::getState Feb 22 19:06:00 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Feb 22 19:06:00 volumio volumio[880]: info: Feb 22 19:06:00 volumio volumio[880]: ---------------------------- MPD announces state update: player Feb 22 19:06:00 volumio volumio[880]: info: ControllerMpd::getState Feb 22 19:06:00 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Feb 22 19:06:00 volumio volumio[880]: info: Feb 22 19:06:00 volumio volumio[880]: ---------------------------- MPD announces state update: player Feb 22 19:06:00 volumio volumio[880]: info: sendMpdCommand status took 20 milliseconds Feb 22 19:06:00 volumio volumio[880]: info: sendMpdCommand status took 18 milliseconds Feb 22 19:06:00 volumio volumio[880]: info: ControllerMpd::getState Feb 22 19:06:00 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Feb 22 19:06:00 volumio volumio[880]: verbose: ControllerMpd::parseState Feb 22 19:06:00 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 19:06:00 volumio volumio[880]: verbose: ControllerMpd::parseState Feb 22 19:06:00 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 19:06:00 volumio volumio[880]: info: sendMpdCommand status took 22 milliseconds Feb 22 19:06:00 volumio volumio[880]: info: sendMpdCommand status took 7 milliseconds Feb 22 19:06:00 volumio volumio[880]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 22 19:06:00 volumio volumio[880]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 22 19:06:00 volumio volumio[880]: verbose: ControllerMpd::parseState Feb 22 19:06:00 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 19:06:00 volumio volumio[880]: verbose: ControllerMpd::parseState Feb 22 19:06:00 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 19:06:00 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Feb 22 19:06:00 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Feb 22 19:06:00 volumio volumio[880]: info: ControllerMpd::pushState Feb 22 19:06:00 volumio volumio[880]: info: CoreCommandRouter::servicePushState Feb 22 19:06:00 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Feb 22 19:06:00 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":293,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Passing Thoughts","artist":"Martin Wind","album":"Stars","uri":"NAS/Media/Martin Wind, Kenny Barron, Anat Cohen, Matt Wilson - Stars (2026) [24-96]/01. Passing Thoughts.flac","trackType":"flac"} Feb 22 19:06:00 volumio volumio[880]: verbose: CURRENT POSITION 0 Feb 22 19:06:00 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Feb 22 19:06:00 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus stop Feb 22 19:06:00 volumio volumio[880]: info: ControllerMpd::pushState Feb 22 19:06:00 volumio volumio[880]: info: CoreCommandRouter::servicePushState Feb 22 19:06:00 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Feb 22 19:06:00 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":293,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Passing Thoughts","artist":"Martin Wind","album":"Stars","uri":"NAS/Media/Martin Wind, Kenny Barron, Anat Cohen, Matt Wilson - Stars (2026) [24-96]/01. Passing Thoughts.flac","trackType":"flac"} Feb 22 19:06:00 volumio volumio[880]: verbose: CURRENT POSITION 0 Feb 22 19:06:00 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Feb 22 19:06:00 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Feb 22 19:06:00 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Feb 22 19:06:00 volumio volumio[880]: info: CoreStateMachine::pushState Feb 22 19:06:00 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Feb 22 19:06:00 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 19:06:00 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Feb 22 19:06:00 volumio volumio[880]: info: CoreStateMachine::pushState Feb 22 19:06:00 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Feb 22 19:06:00 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Feb 22 19:06:00 volumio volumio[880]: info: ------------------------------ 72ms Feb 22 19:06:00 volumio volumio[880]: info: ------------------------------ 89ms Feb 22 19:06:00 volumio volumio[880]: info: Feb 22 19:06:00 volumio volumio[880]: ---------------------------- MPD announces state update: player Feb 22 19:06:00 volumio volumio[880]: info: ControllerMpd::getState Feb 22 19:06:00 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Feb 22 19:06:00 volumio volumio[880]: info: Feb 22 19:06:00 volumio volumio[880]: ---------------------------- MPD announces state update: player Feb 22 19:06:00 volumio volumio[880]: info: ControllerMpd::getState Feb 22 19:06:00 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand status Feb 22 19:06:00 volumio volumio[880]: info: sendMpdCommand playlistinfo took 65 milliseconds Feb 22 19:06:00 volumio volumio[880]: info: sendMpdCommand playlistinfo took 65 milliseconds Feb 22 19:06:00 volumio volumio[880]: info: sendMpdCommand status took 5 milliseconds Feb 22 19:06:00 volumio volumio[880]: info: sendMpdCommand status took 4 milliseconds Feb 22 19:06:00 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Feb 22 19:06:00 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Feb 22 19:06:00 volumio volumio[880]: verbose: ControllerMpd::parseState Feb 22 19:06:00 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 19:06:00 volumio volumio[880]: verbose: ControllerMpd::parseState Feb 22 19:06:00 volumio volumio[880]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 19:06:00 volumio volumio[880]: info: ControllerMpd::pushState Feb 22 19:06:00 volumio volumio[880]: info: CoreCommandRouter::servicePushState Feb 22 19:06:00 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Feb 22 19:06:00 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":293,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Passing Thoughts","artist":"Martin Wind","album":"Stars","uri":"NAS/Media/Martin Wind, Kenny Barron, Anat Cohen, Matt Wilson - Stars (2026) [24-96]/01. Passing Thoughts.flac","trackType":"flac"} Feb 22 19:06:00 volumio volumio[880]: verbose: CURRENT POSITION 0 Feb 22 19:06:00 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Feb 22 19:06:00 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Feb 22 19:06:00 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Feb 22 19:06:00 volumio volumio[880]: info: CoreStateMachine::pushState Feb 22 19:06:00 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Feb 22 19:06:00 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Feb 22 19:06:00 volumio volumio[880]: info: CoreStateMachine::pushState Feb 22 19:06:00 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Feb 22 19:06:00 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Feb 22 19:06:00 volumio volumio[880]: info: ControllerMpd::pushState Feb 22 19:06:00 volumio volumio[880]: info: CoreCommandRouter::servicePushState Feb 22 19:06:00 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Feb 22 19:06:00 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":293,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Passing Thoughts","artist":"Martin Wind","album":"Stars","uri":"NAS/Media/Martin Wind, Kenny Barron, Anat Cohen, Matt Wilson - Stars (2026) [24-96]/01. Passing Thoughts.flac","trackType":"flac"} Feb 22 19:06:00 volumio volumio[880]: verbose: CURRENT POSITION 0 Feb 22 19:06:00 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Feb 22 19:06:00 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Feb 22 19:06:00 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Feb 22 19:06:00 volumio volumio[880]: info: CoreStateMachine::pushState Feb 22 19:06:00 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Feb 22 19:06:00 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Feb 22 19:06:00 volumio volumio[880]: info: CoreStateMachine::pushState Feb 22 19:06:00 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Feb 22 19:06:00 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Feb 22 19:06:00 volumio volumio[880]: info: ------------------------------ 153ms Feb 22 19:06:00 volumio volumio[880]: info: ------------------------------ 139ms Feb 22 19:06:00 volumio volumio[880]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 22 19:06:00 volumio volumio[880]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 22 19:06:00 volumio volumio[880]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 22 19:06:00 volumio volumio[880]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 22 19:06:00 volumio volumio[880]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 22 19:06:00 volumio volumio[880]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 22 19:06:00 volumio volumio[880]: info: sendMpdCommand playlistinfo took 90 milliseconds Feb 22 19:06:00 volumio volumio[880]: info: sendMpdCommand playlistinfo took 89 milliseconds Feb 22 19:06:00 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Feb 22 19:06:00 volumio volumio[880]: verbose: ControllerMpd::parseTrackInfo Feb 22 19:06:00 volumio volumio[880]: info: ControllerMpd::pushState Feb 22 19:06:00 volumio volumio[880]: info: CoreCommandRouter::servicePushState Feb 22 19:06:00 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Feb 22 19:06:00 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":992,"duration":293,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2133 Kbps","isStreaming":false,"title":"Passing Thoughts","artist":"Martin Wind","album":"Stars","uri":"NAS/Media/Martin Wind, Kenny Barron, Anat Cohen, Matt Wilson - Stars (2026) [24-96]/01. Passing Thoughts.flac","trackType":"flac"} Feb 22 19:06:00 volumio volumio[880]: verbose: CURRENT POSITION 0 Feb 22 19:06:00 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Feb 22 19:06:00 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Feb 22 19:06:00 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Feb 22 19:06:00 volumio volumio[880]: info: CoreStateMachine::pushState Feb 22 19:06:00 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Feb 22 19:06:00 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 19:06:00 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Feb 22 19:06:00 volumio volumio[880]: info: CoreStateMachine::pushState Feb 22 19:06:00 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Feb 22 19:06:00 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Feb 22 19:06:00 volumio volumio[880]: info: ControllerMpd::pushState Feb 22 19:06:00 volumio volumio[880]: info: CoreCommandRouter::servicePushState Feb 22 19:06:00 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Feb 22 19:06:00 volumio volumio[880]: verbose: STATE SERVICE {"status":"play","position":0,"seek":992,"duration":293,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2133 Kbps","isStreaming":false,"title":"Passing Thoughts","artist":"Martin Wind","album":"Stars","uri":"NAS/Media/Martin Wind, Kenny Barron, Anat Cohen, Matt Wilson - Stars (2026) [24-96]/01. Passing Thoughts.flac","trackType":"flac"} Feb 22 19:06:00 volumio volumio[880]: verbose: CURRENT POSITION 0 Feb 22 19:06:00 volumio volumio[880]: info: CoreStateMachine::syncState stateService play Feb 22 19:06:00 volumio volumio[880]: info: CoreStateMachine::syncState currentStatus play Feb 22 19:06:00 volumio volumio[880]: info: Received an update from plugin. extracting info from payload Feb 22 19:06:00 volumio volumio[880]: info: CoreStateMachine::pushState Feb 22 19:06:00 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Feb 22 19:06:00 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Feb 22 19:06:00 volumio volumio[880]: info: CoreStateMachine::pushState Feb 22 19:06:00 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Feb 22 19:06:00 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Feb 22 19:06:00 volumio volumio[880]: info: ------------------------------ 160ms Feb 22 19:06:00 volumio volumio[880]: info: ------------------------------ 158ms Feb 22 19:06:00 volumio volumio[880]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 22 19:06:00 volumio volumio[880]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 22 19:06:00 volumio volumio[880]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 22 19:06:00 volumio volumio[880]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 22 19:06:00 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 22 19:06:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 71. Feb 22 19:06:01 volumio systemd[1]: Stopped go-librespot Daemon. Feb 22 19:06:01 volumio systemd[1]: Started go-librespot Daemon. Feb 22 19:06:01 volumio go-librespot[2548]: go-librespot daemon starting... Feb 22 19:06:01 volumio go-librespot[2548]: time="2026-02-22T19:06:01+01:00" level=info msg="running go-librespot 0.2.0" Feb 22 19:06:01 volumio go-librespot[2548]: time="2026-02-22T19:06:01+01:00" level=debug msg="app state loaded" Feb 22 19:06:01 volumio go-librespot[2548]: time="2026-02-22T19:06:01+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 22 19:06:01 volumio go-librespot[2548]: time="2026-02-22T19:06:01+01: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]" Feb 22 19:06:01 volumio go-librespot[2548]: time="2026-02-22T19:06:01+01: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]" Feb 22 19:06:01 volumio go-librespot[2548]: time="2026-02-22T19:06:01+01: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]" Feb 22 19:06:01 volumio go-librespot[2548]: time="2026-02-22T19:06:01+01:00" level=info msg="zeroconf server listening on port 40147" Feb 22 19:06:01 volumio go-librespot[2548]: time="2026-02-22T19:06:01+01:00" level=debug msg="obtained new client token: AABBXM6cy8ON67uHWRazmgao+tMZ+Wv+gethiOTQEub18BkPc3Cj55IUYCznpK+pykWhsRLLo9n0b5LCM4xjzCE2y+otg3WBeOSohe0A+VV9iIUo7X/S9lFgdo0ZV1JrQedjEasfgN60SLhCniH9jLO3Os1MNKCEQtuodvt6ufUz6viPHkbDcCeu+iLBi4nUEV2iqXI1uJGYSy/H3J50PyD4xe/BHgribwv+zGX7uoyeD/lfUrtPcEAbIw==" Feb 22 19:06:01 volumio go-librespot[2548]: time="2026-02-22T19:06:01+01: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" Feb 22 19:06:01 volumio go-librespot[2548]: time="2026-02-22T19:06:01+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Feb 22 19:06:01 volumio go-librespot[2548]: time="2026-02-22T19:06:01+01:00" level=debug msg="completed keyexchange" Feb 22 19:06:01 volumio go-librespot[2548]: time="2026-02-22T19:06:01+01:00" level=debug msg="completed challenge" Feb 22 19:06:01 volumio go-librespot[2548]: time="2026-02-22T19:06:01+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 22 19:06:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 22 19:06:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 22 19:06:01 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 22 19:06:01 volumio volumio[880]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 22 19:06:01 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 22 19:06:01 volumio volumio[880]: info: Received Get System Version Feb 22 19:06:01 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 22 19:06:01 volumio volumio[880]: info: Received Get System Info Feb 22 19:06:01 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 19:06:01 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 19:06:01 volumio volumio[880]: info: Discovery: Getting this device information Feb 22 19:06:01 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Feb 22 19:06:01 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Feb 22 19:06:01 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 19:06:03 volumio volumio[880]: info: Initializing connection to go-librespot Websocket Feb 22 19:06:03 volumio volumio[880]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 22 19:06:04 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 22 19:06:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 72. Feb 22 19:06:04 volumio systemd[1]: Stopped go-librespot Daemon. Feb 22 19:06:04 volumio systemd[1]: Started go-librespot Daemon. Feb 22 19:06:04 volumio go-librespot[2567]: go-librespot daemon starting... Feb 22 19:06:04 volumio go-librespot[2567]: time="2026-02-22T19:06:04+01:00" level=info msg="running go-librespot 0.2.0" Feb 22 19:06:04 volumio go-librespot[2567]: time="2026-02-22T19:06:04+01:00" level=debug msg="app state loaded" Feb 22 19:06:04 volumio go-librespot[2567]: time="2026-02-22T19:06:04+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 22 19:06:05 volumio go-librespot[2567]: time="2026-02-22T19:06:05+01: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]" Feb 22 19:06:05 volumio go-librespot[2567]: time="2026-02-22T19:06:05+01: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]" Feb 22 19:06:05 volumio go-librespot[2567]: time="2026-02-22T19:06:05+01: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]" Feb 22 19:06:05 volumio go-librespot[2567]: time="2026-02-22T19:06:05+01:00" level=info msg="zeroconf server listening on port 46461" Feb 22 19:06:05 volumio go-librespot[2567]: time="2026-02-22T19:06:05+01:00" level=debug msg="obtained new client token: AABHplhujmTfAyW2EoX3mGmJaC/Z4RuXjXbfTaiZQCmmIW/9dh54XxnSEi+ZvxrMyyFN2D34Br1KPRn3RfQpXexxxCfSZCTHvUM544pt+MZy9GIBI6qUwkhUYh49TxOF9N2sYeQs+S5TIPzit4lkr/CWMdU8G5HY8Deic5Q9fD9SdK2g5i5rJO/dRYK6LVfZ4SWl1rVmwM7RXgIwUwDzp0NuXiugCLjNUUgiS3gd++BmvFCjWfgRJzU=" Feb 22 19:06:05 volumio go-librespot[2567]: time="2026-02-22T19:06:05+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 22 19:06:05 volumio go-librespot[2567]: time="2026-02-22T19:06:05+01:00" level=debug msg="completed keyexchange" Feb 22 19:06:05 volumio go-librespot[2567]: time="2026-02-22T19:06:05+01:00" level=debug msg="completed challenge" Feb 22 19:06:05 volumio go-librespot[2567]: time="2026-02-22T19:06:05+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 22 19:06:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 22 19:06:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 22 19:06:06 volumio volumio[880]: info: Initializing connection to go-librespot Websocket Feb 22 19:06:06 volumio volumio[880]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 22 19:06:07 volumio volumio[880]: info: CoreCommandRouter::volumioGetQueue Feb 22 19:06:07 volumio volumio[880]: info: CoreStateMachine::getQueue Feb 22 19:06:07 volumio volumio[880]: info: CorePlayQueue::getQueue Feb 22 19:06:08 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 22 19:06:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 73. Feb 22 19:06:08 volumio systemd[1]: Stopped go-librespot Daemon. Feb 22 19:06:08 volumio systemd[1]: Started go-librespot Daemon. Feb 22 19:06:08 volumio go-librespot[2597]: go-librespot daemon starting... Feb 22 19:06:08 volumio go-librespot[2597]: time="2026-02-22T19:06:08+01:00" level=info msg="running go-librespot 0.2.0" Feb 22 19:06:08 volumio go-librespot[2597]: time="2026-02-22T19:06:08+01:00" level=debug msg="app state loaded" Feb 22 19:06:08 volumio go-librespot[2597]: time="2026-02-22T19:06:08+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 22 19:06:08 volumio go-librespot[2597]: time="2026-02-22T19:06:08+01: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]" Feb 22 19:06:08 volumio go-librespot[2597]: time="2026-02-22T19:06:08+01: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]" Feb 22 19:06:08 volumio go-librespot[2597]: time="2026-02-22T19:06:08+01: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]" Feb 22 19:06:08 volumio go-librespot[2597]: time="2026-02-22T19:06:08+01:00" level=info msg="zeroconf server listening on port 44389" Feb 22 19:06:09 volumio go-librespot[2597]: time="2026-02-22T19:06:09+01:00" level=debug msg="obtained new client token: AAA82mJuc0q+CmREs0xcFawpmC8Hz8L0f26OlUQI96FKFpdZrwktyVL1l7a4cF3ktSbHtWE8gOqWfG8h1usCOkZ2xfGCfvuOfuC+/IK8VctcO34WCANu0V8AEyfhjJUUnbz4gxXHQaqCaALPwFzcCEjp3O0VIwGL27dIUTTYBN9SjHBf2wUv+Zb/Q969aQvmfTEMzmrDpQKZYijjPGTYrZNDY7rs3DnrQgMSy8ZACNlihqSq5NxmxAIarg==" Feb 22 19:06:09 volumio go-librespot[2597]: time="2026-02-22T19:06:09+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 22 19:06:09 volumio go-librespot[2597]: time="2026-02-22T19:06:09+01:00" level=debug msg="completed keyexchange" Feb 22 19:06:09 volumio go-librespot[2597]: time="2026-02-22T19:06:09+01:00" level=debug msg="completed challenge" Feb 22 19:06:09 volumio go-librespot[2597]: time="2026-02-22T19:06:09+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 22 19:06:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 22 19:06:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 22 19:06:09 volumio volumio[880]: info: Initializing connection to go-librespot Websocket Feb 22 19:06:09 volumio volumio[880]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 22 19:06:09 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Feb 22 19:06:09 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Feb 22 19:06:12 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 22 19:06:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 74. Feb 22 19:06:12 volumio systemd[1]: Stopped go-librespot Daemon. Feb 22 19:06:12 volumio volumio[880]: info: Initializing connection to go-librespot Websocket Feb 22 19:06:12 volumio volumio[880]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 22 19:06:12 volumio systemd[1]: Started go-librespot Daemon. Feb 22 19:06:12 volumio go-librespot[2611]: go-librespot daemon starting... Feb 22 19:06:12 volumio go-librespot[2611]: time="2026-02-22T19:06:12+01:00" level=info msg="running go-librespot 0.2.0" Feb 22 19:06:12 volumio go-librespot[2611]: time="2026-02-22T19:06:12+01:00" level=debug msg="app state loaded" Feb 22 19:06:12 volumio go-librespot[2611]: time="2026-02-22T19:06:12+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 22 19:06:12 volumio go-librespot[2611]: time="2026-02-22T19:06:12+01: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]" Feb 22 19:06:12 volumio go-librespot[2611]: time="2026-02-22T19:06:12+01: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]" Feb 22 19:06:12 volumio go-librespot[2611]: time="2026-02-22T19:06:12+01: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]" Feb 22 19:06:12 volumio go-librespot[2611]: time="2026-02-22T19:06:12+01:00" level=info msg="zeroconf server listening on port 34691" Feb 22 19:06:12 volumio go-librespot[2611]: time="2026-02-22T19:06:12+01:00" level=debug msg="obtained new client token: AACdAfb3nIzpoUOmNmV2SHHVy7PljTOkMJpOJ16UlqCnGlOf5cnBt94cjS5kuT19o0IZkdPvCvNVWPAb7rhqruKCPg5aWiUcNZ83mAd7gPN22Ly28Hdgbmf+V4xXQO20Cm1gXwJLslNGs0tgBerhcQb/GD+sot6xx866g/zUlUXTLvlxC1D/3RPoNl1oBD86lWVootXp3QNS9hvnK+r5HizKe7m/OPq55LIYU3bsRrz38G4ipNoFiYXzjA==" Feb 22 19:06:12 volumio go-librespot[2611]: time="2026-02-22T19:06:12+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 22 19:06:12 volumio go-librespot[2611]: time="2026-02-22T19:06:12+01:00" level=debug msg="completed keyexchange" Feb 22 19:06:12 volumio go-librespot[2611]: time="2026-02-22T19:06:12+01:00" level=debug msg="completed challenge" Feb 22 19:06:12 volumio go-librespot[2611]: time="2026-02-22T19:06:12+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 22 19:06:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 22 19:06:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 22 19:06:13 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 22 19:06:13 volumio volumio[880]: info: CURURI: artists:// Feb 22 19:06:13 volumio volumio[880]: info: Preload queue cleared Feb 22 19:06:15 volumio volumio[880]: info: Initializing connection to go-librespot Websocket Feb 22 19:06:15 volumio volumio[880]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 22 19:06:15 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 22 19:06:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 75. Feb 22 19:06:16 volumio systemd[1]: Stopped go-librespot Daemon. Feb 22 19:06:16 volumio systemd[1]: Started go-librespot Daemon. Feb 22 19:06:16 volumio go-librespot[2679]: go-librespot daemon starting... Feb 22 19:06:16 volumio go-librespot[2679]: time="2026-02-22T19:06:16+01:00" level=info msg="running go-librespot 0.2.0" Feb 22 19:06:16 volumio go-librespot[2679]: time="2026-02-22T19:06:16+01:00" level=debug msg="app state loaded" Feb 22 19:06:16 volumio go-librespot[2679]: time="2026-02-22T19:06:16+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 22 19:06:16 volumio go-librespot[2679]: time="2026-02-22T19:06:16+01: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]" Feb 22 19:06:16 volumio go-librespot[2679]: time="2026-02-22T19:06:16+01: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]" Feb 22 19:06:16 volumio go-librespot[2679]: time="2026-02-22T19:06:16+01: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]" Feb 22 19:06:16 volumio go-librespot[2679]: time="2026-02-22T19:06:16+01:00" level=info msg="zeroconf server listening on port 45679" Feb 22 19:06:16 volumio go-librespot[2679]: time="2026-02-22T19:06:16+01:00" level=debug msg="obtained new client token: AAD8hmcwRMH1W9XZzqnSjqn9n/b0DMKMLpcyJGs7W1wtcq6o6QY6P+u5VX5X/bujQU1iWl+0er0fcVcJz6/DZLPmb0wiXdkGnOUs+SiNx0wEd/duipyYy/u/6qHhyj1oDsVMewLPbJ97RZTd4TDJkPxDulS86iDXt29zeANuZy8vEmGY14DMTjdUk4iB+Vcmiysj47Co1QfDXPWPaQ5wxhyQ3HGOm5/c3UMlmP8Geis9sErPxskUPZ55sA==" Feb 22 19:06:16 volumio go-librespot[2679]: time="2026-02-22T19:06:16+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 22 19:06:16 volumio go-librespot[2679]: time="2026-02-22T19:06:16+01:00" level=debug msg="completed keyexchange" Feb 22 19:06:16 volumio go-librespot[2679]: time="2026-02-22T19:06:16+01:00" level=debug msg="completed challenge" Feb 22 19:06:16 volumio go-librespot[2679]: time="2026-02-22T19:06:16+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 22 19:06:16 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 22 19:06:16 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 22 19:06:18 volumio volumio[880]: info: Initializing connection to go-librespot Websocket Feb 22 19:06:18 volumio volumio[880]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 22 19:06:19 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 22 19:06:19 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 76. Feb 22 19:06:19 volumio systemd[1]: Stopped go-librespot Daemon. Feb 22 19:06:19 volumio systemd[1]: Started go-librespot Daemon. Feb 22 19:06:19 volumio go-librespot[2700]: go-librespot daemon starting... Feb 22 19:06:19 volumio go-librespot[2700]: time="2026-02-22T19:06:19+01:00" level=info msg="running go-librespot 0.2.0" Feb 22 19:06:19 volumio go-librespot[2700]: time="2026-02-22T19:06:19+01:00" level=debug msg="app state loaded" Feb 22 19:06:19 volumio go-librespot[2700]: time="2026-02-22T19:06:19+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 22 19:06:19 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Feb 22 19:06:19 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Feb 22 19:06:20 volumio volumio[880]: info: Listing playlists Feb 22 19:06:20 volumio volumio[880]: info: Listing playlists Feb 22 19:06:21 volumio volumio[880]: info: Initializing connection to go-librespot Websocket Feb 22 19:06:21 volumio go-librespot[2700]: time="2026-02-22T19:06:21+01:00" level=debug msg="new websocket client" Feb 22 19:06:21 volumio volumio[880]: info: Connection to go-librespot Websocket established Feb 22 19:06:24 volumio volumio[880]: info: Getting Spotify volume Feb 22 19:06:24 volumio volumio[880]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Feb 22 19:06:24 volumio volumio[880]: info: CoreCommandRouter::volumioGetState Feb 22 19:06:24 volumio volumio[880]: info: CorePlayQueue::getTrack 0 Feb 22 19:06:24 volumio volumio[880]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 22 19:06:25 volumio go-librespot[2700]: time="2026-02-22T19:06:25+01: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]" Feb 22 19:06:25 volumio go-librespot[2700]: time="2026-02-22T19:06:25+01: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]" Feb 22 19:06:25 volumio go-librespot[2700]: time="2026-02-22T19:06:25+01: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]" Feb 22 19:06:25 volumio go-librespot[2700]: time="2026-02-22T19:06:25+01:00" level=info msg="zeroconf server listening on port 42727" Feb 22 19:06:25 volumio go-librespot[2700]: time="2026-02-22T19:06:25+01:00" level=debug msg="obtained new client token: AAAVg69YtChT50H8lBd+03qyomKdQQ/O6uBMw8HXVmY/60YyqxG0g/pcz7SaZS8gpMbEBqwqIj+r0llJz22id5BdJ9FT7CZuIrFPmg0wtzN17boVDFPXIRy3FK+OajdOmLCNjVakrR1OEFLvKNo8lPdULPnXGs0YT5tWkL4Guw9f6Jl6qSObVZAwh1imr80bljZbdjFsnwEA045oB0rWEc3h3GU2oMozWYBW6YyaLRz/WfNDXYPLNfqELw==" Feb 22 19:06:25 volumio go-librespot[2700]: time="2026-02-22T19:06:25+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 22 19:06:25 volumio go-librespot[2700]: time="2026-02-22T19:06:25+01:00" level=debug msg="completed keyexchange" Feb 22 19:06:25 volumio go-librespot[2700]: time="2026-02-22T19:06:25+01:00" level=debug msg="completed challenge" Feb 22 19:06:25 volumio go-librespot[2700]: time="2026-02-22T19:06:25+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 22 19:06:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 22 19:06:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 22 19:06:25 volumio volumio[880]: (node:880) UnhandledPromiseRejectionWarning: Error: socket hang up Feb 22 19:06:25 volumio volumio[880]: at connResetException (internal/errors.js:607:14) Feb 22 19:06:25 volumio volumio[880]: at Socket.socketOnEnd (_http_client.js:493:23) Feb 22 19:06:25 volumio volumio[880]: at Socket.emit (events.js:327:22) Feb 22 19:06:25 volumio volumio[880]: at endReadableNT (internal/streams/readable.js:1327:12) Feb 22 19:06:25 volumio volumio[880]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Feb 22 19:06:25 volumio volumio[880]: (node:880) 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: 8) Feb 22 19:06:25 volumio volumio[880]: info: Connection to go-librespot Websocket closed Feb 22 19:06:27 volumio volumio[880]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 22 19:06:27 volumio volumio[880]: TypeError: Cannot read property 'slice' of null Feb 22 19:06:27 volumio volumio[880]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Feb 22 19:06:27 volumio volumio[880]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Feb 22 19:06:27 volumio volumio[880]: at Object.onceWrapper (events.js:422:26) Feb 22 19:06:27 volumio volumio[880]: at ClientRequest.emit (events.js:315:20) Feb 22 19:06:27 volumio volumio[880]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:641:27) Feb 22 19:06:27 volumio volumio[880]: at HTTPParser.parserOnHeadersComplete (_http_common.js:126:17) Feb 22 19:06:27 volumio volumio[880]: at TLSSocket.socketOnData (_http_client.js:509:22) Feb 22 19:06:27 volumio volumio[880]: at TLSSocket.emit (events.js:315:20) Feb 22 19:06:27 volumio volumio[880]: at addChunk (internal/streams/readable.js:309:12) Feb 22 19:06:27 volumio volumio[880]: at readableAddChunk (internal/streams/readable.js:284:9) Feb 22 19:06:27 volumio volumio[880]: at TLSSocket.Readable.push (internal/streams/readable.js:223:10) Feb 22 19:06:27 volumio volumio[880]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:188:23) Feb 22 19:06:27 volumio volumio[880]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 22 19:06:27 volumio sudo[2745]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-22 19:05 Feb 22 19:06:27 volumio sudo[2745]: 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"