-- Logs begin at Fri 2025-10-31 16:48:56 CET, end at Fri 2025-10-31 18:53:19 CET. -- Oct 31 18:52:00 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 31 18:52:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 83839. Oct 31 18:52:00 volumio systemd[1]: Stopped go-librespot Daemon. Oct 31 18:52:00 volumio systemd[1]: Started go-librespot Daemon. Oct 31 18:52:00 volumio go-librespot[23451]: Librespot-go daemon starting... Oct 31 18:52:00 volumio go-librespot[23451]: time="2025-10-31T18:52:00+01:00" level=info msg="generated new device id: 927ef5a53107d09b997d67e5341c279ec273d9ef" Oct 31 18:52:00 volumio go-librespot[23451]: time="2025-10-31T18:52:00+01:00" level=debug msg="stored credentials found for 1fvz1" Oct 31 18:52:00 volumio go-librespot[23451]: time="2025-10-31T18:52:00+01:00" level=debug msg="obtained new client token: AAD85KkpUhFWadS7V1gQ+EzC66RRC0tQ9zLNklorckPoL/eUcoME4LN9IljpgPCAa6DY5bW7wAGl5+ssDIdaoieuv7lAK8HbIgMbqIH+zugswqG5fz4fr6tnBigDS7+SPd5rB+S8V3QKMrnxg/9EuXbS7LWuwlUa+hapIqDrSuJJm1xg8KK9dflJ/7aDCx5TlQ4q6+UsjEM8uUQe+4UOivLm1EG16fmJ5j5OKPxc6Yg6LrxFzqNeidCapZCqqAFF" Oct 31 18:52:00 volumio volumio[925]: info: Initializing connection to go-librespot Websocket Oct 31 18:52:00 volumio go-librespot[23451]: time="2025-10-31T18:52:00+01:00" level=debug msg="new websocket client" Oct 31 18:52:00 volumio volumio[925]: info: Connection to go-librespot Websocket established Oct 31 18:52:00 volumio go-librespot[23451]: time="2025-10-31T18:52: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]" Oct 31 18:52:00 volumio go-librespot[23451]: time="2025-10-31T18:52:00+01:00" level=debug msg="completed keyexchange" Oct 31 18:52:01 volumio go-librespot[23451]: time="2025-10-31T18:52:01+01:00" level=debug msg="completed challenge" Oct 31 18:52:01 volumio go-librespot[23451]: time="2025-10-31T18:52:01+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Oct 31 18:52:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 31 18:52:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 31 18:52:01 volumio volumio[925]: info: Connection to go-librespot Websocket closed Oct 31 18:52:03 volumio volumio[925]: info: Getting Spotify volume Oct 31 18:52:03 volumio volumio[925]: (node:925) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 31 18:52:03 volumio volumio[925]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Oct 31 18:52:03 volumio volumio[925]: (node:925) 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: 28389) Oct 31 18:52:03 volumio volumio[925]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Oct 31 18:52:03 volumio volumio[925]: info: CoreCommandRouter::volumioGetState Oct 31 18:52:03 volumio volumio[925]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40 Oct 31 18:52:04 volumio volumio[925]: info: Initializing connection to go-librespot Websocket Oct 31 18:52:04 volumio volumio[925]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 31 18:52:04 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 31 18:52:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 83840. Oct 31 18:52:04 volumio systemd[1]: Stopped go-librespot Daemon. Oct 31 18:52:04 volumio systemd[1]: Started go-librespot Daemon. Oct 31 18:52:04 volumio go-librespot[23482]: Librespot-go daemon starting... Oct 31 18:52:04 volumio go-librespot[23482]: time="2025-10-31T18:52:04+01:00" level=info msg="generated new device id: 34f839773ea880e7a77401ad5f650089d413d8f1" Oct 31 18:52:04 volumio go-librespot[23482]: time="2025-10-31T18:52:04+01:00" level=debug msg="stored credentials found for 1fvz1" Oct 31 18:52:04 volumio go-librespot[23482]: time="2025-10-31T18:52:04+01:00" level=debug msg="obtained new client token: AACfTbe7ULdzicqveDxLPPc6PQJscooOXdLRF2UhlekaqNsQp8lFgf0mraYKB+foA+6G5rkXyHsWbpW3DmobNxGtP6im00uoPrJewoNIEjyLEUsSirvmxNioO5X/rHrU7UELx7DpyVGAWEZ1lmx/SEnoWQm8Y2GifLjDI/Ef7S5DVAckhyEa3TVCKX47T/2plAIffqXFtWCA28Mil307mZJrqv9IoHyg3r4G3zF4yKcI6q4yNYJF1OZziMNzLim1" Oct 31 18:52:05 volumio go-librespot[23482]: time="2025-10-31T18:52: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 31 18:52:05 volumio go-librespot[23482]: time="2025-10-31T18:52:05+01:00" level=debug msg="completed keyexchange" Oct 31 18:52:05 volumio go-librespot[23482]: time="2025-10-31T18:52:05+01:00" level=debug msg="completed challenge" Oct 31 18:52:05 volumio go-librespot[23482]: time="2025-10-31T18:52:05+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Oct 31 18:52:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 31 18:52:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 31 18:52:07 volumio volumio[925]: info: Initializing connection to go-librespot Websocket Oct 31 18:52:07 volumio volumio[925]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 31 18:52:08 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 31 18:52:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 83841. Oct 31 18:52:08 volumio systemd[1]: Stopped go-librespot Daemon. Oct 31 18:52:08 volumio systemd[1]: Started go-librespot Daemon. Oct 31 18:52:08 volumio go-librespot[23499]: Librespot-go daemon starting... Oct 31 18:52:08 volumio go-librespot[23499]: time="2025-10-31T18:52:08+01:00" level=info msg="generated new device id: a7c2d74be0587628d208efa79e8357ed75d4ec63" Oct 31 18:52:08 volumio go-librespot[23499]: time="2025-10-31T18:52:08+01:00" level=debug msg="stored credentials found for 1fvz1" Oct 31 18:52:09 volumio go-librespot[23499]: time="2025-10-31T18:52:09+01:00" level=debug msg="obtained new client token: AAAXg35V3Lot6SAcsPqfYxC11HMnM3BLj6CzdB/ijjZT5lQlOVK0RaKC9TOxci9B6lMGbAKQlD+9xOvC3PKkvQsGsA+eFc9s3qXR7SqPFvJqKE0DJl75tFpZHIFkdWBSShmlCP1p+lTCr7L0Va0WkaPjuJvYXnn+cTJAFrmuvGl91WHr1WgZ1croj7k3YfOyHam9Xwx4OFSpeSX4gEw2/27oIQ6M2yt+o/6a987JE/qiXsHMCl+mzDmr2NohdQ==" Oct 31 18:52:09 volumio go-librespot[23499]: time="2025-10-31T18:52: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-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 31 18:52:09 volumio go-librespot[23499]: time="2025-10-31T18:52:09+01:00" level=debug msg="completed keyexchange" Oct 31 18:52:09 volumio go-librespot[23499]: time="2025-10-31T18:52:09+01:00" level=debug msg="completed challenge" Oct 31 18:52:09 volumio go-librespot[23499]: time="2025-10-31T18:52:09+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Oct 31 18:52:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 31 18:52:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 31 18:52:10 volumio volumio[925]: info: Initializing connection to go-librespot Websocket Oct 31 18:52:10 volumio volumio[925]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 31 18:52:13 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 31 18:52:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 83842. Oct 31 18:52:13 volumio systemd[1]: Stopped go-librespot Daemon. Oct 31 18:52:13 volumio systemd[1]: Started go-librespot Daemon. Oct 31 18:52:13 volumio go-librespot[23532]: Librespot-go daemon starting... Oct 31 18:52:13 volumio go-librespot[23532]: time="2025-10-31T18:52:13+01:00" level=info msg="generated new device id: 2cd55018ecaae36b276e650e9b8f93d0491922cc" Oct 31 18:52:13 volumio go-librespot[23532]: time="2025-10-31T18:52:13+01:00" level=debug msg="stored credentials found for 1fvz1" Oct 31 18:52:13 volumio go-librespot[23532]: time="2025-10-31T18:52:13+01:00" level=debug msg="obtained new client token: AACKvSR6Sc/yej8z26cmR2Q1B6kMQMxNzJI52Xx3H+kxIkx6MxuHRhTIZTBAvU0GGho1usLyjzBIy2IuJJ8ZXwcv2ekwTVPUZ8K0l7YhB8MP3C0300sdcN4+XSPfeXQhBFTGZ5e3uLlidBujwg92roilxvuQ6qsQSRuFakDHxt6nVZ9aK1SFxYhalPgjvE1QdapMvCqpI2WHY45+aPxHsG44L0ow7dhtfcvNHwWSC0Gf7U5DOtKwd6NBB5emD8a4" Oct 31 18:52:13 volumio volumio[925]: info: Initializing connection to go-librespot Websocket Oct 31 18:52:13 volumio go-librespot[23532]: time="2025-10-31T18:52:13+01:00" level=debug msg="new websocket client" Oct 31 18:52:13 volumio volumio[925]: info: Connection to go-librespot Websocket established Oct 31 18:52:13 volumio go-librespot[23532]: time="2025-10-31T18:52:13+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]" Oct 31 18:52:13 volumio go-librespot[23532]: time="2025-10-31T18:52:13+01:00" level=debug msg="completed keyexchange" Oct 31 18:52:14 volumio go-librespot[23532]: time="2025-10-31T18:52:14+01:00" level=debug msg="completed challenge" Oct 31 18:52:14 volumio go-librespot[23532]: time="2025-10-31T18:52:14+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Oct 31 18:52:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 31 18:52:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 31 18:52:14 volumio volumio[925]: info: Connection to go-librespot Websocket closed Oct 31 18:52:16 volumio volumio[925]: info: Getting Spotify volume Oct 31 18:52:16 volumio volumio[925]: (node:925) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 31 18:52:16 volumio volumio[925]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Oct 31 18:52:16 volumio volumio[925]: (node:925) 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: 28390) Oct 31 18:52:16 volumio volumio[925]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Oct 31 18:52:16 volumio volumio[925]: info: CoreCommandRouter::volumioGetState Oct 31 18:52:16 volumio volumio[925]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40 Oct 31 18:52:17 volumio volumio[925]: info: Initializing connection to go-librespot Websocket Oct 31 18:52:17 volumio volumio[925]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 31 18:52:17 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 31 18:52:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 83843. Oct 31 18:52:17 volumio systemd[1]: Stopped go-librespot Daemon. Oct 31 18:52:17 volumio systemd[1]: Started go-librespot Daemon. Oct 31 18:52:17 volumio go-librespot[23550]: Librespot-go daemon starting... Oct 31 18:52:17 volumio go-librespot[23550]: time="2025-10-31T18:52:17+01:00" level=info msg="generated new device id: 58650f8236d59ae0cb951759b0b194733309bfd8" Oct 31 18:52:17 volumio go-librespot[23550]: time="2025-10-31T18:52:17+01:00" level=debug msg="stored credentials found for 1fvz1" Oct 31 18:52:17 volumio go-librespot[23550]: time="2025-10-31T18:52:17+01:00" level=debug msg="obtained new client token: AADM8Jolcravzx9o5MgJKAmo1w0LKjFUwR7IpvbRuNSfgSegDXt/myrMzPI14JNSJGZLE3yoIKbSA6vxVnjhVlm3f1RNI+bilp82hzYf3mqn3cXW0J70Xg/vZ85UPODQWIkcR7g5aip08fdWn9A1IqSJ3vAgexxzD7WyIjIvpSdek/9TWWhWxb67etR7F2ZSfHgJpjxcQsUiuLlkZqhG4e4HgLZmqgyt5hp6MV6qRkbQdr8rNKkALFHz7I2i2k9S" Oct 31 18:52:17 volumio go-librespot[23550]: time="2025-10-31T18:52:17+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 31 18:52:17 volumio go-librespot[23550]: time="2025-10-31T18:52:17+01:00" level=debug msg="completed keyexchange" Oct 31 18:52:18 volumio go-librespot[23550]: time="2025-10-31T18:52:18+01:00" level=debug msg="completed challenge" Oct 31 18:52:18 volumio go-librespot[23550]: time="2025-10-31T18:52:18+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Oct 31 18:52:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 31 18:52:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 31 18:52:20 volumio volumio[925]: info: Initializing connection to go-librespot Websocket Oct 31 18:52:20 volumio volumio[925]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 31 18:52:21 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 31 18:52:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 83844. Oct 31 18:52:21 volumio systemd[1]: Stopped go-librespot Daemon. Oct 31 18:52:21 volumio systemd[1]: Started go-librespot Daemon. Oct 31 18:52:21 volumio go-librespot[23580]: Librespot-go daemon starting... Oct 31 18:52:21 volumio go-librespot[23580]: time="2025-10-31T18:52:21+01:00" level=info msg="generated new device id: 907da2cf62fd50ab3aaf48f1402873db4032c606" Oct 31 18:52:21 volumio go-librespot[23580]: time="2025-10-31T18:52:21+01:00" level=debug msg="stored credentials found for 1fvz1" Oct 31 18:52:21 volumio go-librespot[23580]: time="2025-10-31T18:52:21+01:00" level=debug msg="obtained new client token: AAB5R4LCGSJRp2UUQpY58ggwWJjviadoh6lylwtrlFfqwFJ7Eh8NpqML1BSXrLTqGUSuTp3Lpm4MujQeQ/Ltay8w+fZGq18X0iDonsYMT61fN3P02GHv7ETNsez7mgCHBUJAvzhvRq6ytP2Tg3BKw/Ra2iHXu5inBbYotf7+gR/bu+n0LCWjpIYoTtIKsEH5cVwk5yYJQcgtEf33O4Tz9TgBODejiRIpVDL1kxvRXX9E1hZQCw1gh0rshnPC8ye6" Oct 31 18:52:22 volumio go-librespot[23580]: time="2025-10-31T18:52:22+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]" Oct 31 18:52:22 volumio go-librespot[23580]: time="2025-10-31T18:52:22+01:00" level=debug msg="completed keyexchange" Oct 31 18:52:22 volumio go-librespot[23580]: time="2025-10-31T18:52:22+01:00" level=debug msg="completed challenge" Oct 31 18:52:22 volumio go-librespot[23580]: time="2025-10-31T18:52:22+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Oct 31 18:52:22 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 31 18:52:22 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 31 18:52:23 volumio volumio[925]: info: Initializing connection to go-librespot Websocket Oct 31 18:52:23 volumio volumio[925]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 31 18:52:25 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 31 18:52:25 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 83845. Oct 31 18:52:25 volumio systemd[1]: Stopped go-librespot Daemon. Oct 31 18:52:25 volumio systemd[1]: Started go-librespot Daemon. Oct 31 18:52:25 volumio go-librespot[23597]: Librespot-go daemon starting... Oct 31 18:52:25 volumio go-librespot[23597]: time="2025-10-31T18:52:25+01:00" level=info msg="generated new device id: 17718f6577fe6e44362d9cecb5b7047e4f250aed" Oct 31 18:52:25 volumio go-librespot[23597]: time="2025-10-31T18:52:25+01:00" level=debug msg="stored credentials found for 1fvz1" Oct 31 18:52:26 volumio go-librespot[23597]: time="2025-10-31T18:52:26+01:00" level=debug msg="obtained new client token: AAA1uOH8eAr8S6H8CPhqn0/d1toKyMiA0tNtp6iic8DLVLuDaAbL4ccShwbxWrNl7StWex2+GD9rwU6zQzYQnEy3UCaAAmmy9/ObOk2ZWas+GGy23AYnMN3yiKpH1dXI7Um+ODY09QRtLnrDJZi9ruLW1wfQJRSFx7Sy/yAwctiQqtdsYxrDbvksTGTswSsQJ7vXHkxhm9+kSmL8KdmE+kH2oD8f2TMHcou4wpT1hBKUNYW4/sLbWV7zEF/CIg==" Oct 31 18:52:26 volumio volumio[925]: info: Initializing connection to go-librespot Websocket Oct 31 18:52:26 volumio go-librespot[23597]: time="2025-10-31T18:52:26+01:00" level=debug msg="new websocket client" Oct 31 18:52:26 volumio volumio[925]: info: Connection to go-librespot Websocket established Oct 31 18:52:26 volumio go-librespot[23597]: time="2025-10-31T18:52:26+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 31 18:52:26 volumio go-librespot[23597]: time="2025-10-31T18:52:26+01:00" level=debug msg="completed keyexchange" Oct 31 18:52:26 volumio go-librespot[23597]: time="2025-10-31T18:52:26+01:00" level=debug msg="completed challenge" Oct 31 18:52:26 volumio go-librespot[23597]: time="2025-10-31T18:52:26+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Oct 31 18:52:26 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 31 18:52:26 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 31 18:52:26 volumio volumio[925]: info: Connection to go-librespot Websocket closed Oct 31 18:52:29 volumio volumio[925]: info: Getting Spotify volume Oct 31 18:52:29 volumio volumio[925]: (node:925) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 31 18:52:29 volumio volumio[925]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Oct 31 18:52:29 volumio volumio[925]: (node:925) 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: 28391) Oct 31 18:52:29 volumio volumio[925]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Oct 31 18:52:29 volumio volumio[925]: info: CoreCommandRouter::volumioGetState Oct 31 18:52:29 volumio volumio[925]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40 Oct 31 18:52:29 volumio volumio[925]: info: Initializing connection to go-librespot Websocket Oct 31 18:52:29 volumio volumio[925]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 31 18:52:30 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 31 18:52:30 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 83846. Oct 31 18:52:30 volumio systemd[1]: Stopped go-librespot Daemon. Oct 31 18:52:30 volumio systemd[1]: Started go-librespot Daemon. Oct 31 18:52:30 volumio go-librespot[23613]: Librespot-go daemon starting... Oct 31 18:52:30 volumio go-librespot[23613]: time="2025-10-31T18:52:30+01:00" level=info msg="generated new device id: 75c65e7a03e45e3b10b987fe4a1e71c35520df08" Oct 31 18:52:30 volumio go-librespot[23613]: time="2025-10-31T18:52:30+01:00" level=debug msg="stored credentials found for 1fvz1" Oct 31 18:52:30 volumio go-librespot[23613]: time="2025-10-31T18:52:30+01:00" level=debug msg="obtained new client token: AADiWPyBMaABK8KeDH9oUUuhNBWnrljpswt8f/RoVVyefWyTPb0LPAD429XtjBuIpRxPNGY2RrWAeZCLAIQ5r+jb0vNnEpbNIQwyC+PU4JXk66dOBBv9ISMlO26/sG64J/WhSQQ/j9sWXX9lMyI3lajnEkeHH0SogP8VlwHa5Kw7jS0PTu9MuCXbItnf1Hwel2NJZcm+JgOw2hVA3dJmp8ox9vXsmLRDZq8JnFpbqsiHsJTd4uz/J6zBBSQeuN9P" Oct 31 18:52:30 volumio go-librespot[23613]: time="2025-10-31T18:52:30+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Oct 31 18:52:30 volumio go-librespot[23613]: time="2025-10-31T18:52:30+01:00" level=fatal msg="failed running with username and spotify token" error="failed initializing accesspoint: failed dialing accesspoint: dial tcp 34.158.1.133:4070: connect: connection refused" Oct 31 18:52:30 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 31 18:52:30 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 31 18:52:32 volumio volumio[925]: info: Initializing connection to go-librespot Websocket Oct 31 18:52:32 volumio volumio[925]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 31 18:52:33 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 31 18:52:33 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 83847. Oct 31 18:52:33 volumio systemd[1]: Stopped go-librespot Daemon. Oct 31 18:52:33 volumio systemd[1]: Started go-librespot Daemon. Oct 31 18:52:33 volumio go-librespot[23687]: Librespot-go daemon starting... Oct 31 18:52:33 volumio go-librespot[23687]: time="2025-10-31T18:52:33+01:00" level=info msg="generated new device id: 8f52da504b254501aebf0bb62932f04cb7ce514d" Oct 31 18:52:33 volumio go-librespot[23687]: time="2025-10-31T18:52:33+01:00" level=debug msg="stored credentials found for 1fvz1" Oct 31 18:52:34 volumio go-librespot[23687]: time="2025-10-31T18:52:34+01:00" level=debug msg="obtained new client token: AABqG6e/wevgPC1DhDaM2iO8J08tpwFCnzReMLV9xRxnIRowxfwLtW8icDO4VlH/jB93iGPUDKJ4pQ7ntJlsbLoxQ2924IfBeO9WZ/SeoWOiOb9GHh+1PGpTcVNRRbqB8eqbFcjftBaKv7x/AUZDZmy1bH4Nt7yOai8EgIFP7/uLWsvuhQujCzRknXgJJMbEtHJo9ZVVWv9xnpM4ql55zuEVYTDPe2FV36N9EjdN/6nYVYQ3C4uf/8aVwydfmA==" Oct 31 18:52:34 volumio go-librespot[23687]: time="2025-10-31T18:52:34+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 31 18:52:34 volumio go-librespot[23687]: time="2025-10-31T18:52:34+01:00" level=debug msg="completed keyexchange" Oct 31 18:52:34 volumio go-librespot[23687]: time="2025-10-31T18:52:34+01:00" level=debug msg="completed challenge" Oct 31 18:52:34 volumio go-librespot[23687]: time="2025-10-31T18:52:34+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Oct 31 18:52:34 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 31 18:52:34 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 31 18:52:35 volumio volumio[925]: info: Initializing connection to go-librespot Websocket Oct 31 18:52:35 volumio volumio[925]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 31 18:52:38 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 31 18:52:38 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 83848. Oct 31 18:52:38 volumio systemd[1]: Stopped go-librespot Daemon. Oct 31 18:52:38 volumio systemd[1]: Started go-librespot Daemon. Oct 31 18:52:38 volumio go-librespot[23704]: Librespot-go daemon starting... Oct 31 18:52:38 volumio go-librespot[23704]: time="2025-10-31T18:52:38+01:00" level=info msg="generated new device id: 61250b294c49b62dca5bd3e1104e3691ad168b6c" Oct 31 18:52:38 volumio go-librespot[23704]: time="2025-10-31T18:52:38+01:00" level=debug msg="stored credentials found for 1fvz1" Oct 31 18:52:38 volumio go-librespot[23704]: time="2025-10-31T18:52:38+01:00" level=debug msg="obtained new client token: AAAP66noAD4I/NZ8V3QXJI2uNE/umTpA9cYMwhvxCbDjx5ddMI17IYLhhUh/8xX9PcnWk/4zXanM8hflblBKtw3bRTT+klE8xLxKIXBxQqV/jNukZCCHLH+f5rtzM+6+zvbfVZ1lpKfzaeLs445SPgvKYwLUeSrY7s2JnYqDVYN1FY4tSa5F2M24bPIG1/b3t1iLjHpKKUZR7f2Cq/P2AJ7Y6p/wvoNMM9XzYxcKX5ClUA1IV6xQRyt+ik1DFk5H" Oct 31 18:52:38 volumio go-librespot[23704]: time="2025-10-31T18:52:38+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 31 18:52:38 volumio go-librespot[23704]: time="2025-10-31T18:52:38+01:00" level=debug msg="completed keyexchange" Oct 31 18:52:38 volumio volumio[925]: info: Initializing connection to go-librespot Websocket Oct 31 18:52:38 volumio go-librespot[23704]: time="2025-10-31T18:52:38+01:00" level=debug msg="new websocket client" Oct 31 18:52:38 volumio volumio[925]: info: Connection to go-librespot Websocket established Oct 31 18:52:39 volumio go-librespot[23704]: time="2025-10-31T18:52:39+01:00" level=debug msg="completed challenge" Oct 31 18:52:39 volumio go-librespot[23704]: time="2025-10-31T18:52:39+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Oct 31 18:52:39 volumio volumio[925]: info: Connection to go-librespot Websocket closed Oct 31 18:52:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 31 18:52:39 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 31 18:52:41 volumio volumio[925]: info: Getting Spotify volume Oct 31 18:52:41 volumio volumio[925]: (node:925) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 31 18:52:41 volumio volumio[925]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Oct 31 18:52:41 volumio volumio[925]: (node:925) 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: 28392) Oct 31 18:52:41 volumio volumio[925]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Oct 31 18:52:42 volumio volumio[925]: info: CoreCommandRouter::volumioGetState Oct 31 18:52:42 volumio volumio[925]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40 Oct 31 18:52:42 volumio volumio[925]: info: Initializing connection to go-librespot Websocket Oct 31 18:52:42 volumio volumio[925]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 31 18:52:42 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 31 18:52:42 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 83849. Oct 31 18:52:42 volumio systemd[1]: Stopped go-librespot Daemon. Oct 31 18:52:42 volumio systemd[1]: Started go-librespot Daemon. Oct 31 18:52:42 volumio go-librespot[23734]: Librespot-go daemon starting... Oct 31 18:52:42 volumio go-librespot[23734]: time="2025-10-31T18:52:42+01:00" level=info msg="generated new device id: f8603186bcc0ced3f94ffb39bcedebd79314e512" Oct 31 18:52:42 volumio go-librespot[23734]: time="2025-10-31T18:52:42+01:00" level=debug msg="stored credentials found for 1fvz1" Oct 31 18:52:42 volumio go-librespot[23734]: time="2025-10-31T18:52:42+01:00" level=debug msg="obtained new client token: AABdIq/QrTG6tOQLOJrgtgUblcHf7nzCeKRx5HYgUGZMzoekP7G+qWgo4uV66Y0+/R5UfQBqVG5zID5/pRZqRfSKMEZ56CVeWcOTE7SqoDDLf52CcWC+mKb2bOLOVY8BQzo4eMdOfODA2U0+wQ5yw3q7jyCKTkN3JHDyb7g9jtGcs0KXCwmGIpcEfyw2mDJvsLd3aGwLHVAlSD6eX3KERXP3iEM7rN5qXxO0TmjeysBXc3LpnUAP02+ZvU+G00Jy" Oct 31 18:52:42 volumio go-librespot[23734]: time="2025-10-31T18:52:42+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 31 18:52:42 volumio go-librespot[23734]: time="2025-10-31T18:52:42+01:00" level=debug msg="completed keyexchange" Oct 31 18:52:43 volumio go-librespot[23734]: time="2025-10-31T18:52:43+01:00" level=debug msg="completed challenge" Oct 31 18:52:43 volumio go-librespot[23734]: time="2025-10-31T18:52:43+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Oct 31 18:52:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 31 18:52:43 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 31 18:52:45 volumio volumio[925]: info: Initializing connection to go-librespot Websocket Oct 31 18:52:45 volumio volumio[925]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 31 18:52:46 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 31 18:52:46 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 83850. Oct 31 18:52:46 volumio systemd[1]: Stopped go-librespot Daemon. Oct 31 18:52:46 volumio systemd[1]: Started go-librespot Daemon. Oct 31 18:52:46 volumio go-librespot[23750]: Librespot-go daemon starting... Oct 31 18:52:46 volumio go-librespot[23750]: time="2025-10-31T18:52:46+01:00" level=info msg="generated new device id: b8e4a674206a5e035b72f219365a0887b3921125" Oct 31 18:52:46 volumio go-librespot[23750]: time="2025-10-31T18:52:46+01:00" level=debug msg="stored credentials found for 1fvz1" Oct 31 18:52:46 volumio go-librespot[23750]: time="2025-10-31T18:52:46+01:00" level=debug msg="obtained new client token: AACT5oPdhVnRe1J8mNlWEHl3yXdzpjQPgdNQy7FqBk48jJ5NCognxQqgBTm2L1hCS6omrDzOF0V+DeEPGwYqmdffp+6GpQb1aMRoXG8mK+aR/lSqcpv+GWDxAYsr8eNtATmRP3FxlY5VlOzMX02fZySrr4wdsCdN7fLxL+n7g7lFhUgIHv0o2Fo4/D5vZblJOWOlpNrhT/jrOQK7SZLwB3fQQTA9IEQpwaMm7ThW2ISXETNeeK6YqCxKiBI/QaIp" Oct 31 18:52:47 volumio go-librespot[23750]: time="2025-10-31T18:52: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-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 31 18:52:47 volumio go-librespot[23750]: time="2025-10-31T18:52:47+01:00" level=debug msg="completed keyexchange" Oct 31 18:52:47 volumio go-librespot[23750]: time="2025-10-31T18:52:47+01:00" level=debug msg="completed challenge" Oct 31 18:52:47 volumio go-librespot[23750]: time="2025-10-31T18:52:47+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Oct 31 18:52:47 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 31 18:52:47 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 31 18:52:48 volumio volumio[925]: info: Initializing connection to go-librespot Websocket Oct 31 18:52:48 volumio volumio[925]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 31 18:52:51 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 31 18:52:51 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 83851. Oct 31 18:52:51 volumio systemd[1]: Stopped go-librespot Daemon. Oct 31 18:52:51 volumio systemd[1]: Started go-librespot Daemon. Oct 31 18:52:51 volumio go-librespot[23771]: Librespot-go daemon starting... Oct 31 18:52:51 volumio go-librespot[23771]: time="2025-10-31T18:52:51+01:00" level=info msg="generated new device id: 95a8726fc77b426de1a022311ce70f1314cdbcf3" Oct 31 18:52:51 volumio go-librespot[23771]: time="2025-10-31T18:52:51+01:00" level=debug msg="stored credentials found for 1fvz1" Oct 31 18:52:51 volumio volumio[925]: info: Initializing connection to go-librespot Websocket Oct 31 18:52:51 volumio go-librespot[23771]: time="2025-10-31T18:52:51+01:00" level=debug msg="new websocket client" Oct 31 18:52:51 volumio volumio[925]: info: Connection to go-librespot Websocket established Oct 31 18:52:51 volumio go-librespot[23771]: time="2025-10-31T18:52:51+01:00" level=debug msg="obtained new client token: AAASIz0BmqpaUujkmJ+hu3bl18rC19XBEUIaZLRfJQDoeLM5sQpgWOBQo/Z+AfCc+UQZdz89dtVmH7zMtCLMGboIY3rUdw9RKxrbbPtiOOHbrQRHUo13JmFnrsd4qB7NRt79gIqMkuYkqvc8k8jqtOlAb9UKtSQRVNU/y/JE/2sbdKY3c8gQBeWHNGQDztEbEyojo1OK+4yiKe5FmN+CK3MThc/1pPA8ZIG1TE+PRNrl6vJG8goGnf0034KVd9co" Oct 31 18:52:51 volumio go-librespot[23771]: time="2025-10-31T18:52:51+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]" Oct 31 18:52:51 volumio go-librespot[23771]: time="2025-10-31T18:52:51+01:00" level=debug msg="completed keyexchange" Oct 31 18:52:52 volumio go-librespot[23771]: time="2025-10-31T18:52:52+01:00" level=debug msg="completed challenge" Oct 31 18:52:52 volumio go-librespot[23771]: time="2025-10-31T18:52:52+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Oct 31 18:52:52 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 31 18:52:52 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 31 18:52:52 volumio volumio[925]: info: Connection to go-librespot Websocket closed Oct 31 18:52:54 volumio volumio[925]: info: Getting Spotify volume Oct 31 18:52:54 volumio volumio[925]: (node:925) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 31 18:52:54 volumio volumio[925]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Oct 31 18:52:54 volumio volumio[925]: (node:925) 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: 28393) Oct 31 18:52:54 volumio volumio[925]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Oct 31 18:52:54 volumio volumio[925]: info: CoreCommandRouter::volumioGetState Oct 31 18:52:54 volumio volumio[925]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40 Oct 31 18:52:55 volumio volumio[925]: info: Initializing connection to go-librespot Websocket Oct 31 18:52:55 volumio volumio[925]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 31 18:52:55 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 31 18:52:55 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 83852. Oct 31 18:52:55 volumio systemd[1]: Stopped go-librespot Daemon. Oct 31 18:52:55 volumio systemd[1]: Started go-librespot Daemon. Oct 31 18:52:55 volumio go-librespot[23802]: Librespot-go daemon starting... Oct 31 18:52:55 volumio go-librespot[23802]: time="2025-10-31T18:52:55+01:00" level=info msg="generated new device id: 136e05a527da8963e5f00af9f05df4f3526d35e6" Oct 31 18:52:55 volumio go-librespot[23802]: time="2025-10-31T18:52:55+01:00" level=debug msg="stored credentials found for 1fvz1" Oct 31 18:52:55 volumio go-librespot[23802]: time="2025-10-31T18:52:55+01:00" level=debug msg="obtained new client token: AACEXzy3byAl/d7Cvc2CeP/saEkMi43+rA73Ndf8DFGv9DIPg+7PoIUDfWKOkbk1o5ZOTfaQQL3tAJcc1T6wGuJqrefoO7xhCIt7jsJXkSSA+u1L3jp4XemlWPacP/gll3nkOjIA+gV01w0E1la+cqAAhXAUboHmZ6qftrBN1c1blxMkWVEJAEw2wmkfj1uu8oYLu0jDl/A43/dLyOwIH5H1fKikX5odoKd/Flro+N4zOGldByEKiV/pDMcsw3M9" Oct 31 18:52:55 volumio go-librespot[23802]: time="2025-10-31T18:52:55+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 31 18:52:55 volumio go-librespot[23802]: time="2025-10-31T18:52:55+01:00" level=debug msg="completed keyexchange" Oct 31 18:52:56 volumio go-librespot[23802]: time="2025-10-31T18:52:56+01:00" level=debug msg="completed challenge" Oct 31 18:52:56 volumio go-librespot[23802]: time="2025-10-31T18:52:56+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Oct 31 18:52:56 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 31 18:52:56 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 31 18:52:58 volumio volumio[925]: info: Initializing connection to go-librespot Websocket Oct 31 18:52:58 volumio volumio[925]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 31 18:52:59 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 31 18:52:59 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 83853. Oct 31 18:52:59 volumio systemd[1]: Stopped go-librespot Daemon. Oct 31 18:52:59 volumio systemd[1]: Started go-librespot Daemon. Oct 31 18:52:59 volumio go-librespot[23819]: Librespot-go daemon starting... Oct 31 18:52:59 volumio go-librespot[23819]: time="2025-10-31T18:52:59+01:00" level=info msg="generated new device id: d0a5f77cd9996813979fb5adbd6ce822f1e9e7d7" Oct 31 18:52:59 volumio go-librespot[23819]: time="2025-10-31T18:52:59+01:00" level=debug msg="stored credentials found for 1fvz1" Oct 31 18:52:59 volumio go-librespot[23819]: time="2025-10-31T18:52:59+01:00" level=debug msg="obtained new client token: AADp+OeRw+e1dcvw03+0os0F7N5satPmKFo+ZcRA3t4TpKXcJ6WuyL9zImKVevp6yzr+StW4MbzcWQSLZpaPGd3FJn77rJsgTT2D0z81FMxNaeCx8r9eZ+xOu4YjCCA0GBYW8NV6LUSxIDY/dvva01JQ+noNbCvTzXN5sJf1d+8UgcrNo0R0ASw1iIiU+sDqO1b1KL0Gubyvn9F2jgfzcWBYp7fktISZlmRpyQORdu3NdWWs9oaYpPR1pk7Dlf5e" Oct 31 18:53:00 volumio go-librespot[23819]: time="2025-10-31T18:53: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 31 18:53:00 volumio go-librespot[23819]: time="2025-10-31T18:53:00+01:00" level=debug msg="completed keyexchange" Oct 31 18:53:00 volumio go-librespot[23819]: time="2025-10-31T18:53:00+01:00" level=debug msg="completed challenge" Oct 31 18:53:00 volumio go-librespot[23819]: time="2025-10-31T18:53:00+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Oct 31 18:53:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 31 18:53:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 31 18:53:01 volumio volumio[925]: info: Initializing connection to go-librespot Websocket Oct 31 18:53:01 volumio volumio[925]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 31 18:53:04 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 31 18:53:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 83854. Oct 31 18:53:04 volumio systemd[1]: Stopped go-librespot Daemon. Oct 31 18:53:04 volumio systemd[1]: Started go-librespot Daemon. Oct 31 18:53:04 volumio go-librespot[23849]: Librespot-go daemon starting... Oct 31 18:53:04 volumio go-librespot[23849]: time="2025-10-31T18:53:04+01:00" level=info msg="generated new device id: 84f781c69a3c256f8b18a2ef4c69137c86c87033" Oct 31 18:53:04 volumio go-librespot[23849]: time="2025-10-31T18:53:04+01:00" level=debug msg="stored credentials found for 1fvz1" Oct 31 18:53:04 volumio volumio[925]: info: Initializing connection to go-librespot Websocket Oct 31 18:53:04 volumio go-librespot[23849]: time="2025-10-31T18:53:04+01:00" level=debug msg="new websocket client" Oct 31 18:53:04 volumio volumio[925]: info: Connection to go-librespot Websocket established Oct 31 18:53:04 volumio go-librespot[23849]: time="2025-10-31T18:53:04+01:00" level=debug msg="obtained new client token: AAChfO5V1AUxzSGSG+tHiGEaRlEcPzUUV1ONqXsMzzuA4AinxBMrEUFfKkuicZGRt3c4AvS4RjNAmt3lQ5ZmdXTjVPpGeYp5H2LpNiCyKFs6Yg5L145NqH6wlhsQRvBoUj47PuP74+mmIs6ynaylQhAqhLUs4HaI2XKNyrnR/yp6PNcAXjkBaax6dwqLS2JejQZ6N0hnmfCKx3B/RZ0pRAuQrn4Xza51T3yOfGAdgvYBiBZ6imOJ7JiVwDdbAaax" Oct 31 18:53:04 volumio go-librespot[23849]: time="2025-10-31T18:53:04+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Oct 31 18:53:04 volumio go-librespot[23849]: time="2025-10-31T18:53:04+01:00" level=debug msg="completed keyexchange" Oct 31 18:53:05 volumio go-librespot[23849]: time="2025-10-31T18:53:05+01:00" level=debug msg="completed challenge" Oct 31 18:53:05 volumio go-librespot[23849]: time="2025-10-31T18:53:05+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Oct 31 18:53:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 31 18:53:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 31 18:53:05 volumio volumio[925]: info: Connection to go-librespot Websocket closed Oct 31 18:53:07 volumio volumio[925]: info: Getting Spotify volume Oct 31 18:53:07 volumio volumio[925]: (node:925) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 31 18:53:07 volumio volumio[925]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Oct 31 18:53:07 volumio volumio[925]: (node:925) 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: 28394) Oct 31 18:53:07 volumio volumio[925]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Oct 31 18:53:07 volumio volumio[925]: info: CoreCommandRouter::volumioGetState Oct 31 18:53:07 volumio volumio[925]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40 Oct 31 18:53:08 volumio volumio[925]: info: Oct 31 18:53:08 volumio volumio[925]: ---------------------------- MPD announces state update: player Oct 31 18:53:08 volumio volumio[925]: info: ControllerMpd::getState Oct 31 18:53:08 volumio volumio[925]: verbose: ControllerMpd::sendMpdCommand status Oct 31 18:53:08 volumio volumio[925]: info: Oct 31 18:53:08 volumio volumio[925]: ---------------------------- MPD announces state update: player Oct 31 18:53:08 volumio volumio[925]: info: ControllerMpd::getState Oct 31 18:53:08 volumio volumio[925]: verbose: ControllerMpd::sendMpdCommand status Oct 31 18:53:08 volumio volumio[925]: info: sendMpdCommand status took 3 milliseconds Oct 31 18:53:08 volumio volumio[925]: info: sendMpdCommand status took 2 milliseconds Oct 31 18:53:08 volumio volumio[925]: verbose: ControllerMpd::parseState Oct 31 18:53:08 volumio volumio[925]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 18:53:08 volumio volumio[925]: verbose: ControllerMpd::parseState Oct 31 18:53:08 volumio volumio[925]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 18:53:08 volumio volumio[925]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 31 18:53:08 volumio volumio[925]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 31 18:53:08 volumio volumio[925]: verbose: ControllerMpd::parseTrackInfo Oct 31 18:53:08 volumio volumio[925]: verbose: ControllerMpd::parseTrackInfo Oct 31 18:53:08 volumio volumio[925]: info: ControllerMpd::pushState Oct 31 18:53:08 volumio volumio[925]: info: CoreCommandRouter::servicePushState Oct 31 18:53:08 volumio volumio[925]: verbose: In UPNP mode Oct 31 18:53:08 volumio volumio[925]: verbose: STATE SERVICE {"status":"play","position":9,"seek":0,"duration":162,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"790 Kbps","isStreaming":false,"title":"Prayer","artist":"Christian Death","album":"Only Theatre of Pain","uri":"http://192.168.0.104:57645/proxy/tidal/6CF413B7618C45449C153F634E74BB76.flac","trackType":"tidal"} Oct 31 18:53:08 volumio volumio[925]: verbose: CURRENT POSITION 0 Oct 31 18:53:08 volumio volumio[925]: info: CoreStateMachine::syncState stateService play Oct 31 18:53:08 volumio volumio[925]: info: CoreStateMachine::syncState currentStatus play Oct 31 18:53:08 volumio volumio[925]: info: Received an update from plugin. extracting info from payload Oct 31 18:53:08 volumio volumio[925]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 31 18:53:08 volumio volumio[925]: info: CoreStateMachine::pushState Oct 31 18:53:08 volumio volumio[925]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 18:53:08 volumio volumio[925]: info: CoreCommandRouter::volumioPushState Oct 31 18:53:08 volumio volumio[925]: info: CoreStateMachine::pushState Oct 31 18:53:08 volumio volumio[925]: info: CoreCommandRouter::volumioPushState Oct 31 18:53:08 volumio volumio[925]: info: ControllerMpd::pushState Oct 31 18:53:08 volumio volumio[925]: info: CoreCommandRouter::servicePushState Oct 31 18:53:08 volumio volumio[925]: verbose: In UPNP mode Oct 31 18:53:08 volumio volumio[925]: verbose: STATE SERVICE {"status":"play","position":9,"seek":0,"duration":162,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"790 Kbps","isStreaming":false,"title":"Prayer","artist":"Christian Death","album":"Only Theatre of Pain","uri":"http://192.168.0.104:57645/proxy/tidal/6CF413B7618C45449C153F634E74BB76.flac","trackType":"tidal"} Oct 31 18:53:08 volumio volumio[925]: verbose: CURRENT POSITION 0 Oct 31 18:53:08 volumio volumio[925]: info: CoreStateMachine::syncState stateService play Oct 31 18:53:08 volumio volumio[925]: info: CoreStateMachine::syncState currentStatus play Oct 31 18:53:08 volumio volumio[925]: info: Received an update from plugin. extracting info from payload Oct 31 18:53:08 volumio volumio[925]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 31 18:53:08 volumio volumio[925]: info: CoreStateMachine::pushState Oct 31 18:53:08 volumio volumio[925]: info: CoreCommandRouter::volumioPushState Oct 31 18:53:08 volumio volumio[925]: info: CoreStateMachine::pushState Oct 31 18:53:08 volumio volumio[925]: info: CoreCommandRouter::volumioPushState Oct 31 18:53:08 volumio volumio[925]: info: ------------------------------ 27ms Oct 31 18:53:08 volumio volumio[925]: info: ------------------------------ 27ms Oct 31 18:53:08 volumio volumio[925]: info: [LastFM] Current track has sufficient metadata: title (Prayer) and artist (Christian Death) passed on explicitly Oct 31 18:53:08 volumio volumio[925]: info: CoreCommandRouter::volumioGetQueue Oct 31 18:53:08 volumio volumio[925]: info: CoreStateMachine::getQueue Oct 31 18:53:08 volumio volumio[925]: info: CorePlayQueue::getQueue Oct 31 18:53:08 volumio volumio[925]: info: CoreCommandRouter::volumioGetQueue Oct 31 18:53:08 volumio volumio[925]: info: CoreStateMachine::getQueue Oct 31 18:53:08 volumio volumio[925]: info: CorePlayQueue::getQueue Oct 31 18:53:08 volumio volumio[925]: info: CoreCommandRouter::volumioGetQueue Oct 31 18:53:08 volumio volumio[925]: info: CoreStateMachine::getQueue Oct 31 18:53:08 volumio volumio[925]: info: CorePlayQueue::getQueue Oct 31 18:53:08 volumio volumio[925]: info: CoreCommandRouter::volumioGetQueue Oct 31 18:53:08 volumio volumio[925]: info: CoreStateMachine::getQueue Oct 31 18:53:08 volumio volumio[925]: info: CorePlayQueue::getQueue Oct 31 18:53:08 volumio volumio[925]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40 Oct 31 18:53:08 volumio volumio[925]: info: Initializing connection to go-librespot Websocket Oct 31 18:53:08 volumio volumio[925]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 31 18:53:08 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 31 18:53:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 83855. Oct 31 18:53:08 volumio systemd[1]: Stopped go-librespot Daemon. Oct 31 18:53:08 volumio systemd[1]: Started go-librespot Daemon. Oct 31 18:53:08 volumio go-librespot[23870]: Librespot-go daemon starting... Oct 31 18:53:08 volumio go-librespot[23870]: time="2025-10-31T18:53:08+01:00" level=info msg="generated new device id: fb7ca81b74e161f091f7ae4b9bd42804f9b6ff73" Oct 31 18:53:08 volumio go-librespot[23870]: time="2025-10-31T18:53:08+01:00" level=debug msg="stored credentials found for 1fvz1" Oct 31 18:53:08 volumio go-librespot[23870]: time="2025-10-31T18:53:08+01:00" level=debug msg="obtained new client token: AACNPe6VowgH0sx9YDjcUnPZO8q3vgmHJiMG0KmcZDoMgJnunVAb3BGCUqbdKTt9Pgzay/sEVaEzBVjfuF28y0DUMBdr9oORVoNTOUPSppuuF2zBeOaNSfWkM9Jc2GE1ybGkaA+lkgh0RL+EPEJ7b9OBpSAZ/nvzDMN5BzABpV7rrJ44FE3h51wxPUrJ64lXIc4iwAPvjiwY0CPQsSMycfFCbn4DWm3gStbu8vvI+SBDjTQp1DPQRxybc+DgYD8F" Oct 31 18:53:09 volumio go-librespot[23870]: time="2025-10-31T18:53: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]" Oct 31 18:53:09 volumio go-librespot[23870]: time="2025-10-31T18:53:09+01:00" level=debug msg="completed keyexchange" Oct 31 18:53:09 volumio go-librespot[23870]: time="2025-10-31T18:53:09+01:00" level=debug msg="completed challenge" Oct 31 18:53:09 volumio go-librespot[23870]: time="2025-10-31T18:53:09+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Oct 31 18:53:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 31 18:53:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 31 18:53:11 volumio volumio[925]: info: Initializing connection to go-librespot Websocket Oct 31 18:53:11 volumio volumio[925]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 31 18:53:12 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 31 18:53:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 83856. Oct 31 18:53:12 volumio systemd[1]: Stopped go-librespot Daemon. Oct 31 18:53:12 volumio systemd[1]: Started go-librespot Daemon. Oct 31 18:53:12 volumio go-librespot[23903]: Librespot-go daemon starting... Oct 31 18:53:12 volumio go-librespot[23903]: time="2025-10-31T18:53:12+01:00" level=info msg="generated new device id: 776d1d24b039590461be3fe524f6d221fa77605f" Oct 31 18:53:12 volumio go-librespot[23903]: time="2025-10-31T18:53:12+01:00" level=debug msg="stored credentials found for 1fvz1" Oct 31 18:53:13 volumio go-librespot[23903]: time="2025-10-31T18:53:13+01:00" level=debug msg="obtained new client token: AAASaESvMfwzdH30XPVQEKXTZBoslAGm3Ck01Fyhhq0c4SdbMkRlmTNf/ury0gks9w5//tTb5D4Y0VAMB6I5jfpXDSJ/DP6+FbYik5lQfUU2G5rPqPN1tzXwxEJJrPaIbw4R2LtqEswC9OhrjokuwkMYoDBdIw1TpFiCIKQi2cw3h7nfv+2OUZkXvxkMecL/NNo/h87G9PQkqQp6q1LH/PCETO+Nbqx5qMUmBG4GzoovlALNkUmcQjFMAd7PVA==" Oct 31 18:53:13 volumio go-librespot[23903]: time="2025-10-31T18:53:13+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]" Oct 31 18:53:13 volumio go-librespot[23903]: time="2025-10-31T18:53:13+01:00" level=debug msg="completed keyexchange" Oct 31 18:53:13 volumio go-librespot[23903]: time="2025-10-31T18:53:13+01:00" level=debug msg="completed challenge" Oct 31 18:53:13 volumio go-librespot[23903]: time="2025-10-31T18:53:13+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Oct 31 18:53:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 31 18:53:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 31 18:53:14 volumio volumio[925]: info: Initializing connection to go-librespot Websocket Oct 31 18:53:14 volumio volumio[925]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 31 18:53:17 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 31 18:53:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 83857. Oct 31 18:53:17 volumio systemd[1]: Stopped go-librespot Daemon. Oct 31 18:53:17 volumio systemd[1]: Started go-librespot Daemon. Oct 31 18:53:17 volumio go-librespot[23919]: Librespot-go daemon starting... Oct 31 18:53:17 volumio go-librespot[23919]: time="2025-10-31T18:53:17+01:00" level=info msg="generated new device id: b0146b2a7a5accc65160dd2bb4f455284760d582" Oct 31 18:53:17 volumio go-librespot[23919]: time="2025-10-31T18:53:17+01:00" level=debug msg="stored credentials found for 1fvz1" Oct 31 18:53:17 volumio go-librespot[23919]: time="2025-10-31T18:53:17+01:00" level=debug msg="obtained new client token: AADRYh+orjmRkv0Oy1HMi0Jj7YSAlan4hOea9HNItejTzQWqa/I8JEBVlFcvEe1RU6PPTjfPseGKNC8oBbB+qpB6rTsqp538d7bkZJ5VqUpOutrwW6zqldn1/Vwtuzqp+DWuh68sg5S1fefLRFnI3+A1fbQpfe2SVZYfvPcYp8GYw46B9jkp4uwXJVvbWf5NqnAo14fPI8WLAfEuEHd5hf7y5QGIq/C8QsXLwGV1AlMK271Tp1sPNRTo+1mKGIC8" Oct 31 18:53:17 volumio go-librespot[23919]: time="2025-10-31T18:53:17+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 31 18:53:17 volumio volumio[925]: info: Initializing connection to go-librespot Websocket Oct 31 18:53:17 volumio go-librespot[23919]: time="2025-10-31T18:53:17+01:00" level=debug msg="new websocket client" Oct 31 18:53:17 volumio volumio[925]: info: Connection to go-librespot Websocket established Oct 31 18:53:17 volumio go-librespot[23919]: time="2025-10-31T18:53:17+01:00" level=debug msg="completed keyexchange" Oct 31 18:53:17 volumio volumio[925]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 31 18:53:17 volumio volumio[925]: TypeError: Cannot read property '@' of undefined Oct 31 18:53:17 volumio volumio[925]: at Object.callback (/data/plugins/user_interface/lastfm/node_modules/simple-lastfm/lib/index.js:342:14) Oct 31 18:53:17 volumio volumio[925]: at /data/plugins/user_interface/lastfm/node_modules/simple-lastfm/lib/index.js:60:13 Oct 31 18:53:17 volumio volumio[925]: at Parser. (/data/plugins/user_interface/lastfm/node_modules/xml2js/lib/parser.js:308:18) Oct 31 18:53:17 volumio volumio[925]: at Parser.emit (events.js:315:20) Oct 31 18:53:17 volumio volumio[925]: at SAXParser.onerror (/data/plugins/user_interface/lastfm/node_modules/xml2js/lib/parser.js:123:26) Oct 31 18:53:17 volumio volumio[925]: at emit (/data/plugins/user_interface/lastfm/node_modules/sax/lib/sax.js:624:35) Oct 31 18:53:17 volumio volumio[925]: at error (/data/plugins/user_interface/lastfm/node_modules/sax/lib/sax.js:653:5) Oct 31 18:53:17 volumio volumio[925]: at strictFail (/data/plugins/user_interface/lastfm/node_modules/sax/lib/sax.js:677:7) Oct 31 18:53:17 volumio volumio[925]: at closeTag (/data/plugins/user_interface/lastfm/node_modules/sax/lib/sax.js:871:9) Oct 31 18:53:17 volumio volumio[925]: at SAXParser.write (/data/plugins/user_interface/lastfm/node_modules/sax/lib/sax.js:1436:13) Oct 31 18:53:17 volumio volumio[925]: at Parser.exports.Parser.Parser.parseString (/data/plugins/user_interface/lastfm/node_modules/xml2js/lib/parser.js:323:31) Oct 31 18:53:17 volumio volumio[925]: at Parser.parseString (/data/plugins/user_interface/lastfm/node_modules/xml2js/lib/parser.js:5:59) Oct 31 18:53:17 volumio volumio[925]: at IncomingMessage. (/data/plugins/user_interface/lastfm/node_modules/simple-lastfm/lib/index.js:58:14) Oct 31 18:53:17 volumio volumio[925]: at IncomingMessage.emit (events.js:327:22) Oct 31 18:53:17 volumio volumio[925]: at endReadableNT (internal/streams/readable.js:1327:12) Oct 31 18:53:17 volumio volumio[925]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Oct 31 18:53:17 volumio volumio[925]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 31 18:53:18 volumio go-librespot[23919]: time="2025-10-31T18:53:18+01:00" level=debug msg="completed challenge" Oct 31 18:53:18 volumio go-librespot[23919]: time="2025-10-31T18:53:18+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Oct 31 18:53:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 31 18:53:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 31 18:53:19 volumio sudo[23937]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-31 18:52 Oct 31 18:53:19 volumio sudo[23937]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"