-- Logs begin at Sat 2025-05-03 13:40:58 CEST, end at Sat 2025-05-03 16:11:20 CEST. -- May 03 16:10:01 volumiob systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 03 16:10:01 volumiob systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4171. May 03 16:10:01 volumiob systemd[1]: Stopped go-librespot Daemon. May 03 16:10:01 volumiob systemd[1]: Started go-librespot Daemon. May 03 16:10:01 volumiob go-librespot[21515]: Librespot-go daemon starting... May 03 16:10:01 volumiob go-librespot[21515]: time="2025-05-03T16:10:01+02:00" level=info msg="generated new device id: e675c72e6db67eaee81534d4664efc835515ad02" May 03 16:10:01 volumiob go-librespot[21515]: time="2025-05-03T16:10:01+02:00" level=debug msg="stored credentials found for ov2ch4rvdqh5kv7qx4s9m5f5y" May 03 16:10:01 volumiob go-librespot[21515]: time="2025-05-03T16:10:01+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" May 03 16:10:01 volumiob go-librespot[21515]: time="2025-05-03T16:10:01+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 03 16:10:01 volumiob go-librespot[21515]: time="2025-05-03T16:10:01+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 03 16:10:01 volumiob go-librespot[21515]: time="2025-05-03T16:10:01+02:00" level=debug msg="zeroconf server listening on port 45847" May 03 16:10:01 volumiob go-librespot[21515]: time="2025-05-03T16:10:01+02:00" level=debug msg="obtained new client token: AADKWB+AUkIBIJPLH2cYOXApU5Usn0Kxi3zJaGOCdBG4nM0UrtOmvC7jMfzCdJGd59gIS2XbCMTC5Z+bOLmyZX9rjccjM+o+Wa2atE9+AqT0qTA4/8ERpYZE+kIqUU771gJzDfHsIokO8sBvQ08YzCODnLdrf1MgjH+zIkj73ycSMORS1gEKEhoh3peLxRoy2eRXh8Ln4TZpm1WRe3mqA2+sanHK3dmE+Q55NwfOppXvn+0+mQ6oWY7dnw==" May 03 16:10:01 volumiob go-librespot[21515]: time="2025-05-03T16:10:01+02:00" level=info msg="connected to ap-gew1.spotify.com:4070" May 03 16:10:01 volumiob go-librespot[21515]: time="2025-05-03T16:10:01+02:00" level=debug msg="completed keyexchange" May 03 16:10:01 volumiob go-librespot[21515]: time="2025-05-03T16:10:01+02:00" level=debug msg="completed challenge" May 03 16:10:01 volumiob go-librespot[21515]: time="2025-05-03T16:10:01+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" May 03 16:10:01 volumiob systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 03 16:10:01 volumiob systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 03 16:10:02 volumiob volumio[857]: info: Initializing connection to go-librespot Websocket May 03 16:10:02 volumiob volumio[857]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 03 16:10:05 volumiob systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 03 16:10:05 volumiob systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4172. May 03 16:10:05 volumiob systemd[1]: Stopped go-librespot Daemon. May 03 16:10:05 volumiob systemd[1]: Started go-librespot Daemon. May 03 16:10:05 volumiob go-librespot[21531]: Librespot-go daemon starting... May 03 16:10:05 volumiob go-librespot[21531]: time="2025-05-03T16:10:05+02:00" level=info msg="generated new device id: 1c246ba1bd24a940f671f4846cbb885ba340b315" May 03 16:10:05 volumiob go-librespot[21531]: time="2025-05-03T16:10:05+02:00" level=debug msg="stored credentials found for ov2ch4rvdqh5kv7qx4s9m5f5y" May 03 16:10:05 volumiob go-librespot[21531]: time="2025-05-03T16:10:05+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" May 03 16:10:05 volumiob go-librespot[21531]: time="2025-05-03T16:10:05+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 03 16:10:05 volumiob go-librespot[21531]: time="2025-05-03T16:10:05+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 03 16:10:05 volumiob go-librespot[21531]: time="2025-05-03T16:10:05+02:00" level=debug msg="zeroconf server listening on port 39203" May 03 16:10:05 volumiob go-librespot[21531]: time="2025-05-03T16:10:05+02:00" level=debug msg="obtained new client token: AAAGpU8ve4ro+Je2WJkPUSHe6dp31Pw2jbM3dr1aUCOvNgFI2TmW+sCgFVSz2AAon+WnPYvg25nfhgUY7O3gdWGury3fsgEbUU0P5Aux7kuC7VcGIL7sGN4wHDjLZREzwWfxFJav1CxHc8HTRilNYsyZG70TpChuPEaQBJ6NDt/CEiix1aguOWOvjRSB9ABCEsYoVP8EriVO9X8y0YNPf9Cwxx8cqdQz1038owPrY+Gc3bJ+1fr61hbTqQ==" May 03 16:10:05 volumiob go-librespot[21531]: time="2025-05-03T16:10:05+02:00" level=info msg="connected to ap-gew1.spotify.com:4070" May 03 16:10:05 volumiob go-librespot[21531]: time="2025-05-03T16:10:05+02:00" level=debug msg="completed keyexchange" May 03 16:10:05 volumiob go-librespot[21531]: time="2025-05-03T16:10:05+02:00" level=debug msg="completed challenge" May 03 16:10:05 volumiob go-librespot[21531]: time="2025-05-03T16:10:05+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" May 03 16:10:05 volumiob systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 03 16:10:05 volumiob systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 03 16:10:05 volumiob volumio[857]: info: Initializing connection to go-librespot Websocket May 03 16:10:05 volumiob volumio[857]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 03 16:10:08 volumiob volumio[857]: info: Initializing connection to go-librespot Websocket May 03 16:10:08 volumiob volumio[857]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 03 16:10:09 volumiob systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 03 16:10:09 volumiob systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4173. May 03 16:10:09 volumiob systemd[1]: Stopped go-librespot Daemon. May 03 16:10:09 volumiob systemd[1]: Started go-librespot Daemon. May 03 16:10:09 volumiob go-librespot[21547]: Librespot-go daemon starting... May 03 16:10:09 volumiob go-librespot[21547]: time="2025-05-03T16:10:09+02:00" level=info msg="generated new device id: deadb8871f421f92e897cf0bd8b7aab8e701881e" May 03 16:10:09 volumiob go-librespot[21547]: time="2025-05-03T16:10:09+02:00" level=debug msg="stored credentials found for ov2ch4rvdqh5kv7qx4s9m5f5y" May 03 16:10:09 volumiob go-librespot[21547]: time="2025-05-03T16:10:09+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" May 03 16:10:09 volumiob go-librespot[21547]: time="2025-05-03T16:10:09+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 03 16:10:09 volumiob go-librespot[21547]: time="2025-05-03T16:10:09+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 03 16:10:09 volumiob go-librespot[21547]: time="2025-05-03T16:10:09+02:00" level=debug msg="zeroconf server listening on port 38771" May 03 16:10:09 volumiob go-librespot[21547]: time="2025-05-03T16:10:09+02:00" level=debug msg="obtained new client token: AABbUEWPXZe04TXW9uYDegxrTkWuepQQX9oSUPtC0F5orQfZZM9SnqvRBgFr8xmjPUn2HTV7ZH1oScCrupkKZjFjedIi34ydQse12Nx0gfo2phfYh9E6d/EEWWV1AnP7cYaARDQe53H1wpkWrKm0tDvYx6+jj+4P9RIJsRZYFscAkAmP3xOR7b8oCGZkR5e26KKOfGT8CbIIZCqns5X8SYtGAVDgyBX+mhd7vrhC4zTza82MBwcn+tXvGg==" May 03 16:10:09 volumiob go-librespot[21547]: time="2025-05-03T16:10:09+02:00" level=info msg="connected to ap-gew1.spotify.com:4070" May 03 16:10:09 volumiob go-librespot[21547]: time="2025-05-03T16:10:09+02:00" level=debug msg="completed keyexchange" May 03 16:10:09 volumiob go-librespot[21547]: time="2025-05-03T16:10:09+02:00" level=debug msg="completed challenge" May 03 16:10:10 volumiob go-librespot[21547]: time="2025-05-03T16:10:10+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" May 03 16:10:10 volumiob systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 03 16:10:10 volumiob systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 03 16:10:11 volumiob volumio[857]: info: Initializing connection to go-librespot Websocket May 03 16:10:11 volumiob volumio[857]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 03 16:10:13 volumiob systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 03 16:10:13 volumiob systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4174. May 03 16:10:13 volumiob systemd[1]: Stopped go-librespot Daemon. May 03 16:10:13 volumiob systemd[1]: Started go-librespot Daemon. May 03 16:10:13 volumiob go-librespot[21563]: Librespot-go daemon starting... May 03 16:10:13 volumiob go-librespot[21563]: time="2025-05-03T16:10:13+02:00" level=info msg="generated new device id: 474e2651d2d6cc0a3c231f3cd111190f4661b652" May 03 16:10:13 volumiob go-librespot[21563]: time="2025-05-03T16:10:13+02:00" level=debug msg="stored credentials found for ov2ch4rvdqh5kv7qx4s9m5f5y" May 03 16:10:13 volumiob go-librespot[21563]: time="2025-05-03T16:10:13+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" May 03 16:10:13 volumiob go-librespot[21563]: time="2025-05-03T16:10:13+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" May 03 16:10:13 volumiob go-librespot[21563]: time="2025-05-03T16:10:13+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" May 03 16:10:13 volumiob go-librespot[21563]: time="2025-05-03T16:10:13+02:00" level=debug msg="zeroconf server listening on port 46173" May 03 16:10:13 volumiob go-librespot[21563]: time="2025-05-03T16:10:13+02:00" level=debug msg="obtained new client token: AACAZnZ9/8QbhQNYIjwOryg7NNaJrypA4azsuPpGsEUTzYSaK5Glg8BCvGeKpzOSyKGf808MGGlcwOEr2HwvksMeRynteo8YEWeC/OO6fw+5eoMlSQR/LiLqskaKwTarcYu9pJLJidveuzmdovbHUVA0b3MPE3slSX9mzUCG7HfycRPBG7XsmKbBWtT85UUsMsJ2BqSIWsqPuiykNfyc3sARA/wfi05RNc5krGepUxyBgHxBi4yqkVVakQ==" May 03 16:10:13 volumiob go-librespot[21563]: time="2025-05-03T16:10:13+02:00" level=info msg="connected to ap-gew1.spotify.com:4070" May 03 16:10:13 volumiob go-librespot[21563]: time="2025-05-03T16:10:13+02:00" level=debug msg="completed keyexchange" May 03 16:10:13 volumiob go-librespot[21563]: time="2025-05-03T16:10:13+02:00" level=debug msg="completed challenge" May 03 16:10:13 volumiob go-librespot[21563]: time="2025-05-03T16:10:13+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" May 03 16:10:13 volumiob systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 03 16:10:13 volumiob systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 03 16:10:14 volumiob volumio[857]: info: Initializing connection to go-librespot Websocket May 03 16:10:14 volumiob volumio[857]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 03 16:10:17 volumiob systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 03 16:10:17 volumiob systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4175. May 03 16:10:17 volumiob systemd[1]: Stopped go-librespot Daemon. May 03 16:10:17 volumiob systemd[1]: Started go-librespot Daemon. May 03 16:10:17 volumiob go-librespot[21577]: Librespot-go daemon starting... May 03 16:10:17 volumiob go-librespot[21577]: time="2025-05-03T16:10:17+02:00" level=info msg="generated new device id: 29da70d4f179e10449809f3e5890d5f481f1f1c8" May 03 16:10:17 volumiob go-librespot[21577]: time="2025-05-03T16:10:17+02:00" level=debug msg="stored credentials found for ov2ch4rvdqh5kv7qx4s9m5f5y" May 03 16:10:17 volumiob go-librespot[21577]: time="2025-05-03T16:10:17+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" May 03 16:10:17 volumiob go-librespot[21577]: time="2025-05-03T16:10:17+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 03 16:10:17 volumiob go-librespot[21577]: time="2025-05-03T16:10:17+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 03 16:10:17 volumiob go-librespot[21577]: time="2025-05-03T16:10:17+02:00" level=debug msg="zeroconf server listening on port 36631" May 03 16:10:17 volumiob go-librespot[21577]: time="2025-05-03T16:10:17+02:00" level=debug msg="obtained new client token: AAB4OqZI0wE7hBZ+JvG1UzqUN/UMJSnQAMIv+P1Vub+sadu1nRCxmWgmP90/t1kAOTn59Pgzzv5ox761qTcHadUwMvPkDh+Jr3akoWOadg9nI2PzSs+Iqug1sA6u1vymkPE09YaEnkb1pC54uZsSVJVb1iNqlmdesoC1uV6uMfs+gPoVhEkeIOt5d4DBB+8/w0O1wP564TXZjrB0s0W0j3FFuNCTxhg8nkHG6S+q28mOB6CMCO2U2jNkOA==" May 03 16:10:17 volumiob go-librespot[21577]: time="2025-05-03T16:10:17+02:00" level=info msg="connected to ap-gew1.spotify.com:4070" May 03 16:10:17 volumiob go-librespot[21577]: time="2025-05-03T16:10:17+02:00" level=debug msg="completed keyexchange" May 03 16:10:17 volumiob go-librespot[21577]: time="2025-05-03T16:10:17+02:00" level=debug msg="completed challenge" May 03 16:10:17 volumiob go-librespot[21577]: time="2025-05-03T16:10:17+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" May 03 16:10:17 volumiob systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 03 16:10:17 volumiob systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 03 16:10:17 volumiob volumio[857]: info: Initializing connection to go-librespot Websocket May 03 16:10:17 volumiob volumio[857]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 03 16:10:20 volumiob volumio[857]: info: Initializing connection to go-librespot Websocket May 03 16:10:20 volumiob volumio[857]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 03 16:10:21 volumiob systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 03 16:10:21 volumiob systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4176. May 03 16:10:21 volumiob systemd[1]: Stopped go-librespot Daemon. May 03 16:10:21 volumiob systemd[1]: Started go-librespot Daemon. May 03 16:10:21 volumiob go-librespot[21594]: Librespot-go daemon starting... May 03 16:10:21 volumiob go-librespot[21594]: time="2025-05-03T16:10:21+02:00" level=info msg="generated new device id: fd6e6f0a54b1aae93bff926dd85696a75727aabd" May 03 16:10:21 volumiob go-librespot[21594]: time="2025-05-03T16:10:21+02:00" level=debug msg="stored credentials found for ov2ch4rvdqh5kv7qx4s9m5f5y" May 03 16:10:21 volumiob go-librespot[21594]: time="2025-05-03T16:10:21+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" May 03 16:10:21 volumiob go-librespot[21594]: time="2025-05-03T16:10:21+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 03 16:10:21 volumiob go-librespot[21594]: time="2025-05-03T16:10:21+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 03 16:10:21 volumiob go-librespot[21594]: time="2025-05-03T16:10:21+02:00" level=debug msg="zeroconf server listening on port 38851" May 03 16:10:21 volumiob go-librespot[21594]: time="2025-05-03T16:10:21+02:00" level=debug msg="obtained new client token: AADNCzO7MlAtJZkI65tGhzKyNfId/suNvQDYsRH6q0rRhBUT9ee36lo8XZEMGuZf21h2OjAO4gTZiGNTKCA4M8qrJ1e1Kl4hqhCx/BKeqqD1hAE8DPQnOGgk1IQnqWvWcbqL1Ae6lyLF+SXmj5xr3gIDgw6kyXWKDfLSGVj8xBqpdUe3E3X8ALVM+DZBcSYC0nXZqWxH8HbccXVJPr9VVT4Oxe6HcEnEsYTEybNuMk7soQwJzBejeKovuw==" May 03 16:10:21 volumiob go-librespot[21594]: time="2025-05-03T16:10:21+02:00" level=info msg="connected to ap-gew1.spotify.com:4070" May 03 16:10:21 volumiob go-librespot[21594]: time="2025-05-03T16:10:21+02:00" level=debug msg="completed keyexchange" May 03 16:10:21 volumiob go-librespot[21594]: time="2025-05-03T16:10:21+02:00" level=debug msg="completed challenge" May 03 16:10:21 volumiob go-librespot[21594]: time="2025-05-03T16:10:21+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" May 03 16:10:21 volumiob systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 03 16:10:21 volumiob systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 03 16:10:23 volumiob volumio[857]: info: Initializing connection to go-librespot Websocket May 03 16:10:23 volumiob volumio[857]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 03 16:10:25 volumiob systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 03 16:10:25 volumiob systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4177. May 03 16:10:25 volumiob systemd[1]: Stopped go-librespot Daemon. May 03 16:10:25 volumiob systemd[1]: Started go-librespot Daemon. May 03 16:10:25 volumiob go-librespot[21611]: Librespot-go daemon starting... May 03 16:10:25 volumiob go-librespot[21611]: time="2025-05-03T16:10:25+02:00" level=info msg="generated new device id: e6172000bdd3465ca6966e7fa73623435bb48a80" May 03 16:10:25 volumiob go-librespot[21611]: time="2025-05-03T16:10:25+02:00" level=debug msg="stored credentials found for ov2ch4rvdqh5kv7qx4s9m5f5y" May 03 16:10:25 volumiob go-librespot[21611]: time="2025-05-03T16:10:25+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" May 03 16:10:25 volumiob go-librespot[21611]: time="2025-05-03T16:10:25+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" May 03 16:10:25 volumiob go-librespot[21611]: time="2025-05-03T16:10:25+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" May 03 16:10:25 volumiob go-librespot[21611]: time="2025-05-03T16:10:25+02:00" level=debug msg="zeroconf server listening on port 38549" May 03 16:10:25 volumiob go-librespot[21611]: time="2025-05-03T16:10:25+02:00" level=debug msg="obtained new client token: AADMcSfiqKr3oRApnAnQMy3+2BrCDjTOchBqq1gfyyzlWJqt84G8YTCanO2XMPRQtYf3UelR7roDZg3kXZLMGJVdR4N3pdVVqXLfdB3KiCq5Zlpa9r5DCFojHk2LooqbPznMGho1gRUX3NidIuyF6fD9A0oMH99CXow6oz8Bkr6AD1yGHGMGDHmFoXQQ/aUEMjXhygyhuLqb7AjlJJ3fe2nGdgrlpBM2hYCdmLOaRs+vfA28OQxeuB7fEg==" May 03 16:10:25 volumiob go-librespot[21611]: time="2025-05-03T16:10:25+02:00" level=info msg="connected to ap-gew1.spotify.com:4070" May 03 16:10:25 volumiob go-librespot[21611]: time="2025-05-03T16:10:25+02:00" level=debug msg="completed keyexchange" May 03 16:10:25 volumiob go-librespot[21611]: time="2025-05-03T16:10:25+02:00" level=debug msg="completed challenge" May 03 16:10:25 volumiob go-librespot[21611]: time="2025-05-03T16:10:25+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" May 03 16:10:25 volumiob systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 03 16:10:25 volumiob systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 03 16:10:26 volumiob volumio[857]: info: Initializing connection to go-librespot Websocket May 03 16:10:26 volumiob volumio[857]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 03 16:10:29 volumiob systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 03 16:10:29 volumiob systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4178. May 03 16:10:29 volumiob systemd[1]: Stopped go-librespot Daemon. May 03 16:10:29 volumiob systemd[1]: Started go-librespot Daemon. May 03 16:10:29 volumiob go-librespot[21627]: Librespot-go daemon starting... May 03 16:10:29 volumiob go-librespot[21627]: time="2025-05-03T16:10:29+02:00" level=info msg="generated new device id: 46bf3856d9360e082e25754bc9abd15aa2398d9d" May 03 16:10:29 volumiob go-librespot[21627]: time="2025-05-03T16:10:29+02:00" level=debug msg="stored credentials found for ov2ch4rvdqh5kv7qx4s9m5f5y" May 03 16:10:29 volumiob go-librespot[21627]: time="2025-05-03T16:10:29+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" May 03 16:10:29 volumiob go-librespot[21627]: time="2025-05-03T16:10:29+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 03 16:10:29 volumiob go-librespot[21627]: time="2025-05-03T16:10:29+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 03 16:10:29 volumiob go-librespot[21627]: time="2025-05-03T16:10:29+02:00" level=debug msg="zeroconf server listening on port 43283" May 03 16:10:29 volumiob go-librespot[21627]: time="2025-05-03T16:10:29+02:00" level=debug msg="obtained new client token: AAA+6R1oWFAqRf57gULEAeAkQ5gBpWXmEDopA037IwisU2kPGGqbjAqccymRsTxYOotfP8QSvivKIHCTsnpBOIi1zzZXkL1cO1rraAOYN7Mvj0+aiLjFFrdWFHsVOIP0yPVRMCZMwFtC2pQ/ESSkHkLCPobVLXk5NsKNUGPwDGdDfkIstjmGIJxq8K6WylOBdTGDw2B0VpKvNTaBYsuzN+/P/raKnmflBWy9FYnnbE7vVXQOoo+EEIHC2w==" May 03 16:10:29 volumiob go-librespot[21627]: time="2025-05-03T16:10:29+02:00" level=info msg="connected to ap-gew1.spotify.com:4070" May 03 16:10:29 volumiob go-librespot[21627]: time="2025-05-03T16:10:29+02:00" level=debug msg="completed keyexchange" May 03 16:10:29 volumiob go-librespot[21627]: time="2025-05-03T16:10:29+02:00" level=debug msg="completed challenge" May 03 16:10:29 volumiob volumio[857]: info: Initializing connection to go-librespot Websocket May 03 16:10:29 volumiob go-librespot[21627]: time="2025-05-03T16:10:29+02:00" level=debug msg="new websocket client" May 03 16:10:29 volumiob volumio[857]: info: Connection to go-librespot Websocket established May 03 16:10:30 volumiob go-librespot[21627]: time="2025-05-03T16:10:30+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" May 03 16:10:30 volumiob systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 03 16:10:30 volumiob volumio[857]: info: Connection to go-librespot Websocket closed May 03 16:10:30 volumiob systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 03 16:10:32 volumiob volumio[857]: info: Getting Spotify volume May 03 16:10:32 volumiob volumio[857]: (node:857) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 May 03 16:10:32 volumiob volumio[857]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) May 03 16:10:32 volumiob volumio[857]: (node:857) 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: 335) May 03 16:10:32 volumiob volumio[857]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 8 May 03 16:10:32 volumiob volumio[857]: info: CoreCommandRouter::volumioGetState May 03 16:10:33 volumiob volumio[857]: SPOTIFY: RECEIVED VOLUMIO VOLUME 76 May 03 16:10:33 volumiob volumio[857]: info: Initializing connection to go-librespot Websocket May 03 16:10:33 volumiob volumio[857]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 03 16:10:33 volumiob systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 03 16:10:33 volumiob systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4179. May 03 16:10:33 volumiob systemd[1]: Stopped go-librespot Daemon. May 03 16:10:33 volumiob systemd[1]: Started go-librespot Daemon. May 03 16:10:33 volumiob go-librespot[21685]: Librespot-go daemon starting... May 03 16:10:33 volumiob go-librespot[21685]: time="2025-05-03T16:10:33+02:00" level=info msg="generated new device id: 67ad6ffda8503eeaf1eca4ccc4a561e241f8b693" May 03 16:10:33 volumiob go-librespot[21685]: time="2025-05-03T16:10:33+02:00" level=debug msg="stored credentials found for ov2ch4rvdqh5kv7qx4s9m5f5y" May 03 16:10:33 volumiob go-librespot[21685]: time="2025-05-03T16:10:33+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" May 03 16:10:33 volumiob go-librespot[21685]: time="2025-05-03T16:10:33+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 03 16:10:33 volumiob go-librespot[21685]: time="2025-05-03T16:10:33+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 03 16:10:33 volumiob go-librespot[21685]: time="2025-05-03T16:10:33+02:00" level=debug msg="zeroconf server listening on port 46881" May 03 16:10:33 volumiob go-librespot[21685]: time="2025-05-03T16:10:33+02:00" level=debug msg="obtained new client token: AAAMXOnFXRW7mZFHDWov0aS8ZEyitcFhQK20BkB7llX4SnakRKfv6SSWAkIvL4sM4vSeW9I3LUZ/9gpjqmI56P2/5v9iyf8sCka3VsvMLXP9Za9tbhxWEANCDoQs46a4qtRiis9x+lMblQ6eMAIkWblzM900Kvn5947buBVQq5hl/lB5MW/OBz3Ebe/h/WkykZhGUldR88WMgSp/QrS5Xs+5k+OmVMElePg5nbEFMGN3Vl/4cf3zwaJnXQ==" May 03 16:10:33 volumiob go-librespot[21685]: time="2025-05-03T16:10:33+02:00" level=info msg="connected to ap-gew1.spotify.com:4070" May 03 16:10:33 volumiob go-librespot[21685]: time="2025-05-03T16:10:33+02:00" level=debug msg="completed keyexchange" May 03 16:10:33 volumiob go-librespot[21685]: time="2025-05-03T16:10:33+02:00" level=debug msg="completed challenge" May 03 16:10:33 volumiob go-librespot[21685]: time="2025-05-03T16:10:33+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" May 03 16:10:33 volumiob systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 03 16:10:33 volumiob systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 03 16:10:36 volumiob volumio[857]: info: Initializing connection to go-librespot Websocket May 03 16:10:36 volumiob volumio[857]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 03 16:10:37 volumiob systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 03 16:10:37 volumiob systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4180. May 03 16:10:37 volumiob systemd[1]: Stopped go-librespot Daemon. May 03 16:10:37 volumiob systemd[1]: Started go-librespot Daemon. May 03 16:10:37 volumiob go-librespot[21701]: Librespot-go daemon starting... May 03 16:10:37 volumiob go-librespot[21701]: time="2025-05-03T16:10:37+02:00" level=info msg="generated new device id: 32fb442d123f5b94b0b26ecf6e2487dfd3f04ec3" May 03 16:10:37 volumiob go-librespot[21701]: time="2025-05-03T16:10:37+02:00" level=debug msg="stored credentials found for ov2ch4rvdqh5kv7qx4s9m5f5y" May 03 16:10:37 volumiob go-librespot[21701]: time="2025-05-03T16:10:37+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" May 03 16:10:37 volumiob go-librespot[21701]: time="2025-05-03T16:10:37+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 03 16:10:37 volumiob go-librespot[21701]: time="2025-05-03T16:10:37+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 03 16:10:37 volumiob go-librespot[21701]: time="2025-05-03T16:10:37+02:00" level=debug msg="zeroconf server listening on port 46295" May 03 16:10:37 volumiob go-librespot[21701]: time="2025-05-03T16:10:37+02:00" level=debug msg="obtained new client token: AADPvPHErcE8PdbsTdsfRLQJv0C+JKRyNr4PicFixN0OiQHV6Q6dUvrjxC8UcnAuwNY2WKPgHsTV+aOYU81VfJzoEbt5G97TM6dyjkfCS7StPXhn2RedrGFnvdy8TEenqtp9HQgiDRC6lgZTnSVFA5VhpCQx3Ko2ODkT40y/zO3V4o+O1kAas5BcYeOEUJrDPN1HtwE4YKDnXCHhcWbrpoqYc3LmSibxpCwz0KV/AN6aZyK/zvQ8A/Ux2A==" May 03 16:10:37 volumiob go-librespot[21701]: time="2025-05-03T16:10:37+02:00" level=info msg="connected to ap-gew1.spotify.com:4070" May 03 16:10:37 volumiob go-librespot[21701]: time="2025-05-03T16:10:37+02:00" level=debug msg="completed keyexchange" May 03 16:10:37 volumiob go-librespot[21701]: time="2025-05-03T16:10:37+02:00" level=debug msg="completed challenge" May 03 16:10:37 volumiob go-librespot[21701]: time="2025-05-03T16:10:37+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" May 03 16:10:37 volumiob systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 03 16:10:37 volumiob systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 03 16:10:39 volumiob volumio[857]: info: Initializing connection to go-librespot Websocket May 03 16:10:39 volumiob volumio[857]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 03 16:10:41 volumiob systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 03 16:10:41 volumiob systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4181. May 03 16:10:41 volumiob systemd[1]: Stopped go-librespot Daemon. May 03 16:10:41 volumiob systemd[1]: Started go-librespot Daemon. May 03 16:10:41 volumiob go-librespot[21718]: Librespot-go daemon starting... May 03 16:10:41 volumiob go-librespot[21718]: time="2025-05-03T16:10:41+02:00" level=info msg="generated new device id: 0e0623f9695b758d3750695208064141b67a4674" May 03 16:10:41 volumiob go-librespot[21718]: time="2025-05-03T16:10:41+02:00" level=debug msg="stored credentials found for ov2ch4rvdqh5kv7qx4s9m5f5y" May 03 16:10:41 volumiob go-librespot[21718]: time="2025-05-03T16:10:41+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" May 03 16:10:41 volumiob go-librespot[21718]: time="2025-05-03T16:10:41+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" May 03 16:10:41 volumiob go-librespot[21718]: time="2025-05-03T16:10:41+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" May 03 16:10:41 volumiob go-librespot[21718]: time="2025-05-03T16:10:41+02:00" level=debug msg="zeroconf server listening on port 41329" May 03 16:10:41 volumiob go-librespot[21718]: time="2025-05-03T16:10:41+02:00" level=debug msg="obtained new client token: AADLGj0FL0BTLblTw6xxwPzH00ZS/2bMF/RdOGJ8gVHqvVm6BePv4fN2O+XE2wUUcfcl018uhdB9XPypi8j82N+k/4RONfLMa9VqKPX2+90/GpTa+wLMQlFN9b+H1CTkFE9x69Yh8BD6NeKF0ngOAX++nC2kM1p77Jg62DXowNbILqc4bAkYm5IO5BTSmYPvKe+5KX/thcT81tqidgh2lrJpWasMk1I1NkQXsqr6duSw84oX09aHTWHsgQ==" May 03 16:10:41 volumiob go-librespot[21718]: time="2025-05-03T16:10:41+02:00" level=info msg="connected to ap-gew1.spotify.com:4070" May 03 16:10:41 volumiob go-librespot[21718]: time="2025-05-03T16:10:41+02:00" level=debug msg="completed keyexchange" May 03 16:10:41 volumiob go-librespot[21718]: time="2025-05-03T16:10:41+02:00" level=debug msg="completed challenge" May 03 16:10:41 volumiob go-librespot[21718]: time="2025-05-03T16:10:41+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" May 03 16:10:41 volumiob systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 03 16:10:41 volumiob systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 03 16:10:42 volumiob volumio[857]: info: Initializing connection to go-librespot Websocket May 03 16:10:42 volumiob volumio[857]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 03 16:10:45 volumiob volumio[857]: info: Initializing connection to go-librespot Websocket May 03 16:10:45 volumiob systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 03 16:10:45 volumiob systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4182. May 03 16:10:45 volumiob systemd[1]: Stopped go-librespot Daemon. May 03 16:10:45 volumiob volumio[857]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 03 16:10:45 volumiob systemd[1]: Started go-librespot Daemon. May 03 16:10:45 volumiob go-librespot[21734]: Librespot-go daemon starting... May 03 16:10:45 volumiob go-librespot[21734]: time="2025-05-03T16:10:45+02:00" level=info msg="generated new device id: 4918295c5c32a36bbd9134aacc1c2be1efadce98" May 03 16:10:45 volumiob go-librespot[21734]: time="2025-05-03T16:10:45+02:00" level=debug msg="stored credentials found for ov2ch4rvdqh5kv7qx4s9m5f5y" May 03 16:10:45 volumiob go-librespot[21734]: time="2025-05-03T16:10:45+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" May 03 16:10:45 volumiob go-librespot[21734]: time="2025-05-03T16:10:45+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 03 16:10:45 volumiob go-librespot[21734]: time="2025-05-03T16:10:45+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 03 16:10:45 volumiob go-librespot[21734]: time="2025-05-03T16:10:45+02:00" level=debug msg="zeroconf server listening on port 36223" May 03 16:10:45 volumiob go-librespot[21734]: time="2025-05-03T16:10:45+02:00" level=debug msg="obtained new client token: AAA90tzRoZAYYr0At0Q/PkSrCiuCBlNyJLlg1u4YO5Z37C4GyQVlI+hyDxOb0nvaQmEo1r57l/J5THPaF9yj/KnfgxvaZF+4/lCfFsfv4U1H96rinaiGwUC012vRkPzWjSRgjV3UL34ojQYpxHPLscfPC7HM1m4B410Y67IBltqHjDo/bh/il5LLswyoJGsZRBT7gsViVIaFlGKN6ytIZSO6xsnHhRt1OaD3mRETii+KV7df/qNdK3k=" May 03 16:10:45 volumiob go-librespot[21734]: time="2025-05-03T16:10:45+02:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070 (error: dial tcp 104.199.65.9:4070: connect: connection refused), retrying with a different AP" May 03 16:10:45 volumiob go-librespot[21734]: time="2025-05-03T16:10:45+02:00" level=info msg="connected to ap-gew1.spotify.com:443" May 03 16:10:45 volumiob go-librespot[21734]: time="2025-05-03T16:10:45+02:00" level=debug msg="completed keyexchange" May 03 16:10:45 volumiob go-librespot[21734]: time="2025-05-03T16:10:45+02:00" level=debug msg="completed challenge" May 03 16:10:45 volumiob go-librespot[21734]: time="2025-05-03T16:10:45+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" May 03 16:10:45 volumiob systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 03 16:10:45 volumiob systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 03 16:10:48 volumiob volumio[857]: info: Initializing connection to go-librespot Websocket May 03 16:10:48 volumiob volumio[857]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 03 16:10:49 volumiob systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 03 16:10:49 volumiob systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4183. May 03 16:10:49 volumiob systemd[1]: Stopped go-librespot Daemon. May 03 16:10:49 volumiob systemd[1]: Started go-librespot Daemon. May 03 16:10:49 volumiob go-librespot[21750]: Librespot-go daemon starting... May 03 16:10:49 volumiob go-librespot[21750]: time="2025-05-03T16:10:49+02:00" level=info msg="generated new device id: c4e76b76769563ce8b3ad361901f1435998ced26" May 03 16:10:49 volumiob go-librespot[21750]: time="2025-05-03T16:10:49+02:00" level=debug msg="stored credentials found for ov2ch4rvdqh5kv7qx4s9m5f5y" May 03 16:10:49 volumiob go-librespot[21750]: time="2025-05-03T16:10:49+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" May 03 16:10:49 volumiob go-librespot[21750]: time="2025-05-03T16:10:49+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 03 16:10:49 volumiob go-librespot[21750]: time="2025-05-03T16:10:49+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 03 16:10:49 volumiob go-librespot[21750]: time="2025-05-03T16:10:49+02:00" level=debug msg="zeroconf server listening on port 37685" May 03 16:10:49 volumiob go-librespot[21750]: time="2025-05-03T16:10:49+02:00" level=debug msg="obtained new client token: AABKUb2WSJfn8eEZeNoVOwWr1VU2f6M/+hRSqDtn0yFMjXXyQ6Vvl31Mr5HUW3B7mhBpmHDdpADsLUoSqxPm6OMtQqXmoo3SCvILHD8OJX9gh8Pl+hpy9K3dKEnjjpzvp8nf2V/8z+gYl+wQeBt6XYlSbfDfjwdc8ajekuh5gMxBjYSvxiEeo9zNYGi8JAc4WJxy+a8IqrRG9NupD2E3Ghv8TIuHMtdZBL/GM0Paz6zWrupbnV5u2wSBCw==" May 03 16:10:49 volumiob go-librespot[21750]: time="2025-05-03T16:10:49+02:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070 (error: dial tcp 104.199.65.9:4070: connect: connection refused), retrying with a different AP" May 03 16:10:49 volumiob go-librespot[21750]: time="2025-05-03T16:10:49+02:00" level=info msg="connected to ap-gew1.spotify.com:443" May 03 16:10:49 volumiob go-librespot[21750]: time="2025-05-03T16:10:49+02:00" level=debug msg="completed keyexchange" May 03 16:10:50 volumiob go-librespot[21750]: time="2025-05-03T16:10:50+02:00" level=debug msg="completed challenge" May 03 16:10:50 volumiob go-librespot[21750]: time="2025-05-03T16:10:50+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" May 03 16:10:50 volumiob systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 03 16:10:50 volumiob systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 03 16:10:51 volumiob volumio[857]: info: Initializing connection to go-librespot Websocket May 03 16:10:51 volumiob volumio[857]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 03 16:10:53 volumiob systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 03 16:10:53 volumiob systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4184. May 03 16:10:53 volumiob systemd[1]: Stopped go-librespot Daemon. May 03 16:10:53 volumiob systemd[1]: Started go-librespot Daemon. May 03 16:10:53 volumiob go-librespot[21764]: Librespot-go daemon starting... May 03 16:10:53 volumiob go-librespot[21764]: time="2025-05-03T16:10:53+02:00" level=info msg="generated new device id: b07d7c426b673ee40a86eeeb51d43baa8f8b2616" May 03 16:10:53 volumiob go-librespot[21764]: time="2025-05-03T16:10:53+02:00" level=debug msg="stored credentials found for ov2ch4rvdqh5kv7qx4s9m5f5y" May 03 16:10:53 volumiob go-librespot[21764]: time="2025-05-03T16:10:53+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" May 03 16:10:53 volumiob go-librespot[21764]: time="2025-05-03T16:10:53+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 03 16:10:53 volumiob go-librespot[21764]: time="2025-05-03T16:10:53+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 03 16:10:53 volumiob go-librespot[21764]: time="2025-05-03T16:10:53+02:00" level=debug msg="zeroconf server listening on port 46209" May 03 16:10:53 volumiob go-librespot[21764]: time="2025-05-03T16:10:53+02:00" level=debug msg="obtained new client token: AAA4tB+rVe43CxKLy/ndjkW/IM4jJBYG95hWIbmi0V1NzHkzgBNwGji7bOYI7nmLTCIKOLFbmQT6SisSvc7k2ceQXQTQUPSA3ipt7yhWPQxv0tqKuEi/egtGD6qs9AKsft/G2ev+e9SdU4+vJy9PBKtNZ6U+dqXHIdcdpBTgCCBvMthBM0Kg6eFASGx4lt6TaNkhAW+FbOLb9Wi33x9x58GEy+5FFTgrGsA/5/Gg0oahpAijPXExJzu3og==" May 03 16:10:53 volumiob go-librespot[21764]: time="2025-05-03T16:10:53+02:00" level=info msg="connected to ap-gew1.spotify.com:4070" May 03 16:10:53 volumiob go-librespot[21764]: time="2025-05-03T16:10:53+02:00" level=debug msg="completed keyexchange" May 03 16:10:53 volumiob go-librespot[21764]: time="2025-05-03T16:10:53+02:00" level=debug msg="completed challenge" May 03 16:10:53 volumiob go-librespot[21764]: time="2025-05-03T16:10:53+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" May 03 16:10:53 volumiob systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 03 16:10:53 volumiob systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 03 16:10:54 volumiob volumio[857]: info: Initializing connection to go-librespot Websocket May 03 16:10:54 volumiob volumio[857]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 03 16:10:57 volumiob volumio[857]: info: Initializing connection to go-librespot Websocket May 03 16:10:57 volumiob volumio[857]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 03 16:10:57 volumiob systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 03 16:10:57 volumiob systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4185. May 03 16:10:57 volumiob systemd[1]: Stopped go-librespot Daemon. May 03 16:10:57 volumiob systemd[1]: Started go-librespot Daemon. May 03 16:10:57 volumiob go-librespot[21780]: Librespot-go daemon starting... May 03 16:10:57 volumiob go-librespot[21780]: time="2025-05-03T16:10:57+02:00" level=info msg="generated new device id: fff0e69bae8d4e8479dd3c21a18644eb8bd87fcd" May 03 16:10:57 volumiob go-librespot[21780]: time="2025-05-03T16:10:57+02:00" level=debug msg="stored credentials found for ov2ch4rvdqh5kv7qx4s9m5f5y" May 03 16:10:57 volumiob go-librespot[21780]: time="2025-05-03T16:10:57+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" May 03 16:10:57 volumiob go-librespot[21780]: time="2025-05-03T16:10:57+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 03 16:10:57 volumiob go-librespot[21780]: time="2025-05-03T16:10:57+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 03 16:10:57 volumiob go-librespot[21780]: time="2025-05-03T16:10:57+02:00" level=debug msg="zeroconf server listening on port 37475" May 03 16:10:57 volumiob go-librespot[21780]: time="2025-05-03T16:10:57+02:00" level=debug msg="obtained new client token: AADSnbedXfk2uxKvsE2K+cfgOGlD8xQPSwDNQppm7h7C0Xnci9kts+n4vH0Lg+E6rOqtvq6NuB5AzSzZOvA3gdYqdbOZBLDJZwNSkU8JPdjhi7lzdTbsayX4tmIjxvJAChuVolOLm7fGXf7muOVcMh4wOTEWRuksuZqB5dj7LI0r7WL7kqkT6NGoh+0+mbyJ//DtnclFp31hZz4A8qxkxWzfCzHNDO2HxGb8pePMAAv422Ny4YcU9wEhAg==" May 03 16:10:57 volumiob go-librespot[21780]: time="2025-05-03T16:10:57+02:00" level=info msg="connected to ap-gew1.spotify.com:4070" May 03 16:10:57 volumiob go-librespot[21780]: time="2025-05-03T16:10:57+02:00" level=debug msg="completed keyexchange" May 03 16:10:57 volumiob go-librespot[21780]: time="2025-05-03T16:10:57+02:00" level=debug msg="completed challenge" May 03 16:10:57 volumiob go-librespot[21780]: time="2025-05-03T16:10:57+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" May 03 16:10:57 volumiob systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 03 16:10:57 volumiob systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 03 16:11:00 volumiob volumio[857]: info: Initializing connection to go-librespot Websocket May 03 16:11:00 volumiob volumio[857]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 03 16:11:01 volumiob systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 03 16:11:01 volumiob systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4186. May 03 16:11:01 volumiob systemd[1]: Stopped go-librespot Daemon. May 03 16:11:01 volumiob systemd[1]: Started go-librespot Daemon. May 03 16:11:01 volumiob go-librespot[21797]: Librespot-go daemon starting... May 03 16:11:01 volumiob go-librespot[21797]: time="2025-05-03T16:11:01+02:00" level=info msg="generated new device id: fcd28743a82a541994bd0ade1e292df0a6025bac" May 03 16:11:01 volumiob go-librespot[21797]: time="2025-05-03T16:11:01+02:00" level=debug msg="stored credentials found for ov2ch4rvdqh5kv7qx4s9m5f5y" May 03 16:11:01 volumiob go-librespot[21797]: time="2025-05-03T16:11:01+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" May 03 16:11:01 volumiob go-librespot[21797]: time="2025-05-03T16:11:01+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 03 16:11:01 volumiob go-librespot[21797]: time="2025-05-03T16:11:01+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 03 16:11:01 volumiob go-librespot[21797]: time="2025-05-03T16:11:01+02:00" level=debug msg="zeroconf server listening on port 34139" May 03 16:11:01 volumiob go-librespot[21797]: time="2025-05-03T16:11:01+02:00" level=debug msg="obtained new client token: AABHTXnEfvNnVB3D/I1SFPjZ35u079gGcUOzULFc37uEOmSgpjBGOcj+ueX5pSs2vs7xsONzznsXH3fHv3KgyLz+z04123xCG1ZLgwsfPNMNYhl9jyw4bD3qDDT+A6ZABZdmQFMdPw1KzvrPFFEfxu8NSDeViACaNBLMbnaibpc3mrSApcTmJ62iCSp2GhAtcTi7NJgWMXCz9RbV6kaSUR35LB5+2via5B1URR9JJIu55yGFcT623CZT5A==" May 03 16:11:01 volumiob go-librespot[21797]: time="2025-05-03T16:11:01+02:00" level=info msg="connected to ap-gew1.spotify.com:4070" May 03 16:11:01 volumiob go-librespot[21797]: time="2025-05-03T16:11:01+02:00" level=debug msg="completed keyexchange" May 03 16:11:01 volumiob go-librespot[21797]: time="2025-05-03T16:11:01+02:00" level=debug msg="completed challenge" May 03 16:11:01 volumiob go-librespot[21797]: time="2025-05-03T16:11:01+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" May 03 16:11:01 volumiob systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 03 16:11:01 volumiob systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 03 16:11:03 volumiob volumio[857]: info: Initializing connection to go-librespot Websocket May 03 16:11:03 volumiob volumio[857]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 03 16:11:05 volumiob systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 03 16:11:05 volumiob systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4187. May 03 16:11:05 volumiob systemd[1]: Stopped go-librespot Daemon. May 03 16:11:05 volumiob systemd[1]: Started go-librespot Daemon. May 03 16:11:05 volumiob go-librespot[21836]: Librespot-go daemon starting... May 03 16:11:05 volumiob go-librespot[21836]: time="2025-05-03T16:11:05+02:00" level=info msg="generated new device id: dc618d494357992a490ee84b6ee462ac528fbf90" May 03 16:11:05 volumiob go-librespot[21836]: time="2025-05-03T16:11:05+02:00" level=debug msg="stored credentials found for ov2ch4rvdqh5kv7qx4s9m5f5y" May 03 16:11:05 volumiob go-librespot[21836]: time="2025-05-03T16:11:05+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" May 03 16:11:05 volumiob go-librespot[21836]: time="2025-05-03T16:11:05+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" May 03 16:11:05 volumiob go-librespot[21836]: time="2025-05-03T16:11:05+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" May 03 16:11:05 volumiob go-librespot[21836]: time="2025-05-03T16:11:05+02:00" level=debug msg="zeroconf server listening on port 40629" May 03 16:11:05 volumiob go-librespot[21836]: time="2025-05-03T16:11:05+02:00" level=debug msg="obtained new client token: AABKYaDHsesthHkMU87xBBYtl33qKjC4aIi+y7SDp8bYR6tGGuDFNVR8Pf3pZqFAljzIqvrJqhpWCB5LX/+CwcO+t7lcG8R6nuIj6ijJ/N0wrWwbgWezKWoISbNUjxr1BuxFq2A/35bnZr0oslaCDVPDdelhONGsj0HNoin7zbZPPBUoMGARLWtCHlSLtioXRRMpHNYTybwBhFl6vPmJL6KgZY5gmzfAue1h539htEvjoZwakv+hvfBNLg==" May 03 16:11:05 volumiob go-librespot[21836]: time="2025-05-03T16:11:05+02:00" level=info msg="connected to ap-gew1.spotify.com:4070" May 03 16:11:05 volumiob go-librespot[21836]: time="2025-05-03T16:11:05+02:00" level=debug msg="completed keyexchange" May 03 16:11:05 volumiob go-librespot[21836]: time="2025-05-03T16:11:05+02:00" level=debug msg="completed challenge" May 03 16:11:05 volumiob go-librespot[21836]: time="2025-05-03T16:11:05+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" May 03 16:11:05 volumiob systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 03 16:11:05 volumiob systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 03 16:11:06 volumiob volumio[857]: info: Initializing connection to go-librespot Websocket May 03 16:11:06 volumiob volumio[857]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 03 16:11:09 volumiob volumio[857]: info: Initializing connection to go-librespot Websocket May 03 16:11:09 volumiob volumio[857]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 03 16:11:09 volumiob systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 03 16:11:09 volumiob systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4188. May 03 16:11:09 volumiob systemd[1]: Stopped go-librespot Daemon. May 03 16:11:09 volumiob systemd[1]: Started go-librespot Daemon. May 03 16:11:09 volumiob go-librespot[21850]: Librespot-go daemon starting... May 03 16:11:09 volumiob go-librespot[21850]: time="2025-05-03T16:11:09+02:00" level=info msg="generated new device id: c8fe0693a00099697643fccde0fde7da686e0a57" May 03 16:11:09 volumiob go-librespot[21850]: time="2025-05-03T16:11:09+02:00" level=debug msg="stored credentials found for ov2ch4rvdqh5kv7qx4s9m5f5y" May 03 16:11:09 volumiob go-librespot[21850]: time="2025-05-03T16:11:09+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" May 03 16:11:09 volumiob go-librespot[21850]: time="2025-05-03T16:11:09+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 03 16:11:09 volumiob go-librespot[21850]: time="2025-05-03T16:11:09+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 03 16:11:09 volumiob go-librespot[21850]: time="2025-05-03T16:11:09+02:00" level=debug msg="zeroconf server listening on port 35551" May 03 16:11:09 volumiob go-librespot[21850]: time="2025-05-03T16:11:09+02:00" level=debug msg="obtained new client token: AADrzYHk9gNpbxlhbPmYgYbjIVBPY1uajkXAkG09KLSIM0Sv7KN2DwJHGMCizGSzecp8lKkEdzcmdEvV9pVWAXskRCq6ywUQK1A+wcbfhwDZRkbyvuBxVT5yve6Xd6AW+3GEPitMboV57TOEbxSEf9K6yeqVO6YPbTGyfq8FiVH3a6UZOQdtTGPiKos6efk/g76JMYhXtrsWUQx9sUwguXHq62nWNArLMvxqomkLZzUjFU0BrrSDHRqfMA==" May 03 16:11:09 volumiob go-librespot[21850]: time="2025-05-03T16:11:09+02:00" level=info msg="connected to ap-gew1.spotify.com:4070" May 03 16:11:09 volumiob go-librespot[21850]: time="2025-05-03T16:11:09+02:00" level=debug msg="completed keyexchange" May 03 16:11:09 volumiob go-librespot[21850]: time="2025-05-03T16:11:09+02:00" level=debug msg="completed challenge" May 03 16:11:09 volumiob go-librespot[21850]: time="2025-05-03T16:11:09+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" May 03 16:11:09 volumiob systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 03 16:11:09 volumiob systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 03 16:11:12 volumiob volumio[857]: info: Initializing connection to go-librespot Websocket May 03 16:11:12 volumiob volumio[857]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 03 16:11:13 volumiob systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 03 16:11:13 volumiob systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4189. May 03 16:11:13 volumiob systemd[1]: Stopped go-librespot Daemon. May 03 16:11:13 volumiob systemd[1]: Started go-librespot Daemon. May 03 16:11:13 volumiob go-librespot[21866]: Librespot-go daemon starting... May 03 16:11:13 volumiob go-librespot[21866]: time="2025-05-03T16:11:13+02:00" level=info msg="generated new device id: 1c8e81702ead8893b515149d2288e978706b56c5" May 03 16:11:13 volumiob go-librespot[21866]: time="2025-05-03T16:11:13+02:00" level=debug msg="stored credentials found for ov2ch4rvdqh5kv7qx4s9m5f5y" May 03 16:11:13 volumiob go-librespot[21866]: time="2025-05-03T16:11:13+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" May 03 16:11:13 volumiob go-librespot[21866]: time="2025-05-03T16:11:13+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 03 16:11:13 volumiob go-librespot[21866]: time="2025-05-03T16:11:13+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 03 16:11:13 volumiob go-librespot[21866]: time="2025-05-03T16:11:13+02:00" level=debug msg="zeroconf server listening on port 33049" May 03 16:11:13 volumiob go-librespot[21866]: time="2025-05-03T16:11:13+02:00" level=debug msg="obtained new client token: AACrO4Mw7jP2bIjo1QBJW6KNen/wuC/UWNGmQbUs6kc0GTcUmH5WXBprNpo11KFrfNZHm5a42AmdkKncYjrjL/v/WHlLPufb3J+mSdHhvrtKspjEgVqVTZuNKuxeVudwH5zB4BfsgWRzpgwxEo79oYNFRE3woHOCPTRRJruoymoHDnbby9jjAlQ6s0l3dt2DnHshfA4mcmqjfYrYu/GO8StDumiBlEnc/lRzCtno0aKM86WFTv/BS1Gf/A==" May 03 16:11:13 volumiob go-librespot[21866]: time="2025-05-03T16:11:13+02:00" level=info msg="connected to ap-gew1.spotify.com:4070" May 03 16:11:13 volumiob go-librespot[21866]: time="2025-05-03T16:11:13+02:00" level=debug msg="completed keyexchange" May 03 16:11:13 volumiob go-librespot[21866]: time="2025-05-03T16:11:13+02:00" level=debug msg="completed challenge" May 03 16:11:13 volumiob go-librespot[21866]: time="2025-05-03T16:11:13+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" May 03 16:11:13 volumiob systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 03 16:11:13 volumiob systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 03 16:11:15 volumiob volumio[857]: info: Initializing connection to go-librespot Websocket May 03 16:11:15 volumiob volumio[857]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 03 16:11:17 volumiob systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 03 16:11:17 volumiob systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4190. May 03 16:11:17 volumiob systemd[1]: Stopped go-librespot Daemon. May 03 16:11:17 volumiob systemd[1]: Started go-librespot Daemon. May 03 16:11:17 volumiob go-librespot[21882]: Librespot-go daemon starting... May 03 16:11:17 volumiob go-librespot[21882]: time="2025-05-03T16:11:17+02:00" level=info msg="generated new device id: 0037f9e0e29c197dfbeffa2204c69d0dd829f683" May 03 16:11:17 volumiob go-librespot[21882]: time="2025-05-03T16:11:17+02:00" level=debug msg="stored credentials found for ov2ch4rvdqh5kv7qx4s9m5f5y" May 03 16:11:17 volumiob go-librespot[21882]: time="2025-05-03T16:11:17+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" May 03 16:11:17 volumiob go-librespot[21882]: time="2025-05-03T16:11:17+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 03 16:11:17 volumiob go-librespot[21882]: time="2025-05-03T16:11:17+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 03 16:11:17 volumiob go-librespot[21882]: time="2025-05-03T16:11:17+02:00" level=debug msg="zeroconf server listening on port 40371" May 03 16:11:17 volumiob go-librespot[21882]: time="2025-05-03T16:11:17+02:00" level=debug msg="obtained new client token: AAB18O0hx91gGv8R+JRDP6nzegnNH34apT7j9tRju0WAUtbbOn5GlCVESla+UoP4UwvFntgMocHEX4Sw5u349BQmEeloG11gmMRpySYP5S72CCdGweiZA3/vstRD5gn5HN/U1Qy8CVqBc2GSC2Pb0hD3pSkIETw0Jt+RR2BSN3rrNB0UpQS0LALYDusZxU8ohDJQgmoZSsEs4wKZyQhXYaMS3SoPCRQS+mpNGE/J7RsRaVWY86ypuis7uw==" May 03 16:11:17 volumiob go-librespot[21882]: time="2025-05-03T16:11:17+02:00" level=info msg="connected to ap-gew1.spotify.com:4070" May 03 16:11:17 volumiob go-librespot[21882]: time="2025-05-03T16:11:17+02:00" level=debug msg="completed keyexchange" May 03 16:11:17 volumiob go-librespot[21882]: time="2025-05-03T16:11:17+02:00" level=debug msg="completed challenge" May 03 16:11:17 volumiob go-librespot[21882]: time="2025-05-03T16:11:17+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" May 03 16:11:17 volumiob systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 03 16:11:17 volumiob systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 03 16:11:18 volumiob volumio[857]: info: Initializing connection to go-librespot Websocket May 03 16:11:18 volumiob volumio[857]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 03 16:11:20 volumiob volumio[857]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 03 16:11:20 volumiob volumio[857]: Error: connect ETIMEDOUT 151.101.134.79:443 May 03 16:11:20 volumiob volumio[857]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { May 03 16:11:20 volumiob volumio[857]: errno: -110, May 03 16:11:20 volumiob volumio[857]: code: 'ETIMEDOUT', May 03 16:11:20 volumiob volumio[857]: syscall: 'connect', May 03 16:11:20 volumiob volumio[857]: address: '151.101.134.79', May 03 16:11:20 volumiob volumio[857]: port: 443 May 03 16:11:20 volumiob volumio[857]: } May 03 16:11:20 volumiob volumio[857]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 03 16:11:20 volumiob sudo[21906]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-03 16:10 May 03 16:11:20 volumiob sudo[21906]: 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="e7445fab547cd22e23d9bef72406cfb610bc1d7d" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="a9e1e1dd6072ffb15c0fcb8938d9ea0d8900c0da" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 24 Apr 2024 02:10:14 PM CEST" VOLUMIO_VERSION="3.661" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5bdefd4af4423fd17aacb83a8538bf1f"