-- Logs begin at Thu 2019-02-14 10:11:58 UTC, end at Thu 2025-04-24 07:33:50 UTC. -- Apr 24 07:32:00 volumio volumio[988]: info: Initializing connection to go-librespot Websocket Apr 24 07:32:00 volumio volumio[988]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 24 07:32:01 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 24 07:32:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 541. Apr 24 07:32:01 volumio systemd[1]: Stopped go-librespot Daemon. Apr 24 07:32:01 volumio systemd[1]: Started go-librespot Daemon. Apr 24 07:32:01 volumio go-librespot[13847]: Librespot-go daemon starting... Apr 24 07:32:01 volumio go-librespot[13847]: time="2025-04-24T07:32:01Z" level=info msg="generated new device id: 613e6f9a630f17c71f020ce01539edd4c9e5682f" Apr 24 07:32:01 volumio go-librespot[13847]: time="2025-04-24T07:32:01Z" level=debug msg="stored credentials found for fukudvq" Apr 24 07:32:01 volumio go-librespot[13847]: time="2025-04-24T07:32:01Z" 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]" Apr 24 07:32:01 volumio go-librespot[13847]: time="2025-04-24T07:32:01Z" 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]" Apr 24 07:32:01 volumio go-librespot[13847]: time="2025-04-24T07:32:01Z" 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]" Apr 24 07:32:01 volumio go-librespot[13847]: time="2025-04-24T07:32:01Z" level=debug msg="zeroconf server listening on port 39127" Apr 24 07:32:02 volumio go-librespot[13847]: time="2025-04-24T07:32:02Z" level=debug msg="obtained new client token: AACeycX4ZmbwwQY6DW2M9R3IbbWk0Oai+VV85vNf8ToOIGIkkKzNfdYrvnDEaSXKgJuL5siEURXg/BN3YG0BdgERyLJECsA5cZJj8XZWzjxb5gw/lXIcgBJ7ZvTyu6onENBvCxmJWA7srL6v2xFTxtuFmln39tNGcJgCKexDaLjOOHvWc+fgMSGQdiqSqOrvv1XBtMm+2UvPqsY3DgfxHN8lF1YCRqhWab2SoKZZdiPNfh+lQPKlFaKl1g==" Apr 24 07:32:02 volumio go-librespot[13847]: time="2025-04-24T07:32:02Z" level=info msg="connected to ap-gae2.spotify.com:4070" Apr 24 07:32:02 volumio go-librespot[13847]: time="2025-04-24T07:32:02Z" level=debug msg="completed keyexchange" Apr 24 07:32:02 volumio go-librespot[13847]: time="2025-04-24T07:32:02Z" level=debug msg="completed challenge" Apr 24 07:32:02 volumio go-librespot[13847]: time="2025-04-24T07:32:02Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 24 07:32:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 24 07:32:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 24 07:32:03 volumio volumio[988]: info: Initializing connection to go-librespot Websocket Apr 24 07:32:03 volumio volumio[988]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 24 07:32:05 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 24 07:32:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 542. Apr 24 07:32:05 volumio systemd[1]: Stopped go-librespot Daemon. Apr 24 07:32:05 volumio systemd[1]: Started go-librespot Daemon. Apr 24 07:32:05 volumio go-librespot[13869]: Librespot-go daemon starting... Apr 24 07:32:05 volumio go-librespot[13869]: time="2025-04-24T07:32:05Z" level=info msg="generated new device id: 13c4b34b3ca91e106d53c806ff7839976c38321e" Apr 24 07:32:05 volumio go-librespot[13869]: time="2025-04-24T07:32:05Z" level=debug msg="stored credentials found for fukudvq" Apr 24 07:32:06 volumio go-librespot[13869]: time="2025-04-24T07:32: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-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Apr 24 07:32:06 volumio go-librespot[13869]: time="2025-04-24T07:32:06Z" 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]" Apr 24 07:32:06 volumio go-librespot[13869]: time="2025-04-24T07:32:06Z" 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]" Apr 24 07:32:06 volumio go-librespot[13869]: time="2025-04-24T07:32:06Z" level=debug msg="zeroconf server listening on port 35639" Apr 24 07:32:06 volumio go-librespot[13869]: time="2025-04-24T07:32:06Z" level=debug msg="obtained new client token: AADbWj9Vvvxr+rtxEejkkjwJnGtIHexUJa/meziSsCay9TK+oznTzHoU++YvJqjeDBX50tpK0gEpbP/fhrq2/leG4WnTFDEmlmyWdhPpXDB6eYh08505n6V32Tn3Q4H1VZUA4N7fOmnJ6OmxPEu87mlCg5sLy3n9sYbfygqdlv87zMXdTzvfvIhihtuQcVUTO7+jPgy8faKwlLh7hz+VuHhyF34I+ba7+QZNWUdPqHhma+VWUM/EISicWw==" Apr 24 07:32:06 volumio go-librespot[13869]: time="2025-04-24T07:32:06Z" level=info msg="connected to ap-gae2.spotify.com:4070" Apr 24 07:32:06 volumio volumio[988]: info: Initializing connection to go-librespot Websocket Apr 24 07:32:06 volumio go-librespot[13869]: time="2025-04-24T07:32:06Z" level=debug msg="completed keyexchange" Apr 24 07:32:06 volumio go-librespot[13869]: time="2025-04-24T07:32:06Z" level=debug msg="new websocket client" Apr 24 07:32:06 volumio volumio[988]: info: Connection to go-librespot Websocket established Apr 24 07:32:06 volumio go-librespot[13869]: time="2025-04-24T07:32:06Z" level=debug msg="completed challenge" Apr 24 07:32:06 volumio go-librespot[13869]: time="2025-04-24T07:32:06Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 24 07:32:06 volumio volumio[988]: info: Connection to go-librespot Websocket closed Apr 24 07:32:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 24 07:32:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 24 07:32:09 volumio volumio[988]: info: Getting Spotify volume Apr 24 07:32:09 volumio volumio[988]: (node:988) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 24 07:32:09 volumio volumio[988]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Apr 24 07:32:09 volumio volumio[988]: (node:988) 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: 184) Apr 24 07:32:09 volumio volumio[988]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Apr 24 07:32:09 volumio volumio[988]: info: CoreCommandRouter::volumioGetState Apr 24 07:32:09 volumio volumio[988]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Apr 24 07:32:09 volumio volumio[988]: info: Initializing connection to go-librespot Websocket Apr 24 07:32:09 volumio volumio[988]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 24 07:32:10 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 24 07:32:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 543. Apr 24 07:32:10 volumio systemd[1]: Stopped go-librespot Daemon. Apr 24 07:32:10 volumio systemd[1]: Started go-librespot Daemon. Apr 24 07:32:10 volumio go-librespot[13877]: Librespot-go daemon starting... Apr 24 07:32:10 volumio go-librespot[13877]: time="2025-04-24T07:32:10Z" level=info msg="generated new device id: 608d0f4b90cc23b4a7adab2c0cd68745a6142f55" Apr 24 07:32:10 volumio go-librespot[13877]: time="2025-04-24T07:32:10Z" level=debug msg="stored credentials found for fukudvq" Apr 24 07:32:10 volumio go-librespot[13877]: time="2025-04-24T07:32:10Z" 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]" Apr 24 07:32:10 volumio go-librespot[13877]: time="2025-04-24T07:32:10Z" 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]" Apr 24 07:32:10 volumio go-librespot[13877]: time="2025-04-24T07:32:10Z" 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]" Apr 24 07:32:10 volumio go-librespot[13877]: time="2025-04-24T07:32:10Z" level=debug msg="zeroconf server listening on port 36747" Apr 24 07:32:10 volumio go-librespot[13877]: time="2025-04-24T07:32:10Z" level=debug msg="obtained new client token: AAAqcqyFbGeXPxRSWEOB9QJVyoeNrCd+NGN04rL7RMiu/e8i1Ek8wsKg0ib1i7d1HD3vGAfdxjob97CNe3Jt+A6ik6kkVkztdC8ktGA8NKad8lqCHt8J3/52iRauQ9DHQ22C5Pe46iIMga6PeB9JSYN1X2afWt+uhNigBEeE5aWyU3npxtioRi2cLuepdLBMDVGwZd18ehExdulYiNTvhqN9uBkZ2V2cbqLwT+6T4G96JFSr0IH2HpRPHsSp" Apr 24 07:32:10 volumio go-librespot[13877]: time="2025-04-24T07:32:10Z" level=info msg="connected to ap-gae2.spotify.com:4070" Apr 24 07:32:10 volumio go-librespot[13877]: time="2025-04-24T07:32:10Z" level=debug msg="completed keyexchange" Apr 24 07:32:11 volumio go-librespot[13877]: time="2025-04-24T07:32:11Z" level=debug msg="completed challenge" Apr 24 07:32:11 volumio go-librespot[13877]: time="2025-04-24T07:32:11Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 24 07:32:11 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 24 07:32:11 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 24 07:32:12 volumio volumio[988]: info: Initializing connection to go-librespot Websocket Apr 24 07:32:12 volumio volumio[988]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 24 07:32:14 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 24 07:32:14 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 544. Apr 24 07:32:14 volumio systemd[1]: Stopped go-librespot Daemon. Apr 24 07:32:14 volumio systemd[1]: Started go-librespot Daemon. Apr 24 07:32:14 volumio go-librespot[13900]: Librespot-go daemon starting... Apr 24 07:32:14 volumio go-librespot[13900]: time="2025-04-24T07:32:14Z" level=info msg="generated new device id: 31bec5927c2353ae73b957dd0bc91f8f1b35f648" Apr 24 07:32:14 volumio go-librespot[13900]: time="2025-04-24T07:32:14Z" level=debug msg="stored credentials found for fukudvq" Apr 24 07:32:14 volumio go-librespot[13900]: time="2025-04-24T07:32: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-gew4.spotify.com:80]" Apr 24 07:32:14 volumio go-librespot[13900]: time="2025-04-24T07:32:14Z" 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]" Apr 24 07:32:14 volumio go-librespot[13900]: time="2025-04-24T07:32:14Z" 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]" Apr 24 07:32:14 volumio go-librespot[13900]: time="2025-04-24T07:32:14Z" level=debug msg="zeroconf server listening on port 34067" Apr 24 07:32:14 volumio go-librespot[13900]: time="2025-04-24T07:32:14Z" level=debug msg="obtained new client token: AAD8dM5WiFrG8jfWilqlEl+rG7zC2M9Iz9EnQzSYWtjj1RLsPKzfHmhdXI3mTtSvUbhyaWj8fxKTrkuXskH2lD8KuwsPIe8cxnyG/bqmQGANq6OgsHAW2JhIqp5tE/P88fSFY3SYDy7aDcQzdsEIJMKsKOXWoElssU7LD5jyBb5kONpoo4B8MJJ6Ycsfr2tAzgwy/dUjbNG3levhxflUrIdx3SVSm0+ZHgNPj/y81E4JuPhVYX9ouTK6yI3d" Apr 24 07:32:14 volumio go-librespot[13900]: time="2025-04-24T07:32:14Z" level=warning msg="failed to connect to AP ap-gae2.spotify.com:4070 (error: dial tcp 104.199.241.202:4070: connect: connection refused), retrying with a different AP" Apr 24 07:32:14 volumio go-librespot[13900]: time="2025-04-24T07:32:14Z" level=info msg="connected to ap-gae2.spotify.com:443" Apr 24 07:32:14 volumio go-librespot[13900]: time="2025-04-24T07:32:14Z" level=debug msg="completed keyexchange" Apr 24 07:32:15 volumio go-librespot[13900]: time="2025-04-24T07:32:15Z" level=debug msg="completed challenge" Apr 24 07:32:15 volumio go-librespot[13900]: time="2025-04-24T07:32:15Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 24 07:32:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 24 07:32:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 24 07:32:15 volumio volumio[988]: info: Initializing connection to go-librespot Websocket Apr 24 07:32:15 volumio volumio[988]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 24 07:32:18 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 24 07:32:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 545. Apr 24 07:32:18 volumio systemd[1]: Stopped go-librespot Daemon. Apr 24 07:32:18 volumio systemd[1]: Started go-librespot Daemon. Apr 24 07:32:18 volumio go-librespot[13949]: Librespot-go daemon starting... Apr 24 07:32:18 volumio go-librespot[13949]: time="2025-04-24T07:32:18Z" level=info msg="generated new device id: 1dbf93a8838fd4c07d91d9d8dc0993afaf6b3a0a" Apr 24 07:32:18 volumio go-librespot[13949]: time="2025-04-24T07:32:18Z" level=debug msg="stored credentials found for fukudvq" Apr 24 07:32:18 volumio go-librespot[13949]: time="2025-04-24T07:32:18Z" 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]" Apr 24 07:32:18 volumio go-librespot[13949]: time="2025-04-24T07:32:18Z" 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]" Apr 24 07:32:18 volumio go-librespot[13949]: time="2025-04-24T07:32:18Z" 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]" Apr 24 07:32:18 volumio go-librespot[13949]: time="2025-04-24T07:32:18Z" level=debug msg="zeroconf server listening on port 43619" Apr 24 07:32:18 volumio go-librespot[13949]: time="2025-04-24T07:32:18Z" level=debug msg="obtained new client token: AABfwcJSgmtsX6yKi9mRZDD+qT4RarH4beaAdJLlS5VzJBKltxHmjGBAMYC4DI+zNRri3Ck7z/Z9N0MyreFgzl4HphKPNS/WvegcTgIV12uozhDnH33P7yplmu/kEgxzZyT9OdnDKY9rfPc22WpefcDWd+W/kbNbXnvp8vfLBkxmnD00XnCE/GgQkWJHhLUFf/y0KI1j5LitV8Y5bEZkB100hba5vEvry3TIeYd61B4RKYqB5u2W6ZMBh5wO" Apr 24 07:32:18 volumio volumio[988]: info: Initializing connection to go-librespot Websocket Apr 24 07:32:18 volumio go-librespot[13949]: time="2025-04-24T07:32:18Z" level=debug msg="new websocket client" Apr 24 07:32:18 volumio volumio[988]: info: Connection to go-librespot Websocket established Apr 24 07:32:18 volumio go-librespot[13949]: time="2025-04-24T07:32:18Z" level=info msg="connected to ap-gae2.spotify.com:4070" Apr 24 07:32:18 volumio go-librespot[13949]: time="2025-04-24T07:32:18Z" level=debug msg="completed keyexchange" Apr 24 07:32:19 volumio go-librespot[13949]: time="2025-04-24T07:32:19Z" level=debug msg="completed challenge" Apr 24 07:32:19 volumio go-librespot[13949]: time="2025-04-24T07:32:19Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 24 07:32:19 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 24 07:32:19 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 24 07:32:19 volumio volumio[988]: info: Connection to go-librespot Websocket closed Apr 24 07:32:21 volumio volumio[988]: info: Getting Spotify volume Apr 24 07:32:21 volumio volumio[988]: (node:988) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 24 07:32:21 volumio volumio[988]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Apr 24 07:32:21 volumio volumio[988]: (node:988) 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: 185) Apr 24 07:32:21 volumio volumio[988]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Apr 24 07:32:21 volumio volumio[988]: info: CoreCommandRouter::volumioGetState Apr 24 07:32:21 volumio volumio[988]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Apr 24 07:32:22 volumio volumio[988]: info: Initializing connection to go-librespot Websocket Apr 24 07:32:22 volumio volumio[988]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 24 07:32:22 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 24 07:32:22 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 546. Apr 24 07:32:22 volumio systemd[1]: Stopped go-librespot Daemon. Apr 24 07:32:22 volumio systemd[1]: Started go-librespot Daemon. Apr 24 07:32:22 volumio go-librespot[13957]: Librespot-go daemon starting... Apr 24 07:32:22 volumio go-librespot[13957]: time="2025-04-24T07:32:22Z" level=info msg="generated new device id: eca629ceed533f9a3101ad83b40efd19ce258f3f" Apr 24 07:32:22 volumio go-librespot[13957]: time="2025-04-24T07:32:22Z" level=debug msg="stored credentials found for fukudvq" Apr 24 07:32:22 volumio go-librespot[13957]: time="2025-04-24T07:32: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-gew4.spotify.com:80]" Apr 24 07:32:22 volumio go-librespot[13957]: time="2025-04-24T07:32:22Z" 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]" Apr 24 07:32:22 volumio go-librespot[13957]: time="2025-04-24T07:32:22Z" 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]" Apr 24 07:32:22 volumio go-librespot[13957]: time="2025-04-24T07:32:22Z" level=debug msg="zeroconf server listening on port 33221" Apr 24 07:32:22 volumio go-librespot[13957]: time="2025-04-24T07:32:22Z" level=debug msg="obtained new client token: AAD8kN8RRiT00ghaaSAaHlL0wDfeK+3yPzaMYU7JaDC0kS6N8IyC7ar1c8wAbEjFFxW4S4YwiVb+YoTtyfvpY8mNa+tVq8XWT8ENz/hjw7lLzcw/cEp0aNHBPQ3/osGyfMJUxI0zwdxa08FxzGSldxo5bQmJqdW3Fo6Q5FxfeaSH2rTTZFUcUMROe1ncM79/DBNKAxGVi5OjsqnmAz9ibPO+D0QOXLFxhfHjenGO7Of6hvYY4G62Fo3RTxu8" Apr 24 07:32:22 volumio go-librespot[13957]: time="2025-04-24T07:32:22Z" level=info msg="connected to ap-gae2.spotify.com:4070" Apr 24 07:32:22 volumio go-librespot[13957]: time="2025-04-24T07:32:22Z" level=debug msg="completed keyexchange" Apr 24 07:32:23 volumio go-librespot[13957]: time="2025-04-24T07:32:23Z" level=debug msg="completed challenge" Apr 24 07:32:23 volumio go-librespot[13957]: time="2025-04-24T07:32:23Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 24 07:32:23 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 24 07:32:23 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 24 07:32:25 volumio volumio[988]: info: Initializing connection to go-librespot Websocket Apr 24 07:32:25 volumio volumio[988]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 24 07:32:26 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 24 07:32:26 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 547. Apr 24 07:32:26 volumio systemd[1]: Stopped go-librespot Daemon. Apr 24 07:32:26 volumio systemd[1]: Started go-librespot Daemon. Apr 24 07:32:26 volumio go-librespot[13979]: Librespot-go daemon starting... Apr 24 07:32:26 volumio go-librespot[13979]: time="2025-04-24T07:32:26Z" level=info msg="generated new device id: 1316baeb3682958de80bfae4c7db8b531c8d98f8" Apr 24 07:32:26 volumio go-librespot[13979]: time="2025-04-24T07:32:26Z" level=debug msg="stored credentials found for fukudvq" Apr 24 07:32:27 volumio go-librespot[13979]: time="2025-04-24T07:32: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-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Apr 24 07:32:27 volumio go-librespot[13979]: time="2025-04-24T07:32:27Z" 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]" Apr 24 07:32:27 volumio go-librespot[13979]: time="2025-04-24T07:32:27Z" 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]" Apr 24 07:32:27 volumio go-librespot[13979]: time="2025-04-24T07:32:27Z" level=debug msg="zeroconf server listening on port 41695" Apr 24 07:32:27 volumio go-librespot[13979]: time="2025-04-24T07:32:27Z" level=debug msg="obtained new client token: AADmthiAvk5bKImLQmhbvu63FmycAJlBCmxN/lqt9plahc3AI3M7x0ctzHlHXPnPgzl8OCJh/SOPelz5vs4jEVL0/wDVC6WE9FU9BIaFvr0Ur7I5SWmayULDFZoOh8nN/4yNN+2Rwh+xAjwA6BaEWVCNiW2h3YcJve5+Yaxo4+i7X0oucRCVhNg7Sh44HH99waQG8+1b0sAYOSoDTOfmemI6Qp6jww6lSg6jmNXKBBR8WKCVPMw8da5plA==" Apr 24 07:32:27 volumio go-librespot[13979]: time="2025-04-24T07:32:27Z" level=info msg="connected to ap-gae2.spotify.com:4070" Apr 24 07:32:27 volumio go-librespot[13979]: time="2025-04-24T07:32:27Z" level=debug msg="completed keyexchange" Apr 24 07:32:27 volumio go-librespot[13979]: time="2025-04-24T07:32:27Z" level=debug msg="completed challenge" Apr 24 07:32:27 volumio go-librespot[13979]: time="2025-04-24T07:32:27Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 24 07:32:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 24 07:32:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 24 07:32:28 volumio volumio[988]: info: Initializing connection to go-librespot Websocket Apr 24 07:32:28 volumio volumio[988]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 24 07:32:31 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 24 07:32:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 548. Apr 24 07:32:31 volumio systemd[1]: Stopped go-librespot Daemon. Apr 24 07:32:31 volumio systemd[1]: Started go-librespot Daemon. Apr 24 07:32:31 volumio go-librespot[13988]: Librespot-go daemon starting... Apr 24 07:32:31 volumio go-librespot[13988]: time="2025-04-24T07:32:31Z" level=info msg="generated new device id: a154e7e240d12cb325d91cec02fbe680715233ca" Apr 24 07:32:31 volumio go-librespot[13988]: time="2025-04-24T07:32:31Z" level=debug msg="stored credentials found for fukudvq" Apr 24 07:32:31 volumio go-librespot[13988]: time="2025-04-24T07:32:31Z" 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]" Apr 24 07:32:31 volumio go-librespot[13988]: time="2025-04-24T07:32:31Z" 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]" Apr 24 07:32:31 volumio go-librespot[13988]: time="2025-04-24T07:32:31Z" 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]" Apr 24 07:32:31 volumio go-librespot[13988]: time="2025-04-24T07:32:31Z" level=debug msg="zeroconf server listening on port 43955" Apr 24 07:32:31 volumio go-librespot[13988]: time="2025-04-24T07:32:31Z" level=debug msg="obtained new client token: AAB6ZjtvhvU5EEJbWCLO1NjCpeGGqRG7OE1W3f0jnIJsypNg43BHQ5heOU/aY0hUyZgKV2gqEmby4Xlan2XmRJ+FfJjDwttilq8SQGJpafOwL7oMiJWLdYmzWgS/fMV1Bbn96w66zRD5WUTuYcn+wOYIyAI95ay6Y0/Yc+pCDXEa53W8GSqgUzHvxlD03ClI024ax+oS4OJfArYeBFQRrv6fwgyTM36VwZuGr7ED8Vayov8ythDcc4RxYGfC" Apr 24 07:32:31 volumio go-librespot[13988]: time="2025-04-24T07:32:31Z" level=info msg="connected to ap-gae2.spotify.com:4070" Apr 24 07:32:31 volumio go-librespot[13988]: time="2025-04-24T07:32:31Z" level=debug msg="completed keyexchange" Apr 24 07:32:31 volumio volumio[988]: info: Initializing connection to go-librespot Websocket Apr 24 07:32:31 volumio go-librespot[13988]: time="2025-04-24T07:32:31Z" level=debug msg="new websocket client" Apr 24 07:32:31 volumio volumio[988]: info: Connection to go-librespot Websocket established Apr 24 07:32:31 volumio go-librespot[13988]: time="2025-04-24T07:32:31Z" level=debug msg="completed challenge" Apr 24 07:32:32 volumio go-librespot[13988]: time="2025-04-24T07:32:32Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 24 07:32:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 24 07:32:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 24 07:32:32 volumio volumio[988]: info: Connection to go-librespot Websocket closed Apr 24 07:32:34 volumio volumio[988]: info: Getting Spotify volume Apr 24 07:32:34 volumio volumio[988]: (node:988) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 24 07:32:34 volumio volumio[988]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Apr 24 07:32:34 volumio volumio[988]: (node:988) 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: 186) Apr 24 07:32:34 volumio volumio[988]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Apr 24 07:32:34 volumio volumio[988]: info: CoreCommandRouter::volumioGetState Apr 24 07:32:34 volumio volumio[988]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Apr 24 07:32:35 volumio volumio[988]: info: Initializing connection to go-librespot Websocket Apr 24 07:32:35 volumio volumio[988]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 24 07:32:35 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 24 07:32:35 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 549. Apr 24 07:32:35 volumio systemd[1]: Stopped go-librespot Daemon. Apr 24 07:32:35 volumio systemd[1]: Started go-librespot Daemon. Apr 24 07:32:35 volumio go-librespot[14011]: Librespot-go daemon starting... Apr 24 07:32:35 volumio go-librespot[14011]: time="2025-04-24T07:32:35Z" level=info msg="generated new device id: 709d0ab8192d605c19e2e77884519e100c99a35b" Apr 24 07:32:35 volumio go-librespot[14011]: time="2025-04-24T07:32:35Z" level=debug msg="stored credentials found for fukudvq" Apr 24 07:32:35 volumio go-librespot[14011]: time="2025-04-24T07:32: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]" Apr 24 07:32:35 volumio go-librespot[14011]: time="2025-04-24T07:32: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]" Apr 24 07:32:35 volumio go-librespot[14011]: time="2025-04-24T07:32: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]" Apr 24 07:32:35 volumio go-librespot[14011]: time="2025-04-24T07:32:35Z" level=debug msg="zeroconf server listening on port 38201" Apr 24 07:32:35 volumio go-librespot[14011]: time="2025-04-24T07:32:35Z" level=debug msg="obtained new client token: AADsDwjU5UF3NV9S/fWUjRVEWGCY9O2Mqgyo0DQ17r0C0MJgfZ8dw+puArDhMtS7FBcS4OoqBuJzh26oBUO2m3V/e6sqtv8P1u0pmF3UMIBd59cVP1xXb9cNmb8Dl58VKBB/0VFc4Y+9YVzFZpqk8pr1q5JDZYoXhEDmm/Ygf2gh7WdIqEgGiJWchJYyRVo7BhhVmmB+jsoimXAeOsaTCtnA8zr0CXMIgepJMsNq+jftrgioSj2H8/g5S8zn" Apr 24 07:32:36 volumio go-librespot[14011]: time="2025-04-24T07:32:36Z" level=info msg="connected to ap-gae2.spotify.com:4070" Apr 24 07:32:36 volumio go-librespot[14011]: time="2025-04-24T07:32:36Z" level=debug msg="completed keyexchange" Apr 24 07:32:37 volumio go-librespot[14011]: time="2025-04-24T07:32:37Z" level=debug msg="completed challenge" Apr 24 07:32:37 volumio go-librespot[14011]: time="2025-04-24T07:32:37Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 24 07:32:37 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 24 07:32:37 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 24 07:32:38 volumio volumio[988]: info: Initializing connection to go-librespot Websocket Apr 24 07:32:38 volumio volumio[988]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 24 07:32:40 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 24 07:32:40 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 550. Apr 24 07:32:40 volumio systemd[1]: Stopped go-librespot Daemon. Apr 24 07:32:40 volumio systemd[1]: Started go-librespot Daemon. Apr 24 07:32:40 volumio go-librespot[14023]: Librespot-go daemon starting... Apr 24 07:32:40 volumio go-librespot[14023]: time="2025-04-24T07:32:40Z" level=info msg="generated new device id: 5190f146688027ed3e9cfcfee214ea013f1570cb" Apr 24 07:32:40 volumio go-librespot[14023]: time="2025-04-24T07:32:40Z" level=debug msg="stored credentials found for fukudvq" Apr 24 07:32:40 volumio go-librespot[14023]: time="2025-04-24T07:32:40Z" 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]" Apr 24 07:32:40 volumio go-librespot[14023]: time="2025-04-24T07:32:40Z" 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]" Apr 24 07:32:40 volumio go-librespot[14023]: time="2025-04-24T07:32:40Z" 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]" Apr 24 07:32:40 volumio go-librespot[14023]: time="2025-04-24T07:32:40Z" level=debug msg="zeroconf server listening on port 45915" Apr 24 07:32:40 volumio go-librespot[14023]: time="2025-04-24T07:32:40Z" level=debug msg="obtained new client token: AACtt9IRNCjnJCVeb53CPSL7fz8/lmdjszlcVvPEr5Zj8ohSGt91twqrk8E6G13G6p/KtnD6I4tbDugu+vZcG/lPh3G9C1J3EdzwklouDBaSE+mP2h+pnHmFTyIQm+WOU/ZOWKM+oNf28z9YughL1rgX7ED9u7kW1FIMHXPRKw42hHG6cAQ0VvRIEzJZzd9Gh64XrXKQ114igCfQ7FIRP5r7OBMRKgvwomBTDLCFtamCKBIyFr2Beya3R9Wt" Apr 24 07:32:40 volumio go-librespot[14023]: time="2025-04-24T07:32:40Z" level=warning msg="failed to connect to AP ap-gae2.spotify.com:4070 (error: dial tcp 104.199.241.202:4070: connect: connection refused), retrying with a different AP" Apr 24 07:32:41 volumio go-librespot[14023]: time="2025-04-24T07:32:41Z" level=info msg="connected to ap-gae2.spotify.com:443" Apr 24 07:32:41 volumio volumio[988]: info: Initializing connection to go-librespot Websocket Apr 24 07:32:41 volumio go-librespot[14023]: time="2025-04-24T07:32:41Z" level=debug msg="completed keyexchange" Apr 24 07:32:41 volumio go-librespot[14023]: time="2025-04-24T07:32:41Z" level=debug msg="new websocket client" Apr 24 07:32:41 volumio volumio[988]: info: Connection to go-librespot Websocket established Apr 24 07:32:41 volumio go-librespot[14023]: time="2025-04-24T07:32:41Z" level=debug msg="completed challenge" Apr 24 07:32:41 volumio go-librespot[14023]: time="2025-04-24T07:32:41Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 24 07:32:41 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 24 07:32:41 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 24 07:32:41 volumio volumio[988]: info: Connection to go-librespot Websocket closed Apr 24 07:32:44 volumio volumio[988]: info: Getting Spotify volume Apr 24 07:32:44 volumio volumio[988]: (node:988) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 24 07:32:44 volumio volumio[988]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Apr 24 07:32:44 volumio volumio[988]: (node:988) 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: 187) Apr 24 07:32:44 volumio volumio[988]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Apr 24 07:32:44 volumio volumio[988]: info: CoreCommandRouter::volumioGetState Apr 24 07:32:44 volumio volumio[988]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Apr 24 07:32:44 volumio volumio[988]: info: Initializing connection to go-librespot Websocket Apr 24 07:32:44 volumio volumio[988]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 24 07:32:44 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 24 07:32:44 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 551. Apr 24 07:32:44 volumio systemd[1]: Stopped go-librespot Daemon. Apr 24 07:32:44 volumio systemd[1]: Started go-librespot Daemon. Apr 24 07:32:44 volumio go-librespot[14045]: Librespot-go daemon starting... Apr 24 07:32:44 volumio go-librespot[14045]: time="2025-04-24T07:32:44Z" level=info msg="generated new device id: a1656b6f6c0812714bad827d6e047af4eddaeb8c" Apr 24 07:32:44 volumio go-librespot[14045]: time="2025-04-24T07:32:44Z" level=debug msg="stored credentials found for fukudvq" Apr 24 07:32:45 volumio go-librespot[14045]: time="2025-04-24T07:32:45Z" 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]" Apr 24 07:32:45 volumio go-librespot[14045]: time="2025-04-24T07:32:45Z" 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]" Apr 24 07:32:45 volumio go-librespot[14045]: time="2025-04-24T07:32:45Z" 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]" Apr 24 07:32:45 volumio go-librespot[14045]: time="2025-04-24T07:32:45Z" level=debug msg="zeroconf server listening on port 42951" Apr 24 07:32:45 volumio go-librespot[14045]: time="2025-04-24T07:32:45Z" level=debug msg="obtained new client token: AABBuXGtzDg44KLlWZtb825aXwuNW9lvp6iwFYYgK7tIu6LgScNEWE+0581PCf0wL/y29WHhQe6Eqye4zUQmEuXD7bRTQUrFrmSdA/b/VthtAPW9s6FUGb5nMwr4bm8wdqHc4AYEFxKqKIL7/Z/QX149vMDkbiqdTZiKPw1naHKUWInDFb9/gy7ob4oWfiD8ODDIJMKad6nU3tjqSg1F5TAHbRQklPNeZtNdQ+mRT1lZJjUO5anmhIHU0Q==" Apr 24 07:32:45 volumio go-librespot[14045]: time="2025-04-24T07:32:45Z" level=info msg="connected to ap-gae2.spotify.com:4070" Apr 24 07:32:45 volumio go-librespot[14045]: time="2025-04-24T07:32:45Z" level=debug msg="completed keyexchange" Apr 24 07:32:45 volumio go-librespot[14045]: time="2025-04-24T07:32:45Z" level=debug msg="completed challenge" Apr 24 07:32:45 volumio go-librespot[14045]: time="2025-04-24T07:32:45Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 24 07:32:45 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 24 07:32:45 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 24 07:32:47 volumio volumio[988]: info: Initializing connection to go-librespot Websocket Apr 24 07:32:47 volumio volumio[988]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 24 07:32:48 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 24 07:32:48 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 552. Apr 24 07:32:48 volumio systemd[1]: Stopped go-librespot Daemon. Apr 24 07:32:48 volumio systemd[1]: Started go-librespot Daemon. Apr 24 07:32:48 volumio go-librespot[14053]: Librespot-go daemon starting... Apr 24 07:32:48 volumio go-librespot[14053]: time="2025-04-24T07:32:48Z" level=info msg="generated new device id: 3698dea56cfe396eb2a1cd586df34ebfe90e1878" Apr 24 07:32:48 volumio go-librespot[14053]: time="2025-04-24T07:32:48Z" level=debug msg="stored credentials found for fukudvq" Apr 24 07:32:49 volumio go-librespot[14053]: time="2025-04-24T07:32:49Z" 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]" Apr 24 07:32:49 volumio go-librespot[14053]: time="2025-04-24T07:32:49Z" 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]" Apr 24 07:32:49 volumio go-librespot[14053]: time="2025-04-24T07:32:49Z" 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]" Apr 24 07:32:49 volumio go-librespot[14053]: time="2025-04-24T07:32:49Z" level=debug msg="zeroconf server listening on port 39861" Apr 24 07:32:49 volumio go-librespot[14053]: time="2025-04-24T07:32:49Z" level=debug msg="obtained new client token: AADgJudUOBy0zCxZl1kzUilCl5gO1/Atql4vpPWff+oLBmrRoLgZS9fEaq/NnfQlNGEEE4lZXObKYshodbHt8YIbAyoG1cafp8E2J3q3tGfY3EJqjlsrkEHcteC1eE/w38axfuPg3LA+kOD+8FZ2pyXQ5ItoYioIKNjAFQfNg5Yf6SzsEHO+tpEUSfkOuw9ThPFWQIYVK1VDocGW5jmJGwfLA+kUHk2/DIKmBvds6AP4n9bKQVjEPWkKig==" Apr 24 07:32:49 volumio go-librespot[14053]: time="2025-04-24T07:32:49Z" level=info msg="connected to ap-gae2.spotify.com:4070" Apr 24 07:32:49 volumio go-librespot[14053]: time="2025-04-24T07:32:49Z" level=debug msg="completed keyexchange" Apr 24 07:32:49 volumio go-librespot[14053]: time="2025-04-24T07:32:49Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed solving challenge: failed login: BadCredentials" Apr 24 07:32:49 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 24 07:32:49 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 24 07:32:50 volumio volumio[988]: info: Initializing connection to go-librespot Websocket Apr 24 07:32:50 volumio volumio[988]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 24 07:32:52 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 24 07:32:52 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 553. Apr 24 07:32:52 volumio systemd[1]: Stopped go-librespot Daemon. Apr 24 07:32:52 volumio systemd[1]: Started go-librespot Daemon. Apr 24 07:32:52 volumio go-librespot[14061]: Librespot-go daemon starting... Apr 24 07:32:52 volumio go-librespot[14061]: time="2025-04-24T07:32:52Z" level=info msg="generated new device id: 3d5dd797b892e06543bf5f6a540da56a4bf2139f" Apr 24 07:32:52 volumio go-librespot[14061]: time="2025-04-24T07:32:52Z" level=debug msg="stored credentials found for fukudvq" Apr 24 07:32:52 volumio go-librespot[14061]: time="2025-04-24T07:32:52Z" 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]" Apr 24 07:32:52 volumio go-librespot[14061]: time="2025-04-24T07:32:52Z" 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]" Apr 24 07:32:52 volumio go-librespot[14061]: time="2025-04-24T07:32:52Z" 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]" Apr 24 07:32:52 volumio go-librespot[14061]: time="2025-04-24T07:32:52Z" level=debug msg="zeroconf server listening on port 43599" Apr 24 07:32:52 volumio go-librespot[14061]: time="2025-04-24T07:32:52Z" level=debug msg="obtained new client token: AABuYn32nGe6AFz751y/OfxRNKmwIrCbuFOkjCRHgqhMoBCaqPJKShyvtaLS8tEplCa+cT8sBRDByuB+wSKV3N0rVL9HbYvNrF6DIVmSzH6IrbCcuz3hWHddmNj0+KVyjCGcrZBN5UyGrqAwe7n1lMUkjyEGcszGBhO16QcfPNGNzwENBXuRjyzoYdJg5Uzid44FfyRUGpLtx8QGLfBw7p0rvTTCtbjWSvDcHQCLXbhjsQxy1TlmaJnGu/Gj" Apr 24 07:32:52 volumio go-librespot[14061]: time="2025-04-24T07:32:52Z" level=info msg="connected to ap-gae2.spotify.com:4070" Apr 24 07:32:52 volumio go-librespot[14061]: time="2025-04-24T07:32:52Z" level=debug msg="completed keyexchange" Apr 24 07:32:53 volumio go-librespot[14061]: time="2025-04-24T07:32:53Z" level=debug msg="completed challenge" Apr 24 07:32:53 volumio go-librespot[14061]: time="2025-04-24T07:32:53Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 24 07:32:53 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 24 07:32:53 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 24 07:32:53 volumio volumio[988]: info: Initializing connection to go-librespot Websocket Apr 24 07:32:53 volumio volumio[988]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 24 07:32:56 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 24 07:32:56 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 554. Apr 24 07:32:56 volumio systemd[1]: Stopped go-librespot Daemon. Apr 24 07:32:56 volumio systemd[1]: Started go-librespot Daemon. Apr 24 07:32:56 volumio go-librespot[14084]: Librespot-go daemon starting... Apr 24 07:32:56 volumio go-librespot[14084]: time="2025-04-24T07:32:56Z" level=info msg="generated new device id: 8e365d87be0e843d85c2786cd6e8c065c565d35e" Apr 24 07:32:56 volumio go-librespot[14084]: time="2025-04-24T07:32:56Z" level=debug msg="stored credentials found for fukudvq" Apr 24 07:32:56 volumio go-librespot[14084]: time="2025-04-24T07:32: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-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Apr 24 07:32:56 volumio go-librespot[14084]: time="2025-04-24T07:32:56Z" 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]" Apr 24 07:32:56 volumio go-librespot[14084]: time="2025-04-24T07:32:56Z" 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]" Apr 24 07:32:56 volumio go-librespot[14084]: time="2025-04-24T07:32:56Z" level=debug msg="zeroconf server listening on port 43299" Apr 24 07:32:56 volumio go-librespot[14084]: time="2025-04-24T07:32:56Z" level=debug msg="obtained new client token: AAC9oUq8L/AHSFL7zKoGzgfDAdDntvSuV0T8gxv+K7zeRke+XehwMeWsDYhu3cm3k0LBlv7ywQiLh8YxbvAKN6VVF2N62YjbZMFgADimYVLKw6wzq9rdjczgzzsp91D86R5vdlZi4O2Y/jJu1i/aO14L2y3TLryjg1TMA9OKGNNB0dATwvQnyhBCfD4y9pFru4/Glp4q4CPLQp52dg4il+fo8NBswKa7dYU6UG7JfnIonmZBSGz9K577H31m" Apr 24 07:32:56 volumio volumio[988]: info: Initializing connection to go-librespot Websocket Apr 24 07:32:56 volumio go-librespot[14084]: time="2025-04-24T07:32:56Z" level=debug msg="new websocket client" Apr 24 07:32:56 volumio volumio[988]: info: Connection to go-librespot Websocket established Apr 24 07:32:56 volumio go-librespot[14084]: time="2025-04-24T07:32:56Z" level=info msg="connected to ap-gae2.spotify.com:4070" Apr 24 07:32:56 volumio go-librespot[14084]: time="2025-04-24T07:32:56Z" level=debug msg="completed keyexchange" Apr 24 07:32:57 volumio go-librespot[14084]: time="2025-04-24T07:32:57Z" level=debug msg="completed challenge" Apr 24 07:32:57 volumio go-librespot[14084]: time="2025-04-24T07:32:57Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 24 07:32:57 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 24 07:32:57 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 24 07:32:57 volumio volumio[988]: info: Connection to go-librespot Websocket closed Apr 24 07:32:59 volumio volumio[988]: info: Getting Spotify volume Apr 24 07:32:59 volumio volumio[988]: (node:988) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 24 07:32:59 volumio volumio[988]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Apr 24 07:32:59 volumio volumio[988]: (node:988) 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: 188) Apr 24 07:32:59 volumio volumio[988]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Apr 24 07:32:59 volumio volumio[988]: info: CoreCommandRouter::volumioGetState Apr 24 07:32:59 volumio volumio[988]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Apr 24 07:33:00 volumio volumio[988]: info: Initializing connection to go-librespot Websocket Apr 24 07:33:00 volumio volumio[988]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 24 07:33:00 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 24 07:33:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 555. Apr 24 07:33:00 volumio systemd[1]: Stopped go-librespot Daemon. Apr 24 07:33:00 volumio systemd[1]: Started go-librespot Daemon. Apr 24 07:33:00 volumio go-librespot[14105]: Librespot-go daemon starting... Apr 24 07:33:00 volumio go-librespot[14105]: time="2025-04-24T07:33:00Z" level=info msg="generated new device id: 836cb0487fb140c48e05d64dd360c79b2187501d" Apr 24 07:33:00 volumio go-librespot[14105]: time="2025-04-24T07:33:00Z" level=debug msg="stored credentials found for fukudvq" Apr 24 07:33:00 volumio go-librespot[14105]: time="2025-04-24T07:33: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]" Apr 24 07:33:00 volumio go-librespot[14105]: time="2025-04-24T07:33: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]" Apr 24 07:33:00 volumio go-librespot[14105]: time="2025-04-24T07:33: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]" Apr 24 07:33:00 volumio go-librespot[14105]: time="2025-04-24T07:33:00Z" level=debug msg="zeroconf server listening on port 40353" Apr 24 07:33:00 volumio go-librespot[14105]: time="2025-04-24T07:33:00Z" level=debug msg="obtained new client token: AADsnf+2HFtZ5GMbxjsqbdse5xgIUUitRNNQilZOz0R3VdgLWg69/cAKdzX5TahwodLFH22Qd5B14ScvlZglCk1y9L1duDLFYk6ffArZSJRiLEN29kc90kwWWwwmWOkgt+SV7FJoBNeVSvI94nO/S0ZpDAtiuIh++OIiscTph5wDwOIuWZRPefsEOFy4E/tdKVLDzSahEf92WpzaA3oRvMMW8IUJx+0dImttV+VHjIhZjn1D7BpnOOwWCH+X" Apr 24 07:33:00 volumio go-librespot[14105]: time="2025-04-24T07:33:00Z" level=info msg="connected to ap-gae2.spotify.com:4070" Apr 24 07:33:00 volumio go-librespot[14105]: time="2025-04-24T07:33:00Z" level=debug msg="completed keyexchange" Apr 24 07:33:01 volumio go-librespot[14105]: time="2025-04-24T07:33:01Z" level=debug msg="completed challenge" Apr 24 07:33:01 volumio go-librespot[14105]: time="2025-04-24T07:33:01Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 24 07:33:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 24 07:33:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 24 07:33:03 volumio volumio[988]: info: Initializing connection to go-librespot Websocket Apr 24 07:33:03 volumio volumio[988]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 24 07:33:03 volumio volumio[988]: verbose: New Socket.io Connection to 192.168.128.41 from 192.168.128.44 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Apr 24 07:33:04 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 24 07:33:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 556. Apr 24 07:33:04 volumio systemd[1]: Stopped go-librespot Daemon. Apr 24 07:33:04 volumio systemd[1]: Started go-librespot Daemon. Apr 24 07:33:04 volumio go-librespot[14127]: Librespot-go daemon starting... Apr 24 07:33:04 volumio go-librespot[14127]: time="2025-04-24T07:33:04Z" level=info msg="generated new device id: 1b2f7c4117b690b6084fc92d39088d57d7e651d6" Apr 24 07:33:04 volumio go-librespot[14127]: time="2025-04-24T07:33:04Z" level=debug msg="stored credentials found for fukudvq" Apr 24 07:33:05 volumio go-librespot[14127]: time="2025-04-24T07:33:05Z" 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]" Apr 24 07:33:05 volumio go-librespot[14127]: time="2025-04-24T07:33:05Z" 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]" Apr 24 07:33:05 volumio go-librespot[14127]: time="2025-04-24T07:33:05Z" 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]" Apr 24 07:33:05 volumio go-librespot[14127]: time="2025-04-24T07:33:05Z" level=debug msg="zeroconf server listening on port 40337" Apr 24 07:33:05 volumio go-librespot[14127]: time="2025-04-24T07:33:05Z" level=debug msg="obtained new client token: AAA7T4/D0IqgNGFCEIhtpVFztyVvPSVHpEhuL0Qr2AC7jBaKMCW7NGv+iTyFBs6fBqNFHw8slGtkz1D7jswGXuo5NsoH1G70hqxVQcHcwQSpJ3s0ssqWSHLbWG/NPQBNOX2vqhofIKxykqF52rY1KKWa/UcSIgUklErDta1hGCfMTeKk56edMjMRRnfgl+NWq1IcSHKx2ky0IyPjn6lymgh3DzDNZoXM9ZHzl3nuwpHOymzFBSoR6Bw4Kg==" Apr 24 07:33:05 volumio go-librespot[14127]: time="2025-04-24T07:33:05Z" level=info msg="connected to ap-gae2.spotify.com:4070" Apr 24 07:33:05 volumio go-librespot[14127]: time="2025-04-24T07:33:05Z" level=debug msg="completed keyexchange" Apr 24 07:33:05 volumio go-librespot[14127]: time="2025-04-24T07:33:05Z" level=debug msg="completed challenge" Apr 24 07:33:05 volumio go-librespot[14127]: time="2025-04-24T07:33:05Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 24 07:33:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 24 07:33:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 24 07:33:06 volumio volumio[988]: info: Initializing connection to go-librespot Websocket Apr 24 07:33:06 volumio volumio[988]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 24 07:33:07 volumio volumio[988]: verbose: New Socket.io Connection to 192.168.128.41 from 192.168.128.44 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Apr 24 07:33:07 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 24 07:33:07 volumio volumio[988]: info: CoreCommandRouter::volumioGetVisibleSources Apr 24 07:33:07 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 24 07:33:07 volumio volumio[988]: info: CoreCommandRouter::volumioGetState Apr 24 07:33:07 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 24 07:33:07 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 24 07:33:07 volumio volumio[988]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Apr 24 07:33:07 volumio volumio[988]: info: Received Get System Info Apr 24 07:33:07 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 24 07:33:07 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 24 07:33:07 volumio volumio[988]: info: Discovery: Getting this device information Apr 24 07:33:07 volumio volumio[988]: info: CoreCommandRouter::volumioGetState Apr 24 07:33:07 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 24 07:33:07 volumio volumio[988]: info: CoreCommandRouter::volumioGetState Apr 24 07:33:07 volumio volumio[988]: info: Listing playlists Apr 24 07:33:07 volumio volumio[988]: info: CoreCommandRouter::volumioGetQueue Apr 24 07:33:07 volumio volumio[988]: info: CoreStateMachine::getQueue Apr 24 07:33:07 volumio volumio[988]: info: CorePlayQueue::getQueue Apr 24 07:33:07 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 24 07:33:09 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 24 07:33:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 557. Apr 24 07:33:09 volumio systemd[1]: Stopped go-librespot Daemon. Apr 24 07:33:09 volumio systemd[1]: Started go-librespot Daemon. Apr 24 07:33:09 volumio go-librespot[14136]: Librespot-go daemon starting... Apr 24 07:33:09 volumio go-librespot[14136]: time="2025-04-24T07:33:09Z" level=info msg="generated new device id: 5cf8eee324851d4a191c6a07f4e143121e69955c" Apr 24 07:33:09 volumio go-librespot[14136]: time="2025-04-24T07:33:09Z" level=debug msg="stored credentials found for fukudvq" Apr 24 07:33:09 volumio go-librespot[14136]: time="2025-04-24T07:33:09Z" 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]" Apr 24 07:33:09 volumio go-librespot[14136]: time="2025-04-24T07:33:09Z" 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]" Apr 24 07:33:09 volumio go-librespot[14136]: time="2025-04-24T07:33:09Z" 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]" Apr 24 07:33:09 volumio go-librespot[14136]: time="2025-04-24T07:33:09Z" level=debug msg="zeroconf server listening on port 45107" Apr 24 07:33:09 volumio volumio[988]: info: Initializing connection to go-librespot Websocket Apr 24 07:33:09 volumio go-librespot[14136]: time="2025-04-24T07:33:09Z" level=debug msg="new websocket client" Apr 24 07:33:09 volumio volumio[988]: info: Connection to go-librespot Websocket established Apr 24 07:33:09 volumio go-librespot[14136]: time="2025-04-24T07:33:09Z" level=debug msg="obtained new client token: AADeclIDSj6hNR6Oi771qpRWu9vEd1xeNM1usHq72QQg1tZkkr8yy2fokXgoU3Z0pkfC/V5BnAXJEbmPQxq0njNRvrkR0rOsW4PkmJNqPeFBz4ITTS5gXvfTLz6kp/5lARi6Cjk4Lh1MfDqrxXGRB72Y2a1IgLwlrxS7AqR0O6TLGP10ffXWyWUY65hlemb7gucD+eRgQ+jXI9g+cpuDnrlLP4f7G60E2k3g3XBotR5DUm/SvtcFKSW+I3x9" Apr 24 07:33:09 volumio go-librespot[14136]: time="2025-04-24T07:33:09Z" level=info msg="connected to ap-gae2.spotify.com:4070" Apr 24 07:33:09 volumio go-librespot[14136]: time="2025-04-24T07:33:09Z" level=debug msg="completed keyexchange" Apr 24 07:33:09 volumio go-librespot[14136]: time="2025-04-24T07:33:09Z" level=debug msg="completed challenge" Apr 24 07:33:10 volumio go-librespot[14136]: time="2025-04-24T07:33:10Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 24 07:33:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 24 07:33:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 24 07:33:10 volumio volumio[988]: info: Connection to go-librespot Websocket closed Apr 24 07:33:12 volumio volumio[988]: info: Getting Spotify volume Apr 24 07:33:12 volumio volumio[988]: (node:988) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 24 07:33:12 volumio volumio[988]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Apr 24 07:33:12 volumio volumio[988]: (node:988) 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: 189) Apr 24 07:33:12 volumio volumio[988]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Apr 24 07:33:12 volumio volumio[988]: info: CoreCommandRouter::volumioGetState Apr 24 07:33:12 volumio volumio[988]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Apr 24 07:33:13 volumio volumio[988]: info: Initializing connection to go-librespot Websocket Apr 24 07:33:13 volumio volumio[988]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 24 07:33:13 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 24 07:33:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 558. Apr 24 07:33:13 volumio systemd[1]: Stopped go-librespot Daemon. Apr 24 07:33:13 volumio systemd[1]: Started go-librespot Daemon. Apr 24 07:33:13 volumio go-librespot[14145]: Librespot-go daemon starting... Apr 24 07:33:13 volumio go-librespot[14145]: time="2025-04-24T07:33:13Z" level=info msg="generated new device id: d55b1184e4becaaf4aab6061ffc500155ac7dea5" Apr 24 07:33:13 volumio go-librespot[14145]: time="2025-04-24T07:33:13Z" level=debug msg="stored credentials found for fukudvq" Apr 24 07:33:13 volumio go-librespot[14145]: time="2025-04-24T07:33:13Z" 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]" Apr 24 07:33:13 volumio go-librespot[14145]: time="2025-04-24T07:33:13Z" 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]" Apr 24 07:33:13 volumio go-librespot[14145]: time="2025-04-24T07:33:13Z" 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]" Apr 24 07:33:13 volumio go-librespot[14145]: time="2025-04-24T07:33:13Z" level=debug msg="zeroconf server listening on port 42131" Apr 24 07:33:13 volumio go-librespot[14145]: time="2025-04-24T07:33:13Z" level=debug msg="obtained new client token: AADMt9ILN7asyVujyYp4akppaDiPpYa/rS3tUjsyTQLU/zKqInm57rutP3GJ5ecs0uDU3h87OulOEQS92fulr3an7/AGvZazIeiPnLmPeDkF2ApVwq31Ruso4xJYdxouCLPY47MOdUNoZIKdIaztZfKIvFoTjq+03f2t9vFPAkbBoGtgN6jGlAoLntEkNet0d6iglFlztQcZQOlnzWvff3Y83+knR/GBl1fW5TJPFfSq8O6AdqBArJC7M9Is" Apr 24 07:33:13 volumio go-librespot[14145]: time="2025-04-24T07:33:13Z" level=info msg="connected to ap-gae2.spotify.com:4070" Apr 24 07:33:13 volumio go-librespot[14145]: time="2025-04-24T07:33:13Z" level=debug msg="completed keyexchange" Apr 24 07:33:14 volumio go-librespot[14145]: time="2025-04-24T07:33:14Z" level=debug msg="completed challenge" Apr 24 07:33:14 volumio go-librespot[14145]: time="2025-04-24T07:33:14Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 24 07:33:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 24 07:33:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 24 07:33:16 volumio volumio[988]: info: Initializing connection to go-librespot Websocket Apr 24 07:33:16 volumio volumio[988]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 24 07:33:17 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 24 07:33:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 559. Apr 24 07:33:17 volumio systemd[1]: Stopped go-librespot Daemon. Apr 24 07:33:17 volumio systemd[1]: Started go-librespot Daemon. Apr 24 07:33:17 volumio go-librespot[14168]: Librespot-go daemon starting... Apr 24 07:33:17 volumio go-librespot[14168]: time="2025-04-24T07:33:17Z" level=info msg="generated new device id: 27be29d94cb70d2cdf5bd703aa1749c7f9653d26" Apr 24 07:33:17 volumio go-librespot[14168]: time="2025-04-24T07:33:17Z" level=debug msg="stored credentials found for fukudvq" Apr 24 07:33:17 volumio go-librespot[14168]: time="2025-04-24T07:33:17Z" 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]" Apr 24 07:33:17 volumio go-librespot[14168]: time="2025-04-24T07:33:17Z" 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]" Apr 24 07:33:17 volumio go-librespot[14168]: time="2025-04-24T07:33:17Z" 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]" Apr 24 07:33:17 volumio go-librespot[14168]: time="2025-04-24T07:33:17Z" level=debug msg="zeroconf server listening on port 45933" Apr 24 07:33:17 volumio go-librespot[14168]: time="2025-04-24T07:33:17Z" level=debug msg="obtained new client token: AAAcPI3x31NTB1LS4XZBvPPYmWQ7c+O0/CppEvY2hMau+127TBk+XaxoejcqxA9jtzpR2VW/SVX7g06O5ToEV+v3u4+j2RVvM4iGM/PMRAJAPAmU5Oa5HXhg7vYBc3/FxD51uCKbOc6udQxw6x8B/BH92vgXNM6Ht47y5SLSfqIfufqRHF0XZuRpvJsKJi6sXbJfeJW1QwVCpFolPGdZX13GIpZJCAjlwpMkGR/cdB7KfLO8XzpvwZMzRNYW" Apr 24 07:33:17 volumio go-librespot[14168]: time="2025-04-24T07:33:17Z" level=info msg="connected to ap-gae2.spotify.com:4070" Apr 24 07:33:17 volumio go-librespot[14168]: time="2025-04-24T07:33:17Z" level=debug msg="completed keyexchange" Apr 24 07:33:18 volumio go-librespot[14168]: time="2025-04-24T07:33:18Z" level=debug msg="completed challenge" Apr 24 07:33:18 volumio go-librespot[14168]: time="2025-04-24T07:33:18Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 24 07:33:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 24 07:33:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 24 07:33:19 volumio volumio[988]: info: Initializing connection to go-librespot Websocket Apr 24 07:33:19 volumio volumio[988]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 24 07:33:19 volumio volumio[988]: info: CoreCommandRouter::volumioPause Apr 24 07:33:19 volumio volumio[988]: info: CoreStateMachine::pause Apr 24 07:33:19 volumio volumio[988]: info: CoreStateMachine::stPlaybackTimer Apr 24 07:33:19 volumio volumio[988]: info: CoreStateMachine::servicePause Apr 24 07:33:19 volumio volumio[988]: info: CorePlayQueue::getTrack 8 Apr 24 07:33:19 volumio volumio[988]: info: CoreCommandRouter::servicePause Apr 24 07:33:19 volumio volumio[988]: info: [1745479999060] ControllerQobuz::pause Apr 24 07:33:19 volumio volumio[988]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 24 07:33:19 volumio volumio[988]: info: ControllerMpd::pause Apr 24 07:33:19 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand pause Apr 24 07:33:19 volumio volumio[988]: info: Apr 24 07:33:19 volumio volumio[988]: ---------------------------- MPD announces state update: player Apr 24 07:33:19 volumio volumio[988]: info: sendMpdCommand pause took 1 milliseconds Apr 24 07:33:19 volumio volumio[988]: info: ControllerMpd::getState Apr 24 07:33:19 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand status Apr 24 07:33:19 volumio volumio[988]: info: sendMpdCommand status took 0 milliseconds Apr 24 07:33:19 volumio volumio[988]: verbose: ControllerMpd::parseState Apr 24 07:33:19 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 24 07:33:19 volumio volumio[988]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 24 07:33:19 volumio volumio[988]: verbose: ControllerMpd::parseTrackInfo Apr 24 07:33:19 volumio volumio[988]: info: ControllerMpd::pushState Apr 24 07:33:19 volumio volumio[988]: info: CoreCommandRouter::servicePushState Apr 24 07:33:19 volumio volumio[988]: info: CorePlayQueue::getTrack 8 Apr 24 07:33:19 volumio volumio[988]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":273740,"duration":395,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"3073 Kbps","isStreaming":false,"title":"281763445","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/281763445","trackType":"qobuz"} Apr 24 07:33:19 volumio volumio[988]: verbose: CURRENT POSITION 8 Apr 24 07:33:19 volumio volumio[988]: info: CoreStateMachine::syncState stateService pause Apr 24 07:33:19 volumio volumio[988]: info: CoreStateMachine::syncState currentStatus pause Apr 24 07:33:19 volumio volumio[988]: info: CoreStateMachine::pushState Apr 24 07:33:19 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 07:33:19 volumio volumio[988]: info: CoreCommandRouter::volumioPushState Apr 24 07:33:19 volumio volumio[988]: info: MRS: Pushing multiroomSync output update for this device Apr 24 07:33:19 volumio volumio[988]: info: MRS: Pushing multiroomSync output Apr 24 07:33:19 volumio volumio[988]: info: CoreStateMachine::stPlaybackTimer Apr 24 07:33:19 volumio volumio[988]: info: ------------------------------ 8ms Apr 24 07:33:19 volumio volumio[988]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Apr 24 07:33:21 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 24 07:33:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 560. Apr 24 07:33:21 volumio systemd[1]: Stopped go-librespot Daemon. Apr 24 07:33:21 volumio systemd[1]: Started go-librespot Daemon. Apr 24 07:33:21 volumio go-librespot[14217]: Librespot-go daemon starting... Apr 24 07:33:21 volumio go-librespot[14217]: time="2025-04-24T07:33:21Z" level=info msg="generated new device id: a1b9be40eb9f098b88b633f97726cbd96f195fa6" Apr 24 07:33:21 volumio go-librespot[14217]: time="2025-04-24T07:33:21Z" level=debug msg="stored credentials found for fukudvq" Apr 24 07:33:21 volumio go-librespot[14217]: time="2025-04-24T07:33:21Z" 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]" Apr 24 07:33:21 volumio go-librespot[14217]: time="2025-04-24T07:33:21Z" 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]" Apr 24 07:33:21 volumio go-librespot[14217]: time="2025-04-24T07:33:21Z" 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]" Apr 24 07:33:21 volumio go-librespot[14217]: time="2025-04-24T07:33:21Z" level=debug msg="zeroconf server listening on port 45981" Apr 24 07:33:21 volumio go-librespot[14217]: time="2025-04-24T07:33:21Z" level=debug msg="obtained new client token: AAAudv2HiKmaW8RzD1OcDPzPmzlneDeg9RDwF0QmDBKvgh/7tdKhCkhq0oftc0/0pciGZIUyh1rUvFKyrvt5SAIIwUgGdy+1vAPCnXd1Pmo40enJrRyy+3H4BDvZHvh30dIHRTCnyHztejlzHSnw3k0pQmcqu66vPXXBZdd5QmOo0oSXmSr7NvvummYKrBvpTQvockY8Fblj6mH1jeperXDUtL/Nl8SFMLeDVbBYdpYwpFJAXb6Yt4JnBEA8" Apr 24 07:33:21 volumio go-librespot[14217]: time="2025-04-24T07:33:21Z" level=info msg="connected to ap-gae2.spotify.com:4070" Apr 24 07:33:21 volumio go-librespot[14217]: time="2025-04-24T07:33:21Z" level=debug msg="completed keyexchange" Apr 24 07:33:22 volumio volumio[988]: info: Initializing connection to go-librespot Websocket Apr 24 07:33:22 volumio go-librespot[14217]: time="2025-04-24T07:33:22Z" level=debug msg="new websocket client" Apr 24 07:33:22 volumio volumio[988]: info: Connection to go-librespot Websocket established Apr 24 07:33:22 volumio go-librespot[14217]: time="2025-04-24T07:33:22Z" level=debug msg="completed challenge" Apr 24 07:33:22 volumio go-librespot[14217]: time="2025-04-24T07:33:22Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 24 07:33:22 volumio volumio[988]: info: Connection to go-librespot Websocket closed Apr 24 07:33:22 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 24 07:33:22 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 24 07:33:25 volumio volumio[988]: info: Getting Spotify volume Apr 24 07:33:25 volumio volumio[988]: (node:988) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 24 07:33:25 volumio volumio[988]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Apr 24 07:33:25 volumio volumio[988]: (node:988) 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: 190) Apr 24 07:33:25 volumio volumio[988]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Apr 24 07:33:25 volumio volumio[988]: info: CoreCommandRouter::volumioGetState Apr 24 07:33:25 volumio volumio[988]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Apr 24 07:33:25 volumio volumio[988]: info: Initializing connection to go-librespot Websocket Apr 24 07:33:25 volumio volumio[988]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 24 07:33:25 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 24 07:33:25 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 561. Apr 24 07:33:25 volumio systemd[1]: Stopped go-librespot Daemon. Apr 24 07:33:25 volumio systemd[1]: Started go-librespot Daemon. Apr 24 07:33:25 volumio go-librespot[14240]: Librespot-go daemon starting... Apr 24 07:33:25 volumio go-librespot[14240]: time="2025-04-24T07:33:25Z" level=info msg="generated new device id: 1f0c5cdc2f4530739b3badd66bd60a0c72b64720" Apr 24 07:33:25 volumio go-librespot[14240]: time="2025-04-24T07:33:25Z" level=debug msg="stored credentials found for fukudvq" Apr 24 07:33:25 volumio go-librespot[14240]: time="2025-04-24T07:33:25Z" 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]" Apr 24 07:33:25 volumio go-librespot[14240]: time="2025-04-24T07:33:25Z" 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]" Apr 24 07:33:25 volumio go-librespot[14240]: time="2025-04-24T07:33:25Z" 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]" Apr 24 07:33:25 volumio go-librespot[14240]: time="2025-04-24T07:33:25Z" level=debug msg="zeroconf server listening on port 40747" Apr 24 07:33:25 volumio go-librespot[14240]: time="2025-04-24T07:33:25Z" level=debug msg="obtained new client token: AADd7LtknKNga/6y+WomcIxfcSo2bbuK2WdkK1gqVOSn1WnTg6aBGtfGqXlqm+a1FKHK5xopaCbWskZJJyfbOqWxWM2Y5VO5Ta/iWA0Rjw/FbTk8/0ifLES9ib/hiMsDoSlPhI7t7IdnEqNud45V1ZyH2smy7Xfw+0ptrmuXyST2ecoexbaqVQCqYv/dkl5AroftDnfBy6yKJ7RFSn5bJN/eAT/gN3Fsd+hoYHl5VCnDLl0jDfnhva4PvygF" Apr 24 07:33:25 volumio go-librespot[14240]: time="2025-04-24T07:33:25Z" level=info msg="connected to ap-gae2.spotify.com:4070" Apr 24 07:33:25 volumio go-librespot[14240]: time="2025-04-24T07:33:25Z" level=debug msg="completed keyexchange" Apr 24 07:33:26 volumio go-librespot[14240]: time="2025-04-24T07:33:26Z" level=debug msg="completed challenge" Apr 24 07:33:26 volumio go-librespot[14240]: time="2025-04-24T07:33:26Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 24 07:33:26 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 24 07:33:26 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 24 07:33:28 volumio volumio[988]: info: Initializing connection to go-librespot Websocket Apr 24 07:33:28 volumio volumio[988]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 24 07:33:29 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 24 07:33:29 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 562. Apr 24 07:33:29 volumio systemd[1]: Stopped go-librespot Daemon. Apr 24 07:33:29 volumio systemd[1]: Started go-librespot Daemon. Apr 24 07:33:29 volumio go-librespot[14248]: Librespot-go daemon starting... Apr 24 07:33:29 volumio go-librespot[14248]: time="2025-04-24T07:33:29Z" level=info msg="generated new device id: 1c89758ed0528c04fe2ffccc4f2b13c8f8daf0e4" Apr 24 07:33:29 volumio go-librespot[14248]: time="2025-04-24T07:33:29Z" level=debug msg="stored credentials found for fukudvq" Apr 24 07:33:30 volumio go-librespot[14248]: time="2025-04-24T07:33:30Z" 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]" Apr 24 07:33:30 volumio go-librespot[14248]: time="2025-04-24T07:33:30Z" 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]" Apr 24 07:33:30 volumio go-librespot[14248]: time="2025-04-24T07:33:30Z" 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]" Apr 24 07:33:30 volumio go-librespot[14248]: time="2025-04-24T07:33:30Z" level=debug msg="zeroconf server listening on port 40623" Apr 24 07:33:30 volumio go-librespot[14248]: time="2025-04-24T07:33:30Z" level=debug msg="obtained new client token: AACNL7zngxi9D7tY5FQ2L/UhXtCrwLY+bUt+uRoTC02YdwpKu1DffmxJkvDCEmCmtEg5Z2U7R44oPcT0kBat3gLkforyz1oFIct8UPH1xI5wCfce27MrD9J77jHKRYo6RG2HPTLHz1YTIanTZLl+dcYBPa7+8UDm6VnLHTcxJqNKj9KA8G3Es6Hqbr+zBFX+LeNC8zqqQ9F31FuGrEv4QM/Tr7+gBV4o2auGKfRlKNqi4FR5lwII1DRqAA==" Apr 24 07:33:30 volumio go-librespot[14248]: time="2025-04-24T07:33:30Z" level=info msg="connected to ap-gae2.spotify.com:4070" Apr 24 07:33:30 volumio go-librespot[14248]: time="2025-04-24T07:33:30Z" level=debug msg="completed keyexchange" Apr 24 07:33:30 volumio go-librespot[14248]: time="2025-04-24T07:33:30Z" level=debug msg="completed challenge" Apr 24 07:33:30 volumio go-librespot[14248]: time="2025-04-24T07:33:30Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 24 07:33:30 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 24 07:33:30 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 24 07:33:31 volumio volumio[988]: info: Initializing connection to go-librespot Websocket Apr 24 07:33:31 volumio volumio[988]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 24 07:33:33 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 24 07:33:33 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 563. Apr 24 07:33:33 volumio systemd[1]: Stopped go-librespot Daemon. Apr 24 07:33:33 volumio systemd[1]: Started go-librespot Daemon. Apr 24 07:33:33 volumio go-librespot[14259]: Librespot-go daemon starting... Apr 24 07:33:33 volumio go-librespot[14259]: time="2025-04-24T07:33:33Z" level=info msg="generated new device id: 64c96dfb3e9007a6a2bf32f2a2e2a51f8aeda06d" Apr 24 07:33:33 volumio go-librespot[14259]: time="2025-04-24T07:33:33Z" level=debug msg="stored credentials found for fukudvq" Apr 24 07:33:34 volumio go-librespot[14259]: time="2025-04-24T07:33:34Z" 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]" Apr 24 07:33:34 volumio go-librespot[14259]: time="2025-04-24T07:33:34Z" 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]" Apr 24 07:33:34 volumio go-librespot[14259]: time="2025-04-24T07:33:34Z" 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]" Apr 24 07:33:34 volumio go-librespot[14259]: time="2025-04-24T07:33:34Z" level=debug msg="zeroconf server listening on port 36477" Apr 24 07:33:34 volumio go-librespot[14259]: time="2025-04-24T07:33:34Z" level=debug msg="obtained new client token: AAAG7FWG+CMPX8UnP3ZLLLD07v3DF/jYaJjFf00s4ir4XtW+oF/KFycVBWJK0tXUWUz1/I1gbVWtzaqGV2mx3NROmGSCb1EU6EngvPjkmcZpYurrkn0+YPu1xKGyTWrOI0A+w9XfZoKM5tmKsCfjcprd8/W0xf3oS+0KwmXopOBjxe6depJpLP0vz/v6mGEys4SQxI12n9JwTlNd5A1F1a28tp7xm2fc9ZJr9J23Hauj3SR1MOG8iBxSDw==" Apr 24 07:33:34 volumio go-librespot[14259]: time="2025-04-24T07:33:34Z" level=info msg="connected to ap-gae2.spotify.com:4070" Apr 24 07:33:34 volumio go-librespot[14259]: time="2025-04-24T07:33:34Z" level=debug msg="completed keyexchange" Apr 24 07:33:34 volumio go-librespot[14259]: time="2025-04-24T07:33:34Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed solving challenge: failed login: BadCredentials" Apr 24 07:33:34 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 24 07:33:34 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 24 07:33:34 volumio volumio[988]: info: Initializing connection to go-librespot Websocket Apr 24 07:33:34 volumio volumio[988]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 24 07:33:37 volumio volumio[988]: info: Initializing connection to go-librespot Websocket Apr 24 07:33:37 volumio volumio[988]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 24 07:33:37 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 24 07:33:37 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 564. Apr 24 07:33:37 volumio systemd[1]: Stopped go-librespot Daemon. Apr 24 07:33:37 volumio systemd[1]: Started go-librespot Daemon. Apr 24 07:33:37 volumio go-librespot[14282]: Librespot-go daemon starting... Apr 24 07:33:37 volumio go-librespot[14282]: time="2025-04-24T07:33:37Z" level=info msg="generated new device id: a3f24033cb0eb238fa8bf5a9e4c5aeb1f47ad8fd" Apr 24 07:33:37 volumio go-librespot[14282]: time="2025-04-24T07:33:37Z" level=debug msg="stored credentials found for fukudvq" Apr 24 07:33:37 volumio go-librespot[14282]: time="2025-04-24T07:33: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-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Apr 24 07:33:37 volumio go-librespot[14282]: time="2025-04-24T07:33:37Z" 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]" Apr 24 07:33:37 volumio go-librespot[14282]: time="2025-04-24T07:33:37Z" 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]" Apr 24 07:33:37 volumio go-librespot[14282]: time="2025-04-24T07:33:37Z" level=debug msg="zeroconf server listening on port 38637" Apr 24 07:33:37 volumio go-librespot[14282]: time="2025-04-24T07:33:37Z" level=debug msg="obtained new client token: AABpNAIYDXj2DQXtlzoWUOQAgs5kzJEUPlAIz2gJUW4Z2zPrrJt5COvORHYyr0NONFOA4yjqF+8Za++Dv8QZ91z1EWurW3ePwM9ZQyysEJz0KXHQ92+6VGmYUWq+h+H+suwacr9dq745I4E/1ooXGSR65KLgsHvMM8G6/K2Wzx2OBhtUk7oGAzYKZn5l+DP0juOPX59ADj0afPVoHMoafp1SklqRhnc/esrRvyS7BObpiBVXaWmVSaYGXUl1" Apr 24 07:33:37 volumio go-librespot[14282]: time="2025-04-24T07:33:37Z" level=warning msg="failed to connect to AP ap-gae2.spotify.com:4070 (error: dial tcp 104.199.241.202:4070: connect: connection refused), retrying with a different AP" Apr 24 07:33:37 volumio go-librespot[14282]: time="2025-04-24T07:33:37Z" level=info msg="connected to ap-gae2.spotify.com:443" Apr 24 07:33:38 volumio go-librespot[14282]: time="2025-04-24T07:33:38Z" level=debug msg="completed keyexchange" Apr 24 07:33:38 volumio go-librespot[14282]: time="2025-04-24T07:33:38Z" level=debug msg="completed challenge" Apr 24 07:33:38 volumio go-librespot[14282]: time="2025-04-24T07:33:38Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 24 07:33:38 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 24 07:33:38 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 24 07:33:40 volumio volumio[988]: info: Initializing connection to go-librespot Websocket Apr 24 07:33:40 volumio volumio[988]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 24 07:33:41 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 24 07:33:41 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 565. Apr 24 07:33:41 volumio systemd[1]: Stopped go-librespot Daemon. Apr 24 07:33:41 volumio systemd[1]: Started go-librespot Daemon. Apr 24 07:33:41 volumio go-librespot[14293]: Librespot-go daemon starting... Apr 24 07:33:41 volumio go-librespot[14293]: time="2025-04-24T07:33:41Z" level=info msg="generated new device id: 06cbbed1035bfee1c2850b7d18151921505ebb53" Apr 24 07:33:41 volumio go-librespot[14293]: time="2025-04-24T07:33:41Z" level=debug msg="stored credentials found for fukudvq" Apr 24 07:33:42 volumio go-librespot[14293]: time="2025-04-24T07:33: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]" Apr 24 07:33:42 volumio go-librespot[14293]: time="2025-04-24T07:33: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]" Apr 24 07:33:42 volumio go-librespot[14293]: time="2025-04-24T07:33: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]" Apr 24 07:33:42 volumio go-librespot[14293]: time="2025-04-24T07:33:42Z" level=debug msg="zeroconf server listening on port 37235" Apr 24 07:33:42 volumio go-librespot[14293]: time="2025-04-24T07:33:42Z" level=debug msg="obtained new client token: AAAf9ZYX6ML824k2fwP2KYFl9OXVSG+yJBIAIyZ19QlzU6+5wEQslkk7RNe6vrPdYQaB67kFBNBqCDtLSqdMQVtuX/BaY86TIlWdv56Co8FQCtbFpNw0ceuDICVmTUfJsApiCCWDBqWDaAmh0SqOTjT/mAEFq4JHcuGYBAf7sJmOTYzxU/FQ2ncBpmPIQN6udXU5T+g73aso5bQN/bti5k2BBkfiU+7odG39IzDN3P7gTnTbR4cT18MoAQ==" Apr 24 07:33:42 volumio go-librespot[14293]: time="2025-04-24T07:33:42Z" level=info msg="connected to ap-gae2.spotify.com:4070" Apr 24 07:33:42 volumio go-librespot[14293]: time="2025-04-24T07:33:42Z" level=debug msg="completed keyexchange" Apr 24 07:33:42 volumio go-librespot[14293]: time="2025-04-24T07:33:42Z" level=debug msg="completed challenge" Apr 24 07:33:42 volumio go-librespot[14293]: time="2025-04-24T07:33:42Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 24 07:33:42 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 24 07:33:42 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 24 07:33:43 volumio volumio[988]: info: Initializing connection to go-librespot Websocket Apr 24 07:33:43 volumio volumio[988]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 24 07:33:46 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 24 07:33:46 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 566. Apr 24 07:33:46 volumio systemd[1]: Stopped go-librespot Daemon. Apr 24 07:33:46 volumio systemd[1]: Started go-librespot Daemon. Apr 24 07:33:46 volumio go-librespot[14315]: Librespot-go daemon starting... Apr 24 07:33:46 volumio go-librespot[14315]: time="2025-04-24T07:33:46Z" level=info msg="generated new device id: aff3db75ef324fc6b1ac4e00455b7d07b12f24db" Apr 24 07:33:46 volumio go-librespot[14315]: time="2025-04-24T07:33:46Z" level=debug msg="stored credentials found for fukudvq" Apr 24 07:33:46 volumio go-librespot[14315]: time="2025-04-24T07:33: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-gew1.spotify.com:80]" Apr 24 07:33:46 volumio go-librespot[14315]: time="2025-04-24T07:33:46Z" 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]" Apr 24 07:33:46 volumio go-librespot[14315]: time="2025-04-24T07:33:46Z" 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]" Apr 24 07:33:46 volumio go-librespot[14315]: time="2025-04-24T07:33:46Z" level=debug msg="zeroconf server listening on port 39829" Apr 24 07:33:46 volumio go-librespot[14315]: time="2025-04-24T07:33:46Z" level=debug msg="obtained new client token: AAC75okd7hsYPR1F506nwHQcMf8Fi5Y81EGlWzCKjDgsHfCYrdwM3PjKlLoM2JmT3QLAXj7eUcBUytSkscyIeOerM4l7McGk34QYR50T2X1S+aFhq3GTpQnfJg19iopLTWysTqyK+eL/CiXUpTtJs4RbGHJ5YDIlPa6MrRSpci5Wr8iBdzNg1+AXfgiClN2A1LXbHtVxxbF1pApoNgpC266z8MFH6jphsn6fVeGqmVVhMfoVniGOYaFW5qdY" Apr 24 07:33:46 volumio volumio[988]: info: Initializing connection to go-librespot Websocket Apr 24 07:33:46 volumio go-librespot[14315]: time="2025-04-24T07:33:46Z" level=debug msg="new websocket client" Apr 24 07:33:46 volumio volumio[988]: info: Connection to go-librespot Websocket established Apr 24 07:33:46 volumio go-librespot[14315]: time="2025-04-24T07:33:46Z" level=info msg="connected to ap-gae2.spotify.com:4070" Apr 24 07:33:46 volumio go-librespot[14315]: time="2025-04-24T07:33:46Z" level=debug msg="completed keyexchange" Apr 24 07:33:46 volumio go-librespot[14315]: time="2025-04-24T07:33:46Z" level=debug msg="completed challenge" Apr 24 07:33:47 volumio go-librespot[14315]: time="2025-04-24T07:33:47Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Apr 24 07:33:47 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 24 07:33:47 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 24 07:33:47 volumio volumio[988]: info: Connection to go-librespot Websocket closed Apr 24 07:33:49 volumio volumio[988]: info: Getting Spotify volume Apr 24 07:33:49 volumio volumio[988]: (node:988) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 24 07:33:49 volumio volumio[988]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Apr 24 07:33:49 volumio volumio[988]: (node:988) 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: 191) Apr 24 07:33:49 volumio volumio[988]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Apr 24 07:33:49 volumio volumio[988]: info: CoreCommandRouter::volumioGetState Apr 24 07:33:49 volumio volumio[988]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Apr 24 07:33:50 volumio volumio[988]: info: Initializing connection to go-librespot Websocket Apr 24 07:33:50 volumio volumio[988]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 24 07:33:50 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 24 07:33:50 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 567. Apr 24 07:33:50 volumio systemd[1]: Stopped go-librespot Daemon. Apr 24 07:33:50 volumio volumio[988]: Searching all installed plugins Apr 24 07:33:50 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 24 07:33:50 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: , search Apr 24 07:33:50 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: mpd , search Apr 24 07:33:50 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Apr 24 07:33:50 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Apr 24 07:33:50 volumio volumio[988]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Apr 24 07:33:50 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: webradio , search Apr 24 07:33:50 volumio systemd[1]: Started go-librespot Daemon. Apr 24 07:33:50 volumio go-librespot[14323]: Librespot-go daemon starting... Apr 24 07:33:50 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: calmradio , search Apr 24 07:33:50 volumio volumio[988]: info: [1745480030338] ControllerCalmRadio::searchCategories Apr 24 07:33:50 volumio volumio[988]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 24 07:33:50 volumio volumio[988]: TypeError: Cannot read property 'cats' of undefined Apr 24 07:33:50 volumio volumio[988]: at ControllerCalmRadio.searchCategories (/data/plugins/music_service/calmradio/index.js:615:42) Apr 24 07:33:50 volumio volumio[988]: at ControllerCalmRadio.search (/data/plugins/music_service/calmradio/index.js:665:28) Apr 24 07:33:50 volumio volumio[988]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) Apr 24 07:33:50 volumio volumio[988]: at CoreMusicLibrary.searchOnPlugin (/volumio/app/musiclibrary.js:668:44) Apr 24 07:33:50 volumio volumio[988]: at CoreMusicLibrary.search (/volumio/app/musiclibrary.js:455:27) Apr 24 07:33:50 volumio volumio[988]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:485:44) Apr 24 07:33:50 volumio volumio[988]: at Socket.emit (events.js:315:20) Apr 24 07:33:50 volumio volumio[988]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Apr 24 07:33:50 volumio volumio[988]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Apr 24 07:33:50 volumio volumio[988]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 24 07:33:50 volumio go-librespot[14323]: time="2025-04-24T07:33:50Z" level=info msg="generated new device id: b27bc70b953e320be0e91c354b3f38dc0dd188b8" Apr 24 07:33:50 volumio go-librespot[14323]: time="2025-04-24T07:33:50Z" level=debug msg="stored credentials found for fukudvq" Apr 24 07:33:50 volumio go-librespot[14323]: time="2025-04-24T07:33: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-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Apr 24 07:33:50 volumio go-librespot[14323]: time="2025-04-24T07:33:50Z" 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]" Apr 24 07:33:50 volumio go-librespot[14323]: time="2025-04-24T07:33:50Z" 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]" Apr 24 07:33:50 volumio go-librespot[14323]: time="2025-04-24T07:33:50Z" level=debug msg="zeroconf server listening on port 39577" Apr 24 07:33:50 volumio go-librespot[14323]: time="2025-04-24T07:33:50Z" level=debug msg="obtained new client token: AADOXM3IHvJcpE98eom0iuKllzV0IBvF/OgSKPt4qQc3O5K2YqYFreFJLsP2bnsF3c4yCFIZcndhmaA+YUue80z34koQjU1V+1NFXGQpAqibn8D5zlhYa9Xk3UQtJ0KEYgoHKX4mVrwCx6XKk1BSq6YPHjZGaAEzg+UrbDG9joE+MANa9vXmMfAdG5xjmL+Kwmpc3q5EbIin4GGR17QdoKMec/X4bltWOW7f4pPurn2Dqoo46dhEIoFJ9aPw" Apr 24 07:33:50 volumio go-librespot[14323]: time="2025-04-24T07:33:50Z" level=info msg="connected to ap-gae2.spotify.com:4070" Apr 24 07:33:50 volumio sudo[14340]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-24 07:32 Apr 24 07:33:50 volumio sudo[14340]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 07:33:50 volumio go-librespot[14323]: time="2025-04-24T07:33:50Z" level=debug msg="completed keyexchange" 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 06:54:47 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="436f3f84f6d0b4ffc344f0bc132a32e8"