-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Sun 2025-08-31 11:49:46 CEST. -- Aug 31 11:48:02 volumio volumio[1054]: info: Initializing connection to go-librespot Websocket Aug 31 11:48:02 volumio volumio[1054]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 31 11:48:02 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 31 11:48:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 194. Aug 31 11:48:02 volumio systemd[1]: Stopped go-librespot Daemon. Aug 31 11:48:02 volumio systemd[1]: Started go-librespot Daemon. Aug 31 11:48:02 volumio go-librespot[7415]: go-librespot daemon starting... Aug 31 11:48:02 volumio go-librespot[7415]: time="2025-08-31T11:48:02+02:00" level=info msg="running go-librespot 0.2.0" Aug 31 11:48:02 volumio go-librespot[7415]: time="2025-08-31T11:48:02+02:00" level=debug msg="app state loaded" Aug 31 11:48:02 volumio go-librespot[7415]: time="2025-08-31T11:48:02+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 31 11:48:03 volumio go-librespot[7415]: time="2025-08-31T11:48:03+02: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]" Aug 31 11:48:03 volumio go-librespot[7415]: time="2025-08-31T11:48:03+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Aug 31 11:48:03 volumio go-librespot[7415]: time="2025-08-31T11:48:03+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Aug 31 11:48:03 volumio go-librespot[7415]: time="2025-08-31T11:48:03+02:00" level=info msg="zeroconf server listening on port 36963" Aug 31 11:48:03 volumio go-librespot[7415]: time="2025-08-31T11:48:03+02:00" level=debug msg="obtained new client token: AAAQGgccMWZBOwAy1mg3oxjKE99ZAOOkmcEK1Wjxk5O5pGX4nheVazBa3PPPy6bd/lA433OP3sNvMl/bP8Lnx2flZ+IzLjPz8NydgDR1MFSD3GwhpNoPtwwJnwYh4/jvKIrUXS6ruUZjKMTmE84Wtc3o28D9s20N49GCULTxQg068Rq4zJlhBGlMFwpqmXBWSjR+3iH3NrCqsovY5mzqJ8Ea9kMfIHoKAa148Rps3J+u1UpJVS6UV5F2" Aug 31 11:48:03 volumio go-librespot[7415]: time="2025-08-31T11:48:03+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 31 11:48:03 volumio go-librespot[7415]: time="2025-08-31T11:48:03+02:00" level=debug msg="completed keyexchange" Aug 31 11:48:03 volumio go-librespot[7415]: time="2025-08-31T11:48:03+02:00" level=debug msg="completed challenge" Aug 31 11:48:03 volumio go-librespot[7415]: time="2025-08-31T11:48:03+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Aug 31 11:48:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 31 11:48:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 31 11:48:05 volumio volumio[1054]: info: Initializing connection to go-librespot Websocket Aug 31 11:48:05 volumio volumio[1054]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 31 11:48:06 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 31 11:48:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 195. Aug 31 11:48:06 volumio systemd[1]: Stopped go-librespot Daemon. Aug 31 11:48:06 volumio systemd[1]: Started go-librespot Daemon. Aug 31 11:48:06 volumio go-librespot[7438]: go-librespot daemon starting... Aug 31 11:48:06 volumio go-librespot[7438]: time="2025-08-31T11:48:06+02:00" level=info msg="running go-librespot 0.2.0" Aug 31 11:48:06 volumio go-librespot[7438]: time="2025-08-31T11:48:06+02:00" level=debug msg="app state loaded" Aug 31 11:48:06 volumio go-librespot[7438]: time="2025-08-31T11:48:06+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 31 11:48:06 volumio go-librespot[7438]: time="2025-08-31T11:48:06+02: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]" Aug 31 11:48:06 volumio go-librespot[7438]: time="2025-08-31T11:48:06+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 31 11:48:06 volumio go-librespot[7438]: time="2025-08-31T11:48:06+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 31 11:48:06 volumio go-librespot[7438]: time="2025-08-31T11:48:06+02:00" level=info msg="zeroconf server listening on port 45081" Aug 31 11:48:07 volumio go-librespot[7438]: time="2025-08-31T11:48:07+02:00" level=debug msg="obtained new client token: AADEQfV0rpgq05JqAzvCwp8xLrvEpzVSWc+NPDivGXyXtBmr6aANB+5k4Kvr/Z1DTt/H4ridQcULWrDI18Vzm2E/HDcccO0zbFs0Vmsk+HgCVJUnW4vdx0XHHM0OUxClvYNfPdmHoPz2dzO6Q3jARYq75BamYubmWqWFSUY57FSnvjL6B9ia2MjiOvBcF0mdKf5kJmWCRjJ+P6rt/Xh7HO5nHolwZD7wFPXoaI5xAxsaAlzI1ZaK/w==" Aug 31 11:48:07 volumio go-librespot[7438]: time="2025-08-31T11:48:07+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 31 11:48:07 volumio go-librespot[7438]: time="2025-08-31T11:48:07+02:00" level=debug msg="completed keyexchange" Aug 31 11:48:07 volumio go-librespot[7438]: time="2025-08-31T11:48:07+02:00" level=debug msg="completed challenge" Aug 31 11:48:07 volumio go-librespot[7438]: time="2025-08-31T11:48:07+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Aug 31 11:48:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 31 11:48:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 31 11:48:08 volumio volumio[1054]: info: Initializing connection to go-librespot Websocket Aug 31 11:48:08 volumio volumio[1054]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 31 11:48:10 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 31 11:48:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 196. Aug 31 11:48:10 volumio systemd[1]: Stopped go-librespot Daemon. Aug 31 11:48:10 volumio systemd[1]: Started go-librespot Daemon. Aug 31 11:48:10 volumio go-librespot[7446]: go-librespot daemon starting... Aug 31 11:48:10 volumio go-librespot[7446]: time="2025-08-31T11:48:10+02:00" level=info msg="running go-librespot 0.2.0" Aug 31 11:48:10 volumio go-librespot[7446]: time="2025-08-31T11:48:10+02:00" level=debug msg="app state loaded" Aug 31 11:48:10 volumio go-librespot[7446]: time="2025-08-31T11:48:10+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 31 11:48:10 volumio go-librespot[7446]: time="2025-08-31T11:48:10+02: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]" Aug 31 11:48:10 volumio go-librespot[7446]: time="2025-08-31T11:48:10+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 31 11:48:10 volumio go-librespot[7446]: time="2025-08-31T11:48:10+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 31 11:48:10 volumio go-librespot[7446]: time="2025-08-31T11:48:10+02:00" level=info msg="zeroconf server listening on port 38395" Aug 31 11:48:10 volumio go-librespot[7446]: time="2025-08-31T11:48:10+02:00" level=debug msg="obtained new client token: AAAH0WpNCKyxAgIQDSe6f3x5dkjizH7lVVDmAgGriEgY03XGv7BqIgFDTGvdroLzieRNq5rHSOX+v8j6r0YxCyzCmWovnW7vy1NxlIsB4gRrrIvvPQKx8QgGJfobgnKl4OddGHeVdFhdIoiLtVIfDQPUgwZCoOhqKcGpyJUHK5Jgd1dH2oj7qpbCQocxBbvW2VIdHblgDWCtyNi8Zg4vlKS3OsIUow4eRTjC1J54PSfJkzhjVOgFXc9l" Aug 31 11:48:10 volumio go-librespot[7446]: time="2025-08-31T11:48:10+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 31 11:48:10 volumio go-librespot[7446]: time="2025-08-31T11:48:10+02:00" level=debug msg="completed keyexchange" Aug 31 11:48:10 volumio go-librespot[7446]: time="2025-08-31T11:48:10+02:00" level=debug msg="completed challenge" Aug 31 11:48:10 volumio go-librespot[7446]: time="2025-08-31T11:48:10+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Aug 31 11:48:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 31 11:48:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 31 11:48:11 volumio volumio[1054]: info: Initializing connection to go-librespot Websocket Aug 31 11:48:11 volumio volumio[1054]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 31 11:48:13 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 31 11:48:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 197. Aug 31 11:48:13 volumio systemd[1]: Stopped go-librespot Daemon. Aug 31 11:48:13 volumio systemd[1]: Started go-librespot Daemon. Aug 31 11:48:13 volumio go-librespot[7469]: go-librespot daemon starting... Aug 31 11:48:13 volumio go-librespot[7469]: time="2025-08-31T11:48:13+02:00" level=info msg="running go-librespot 0.2.0" Aug 31 11:48:13 volumio go-librespot[7469]: time="2025-08-31T11:48:13+02:00" level=debug msg="app state loaded" Aug 31 11:48:13 volumio go-librespot[7469]: time="2025-08-31T11:48:13+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 31 11:48:14 volumio go-librespot[7469]: time="2025-08-31T11:48:14+02: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]" Aug 31 11:48:14 volumio go-librespot[7469]: time="2025-08-31T11:48:14+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 31 11:48:14 volumio go-librespot[7469]: time="2025-08-31T11:48:14+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 31 11:48:14 volumio go-librespot[7469]: time="2025-08-31T11:48:14+02:00" level=info msg="zeroconf server listening on port 41007" Aug 31 11:48:14 volumio go-librespot[7469]: time="2025-08-31T11:48:14+02:00" level=debug msg="obtained new client token: AABL8fdCti0YGlTIUIdnx/u7NM/9iP5u7RsvS3y7fYPiRO6zC8XRUq3LWR7FfakzQUtemi259Xky0NLy0lAEz+FI1mKckXA3FD8H3doPTtcDMMRv91pzWQ2+LZLDJAzcoFiAo4zmEPht7drPrpG+N8ylpIZxGv4BEoQTH8+RlEyocbdE52iA/9UmiO1vrxdqu0ZKT517lGTY1YCmgBak9vFE0WzlAQn3KlTtdgzNaHEUQYRsYuGlmg==" Aug 31 11:48:14 volumio go-librespot[7469]: time="2025-08-31T11:48:14+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 31 11:48:14 volumio go-librespot[7469]: time="2025-08-31T11:48:14+02:00" level=debug msg="completed keyexchange" Aug 31 11:48:14 volumio go-librespot[7469]: time="2025-08-31T11:48:14+02:00" level=debug msg="completed challenge" Aug 31 11:48:14 volumio go-librespot[7469]: time="2025-08-31T11:48:14+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Aug 31 11:48:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 31 11:48:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 31 11:48:14 volumio volumio[1054]: info: Initializing connection to go-librespot Websocket Aug 31 11:48:14 volumio volumio[1054]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 31 11:48:17 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 31 11:48:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 198. Aug 31 11:48:17 volumio systemd[1]: Stopped go-librespot Daemon. Aug 31 11:48:17 volumio systemd[1]: Started go-librespot Daemon. Aug 31 11:48:17 volumio go-librespot[7478]: go-librespot daemon starting... Aug 31 11:48:17 volumio volumio[1054]: info: Initializing connection to go-librespot Websocket Aug 31 11:48:17 volumio go-librespot[7478]: time="2025-08-31T11:48:17+02:00" level=info msg="running go-librespot 0.2.0" Aug 31 11:48:17 volumio go-librespot[7478]: time="2025-08-31T11:48:17+02:00" level=debug msg="app state loaded" Aug 31 11:48:17 volumio volumio[1054]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 31 11:48:17 volumio go-librespot[7478]: time="2025-08-31T11:48:17+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 31 11:48:17 volumio go-librespot[7478]: time="2025-08-31T11:48:17+02: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]" Aug 31 11:48:17 volumio go-librespot[7478]: time="2025-08-31T11:48:17+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 31 11:48:17 volumio go-librespot[7478]: time="2025-08-31T11:48:17+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 31 11:48:17 volumio go-librespot[7478]: time="2025-08-31T11:48:17+02:00" level=info msg="zeroconf server listening on port 39653" Aug 31 11:48:18 volumio go-librespot[7478]: time="2025-08-31T11:48:18+02:00" level=debug msg="obtained new client token: AAAXWGFtJMRmgVli7U5vDOqh6d+V8icx7oNBmM3aDyCjGxIKWyYIGeg6f/a/lx/yMwShFusENY8Iu00L/bb53k+uUIg3VU2KjUogPCOPT93UGW03ZczqLCIRc99Rkfh08FnmqxYxZ3R9ecwA1RxmOBW9PS5vo946IQcw0yvRDVPZz5VC2t47My4DjwE1L1BBKkkrr39BAlG4GK0aBE6RnAz0HVheQ7agpjbfafUobmOSduwKWy0yTQ==" Aug 31 11:48:18 volumio go-librespot[7478]: time="2025-08-31T11:48:18+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 31 11:48:18 volumio go-librespot[7478]: time="2025-08-31T11:48:18+02:00" level=debug msg="completed keyexchange" Aug 31 11:48:18 volumio go-librespot[7478]: time="2025-08-31T11:48:18+02:00" level=debug msg="completed challenge" Aug 31 11:48:18 volumio go-librespot[7478]: time="2025-08-31T11:48:18+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Aug 31 11:48:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 31 11:48:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 31 11:48:20 volumio volumio[1054]: info: Initializing connection to go-librespot Websocket Aug 31 11:48:20 volumio volumio[1054]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 31 11:48:21 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 31 11:48:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 199. Aug 31 11:48:21 volumio systemd[1]: Stopped go-librespot Daemon. Aug 31 11:48:21 volumio systemd[1]: Started go-librespot Daemon. Aug 31 11:48:21 volumio go-librespot[7489]: go-librespot daemon starting... Aug 31 11:48:21 volumio go-librespot[7489]: time="2025-08-31T11:48:21+02:00" level=info msg="running go-librespot 0.2.0" Aug 31 11:48:21 volumio go-librespot[7489]: time="2025-08-31T11:48:21+02:00" level=debug msg="app state loaded" Aug 31 11:48:21 volumio go-librespot[7489]: time="2025-08-31T11:48:21+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 31 11:48:21 volumio go-librespot[7489]: time="2025-08-31T11:48:21+02: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]" Aug 31 11:48:21 volumio go-librespot[7489]: time="2025-08-31T11:48:21+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Aug 31 11:48:21 volumio go-librespot[7489]: time="2025-08-31T11:48:21+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Aug 31 11:48:21 volumio go-librespot[7489]: time="2025-08-31T11:48:21+02:00" level=info msg="zeroconf server listening on port 42439" Aug 31 11:48:21 volumio go-librespot[7489]: time="2025-08-31T11:48:21+02:00" level=debug msg="obtained new client token: AACN+zeVJkTVN0kdcySiUIr2Bmd0S1kBBhLiz6+0M8CWzLbc1V/pJS1sgUANSc5jl5nXqZpXzop/rmTvTX5acTA0flJy1lp1iD7nxWB/8EHJCFdoNxK05QiMtwcAIVyNxzr5fH8qp13mSfsC8khTqWfglHpCrtV8vX6FBmXSUbE2xLkC6FA+7iGnOxGFox8VX0n/Lydf0nyfheXHbQg2gQJPJ71HZYIKOL3IGAgufwwPBiu43Gd4vex5" Aug 31 11:48:21 volumio go-librespot[7489]: time="2025-08-31T11:48:21+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 31 11:48:21 volumio go-librespot[7489]: time="2025-08-31T11:48:21+02:00" level=debug msg="completed keyexchange" Aug 31 11:48:21 volumio go-librespot[7489]: time="2025-08-31T11:48:21+02:00" level=debug msg="completed challenge" Aug 31 11:48:21 volumio go-librespot[7489]: time="2025-08-31T11:48:21+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Aug 31 11:48:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 31 11:48:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 31 11:48:23 volumio volumio[1054]: info: Initializing connection to go-librespot Websocket Aug 31 11:48:23 volumio volumio[1054]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 31 11:48:24 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 31 11:48:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 200. Aug 31 11:48:24 volumio systemd[1]: Stopped go-librespot Daemon. Aug 31 11:48:24 volumio systemd[1]: Started go-librespot Daemon. Aug 31 11:48:24 volumio go-librespot[7511]: go-librespot daemon starting... Aug 31 11:48:24 volumio go-librespot[7511]: time="2025-08-31T11:48:24+02:00" level=info msg="running go-librespot 0.2.0" Aug 31 11:48:24 volumio go-librespot[7511]: time="2025-08-31T11:48:24+02:00" level=debug msg="app state loaded" Aug 31 11:48:24 volumio go-librespot[7511]: time="2025-08-31T11:48:24+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 31 11:48:24 volumio go-librespot[7511]: time="2025-08-31T11:48:24+02: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]" Aug 31 11:48:24 volumio go-librespot[7511]: time="2025-08-31T11:48:24+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Aug 31 11:48:24 volumio go-librespot[7511]: time="2025-08-31T11:48:24+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Aug 31 11:48:24 volumio go-librespot[7511]: time="2025-08-31T11:48:24+02:00" level=info msg="zeroconf server listening on port 46195" Aug 31 11:48:25 volumio go-librespot[7511]: time="2025-08-31T11:48:25+02:00" level=debug msg="obtained new client token: AADz2ggDRUIJZW06Ta8r8+6C0rYhq4XQJ6BruOvQSHNBnhld7N8QwwT1lN2paxWHwC0zCgDbUW+y7Zcn4q31V2kDKuShOnQIXpTu/jmxSTfnwrgFrha1Lx2m4UK3pYumjwjEfHizsjCe+zqp+1AeiyN3QUNRnN5T1eDSRLBdAWVeWESGuSRKe+wurFqQ/azVYu53ftgutKn9bi9yab9qySF70mBZLYMWX1llFQf1Uh+WDjh9hpAYwg==" Aug 31 11:48:25 volumio go-librespot[7511]: time="2025-08-31T11:48:25+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 31 11:48:25 volumio go-librespot[7511]: time="2025-08-31T11:48:25+02:00" level=debug msg="completed keyexchange" Aug 31 11:48:25 volumio go-librespot[7511]: time="2025-08-31T11:48:25+02:00" level=debug msg="completed challenge" Aug 31 11:48:25 volumio go-librespot[7511]: time="2025-08-31T11:48:25+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Aug 31 11:48:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 31 11:48:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 31 11:48:26 volumio volumio[1054]: info: Initializing connection to go-librespot Websocket Aug 31 11:48:26 volumio volumio[1054]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 31 11:48:28 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 31 11:48:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 201. Aug 31 11:48:28 volumio systemd[1]: Stopped go-librespot Daemon. Aug 31 11:48:28 volumio systemd[1]: Started go-librespot Daemon. Aug 31 11:48:28 volumio go-librespot[7519]: go-librespot daemon starting... Aug 31 11:48:28 volumio go-librespot[7519]: time="2025-08-31T11:48:28+02:00" level=info msg="running go-librespot 0.2.0" Aug 31 11:48:28 volumio go-librespot[7519]: time="2025-08-31T11:48:28+02:00" level=debug msg="app state loaded" Aug 31 11:48:28 volumio go-librespot[7519]: time="2025-08-31T11:48:28+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 31 11:48:28 volumio go-librespot[7519]: time="2025-08-31T11:48:28+02: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]" Aug 31 11:48:28 volumio go-librespot[7519]: time="2025-08-31T11:48:28+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Aug 31 11:48:28 volumio go-librespot[7519]: time="2025-08-31T11:48:28+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Aug 31 11:48:28 volumio go-librespot[7519]: time="2025-08-31T11:48:28+02:00" level=info msg="zeroconf server listening on port 45747" Aug 31 11:48:28 volumio go-librespot[7519]: time="2025-08-31T11:48:28+02:00" level=debug msg="obtained new client token: AACXtXohC11FqYshUyJI5kGgp5vW9tw0+a6kSPXeD8FR8SXLE5UJHf6Uhpu1FPBkusg49DiZ9hhvre+fMd2HGGmJGahkPtyjFkE6hsOjNVmUVQBa4+GQ+F8wZEGIS1Uh2YUdv/a6+B+C0PBjvlJEKfUFoNvNWeCX0WJwJ4LierpeifD1CnnJFCYIBjYuV8BnimgtrRXsae7T3wDsJVfEBHiT5gwXdNA/BiDHfAilzfaOidAnOZaPZH6Y" Aug 31 11:48:28 volumio go-librespot[7519]: time="2025-08-31T11:48:28+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 31 11:48:28 volumio go-librespot[7519]: time="2025-08-31T11:48:28+02:00" level=debug msg="completed keyexchange" Aug 31 11:48:28 volumio go-librespot[7519]: time="2025-08-31T11:48:28+02:00" level=debug msg="completed challenge" Aug 31 11:48:28 volumio go-librespot[7519]: time="2025-08-31T11:48:28+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Aug 31 11:48:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 31 11:48:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 31 11:48:29 volumio volumio[1054]: info: Initializing connection to go-librespot Websocket Aug 31 11:48:29 volumio volumio[1054]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 31 11:48:31 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 31 11:48:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 202. Aug 31 11:48:31 volumio systemd[1]: Stopped go-librespot Daemon. Aug 31 11:48:31 volumio systemd[1]: Started go-librespot Daemon. Aug 31 11:48:31 volumio go-librespot[7529]: go-librespot daemon starting... Aug 31 11:48:31 volumio go-librespot[7529]: time="2025-08-31T11:48:31+02:00" level=info msg="running go-librespot 0.2.0" Aug 31 11:48:31 volumio go-librespot[7529]: time="2025-08-31T11:48:31+02:00" level=debug msg="app state loaded" Aug 31 11:48:31 volumio go-librespot[7529]: time="2025-08-31T11:48:31+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 31 11:48:32 volumio go-librespot[7529]: time="2025-08-31T11:48:32+02: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]" Aug 31 11:48:32 volumio go-librespot[7529]: time="2025-08-31T11:48:32+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 31 11:48:32 volumio go-librespot[7529]: time="2025-08-31T11:48:32+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 31 11:48:32 volumio go-librespot[7529]: time="2025-08-31T11:48:32+02:00" level=info msg="zeroconf server listening on port 34197" Aug 31 11:48:32 volumio go-librespot[7529]: time="2025-08-31T11:48:32+02:00" level=debug msg="obtained new client token: AABoTB70aQWNbeSC8bylCgiC+5MMQw9Y5XGHqsZ7SNiOBleaaTwylEU5p3S/6qlbln11cnshU+gZh1X3aLjkyifGwHuzzuXVaIrcxQjDN9NiKkPjrRIc4CFVM/DLBTwLV5jqAqU1YO6Uou+TGnnMbzE4V7A4H4jPzlHwLUTr4VMP207cro+wp6IOZdJEJPjxFMUwDJXIDMQuGTm+6ftTO0k9UDQdhedJwg00+rdbIr6I1ZfbRLWTMw==" Aug 31 11:48:32 volumio go-librespot[7529]: time="2025-08-31T11:48:32+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 31 11:48:32 volumio go-librespot[7529]: time="2025-08-31T11:48:32+02:00" level=debug msg="completed keyexchange" Aug 31 11:48:32 volumio go-librespot[7529]: time="2025-08-31T11:48:32+02:00" level=debug msg="completed challenge" Aug 31 11:48:32 volumio go-librespot[7529]: time="2025-08-31T11:48:32+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Aug 31 11:48:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 31 11:48:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 31 11:48:32 volumio volumio[1054]: info: Initializing connection to go-librespot Websocket Aug 31 11:48:32 volumio volumio[1054]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 31 11:48:35 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 31 11:48:35 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 203. Aug 31 11:48:35 volumio systemd[1]: Stopped go-librespot Daemon. Aug 31 11:48:35 volumio systemd[1]: Started go-librespot Daemon. Aug 31 11:48:35 volumio go-librespot[7552]: go-librespot daemon starting... Aug 31 11:48:35 volumio go-librespot[7552]: time="2025-08-31T11:48:35+02:00" level=info msg="running go-librespot 0.2.0" Aug 31 11:48:35 volumio go-librespot[7552]: time="2025-08-31T11:48:35+02:00" level=debug msg="app state loaded" Aug 31 11:48:35 volumio go-librespot[7552]: time="2025-08-31T11:48:35+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 31 11:48:35 volumio go-librespot[7552]: time="2025-08-31T11:48:35+02: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]" Aug 31 11:48:35 volumio go-librespot[7552]: time="2025-08-31T11:48:35+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 31 11:48:35 volumio go-librespot[7552]: time="2025-08-31T11:48:35+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 31 11:48:35 volumio go-librespot[7552]: time="2025-08-31T11:48:35+02:00" level=info msg="zeroconf server listening on port 46095" Aug 31 11:48:35 volumio volumio[1054]: info: Initializing connection to go-librespot Websocket Aug 31 11:48:35 volumio go-librespot[7552]: time="2025-08-31T11:48:35+02:00" level=debug msg="new websocket client" Aug 31 11:48:35 volumio volumio[1054]: info: Connection to go-librespot Websocket established Aug 31 11:48:35 volumio go-librespot[7552]: time="2025-08-31T11:48:35+02:00" level=debug msg="obtained new client token: AAB+7mlcgSaj2LADWDtph9UfLEFe9/Ro4lRZ47EPT3d8P+VmGMmUltJlap4x69Bky2uUxAj5II9okKGKTbDYiu7p4ooZaFDpK30/5p/eAwBdx0FoiAucQ6mXUFSKchbzIzYr/Lt4d6b2NNodk0EVWIIwzN4batOc74voQ1YWSc6li7Ah5EKuzH7bOYwNtzpLukP55v8SXt2e3AeJZZBnqxCQsXzhC9lQXXZ4pi9OYdF9fH6q0vfW+tJq" Aug 31 11:48:35 volumio go-librespot[7552]: time="2025-08-31T11:48:35+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 31 11:48:35 volumio go-librespot[7552]: time="2025-08-31T11:48:35+02:00" level=debug msg="completed keyexchange" Aug 31 11:48:35 volumio go-librespot[7552]: time="2025-08-31T11:48:35+02:00" level=debug msg="completed challenge" Aug 31 11:48:35 volumio go-librespot[7552]: time="2025-08-31T11:48:35+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Aug 31 11:48:35 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 31 11:48:35 volumio volumio[1054]: info: Connection to go-librespot Websocket closed Aug 31 11:48:35 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 31 11:48:38 volumio volumio[1054]: info: Getting Spotify volume Aug 31 11:48:38 volumio volumio[1054]: (node:1054) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 31 11:48:38 volumio volumio[1054]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Aug 31 11:48:38 volumio volumio[1054]: (node:1054) 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: 20) Aug 31 11:48:38 volumio volumio[1054]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Aug 31 11:48:38 volumio volumio[1054]: info: CoreCommandRouter::volumioGetState Aug 31 11:48:38 volumio volumio[1054]: info: CorePlayQueue::getTrack 0 Aug 31 11:48:38 volumio volumio[1054]: info: Initializing connection to go-librespot Websocket Aug 31 11:48:38 volumio volumio[1054]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 31 11:48:39 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 31 11:48:39 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 204. Aug 31 11:48:39 volumio systemd[1]: Stopped go-librespot Daemon. Aug 31 11:48:39 volumio systemd[1]: Started go-librespot Daemon. Aug 31 11:48:39 volumio go-librespot[7604]: go-librespot daemon starting... Aug 31 11:48:39 volumio go-librespot[7604]: time="2025-08-31T11:48:39+02:00" level=info msg="running go-librespot 0.2.0" Aug 31 11:48:39 volumio go-librespot[7604]: time="2025-08-31T11:48:39+02:00" level=debug msg="app state loaded" Aug 31 11:48:39 volumio go-librespot[7604]: time="2025-08-31T11:48:39+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 31 11:48:39 volumio go-librespot[7604]: time="2025-08-31T11:48:39+02: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]" Aug 31 11:48:39 volumio go-librespot[7604]: time="2025-08-31T11:48:39+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 31 11:48:39 volumio go-librespot[7604]: time="2025-08-31T11:48:39+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 31 11:48:39 volumio go-librespot[7604]: time="2025-08-31T11:48:39+02:00" level=info msg="zeroconf server listening on port 34469" Aug 31 11:48:39 volumio go-librespot[7604]: time="2025-08-31T11:48:39+02:00" level=debug msg="obtained new client token: AABd8qXA5zo4RmjJF+xySKQ4vfsvKz3yhfdzhO6qQzTl6cAHeuLDLf7+hgmHrcysTuzAxDY5Vwo5CuDWIXqppCCkzS2+oKiT+QZ2ueTkDvx3e7gbf3wM3wZdAo1bEvkHHTVkOX9Gaouhou/Jwkzl3JaL65xggzUSo6ZM4ZA4n885K6W/up6tB0NK6yoBNhYCpfX8wSuFVn8zOC0dKjjLI/r5DfyzHBpjxzzu/SLibKLXpD8udOe4oAi/" Aug 31 11:48:39 volumio go-librespot[7604]: time="2025-08-31T11:48:39+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 31 11:48:39 volumio go-librespot[7604]: time="2025-08-31T11:48:39+02:00" level=debug msg="completed keyexchange" Aug 31 11:48:39 volumio go-librespot[7604]: time="2025-08-31T11:48:39+02:00" level=debug msg="completed challenge" Aug 31 11:48:39 volumio go-librespot[7604]: time="2025-08-31T11:48:39+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Aug 31 11:48:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 31 11:48:39 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 31 11:48:41 volumio volumio[1054]: info: Initializing connection to go-librespot Websocket Aug 31 11:48:41 volumio volumio[1054]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 31 11:48:42 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 31 11:48:42 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 205. Aug 31 11:48:42 volumio systemd[1]: Stopped go-librespot Daemon. Aug 31 11:48:42 volumio systemd[1]: Started go-librespot Daemon. Aug 31 11:48:42 volumio go-librespot[7612]: go-librespot daemon starting... Aug 31 11:48:42 volumio go-librespot[7612]: time="2025-08-31T11:48:42+02:00" level=info msg="running go-librespot 0.2.0" Aug 31 11:48:42 volumio go-librespot[7612]: time="2025-08-31T11:48:42+02:00" level=debug msg="app state loaded" Aug 31 11:48:42 volumio go-librespot[7612]: time="2025-08-31T11:48:42+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 31 11:48:43 volumio go-librespot[7612]: time="2025-08-31T11:48:43+02: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]" Aug 31 11:48:43 volumio go-librespot[7612]: time="2025-08-31T11:48:43+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 31 11:48:43 volumio go-librespot[7612]: time="2025-08-31T11:48:43+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 31 11:48:43 volumio go-librespot[7612]: time="2025-08-31T11:48:43+02:00" level=info msg="zeroconf server listening on port 41193" Aug 31 11:48:43 volumio go-librespot[7612]: time="2025-08-31T11:48:43+02:00" level=debug msg="obtained new client token: AADYyWEMXKtXLgBvHrGICcaBPiNv3sSuzo0J9FL0MBZn5p2O0zDCAIn+NbqKJdjVjo93Dodae3YUWRc0D4OOs1zYFWEUTP0HsdlMc06+PZ2rMPG+cD8xsQiHtc83ec9PbqV6QrpnxPBHkgewDwwgqp6aI0H0tL+PLML8ujZ4CZSQge88LbgbbZCBQapGe8OUuXoR/RYPulWK5WXKWh89zf3H3oCm7D845XBFZG4it3c7+mPA8nVMDQ==" Aug 31 11:48:43 volumio go-librespot[7612]: time="2025-08-31T11:48:43+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 31 11:48:43 volumio go-librespot[7612]: time="2025-08-31T11:48:43+02:00" level=debug msg="completed keyexchange" Aug 31 11:48:43 volumio go-librespot[7612]: time="2025-08-31T11:48:43+02:00" level=debug msg="completed challenge" Aug 31 11:48:43 volumio go-librespot[7612]: time="2025-08-31T11:48:43+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Aug 31 11:48:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 31 11:48:43 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 31 11:48:44 volumio volumio[1054]: info: Initializing connection to go-librespot Websocket Aug 31 11:48:44 volumio volumio[1054]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 31 11:48:46 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 31 11:48:46 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 206. Aug 31 11:48:46 volumio systemd[1]: Stopped go-librespot Daemon. Aug 31 11:48:46 volumio systemd[1]: Started go-librespot Daemon. Aug 31 11:48:46 volumio go-librespot[7635]: go-librespot daemon starting... Aug 31 11:48:46 volumio go-librespot[7635]: time="2025-08-31T11:48:46+02:00" level=info msg="running go-librespot 0.2.0" Aug 31 11:48:46 volumio go-librespot[7635]: time="2025-08-31T11:48:46+02:00" level=debug msg="app state loaded" Aug 31 11:48:46 volumio go-librespot[7635]: time="2025-08-31T11:48:46+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 31 11:48:46 volumio go-librespot[7635]: time="2025-08-31T11:48:46+02: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]" Aug 31 11:48:46 volumio go-librespot[7635]: time="2025-08-31T11:48:46+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 31 11:48:46 volumio go-librespot[7635]: time="2025-08-31T11:48:46+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 31 11:48:46 volumio go-librespot[7635]: time="2025-08-31T11:48:46+02:00" level=info msg="zeroconf server listening on port 36293" Aug 31 11:48:47 volumio go-librespot[7635]: time="2025-08-31T11:48:47+02:00" level=debug msg="obtained new client token: AAC6+zc8uu20LPyzvDCcYcurHViVnCOZNLbNk7BkUZ2dDkOj/t+BKZkzkNwkZt5D55jCzqbNhcGL5X1Tkg4Vl8/6QrpZo+emS+OWMnsou6fXovZ31TVit+uAUg5rYEdh0jm6ixnykyL2xk7Pkw8yWeORFE8I2Kbu5s3Tf89OINBxTQCf9MBEAZfOtv1smrtg4YUOQITOLE4rHCUMU4U3zXuu9eIuudWXzHJ8xB4P9uFLb/aXnjFihw==" Aug 31 11:48:47 volumio go-librespot[7635]: time="2025-08-31T11:48:47+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 31 11:48:47 volumio go-librespot[7635]: time="2025-08-31T11:48:47+02:00" level=debug msg="completed keyexchange" Aug 31 11:48:47 volumio go-librespot[7635]: time="2025-08-31T11:48:47+02:00" level=debug msg="completed challenge" Aug 31 11:48:47 volumio go-librespot[7635]: time="2025-08-31T11:48:47+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Aug 31 11:48:47 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 31 11:48:47 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 31 11:48:47 volumio volumio[1054]: info: Initializing connection to go-librespot Websocket Aug 31 11:48:47 volumio volumio[1054]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 31 11:48:50 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 31 11:48:50 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 207. Aug 31 11:48:50 volumio systemd[1]: Stopped go-librespot Daemon. Aug 31 11:48:50 volumio systemd[1]: Started go-librespot Daemon. Aug 31 11:48:50 volumio go-librespot[7643]: go-librespot daemon starting... Aug 31 11:48:50 volumio go-librespot[7643]: time="2025-08-31T11:48:50+02:00" level=info msg="running go-librespot 0.2.0" Aug 31 11:48:50 volumio go-librespot[7643]: time="2025-08-31T11:48:50+02:00" level=debug msg="app state loaded" Aug 31 11:48:50 volumio go-librespot[7643]: time="2025-08-31T11:48:50+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 31 11:48:50 volumio go-librespot[7643]: time="2025-08-31T11:48:50+02: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]" Aug 31 11:48:50 volumio go-librespot[7643]: time="2025-08-31T11:48:50+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 31 11:48:50 volumio go-librespot[7643]: time="2025-08-31T11:48:50+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 31 11:48:50 volumio go-librespot[7643]: time="2025-08-31T11:48:50+02:00" level=info msg="zeroconf server listening on port 38923" Aug 31 11:48:50 volumio go-librespot[7643]: time="2025-08-31T11:48:50+02:00" level=debug msg="obtained new client token: AAAuyinFPJo2OHemNQL7TdIMPvR59MHAj19NQFRSUFQZjWI+8W8dvgvxS1QRVGmjtKC5V/snI1RhncPbs7S39EsFKj0CRpbMWY6u8jYjArojmL3GdQgxQM+242sDRVNmh9TXLOsjsq1Ymx8XEopYMF+OXeXFD/8WXcNJJRd85EIulm1ReyB1lk1+TI/oj6ZVLPeixVaRmNElJDZeO8X/rmhPQjiMghq8UJYvM3gvtMKLqXc4TjSIkRZA" Aug 31 11:48:50 volumio go-librespot[7643]: time="2025-08-31T11:48:50+02:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.1.133:4070: connect: connection refused" Aug 31 11:48:50 volumio go-librespot[7643]: time="2025-08-31T11:48:50+02:00" level=debug msg="connected to ap-gew4.spotify.com:443" Aug 31 11:48:50 volumio go-librespot[7643]: time="2025-08-31T11:48:50+02:00" level=debug msg="completed keyexchange" Aug 31 11:48:50 volumio go-librespot[7643]: time="2025-08-31T11:48:50+02:00" level=debug msg="completed challenge" Aug 31 11:48:50 volumio go-librespot[7643]: time="2025-08-31T11:48:50+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Aug 31 11:48:50 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 31 11:48:50 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 31 11:48:50 volumio volumio[1054]: info: Initializing connection to go-librespot Websocket Aug 31 11:48:50 volumio volumio[1054]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 31 11:48:53 volumio volumio[1054]: info: Initializing connection to go-librespot Websocket Aug 31 11:48:53 volumio volumio[1054]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 31 11:48:54 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 31 11:48:54 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 208. Aug 31 11:48:54 volumio systemd[1]: Stopped go-librespot Daemon. Aug 31 11:48:54 volumio systemd[1]: Started go-librespot Daemon. Aug 31 11:48:54 volumio go-librespot[7665]: go-librespot daemon starting... Aug 31 11:48:54 volumio go-librespot[7665]: time="2025-08-31T11:48:54+02:00" level=info msg="running go-librespot 0.2.0" Aug 31 11:48:54 volumio go-librespot[7665]: time="2025-08-31T11:48:54+02:00" level=debug msg="app state loaded" Aug 31 11:48:54 volumio go-librespot[7665]: time="2025-08-31T11:48:54+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 31 11:48:54 volumio go-librespot[7665]: time="2025-08-31T11:48:54+02: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]" Aug 31 11:48:54 volumio go-librespot[7665]: time="2025-08-31T11:48:54+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 31 11:48:54 volumio go-librespot[7665]: time="2025-08-31T11:48:54+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 31 11:48:54 volumio go-librespot[7665]: time="2025-08-31T11:48:54+02:00" level=info msg="zeroconf server listening on port 42139" Aug 31 11:48:54 volumio go-librespot[7665]: time="2025-08-31T11:48:54+02:00" level=debug msg="obtained new client token: AAD0dyWY8zDuPkvzhEVLtPM4ybgQCHTV5Hyw6drkLY9tq6sdMqQFhf0rW3hKcBR8H7l2gbP7I6KnuY+beWOgQBLpjoIFmYHVNnIQcJR48ODYxgNjWger4pmUCGvyfGA0X5MoNHmu2ow3sDp55nUFilAhe1YA2EN5gkvMTWALuIdVC1G8ItdNkY7LR6zRVeNeZrIGNNAo/aEkMEuQ71MlNhw/4ejTqvIeZhlfb+UFxVwf028t8Y4q0PFn" Aug 31 11:48:54 volumio go-librespot[7665]: time="2025-08-31T11:48:54+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 31 11:48:54 volumio go-librespot[7665]: time="2025-08-31T11:48:54+02:00" level=debug msg="completed keyexchange" Aug 31 11:48:54 volumio go-librespot[7665]: time="2025-08-31T11:48:54+02:00" level=debug msg="completed challenge" Aug 31 11:48:54 volumio go-librespot[7665]: time="2025-08-31T11:48:54+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Aug 31 11:48:54 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 31 11:48:54 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 31 11:48:56 volumio volumio[1054]: info: Initializing connection to go-librespot Websocket Aug 31 11:48:56 volumio volumio[1054]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 31 11:48:57 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 31 11:48:57 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 209. Aug 31 11:48:57 volumio systemd[1]: Stopped go-librespot Daemon. Aug 31 11:48:57 volumio systemd[1]: Started go-librespot Daemon. Aug 31 11:48:57 volumio go-librespot[7673]: go-librespot daemon starting... Aug 31 11:48:58 volumio go-librespot[7673]: time="2025-08-31T11:48:58+02:00" level=info msg="running go-librespot 0.2.0" Aug 31 11:48:58 volumio go-librespot[7673]: time="2025-08-31T11:48:58+02:00" level=debug msg="app state loaded" Aug 31 11:48:58 volumio go-librespot[7673]: time="2025-08-31T11:48:58+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 31 11:48:58 volumio go-librespot[7673]: time="2025-08-31T11:48:58+02: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]" Aug 31 11:48:58 volumio go-librespot[7673]: time="2025-08-31T11:48:58+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 31 11:48:58 volumio go-librespot[7673]: time="2025-08-31T11:48:58+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 31 11:48:58 volumio go-librespot[7673]: time="2025-08-31T11:48:58+02:00" level=info msg="zeroconf server listening on port 43607" Aug 31 11:48:58 volumio go-librespot[7673]: time="2025-08-31T11:48:58+02:00" level=debug msg="obtained new client token: AADFz6ur3XbU7LOJCr4vMqZ8E9bBiE5FCnG2lRiMCucxjpUy8JyS9b3A6Fwsr8JjJzi8FvxKRbS6p2xOq5py1V4RKMa0R5PRCCMV/zhdyHCba46DNufXRIPwKn0H9PtiHg+K8kbA+oYLX0P9Die5CGpmZvCpua3wJfGvKyQicD1UNuGtaDpNJc5pwdrmOXy9dp+2wGw+rcRmTpZel7+vcMzVzPt2U2jrv2Au5GT+cABYpoDczVY/iVZR" Aug 31 11:48:58 volumio go-librespot[7673]: time="2025-08-31T11:48:58+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 31 11:48:58 volumio go-librespot[7673]: time="2025-08-31T11:48:58+02:00" level=debug msg="completed keyexchange" Aug 31 11:48:58 volumio go-librespot[7673]: time="2025-08-31T11:48:58+02:00" level=debug msg="completed challenge" Aug 31 11:48:58 volumio go-librespot[7673]: time="2025-08-31T11:48:58+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Aug 31 11:48:58 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 31 11:48:58 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 31 11:48:59 volumio volumio[1054]: info: Initializing connection to go-librespot Websocket Aug 31 11:48:59 volumio volumio[1054]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 31 11:49:01 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 31 11:49:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 210. Aug 31 11:49:01 volumio systemd[1]: Stopped go-librespot Daemon. Aug 31 11:49:01 volumio systemd[1]: Started go-librespot Daemon. Aug 31 11:49:01 volumio go-librespot[7685]: go-librespot daemon starting... Aug 31 11:49:01 volumio go-librespot[7685]: time="2025-08-31T11:49:01+02:00" level=info msg="running go-librespot 0.2.0" Aug 31 11:49:01 volumio go-librespot[7685]: time="2025-08-31T11:49:01+02:00" level=debug msg="app state loaded" Aug 31 11:49:01 volumio go-librespot[7685]: time="2025-08-31T11:49:01+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 31 11:49:01 volumio go-librespot[7685]: time="2025-08-31T11:49:01+02: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]" Aug 31 11:49:01 volumio go-librespot[7685]: time="2025-08-31T11:49:01+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 31 11:49:01 volumio go-librespot[7685]: time="2025-08-31T11:49:01+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 31 11:49:01 volumio go-librespot[7685]: time="2025-08-31T11:49:01+02:00" level=info msg="zeroconf server listening on port 37493" Aug 31 11:49:02 volumio go-librespot[7685]: time="2025-08-31T11:49:02+02:00" level=debug msg="obtained new client token: AAATxVj5Zjk6SKLZ7iI3BOnMJpub87y1ej5ME3gSlwcWhByIX399IfwT9b5RUPX1HXPYeWPsrbDRc6QYEP5MEziof0PCzUx0HllstP2Sk+8cityqP6D92q+p3KJNRi4uMIohBuRQPW+eQSUxRkfxHy74hoOzndgqKeopgf7ILwqTaezBkX4QLcO+5C258tQz9YRXBVPIGKGmkjbwCGwLZIaVU0ZApVpCP7zioLvz1sannF/4HP6PTg==" Aug 31 11:49:02 volumio go-librespot[7685]: time="2025-08-31T11:49:02+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 31 11:49:02 volumio go-librespot[7685]: time="2025-08-31T11:49:02+02:00" level=debug msg="completed keyexchange" Aug 31 11:49:02 volumio go-librespot[7685]: time="2025-08-31T11:49:02+02:00" level=debug msg="completed challenge" Aug 31 11:49:02 volumio go-librespot[7685]: time="2025-08-31T11:49:02+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Aug 31 11:49:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 31 11:49:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 31 11:49:02 volumio volumio[1054]: info: Initializing connection to go-librespot Websocket Aug 31 11:49:02 volumio volumio[1054]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 31 11:49:05 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 31 11:49:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 211. Aug 31 11:49:05 volumio systemd[1]: Stopped go-librespot Daemon. Aug 31 11:49:05 volumio systemd[1]: Started go-librespot Daemon. Aug 31 11:49:05 volumio go-librespot[7707]: go-librespot daemon starting... Aug 31 11:49:05 volumio go-librespot[7707]: time="2025-08-31T11:49:05+02:00" level=info msg="running go-librespot 0.2.0" Aug 31 11:49:05 volumio go-librespot[7707]: time="2025-08-31T11:49:05+02:00" level=debug msg="app state loaded" Aug 31 11:49:05 volumio go-librespot[7707]: time="2025-08-31T11:49:05+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 31 11:49:05 volumio go-librespot[7707]: time="2025-08-31T11:49:05+02: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]" Aug 31 11:49:05 volumio go-librespot[7707]: time="2025-08-31T11:49:05+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 31 11:49:05 volumio go-librespot[7707]: time="2025-08-31T11:49:05+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 31 11:49:05 volumio go-librespot[7707]: time="2025-08-31T11:49:05+02:00" level=info msg="zeroconf server listening on port 39831" Aug 31 11:49:05 volumio go-librespot[7707]: time="2025-08-31T11:49:05+02:00" level=debug msg="obtained new client token: AAC360bCaAaiLS0MUBzLRnfoa/oiZJga1JEFUnm+MhVlBVLNhCQq61BEqGBsoMPB0OvjosRBV87rkMkkjAk2aYyy4BydevC/XHS+wkcV6brZ/1TBuhTDDtQGrQGNzgxcqwKSHqUaSzr8PC9jiVddoFY2v1dEe741pFj2T/Z65ByGeQIoR8ybU9NCFogBoTRp4hG9J650QbWfHKu0miy6jWi2j5u1beFmiMVpLt6OS8/UC8xloM5I9bxO" Aug 31 11:49:05 volumio go-librespot[7707]: time="2025-08-31T11:49:05+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 31 11:49:05 volumio go-librespot[7707]: time="2025-08-31T11:49:05+02:00" level=debug msg="completed keyexchange" Aug 31 11:49:05 volumio go-librespot[7707]: time="2025-08-31T11:49:05+02:00" level=debug msg="completed challenge" Aug 31 11:49:05 volumio go-librespot[7707]: time="2025-08-31T11:49:05+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Aug 31 11:49:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 31 11:49:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 31 11:49:05 volumio volumio[1054]: info: Initializing connection to go-librespot Websocket Aug 31 11:49:05 volumio volumio[1054]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 31 11:49:08 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 31 11:49:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 212. Aug 31 11:49:08 volumio systemd[1]: Stopped go-librespot Daemon. Aug 31 11:49:08 volumio systemd[1]: Started go-librespot Daemon. Aug 31 11:49:08 volumio go-librespot[7715]: go-librespot daemon starting... Aug 31 11:49:08 volumio volumio[1054]: info: Initializing connection to go-librespot Websocket Aug 31 11:49:08 volumio volumio[1054]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 31 11:49:09 volumio go-librespot[7715]: time="2025-08-31T11:49:09+02:00" level=info msg="running go-librespot 0.2.0" Aug 31 11:49:09 volumio go-librespot[7715]: time="2025-08-31T11:49:09+02:00" level=debug msg="app state loaded" Aug 31 11:49:09 volumio go-librespot[7715]: time="2025-08-31T11:49:09+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 31 11:49:09 volumio go-librespot[7715]: time="2025-08-31T11:49:09+02: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]" Aug 31 11:49:09 volumio go-librespot[7715]: time="2025-08-31T11:49:09+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 31 11:49:09 volumio go-librespot[7715]: time="2025-08-31T11:49:09+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 31 11:49:09 volumio go-librespot[7715]: time="2025-08-31T11:49:09+02:00" level=info msg="zeroconf server listening on port 42693" Aug 31 11:49:09 volumio go-librespot[7715]: time="2025-08-31T11:49:09+02:00" level=debug msg="obtained new client token: AABYjAX0xe8GVJ+F8eYK4FSbusEaxYcgK4J1OICqr+tOnFdNKhkQRdQqRvChppX3eXxfO0Iid2DuOMhD9CmDNDX5VP6jqtsQHCgpEE23hyGAd76gzRB+lKHEf1aVOqg3lvO24MzGO/LgBrb68CofO6KyuZrAntsfZbIKrPJP7pW2AxooHBqrPv3JhtJ8VHWcmmVrZH9cuyCWgqIgVy2s8ZYUlj6nI6RBvERuHUeF3vwRCOheiftQdulh" Aug 31 11:49:09 volumio go-librespot[7715]: time="2025-08-31T11:49:09+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 31 11:49:09 volumio go-librespot[7715]: time="2025-08-31T11:49:09+02:00" level=debug msg="completed keyexchange" Aug 31 11:49:09 volumio go-librespot[7715]: time="2025-08-31T11:49:09+02:00" level=debug msg="completed challenge" Aug 31 11:49:09 volumio go-librespot[7715]: time="2025-08-31T11:49:09+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Aug 31 11:49:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 31 11:49:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 31 11:49:11 volumio volumio[1054]: info: Initializing connection to go-librespot Websocket Aug 31 11:49:11 volumio volumio[1054]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 31 11:49:12 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 31 11:49:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 213. Aug 31 11:49:12 volumio systemd[1]: Stopped go-librespot Daemon. Aug 31 11:49:12 volumio systemd[1]: Started go-librespot Daemon. Aug 31 11:49:12 volumio go-librespot[7723]: go-librespot daemon starting... Aug 31 11:49:12 volumio go-librespot[7723]: time="2025-08-31T11:49:12+02:00" level=info msg="running go-librespot 0.2.0" Aug 31 11:49:12 volumio go-librespot[7723]: time="2025-08-31T11:49:12+02:00" level=debug msg="app state loaded" Aug 31 11:49:12 volumio go-librespot[7723]: time="2025-08-31T11:49:12+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 31 11:49:12 volumio go-librespot[7723]: time="2025-08-31T11:49:12+02: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]" Aug 31 11:49:12 volumio go-librespot[7723]: time="2025-08-31T11:49:12+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 31 11:49:12 volumio go-librespot[7723]: time="2025-08-31T11:49:12+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 31 11:49:12 volumio go-librespot[7723]: time="2025-08-31T11:49:12+02:00" level=info msg="zeroconf server listening on port 43449" Aug 31 11:49:13 volumio go-librespot[7723]: time="2025-08-31T11:49:13+02:00" level=debug msg="obtained new client token: AABltvW3UDa90pzo0ZUrLTV5QfwK/HHSVfLCw2AEh+lUpGkFxnSqn8017Eo5iH4LGxhTWOrQtsmgNYpuM5/lxG1HPywQXDcvmpdyJ5nTehfAVpEZU9IFqbC4512sKyTGRCLrF/9QigvxBvoQOB6ZH9vFyl4r8/NnzLvtcch63+NA2QvWECIZmCJd7PnzK2qJOrvZD56SwmH8r3nLOp7g43gUcXn/Ss56sgU4Oq+yoCu0bHyNRL8HAQ==" Aug 31 11:49:13 volumio go-librespot[7723]: time="2025-08-31T11:49:13+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 31 11:49:13 volumio go-librespot[7723]: time="2025-08-31T11:49:13+02:00" level=debug msg="completed keyexchange" Aug 31 11:49:13 volumio go-librespot[7723]: time="2025-08-31T11:49:13+02:00" level=debug msg="completed challenge" Aug 31 11:49:13 volumio go-librespot[7723]: time="2025-08-31T11:49:13+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Aug 31 11:49:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 31 11:49:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 31 11:49:15 volumio volumio[1054]: info: Initializing connection to go-librespot Websocket Aug 31 11:49:15 volumio volumio[1054]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 31 11:49:16 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 31 11:49:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 214. Aug 31 11:49:16 volumio systemd[1]: Stopped go-librespot Daemon. Aug 31 11:49:16 volumio systemd[1]: Started go-librespot Daemon. Aug 31 11:49:16 volumio go-librespot[7745]: go-librespot daemon starting... Aug 31 11:49:16 volumio go-librespot[7745]: time="2025-08-31T11:49:16+02:00" level=info msg="running go-librespot 0.2.0" Aug 31 11:49:16 volumio go-librespot[7745]: time="2025-08-31T11:49:16+02:00" level=debug msg="app state loaded" Aug 31 11:49:16 volumio go-librespot[7745]: time="2025-08-31T11:49:16+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 31 11:49:16 volumio go-librespot[7745]: time="2025-08-31T11:49:16+02: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]" Aug 31 11:49:16 volumio go-librespot[7745]: time="2025-08-31T11:49:16+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Aug 31 11:49:16 volumio go-librespot[7745]: time="2025-08-31T11:49:16+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Aug 31 11:49:16 volumio go-librespot[7745]: time="2025-08-31T11:49:16+02:00" level=info msg="zeroconf server listening on port 44721" Aug 31 11:49:16 volumio go-librespot[7745]: time="2025-08-31T11:49:16+02:00" level=debug msg="obtained new client token: AADTtFxz1GQTwCXBMd8SqO4aBPdM8v87iBQljVtZPU7GY5VWkgDvxNQey4p5I4eJnt2yupe1R09g/2qKKx7Jc498j3KPt4xwwEeeYPAdXjeiROF4iez60M2QJLyikBxoarjFpTNoHfaKq5VFHmmEIyTO/DJRZkH1DCuajDbukOC42PscNeR5CLJPHhOidAhegKJJjCYYpYikOr2YWqDZ7A46TQKRpJb7oivgORcSBn0T3gaFMOH2rbYW" Aug 31 11:49:16 volumio go-librespot[7745]: time="2025-08-31T11:49:16+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 31 11:49:16 volumio go-librespot[7745]: time="2025-08-31T11:49:16+02:00" level=debug msg="completed keyexchange" Aug 31 11:49:16 volumio go-librespot[7745]: time="2025-08-31T11:49:16+02:00" level=debug msg="completed challenge" Aug 31 11:49:16 volumio go-librespot[7745]: time="2025-08-31T11:49:16+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Aug 31 11:49:16 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 31 11:49:16 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 31 11:49:18 volumio volumio[1054]: info: Initializing connection to go-librespot Websocket Aug 31 11:49:18 volumio volumio[1054]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 31 11:49:19 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 31 11:49:19 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 215. Aug 31 11:49:19 volumio systemd[1]: Stopped go-librespot Daemon. Aug 31 11:49:19 volumio systemd[1]: Started go-librespot Daemon. Aug 31 11:49:19 volumio go-librespot[7756]: go-librespot daemon starting... Aug 31 11:49:19 volumio go-librespot[7756]: time="2025-08-31T11:49:19+02:00" level=info msg="running go-librespot 0.2.0" Aug 31 11:49:19 volumio go-librespot[7756]: time="2025-08-31T11:49:19+02:00" level=debug msg="app state loaded" Aug 31 11:49:19 volumio go-librespot[7756]: time="2025-08-31T11:49:19+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 31 11:49:20 volumio go-librespot[7756]: time="2025-08-31T11:49:20+02: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]" Aug 31 11:49:20 volumio go-librespot[7756]: time="2025-08-31T11:49:20+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 31 11:49:20 volumio go-librespot[7756]: time="2025-08-31T11:49:20+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 31 11:49:20 volumio go-librespot[7756]: time="2025-08-31T11:49:20+02:00" level=info msg="zeroconf server listening on port 38909" Aug 31 11:49:20 volumio go-librespot[7756]: time="2025-08-31T11:49:20+02:00" level=debug msg="obtained new client token: AAC46ndN4ZUb+q1yYRWSX3/EuoRs2Xr9Co+DquxQYHGbgUohPaQczBz8fK2yWkOrwPI7ZTQbqDs3uysWkiTTbrSrMV5UtNk6CVXVN0P5gJlgiHhT7wQ/xjDv0NFvgiC+Vk4VrOZIsVzRkiqB0f+u/fUQDyOTSiyS0fmElhA22XTGmtzC1RcSF95mWKIrHCeMN92i3nhJkZLHipWLXlXsDZWY/NHGzUTr/RY5Dr+d7xScFcfr9Fg9SWYS" Aug 31 11:49:20 volumio go-librespot[7756]: time="2025-08-31T11:49:20+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 31 11:49:20 volumio go-librespot[7756]: time="2025-08-31T11:49:20+02:00" level=debug msg="completed keyexchange" Aug 31 11:49:20 volumio go-librespot[7756]: time="2025-08-31T11:49:20+02:00" level=debug msg="completed challenge" Aug 31 11:49:20 volumio go-librespot[7756]: time="2025-08-31T11:49:20+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Aug 31 11:49:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 31 11:49:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 31 11:49:21 volumio volumio[1054]: info: Initializing connection to go-librespot Websocket Aug 31 11:49:21 volumio volumio[1054]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 31 11:49:23 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 31 11:49:23 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 216. Aug 31 11:49:23 volumio systemd[1]: Stopped go-librespot Daemon. Aug 31 11:49:23 volumio systemd[1]: Started go-librespot Daemon. Aug 31 11:49:23 volumio go-librespot[7764]: go-librespot daemon starting... Aug 31 11:49:23 volumio go-librespot[7764]: time="2025-08-31T11:49:23+02:00" level=info msg="running go-librespot 0.2.0" Aug 31 11:49:23 volumio go-librespot[7764]: time="2025-08-31T11:49:23+02:00" level=debug msg="app state loaded" Aug 31 11:49:23 volumio go-librespot[7764]: time="2025-08-31T11:49:23+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 31 11:49:23 volumio go-librespot[7764]: time="2025-08-31T11:49:23+02: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]" Aug 31 11:49:23 volumio go-librespot[7764]: time="2025-08-31T11:49:23+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 31 11:49:23 volumio go-librespot[7764]: time="2025-08-31T11:49:23+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 31 11:49:23 volumio go-librespot[7764]: time="2025-08-31T11:49:23+02:00" level=info msg="zeroconf server listening on port 40851" Aug 31 11:49:24 volumio volumio[1054]: info: Initializing connection to go-librespot Websocket Aug 31 11:49:24 volumio go-librespot[7764]: time="2025-08-31T11:49:24+02:00" level=debug msg="new websocket client" Aug 31 11:49:24 volumio volumio[1054]: info: Connection to go-librespot Websocket established Aug 31 11:49:24 volumio go-librespot[7764]: time="2025-08-31T11:49:24+02:00" level=debug msg="obtained new client token: AACVkGQNe5sgXqey8GeXk6+0SOJE5Yg/w155VnsoYGFIxGNcHw7a7Z5in8Syc3wTkmnCCObiX7YyCwvzq7AOuzcJJmQJCvcxsaCXXlK/Nk8GZcNYHgMQwIn1EFw0VkQlBvCYkMInH43rOmqdCOagJRazbF8MyMF7VB0VQMu2GD3r8WhzGeD+4WX1WAJKqU/tyN/zLTMHkZfv0V8ORFlIQrn0YujeOtKZgSvVhSN+eMBp3ynN5T6Akg==" Aug 31 11:49:24 volumio go-librespot[7764]: time="2025-08-31T11:49:24+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 31 11:49:24 volumio go-librespot[7764]: time="2025-08-31T11:49:24+02:00" level=debug msg="completed keyexchange" Aug 31 11:49:24 volumio go-librespot[7764]: time="2025-08-31T11:49:24+02:00" level=debug msg="completed challenge" Aug 31 11:49:24 volumio go-librespot[7764]: time="2025-08-31T11:49:24+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Aug 31 11:49:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 31 11:49:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 31 11:49:24 volumio volumio[1054]: info: Connection to go-librespot Websocket closed Aug 31 11:49:25 volumio volumio[1054]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 31 11:49:25 volumio volumio[1054]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 31 11:49:25 volumio volumio[1054]: info: Retrieving Cloud Streaming UI Aug 31 11:49:25 volumio volumio[1054]: info: Getting Tidal Cloud Configuration Aug 31 11:49:25 volumio volumio[1054]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 31 11:49:25 volumio volumio[1054]: info: Getting Qobuz Cloud Configuration Aug 31 11:49:25 volumio volumio[1054]: info: Asking plugin for UI Config Aug 31 11:49:25 volumio volumio[1054]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 31 11:49:25 volumio volumio[1054]: info: Getting Spotify Cloud Configuration Aug 31 11:49:25 volumio volumio[1054]: info: Asking plugin for UI Config Aug 31 11:49:25 volumio volumio[1054]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 31 11:49:25 volumio volumio[1054]: info: Saving Spotify Acccount Aug 31 11:49:25 volumio volumio[1054]: info: Got it Aug 31 11:49:25 volumio volumio[1054]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Aug 31 11:49:25 volumio volumio[1054]: info: Got Tidal Cloud Configuration Aug 31 11:49:25 volumio volumio[1054]: info: Got it Aug 31 11:49:25 volumio volumio[1054]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 31 11:49:25 volumio volumio[1054]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Aug 31 11:49:25 volumio volumio[1054]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 31 11:49:25 volumio volumio[1054]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 31 11:49:25 volumio volumio[1054]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 31 11:49:25 volumio volumio[1054]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 31 11:49:25 volumio volumio[1054]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 11:49:25 volumio volumio[1054]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 11:49:25 volumio volumio[1054]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 11:49:25 volumio volumio[1054]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 11:49:25 volumio volumio[1054]: info: CoreCommandRouter::volumioGetBrowseSources Aug 31 11:49:25 volumio volumio[1054]: info: CoreCommandRouter::volumioGetBrowseSources Aug 31 11:49:25 volumio volumio[1054]: info: CoreCommandRouter::volumioGetBrowseSources Aug 31 11:49:25 volumio volumio[1054]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 11:49:25 volumio volumio[1054]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Aug 31 11:49:27 volumio volumio[1054]: info: Getting Spotify volume Aug 31 11:49:27 volumio volumio[1054]: (node:1054) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 31 11:49:27 volumio volumio[1054]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Aug 31 11:49:27 volumio volumio[1054]: (node:1054) 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: 21) Aug 31 11:49:27 volumio volumio[1054]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Aug 31 11:49:27 volumio volumio[1054]: info: CoreCommandRouter::volumioGetState Aug 31 11:49:27 volumio volumio[1054]: info: CorePlayQueue::getTrack 0 Aug 31 11:49:27 volumio volumio[1054]: info: Initializing connection to go-librespot Websocket Aug 31 11:49:27 volumio volumio[1054]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 31 11:49:27 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 31 11:49:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 217. Aug 31 11:49:27 volumio systemd[1]: Stopped go-librespot Daemon. Aug 31 11:49:27 volumio systemd[1]: Started go-librespot Daemon. Aug 31 11:49:27 volumio go-librespot[7787]: go-librespot daemon starting... Aug 31 11:49:27 volumio go-librespot[7787]: time="2025-08-31T11:49:27+02:00" level=info msg="running go-librespot 0.2.0" Aug 31 11:49:27 volumio go-librespot[7787]: time="2025-08-31T11:49:27+02:00" level=debug msg="app state loaded" Aug 31 11:49:27 volumio go-librespot[7787]: time="2025-08-31T11:49:27+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 31 11:49:27 volumio go-librespot[7787]: time="2025-08-31T11:49:27+02: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]" Aug 31 11:49:27 volumio go-librespot[7787]: time="2025-08-31T11:49:27+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 31 11:49:27 volumio go-librespot[7787]: time="2025-08-31T11:49:27+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 31 11:49:27 volumio go-librespot[7787]: time="2025-08-31T11:49:27+02:00" level=info msg="zeroconf server listening on port 46759" Aug 31 11:49:27 volumio go-librespot[7787]: time="2025-08-31T11:49:27+02:00" level=debug msg="obtained new client token: AAB7P1wgVAQJvCWjJIQiAFYErm6ONWyVc7tdIp0s7BOqvU8/vzWU5QolzYl8ZH4zj6CihFhiJ/OdKIaeM3DKKMxHc9W1KuxIrUwZSXcdfFk4mLi8Qu+wYSXoKRJ7w8Qkdj7AvuDxxX+TTDnTifFtwdtZ02RoOWK2FMLGGIwGZL8VzrSf8I2pfm4Qy+g3IvBr1FN9yrBDNmILR2EMivIjB5MB7BUxAvI2vfVpv+JyshmwPCUPe3yNTzpn" Aug 31 11:49:27 volumio go-librespot[7787]: time="2025-08-31T11:49:27+02:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.1.133:4070: connect: connection refused" Aug 31 11:49:27 volumio go-librespot[7787]: time="2025-08-31T11:49:27+02:00" level=debug msg="connected to ap-gew4.spotify.com:443" Aug 31 11:49:27 volumio go-librespot[7787]: time="2025-08-31T11:49:27+02:00" level=debug msg="completed keyexchange" Aug 31 11:49:27 volumio go-librespot[7787]: time="2025-08-31T11:49:27+02:00" level=debug msg="completed challenge" Aug 31 11:49:27 volumio go-librespot[7787]: time="2025-08-31T11:49:27+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Aug 31 11:49:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 31 11:49:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 31 11:49:29 volumio volumio[1054]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 11:49:30 volumio volumio[1054]: info: Initializing connection to go-librespot Websocket Aug 31 11:49:30 volumio volumio[1054]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 31 11:49:31 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 31 11:49:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 218. Aug 31 11:49:31 volumio systemd[1]: Stopped go-librespot Daemon. Aug 31 11:49:31 volumio volumio[1054]: info: CoreCommandRouter::volumioGetState Aug 31 11:49:31 volumio volumio[1054]: info: CorePlayQueue::getTrack 0 Aug 31 11:49:31 volumio systemd[1]: Started go-librespot Daemon. Aug 31 11:49:31 volumio go-librespot[7796]: go-librespot daemon starting... Aug 31 11:49:31 volumio go-librespot[7796]: time="2025-08-31T11:49:31+02:00" level=info msg="running go-librespot 0.2.0" Aug 31 11:49:31 volumio go-librespot[7796]: time="2025-08-31T11:49:31+02:00" level=debug msg="app state loaded" Aug 31 11:49:31 volumio go-librespot[7796]: time="2025-08-31T11:49:31+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 31 11:49:31 volumio go-librespot[7796]: time="2025-08-31T11:49:31+02: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]" Aug 31 11:49:31 volumio go-librespot[7796]: time="2025-08-31T11:49:31+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 31 11:49:31 volumio go-librespot[7796]: time="2025-08-31T11:49:31+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 31 11:49:31 volumio go-librespot[7796]: time="2025-08-31T11:49:31+02:00" level=info msg="zeroconf server listening on port 35635" Aug 31 11:49:31 volumio go-librespot[7796]: time="2025-08-31T11:49:31+02:00" level=debug msg="obtained new client token: AAApN0yjcUKLk51bmqXns6xdiPTIIs5xsnOJiBG+XISYtbNwdBCstyZ0Z1UfXZAMGx2TTm625yXzLAFjNVDvunQAmNYMin3YpQ/lWDS/Fil1bAXdZZpsNU5S8Lk8qGIl3orDRmTZJq9AcwG3Bkw+ImYbRmg6tD2aRkFZdKlHuUsnh64itvCBCzR4i1bZKsQ4z6mtu174is0o3XGyCXdTqLNEUQ+hnZs1dpkZYXhkyXaMAn3V4meTWna7" Aug 31 11:49:31 volumio go-librespot[7796]: time="2025-08-31T11:49:31+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 31 11:49:31 volumio go-librespot[7796]: time="2025-08-31T11:49:31+02:00" level=debug msg="completed keyexchange" Aug 31 11:49:31 volumio go-librespot[7796]: time="2025-08-31T11:49:31+02:00" level=debug msg="completed challenge" Aug 31 11:49:31 volumio go-librespot[7796]: time="2025-08-31T11:49:31+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Aug 31 11:49:31 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 31 11:49:31 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 31 11:49:33 volumio volumio[1054]: info: Initializing connection to go-librespot Websocket Aug 31 11:49:33 volumio volumio[1054]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 31 11:49:34 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 31 11:49:34 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 219. Aug 31 11:49:34 volumio systemd[1]: Stopped go-librespot Daemon. Aug 31 11:49:34 volumio systemd[1]: Started go-librespot Daemon. Aug 31 11:49:34 volumio go-librespot[7818]: go-librespot daemon starting... Aug 31 11:49:34 volumio go-librespot[7818]: time="2025-08-31T11:49:34+02:00" level=info msg="running go-librespot 0.2.0" Aug 31 11:49:34 volumio go-librespot[7818]: time="2025-08-31T11:49:34+02:00" level=debug msg="app state loaded" Aug 31 11:49:34 volumio go-librespot[7818]: time="2025-08-31T11:49:34+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 31 11:49:35 volumio go-librespot[7818]: time="2025-08-31T11:49:35+02: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]" Aug 31 11:49:35 volumio go-librespot[7818]: time="2025-08-31T11:49:35+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 31 11:49:35 volumio go-librespot[7818]: time="2025-08-31T11:49:35+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 31 11:49:35 volumio go-librespot[7818]: time="2025-08-31T11:49:35+02:00" level=info msg="zeroconf server listening on port 37591" Aug 31 11:49:35 volumio go-librespot[7818]: time="2025-08-31T11:49:35+02:00" level=debug msg="obtained new client token: AACNWf2N0ihqhKENNlJ5rZ80U+Z5sQHeYr4G/cr2mXewkQlJ0N96ZglCeGZ2hvycNxQrTXpjIgfiqmMR/iCXDc4YpNKchq/jJA13Y9CXSmiJCcE3+3iIY7B/j8fOCo2OLqvXmYPu2xPDTJ7nvrESeCoWywierTckY+4Id4IAsHQAC6OvVMV3WAWyVs/kILIwhJQ0P/paOFOIfWXvF+DcQz+Us2jhzoDoHhKG92K21qxbTc2di4sNaQ==" Aug 31 11:49:35 volumio go-librespot[7818]: time="2025-08-31T11:49:35+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 31 11:49:35 volumio go-librespot[7818]: time="2025-08-31T11:49:35+02:00" level=debug msg="completed keyexchange" Aug 31 11:49:35 volumio go-librespot[7818]: time="2025-08-31T11:49:35+02:00" level=debug msg="completed challenge" Aug 31 11:49:35 volumio volumio[1054]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 31 11:49:35 volumio volumio[1054]: info: CURURI: music-library/NAS Aug 31 11:49:35 volumio volumio[1054]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Aug 31 11:49:35 volumio go-librespot[7818]: time="2025-08-31T11:49:35+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Aug 31 11:49:35 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 31 11:49:35 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 31 11:49:35 volumio volumio[1054]: info: Preload queue cleared Aug 31 11:49:36 volumio volumio[1054]: info: Initializing connection to go-librespot Websocket Aug 31 11:49:36 volumio volumio[1054]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 31 11:49:37 volumio volumio[1054]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 31 11:49:37 volumio volumio[1054]: info: CURURI: music-library/USB Aug 31 11:49:37 volumio volumio[1054]: info: Preload queue cleared Aug 31 11:49:38 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 31 11:49:38 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 220. Aug 31 11:49:38 volumio systemd[1]: Stopped go-librespot Daemon. Aug 31 11:49:38 volumio systemd[1]: Started go-librespot Daemon. Aug 31 11:49:38 volumio go-librespot[7870]: go-librespot daemon starting... Aug 31 11:49:38 volumio go-librespot[7870]: time="2025-08-31T11:49:38+02:00" level=info msg="running go-librespot 0.2.0" Aug 31 11:49:38 volumio go-librespot[7870]: time="2025-08-31T11:49:38+02:00" level=debug msg="app state loaded" Aug 31 11:49:38 volumio go-librespot[7870]: time="2025-08-31T11:49:38+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 31 11:49:38 volumio go-librespot[7870]: time="2025-08-31T11:49:38+02: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]" Aug 31 11:49:38 volumio go-librespot[7870]: time="2025-08-31T11:49:38+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 31 11:49:38 volumio go-librespot[7870]: time="2025-08-31T11:49:38+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 31 11:49:38 volumio go-librespot[7870]: time="2025-08-31T11:49:38+02:00" level=info msg="zeroconf server listening on port 33815" Aug 31 11:49:39 volumio go-librespot[7870]: time="2025-08-31T11:49:39+02:00" level=debug msg="obtained new client token: AAAH5GPz3Nw0JBf7H9Qx8KfK0caazBUh0m815o81dK1A1G2AhEAH8rPMpWNpcbbiUQo64YTa3rnVKV19+WkWOLwvgE1Un3RbiPN+vtLXUZ3H1SCYQOqYQmzVSmIaq9f26mmCAcu25J/3Jww3Do9UUr7xjlJPyph3CYLcouh25k5b0aPUdtJzeCtyns3IKKfSI4iXfY3v83J9E01oUO1w8nPBM2A7e3i0PSjdQjeQf+cfacSyuuGAMw==" Aug 31 11:49:39 volumio go-librespot[7870]: time="2025-08-31T11:49:39+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 31 11:49:39 volumio go-librespot[7870]: time="2025-08-31T11:49:39+02:00" level=debug msg="completed keyexchange" Aug 31 11:49:39 volumio go-librespot[7870]: time="2025-08-31T11:49:39+02:00" level=debug msg="completed challenge" Aug 31 11:49:39 volumio go-librespot[7870]: time="2025-08-31T11:49:39+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Aug 31 11:49:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 31 11:49:39 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 31 11:49:39 volumio volumio[1054]: info: Initializing connection to go-librespot Websocket Aug 31 11:49:39 volumio volumio[1054]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 31 11:49:42 volumio volumio[1054]: info: CoreCommandRouter::volumioGetState Aug 31 11:49:42 volumio volumio[1054]: info: CorePlayQueue::getTrack 0 Aug 31 11:49:42 volumio volumio[1054]: info: Initializing connection to go-librespot Websocket Aug 31 11:49:42 volumio volumio[1054]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 31 11:49:42 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 31 11:49:42 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 221. Aug 31 11:49:42 volumio systemd[1]: Stopped go-librespot Daemon. Aug 31 11:49:42 volumio systemd[1]: Started go-librespot Daemon. Aug 31 11:49:42 volumio go-librespot[7878]: go-librespot daemon starting... Aug 31 11:49:42 volumio go-librespot[7878]: time="2025-08-31T11:49:42+02:00" level=info msg="running go-librespot 0.2.0" Aug 31 11:49:42 volumio go-librespot[7878]: time="2025-08-31T11:49:42+02:00" level=debug msg="app state loaded" Aug 31 11:49:42 volumio go-librespot[7878]: time="2025-08-31T11:49:42+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 31 11:49:42 volumio go-librespot[7878]: time="2025-08-31T11:49:42+02: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]" Aug 31 11:49:42 volumio go-librespot[7878]: time="2025-08-31T11:49:42+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 31 11:49:42 volumio go-librespot[7878]: time="2025-08-31T11:49:42+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 31 11:49:42 volumio go-librespot[7878]: time="2025-08-31T11:49:42+02:00" level=info msg="zeroconf server listening on port 36987" Aug 31 11:49:42 volumio go-librespot[7878]: time="2025-08-31T11:49:42+02:00" level=debug msg="obtained new client token: AABTc5gsU+jeoo240vvFbHJy/uU4hBphvVUhFSK9n6KFaJDk+y+Juh2qULmfolNsdlWckS4QiPb5xgUjWBrJTD4QxFn6IKS1oZ0Qx6itWGVbDtGfDAQWaxXmO73HmrNsEvwfsOZRdNo0C11vRKHVG8AFNPqry6P9cH5rp0kPxZZbSpxU/WHJLO0t8PW48Q9USXQdI1uWBy8IbimFBmO24tOQZ/kSDritGvTbigsFRG3q72zEuf0isJPl" Aug 31 11:49:42 volumio go-librespot[7878]: time="2025-08-31T11:49:42+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 31 11:49:42 volumio go-librespot[7878]: time="2025-08-31T11:49:42+02:00" level=debug msg="completed keyexchange" Aug 31 11:49:42 volumio go-librespot[7878]: time="2025-08-31T11:49:42+02:00" level=debug msg="completed challenge" Aug 31 11:49:42 volumio go-librespot[7878]: time="2025-08-31T11:49:42+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Aug 31 11:49:42 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 31 11:49:42 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 31 11:49:44 volumio volumio[1054]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 31 11:49:44 volumio volumio[1054]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 31 11:49:44 volumio volumio[1054]: info: Retrieving Cloud Streaming UI Aug 31 11:49:44 volumio volumio[1054]: info: Getting Tidal Cloud Configuration Aug 31 11:49:44 volumio volumio[1054]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 31 11:49:44 volumio volumio[1054]: info: Getting Qobuz Cloud Configuration Aug 31 11:49:44 volumio volumio[1054]: info: Asking plugin for UI Config Aug 31 11:49:44 volumio volumio[1054]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 31 11:49:44 volumio volumio[1054]: info: Getting Spotify Cloud Configuration Aug 31 11:49:44 volumio volumio[1054]: info: Asking plugin for UI Config Aug 31 11:49:44 volumio volumio[1054]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 31 11:49:44 volumio volumio[1054]: info: Saving Spotify Acccount Aug 31 11:49:44 volumio volumio[1054]: info: Got it Aug 31 11:49:44 volumio volumio[1054]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Aug 31 11:49:44 volumio volumio[1054]: info: Got Tidal Cloud Configuration Aug 31 11:49:44 volumio volumio[1054]: info: Got it Aug 31 11:49:44 volumio volumio[1054]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 31 11:49:44 volumio volumio[1054]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Aug 31 11:49:44 volumio volumio[1054]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 31 11:49:44 volumio volumio[1054]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 31 11:49:44 volumio volumio[1054]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 31 11:49:44 volumio volumio[1054]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 31 11:49:44 volumio volumio[1054]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 11:49:44 volumio volumio[1054]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 11:49:44 volumio volumio[1054]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 11:49:44 volumio volumio[1054]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 11:49:44 volumio volumio[1054]: info: CoreCommandRouter::volumioGetBrowseSources Aug 31 11:49:44 volumio volumio[1054]: info: CoreCommandRouter::volumioGetBrowseSources Aug 31 11:49:44 volumio volumio[1054]: info: CoreCommandRouter::volumioGetBrowseSources Aug 31 11:49:44 volumio volumio[1054]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 31 11:49:44 volumio volumio[1054]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Aug 31 11:49:45 volumio volumio[1054]: info: Initializing connection to go-librespot Websocket Aug 31 11:49:45 volumio volumio[1054]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 31 11:49:46 volumio volumio[1054]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Aug 31 11:49:46 volumio volumio[1054]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Aug 31 11:49:46 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 31 11:49:46 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 222. Aug 31 11:49:46 volumio systemd[1]: Stopped go-librespot Daemon. Aug 31 11:49:46 volumio systemd[1]: Started go-librespot Daemon. Aug 31 11:49:46 volumio go-librespot[7904]: go-librespot daemon starting... Aug 31 11:49:46 volumio volumio[1054]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 11:49:46 volumio volumio[1054]: Error: getaddrinfo -3007 Aug 31 11:49:46 volumio volumio[1054]: at errnoException (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:199:11) Aug 31 11:49:46 volumio volumio[1054]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:112:10) Aug 31 11:49:46 volumio volumio[1054]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) { Aug 31 11:49:46 volumio volumio[1054]: code: -3007, Aug 31 11:49:46 volumio volumio[1054]: errno: -3007, Aug 31 11:49:46 volumio volumio[1054]: syscall: 'getaddrinfo' Aug 31 11:49:46 volumio volumio[1054]: } Aug 31 11:49:46 volumio volumio[1054]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 11:49:46 volumio go-librespot[7904]: time="2025-08-31T11:49:46+02:00" level=info msg="running go-librespot 0.2.0" Aug 31 11:49:46 volumio go-librespot[7904]: time="2025-08-31T11:49:46+02:00" level=debug msg="app state loaded" Aug 31 11:49:46 volumio go-librespot[7904]: time="2025-08-31T11:49:46+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 31 11:49:46 volumio go-librespot[7904]: time="2025-08-31T11:49:46+02: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]" Aug 31 11:49:46 volumio go-librespot[7904]: time="2025-08-31T11:49:46+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 31 11:49:46 volumio go-librespot[7904]: time="2025-08-31T11:49:46+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 31 11:49:46 volumio go-librespot[7904]: time="2025-08-31T11:49:46+02:00" level=info msg="zeroconf server listening on port 43257" Aug 31 11:49:46 volumio go-librespot[7904]: time="2025-08-31T11:49:46+02:00" level=debug msg="obtained new client token: AAAeDTE+v3LLn+BjuwPJztqjnKZHfAuTVW0bJL0OXr3GprHHJDbOGUGOIhIMGIVnQ2G+1OZuCdw/tOQE397+Tjg65zhAt5Z3lW0XwzjG4MK8tYXRQbZ4n2gx2M/A04epqDS9hNwsH3CRI3GM3IPv10yosaEmsvNldMN3MHJ2lZfqqqy8v19YDo6NRdDj27qSw3gxK0+MIutkIYJ1tay2lqgyZ67+dXIP+4MVBrrBDo+n2RQVZXFgnq62" Aug 31 11:49:46 volumio go-librespot[7904]: time="2025-08-31T11:49:46+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 31 11:49:46 volumio go-librespot[7904]: time="2025-08-31T11:49:46+02:00" level=debug msg="completed keyexchange" Aug 31 11:49:46 volumio go-librespot[7904]: time="2025-08-31T11:49:46+02:00" level=debug msg="completed challenge" Aug 31 11:49:46 volumio go-librespot[7904]: time="2025-08-31T11:49:46+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Aug 31 11:49:46 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 31 11:49:46 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 31 11:49:46 volumio sudo[7921]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-08-31 11:48 Aug 31 11:49:46 volumio sudo[7921]: 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"