-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Mon 2025-02-17 00:58:37 CET. -- Feb 17 00:57:00 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 17 00:57:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 220. Feb 17 00:57:00 volumio systemd[1]: Stopped go-librespot Daemon. Feb 17 00:57:00 volumio systemd[1]: Started go-librespot Daemon. Feb 17 00:57:00 volumio go-librespot[5433]: go-librespot daemon starting... Feb 17 00:57:00 volumio go-librespot[5433]: time="2025-02-17T00:57:00+01:00" level=info msg="running go-librespot 0.2.0" Feb 17 00:57:00 volumio go-librespot[5433]: time="2025-02-17T00:57:00+01:00" level=debug msg="app state loaded" Feb 17 00:57:00 volumio go-librespot[5433]: time="2025-02-17T00:57:00+01:00" level=debug msg="stored credentials not found" Feb 17 00:57:00 volumio go-librespot[5433]: time="2025-02-17T00:57:00+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 00:57:00 volumio go-librespot[5433]: time="2025-02-17T00:57:00+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 17 00:57:00 volumio go-librespot[5433]: time="2025-02-17T00:57:00+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 17 00:57:00 volumio go-librespot[5433]: time="2025-02-17T00:57:00+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 17 00:57:00 volumio go-librespot[5433]: time="2025-02-17T00:57:00+01:00" level=info msg="zeroconf server listening on port 33893" Feb 17 00:57:00 volumio go-librespot[5433]: time="2025-02-17T00:57:00+01:00" level=debug msg="obtained new client token: AABIN+XSDrCA3YrtlReQnp+KisB2IBhHT8fK+uA43VLlcR3D3QF/7f/ibRzIAwLLhOhb7bhWHSmZzRF3Zj9PMMnSbVvHgauiVME6qQnDWaG36yi9cfmOZqTncG7g3VR4ZSo4Dh98KWHJhheAViylRvXJbmrgkQDK6Mfy1zxuTEfccbwQn5Vh99DYj2SFZNMXNXE+X8zcU8pVxh03T9cDiydsqkyxmSNcEkXgEeRsmG6d8Fd4LGWVOiChqQ==" Feb 17 00:57:01 volumio go-librespot[5433]: time="2025-02-17T00:57: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 17 00:57:01 volumio go-librespot[5433]: time="2025-02-17T00:57:01+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Feb 17 00:57:01 volumio go-librespot[5433]: time="2025-02-17T00:57:01+01:00" level=debug msg="completed keyexchange" Feb 17 00:57:01 volumio go-librespot[5433]: time="2025-02-17T00:57:01+01:00" level=debug msg="completed challenge" Feb 17 00:57:01 volumio go-librespot[5433]: time="2025-02-17T00:57:01+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 17 00:57:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 00:57:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 00:57:02 volumio volumio[995]: info: Initializing connection to go-librespot Websocket Feb 17 00:57:02 volumio volumio[995]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 17 00:57:04 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 17 00:57:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 221. Feb 17 00:57:04 volumio systemd[1]: Stopped go-librespot Daemon. Feb 17 00:57:04 volumio systemd[1]: Started go-librespot Daemon. Feb 17 00:57:04 volumio go-librespot[5442]: go-librespot daemon starting... Feb 17 00:57:04 volumio go-librespot[5442]: time="2025-02-17T00:57:04+01:00" level=info msg="running go-librespot 0.2.0" Feb 17 00:57:04 volumio go-librespot[5442]: time="2025-02-17T00:57:04+01:00" level=debug msg="app state loaded" Feb 17 00:57:04 volumio go-librespot[5442]: time="2025-02-17T00:57:04+01:00" level=debug msg="stored credentials not found" Feb 17 00:57:04 volumio go-librespot[5442]: time="2025-02-17T00:57:04+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 00:57:04 volumio go-librespot[5442]: time="2025-02-17T00:57: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-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 17 00:57:04 volumio go-librespot[5442]: time="2025-02-17T00:57:04+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 17 00:57:04 volumio go-librespot[5442]: time="2025-02-17T00:57:04+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 17 00:57:04 volumio go-librespot[5442]: time="2025-02-17T00:57:04+01:00" level=info msg="zeroconf server listening on port 43065" Feb 17 00:57:05 volumio go-librespot[5442]: time="2025-02-17T00:57:05+01:00" level=debug msg="obtained new client token: AAAm2Af9lU/k6sHZ+kPoSrw6iTDLA+MMSLic4X2G18OUPW5OYh+oszMFRE41KGPS9YqvYHKvD+EeRcpCoZ4EwdpnZopbgHrdWRZX5V3CUnWbQnKmMJiGy5YwpAfDCKvTTcMm9z8fY9/5DPUhS7UgSYiDOOlX2E61lJiBvJ6aNasdrd0z3e4cv2JNw7OofreR8+Oy1je6tLbsHGRA9+N+LEAsewNKF0Qi7iW+cky+NKIJAdnoS8bh3YM=" Feb 17 00:57:05 volumio go-librespot[5442]: time="2025-02-17T00:57:05+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 17 00:57:05 volumio go-librespot[5442]: time="2025-02-17T00:57:05+01:00" level=debug msg="completed keyexchange" Feb 17 00:57:05 volumio go-librespot[5442]: time="2025-02-17T00:57:05+01:00" level=debug msg="completed challenge" Feb 17 00:57:05 volumio go-librespot[5442]: time="2025-02-17T00:57:05+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 17 00:57:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 00:57:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 00:57:05 volumio volumio[995]: info: Initializing connection to go-librespot Websocket Feb 17 00:57:05 volumio volumio[995]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 17 00:57:07 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 17 00:57:07 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 17 00:57:07 volumio volumio[995]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 17 00:57:08 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 17 00:57:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 222. Feb 17 00:57:08 volumio systemd[1]: Stopped go-librespot Daemon. Feb 17 00:57:08 volumio systemd[1]: Started go-librespot Daemon. Feb 17 00:57:08 volumio go-librespot[5467]: go-librespot daemon starting... Feb 17 00:57:08 volumio go-librespot[5467]: time="2025-02-17T00:57:08+01:00" level=info msg="running go-librespot 0.2.0" Feb 17 00:57:08 volumio go-librespot[5467]: time="2025-02-17T00:57:08+01:00" level=debug msg="app state loaded" Feb 17 00:57:08 volumio go-librespot[5467]: time="2025-02-17T00:57:08+01:00" level=debug msg="stored credentials not found" Feb 17 00:57:08 volumio go-librespot[5467]: time="2025-02-17T00:57:08+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 00:57:08 volumio volumio[995]: info: Initializing connection to go-librespot Websocket Feb 17 00:57:08 volumio go-librespot[5467]: time="2025-02-17T00:57:08+01:00" level=debug msg="new websocket client" Feb 17 00:57:08 volumio volumio[995]: info: Connection to go-librespot Websocket established Feb 17 00:57:08 volumio go-librespot[5467]: time="2025-02-17T00:57: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 17 00:57:08 volumio go-librespot[5467]: time="2025-02-17T00:57: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 17 00:57:08 volumio go-librespot[5467]: time="2025-02-17T00:57: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 17 00:57:08 volumio go-librespot[5467]: time="2025-02-17T00:57:08+01:00" level=info msg="zeroconf server listening on port 39873" Feb 17 00:57:08 volumio go-librespot[5467]: time="2025-02-17T00:57:08+01:00" level=debug msg="obtained new client token: AAAIA78SrXO50jTsR8BTItEphcrr0z1QOzMeMsZNmonA2xLQ1yG/Yrj8FQZ+EcJRgAijwfg6eQb1wY20Ke97dzmIrvIUdOBcSELdLLNzUscGpKfUyDN0myT4Kb5IFoFbWBlbSyBfy2+sQiJ+jiMLC8Pv1oZslvQXQ9PYL/5XTUA/ZQykYpWttM3UqJkNFuoAjxiM3aU2kYWKxQUkC8a7hARtfDDX1ykoFI0BxSZIf2H+XnId1CaWBL7Fwg==" Feb 17 00:57:08 volumio go-librespot[5467]: time="2025-02-17T00:57:08+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 17 00:57:08 volumio go-librespot[5467]: time="2025-02-17T00:57:08+01:00" level=debug msg="completed keyexchange" Feb 17 00:57:08 volumio go-librespot[5467]: time="2025-02-17T00:57:08+01:00" level=debug msg="completed challenge" Feb 17 00:57:08 volumio go-librespot[5467]: time="2025-02-17T00:57:08+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 17 00:57:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 00:57:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 00:57:08 volumio volumio[995]: info: Connection to go-librespot Websocket closed Feb 17 00:57:11 volumio volumio[995]: info: Getting Spotify volume Feb 17 00:57:11 volumio volumio[995]: (node:995) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 17 00:57:11 volumio volumio[995]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Feb 17 00:57:11 volumio volumio[995]: (node:995) 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: 32) Feb 17 00:57:11 volumio volumio[995]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Feb 17 00:57:11 volumio volumio[995]: info: CoreCommandRouter::volumioGetState Feb 17 00:57:11 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Feb 17 00:57:11 volumio volumio[995]: SPOTIFY: RECEIVED VOLUMIO VOLUME 96 Feb 17 00:57:11 volumio volumio[995]: info: Initializing connection to go-librespot Websocket Feb 17 00:57:11 volumio volumio[995]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 17 00:57:11 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 17 00:57:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 223. Feb 17 00:57:11 volumio systemd[1]: Stopped go-librespot Daemon. Feb 17 00:57:12 volumio systemd[1]: Started go-librespot Daemon. Feb 17 00:57:12 volumio go-librespot[5475]: go-librespot daemon starting... Feb 17 00:57:12 volumio go-librespot[5475]: time="2025-02-17T00:57:12+01:00" level=info msg="running go-librespot 0.2.0" Feb 17 00:57:12 volumio go-librespot[5475]: time="2025-02-17T00:57:12+01:00" level=debug msg="app state loaded" Feb 17 00:57:12 volumio go-librespot[5475]: time="2025-02-17T00:57:12+01:00" level=debug msg="stored credentials not found" Feb 17 00:57:12 volumio go-librespot[5475]: time="2025-02-17T00:57:12+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 00:57:12 volumio go-librespot[5475]: time="2025-02-17T00:57: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 17 00:57:12 volumio go-librespot[5475]: time="2025-02-17T00:57: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 17 00:57:12 volumio go-librespot[5475]: time="2025-02-17T00:57: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 17 00:57:12 volumio go-librespot[5475]: time="2025-02-17T00:57:12+01:00" level=info msg="zeroconf server listening on port 40537" Feb 17 00:57:12 volumio go-librespot[5475]: time="2025-02-17T00:57:12+01:00" level=debug msg="obtained new client token: AACuMgj/iBckzFFXSJIyDUYpfDqwt/pdYxy/CV/+qdkQyCXYsawSSQ/Gnly+sQ4UXHMElfvdpTHey3XAvcfwS7508LSC6taEHPBB1SSJm6yecPGoKA8cFJUgWAofiIZV+N7TCCuls0Fwa4lG6SNKOOVj3f2OHILOQu3UO1+kVHtZikIwyg3HNnqYM/m+qFRtlVFWwBm8HiCLfzVx0auJb78cd6tayOXZV5oQvldL909LSD32NQ9Ocxk=" Feb 17 00:57:12 volumio go-librespot[5475]: time="2025-02-17T00:57:12+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 17 00:57:12 volumio go-librespot[5475]: time="2025-02-17T00:57:12+01:00" level=debug msg="completed keyexchange" Feb 17 00:57:12 volumio go-librespot[5475]: time="2025-02-17T00:57:12+01:00" level=debug msg="completed challenge" Feb 17 00:57:12 volumio go-librespot[5475]: time="2025-02-17T00:57:12+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 17 00:57:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 00:57:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 00:57:14 volumio volumio[995]: info: Initializing connection to go-librespot Websocket Feb 17 00:57:14 volumio volumio[995]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 17 00:57:15 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 17 00:57:15 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 224. Feb 17 00:57:15 volumio systemd[1]: Stopped go-librespot Daemon. Feb 17 00:57:15 volumio systemd[1]: Started go-librespot Daemon. Feb 17 00:57:15 volumio go-librespot[5487]: go-librespot daemon starting... Feb 17 00:57:15 volumio go-librespot[5487]: time="2025-02-17T00:57:15+01:00" level=info msg="running go-librespot 0.2.0" Feb 17 00:57:15 volumio go-librespot[5487]: time="2025-02-17T00:57:15+01:00" level=debug msg="app state loaded" Feb 17 00:57:15 volumio go-librespot[5487]: time="2025-02-17T00:57:15+01:00" level=debug msg="stored credentials not found" Feb 17 00:57:15 volumio go-librespot[5487]: time="2025-02-17T00:57:15+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 00:57:15 volumio go-librespot[5487]: time="2025-02-17T00:57:15+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 17 00:57:15 volumio go-librespot[5487]: time="2025-02-17T00:57:15+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 17 00:57:15 volumio go-librespot[5487]: time="2025-02-17T00:57:15+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 17 00:57:15 volumio go-librespot[5487]: time="2025-02-17T00:57:15+01:00" level=info msg="zeroconf server listening on port 38455" Feb 17 00:57:16 volumio go-librespot[5487]: time="2025-02-17T00:57:16+01:00" level=debug msg="obtained new client token: AABW5U5XhFHMIUvM3DX4aY6QY1evMAUCltsezQ0dHpmjPErhUdSSzmaG0f9F05dZAekBb4JIjA2lJc/qHx187bHs4dHES1X+PkQBajNK0aTTFetnfwLmoQketCQqbl6HG/pdEx5ub+GNkMMIfnNj250LEl0OPuEf6Mftao0IEgDP2HYm16H6o8PvshcI966RHIZdCNrwVWwYgdcycaR4w/tgl1J6qipdHhYVzJ00X7GpNC+kHB39g4sJDw==" Feb 17 00:57:16 volumio go-librespot[5487]: time="2025-02-17T00:57:16+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 17 00:57:16 volumio go-librespot[5487]: time="2025-02-17T00:57:16+01:00" level=debug msg="completed keyexchange" Feb 17 00:57:16 volumio go-librespot[5487]: time="2025-02-17T00:57:16+01:00" level=debug msg="completed challenge" Feb 17 00:57:16 volumio go-librespot[5487]: time="2025-02-17T00:57:16+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 17 00:57:16 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 00:57:16 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 00:57:17 volumio volumio[995]: info: Initializing connection to go-librespot Websocket Feb 17 00:57:17 volumio volumio[995]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 17 00:57:18 volumio volumio[995]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 17 00:57:19 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 17 00:57:19 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 225. Feb 17 00:57:19 volumio systemd[1]: Stopped go-librespot Daemon. Feb 17 00:57:19 volumio systemd[1]: Started go-librespot Daemon. Feb 17 00:57:19 volumio go-librespot[5513]: go-librespot daemon starting... Feb 17 00:57:19 volumio go-librespot[5513]: time="2025-02-17T00:57:19+01:00" level=info msg="running go-librespot 0.2.0" Feb 17 00:57:19 volumio go-librespot[5513]: time="2025-02-17T00:57:19+01:00" level=debug msg="app state loaded" Feb 17 00:57:19 volumio go-librespot[5513]: time="2025-02-17T00:57:19+01:00" level=debug msg="stored credentials not found" Feb 17 00:57:19 volumio go-librespot[5513]: time="2025-02-17T00:57:19+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 00:57:19 volumio go-librespot[5513]: time="2025-02-17T00:57:19+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 17 00:57:19 volumio go-librespot[5513]: time="2025-02-17T00:57:19+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 17 00:57:19 volumio go-librespot[5513]: time="2025-02-17T00:57:19+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 17 00:57:19 volumio go-librespot[5513]: time="2025-02-17T00:57:19+01:00" level=info msg="zeroconf server listening on port 36187" Feb 17 00:57:19 volumio go-librespot[5513]: time="2025-02-17T00:57:19+01:00" level=debug msg="obtained new client token: AAAxaL89VbBJFU/6K03TOyhAZniUNtkVYS+ZMVcHz1wrgBtnPYMNg5nSMAg21hPSK7QSi9A1J7v7MbzZNJGoBopzn6TOYjxn1HRA0SOGuoX0ilaLJD/GmtpTQKQ+/FgVmHGMC2wmc7GNFVIoqgMVFHsB9obHnECiYE+u3+Jq12kOKlvYJyxxtUtM5bcQnnfwbziN5DO+Q/oQsN3OYpLhqTcFcpN08eTjcWm8ns6aZ4SS0ixlKH3SIJTQNQ==" Feb 17 00:57:19 volumio go-librespot[5513]: time="2025-02-17T00:57:19+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 17 00:57:19 volumio go-librespot[5513]: time="2025-02-17T00:57:19+01:00" level=debug msg="completed keyexchange" Feb 17 00:57:19 volumio go-librespot[5513]: time="2025-02-17T00:57:19+01:00" level=debug msg="completed challenge" Feb 17 00:57:19 volumio go-librespot[5513]: time="2025-02-17T00:57:19+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 17 00:57:19 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 00:57:19 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 00:57:20 volumio volumio[995]: info: Initializing connection to go-librespot Websocket Feb 17 00:57:20 volumio volumio[995]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 17 00:57:22 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 17 00:57:22 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 226. Feb 17 00:57:22 volumio systemd[1]: Stopped go-librespot Daemon. Feb 17 00:57:22 volumio systemd[1]: Started go-librespot Daemon. Feb 17 00:57:22 volumio go-librespot[5521]: go-librespot daemon starting... Feb 17 00:57:23 volumio go-librespot[5521]: time="2025-02-17T00:57:23+01:00" level=info msg="running go-librespot 0.2.0" Feb 17 00:57:23 volumio go-librespot[5521]: time="2025-02-17T00:57:23+01:00" level=debug msg="app state loaded" Feb 17 00:57:23 volumio go-librespot[5521]: time="2025-02-17T00:57:23+01:00" level=debug msg="stored credentials not found" Feb 17 00:57:23 volumio go-librespot[5521]: time="2025-02-17T00:57:23+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 00:57:23 volumio go-librespot[5521]: time="2025-02-17T00:57: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-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 17 00:57:23 volumio go-librespot[5521]: time="2025-02-17T00:57:23+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 17 00:57:23 volumio go-librespot[5521]: time="2025-02-17T00:57:23+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 17 00:57:23 volumio go-librespot[5521]: time="2025-02-17T00:57:23+01:00" level=info msg="zeroconf server listening on port 38313" Feb 17 00:57:23 volumio go-librespot[5521]: time="2025-02-17T00:57:23+01:00" level=debug msg="obtained new client token: AADTvCmRbnGu7QFtUz8lOUACmwx3BRTbZNBqg3B5OZi2EfIL491shwxxT/xFCKzB7jD8ARWQ79asFb2mU6DTmHGzbLuDL3Zj8My8mma5Ez6Ws2aABAbHDsNLCV2OsDLrGdAUm64B7MWou3YZxAnrYq6Wpuhc/LHgu1qSIbZNpY3KzfPQek8xj8Xp0UHsu9NtmUV7q/tR16W7sQ8CLp6eFpfCazsZ3wEBpd7Tv9zmb6MwWPui5ECBaCY=" Feb 17 00:57:23 volumio go-librespot[5521]: time="2025-02-17T00:57:23+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 17 00:57:23 volumio go-librespot[5521]: time="2025-02-17T00:57:23+01:00" level=debug msg="completed keyexchange" Feb 17 00:57:23 volumio go-librespot[5521]: time="2025-02-17T00:57:23+01:00" level=debug msg="completed challenge" Feb 17 00:57:23 volumio go-librespot[5521]: time="2025-02-17T00:57:23+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 17 00:57:23 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 00:57:23 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 00:57:23 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 17 00:57:23 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 17 00:57:23 volumio volumio[995]: info: Initializing connection to go-librespot Websocket Feb 17 00:57:23 volumio volumio[995]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 17 00:57:26 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 17 00:57:26 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 227. Feb 17 00:57:26 volumio systemd[1]: Stopped go-librespot Daemon. Feb 17 00:57:26 volumio systemd[1]: Started go-librespot Daemon. Feb 17 00:57:26 volumio go-librespot[5546]: go-librespot daemon starting... Feb 17 00:57:26 volumio go-librespot[5546]: time="2025-02-17T00:57:26+01:00" level=info msg="running go-librespot 0.2.0" Feb 17 00:57:26 volumio go-librespot[5546]: time="2025-02-17T00:57:26+01:00" level=debug msg="app state loaded" Feb 17 00:57:26 volumio go-librespot[5546]: time="2025-02-17T00:57:26+01:00" level=debug msg="stored credentials not found" Feb 17 00:57:26 volumio go-librespot[5546]: time="2025-02-17T00:57:26+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 00:57:26 volumio go-librespot[5546]: time="2025-02-17T00:57:26+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 17 00:57:26 volumio go-librespot[5546]: time="2025-02-17T00:57:26+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 17 00:57:26 volumio go-librespot[5546]: time="2025-02-17T00:57:26+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 17 00:57:26 volumio go-librespot[5546]: time="2025-02-17T00:57:26+01:00" level=info msg="zeroconf server listening on port 34555" Feb 17 00:57:26 volumio go-librespot[5546]: time="2025-02-17T00:57:26+01:00" level=debug msg="obtained new client token: AACxy5adgXLMBPAWCbxyAIP6pVGhD90y8+s3cXyHWx2GeGW32x8Tb+0kgwr4WV5iKZwvpjj/qdeNp4oWDBUPWtJ803H9VYyEaS6bNRsFGtliDZV3MkApadEXv/D8+jBhT+ahNTgNaKtNsg+6loPcS8WP/ilOKIAYw/yk8/WsZREnfSdvNick7EW4s/WGzSPT0+cfLPAMXFZwSNDA51OoQQ0y8zaPmtTO09U/7LITNDo9P6EiZ/KUU+6Vvw==" Feb 17 00:57:26 volumio go-librespot[5546]: time="2025-02-17T00:57:26+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 17 00:57:26 volumio go-librespot[5546]: time="2025-02-17T00:57:26+01:00" level=debug msg="completed keyexchange" Feb 17 00:57:26 volumio go-librespot[5546]: time="2025-02-17T00:57:26+01:00" level=debug msg="completed challenge" Feb 17 00:57:26 volumio go-librespot[5546]: time="2025-02-17T00:57:26+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 17 00:57:26 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 00:57:26 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 00:57:26 volumio volumio[995]: info: Initializing connection to go-librespot Websocket Feb 17 00:57:26 volumio volumio[995]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 17 00:57:29 volumio volumio[995]: info: Initializing connection to go-librespot Websocket Feb 17 00:57:29 volumio volumio[995]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 17 00:57:29 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 17 00:57:29 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 228. Feb 17 00:57:29 volumio systemd[1]: Stopped go-librespot Daemon. Feb 17 00:57:29 volumio systemd[1]: Started go-librespot Daemon. Feb 17 00:57:29 volumio go-librespot[5554]: go-librespot daemon starting... Feb 17 00:57:29 volumio go-librespot[5554]: time="2025-02-17T00:57:29+01:00" level=info msg="running go-librespot 0.2.0" Feb 17 00:57:29 volumio go-librespot[5554]: time="2025-02-17T00:57:29+01:00" level=debug msg="app state loaded" Feb 17 00:57:29 volumio go-librespot[5554]: time="2025-02-17T00:57:29+01:00" level=debug msg="stored credentials not found" Feb 17 00:57:29 volumio go-librespot[5554]: time="2025-02-17T00:57:29+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 00:57:30 volumio go-librespot[5554]: time="2025-02-17T00:57:30+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 17 00:57:30 volumio go-librespot[5554]: time="2025-02-17T00:57:30+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 17 00:57:30 volumio go-librespot[5554]: time="2025-02-17T00:57:30+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 17 00:57:30 volumio go-librespot[5554]: time="2025-02-17T00:57:30+01:00" level=info msg="zeroconf server listening on port 39655" Feb 17 00:57:30 volumio go-librespot[5554]: time="2025-02-17T00:57:30+01:00" level=debug msg="obtained new client token: AADBhoc5f0wygxr2x2YN79RnDxdm7JjrG+vVQWy1Die1dVfccY6GqngDPxcb+smTgyCAUpFQ4/GdNeQs1iXsC8QOn1vGldGqwhYLQ+NWJEXpSJQJbhqzkp6JzKA9M1CuEyVnuQ9lO3AGMNg4GXdissPWzHOlfdNa/dMoissSDEQwsgEV9k15dIAmS+w3FIImR4oRmz/8seehupftn4gvIvTg5vxBKkO3s8jJJs9tbyL0XdX9/my/kmrDyQ==" Feb 17 00:57:30 volumio go-librespot[5554]: time="2025-02-17T00:57:30+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 17 00:57:30 volumio go-librespot[5554]: time="2025-02-17T00:57:30+01:00" level=debug msg="completed keyexchange" Feb 17 00:57:30 volumio go-librespot[5554]: time="2025-02-17T00:57:30+01:00" level=debug msg="completed challenge" Feb 17 00:57:31 volumio go-librespot[5554]: time="2025-02-17T00:57:31+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 17 00:57:31 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 00:57:31 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 00:57:32 volumio volumio[995]: info: Initializing connection to go-librespot Websocket Feb 17 00:57:32 volumio volumio[995]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 17 00:57:34 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 17 00:57:34 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 229. Feb 17 00:57:34 volumio systemd[1]: Stopped go-librespot Daemon. Feb 17 00:57:34 volumio systemd[1]: Started go-librespot Daemon. Feb 17 00:57:34 volumio go-librespot[5562]: go-librespot daemon starting... Feb 17 00:57:34 volumio go-librespot[5562]: time="2025-02-17T00:57:34+01:00" level=info msg="running go-librespot 0.2.0" Feb 17 00:57:34 volumio go-librespot[5562]: time="2025-02-17T00:57:34+01:00" level=debug msg="app state loaded" Feb 17 00:57:34 volumio go-librespot[5562]: time="2025-02-17T00:57:34+01:00" level=debug msg="stored credentials not found" Feb 17 00:57:34 volumio go-librespot[5562]: time="2025-02-17T00:57:34+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 00:57:34 volumio go-librespot[5562]: time="2025-02-17T00:57:34+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 17 00:57:34 volumio go-librespot[5562]: time="2025-02-17T00:57:34+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 17 00:57:34 volumio go-librespot[5562]: time="2025-02-17T00:57:34+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 17 00:57:34 volumio go-librespot[5562]: time="2025-02-17T00:57:34+01:00" level=info msg="zeroconf server listening on port 39775" Feb 17 00:57:34 volumio go-librespot[5562]: time="2025-02-17T00:57:34+01:00" level=debug msg="obtained new client token: AABcVxZ3Kt94a9cg50MYqH4dm3EAO3Tq4U9WG/luA/4ipgH1mVxX5DOkEqgF4Lciuf8eC9IjeAEWozuL4zdUJ9wzhTGzCKRPR8GNM42QMOzp1120G+1D8wwZnQCcwJ3WxJq6dS5mGrgWLgf8HCViIN6sPnkWkgL2rtAlUk8oFluz4RfDxZTl/LQ298PUwmFXtL/UALp/2BkGHtiVZfmbFQK7WS/UuS7/IqeIv5nax4x/nC6XmKHctMJaqg==" Feb 17 00:57:34 volumio go-librespot[5562]: time="2025-02-17T00:57:34+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 17 00:57:34 volumio go-librespot[5562]: time="2025-02-17T00:57:34+01:00" level=debug msg="completed keyexchange" Feb 17 00:57:34 volumio go-librespot[5562]: time="2025-02-17T00:57:34+01:00" level=debug msg="completed challenge" Feb 17 00:57:34 volumio go-librespot[5562]: time="2025-02-17T00:57:34+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 17 00:57:34 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 00:57:34 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 00:57:35 volumio volumio[995]: info: Initializing connection to go-librespot Websocket Feb 17 00:57:35 volumio volumio[995]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 17 00:57:36 volumio systemd[1]: Starting Cleanup of Temporary Directories... Feb 17 00:57:36 volumio systemd[1]: systemd-tmpfiles-clean.service: Succeeded. Feb 17 00:57:36 volumio systemd[1]: Started Cleanup of Temporary Directories. Feb 17 00:57:37 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 17 00:57:37 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 230. Feb 17 00:57:37 volumio systemd[1]: Stopped go-librespot Daemon. Feb 17 00:57:37 volumio systemd[1]: Started go-librespot Daemon. Feb 17 00:57:37 volumio go-librespot[5588]: go-librespot daemon starting... Feb 17 00:57:37 volumio go-librespot[5588]: time="2025-02-17T00:57:37+01:00" level=info msg="running go-librespot 0.2.0" Feb 17 00:57:37 volumio go-librespot[5588]: time="2025-02-17T00:57:37+01:00" level=debug msg="app state loaded" Feb 17 00:57:37 volumio go-librespot[5588]: time="2025-02-17T00:57:37+01:00" level=debug msg="stored credentials not found" Feb 17 00:57:37 volumio go-librespot[5588]: time="2025-02-17T00:57:37+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 00:57:37 volumio go-librespot[5588]: time="2025-02-17T00:57:37+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 17 00:57:37 volumio go-librespot[5588]: time="2025-02-17T00:57:37+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 17 00:57:37 volumio go-librespot[5588]: time="2025-02-17T00:57:37+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 17 00:57:37 volumio go-librespot[5588]: time="2025-02-17T00:57:37+01:00" level=info msg="zeroconf server listening on port 43029" Feb 17 00:57:37 volumio go-librespot[5588]: time="2025-02-17T00:57:37+01:00" level=debug msg="obtained new client token: AADc2oZsykHB/Wuc3kTzxLwb8oX7ib+xSi5yYazZBPeeDBaHK+/9FCGHMGDH7nufiMWWqiGXq7vcBZFt/wWAZ39qbb0p9o0IWYpOCNZHJZy41aq8thJ8mQ1OsHq7mzNqlZ77NQq5Kt5hwymdiZqZPl6zcj7RyoQz4q8jZ07a+MonNiqufHjh02DVh2jUpg+Pj453J/4yU8GXQIctt/YKc3DRq1Wmvhq0WvKYjSS4w9ZMZVkGB3A9WDi3AA==" Feb 17 00:57:38 volumio go-librespot[5588]: time="2025-02-17T00:57:38+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 17 00:57:38 volumio go-librespot[5588]: time="2025-02-17T00:57:38+01:00" level=debug msg="completed keyexchange" Feb 17 00:57:38 volumio go-librespot[5588]: time="2025-02-17T00:57:38+01:00" level=debug msg="completed challenge" Feb 17 00:57:38 volumio go-librespot[5588]: time="2025-02-17T00:57:38+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 17 00:57:38 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 00:57:38 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 00:57:38 volumio volumio[995]: info: Initializing connection to go-librespot Websocket Feb 17 00:57:38 volumio volumio[995]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 17 00:57:41 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 17 00:57:41 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 231. Feb 17 00:57:41 volumio systemd[1]: Stopped go-librespot Daemon. Feb 17 00:57:41 volumio systemd[1]: Started go-librespot Daemon. Feb 17 00:57:41 volumio go-librespot[5597]: go-librespot daemon starting... Feb 17 00:57:41 volumio go-librespot[5597]: time="2025-02-17T00:57:41+01:00" level=info msg="running go-librespot 0.2.0" Feb 17 00:57:41 volumio go-librespot[5597]: time="2025-02-17T00:57:41+01:00" level=debug msg="app state loaded" Feb 17 00:57:41 volumio go-librespot[5597]: time="2025-02-17T00:57:41+01:00" level=debug msg="stored credentials not found" Feb 17 00:57:41 volumio go-librespot[5597]: time="2025-02-17T00:57:41+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 00:57:41 volumio go-librespot[5597]: time="2025-02-17T00:57:41+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 17 00:57:41 volumio go-librespot[5597]: time="2025-02-17T00:57:41+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 17 00:57:41 volumio go-librespot[5597]: time="2025-02-17T00:57:41+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 17 00:57:41 volumio go-librespot[5597]: time="2025-02-17T00:57:41+01:00" level=info msg="zeroconf server listening on port 39847" Feb 17 00:57:41 volumio go-librespot[5597]: time="2025-02-17T00:57:41+01:00" level=debug msg="obtained new client token: AACUpysuaWa7CGbC0t9jNcZedB6J6dOosJEIZtbrGWWPG9r19DNy0/tSmD8Jlrm1LruKHUB1mtYLlwgJ0kk40y3iwYLxUNUMJxm8/7wcY1UuhsvGZCYDvoD57++PL0XCKvXwiO62K2wLYRs+RYihKK1hJKWVDKHtGdpKzo6H/hhOY9dSIMFJV42BP4q54NcJzXiAkRgh3kWF+NQt9CCEosmgoUt+NAFshNVBOApNprJPmaFDfB49+JkYBg==" Feb 17 00:57:41 volumio go-librespot[5597]: time="2025-02-17T00:57:41+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 17 00:57:41 volumio go-librespot[5597]: time="2025-02-17T00:57:41+01:00" level=debug msg="completed keyexchange" Feb 17 00:57:41 volumio go-librespot[5597]: time="2025-02-17T00:57:41+01:00" level=debug msg="completed challenge" Feb 17 00:57:41 volumio go-librespot[5597]: time="2025-02-17T00:57:41+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 17 00:57:41 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 00:57:41 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 00:57:41 volumio volumio[995]: info: Initializing connection to go-librespot Websocket Feb 17 00:57:41 volumio volumio[995]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 17 00:57:44 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 17 00:57:44 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 232. Feb 17 00:57:44 volumio systemd[1]: Stopped go-librespot Daemon. Feb 17 00:57:44 volumio systemd[1]: Started go-librespot Daemon. Feb 17 00:57:44 volumio go-librespot[5607]: go-librespot daemon starting... Feb 17 00:57:44 volumio go-librespot[5607]: time="2025-02-17T00:57:44+01:00" level=info msg="running go-librespot 0.2.0" Feb 17 00:57:44 volumio go-librespot[5607]: time="2025-02-17T00:57:44+01:00" level=debug msg="app state loaded" Feb 17 00:57:44 volumio go-librespot[5607]: time="2025-02-17T00:57:44+01:00" level=debug msg="stored credentials not found" Feb 17 00:57:44 volumio go-librespot[5607]: time="2025-02-17T00:57:44+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 00:57:44 volumio volumio[995]: info: Initializing connection to go-librespot Websocket Feb 17 00:57:44 volumio go-librespot[5607]: time="2025-02-17T00:57:44+01:00" level=debug msg="new websocket client" Feb 17 00:57:44 volumio volumio[995]: info: Connection to go-librespot Websocket established Feb 17 00:57:45 volumio go-librespot[5607]: time="2025-02-17T00:57:45+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 17 00:57:45 volumio go-librespot[5607]: time="2025-02-17T00:57:45+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 17 00:57:45 volumio go-librespot[5607]: time="2025-02-17T00:57:45+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 17 00:57:45 volumio go-librespot[5607]: time="2025-02-17T00:57:45+01:00" level=info msg="zeroconf server listening on port 41305" Feb 17 00:57:45 volumio go-librespot[5607]: time="2025-02-17T00:57:45+01:00" level=debug msg="obtained new client token: AAAqjPzuBCwIjIdfg/rqQDdANpD5cR0P16fogtKVgQFx5HqChZUMVVmmD8S8BbL4+LS8uW1u8k9eaFJckTwe6+nGsuCdHvXNRfeupPvbPf64tGhgFO6T86LwhJZGqDdatG162Ecz2dG2o8HEXfvD4G0aTHCnxL4m1oi/frYuLviy8YYpUM3mtBx0L0mpc+7bEpuAVBzVvmxEHO9n1EX5Kn8xX1sKAz2ke27aUrOLlJtrK+3JF/NkBi6EUQ==" Feb 17 00:57:45 volumio go-librespot[5607]: time="2025-02-17T00:57:45+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 17 00:57:45 volumio go-librespot[5607]: time="2025-02-17T00:57:45+01:00" level=debug msg="completed keyexchange" Feb 17 00:57:45 volumio go-librespot[5607]: time="2025-02-17T00:57:45+01:00" level=debug msg="completed challenge" Feb 17 00:57:45 volumio go-librespot[5607]: time="2025-02-17T00:57:45+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 17 00:57:45 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 00:57:45 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 00:57:45 volumio volumio[995]: info: Connection to go-librespot Websocket closed Feb 17 00:57:47 volumio volumio[995]: info: Getting Spotify volume Feb 17 00:57:47 volumio volumio[995]: (node:995) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 17 00:57:47 volumio volumio[995]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Feb 17 00:57:47 volumio volumio[995]: (node:995) 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: 33) Feb 17 00:57:47 volumio volumio[995]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Feb 17 00:57:47 volumio volumio[995]: info: CoreCommandRouter::volumioGetState Feb 17 00:57:47 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Feb 17 00:57:47 volumio volumio[995]: SPOTIFY: RECEIVED VOLUMIO VOLUME 96 Feb 17 00:57:48 volumio volumio[995]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/80s80s/volumio/buster/armhf Feb 17 00:57:48 volumio volumio[995]: info: Initializing connection to go-librespot Websocket Feb 17 00:57:48 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 17 00:57:48 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 233. Feb 17 00:57:48 volumio systemd[1]: Stopped go-librespot Daemon. Feb 17 00:57:48 volumio volumio[995]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 17 00:57:48 volumio systemd[1]: Started go-librespot Daemon. Feb 17 00:57:48 volumio go-librespot[5634]: go-librespot daemon starting... Feb 17 00:57:48 volumio go-librespot[5634]: time="2025-02-17T00:57:48+01:00" level=info msg="running go-librespot 0.2.0" Feb 17 00:57:48 volumio go-librespot[5634]: time="2025-02-17T00:57:48+01:00" level=debug msg="app state loaded" Feb 17 00:57:48 volumio go-librespot[5634]: time="2025-02-17T00:57:48+01:00" level=debug msg="stored credentials not found" Feb 17 00:57:48 volumio go-librespot[5634]: time="2025-02-17T00:57:48+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 00:57:48 volumio go-librespot[5634]: time="2025-02-17T00:57:48+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 17 00:57:48 volumio go-librespot[5634]: time="2025-02-17T00:57:48+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 17 00:57:48 volumio go-librespot[5634]: time="2025-02-17T00:57:48+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 17 00:57:48 volumio go-librespot[5634]: time="2025-02-17T00:57:48+01:00" level=info msg="zeroconf server listening on port 33237" Feb 17 00:57:48 volumio go-librespot[5634]: time="2025-02-17T00:57:48+01:00" level=debug msg="obtained new client token: AADUYRtt2tNLi4QVzKQBF1TSe0kqyg6OKwBQLZpYnOajLGGASnH//DC+G/y+d2e5W6oQxdyHuHQa31itZRe/F7PPoq7c0gG8SDVvX5JybIa+3yACdY/UFnSmZ2YdC2ppsecvEH0HqxuPDsqjIq4BR+6Cf3tmNLwcDUZ8CreWGzWG1d4qXOxp7fsw9WZpaMNL7hSpNy+6YuWZjEDDp+MzVRDUw45/pH5RAfz9Ss0PhHsdV0nEfnJAsP9A/Q==" Feb 17 00:57:48 volumio go-librespot[5634]: time="2025-02-17T00:57:48+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 17 00:57:48 volumio go-librespot[5634]: time="2025-02-17T00:57:48+01:00" level=debug msg="completed keyexchange" Feb 17 00:57:48 volumio go-librespot[5634]: time="2025-02-17T00:57:48+01:00" level=debug msg="completed challenge" Feb 17 00:57:48 volumio go-librespot[5634]: time="2025-02-17T00:57:48+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 17 00:57:48 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 00:57:48 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 00:57:50 volumio volumio[995]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/80s80s/volumio/buster/armhf Feb 17 00:57:50 volumio volumio[995]: info: Check plugin dependencies Feb 17 00:57:50 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 17 00:57:50 volumio volumio[995]: info: Rename folder Feb 17 00:57:50 volumio volumio[995]: info: Folder /tmp/downloaded_plugin.zip removed Feb 17 00:57:50 volumio volumio[995]: info: Move to category Feb 17 00:57:51 volumio volumio[995]: info: Checking if install.sh is present Feb 17 00:57:51 volumio volumio[995]: info: Initializing connection to go-librespot Websocket Feb 17 00:57:51 volumio volumio[995]: info: Executing install.sh Feb 17 00:57:51 volumio volumio[995]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 17 00:57:51 volumio sudo[5655]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/80s80s/install.sh Feb 17 00:57:51 volumio sudo[5655]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 00:57:51 volumio sudo[5655]: pam_unix(sudo:session): session closed for user root Feb 17 00:57:51 volumio volumio[995]: info: Install script completed Feb 17 00:57:51 volumio volumio[995]: info: Done installing plugin. Feb 17 00:57:51 volumio volumio[995]: info: Enabling plugin 80s80s Feb 17 00:57:51 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 17 00:57:51 volumio volumio[995]: info: [{"prettyName":"FusionDsp","name":"fusiondsp","category":"audio_interface","version":"1.0.54","icon":"fa-sliders fa-rotate-90","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"80s80s Radio","name":"80s80s","category":"music_service","version":"1.0.4","icon":"fa-headphones","isManuallyInstalled":false,"enabled":true,"active":false},{"prettyName":"Roon Bridge","name":"RoonBridge","category":"music_service","version":"1.0.0","icon":"fas fa-volume-up","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Calm Radio","name":"calmradio","category":"music_service","version":"1.2.4","icon":"fa-heartbeat","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"DMD2 Music","name":"dmd2_music","category":"music_service","version":"1.1.3","icon":"fa-headphones","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Jellyfin","name":"jellyfin","category":"music_service","version":"1.0.6","icon":"fa-music","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Mixcloud","name":"mixcloud","category":"music_service","version":"0.1.3","icon":"fa-mixcloud","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"MotherEarthRadio","name":"motherearthradio","category":"music_service","version":"1.2.2","icon":"fa-lightbulb-o","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"pandora","name":"pandora","category":"music_service","version":"2.12.1","icon":"fa-lightbulb-o","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Personal Radio","name":"personal_radio","category":"music_service","version":"1.3.0","icon":"fa-headphones","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"RADIO 357","name":"rad357pl","category":"music_service","version":"1.0.2","icon":"fa-lightbulb-o","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Radio Paradise","name":"radio_paradise","category":"music_service","version":"1.0.3","icon":"fa-headphones","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"SoundCloud","name":"soundcloud","category":"music_service","version":"1.0.2","icon":"fa-music","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Spotify","name":"spop","category":"music_service","version":"4.1.3","icon":"fa-spotify","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"SWARM Station","name":"swarmstation","category":"music_service","version":"1.0.2","icon":"fa-lightbulb-o","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Audiophonics ON/OFF","name":"audiophonicsonoff","category":"system_controller","version":"1.0.4","icon":"fa fa-cogs","isManuallyInstalled":false,"enabled":true,"active":false},{"prettyName":"Audiophonics Evo Sabre","name":"audiophonics_evo_sabre","category":"system_hardware","version":"1.0.0","icon":"fa-hdd-o","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Audiophonics ON/OFF","name":"audiophonicsonoff","category":"system_hardware","version":"1.0.5","icon":"fa fa-cogs","isManuallyInstalled":false,"enabled":true,"active":false}] Feb 17 00:57:51 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 17 00:57:51 volumio volumio[995]: info: Installing 80s80s radio Dependencies Feb 17 00:57:51 volumio volumio[995]: Plugin install end detected on script Feb 17 00:57:51 volumio volumio[995]: info: Folder /tmp/plugins removed Feb 17 00:57:51 volumio volumio[995]: info: Folder /tmp/downloaded_plugin.zip removed Feb 17 00:57:51 volumio volumio[995]: info: Folder /data/temp removed Feb 17 00:57:51 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 17 00:57:51 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 234. Feb 17 00:57:51 volumio systemd[1]: Stopped go-librespot Daemon. Feb 17 00:57:51 volumio systemd[1]: Started go-librespot Daemon. Feb 17 00:57:51 volumio go-librespot[5657]: go-librespot daemon starting... Feb 17 00:57:51 volumio go-librespot[5657]: time="2025-02-17T00:57:51+01:00" level=info msg="running go-librespot 0.2.0" Feb 17 00:57:51 volumio go-librespot[5657]: time="2025-02-17T00:57:51+01:00" level=debug msg="app state loaded" Feb 17 00:57:51 volumio go-librespot[5657]: time="2025-02-17T00:57:51+01:00" level=debug msg="stored credentials not found" Feb 17 00:57:51 volumio go-librespot[5657]: time="2025-02-17T00:57:51+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 00:57:52 volumio go-librespot[5657]: time="2025-02-17T00:57:52+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 17 00:57:52 volumio go-librespot[5657]: time="2025-02-17T00:57:52+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 17 00:57:52 volumio go-librespot[5657]: time="2025-02-17T00:57:52+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 17 00:57:52 volumio go-librespot[5657]: time="2025-02-17T00:57:52+01:00" level=info msg="zeroconf server listening on port 40469" Feb 17 00:57:52 volumio go-librespot[5657]: time="2025-02-17T00:57:52+01:00" level=debug msg="obtained new client token: AABVM+gnTdlC3ZAuntHHJIExleH3h/L89w81I68YDw4RegnQORlVkCuluOmRUM2o92Re96zl6h/oc33q1LReWfTnVn5c+e9ex8Y3oV1DpuZopyowszciU8QeEy0u2i+Nsdu1rBGGv4YvH3FYNH9mElYtmV4D5GIMqAxpXCklBrFpdkynoZfH1Db6XWbZszFmWlhTj8+bEzyY0cVmKaet3vepZWqynUGcjGc71d/Ar8Fx6LLKFrlcnTo=" Feb 17 00:57:52 volumio go-librespot[5657]: time="2025-02-17T00:57:52+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 17 00:57:52 volumio go-librespot[5657]: time="2025-02-17T00:57:52+01:00" level=debug msg="completed keyexchange" Feb 17 00:57:52 volumio go-librespot[5657]: time="2025-02-17T00:57:52+01:00" level=debug msg="completed challenge" Feb 17 00:57:52 volumio go-librespot[5657]: time="2025-02-17T00:57:52+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 17 00:57:52 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 00:57:52 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 00:57:54 volumio volumio[995]: info: Initializing connection to go-librespot Websocket Feb 17 00:57:54 volumio volumio[995]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 17 00:57:55 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 17 00:57:55 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 235. Feb 17 00:57:55 volumio systemd[1]: Stopped go-librespot Daemon. Feb 17 00:57:55 volumio systemd[1]: Started go-librespot Daemon. Feb 17 00:57:55 volumio go-librespot[5703]: go-librespot daemon starting... Feb 17 00:57:55 volumio go-librespot[5703]: time="2025-02-17T00:57:55+01:00" level=info msg="running go-librespot 0.2.0" Feb 17 00:57:55 volumio go-librespot[5703]: time="2025-02-17T00:57:55+01:00" level=debug msg="app state loaded" Feb 17 00:57:55 volumio go-librespot[5703]: time="2025-02-17T00:57:55+01:00" level=debug msg="stored credentials not found" Feb 17 00:57:55 volumio go-librespot[5703]: time="2025-02-17T00:57:55+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 00:57:55 volumio go-librespot[5703]: time="2025-02-17T00:57:55+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 17 00:57:55 volumio go-librespot[5703]: time="2025-02-17T00:57:55+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 17 00:57:55 volumio go-librespot[5703]: time="2025-02-17T00:57:55+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 17 00:57:55 volumio go-librespot[5703]: time="2025-02-17T00:57:55+01:00" level=info msg="zeroconf server listening on port 42345" Feb 17 00:57:56 volumio go-librespot[5703]: time="2025-02-17T00:57:56+01:00" level=debug msg="obtained new client token: AAAf27vKewcDmPX2lpiP1MPPvlPtlyZ5taI8435MBrURJfFmRyNp7cZ5j+67uR2YcvOUQDWSJSceaA2FbG2Ol4e+Q0nEhdLrEuTupe5uUfI8FSgBfUeRJm6vpLuU2xd0c+rSrwhrBqgmTB56jCFOC9ZIWeEhaECjQWxamP8C6EKnAKiNqoyZ9P4XnzY+Tq8uhDmJaFEozTw2PvaeE7p2wJ9c/RogYkorb6dRMaW9gACv7VrU1VecMwc=" Feb 17 00:57:56 volumio go-librespot[5703]: time="2025-02-17T00:57:56+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 17 00:57:56 volumio go-librespot[5703]: time="2025-02-17T00:57:56+01:00" level=debug msg="completed keyexchange" Feb 17 00:57:56 volumio go-librespot[5703]: time="2025-02-17T00:57:56+01:00" level=debug msg="completed challenge" Feb 17 00:57:56 volumio go-librespot[5703]: time="2025-02-17T00:57:56+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 17 00:57:56 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 00:57:56 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 00:57:57 volumio volumio[995]: info: Initializing connection to go-librespot Websocket Feb 17 00:57:57 volumio volumio[995]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 17 00:57:59 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 17 00:57:59 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 236. Feb 17 00:57:59 volumio systemd[1]: Stopped go-librespot Daemon. Feb 17 00:57:59 volumio systemd[1]: Started go-librespot Daemon. Feb 17 00:57:59 volumio go-librespot[5730]: go-librespot daemon starting... Feb 17 00:57:59 volumio go-librespot[5730]: time="2025-02-17T00:57:59+01:00" level=info msg="running go-librespot 0.2.0" Feb 17 00:57:59 volumio go-librespot[5730]: time="2025-02-17T00:57:59+01:00" level=debug msg="app state loaded" Feb 17 00:57:59 volumio go-librespot[5730]: time="2025-02-17T00:57:59+01:00" level=debug msg="stored credentials not found" Feb 17 00:57:59 volumio go-librespot[5730]: time="2025-02-17T00:57:59+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 00:57:59 volumio go-librespot[5730]: time="2025-02-17T00:57:59+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 17 00:57:59 volumio go-librespot[5730]: time="2025-02-17T00:57:59+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 17 00:57:59 volumio go-librespot[5730]: time="2025-02-17T00:57:59+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 17 00:57:59 volumio go-librespot[5730]: time="2025-02-17T00:57:59+01:00" level=info msg="zeroconf server listening on port 42295" Feb 17 00:57:59 volumio go-librespot[5730]: time="2025-02-17T00:57:59+01:00" level=debug msg="obtained new client token: AABf0P1tqgHer6oPTdKkt1tTB645tXSbeWb6+acEPuVnzhzbmLNEzi7fiHBlehXMrUmK7lUHx6gnQTgQ3DL4xrb/yC4CyMU13wcOb+1leqNlyz3EQXRs3jE0nQax407ISdH0EZbff0OkftlbEw0W29ZF/KHIF8cPvQpsgJSF+pfzWRILGi/ejSRl87CFogvUxqUowGnukLirkd5oYKJ8BLxPRb7qhzCwsMfqECfUXM9ZF0Ygfzdg2YsbEQ==" Feb 17 00:57:59 volumio go-librespot[5730]: time="2025-02-17T00:57:59+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 17 00:57:59 volumio go-librespot[5730]: time="2025-02-17T00:57:59+01:00" level=debug msg="completed keyexchange" Feb 17 00:57:59 volumio go-librespot[5730]: time="2025-02-17T00:57:59+01:00" level=debug msg="completed challenge" Feb 17 00:57:59 volumio go-librespot[5730]: time="2025-02-17T00:57:59+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 17 00:57:59 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 00:57:59 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 00:58:00 volumio volumio[995]: info: Initializing connection to go-librespot Websocket Feb 17 00:58:00 volumio volumio[995]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 17 00:58:02 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 17 00:58:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 237. Feb 17 00:58:02 volumio systemd[1]: Stopped go-librespot Daemon. Feb 17 00:58:02 volumio systemd[1]: Started go-librespot Daemon. Feb 17 00:58:02 volumio go-librespot[5738]: go-librespot daemon starting... Feb 17 00:58:02 volumio go-librespot[5738]: time="2025-02-17T00:58:02+01:00" level=info msg="running go-librespot 0.2.0" Feb 17 00:58:02 volumio go-librespot[5738]: time="2025-02-17T00:58:02+01:00" level=debug msg="app state loaded" Feb 17 00:58:02 volumio go-librespot[5738]: time="2025-02-17T00:58:02+01:00" level=debug msg="stored credentials not found" Feb 17 00:58:02 volumio go-librespot[5738]: time="2025-02-17T00:58:02+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 00:58:03 volumio go-librespot[5738]: time="2025-02-17T00:58:03+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 17 00:58:03 volumio go-librespot[5738]: time="2025-02-17T00:58:03+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 17 00:58:03 volumio go-librespot[5738]: time="2025-02-17T00:58:03+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 17 00:58:03 volumio go-librespot[5738]: time="2025-02-17T00:58:03+01:00" level=info msg="zeroconf server listening on port 44345" Feb 17 00:58:03 volumio go-librespot[5738]: time="2025-02-17T00:58:03+01:00" level=debug msg="obtained new client token: AADOiGaGtZvvx8BWTnfkPKyh1cq7SW5kl82K0aB8SXjA52LYhJkgHuKaivF16VMXLoiPouYIBqZGKJehFib6bPEhlXdvpXt5JH8pxKxx1MwK2l21J+4eZESInZOcJ5kyP8PAV1XfmqW80z0Hb6F/JSFHN4ELldIIMEg0q2SIIfUHRlpoRMarFoA1StstEs0JnMnkgJ4Aul+Hi6RLMCdcmCiPgrvunFBkPU7cKSyxmHthdlqPWz9WKFg=" Feb 17 00:58:03 volumio go-librespot[5738]: time="2025-02-17T00:58:03+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 17 00:58:03 volumio go-librespot[5738]: time="2025-02-17T00:58:03+01:00" level=debug msg="completed keyexchange" Feb 17 00:58:03 volumio go-librespot[5738]: time="2025-02-17T00:58:03+01:00" level=debug msg="completed challenge" Feb 17 00:58:03 volumio go-librespot[5738]: time="2025-02-17T00:58:03+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 17 00:58:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 00:58:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 00:58:03 volumio volumio[995]: info: Initializing connection to go-librespot Websocket Feb 17 00:58:03 volumio volumio[995]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 17 00:58:06 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 17 00:58:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 238. Feb 17 00:58:06 volumio systemd[1]: Stopped go-librespot Daemon. Feb 17 00:58:06 volumio systemd[1]: Started go-librespot Daemon. Feb 17 00:58:06 volumio go-librespot[5763]: go-librespot daemon starting... Feb 17 00:58:06 volumio go-librespot[5763]: time="2025-02-17T00:58:06+01:00" level=info msg="running go-librespot 0.2.0" Feb 17 00:58:06 volumio go-librespot[5763]: time="2025-02-17T00:58:06+01:00" level=debug msg="app state loaded" Feb 17 00:58:06 volumio go-librespot[5763]: time="2025-02-17T00:58:06+01:00" level=debug msg="stored credentials not found" Feb 17 00:58:06 volumio go-librespot[5763]: time="2025-02-17T00:58:06+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 00:58:06 volumio volumio[995]: info: Initializing connection to go-librespot Websocket Feb 17 00:58:06 volumio go-librespot[5763]: time="2025-02-17T00:58:06+01:00" level=debug msg="new websocket client" Feb 17 00:58:06 volumio volumio[995]: info: Connection to go-librespot Websocket established Feb 17 00:58:06 volumio go-librespot[5763]: time="2025-02-17T00:58:06+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 17 00:58:06 volumio go-librespot[5763]: time="2025-02-17T00:58:06+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 17 00:58:06 volumio go-librespot[5763]: time="2025-02-17T00:58:06+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 17 00:58:06 volumio go-librespot[5763]: time="2025-02-17T00:58:06+01:00" level=info msg="zeroconf server listening on port 42743" Feb 17 00:58:06 volumio go-librespot[5763]: time="2025-02-17T00:58:06+01:00" level=debug msg="obtained new client token: AADelnngJcoNTgVCuKVGEgVcyPH0MHquPKKeC5lHQiavfoCGcnE8OE6jtyZno98N7oKXijpfPhB32JqxTRpKJReJtca9uD6RIRnq+DwU6PEjJdSw8rp8wW7dbYjsEAXl60m4dk21V6QaekyTUXK7Eq7+YYRQ2ONQPbt3tAQwKcN4wX542KMO62QIHSjKI0oQ3UoL6kYKFUp3t+6uNlU1Qr1hlklsV1n36vbSSxlyFvi4LAIYei5SLBtMiA==" Feb 17 00:58:06 volumio go-librespot[5763]: time="2025-02-17T00:58:06+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 17 00:58:06 volumio go-librespot[5763]: time="2025-02-17T00:58:06+01:00" level=debug msg="completed keyexchange" Feb 17 00:58:06 volumio go-librespot[5763]: time="2025-02-17T00:58:06+01:00" level=debug msg="completed challenge" Feb 17 00:58:06 volumio go-librespot[5763]: time="2025-02-17T00:58:06+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 17 00:58:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 00:58:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 00:58:06 volumio volumio[995]: info: Connection to go-librespot Websocket closed Feb 17 00:58:09 volumio volumio[995]: info: Getting Spotify volume Feb 17 00:58:09 volumio volumio[995]: (node:995) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 17 00:58:09 volumio volumio[995]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Feb 17 00:58:09 volumio volumio[995]: (node:995) 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: 34) Feb 17 00:58:09 volumio volumio[995]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Feb 17 00:58:09 volumio volumio[995]: info: CoreCommandRouter::volumioGetState Feb 17 00:58:09 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Feb 17 00:58:09 volumio volumio[995]: SPOTIFY: RECEIVED VOLUMIO VOLUME 96 Feb 17 00:58:09 volumio volumio[995]: info: Initializing connection to go-librespot Websocket Feb 17 00:58:09 volumio volumio[995]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 17 00:58:09 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 17 00:58:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 239. Feb 17 00:58:09 volumio systemd[1]: Stopped go-librespot Daemon. Feb 17 00:58:09 volumio systemd[1]: Started go-librespot Daemon. Feb 17 00:58:09 volumio go-librespot[5771]: go-librespot daemon starting... Feb 17 00:58:09 volumio go-librespot[5771]: time="2025-02-17T00:58:09+01:00" level=info msg="running go-librespot 0.2.0" Feb 17 00:58:09 volumio go-librespot[5771]: time="2025-02-17T00:58:09+01:00" level=debug msg="app state loaded" Feb 17 00:58:09 volumio go-librespot[5771]: time="2025-02-17T00:58:09+01:00" level=debug msg="stored credentials not found" Feb 17 00:58:09 volumio go-librespot[5771]: time="2025-02-17T00:58:09+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 00:58:10 volumio go-librespot[5771]: time="2025-02-17T00:58:10+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 17 00:58:10 volumio go-librespot[5771]: time="2025-02-17T00:58:10+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 17 00:58:10 volumio go-librespot[5771]: time="2025-02-17T00:58:10+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 17 00:58:10 volumio go-librespot[5771]: time="2025-02-17T00:58:10+01:00" level=info msg="zeroconf server listening on port 39033" Feb 17 00:58:10 volumio go-librespot[5771]: time="2025-02-17T00:58:10+01:00" level=debug msg="obtained new client token: AAC/b2F5h6sijf6xs7ZvV+kW6JuQVvoWKLK904i9DTJBTBo07NVyfOkbZW4rrEEhv6DO8lrplBAxOYqJ9dImPbjQwao1pj48XcG1rD8tRSal5tqV0U5qUmxm3jDBRt2CO8sTUtcWvrsUvGwV1CyGmiXOOia6nzfz0c95KYEw4TK81QMyoJBbjrFOsjO1mQamVnivIeEqxJUDicGdikLmR/y8+9WQ3JL746kTOB3cw3w+oHCgYaqWqta+qg==" Feb 17 00:58:10 volumio go-librespot[5771]: time="2025-02-17T00:58:10+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 17 00:58:10 volumio go-librespot[5771]: time="2025-02-17T00:58:10+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Feb 17 00:58:10 volumio go-librespot[5771]: time="2025-02-17T00:58:10+01:00" level=debug msg="completed keyexchange" Feb 17 00:58:10 volumio go-librespot[5771]: time="2025-02-17T00:58:10+01:00" level=debug msg="completed challenge" Feb 17 00:58:10 volumio go-librespot[5771]: time="2025-02-17T00:58:10+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 17 00:58:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 00:58:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 00:58:12 volumio volumio[995]: info: Initializing connection to go-librespot Websocket Feb 17 00:58:12 volumio volumio[995]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 17 00:58:14 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 17 00:58:14 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 240. Feb 17 00:58:14 volumio systemd[1]: Stopped go-librespot Daemon. Feb 17 00:58:14 volumio systemd[1]: Started go-librespot Daemon. Feb 17 00:58:14 volumio go-librespot[5783]: go-librespot daemon starting... Feb 17 00:58:14 volumio go-librespot[5783]: time="2025-02-17T00:58:14+01:00" level=info msg="running go-librespot 0.2.0" Feb 17 00:58:14 volumio go-librespot[5783]: time="2025-02-17T00:58:14+01:00" level=debug msg="app state loaded" Feb 17 00:58:14 volumio go-librespot[5783]: time="2025-02-17T00:58:14+01:00" level=debug msg="stored credentials not found" Feb 17 00:58:14 volumio go-librespot[5783]: time="2025-02-17T00:58:14+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 00:58:14 volumio go-librespot[5783]: time="2025-02-17T00:58:14+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 17 00:58:14 volumio go-librespot[5783]: time="2025-02-17T00:58:14+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 17 00:58:14 volumio go-librespot[5783]: time="2025-02-17T00:58:14+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 17 00:58:14 volumio go-librespot[5783]: time="2025-02-17T00:58:14+01:00" level=info msg="zeroconf server listening on port 38427" Feb 17 00:58:14 volumio go-librespot[5783]: time="2025-02-17T00:58:14+01:00" level=debug msg="obtained new client token: AACesThWK+7Xt6j/Sl3ma5cGu3iQgO7B04jQWg11Cm/tDDdwCjzg9hOCZRTfH7MrKT0oTI7on+b4YHoE+ULCp9oknr4gTtcOnkPZcGJ4FW9uiOK/oXbhE0KPljgz7joUKiPq8FdmvtvoIZClD1lRf+H5/o7R0p61U/iJR2Jt0Ursyd4eIzcRWNHt5OLK/WnDfxgvcoFLpWmDuAKfU6ZWFWI4mQYa9ivdRoHd3ANivlkYPW4O+Lp+4Rtt0A==" Feb 17 00:58:14 volumio go-librespot[5783]: time="2025-02-17T00:58:14+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 17 00:58:14 volumio go-librespot[5783]: time="2025-02-17T00:58:14+01:00" level=debug msg="completed keyexchange" Feb 17 00:58:14 volumio go-librespot[5783]: time="2025-02-17T00:58:14+01:00" level=debug msg="completed challenge" Feb 17 00:58:14 volumio go-librespot[5783]: time="2025-02-17T00:58:14+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 17 00:58:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 00:58:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 00:58:15 volumio volumio[995]: info: Initializing connection to go-librespot Websocket Feb 17 00:58:15 volumio volumio[995]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 17 00:58:17 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 17 00:58:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 241. Feb 17 00:58:17 volumio systemd[1]: Stopped go-librespot Daemon. Feb 17 00:58:17 volumio systemd[1]: Started go-librespot Daemon. Feb 17 00:58:17 volumio go-librespot[5811]: go-librespot daemon starting... Feb 17 00:58:17 volumio go-librespot[5811]: time="2025-02-17T00:58:17+01:00" level=info msg="running go-librespot 0.2.0" Feb 17 00:58:17 volumio go-librespot[5811]: time="2025-02-17T00:58:17+01:00" level=debug msg="app state loaded" Feb 17 00:58:17 volumio go-librespot[5811]: time="2025-02-17T00:58:17+01:00" level=debug msg="stored credentials not found" Feb 17 00:58:17 volumio go-librespot[5811]: time="2025-02-17T00:58:17+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 00:58:17 volumio go-librespot[5811]: time="2025-02-17T00:58:17+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 17 00:58:17 volumio go-librespot[5811]: time="2025-02-17T00:58:17+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 17 00:58:17 volumio go-librespot[5811]: time="2025-02-17T00:58:17+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 17 00:58:17 volumio go-librespot[5811]: time="2025-02-17T00:58:17+01:00" level=info msg="zeroconf server listening on port 35623" Feb 17 00:58:17 volumio go-librespot[5811]: time="2025-02-17T00:58:17+01:00" level=debug msg="obtained new client token: AACYtkk9erh7h8j5LdPpNdBwWvoEMKH7sYVIjuDYaPsAErLf669bnP/efwH7OxjpIRfldOWeavZKopu1k/uOJp2a4i19UPN4V0mLnMne3Smt163dn0t8oSFhJRx1VmvEThm7h+j5zZnIPSb0QNVayIqBKGdQm1IdfBxIvw/huNOfTF3+iHcPNN2gRGYCUGFHOOz6TXwhyT6jO8qA77nmHyrxK/CZTvTBW8nRRc6sYMrSdjjh/D/vqvdvwQ==" Feb 17 00:58:17 volumio go-librespot[5811]: time="2025-02-17T00:58:17+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 17 00:58:18 volumio go-librespot[5811]: time="2025-02-17T00:58:18+01:00" level=debug msg="completed keyexchange" Feb 17 00:58:18 volumio go-librespot[5811]: time="2025-02-17T00:58:18+01:00" level=debug msg="completed challenge" Feb 17 00:58:18 volumio go-librespot[5811]: time="2025-02-17T00:58:18+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 17 00:58:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 00:58:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 00:58:18 volumio volumio[995]: info: Initializing connection to go-librespot Websocket Feb 17 00:58:18 volumio volumio[995]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 17 00:58:21 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 17 00:58:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 242. Feb 17 00:58:21 volumio systemd[1]: Stopped go-librespot Daemon. Feb 17 00:58:21 volumio systemd[1]: Started go-librespot Daemon. Feb 17 00:58:21 volumio go-librespot[5819]: go-librespot daemon starting... Feb 17 00:58:21 volumio go-librespot[5819]: time="2025-02-17T00:58:21+01:00" level=info msg="running go-librespot 0.2.0" Feb 17 00:58:21 volumio go-librespot[5819]: time="2025-02-17T00:58:21+01:00" level=debug msg="app state loaded" Feb 17 00:58:21 volumio go-librespot[5819]: time="2025-02-17T00:58:21+01:00" level=debug msg="stored credentials not found" Feb 17 00:58:21 volumio go-librespot[5819]: time="2025-02-17T00:58:21+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 00:58:21 volumio go-librespot[5819]: time="2025-02-17T00:58:21+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 17 00:58:21 volumio go-librespot[5819]: time="2025-02-17T00:58:21+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 17 00:58:21 volumio go-librespot[5819]: time="2025-02-17T00:58:21+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 17 00:58:21 volumio go-librespot[5819]: time="2025-02-17T00:58:21+01:00" level=info msg="zeroconf server listening on port 45051" Feb 17 00:58:21 volumio volumio[995]: info: Initializing connection to go-librespot Websocket Feb 17 00:58:21 volumio go-librespot[5819]: time="2025-02-17T00:58:21+01:00" level=debug msg="new websocket client" Feb 17 00:58:21 volumio volumio[995]: info: Connection to go-librespot Websocket established Feb 17 00:58:22 volumio go-librespot[5819]: time="2025-02-17T00:58:22+01:00" level=debug msg="obtained new client token: AAAfBl8MTpCwhmporCCaq4p8jAPziUhP+My9u/ZX4H6aSuOIqFyrI4xSYRGRU2SAf2kp1v20KUvmZZwG3GwCqu4BoYhq8fVmpUc0yd+JKxsSQpoz3dlBieUPg0klRREz6rI99YQQrZJKyVO9/YaH+JFIy4jzfOC9q6B5T0SnLXHr08CMEos8fgoMkz4Hnvf11mSU8l8V57U8VzdSTzjpkGPZ6ayB8YyStThTzqKiau9jjCelGz0LwOYBrQ==" Feb 17 00:58:22 volumio go-librespot[5819]: time="2025-02-17T00:58:22+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 17 00:58:22 volumio go-librespot[5819]: time="2025-02-17T00:58:22+01:00" level=debug msg="completed keyexchange" Feb 17 00:58:22 volumio go-librespot[5819]: time="2025-02-17T00:58:22+01:00" level=debug msg="completed challenge" Feb 17 00:58:22 volumio go-librespot[5819]: time="2025-02-17T00:58:22+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 17 00:58:22 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 00:58:22 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 00:58:22 volumio volumio[995]: info: Connection to go-librespot Websocket closed Feb 17 00:58:24 volumio volumio[995]: info: Getting Spotify volume Feb 17 00:58:24 volumio volumio[995]: (node:995) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 17 00:58:24 volumio volumio[995]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Feb 17 00:58:24 volumio volumio[995]: (node:995) 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: 35) Feb 17 00:58:24 volumio volumio[995]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Feb 17 00:58:24 volumio volumio[995]: info: CoreCommandRouter::volumioGetState Feb 17 00:58:24 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Feb 17 00:58:24 volumio volumio[995]: SPOTIFY: RECEIVED VOLUMIO VOLUME 96 Feb 17 00:58:25 volumio volumio[995]: info: Initializing connection to go-librespot Websocket Feb 17 00:58:25 volumio volumio[995]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 17 00:58:25 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 17 00:58:25 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 243. Feb 17 00:58:25 volumio systemd[1]: Stopped go-librespot Daemon. Feb 17 00:58:25 volumio systemd[1]: Started go-librespot Daemon. Feb 17 00:58:25 volumio go-librespot[5827]: go-librespot daemon starting... Feb 17 00:58:25 volumio go-librespot[5827]: time="2025-02-17T00:58:25+01:00" level=info msg="running go-librespot 0.2.0" Feb 17 00:58:25 volumio go-librespot[5827]: time="2025-02-17T00:58:25+01:00" level=debug msg="app state loaded" Feb 17 00:58:25 volumio go-librespot[5827]: time="2025-02-17T00:58:25+01:00" level=debug msg="stored credentials not found" Feb 17 00:58:25 volumio go-librespot[5827]: time="2025-02-17T00:58:25+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 00:58:25 volumio go-librespot[5827]: time="2025-02-17T00:58: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-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 17 00:58:25 volumio go-librespot[5827]: time="2025-02-17T00:58:25+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 17 00:58:25 volumio go-librespot[5827]: time="2025-02-17T00:58:25+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 17 00:58:25 volumio go-librespot[5827]: time="2025-02-17T00:58:25+01:00" level=info msg="zeroconf server listening on port 44683" Feb 17 00:58:25 volumio go-librespot[5827]: time="2025-02-17T00:58:25+01:00" level=debug msg="obtained new client token: AABnoD1jnYX0nfv8SXjuhq/tAB4FoGr6+u5bs1q25b7Iakre+fZpYnFDRJceS3Ca5dT7Umwb+PVzz5+A/CCX33eZDfjry9hjqRJI2zcKjVfaQ1UHYS+8HTFHjRQ0GKRSXJacoLFp+Dc2A5i1e1ElSdGnfa24zUEYDUqM4o61IBTU039/+UTLXhE2V9IOCOKgPa+29q3euDaGDlK7SeMcflGmWzCIeCWr0zSKDOpjeJWCJmukbGCv96sXbQ==" Feb 17 00:58:26 volumio go-librespot[5827]: time="2025-02-17T00:58:26+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 17 00:58:26 volumio go-librespot[5827]: time="2025-02-17T00:58:26+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Feb 17 00:58:26 volumio go-librespot[5827]: time="2025-02-17T00:58:26+01:00" level=debug msg="completed keyexchange" Feb 17 00:58:26 volumio go-librespot[5827]: time="2025-02-17T00:58:26+01:00" level=debug msg="completed challenge" Feb 17 00:58:26 volumio go-librespot[5827]: time="2025-02-17T00:58:26+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 17 00:58:26 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 00:58:26 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 00:58:28 volumio volumio[995]: info: Initializing connection to go-librespot Websocket Feb 17 00:58:28 volumio volumio[995]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 17 00:58:29 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 17 00:58:29 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 244. Feb 17 00:58:29 volumio systemd[1]: Stopped go-librespot Daemon. Feb 17 00:58:29 volumio systemd[1]: Started go-librespot Daemon. Feb 17 00:58:29 volumio go-librespot[5852]: go-librespot daemon starting... Feb 17 00:58:29 volumio go-librespot[5852]: time="2025-02-17T00:58:29+01:00" level=info msg="running go-librespot 0.2.0" Feb 17 00:58:29 volumio go-librespot[5852]: time="2025-02-17T00:58:29+01:00" level=debug msg="app state loaded" Feb 17 00:58:29 volumio go-librespot[5852]: time="2025-02-17T00:58:29+01:00" level=debug msg="stored credentials not found" Feb 17 00:58:29 volumio go-librespot[5852]: time="2025-02-17T00:58:29+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 00:58:29 volumio go-librespot[5852]: time="2025-02-17T00:58:29+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 17 00:58:29 volumio go-librespot[5852]: time="2025-02-17T00:58:29+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 17 00:58:29 volumio go-librespot[5852]: time="2025-02-17T00:58:29+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 17 00:58:29 volumio go-librespot[5852]: time="2025-02-17T00:58:29+01:00" level=info msg="zeroconf server listening on port 44599" Feb 17 00:58:29 volumio go-librespot[5852]: time="2025-02-17T00:58:29+01:00" level=debug msg="obtained new client token: AACZUE5zBRiJcVkcd+fU2VbivdmiKUH//39BevirXNh7PXGBDWlXbLimfiGmpFIPIZIszBgFQ7GQaBRywBvuRq+KF89z+Qh4BHd0vBPM8trn+RCzj9Y0o46lNQxCaOEClcymGbChtvzjUqx+ipWS5fIdfBj2Yq+gnR8QDCD79cr8kOYiHZSPhxufZtcRQAqgYOhIc/9TvNKJjhzpFveoertclPQ85Unp/PxzROqOjuMBGsWUbK1EEE+Rfg==" Feb 17 00:58:29 volumio go-librespot[5852]: time="2025-02-17T00:58:29+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 17 00:58:29 volumio go-librespot[5852]: time="2025-02-17T00:58:29+01:00" level=debug msg="completed keyexchange" Feb 17 00:58:29 volumio go-librespot[5852]: time="2025-02-17T00:58:29+01:00" level=debug msg="completed challenge" Feb 17 00:58:29 volumio go-librespot[5852]: time="2025-02-17T00:58:29+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 17 00:58:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 00:58:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 00:58:31 volumio volumio[995]: info: Initializing connection to go-librespot Websocket Feb 17 00:58:31 volumio volumio[995]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 17 00:58:31 volumio volumio[995]: info: CoreCommandRouter::volumioGetState Feb 17 00:58:31 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Feb 17 00:58:32 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 17 00:58:32 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 245. Feb 17 00:58:32 volumio systemd[1]: Stopped go-librespot Daemon. Feb 17 00:58:32 volumio systemd[1]: Started go-librespot Daemon. Feb 17 00:58:32 volumio go-librespot[5860]: go-librespot daemon starting... Feb 17 00:58:32 volumio go-librespot[5860]: time="2025-02-17T00:58:32+01:00" level=info msg="running go-librespot 0.2.0" Feb 17 00:58:32 volumio go-librespot[5860]: time="2025-02-17T00:58:32+01:00" level=debug msg="app state loaded" Feb 17 00:58:32 volumio go-librespot[5860]: time="2025-02-17T00:58:32+01:00" level=debug msg="stored credentials not found" Feb 17 00:58:32 volumio go-librespot[5860]: time="2025-02-17T00:58:32+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 00:58:32 volumio go-librespot[5860]: time="2025-02-17T00:58:32+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 17 00:58:32 volumio go-librespot[5860]: time="2025-02-17T00:58:32+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 17 00:58:32 volumio go-librespot[5860]: time="2025-02-17T00:58:32+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 17 00:58:32 volumio go-librespot[5860]: time="2025-02-17T00:58:32+01:00" level=info msg="zeroconf server listening on port 39345" Feb 17 00:58:32 volumio go-librespot[5860]: time="2025-02-17T00:58:32+01:00" level=debug msg="obtained new client token: AABm8ixR9niY6jw4IdXn8ReICCJSG7v/M9ccgePXKh0cRWNXLVr113nES42QkeV0TylmxSXud8sUh3IRDs0/B1au6I74/6UYBBEo/AC+7oggkFze3n5aORtOWHKup5AiKxTbWDLBhg+0VllGD7zAz4AdvaDLavDCg1sF8flkagZpfPMkk1ZJDzkT/mMNTC9wyiKvwRBfCr7lSuv02KUamMuS41uZ6t16zt/0AV4IAvg0DLqE6a26d557kg==" Feb 17 00:58:32 volumio go-librespot[5860]: time="2025-02-17T00:58:32+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 17 00:58:33 volumio go-librespot[5860]: time="2025-02-17T00:58:33+01:00" level=debug msg="completed keyexchange" Feb 17 00:58:33 volumio go-librespot[5860]: time="2025-02-17T00:58:33+01:00" level=debug msg="completed challenge" Feb 17 00:58:33 volumio go-librespot[5860]: time="2025-02-17T00:58:33+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 17 00:58:33 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 00:58:33 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 00:58:34 volumio volumio[995]: info: Initializing connection to go-librespot Websocket Feb 17 00:58:34 volumio volumio[995]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 17 00:58:36 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 17 00:58:36 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 246. Feb 17 00:58:36 volumio systemd[1]: Stopped go-librespot Daemon. Feb 17 00:58:36 volumio systemd[1]: Started go-librespot Daemon. Feb 17 00:58:36 volumio go-librespot[5885]: go-librespot daemon starting... Feb 17 00:58:36 volumio go-librespot[5885]: time="2025-02-17T00:58:36+01:00" level=info msg="running go-librespot 0.2.0" Feb 17 00:58:36 volumio go-librespot[5885]: time="2025-02-17T00:58:36+01:00" level=debug msg="app state loaded" Feb 17 00:58:36 volumio go-librespot[5885]: time="2025-02-17T00:58:36+01:00" level=debug msg="stored credentials not found" Feb 17 00:58:36 volumio go-librespot[5885]: time="2025-02-17T00:58:36+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 00:58:36 volumio go-librespot[5885]: time="2025-02-17T00:58:36+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 17 00:58:36 volumio go-librespot[5885]: time="2025-02-17T00:58:36+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 17 00:58:36 volumio go-librespot[5885]: time="2025-02-17T00:58:36+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 17 00:58:36 volumio go-librespot[5885]: time="2025-02-17T00:58:36+01:00" level=info msg="zeroconf server listening on port 35601" Feb 17 00:58:36 volumio go-librespot[5885]: time="2025-02-17T00:58:36+01:00" level=debug msg="obtained new client token: AACPhWeptS5JMwsocfxbbnnBYgcgMPheEhYuAUA6MVLA6A01s0+2l2FcchqA6c13sI8fX3jIoz54dfzm9y9lIwGWdZHV56LRcE41yYTb4WGEhXS+PFGLhe1UUsRjSDnXliKbO0b2cj+GmKVuZOGasUSAxYnfkv8LsB+Cv9K+0GFMGFIH7mINdVbuGV23C0lwzUEneLWDbhzwN1PHJOC2TqJVGacq3Cxx8yJdyX2PPVLKpdU0cK7KQ+xrqw==" Feb 17 00:58:36 volumio go-librespot[5885]: time="2025-02-17T00:58:36+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 17 00:58:37 volumio go-librespot[5885]: time="2025-02-17T00:58:37+01:00" level=debug msg="completed keyexchange" Feb 17 00:58:37 volumio go-librespot[5885]: time="2025-02-17T00:58:37+01:00" level=debug msg="completed challenge" Feb 17 00:58:37 volumio go-librespot[5885]: time="2025-02-17T00:58:37+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 17 00:58:37 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 00:58:37 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 00:58:37 volumio volumio[995]: info: Initializing connection to go-librespot Websocket Feb 17 00:58:37 volumio volumio[995]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 17 00:58:37 volumio volumio[995]: info: ___________ PLUGINS: Run Shutdown Tasks ___________ Feb 17 00:58:37 volumio volumio[995]: info: PLUGIN onShutdown : networkfs Feb 17 00:58:37 volumio volumio[995]: info: PLUGIN onShutdown : audiophonicsonoff Feb 17 00:58:37 volumio volumio[995]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 17 00:58:37 volumio volumio[995]: TypeError: Cannot read property 'writeSync' of undefined Feb 17 00:58:37 volumio volumio[995]: at ControllerAudiophonicsOnOff.onVolumioShutdown (/data/plugins/system_controller/audiophonicsonoff/index.js:43:25) Feb 17 00:58:37 volumio volumio[995]: at PluginManager.onVolumioShutdownPlugin (/volumio/app/pluginmanager.js:638:30) Feb 17 00:58:37 volumio volumio[995]: at HashMap. (/volumio/app/pluginmanager.js:621:31) Feb 17 00:58:37 volumio volumio[995]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Feb 17 00:58:37 volumio volumio[995]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Feb 17 00:58:37 volumio volumio[995]: at PluginManager.onVolumioShutdown (/volumio/app/pluginmanager.js:619:20) Feb 17 00:58:37 volumio volumio[995]: at CoreCommandRouter.shutdown (/volumio/app/index.js:1332:24) Feb 17 00:58:37 volumio volumio[995]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:859:33) Feb 17 00:58:37 volumio volumio[995]: at Socket.emit (events.js:315:20) Feb 17 00:58:37 volumio volumio[995]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Feb 17 00:58:37 volumio volumio[995]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Feb 17 00:58:37 volumio volumio[995]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 17 00:58:37 volumio sudo[5902]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-17 00:57 Feb 17 00:58:37 volumio sudo[5902]: 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"