Oct 26 08:27:00 volumio volumio[5244]: info: Initializing connection to go-librespot Websocket Oct 26 08:27:00 volumio volumio[5244]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 26 08:27:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 597. Oct 26 08:27:01 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 26 08:27:01 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 26 08:27:01 volumio go-librespot[6098]: go-librespot daemon starting... Oct 26 08:27:01 volumio go-librespot[6099]: time="2025-10-26T08:27:01Z" level=info msg="running go-librespot 0.4.0" Oct 26 08:27:01 volumio go-librespot[6099]: time="2025-10-26T08:27:01Z" level=debug msg="app state loaded" Oct 26 08:27:01 volumio go-librespot[6099]: time="2025-10-26T08:27:01Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 26 08:27:01 volumio go-librespot[6099]: time="2025-10-26T08:27:01Z" 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 26 08:27:01 volumio go-librespot[6099]: time="2025-10-26T08:27:01Z" 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 26 08:27:01 volumio go-librespot[6099]: time="2025-10-26T08:27:01Z" 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 26 08:27:01 volumio go-librespot[6099]: time="2025-10-26T08:27:01Z" level=info msg="zeroconf server listening on port 44907" Oct 26 08:27:01 volumio go-librespot[6099]: time="2025-10-26T08:27:01Z" level=debug msg="obtained new client token: AACtBszlwga1k79lyo6yC5i7TVMkPRBq7Q4xvVvXrGJs1GVFbKDSteJLe/GNjk0qnmoFou3M88JyIgBxiv/oTQ74JMSPZduote9OYrKM1GNnH50FJs3ErFtCM5UgNh23PNzOlzPHMTUMVyxj7bH5yMlc0A4aRR1LhcDIDr0acogyzFXX7YXS3UfZc4kYsjRc4++4cvWHLCcfYmIHG1m676xGbiU21n4EGA00gtPIAe+qDnTYVxSUoyM=" Oct 26 08:27:01 volumio go-librespot[6099]: time="2025-10-26T08:27:01Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 26 08:27:01 volumio go-librespot[6099]: time="2025-10-26T08:27:01Z" level=debug msg="completed keyexchange" Oct 26 08:27:01 volumio go-librespot[6099]: time="2025-10-26T08:27:01Z" level=debug msg="completed challenge" Oct 26 08:27:01 volumio go-librespot[6099]: time="2025-10-26T08:27:01Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 26 08:27:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 26 08:27:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 26 08:27:03 volumio volumio[5244]: info: Initializing connection to go-librespot Websocket Oct 26 08:27:03 volumio volumio[5244]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 26 08:27:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 598. Oct 26 08:27:04 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 26 08:27:04 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 26 08:27:04 volumio go-librespot[6106]: go-librespot daemon starting... Oct 26 08:27:04 volumio go-librespot[6107]: time="2025-10-26T08:27:04Z" level=info msg="running go-librespot 0.4.0" Oct 26 08:27:04 volumio go-librespot[6107]: time="2025-10-26T08:27:04Z" level=debug msg="app state loaded" Oct 26 08:27:04 volumio go-librespot[6107]: time="2025-10-26T08:27:04Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 26 08:27:05 volumio go-librespot[6107]: time="2025-10-26T08:27:05Z" 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 26 08:27:05 volumio go-librespot[6107]: time="2025-10-26T08:27:05Z" 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 26 08:27:05 volumio go-librespot[6107]: time="2025-10-26T08:27:05Z" 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 26 08:27:05 volumio go-librespot[6107]: time="2025-10-26T08:27:05Z" level=info msg="zeroconf server listening on port 34363" Oct 26 08:27:05 volumio go-librespot[6107]: time="2025-10-26T08:27:05Z" level=debug msg="obtained new client token: AAD4i/9/D+SCJeNeyR4PrIWA2LYcOpmlIWqaGwKZgvZdmyJH0lqVZ3/GUS/hK8cXd24MmA/ManTjbOgpqb+8Fvdf07ipX4MqAT8TWfbhH9O7T5YxHUdZctwtmTDCr7f0BoVZjg5lC/Co7d/dUSo1IhuQJxRvyiu50lyVG7Z0CKcc5JREwQCXFfGx8Efn1XHTr7meWmP8mxdFqaidGGJVjNBSlV1PAJGzQnpSwW+q/kDytfIfuuL7" Oct 26 08:27:05 volumio go-librespot[6107]: time="2025-10-26T08:27:05Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 26 08:27:05 volumio go-librespot[6107]: time="2025-10-26T08:27:05Z" level=debug msg="completed keyexchange" Oct 26 08:27:05 volumio go-librespot[6107]: time="2025-10-26T08:27:05Z" level=debug msg="completed challenge" Oct 26 08:27:05 volumio go-librespot[6107]: time="2025-10-26T08:27:05Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 26 08:27:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 26 08:27:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 26 08:27:06 volumio volumio[5244]: info: Initializing connection to go-librespot Websocket Oct 26 08:27:06 volumio volumio[5244]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 26 08:27:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 599. Oct 26 08:27:08 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 26 08:27:08 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 26 08:27:08 volumio go-librespot[6118]: go-librespot daemon starting... Oct 26 08:27:08 volumio go-librespot[6119]: time="2025-10-26T08:27:08Z" level=info msg="running go-librespot 0.4.0" Oct 26 08:27:08 volumio go-librespot[6119]: time="2025-10-26T08:27:08Z" level=debug msg="app state loaded" Oct 26 08:27:08 volumio go-librespot[6119]: time="2025-10-26T08:27:08Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 26 08:27:08 volumio go-librespot[6119]: time="2025-10-26T08:27:08Z" 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 26 08:27:08 volumio go-librespot[6119]: time="2025-10-26T08:27:08Z" 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 26 08:27:08 volumio go-librespot[6119]: time="2025-10-26T08:27:08Z" 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 26 08:27:08 volumio go-librespot[6119]: time="2025-10-26T08:27:08Z" level=info msg="zeroconf server listening on port 38959" Oct 26 08:27:08 volumio go-librespot[6119]: time="2025-10-26T08:27:08Z" level=debug msg="obtained new client token: AABAUzh0EUg/nttu5iojiWtH9HAlsp0xcx+tmBgnyT6vTtIVGyC94+umpYzvrvqEkNuePrG1zANX/3+JY3HZRyMKmFHkdDr/QelTlSCvM+cXWGv3xVvsLz9iQtcizuhD4oZQllXEl0wEYsidwdkronRjQZfFNIz0ZGsS4ZlqQkA0IqTF0PUEajARClC3++fnk+F0uH/wEwsi1Yqnt95D60x8fO9ExquftD/yD0V6k6+5U1x4MBwJaDg=" Oct 26 08:27:08 volumio go-librespot[6119]: time="2025-10-26T08:27:08Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 26 08:27:08 volumio go-librespot[6119]: time="2025-10-26T08:27:08Z" level=debug msg="completed keyexchange" Oct 26 08:27:08 volumio go-librespot[6119]: time="2025-10-26T08:27:08Z" level=debug msg="completed challenge" Oct 26 08:27:08 volumio go-librespot[6119]: time="2025-10-26T08:27:08Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 26 08:27:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 26 08:27:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 26 08:27:09 volumio volumio[5244]: info: Initializing connection to go-librespot Websocket Oct 26 08:27:09 volumio volumio[5244]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 26 08:27:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 600. Oct 26 08:27:11 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 26 08:27:11 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 26 08:27:11 volumio go-librespot[6140]: go-librespot daemon starting... Oct 26 08:27:11 volumio go-librespot[6141]: time="2025-10-26T08:27:11Z" level=info msg="running go-librespot 0.4.0" Oct 26 08:27:11 volumio go-librespot[6141]: time="2025-10-26T08:27:11Z" level=debug msg="app state loaded" Oct 26 08:27:11 volumio go-librespot[6141]: time="2025-10-26T08:27:11Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 26 08:27:12 volumio go-librespot[6141]: time="2025-10-26T08:27:12Z" 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 26 08:27:12 volumio go-librespot[6141]: time="2025-10-26T08:27:12Z" 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 26 08:27:12 volumio go-librespot[6141]: time="2025-10-26T08:27:12Z" 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 26 08:27:12 volumio go-librespot[6141]: time="2025-10-26T08:27:12Z" level=info msg="zeroconf server listening on port 46053" Oct 26 08:27:12 volumio go-librespot[6141]: time="2025-10-26T08:27:12Z" level=debug msg="obtained new client token: AABbLHIxuuiW6b97yZpGYLoTUp6wmODmNAMOq86mODseN87hkuQZJizB9nyfZlsZF5nEemkQwh/Icw0c/WDjeceYq9Rn4HIn58s7QG/DXfKGl8oDbZSV1b8Ax5auwJvOFUF/hEqBr1yaVCSMVESHROCOwayFtHkTxqaA2F9Z8MrMOcqwbOyHIGNBPCcG9Wt+RPm/tXEssLQqvPn1B9xYWqRI6A+1iTs0Ep8/B5OiMNeenKwQIWbZ" Oct 26 08:27:12 volumio go-librespot[6141]: time="2025-10-26T08:27:12Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 26 08:27:12 volumio go-librespot[6141]: time="2025-10-26T08:27:12Z" level=debug msg="completed keyexchange" Oct 26 08:27:12 volumio go-librespot[6141]: time="2025-10-26T08:27:12Z" level=debug msg="completed challenge" Oct 26 08:27:12 volumio go-librespot[6141]: time="2025-10-26T08:27:12Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 26 08:27:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 26 08:27:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 26 08:27:12 volumio volumio[5244]: info: Initializing connection to go-librespot Websocket Oct 26 08:27:12 volumio volumio[5244]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 26 08:27:15 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 601. Oct 26 08:27:15 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 26 08:27:15 volumio volumio[5244]: info: Initializing connection to go-librespot Websocket Oct 26 08:27:15 volumio volumio[5244]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 26 08:27:15 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 26 08:27:15 volumio go-librespot[6149]: go-librespot daemon starting... Oct 26 08:27:15 volumio go-librespot[6150]: time="2025-10-26T08:27:15Z" level=info msg="running go-librespot 0.4.0" Oct 26 08:27:15 volumio go-librespot[6150]: time="2025-10-26T08:27:15Z" level=debug msg="app state loaded" Oct 26 08:27:15 volumio go-librespot[6150]: time="2025-10-26T08:27:15Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 26 08:27:15 volumio go-librespot[6150]: time="2025-10-26T08:27:15Z" 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 26 08:27:15 volumio go-librespot[6150]: time="2025-10-26T08:27:15Z" 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 26 08:27:15 volumio go-librespot[6150]: time="2025-10-26T08:27:15Z" 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 26 08:27:15 volumio go-librespot[6150]: time="2025-10-26T08:27:15Z" level=info msg="zeroconf server listening on port 36875" Oct 26 08:27:15 volumio go-librespot[6150]: time="2025-10-26T08:27:15Z" level=debug msg="obtained new client token: AADZVhLBMfDsgba6AybuAD19YNf+xBwdwEpCYlzZqFn/D4xMrMrgMhITn/ynZjzMvakb+kbGTc09hV5xUnZ6DlyE4wc38T3Q+IvHin3TDyO8oNPlSAR0iCmBNcEbN7TgfLie4/AJy6QC9w6q2MhC/qRAj0itOd8A7WLWnMU5jtTtByVly3NnGNWcT1Y24NVBSvEZxFUeUnaf7LXuBajMQIwF3+ZQ2dFmWjrlVxUBDi/rDjjwqLLviUI=" Oct 26 08:27:15 volumio go-librespot[6150]: time="2025-10-26T08:27:15Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 26 08:27:15 volumio go-librespot[6150]: time="2025-10-26T08:27:15Z" level=debug msg="completed keyexchange" Oct 26 08:27:15 volumio go-librespot[6150]: time="2025-10-26T08:27:15Z" level=debug msg="completed challenge" Oct 26 08:27:15 volumio go-librespot[6150]: time="2025-10-26T08:27:15Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 26 08:27:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 26 08:27:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 26 08:27:18 volumio volumio[5244]: info: Initializing connection to go-librespot Websocket Oct 26 08:27:18 volumio volumio[5244]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 26 08:27:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 602. Oct 26 08:27:18 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 26 08:27:18 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 26 08:27:18 volumio go-librespot[6171]: go-librespot daemon starting... Oct 26 08:27:18 volumio go-librespot[6172]: time="2025-10-26T08:27:18Z" level=info msg="running go-librespot 0.4.0" Oct 26 08:27:18 volumio go-librespot[6172]: time="2025-10-26T08:27:18Z" level=debug msg="app state loaded" Oct 26 08:27:18 volumio go-librespot[6172]: time="2025-10-26T08:27:18Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 26 08:27:19 volumio go-librespot[6172]: time="2025-10-26T08:27:19Z" 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 26 08:27:19 volumio go-librespot[6172]: time="2025-10-26T08:27:19Z" 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 26 08:27:19 volumio go-librespot[6172]: time="2025-10-26T08:27:19Z" 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 26 08:27:19 volumio go-librespot[6172]: time="2025-10-26T08:27:19Z" level=info msg="zeroconf server listening on port 39203" Oct 26 08:27:19 volumio go-librespot[6172]: time="2025-10-26T08:27:19Z" level=debug msg="obtained new client token: AACVRnoA8LlpglNl6jfKdday21qES8dMXCrBNUCG2uDuWUtnFb0jDcPN/a3CSSNrAgJ7Vj5YSmliWwxvTzd+RjMWcdJ9zH/5UQ4Z7pGnVlflOodZwMVcUoprYp/4ID6iVF29bC1JZDZ0uybe4o1ffy+petG8ubbP6RT1Y7Z8QDB+5C2evrXGUFbTdRQNHDCNnr7d7Ssa8cWU4EgZFi+Cogb0N/7yanR4S5Pauwobmgt7LHUn6eES" Oct 26 08:27:19 volumio go-librespot[6172]: time="2025-10-26T08:27:19Z" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.65.9:4070: connect: connection refused" Oct 26 08:27:19 volumio go-librespot[6172]: time="2025-10-26T08:27:19Z" level=debug msg="connected to ap-gew1.spotify.com:443" Oct 26 08:27:19 volumio go-librespot[6172]: time="2025-10-26T08:27:19Z" level=debug msg="completed keyexchange" Oct 26 08:27:19 volumio go-librespot[6172]: time="2025-10-26T08:27:19Z" level=debug msg="completed challenge" Oct 26 08:27:19 volumio go-librespot[6172]: time="2025-10-26T08:27:19Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 26 08:27:19 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 26 08:27:19 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 26 08:27:21 volumio volumio[5244]: info: Initializing connection to go-librespot Websocket Oct 26 08:27:21 volumio volumio[5244]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 26 08:27:22 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 603. Oct 26 08:27:22 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 26 08:27:22 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 26 08:27:22 volumio go-librespot[6180]: go-librespot daemon starting... Oct 26 08:27:22 volumio go-librespot[6181]: time="2025-10-26T08:27:22Z" level=info msg="running go-librespot 0.4.0" Oct 26 08:27:22 volumio go-librespot[6181]: time="2025-10-26T08:27:22Z" level=debug msg="app state loaded" Oct 26 08:27:22 volumio go-librespot[6181]: time="2025-10-26T08:27:22Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 26 08:27:22 volumio go-librespot[6181]: time="2025-10-26T08:27:22Z" 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 26 08:27:22 volumio go-librespot[6181]: time="2025-10-26T08:27:22Z" 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 26 08:27:22 volumio go-librespot[6181]: time="2025-10-26T08:27:22Z" 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 26 08:27:22 volumio go-librespot[6181]: time="2025-10-26T08:27:22Z" level=info msg="zeroconf server listening on port 41445" Oct 26 08:27:22 volumio go-librespot[6181]: time="2025-10-26T08:27:22Z" level=debug msg="obtained new client token: AACW7StoXrWhqA4p6odOMJJqPtfQ+0v+ENPCU7Bp7Yw8zuAQOcMo4ogim+Zq3BhaHGWOzgh8GpIoDCAjgUMYpZzWASf8bqS8YR0i6aeA+DgFXybru9HYrdeyCQ/tgufbdwDfdLLZmZjL8+cJ7nadRPCw5HgFk8xCghTcrXzVgvQKqyd0DYYCMBiA85u3HEGG7PgbQD3Xk/pPgi2peWSLlybwvmYtFO/rhJh91gmGCbVdiayzp6l7nxg=" Oct 26 08:27:22 volumio go-librespot[6181]: time="2025-10-26T08:27:22Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 26 08:27:22 volumio go-librespot[6181]: time="2025-10-26T08:27:22Z" level=debug msg="completed keyexchange" Oct 26 08:27:22 volumio go-librespot[6181]: time="2025-10-26T08:27:22Z" level=debug msg="completed challenge" Oct 26 08:27:22 volumio go-librespot[6181]: time="2025-10-26T08:27:22Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 26 08:27:22 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 26 08:27:22 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 26 08:27:24 volumio volumio[5244]: info: Initializing connection to go-librespot Websocket Oct 26 08:27:24 volumio volumio[5244]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 26 08:27:25 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 604. Oct 26 08:27:25 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 26 08:27:25 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 26 08:27:25 volumio go-librespot[6191]: go-librespot daemon starting... Oct 26 08:27:25 volumio go-librespot[6192]: time="2025-10-26T08:27:25Z" level=info msg="running go-librespot 0.4.0" Oct 26 08:27:25 volumio go-librespot[6192]: time="2025-10-26T08:27:25Z" level=debug msg="app state loaded" Oct 26 08:27:25 volumio go-librespot[6192]: time="2025-10-26T08:27:25Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 26 08:27:26 volumio go-librespot[6192]: time="2025-10-26T08:27:26Z" 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 26 08:27:26 volumio go-librespot[6192]: time="2025-10-26T08:27:26Z" 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 26 08:27:26 volumio go-librespot[6192]: time="2025-10-26T08:27:26Z" 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 26 08:27:26 volumio go-librespot[6192]: time="2025-10-26T08:27:26Z" level=info msg="zeroconf server listening on port 35349" Oct 26 08:27:26 volumio go-librespot[6192]: time="2025-10-26T08:27:26Z" level=debug msg="obtained new client token: AABabVpcYUtv6iL9ispYbwGA64AeXCABx/pgy8pQ8mjCIx7ICcFg2i782FIE0SRWobB/AfHI6Sym7Dv8Vu2tKK8RIq4sw92Nf1LNCzA6KUUXgXPu8HYseH7iT7P2ImJA9NEmlOGP2/JBxpJm+CunYVHqFjGFj+Kw4KWeTAEDRUgc8T2WdWpGm2ViO7l/3I9ZBbYu1U835J5YFZcoi9ZUhkAsT1/vEfw5AYSZFKwJgmKbGNiCX4Un" Oct 26 08:27:26 volumio go-librespot[6192]: time="2025-10-26T08:27:26Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 26 08:27:26 volumio go-librespot[6192]: time="2025-10-26T08:27:26Z" level=debug msg="completed keyexchange" Oct 26 08:27:26 volumio go-librespot[6192]: time="2025-10-26T08:27:26Z" level=debug msg="completed challenge" Oct 26 08:27:26 volumio go-librespot[6192]: time="2025-10-26T08:27:26Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 26 08:27:26 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 26 08:27:26 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 26 08:27:27 volumio volumio[5244]: info: Initializing connection to go-librespot Websocket Oct 26 08:27:27 volumio volumio[5244]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 26 08:27:29 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 605. Oct 26 08:27:29 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 26 08:27:29 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 26 08:27:29 volumio go-librespot[6213]: go-librespot daemon starting... Oct 26 08:27:29 volumio go-librespot[6214]: time="2025-10-26T08:27:29Z" level=info msg="running go-librespot 0.4.0" Oct 26 08:27:29 volumio go-librespot[6214]: time="2025-10-26T08:27:29Z" level=debug msg="app state loaded" Oct 26 08:27:29 volumio go-librespot[6214]: time="2025-10-26T08:27:29Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 26 08:27:29 volumio go-librespot[6214]: time="2025-10-26T08:27:29Z" 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 26 08:27:29 volumio go-librespot[6214]: time="2025-10-26T08:27:29Z" 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 26 08:27:29 volumio go-librespot[6214]: time="2025-10-26T08:27:29Z" 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 26 08:27:29 volumio go-librespot[6214]: time="2025-10-26T08:27:29Z" level=info msg="zeroconf server listening on port 43923" Oct 26 08:27:29 volumio go-librespot[6214]: time="2025-10-26T08:27:29Z" level=debug msg="obtained new client token: AABJ3Rs556XdRMqQxqotaPU671E7JG4hyoRiul9Mm7CRuIB5y3OYRW8FTZXjuQ8PlPDDPZGmskEe+WSxvYupeAYB4Yx/KlrjO+EUpyUVvRlXcgAwP4q4uipDoJR7rzZn8ggGA3y7E/xmg9XEqMnUx7oVD/2aU5s2/k41/Jf/bnMmkag4eDWOwvBt0Q/83bjEem7N5cEF6aC/7C4ZWDV1bRm0qpGn1EoOivjgmv5JRO7n6Vuz1oqoKBk=" Oct 26 08:27:29 volumio go-librespot[6214]: time="2025-10-26T08:27:29Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 26 08:27:29 volumio go-librespot[6214]: time="2025-10-26T08:27:29Z" level=debug msg="completed keyexchange" Oct 26 08:27:29 volumio go-librespot[6214]: time="2025-10-26T08:27:29Z" level=debug msg="completed challenge" Oct 26 08:27:29 volumio go-librespot[6214]: time="2025-10-26T08:27:29Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 26 08:27:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 26 08:27:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 26 08:27:30 volumio volumio[5244]: info: Initializing connection to go-librespot Websocket Oct 26 08:27:30 volumio volumio[5244]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 26 08:27:32 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 606. Oct 26 08:27:32 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 26 08:27:32 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 26 08:27:32 volumio go-librespot[6221]: go-librespot daemon starting... Oct 26 08:27:32 volumio go-librespot[6222]: time="2025-10-26T08:27:32Z" level=info msg="running go-librespot 0.4.0" Oct 26 08:27:32 volumio go-librespot[6222]: time="2025-10-26T08:27:32Z" level=debug msg="app state loaded" Oct 26 08:27:32 volumio go-librespot[6222]: time="2025-10-26T08:27:32Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 26 08:27:33 volumio go-librespot[6222]: time="2025-10-26T08:27:33Z" 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 26 08:27:33 volumio go-librespot[6222]: time="2025-10-26T08:27:33Z" 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 26 08:27:33 volumio go-librespot[6222]: time="2025-10-26T08:27:33Z" 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 26 08:27:33 volumio go-librespot[6222]: time="2025-10-26T08:27:33Z" level=info msg="zeroconf server listening on port 35073" Oct 26 08:27:33 volumio go-librespot[6222]: time="2025-10-26T08:27:33Z" level=debug msg="obtained new client token: AACAC4vy8+xAHV/W40NGQuC/kpbk/tRS17vzqanO195h+YyHe5j9fz0y5P0+SzJwBSggb208ZmNFjO4pfVg9yKy9E9l44rODtqXY++s627Tte6wDMijSC2PoG5SOBnjTsaw9B8oh4/VY5lFQz9JeBpsrityNiFTNp1rEeEgjhjZNwbowdYUOh1L+CuQuAw5nTgCXSKZfuEB5Bf88Ojr3qZPXp9q97dRU1z+ODIKglPF2tKtOdYog" Oct 26 08:27:33 volumio go-librespot[6222]: time="2025-10-26T08:27:33Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 26 08:27:33 volumio go-librespot[6222]: time="2025-10-26T08:27:33Z" level=debug msg="completed keyexchange" Oct 26 08:27:33 volumio go-librespot[6222]: time="2025-10-26T08:27:33Z" level=debug msg="completed challenge" Oct 26 08:27:33 volumio go-librespot[6222]: time="2025-10-26T08:27:33Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 26 08:27:33 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 26 08:27:33 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 26 08:27:33 volumio volumio[5244]: info: Initializing connection to go-librespot Websocket Oct 26 08:27:33 volumio volumio[5244]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 26 08:27:36 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 607. Oct 26 08:27:36 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 26 08:27:36 volumio volumio[5244]: info: Initializing connection to go-librespot Websocket Oct 26 08:27:36 volumio volumio[5244]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 26 08:27:36 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 26 08:27:36 volumio go-librespot[6230]: go-librespot daemon starting... Oct 26 08:27:36 volumio go-librespot[6231]: time="2025-10-26T08:27:36Z" level=info msg="running go-librespot 0.4.0" Oct 26 08:27:36 volumio go-librespot[6231]: time="2025-10-26T08:27:36Z" level=debug msg="app state loaded" Oct 26 08:27:36 volumio go-librespot[6231]: time="2025-10-26T08:27:36Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 26 08:27:36 volumio go-librespot[6231]: time="2025-10-26T08:27:36Z" 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 26 08:27:36 volumio go-librespot[6231]: time="2025-10-26T08:27:36Z" 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 26 08:27:36 volumio go-librespot[6231]: time="2025-10-26T08:27:36Z" 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 26 08:27:36 volumio go-librespot[6231]: time="2025-10-26T08:27:36Z" level=info msg="zeroconf server listening on port 39939" Oct 26 08:27:36 volumio go-librespot[6231]: time="2025-10-26T08:27:36Z" level=debug msg="obtained new client token: AACXxqNSFaDzcRIq5zxQMG45H41rE4ztJjkfNN3dWn72bAFtnCBs/kmUFwUMTw1gAQtsPmOIm0myh0SNybCdb9qPQ5wT8RG29kHysT0lEf++H0+Ga/8oFp1ej5W7ASd3oDSkT4YJ+CG8qEN7P3hLr2Gr0YPfLiSlvaD8SpGfDIdGo/Iee6FfoFpihjC/1wyhkCGVSSfwUoYZ3ByeCCOfQEgmi99KU37x4mCYGrdtUq1/q+dB8f3N0Hk=" Oct 26 08:27:36 volumio go-librespot[6231]: time="2025-10-26T08:27:36Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 26 08:27:36 volumio go-librespot[6231]: time="2025-10-26T08:27:36Z" level=debug msg="completed keyexchange" Oct 26 08:27:36 volumio go-librespot[6231]: time="2025-10-26T08:27:36Z" level=debug msg="completed challenge" Oct 26 08:27:36 volumio go-librespot[6231]: time="2025-10-26T08:27:36Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 26 08:27:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 26 08:27:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 26 08:27:39 volumio volumio[5244]: info: Initializing connection to go-librespot Websocket Oct 26 08:27:39 volumio volumio[5244]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 26 08:27:39 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 608. Oct 26 08:27:39 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 26 08:27:39 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 26 08:27:39 volumio go-librespot[6252]: go-librespot daemon starting... Oct 26 08:27:39 volumio go-librespot[6253]: time="2025-10-26T08:27:39Z" level=info msg="running go-librespot 0.4.0" Oct 26 08:27:39 volumio go-librespot[6253]: time="2025-10-26T08:27:39Z" level=debug msg="app state loaded" Oct 26 08:27:39 volumio go-librespot[6253]: time="2025-10-26T08:27:39Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 26 08:27:40 volumio go-librespot[6253]: time="2025-10-26T08:27:40Z" 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 26 08:27:40 volumio go-librespot[6253]: time="2025-10-26T08:27:40Z" 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 26 08:27:40 volumio go-librespot[6253]: time="2025-10-26T08:27:40Z" 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 26 08:27:40 volumio go-librespot[6253]: time="2025-10-26T08:27:40Z" level=info msg="zeroconf server listening on port 46383" Oct 26 08:27:40 volumio go-librespot[6253]: time="2025-10-26T08:27:40Z" level=debug msg="obtained new client token: AADfRbmtrsNY0J07TZsWoY3O4PlrrPUBPJc7hK7+Q3gBXwXZapIVMBlPJFpZ3wk3HZITZYHNCDH1C5n5zSiAtPsWsOKX+7hZPTsUPbv1U8IPSXhEQ1ajvv6IwiVpIY4wArkcwagU78V2ohU62MTiHEI1M1osoOpliSvrVgY6vzOif/N2DVgua/npIbU3gxgqB0/cJw83wErA7RWyTo4zlcuhAc8s+FJxfZLx/hLMxA13/axH3yUV" Oct 26 08:27:40 volumio go-librespot[6253]: time="2025-10-26T08:27:40Z" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.65.9:4070: connect: connection refused" Oct 26 08:27:40 volumio go-librespot[6253]: time="2025-10-26T08:27:40Z" level=debug msg="connected to ap-gew1.spotify.com:443" Oct 26 08:27:40 volumio go-librespot[6253]: time="2025-10-26T08:27:40Z" level=debug msg="completed keyexchange" Oct 26 08:27:40 volumio go-librespot[6253]: time="2025-10-26T08:27:40Z" level=debug msg="completed challenge" Oct 26 08:27:40 volumio go-librespot[6253]: time="2025-10-26T08:27:40Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 26 08:27:40 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 26 08:27:40 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 26 08:27:42 volumio volumio[5244]: info: Initializing connection to go-librespot Websocket Oct 26 08:27:42 volumio volumio[5244]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 26 08:27:43 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 609. Oct 26 08:27:43 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 26 08:27:43 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 26 08:27:43 volumio go-librespot[6261]: go-librespot daemon starting... Oct 26 08:27:43 volumio go-librespot[6262]: time="2025-10-26T08:27:43Z" level=info msg="running go-librespot 0.4.0" Oct 26 08:27:43 volumio go-librespot[6262]: time="2025-10-26T08:27:43Z" level=debug msg="app state loaded" Oct 26 08:27:43 volumio go-librespot[6262]: time="2025-10-26T08:27:43Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 26 08:27:43 volumio go-librespot[6262]: time="2025-10-26T08:27:43Z" 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 26 08:27:43 volumio go-librespot[6262]: time="2025-10-26T08:27:43Z" 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 26 08:27:43 volumio go-librespot[6262]: time="2025-10-26T08:27:43Z" 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 26 08:27:43 volumio go-librespot[6262]: time="2025-10-26T08:27:43Z" level=info msg="zeroconf server listening on port 45195" Oct 26 08:27:43 volumio go-librespot[6262]: time="2025-10-26T08:27:43Z" level=debug msg="obtained new client token: AAB+uvfcWPnAgArI69lQIDQIqKGCseLczfL+kh3DdGfZycGbBMYMb/u4R8UxW60UB4UBE//Eo75ArMiEtCZpcCT0WcV1hm1yoHQS/nuIn2NBOlCEGUcKGVpLSss+FM7KbgQyRyAx9WZzaXnpy3Wj0hNkDshK9iyprIU6Tykm00qXo4D5cHdkKy6BiTojkRasQuPN/QbVCP30nA5kwjB3Hx7ohNDF4VSMtJ22y4VdjfLLeS0HtEFw2Vg=" Oct 26 08:27:43 volumio go-librespot[6262]: time="2025-10-26T08:27:43Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 26 08:27:43 volumio go-librespot[6262]: time="2025-10-26T08:27:43Z" level=debug msg="completed keyexchange" Oct 26 08:27:43 volumio go-librespot[6262]: time="2025-10-26T08:27:43Z" level=debug msg="completed challenge" Oct 26 08:27:43 volumio go-librespot[6262]: time="2025-10-26T08:27:43Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 26 08:27:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 26 08:27:43 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 26 08:27:45 volumio volumio[5244]: info: Initializing connection to go-librespot Websocket Oct 26 08:27:45 volumio volumio[5244]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 26 08:27:46 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 610. Oct 26 08:27:46 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 26 08:27:46 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 26 08:27:46 volumio go-librespot[6272]: go-librespot daemon starting... Oct 26 08:27:46 volumio go-librespot[6273]: time="2025-10-26T08:27:46Z" level=info msg="running go-librespot 0.4.0" Oct 26 08:27:46 volumio go-librespot[6273]: time="2025-10-26T08:27:46Z" level=debug msg="app state loaded" Oct 26 08:27:46 volumio go-librespot[6273]: time="2025-10-26T08:27:46Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 26 08:27:47 volumio go-librespot[6273]: time="2025-10-26T08:27:47Z" 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 26 08:27:47 volumio go-librespot[6273]: time="2025-10-26T08:27:47Z" 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 26 08:27:47 volumio go-librespot[6273]: time="2025-10-26T08:27:47Z" 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 26 08:27:47 volumio go-librespot[6273]: time="2025-10-26T08:27:47Z" level=info msg="zeroconf server listening on port 43601" Oct 26 08:27:47 volumio go-librespot[6273]: time="2025-10-26T08:27:47Z" level=debug msg="obtained new client token: AAASKO1uNQEVEsXTkfhbErOXU2yzeigqk3OQHabfv9z6v560qx4Ty5Nan2h/Wsrl4voiWwh63jaGBF2u+cZ59wcOL1ZhxSgo6VhuKOpIYe1MBjaDFSjqcDzgOitYjHwDXaTqauPwjBIXMxE78ooZ34ECf6QQAehriugxDOGd0dt/LNj1ey0tuweNGdlqQQ2gJx6k0zgXdDeBDzMNXrK0s8JOHuAJSai3E9HAQNfCnRhS02QSM9PC" Oct 26 08:27:47 volumio go-librespot[6273]: time="2025-10-26T08:27:47Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 26 08:27:47 volumio go-librespot[6273]: time="2025-10-26T08:27:47Z" level=debug msg="completed keyexchange" Oct 26 08:27:47 volumio go-librespot[6273]: time="2025-10-26T08:27:47Z" level=debug msg="completed challenge" Oct 26 08:27:47 volumio go-librespot[6273]: time="2025-10-26T08:27:47Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 26 08:27:47 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 26 08:27:47 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 26 08:27:48 volumio volumio[5244]: info: Initializing connection to go-librespot Websocket Oct 26 08:27:48 volumio volumio[5244]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 26 08:27:50 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 611. Oct 26 08:27:50 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 26 08:27:50 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 26 08:27:50 volumio go-librespot[6294]: go-librespot daemon starting... Oct 26 08:27:50 volumio go-librespot[6295]: time="2025-10-26T08:27:50Z" level=info msg="running go-librespot 0.4.0" Oct 26 08:27:50 volumio go-librespot[6295]: time="2025-10-26T08:27:50Z" level=debug msg="app state loaded" Oct 26 08:27:50 volumio go-librespot[6295]: time="2025-10-26T08:27:50Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 26 08:27:50 volumio go-librespot[6295]: time="2025-10-26T08:27:50Z" 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 26 08:27:50 volumio go-librespot[6295]: time="2025-10-26T08:27:50Z" 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 26 08:27:50 volumio go-librespot[6295]: time="2025-10-26T08:27:50Z" 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 26 08:27:50 volumio go-librespot[6295]: time="2025-10-26T08:27:50Z" level=info msg="zeroconf server listening on port 39525" Oct 26 08:27:50 volumio go-librespot[6295]: time="2025-10-26T08:27:50Z" level=debug msg="obtained new client token: AAC51JQRZYRhFu1prBBhnatTAIU27819rF1WBsCHkxg0PRdE9JJv+CiPVojc+HLY8ZLjF1WywRuWYTsKc3T4frDYnLCWYOroa+9VNQfyXujMlOpaWABzmWfPm31xJUwsA06suF2zokPd52G+Xlbzw2NIAS+k5NM57bWyr8CgR7phjN3bbEGgZcLbaX/cB9jg5rzIK0CqdnWnS16ke97vlyxMVqkh7b0dxdAU1zeDH27lrVWJyFj7LbA=" Oct 26 08:27:50 volumio go-librespot[6295]: time="2025-10-26T08:27:50Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 26 08:27:50 volumio go-librespot[6295]: time="2025-10-26T08:27:50Z" level=debug msg="completed keyexchange" Oct 26 08:27:50 volumio go-librespot[6295]: time="2025-10-26T08:27:50Z" level=debug msg="completed challenge" Oct 26 08:27:50 volumio go-librespot[6295]: time="2025-10-26T08:27:50Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 26 08:27:50 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 26 08:27:50 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 26 08:27:51 volumio volumio[5244]: info: Initializing connection to go-librespot Websocket Oct 26 08:27:51 volumio volumio[5244]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 26 08:27:53 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 612. Oct 26 08:27:53 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 26 08:27:53 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 26 08:27:53 volumio go-librespot[6302]: go-librespot daemon starting... Oct 26 08:27:53 volumio go-librespot[6303]: time="2025-10-26T08:27:53Z" level=info msg="running go-librespot 0.4.0" Oct 26 08:27:53 volumio go-librespot[6303]: time="2025-10-26T08:27:53Z" level=debug msg="app state loaded" Oct 26 08:27:53 volumio go-librespot[6303]: time="2025-10-26T08:27:53Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 26 08:27:54 volumio go-librespot[6303]: time="2025-10-26T08:27:54Z" 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 26 08:27:54 volumio go-librespot[6303]: time="2025-10-26T08:27:54Z" 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 26 08:27:54 volumio go-librespot[6303]: time="2025-10-26T08:27:54Z" 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 26 08:27:54 volumio go-librespot[6303]: time="2025-10-26T08:27:54Z" level=info msg="zeroconf server listening on port 39961" Oct 26 08:27:54 volumio go-librespot[6303]: time="2025-10-26T08:27:54Z" level=debug msg="obtained new client token: AACPNIyTE1k/lks2Ha9/shgOGHjQlspHjJBqJo4FMP37Ulsmr0b1FQ54X9CUmCq05CPLFwFUYNaxF4/W/8fTf4nlzYbUFDVrc1Dyru+1Ro/VIXArpx6Q8q4pFBcJQRW7UftDWjJt4oXU/cDzGctz45mxgrdkKnDrRBj9nljajKcbIGzMLe9W9Vd6OhDmUa/+xFSL+CFVDWJWGq6O4uvCZnBN+Xi6pxJ5mYbNkt4OZRZteCKykFOx" Oct 26 08:27:54 volumio go-librespot[6303]: time="2025-10-26T08:27:54Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 26 08:27:54 volumio go-librespot[6303]: time="2025-10-26T08:27:54Z" level=debug msg="completed keyexchange" Oct 26 08:27:54 volumio go-librespot[6303]: time="2025-10-26T08:27:54Z" level=debug msg="completed challenge" Oct 26 08:27:54 volumio go-librespot[6303]: time="2025-10-26T08:27:54Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 26 08:27:54 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 26 08:27:54 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 26 08:27:54 volumio volumio[5244]: info: Initializing connection to go-librespot Websocket Oct 26 08:27:54 volumio volumio[5244]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 26 08:27:57 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 613. Oct 26 08:27:57 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 26 08:27:57 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 26 08:27:57 volumio go-librespot[6310]: go-librespot daemon starting... Oct 26 08:27:57 volumio go-librespot[6311]: time="2025-10-26T08:27:57Z" level=info msg="running go-librespot 0.4.0" Oct 26 08:27:57 volumio go-librespot[6311]: time="2025-10-26T08:27:57Z" level=debug msg="app state loaded" Oct 26 08:27:57 volumio go-librespot[6311]: time="2025-10-26T08:27:57Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 26 08:27:57 volumio volumio[5244]: info: Initializing connection to go-librespot Websocket Oct 26 08:27:57 volumio go-librespot[6311]: time="2025-10-26T08:27:57Z" level=debug msg="new websocket client" Oct 26 08:27:57 volumio volumio[5244]: info: Connection to go-librespot Websocket established Oct 26 08:27:57 volumio go-librespot[6311]: time="2025-10-26T08:27:57Z" 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 26 08:27:57 volumio go-librespot[6311]: time="2025-10-26T08:27:57Z" 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 26 08:27:57 volumio go-librespot[6311]: time="2025-10-26T08:27:57Z" 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 26 08:27:57 volumio go-librespot[6311]: time="2025-10-26T08:27:57Z" level=info msg="zeroconf server listening on port 46479" Oct 26 08:27:57 volumio go-librespot[6311]: time="2025-10-26T08:27:57Z" level=debug msg="obtained new client token: AADCiP1JZXxkg1UuL9JzG/5IUg+8mpCjIvQXiayoZwAXxApmErybOhFo4IzyipnRYLl2XgPV7qYEdelYPe+cqSDPOF0bLjjQXqpeuU9ik/pCGXs2agEJRmCjXKERnq1cBC38VBVDsQ3dAXYqTeCl6tgetsZTcmSop3+e+0NxN/b8Pxk5GsrzkyWm1g0FdNsNCZPfo19NYNwhcsIKfqxf98r2Gmd/2YR4QvflFl11Nq31ss4C8sRPuBA=" Oct 26 08:27:57 volumio go-librespot[6311]: time="2025-10-26T08:27:57Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 26 08:27:57 volumio go-librespot[6311]: time="2025-10-26T08:27:57Z" level=debug msg="completed keyexchange" Oct 26 08:27:57 volumio go-librespot[6311]: time="2025-10-26T08:27:57Z" level=debug msg="completed challenge" Oct 26 08:27:57 volumio go-librespot[6311]: time="2025-10-26T08:27:57Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 26 08:27:57 volumio volumio[5244]: info: Connection to go-librespot Websocket closed Oct 26 08:27:57 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 26 08:27:57 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 26 08:28:00 volumio volumio[5244]: info: Getting Spotify volume Oct 26 08:28:00 volumio volumio[5244]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 26 08:28:00 volumio volumio[5244]: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 26 08:28:00 volumio volumio[5244]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Oct 26 08:28:00 volumio volumio[5244]: errno: -111, Oct 26 08:28:00 volumio volumio[5244]: code: 'ECONNREFUSED', Oct 26 08:28:00 volumio volumio[5244]: syscall: 'connect', Oct 26 08:28:00 volumio volumio[5244]: address: '127.0.0.1', Oct 26 08:28:00 volumio volumio[5244]: port: 9879, Oct 26 08:28:00 volumio volumio[5244]: response: undefined Oct 26 08:28:00 volumio volumio[5244]: } Oct 26 08:28:00 volumio volumio[5244]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 26 08:28:00 volumio sudo[6347]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-10-26 08:27' Oct 26 08:28:00 volumio sudo[6347]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="c22b72a8acb34a699a626adb0a09a6b88ee7233a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="40400d6a6fa57cf20e36499e510876b3654e9c4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Oct 4 16:40:08 UTC 2025" VOLUMIO_VERSION="4.061" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="7f8ace80645f85765fa10c72d1769d04"