Oct 28 16:46:00 volumio volumio[10332]: info: Initializing connection to go-librespot Websocket Oct 28 16:46:00 volumio volumio[10332]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 16:46:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 21. Oct 28 16:46:01 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 16:46:01 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 16:46:01 volumio go-librespot[10927]: go-librespot daemon starting... Oct 28 16:46:01 volumio go-librespot[10928]: time="2025-10-28T16:46:01Z" level=info msg="running go-librespot 0.4.0" Oct 28 16:46:01 volumio go-librespot[10928]: time="2025-10-28T16:46:01Z" level=debug msg="app state loaded" Oct 28 16:46:01 volumio go-librespot[10928]: time="2025-10-28T16:46:01Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 16:46:01 volumio go-librespot[10928]: time="2025-10-28T16:46: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-gew4.spotify.com:80]" Oct 28 16:46:01 volumio go-librespot[10928]: time="2025-10-28T16:46:01Z" 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 28 16:46:01 volumio go-librespot[10928]: time="2025-10-28T16:46:01Z" 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 28 16:46:01 volumio go-librespot[10928]: time="2025-10-28T16:46:01Z" level=info msg="zeroconf server listening on port 41809" Oct 28 16:46:01 volumio go-librespot[10928]: time="2025-10-28T16:46:01Z" level=debug msg="obtained new client token: AAB8lZIGNVVSyuyUqSsbTipeTUFloQsjwfI1l5CJ9HL74IvEz8r3gtMhIon6NIf59S4PcdLKU9AHFwL6GJgknZm1sWRZDtgKFK8b3yNjg5tJiFUNEDarmx5uuzZdUIEGfaQms8h2N+sv1p0JRwesAeM5ZVfarFBnvAeXc8KjjfYh0oCw6hLMqV4INcvk3zm5QFOPqClcJftnNIoBcQt9QClcxEtceWIHN205JV91izAEBPc8LS4yKO8=" Oct 28 16:46:01 volumio go-librespot[10928]: time="2025-10-28T16:46:01Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 16:46:01 volumio go-librespot[10928]: time="2025-10-28T16:46:01Z" level=debug msg="completed keyexchange" Oct 28 16:46:01 volumio go-librespot[10928]: time="2025-10-28T16:46:01Z" level=debug msg="completed challenge" Oct 28 16:46:01 volumio go-librespot[10928]: time="2025-10-28T16:46: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 28 16:46:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 16:46:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 16:46:03 volumio volumio[10332]: info: Initializing connection to go-librespot Websocket Oct 28 16:46:03 volumio volumio[10332]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 16:46:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22. Oct 28 16:46:05 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 16:46:05 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 16:46:05 volumio go-librespot[10935]: go-librespot daemon starting... Oct 28 16:46:05 volumio go-librespot[10936]: time="2025-10-28T16:46:05Z" level=info msg="running go-librespot 0.4.0" Oct 28 16:46:05 volumio go-librespot[10936]: time="2025-10-28T16:46:05Z" level=debug msg="app state loaded" Oct 28 16:46:05 volumio go-librespot[10936]: time="2025-10-28T16:46:05Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 16:46:05 volumio go-librespot[10936]: time="2025-10-28T16:46: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 28 16:46:05 volumio go-librespot[10936]: time="2025-10-28T16:46: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 28 16:46:05 volumio go-librespot[10936]: time="2025-10-28T16:46: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 28 16:46:05 volumio go-librespot[10936]: time="2025-10-28T16:46:05Z" level=info msg="zeroconf server listening on port 43133" Oct 28 16:46:05 volumio go-librespot[10936]: time="2025-10-28T16:46:05Z" level=debug msg="obtained new client token: AADiPSAfZk4IkjlPS6XTsCh2dYW2qFnpwHzKp/BrJMI7QP3htcQEZ4H9CMiJ7ZwrYuBYv2Y7P/N/JhSYniO+X7WBAs1hcd+dj922/JSaumPGl4jYse4XJEQWYFwOZCHgUh1HgZHxTHmQeJA9UDW0oPv+4r5QlcLIY7RlicCVwzEH0k0960Qb70hsvineh4wjsESZnMGOzUPkku9iZT9TNrbRBAv1EPqsLj3APXjqsyaSSOjJhCXm" Oct 28 16:46:05 volumio go-librespot[10936]: time="2025-10-28T16:46:05Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 16:46:05 volumio go-librespot[10936]: time="2025-10-28T16:46:05Z" level=debug msg="completed keyexchange" Oct 28 16:46:05 volumio go-librespot[10936]: time="2025-10-28T16:46:05Z" level=debug msg="completed challenge" Oct 28 16:46:05 volumio go-librespot[10936]: time="2025-10-28T16:46: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 28 16:46:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 16:46:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 16:46:06 volumio volumio[10332]: info: Initializing connection to go-librespot Websocket Oct 28 16:46:06 volumio volumio[10332]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 16:46:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 23. Oct 28 16:46:08 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 16:46:08 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 16:46:08 volumio go-librespot[10957]: go-librespot daemon starting... Oct 28 16:46:08 volumio go-librespot[10958]: time="2025-10-28T16:46:08Z" level=info msg="running go-librespot 0.4.0" Oct 28 16:46:08 volumio go-librespot[10958]: time="2025-10-28T16:46:08Z" level=debug msg="app state loaded" Oct 28 16:46:08 volumio go-librespot[10958]: time="2025-10-28T16:46:08Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 16:46:08 volumio go-librespot[10958]: time="2025-10-28T16:46: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-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 28 16:46:08 volumio go-librespot[10958]: time="2025-10-28T16:46:08Z" 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 28 16:46:08 volumio go-librespot[10958]: time="2025-10-28T16:46:08Z" 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 28 16:46:08 volumio go-librespot[10958]: time="2025-10-28T16:46:08Z" level=info msg="zeroconf server listening on port 40219" Oct 28 16:46:08 volumio go-librespot[10958]: time="2025-10-28T16:46:08Z" level=debug msg="obtained new client token: AADjUwpB8Ib3Evd9CONW4zh7bATlAOsUG3YjYl/kelNg8pru0VyJrGGfT93YioRVCc0MLHjlMu5ahtkXhi86ejs/5GhKMDZwFHW2R6zWGKUisIO7GWMKlOx/KAwI5hwM+w6dZsyWItz7nexa6kfvMZ+pdm4J1YJedugk/kyWT8kZUXZxeSwz3qvl1YZHbiCL0KYA5HmxDPYmW+4UR7eteYqY7ZoqUHxu6M0hZQcquNGUj/6ARL7QeDI=" Oct 28 16:46:08 volumio go-librespot[10958]: time="2025-10-28T16:46:08Z" 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 28 16:46:08 volumio go-librespot[10958]: time="2025-10-28T16:46:08Z" level=warning msg="failed to connect to AP ap-gew1.spotify.com:443, retrying with a different AP" error="dial tcp 104.199.65.9:443: connect: connection refused" Oct 28 16:46:08 volumio go-librespot[10958]: time="2025-10-28T16:46:08Z" level=debug msg="connected to ap-gew1.spotify.com:80" Oct 28 16:46:08 volumio go-librespot[10958]: time="2025-10-28T16:46:08Z" level=debug msg="completed keyexchange" Oct 28 16:46:08 volumio go-librespot[10958]: time="2025-10-28T16:46:08Z" level=debug msg="completed challenge" Oct 28 16:46:08 volumio go-librespot[10958]: time="2025-10-28T16:46: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 28 16:46:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 16:46:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 16:46:09 volumio volumio[10332]: info: Initializing connection to go-librespot Websocket Oct 28 16:46:09 volumio volumio[10332]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 16:46:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 24. Oct 28 16:46:12 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 16:46:12 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 16:46:12 volumio go-librespot[10969]: go-librespot daemon starting... Oct 28 16:46:12 volumio go-librespot[10970]: time="2025-10-28T16:46:12Z" level=info msg="running go-librespot 0.4.0" Oct 28 16:46:12 volumio go-librespot[10970]: time="2025-10-28T16:46:12Z" level=debug msg="app state loaded" Oct 28 16:46:12 volumio go-librespot[10970]: time="2025-10-28T16:46:12Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 16:46:12 volumio go-librespot[10970]: time="2025-10-28T16:46:12Z" 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 28 16:46:12 volumio go-librespot[10970]: time="2025-10-28T16:46:12Z" 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 28 16:46:12 volumio go-librespot[10970]: time="2025-10-28T16:46:12Z" 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 28 16:46:12 volumio go-librespot[10970]: time="2025-10-28T16:46:12Z" level=info msg="zeroconf server listening on port 36051" Oct 28 16:46:12 volumio go-librespot[10970]: time="2025-10-28T16:46:12Z" level=debug msg="obtained new client token: AACidLFC+jmYbQKQpOVeC6z21F0/lv2MmyI3QY2307LVPDl6dG0F65zkuOhXQksqOkQ4yMLZhgo9kg9Uu7E48YNeHqikXU7hZ4rIApdI4T8dBfQx3E1hQQhyUBKWlzle5qdyGID5qeteq9SBHO3EMQU0z3e0+KjAOPJ/jQhio5Q2HWnwL7+unIAQCzSGp/15meqvqgj8hvRRH2Puflni87QfZAUr2b9tTJyRnHde3aH7WNhKPrBw" Oct 28 16:46:12 volumio go-librespot[10970]: time="2025-10-28T16:46:12Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 16:46:12 volumio go-librespot[10970]: time="2025-10-28T16:46:12Z" level=debug msg="completed keyexchange" Oct 28 16:46:12 volumio go-librespot[10970]: time="2025-10-28T16:46:12Z" level=debug msg="completed challenge" Oct 28 16:46:12 volumio go-librespot[10970]: time="2025-10-28T16:46: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 28 16:46:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 16:46:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 16:46:12 volumio volumio[10332]: info: Initializing connection to go-librespot Websocket Oct 28 16:46:12 volumio volumio[10332]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 16:46:15 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 25. Oct 28 16:46:15 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 16:46:15 volumio volumio[10332]: info: Initializing connection to go-librespot Websocket Oct 28 16:46:15 volumio volumio[10332]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 16:46:15 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 16:46:15 volumio go-librespot[10977]: go-librespot daemon starting... Oct 28 16:46:15 volumio go-librespot[10978]: time="2025-10-28T16:46:15Z" level=info msg="running go-librespot 0.4.0" Oct 28 16:46:15 volumio go-librespot[10978]: time="2025-10-28T16:46:15Z" level=debug msg="app state loaded" Oct 28 16:46:15 volumio go-librespot[10978]: time="2025-10-28T16:46:15Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 16:46:15 volumio go-librespot[10978]: time="2025-10-28T16:46:15Z" 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 28 16:46:15 volumio go-librespot[10978]: time="2025-10-28T16:46:15Z" 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 28 16:46:15 volumio go-librespot[10978]: time="2025-10-28T16:46:15Z" 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 28 16:46:15 volumio go-librespot[10978]: time="2025-10-28T16:46:15Z" level=info msg="zeroconf server listening on port 40139" Oct 28 16:46:15 volumio go-librespot[10978]: time="2025-10-28T16:46:15Z" level=debug msg="obtained new client token: AADlQUQZjcMrrrGdLtybJxPRjUBmgusQ+g9SOfvOEfdxtbmSnK1KTjN4qpOPPwsfp1pGSU0cZ7nzbejmYpI0w63GDmS42tOQYhuHIUQelr/pva6wy8EbfXA1FPz8gLfu9s3+/Phj/AFWebrbzK6VVfM9jWqZ1OhLN7nQSSP65wa7RBA2aLwCxIDnq+SNsTxYkfY8DA5ifFwRDrpoOSGlPIuokGypc6exkonMGECpuyf3MsbLDQeWeHw=" Oct 28 16:46:15 volumio go-librespot[10978]: time="2025-10-28T16:46:15Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 16:46:15 volumio go-librespot[10978]: time="2025-10-28T16:46:15Z" level=debug msg="completed keyexchange" Oct 28 16:46:15 volumio go-librespot[10978]: time="2025-10-28T16:46:15Z" level=debug msg="completed challenge" Oct 28 16:46:15 volumio go-librespot[10978]: time="2025-10-28T16:46: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 28 16:46:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 16:46:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 16:46:18 volumio volumio[10332]: info: Initializing connection to go-librespot Websocket Oct 28 16:46:18 volumio volumio[10332]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 16:46:19 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 26. Oct 28 16:46:19 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 16:46:19 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 16:46:19 volumio go-librespot[10999]: go-librespot daemon starting... Oct 28 16:46:19 volumio go-librespot[11000]: time="2025-10-28T16:46:19Z" level=info msg="running go-librespot 0.4.0" Oct 28 16:46:19 volumio go-librespot[11000]: time="2025-10-28T16:46:19Z" level=debug msg="app state loaded" Oct 28 16:46:19 volumio go-librespot[11000]: time="2025-10-28T16:46:19Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 16:46:19 volumio go-librespot[11000]: time="2025-10-28T16:46:19Z" 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 28 16:46:19 volumio go-librespot[11000]: time="2025-10-28T16:46:19Z" 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 28 16:46:19 volumio go-librespot[11000]: time="2025-10-28T16:46:19Z" 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 28 16:46:19 volumio go-librespot[11000]: time="2025-10-28T16:46:19Z" level=info msg="zeroconf server listening on port 38143" Oct 28 16:46:19 volumio go-librespot[11000]: time="2025-10-28T16:46:19Z" level=debug msg="obtained new client token: AAA8J0HDDvIJ2GOKmlZYatr2pcnK9pRbT+8yZ8bfCRJzaiy4HYL32TFNdPzyJ44fHfWIg9JMkH++mWIvhfHNhKqt4s6tzVVqu9bSQFT0Z5ftSV41Rzatlz4MLBZfVNwey4wQfDYsMgZ/Wk22ONwa/Ch+7gLIdXpHgsQ21mH66GMhO9LIp6m9B3UMwGzWTpctV/0aROnxNm/9l1RVbgJrc5REg5Eukox3Z4H3W+pfQMXdr2LDNAft" Oct 28 16:46:19 volumio go-librespot[11000]: time="2025-10-28T16:46:19Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 16:46:19 volumio go-librespot[11000]: time="2025-10-28T16:46:19Z" level=debug msg="completed keyexchange" Oct 28 16:46:19 volumio go-librespot[11000]: time="2025-10-28T16:46:19Z" level=debug msg="completed challenge" Oct 28 16:46:19 volumio go-librespot[11000]: time="2025-10-28T16:46: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 28 16:46:19 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 16:46:19 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 16:46:21 volumio volumio[10332]: info: Initializing connection to go-librespot Websocket Oct 28 16:46:21 volumio volumio[10332]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 16:46:22 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 27. Oct 28 16:46:22 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 16:46:22 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 16:46:22 volumio go-librespot[11007]: go-librespot daemon starting... Oct 28 16:46:22 volumio go-librespot[11008]: time="2025-10-28T16:46:22Z" level=info msg="running go-librespot 0.4.0" Oct 28 16:46:22 volumio go-librespot[11008]: time="2025-10-28T16:46:22Z" level=debug msg="app state loaded" Oct 28 16:46:22 volumio go-librespot[11008]: time="2025-10-28T16:46:22Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 16:46:22 volumio go-librespot[11008]: time="2025-10-28T16:46: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-gae2.spotify.com:80]" Oct 28 16:46:22 volumio go-librespot[11008]: time="2025-10-28T16:46:22Z" 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 28 16:46:22 volumio go-librespot[11008]: time="2025-10-28T16:46:22Z" 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 28 16:46:22 volumio go-librespot[11008]: time="2025-10-28T16:46:22Z" level=info msg="zeroconf server listening on port 40239" Oct 28 16:46:22 volumio go-librespot[11008]: time="2025-10-28T16:46:22Z" level=debug msg="obtained new client token: AAB/l9kSVRUaBIBrPDGajCsMpZZSx7CuWJT0OtNUVapu8XipkMY9FccBi1L1G82pEt7lkCsLJv9pfD1El78g0GsLqBA3EHH3OHle7kMK9ADfj69/sdwG549hV9B7z8pL0CabdBS9aCoO3QlWMPXzkYVh0YK5Ym9W45RhZlOAsmYeS6ETjd8nnGUhAwANQe1JiCtIOkMiFSdx+u4uV2lizTTUdcl3HwpB6/ILgQsGs6kjJnPR6QSDwpQ=" Oct 28 16:46:22 volumio go-librespot[11008]: time="2025-10-28T16:46:22Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 16:46:22 volumio go-librespot[11008]: time="2025-10-28T16:46:22Z" level=debug msg="completed keyexchange" Oct 28 16:46:22 volumio go-librespot[11008]: time="2025-10-28T16:46:22Z" level=debug msg="completed challenge" Oct 28 16:46:22 volumio go-librespot[11008]: time="2025-10-28T16:46: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 28 16:46:22 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 16:46:22 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 16:46:24 volumio volumio[10332]: info: Initializing connection to go-librespot Websocket Oct 28 16:46:24 volumio volumio[10332]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 16:46:26 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 28. Oct 28 16:46:26 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 16:46:26 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 16:46:26 volumio go-librespot[11029]: go-librespot daemon starting... Oct 28 16:46:26 volumio go-librespot[11030]: time="2025-10-28T16:46:26Z" level=info msg="running go-librespot 0.4.0" Oct 28 16:46:26 volumio go-librespot[11030]: time="2025-10-28T16:46:26Z" level=debug msg="app state loaded" Oct 28 16:46:26 volumio go-librespot[11030]: time="2025-10-28T16:46:26Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 16:46:26 volumio go-librespot[11030]: time="2025-10-28T16:46: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 28 16:46:26 volumio go-librespot[11030]: time="2025-10-28T16:46: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 28 16:46:26 volumio go-librespot[11030]: time="2025-10-28T16:46: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 28 16:46:26 volumio go-librespot[11030]: time="2025-10-28T16:46:26Z" level=info msg="zeroconf server listening on port 45777" Oct 28 16:46:26 volumio go-librespot[11030]: time="2025-10-28T16:46:26Z" level=debug msg="obtained new client token: AACjLurW+wbqMeUXgArMUYphFfBh3ObYhgjp79I5YHqsKzrWMh8+fw4Dv7yy004O9e05+87TOPypeINJYDibwFj0BdSgcILNhRGyqTmrpIeTdGqN8uTE3EfSNCqZLTVoBga6OZPe1qik91GtW631Hiv5Rk2eiWwGxV0Lvo8K2DZRJEcr+n5Uiqb2I/7vFmF/dM0I6erugaGshywQ9OxTfIpieIx6wtBgWv3XwlvmF+kd/+lSrz5i" Oct 28 16:46:26 volumio go-librespot[11030]: time="2025-10-28T16:46:26Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 16:46:26 volumio go-librespot[11030]: time="2025-10-28T16:46:26Z" level=debug msg="completed keyexchange" Oct 28 16:46:26 volumio go-librespot[11030]: time="2025-10-28T16:46:26Z" level=debug msg="completed challenge" Oct 28 16:46:26 volumio go-librespot[11030]: time="2025-10-28T16:46: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 28 16:46:26 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 16:46:26 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 16:46:27 volumio volumio[10332]: info: Initializing connection to go-librespot Websocket Oct 28 16:46:27 volumio volumio[10332]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 16:46:29 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 29. Oct 28 16:46:29 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 16:46:29 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 16:46:29 volumio go-librespot[11038]: go-librespot daemon starting... Oct 28 16:46:29 volumio go-librespot[11039]: time="2025-10-28T16:46:29Z" level=info msg="running go-librespot 0.4.0" Oct 28 16:46:29 volumio go-librespot[11039]: time="2025-10-28T16:46:29Z" level=debug msg="app state loaded" Oct 28 16:46:29 volumio go-librespot[11039]: time="2025-10-28T16:46:29Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 16:46:29 volumio go-librespot[11039]: time="2025-10-28T16:46: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-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 28 16:46:29 volumio go-librespot[11039]: time="2025-10-28T16:46:29Z" 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 28 16:46:29 volumio go-librespot[11039]: time="2025-10-28T16:46:29Z" 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 28 16:46:29 volumio go-librespot[11039]: time="2025-10-28T16:46:29Z" level=info msg="zeroconf server listening on port 41873" Oct 28 16:46:29 volumio go-librespot[11039]: time="2025-10-28T16:46:29Z" level=debug msg="obtained new client token: AABGr4s/OOSkAekvsTJtX1d3XEuzB7VQz5WcHSq+3vhYk+0U9V5CS8ao1uGErQKlRtSyUzo6JbLjY9daisV6SXCRk8WzcZw53vp/4pWMaKJGsouijCdLZncC6FpLuzvFTfA1gBQq+aKChHYof4EgYCy0kKrp4CtKQx6OlbA5IG96zXIqdhIPSP5j+UAByOSPmgg5un9Eb7n2WfIIFxXC40ddRVN2PFX6rqK50XdFNFgU0cnxnhtmFaI=" Oct 28 16:46:29 volumio go-librespot[11039]: time="2025-10-28T16:46:29Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 16:46:29 volumio go-librespot[11039]: time="2025-10-28T16:46:29Z" level=debug msg="completed keyexchange" Oct 28 16:46:29 volumio go-librespot[11039]: time="2025-10-28T16:46:29Z" level=debug msg="completed challenge" Oct 28 16:46:29 volumio go-librespot[11039]: time="2025-10-28T16:46: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 28 16:46:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 16:46:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 16:46:30 volumio volumio[10332]: info: Initializing connection to go-librespot Websocket Oct 28 16:46:30 volumio volumio[10332]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 16:46:33 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 30. Oct 28 16:46:33 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 16:46:33 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 16:46:33 volumio go-librespot[11049]: go-librespot daemon starting... Oct 28 16:46:33 volumio go-librespot[11050]: time="2025-10-28T16:46:33Z" level=info msg="running go-librespot 0.4.0" Oct 28 16:46:33 volumio go-librespot[11050]: time="2025-10-28T16:46:33Z" level=debug msg="app state loaded" Oct 28 16:46:33 volumio go-librespot[11050]: time="2025-10-28T16:46:33Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 16:46:33 volumio go-librespot[11050]: time="2025-10-28T16:46: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 28 16:46:33 volumio go-librespot[11050]: time="2025-10-28T16:46: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 28 16:46:33 volumio go-librespot[11050]: time="2025-10-28T16:46: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 28 16:46:33 volumio go-librespot[11050]: time="2025-10-28T16:46:33Z" level=info msg="zeroconf server listening on port 33883" Oct 28 16:46:33 volumio go-librespot[11050]: time="2025-10-28T16:46:33Z" level=debug msg="obtained new client token: AADwLRXKy1mnMAEuwAZugzL3LF7txfuLHPBic3AcCIRX8oiRfRiLZ/LSo9t5mcdy0tNSPHMLgvu+MCjy/ek1dPMPKCej1juBDhbJwzx4h1NcTwgZFp6FjZfEtyI4GKD9w7qAvbcpplvLi1Pw3rZELutyxGC9GlbncJRqrUEPdHu0CWEF0mk4hPSwwKE1DN9+3l6nzaIe3rxKi/ExlCh7nPcolyO/Ny1a73Vo6IRi3n9E+NxZa7WT" Oct 28 16:46:33 volumio go-librespot[11050]: time="2025-10-28T16:46:33Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 16:46:33 volumio go-librespot[11050]: time="2025-10-28T16:46:33Z" level=debug msg="completed keyexchange" Oct 28 16:46:33 volumio go-librespot[11050]: time="2025-10-28T16:46:33Z" level=debug msg="completed challenge" Oct 28 16:46:33 volumio go-librespot[11050]: time="2025-10-28T16:46: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 28 16:46:33 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 16:46:33 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 16:46:33 volumio volumio[10332]: info: Initializing connection to go-librespot Websocket Oct 28 16:46:33 volumio volumio[10332]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 16:46:36 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 31. Oct 28 16:46:36 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 16:46:36 volumio volumio[10332]: info: Initializing connection to go-librespot Websocket Oct 28 16:46:36 volumio volumio[10332]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 16:46:36 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 16:46:36 volumio go-librespot[11071]: go-librespot daemon starting... Oct 28 16:46:36 volumio go-librespot[11072]: time="2025-10-28T16:46:36Z" level=info msg="running go-librespot 0.4.0" Oct 28 16:46:36 volumio go-librespot[11072]: time="2025-10-28T16:46:36Z" level=debug msg="app state loaded" Oct 28 16:46:36 volumio go-librespot[11072]: time="2025-10-28T16:46:36Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 16:46:36 volumio go-librespot[11072]: time="2025-10-28T16:46: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 28 16:46:36 volumio go-librespot[11072]: time="2025-10-28T16:46: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 28 16:46:36 volumio go-librespot[11072]: time="2025-10-28T16:46: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 28 16:46:36 volumio go-librespot[11072]: time="2025-10-28T16:46:36Z" level=info msg="zeroconf server listening on port 46313" Oct 28 16:46:36 volumio go-librespot[11072]: time="2025-10-28T16:46:36Z" level=debug msg="obtained new client token: AABoIEZXmVOEvGURKu2Y924eC68hgwANXNGhtoEiynkRyCP28q/BqnARjsreQSr6c9rzMQ1VEfdUuoY86XSM/n3pwUv88VLezcojXtUAShfyPtIiV2gEVBqrj+G8KNI6jtOseRkS6OnWtp8cOa6G1oUqr85NzWjdBlpBlPwMlP9E+ryekbQkonO9t0YbpunMktgXfeo79NJUKyIi7c8pYu2XaN8M/VowyZqLhT+cQRfbV+wCmZwFPFs=" Oct 28 16:46:36 volumio go-librespot[11072]: time="2025-10-28T16:46:36Z" 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 28 16:46:36 volumio go-librespot[11072]: time="2025-10-28T16:46:36Z" level=debug msg="connected to ap-gew1.spotify.com:443" Oct 28 16:46:36 volumio go-librespot[11072]: time="2025-10-28T16:46:36Z" level=debug msg="completed keyexchange" Oct 28 16:46:36 volumio go-librespot[11072]: time="2025-10-28T16:46:36Z" level=debug msg="completed challenge" Oct 28 16:46:36 volumio go-librespot[11072]: time="2025-10-28T16:46: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 28 16:46:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 16:46:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 16:46:39 volumio volumio[10332]: info: Initializing connection to go-librespot Websocket Oct 28 16:46:39 volumio volumio[10332]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 16:46:40 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 32. Oct 28 16:46:40 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 16:46:40 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 16:46:40 volumio go-librespot[11079]: go-librespot daemon starting... Oct 28 16:46:40 volumio go-librespot[11080]: time="2025-10-28T16:46:40Z" level=info msg="running go-librespot 0.4.0" Oct 28 16:46:40 volumio go-librespot[11080]: time="2025-10-28T16:46:40Z" level=debug msg="app state loaded" Oct 28 16:46:40 volumio go-librespot[11080]: time="2025-10-28T16:46:40Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 16:46:40 volumio go-librespot[11080]: time="2025-10-28T16:46: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-gew4.spotify.com:80]" Oct 28 16:46:40 volumio go-librespot[11080]: time="2025-10-28T16:46:40Z" 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 28 16:46:40 volumio go-librespot[11080]: time="2025-10-28T16:46:40Z" 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 28 16:46:40 volumio go-librespot[11080]: time="2025-10-28T16:46:40Z" level=info msg="zeroconf server listening on port 39509" Oct 28 16:46:40 volumio go-librespot[11080]: time="2025-10-28T16:46:40Z" level=debug msg="obtained new client token: AADujuUZ6mDoFKEZAHRY9cxkdx/U7ySViIbMhm0gH1ha5M1b7EV6wsx2E3MsQm8z4E1NJgIbzjIj3Uwpqojppd0tTYW8IQRe2v5ZC6k248fTOhf2D1d9KDBl3+aA21tNnCLSCOiMhuIUHix/GvGqijQb+UsPYV+S7GZE/Y5pmGPE7au08tH7GYVCcHwnuTplwq7dNySzuZ+u9VaHQc5PrVauirAy05u1a/zfOkVFF+25ksZMEt7U" Oct 28 16:46:40 volumio go-librespot[11080]: time="2025-10-28T16:46:40Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 16:46:40 volumio go-librespot[11080]: time="2025-10-28T16:46:40Z" level=debug msg="completed keyexchange" Oct 28 16:46:40 volumio go-librespot[11080]: time="2025-10-28T16:46:40Z" level=debug msg="completed challenge" Oct 28 16:46:40 volumio go-librespot[11080]: time="2025-10-28T16:46: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 28 16:46:40 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 16:46:40 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 16:46:42 volumio volumio[10332]: info: Initializing connection to go-librespot Websocket Oct 28 16:46:42 volumio volumio[10332]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 16:46:43 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 33. Oct 28 16:46:43 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 16:46:43 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 16:46:43 volumio go-librespot[11087]: go-librespot daemon starting... Oct 28 16:46:43 volumio go-librespot[11088]: time="2025-10-28T16:46:43Z" level=info msg="running go-librespot 0.4.0" Oct 28 16:46:43 volumio go-librespot[11088]: time="2025-10-28T16:46:43Z" level=debug msg="app state loaded" Oct 28 16:46:43 volumio go-librespot[11088]: time="2025-10-28T16:46:43Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 16:46:43 volumio go-librespot[11088]: time="2025-10-28T16:46: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-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 28 16:46:43 volumio go-librespot[11088]: time="2025-10-28T16:46:43Z" 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 28 16:46:43 volumio go-librespot[11088]: time="2025-10-28T16:46:43Z" 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 28 16:46:43 volumio go-librespot[11088]: time="2025-10-28T16:46:43Z" level=info msg="zeroconf server listening on port 39283" Oct 28 16:46:43 volumio go-librespot[11088]: time="2025-10-28T16:46:43Z" level=debug msg="obtained new client token: AAAfiCERuEiEWfa3oBh0KIYAeRlyMDeNlVW5p+f/ACVF4KO31NCBGULLCfzGL9i1svC2WkVwcpkJYmk3QZGWOstJkhrN6NG3XJxjFUVQQlNISNo2ZsxPvrh8xA3lWILGzqXzz6VRN7CJ2DfJYcx9C36kZiJvsjQuKPEDWmmvwOO7t8BX3MNOpKMh7vqGcH+0K1uGpk9cD+N4E9j6KdCv87cjUkTiIK4jKM7ZTxfitO74hz+wMtNP0Gc=" Oct 28 16:46:43 volumio go-librespot[11088]: time="2025-10-28T16:46:43Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 16:46:43 volumio go-librespot[11088]: time="2025-10-28T16:46:43Z" level=debug msg="completed keyexchange" Oct 28 16:46:43 volumio go-librespot[11088]: time="2025-10-28T16:46:43Z" level=debug msg="completed challenge" Oct 28 16:46:43 volumio go-librespot[11088]: time="2025-10-28T16:46: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 28 16:46:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 16:46:43 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 16:46:45 volumio volumio[10332]: info: Initializing connection to go-librespot Websocket Oct 28 16:46:45 volumio volumio[10332]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 16:46:47 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 34. Oct 28 16:46:47 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 16:46:47 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 16:46:47 volumio go-librespot[11110]: go-librespot daemon starting... Oct 28 16:46:47 volumio go-librespot[11111]: time="2025-10-28T16:46:47Z" level=info msg="running go-librespot 0.4.0" Oct 28 16:46:47 volumio go-librespot[11111]: time="2025-10-28T16:46:47Z" level=debug msg="app state loaded" Oct 28 16:46:47 volumio go-librespot[11111]: time="2025-10-28T16:46:47Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 16:46:47 volumio go-librespot[11111]: time="2025-10-28T16:46: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 28 16:46:47 volumio go-librespot[11111]: time="2025-10-28T16:46: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 28 16:46:47 volumio go-librespot[11111]: time="2025-10-28T16:46: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 28 16:46:47 volumio go-librespot[11111]: time="2025-10-28T16:46:47Z" level=info msg="zeroconf server listening on port 46005" Oct 28 16:46:47 volumio go-librespot[11111]: time="2025-10-28T16:46:47Z" level=debug msg="obtained new client token: AADDy85PPftzwPKT2xG5x5OpQLVbhCv4+iQ0DDb7ODOvXErhidJnhTTtH9M0uswVRL5hFh3cffQsKkjiEFvB+GGJ8gHSWHpI9v/X4H4dtg2nfLkqAZj7sOa35JBRK/sQuPkVu4O+HdtNZPvliZH6ln1+FAIkboR6Cspt498HionDBYuEPe6klf/79iyi2kJXfR+CNFDbzpm3Kxw7eeQCD4UOCPu9IjcElGdYMrrG3gmHN198MztJ" Oct 28 16:46:47 volumio go-librespot[11111]: time="2025-10-28T16:46:47Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 16:46:47 volumio go-librespot[11111]: time="2025-10-28T16:46:47Z" level=debug msg="completed keyexchange" Oct 28 16:46:47 volumio go-librespot[11111]: time="2025-10-28T16:46:47Z" level=debug msg="completed challenge" Oct 28 16:46:47 volumio go-librespot[11111]: time="2025-10-28T16:46: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 28 16:46:47 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 16:46:47 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 16:46:48 volumio volumio[10332]: info: Initializing connection to go-librespot Websocket Oct 28 16:46:48 volumio volumio[10332]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 16:46:50 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 35. Oct 28 16:46:50 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 16:46:50 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 16:46:50 volumio go-librespot[11119]: go-librespot daemon starting... Oct 28 16:46:50 volumio go-librespot[11120]: time="2025-10-28T16:46:50Z" level=info msg="running go-librespot 0.4.0" Oct 28 16:46:50 volumio go-librespot[11120]: time="2025-10-28T16:46:50Z" level=debug msg="app state loaded" Oct 28 16:46:50 volumio go-librespot[11120]: time="2025-10-28T16:46:50Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 16:46:50 volumio go-librespot[11120]: time="2025-10-28T16:46: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 28 16:46:50 volumio go-librespot[11120]: time="2025-10-28T16:46: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 28 16:46:50 volumio go-librespot[11120]: time="2025-10-28T16:46: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 28 16:46:50 volumio go-librespot[11120]: time="2025-10-28T16:46:50Z" level=info msg="zeroconf server listening on port 32917" Oct 28 16:46:50 volumio go-librespot[11120]: time="2025-10-28T16:46:50Z" level=debug msg="obtained new client token: AAARfDZ2YkoeokfmCWWFfuj1XJYlxg3uMO1EXnpadjkp0GpDOxm3R5Fm+//JqQHAAAEOY1piXIkSJtcTpg/sG+PfNMA61NmmM+xTPMt/3J96AFYMM5RV3x320Xu0jQOmOl+91WYUk02bujaxhdbPfFOFLzVB33kihqxRt0MqEnDSo65Pe84UvmoeOueUNBrdvYIavNO9lSAYaHiFpSBkoBTnmDIwf+z9Or06MuMkWzrvfkKDCtFyPxM=" Oct 28 16:46:50 volumio go-librespot[11120]: time="2025-10-28T16:46:50Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 16:46:50 volumio go-librespot[11120]: time="2025-10-28T16:46:50Z" level=debug msg="completed keyexchange" Oct 28 16:46:50 volumio go-librespot[11120]: time="2025-10-28T16:46:50Z" level=debug msg="completed challenge" Oct 28 16:46:50 volumio go-librespot[11120]: time="2025-10-28T16:46: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 28 16:46:50 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 16:46:50 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 16:46:51 volumio volumio[10332]: info: Initializing connection to go-librespot Websocket Oct 28 16:46:51 volumio volumio[10332]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 16:46:54 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 36. Oct 28 16:46:54 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 16:46:54 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 16:46:54 volumio go-librespot[11131]: go-librespot daemon starting... Oct 28 16:46:54 volumio go-librespot[11132]: time="2025-10-28T16:46:54Z" level=info msg="running go-librespot 0.4.0" Oct 28 16:46:54 volumio go-librespot[11132]: time="2025-10-28T16:46:54Z" level=debug msg="app state loaded" Oct 28 16:46:54 volumio go-librespot[11132]: time="2025-10-28T16:46:54Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 16:46:54 volumio go-librespot[11132]: time="2025-10-28T16:46:54Z" 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 28 16:46:54 volumio go-librespot[11132]: time="2025-10-28T16:46:54Z" 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 28 16:46:54 volumio go-librespot[11132]: time="2025-10-28T16:46:54Z" 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 28 16:46:54 volumio go-librespot[11132]: time="2025-10-28T16:46:54Z" level=info msg="zeroconf server listening on port 35983" Oct 28 16:46:54 volumio go-librespot[11132]: time="2025-10-28T16:46:54Z" level=debug msg="obtained new client token: AAAzKadXMyjcECFKpnyexgNK6n23h0TTsvzm4d0wx/tCW+zNbDxCqoeMGWUsTBhkjUdtM3Ivh7Z32NCOQ6C8VBRMVNkFJdSPGkaU0dqp1LHtJq7T7Lx4yTPkLz1+Op/NRecm7/ANf46tcE6yzanF088EJHpvgiyfDuu+enw3/Ep/gIuHeK+8xpHYEdf0AS6M4Q1nnpv1n20e8n64/VBHUfqGks9LQ6pEweeKzi6XfXr04tqOP+74" Oct 28 16:46:54 volumio go-librespot[11132]: time="2025-10-28T16:46:54Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 16:46:54 volumio go-librespot[11132]: time="2025-10-28T16:46:54Z" level=debug msg="completed keyexchange" Oct 28 16:46:54 volumio go-librespot[11132]: time="2025-10-28T16:46:54Z" level=debug msg="completed challenge" Oct 28 16:46:54 volumio go-librespot[11132]: time="2025-10-28T16:46: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 28 16:46:54 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 16:46:54 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 16:46:54 volumio volumio[10332]: info: Initializing connection to go-librespot Websocket Oct 28 16:46:54 volumio volumio[10332]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 16:46:57 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 37. Oct 28 16:46:57 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 16:46:57 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 16:46:57 volumio go-librespot[11154]: go-librespot daemon starting... Oct 28 16:46:57 volumio go-librespot[11155]: time="2025-10-28T16:46:57Z" level=info msg="running go-librespot 0.4.0" Oct 28 16:46:57 volumio go-librespot[11155]: time="2025-10-28T16:46:57Z" level=debug msg="app state loaded" Oct 28 16:46:57 volumio volumio[10332]: info: Initializing connection to go-librespot Websocket Oct 28 16:46:57 volumio go-librespot[11155]: time="2025-10-28T16:46:57Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 16:46:57 volumio go-librespot[11155]: time="2025-10-28T16:46:57Z" level=debug msg="new websocket client" Oct 28 16:46:57 volumio volumio[10332]: info: Connection to go-librespot Websocket established Oct 28 16:46:57 volumio go-librespot[11155]: time="2025-10-28T16:46: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 28 16:46:57 volumio go-librespot[11155]: time="2025-10-28T16:46: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 28 16:46:57 volumio go-librespot[11155]: time="2025-10-28T16:46: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 28 16:46:57 volumio go-librespot[11155]: time="2025-10-28T16:46:57Z" level=info msg="zeroconf server listening on port 46521" Oct 28 16:46:57 volumio go-librespot[11155]: time="2025-10-28T16:46:57Z" level=debug msg="obtained new client token: AABzjJYmPOZg0XZd/5IPFLhlJn4dyeDFOtsZk2kyq+VTaNj55QnUchnNy6YAkKouRVRKH1jcjudNcSsRSs8GhrNOlbDSU+9UxAbweSvApa+wLSDJMtH7cvm4xttPIqizlkM5ZgKIh78ppKrl9qJKQqbFX6uxmbt6KNdWhipB2WWtxjk4nZ1GuTHAdJMp201YXsdRO0jrN8qefM5/ISGKBmSjHiNs12+SlaZ+i8PlY5RCmKG0eKH1PQ4=" Oct 28 16:46:57 volumio go-librespot[11155]: time="2025-10-28T16:46:57Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 16:46:57 volumio go-librespot[11155]: time="2025-10-28T16:46:57Z" level=debug msg="completed keyexchange" Oct 28 16:46:57 volumio go-librespot[11155]: time="2025-10-28T16:46:57Z" level=debug msg="completed challenge" Oct 28 16:46:57 volumio go-librespot[11155]: time="2025-10-28T16:46: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 28 16:46:57 volumio volumio[10332]: info: Connection to go-librespot Websocket closed Oct 28 16:46:57 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 16:46:57 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 16:47:00 volumio volumio[10332]: info: Getting Spotify volume Oct 28 16:47:00 volumio volumio[10332]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 28 16:47:00 volumio volumio[10332]: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 16:47:00 volumio volumio[10332]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Oct 28 16:47:00 volumio volumio[10332]: errno: -111, Oct 28 16:47:00 volumio volumio[10332]: code: 'ECONNREFUSED', Oct 28 16:47:00 volumio volumio[10332]: syscall: 'connect', Oct 28 16:47:00 volumio volumio[10332]: address: '127.0.0.1', Oct 28 16:47:00 volumio volumio[10332]: port: 9879, Oct 28 16:47:00 volumio volumio[10332]: response: undefined Oct 28 16:47:00 volumio volumio[10332]: } Oct 28 16:47:00 volumio volumio[10332]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 28 16:47:00 volumio sudo[11177]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-10-28 16:46' Oct 28 16:47:00 volumio sudo[11177]: 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"