-- Logs begin at Sun 2025-12-14 19:19:16 CET, end at Sun 2025-12-14 21:15:06 CET. -- Dec 14 21:14:00 rivo volumio[3283]: info: Initializing connection to go-librespot Websocket Dec 14 21:14:00 rivo volumio[3283]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 14 21:14:00 rivo systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 14 21:14:00 rivo systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2745. Dec 14 21:14:00 rivo systemd[1]: Stopped go-librespot Daemon. Dec 14 21:14:00 rivo systemd[1]: Started go-librespot Daemon. Dec 14 21:14:00 rivo go-librespot[8959]: Librespot-go daemon starting... Dec 14 21:14:00 rivo go-librespot[8959]: time="2025-12-14T21:14:00+01:00" level=info msg="generated new device id: b4f6bfbbe785da3775a91b54cc9e69f6bb3ac310" Dec 14 21:14:00 rivo go-librespot[8959]: time="2025-12-14T21:14:00+01:00" level=debug msg="stored credentials found for 31ufjgzm7ki5qunbvnwotliejbxm" Dec 14 21:14:00 rivo go-librespot[8959]: time="2025-12-14T21:14:00+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 14 21:14:00 rivo go-librespot[8959]: time="2025-12-14T21:14:00+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 14 21:14:00 rivo go-librespot[8959]: time="2025-12-14T21:14:00+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 14 21:14:00 rivo go-librespot[8959]: time="2025-12-14T21:14:00+01:00" level=debug msg="zeroconf server listening on port 41501" Dec 14 21:14:00 rivo go-librespot[8959]: time="2025-12-14T21:14:00+01:00" level=debug msg="obtained new client token: AADbzVYdXxZ2eo7Zba77KpIEmGD5G3xgnLAbQnozVEz/42kDnoTySmIpZLfr95ruJhMWxsELQJdrxc704MbxUw94US4Tr0A3HzmDUT6d9n227/8f/iUXvFHFtlV0Zyl2EN3atclc0vb1AS4gzXAalrcx7QwOi/48eJP6lBsMqq317nIMecYSUqw9vCqwOSost2MdROSTlUZOMZcFXd+3sFfNoPRoHkieviLfGuKoB7KxDn47zy3SAiSktz/Z" Dec 14 21:14:01 rivo go-librespot[8959]: time="2025-12-14T21:14:01+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Dec 14 21:14:01 rivo go-librespot[8959]: time="2025-12-14T21:14:01+01:00" level=debug msg="completed keyexchange" Dec 14 21:14:01 rivo go-librespot[8959]: time="2025-12-14T21:14:01+01:00" level=debug msg="completed challenge" Dec 14 21:14:01 rivo go-librespot[8959]: time="2025-12-14T21:14:01+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Dec 14 21:14:01 rivo systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 14 21:14:01 rivo systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 14 21:14:03 rivo volumio[3283]: info: Initializing connection to go-librespot Websocket Dec 14 21:14:03 rivo volumio[3283]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 14 21:14:04 rivo systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 14 21:14:04 rivo systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2746. Dec 14 21:14:04 rivo systemd[1]: Stopped go-librespot Daemon. Dec 14 21:14:04 rivo systemd[1]: Started go-librespot Daemon. Dec 14 21:14:04 rivo go-librespot[8994]: Librespot-go daemon starting... Dec 14 21:14:04 rivo go-librespot[8994]: time="2025-12-14T21:14:04+01:00" level=info msg="generated new device id: cd3a017106982fa5731b05742c3388a36def4521" Dec 14 21:14:04 rivo go-librespot[8994]: time="2025-12-14T21:14:04+01:00" level=debug msg="stored credentials found for 31ufjgzm7ki5qunbvnwotliejbxm" Dec 14 21:14:05 rivo go-librespot[8994]: time="2025-12-14T21:14:05+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 14 21:14:05 rivo go-librespot[8994]: time="2025-12-14T21:14:05+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 14 21:14:05 rivo go-librespot[8994]: time="2025-12-14T21:14:05+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 14 21:14:05 rivo go-librespot[8994]: time="2025-12-14T21:14:05+01:00" level=debug msg="zeroconf server listening on port 45113" Dec 14 21:14:05 rivo go-librespot[8994]: time="2025-12-14T21:14:05+01:00" level=debug msg="obtained new client token: AABvWdXo0EfuBHLYV+etXE3XKnwnyLlH3TWCUb9DqnYBRxOVuSGOHuK97G/69Abzm3ggXbxyjjS7bMcyqPOl9+qLDrMN1CChKLqXqQjOrpR7m27nqEtGtzH+U3GprtFppOxzNqne35CvCra7Xa6dQazAEkRs/FXW8uP4hSvOW/6TxDazy4jOBbJa9WwrPKg1jPTFmxfLNVfwZg8zwVJgCOzCgVGlJBje77Shy0FimCty5AQm/2J3ukFVYt2W" Dec 14 21:14:05 rivo go-librespot[8994]: time="2025-12-14T21:14:05+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Dec 14 21:14:05 rivo go-librespot[8994]: time="2025-12-14T21:14:05+01:00" level=debug msg="completed keyexchange" Dec 14 21:14:06 rivo volumio[3283]: info: Initializing connection to go-librespot Websocket Dec 14 21:14:06 rivo go-librespot[8994]: time="2025-12-14T21:14:06+01:00" level=debug msg="new websocket client" Dec 14 21:14:06 rivo volumio[3283]: info: Connection to go-librespot Websocket established Dec 14 21:14:06 rivo go-librespot[8994]: time="2025-12-14T21:14:06+01:00" level=debug msg="completed challenge" Dec 14 21:14:06 rivo go-librespot[8994]: time="2025-12-14T21:14:06+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Dec 14 21:14:06 rivo systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 14 21:14:06 rivo systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 14 21:14:06 rivo volumio[3283]: info: Connection to go-librespot Websocket closed Dec 14 21:14:09 rivo volumio[3283]: info: Getting Spotify volume Dec 14 21:14:09 rivo volumio[3283]: (node:3283) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 14 21:14:09 rivo volumio[3283]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) Dec 14 21:14:09 rivo volumio[3283]: (node:3283) 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: 1248) Dec 14 21:14:09 rivo volumio[3283]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Dec 14 21:14:09 rivo volumio[3283]: info: CoreCommandRouter::volumioGetState Dec 14 21:14:09 rivo volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 14 21:14:09 rivo volumio[3283]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 14 21:14:09 rivo volumio[3283]: info: Initializing connection to go-librespot Websocket Dec 14 21:14:09 rivo volumio[3283]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 14 21:14:09 rivo systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 14 21:14:09 rivo systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2747. Dec 14 21:14:09 rivo systemd[1]: Stopped go-librespot Daemon. Dec 14 21:14:09 rivo systemd[1]: Started go-librespot Daemon. Dec 14 21:14:09 rivo go-librespot[9013]: Librespot-go daemon starting... Dec 14 21:14:09 rivo go-librespot[9013]: time="2025-12-14T21:14:09+01:00" level=info msg="generated new device id: 319a617dc3db3ecf9d437b9f5a7c829cfe38f423" Dec 14 21:14:09 rivo go-librespot[9013]: time="2025-12-14T21:14:09+01:00" level=debug msg="stored credentials found for 31ufjgzm7ki5qunbvnwotliejbxm" Dec 14 21:14:09 rivo go-librespot[9013]: time="2025-12-14T21:14:09+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 14 21:14:09 rivo go-librespot[9013]: time="2025-12-14T21:14:09+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 14 21:14:09 rivo go-librespot[9013]: time="2025-12-14T21:14:09+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 14 21:14:09 rivo go-librespot[9013]: time="2025-12-14T21:14:09+01:00" level=debug msg="zeroconf server listening on port 44781" Dec 14 21:14:09 rivo go-librespot[9013]: time="2025-12-14T21:14:09+01:00" level=debug msg="obtained new client token: AABmM7aP8KyPbdoqJ4wxkb+UTBVjy6FaH5Q5Kma+LoWzK/i909X6fkN5sDfXi9RsLcLdiFZYJnsVfAClkaf0L52NeWCrbZO2chphr5Gig52hEgf2cHwPtZ+kxGmRR3oMFFanQporTY5SPTLstjr2GtYJKDX/1uom7n+eXwzNfeqpkQmhilOzS8LhJCr31uDWq7lEx7vJDZV1vbEQWGSeWOceeaO/vE+vGAf9f9ov15UUdpcDn0t00GxwCfI2" Dec 14 21:14:10 rivo go-librespot[9013]: time="2025-12-14T21:14:10+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Dec 14 21:14:10 rivo go-librespot[9013]: time="2025-12-14T21:14:10+01:00" level=debug msg="completed keyexchange" Dec 14 21:14:10 rivo go-librespot[9013]: time="2025-12-14T21:14:10+01:00" level=debug msg="completed challenge" Dec 14 21:14:10 rivo go-librespot[9013]: time="2025-12-14T21:14:10+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Dec 14 21:14:10 rivo systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 14 21:14:10 rivo systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 14 21:14:12 rivo volumio[3283]: info: Initializing connection to go-librespot Websocket Dec 14 21:14:12 rivo volumio[3283]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 14 21:14:14 rivo systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 14 21:14:14 rivo systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2748. Dec 14 21:14:14 rivo systemd[1]: Stopped go-librespot Daemon. Dec 14 21:14:14 rivo systemd[1]: Started go-librespot Daemon. Dec 14 21:14:14 rivo go-librespot[9057]: Librespot-go daemon starting... Dec 14 21:14:14 rivo go-librespot[9057]: time="2025-12-14T21:14:14+01:00" level=info msg="generated new device id: 3116c8c1143b973fb1b229e4647e5fd9a00da2ca" Dec 14 21:14:14 rivo go-librespot[9057]: time="2025-12-14T21:14:14+01:00" level=debug msg="stored credentials found for 31ufjgzm7ki5qunbvnwotliejbxm" Dec 14 21:14:14 rivo go-librespot[9057]: time="2025-12-14T21:14:14+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 14 21:14:14 rivo go-librespot[9057]: time="2025-12-14T21:14:14+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 14 21:14:14 rivo go-librespot[9057]: time="2025-12-14T21:14:14+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 14 21:14:14 rivo go-librespot[9057]: time="2025-12-14T21:14:14+01:00" level=debug msg="zeroconf server listening on port 36623" Dec 14 21:14:14 rivo go-librespot[9057]: time="2025-12-14T21:14:14+01:00" level=debug msg="obtained new client token: AAC0CVKxPB9jnL9mHo6QGFnptK12P8qkioOls96qxZlBlD0MLzf/lc+gZFkwG1sEQPGMKdt50rotur3ej/nsDQiuYtj30B2XDz8K5jbwVcY6YCVayIE+08HmGUtl3/R3fr+XIqMhO40KmKg6hxf8FITTDjf834TVKySPqBhP6ozMJgMqCM5WcrHv0v/IMbzeAw5lHsLr6zRY/X0viTPUEO64T21b84jnNfNZhLaRRyiT6m9m5e/xMUe1Svtw" Dec 14 21:14:14 rivo go-librespot[9057]: time="2025-12-14T21:14:14+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Dec 14 21:14:14 rivo go-librespot[9057]: time="2025-12-14T21:14:14+01:00" level=debug msg="completed keyexchange" Dec 14 21:14:15 rivo volumio[3283]: info: Initializing connection to go-librespot Websocket Dec 14 21:14:15 rivo go-librespot[9057]: time="2025-12-14T21:14:15+01:00" level=debug msg="new websocket client" Dec 14 21:14:15 rivo volumio[3283]: info: Connection to go-librespot Websocket established Dec 14 21:14:15 rivo go-librespot[9057]: time="2025-12-14T21:14:15+01:00" level=debug msg="completed challenge" Dec 14 21:14:15 rivo go-librespot[9057]: time="2025-12-14T21:14:15+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Dec 14 21:14:15 rivo volumio[3283]: info: Connection to go-librespot Websocket closed Dec 14 21:14:15 rivo systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 14 21:14:15 rivo systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 14 21:14:18 rivo volumio[3283]: info: Getting Spotify volume Dec 14 21:14:18 rivo volumio[3283]: (node:3283) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 14 21:14:18 rivo volumio[3283]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) Dec 14 21:14:18 rivo volumio[3283]: (node:3283) 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: 1249) Dec 14 21:14:18 rivo volumio[3283]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Dec 14 21:14:18 rivo volumio[3283]: info: CoreCommandRouter::volumioGetState Dec 14 21:14:18 rivo volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 14 21:14:18 rivo volumio[3283]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 14 21:14:18 rivo volumio[3283]: info: Initializing connection to go-librespot Websocket Dec 14 21:14:18 rivo volumio[3283]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 14 21:14:18 rivo systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 14 21:14:18 rivo systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2749. Dec 14 21:14:18 rivo systemd[1]: Stopped go-librespot Daemon. Dec 14 21:14:18 rivo systemd[1]: Started go-librespot Daemon. Dec 14 21:14:18 rivo go-librespot[9074]: Librespot-go daemon starting... Dec 14 21:14:18 rivo go-librespot[9074]: time="2025-12-14T21:14:18+01:00" level=info msg="generated new device id: af7d11adaee44689fa458425bf48b630c9f81211" Dec 14 21:14:18 rivo go-librespot[9074]: time="2025-12-14T21:14:18+01:00" level=debug msg="stored credentials found for 31ufjgzm7ki5qunbvnwotliejbxm" Dec 14 21:14:18 rivo go-librespot[9074]: time="2025-12-14T21:14:18+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 14 21:14:18 rivo go-librespot[9074]: time="2025-12-14T21:14:18+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 14 21:14:18 rivo go-librespot[9074]: time="2025-12-14T21:14:18+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 14 21:14:18 rivo go-librespot[9074]: time="2025-12-14T21:14:18+01:00" level=debug msg="zeroconf server listening on port 37821" Dec 14 21:14:19 rivo go-librespot[9074]: time="2025-12-14T21:14:19+01:00" level=debug msg="obtained new client token: AACjTV7M/lh43DJP6zqi48aZHvYOzpb9JhFgNemkdeP7obvD20acJerHMjNAXg5EYG5y+1kPgd1mHWc+ixNvwOAZPruZo9qMoTX4PSwJjGidqHC8ClzRZTWB+/oobPhblVxQ8QbxLLbfyDopO7c+XD9QaSZURPJq96KgENMWsfyJI5v1mVBWhhtmLVpxNgziMj0js4w43pEYZgtq0t5Dj0nA1ThqY7gcViP9y2mTW9Fkqx0tJnWvqVZNgw==" Dec 14 21:14:19 rivo go-librespot[9074]: time="2025-12-14T21:14:19+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Dec 14 21:14:19 rivo go-librespot[9074]: time="2025-12-14T21:14:19+01:00" level=debug msg="completed keyexchange" Dec 14 21:14:19 rivo go-librespot[9074]: time="2025-12-14T21:14:19+01:00" level=debug msg="completed challenge" Dec 14 21:14:19 rivo go-librespot[9074]: time="2025-12-14T21:14:19+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Dec 14 21:14:20 rivo systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 14 21:14:20 rivo systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 14 21:14:21 rivo volumio[3283]: info: Initializing connection to go-librespot Websocket Dec 14 21:14:21 rivo volumio[3283]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 14 21:14:23 rivo systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 14 21:14:23 rivo systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2750. Dec 14 21:14:23 rivo systemd[1]: Stopped go-librespot Daemon. Dec 14 21:14:23 rivo systemd[1]: Started go-librespot Daemon. Dec 14 21:14:23 rivo go-librespot[9090]: Librespot-go daemon starting... Dec 14 21:14:23 rivo go-librespot[9090]: time="2025-12-14T21:14:23+01:00" level=info msg="generated new device id: f57b4245f9ea736e8be8d0b86c888afe9654d480" Dec 14 21:14:23 rivo go-librespot[9090]: time="2025-12-14T21:14:23+01:00" level=debug msg="stored credentials found for 31ufjgzm7ki5qunbvnwotliejbxm" Dec 14 21:14:23 rivo go-librespot[9090]: time="2025-12-14T21:14:23+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Dec 14 21:14:23 rivo go-librespot[9090]: time="2025-12-14T21:14:23+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Dec 14 21:14:23 rivo go-librespot[9090]: time="2025-12-14T21:14:23+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Dec 14 21:14:23 rivo go-librespot[9090]: time="2025-12-14T21:14:23+01:00" level=debug msg="zeroconf server listening on port 39419" Dec 14 21:14:23 rivo go-librespot[9090]: time="2025-12-14T21:14:23+01:00" level=debug msg="obtained new client token: AABYwXbQMC3loz89rfFUTHOBdryLRBUthr6BA9+tF1KCLN5DGXlfCViW/hkTMmh5GNJ3ELKpZhzg2ifPnBpx2OKgATOZoXc+6TK8Lm7c8sYoXxVbrqXncuGrpWsVbrmjYZhTBvGsZKt6SwJsdItvrnNDlGtc0WZUBRkR4Q1JcGb9Cp3dKLwzkTbUIY0wdH0WOI0k2m4ZQ13+wToRqWan5bNWpANeLWZ5WHMA7sRVO2vM6NC68eBqbbJk98sM" Dec 14 21:14:23 rivo go-librespot[9090]: time="2025-12-14T21:14:23+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Dec 14 21:14:23 rivo go-librespot[9090]: time="2025-12-14T21:14:23+01:00" level=debug msg="completed keyexchange" Dec 14 21:14:24 rivo go-librespot[9090]: time="2025-12-14T21:14:24+01:00" level=debug msg="completed challenge" Dec 14 21:14:24 rivo volumio[3283]: info: Initializing connection to go-librespot Websocket Dec 14 21:14:24 rivo go-librespot[9090]: time="2025-12-14T21:14:24+01:00" level=debug msg="new websocket client" Dec 14 21:14:24 rivo volumio[3283]: info: Connection to go-librespot Websocket established Dec 14 21:14:24 rivo go-librespot[9090]: time="2025-12-14T21:14:24+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Dec 14 21:14:24 rivo volumio[3283]: info: Connection to go-librespot Websocket closed Dec 14 21:14:24 rivo systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 14 21:14:24 rivo systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 14 21:14:27 rivo volumio[3283]: info: Getting Spotify volume Dec 14 21:14:27 rivo volumio[3283]: (node:3283) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 14 21:14:27 rivo volumio[3283]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) Dec 14 21:14:27 rivo volumio[3283]: (node:3283) 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: 1250) Dec 14 21:14:27 rivo volumio[3283]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Dec 14 21:14:27 rivo volumio[3283]: info: CoreCommandRouter::volumioGetState Dec 14 21:14:27 rivo volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 14 21:14:27 rivo volumio[3283]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 14 21:14:27 rivo volumio[3283]: info: Initializing connection to go-librespot Websocket Dec 14 21:14:27 rivo volumio[3283]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 14 21:14:27 rivo systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 14 21:14:27 rivo systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2751. Dec 14 21:14:27 rivo systemd[1]: Stopped go-librespot Daemon. Dec 14 21:14:27 rivo systemd[1]: Started go-librespot Daemon. Dec 14 21:14:27 rivo go-librespot[9170]: Librespot-go daemon starting... Dec 14 21:14:27 rivo go-librespot[9170]: time="2025-12-14T21:14:27+01:00" level=info msg="generated new device id: 55a7e8aa1f6b433250d7ac76bf4653d42e472a74" Dec 14 21:14:27 rivo go-librespot[9170]: time="2025-12-14T21:14:27+01:00" level=debug msg="stored credentials found for 31ufjgzm7ki5qunbvnwotliejbxm" Dec 14 21:14:28 rivo go-librespot[9170]: time="2025-12-14T21:14:28+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Dec 14 21:14:28 rivo go-librespot[9170]: time="2025-12-14T21:14:28+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Dec 14 21:14:28 rivo go-librespot[9170]: time="2025-12-14T21:14:28+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Dec 14 21:14:28 rivo go-librespot[9170]: time="2025-12-14T21:14:28+01:00" level=debug msg="zeroconf server listening on port 43079" Dec 14 21:14:28 rivo go-librespot[9170]: time="2025-12-14T21:14:28+01:00" level=debug msg="obtained new client token: AAAVbphtYPeTZO3Qrlx5WRwbXW5B5HKgG0hKYIZ81cdDBP/xNsc9MaLW9zqe599lnJMZVLj0bSSaV8sIxjs8QuhkP3oDahqdR1LAeEBliRRawDpreGdgB8mSt41dRp0WIaAJvu8uk0MVLt/CYmE+lrYZIeptnkrfpqdrFFndyTy5qd3E/FXbVHuRIKRqYAr3ZReR4CHCxTkoVSDl0dGfJC5yCVtH+0e70hsuDttZLapUja373H/sS63Xgd85" Dec 14 21:14:28 rivo go-librespot[9170]: time="2025-12-14T21:14:28+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Dec 14 21:14:28 rivo go-librespot[9170]: time="2025-12-14T21:14:28+01:00" level=debug msg="completed keyexchange" Dec 14 21:14:29 rivo go-librespot[9170]: time="2025-12-14T21:14:29+01:00" level=debug msg="completed challenge" Dec 14 21:14:29 rivo go-librespot[9170]: time="2025-12-14T21:14:29+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Dec 14 21:14:29 rivo systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 14 21:14:29 rivo systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 14 21:14:30 rivo volumio[3283]: info: Initializing connection to go-librespot Websocket Dec 14 21:14:30 rivo volumio[3283]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 14 21:14:32 rivo systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 14 21:14:32 rivo systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2752. Dec 14 21:14:32 rivo systemd[1]: Stopped go-librespot Daemon. Dec 14 21:14:32 rivo systemd[1]: Started go-librespot Daemon. Dec 14 21:14:32 rivo go-librespot[9196]: Librespot-go daemon starting... Dec 14 21:14:32 rivo go-librespot[9196]: time="2025-12-14T21:14:32+01:00" level=info msg="generated new device id: 05f2dbc7b69e15c6f306e21724955a19594ebfae" Dec 14 21:14:32 rivo go-librespot[9196]: time="2025-12-14T21:14:32+01:00" level=debug msg="stored credentials found for 31ufjgzm7ki5qunbvnwotliejbxm" Dec 14 21:14:32 rivo go-librespot[9196]: time="2025-12-14T21:14:32+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 14 21:14:32 rivo go-librespot[9196]: time="2025-12-14T21:14:32+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 14 21:14:32 rivo go-librespot[9196]: time="2025-12-14T21:14:32+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 14 21:14:32 rivo go-librespot[9196]: time="2025-12-14T21:14:32+01:00" level=debug msg="zeroconf server listening on port 33071" Dec 14 21:14:32 rivo go-librespot[9196]: time="2025-12-14T21:14:32+01:00" level=debug msg="obtained new client token: AAD4GI6UmZPre+K35XuJXn/AwxF39Minp9afo+Sg/BzI84D4UzuD0G/2gg9i7zgN9fKsS010ebpnV3/pxs5guPr4CexUmSH+nuCErLIerEQ+3jsQRxuFNJRI9WausalqDW09JOnhGz22J1icmOqXCGuMUCsxFhnSTbNqjtT7plmX91rZWronMfd9XncI5YLNaMsgR0xwm2U2ydamjyTQGCgyxhP4i5/CUGwzaFLlc4WeT2Zd2+H0oGluL4vz" Dec 14 21:14:33 rivo go-librespot[9196]: time="2025-12-14T21:14:33+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Dec 14 21:14:33 rivo go-librespot[9196]: time="2025-12-14T21:14:33+01:00" level=debug msg="completed keyexchange" Dec 14 21:14:33 rivo volumio[3283]: info: Initializing connection to go-librespot Websocket Dec 14 21:14:33 rivo go-librespot[9196]: time="2025-12-14T21:14:33+01:00" level=debug msg="new websocket client" Dec 14 21:14:33 rivo volumio[3283]: info: Connection to go-librespot Websocket established Dec 14 21:14:33 rivo go-librespot[9196]: time="2025-12-14T21:14:33+01:00" level=debug msg="completed challenge" Dec 14 21:14:33 rivo go-librespot[9196]: time="2025-12-14T21:14:33+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Dec 14 21:14:33 rivo systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 14 21:14:33 rivo systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 14 21:14:33 rivo volumio[3283]: info: Connection to go-librespot Websocket closed Dec 14 21:14:36 rivo volumio[3283]: info: Getting Spotify volume Dec 14 21:14:36 rivo volumio[3283]: (node:3283) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 14 21:14:36 rivo volumio[3283]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) Dec 14 21:14:36 rivo volumio[3283]: (node:3283) 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: 1251) Dec 14 21:14:36 rivo volumio[3283]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Dec 14 21:14:36 rivo volumio[3283]: info: CoreCommandRouter::volumioGetState Dec 14 21:14:36 rivo volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 14 21:14:36 rivo volumio[3283]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 14 21:14:36 rivo volumio[3283]: info: Initializing connection to go-librespot Websocket Dec 14 21:14:36 rivo volumio[3283]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 14 21:14:36 rivo systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 14 21:14:36 rivo systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2753. Dec 14 21:14:36 rivo systemd[1]: Stopped go-librespot Daemon. Dec 14 21:14:36 rivo systemd[1]: Started go-librespot Daemon. Dec 14 21:14:36 rivo go-librespot[9230]: Librespot-go daemon starting... Dec 14 21:14:36 rivo go-librespot[9230]: time="2025-12-14T21:14:36+01:00" level=info msg="generated new device id: e00c74bf6f42d0c7ec93ae2478297301b38fafa9" Dec 14 21:14:36 rivo go-librespot[9230]: time="2025-12-14T21:14:36+01:00" level=debug msg="stored credentials found for 31ufjgzm7ki5qunbvnwotliejbxm" Dec 14 21:14:37 rivo go-librespot[9230]: time="2025-12-14T21:14:37+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 14 21:14:37 rivo go-librespot[9230]: time="2025-12-14T21:14:37+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 14 21:14:37 rivo go-librespot[9230]: time="2025-12-14T21:14:37+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 14 21:14:37 rivo go-librespot[9230]: time="2025-12-14T21:14:37+01:00" level=debug msg="zeroconf server listening on port 38765" Dec 14 21:14:37 rivo go-librespot[9230]: time="2025-12-14T21:14:37+01:00" level=debug msg="obtained new client token: AABimTBslGNzaZKUERIWEnkNlKsPCT93ZYh1Lk5ulGkgsSgGKOr57I6E+1b2KrJenGl4Jhk1pFL5WYl/i5U+qJ1cBqTlRqDzrJbjGhTX70QaN6VqY6Wz5vo/g4aOrvTrqnOUkOKTopoa3fyI4uRLN0j3yTR2W+BfrIWZ52Tp2BiyXCGRSJ91PF3nkB48Ci3oR95XE1//xIG31qZPhKgVvzv8z5J3T433r9hPpRG02jYkZI4X+Hf1OsUdWTIh" Dec 14 21:14:37 rivo go-librespot[9230]: time="2025-12-14T21:14:37+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Dec 14 21:14:37 rivo go-librespot[9230]: time="2025-12-14T21:14:37+01:00" level=debug msg="completed keyexchange" Dec 14 21:14:38 rivo go-librespot[9230]: time="2025-12-14T21:14:38+01:00" level=debug msg="completed challenge" Dec 14 21:14:38 rivo go-librespot[9230]: time="2025-12-14T21:14:38+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Dec 14 21:14:38 rivo systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 14 21:14:38 rivo systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 14 21:14:39 rivo volumio[3283]: info: Initializing connection to go-librespot Websocket Dec 14 21:14:39 rivo volumio[3283]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 14 21:14:41 rivo systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 14 21:14:41 rivo systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2754. Dec 14 21:14:41 rivo systemd[1]: Stopped go-librespot Daemon. Dec 14 21:14:41 rivo systemd[1]: Started go-librespot Daemon. Dec 14 21:14:41 rivo go-librespot[9249]: Librespot-go daemon starting... Dec 14 21:14:41 rivo go-librespot[9249]: time="2025-12-14T21:14:41+01:00" level=info msg="generated new device id: 5d6c51055128f799feb126bf849be70d9cead04a" Dec 14 21:14:41 rivo go-librespot[9249]: time="2025-12-14T21:14:41+01:00" level=debug msg="stored credentials found for 31ufjgzm7ki5qunbvnwotliejbxm" Dec 14 21:14:41 rivo go-librespot[9249]: time="2025-12-14T21:14:41+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 14 21:14:41 rivo go-librespot[9249]: time="2025-12-14T21:14:41+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 14 21:14:41 rivo go-librespot[9249]: time="2025-12-14T21:14:41+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 14 21:14:41 rivo go-librespot[9249]: time="2025-12-14T21:14:41+01:00" level=debug msg="zeroconf server listening on port 41645" Dec 14 21:14:42 rivo go-librespot[9249]: time="2025-12-14T21:14:42+01:00" level=debug msg="obtained new client token: AACAJwj4vxOtvMLH4L32v2jRGt4Q8Ty118z93lTrsP/llmwYuoI60bdD1r0IbljtMAgRBfGiHFWMbFQW8OF1bepOikYy0fJnYZmOR7QIyXBX9uwChSyAqkr7umzhRwhV+fM211WMastgOcWyFljewWe+H8BmE5UwcE3d0L3LNZvda8J/hAXJAUkX0MnFeNXqPbXXWr9bQeefBlDKPN+cnenJ8fmEOwuLpYFzrOsmDHDF7n3rLfZD0Ztjcg==" Dec 14 21:14:42 rivo go-librespot[9249]: time="2025-12-14T21:14:42+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Dec 14 21:14:42 rivo volumio[3283]: info: Initializing connection to go-librespot Websocket Dec 14 21:14:42 rivo go-librespot[9249]: time="2025-12-14T21:14:42+01:00" level=debug msg="new websocket client" Dec 14 21:14:42 rivo volumio[3283]: info: Connection to go-librespot Websocket established Dec 14 21:14:43 rivo go-librespot[9249]: time="2025-12-14T21:14:43+01:00" level=debug msg="completed keyexchange" Dec 14 21:14:43 rivo go-librespot[9249]: time="2025-12-14T21:14:43+01:00" level=debug msg="completed challenge" Dec 14 21:14:43 rivo go-librespot[9249]: time="2025-12-14T21:14:43+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Dec 14 21:14:43 rivo volumio[3283]: info: Connection to go-librespot Websocket closed Dec 14 21:14:43 rivo systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 14 21:14:43 rivo systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 14 21:14:45 rivo volumio[3283]: info: Getting Spotify volume Dec 14 21:14:45 rivo volumio[3283]: (node:3283) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 14 21:14:45 rivo volumio[3283]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) Dec 14 21:14:45 rivo volumio[3283]: (node:3283) 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: 1252) Dec 14 21:14:45 rivo volumio[3283]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Dec 14 21:14:45 rivo volumio[3283]: info: CoreCommandRouter::volumioGetState Dec 14 21:14:45 rivo volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 14 21:14:45 rivo volumio[3283]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 14 21:14:46 rivo volumio[3283]: info: Initializing connection to go-librespot Websocket Dec 14 21:14:46 rivo volumio[3283]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 14 21:14:47 rivo systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 14 21:14:47 rivo systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2755. Dec 14 21:14:47 rivo systemd[1]: Stopped go-librespot Daemon. Dec 14 21:14:47 rivo systemd[1]: Started go-librespot Daemon. Dec 14 21:14:47 rivo go-librespot[9287]: Librespot-go daemon starting... Dec 14 21:14:47 rivo go-librespot[9287]: time="2025-12-14T21:14:47+01:00" level=info msg="generated new device id: 2dbd6561619ab1564190c6f7bee93d1d79b66792" Dec 14 21:14:47 rivo go-librespot[9287]: time="2025-12-14T21:14:47+01:00" level=debug msg="stored credentials found for 31ufjgzm7ki5qunbvnwotliejbxm" Dec 14 21:14:47 rivo go-librespot[9287]: time="2025-12-14T21:14:47+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 14 21:14:47 rivo go-librespot[9287]: time="2025-12-14T21:14:47+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 14 21:14:47 rivo go-librespot[9287]: time="2025-12-14T21:14:47+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 14 21:14:47 rivo go-librespot[9287]: time="2025-12-14T21:14:47+01:00" level=debug msg="zeroconf server listening on port 43555" Dec 14 21:14:47 rivo go-librespot[9287]: time="2025-12-14T21:14:47+01:00" level=debug msg="obtained new client token: AADmcmW8dX2EyH9fDeQ91WUQJT2lO4plRXOAQ9wQ1XWZ5au4jWZxdqcTsuC0dz0VUCEUc7IiOyaqvt8I56UmFNYrf/KcLXE43COAeiZnuyFQmvVZs+Yx062iV2FWx6pPe1kJURlHv3efQO6+P5ZidtmAt7EN3KydWYb3zia3CAANuP+/h+RcHsvcfgQtCxJN/miznE5ks2HNKHojms7oDjcxu/WfGgraTrgBNAgXfmXPs22DSaWsryrLT46s" Dec 14 21:14:47 rivo go-librespot[9287]: time="2025-12-14T21:14:47+01:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:4070 (error: dial tcp 34.158.1.133:4070: connect: connection refused), retrying with a different AP" Dec 14 21:14:47 rivo go-librespot[9287]: time="2025-12-14T21:14:47+01:00" level=info msg="connected to ap-gew4.spotify.com:443" Dec 14 21:14:48 rivo go-librespot[9287]: time="2025-12-14T21:14:48+01:00" level=debug msg="completed keyexchange" Dec 14 21:14:48 rivo go-librespot[9287]: time="2025-12-14T21:14:48+01:00" level=debug msg="completed challenge" Dec 14 21:14:48 rivo go-librespot[9287]: time="2025-12-14T21:14:48+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Dec 14 21:14:48 rivo systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 14 21:14:48 rivo systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 14 21:14:49 rivo volumio[3283]: info: Initializing connection to go-librespot Websocket Dec 14 21:14:49 rivo volumio[3283]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 14 21:14:51 rivo systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 14 21:14:51 rivo systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2756. Dec 14 21:14:51 rivo systemd[1]: Stopped go-librespot Daemon. Dec 14 21:14:51 rivo systemd[1]: Started go-librespot Daemon. Dec 14 21:14:51 rivo go-librespot[9313]: Librespot-go daemon starting... Dec 14 21:14:51 rivo go-librespot[9313]: time="2025-12-14T21:14:51+01:00" level=info msg="generated new device id: 36cbc3615dcd113daf3e6db5f17e2afdfb603f2d" Dec 14 21:14:51 rivo go-librespot[9313]: time="2025-12-14T21:14:51+01:00" level=debug msg="stored credentials found for 31ufjgzm7ki5qunbvnwotliejbxm" Dec 14 21:14:52 rivo go-librespot[9313]: time="2025-12-14T21:14:52+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 14 21:14:52 rivo go-librespot[9313]: time="2025-12-14T21:14:52+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 14 21:14:52 rivo go-librespot[9313]: time="2025-12-14T21:14:52+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 14 21:14:52 rivo go-librespot[9313]: time="2025-12-14T21:14:52+01:00" level=debug msg="zeroconf server listening on port 46691" Dec 14 21:14:52 rivo go-librespot[9313]: time="2025-12-14T21:14:52+01:00" level=debug msg="obtained new client token: AABuKeGwr5y37dRmc8eMmUsgwcJyi5IMFbqZ1S9hluWjcQrDOvQbZh6gIMl66CiCQPt2DqiNJYwi8ON84rI5BUVfML3t6XryR1VkCqBkl2Z72IiTvMWHuUCcj5FqcA9FBpzBjAlYWwvvHQLz4f0hyOnlY322mB7ARlYz2MA8EiSFDGHL2c5eArfrXWQibj/Bdg4zS5RMY2KYvUp6eNKhqqJulLxiniALAb8h65fe0NgOaSdeLcGSjuZn8Vl5" Dec 14 21:14:52 rivo go-librespot[9313]: time="2025-12-14T21:14:52+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Dec 14 21:14:52 rivo go-librespot[9313]: time="2025-12-14T21:14:52+01:00" level=debug msg="completed keyexchange" Dec 14 21:14:52 rivo volumio[3283]: info: Initializing connection to go-librespot Websocket Dec 14 21:14:52 rivo go-librespot[9313]: time="2025-12-14T21:14:52+01:00" level=debug msg="new websocket client" Dec 14 21:14:52 rivo volumio[3283]: info: Connection to go-librespot Websocket established Dec 14 21:14:53 rivo go-librespot[9313]: time="2025-12-14T21:14:53+01:00" level=debug msg="completed challenge" Dec 14 21:14:53 rivo go-librespot[9313]: time="2025-12-14T21:14:53+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Dec 14 21:14:53 rivo systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 14 21:14:53 rivo systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 14 21:14:53 rivo volumio[3283]: info: Connection to go-librespot Websocket closed Dec 14 21:14:55 rivo volumio[3283]: info: Getting Spotify volume Dec 14 21:14:55 rivo volumio[3283]: (node:3283) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 14 21:14:55 rivo volumio[3283]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) Dec 14 21:14:55 rivo volumio[3283]: (node:3283) 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: 1253) Dec 14 21:14:55 rivo volumio[3283]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Dec 14 21:14:55 rivo volumio[3283]: info: CoreCommandRouter::volumioGetState Dec 14 21:14:55 rivo volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 14 21:14:55 rivo volumio[3283]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 14 21:14:56 rivo volumio[3283]: info: Initializing connection to go-librespot Websocket Dec 14 21:14:56 rivo volumio[3283]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 14 21:14:56 rivo systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 14 21:14:56 rivo systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2757. Dec 14 21:14:56 rivo systemd[1]: Stopped go-librespot Daemon. Dec 14 21:14:56 rivo systemd[1]: Started go-librespot Daemon. Dec 14 21:14:56 rivo go-librespot[9346]: Librespot-go daemon starting... Dec 14 21:14:56 rivo go-librespot[9346]: time="2025-12-14T21:14:56+01:00" level=info msg="generated new device id: e9ccbaf83544237c584397e089f00a9f21b0a082" Dec 14 21:14:56 rivo go-librespot[9346]: time="2025-12-14T21:14:56+01:00" level=debug msg="stored credentials found for 31ufjgzm7ki5qunbvnwotliejbxm" Dec 14 21:14:56 rivo go-librespot[9346]: time="2025-12-14T21:14:56+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 14 21:14:56 rivo go-librespot[9346]: time="2025-12-14T21:14:56+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 14 21:14:56 rivo go-librespot[9346]: time="2025-12-14T21:14:56+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 14 21:14:56 rivo go-librespot[9346]: time="2025-12-14T21:14:56+01:00" level=debug msg="zeroconf server listening on port 40961" Dec 14 21:14:57 rivo go-librespot[9346]: time="2025-12-14T21:14:57+01:00" level=debug msg="obtained new client token: AAAm6LCNBA2OpskCNNdw1cxKklua+q8a5+72v1tGw7gkvUVRBkCV+W65DD9gpkS3A1NpUfFJ6af4Mz2pkrpI/4Q+qfWD48C5CHho7t2PyRUOwOJiot2yZNEUPgWrdpzw4jQB9OOyzXjWSTKhmG9xXK3BxnUEVoSr6Q7qU46JGUnXeF4S8Po11EHYi4ZE01IWFk75Zv4fyLIXYCaNkcL4bCljrBBk+ziKLaUe3BuUtc1zYxKNG2xpqVBXAQ==" Dec 14 21:14:57 rivo go-librespot[9346]: time="2025-12-14T21:14:57+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Dec 14 21:14:57 rivo go-librespot[9346]: time="2025-12-14T21:14:57+01:00" level=debug msg="completed keyexchange" Dec 14 21:14:57 rivo go-librespot[9346]: time="2025-12-14T21:14:57+01:00" level=debug msg="completed challenge" Dec 14 21:14:58 rivo go-librespot[9346]: time="2025-12-14T21:14:58+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Dec 14 21:14:58 rivo systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 14 21:14:58 rivo systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 14 21:14:59 rivo volumio[3283]: info: Initializing connection to go-librespot Websocket Dec 14 21:14:59 rivo volumio[3283]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 14 21:15:01 rivo systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 14 21:15:01 rivo systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2758. Dec 14 21:15:01 rivo systemd[1]: Stopped go-librespot Daemon. Dec 14 21:15:01 rivo systemd[1]: Started go-librespot Daemon. Dec 14 21:15:01 rivo go-librespot[9374]: Librespot-go daemon starting... Dec 14 21:15:01 rivo go-librespot[9374]: time="2025-12-14T21:15:01+01:00" level=info msg="generated new device id: 481f8e76f8b8de8f36104c85878ed07019276d53" Dec 14 21:15:01 rivo go-librespot[9374]: time="2025-12-14T21:15:01+01:00" level=debug msg="stored credentials found for 31ufjgzm7ki5qunbvnwotliejbxm" Dec 14 21:15:01 rivo go-librespot[9374]: time="2025-12-14T21:15:01+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Dec 14 21:15:01 rivo go-librespot[9374]: time="2025-12-14T21:15:01+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Dec 14 21:15:01 rivo go-librespot[9374]: time="2025-12-14T21:15:01+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Dec 14 21:15:01 rivo go-librespot[9374]: time="2025-12-14T21:15:01+01:00" level=debug msg="zeroconf server listening on port 46637" Dec 14 21:15:01 rivo go-librespot[9374]: time="2025-12-14T21:15:01+01:00" level=debug msg="obtained new client token: AABR+QkhW75t16R5Vsq3JtLpC1fmsPDdIlsvjAykTPYxMHhDAhHACMzkVSSI4Whxzh0uyy2vtCdS8tLF7RLqJtxOydL6ADsM2Qj2ymV2JJM2apSzQ2Mf5ihETJHJVLzNW7Ocl5tjpGKiUlLbnkWwWVvl9muH+OwzmyvCy0IKAEYHtA9Rlieka5co+QXkfbTgjzgHMbdDjrBYTuwaTiEUrlu4yorNr71V6mviL8A8rdplYaEM9pUZgnMBqUok" Dec 14 21:15:02 rivo go-librespot[9374]: time="2025-12-14T21:15:02+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Dec 14 21:15:02 rivo volumio[3283]: info: [1765743302109] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=0 Dec 14 21:15:02 rivo go-librespot[9374]: time="2025-12-14T21:15:02+01:00" level=debug msg="completed keyexchange" Dec 14 21:15:02 rivo volumio[3283]: info: Initializing connection to go-librespot Websocket Dec 14 21:15:02 rivo go-librespot[9374]: time="2025-12-14T21:15:02+01:00" level=debug msg="new websocket client" Dec 14 21:15:02 rivo volumio[3283]: info: Connection to go-librespot Websocket established Dec 14 21:15:02 rivo go-librespot[9374]: time="2025-12-14T21:15:02+01:00" level=debug msg="completed challenge" Dec 14 21:15:02 rivo volumio[3283]: info: [1765743302768] [RadioParadise] received new metadata: {"time":228,"artist":"Christian Löffler","title":"A Life (Parra for Cuva remix)","album":"A Life (Parra for Cuva remix)","year":"2024","cover":"https://img.radioparadise.com/covers/l/19698.jpg","cover_med":"https://img.radioparadise.com/covers/m/19698.jpg","cover_small":"https://img.radioparadise.com/covers/s/19698.jpg"} Dec 14 21:15:02 rivo volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 14 21:15:02 rivo volumio[3283]: info: CoreCommandRouter::servicePushState Dec 14 21:15:02 rivo volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 14 21:15:02 rivo volumio[3283]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/19698.jpg","uri":"https://stream.radioparadise.com/flac","name":"A Life (Parra for Cuva remix)","title":"A Life (Parra for Cuva remix)","artist":"Christian Löffler","album":"A Life (Parra for Cuva remix)","streaming":true,"disableUiControls":true,"duration":233,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Dec 14 21:15:02 rivo volumio[3283]: verbose: CURRENT POSITION 0 Dec 14 21:15:02 rivo volumio[3283]: info: CoreStateMachine::syncState stateService play Dec 14 21:15:02 rivo volumio[3283]: info: CoreStateMachine::syncState currentStatus play Dec 14 21:15:02 rivo volumio[3283]: info: Received an update from plugin. extracting info from payload Dec 14 21:15:02 rivo volumio[3283]: info: CoreStateMachine::pushState Dec 14 21:15:02 rivo volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 14 21:15:02 rivo volumio[3283]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 21:15:02 rivo volumio[3283]: info: CoreCommandRouter::volumioPushState Dec 14 21:15:02 rivo volumio[3283]: info: CoreCommandRouter::volumioGetState Dec 14 21:15:02 rivo volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 14 21:15:02 rivo volumio[3283]: info: CoreStateMachine::pushState Dec 14 21:15:02 rivo volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 14 21:15:02 rivo volumio[3283]: info: CoreCommandRouter::volumioPushState Dec 14 21:15:02 rivo volumio[3283]: info: CoreCommandRouter::volumioGetState Dec 14 21:15:02 rivo volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 14 21:15:02 rivo volumio[3283]: info: [1765743302792] [RadioParadise] setting new timer with duration of 233000 seconds. Dec 14 21:15:02 rivo volumio[3283]: info: [LastFM] Current track has sufficient metadata: title (A Life (Parra for Cuva remix)) and artist (Christian Löffler) passed on explicitly Dec 14 21:15:02 rivo volumio[3283]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 14 21:15:02 rivo volumio[3283]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 14 21:15:02 rivo go-librespot[9374]: time="2025-12-14T21:15:02+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Dec 14 21:15:02 rivo systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 14 21:15:02 rivo systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 14 21:15:02 rivo volumio[3283]: info: Connection to go-librespot Websocket closed Dec 14 21:15:02 rivo volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 14 21:15:02 rivo volumio[3283]: info: CorePlayQueue::getTrack 1 Dec 14 21:15:04 rivo volumio[3283]: info: Executing endpoint metavolumio Dec 14 21:15:04 rivo volumio[3283]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 14 21:15:04 rivo volumio[3283]: info: Executing endpoint metavolumio Dec 14 21:15:04 rivo volumio[3283]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 14 21:15:04 rivo volumio[3283]: info: Executing endpoint metavolumio Dec 14 21:15:04 rivo volumio[3283]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 14 21:15:05 rivo volumio[3283]: info: Getting Spotify volume Dec 14 21:15:05 rivo volumio[3283]: (node:3283) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 14 21:15:05 rivo volumio[3283]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) Dec 14 21:15:05 rivo volumio[3283]: (node:3283) 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: 1254) Dec 14 21:15:05 rivo volumio[3283]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Dec 14 21:15:05 rivo volumio[3283]: info: CoreCommandRouter::volumioGetState Dec 14 21:15:05 rivo volumio[3283]: info: CorePlayQueue::getTrack 0 Dec 14 21:15:05 rivo volumio[3283]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 14 21:15:05 rivo volumio[3283]: info: Initializing connection to go-librespot Websocket Dec 14 21:15:05 rivo volumio[3283]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 14 21:15:06 rivo volumio[3283]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 14 21:15:06 rivo volumio[3283]: TypeError: Cannot read property '@' of null Dec 14 21:15:06 rivo volumio[3283]: at Object.callback (/data/plugins/user_interface/lastfm/node_modules/simple-lastfm/lib/index.js:342:14) Dec 14 21:15:06 rivo volumio[3283]: at /data/plugins/user_interface/lastfm/node_modules/simple-lastfm/lib/index.js:60:13 Dec 14 21:15:06 rivo volumio[3283]: at Parser. (/data/plugins/user_interface/lastfm/node_modules/xml2js/lib/parser.js:304:18) Dec 14 21:15:06 rivo volumio[3283]: at Parser.emit (events.js:400:28) Dec 14 21:15:06 rivo volumio[3283]: at Parser.exports.Parser.Parser.parseString (/data/plugins/user_interface/lastfm/node_modules/xml2js/lib/parser.js:314:16) Dec 14 21:15:06 rivo volumio[3283]: at Parser.parseString (/data/plugins/user_interface/lastfm/node_modules/xml2js/lib/parser.js:5:59) Dec 14 21:15:06 rivo volumio[3283]: at IncomingMessage. (/data/plugins/user_interface/lastfm/node_modules/simple-lastfm/lib/index.js:58:14) Dec 14 21:15:06 rivo volumio[3283]: at IncomingMessage.emit (events.js:412:35) Dec 14 21:15:06 rivo volumio[3283]: at endReadableNT (internal/streams/readable.js:1333:12) Dec 14 21:15:06 rivo volumio[3283]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Dec 14 21:15:06 rivo volumio[3283]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 14 21:15:06 rivo systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 14 21:15:06 rivo systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2759. Dec 14 21:15:06 rivo systemd[1]: Stopped go-librespot Daemon. Dec 14 21:15:06 rivo systemd[1]: Started go-librespot Daemon. Dec 14 21:15:06 rivo go-librespot[9410]: Librespot-go daemon starting... Dec 14 21:15:06 rivo go-librespot[9410]: time="2025-12-14T21:15:06+01:00" level=info msg="generated new device id: ca3fd8bd6858a4db1776d62c92b85b4b3828a073" Dec 14 21:15:06 rivo go-librespot[9410]: time="2025-12-14T21:15:06+01:00" level=debug msg="stored credentials found for 31ufjgzm7ki5qunbvnwotliejbxm" Dec 14 21:15:06 rivo go-librespot[9410]: time="2025-12-14T21:15:06+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Dec 14 21:15:06 rivo go-librespot[9410]: time="2025-12-14T21:15:06+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Dec 14 21:15:06 rivo go-librespot[9410]: time="2025-12-14T21:15:06+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Dec 14 21:15:06 rivo go-librespot[9410]: time="2025-12-14T21:15:06+01:00" level=debug msg="zeroconf server listening on port 39363" Dec 14 21:15:06 rivo go-librespot[9410]: time="2025-12-14T21:15:06+01:00" level=debug msg="obtained new client token: AADKWtFiiVHfLUfbEUOjtgCC6DnqHwxKd+Hr1D8cou2BnqIHVde1ZPsGuZ0jKEvtgkiS/zUWwT5iQ5/3TCxXOMnwdJZR7sIC34bPdhaZ3xxQzzJAJ3eACZuTpCN0sCDzXJ+nwFTGQxH7xQ1Z8v9eyynHTzblHxnhHbb7VVsrGRdFRWhiPLDVB339TsD5C6aAg1VV4cFQpsETXovQAGJ1tCdoBBzl0Wg39Rp2/YNNjasR8eC1vp8unhKiclwC" Dec 14 21:15:06 rivo sudo[9440]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-14 21:14 Dec 14 21:15:06 rivo sudo[9440]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 21:15:06 rivo go-librespot[9410]: time="2025-12-14T21:15:06+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="cc50ad4a2058d01de272214eb33827883bd2b7d8" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="502750e5d54ecf269d25125b622bfabc030fb553" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 12 Nov 2025 08:04:08 PM CET" VOLUMIO_VERSION="3.874" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="bbdd85796562d864ebadb328c8f41ca1"