-- Logs begin at Wed 2025-10-29 02:28:39 UTC, end at Wed 2025-10-29 05:26:58 UTC. -- Oct 29 05:25:01 neosonar volumio[994]: info: Initializing connection to go-librespot Websocket Oct 29 05:25:01 neosonar volumio[994]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 05:25:02 neosonar systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 29 05:25:02 neosonar systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 129072. Oct 29 05:25:02 neosonar systemd[1]: Stopped go-librespot Daemon. Oct 29 05:25:02 neosonar systemd[1]: Started go-librespot Daemon. Oct 29 05:25:02 neosonar go-librespot[30066]: go-librespot daemon starting... Oct 29 05:25:03 neosonar go-librespot[30066]: time="2025-10-29T05:25:03Z" level=info msg="running go-librespot 0.2.0" Oct 29 05:25:03 neosonar go-librespot[30066]: time="2025-10-29T05:25:03Z" level=debug msg="app state loaded" Oct 29 05:25:03 neosonar go-librespot[30066]: time="2025-10-29T05:25:03Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 05:25:03 neosonar go-librespot[30066]: time="2025-10-29T05:25:03Z" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 29 05:25:03 neosonar go-librespot[30066]: time="2025-10-29T05:25:03Z" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 29 05:25:03 neosonar go-librespot[30066]: time="2025-10-29T05:25:03Z" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 29 05:25:03 neosonar go-librespot[30066]: time="2025-10-29T05:25:03Z" level=info msg="zeroconf server listening on port 43409" Oct 29 05:25:03 neosonar go-librespot[30066]: time="2025-10-29T05:25:03Z" level=debug msg="obtained new client token: AABWHzX34Uy41yzfKuNMm9HfMBJ0OL88yNNYBxziiSE0M050I1Nq/6qRRpeuETRfkkgnRP08dLq+LpE2AvSURLSaPrzjfb6eyI2ITnd36x/riaukMEcbsAheoLpdggku7U4LsdDixWcatYTy34Lxe8T0zqLS2OfJBAPk9RmwOK4wfXOrOdCByrddDjWd49Qkav8FV7fQLa9zDIVuw70OpIpqs/I2GdHIH8nEEd3jmO2TQk3VkPTZ8apsMNo=" Oct 29 05:25:03 neosonar go-librespot[30066]: time="2025-10-29T05:25:03Z" level=debug msg="connected to ap-gae2.spotify.com:4070" Oct 29 05:25:03 neosonar go-librespot[30066]: time="2025-10-29T05:25:03Z" level=debug msg="completed keyexchange" Oct 29 05:25:03 neosonar go-librespot[30066]: time="2025-10-29T05:25:03Z" level=debug msg="completed challenge" Oct 29 05:25:03 neosonar go-librespot[30066]: time="2025-10-29T05:25:03Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Oct 29 05:25:03 neosonar systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 05:25:03 neosonar systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 05:25:04 neosonar volumio[994]: info: Initializing connection to go-librespot Websocket Oct 29 05:25:04 neosonar volumio[994]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 05:25:06 neosonar systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 29 05:25:06 neosonar systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 129073. Oct 29 05:25:06 neosonar systemd[1]: Stopped go-librespot Daemon. Oct 29 05:25:06 neosonar systemd[1]: Started go-librespot Daemon. Oct 29 05:25:06 neosonar go-librespot[30075]: go-librespot daemon starting... Oct 29 05:25:06 neosonar go-librespot[30075]: time="2025-10-29T05:25:06Z" level=info msg="running go-librespot 0.2.0" Oct 29 05:25:06 neosonar go-librespot[30075]: time="2025-10-29T05:25:06Z" level=debug msg="app state loaded" Oct 29 05:25:06 neosonar go-librespot[30075]: time="2025-10-29T05:25:06Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 05:25:06 neosonar go-librespot[30075]: time="2025-10-29T05:25:06Z" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 29 05:25:06 neosonar go-librespot[30075]: time="2025-10-29T05:25:06Z" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 29 05:25:06 neosonar go-librespot[30075]: time="2025-10-29T05:25:06Z" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 29 05:25:07 neosonar go-librespot[30075]: time="2025-10-29T05:25:07Z" level=info msg="zeroconf server listening on port 43909" Oct 29 05:25:07 neosonar go-librespot[30075]: time="2025-10-29T05:25:07Z" level=debug msg="obtained new client token: AABQk8cLeF0mD6MVjGby+i0iuZrNA8L1qAxJXovtpbq2GoYJY9+R0H7jQsePEBbH4APKzBrk4L7lWKg+E17K8jgniD6qJ3bwLmB+MKBAtDz0ngjy/BrJaGnPYe2/c6KlVQL5skqLJvtnixy9vKB4VGXfuTr7NI/7XZ8E0dNmbZvf3ZrmvvSOIl60aeJlCf250exhRUYBxhBORVZoKOU8naGXck2UfLSquxAqr9IlsjWbwMeW7ou4gPPE" Oct 29 05:25:07 neosonar go-librespot[30075]: time="2025-10-29T05:25:07Z" level=debug msg="connected to ap-gae2.spotify.com:4070" Oct 29 05:25:07 neosonar volumio[994]: info: Initializing connection to go-librespot Websocket Oct 29 05:25:07 neosonar go-librespot[30075]: time="2025-10-29T05:25:07Z" level=debug msg="new websocket client" Oct 29 05:25:07 neosonar volumio[994]: info: Connection to go-librespot Websocket established Oct 29 05:25:07 neosonar go-librespot[30075]: time="2025-10-29T05:25:07Z" level=debug msg="completed keyexchange" Oct 29 05:25:07 neosonar go-librespot[30075]: time="2025-10-29T05:25:07Z" level=debug msg="completed challenge" Oct 29 05:25:07 neosonar go-librespot[30075]: time="2025-10-29T05:25:07Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Oct 29 05:25:07 neosonar systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 05:25:07 neosonar systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 05:25:07 neosonar volumio[994]: info: Connection to go-librespot Websocket closed Oct 29 05:25:10 neosonar volumio[994]: info: Getting Spotify volume Oct 29 05:25:10 neosonar volumio[994]: (node:994) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 05:25:10 neosonar volumio[994]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Oct 29 05:25:10 neosonar volumio[994]: (node:994) 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: 15926) Oct 29 05:25:10 neosonar volumio[994]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 29 05:25:10 neosonar volumio[994]: info: CoreCommandRouter::volumioGetState Oct 29 05:25:10 neosonar volumio[994]: info: CorePlayQueue::getTrack 0 Oct 29 05:25:10 neosonar volumio[994]: SPOTIFY: RECEIVED VOLUMIO VOLUME 89 Oct 29 05:25:10 neosonar volumio[994]: info: Initializing connection to go-librespot Websocket Oct 29 05:25:10 neosonar volumio[994]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 05:25:10 neosonar systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 29 05:25:10 neosonar systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 129074. Oct 29 05:25:10 neosonar systemd[1]: Stopped go-librespot Daemon. Oct 29 05:25:10 neosonar systemd[1]: Started go-librespot Daemon. Oct 29 05:25:10 neosonar go-librespot[30084]: go-librespot daemon starting... Oct 29 05:25:10 neosonar go-librespot[30084]: time="2025-10-29T05:25:10Z" level=info msg="running go-librespot 0.2.0" Oct 29 05:25:10 neosonar go-librespot[30084]: time="2025-10-29T05:25:10Z" level=debug msg="app state loaded" Oct 29 05:25:10 neosonar go-librespot[30084]: time="2025-10-29T05:25:10Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 05:25:10 neosonar go-librespot[30084]: time="2025-10-29T05:25:10Z" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 29 05:25:10 neosonar go-librespot[30084]: time="2025-10-29T05:25:10Z" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 29 05:25:10 neosonar go-librespot[30084]: time="2025-10-29T05:25:10Z" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 29 05:25:11 neosonar go-librespot[30084]: time="2025-10-29T05:25:11Z" level=info msg="zeroconf server listening on port 33409" Oct 29 05:25:11 neosonar go-librespot[30084]: time="2025-10-29T05:25:11Z" level=debug msg="obtained new client token: AABSc6EowWX0mG34dRJUju34d3k4XG1C8ZP7UfrSTPqlDDnMAjYAkQiNE6uOjSadAkfe2kSs8xb2pGjdp35U/Xmj4r7BVmotu8AmyMOsCsLobhlaFb5zXC6sNpdDJPqaGyFvSXu68EMlTETp6zV8EJwdRynkDBBblefTgQY8jWtpKgk6HPqnoGCC7/3oeqavbEF01yoJ7k6rA6nzx1d5+FH8uKV1uLG6yzRbO3HslNP45vNMjQYYiTBo" Oct 29 05:25:11 neosonar go-librespot[30084]: time="2025-10-29T05:25:11Z" level=debug msg="connected to ap-gae2.spotify.com:4070" Oct 29 05:25:11 neosonar go-librespot[30084]: time="2025-10-29T05:25:11Z" level=debug msg="completed keyexchange" Oct 29 05:25:11 neosonar go-librespot[30084]: time="2025-10-29T05:25:11Z" level=debug msg="completed challenge" Oct 29 05:25:11 neosonar go-librespot[30084]: time="2025-10-29T05:25:11Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Oct 29 05:25:11 neosonar systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 05:25:11 neosonar systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 05:25:13 neosonar volumio[994]: info: Initializing connection to go-librespot Websocket Oct 29 05:25:13 neosonar volumio[994]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 05:25:14 neosonar systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 29 05:25:14 neosonar systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 129075. Oct 29 05:25:14 neosonar systemd[1]: Stopped go-librespot Daemon. Oct 29 05:25:14 neosonar systemd[1]: Started go-librespot Daemon. Oct 29 05:25:14 neosonar go-librespot[30106]: go-librespot daemon starting... Oct 29 05:25:14 neosonar go-librespot[30106]: time="2025-10-29T05:25:14Z" level=info msg="running go-librespot 0.2.0" Oct 29 05:25:14 neosonar go-librespot[30106]: time="2025-10-29T05:25:14Z" level=debug msg="app state loaded" Oct 29 05:25:14 neosonar go-librespot[30106]: time="2025-10-29T05:25:14Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 05:25:14 neosonar go-librespot[30106]: time="2025-10-29T05:25:14Z" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Oct 29 05:25:14 neosonar go-librespot[30106]: time="2025-10-29T05:25:14Z" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Oct 29 05:25:14 neosonar go-librespot[30106]: time="2025-10-29T05:25:14Z" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Oct 29 05:25:14 neosonar go-librespot[30106]: time="2025-10-29T05:25:14Z" level=info msg="zeroconf server listening on port 44689" Oct 29 05:25:14 neosonar go-librespot[30106]: time="2025-10-29T05:25:14Z" level=debug msg="obtained new client token: AAA8ptk2Amvz6e/daHK+WvZbsZwOqo7TG+nXDC2JRTEYNLK4u79JrRRBuK9irthK8+6upzfgl81TdhD2PHtOsyTxBfqM3eyH/5npMfZBMSRW8uT6OenpyIsEmTBWeGg8B2AXeComua3pm99wM/enB/wtzL9dG8XH1D4Aj0XqA+hld3AxFjLVvba5nTGyRKIW1307AaZIqEnAKwInAvEsdPH+JyJhwQlpFX3YV55ZqG82vKxMkFQ6QFTl4TY=" Oct 29 05:25:15 neosonar go-librespot[30106]: time="2025-10-29T05:25:15Z" level=debug msg="connected to ap-gae2.spotify.com:4070" Oct 29 05:25:15 neosonar go-librespot[30106]: time="2025-10-29T05:25:15Z" level=debug msg="completed keyexchange" Oct 29 05:25:15 neosonar go-librespot[30106]: time="2025-10-29T05:25:15Z" level=debug msg="completed challenge" Oct 29 05:25:15 neosonar go-librespot[30106]: time="2025-10-29T05:25:15Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Oct 29 05:25:15 neosonar systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 05:25:15 neosonar systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 05:25:16 neosonar volumio[994]: info: Initializing connection to go-librespot Websocket Oct 29 05:25:16 neosonar volumio[994]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 05:25:18 neosonar systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 29 05:25:18 neosonar systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 129076. Oct 29 05:25:18 neosonar systemd[1]: Stopped go-librespot Daemon. Oct 29 05:25:18 neosonar systemd[1]: Started go-librespot Daemon. Oct 29 05:25:18 neosonar go-librespot[30114]: go-librespot daemon starting... Oct 29 05:25:18 neosonar go-librespot[30114]: time="2025-10-29T05:25:18Z" level=info msg="running go-librespot 0.2.0" Oct 29 05:25:18 neosonar go-librespot[30114]: time="2025-10-29T05:25:18Z" level=debug msg="app state loaded" Oct 29 05:25:18 neosonar go-librespot[30114]: time="2025-10-29T05:25:18Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 05:25:18 neosonar go-librespot[30114]: time="2025-10-29T05:25:18Z" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Oct 29 05:25:18 neosonar go-librespot[30114]: time="2025-10-29T05:25:18Z" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Oct 29 05:25:18 neosonar go-librespot[30114]: time="2025-10-29T05:25:18Z" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Oct 29 05:25:18 neosonar go-librespot[30114]: time="2025-10-29T05:25:18Z" level=info msg="zeroconf server listening on port 37531" Oct 29 05:25:18 neosonar go-librespot[30114]: time="2025-10-29T05:25:18Z" level=debug msg="obtained new client token: AADKEmyZ7MhxT8x+z6OL54FTPcppMvZoOJAl3BopnOa8Lbo2o0H25mhGLsevoOVh30gXgrul2wpcwg4/NiRYyN3wsAqK3a8vTKD+Cpom48jPiHUrS5EY7XMlcpRiLJADEJIXqPxaWCKH+Vmvl3yXezmvSyonSEzj/BRfatfcw50gkHW7kVmZHP5bkgW2laZdSBQ5SeaBZo4bafCfQhULBOS4LzjuQPnn/PJ9A5amtfMsDot2nCsDa5ZXI6c=" Oct 29 05:25:18 neosonar go-librespot[30114]: time="2025-10-29T05:25:18Z" level=debug msg="connected to ap-gae2.spotify.com:4070" Oct 29 05:25:18 neosonar go-librespot[30114]: time="2025-10-29T05:25:18Z" level=debug msg="completed keyexchange" Oct 29 05:25:18 neosonar go-librespot[30114]: time="2025-10-29T05:25:18Z" level=debug msg="completed challenge" Oct 29 05:25:18 neosonar go-librespot[30114]: time="2025-10-29T05:25:18Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Oct 29 05:25:18 neosonar systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 05:25:18 neosonar systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 05:25:19 neosonar volumio[994]: info: Initializing connection to go-librespot Websocket Oct 29 05:25:19 neosonar volumio[994]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 05:25:21 neosonar systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 29 05:25:21 neosonar systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 129077. Oct 29 05:25:21 neosonar systemd[1]: Stopped go-librespot Daemon. Oct 29 05:25:21 neosonar systemd[1]: Started go-librespot Daemon. Oct 29 05:25:21 neosonar go-librespot[30139]: go-librespot daemon starting... Oct 29 05:25:22 neosonar go-librespot[30139]: time="2025-10-29T05:25:22Z" level=info msg="running go-librespot 0.2.0" Oct 29 05:25:22 neosonar go-librespot[30139]: time="2025-10-29T05:25:22Z" level=debug msg="app state loaded" Oct 29 05:25:22 neosonar go-librespot[30139]: time="2025-10-29T05:25:22Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 05:25:22 neosonar go-librespot[30139]: time="2025-10-29T05:25:22Z" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Oct 29 05:25:22 neosonar go-librespot[30139]: time="2025-10-29T05:25:22Z" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Oct 29 05:25:22 neosonar go-librespot[30139]: time="2025-10-29T05:25:22Z" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Oct 29 05:25:22 neosonar go-librespot[30139]: time="2025-10-29T05:25:22Z" level=info msg="zeroconf server listening on port 39875" Oct 29 05:25:22 neosonar go-librespot[30139]: time="2025-10-29T05:25:22Z" level=debug msg="obtained new client token: AACT9KOEQ4VV3774QE4cOcbWy+i/k43ODtFB07XRWp3JlSGjQm6nVUrlivQppoV0ue9BmZYk8yiNH6VgGrRAvdaYw8VquPWek9SUmhviWXIzz77lP2ZBwicCmsJr6rob+LGS490qmdS2eQJtZM9J2ehp9bTis3uJW5q/iN50JSSSiKw0uamRgeGjGcmMZBWF6cjp9dEk4mmgsm1m0zxpwiwIwA9rTIGSNUQJV8UFEM61CzM2ZsrXn0iXnn8=" Oct 29 05:25:22 neosonar volumio[994]: info: Initializing connection to go-librespot Websocket Oct 29 05:25:22 neosonar go-librespot[30139]: time="2025-10-29T05:25:22Z" level=debug msg="connected to ap-gae2.spotify.com:4070" Oct 29 05:25:22 neosonar go-librespot[30139]: time="2025-10-29T05:25:22Z" level=debug msg="new websocket client" Oct 29 05:25:22 neosonar volumio[994]: info: Connection to go-librespot Websocket established Oct 29 05:25:22 neosonar go-librespot[30139]: time="2025-10-29T05:25:22Z" level=debug msg="completed keyexchange" Oct 29 05:25:22 neosonar go-librespot[30139]: time="2025-10-29T05:25:22Z" level=debug msg="completed challenge" Oct 29 05:25:22 neosonar go-librespot[30139]: time="2025-10-29T05:25:22Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Oct 29 05:25:22 neosonar systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 05:25:22 neosonar systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 05:25:22 neosonar volumio[994]: info: Connection to go-librespot Websocket closed Oct 29 05:25:25 neosonar volumio[994]: info: Getting Spotify volume Oct 29 05:25:25 neosonar volumio[994]: (node:994) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 05:25:25 neosonar volumio[994]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Oct 29 05:25:25 neosonar volumio[994]: (node:994) 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: 15927) Oct 29 05:25:25 neosonar volumio[994]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 29 05:25:25 neosonar volumio[994]: info: CoreCommandRouter::volumioGetState Oct 29 05:25:25 neosonar volumio[994]: info: CorePlayQueue::getTrack 0 Oct 29 05:25:25 neosonar volumio[994]: SPOTIFY: RECEIVED VOLUMIO VOLUME 89 Oct 29 05:25:25 neosonar volumio[994]: info: Initializing connection to go-librespot Websocket Oct 29 05:25:25 neosonar volumio[994]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 05:25:25 neosonar systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 29 05:25:25 neosonar systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 129078. Oct 29 05:25:25 neosonar systemd[1]: Stopped go-librespot Daemon. Oct 29 05:25:25 neosonar systemd[1]: Started go-librespot Daemon. Oct 29 05:25:25 neosonar go-librespot[30147]: go-librespot daemon starting... Oct 29 05:25:25 neosonar go-librespot[30147]: time="2025-10-29T05:25:25Z" level=info msg="running go-librespot 0.2.0" Oct 29 05:25:25 neosonar go-librespot[30147]: time="2025-10-29T05:25:25Z" level=debug msg="app state loaded" Oct 29 05:25:25 neosonar go-librespot[30147]: time="2025-10-29T05:25:25Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 05:25:26 neosonar go-librespot[30147]: time="2025-10-29T05:25:26Z" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Oct 29 05:25:26 neosonar go-librespot[30147]: time="2025-10-29T05:25:26Z" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Oct 29 05:25:26 neosonar go-librespot[30147]: time="2025-10-29T05:25:26Z" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Oct 29 05:25:26 neosonar go-librespot[30147]: time="2025-10-29T05:25:26Z" level=info msg="zeroconf server listening on port 40975" Oct 29 05:25:26 neosonar go-librespot[30147]: time="2025-10-29T05:25:26Z" level=debug msg="obtained new client token: AAAFVcYAfYm/5a/MC/piH3ZlKWcq4d8G7U6jXFMb7Od/BT7WYshzDVwN/qb5voEtNPeAST8e4wHSDl09aDDim+XtrfHQ3oi7p3wqlWLYi94o7RtbSIcLa/ZO2SgLwpmLJpnzPdnzSwBmrMqJ4trGBRduV3FLfFzYbMKfCKzZnKJX7exZWYGIH1lwUNZ2agTNSS/FD5vioQ6FElb5b7bimH0tWMZ8G8MIpiefYDTp3Lju5sw20Qy5Erog" Oct 29 05:25:26 neosonar go-librespot[30147]: time="2025-10-29T05:25:26Z" level=debug msg="connected to ap-gae2.spotify.com:4070" Oct 29 05:25:26 neosonar go-librespot[30147]: time="2025-10-29T05:25:26Z" level=debug msg="completed keyexchange" Oct 29 05:25:26 neosonar go-librespot[30147]: time="2025-10-29T05:25:26Z" level=debug msg="completed challenge" Oct 29 05:25:26 neosonar go-librespot[30147]: time="2025-10-29T05:25:26Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Oct 29 05:25:26 neosonar systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 05:25:26 neosonar systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 05:25:28 neosonar volumio[994]: info: Initializing connection to go-librespot Websocket Oct 29 05:25:28 neosonar volumio[994]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 05:25:29 neosonar systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 29 05:25:29 neosonar systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 129079. Oct 29 05:25:29 neosonar systemd[1]: Stopped go-librespot Daemon. Oct 29 05:25:29 neosonar systemd[1]: Started go-librespot Daemon. Oct 29 05:25:29 neosonar go-librespot[30155]: go-librespot daemon starting... Oct 29 05:25:29 neosonar go-librespot[30155]: time="2025-10-29T05:25:29Z" level=info msg="running go-librespot 0.2.0" Oct 29 05:25:29 neosonar go-librespot[30155]: time="2025-10-29T05:25:29Z" level=debug msg="app state loaded" Oct 29 05:25:29 neosonar go-librespot[30155]: time="2025-10-29T05:25:29Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 05:25:29 neosonar go-librespot[30155]: time="2025-10-29T05:25:29Z" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 29 05:25:29 neosonar go-librespot[30155]: time="2025-10-29T05:25:29Z" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 29 05:25:29 neosonar go-librespot[30155]: time="2025-10-29T05:25:29Z" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 29 05:25:29 neosonar go-librespot[30155]: time="2025-10-29T05:25:29Z" level=info msg="zeroconf server listening on port 33783" Oct 29 05:25:30 neosonar go-librespot[30155]: time="2025-10-29T05:25:30Z" level=debug msg="obtained new client token: AAAP9UT5SvSwNNmMk2TbzcJ5aBYscw9sZ/Esv6L9uNBfttU6PI4OZ+TkY43ltKKHC9bfExQxQY61roTljKubMY9qWkGZ/crvA/3uuiTYFiOcsHuJQlgJLcrhpBIU2hHxMXAl8AXTBk6RZ52W4ppROiLbUtVA4bNWxYAhhKoGlW6GOm8cuXjVdRHRJM7POdN83KOJX08CkHY+M7fQB9XIaqlpNidhYHw4iVYZQOMAG9lr+Fc//j0jAu7vw7Q=" Oct 29 05:25:30 neosonar go-librespot[30155]: time="2025-10-29T05:25:30Z" level=debug msg="connected to ap-gae2.spotify.com:4070" Oct 29 05:25:30 neosonar go-librespot[30155]: time="2025-10-29T05:25:30Z" level=debug msg="completed keyexchange" Oct 29 05:25:30 neosonar go-librespot[30155]: time="2025-10-29T05:25:30Z" level=debug msg="completed challenge" Oct 29 05:25:30 neosonar go-librespot[30155]: time="2025-10-29T05:25:30Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Oct 29 05:25:30 neosonar systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 05:25:30 neosonar systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 05:25:31 neosonar volumio[994]: info: Initializing connection to go-librespot Websocket Oct 29 05:25:31 neosonar volumio[994]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 05:25:33 neosonar systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 29 05:25:33 neosonar systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 129080. Oct 29 05:25:33 neosonar systemd[1]: Stopped go-librespot Daemon. Oct 29 05:25:33 neosonar systemd[1]: Started go-librespot Daemon. Oct 29 05:25:33 neosonar go-librespot[30177]: go-librespot daemon starting... Oct 29 05:25:33 neosonar go-librespot[30177]: time="2025-10-29T05:25:33Z" level=info msg="running go-librespot 0.2.0" Oct 29 05:25:33 neosonar go-librespot[30177]: time="2025-10-29T05:25:33Z" level=debug msg="app state loaded" Oct 29 05:25:33 neosonar go-librespot[30177]: time="2025-10-29T05:25:33Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 05:25:33 neosonar go-librespot[30177]: time="2025-10-29T05:25:33Z" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 29 05:25:33 neosonar go-librespot[30177]: time="2025-10-29T05:25:33Z" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 29 05:25:33 neosonar go-librespot[30177]: time="2025-10-29T05:25:33Z" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 29 05:25:33 neosonar go-librespot[30177]: time="2025-10-29T05:25:33Z" level=info msg="zeroconf server listening on port 35565" Oct 29 05:25:33 neosonar go-librespot[30177]: time="2025-10-29T05:25:33Z" level=debug msg="obtained new client token: AACq0OagVypqjjnHaSfMkvIMciGA9VWzMCXFfSogc8vVz6s/4p+zCAon1NVZS9TftD0QDhGecMgPGaMVC5+tQIcuTEOtSeso/8oTlLJsxZ4YksO4hw/AQYbRzSZ6wAG4x8msMVDNcJ7PytBppVaZoHQd8DgAXqITdTVRXH6C7xI1irOVzcSJ4YaXltJCATJ/088gSmcmYu9YE23IRM5I07Fskp4Tqvix1nUhQtRP/uZMvaX740ilWwp4Jx0=" Oct 29 05:25:33 neosonar go-librespot[30177]: time="2025-10-29T05:25:33Z" level=debug msg="connected to ap-gae2.spotify.com:4070" Oct 29 05:25:34 neosonar go-librespot[30177]: time="2025-10-29T05:25:34Z" level=debug msg="completed keyexchange" Oct 29 05:25:34 neosonar go-librespot[30177]: time="2025-10-29T05:25:34Z" level=debug msg="completed challenge" Oct 29 05:25:34 neosonar go-librespot[30177]: time="2025-10-29T05:25:34Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Oct 29 05:25:34 neosonar systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 05:25:34 neosonar systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 05:25:34 neosonar volumio[994]: info: Initializing connection to go-librespot Websocket Oct 29 05:25:34 neosonar volumio[994]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 05:25:37 neosonar systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 29 05:25:37 neosonar systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 129081. Oct 29 05:25:37 neosonar systemd[1]: Stopped go-librespot Daemon. Oct 29 05:25:37 neosonar systemd[1]: Started go-librespot Daemon. Oct 29 05:25:37 neosonar go-librespot[30186]: go-librespot daemon starting... Oct 29 05:25:37 neosonar go-librespot[30186]: time="2025-10-29T05:25:37Z" level=info msg="running go-librespot 0.2.0" Oct 29 05:25:37 neosonar go-librespot[30186]: time="2025-10-29T05:25:37Z" level=debug msg="app state loaded" Oct 29 05:25:37 neosonar go-librespot[30186]: time="2025-10-29T05:25:37Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 05:25:37 neosonar go-librespot[30186]: time="2025-10-29T05:25:37Z" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 29 05:25:37 neosonar go-librespot[30186]: time="2025-10-29T05:25:37Z" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 29 05:25:37 neosonar go-librespot[30186]: time="2025-10-29T05:25:37Z" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 29 05:25:37 neosonar go-librespot[30186]: time="2025-10-29T05:25:37Z" level=info msg="zeroconf server listening on port 36837" Oct 29 05:25:37 neosonar volumio[994]: info: Initializing connection to go-librespot Websocket Oct 29 05:25:37 neosonar go-librespot[30186]: time="2025-10-29T05:25:37Z" level=debug msg="new websocket client" Oct 29 05:25:37 neosonar volumio[994]: info: Connection to go-librespot Websocket established Oct 29 05:25:37 neosonar go-librespot[30186]: time="2025-10-29T05:25:37Z" level=debug msg="obtained new client token: AADg+GlaQ40VsXH/GIOJDYHJniYW0tiW6+gHLzkr0mjlUFK4dqnvxDJgTTW7qqGw3HHKxAAIBaqPr2l6SfOtwOno5feK/hFjxJCLnqOy5P1qy/Icc7NI7UOBvYpjMPCnK/mUVKUeO2s7jfijrlZA0c528OQsby7vMrG1L/fM/S8oduI+ikEdEiSOXzaW8zCW8T3Rk4HYiERq9PNR3k6XHUKQHmqSFVJERx+SmfDz8T9LiimwS6iK9ERqZ6I=" Oct 29 05:25:37 neosonar go-librespot[30186]: time="2025-10-29T05:25:37Z" level=debug msg="connected to ap-gae2.spotify.com:4070" Oct 29 05:25:37 neosonar go-librespot[30186]: time="2025-10-29T05:25:37Z" level=debug msg="completed keyexchange" Oct 29 05:25:37 neosonar go-librespot[30186]: time="2025-10-29T05:25:37Z" level=debug msg="completed challenge" Oct 29 05:25:37 neosonar go-librespot[30186]: time="2025-10-29T05:25:37Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Oct 29 05:25:37 neosonar systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 05:25:37 neosonar systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 05:25:37 neosonar volumio[994]: info: Connection to go-librespot Websocket closed Oct 29 05:25:40 neosonar volumio[994]: info: Getting Spotify volume Oct 29 05:25:40 neosonar volumio[994]: (node:994) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 05:25:40 neosonar volumio[994]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Oct 29 05:25:40 neosonar volumio[994]: (node:994) 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: 15928) Oct 29 05:25:40 neosonar volumio[994]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 29 05:25:40 neosonar volumio[994]: info: CoreCommandRouter::volumioGetState Oct 29 05:25:40 neosonar volumio[994]: info: CorePlayQueue::getTrack 0 Oct 29 05:25:40 neosonar volumio[994]: SPOTIFY: RECEIVED VOLUMIO VOLUME 89 Oct 29 05:25:40 neosonar volumio[994]: info: Initializing connection to go-librespot Websocket Oct 29 05:25:40 neosonar volumio[994]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 05:25:40 neosonar systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 29 05:25:40 neosonar systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 129082. Oct 29 05:25:40 neosonar systemd[1]: Stopped go-librespot Daemon. Oct 29 05:25:40 neosonar systemd[1]: Started go-librespot Daemon. Oct 29 05:25:40 neosonar go-librespot[30235]: go-librespot daemon starting... Oct 29 05:25:41 neosonar go-librespot[30235]: time="2025-10-29T05:25:41Z" level=info msg="running go-librespot 0.2.0" Oct 29 05:25:41 neosonar go-librespot[30235]: time="2025-10-29T05:25:41Z" level=debug msg="app state loaded" Oct 29 05:25:41 neosonar go-librespot[30235]: time="2025-10-29T05:25:41Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 05:25:41 neosonar go-librespot[30235]: time="2025-10-29T05:25:41Z" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 29 05:25:41 neosonar go-librespot[30235]: time="2025-10-29T05:25:41Z" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 29 05:25:41 neosonar go-librespot[30235]: time="2025-10-29T05:25:41Z" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 29 05:25:41 neosonar go-librespot[30235]: time="2025-10-29T05:25:41Z" level=info msg="zeroconf server listening on port 41623" Oct 29 05:25:41 neosonar go-librespot[30235]: time="2025-10-29T05:25:41Z" level=debug msg="obtained new client token: AABcFcoAp90ejYFq70kxRvD3DduBaICzRNhiMvPZs55aFTVpNFkohl1mkEhgEPPJBIMJ+SIK4DFJiezs9xMICM3FsyHUmzSXJ468tXvXQNf2t8Mors5nt219mvRVFjJt6/UzpWycEYRr3dDRMeYt1dyCTEf/rFnmMkG/GjIUZ44OFBLIZRUn9d/7zkYP0/Ix937o2qmfJrG6C2MtXKQR/zxqfnm0BGtSm6CL/g9nt+nX09kqe+tHLfmO7CM=" Oct 29 05:25:41 neosonar go-librespot[30235]: time="2025-10-29T05:25:41Z" level=debug msg="connected to ap-gae2.spotify.com:4070" Oct 29 05:25:41 neosonar go-librespot[30235]: time="2025-10-29T05:25:41Z" level=debug msg="completed keyexchange" Oct 29 05:25:41 neosonar go-librespot[30235]: time="2025-10-29T05:25:41Z" level=debug msg="completed challenge" Oct 29 05:25:41 neosonar go-librespot[30235]: time="2025-10-29T05:25:41Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Oct 29 05:25:41 neosonar systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 05:25:41 neosonar systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 05:25:43 neosonar volumio[994]: info: Initializing connection to go-librespot Websocket Oct 29 05:25:43 neosonar volumio[994]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 05:25:44 neosonar systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 29 05:25:44 neosonar systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 129083. Oct 29 05:25:44 neosonar systemd[1]: Stopped go-librespot Daemon. Oct 29 05:25:44 neosonar systemd[1]: Started go-librespot Daemon. Oct 29 05:25:44 neosonar go-librespot[30257]: go-librespot daemon starting... Oct 29 05:25:44 neosonar go-librespot[30257]: time="2025-10-29T05:25:44Z" level=info msg="running go-librespot 0.2.0" Oct 29 05:25:44 neosonar go-librespot[30257]: time="2025-10-29T05:25:44Z" level=debug msg="app state loaded" Oct 29 05:25:44 neosonar go-librespot[30257]: time="2025-10-29T05:25:44Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 05:25:44 neosonar go-librespot[30257]: time="2025-10-29T05:25:44Z" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 29 05:25:44 neosonar go-librespot[30257]: time="2025-10-29T05:25:44Z" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 29 05:25:44 neosonar go-librespot[30257]: time="2025-10-29T05:25:44Z" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 29 05:25:44 neosonar go-librespot[30257]: time="2025-10-29T05:25:44Z" level=info msg="zeroconf server listening on port 35459" Oct 29 05:25:45 neosonar go-librespot[30257]: time="2025-10-29T05:25:45Z" level=debug msg="obtained new client token: AAAjVMsIjsW3o66ATyn2oyhsf5ISqUhR02pvrrFQFBWURPBBYy9tpOiAAbc/baE64IP2+xIFycIxiDntuNk/oWZMxFzA3yZBL9HXflhukKLnSoa/VhzAgG0eXiBmR8NxGY74vUDShWveSNQZG5N5hCFfz2imn636DNAy5ek1MsP/rTGRP4QjUVhT55kNP7olnPlQxY1J/2SF6CEM6sKtfRBjHeJ6e8zMxszZSAW6PikefOUs9V0Sqtdf" Oct 29 05:25:45 neosonar go-librespot[30257]: time="2025-10-29T05:25:45Z" level=warning msg="failed to connect to AP ap-gae2.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.241.202:4070: connect: connection refused" Oct 29 05:25:45 neosonar go-librespot[30257]: time="2025-10-29T05:25:45Z" level=debug msg="connected to ap-gae2.spotify.com:443" Oct 29 05:25:45 neosonar go-librespot[30257]: time="2025-10-29T05:25:45Z" level=debug msg="completed keyexchange" Oct 29 05:25:45 neosonar go-librespot[30257]: time="2025-10-29T05:25:45Z" level=debug msg="completed challenge" Oct 29 05:25:45 neosonar go-librespot[30257]: time="2025-10-29T05:25:45Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Oct 29 05:25:45 neosonar systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 05:25:45 neosonar systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 05:25:46 neosonar volumio[994]: info: Initializing connection to go-librespot Websocket Oct 29 05:25:46 neosonar volumio[994]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 05:25:48 neosonar systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 29 05:25:48 neosonar systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 129084. Oct 29 05:25:48 neosonar systemd[1]: Stopped go-librespot Daemon. Oct 29 05:25:48 neosonar systemd[1]: Started go-librespot Daemon. Oct 29 05:25:48 neosonar go-librespot[30265]: go-librespot daemon starting... Oct 29 05:25:48 neosonar go-librespot[30265]: time="2025-10-29T05:25:48Z" level=info msg="running go-librespot 0.2.0" Oct 29 05:25:48 neosonar go-librespot[30265]: time="2025-10-29T05:25:48Z" level=debug msg="app state loaded" Oct 29 05:25:48 neosonar go-librespot[30265]: time="2025-10-29T05:25:48Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 05:25:48 neosonar go-librespot[30265]: time="2025-10-29T05:25:48Z" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Oct 29 05:25:48 neosonar go-librespot[30265]: time="2025-10-29T05:25:48Z" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Oct 29 05:25:48 neosonar go-librespot[30265]: time="2025-10-29T05:25:48Z" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Oct 29 05:25:48 neosonar go-librespot[30265]: time="2025-10-29T05:25:48Z" level=info msg="zeroconf server listening on port 37617" Oct 29 05:25:48 neosonar go-librespot[30265]: time="2025-10-29T05:25:48Z" level=debug msg="obtained new client token: AACbdnh8jTyxuqQKZTGyY1J1mDC7k+DfqoMDHy2Z0abue5mPhv1uMmpcDa8D34Lg53k2F5FFIJw7UHowhYhfIvn1FjjrVS92Et83gLLgBxME7NDJ1iKMn40VvmJyCeIhYITdtDQLhp0Rp7KiBGLlsBZQO76zGbtUFS/qjQjRflP65Ngmtk7UBda/ladIc6ou3Rva/0FTFePtmpPc9RhkYyb0fBN16xC9UN71Jabo21D/X2Ij0TStPAaEhH4=" Oct 29 05:25:49 neosonar go-librespot[30265]: time="2025-10-29T05:25:49Z" level=debug msg="connected to ap-gae2.spotify.com:4070" Oct 29 05:25:49 neosonar go-librespot[30265]: time="2025-10-29T05:25:49Z" level=debug msg="completed keyexchange" Oct 29 05:25:49 neosonar go-librespot[30265]: time="2025-10-29T05:25:49Z" level=debug msg="completed challenge" Oct 29 05:25:49 neosonar go-librespot[30265]: time="2025-10-29T05:25:49Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Oct 29 05:25:49 neosonar systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 05:25:49 neosonar systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 05:25:49 neosonar volumio[994]: info: Initializing connection to go-librespot Websocket Oct 29 05:25:49 neosonar volumio[994]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 05:25:52 neosonar systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 29 05:25:52 neosonar systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 129085. Oct 29 05:25:52 neosonar systemd[1]: Stopped go-librespot Daemon. Oct 29 05:25:52 neosonar systemd[1]: Started go-librespot Daemon. Oct 29 05:25:52 neosonar go-librespot[30287]: go-librespot daemon starting... Oct 29 05:25:52 neosonar go-librespot[30287]: time="2025-10-29T05:25:52Z" level=info msg="running go-librespot 0.2.0" Oct 29 05:25:52 neosonar go-librespot[30287]: time="2025-10-29T05:25:52Z" level=debug msg="app state loaded" Oct 29 05:25:52 neosonar go-librespot[30287]: time="2025-10-29T05:25:52Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 05:25:52 neosonar go-librespot[30287]: time="2025-10-29T05:25:52Z" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 29 05:25:52 neosonar go-librespot[30287]: time="2025-10-29T05:25:52Z" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 29 05:25:52 neosonar go-librespot[30287]: time="2025-10-29T05:25:52Z" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 29 05:25:52 neosonar go-librespot[30287]: time="2025-10-29T05:25:52Z" level=info msg="zeroconf server listening on port 36151" Oct 29 05:25:52 neosonar go-librespot[30287]: time="2025-10-29T05:25:52Z" level=debug msg="obtained new client token: AAAJI8EeawSR40k9d6w3SGrBfzCiSvmGZUXXKLnrvINycwQA7Mxd/AP5HnlppoQhniR5iNKUCQ4xsPKgmxA8a0rbWmFmDnyDqWa2uHzJsGnZN2oNsNeZV49G3B3TO2fR1GThw3gmldFIR1GXFXKxIThSm6vnAvOsWKqjLdUa+6y3XbWOAfHhwCIR4eG/v/ZSCoFwhfyLKFMoV+yXwZjR5ncGs+PPFX/tXcbD7D4Zk1n+AX63oIfjbgJUeMo=" Oct 29 05:25:52 neosonar go-librespot[30287]: time="2025-10-29T05:25:52Z" level=debug msg="connected to ap-gae2.spotify.com:4070" Oct 29 05:25:52 neosonar go-librespot[30287]: time="2025-10-29T05:25:52Z" level=debug msg="completed keyexchange" Oct 29 05:25:52 neosonar go-librespot[30287]: time="2025-10-29T05:25:52Z" level=debug msg="completed challenge" Oct 29 05:25:52 neosonar go-librespot[30287]: time="2025-10-29T05:25:52Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Oct 29 05:25:52 neosonar systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 05:25:52 neosonar systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 05:25:52 neosonar volumio[994]: info: Initializing connection to go-librespot Websocket Oct 29 05:25:52 neosonar volumio[994]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 05:25:55 neosonar volumio[994]: info: Initializing connection to go-librespot Websocket Oct 29 05:25:55 neosonar volumio[994]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 05:25:55 neosonar systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 29 05:25:55 neosonar systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 129086. Oct 29 05:25:55 neosonar systemd[1]: Stopped go-librespot Daemon. Oct 29 05:25:55 neosonar systemd[1]: Started go-librespot Daemon. Oct 29 05:25:55 neosonar go-librespot[30296]: go-librespot daemon starting... Oct 29 05:25:56 neosonar go-librespot[30296]: time="2025-10-29T05:25:56Z" level=info msg="running go-librespot 0.2.0" Oct 29 05:25:56 neosonar go-librespot[30296]: time="2025-10-29T05:25:56Z" level=debug msg="app state loaded" Oct 29 05:25:56 neosonar go-librespot[30296]: time="2025-10-29T05:25:56Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 05:25:56 neosonar go-librespot[30296]: time="2025-10-29T05:25:56Z" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 29 05:25:56 neosonar go-librespot[30296]: time="2025-10-29T05:25:56Z" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 29 05:25:56 neosonar go-librespot[30296]: time="2025-10-29T05:25:56Z" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 29 05:25:56 neosonar go-librespot[30296]: time="2025-10-29T05:25:56Z" level=info msg="zeroconf server listening on port 38277" Oct 29 05:25:56 neosonar go-librespot[30296]: time="2025-10-29T05:25:56Z" level=debug msg="obtained new client token: AAAXFsphRDigomRk+OFpqG5wOCCUtgz9TLIit1GK6jzhqt0t5coOD8/uk6/7ZnEVSvr7ylrJKyY/p6/MbUHLq72BQsJxr//sjPVShZRtfzZLx7PUkFQxbh54X/08FpmN4vYn4y2Lf42Zi0xvgub3J7wWzeofCVeoanbKebYyEqrsQtUX4rw3oBzSgCwRoRzQJK/62S8je2YShS6xQYUShhZ8d/aEh0KifC8/Kn0iMqbw9KMGHdZs+fwv4Gc=" Oct 29 05:25:56 neosonar go-librespot[30296]: time="2025-10-29T05:25:56Z" level=debug msg="connected to ap-gae2.spotify.com:4070" Oct 29 05:25:56 neosonar go-librespot[30296]: time="2025-10-29T05:25:56Z" level=debug msg="completed keyexchange" Oct 29 05:25:56 neosonar go-librespot[30296]: time="2025-10-29T05:25:56Z" level=debug msg="completed challenge" Oct 29 05:25:56 neosonar go-librespot[30296]: time="2025-10-29T05:25:56Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Oct 29 05:25:56 neosonar systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 05:25:56 neosonar systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 05:25:58 neosonar volumio[994]: info: Initializing connection to go-librespot Websocket Oct 29 05:25:58 neosonar volumio[994]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 05:25:59 neosonar systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 29 05:25:59 neosonar systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 129087. Oct 29 05:25:59 neosonar systemd[1]: Stopped go-librespot Daemon. Oct 29 05:25:59 neosonar systemd[1]: Started go-librespot Daemon. Oct 29 05:25:59 neosonar go-librespot[30307]: go-librespot daemon starting... Oct 29 05:25:59 neosonar go-librespot[30307]: time="2025-10-29T05:25:59Z" level=info msg="running go-librespot 0.2.0" Oct 29 05:25:59 neosonar go-librespot[30307]: time="2025-10-29T05:25:59Z" level=debug msg="app state loaded" Oct 29 05:25:59 neosonar go-librespot[30307]: time="2025-10-29T05:25:59Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 05:26:00 neosonar go-librespot[30307]: time="2025-10-29T05:26:00Z" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 29 05:26:00 neosonar go-librespot[30307]: time="2025-10-29T05:26:00Z" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 29 05:26:00 neosonar go-librespot[30307]: time="2025-10-29T05:26:00Z" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 29 05:26:00 neosonar go-librespot[30307]: time="2025-10-29T05:26:00Z" level=info msg="zeroconf server listening on port 37327" Oct 29 05:26:00 neosonar go-librespot[30307]: time="2025-10-29T05:26:00Z" level=debug msg="obtained new client token: AADvS/mGBeRk0E3WVwY3GMN2YiR1GTKqc6uiLGeoVwuPv0K8itGoHYz5sr4WBuPQ0vVPgguKZyeAje2tMOKqCkqe8SOU8TUxY+K/8iOYsjf9pxV1FOmKhfMkCr1StZyD/yrAOFa9UNEc/mNnuc8CXsQ3zbSgRRTk3ze3DEWgyBFRtYQPe0tXDsrZuEydxRovDXPUU4VVlBGF97FP+1bFsswDrY6frzaocI49/v9+QBxTaqE/HYhR8GP5" Oct 29 05:26:00 neosonar go-librespot[30307]: time="2025-10-29T05:26:00Z" level=debug msg="connected to ap-gae2.spotify.com:4070" Oct 29 05:26:00 neosonar go-librespot[30307]: time="2025-10-29T05:26:00Z" level=debug msg="completed keyexchange" Oct 29 05:26:00 neosonar go-librespot[30307]: time="2025-10-29T05:26:00Z" level=debug msg="completed challenge" Oct 29 05:26:00 neosonar go-librespot[30307]: time="2025-10-29T05:26:00Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Oct 29 05:26:00 neosonar systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 05:26:00 neosonar systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 05:26:01 neosonar volumio[994]: info: Initializing connection to go-librespot Websocket Oct 29 05:26:01 neosonar volumio[994]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 05:26:03 neosonar systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 29 05:26:03 neosonar systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 129088. Oct 29 05:26:03 neosonar systemd[1]: Stopped go-librespot Daemon. Oct 29 05:26:03 neosonar systemd[1]: Started go-librespot Daemon. Oct 29 05:26:03 neosonar go-librespot[30329]: go-librespot daemon starting... Oct 29 05:26:03 neosonar go-librespot[30329]: time="2025-10-29T05:26:03Z" level=info msg="running go-librespot 0.2.0" Oct 29 05:26:03 neosonar go-librespot[30329]: time="2025-10-29T05:26:03Z" level=debug msg="app state loaded" Oct 29 05:26:03 neosonar go-librespot[30329]: time="2025-10-29T05:26:03Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 05:26:03 neosonar go-librespot[30329]: time="2025-10-29T05:26:03Z" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 29 05:26:03 neosonar go-librespot[30329]: time="2025-10-29T05:26:03Z" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 29 05:26:03 neosonar go-librespot[30329]: time="2025-10-29T05:26:03Z" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 29 05:26:04 neosonar go-librespot[30329]: time="2025-10-29T05:26:04Z" level=info msg="zeroconf server listening on port 40341" Oct 29 05:26:04 neosonar go-librespot[30329]: time="2025-10-29T05:26:04Z" level=debug msg="obtained new client token: AACg/K5w0eJiu7M2/l1xknQrOPjTIxS383NvfuwOXlCFiZK+womJMmpjIaRrcMYP7YE3eCjGWHeTuoRiZ28oM0hVGXUW8tL8y5cyBllUA5MBYs0yX8ZKJlkH7xMiLKhpvrmUaLpEot+yPiza8VvYuppmWLad/i3FzzH9jIzU9tf7ClrvB6G6liCQ8Hv/rxuODceVAzYv3vvODd0ECwzxvaCrv0653syttEXQP2K0HWAlbVJVcz3AgFQK" Oct 29 05:26:04 neosonar go-librespot[30329]: time="2025-10-29T05:26:04Z" level=debug msg="connected to ap-gae2.spotify.com:4070" Oct 29 05:26:04 neosonar go-librespot[30329]: time="2025-10-29T05:26:04Z" level=debug msg="completed keyexchange" Oct 29 05:26:04 neosonar go-librespot[30329]: time="2025-10-29T05:26:04Z" level=debug msg="completed challenge" Oct 29 05:26:04 neosonar go-librespot[30329]: time="2025-10-29T05:26:04Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Oct 29 05:26:04 neosonar systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 05:26:04 neosonar systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 05:26:04 neosonar volumio[994]: info: Initializing connection to go-librespot Websocket Oct 29 05:26:04 neosonar volumio[994]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 05:26:07 neosonar systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 29 05:26:07 neosonar systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 129089. Oct 29 05:26:07 neosonar systemd[1]: Stopped go-librespot Daemon. Oct 29 05:26:07 neosonar systemd[1]: Started go-librespot Daemon. Oct 29 05:26:07 neosonar go-librespot[30337]: go-librespot daemon starting... Oct 29 05:26:07 neosonar go-librespot[30337]: time="2025-10-29T05:26:07Z" level=info msg="running go-librespot 0.2.0" Oct 29 05:26:07 neosonar go-librespot[30337]: time="2025-10-29T05:26:07Z" level=debug msg="app state loaded" Oct 29 05:26:07 neosonar go-librespot[30337]: time="2025-10-29T05:26:07Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 05:26:07 neosonar go-librespot[30337]: time="2025-10-29T05:26:07Z" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 29 05:26:07 neosonar go-librespot[30337]: time="2025-10-29T05:26:07Z" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 29 05:26:07 neosonar go-librespot[30337]: time="2025-10-29T05:26:07Z" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 29 05:26:07 neosonar go-librespot[30337]: time="2025-10-29T05:26:07Z" level=info msg="zeroconf server listening on port 46127" Oct 29 05:26:07 neosonar go-librespot[30337]: time="2025-10-29T05:26:07Z" level=debug msg="obtained new client token: AACO3u4iO7hEz67HGkRXjOiDgVJgyEmcXEgZ8WHARhyJqMMI+Ybyn0b0Ah0OB31IeJi8hGyYbsiEojmddSXvmtJHKvaXEvnpuqZi27RwT2W3sBaueEj1zhmKbn+fV8sjgKATb/xk50e2VJyHbVLmztP7+Y9ptKHEfkGlUOoBYhW51G+4UKYs6m3CxywGfKrS5z1d9tXiCBVhLSDZuAhXepnjpoE798t/pcHBsEZIdVczljSA+q15bQFl89Y=" Oct 29 05:26:07 neosonar volumio[994]: info: Initializing connection to go-librespot Websocket Oct 29 05:26:07 neosonar go-librespot[30337]: time="2025-10-29T05:26:07Z" level=debug msg="new websocket client" Oct 29 05:26:07 neosonar volumio[994]: info: Connection to go-librespot Websocket established Oct 29 05:26:08 neosonar go-librespot[30337]: time="2025-10-29T05:26:08Z" level=debug msg="connected to ap-gae2.spotify.com:4070" Oct 29 05:26:08 neosonar go-librespot[30337]: time="2025-10-29T05:26:08Z" level=debug msg="completed keyexchange" Oct 29 05:26:08 neosonar go-librespot[30337]: time="2025-10-29T05:26:08Z" level=debug msg="completed challenge" Oct 29 05:26:08 neosonar go-librespot[30337]: time="2025-10-29T05:26:08Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Oct 29 05:26:08 neosonar systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 05:26:08 neosonar systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 05:26:08 neosonar volumio[994]: info: Connection to go-librespot Websocket closed Oct 29 05:26:10 neosonar volumio[994]: info: Getting Spotify volume Oct 29 05:26:10 neosonar volumio[994]: (node:994) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 05:26:10 neosonar volumio[994]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Oct 29 05:26:10 neosonar volumio[994]: (node:994) 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: 15929) Oct 29 05:26:10 neosonar volumio[994]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 29 05:26:10 neosonar volumio[994]: info: CoreCommandRouter::volumioGetState Oct 29 05:26:10 neosonar volumio[994]: info: CorePlayQueue::getTrack 0 Oct 29 05:26:10 neosonar volumio[994]: SPOTIFY: RECEIVED VOLUMIO VOLUME 89 Oct 29 05:26:11 neosonar volumio[994]: info: Initializing connection to go-librespot Websocket Oct 29 05:26:11 neosonar volumio[994]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 05:26:11 neosonar systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 29 05:26:11 neosonar systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 129090. Oct 29 05:26:11 neosonar systemd[1]: Stopped go-librespot Daemon. Oct 29 05:26:11 neosonar systemd[1]: Started go-librespot Daemon. Oct 29 05:26:11 neosonar go-librespot[30360]: go-librespot daemon starting... Oct 29 05:26:11 neosonar go-librespot[30360]: time="2025-10-29T05:26:11Z" level=info msg="running go-librespot 0.2.0" Oct 29 05:26:11 neosonar go-librespot[30360]: time="2025-10-29T05:26:11Z" level=debug msg="app state loaded" Oct 29 05:26:11 neosonar go-librespot[30360]: time="2025-10-29T05:26:11Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 05:26:11 neosonar go-librespot[30360]: time="2025-10-29T05:26:11Z" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 29 05:26:11 neosonar go-librespot[30360]: time="2025-10-29T05:26:11Z" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 29 05:26:11 neosonar go-librespot[30360]: time="2025-10-29T05:26:11Z" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 29 05:26:11 neosonar go-librespot[30360]: time="2025-10-29T05:26:11Z" level=info msg="zeroconf server listening on port 44095" Oct 29 05:26:11 neosonar go-librespot[30360]: time="2025-10-29T05:26:11Z" level=debug msg="obtained new client token: AACV4gXZIWYjEgT8L/dqdCay4GIlcDxGC4sWpiUGu7SuHVrbIjhNZP/j6s4ImZmcc1Y68I3rEXrghYEjureZHh3YcPaiTuBtrFm1eoPvTxw467nOCbj0Frm4PfGw2Hw6Fxv+ip5LI8wTHbnOQkiRYJwT+TNZ4b7bjtRjBmNQZx6QJC50Ll0x0s8kZ9J+HljuxA4gEIuleJk4VJcVNuiBM+McWBBwAyfYLAYYGW4YS3izuCbx+cIMBdkEnVs=" Oct 29 05:26:11 neosonar go-librespot[30360]: time="2025-10-29T05:26:11Z" level=debug msg="connected to ap-gae2.spotify.com:4070" Oct 29 05:26:12 neosonar go-librespot[30360]: time="2025-10-29T05:26:12Z" level=debug msg="completed keyexchange" Oct 29 05:26:12 neosonar go-librespot[30360]: time="2025-10-29T05:26:12Z" level=debug msg="completed challenge" Oct 29 05:26:12 neosonar go-librespot[30360]: time="2025-10-29T05:26:12Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Oct 29 05:26:12 neosonar systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 05:26:12 neosonar systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 05:26:14 neosonar volumio[994]: info: Initializing connection to go-librespot Websocket Oct 29 05:26:14 neosonar volumio[994]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 05:26:15 neosonar systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 29 05:26:15 neosonar systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 129091. Oct 29 05:26:15 neosonar systemd[1]: Stopped go-librespot Daemon. Oct 29 05:26:15 neosonar systemd[1]: Started go-librespot Daemon. Oct 29 05:26:15 neosonar go-librespot[30369]: go-librespot daemon starting... Oct 29 05:26:15 neosonar go-librespot[30369]: time="2025-10-29T05:26:15Z" level=info msg="running go-librespot 0.2.0" Oct 29 05:26:15 neosonar go-librespot[30369]: time="2025-10-29T05:26:15Z" level=debug msg="app state loaded" Oct 29 05:26:15 neosonar go-librespot[30369]: time="2025-10-29T05:26:15Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 05:26:15 neosonar go-librespot[30369]: time="2025-10-29T05:26:15Z" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Oct 29 05:26:15 neosonar go-librespot[30369]: time="2025-10-29T05:26:15Z" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Oct 29 05:26:15 neosonar go-librespot[30369]: time="2025-10-29T05:26:15Z" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Oct 29 05:26:15 neosonar go-librespot[30369]: time="2025-10-29T05:26:15Z" level=info msg="zeroconf server listening on port 42761" Oct 29 05:26:15 neosonar go-librespot[30369]: time="2025-10-29T05:26:15Z" level=debug msg="obtained new client token: AAAMJMx8yFXPzIA2Y+txmApBLhX93VmT7nkkoL2KCl6BfqpUisCsm8ceoJxNTT287gIWY/re897GUZiSLpyn+1hE92xrnT40NpakTGo3+vo+VtklIjCHv8V5BZs7vpEYMJpT/wkKStZUMdDgruH3V9SJAjOq1pSXmvfEw8ko17OMZpbgOyMGDhVEeBszQUE+0FDrSbvoM3v3Kvl+IOhXBlzXWSkCVFQ4Jmo55rv2nA2AiB/Sp5yE72TtMxc=" Oct 29 05:26:15 neosonar go-librespot[30369]: time="2025-10-29T05:26:15Z" level=debug msg="connected to ap-gae2.spotify.com:4070" Oct 29 05:26:15 neosonar go-librespot[30369]: time="2025-10-29T05:26:15Z" level=debug msg="completed keyexchange" Oct 29 05:26:15 neosonar go-librespot[30369]: time="2025-10-29T05:26:15Z" level=debug msg="completed challenge" Oct 29 05:26:16 neosonar go-librespot[30369]: time="2025-10-29T05:26:16Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Oct 29 05:26:16 neosonar systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 05:26:16 neosonar systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 05:26:17 neosonar volumio[994]: info: Initializing connection to go-librespot Websocket Oct 29 05:26:17 neosonar volumio[994]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 05:26:19 neosonar systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 29 05:26:19 neosonar systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 129092. Oct 29 05:26:19 neosonar systemd[1]: Stopped go-librespot Daemon. Oct 29 05:26:19 neosonar systemd[1]: Started go-librespot Daemon. Oct 29 05:26:19 neosonar go-librespot[30377]: go-librespot daemon starting... Oct 29 05:26:19 neosonar go-librespot[30377]: time="2025-10-29T05:26:19Z" level=info msg="running go-librespot 0.2.0" Oct 29 05:26:19 neosonar go-librespot[30377]: time="2025-10-29T05:26:19Z" level=debug msg="app state loaded" Oct 29 05:26:19 neosonar go-librespot[30377]: time="2025-10-29T05:26:19Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 05:26:19 neosonar go-librespot[30377]: time="2025-10-29T05:26:19Z" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 29 05:26:19 neosonar go-librespot[30377]: time="2025-10-29T05:26:19Z" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 29 05:26:19 neosonar go-librespot[30377]: time="2025-10-29T05:26:19Z" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 29 05:26:19 neosonar go-librespot[30377]: time="2025-10-29T05:26:19Z" level=info msg="zeroconf server listening on port 42709" Oct 29 05:26:19 neosonar go-librespot[30377]: time="2025-10-29T05:26:19Z" level=debug msg="obtained new client token: AABVLcpKUhK5AiFftXMyhTrTGDaKHJCJNlBaiUdh7vbNBsLi85XXttK8LzyqhkcO+lZMJzddCasEQpreeINuNmB6ShFXiSsJVVnOExjroZ1YWxoB/KTyNTHCy4U+EQKrjP9TwwUWr1YStwlorTnIvEE6oft6TXCrVaylOPpmW9YoQJLUogCgs+sf01wnCvFWjiullbV9DG/8PF0E0gvcHE5ZLK2RfxOGPJFuxXno2CS9x8sU5UHlzil6IuQ=" Oct 29 05:26:19 neosonar go-librespot[30377]: time="2025-10-29T05:26:19Z" level=debug msg="connected to ap-gae2.spotify.com:4070" Oct 29 05:26:19 neosonar go-librespot[30377]: time="2025-10-29T05:26:19Z" level=debug msg="completed keyexchange" Oct 29 05:26:19 neosonar go-librespot[30377]: time="2025-10-29T05:26:19Z" level=debug msg="completed challenge" Oct 29 05:26:19 neosonar go-librespot[30377]: time="2025-10-29T05:26:19Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Oct 29 05:26:19 neosonar systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 05:26:19 neosonar systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 05:26:20 neosonar volumio[994]: info: Initializing connection to go-librespot Websocket Oct 29 05:26:20 neosonar volumio[994]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 05:26:22 neosonar systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 29 05:26:22 neosonar systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 129093. Oct 29 05:26:22 neosonar systemd[1]: Stopped go-librespot Daemon. Oct 29 05:26:22 neosonar systemd[1]: Started go-librespot Daemon. Oct 29 05:26:22 neosonar go-librespot[30402]: go-librespot daemon starting... Oct 29 05:26:23 neosonar go-librespot[30402]: time="2025-10-29T05:26:23Z" level=info msg="running go-librespot 0.2.0" Oct 29 05:26:23 neosonar go-librespot[30402]: time="2025-10-29T05:26:23Z" level=debug msg="app state loaded" Oct 29 05:26:23 neosonar go-librespot[30402]: time="2025-10-29T05:26:23Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 05:26:23 neosonar volumio[994]: info: Initializing connection to go-librespot Websocket Oct 29 05:26:23 neosonar go-librespot[30402]: time="2025-10-29T05:26:23Z" level=debug msg="new websocket client" Oct 29 05:26:23 neosonar volumio[994]: info: Connection to go-librespot Websocket established Oct 29 05:26:23 neosonar go-librespot[30402]: time="2025-10-29T05:26:23Z" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 29 05:26:23 neosonar go-librespot[30402]: time="2025-10-29T05:26:23Z" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 29 05:26:23 neosonar go-librespot[30402]: time="2025-10-29T05:26:23Z" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 29 05:26:23 neosonar go-librespot[30402]: time="2025-10-29T05:26:23Z" level=info msg="zeroconf server listening on port 43469" Oct 29 05:26:23 neosonar go-librespot[30402]: time="2025-10-29T05:26:23Z" level=debug msg="obtained new client token: AAD4/3Zm0U4qpT82d9hj9Hde7kxzQZ+FyPsMSvDuuysav2w5scQy7BPIxyYlvZsadHpA6A4LjuPzYsgsUGlsDIHbLhpWCm+3Yzne/qJaPrwXHDR2UYcrve1IJoIfSFRskG7mq/PbDpJwU3UKXRjVR9xCyyJk8bHs6PXuw7h6qlq7tACLzu3/jtE/6pf1ZYPAKzAwifXqzB2VO+OaYSxu2FJUkQLi6/OeFWhViS7HXB/HJAN42Ajs4kvAIis=" Oct 29 05:26:23 neosonar go-librespot[30402]: time="2025-10-29T05:26:23Z" level=debug msg="connected to ap-gae2.spotify.com:4070" Oct 29 05:26:23 neosonar go-librespot[30402]: time="2025-10-29T05:26:23Z" level=debug msg="completed keyexchange" Oct 29 05:26:23 neosonar go-librespot[30402]: time="2025-10-29T05:26:23Z" level=debug msg="completed challenge" Oct 29 05:26:23 neosonar go-librespot[30402]: time="2025-10-29T05:26:23Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Oct 29 05:26:23 neosonar systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 05:26:23 neosonar systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 05:26:23 neosonar volumio[994]: info: Connection to go-librespot Websocket closed Oct 29 05:26:26 neosonar volumio[994]: info: Getting Spotify volume Oct 29 05:26:26 neosonar volumio[994]: (node:994) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 05:26:26 neosonar volumio[994]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Oct 29 05:26:26 neosonar volumio[994]: (node:994) 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: 15930) Oct 29 05:26:26 neosonar volumio[994]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 29 05:26:26 neosonar volumio[994]: info: CoreCommandRouter::volumioGetState Oct 29 05:26:26 neosonar volumio[994]: info: CorePlayQueue::getTrack 0 Oct 29 05:26:26 neosonar volumio[994]: SPOTIFY: RECEIVED VOLUMIO VOLUME 89 Oct 29 05:26:26 neosonar volumio[994]: info: Initializing connection to go-librespot Websocket Oct 29 05:26:26 neosonar volumio[994]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 05:26:26 neosonar systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 29 05:26:26 neosonar systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 129094. Oct 29 05:26:26 neosonar systemd[1]: Stopped go-librespot Daemon. Oct 29 05:26:26 neosonar systemd[1]: Started go-librespot Daemon. Oct 29 05:26:26 neosonar go-librespot[30410]: go-librespot daemon starting... Oct 29 05:26:27 neosonar go-librespot[30410]: time="2025-10-29T05:26:27Z" level=info msg="running go-librespot 0.2.0" Oct 29 05:26:27 neosonar go-librespot[30410]: time="2025-10-29T05:26:27Z" level=debug msg="app state loaded" Oct 29 05:26:27 neosonar go-librespot[30410]: time="2025-10-29T05:26:27Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 05:26:27 neosonar go-librespot[30410]: time="2025-10-29T05:26:27Z" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 29 05:26:27 neosonar go-librespot[30410]: time="2025-10-29T05:26:27Z" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 29 05:26:27 neosonar go-librespot[30410]: time="2025-10-29T05:26:27Z" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 29 05:26:27 neosonar go-librespot[30410]: time="2025-10-29T05:26:27Z" level=info msg="zeroconf server listening on port 33035" Oct 29 05:26:27 neosonar go-librespot[30410]: time="2025-10-29T05:26:27Z" level=debug msg="obtained new client token: AACh8tcMkyFJ1I/6ptuzu/6PNRdpsNpBRBXACHvXvnmnJIGaCMeavcAN8zul5AYp7r/L45n74TvxKc/6DMJcoNS7bH1Mk8JxpPVM1UQAZygeepFL2MzR+ZtVO0EhPJpYtDotet/IX+EeJEfVhdgZP7HMdsxrT+SidknsaVeKYThotZ8eoCun+4pCjAEu2F4MBk2g41A+QPdvPR3fDbN+cLYJc5LxbyQKGrL0dhART5cSLxs2kZPu6SrNqLo=" Oct 29 05:26:27 neosonar go-librespot[30410]: time="2025-10-29T05:26:27Z" level=debug msg="connected to ap-gae2.spotify.com:4070" Oct 29 05:26:27 neosonar go-librespot[30410]: time="2025-10-29T05:26:27Z" level=debug msg="completed keyexchange" Oct 29 05:26:27 neosonar go-librespot[30410]: time="2025-10-29T05:26:27Z" level=debug msg="completed challenge" Oct 29 05:26:27 neosonar go-librespot[30410]: time="2025-10-29T05:26:27Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Oct 29 05:26:27 neosonar systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 05:26:27 neosonar systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 05:26:29 neosonar volumio[994]: info: Initializing connection to go-librespot Websocket Oct 29 05:26:29 neosonar volumio[994]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 05:26:30 neosonar systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 29 05:26:30 neosonar systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 129095. Oct 29 05:26:30 neosonar systemd[1]: Stopped go-librespot Daemon. Oct 29 05:26:30 neosonar systemd[1]: Started go-librespot Daemon. Oct 29 05:26:30 neosonar go-librespot[30418]: go-librespot daemon starting... Oct 29 05:26:30 neosonar go-librespot[30418]: time="2025-10-29T05:26:30Z" level=info msg="running go-librespot 0.2.0" Oct 29 05:26:30 neosonar go-librespot[30418]: time="2025-10-29T05:26:30Z" level=debug msg="app state loaded" Oct 29 05:26:30 neosonar go-librespot[30418]: time="2025-10-29T05:26:30Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 05:26:31 neosonar go-librespot[30418]: time="2025-10-29T05:26:31Z" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Oct 29 05:26:31 neosonar go-librespot[30418]: time="2025-10-29T05:26:31Z" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Oct 29 05:26:31 neosonar go-librespot[30418]: time="2025-10-29T05:26:31Z" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Oct 29 05:26:31 neosonar go-librespot[30418]: time="2025-10-29T05:26:31Z" level=info msg="zeroconf server listening on port 33469" Oct 29 05:26:31 neosonar go-librespot[30418]: time="2025-10-29T05:26:31Z" level=debug msg="obtained new client token: AADuTumT7xO3zq10AMM+6/Ez4RyP2dJatc/5aHK8/4GmQAttRqfScRFNJxexpQ9JIyt/CTirB4hMtAxeZS0SRYZI/7NbJbTv73hOuzpHEwjmtMmL5pnRcD/CWvFMwLHGCpfBitG2k/Y+0BXKIBJF30lsoxg1dXnPBGYAyYp7S0wSB7vKHRatJQliPzahTPWGolRbjxuYhc7YSdBLPQMy2x8UrlEqFL0h0QpUpUxW52pIM2j5ydtsdBce" Oct 29 05:26:31 neosonar go-librespot[30418]: time="2025-10-29T05:26:31Z" level=debug msg="connected to ap-gae2.spotify.com:4070" Oct 29 05:26:31 neosonar go-librespot[30418]: time="2025-10-29T05:26:31Z" level=debug msg="completed keyexchange" Oct 29 05:26:31 neosonar go-librespot[30418]: time="2025-10-29T05:26:31Z" level=debug msg="completed challenge" Oct 29 05:26:31 neosonar go-librespot[30418]: time="2025-10-29T05:26:31Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Oct 29 05:26:31 neosonar systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 05:26:31 neosonar systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 05:26:32 neosonar volumio[994]: info: Initializing connection to go-librespot Websocket Oct 29 05:26:32 neosonar volumio[994]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 05:26:34 neosonar systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 29 05:26:34 neosonar systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 129096. Oct 29 05:26:34 neosonar systemd[1]: Stopped go-librespot Daemon. Oct 29 05:26:34 neosonar systemd[1]: Started go-librespot Daemon. Oct 29 05:26:34 neosonar go-librespot[30441]: go-librespot daemon starting... Oct 29 05:26:34 neosonar go-librespot[30441]: time="2025-10-29T05:26:34Z" level=info msg="running go-librespot 0.2.0" Oct 29 05:26:34 neosonar go-librespot[30441]: time="2025-10-29T05:26:34Z" level=debug msg="app state loaded" Oct 29 05:26:34 neosonar go-librespot[30441]: time="2025-10-29T05:26:34Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 05:26:35 neosonar go-librespot[30441]: time="2025-10-29T05:26:35Z" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Oct 29 05:26:35 neosonar go-librespot[30441]: time="2025-10-29T05:26:35Z" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Oct 29 05:26:35 neosonar go-librespot[30441]: time="2025-10-29T05:26:35Z" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Oct 29 05:26:35 neosonar go-librespot[30441]: time="2025-10-29T05:26:35Z" level=info msg="zeroconf server listening on port 44453" Oct 29 05:26:35 neosonar go-librespot[30441]: time="2025-10-29T05:26:35Z" level=debug msg="obtained new client token: AAAui7DrILXNMQjNSSJi+VkgYNsZek49CGzZT1FJuGKpKFJtGT0qB75v6OBAfuMwrCH7gTQpWSk7H8cFJ9rumN+lRntulSAmPO8blyzQX5I+1rj7YwUwSdSsWygJKmWTFxHDug4cFEB997pYi9Nwj/Y2UzfuT/rx40EpAk8FOjPtjXKTfBMEgK5WQw99hzB/fCrKTOGw0Y5WjYEtqkQLS8EmIVS8qrlzA+dO8Yv0ElM70Dog/uGOvX+b" Oct 29 05:26:35 neosonar go-librespot[30441]: time="2025-10-29T05:26:35Z" level=debug msg="connected to ap-gae2.spotify.com:4070" Oct 29 05:26:35 neosonar go-librespot[30441]: time="2025-10-29T05:26:35Z" level=debug msg="completed keyexchange" Oct 29 05:26:35 neosonar go-librespot[30441]: time="2025-10-29T05:26:35Z" level=debug msg="completed challenge" Oct 29 05:26:35 neosonar go-librespot[30441]: time="2025-10-29T05:26:35Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Oct 29 05:26:35 neosonar systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 05:26:35 neosonar systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 05:26:35 neosonar volumio[994]: info: Initializing connection to go-librespot Websocket Oct 29 05:26:35 neosonar volumio[994]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 05:26:38 neosonar systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 29 05:26:38 neosonar systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 129097. Oct 29 05:26:38 neosonar systemd[1]: Stopped go-librespot Daemon. Oct 29 05:26:38 neosonar systemd[1]: Started go-librespot Daemon. Oct 29 05:26:38 neosonar go-librespot[30451]: go-librespot daemon starting... Oct 29 05:26:38 neosonar go-librespot[30451]: time="2025-10-29T05:26:38Z" level=info msg="running go-librespot 0.2.0" Oct 29 05:26:38 neosonar go-librespot[30451]: time="2025-10-29T05:26:38Z" level=debug msg="app state loaded" Oct 29 05:26:38 neosonar go-librespot[30451]: time="2025-10-29T05:26:38Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 05:26:38 neosonar volumio[994]: info: Initializing connection to go-librespot Websocket Oct 29 05:26:38 neosonar go-librespot[30451]: time="2025-10-29T05:26:38Z" level=debug msg="new websocket client" Oct 29 05:26:38 neosonar volumio[994]: info: Connection to go-librespot Websocket established Oct 29 05:26:38 neosonar go-librespot[30451]: time="2025-10-29T05:26:38Z" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 29 05:26:38 neosonar go-librespot[30451]: time="2025-10-29T05:26:38Z" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 29 05:26:38 neosonar go-librespot[30451]: time="2025-10-29T05:26:38Z" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 29 05:26:38 neosonar go-librespot[30451]: time="2025-10-29T05:26:38Z" level=info msg="zeroconf server listening on port 42087" Oct 29 05:26:38 neosonar go-librespot[30451]: time="2025-10-29T05:26:38Z" level=debug msg="obtained new client token: AAAdYJLSVVJY9MiYygNObbRcZta1E27hN9CDKyu1cfaT5AdRAjYow8EJ8EPd6k9EKWkOZhJrZSemE8KK+9gpB8BohW3cTAyNJLNp/fgqc+YWNvqfGCiU94yFmS0UviiH/CHgP027RvVdXdW9qMg+7y3GE44wK2hKBw9Zjiu4U3ZM/0fu6J8nSgcqbgRQ0IMprA5hVw/WYg2jiiWP7cSn9Jq3lcp3ZRzB5rEMX/9mNOzUYNhZittlhqAoOTs=" Oct 29 05:26:38 neosonar go-librespot[30451]: time="2025-10-29T05:26:38Z" level=warning msg="failed to connect to AP ap-gae2.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.241.202:4070: connect: connection refused" Oct 29 05:26:39 neosonar go-librespot[30451]: time="2025-10-29T05:26:39Z" level=debug msg="connected to ap-gae2.spotify.com:443" Oct 29 05:26:39 neosonar go-librespot[30451]: time="2025-10-29T05:26:39Z" level=debug msg="completed keyexchange" Oct 29 05:26:39 neosonar go-librespot[30451]: time="2025-10-29T05:26:39Z" level=debug msg="completed challenge" Oct 29 05:26:39 neosonar go-librespot[30451]: time="2025-10-29T05:26:39Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Oct 29 05:26:39 neosonar systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 05:26:39 neosonar volumio[994]: info: Connection to go-librespot Websocket closed Oct 29 05:26:39 neosonar systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 05:26:41 neosonar volumio[994]: info: Getting Spotify volume Oct 29 05:26:41 neosonar volumio[994]: (node:994) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 05:26:41 neosonar volumio[994]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Oct 29 05:26:41 neosonar volumio[994]: (node:994) 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: 15931) Oct 29 05:26:41 neosonar volumio[994]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Oct 29 05:26:41 neosonar volumio[994]: info: CoreCommandRouter::volumioGetState Oct 29 05:26:41 neosonar volumio[994]: info: CorePlayQueue::getTrack 0 Oct 29 05:26:41 neosonar volumio[994]: SPOTIFY: RECEIVED VOLUMIO VOLUME 89 Oct 29 05:26:42 neosonar volumio[994]: info: Initializing connection to go-librespot Websocket Oct 29 05:26:42 neosonar volumio[994]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 05:26:42 neosonar systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 29 05:26:42 neosonar systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 129098. Oct 29 05:26:42 neosonar systemd[1]: Stopped go-librespot Daemon. Oct 29 05:26:42 neosonar systemd[1]: Started go-librespot Daemon. Oct 29 05:26:42 neosonar go-librespot[30514]: go-librespot daemon starting... Oct 29 05:26:42 neosonar go-librespot[30514]: time="2025-10-29T05:26:42Z" level=info msg="running go-librespot 0.2.0" Oct 29 05:26:42 neosonar go-librespot[30514]: time="2025-10-29T05:26:42Z" level=debug msg="app state loaded" Oct 29 05:26:42 neosonar go-librespot[30514]: time="2025-10-29T05:26:42Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 05:26:42 neosonar go-librespot[30514]: time="2025-10-29T05:26:42Z" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 29 05:26:42 neosonar go-librespot[30514]: time="2025-10-29T05:26:42Z" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 29 05:26:42 neosonar go-librespot[30514]: time="2025-10-29T05:26:42Z" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 29 05:26:42 neosonar go-librespot[30514]: time="2025-10-29T05:26:42Z" level=info msg="zeroconf server listening on port 42869" Oct 29 05:26:42 neosonar go-librespot[30514]: time="2025-10-29T05:26:42Z" level=debug msg="obtained new client token: AADz1a/l0Sbvb1OFlkRK/EsVAyEfXa8XsiKKv7vAlds9vqkWQR96eN0JX1446rY+jobM0yTm0lWANFAGuhVO3fzo4dv6skZ3TTn7J79aViW/ekWkRtcQnJdEQfX8Ohbua7iDxo+UiON18IX9Q3A+V8FMs11yR7uW0oQHiLVVTIpRnBd62JcvxrbQcq4oU9EnuW434VsCOSzZb7tk8GJyhnP/b+FyLKFjITEa+5mWvSVsbTCa3Qcc8n0Ny2Y=" Oct 29 05:26:43 neosonar go-librespot[30514]: time="2025-10-29T05:26:43Z" level=debug msg="connected to ap-gae2.spotify.com:4070" Oct 29 05:26:43 neosonar go-librespot[30514]: time="2025-10-29T05:26:43Z" level=debug msg="completed keyexchange" Oct 29 05:26:43 neosonar go-librespot[30514]: time="2025-10-29T05:26:43Z" level=debug msg="completed challenge" Oct 29 05:26:43 neosonar go-librespot[30514]: time="2025-10-29T05:26:43Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Oct 29 05:26:43 neosonar systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 05:26:43 neosonar systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 05:26:45 neosonar volumio[994]: info: Initializing connection to go-librespot Websocket Oct 29 05:26:45 neosonar volumio[994]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 05:26:46 neosonar systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 29 05:26:46 neosonar systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 129099. Oct 29 05:26:46 neosonar systemd[1]: Stopped go-librespot Daemon. Oct 29 05:26:46 neosonar systemd[1]: Started go-librespot Daemon. Oct 29 05:26:46 neosonar go-librespot[30522]: go-librespot daemon starting... Oct 29 05:26:46 neosonar go-librespot[30522]: time="2025-10-29T05:26:46Z" level=info msg="running go-librespot 0.2.0" Oct 29 05:26:46 neosonar go-librespot[30522]: time="2025-10-29T05:26:46Z" level=debug msg="app state loaded" Oct 29 05:26:46 neosonar go-librespot[30522]: time="2025-10-29T05:26:46Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 05:26:46 neosonar go-librespot[30522]: time="2025-10-29T05:26:46Z" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 29 05:26:46 neosonar go-librespot[30522]: time="2025-10-29T05:26:46Z" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 29 05:26:46 neosonar go-librespot[30522]: time="2025-10-29T05:26:46Z" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 29 05:26:46 neosonar go-librespot[30522]: time="2025-10-29T05:26:46Z" level=info msg="zeroconf server listening on port 45901" Oct 29 05:26:46 neosonar go-librespot[30522]: time="2025-10-29T05:26:46Z" level=debug msg="obtained new client token: AAAZXYKxw0g8KBFQJe3uXUsDgFm+OmmCrOShHbhwjmbIIQrAz7SYOdEAVO8iifJyhFb9xOElPhyIQwUpk4B3cgqOCKAvrlLKZu726lN8RIBxi3Bzeuj5y398o+5I8t6BOTnVtHyU6eWiDIMHKAbL9oxhYWqHeZuOiWSJn+5UHQF5qHxfi/d/C8LrNrMofKk8l4bod3JzFd3C5P5zPAOLAlXvDYrdAVYPXO7sHyaJbA7HNV1gxmRQD0HQtIw=" Oct 29 05:26:46 neosonar go-librespot[30522]: time="2025-10-29T05:26:46Z" level=warning msg="failed to connect to AP ap-gae2.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.241.202:4070: connect: connection refused" Oct 29 05:26:46 neosonar go-librespot[30522]: time="2025-10-29T05:26:46Z" level=debug msg="connected to ap-gae2.spotify.com:443" Oct 29 05:26:46 neosonar go-librespot[30522]: time="2025-10-29T05:26:46Z" level=debug msg="completed keyexchange" Oct 29 05:26:46 neosonar go-librespot[30522]: time="2025-10-29T05:26:46Z" level=debug msg="completed challenge" Oct 29 05:26:46 neosonar go-librespot[30522]: time="2025-10-29T05:26:46Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Oct 29 05:26:46 neosonar systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 05:26:46 neosonar systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 05:26:48 neosonar volumio[994]: info: Initializing connection to go-librespot Websocket Oct 29 05:26:48 neosonar volumio[994]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 05:26:50 neosonar systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 29 05:26:50 neosonar systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 129100. Oct 29 05:26:50 neosonar systemd[1]: Stopped go-librespot Daemon. Oct 29 05:26:50 neosonar systemd[1]: Started go-librespot Daemon. Oct 29 05:26:50 neosonar go-librespot[30530]: go-librespot daemon starting... Oct 29 05:26:50 neosonar go-librespot[30530]: time="2025-10-29T05:26:50Z" level=info msg="running go-librespot 0.2.0" Oct 29 05:26:50 neosonar go-librespot[30530]: time="2025-10-29T05:26:50Z" level=debug msg="app state loaded" Oct 29 05:26:50 neosonar go-librespot[30530]: time="2025-10-29T05:26:50Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 05:26:50 neosonar go-librespot[30530]: time="2025-10-29T05:26:50Z" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Oct 29 05:26:50 neosonar go-librespot[30530]: time="2025-10-29T05:26:50Z" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Oct 29 05:26:50 neosonar go-librespot[30530]: time="2025-10-29T05:26:50Z" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Oct 29 05:26:50 neosonar go-librespot[30530]: time="2025-10-29T05:26:50Z" level=info msg="zeroconf server listening on port 40253" Oct 29 05:26:50 neosonar go-librespot[30530]: time="2025-10-29T05:26:50Z" level=debug msg="obtained new client token: AACTf/SSoM1do4S/cYLb/UBbQ/4ipQ7XB5PEmAQloP2y8D/tNeaMHlY8RFzxUu76wcMbtUhYog6jW46lto9dPFp2lcA3CboWQ/a4oINW1hjJrN+xQ8PiIxirGTMg3BUh7lxFMEQxG2MhJmRoSglzruKTvztHlU0Ls6p0h8WQawj9mhHnUeVTbVl2itaYPDlfAQK5gI2S+igSusvJQzhH5aOhKsO/ot8J68P6h2oi1TZqdoq4jFNH/SV8iIU=" Oct 29 05:26:50 neosonar go-librespot[30530]: time="2025-10-29T05:26:50Z" level=debug msg="connected to ap-gae2.spotify.com:4070" Oct 29 05:26:50 neosonar go-librespot[30530]: time="2025-10-29T05:26:50Z" level=debug msg="completed keyexchange" Oct 29 05:26:50 neosonar go-librespot[30530]: time="2025-10-29T05:26:50Z" level=debug msg="completed challenge" Oct 29 05:26:50 neosonar go-librespot[30530]: time="2025-10-29T05:26:50Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Oct 29 05:26:50 neosonar systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 05:26:50 neosonar systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 05:26:51 neosonar volumio[994]: info: Initializing connection to go-librespot Websocket Oct 29 05:26:51 neosonar volumio[994]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 05:26:53 neosonar systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 29 05:26:53 neosonar systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 129101. Oct 29 05:26:53 neosonar systemd[1]: Stopped go-librespot Daemon. Oct 29 05:26:53 neosonar systemd[1]: Started go-librespot Daemon. Oct 29 05:26:53 neosonar go-librespot[30552]: go-librespot daemon starting... Oct 29 05:26:54 neosonar go-librespot[30552]: time="2025-10-29T05:26:54Z" level=info msg="running go-librespot 0.2.0" Oct 29 05:26:54 neosonar go-librespot[30552]: time="2025-10-29T05:26:54Z" level=debug msg="app state loaded" Oct 29 05:26:54 neosonar go-librespot[30552]: time="2025-10-29T05:26:54Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 05:26:54 neosonar volumio[994]: info: Initializing connection to go-librespot Websocket Oct 29 05:26:54 neosonar go-librespot[30552]: time="2025-10-29T05:26:54Z" level=debug msg="new websocket client" Oct 29 05:26:54 neosonar volumio[994]: info: Connection to go-librespot Websocket established Oct 29 05:26:54 neosonar go-librespot[30552]: time="2025-10-29T05:26:54Z" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 29 05:26:54 neosonar go-librespot[30552]: time="2025-10-29T05:26:54Z" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 29 05:26:54 neosonar go-librespot[30552]: time="2025-10-29T05:26:54Z" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 29 05:26:54 neosonar go-librespot[30552]: time="2025-10-29T05:26:54Z" level=info msg="zeroconf server listening on port 36565" Oct 29 05:26:54 neosonar go-librespot[30552]: time="2025-10-29T05:26:54Z" level=debug msg="obtained new client token: AADNuHTlwXwJUvc3MEfKZuRWf4OVgGuPwO9EIYGUQtLPljnnFIWb2m3f6Km00zFjVe3Pb1iEqV37WGF0z30IPZJ2XK2erVDhSpsFMRn5qt0wGGYAPHVl8F7AjmDI/Zl7C4g9bmFK7OHzr+GFNujlEn6VmPbIfSfnLuWdyt+boqWdwLPVqVIFNsdFF0LshUUf0vvUbDJfdxRkDiMdJPrZByQ0f/7ClFq6uH2nUBvxBdv+Prs3Ljgcf/gOb8E=" Oct 29 05:26:54 neosonar go-librespot[30552]: time="2025-10-29T05:26:54Z" level=debug msg="connected to ap-gae2.spotify.com:4070" Oct 29 05:26:54 neosonar go-librespot[30552]: time="2025-10-29T05:26:54Z" level=debug msg="completed keyexchange" Oct 29 05:26:54 neosonar go-librespot[30552]: time="2025-10-29T05:26:54Z" level=debug msg="completed challenge" Oct 29 05:26:54 neosonar go-librespot[30552]: time="2025-10-29T05:26:54Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Oct 29 05:26:54 neosonar systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 05:26:54 neosonar systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 05:26:54 neosonar volumio[994]: info: Connection to go-librespot Websocket closed Oct 29 05:26:57 neosonar volumio[994]: info: Getting Spotify volume Oct 29 05:26:57 neosonar volumio[994]: (node:994) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 05:26:57 neosonar volumio[994]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Oct 29 05:26:57 neosonar volumio[994]: (node:994) 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: 15932) Oct 29 05:26:57 neosonar volumio[994]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 29 05:26:57 neosonar volumio[994]: info: CoreCommandRouter::volumioGetState Oct 29 05:26:57 neosonar volumio[994]: info: CorePlayQueue::getTrack 0 Oct 29 05:26:57 neosonar volumio[994]: SPOTIFY: RECEIVED VOLUMIO VOLUME 89 Oct 29 05:26:57 neosonar volumio[994]: info: Initializing connection to go-librespot Websocket Oct 29 05:26:57 neosonar volumio[994]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 29 05:26:57 neosonar systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 29 05:26:57 neosonar systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 129102. Oct 29 05:26:57 neosonar systemd[1]: Stopped go-librespot Daemon. Oct 29 05:26:57 neosonar systemd[1]: Started go-librespot Daemon. Oct 29 05:26:57 neosonar go-librespot[30562]: go-librespot daemon starting... Oct 29 05:26:57 neosonar go-librespot[30562]: time="2025-10-29T05:26:57Z" level=info msg="running go-librespot 0.2.0" Oct 29 05:26:57 neosonar go-librespot[30562]: time="2025-10-29T05:26:57Z" level=debug msg="app state loaded" Oct 29 05:26:57 neosonar go-librespot[30562]: time="2025-10-29T05:26:57Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 29 05:26:58 neosonar go-librespot[30562]: time="2025-10-29T05:26:58Z" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 29 05:26:58 neosonar go-librespot[30562]: time="2025-10-29T05:26:58Z" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 29 05:26:58 neosonar go-librespot[30562]: time="2025-10-29T05:26:58Z" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 29 05:26:58 neosonar volumio[994]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 29 05:26:58 neosonar volumio[994]: [t [Error]: A network error (such as timeout, interrupted connection or unreachable host) has occurred.] { Oct 29 05:26:58 neosonar volumio[994]: code: 'auth/network-request-failed', Oct 29 05:26:58 neosonar volumio[994]: a: null Oct 29 05:26:58 neosonar volumio[994]: } Oct 29 05:26:58 neosonar volumio[994]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 29 05:26:58 neosonar go-librespot[30562]: time="2025-10-29T05:26:58Z" level=info msg="zeroconf server listening on port 41555" Oct 29 05:26:58 neosonar go-librespot[30562]: time="2025-10-29T05:26:58Z" level=debug msg="obtained new client token: AAA2u/42hOqEuv48C9L18u3aSVca1t9Fa7EulpdRIBEwx85pBvbCgPhJz6uztAaTYnGFNI295sIdqMfXZXKn78kUI9TJpbMCL+RmuwFiioE1b8VDMlnhxWWw2gCmxT9Ypajv+Ec1DtBoBmNLba+WJaCKmY/l7rU8k7VRyp/yOvyV+ocslmvj3G9sqS+pt6DaS8Px0GjsRt2CcyBnb5SOinBhnJxJMkjE7+npreqAx/p7mmE83EyaJm0Z" Oct 29 05:26:58 neosonar go-librespot[30562]: time="2025-10-29T05:26:58Z" level=debug msg="connected to ap-gae2.spotify.com:4070" Oct 29 05:26:58 neosonar go-librespot[30562]: time="2025-10-29T05:26:58Z" level=debug msg="completed keyexchange" Oct 29 05:26:58 neosonar go-librespot[30562]: time="2025-10-29T05:26:58Z" level=debug msg="completed challenge" Oct 29 05:26:58 neosonar go-librespot[30562]: time="2025-10-29T05:26:58Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Oct 29 05:26:58 neosonar systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 29 05:26:58 neosonar systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 29 05:26:58 neosonar sudo[30580]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-29 05:25 Oct 29 05:26:58 neosonar sudo[30580]: 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="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"