-- Logs begin at Sat 2024-10-05 18:25:16 CEST, end at Sat 2024-10-05 18:31:09 CEST. -- Oct 05 18:30:00 volumio volumio[808]: info: Initializing connection to go-librespot Websocket Oct 05 18:30:00 volumio volumio[808]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 05 18:30:01 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 05 18:30:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 66. Oct 05 18:30:01 volumio systemd[1]: Stopped go-librespot Daemon. Oct 05 18:30:01 volumio systemd[1]: Started go-librespot Daemon. Oct 05 18:30:01 volumio go-librespot[2766]: Librespot-go daemon starting... Oct 05 18:30:01 volumio go-librespot[2766]: time="2024-10-05T18:30:01+02:00" level=info msg="generated new device id: fa146ad7ca2ac7ad4234c11d0ae6d07bd1a93c10" Oct 05 18:30:01 volumio go-librespot[2766]: time="2024-10-05T18:30:01+02:00" level=debug msg="stored credentials found for sloam8" Oct 05 18:30:01 volumio go-librespot[2766]: time="2024-10-05T18:30: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-gew4.spotify.com:80]" Oct 05 18:30:01 volumio go-librespot[2766]: time="2024-10-05T18:30:01+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]" Oct 05 18:30:01 volumio go-librespot[2766]: time="2024-10-05T18:30:01+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]" Oct 05 18:30:01 volumio go-librespot[2766]: time="2024-10-05T18:30:01+02:00" level=debug msg="zeroconf server listening on port 36247" Oct 05 18:30:01 volumio go-librespot[2766]: time="2024-10-05T18:30:01+02:00" level=debug msg="obtained new client token: AADvSqPYrBEeeH05lwaNgNHWn+dfafyx7Z/34iU6cxd7rSoxKLlSpIBM+HsqFkw3v0oTpi/ydfzYXSKznp3WnwDrkDIK0k6J/SxqgT3MKEul7uxhWs7d/JmuWXfggOlNfnMiVmYpI9l7ldi6GGLla2NOyVM/q+XulUVEQV+REuHdJs/rXlU+Z+ae9Hb5zHoW19hY1CSbrRB2B3aGuA5fxQavJHIi6lkP3m7nLm4xWLjxV97wg/JULyDZ/VwH" Oct 05 18:30:02 volumio go-librespot[2766]: time="2024-10-05T18:30:02+02:00" level=info msg="connected to ap-gew1.spotify.com:4070" Oct 05 18:30:02 volumio go-librespot[2766]: time="2024-10-05T18:30:02+02:00" level=debug msg="completed keyexchange" Oct 05 18:30:02 volumio go-librespot[2766]: time="2024-10-05T18:30:02+02:00" level=debug msg="completed challenge" Oct 05 18:30:02 volumio go-librespot[2766]: time="2024-10-05T18:30:02+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Oct 05 18:30:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 05 18:30:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 05 18:30:03 volumio volumio[808]: info: Initializing connection to go-librespot Websocket Oct 05 18:30:03 volumio volumio[808]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 05 18:30:05 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 05 18:30:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 67. Oct 05 18:30:05 volumio systemd[1]: Stopped go-librespot Daemon. Oct 05 18:30:05 volumio systemd[1]: Started go-librespot Daemon. Oct 05 18:30:05 volumio go-librespot[2775]: Librespot-go daemon starting... Oct 05 18:30:05 volumio go-librespot[2775]: time="2024-10-05T18:30:05+02:00" level=info msg="generated new device id: c4fcf8ba81c4510eb52c10346422a2f3b10261b3" Oct 05 18:30:05 volumio go-librespot[2775]: time="2024-10-05T18:30:05+02:00" level=debug msg="stored credentials found for sloam8" Oct 05 18:30:05 volumio go-librespot[2775]: time="2024-10-05T18:30: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-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 05 18:30:05 volumio go-librespot[2775]: time="2024-10-05T18:30:05+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]" Oct 05 18:30:05 volumio go-librespot[2775]: time="2024-10-05T18:30:05+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]" Oct 05 18:30:05 volumio go-librespot[2775]: time="2024-10-05T18:30:05+02:00" level=debug msg="zeroconf server listening on port 38981" Oct 05 18:30:06 volumio go-librespot[2775]: time="2024-10-05T18:30:06+02:00" level=debug msg="obtained new client token: AACKWTolHPYlNYshDFto+S5BX9k+Jmn6BqfUk5m/BUPq9S5W36ui4k5EnWwWwTzRGVp+fZ66hGDQGn/rgRR+pefabyxd1aZaBIHA8EKXvVtcXqKh1W6XYWjnxGkdCvKzoZgNm57u1Z6NtjSjr+t87RnMqzUgyOKZEmPFuHinZtuE5zCrV1Rr49DKYFtLpFxQZw4sRF5OTSevxxsyZ0L+279aKQAugFE27daUY7kK3XDJ4Iyx6dybA3xxOCP4" Oct 05 18:30:06 volumio go-librespot[2775]: time="2024-10-05T18:30:06+02:00" level=info msg="connected to ap-gew1.spotify.com:4070" Oct 05 18:30:06 volumio go-librespot[2775]: time="2024-10-05T18:30:06+02:00" level=debug msg="completed keyexchange" Oct 05 18:30:06 volumio go-librespot[2775]: time="2024-10-05T18:30:06+02:00" level=debug msg="completed challenge" Oct 05 18:30:06 volumio go-librespot[2775]: time="2024-10-05T18:30:06+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Oct 05 18:30:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 05 18:30:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 05 18:30:06 volumio volumio[808]: info: Initializing connection to go-librespot Websocket Oct 05 18:30:06 volumio volumio[808]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 05 18:30:09 volumio volumio[808]: info: Initializing connection to go-librespot Websocket Oct 05 18:30:09 volumio volumio[808]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 05 18:30:09 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 05 18:30:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 68. Oct 05 18:30:09 volumio systemd[1]: Stopped go-librespot Daemon. Oct 05 18:30:09 volumio systemd[1]: Started go-librespot Daemon. Oct 05 18:30:09 volumio go-librespot[2784]: Librespot-go daemon starting... Oct 05 18:30:09 volumio go-librespot[2784]: time="2024-10-05T18:30:09+02:00" level=info msg="generated new device id: 2c158cdcce67b4c9dd36e0b31c9f36f421cf82ce" Oct 05 18:30:09 volumio go-librespot[2784]: time="2024-10-05T18:30:09+02:00" level=debug msg="stored credentials found for sloam8" Oct 05 18:30:09 volumio go-librespot[2784]: time="2024-10-05T18:30: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-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 05 18:30:09 volumio go-librespot[2784]: time="2024-10-05T18:30:09+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]" Oct 05 18:30:09 volumio go-librespot[2784]: time="2024-10-05T18:30:09+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]" Oct 05 18:30:09 volumio go-librespot[2784]: time="2024-10-05T18:30:09+02:00" level=debug msg="zeroconf server listening on port 37671" Oct 05 18:30:10 volumio go-librespot[2784]: time="2024-10-05T18:30:10+02:00" level=debug msg="obtained new client token: AACheTGAisL7kHcT59igOWzjXF2rLAGjFUpKjXKHjE6ypfXoLSBOG1zKHBsR+7XPr9Hv9RK8KAhErTq766EfggavTaG9m2Bmz4eMPY+SlS+liAkZ7GwnHxt4BufXqgrdK45B5iR0chE3RoItu0SIuj+gURczlw4KZmEABIDy9lIVCpu+6pk3uIzcL+YPymJHBuBXZRE8WIX3gmmMNtVUktSnNXu7KkaQ0N5YixPPo8UUWaOkGlekg0ScGA==" Oct 05 18:30:10 volumio go-librespot[2784]: time="2024-10-05T18:30:10+02:00" level=info msg="connected to ap-gew1.spotify.com:4070" Oct 05 18:30:10 volumio go-librespot[2784]: time="2024-10-05T18:30:10+02:00" level=debug msg="completed keyexchange" Oct 05 18:30:10 volumio go-librespot[2784]: time="2024-10-05T18:30:10+02:00" level=debug msg="completed challenge" Oct 05 18:30:10 volumio go-librespot[2784]: time="2024-10-05T18:30: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" Oct 05 18:30:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 05 18:30:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 05 18:30:12 volumio volumio[808]: info: Initializing connection to go-librespot Websocket Oct 05 18:30:12 volumio volumio[808]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 05 18:30:13 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 05 18:30:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 69. Oct 05 18:30:13 volumio systemd[1]: Stopped go-librespot Daemon. Oct 05 18:30:13 volumio systemd[1]: Started go-librespot Daemon. Oct 05 18:30:13 volumio go-librespot[2792]: Librespot-go daemon starting... Oct 05 18:30:13 volumio go-librespot[2792]: time="2024-10-05T18:30:13+02:00" level=info msg="generated new device id: 66d9295efeb2352b75e10b617a4dea77b36bbd27" Oct 05 18:30:13 volumio go-librespot[2792]: time="2024-10-05T18:30:13+02:00" level=debug msg="stored credentials found for sloam8" Oct 05 18:30:13 volumio go-librespot[2792]: time="2024-10-05T18:30: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]" Oct 05 18:30:13 volumio go-librespot[2792]: time="2024-10-05T18:30: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]" Oct 05 18:30:13 volumio go-librespot[2792]: time="2024-10-05T18:30: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]" Oct 05 18:30:13 volumio go-librespot[2792]: time="2024-10-05T18:30:13+02:00" level=debug msg="zeroconf server listening on port 33517" Oct 05 18:30:13 volumio go-librespot[2792]: time="2024-10-05T18:30:13+02:00" level=debug msg="obtained new client token: AAC87kjrEt0GeFMxnkIYeI4L+nl8muc+66ap8xgT1v29VWBZyaxYCsQT/yNIlkQorkufw3JwwU3UFqYdh2SUdZqWP4CYG/pGnynkZ8UJYeKV/t/Q0ufy7bq+5CsvQDvVNVUYo70AqXan9PsnX7FjDo9EhXxlK29vpJ1nAt19yjCbj53V9ioSrx6IysY6teT5CtqSpUssEn4ZncGFv2U7PY7NVKkbM8XZ1Zvto6SD3oOEzi0t86Lz638WPHS6" Oct 05 18:30:14 volumio go-librespot[2792]: time="2024-10-05T18:30:14+02:00" level=info msg="connected to ap-gew1.spotify.com:4070" Oct 05 18:30:14 volumio go-librespot[2792]: time="2024-10-05T18:30:14+02:00" level=debug msg="completed keyexchange" Oct 05 18:30:14 volumio go-librespot[2792]: time="2024-10-05T18:30:14+02:00" level=debug msg="completed challenge" Oct 05 18:30:14 volumio go-librespot[2792]: time="2024-10-05T18:30:14+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Oct 05 18:30:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 05 18:30:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 05 18:30:15 volumio volumio[808]: info: Initializing connection to go-librespot Websocket Oct 05 18:30:15 volumio volumio[808]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 05 18:30:17 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 05 18:30:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 70. Oct 05 18:30:17 volumio systemd[1]: Stopped go-librespot Daemon. Oct 05 18:30:17 volumio systemd[1]: Started go-librespot Daemon. Oct 05 18:30:17 volumio go-librespot[2844]: Librespot-go daemon starting... Oct 05 18:30:17 volumio go-librespot[2844]: time="2024-10-05T18:30:17+02:00" level=info msg="generated new device id: 93c0a8f06e1241d44dc078afb624172d27df41c2" Oct 05 18:30:17 volumio go-librespot[2844]: time="2024-10-05T18:30:17+02:00" level=debug msg="stored credentials found for sloam8" Oct 05 18:30:17 volumio go-librespot[2844]: time="2024-10-05T18:30: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]" Oct 05 18:30:17 volumio go-librespot[2844]: time="2024-10-05T18:30: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]" Oct 05 18:30:17 volumio go-librespot[2844]: time="2024-10-05T18:30: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]" Oct 05 18:30:17 volumio go-librespot[2844]: time="2024-10-05T18:30:17+02:00" level=debug msg="zeroconf server listening on port 34347" Oct 05 18:30:17 volumio go-librespot[2844]: time="2024-10-05T18:30:17+02:00" level=debug msg="obtained new client token: AAD0nUsSnmTyDQRThQxe6Hp6hABR0vVlG8O2bcNAug//nuPGiqlLI/00ETwI4SAO7SY4POyOH+y+ZlmgkMJOgAIZrmxBEmtgD+kJiEXIsy4BH8vbCqGGhPOAc7lu/PV808TKWs0JtFYWFEQW+aR8Agrceh31a6oxAF/cUNbuB82cBGZ3Hxswqs6J0VCe5RFHMDELazhN/wwdVDzVluiizRAwl+LFL5eVlGar6Irwv3PIxTXglir9QH8CjEIv" Oct 05 18:30:18 volumio go-librespot[2844]: time="2024-10-05T18:30:18+02:00" level=info msg="connected to ap-gew1.spotify.com:4070" Oct 05 18:30:18 volumio go-librespot[2844]: time="2024-10-05T18:30:18+02:00" level=debug msg="completed keyexchange" Oct 05 18:30:18 volumio go-librespot[2844]: time="2024-10-05T18:30:18+02:00" level=debug msg="completed challenge" Oct 05 18:30:18 volumio go-librespot[2844]: time="2024-10-05T18:30:18+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Oct 05 18:30:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 05 18:30:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 05 18:30:18 volumio volumio[808]: info: Initializing connection to go-librespot Websocket Oct 05 18:30:18 volumio volumio[808]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 05 18:30:21 volumio volumio[808]: info: Initializing connection to go-librespot Websocket Oct 05 18:30:21 volumio volumio[808]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 05 18:30:21 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 05 18:30:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 71. Oct 05 18:30:21 volumio systemd[1]: Stopped go-librespot Daemon. Oct 05 18:30:21 volumio systemd[1]: Started go-librespot Daemon. Oct 05 18:30:21 volumio go-librespot[2855]: Librespot-go daemon starting... Oct 05 18:30:21 volumio go-librespot[2855]: time="2024-10-05T18:30:21+02:00" level=info msg="generated new device id: 1331738388a6e00e7bc6b5407c53c62f5bca8034" Oct 05 18:30:21 volumio go-librespot[2855]: time="2024-10-05T18:30:21+02:00" level=debug msg="stored credentials found for sloam8" Oct 05 18:30:21 volumio go-librespot[2855]: time="2024-10-05T18:30: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-gew4.spotify.com:80]" Oct 05 18:30:21 volumio go-librespot[2855]: time="2024-10-05T18:30:21+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]" Oct 05 18:30:21 volumio go-librespot[2855]: time="2024-10-05T18:30:21+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]" Oct 05 18:30:21 volumio go-librespot[2855]: time="2024-10-05T18:30:21+02:00" level=debug msg="zeroconf server listening on port 43391" Oct 05 18:30:22 volumio go-librespot[2855]: time="2024-10-05T18:30:22+02:00" level=debug msg="obtained new client token: AACai7t8G9Xqi0vI3ri2YlFZOFTnqvo1YVqMPzHu0TWLTnd/grWh/haRH84kW4SaBBxgLjs1cXsJQtoDwskL7ee+QhzakdU42o/+aX0u9u2DBQ9eJs613F0bNmX2rNd1jdKDynrCr6qf5f+fie8lA40poZovdeesxmmXXIf+VJmxV0RCN0TKzO66fPcOaqdYPQNY/gs+5Z5gAB9/2MbwVHLBbJFRV4LY3UssNWMMcsAfCPnv2HxRmsEbrg==" Oct 05 18:30:22 volumio go-librespot[2855]: time="2024-10-05T18:30:22+02:00" level=info msg="connected to ap-gew1.spotify.com:4070" Oct 05 18:30:22 volumio go-librespot[2855]: time="2024-10-05T18:30:22+02:00" level=debug msg="completed keyexchange" Oct 05 18:30:22 volumio go-librespot[2855]: time="2024-10-05T18:30:22+02:00" level=debug msg="completed challenge" Oct 05 18:30:22 volumio go-librespot[2855]: time="2024-10-05T18:30:22+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Oct 05 18:30:22 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 05 18:30:22 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 05 18:30:24 volumio volumio[808]: info: Initializing connection to go-librespot Websocket Oct 05 18:30:24 volumio volumio[808]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 05 18:30:25 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 05 18:30:25 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 72. Oct 05 18:30:25 volumio systemd[1]: Stopped go-librespot Daemon. Oct 05 18:30:25 volumio systemd[1]: Started go-librespot Daemon. Oct 05 18:30:25 volumio go-librespot[2863]: Librespot-go daemon starting... Oct 05 18:30:25 volumio go-librespot[2863]: time="2024-10-05T18:30:25+02:00" level=info msg="generated new device id: b7906b5087d7cd179ebe0e31d097a961439e22e2" Oct 05 18:30:25 volumio go-librespot[2863]: time="2024-10-05T18:30:25+02:00" level=debug msg="stored credentials found for sloam8" Oct 05 18:30:25 volumio go-librespot[2863]: time="2024-10-05T18:30: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]" Oct 05 18:30:25 volumio go-librespot[2863]: time="2024-10-05T18:30: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]" Oct 05 18:30:25 volumio go-librespot[2863]: time="2024-10-05T18:30: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]" Oct 05 18:30:25 volumio go-librespot[2863]: time="2024-10-05T18:30:25+02:00" level=debug msg="zeroconf server listening on port 41723" Oct 05 18:30:25 volumio go-librespot[2863]: time="2024-10-05T18:30:25+02:00" level=debug msg="obtained new client token: AAAsCiaTMUzo6Zbk+cDx7nwnLm79wuKMdajG/xxZMLp+9Yp7w4vcVMsmTmQrrf0umencZ32xiMlQcZgrgobzlGPYcWgEOBRyxGR2zJcpP5hZjG8uKZKT7W9flmEKfjF1mm0iqWWwja7Pn1r1TB0a3eWxE6BrzdHKd2Lp+k6jeQIw2fmlAnKrvaRMhIpRcrpVBff2yu+aTPnL0wLGLupj2p3mYUoZGH2HbhDsllUyNU9wNKEBmJNXec+IXwZy" Oct 05 18:30:26 volumio go-librespot[2863]: time="2024-10-05T18:30:26+02:00" level=info msg="connected to ap-gew1.spotify.com:4070" Oct 05 18:30:26 volumio go-librespot[2863]: time="2024-10-05T18:30:26+02:00" level=debug msg="completed keyexchange" Oct 05 18:30:26 volumio go-librespot[2863]: time="2024-10-05T18:30:26+02:00" level=debug msg="completed challenge" Oct 05 18:30:26 volumio go-librespot[2863]: time="2024-10-05T18:30:26+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Oct 05 18:30:26 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 05 18:30:26 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 05 18:30:27 volumio volumio[808]: info: Initializing connection to go-librespot Websocket Oct 05 18:30:27 volumio volumio[808]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 05 18:30:29 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 05 18:30:29 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 73. Oct 05 18:30:29 volumio systemd[1]: Stopped go-librespot Daemon. Oct 05 18:30:29 volumio systemd[1]: Started go-librespot Daemon. Oct 05 18:30:29 volumio go-librespot[2873]: Librespot-go daemon starting... Oct 05 18:30:29 volumio go-librespot[2873]: time="2024-10-05T18:30:29+02:00" level=info msg="generated new device id: 6bc3e06a3f26b6e2d6cfebec09767e85ac6fcc59" Oct 05 18:30:29 volumio go-librespot[2873]: time="2024-10-05T18:30:29+02:00" level=debug msg="stored credentials found for sloam8" Oct 05 18:30:29 volumio go-librespot[2873]: time="2024-10-05T18:30: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-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 05 18:30:29 volumio go-librespot[2873]: time="2024-10-05T18:30:29+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]" Oct 05 18:30:29 volumio go-librespot[2873]: time="2024-10-05T18:30:29+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]" Oct 05 18:30:29 volumio go-librespot[2873]: time="2024-10-05T18:30:29+02:00" level=debug msg="zeroconf server listening on port 34255" Oct 05 18:30:29 volumio go-librespot[2873]: time="2024-10-05T18:30:29+02:00" level=debug msg="obtained new client token: AAAXbq/755wW826O+MzUQiknBnwACBjIVyrPMocFgXICi7i4pEn61IatjbJAyJR7XzJxWH2QhkQLUp/bs2WOhBQ03RVKVV1XvJ2VeAvPP5GTKXgu2Re3QS4YNLah1lNg+J+bHOb/FdFhiOXWvCZTtHVB3goUgBPgLggzGeQcuQ90bwI9n44Eu4AEtI7vzc4s3cDAUwCbjDGaV/SdPuOehHwbftM38ISsjg5hCLNnHrhAnfHwjywN9w64nAKe" Oct 05 18:30:30 volumio go-librespot[2873]: time="2024-10-05T18:30:30+02:00" level=info msg="connected to ap-gew1.spotify.com:4070" Oct 05 18:30:30 volumio go-librespot[2873]: time="2024-10-05T18:30:30+02:00" level=debug msg="completed keyexchange" Oct 05 18:30:30 volumio go-librespot[2873]: time="2024-10-05T18:30:30+02:00" level=debug msg="completed challenge" Oct 05 18:30:30 volumio go-librespot[2873]: time="2024-10-05T18:30: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" Oct 05 18:30:30 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 05 18:30:30 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 05 18:30:30 volumio volumio[808]: info: Initializing connection to go-librespot Websocket Oct 05 18:30:30 volumio volumio[808]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 05 18:30:33 volumio volumio[808]: info: Initializing connection to go-librespot Websocket Oct 05 18:30:33 volumio volumio[808]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 05 18:30:33 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 05 18:30:33 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 74. Oct 05 18:30:33 volumio systemd[1]: Stopped go-librespot Daemon. Oct 05 18:30:33 volumio systemd[1]: Started go-librespot Daemon. Oct 05 18:30:33 volumio go-librespot[2882]: Librespot-go daemon starting... Oct 05 18:30:33 volumio go-librespot[2882]: time="2024-10-05T18:30:33+02:00" level=info msg="generated new device id: 6320c7fcaf3b471b66c246455c6821bea4515e8f" Oct 05 18:30:33 volumio go-librespot[2882]: time="2024-10-05T18:30:33+02:00" level=debug msg="stored credentials found for sloam8" Oct 05 18:30:33 volumio go-librespot[2882]: time="2024-10-05T18:30: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-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 05 18:30:33 volumio go-librespot[2882]: time="2024-10-05T18:30:33+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]" Oct 05 18:30:33 volumio go-librespot[2882]: time="2024-10-05T18:30:33+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]" Oct 05 18:30:33 volumio go-librespot[2882]: time="2024-10-05T18:30:33+02:00" level=debug msg="zeroconf server listening on port 38047" Oct 05 18:30:33 volumio go-librespot[2882]: time="2024-10-05T18:30:33+02:00" level=debug msg="obtained new client token: AABZGSqgofvheCOof3rePpTWT9HccMhfKCO6QXqCinoc/xbWpQlVFhF+phYUkwqlqyk43JELjq0sLuIPova4AYj4ww4mgIYVhHfqDKERe/aHDmHzGY84pJQneuy4DghF2a/H9NTLfXatUwUa6df3c+NRzI15Gh1MMpPIpp55HCkkqc5XfjHs22fJdsYOhcHJ1JWsUZlR7NQra+fYosZcW037HarLe1IE8J8IKkktsJQlGp+FpdQ0367ms0M5" Oct 05 18:30:34 volumio go-librespot[2882]: time="2024-10-05T18:30:34+02:00" level=info msg="connected to ap-gew1.spotify.com:4070" Oct 05 18:30:34 volumio go-librespot[2882]: time="2024-10-05T18:30:34+02:00" level=debug msg="completed keyexchange" Oct 05 18:30:34 volumio go-librespot[2882]: time="2024-10-05T18:30:34+02:00" level=debug msg="completed challenge" Oct 05 18:30:34 volumio go-librespot[2882]: time="2024-10-05T18:30:34+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Oct 05 18:30:34 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 05 18:30:34 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 05 18:30:36 volumio volumio[808]: info: Initializing connection to go-librespot Websocket Oct 05 18:30:36 volumio volumio[808]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 05 18:30:37 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 05 18:30:37 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 75. Oct 05 18:30:37 volumio systemd[1]: Stopped go-librespot Daemon. Oct 05 18:30:37 volumio systemd[1]: Started go-librespot Daemon. Oct 05 18:30:37 volumio go-librespot[2891]: Librespot-go daemon starting... Oct 05 18:30:37 volumio go-librespot[2891]: time="2024-10-05T18:30:37+02:00" level=info msg="generated new device id: 237b43c99d4cf20b146a5ecd7981c9f2e4607a3b" Oct 05 18:30:37 volumio go-librespot[2891]: time="2024-10-05T18:30:37+02:00" level=debug msg="stored credentials found for sloam8" Oct 05 18:30:37 volumio go-librespot[2891]: time="2024-10-05T18:30: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]" Oct 05 18:30:37 volumio go-librespot[2891]: time="2024-10-05T18:30: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]" Oct 05 18:30:37 volumio go-librespot[2891]: time="2024-10-05T18:30: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]" Oct 05 18:30:37 volumio go-librespot[2891]: time="2024-10-05T18:30:37+02:00" level=debug msg="zeroconf server listening on port 39241" Oct 05 18:30:38 volumio go-librespot[2891]: time="2024-10-05T18:30:38+02:00" level=debug msg="obtained new client token: AAB7ZO+f074I+hTpg0gAFUTHbdSWmt3h26bCAjNdW3cV5iqNi9tmFnukSht0n0mWKKHTLLfhsJZaKnz/ZtxmHVVpNH++9kE7J9lWd8to8BBh36VVC0oliTbOaRpU9LB1TX76e955T9dd4qotv6V0OFZvAhSfHYaHh+8UQB25GOzpa29PYI2eLMYNqJSrU3BRojkjFfzupzJwP6QyggugMKfXSuNH6dsL1rJMQgtnlhVymrjc5rcWjD45r7Ix" Oct 05 18:30:38 volumio go-librespot[2891]: time="2024-10-05T18:30:38+02:00" level=info msg="connected to ap-gew1.spotify.com:4070" Oct 05 18:30:38 volumio go-librespot[2891]: time="2024-10-05T18:30:38+02:00" level=debug msg="completed keyexchange" Oct 05 18:30:38 volumio go-librespot[2891]: time="2024-10-05T18:30:38+02:00" level=debug msg="completed challenge" Oct 05 18:30:38 volumio go-librespot[2891]: time="2024-10-05T18:30:38+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Oct 05 18:30:38 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 05 18:30:38 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 05 18:30:39 volumio volumio[808]: info: Initializing connection to go-librespot Websocket Oct 05 18:30:39 volumio volumio[808]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 05 18:30:41 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 05 18:30:41 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 76. Oct 05 18:30:41 volumio systemd[1]: Stopped go-librespot Daemon. Oct 05 18:30:41 volumio systemd[1]: Started go-librespot Daemon. Oct 05 18:30:41 volumio go-librespot[2900]: Librespot-go daemon starting... Oct 05 18:30:41 volumio go-librespot[2900]: time="2024-10-05T18:30:41+02:00" level=info msg="generated new device id: c900d4c32c871c416481871112d9d9b8e1e758fb" Oct 05 18:30:41 volumio go-librespot[2900]: time="2024-10-05T18:30:41+02:00" level=debug msg="stored credentials found for sloam8" Oct 05 18:30:41 volumio go-librespot[2900]: time="2024-10-05T18:30: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-gae2.spotify.com:80]" Oct 05 18:30:41 volumio go-librespot[2900]: time="2024-10-05T18:30:41+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]" Oct 05 18:30:41 volumio go-librespot[2900]: time="2024-10-05T18:30:41+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]" Oct 05 18:30:41 volumio go-librespot[2900]: time="2024-10-05T18:30:41+02:00" level=debug msg="zeroconf server listening on port 46743" Oct 05 18:30:42 volumio go-librespot[2900]: time="2024-10-05T18:30:42+02:00" level=debug msg="obtained new client token: AACCQu2y1Le7SgG8nIEXcoSFFU3VyfeXCaNjh5bvDsXZ8cEaoSB8oFc6POIvQP3Bw9huE7aEUcbqKQSkvxwuXZ8unr4QOqdL2BwQWAxKPrQsXBW8Uk6xSoTB8Sa9Wbmcunib4GGm9aHoWwUes+t/mlFcfhfHcxX13U9FEhUhgnCHNc4bDyXtA7ETVkV24zkmyi8EZfKs47Hgd/nmRgYCe7hp2yMLP1u8RV4ufug47dltSuSCU8EI1/V/ww==" Oct 05 18:30:42 volumio go-librespot[2900]: time="2024-10-05T18:30:42+02:00" level=info msg="connected to ap-gew1.spotify.com:4070" Oct 05 18:30:42 volumio go-librespot[2900]: time="2024-10-05T18:30:42+02:00" level=debug msg="completed keyexchange" Oct 05 18:30:42 volumio go-librespot[2900]: time="2024-10-05T18:30:42+02:00" level=debug msg="completed challenge" Oct 05 18:30:42 volumio go-librespot[2900]: time="2024-10-05T18:30:42+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Oct 05 18:30:42 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 05 18:30:42 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 05 18:30:42 volumio volumio[808]: info: Initializing connection to go-librespot Websocket Oct 05 18:30:42 volumio volumio[808]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 05 18:30:44 volumio volumio[808]: info: Clearing queue after UPNP request Oct 05 18:30:44 volumio volumio[808]: info: CoreStateMachine::ClearQueue Oct 05 18:30:44 volumio volumio[808]: info: CoreStateMachine::stop Oct 05 18:30:44 volumio volumio[808]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 05 18:30:44 volumio volumio[808]: info: CorePlayQueue::clearPlayQueue Oct 05 18:30:44 volumio volumio[808]: info: CorePlayQueue::saveQueue Oct 05 18:30:44 volumio volumio[808]: info: CoreCommandRouter::volumioPushState Oct 05 18:30:44 volumio volumio[808]: info: CoreCommandRouter::volumioPushQueue Oct 05 18:30:44 volumio volumio[808]: error: Upnp client error: Error: This socket has been ended by the other party Oct 05 18:30:44 volumio volumio[808]: SPOTIFY: RECEIVED VOLUMIO VOLUME 79 Oct 05 18:30:45 volumio volumio[808]: info: Initializing connection to go-librespot Websocket Oct 05 18:30:45 volumio volumio[808]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 05 18:30:45 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 05 18:30:45 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 77. Oct 05 18:30:45 volumio systemd[1]: Stopped go-librespot Daemon. Oct 05 18:30:45 volumio systemd[1]: Started go-librespot Daemon. Oct 05 18:30:45 volumio go-librespot[2910]: Librespot-go daemon starting... Oct 05 18:30:45 volumio go-librespot[2910]: time="2024-10-05T18:30:45+02:00" level=info msg="generated new device id: b18dbf4b163f01405c845ab2cee88b0c267f418b" Oct 05 18:30:45 volumio go-librespot[2910]: time="2024-10-05T18:30:45+02:00" level=debug msg="stored credentials found for sloam8" Oct 05 18:30:45 volumio go-librespot[2910]: time="2024-10-05T18:30: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-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 05 18:30:45 volumio go-librespot[2910]: time="2024-10-05T18:30:45+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]" Oct 05 18:30:45 volumio go-librespot[2910]: time="2024-10-05T18:30:45+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]" Oct 05 18:30:45 volumio go-librespot[2910]: time="2024-10-05T18:30:45+02:00" level=debug msg="zeroconf server listening on port 34627" Oct 05 18:30:45 volumio go-librespot[2910]: time="2024-10-05T18:30:45+02:00" level=debug msg="obtained new client token: AAA6bwKIBhIpxhZiD1+UFW8VHZm4M76C50k9XUI2rl9YyGSkFeLMt36tb0wrRNBdv79LxMASOqfcSwu4UXTW+/3h72RWKK0RpoAEnt0i4qpEQ7BYFcqQK1Zs3Z8jYlkeTYt8JwwnhFR8iN+gsoUMf+qjoW+XzbRwp37FxES6ZC3Y9HUhVCTiD2spKngaL6mKnTmy8ydJ3fAeWCZKPkMai5CMqhBAnyRbpSIGmY1tRTxN/Bd6lGGz3xegPDYG" Oct 05 18:30:46 volumio go-librespot[2910]: time="2024-10-05T18:30:46+02:00" level=info msg="connected to ap-gew1.spotify.com:4070" Oct 05 18:30:46 volumio go-librespot[2910]: time="2024-10-05T18:30:46+02:00" level=debug msg="completed keyexchange" Oct 05 18:30:46 volumio volumio[808]: info: Starting UPNP Playback Oct 05 18:30:46 volumio volumio[808]: info: Preparing playback through UPNP Oct 05 18:30:46 volumio volumio[808]: info: CoreCommandRouter::volumioGetState Oct 05 18:30:46 volumio volumio[808]: info: CorePlayQueue::getTrack 0 Oct 05 18:30:46 volumio volumio[808]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 05 18:30:46 volumio go-librespot[2910]: time="2024-10-05T18:30:46+02:00" level=debug msg="completed challenge" Oct 05 18:30:46 volumio go-librespot[2910]: time="2024-10-05T18:30:46+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Oct 05 18:30:46 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 05 18:30:46 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 05 18:30:47 volumio volumio[808]: info: Oct 05 18:30:47 volumio volumio[808]: ---------------------------- MPD announces system playlist update Oct 05 18:30:47 volumio volumio[808]: info: Ignoring MPD Status Update Oct 05 18:30:47 volumio volumio[808]: info: ------------------------------ 0ms Oct 05 18:30:47 volumio volumio[808]: info: Oct 05 18:30:47 volumio volumio[808]: ---------------------------- MPD announces system playlist update Oct 05 18:30:47 volumio volumio[808]: info: Ignoring MPD Status Update Oct 05 18:30:47 volumio volumio[808]: info: ------------------------------ 1ms Oct 05 18:30:47 volumio volumio[808]: info: Oct 05 18:30:47 volumio volumio[808]: ---------------------------- MPD announces system playlist update Oct 05 18:30:47 volumio volumio[808]: info: Ignoring MPD Status Update Oct 05 18:30:47 volumio volumio[808]: info: ------------------------------ 0ms Oct 05 18:30:47 volumio volumio[808]: info: Oct 05 18:30:47 volumio volumio[808]: ---------------------------- MPD announces system playlist update Oct 05 18:30:47 volumio volumio[808]: info: Ignoring MPD Status Update Oct 05 18:30:47 volumio volumio[808]: info: ------------------------------ 0ms Oct 05 18:30:47 volumio volumio[808]: info: Oct 05 18:30:47 volumio volumio[808]: ---------------------------- MPD announces system playlist update Oct 05 18:30:47 volumio volumio[808]: info: Ignoring MPD Status Update Oct 05 18:30:47 volumio volumio[808]: info: ------------------------------ 0ms Oct 05 18:30:47 volumio volumio[808]: info: Oct 05 18:30:47 volumio volumio[808]: ---------------------------- MPD announces system playlist update Oct 05 18:30:47 volumio volumio[808]: info: Ignoring MPD Status Update Oct 05 18:30:47 volumio volumio[808]: info: ------------------------------ 0ms Oct 05 18:30:47 volumio volumio[1685]: 50 Oct 05 18:30:47 volumio volumio[1685]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 05 18:30:47 volumio volumio[1685]: Dload Upload Total Spent Left Speed Oct 05 18:30:47 volumio volumio[808]: info: Oct 05 18:30:47 volumio volumio[808]: ---------------------------- Client requests Volume 50 Oct 05 18:30:47 volumio volumio[808]: info: VolumeController::SetAlsaVolume50 Oct 05 18:30:47 volumio volumio[808]: info: CoreStateMachine::pushState Oct 05 18:30:47 volumio volumio[808]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 05 18:30:47 volumio volumio[808]: info: CoreCommandRouter::volumioPushState Oct 05 18:30:47 volumio volumio[1685]: [158B blob data] Oct 05 18:30:47 volumio volumio[1685]: {"time":1728145847085,"response":"volume Success"}50 Oct 05 18:30:47 volumio volumio[808]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Oct 05 18:30:47 volumio volumio[808]: SPOTIFY: SPOTIFY VOLUME 79 Oct 05 18:30:47 volumio volumio[808]: SPOTIFY: VOLUMIO VOLUME 50 Oct 05 18:30:47 volumio volumio[808]: SPOTIFY: DELTA VOLUME ENOUGH: true Oct 05 18:30:47 volumio volumio[808]: info: Setting Spotify Volume from Volumio: 50 Oct 05 18:30:47 volumio volumio[808]: info: Oct 05 18:30:47 volumio volumio[808]: ---------------------------- MPD announces state update: player Oct 05 18:30:47 volumio volumio[808]: info: ControllerMpd::getState Oct 05 18:30:47 volumio volumio[808]: verbose: ControllerMpd::sendMpdCommand status Oct 05 18:30:47 volumio volumio[808]: info: Oct 05 18:30:47 volumio volumio[808]: ---------------------------- MPD announces state update: player Oct 05 18:30:47 volumio volumio[808]: info: sendMpdCommand status took 431 milliseconds Oct 05 18:30:47 volumio volumio[808]: info: ControllerMpd::getState Oct 05 18:30:47 volumio volumio[808]: verbose: ControllerMpd::sendMpdCommand status Oct 05 18:30:47 volumio volumio[808]: verbose: ControllerMpd::parseState Oct 05 18:30:47 volumio volumio[808]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 18:30:47 volumio volumio[808]: info: sendMpdCommand status took 1 milliseconds Oct 05 18:30:47 volumio volumio[808]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 05 18:30:47 volumio volumio[808]: verbose: ControllerMpd::parseState Oct 05 18:30:47 volumio volumio[808]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 18:30:47 volumio volumio[808]: verbose: ControllerMpd::parseTrackInfo Oct 05 18:30:47 volumio volumio[808]: info: ControllerMpd::pushState Oct 05 18:30:47 volumio volumio[808]: info: CoreCommandRouter::servicePushState Oct 05 18:30:47 volumio volumio[808]: verbose: In UPNP mode Oct 05 18:30:47 volumio volumio[808]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"HiFy","artist":"HiFy (Performer)","album":"HiFy","uri":"http://192.168.1.20:46675/HiFy.LPCM","trackType":"LPCM"} Oct 05 18:30:47 volumio volumio[808]: verbose: CURRENT POSITION 0 Oct 05 18:30:47 volumio volumio[808]: info: CoreStateMachine::syncState stateService play Oct 05 18:30:47 volumio volumio[808]: info: CoreStateMachine::syncState currentStatus stop Oct 05 18:30:47 volumio volumio[808]: info: ------------------------------ 434ms Oct 05 18:30:47 volumio volumio[808]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 05 18:30:47 volumio volumio[808]: verbose: ControllerMpd::parseTrackInfo Oct 05 18:30:47 volumio volumio[808]: info: ControllerMpd::pushState Oct 05 18:30:47 volumio volumio[808]: info: CoreCommandRouter::servicePushState Oct 05 18:30:47 volumio volumio[808]: verbose: In UPNP mode Oct 05 18:30:47 volumio volumio[808]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"HiFy","artist":"HiFy (Performer)","album":"HiFy","uri":"http://192.168.1.20:46675/HiFy.LPCM","trackType":"LPCM"} Oct 05 18:30:47 volumio volumio[808]: verbose: CURRENT POSITION 0 Oct 05 18:30:47 volumio volumio[808]: info: CoreStateMachine::syncState stateService play Oct 05 18:30:47 volumio volumio[808]: info: CoreStateMachine::syncState currentStatus play Oct 05 18:30:47 volumio volumio[808]: info: Received an update from plugin. extracting info from payload Oct 05 18:30:47 volumio volumio[808]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 05 18:30:47 volumio volumio[808]: info: CoreStateMachine::pushState Oct 05 18:30:47 volumio volumio[808]: info: CorePlayQueue::getTrack 0 Oct 05 18:30:47 volumio volumio[808]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 05 18:30:47 volumio volumio[808]: info: ------------------------------ 3ms Oct 05 18:30:48 volumio volumio[808]: SPOTIFY: SETTING SPOTIFY VOLUME 50 Oct 05 18:30:48 volumio volumio[808]: info: Sending Spotify command with payload to local API: /player/volume Oct 05 18:30:48 volumio volumio[808]: error: Failed to send command to Spotify local API: /player/volume: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 05 18:30:48 volumio volumio[808]: info: Initializing connection to go-librespot Websocket Oct 05 18:30:48 volumio volumio[808]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 05 18:30:49 volumio volumio[808]: info: Executing endpoint metavolumio Oct 05 18:30:49 volumio volumio[808]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 05 18:30:49 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 05 18:30:49 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 78. Oct 05 18:30:49 volumio systemd[1]: Stopped go-librespot Daemon. Oct 05 18:30:49 volumio systemd[1]: Started go-librespot Daemon. Oct 05 18:30:49 volumio go-librespot[2934]: Librespot-go daemon starting... Oct 05 18:30:49 volumio go-librespot[2934]: time="2024-10-05T18:30:49+02:00" level=info msg="generated new device id: 01a705a67ebaf610e5b6a706d346fe28ee1c84f1" Oct 05 18:30:49 volumio go-librespot[2934]: time="2024-10-05T18:30:49+02:00" level=debug msg="stored credentials found for sloam8" Oct 05 18:30:49 volumio go-librespot[2934]: time="2024-10-05T18:30: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]" Oct 05 18:30:49 volumio go-librespot[2934]: time="2024-10-05T18:30: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]" Oct 05 18:30:49 volumio go-librespot[2934]: time="2024-10-05T18:30: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]" Oct 05 18:30:49 volumio go-librespot[2934]: time="2024-10-05T18:30:49+02:00" level=debug msg="zeroconf server listening on port 45885" Oct 05 18:30:49 volumio go-librespot[2934]: time="2024-10-05T18:30:49+02:00" level=debug msg="obtained new client token: AABl0fF+XTkEYRG3zW6HLEVgDb6GpwlmqEUlOTlZuA/h4hKLAiravldmXkFs6BDT3jm0sg+z73R9Y3lJoyPeaa3BPyz0go9l2VHoZuq3ERy6m1Oy4kScz9lxftBT1qbsm8i5LNHhQMwvJvaB4Vjz68J8YcgIJnK3j7C6k8z6inQkv7jh0Ukh1dD08o3aoUzwG8gyIG1TCtnFil76/ystybl9fpXRhY8TmQEZmQMAsqs57AjLep6gahSY9iIy" Oct 05 18:30:50 volumio go-librespot[2934]: time="2024-10-05T18:30:50+02:00" level=info msg="connected to ap-gew1.spotify.com:4070" Oct 05 18:30:50 volumio go-librespot[2934]: time="2024-10-05T18:30:50+02:00" level=debug msg="completed keyexchange" Oct 05 18:30:50 volumio go-librespot[2934]: time="2024-10-05T18:30:50+02:00" level=debug msg="completed challenge" Oct 05 18:30:50 volumio go-librespot[2934]: time="2024-10-05T18:30: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" Oct 05 18:30:50 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 05 18:30:50 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 05 18:30:51 volumio volumio[808]: info: Initializing connection to go-librespot Websocket Oct 05 18:30:51 volumio volumio[808]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 05 18:30:53 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 05 18:30:53 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 79. Oct 05 18:30:53 volumio systemd[1]: Stopped go-librespot Daemon. Oct 05 18:30:53 volumio systemd[1]: Started go-librespot Daemon. Oct 05 18:30:53 volumio go-librespot[2942]: Librespot-go daemon starting... Oct 05 18:30:53 volumio go-librespot[2942]: time="2024-10-05T18:30:53+02:00" level=info msg="generated new device id: 8f5a9e35b9eb6cda818229b8dd1447a720f02f47" Oct 05 18:30:53 volumio go-librespot[2942]: time="2024-10-05T18:30:53+02:00" level=debug msg="stored credentials found for sloam8" Oct 05 18:30:53 volumio go-librespot[2942]: time="2024-10-05T18:30: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-gew4.spotify.com:80]" Oct 05 18:30:53 volumio go-librespot[2942]: time="2024-10-05T18:30:53+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]" Oct 05 18:30:53 volumio go-librespot[2942]: time="2024-10-05T18:30:53+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]" Oct 05 18:30:53 volumio go-librespot[2942]: time="2024-10-05T18:30:53+02:00" level=debug msg="zeroconf server listening on port 41587" Oct 05 18:30:53 volumio go-librespot[2942]: time="2024-10-05T18:30:53+02:00" level=debug msg="obtained new client token: AACMmC8WhwzPD8bQ3zNdnNnppHng+7GdZomZRQgQuuenFuxZPg/yX2s3ZjI9gFzRZNr0z3r+duW7tB6d984cOwfefI/04qmp7lPGY70I6Yehyyd7jGcsWxgio9AIYd253U7+QQg9QgTxiSiNince8jpkfFoo6kBlRazzrhjfb2Yo1IVZzq9Mgb1ePrvmtQgsDjVXSxiPEVes05VK6YDWugkATZZD3CzflIknNy9IG/1uidtEQItX8+iQfkLm" Oct 05 18:30:54 volumio go-librespot[2942]: time="2024-10-05T18:30:54+02:00" level=info msg="connected to ap-gew1.spotify.com:4070" Oct 05 18:30:54 volumio go-librespot[2942]: time="2024-10-05T18:30:54+02:00" level=debug msg="completed keyexchange" Oct 05 18:30:54 volumio go-librespot[2942]: time="2024-10-05T18:30:54+02:00" level=debug msg="completed challenge" Oct 05 18:30:54 volumio go-librespot[2942]: time="2024-10-05T18:30:54+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Oct 05 18:30:54 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 05 18:30:54 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 05 18:30:54 volumio volumio[808]: info: Initializing connection to go-librespot Websocket Oct 05 18:30:54 volumio volumio[808]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 05 18:30:57 volumio volumio[808]: info: Initializing connection to go-librespot Websocket Oct 05 18:30:57 volumio volumio[808]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 05 18:30:57 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 05 18:30:57 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 80. Oct 05 18:30:57 volumio systemd[1]: Stopped go-librespot Daemon. Oct 05 18:30:57 volumio systemd[1]: Started go-librespot Daemon. Oct 05 18:30:57 volumio go-librespot[2951]: Librespot-go daemon starting... Oct 05 18:30:57 volumio go-librespot[2951]: time="2024-10-05T18:30:57+02:00" level=info msg="generated new device id: 7c89fd7f4a3300803d6b193eb9658cdba0a01d1f" Oct 05 18:30:57 volumio go-librespot[2951]: time="2024-10-05T18:30:57+02:00" level=debug msg="stored credentials found for sloam8" Oct 05 18:30:57 volumio go-librespot[2951]: time="2024-10-05T18:30: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-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 05 18:30:57 volumio go-librespot[2951]: time="2024-10-05T18:30:57+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]" Oct 05 18:30:57 volumio go-librespot[2951]: time="2024-10-05T18:30:57+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]" Oct 05 18:30:57 volumio go-librespot[2951]: time="2024-10-05T18:30:57+02:00" level=debug msg="zeroconf server listening on port 44893" Oct 05 18:30:58 volumio go-librespot[2951]: time="2024-10-05T18:30:58+02:00" level=debug msg="obtained new client token: AAA74daNAr68FbJi6NN98UPwD8erdHOd0JAjeIJQ2DR31MLoWkEPyny58PQZfwXOJi/BWblu5vhpRVG1fSl55UOduDYxOCxS9ihKpMaWVSMzmV+xTy2XInkYLC5s8HypUocRHK/i5trlzvjko+TjvkulM0D9h+0jfoplRvbjbVH7rLCfOadSvARzEzQaPxGyE/dOb5oPl2ZQNvWs/qt4e6enl3vVzCPi+TS+TOoqjo9voCFBQRH06kwKk8eh" Oct 05 18:30:58 volumio go-librespot[2951]: time="2024-10-05T18:30:58+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" Oct 05 18:30:58 volumio go-librespot[2951]: time="2024-10-05T18:30:58+02:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:443 (error: dial tcp 104.199.65.9:443: connect: connection refused), retrying with a different AP" Oct 05 18:30:58 volumio go-librespot[2951]: time="2024-10-05T18:30:58+02:00" level=info msg="connected to ap-gew1.spotify.com:80" Oct 05 18:30:58 volumio go-librespot[2951]: time="2024-10-05T18:30:58+02:00" level=debug msg="completed keyexchange" Oct 05 18:30:59 volumio go-librespot[2951]: time="2024-10-05T18:30:59+02:00" level=debug msg="completed challenge" Oct 05 18:30:59 volumio go-librespot[2951]: time="2024-10-05T18:30:59+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Oct 05 18:30:59 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 05 18:30:59 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 05 18:31:00 volumio volumio[808]: info: Initializing connection to go-librespot Websocket Oct 05 18:31:00 volumio volumio[808]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 05 18:31:02 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 05 18:31:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 81. Oct 05 18:31:02 volumio systemd[1]: Stopped go-librespot Daemon. Oct 05 18:31:02 volumio systemd[1]: Started go-librespot Daemon. Oct 05 18:31:02 volumio go-librespot[2960]: Librespot-go daemon starting... Oct 05 18:31:02 volumio go-librespot[2960]: time="2024-10-05T18:31:02+02:00" level=info msg="generated new device id: f0469c0d7cad0b8413864bdc0b06cf110db37511" Oct 05 18:31:02 volumio go-librespot[2960]: time="2024-10-05T18:31:02+02:00" level=debug msg="stored credentials found for sloam8" Oct 05 18:31:02 volumio go-librespot[2960]: time="2024-10-05T18:31:02+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]" Oct 05 18:31:02 volumio go-librespot[2960]: time="2024-10-05T18:31:02+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]" Oct 05 18:31:02 volumio go-librespot[2960]: time="2024-10-05T18:31:02+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]" Oct 05 18:31:02 volumio go-librespot[2960]: time="2024-10-05T18:31:02+02:00" level=debug msg="zeroconf server listening on port 44437" Oct 05 18:31:02 volumio go-librespot[2960]: time="2024-10-05T18:31:02+02:00" level=debug msg="obtained new client token: AAC9kz7K/CkhbeONb1ghtVODj0i+w4CSpZDMizJ3dFq8jItx6/rGDiKeap1WBtODSC5N1fJq//i1SsAd1TO9KQnzb813Q8QWLRFgGnrs3ez+Q0475wZLdAuVgf3EwNKHZbryL/DpQihNAXlpWKS/hn/O3+7VwcK7LDsKI1flWnU26jFmrRaDaH/Pwi4pwWrUHnMubS56Iyj8RY/D3eO1YQyKwRsVP15fb1ndo3Ir9njjQlOAl+73rC5MZIW3" Oct 05 18:31:02 volumio go-librespot[2960]: time="2024-10-05T18:31:02+02:00" level=info msg="connected to ap-gew1.spotify.com:4070" Oct 05 18:31:02 volumio go-librespot[2960]: time="2024-10-05T18:31:02+02:00" level=debug msg="completed keyexchange" Oct 05 18:31:03 volumio go-librespot[2960]: time="2024-10-05T18:31:03+02:00" level=debug msg="completed challenge" Oct 05 18:31:03 volumio go-librespot[2960]: time="2024-10-05T18:31:03+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Oct 05 18:31:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 05 18:31:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 05 18:31:03 volumio volumio[808]: info: Initializing connection to go-librespot Websocket Oct 05 18:31:03 volumio volumio[808]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 05 18:31:06 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 05 18:31:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 82. Oct 05 18:31:06 volumio systemd[1]: Stopped go-librespot Daemon. Oct 05 18:31:06 volumio systemd[1]: Started go-librespot Daemon. Oct 05 18:31:06 volumio go-librespot[2968]: Librespot-go daemon starting... Oct 05 18:31:06 volumio go-librespot[2968]: time="2024-10-05T18:31:06+02:00" level=info msg="generated new device id: c01dc104f20d43edfb0aceed3ebdcd4fb1430ff0" Oct 05 18:31:06 volumio go-librespot[2968]: time="2024-10-05T18:31:06+02:00" level=debug msg="stored credentials found for sloam8" Oct 05 18:31:06 volumio go-librespot[2968]: time="2024-10-05T18:31:06+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]" Oct 05 18:31:06 volumio go-librespot[2968]: time="2024-10-05T18:31:06+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]" Oct 05 18:31:06 volumio go-librespot[2968]: time="2024-10-05T18:31:06+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]" Oct 05 18:31:06 volumio go-librespot[2968]: time="2024-10-05T18:31:06+02:00" level=debug msg="zeroconf server listening on port 33489" Oct 05 18:31:06 volumio go-librespot[2968]: time="2024-10-05T18:31:06+02:00" level=debug msg="obtained new client token: AACu0ABAnBUZLURgf1XF4DtgVaYffz1rTYx+fRLriaZd8aBd3IA54Rc4HLiH33ENRVzpgDIk1Qw9nZCvvTGV40iXKIbKhpgqdzNCrj9Tabe8hmeJq/bMHKWEUU9hBYrc/f6Ia8yUreehJgDr+DZgaj4t/EdjZQSPxfg0CWEfWh5VVCFG/xhfaex4zLc1HXXsqrf+upf6DLvxq1m51BGUm0hvT9Jim5wkcY8f78oPW7mMWb/q11zzECIXyyoh" Oct 05 18:31:06 volumio volumio[808]: info: Initializing connection to go-librespot Websocket Oct 05 18:31:06 volumio go-librespot[2968]: time="2024-10-05T18:31:06+02:00" level=debug msg="new websocket client" Oct 05 18:31:06 volumio volumio[808]: info: Connection to go-librespot Websocket established Oct 05 18:31:06 volumio go-librespot[2968]: time="2024-10-05T18:31:06+02:00" level=info msg="connected to ap-gew1.spotify.com:4070" Oct 05 18:31:06 volumio go-librespot[2968]: time="2024-10-05T18:31:06+02:00" level=debug msg="completed keyexchange" Oct 05 18:31:07 volumio go-librespot[2968]: time="2024-10-05T18:31:07+02:00" level=debug msg="completed challenge" Oct 05 18:31:07 volumio go-librespot[2968]: time="2024-10-05T18:31:07+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Oct 05 18:31:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 05 18:31:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 05 18:31:07 volumio volumio[808]: info: Connection to go-librespot Websocket closed Oct 05 18:31:09 volumio volumio[808]: info: Getting Spotify volume Oct 05 18:31:09 volumio volumio[808]: (node:808) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 05 18:31:09 volumio volumio[808]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) Oct 05 18:31:09 volumio volumio[808]: (node:808) 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: 9) Oct 05 18:31:09 volumio volumio[808]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Oct 05 18:31:09 volumio volumio[808]: info: CoreCommandRouter::volumioGetState Oct 05 18:31:09 volumio volumio[808]: info: CorePlayQueue::getTrack 0 Oct 05 18:31:09 volumio volumio[808]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 05 18:31:09 volumio volumio[808]: TypeError: Cannot read property 'name' of undefined Oct 05 18:31:09 volumio volumio[808]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Oct 05 18:31:09 volumio volumio[808]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:107:28) Oct 05 18:31:09 volumio volumio[808]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:58:38) Oct 05 18:31:09 volumio volumio[808]: at Socket.emit (events.js:400:28) Oct 05 18:31:09 volumio volumio[808]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Oct 05 18:31:09 volumio volumio[808]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Oct 05 18:31:09 volumio volumio[808]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 05 18:31:09 volumio sudo[2986]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-05 18:30 Oct 05 18:31:09 volumio sudo[2986]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:33:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="ee834e1c2a28de3c5d8c48611ecf1167"