-- Logs begin at Mon 2025-06-30 20:38:04 JST, end at Mon 2025-06-30 20:43:15 JST. -- Jun 30 20:42:00 volumio volumio[1293]: info: Initializing connection to go-librespot Websocket Jun 30 20:42:00 volumio volumio[1293]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 30 20:42:01 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 30 20:42:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 59. Jun 30 20:42:01 volumio systemd[1]: Stopped go-librespot Daemon. Jun 30 20:42:01 volumio systemd[1]: Started go-librespot Daemon. Jun 30 20:42:01 volumio go-librespot[3270]: go-librespot daemon starting... Jun 30 20:42:01 volumio go-librespot[3270]: time="2025-06-30T20:42:01+09:00" level=info msg="running go-librespot 0.2.0" Jun 30 20:42:01 volumio go-librespot[3270]: time="2025-06-30T20:42:01+09:00" level=debug msg="app state loaded" Jun 30 20:42:01 volumio go-librespot[3270]: time="2025-06-30T20:42:01+09:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 30 20:42:01 volumio go-librespot[3270]: time="2025-06-30T20:42:01+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Jun 30 20:42:01 volumio go-librespot[3270]: time="2025-06-30T20:42:01+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Jun 30 20:42:01 volumio go-librespot[3270]: time="2025-06-30T20:42:01+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Jun 30 20:42:01 volumio go-librespot[3270]: time="2025-06-30T20:42:01+09:00" level=info msg="zeroconf server listening on port 35587" Jun 30 20:42:01 volumio go-librespot[3270]: time="2025-06-30T20:42:01+09:00" level=debug msg="obtained new client token: AAAErMx9nT3ovz9kU2oA/6eGSp6g/9CUzLJVY5huBDe7qo4Jzk+0HsGDugp4thX1km5Dqp3xlwbhXRXMmMgDBXywFJ2hN33Eh6taLJFFP3WoKX7vy2zvNid6RAjsxan8gccbo9Df/Z+cFyI2Xp48EcKdKhZXyVycE/mJ5DZyQQOK0SM2bfbof5AEj9kS0EQL+u5yMRSRq+kN76uJQo0atdMQHu/TAUTaI/iHcDPbScjvnkbtHbCKir+qp6s=" Jun 30 20:42:01 volumio go-librespot[3270]: time="2025-06-30T20:42:01+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Jun 30 20:42:01 volumio go-librespot[3270]: time="2025-06-30T20:42:01+09:00" level=debug msg="completed keyexchange" Jun 30 20:42:01 volumio go-librespot[3270]: time="2025-06-30T20:42:01+09:00" level=debug msg="completed challenge" Jun 30 20:42:01 volumio go-librespot[3270]: time="2025-06-30T20:42:01+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Jun 30 20:42:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 30 20:42:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 30 20:42:03 volumio volumio[1293]: info: Initializing connection to go-librespot Websocket Jun 30 20:42:03 volumio volumio[1293]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 30 20:42:04 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 30 20:42:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 60. Jun 30 20:42:04 volumio systemd[1]: Stopped go-librespot Daemon. Jun 30 20:42:04 volumio systemd[1]: Started go-librespot Daemon. Jun 30 20:42:04 volumio go-librespot[3278]: go-librespot daemon starting... Jun 30 20:42:04 volumio go-librespot[3278]: time="2025-06-30T20:42:04+09:00" level=info msg="running go-librespot 0.2.0" Jun 30 20:42:04 volumio go-librespot[3278]: time="2025-06-30T20:42:04+09:00" level=debug msg="app state loaded" Jun 30 20:42:04 volumio go-librespot[3278]: time="2025-06-30T20:42:04+09:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 30 20:42:04 volumio go-librespot[3278]: time="2025-06-30T20:42:04+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Jun 30 20:42:04 volumio go-librespot[3278]: time="2025-06-30T20:42:04+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jun 30 20:42:04 volumio go-librespot[3278]: time="2025-06-30T20:42:04+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jun 30 20:42:04 volumio go-librespot[3278]: time="2025-06-30T20:42:04+09:00" level=info msg="zeroconf server listening on port 43377" Jun 30 20:42:04 volumio go-librespot[3278]: time="2025-06-30T20:42:04+09:00" level=debug msg="obtained new client token: AACf3antpKmuwe0RUwnRIJAs7dlVrw8m8GgCc2052lvrw2IzoXRCrONGfGRV+w1F6GPDcbxohpBeOVFXF1J/8merc7fciYP+dPPqqDQ2OEpke6F5QOFR29JEx+Bjd5zhYSS9wnTlvIvxCquWSieXkMtVrMUf8okBwxIsAtc6cCxFuloDjEeAwpaUg+H8TYnKTCIBHq+AMheM0W4kB3UeLljmW2a4Stu3L3c5GEi5fWtmbcH7aRgkPkrj/qk=" Jun 30 20:42:05 volumio go-librespot[3278]: time="2025-06-30T20:42:05+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Jun 30 20:42:05 volumio go-librespot[3278]: time="2025-06-30T20:42:05+09:00" level=debug msg="completed keyexchange" Jun 30 20:42:05 volumio go-librespot[3278]: time="2025-06-30T20:42:05+09:00" level=debug msg="completed challenge" Jun 30 20:42:05 volumio go-librespot[3278]: time="2025-06-30T20:42:05+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Jun 30 20:42:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 30 20:42:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 30 20:42:06 volumio volumio[1293]: info: Initializing connection to go-librespot Websocket Jun 30 20:42:06 volumio volumio[1293]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 30 20:42:08 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 30 20:42:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 61. Jun 30 20:42:08 volumio systemd[1]: Stopped go-librespot Daemon. Jun 30 20:42:08 volumio systemd[1]: Started go-librespot Daemon. Jun 30 20:42:08 volumio go-librespot[3341]: go-librespot daemon starting... Jun 30 20:42:08 volumio go-librespot[3341]: time="2025-06-30T20:42:08+09:00" level=info msg="running go-librespot 0.2.0" Jun 30 20:42:08 volumio go-librespot[3341]: time="2025-06-30T20:42:08+09:00" level=debug msg="app state loaded" Jun 30 20:42:08 volumio go-librespot[3341]: time="2025-06-30T20:42:08+09:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 30 20:42:08 volumio go-librespot[3341]: time="2025-06-30T20:42:08+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Jun 30 20:42:08 volumio go-librespot[3341]: time="2025-06-30T20:42:08+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jun 30 20:42:08 volumio go-librespot[3341]: time="2025-06-30T20:42:08+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jun 30 20:42:08 volumio go-librespot[3341]: time="2025-06-30T20:42:08+09:00" level=info msg="zeroconf server listening on port 35189" Jun 30 20:42:08 volumio go-librespot[3341]: time="2025-06-30T20:42:08+09:00" level=debug msg="obtained new client token: AABh4kLDSJ9VWv2eNzE6izkRzn3ugck7wfjX5ld4cUNxFdImhJjJPFrN/REL75zFrrxajmEGvUx2QwDReTOLJkMSRIWZqsyVjGW4d2ZXTDr2bH5jBDsxmUxtLg2tkkJoLXJZxA+4246sxCj3b7yMbGuwsz5Lbriyyy7qQMwGkyZ0aXHatQIKzNc1zbuhCzBDC7qg7l1fqm0UWU0VG2Lhq1zdA+vU9cPAnFZNsZl23NvYoEhM7Fq3QOghKfU=" Jun 30 20:42:08 volumio go-librespot[3341]: time="2025-06-30T20:42:08+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Jun 30 20:42:08 volumio go-librespot[3341]: time="2025-06-30T20:42:08+09:00" level=debug msg="completed keyexchange" Jun 30 20:42:08 volumio go-librespot[3341]: time="2025-06-30T20:42:08+09:00" level=debug msg="completed challenge" Jun 30 20:42:08 volumio go-librespot[3341]: time="2025-06-30T20:42:08+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Jun 30 20:42:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 30 20:42:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 30 20:42:09 volumio volumio[1293]: info: Initializing connection to go-librespot Websocket Jun 30 20:42:09 volumio volumio[1293]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 30 20:42:11 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 30 20:42:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 62. Jun 30 20:42:11 volumio systemd[1]: Stopped go-librespot Daemon. Jun 30 20:42:11 volumio systemd[1]: Started go-librespot Daemon. Jun 30 20:42:11 volumio go-librespot[3353]: go-librespot daemon starting... Jun 30 20:42:11 volumio go-librespot[3353]: time="2025-06-30T20:42:11+09:00" level=info msg="running go-librespot 0.2.0" Jun 30 20:42:11 volumio go-librespot[3353]: time="2025-06-30T20:42:11+09:00" level=debug msg="app state loaded" Jun 30 20:42:11 volumio go-librespot[3353]: time="2025-06-30T20:42:11+09:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 30 20:42:11 volumio go-librespot[3353]: time="2025-06-30T20:42:11+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Jun 30 20:42:11 volumio go-librespot[3353]: time="2025-06-30T20:42:11+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Jun 30 20:42:11 volumio go-librespot[3353]: time="2025-06-30T20:42:11+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Jun 30 20:42:11 volumio go-librespot[3353]: time="2025-06-30T20:42:11+09:00" level=info msg="zeroconf server listening on port 40369" Jun 30 20:42:11 volumio go-librespot[3353]: time="2025-06-30T20:42:11+09:00" level=debug msg="obtained new client token: AAC4czTbC6F1g7VsNHQcCDELZDpK2ZvAwGB1UGvZyaIN66aNyY4Ee7lZg5hRn9hiVf5taTsgUuE5yVYQ6QVzGYzt0qQdF+YhlqAV7yKh/g+03m1vz82+SCZE/2AoIVkpKTk5jVgJkbI2znBMic5yBxlQBwAyz0NoU3KROBX5vE68Fvabp3NKg8cyD0UMkoVFmVZ2BkkbORpqZWz9qaniTXUvqBLn+o++PJ9tMjlgamBKPulArXGNW6WegIw=" Jun 30 20:42:11 volumio go-librespot[3353]: time="2025-06-30T20:42:11+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Jun 30 20:42:12 volumio go-librespot[3353]: time="2025-06-30T20:42:12+09:00" level=debug msg="completed keyexchange" Jun 30 20:42:12 volumio go-librespot[3353]: time="2025-06-30T20:42:12+09:00" level=debug msg="completed challenge" Jun 30 20:42:12 volumio go-librespot[3353]: time="2025-06-30T20:42:12+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Jun 30 20:42:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 30 20:42:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 30 20:42:12 volumio volumio[1293]: info: Initializing connection to go-librespot Websocket Jun 30 20:42:12 volumio volumio[1293]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 30 20:42:15 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 30 20:42:15 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 63. Jun 30 20:42:15 volumio systemd[1]: Stopped go-librespot Daemon. Jun 30 20:42:15 volumio systemd[1]: Started go-librespot Daemon. Jun 30 20:42:15 volumio go-librespot[3361]: go-librespot daemon starting... Jun 30 20:42:15 volumio go-librespot[3361]: time="2025-06-30T20:42:15+09:00" level=info msg="running go-librespot 0.2.0" Jun 30 20:42:15 volumio go-librespot[3361]: time="2025-06-30T20:42:15+09:00" level=debug msg="app state loaded" Jun 30 20:42:15 volumio go-librespot[3361]: time="2025-06-30T20:42:15+09:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 30 20:42:15 volumio go-librespot[3361]: time="2025-06-30T20:42:15+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Jun 30 20:42:15 volumio go-librespot[3361]: time="2025-06-30T20:42:15+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jun 30 20:42:15 volumio go-librespot[3361]: time="2025-06-30T20:42:15+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jun 30 20:42:15 volumio go-librespot[3361]: time="2025-06-30T20:42:15+09:00" level=info msg="zeroconf server listening on port 33587" Jun 30 20:42:15 volumio go-librespot[3361]: time="2025-06-30T20:42:15+09:00" level=debug msg="obtained new client token: AADP07v2DNULAtqMRQslPdgb43p33A0cr2ltPJikkt6Llu3XH4XLOFF5Hwe5RumpTB7GVNjS3uwDTVsC5oH0t9go58XlKahVmZGxt4srMRBOflaSD5IZ2BD57J7qTLpsXswbf5f3BiDSBx7PFDDHELSFYw8ZuYBuiwJES+YP/mebdLbTCtlicQ4YwJgXyhG9KAdd2p6/boAC0ByRk6V0ikxjOwcuhrqrAT7VOil0w24l0yTTEsF5keHORA8=" Jun 30 20:42:15 volumio go-librespot[3361]: time="2025-06-30T20:42:15+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Jun 30 20:42:15 volumio go-librespot[3361]: time="2025-06-30T20:42:15+09:00" level=debug msg="completed keyexchange" Jun 30 20:42:15 volumio go-librespot[3361]: time="2025-06-30T20:42:15+09:00" level=debug msg="completed challenge" Jun 30 20:42:15 volumio go-librespot[3361]: time="2025-06-30T20:42:15+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Jun 30 20:42:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 30 20:42:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 30 20:42:15 volumio volumio[1293]: info: Initializing connection to go-librespot Websocket Jun 30 20:42:15 volumio volumio[1293]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 30 20:42:18 volumio volumio[1293]: info: Initializing connection to go-librespot Websocket Jun 30 20:42:18 volumio volumio[1293]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 30 20:42:18 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 30 20:42:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 64. Jun 30 20:42:18 volumio systemd[1]: Stopped go-librespot Daemon. Jun 30 20:42:18 volumio systemd[1]: Started go-librespot Daemon. Jun 30 20:42:18 volumio go-librespot[3383]: go-librespot daemon starting... Jun 30 20:42:18 volumio go-librespot[3383]: time="2025-06-30T20:42:18+09:00" level=info msg="running go-librespot 0.2.0" Jun 30 20:42:18 volumio go-librespot[3383]: time="2025-06-30T20:42:18+09:00" level=debug msg="app state loaded" Jun 30 20:42:18 volumio go-librespot[3383]: time="2025-06-30T20:42:18+09:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 30 20:42:18 volumio go-librespot[3383]: time="2025-06-30T20:42:18+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Jun 30 20:42:18 volumio go-librespot[3383]: time="2025-06-30T20:42:18+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Jun 30 20:42:18 volumio go-librespot[3383]: time="2025-06-30T20:42:18+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Jun 30 20:42:18 volumio go-librespot[3383]: time="2025-06-30T20:42:18+09:00" level=info msg="zeroconf server listening on port 39895" Jun 30 20:42:18 volumio go-librespot[3383]: time="2025-06-30T20:42:18+09:00" level=debug msg="obtained new client token: AACwAOT52txnR//t6OebA6/WzfiOl4Ak7yqqbl96xbUPnClgokDY6apn7x4NiahjBSSxxccPvBX/YvdstYLbCyJAMOh+SEMNaWbm4uNNy2oK550NL5v/G0VkKf9DefBtjjwaiVAvMW+uqnKEslq+TRuUsQikbE9OkB4MBtG/gqA8jE6xIIWFsS/9tHaTDIFkeqXrWYzWgFuRipXzD5hQpx8g+p7bjQzg9ifJ0HJrh6ho/WoAdsHpHd0H8co=" Jun 30 20:42:18 volumio go-librespot[3383]: time="2025-06-30T20:42:18+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Jun 30 20:42:19 volumio go-librespot[3383]: time="2025-06-30T20:42:19+09:00" level=debug msg="completed keyexchange" Jun 30 20:42:19 volumio go-librespot[3383]: time="2025-06-30T20:42:19+09:00" level=debug msg="completed challenge" Jun 30 20:42:19 volumio go-librespot[3383]: time="2025-06-30T20:42:19+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Jun 30 20:42:19 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 30 20:42:19 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 30 20:42:21 volumio volumio[1293]: info: Initializing connection to go-librespot Websocket Jun 30 20:42:21 volumio volumio[1293]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 30 20:42:22 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 30 20:42:22 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 65. Jun 30 20:42:22 volumio systemd[1]: Stopped go-librespot Daemon. Jun 30 20:42:22 volumio systemd[1]: Started go-librespot Daemon. Jun 30 20:42:22 volumio go-librespot[3392]: go-librespot daemon starting... Jun 30 20:42:22 volumio go-librespot[3392]: time="2025-06-30T20:42:22+09:00" level=info msg="running go-librespot 0.2.0" Jun 30 20:42:22 volumio go-librespot[3392]: time="2025-06-30T20:42:22+09:00" level=debug msg="app state loaded" Jun 30 20:42:22 volumio go-librespot[3392]: time="2025-06-30T20:42:22+09:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 30 20:42:22 volumio go-librespot[3392]: time="2025-06-30T20:42:22+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Jun 30 20:42:22 volumio go-librespot[3392]: time="2025-06-30T20:42:22+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Jun 30 20:42:22 volumio go-librespot[3392]: time="2025-06-30T20:42:22+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Jun 30 20:42:22 volumio go-librespot[3392]: time="2025-06-30T20:42:22+09:00" level=info msg="zeroconf server listening on port 37677" Jun 30 20:42:22 volumio go-librespot[3392]: time="2025-06-30T20:42:22+09:00" level=debug msg="obtained new client token: AADV1mH4Ykkj0MHJ154namdabeKXF+aLgdyReOXx9zr/UQ1QlnYnwTmOLE7jgmIGxLSdJIHZm3L+87IomeYaES633J7U2Zow/Qajdh63C+o2akVRFSe410Ud6pArfaUw1g/CFqjy3B0oEOjS8qVs+33nk9WSBUEV0igLF6y+JbvlqThhZbRVOQgOdwD78Ny9oXQhtpTw7zg5ppIwyuYp0RWcS15kQdK0Fl9k+68KQx9JBiWR3m4+amxAsi0=" Jun 30 20:42:22 volumio go-librespot[3392]: time="2025-06-30T20:42:22+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Jun 30 20:42:22 volumio go-librespot[3392]: time="2025-06-30T20:42:22+09:00" level=debug msg="completed keyexchange" Jun 30 20:42:22 volumio go-librespot[3392]: time="2025-06-30T20:42:22+09:00" level=debug msg="completed challenge" Jun 30 20:42:22 volumio go-librespot[3392]: time="2025-06-30T20:42:22+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Jun 30 20:42:22 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 30 20:42:22 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 30 20:42:24 volumio volumio[1293]: info: Initializing connection to go-librespot Websocket Jun 30 20:42:24 volumio volumio[1293]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 30 20:42:25 volumio volumio[1293]: info: CoreCommandRouter::volumioGetState Jun 30 20:42:25 volumio volumio[1293]: info: CorePlayQueue::getTrack 0 Jun 30 20:42:25 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 30 20:42:25 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 66. Jun 30 20:42:25 volumio systemd[1]: Stopped go-librespot Daemon. Jun 30 20:42:25 volumio systemd[1]: Started go-librespot Daemon. Jun 30 20:42:25 volumio go-librespot[3400]: go-librespot daemon starting... Jun 30 20:42:25 volumio go-librespot[3400]: time="2025-06-30T20:42:25+09:00" level=info msg="running go-librespot 0.2.0" Jun 30 20:42:25 volumio go-librespot[3400]: time="2025-06-30T20:42:25+09:00" level=debug msg="app state loaded" Jun 30 20:42:25 volumio go-librespot[3400]: time="2025-06-30T20:42:25+09:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 30 20:42:25 volumio go-librespot[3400]: time="2025-06-30T20:42:25+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Jun 30 20:42:25 volumio go-librespot[3400]: time="2025-06-30T20:42:25+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jun 30 20:42:25 volumio go-librespot[3400]: time="2025-06-30T20:42:25+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jun 30 20:42:25 volumio go-librespot[3400]: time="2025-06-30T20:42:25+09:00" level=info msg="zeroconf server listening on port 45155" Jun 30 20:42:25 volumio go-librespot[3400]: time="2025-06-30T20:42:25+09:00" level=debug msg="obtained new client token: AADD+AId4Prt4lA7c5yuOhnqj2MUf1mnyV+Mbf6IXYhNWo8JFpHcFmA/JFyXP6a1rmoYoHrzQzG7y7Gt79StfFiR78mdyOGFw8DE58wtck+Ut/Fbhq/uuJ8ZOTbeero42c9NueHNahB5MRaqgRzS5ZbDK7UVZqi5T07iY22sM+hCASwiTs4RD93Fh9pEVhkS3NUXzFVk69b37uuEtUfrv1R8PhXhiRK+HazbpP2Ar6H/Q6OhPTYKbBY4bjg=" Jun 30 20:42:25 volumio go-librespot[3400]: time="2025-06-30T20:42:25+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Jun 30 20:42:26 volumio go-librespot[3400]: time="2025-06-30T20:42:26+09:00" level=debug msg="completed keyexchange" Jun 30 20:42:26 volumio go-librespot[3400]: time="2025-06-30T20:42:26+09:00" level=debug msg="completed challenge" Jun 30 20:42:26 volumio go-librespot[3400]: time="2025-06-30T20:42:26+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Jun 30 20:42:26 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 30 20:42:26 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 30 20:42:27 volumio volumio[1293]: info: Initializing connection to go-librespot Websocket Jun 30 20:42:27 volumio volumio[1293]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 30 20:42:29 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 30 20:42:29 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 67. Jun 30 20:42:29 volumio systemd[1]: Stopped go-librespot Daemon. Jun 30 20:42:29 volumio systemd[1]: Started go-librespot Daemon. Jun 30 20:42:29 volumio go-librespot[3423]: go-librespot daemon starting... Jun 30 20:42:29 volumio go-librespot[3423]: time="2025-06-30T20:42:29+09:00" level=info msg="running go-librespot 0.2.0" Jun 30 20:42:29 volumio go-librespot[3423]: time="2025-06-30T20:42:29+09:00" level=debug msg="app state loaded" Jun 30 20:42:29 volumio go-librespot[3423]: time="2025-06-30T20:42:29+09:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 30 20:42:29 volumio go-librespot[3423]: time="2025-06-30T20:42:29+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Jun 30 20:42:29 volumio go-librespot[3423]: time="2025-06-30T20:42:29+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jun 30 20:42:29 volumio go-librespot[3423]: time="2025-06-30T20:42:29+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jun 30 20:42:29 volumio go-librespot[3423]: time="2025-06-30T20:42:29+09:00" level=info msg="zeroconf server listening on port 42471" Jun 30 20:42:29 volumio go-librespot[3423]: time="2025-06-30T20:42:29+09:00" level=debug msg="obtained new client token: AADmqP2q0z28m2xH9mXX0MI2kedJnwwnOWy39fvHEwH6Yw9VPH8PTY9qW1xjAXy7PnnGDSfHn/yCOu6Tp955qf5I7rHilpOK6thHkhlBk2qNOAC83fvHHPNSxnqdEgKM2oabK3IddHA07UDznlndeO58WuXzu3SXxOGoybZHrOTgXQziPaMvm/iEpaRvPEI/TGN/lBt5mf/iNI8sUeGM8ig2TWY/9sdgsfzYLrJLH5H3I97MduPcihDyYLM=" Jun 30 20:42:29 volumio go-librespot[3423]: time="2025-06-30T20:42:29+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Jun 30 20:42:29 volumio go-librespot[3423]: time="2025-06-30T20:42:29+09:00" level=debug msg="completed keyexchange" Jun 30 20:42:29 volumio go-librespot[3423]: time="2025-06-30T20:42:29+09:00" level=debug msg="completed challenge" Jun 30 20:42:29 volumio go-librespot[3423]: time="2025-06-30T20:42:29+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Jun 30 20:42:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 30 20:42:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 30 20:42:30 volumio volumio[1293]: info: Initializing connection to go-librespot Websocket Jun 30 20:42:30 volumio volumio[1293]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 30 20:42:32 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 30 20:42:32 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 68. Jun 30 20:42:32 volumio systemd[1]: Stopped go-librespot Daemon. Jun 30 20:42:32 volumio systemd[1]: Started go-librespot Daemon. Jun 30 20:42:32 volumio go-librespot[3434]: go-librespot daemon starting... Jun 30 20:42:32 volumio go-librespot[3434]: time="2025-06-30T20:42:32+09:00" level=info msg="running go-librespot 0.2.0" Jun 30 20:42:32 volumio go-librespot[3434]: time="2025-06-30T20:42:32+09:00" level=debug msg="app state loaded" Jun 30 20:42:32 volumio go-librespot[3434]: time="2025-06-30T20:42:32+09:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 30 20:42:32 volumio go-librespot[3434]: time="2025-06-30T20:42:32+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Jun 30 20:42:32 volumio go-librespot[3434]: time="2025-06-30T20:42:32+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jun 30 20:42:32 volumio go-librespot[3434]: time="2025-06-30T20:42:32+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jun 30 20:42:32 volumio go-librespot[3434]: time="2025-06-30T20:42:32+09:00" level=info msg="zeroconf server listening on port 46817" Jun 30 20:42:32 volumio go-librespot[3434]: time="2025-06-30T20:42:32+09:00" level=debug msg="obtained new client token: AABrl5Q3x21Dm70prXVErHQGyUN41LobufdMip7cCrn2LVab3MKjpxl6dRGkgmycGupK6wY3D1+UYe2VmutUplu/0hueKHXZ+oGyMRy2mZZA6pKhF07j90ljU4yIOQblkTDB0fDM+YUPjGhRlkUiwQw/R4BZ4E6SOZz18/mxbSUD5BgSoVoiXhLHkVW3wfESlgjBAmrIuaxgu3GFO8Kg9wPg1z5d+pm+TLE9Tnxm0QP/g7xaO/KVc3xkj18=" Jun 30 20:42:32 volumio go-librespot[3434]: time="2025-06-30T20:42:32+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Jun 30 20:42:33 volumio go-librespot[3434]: time="2025-06-30T20:42:33+09:00" level=debug msg="completed keyexchange" Jun 30 20:42:33 volumio go-librespot[3434]: time="2025-06-30T20:42:33+09:00" level=debug msg="completed challenge" Jun 30 20:42:33 volumio go-librespot[3434]: time="2025-06-30T20:42:33+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Jun 30 20:42:33 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 30 20:42:33 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 30 20:42:33 volumio volumio[1293]: info: Initializing connection to go-librespot Websocket Jun 30 20:42:33 volumio volumio[1293]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 30 20:42:36 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 30 20:42:36 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 69. Jun 30 20:42:36 volumio systemd[1]: Stopped go-librespot Daemon. Jun 30 20:42:36 volumio systemd[1]: Started go-librespot Daemon. Jun 30 20:42:36 volumio go-librespot[3442]: go-librespot daemon starting... Jun 30 20:42:36 volumio go-librespot[3442]: time="2025-06-30T20:42:36+09:00" level=info msg="running go-librespot 0.2.0" Jun 30 20:42:36 volumio go-librespot[3442]: time="2025-06-30T20:42:36+09:00" level=debug msg="app state loaded" Jun 30 20:42:36 volumio go-librespot[3442]: time="2025-06-30T20:42:36+09:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 30 20:42:36 volumio go-librespot[3442]: time="2025-06-30T20:42:36+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Jun 30 20:42:36 volumio go-librespot[3442]: time="2025-06-30T20:42:36+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jun 30 20:42:36 volumio go-librespot[3442]: time="2025-06-30T20:42:36+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jun 30 20:42:36 volumio go-librespot[3442]: time="2025-06-30T20:42:36+09:00" level=info msg="zeroconf server listening on port 33443" Jun 30 20:42:36 volumio go-librespot[3442]: time="2025-06-30T20:42:36+09:00" level=debug msg="obtained new client token: AAAJQ8JIFNqAJOtt7Vj/F1aC8lfA2l70quz/JymbQuoS5UhGQE9aYnaN3DaZYeiFhLGlza1JzOYuWioo7L9TX4l6jm2RmkayUZqhlSrkTqOarDlGsSurw74zocZXoHSMz38PyRaN0yl4PDFi4luaQn3zZIpGizPaWXdYblSJa2EPrpEajO5D2jB495tXB9nbS1RGGjA6OVtbPINDPVJCacsNrM7v2t0nlu1mfYH7JaNldGDDB1sPVmq15l8=" Jun 30 20:42:36 volumio go-librespot[3442]: time="2025-06-30T20:42:36+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Jun 30 20:42:36 volumio go-librespot[3442]: time="2025-06-30T20:42:36+09:00" level=debug msg="completed keyexchange" Jun 30 20:42:36 volumio go-librespot[3442]: time="2025-06-30T20:42:36+09:00" level=debug msg="completed challenge" Jun 30 20:42:36 volumio go-librespot[3442]: time="2025-06-30T20:42:36+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Jun 30 20:42:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 30 20:42:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 30 20:42:36 volumio volumio[1293]: info: Initializing connection to go-librespot Websocket Jun 30 20:42:36 volumio volumio[1293]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 30 20:42:39 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 30 20:42:39 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 70. Jun 30 20:42:39 volumio volumio[1293]: info: Initializing connection to go-librespot Websocket Jun 30 20:42:39 volumio systemd[1]: Stopped go-librespot Daemon. Jun 30 20:42:39 volumio volumio[1293]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 30 20:42:39 volumio systemd[1]: Started go-librespot Daemon. Jun 30 20:42:39 volumio go-librespot[3464]: go-librespot daemon starting... Jun 30 20:42:39 volumio go-librespot[3464]: time="2025-06-30T20:42:39+09:00" level=info msg="running go-librespot 0.2.0" Jun 30 20:42:39 volumio go-librespot[3464]: time="2025-06-30T20:42:39+09:00" level=debug msg="app state loaded" Jun 30 20:42:39 volumio go-librespot[3464]: time="2025-06-30T20:42:39+09:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 30 20:42:39 volumio go-librespot[3464]: time="2025-06-30T20:42:39+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Jun 30 20:42:39 volumio go-librespot[3464]: time="2025-06-30T20:42:39+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jun 30 20:42:39 volumio go-librespot[3464]: time="2025-06-30T20:42:39+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jun 30 20:42:39 volumio go-librespot[3464]: time="2025-06-30T20:42:39+09:00" level=info msg="zeroconf server listening on port 37263" Jun 30 20:42:39 volumio go-librespot[3464]: time="2025-06-30T20:42:39+09:00" level=debug msg="obtained new client token: AAD5bxk+OcDmP6TVkMW08vJCRkeU0RpcrHXWSfYiMmJya0Dby4wvKcFjK5xOBz1NUxrz03G6t+ap1m+ic2kHIdeDKIjT379yXgGP9PQ4FUV1+SkgKv3twQgxQFlxgrSx1EdomTbldAqqzPPltq55p0Sn0TqbYSC+c1wGB6sF4O1V0w39mSP6ueWcKl4wCS86vQ67b92nuQxahRDxG986UDRXob/Eb11T/xzzwmTy5OZacGrkuEgsBSgf5Sc=" Jun 30 20:42:39 volumio go-librespot[3464]: time="2025-06-30T20:42:39+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Jun 30 20:42:40 volumio go-librespot[3464]: time="2025-06-30T20:42:40+09:00" level=debug msg="completed keyexchange" Jun 30 20:42:40 volumio go-librespot[3464]: time="2025-06-30T20:42:40+09:00" level=debug msg="completed challenge" Jun 30 20:42:40 volumio go-librespot[3464]: time="2025-06-30T20:42:40+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Jun 30 20:42:40 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 30 20:42:40 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 30 20:42:42 volumio volumio[1293]: info: Initializing connection to go-librespot Websocket Jun 30 20:42:42 volumio volumio[1293]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 30 20:42:43 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 30 20:42:43 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 71. Jun 30 20:42:43 volumio systemd[1]: Stopped go-librespot Daemon. Jun 30 20:42:43 volumio systemd[1]: Started go-librespot Daemon. Jun 30 20:42:43 volumio go-librespot[3472]: go-librespot daemon starting... Jun 30 20:42:43 volumio go-librespot[3472]: time="2025-06-30T20:42:43+09:00" level=info msg="running go-librespot 0.2.0" Jun 30 20:42:43 volumio go-librespot[3472]: time="2025-06-30T20:42:43+09:00" level=debug msg="app state loaded" Jun 30 20:42:43 volumio go-librespot[3472]: time="2025-06-30T20:42:43+09:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 30 20:42:43 volumio go-librespot[3472]: time="2025-06-30T20:42:43+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Jun 30 20:42:43 volumio go-librespot[3472]: time="2025-06-30T20:42:43+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Jun 30 20:42:43 volumio go-librespot[3472]: time="2025-06-30T20:42:43+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Jun 30 20:42:43 volumio go-librespot[3472]: time="2025-06-30T20:42:43+09:00" level=info msg="zeroconf server listening on port 39841" Jun 30 20:42:43 volumio go-librespot[3472]: time="2025-06-30T20:42:43+09:00" level=debug msg="obtained new client token: AAAQSpM/X5z6x42Qjfz3SwU+TSf/XWJphhq8j93ymV3z/siRN6g3lG+UMZpdqEvSoTJB8QEt6dk1W/KuVTCAWXfihE3l4gE18VKL3Q6X/zhMmTGrZAJe92uI+neIBxNRVG6DZMv6IHYPa67DKSJRdNmVNSlliH9sTv8Y8ThiVYLVnnN9CfB7YOaoC+BxJblEv00tBpTzAxIz5THNIs7f8r7uwhL54G4TTiHMteq+VGjc055OodFbh72l3jg=" Jun 30 20:42:43 volumio go-librespot[3472]: time="2025-06-30T20:42:43+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Jun 30 20:42:43 volumio go-librespot[3472]: time="2025-06-30T20:42:43+09:00" level=debug msg="completed keyexchange" Jun 30 20:42:43 volumio go-librespot[3472]: time="2025-06-30T20:42:43+09:00" level=debug msg="completed challenge" Jun 30 20:42:43 volumio go-librespot[3472]: time="2025-06-30T20:42:43+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Jun 30 20:42:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 30 20:42:43 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 30 20:42:45 volumio volumio[1293]: info: Initializing connection to go-librespot Websocket Jun 30 20:42:45 volumio volumio[1293]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 30 20:42:46 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 30 20:42:46 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 72. Jun 30 20:42:46 volumio systemd[1]: Stopped go-librespot Daemon. Jun 30 20:42:46 volumio systemd[1]: Started go-librespot Daemon. Jun 30 20:42:46 volumio go-librespot[3480]: go-librespot daemon starting... Jun 30 20:42:46 volumio go-librespot[3480]: time="2025-06-30T20:42:46+09:00" level=info msg="running go-librespot 0.2.0" Jun 30 20:42:46 volumio go-librespot[3480]: time="2025-06-30T20:42:46+09:00" level=debug msg="app state loaded" Jun 30 20:42:46 volumio go-librespot[3480]: time="2025-06-30T20:42:46+09:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 30 20:42:46 volumio go-librespot[3480]: time="2025-06-30T20:42:46+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Jun 30 20:42:46 volumio go-librespot[3480]: time="2025-06-30T20:42:46+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Jun 30 20:42:46 volumio go-librespot[3480]: time="2025-06-30T20:42:46+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Jun 30 20:42:46 volumio go-librespot[3480]: time="2025-06-30T20:42:46+09:00" level=info msg="zeroconf server listening on port 35795" Jun 30 20:42:46 volumio go-librespot[3480]: time="2025-06-30T20:42:46+09:00" level=debug msg="obtained new client token: AAD7x/NnT0xoG26U76yRTdAs3PJqms7UXhiNBuVyW/jW3RC9Luw1IFYfOSrORr3ba/I/xYVaZHX2LBeFUYZF9oHNnKAcVGcpzRjlzfM11JV7HZJNjg/b0vXkW0ZMLayZlZS649N2veq3RJo/J7cUt7K5baWGM2t/lRrr3FyXrIv7XxaJjnwHqycw37EyaF6JULm87eg46pOXsTyh92guxQRPIuiApy8SYGWIKd/BdGxYEG7vt3zIpNkO7B0=" Jun 30 20:42:46 volumio go-librespot[3480]: time="2025-06-30T20:42:46+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Jun 30 20:42:47 volumio go-librespot[3480]: time="2025-06-30T20:42:47+09:00" level=debug msg="completed keyexchange" Jun 30 20:42:47 volumio go-librespot[3480]: time="2025-06-30T20:42:47+09:00" level=debug msg="completed challenge" Jun 30 20:42:47 volumio go-librespot[3480]: time="2025-06-30T20:42:47+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Jun 30 20:42:47 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 30 20:42:47 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 30 20:42:48 volumio volumio[1293]: info: Initializing connection to go-librespot Websocket Jun 30 20:42:48 volumio volumio[1293]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 30 20:42:50 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 30 20:42:50 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 73. Jun 30 20:42:50 volumio systemd[1]: Stopped go-librespot Daemon. Jun 30 20:42:50 volumio systemd[1]: Started go-librespot Daemon. Jun 30 20:42:50 volumio go-librespot[3506]: go-librespot daemon starting... Jun 30 20:42:50 volumio go-librespot[3506]: time="2025-06-30T20:42:50+09:00" level=info msg="running go-librespot 0.2.0" Jun 30 20:42:50 volumio go-librespot[3506]: time="2025-06-30T20:42:50+09:00" level=debug msg="app state loaded" Jun 30 20:42:50 volumio go-librespot[3506]: time="2025-06-30T20:42:50+09:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 30 20:42:50 volumio go-librespot[3506]: time="2025-06-30T20:42:50+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Jun 30 20:42:50 volumio go-librespot[3506]: time="2025-06-30T20:42:50+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jun 30 20:42:50 volumio go-librespot[3506]: time="2025-06-30T20:42:50+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jun 30 20:42:50 volumio go-librespot[3506]: time="2025-06-30T20:42:50+09:00" level=info msg="zeroconf server listening on port 44805" Jun 30 20:42:50 volumio go-librespot[3506]: time="2025-06-30T20:42:50+09:00" level=debug msg="obtained new client token: AABjmremdsPG9Wcpy2daHUkpkjibzfnfrUbUrTyApcprRkg05UGPs3LLF8uizxpJc3FAIniaDjgApQ76GelZbFJIDEdwNp0tZQwtuqGDtEXFL91DwB/VMtnTcuXyZ3PBLDU6F6TNtPart2Holxwso9I5aoUJCvxiL4XaAag5UMMSN0Gjyxan9nTK5ZbokDk1PqfFqyYOw15p6XKBHpLYL8Q+HrJxGtNEKcRewL8jk2KZFdBUhHcK3pS1rRc=" Jun 30 20:42:50 volumio go-librespot[3506]: time="2025-06-30T20:42:50+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Jun 30 20:42:50 volumio go-librespot[3506]: time="2025-06-30T20:42:50+09:00" level=debug msg="completed keyexchange" Jun 30 20:42:50 volumio go-librespot[3506]: time="2025-06-30T20:42:50+09:00" level=debug msg="completed challenge" Jun 30 20:42:50 volumio go-librespot[3506]: time="2025-06-30T20:42:50+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Jun 30 20:42:50 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 30 20:42:50 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 30 20:42:51 volumio volumio[1293]: info: Initializing connection to go-librespot Websocket Jun 30 20:42:51 volumio volumio[1293]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 30 20:42:53 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 30 20:42:53 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 74. Jun 30 20:42:53 volumio systemd[1]: Stopped go-librespot Daemon. Jun 30 20:42:53 volumio systemd[1]: Started go-librespot Daemon. Jun 30 20:42:53 volumio go-librespot[3514]: go-librespot daemon starting... Jun 30 20:42:53 volumio go-librespot[3514]: time="2025-06-30T20:42:53+09:00" level=info msg="running go-librespot 0.2.0" Jun 30 20:42:53 volumio go-librespot[3514]: time="2025-06-30T20:42:53+09:00" level=debug msg="app state loaded" Jun 30 20:42:53 volumio go-librespot[3514]: time="2025-06-30T20:42:53+09:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 30 20:42:53 volumio go-librespot[3514]: time="2025-06-30T20:42:53+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Jun 30 20:42:53 volumio go-librespot[3514]: time="2025-06-30T20:42:53+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jun 30 20:42:53 volumio go-librespot[3514]: time="2025-06-30T20:42:53+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jun 30 20:42:53 volumio go-librespot[3514]: time="2025-06-30T20:42:53+09:00" level=info msg="zeroconf server listening on port 43257" Jun 30 20:42:53 volumio go-librespot[3514]: time="2025-06-30T20:42:53+09:00" level=debug msg="obtained new client token: AAATi78axtzpouiFJ6nHAmH+YFl7Q98339ST1LAAIsfVu+4Pb91emfPwWQDaTse3yP4DHWLLh1XlkvYJr5klzbSEBjb2aRcW8AmCpUoyg6+jJuiy0k5TS3dlVymwUGMFA7zSjploLdL3esFcK805ifOPMoWgfJDG5Ea0hgsYfOsaC2XSsHUv3kv9xhrZEQdUOQOlyvCyCZ86Yq9gDAhq+6ZM5AUVKKB6Bsk5tbty9tH5T4bFZSDixgUW1hI=" Jun 30 20:42:53 volumio go-librespot[3514]: time="2025-06-30T20:42:53+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Jun 30 20:42:54 volumio go-librespot[3514]: time="2025-06-30T20:42:54+09:00" level=debug msg="completed keyexchange" Jun 30 20:42:54 volumio go-librespot[3514]: time="2025-06-30T20:42:54+09:00" level=debug msg="completed challenge" Jun 30 20:42:54 volumio go-librespot[3514]: time="2025-06-30T20:42:54+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Jun 30 20:42:54 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 30 20:42:54 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 30 20:42:54 volumio volumio[1293]: info: Initializing connection to go-librespot Websocket Jun 30 20:42:54 volumio volumio[1293]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 30 20:42:57 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 30 20:42:57 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 75. Jun 30 20:42:57 volumio systemd[1]: Stopped go-librespot Daemon. Jun 30 20:42:57 volumio systemd[1]: Started go-librespot Daemon. Jun 30 20:42:57 volumio go-librespot[3536]: go-librespot daemon starting... Jun 30 20:42:57 volumio go-librespot[3536]: time="2025-06-30T20:42:57+09:00" level=info msg="running go-librespot 0.2.0" Jun 30 20:42:57 volumio go-librespot[3536]: time="2025-06-30T20:42:57+09:00" level=debug msg="app state loaded" Jun 30 20:42:57 volumio go-librespot[3536]: time="2025-06-30T20:42:57+09:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 30 20:42:57 volumio go-librespot[3536]: time="2025-06-30T20:42:57+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Jun 30 20:42:57 volumio go-librespot[3536]: time="2025-06-30T20:42:57+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Jun 30 20:42:57 volumio go-librespot[3536]: time="2025-06-30T20:42:57+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Jun 30 20:42:57 volumio go-librespot[3536]: time="2025-06-30T20:42:57+09:00" level=info msg="zeroconf server listening on port 39439" Jun 30 20:42:57 volumio go-librespot[3536]: time="2025-06-30T20:42:57+09:00" level=debug msg="obtained new client token: AAAFm6UkEWSWeFjoTFZhYJu1RQtsgCHfKn1jIo7zYJqr9j5Vpw2sfpaw8RMprDnrxBW9rDStXKvGnYlOQnByujHQLGOCkgYqDS60F5X/9HXF9exF+oQly0V7bbQYNgJBXeU2ZiIJcDPsE9b0FqQOADz6iAXgXysHvoglPALRLnSPOmTAvGrFDZDCB+qW/e+I+7FQj8PF6OzBtNhq1VYoonmvFy8juabNjhx/SzxGQsFcw3eXdbGnpjnSewQ=" Jun 30 20:42:57 volumio go-librespot[3536]: time="2025-06-30T20:42:57+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Jun 30 20:42:57 volumio go-librespot[3536]: time="2025-06-30T20:42:57+09:00" level=debug msg="completed keyexchange" Jun 30 20:42:57 volumio go-librespot[3536]: time="2025-06-30T20:42:57+09:00" level=debug msg="completed challenge" Jun 30 20:42:57 volumio go-librespot[3536]: time="2025-06-30T20:42:57+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Jun 30 20:42:57 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 30 20:42:57 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 30 20:42:57 volumio volumio[1293]: info: Initializing connection to go-librespot Websocket Jun 30 20:42:57 volumio volumio[1293]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 30 20:43:00 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 30 20:43:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 76. Jun 30 20:43:00 volumio systemd[1]: Stopped go-librespot Daemon. Jun 30 20:43:00 volumio systemd[1]: Started go-librespot Daemon. Jun 30 20:43:00 volumio go-librespot[3545]: go-librespot daemon starting... Jun 30 20:43:00 volumio volumio[1293]: info: Initializing connection to go-librespot Websocket Jun 30 20:43:00 volumio volumio[1293]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 30 20:43:00 volumio go-librespot[3545]: time="2025-06-30T20:43:00+09:00" level=info msg="running go-librespot 0.2.0" Jun 30 20:43:00 volumio go-librespot[3545]: time="2025-06-30T20:43:00+09:00" level=debug msg="app state loaded" Jun 30 20:43:00 volumio go-librespot[3545]: time="2025-06-30T20:43:00+09:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 30 20:43:00 volumio go-librespot[3545]: time="2025-06-30T20:43:00+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Jun 30 20:43:00 volumio go-librespot[3545]: time="2025-06-30T20:43:00+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Jun 30 20:43:00 volumio go-librespot[3545]: time="2025-06-30T20:43:00+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Jun 30 20:43:00 volumio go-librespot[3545]: time="2025-06-30T20:43:00+09:00" level=info msg="zeroconf server listening on port 42139" Jun 30 20:43:00 volumio go-librespot[3545]: time="2025-06-30T20:43:00+09:00" level=debug msg="obtained new client token: AADhfs0EU1z7c4ZH4dMtL+1n2wg85XnAsRyDAAy0TH2wMrR6dZZMbrbipm2bYjo2pDuaI2t97O8yA2NJo7F8+v8fiDAdnSY5xN9/ORgxxBwMK94zne0r1XJDW2j+HTKz4zaWheyHqwAOcwsKoI3MQNyExZlRpDt3LxHg5crMLmbjj4BdSrHwgYCIIAdnLuCMRD4pA3aV65fCtv7Ooxrn67MINUm5xFSkjOdoLVvdFSKvdjIUyhoBvWpICAw=" Jun 30 20:43:00 volumio go-librespot[3545]: time="2025-06-30T20:43:00+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Jun 30 20:43:01 volumio go-librespot[3545]: time="2025-06-30T20:43:01+09:00" level=debug msg="completed keyexchange" Jun 30 20:43:01 volumio go-librespot[3545]: time="2025-06-30T20:43:01+09:00" level=debug msg="completed challenge" Jun 30 20:43:01 volumio go-librespot[3545]: time="2025-06-30T20:43:01+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Jun 30 20:43:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 30 20:43:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 30 20:43:03 volumio volumio[1293]: info: Initializing connection to go-librespot Websocket Jun 30 20:43:03 volumio volumio[1293]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 30 20:43:04 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 30 20:43:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 77. Jun 30 20:43:04 volumio systemd[1]: Stopped go-librespot Daemon. Jun 30 20:43:04 volumio systemd[1]: Started go-librespot Daemon. Jun 30 20:43:04 volumio go-librespot[3553]: go-librespot daemon starting... Jun 30 20:43:04 volumio go-librespot[3553]: time="2025-06-30T20:43:04+09:00" level=info msg="running go-librespot 0.2.0" Jun 30 20:43:04 volumio go-librespot[3553]: time="2025-06-30T20:43:04+09:00" level=debug msg="app state loaded" Jun 30 20:43:04 volumio go-librespot[3553]: time="2025-06-30T20:43:04+09:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 30 20:43:04 volumio go-librespot[3553]: time="2025-06-30T20:43:04+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Jun 30 20:43:04 volumio go-librespot[3553]: time="2025-06-30T20:43:04+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jun 30 20:43:04 volumio go-librespot[3553]: time="2025-06-30T20:43:04+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jun 30 20:43:04 volumio go-librespot[3553]: time="2025-06-30T20:43:04+09:00" level=info msg="zeroconf server listening on port 37095" Jun 30 20:43:04 volumio go-librespot[3553]: time="2025-06-30T20:43:04+09:00" level=debug msg="obtained new client token: AABGmeOJ363/NNOq7l8dEbDTRE9gyfkDAlqvPLoycz/L098A9iZGQvxtboA4VWnMQ8ueWqglwDzJdsn4C6PI3ApeIx8ZjKBViIiAzn47/iXtbZOr3kc2ukpWNl631k1WlGYFhAs2qcJaxNJUwCh80BxVUrQKy89WYWPOnQ7jrpW0eCyyQLRC11IRRPVoVeWS3f8By3tWjmEHEkqxGDXjv21w+8g4buqH5PCojFRXUrM88El9yRMl0hAHnrE=" Jun 30 20:43:04 volumio go-librespot[3553]: time="2025-06-30T20:43:04+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Jun 30 20:43:04 volumio go-librespot[3553]: time="2025-06-30T20:43:04+09:00" level=debug msg="completed keyexchange" Jun 30 20:43:04 volumio go-librespot[3553]: time="2025-06-30T20:43:04+09:00" level=debug msg="completed challenge" Jun 30 20:43:04 volumio go-librespot[3553]: time="2025-06-30T20:43:04+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Jun 30 20:43:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 30 20:43:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 30 20:43:06 volumio volumio[1293]: info: Initializing connection to go-librespot Websocket Jun 30 20:43:06 volumio volumio[1293]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 30 20:43:07 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 30 20:43:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 78. Jun 30 20:43:07 volumio systemd[1]: Stopped go-librespot Daemon. Jun 30 20:43:07 volumio systemd[1]: Started go-librespot Daemon. Jun 30 20:43:07 volumio go-librespot[3616]: go-librespot daemon starting... Jun 30 20:43:07 volumio go-librespot[3616]: time="2025-06-30T20:43:07+09:00" level=info msg="running go-librespot 0.2.0" Jun 30 20:43:07 volumio go-librespot[3616]: time="2025-06-30T20:43:07+09:00" level=debug msg="app state loaded" Jun 30 20:43:07 volumio go-librespot[3616]: time="2025-06-30T20:43:07+09:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 30 20:43:07 volumio go-librespot[3616]: time="2025-06-30T20:43:07+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Jun 30 20:43:07 volumio go-librespot[3616]: time="2025-06-30T20:43:07+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jun 30 20:43:07 volumio go-librespot[3616]: time="2025-06-30T20:43:07+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jun 30 20:43:07 volumio go-librespot[3616]: time="2025-06-30T20:43:07+09:00" level=info msg="zeroconf server listening on port 45447" Jun 30 20:43:07 volumio go-librespot[3616]: time="2025-06-30T20:43:07+09:00" level=debug msg="obtained new client token: AAChPdQBgX0rAJDT+BJlWkLqIDRX11vmtoyColAP/ZZPfClhvqaoIQr0ww/+iYTLqkfDsvMPoql7iQEbV3Bpb1gMQftM+f4d0BYenfRouEnYwjsx4rQ77O1fUlfiYncyP8Q2y3vo1KBWXoKb5k4QTHo5Fw9K7Y32UbPT1D5M0ofU+sW73WjiYdf7lGzSNXTuqGiMwj2X0SWWrK1BEV6XVoeRKJxjvviU+wVEOgrnzaHDzXGJG2a+xFJN1pY=" Jun 30 20:43:07 volumio go-librespot[3616]: time="2025-06-30T20:43:07+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Jun 30 20:43:08 volumio go-librespot[3616]: time="2025-06-30T20:43:08+09:00" level=debug msg="completed keyexchange" Jun 30 20:43:08 volumio go-librespot[3616]: time="2025-06-30T20:43:08+09:00" level=debug msg="completed challenge" Jun 30 20:43:08 volumio go-librespot[3616]: time="2025-06-30T20:43:08+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Jun 30 20:43:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 30 20:43:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 30 20:43:09 volumio volumio[1293]: info: Initializing connection to go-librespot Websocket Jun 30 20:43:09 volumio volumio[1293]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 30 20:43:11 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 30 20:43:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 79. Jun 30 20:43:11 volumio systemd[1]: Stopped go-librespot Daemon. Jun 30 20:43:11 volumio systemd[1]: Started go-librespot Daemon. Jun 30 20:43:11 volumio go-librespot[3628]: go-librespot daemon starting... Jun 30 20:43:11 volumio go-librespot[3628]: time="2025-06-30T20:43:11+09:00" level=info msg="running go-librespot 0.2.0" Jun 30 20:43:11 volumio go-librespot[3628]: time="2025-06-30T20:43:11+09:00" level=debug msg="app state loaded" Jun 30 20:43:11 volumio go-librespot[3628]: time="2025-06-30T20:43:11+09:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 30 20:43:11 volumio go-librespot[3628]: time="2025-06-30T20:43:11+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Jun 30 20:43:11 volumio go-librespot[3628]: time="2025-06-30T20:43:11+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jun 30 20:43:11 volumio go-librespot[3628]: time="2025-06-30T20:43:11+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jun 30 20:43:11 volumio go-librespot[3628]: time="2025-06-30T20:43:11+09:00" level=info msg="zeroconf server listening on port 44415" Jun 30 20:43:11 volumio go-librespot[3628]: time="2025-06-30T20:43:11+09:00" level=debug msg="obtained new client token: AABE194LQdMhZgl10o3u/65rcq2WeYifS9/aq6J0evoxSGpYO+sRHWizZRidt+ZeUSMJRUGxlwNCgZlK5kGoR66SVO3/GeMAMhn0KK2OqILGLGRIpaK3vGrhcT5Nu9bUiiqcPPng4HaNLCzrHo7cjYqfrLhG4WBot512fCXw/mgPWysFw8vAsNIx/PJs9ihxF6TFrBsXq8Q1NrFll2O2//K6WKF8m6MrKJJ4JDG+GgFRS28cHsEnpYD3thI=" Jun 30 20:43:11 volumio go-librespot[3628]: time="2025-06-30T20:43:11+09:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.241.202:4070: connect: connection refused" Jun 30 20:43:11 volumio go-librespot[3628]: time="2025-06-30T20:43:11+09:00" level=debug msg="connected to ap-gae2.spotify.com:443" Jun 30 20:43:11 volumio go-librespot[3628]: time="2025-06-30T20:43:11+09:00" level=debug msg="completed keyexchange" Jun 30 20:43:11 volumio go-librespot[3628]: time="2025-06-30T20:43:11+09:00" level=debug msg="completed challenge" Jun 30 20:43:11 volumio go-librespot[3628]: time="2025-06-30T20:43:11+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Jun 30 20:43:11 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 30 20:43:11 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 30 20:43:12 volumio volumio[1293]: info: Initializing connection to go-librespot Websocket Jun 30 20:43:12 volumio volumio[1293]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 30 20:43:14 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 30 20:43:14 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 80. Jun 30 20:43:14 volumio systemd[1]: Stopped go-librespot Daemon. Jun 30 20:43:14 volumio systemd[1]: Started go-librespot Daemon. Jun 30 20:43:14 volumio go-librespot[3637]: go-librespot daemon starting... Jun 30 20:43:14 volumio go-librespot[3637]: time="2025-06-30T20:43:14+09:00" level=info msg="running go-librespot 0.2.0" Jun 30 20:43:14 volumio go-librespot[3637]: time="2025-06-30T20:43:14+09:00" level=debug msg="app state loaded" Jun 30 20:43:14 volumio go-librespot[3637]: time="2025-06-30T20:43:14+09:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 30 20:43:14 volumio go-librespot[3637]: time="2025-06-30T20:43:14+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Jun 30 20:43:14 volumio go-librespot[3637]: time="2025-06-30T20:43:14+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jun 30 20:43:14 volumio go-librespot[3637]: time="2025-06-30T20:43:14+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jun 30 20:43:14 volumio go-librespot[3637]: time="2025-06-30T20:43:14+09:00" level=info msg="zeroconf server listening on port 46035" Jun 30 20:43:14 volumio go-librespot[3637]: time="2025-06-30T20:43:14+09:00" level=debug msg="obtained new client token: AABDEHIx+19MXZX5mokrPV2LcHCtqtE/ZY2ibGkvsYJNZ5gI0a+IM7e/HFfzzvZez42opRPHoGmYMQJC+SRKxNKzpjoTaY8gyJUMLCMnj5bsqZe9qSpVDuiFgOIA8l4o6oRBCX3EP2o2uEiuN0bKiqqIDn3yIiGbwicXoxlJvg4Ue9avLIZXzUbsb1C56VIiDpDHbOVn5Y90i3PTL0PuQA4OVU65kTMIT1QHOmi9TYvneZqAicDNpNe0vQ4=" Jun 30 20:43:14 volumio go-librespot[3637]: time="2025-06-30T20:43:14+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Jun 30 20:43:15 volumio go-librespot[3637]: time="2025-06-30T20:43:15+09:00" level=debug msg="completed keyexchange" Jun 30 20:43:15 volumio go-librespot[3637]: time="2025-06-30T20:43:15+09:00" level=debug msg="completed challenge" Jun 30 20:43:15 volumio go-librespot[3637]: time="2025-06-30T20:43:15+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Jun 30 20:43:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 30 20:43:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 30 20:43:15 volumio volumio[1293]: info: [1751283795711] [80s80s] Pushing the next song state The Cars - You Might Think and getting next track. Jun 30 20:43:15 volumio volumio[1293]: info: CorePlayQueue::getTrack 0 Jun 30 20:43:15 volumio volumio[1293]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 30 20:43:15 volumio volumio[1293]: TypeError: Cannot set property 'name' of undefined Jun 30 20:43:15 volumio volumio[1293]: at Controller80s80s.pushSongState (/data/plugins/music_service/80s80s/index.js:565:20) Jun 30 20:43:15 volumio volumio[1293]: at Controller80s80s.playNextTrack (/data/plugins/music_service/80s80s/index.js:665:34) Jun 30 20:43:15 volumio volumio[1293]: at NanoTimer.setTimeout (/data/plugins/music_service/80s80s/node_modules/nanotimer/lib/nanotimer.js:343:9) Jun 30 20:43:15 volumio volumio[1293]: at Immediate. (/data/plugins/music_service/80s80s/node_modules/nanotimer/lib/nanotimer.js:327:66) Jun 30 20:43:15 volumio volumio[1293]: at processImmediate (internal/timers.js:461:21) Jun 30 20:43:15 volumio volumio[1293]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 30 20:43:15 volumio sudo[3656]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-30 20:42 Jun 30 20:43:15 volumio sudo[3656]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 04:52:53 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="a72866a0de4045751d03a035de6290e1"