-- Logs begin at Tue 2026-01-13 19:08:13 CET, end at Tue 2026-01-13 21:01:24 CET. -- Jan 13 21:00:00 volumio volumio[915]: info: Initializing connection to go-librespot Websocket Jan 13 21:00:00 volumio volumio[915]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 13 21:00:02 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 13 21:00:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17637. Jan 13 21:00:02 volumio systemd[1]: Stopped go-librespot Daemon. Jan 13 21:00:02 volumio systemd[1]: Started go-librespot Daemon. Jan 13 21:00:02 volumio go-librespot[28347]: Librespot-go daemon starting... Jan 13 21:00:02 volumio go-librespot[28347]: time="2026-01-13T21:00:02+01:00" level=info msg="generated new device id: 7b472a62ce0de398caeae37238d393e72baca753" Jan 13 21:00:02 volumio go-librespot[28347]: time="2026-01-13T21:00:02+01:00" level=debug msg="stored credentials found for 1fvz1" Jan 13 21:00:03 volumio go-librespot[28347]: time="2026-01-13T21:00:03+01:00" level=debug msg="obtained new client token: AAC/7WT8MRwzHQ7HPt3NaWpO/LSgOg1VO68tutTmP/odFkpws3EKwynHpJxbv/PZmaGSZaRn5xLDoOfgB5wUmfyMwq6hiUJjCwTU5ze+cNvH7jVI5vuZMEIsupAmq0LZ6XxVhGRcMJEB9jpLc/LdlAcybY44KbBKvamycHwKvztGCFiWoDDgIe6CWa/X47gEqgHsuRt6kJ9Pyo4977ICOD+kCuzXU0zCPVEZK2QISkVZaEq8atR9Npgz/5P+zMh9" Jan 13 21:00:03 volumio go-librespot[28347]: time="2026-01-13T21:00:03+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Jan 13 21:00:03 volumio go-librespot[28347]: time="2026-01-13T21:00:03+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" Jan 13 21:00:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 13 21:00:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 13 21:00:03 volumio volumio[915]: info: Initializing connection to go-librespot Websocket Jan 13 21:00:03 volumio volumio[915]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 13 21:00:06 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 13 21:00:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17638. Jan 13 21:00:06 volumio systemd[1]: Stopped go-librespot Daemon. Jan 13 21:00:06 volumio systemd[1]: Started go-librespot Daemon. Jan 13 21:00:06 volumio go-librespot[28361]: Librespot-go daemon starting... Jan 13 21:00:06 volumio go-librespot[28361]: time="2026-01-13T21:00:06+01:00" level=info msg="generated new device id: 2fd451141b598359ae2e9517c42110fae8c4915c" Jan 13 21:00:06 volumio go-librespot[28361]: time="2026-01-13T21:00:06+01:00" level=debug msg="stored credentials found for 1fvz1" Jan 13 21:00:06 volumio volumio[915]: info: Initializing connection to go-librespot Websocket Jan 13 21:00:06 volumio go-librespot[28361]: time="2026-01-13T21:00:06+01:00" level=debug msg="new websocket client" Jan 13 21:00:06 volumio volumio[915]: info: Connection to go-librespot Websocket established Jan 13 21:00:06 volumio go-librespot[28361]: time="2026-01-13T21:00:06+01:00" level=debug msg="obtained new client token: AABYu3BOE+2+OLhJ5a+vzQDK5FbQYTG6zHsjC5xRt73hHf+DD1YgurwNkZ099vHwO0V2h1Xax5LrOKCX/0GAU0fpu7U8LxL6EEqLH0V+2pyxu0HvMa9POGTaaet1KSSsk3ihbGA+RCNgW17A4dOyc2NAk1Uzl/Gfh8uQcry3lRrRk9qdG6r5k9LGULFxrMm25FeIsYY6hnPnqQ9R67SjeuPGfQmUU/OoqWvwKtw7uV+1M/vppUgmaWpM0Gt27Pjs" Jan 13 21:00:06 volumio go-librespot[28361]: time="2026-01-13T21:00: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-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Jan 13 21:00:08 volumio go-librespot[28361]: time="2026-01-13T21:00:08+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: read tcp 192.168.0.25:54630->34.158.1.133:4070: read: connection reset by peer" Jan 13 21:00:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 13 21:00:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 13 21:00:08 volumio volumio[915]: info: Connection to go-librespot Websocket closed Jan 13 21:00:09 volumio volumio[915]: info: Getting Spotify volume Jan 13 21:00:09 volumio volumio[915]: (node:915) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 13 21:00:09 volumio volumio[915]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Jan 13 21:00:09 volumio volumio[915]: (node:915) 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: 6109) Jan 13 21:00:09 volumio volumio[915]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Jan 13 21:00:09 volumio volumio[915]: info: CoreCommandRouter::volumioGetState Jan 13 21:00:09 volumio volumio[915]: SPOTIFY: RECEIVED VOLUMIO VOLUME 34 Jan 13 21:00:11 volumio volumio[915]: info: Initializing connection to go-librespot Websocket Jan 13 21:00:11 volumio volumio[915]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 13 21:00:11 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 13 21:00:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17639. Jan 13 21:00:11 volumio systemd[1]: Stopped go-librespot Daemon. Jan 13 21:00:11 volumio systemd[1]: Started go-librespot Daemon. Jan 13 21:00:11 volumio go-librespot[28394]: Librespot-go daemon starting... Jan 13 21:00:11 volumio go-librespot[28394]: time="2026-01-13T21:00:11+01:00" level=info msg="generated new device id: a63d102fc18fd3994c1706778302f883a1ed6dfa" Jan 13 21:00:11 volumio go-librespot[28394]: time="2026-01-13T21:00:11+01:00" level=debug msg="stored credentials found for 1fvz1" Jan 13 21:00:11 volumio go-librespot[28394]: time="2026-01-13T21:00:11+01:00" level=debug msg="obtained new client token: AADQjBLWVLChkXBr6CZx/8gSMkNpuCuSRmhdZXKMlRSt5wdqrrY8ORGy4Q7/XV6xWDJi8wtsE3NCFg0GendDkTa8oC0YjF0Dowr3KNXWfCh0xNHaN6S1UNBLvUoMmlvemVjLhdafIru9ySUp+qLZuebIffJiBRNwDiDkjn+NONG3IVXl3ymC1DRXwC3mp+cH5CowwS/s4jnkdxqLdyfs0Elb/jT/3qFtq7IXX8RRTjLIDyZCfSkYo9T1gAu4jo6F" Jan 13 21:00:11 volumio go-librespot[28394]: time="2026-01-13T21:00:11+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]" Jan 13 21:00:12 volumio go-librespot[28394]: time="2026-01-13T21:00:12+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: read tcp 192.168.0.25:54640->34.158.1.133:4070: read: connection reset by peer" Jan 13 21:00:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 13 21:00:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 13 21:00:14 volumio volumio[915]: info: Initializing connection to go-librespot Websocket Jan 13 21:00:14 volumio volumio[915]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 13 21:00:15 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 13 21:00:15 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17640. Jan 13 21:00:15 volumio systemd[1]: Stopped go-librespot Daemon. Jan 13 21:00:15 volumio systemd[1]: Started go-librespot Daemon. Jan 13 21:00:15 volumio go-librespot[28413]: Librespot-go daemon starting... Jan 13 21:00:15 volumio go-librespot[28413]: time="2026-01-13T21:00:15+01:00" level=info msg="generated new device id: 9fab27d71b1a205fa48c132711d2fbbe10f2df0e" Jan 13 21:00:15 volumio go-librespot[28413]: time="2026-01-13T21:00:15+01:00" level=debug msg="stored credentials found for 1fvz1" Jan 13 21:00:15 volumio go-librespot[28413]: time="2026-01-13T21:00:15+01:00" level=debug msg="obtained new client token: AAB2GSyoDolsaQ94dGf63xGL3AVZ/HjzPZa4XJtFp3LJrnYkseKC/pbrHCqpcXR0NmTRrruYKQ3/JGfKdrM3uM041GA8DmrFMzuQwbiUAoCTb/qafpOzK+fiZ3n+MhXFW/HaTP2Avwpw5K3orNe6Ufakuox2Vcm7oAm2w43+Qr2T1Vbi83wqRyvUXgmA1KiRt/Eui31g70OjUU50L1zdkN9yaSG/aZ9x5n5TcvcuzYca7lrCxJnRVMZuJk2XCe1m" Jan 13 21:00:15 volumio go-librespot[28413]: time="2026-01-13T21:00:15+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Jan 13 21:00:15 volumio go-librespot[28413]: time="2026-01-13T21:00:15+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: read tcp 192.168.0.25:48124->34.158.1.133:4070: read: connection reset by peer" Jan 13 21:00:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 13 21:00:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 13 21:00:17 volumio volumio[915]: info: Initializing connection to go-librespot Websocket Jan 13 21:00:17 volumio volumio[915]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 13 21:00:19 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 13 21:00:19 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17641. Jan 13 21:00:19 volumio systemd[1]: Stopped go-librespot Daemon. Jan 13 21:00:19 volumio systemd[1]: Started go-librespot Daemon. Jan 13 21:00:19 volumio go-librespot[28445]: Librespot-go daemon starting... Jan 13 21:00:19 volumio go-librespot[28445]: time="2026-01-13T21:00:19+01:00" level=info msg="generated new device id: 1c5c11a005c6f78e95460026f59df9e3d34f570b" Jan 13 21:00:19 volumio go-librespot[28445]: time="2026-01-13T21:00:19+01:00" level=debug msg="stored credentials found for 1fvz1" Jan 13 21:00:19 volumio go-librespot[28445]: time="2026-01-13T21:00:19+01:00" level=debug msg="obtained new client token: AAA1qu0mI2CBGXV2arlHCth9596jEuHFZ75sEe+xQsFpwom1nDmDYKK2JvoPGaCVpB4/URlLCdh5ZUIQPwLe1gUJ0J9+OljpBVhAhU5AfK/VDuMqTT2ErU0QLW+F2B7isQ5+xQN/vW6hXLXUlX2kMM/sDv+1SEIMvW3vzE1YXRg4oa6dlvcZbyfcoIuE+RN1C8aHwBdhidZsYTJTQx+SZrneaoZ7BJg1g9zcsG7cE0pcyt3donUloh+yE5EDh4Oq" Jan 13 21:00:19 volumio go-librespot[28445]: time="2026-01-13T21:00:19+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Jan 13 21:00:19 volumio go-librespot[28445]: time="2026-01-13T21:00:19+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Jan 13 21:00:19 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 13 21:00:19 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 13 21:00:20 volumio volumio[915]: info: Initializing connection to go-librespot Websocket Jan 13 21:00:20 volumio volumio[915]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 13 21:00:22 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 13 21:00:22 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17642. Jan 13 21:00:22 volumio systemd[1]: Stopped go-librespot Daemon. Jan 13 21:00:22 volumio systemd[1]: Started go-librespot Daemon. Jan 13 21:00:22 volumio go-librespot[28461]: Librespot-go daemon starting... Jan 13 21:00:22 volumio go-librespot[28461]: time="2026-01-13T21:00:22+01:00" level=info msg="generated new device id: af9e235ab6769be316791b7f89dba31846ebaa44" Jan 13 21:00:22 volumio go-librespot[28461]: time="2026-01-13T21:00:22+01:00" level=debug msg="stored credentials found for 1fvz1" Jan 13 21:00:23 volumio volumio[915]: info: Initializing connection to go-librespot Websocket Jan 13 21:00:23 volumio go-librespot[28461]: time="2026-01-13T21:00:23+01:00" level=debug msg="new websocket client" Jan 13 21:00:23 volumio volumio[915]: info: Connection to go-librespot Websocket established Jan 13 21:00:23 volumio go-librespot[28461]: time="2026-01-13T21:00:23+01:00" level=debug msg="obtained new client token: AACLegFrG0Xj7EOcvWcR17DHtIzkxVr558TSwG8ImyeC+0DFcv2VtUtZ8gXRGqMI7QlKRi1SCq6nM1KQkygqsH1F7Z9bGDhv/ykHxZtUdMT8ZBxGi06w9/wMkSiQ1F42JhtKJGJVC9QF3VU35j+bFPY53Tz5wbiJB2ksDrQFvXHibCmMxLTAJjZ5islX7ZkKYWfcbKQ5KVkOUgS8WHCTQ/XTCv9J14H7C6tuNyciFMeScE9lO58+RuG9l7hosw==" Jan 13 21:00:23 volumio go-librespot[28461]: time="2026-01-13T21:00: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]" Jan 13 21:00:23 volumio go-librespot[28461]: time="2026-01-13T21:00:23+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Jan 13 21:00:23 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 13 21:00:23 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 13 21:00:23 volumio volumio[915]: info: Connection to go-librespot Websocket closed Jan 13 21:00:26 volumio volumio[915]: info: Getting Spotify volume Jan 13 21:00:26 volumio volumio[915]: (node:915) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 13 21:00:26 volumio volumio[915]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Jan 13 21:00:26 volumio volumio[915]: (node:915) 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: 6110) Jan 13 21:00:26 volumio volumio[915]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Jan 13 21:00:26 volumio volumio[915]: info: CoreCommandRouter::volumioGetState Jan 13 21:00:26 volumio volumio[915]: SPOTIFY: RECEIVED VOLUMIO VOLUME 34 Jan 13 21:00:26 volumio volumio[915]: info: Initializing connection to go-librespot Websocket Jan 13 21:00:26 volumio volumio[915]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 13 21:00:26 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 13 21:00:26 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17643. Jan 13 21:00:26 volumio systemd[1]: Stopped go-librespot Daemon. Jan 13 21:00:26 volumio systemd[1]: Started go-librespot Daemon. Jan 13 21:00:26 volumio go-librespot[28478]: Librespot-go daemon starting... Jan 13 21:00:26 volumio go-librespot[28478]: time="2026-01-13T21:00:26+01:00" level=info msg="generated new device id: c53c45254b1b1fdafa4689bda9d69ca87fd0b702" Jan 13 21:00:26 volumio go-librespot[28478]: time="2026-01-13T21:00:26+01:00" level=debug msg="stored credentials found for 1fvz1" Jan 13 21:00:26 volumio go-librespot[28478]: time="2026-01-13T21:00:26+01:00" level=debug msg="obtained new client token: AACynI5lzfAu8QFltRZ+qsx+8Ut/UhlFapPuNIaGFJ2O5tyUABd24+/NwTVJrOpktf20gwWtrZyMFpxAGkliGPRWkXKJ7qagShREXUy55Y1QouT/h3LeGCnkW5+6njJFMYkbzdT2uN9o7Bhb8+U+ye4QzRlKY9Bno6XnFYJTCluWeZDLvgQgGI2pKPo4zgLMx0hiDXFIalBk8H9zx/QqdsH6QfM06xlB4wJFzn3wRI4uhSyfuFymuEaGqaBR0WP9" Jan 13 21:00:27 volumio go-librespot[28478]: time="2026-01-13T21:00:27+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]" Jan 13 21:00:27 volumio go-librespot[28478]: time="2026-01-13T21:00:27+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Jan 13 21:00:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 13 21:00:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 13 21:00:29 volumio volumio[915]: info: Initializing connection to go-librespot Websocket Jan 13 21:00:29 volumio volumio[915]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 13 21:00:30 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 13 21:00:30 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17644. Jan 13 21:00:30 volumio systemd[1]: Stopped go-librespot Daemon. Jan 13 21:00:30 volumio systemd[1]: Started go-librespot Daemon. Jan 13 21:00:30 volumio go-librespot[28508]: Librespot-go daemon starting... Jan 13 21:00:30 volumio go-librespot[28508]: time="2026-01-13T21:00:30+01:00" level=info msg="generated new device id: 04371560f14a56dd5c259ecb8de029cb61ea4fe2" Jan 13 21:00:30 volumio go-librespot[28508]: time="2026-01-13T21:00:30+01:00" level=debug msg="stored credentials found for 1fvz1" Jan 13 21:00:30 volumio go-librespot[28508]: time="2026-01-13T21:00:30+01:00" level=debug msg="obtained new client token: AABDDD1pdSYT9nENUWnlgmWihrE9J7C+//u3zx5RhxtURTPaFY8BNaL2Ej4O5UA8hTuQd1ElKeRNO8YU6Qpqp4hfADIbLv6RqzljZXrfGhk5qRUeg4YuzEdntPvioxs1CTf6tUkaTO7dvhJ28IytN7Pu4wpVCEr1p82eV39++yCYI3aJXgRLae4tfdRkrvbm9GJMmamOMNJdHG6FjMI+4ID4LQlkx/KSd2My6cUsvttnFlDMY7IkRJt3l9cEofo5" Jan 13 21:00:30 volumio go-librespot[28508]: time="2026-01-13T21:00: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]" Jan 13 21:00:31 volumio go-librespot[28508]: time="2026-01-13T21:00:31+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: read tcp 192.168.0.25:34828->34.158.1.133:4070: read: connection reset by peer" Jan 13 21:00:31 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 13 21:00:31 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 13 21:00:32 volumio volumio[915]: info: Initializing connection to go-librespot Websocket Jan 13 21:00:32 volumio volumio[915]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 13 21:00:34 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 13 21:00:34 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17645. Jan 13 21:00:34 volumio systemd[1]: Stopped go-librespot Daemon. Jan 13 21:00:34 volumio systemd[1]: Started go-librespot Daemon. Jan 13 21:00:34 volumio go-librespot[28527]: Librespot-go daemon starting... Jan 13 21:00:34 volumio go-librespot[28527]: time="2026-01-13T21:00:34+01:00" level=info msg="generated new device id: 67284dd17f3efc456c1f8e24dd7fe3e233ad5b77" Jan 13 21:00:34 volumio go-librespot[28527]: time="2026-01-13T21:00:34+01:00" level=debug msg="stored credentials found for 1fvz1" Jan 13 21:00:34 volumio go-librespot[28527]: time="2026-01-13T21:00:34+01:00" level=debug msg="obtained new client token: AACxlL2kXoahcSm2e1lHiqTxGU/Pa1WLlKPBsrv9nlvHqUdBIpM0YIyo9wUmrWJ+Rsn3FF0r191KmeLp/37g8z1du40ka/qTYYzOnYsNhViQLQT8hAafPchCtD1UG+nNarycOyUxCtAHMc1+0IWUrHYl0BAnmCn2/GrzkEbjdypjp1JOqP6kfKT8/JLwCgEwe625ZlJ3bbasNqpPqPpvb6ZrG9I1StHgSOZQqVeiI60Ju2Kd58uzNAaemyJCC1vn" Jan 13 21:00:34 volumio go-librespot[28527]: time="2026-01-13T21:00: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]" Jan 13 21:00:35 volumio go-librespot[28527]: time="2026-01-13T21:00:35+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Jan 13 21:00:35 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 13 21:00:35 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 13 21:00:35 volumio volumio[915]: info: Initializing connection to go-librespot Websocket Jan 13 21:00:35 volumio volumio[915]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 13 21:00:38 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 13 21:00:38 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17646. Jan 13 21:00:38 volumio systemd[1]: Stopped go-librespot Daemon. Jan 13 21:00:38 volumio systemd[1]: Started go-librespot Daemon. Jan 13 21:00:38 volumio go-librespot[28543]: Librespot-go daemon starting... Jan 13 21:00:38 volumio go-librespot[28543]: time="2026-01-13T21:00:38+01:00" level=info msg="generated new device id: ed0344ed94e68889878bd77730ce9c674bb0e2ce" Jan 13 21:00:38 volumio go-librespot[28543]: time="2026-01-13T21:00:38+01:00" level=debug msg="stored credentials found for 1fvz1" Jan 13 21:00:38 volumio volumio[915]: info: Initializing connection to go-librespot Websocket Jan 13 21:00:38 volumio go-librespot[28543]: time="2026-01-13T21:00:38+01:00" level=debug msg="new websocket client" Jan 13 21:00:38 volumio volumio[915]: info: Connection to go-librespot Websocket established Jan 13 21:00:38 volumio go-librespot[28543]: time="2026-01-13T21:00:38+01:00" level=debug msg="obtained new client token: AADN5XYMUqOktLamoUeu95Ppcy+LJjDVZeC07t3NRAefhJxeh8CINvDLUVKRaUT1zEfSkgTrP5X5WKTkcPm/Q5vD63cDPGnDi+4CrrXp7whEzgMO+ncNmwegjRl14+uq6x4i8pmwjDYMSFPEwVN9o75goho43KhBzGhyWGB9j9xAcJ+IrlHgalHcra4bW/QwTD2KX0tRpZqqJ9724tRpAXw567EwXD+0x3J/XFn7R2fOV4hwTd9KJBq8Rg8d2M0B" Jan 13 21:00:39 volumio go-librespot[28543]: time="2026-01-13T21:00:39+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]" Jan 13 21:00:39 volumio go-librespot[28543]: time="2026-01-13T21:00:39+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: read tcp 192.168.0.25:33670->34.158.1.133:4070: read: connection reset by peer" Jan 13 21:00:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 13 21:00:39 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 13 21:00:39 volumio volumio[915]: info: Connection to go-librespot Websocket closed Jan 13 21:00:41 volumio volumio[915]: info: Getting Spotify volume Jan 13 21:00:41 volumio volumio[915]: (node:915) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 13 21:00:41 volumio volumio[915]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Jan 13 21:00:41 volumio volumio[915]: (node:915) 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: 6111) Jan 13 21:00:41 volumio volumio[915]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Jan 13 21:00:41 volumio volumio[915]: info: CoreCommandRouter::volumioGetState Jan 13 21:00:41 volumio volumio[915]: SPOTIFY: RECEIVED VOLUMIO VOLUME 34 Jan 13 21:00:42 volumio volumio[915]: info: Initializing connection to go-librespot Websocket Jan 13 21:00:42 volumio volumio[915]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 13 21:00:42 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 13 21:00:42 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17647. Jan 13 21:00:42 volumio systemd[1]: Stopped go-librespot Daemon. Jan 13 21:00:42 volumio systemd[1]: Started go-librespot Daemon. Jan 13 21:00:42 volumio go-librespot[28575]: Librespot-go daemon starting... Jan 13 21:00:42 volumio go-librespot[28575]: time="2026-01-13T21:00:42+01:00" level=info msg="generated new device id: c5422f47c2831c0a946f8696ecd36dcf3146101d" Jan 13 21:00:42 volumio go-librespot[28575]: time="2026-01-13T21:00:42+01:00" level=debug msg="stored credentials found for 1fvz1" Jan 13 21:00:42 volumio go-librespot[28575]: time="2026-01-13T21:00:42+01:00" level=debug msg="obtained new client token: AACl5DDYVSrq6Yzj9GOTU/nyGqHIWVKMf0Ld4UNMNn2eKSKwoaPOm7m5+QXfo5CfrRoW8VHZvIXXiaFw4cWlsTeI/5s2l9eoe+5w07RSwv8rFNJuw2DUJ/NLff1bzm4xsqXt7dlT3i1THw6QODkQxv6zvDQSTwh9lhfBLGeWDFgsbRPPuk4TTBcHSxB/iycj4rrqNdHtt3Wt8DKAITP5CGv5vIbCHi/Kvi7spL9OIBOwHLE4k+hv+amJ+Ye0o1xB" Jan 13 21:00:42 volumio go-librespot[28575]: time="2026-01-13T21:00: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-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Jan 13 21:00:43 volumio go-librespot[28575]: time="2026-01-13T21:00:43+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Jan 13 21:00:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 13 21:00:43 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 13 21:00:45 volumio volumio[915]: info: Initializing connection to go-librespot Websocket Jan 13 21:00:45 volumio volumio[915]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 13 21:00:46 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 13 21:00:46 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17648. Jan 13 21:00:46 volumio systemd[1]: Stopped go-librespot Daemon. Jan 13 21:00:46 volumio systemd[1]: Started go-librespot Daemon. Jan 13 21:00:46 volumio go-librespot[28589]: Librespot-go daemon starting... Jan 13 21:00:46 volumio go-librespot[28589]: time="2026-01-13T21:00:46+01:00" level=info msg="generated new device id: 24d28c86fd677bbaeae991c2f6977fb24fb8d3c5" Jan 13 21:00:46 volumio go-librespot[28589]: time="2026-01-13T21:00:46+01:00" level=debug msg="stored credentials found for 1fvz1" Jan 13 21:00:46 volumio go-librespot[28589]: time="2026-01-13T21:00:46+01:00" level=debug msg="obtained new client token: AABlCe0aot74XpdK03VsReU7Xj49xbbx0eXfRezETxdxVVMHhOdX8sgkWwF+ojG72sRXpOTPrDTJnke7HE6HyEsuFrkDRrn+i0cDdxTgEIekuYDYbWbGEG8hbNNpYPPPVMJi/XSB4cbJNUorFMrj4EunJeqPTu2JjM3rIK2uDM6cikiBoVPxEDDUv2+wopvDZrvYAlaLXJ1Hfszo5Y5mYmWHS8sNGQ1v0n65zG6YFfLtO535D3mvkIkoX4g7+nl8" Jan 13 21:00:46 volumio go-librespot[28589]: time="2026-01-13T21:00:46+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]" Jan 13 21:00:46 volumio go-librespot[28589]: time="2026-01-13T21:00:46+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" Jan 13 21:00:46 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 13 21:00:46 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 13 21:00:48 volumio volumio[915]: info: Initializing connection to go-librespot Websocket Jan 13 21:00:48 volumio volumio[915]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 13 21:00:50 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 13 21:00:50 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17649. Jan 13 21:00:50 volumio systemd[1]: Stopped go-librespot Daemon. Jan 13 21:00:50 volumio systemd[1]: Started go-librespot Daemon. Jan 13 21:00:50 volumio go-librespot[28620]: Librespot-go daemon starting... Jan 13 21:00:50 volumio go-librespot[28620]: time="2026-01-13T21:00:50+01:00" level=info msg="generated new device id: 37e4e249bae0aaadd2102b10fd552f26ef0fff99" Jan 13 21:00:50 volumio go-librespot[28620]: time="2026-01-13T21:00:50+01:00" level=debug msg="stored credentials found for 1fvz1" Jan 13 21:00:50 volumio go-librespot[28620]: time="2026-01-13T21:00:50+01:00" level=debug msg="obtained new client token: AAAeRFX7gr+CIwkfgeRidwJaCictmUxh1A1sR23JE9dqjK74ZhUICyzfmBbMlNZY0Swj17D2JqOdrv3YPUDGiJoFQa6kuX3gfga/KSPUvJ+VZymeQ9Ym3Ov/ioYKgRwBLvMkMUNfICBuDOZ2nkOy58+OkHYHa9dNdxyqyMeFZaoesjKOD/M3JhMcTFDxI+iEqQfpW8EZ3+M70zrMbNcP0hQAknqL5GJk4nf0A3JwCcwqFEgS7viUdzvgRyYGCo91" Jan 13 21:00:50 volumio go-librespot[28620]: time="2026-01-13T21:00:50+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]" Jan 13 21:00:50 volumio go-librespot[28620]: time="2026-01-13T21:00:50+01:00" level=debug msg="completed keyexchange" Jan 13 21:00:51 volumio volumio[915]: info: Initializing connection to go-librespot Websocket Jan 13 21:00:51 volumio go-librespot[28620]: time="2026-01-13T21:00:51+01:00" level=debug msg="new websocket client" Jan 13 21:00:51 volumio volumio[915]: info: Connection to go-librespot Websocket established Jan 13 21:00:51 volumio go-librespot[28620]: time="2026-01-13T21:00:51+01:00" level=debug msg="completed challenge" Jan 13 21:00:51 volumio go-librespot[28620]: time="2026-01-13T21:00:51+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 13 21:00:51 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 13 21:00:51 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 13 21:00:51 volumio volumio[915]: info: Connection to go-librespot Websocket closed Jan 13 21:00:54 volumio volumio[915]: info: Getting Spotify volume Jan 13 21:00:54 volumio volumio[915]: (node:915) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 13 21:00:54 volumio volumio[915]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Jan 13 21:00:54 volumio volumio[915]: (node:915) 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: 6112) Jan 13 21:00:54 volumio volumio[915]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Jan 13 21:00:54 volumio volumio[915]: info: CoreCommandRouter::volumioGetState Jan 13 21:00:54 volumio volumio[915]: SPOTIFY: RECEIVED VOLUMIO VOLUME 34 Jan 13 21:00:54 volumio volumio[915]: info: Initializing connection to go-librespot Websocket Jan 13 21:00:54 volumio volumio[915]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 13 21:00:54 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 13 21:00:54 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17650. Jan 13 21:00:54 volumio systemd[1]: Stopped go-librespot Daemon. Jan 13 21:00:54 volumio systemd[1]: Started go-librespot Daemon. Jan 13 21:00:54 volumio go-librespot[28640]: Librespot-go daemon starting... Jan 13 21:00:54 volumio go-librespot[28640]: time="2026-01-13T21:00:54+01:00" level=info msg="generated new device id: cef7f790d51413b70395b19c90786ca90861d699" Jan 13 21:00:54 volumio go-librespot[28640]: time="2026-01-13T21:00:54+01:00" level=debug msg="stored credentials found for 1fvz1" Jan 13 21:00:54 volumio go-librespot[28640]: time="2026-01-13T21:00:54+01:00" level=debug msg="obtained new client token: AAB5asrH3K+bMWsiQhc9S0KkS5w/eYNepFvj4vOiY6b8VPfrKYoQUgTY7rWg6dJzpyDiSWf+PCQUAaMU8mxLydXD9b0fbqfpAxhyVUDPKPPtt0QFKfPrY3U1e1vqAnQ73qQQsp47+HeRrq7nHo4iHejF1uM2rf0SUOuNexZfPh/yn8Sn5WtxLCrd/P7ZqiBIX5cUB+XolsQixe0BZ4mlVoK4sMm8jRBHSRhMRp7JTkSTM7QQh2p1FpQ+O6D0wHtE" Jan 13 21:00:54 volumio go-librespot[28640]: time="2026-01-13T21:00:54+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]" Jan 13 21:00:54 volumio go-librespot[28640]: time="2026-01-13T21:00:54+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Jan 13 21:00:55 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 13 21:00:55 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 13 21:00:57 volumio volumio[915]: info: Initializing connection to go-librespot Websocket Jan 13 21:00:57 volumio volumio[915]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 13 21:00:58 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 13 21:00:58 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17651. Jan 13 21:00:58 volumio systemd[1]: Stopped go-librespot Daemon. Jan 13 21:00:58 volumio systemd[1]: Started go-librespot Daemon. Jan 13 21:00:58 volumio go-librespot[28699]: Librespot-go daemon starting... Jan 13 21:00:58 volumio go-librespot[28699]: time="2026-01-13T21:00:58+01:00" level=info msg="generated new device id: a76dc4b02ff5d36b913a7479f4c816206a531a65" Jan 13 21:00:58 volumio go-librespot[28699]: time="2026-01-13T21:00:58+01:00" level=debug msg="stored credentials found for 1fvz1" Jan 13 21:00:58 volumio go-librespot[28699]: time="2026-01-13T21:00:58+01:00" level=debug msg="obtained new client token: AABGpteVywaIRVJ0DmaQSvGqQLAmz29B3wsnpojLGGnkEKvPOp5dW42VRaFpUyRMdkVgZ/Si798/W7gvy4guLdusA/W2NnaCygtHlnPqkbJnEUT8hGPyN2CXy4StNQfsPiPZWEssH0EBEMp3RknpDo8eH5tIVCF/KyYxpAoDZUHNgYC8lZka7pdoREl7nhH9g7T//hElbetlDoQd8H6jsW6M2eDluiQd1R4VgD8gvW+lI9aA/OIAPfK+Uz0np8d3" Jan 13 21:00:58 volumio go-librespot[28699]: time="2026-01-13T21:00:58+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]" Jan 13 21:00:58 volumio go-librespot[28699]: time="2026-01-13T21:00:58+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Jan 13 21:00:58 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 13 21:00:58 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 13 21:01:00 volumio volumio[915]: info: Initializing connection to go-librespot Websocket Jan 13 21:01:00 volumio volumio[915]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 13 21:01:01 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 13 21:01:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17652. Jan 13 21:01:01 volumio systemd[1]: Stopped go-librespot Daemon. Jan 13 21:01:01 volumio systemd[1]: Started go-librespot Daemon. Jan 13 21:01:01 volumio go-librespot[28729]: Librespot-go daemon starting... Jan 13 21:01:01 volumio go-librespot[28729]: time="2026-01-13T21:01:01+01:00" level=info msg="generated new device id: 31d7cde14acc3fde5a89cdcd0879734ffe322e02" Jan 13 21:01:01 volumio go-librespot[28729]: time="2026-01-13T21:01:01+01:00" level=debug msg="stored credentials found for 1fvz1" Jan 13 21:01:02 volumio go-librespot[28729]: time="2026-01-13T21:01:02+01:00" level=debug msg="obtained new client token: AABV3Kzb7IM8Q93m/R1NGJUCf6ofBPV186HbjswUcvaeHx75IWA77zUdR/T/GBtyoM7rfLac8/6iWpuPGVDtd1RXWI8uRnyhkHIgDuWpbvJz+EMN4YGb/VcO5htEQubYqNBvb58XcArpjgOevoE5DzxU/jGpYiG3SDpJc8sFHQXvgMaTu4mFE5TsdJQW/qzIZMH0Lzyj9/3SFj3SWiTq3LKs+c8xjt3Y2jbkds5B9uSIwSRU2+jLa6kjTziD+A==" Jan 13 21:01:02 volumio go-librespot[28729]: time="2026-01-13T21:01:02+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]" Jan 13 21:01:02 volumio go-librespot[28729]: time="2026-01-13T21:01:02+01:00" level=debug msg="completed keyexchange" Jan 13 21:01:03 volumio go-librespot[28729]: time="2026-01-13T21:01:03+01:00" level=debug msg="completed challenge" Jan 13 21:01:03 volumio go-librespot[28729]: time="2026-01-13T21:01:03+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 13 21:01:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 13 21:01:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 13 21:01:03 volumio volumio[915]: info: Initializing connection to go-librespot Websocket Jan 13 21:01:03 volumio volumio[915]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 13 21:01:06 volumio volumio[915]: info: Initializing connection to go-librespot Websocket Jan 13 21:01:06 volumio volumio[915]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 13 21:01:06 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 13 21:01:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17653. Jan 13 21:01:06 volumio systemd[1]: Stopped go-librespot Daemon. Jan 13 21:01:06 volumio systemd[1]: Started go-librespot Daemon. Jan 13 21:01:06 volumio go-librespot[28747]: Librespot-go daemon starting... Jan 13 21:01:06 volumio go-librespot[28747]: time="2026-01-13T21:01:06+01:00" level=info msg="generated new device id: b395a3e86a8e12349c191038b6f2b647b330d5de" Jan 13 21:01:06 volumio go-librespot[28747]: time="2026-01-13T21:01:06+01:00" level=debug msg="stored credentials found for 1fvz1" Jan 13 21:01:06 volumio go-librespot[28747]: time="2026-01-13T21:01:06+01:00" level=debug msg="obtained new client token: AADid6mukTeDjvfAIq7H7RPlWEXj48Ts663Q/O38e4Zw0okfbQVEyHkKhUcKZu2cikz68D0BSkEfY58caYvCVHSZ5SoggkGzYEtFqif+S4xffhxE4DKGHdwJv3k6Yc6T+rCOMi9FctLwEwcV0qg1gnYf5Q/Pygett2v8VmGpjuXjgqRX38sxk8O5zwrxwRpqN2jBFxda4NCYltYb17iUiBPRMK19lhRA8J7UsOXMpiqtZ5NVxuBzXM3Nh4xRoUDg" Jan 13 21:01:06 volumio go-librespot[28747]: time="2026-01-13T21:01: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]" Jan 13 21:01:07 volumio go-librespot[28747]: time="2026-01-13T21:01:07+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Jan 13 21:01:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 13 21:01:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 13 21:01:09 volumio volumio[915]: info: Initializing connection to go-librespot Websocket Jan 13 21:01:09 volumio volumio[915]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 13 21:01:10 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 13 21:01:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17654. Jan 13 21:01:10 volumio systemd[1]: Stopped go-librespot Daemon. Jan 13 21:01:10 volumio systemd[1]: Started go-librespot Daemon. Jan 13 21:01:10 volumio go-librespot[28778]: Librespot-go daemon starting... Jan 13 21:01:10 volumio go-librespot[28778]: time="2026-01-13T21:01:10+01:00" level=info msg="generated new device id: 71d8660b989a16618996a008fad7105b23551568" Jan 13 21:01:10 volumio go-librespot[28778]: time="2026-01-13T21:01:10+01:00" level=debug msg="stored credentials found for 1fvz1" Jan 13 21:01:10 volumio go-librespot[28778]: time="2026-01-13T21:01:10+01:00" level=debug msg="obtained new client token: AAAHdLZq3UdCCU1YG8NsTJwfLL4fdUr8QeMuLf0Zs0HDt0jWZdlVPEo5+/HdNPvhrJAmxmr+cDBlI5N6SIWV9oSbm4Aetkb5ItPGqtp7F6cY1UdEYSFFhN9LS185bmDsHKJxJ/GaMv7F3SC0P8Ih4f9ke+kUtoEy0OEDQtKoW+MdFvlyjQAbJ6vDA4pgpyBnx1+MURPYZSn4/83wZV4KDGwwuox+VdshvPGWCT0VwoqI7fAV5Dj26VnelvSmOdsJ" Jan 13 21:01:10 volumio go-librespot[28778]: time="2026-01-13T21:01:10+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Jan 13 21:01:10 volumio go-librespot[28778]: time="2026-01-13T21:01:10+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: read tcp 192.168.0.25:33740->34.158.1.133:4070: read: connection reset by peer" Jan 13 21:01:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 13 21:01:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 13 21:01:12 volumio volumio[915]: info: Initializing connection to go-librespot Websocket Jan 13 21:01:12 volumio volumio[915]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 13 21:01:13 volumio volumio[915]: info: Jan 13 21:01:13 volumio volumio[915]: ---------------------------- MPD announces state update: player Jan 13 21:01:13 volumio volumio[915]: info: ControllerMpd::getState Jan 13 21:01:13 volumio volumio[915]: verbose: ControllerMpd::sendMpdCommand status Jan 13 21:01:13 volumio volumio[915]: info: Jan 13 21:01:13 volumio volumio[915]: ---------------------------- MPD announces state update: player Jan 13 21:01:13 volumio volumio[915]: info: ControllerMpd::getState Jan 13 21:01:13 volumio volumio[915]: verbose: ControllerMpd::sendMpdCommand status Jan 13 21:01:13 volumio volumio[915]: info: Jan 13 21:01:13 volumio volumio[915]: ---------------------------- MPD announces state update: player Jan 13 21:01:13 volumio volumio[915]: info: ControllerMpd::getState Jan 13 21:01:13 volumio volumio[915]: verbose: ControllerMpd::sendMpdCommand status Jan 13 21:01:13 volumio volumio[915]: info: sendMpdCommand status took 5 milliseconds Jan 13 21:01:13 volumio volumio[915]: info: sendMpdCommand status took 4 milliseconds Jan 13 21:01:13 volumio volumio[915]: info: sendMpdCommand status took 2 milliseconds Jan 13 21:01:13 volumio volumio[915]: verbose: ControllerMpd::parseState Jan 13 21:01:13 volumio volumio[915]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 13 21:01:13 volumio volumio[915]: verbose: ControllerMpd::parseState Jan 13 21:01:13 volumio volumio[915]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 13 21:01:13 volumio volumio[915]: verbose: ControllerMpd::parseState Jan 13 21:01:13 volumio volumio[915]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 13 21:01:13 volumio volumio[915]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 13 21:01:13 volumio volumio[915]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 13 21:01:13 volumio volumio[915]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 13 21:01:13 volumio volumio[915]: verbose: ControllerMpd::parseTrackInfo Jan 13 21:01:13 volumio volumio[915]: verbose: ControllerMpd::parseTrackInfo Jan 13 21:01:13 volumio volumio[915]: verbose: ControllerMpd::parseTrackInfo Jan 13 21:01:13 volumio volumio[915]: info: ControllerMpd::pushState Jan 13 21:01:13 volumio volumio[915]: info: CoreCommandRouter::servicePushState Jan 13 21:01:13 volumio volumio[915]: verbose: In UPNP mode Jan 13 21:01:13 volumio volumio[915]: verbose: STATE SERVICE {"status":"play","position":21,"seek":0,"duration":247,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"619 Kbps","isStreaming":false,"title":"Valerie","artist":"Steve Winwood","album":"Talking Back To The Night","uri":"http://192.168.0.105:57645/proxy/tidal/F44D8177038ED0E2DBC27DE157A43B7D.flac","trackType":"tidal"} Jan 13 21:01:13 volumio volumio[915]: verbose: CURRENT POSITION 0 Jan 13 21:01:13 volumio volumio[915]: info: CoreStateMachine::syncState stateService play Jan 13 21:01:13 volumio volumio[915]: info: CoreStateMachine::syncState currentStatus play Jan 13 21:01:13 volumio volumio[915]: info: Received an update from plugin. extracting info from payload Jan 13 21:01:13 volumio volumio[915]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 13 21:01:13 volumio volumio[915]: info: CoreStateMachine::pushState Jan 13 21:01:13 volumio volumio[915]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 13 21:01:13 volumio volumio[915]: info: CoreCommandRouter::volumioPushState Jan 13 21:01:13 volumio volumio[915]: info: CoreStateMachine::pushState Jan 13 21:01:13 volumio volumio[915]: info: CoreCommandRouter::volumioPushState Jan 13 21:01:13 volumio volumio[915]: info: ControllerMpd::pushState Jan 13 21:01:13 volumio volumio[915]: info: CoreCommandRouter::servicePushState Jan 13 21:01:13 volumio volumio[915]: verbose: In UPNP mode Jan 13 21:01:13 volumio volumio[915]: verbose: STATE SERVICE {"status":"play","position":21,"seek":0,"duration":247,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"619 Kbps","isStreaming":false,"title":"Valerie","artist":"Steve Winwood","album":"Talking Back To The Night","uri":"http://192.168.0.105:57645/proxy/tidal/F44D8177038ED0E2DBC27DE157A43B7D.flac","trackType":"tidal"} Jan 13 21:01:13 volumio volumio[915]: verbose: CURRENT POSITION 0 Jan 13 21:01:13 volumio volumio[915]: info: CoreStateMachine::syncState stateService play Jan 13 21:01:13 volumio volumio[915]: info: CoreStateMachine::syncState currentStatus play Jan 13 21:01:13 volumio volumio[915]: info: Received an update from plugin. extracting info from payload Jan 13 21:01:13 volumio volumio[915]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 13 21:01:13 volumio volumio[915]: info: CoreStateMachine::pushState Jan 13 21:01:13 volumio volumio[915]: info: CoreCommandRouter::volumioPushState Jan 13 21:01:13 volumio volumio[915]: info: CoreStateMachine::pushState Jan 13 21:01:13 volumio volumio[915]: info: CoreCommandRouter::volumioPushState Jan 13 21:01:13 volumio volumio[915]: info: ControllerMpd::pushState Jan 13 21:01:13 volumio volumio[915]: info: CoreCommandRouter::servicePushState Jan 13 21:01:13 volumio volumio[915]: verbose: In UPNP mode Jan 13 21:01:13 volumio volumio[915]: verbose: STATE SERVICE {"status":"play","position":21,"seek":0,"duration":247,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"619 Kbps","isStreaming":false,"title":"Valerie","artist":"Steve Winwood","album":"Talking Back To The Night","uri":"http://192.168.0.105:57645/proxy/tidal/F44D8177038ED0E2DBC27DE157A43B7D.flac","trackType":"tidal"} Jan 13 21:01:13 volumio volumio[915]: verbose: CURRENT POSITION 0 Jan 13 21:01:13 volumio volumio[915]: info: CoreStateMachine::syncState stateService play Jan 13 21:01:13 volumio volumio[915]: info: CoreStateMachine::syncState currentStatus play Jan 13 21:01:13 volumio volumio[915]: info: Received an update from plugin. extracting info from payload Jan 13 21:01:13 volumio volumio[915]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 13 21:01:13 volumio volumio[915]: info: CoreStateMachine::pushState Jan 13 21:01:13 volumio volumio[915]: info: CoreCommandRouter::volumioPushState Jan 13 21:01:13 volumio volumio[915]: info: CoreStateMachine::pushState Jan 13 21:01:13 volumio volumio[915]: info: CoreCommandRouter::volumioPushState Jan 13 21:01:13 volumio volumio[915]: info: ------------------------------ 40ms Jan 13 21:01:13 volumio volumio[915]: info: ------------------------------ 39ms Jan 13 21:01:13 volumio volumio[915]: info: ------------------------------ 39ms Jan 13 21:01:13 volumio volumio[915]: info: [LastFM] Current track has sufficient metadata: title (Valerie) and artist (Steve Winwood) passed on explicitly Jan 13 21:01:13 volumio volumio[915]: info: CoreCommandRouter::volumioGetQueue Jan 13 21:01:13 volumio volumio[915]: info: CoreStateMachine::getQueue Jan 13 21:01:13 volumio volumio[915]: info: CorePlayQueue::getQueue Jan 13 21:01:13 volumio volumio[915]: info: CoreCommandRouter::volumioGetQueue Jan 13 21:01:13 volumio volumio[915]: info: CoreStateMachine::getQueue Jan 13 21:01:13 volumio volumio[915]: info: CorePlayQueue::getQueue Jan 13 21:01:13 volumio volumio[915]: info: CoreCommandRouter::volumioGetQueue Jan 13 21:01:13 volumio volumio[915]: info: CoreStateMachine::getQueue Jan 13 21:01:13 volumio volumio[915]: info: CorePlayQueue::getQueue Jan 13 21:01:13 volumio volumio[915]: info: CoreCommandRouter::volumioGetQueue Jan 13 21:01:13 volumio volumio[915]: info: CoreStateMachine::getQueue Jan 13 21:01:13 volumio volumio[915]: info: CorePlayQueue::getQueue Jan 13 21:01:13 volumio volumio[915]: info: CoreCommandRouter::volumioGetQueue Jan 13 21:01:13 volumio volumio[915]: info: CoreStateMachine::getQueue Jan 13 21:01:13 volumio volumio[915]: info: CorePlayQueue::getQueue Jan 13 21:01:13 volumio volumio[915]: info: CoreCommandRouter::volumioGetQueue Jan 13 21:01:13 volumio volumio[915]: info: CoreStateMachine::getQueue Jan 13 21:01:13 volumio volumio[915]: info: CorePlayQueue::getQueue Jan 13 21:01:13 volumio volumio[915]: SPOTIFY: RECEIVED VOLUMIO VOLUME 34 Jan 13 21:01:13 volumio volumio[915]: SPOTIFY: RECEIVED VOLUMIO VOLUME 34 Jan 13 21:01:13 volumio volumio[915]: SPOTIFY: RECEIVED VOLUMIO VOLUME 34 Jan 13 21:01:13 volumio volumio[915]: SPOTIFY: RECEIVED VOLUMIO VOLUME 34 Jan 13 21:01:13 volumio volumio[915]: SPOTIFY: RECEIVED VOLUMIO VOLUME 34 Jan 13 21:01:13 volumio volumio[915]: SPOTIFY: RECEIVED VOLUMIO VOLUME 34 Jan 13 21:01:13 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 13 21:01:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17655. Jan 13 21:01:13 volumio systemd[1]: Stopped go-librespot Daemon. Jan 13 21:01:13 volumio systemd[1]: Started go-librespot Daemon. Jan 13 21:01:13 volumio go-librespot[28800]: Librespot-go daemon starting... Jan 13 21:01:13 volumio go-librespot[28800]: time="2026-01-13T21:01:13+01:00" level=info msg="generated new device id: b5a1435f011bb807d8225d9c0cd5882266f85165" Jan 13 21:01:14 volumio go-librespot[28800]: time="2026-01-13T21:01:13+01:00" level=debug msg="stored credentials found for 1fvz1" Jan 13 21:01:14 volumio go-librespot[28800]: time="2026-01-13T21:01:14+01:00" level=debug msg="obtained new client token: AAB61C6GLpLRH1Tv3rMxzh9KSjs8VujcR7b/fcmJgkmhHCN/Adwc53oaLh6/Xev/pCotoHBENFAYt+htiUWrwumCOPr3zHl2TTcL/5lsyHaaC2Jefih3d2STWVgSym6k1mHKRZDZ/s00TD4rJDBVfeIGTUcemajWhWqF0Xw3ot/2+kFdhCxfFGAI45R3h/nTxe5MmpykzhDVjQ2lMNZ+/zGB1XQz5nVeVVDAtGBlScSQ3YMhDdxtNi/XQX1H5M9a" Jan 13 21:01:14 volumio go-librespot[28800]: time="2026-01-13T21:01: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-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Jan 13 21:01:14 volumio go-librespot[28800]: time="2026-01-13T21:01:14+01:00" level=debug msg="completed keyexchange" Jan 13 21:01:15 volumio go-librespot[28800]: time="2026-01-13T21:01:15+01:00" level=debug msg="completed challenge" Jan 13 21:01:15 volumio go-librespot[28800]: time="2026-01-13T21:01:15+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 13 21:01:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 13 21:01:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 13 21:01:15 volumio volumio[915]: info: Initializing connection to go-librespot Websocket Jan 13 21:01:15 volumio volumio[915]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 13 21:01:18 volumio volumio[915]: info: Initializing connection to go-librespot Websocket Jan 13 21:01:18 volumio volumio[915]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 13 21:01:18 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 13 21:01:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17656. Jan 13 21:01:18 volumio systemd[1]: Stopped go-librespot Daemon. Jan 13 21:01:18 volumio systemd[1]: Started go-librespot Daemon. Jan 13 21:01:18 volumio go-librespot[28819]: Librespot-go daemon starting... Jan 13 21:01:18 volumio go-librespot[28819]: time="2026-01-13T21:01:18+01:00" level=info msg="generated new device id: 26bbc7f0dd7eba67b720a18ef83ab0be2b1d4081" Jan 13 21:01:18 volumio go-librespot[28819]: time="2026-01-13T21:01:18+01:00" level=debug msg="stored credentials found for 1fvz1" Jan 13 21:01:18 volumio go-librespot[28819]: time="2026-01-13T21:01:18+01:00" level=debug msg="obtained new client token: AAD0tjVZhmwtwe7dmYmLWSObhUda/DVzo5D9yTTyjlw13QHnmasIIp0PWAe98LTtmhFnI3gkNbLYRbt9KoFwUF+3AQvPxHaRYeirQrxl3ShKSuMT0E5Oc3GiQ9olWAQSWlpZxJRqFg7npLdtxRgfz/Q5wZet2r43zvwSM9Awj3iTnH68drpFLisi1i/TEE6HLxBcjvmXkbi9b2HsAL9gyCjfKruXZe79YM3mPHo/PP0C7h6ClBTomhyJPowRYT2R" Jan 13 21:01:18 volumio go-librespot[28819]: time="2026-01-13T21:01: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]" Jan 13 21:01:18 volumio go-librespot[28819]: time="2026-01-13T21:01:18+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Jan 13 21:01:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 13 21:01:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 13 21:01:21 volumio volumio[915]: info: Initializing connection to go-librespot Websocket Jan 13 21:01:21 volumio volumio[915]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 13 21:01:22 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 13 21:01:22 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17657. Jan 13 21:01:22 volumio systemd[1]: Stopped go-librespot Daemon. Jan 13 21:01:22 volumio systemd[1]: Started go-librespot Daemon. Jan 13 21:01:22 volumio go-librespot[28854]: Librespot-go daemon starting... Jan 13 21:01:22 volumio go-librespot[28854]: time="2026-01-13T21:01:22+01:00" level=info msg="generated new device id: f4f8bff7480e3216800d93f9805143b966b9c995" Jan 13 21:01:22 volumio go-librespot[28854]: time="2026-01-13T21:01:22+01:00" level=debug msg="stored credentials found for 1fvz1" Jan 13 21:01:22 volumio volumio[915]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 13 21:01:22 volumio volumio[915]: TypeError: Cannot read property '@' of undefined Jan 13 21:01:22 volumio volumio[915]: at Object.callback (/data/plugins/user_interface/lastfm/node_modules/simple-lastfm/lib/index.js:342:14) Jan 13 21:01:22 volumio volumio[915]: at /data/plugins/user_interface/lastfm/node_modules/simple-lastfm/lib/index.js:60:13 Jan 13 21:01:22 volumio volumio[915]: at Parser. (/data/plugins/user_interface/lastfm/node_modules/xml2js/lib/parser.js:308:18) Jan 13 21:01:22 volumio volumio[915]: at Parser.emit (events.js:315:20) Jan 13 21:01:22 volumio volumio[915]: at SAXParser.onerror (/data/plugins/user_interface/lastfm/node_modules/xml2js/lib/parser.js:123:26) Jan 13 21:01:22 volumio volumio[915]: at emit (/data/plugins/user_interface/lastfm/node_modules/sax/lib/sax.js:624:35) Jan 13 21:01:22 volumio volumio[915]: at error (/data/plugins/user_interface/lastfm/node_modules/sax/lib/sax.js:653:5) Jan 13 21:01:22 volumio volumio[915]: at strictFail (/data/plugins/user_interface/lastfm/node_modules/sax/lib/sax.js:677:7) Jan 13 21:01:22 volumio volumio[915]: at closeTag (/data/plugins/user_interface/lastfm/node_modules/sax/lib/sax.js:871:9) Jan 13 21:01:22 volumio volumio[915]: at SAXParser.write (/data/plugins/user_interface/lastfm/node_modules/sax/lib/sax.js:1436:13) Jan 13 21:01:22 volumio volumio[915]: at Parser.exports.Parser.Parser.parseString (/data/plugins/user_interface/lastfm/node_modules/xml2js/lib/parser.js:323:31) Jan 13 21:01:22 volumio volumio[915]: at Parser.parseString (/data/plugins/user_interface/lastfm/node_modules/xml2js/lib/parser.js:5:59) Jan 13 21:01:22 volumio volumio[915]: at IncomingMessage. (/data/plugins/user_interface/lastfm/node_modules/simple-lastfm/lib/index.js:58:14) Jan 13 21:01:22 volumio volumio[915]: at IncomingMessage.emit (events.js:327:22) Jan 13 21:01:22 volumio volumio[915]: at endReadableNT (internal/streams/readable.js:1327:12) Jan 13 21:01:22 volumio volumio[915]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jan 13 21:01:22 volumio volumio[915]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 13 21:01:22 volumio go-librespot[28854]: time="2026-01-13T21:01:22+01:00" level=debug msg="obtained new client token: AACvtAZykKia6N5f98O5p6rh7fxltzZO80s6ibCI2iVIw40tDZ8ofxNf4/c2w+ez/GRitN6NVAJnqv9847F4shaJpvQvRjEBJ5yE9wILlcFS9fX3WN4L0at6G3fpTPLuzSs/UR6rwNlgZJKK14gHRYV8XlRuijCzUvJuG80rML+on5JnF7WzwF5n/x0KRo2gaZhwPaYqQAYmHk+il1rBaRS3Bx1vVIlgrlpMS16wZ2v0tS2x5tuoog9VPfFvm2fW" Jan 13 21:01:22 volumio go-librespot[28854]: time="2026-01-13T21:01: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Jan 13 21:01:22 volumio go-librespot[28854]: time="2026-01-13T21:01:22+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Jan 13 21:01:22 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 13 21:01:22 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 13 21:01:24 volumio sudo[28874]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-01-13 21:00 Jan 13 21:01:24 volumio sudo[28874]: 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="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 05:43:24 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="15d0241b0cd805792809f3ddf4a0fc4e"