Nov 06 00:12:02 volumio volumio[28922]: info: Initializing connection to go-librespot Websocket Nov 06 00:12:02 volumio volumio[28922]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 00:12:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 244. Nov 06 00:12:03 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 00:12:03 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 00:12:03 volumio go-librespot[29864]: go-librespot daemon starting... Nov 06 00:12:03 volumio go-librespot[29865]: time="2025-11-06T00:12:03Z" level=info msg="running go-librespot 0.4.0" Nov 06 00:12:03 volumio go-librespot[29865]: time="2025-11-06T00:12:03Z" level=debug msg="app state loaded" Nov 06 00:12:03 volumio go-librespot[29865]: time="2025-11-06T00:12:03Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 00:12:03 volumio go-librespot[29865]: time="2025-11-06T00:12:03Z" 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]" Nov 06 00:12:03 volumio go-librespot[29865]: time="2025-11-06T00:12:03Z" 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]" Nov 06 00:12:03 volumio go-librespot[29865]: time="2025-11-06T00:12:03Z" 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]" Nov 06 00:12:03 volumio go-librespot[29865]: time="2025-11-06T00:12:03Z" level=info msg="zeroconf server listening on port 38799" Nov 06 00:12:03 volumio go-librespot[29865]: time="2025-11-06T00:12:03Z" level=debug msg="obtained new client token: AAAj0dgDPXJuVEh4EW2JCZaivVU82+7HClBWOhNSjIOFBNj7xYp1HUzb9Fj9YCGjpxFyQ4eb7VSsSS7tKJBc3kpH8qedtOcszgMugi0F5Rn+TPfxH3eZqlaLmWurw+eqyoeUsfNA5/o8HF0SuLNSmZdpK/ovWZ1+OEPhFDCC5Zlu+TZ2W5n4jyLu0/me7rBp6cRAxf07PiCIy7cpeN6+GySudjgfeSpS88vJKiLqN5RnxsdgejOq" Nov 06 00:12:03 volumio go-librespot[29865]: time="2025-11-06T00:12:03Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 06 00:12:03 volumio go-librespot[29865]: time="2025-11-06T00:12:03Z" level=debug msg="completed keyexchange" Nov 06 00:12:03 volumio go-librespot[29865]: time="2025-11-06T00:12:03Z" level=debug msg="completed challenge" Nov 06 00:12:03 volumio go-librespot[29865]: time="2025-11-06T00:12:03Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 06 00:12:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 00:12:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 00:12:05 volumio volumio[28922]: info: Initializing connection to go-librespot Websocket Nov 06 00:12:05 volumio volumio[28922]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 00:12:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 245. Nov 06 00:12:06 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 00:12:06 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 00:12:06 volumio go-librespot[29886]: go-librespot daemon starting... Nov 06 00:12:06 volumio go-librespot[29887]: time="2025-11-06T00:12:06Z" level=info msg="running go-librespot 0.4.0" Nov 06 00:12:06 volumio go-librespot[29887]: time="2025-11-06T00:12:06Z" level=debug msg="app state loaded" Nov 06 00:12:06 volumio go-librespot[29887]: time="2025-11-06T00:12:06Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 00:12:06 volumio go-librespot[29887]: time="2025-11-06T00:12:06Z" 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]" Nov 06 00:12:06 volumio go-librespot[29887]: time="2025-11-06T00:12:06Z" 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]" Nov 06 00:12:06 volumio go-librespot[29887]: time="2025-11-06T00:12:06Z" 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]" Nov 06 00:12:06 volumio go-librespot[29887]: time="2025-11-06T00:12:06Z" level=info msg="zeroconf server listening on port 39647" Nov 06 00:12:06 volumio go-librespot[29887]: time="2025-11-06T00:12:06Z" level=debug msg="obtained new client token: AAD6OeWSxfsbU52qxVE040gXVeBU7oBewdF5RT970QdVwOA1nojWl2w6838htq3zdtgRKe7Jshb3hxAifjlbFkiFNg6BNnB+66Ue9OMdhmu+Fq6/z+9Im6psOvKogsZeADKAXJeGvgbmuI4Y4iKKnLPH/AsdXgZdoBpsP0rKeh+FqNuS6aKOAi4ApCHwrrReflS9blwBqztVflOKadLBsxjOtVmk+zloewL50rkpnCMpZ6i7d2NfVfg=" Nov 06 00:12:06 volumio go-librespot[29887]: time="2025-11-06T00:12:06Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 06 00:12:06 volumio go-librespot[29887]: time="2025-11-06T00:12:06Z" level=debug msg="completed keyexchange" Nov 06 00:12:06 volumio go-librespot[29887]: time="2025-11-06T00:12:06Z" level=debug msg="completed challenge" Nov 06 00:12:06 volumio go-librespot[29887]: time="2025-11-06T00:12:06Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 06 00:12:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 00:12:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 00:12:08 volumio volumio[28922]: info: Initializing connection to go-librespot Websocket Nov 06 00:12:08 volumio volumio[28922]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 00:12:09 volumio volumio[28922]: info: CoreCommandRouter::volumioGetState Nov 06 00:12:09 volumio volumio[28922]: info: CorePlayQueue::getTrack 0 Nov 06 00:12:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 246. Nov 06 00:12:10 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 00:12:10 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 00:12:10 volumio go-librespot[29897]: go-librespot daemon starting... Nov 06 00:12:10 volumio go-librespot[29898]: time="2025-11-06T00:12:10Z" level=info msg="running go-librespot 0.4.0" Nov 06 00:12:10 volumio go-librespot[29898]: time="2025-11-06T00:12:10Z" level=debug msg="app state loaded" Nov 06 00:12:10 volumio go-librespot[29898]: time="2025-11-06T00:12:10Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 00:12:10 volumio go-librespot[29898]: time="2025-11-06T00:12:10Z" 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]" Nov 06 00:12:10 volumio go-librespot[29898]: time="2025-11-06T00:12:10Z" 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]" Nov 06 00:12:10 volumio go-librespot[29898]: time="2025-11-06T00:12:10Z" 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]" Nov 06 00:12:10 volumio go-librespot[29898]: time="2025-11-06T00:12:10Z" level=info msg="zeroconf server listening on port 39583" Nov 06 00:12:10 volumio go-librespot[29898]: time="2025-11-06T00:12:10Z" level=debug msg="obtained new client token: AAAbsBWlbeRpCaEmVJdb85umI7kKPDXH+xTdEKxKrTFanQxfzY1G2COvvtyppYTqb+obgDn3mxflAnYEDB1i1JWO3cja/zKxyUulcDjsUSm662gyNTKAk3CElMuxJYYZ0Zufho9Zn5mewLR7vy6EUj4sMV8HldVfh4TPrHxc/r9BT/vuxUxrg2o6foTKTBSdqZJS+//iZxpf00Lwh1z8Fs5jG6dytmiMJIRA51grrhNwcE558jAJ" Nov 06 00:12:10 volumio go-librespot[29898]: time="2025-11-06T00:12:10Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 06 00:12:10 volumio go-librespot[29898]: time="2025-11-06T00:12:10Z" level=debug msg="completed keyexchange" Nov 06 00:12:10 volumio go-librespot[29898]: time="2025-11-06T00:12:10Z" level=debug msg="completed challenge" Nov 06 00:12:10 volumio go-librespot[29898]: time="2025-11-06T00:12:10Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 06 00:12:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 00:12:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 00:12:11 volumio volumio[28922]: info: Initializing connection to go-librespot Websocket Nov 06 00:12:11 volumio volumio[28922]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 00:12:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 247. Nov 06 00:12:13 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 00:12:13 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 00:12:13 volumio go-librespot[29919]: go-librespot daemon starting... Nov 06 00:12:13 volumio go-librespot[29920]: time="2025-11-06T00:12:13Z" level=info msg="running go-librespot 0.4.0" Nov 06 00:12:13 volumio go-librespot[29920]: time="2025-11-06T00:12:13Z" level=debug msg="app state loaded" Nov 06 00:12:13 volumio go-librespot[29920]: time="2025-11-06T00:12:13Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 00:12:13 volumio go-librespot[29920]: time="2025-11-06T00:12:13Z" 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]" Nov 06 00:12:13 volumio go-librespot[29920]: time="2025-11-06T00:12:13Z" 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]" Nov 06 00:12:13 volumio go-librespot[29920]: time="2025-11-06T00:12:13Z" 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]" Nov 06 00:12:13 volumio go-librespot[29920]: time="2025-11-06T00:12:13Z" level=info msg="zeroconf server listening on port 38803" Nov 06 00:12:13 volumio go-librespot[29920]: time="2025-11-06T00:12:13Z" level=debug msg="obtained new client token: AADMK/u9HCQuDPqgLS7YhsKiEI5VY/AlaWJW+LGJKAlHiMMzPc54uFZ/RCRO+G1sRiTeVyZ2sohQ2xa6KFxFaHvQR3rwKp9Qsb70pU+2NGt4csFMJ6CYSzxbuNrrkSptrGlj/b806Hacbp+VaJH0G27PbaA/CzKKjlmCG3mcWUoMw42XKBfADNxQ+i4ubyKtpQzd6ZUogkt5/GPVZThuXzAOJNBki9RJptcnrBFBKyvx8ox0h+z4rlU=" Nov 06 00:12:13 volumio go-librespot[29920]: time="2025-11-06T00:12:13Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 06 00:12:13 volumio go-librespot[29920]: time="2025-11-06T00:12:13Z" level=debug msg="completed keyexchange" Nov 06 00:12:13 volumio go-librespot[29920]: time="2025-11-06T00:12:13Z" level=debug msg="completed challenge" Nov 06 00:12:13 volumio go-librespot[29920]: time="2025-11-06T00:12:13Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 06 00:12:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 00:12:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 00:12:14 volumio volumio[28922]: info: Initializing connection to go-librespot Websocket Nov 06 00:12:14 volumio volumio[28922]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 00:12:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 248. Nov 06 00:12:17 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 00:12:17 volumio volumio[28922]: info: Initializing connection to go-librespot Websocket Nov 06 00:12:17 volumio volumio[28922]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 00:12:17 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 00:12:17 volumio go-librespot[29928]: go-librespot daemon starting... Nov 06 00:12:17 volumio go-librespot[29929]: time="2025-11-06T00:12:17Z" level=info msg="running go-librespot 0.4.0" Nov 06 00:12:17 volumio go-librespot[29929]: time="2025-11-06T00:12:17Z" level=debug msg="app state loaded" Nov 06 00:12:17 volumio go-librespot[29929]: time="2025-11-06T00:12:17Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 00:12:17 volumio go-librespot[29929]: time="2025-11-06T00:12:17Z" 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]" Nov 06 00:12:17 volumio go-librespot[29929]: time="2025-11-06T00:12:17Z" 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]" Nov 06 00:12:17 volumio go-librespot[29929]: time="2025-11-06T00:12:17Z" 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]" Nov 06 00:12:17 volumio go-librespot[29929]: time="2025-11-06T00:12:17Z" level=info msg="zeroconf server listening on port 46723" Nov 06 00:12:17 volumio go-librespot[29929]: time="2025-11-06T00:12:17Z" level=debug msg="obtained new client token: AADijD5aYdW0EVIw/OfmOb5Q9HH70KFaj+HdoiLvVtRubdasZp38mVqMLHAHaTn/W9C+jFg59q/TJupHN46qbtXhYO5HriZJBp40vX21vXlk6E8G+8gujhRgSUCjsbjyqJ2nEgVxje5MHbkqS1crCKePOA51JEeiDAMnpaFfOskMEIYFOmmmkLNbINNC+04YjZ4r1zLzvQZQvxOiQNDJrzDLR3ZlU+wA5+Tp8sWrUTyvpvBJ2hCf" Nov 06 00:12:17 volumio go-librespot[29929]: time="2025-11-06T00:12:17Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 06 00:12:17 volumio go-librespot[29929]: time="2025-11-06T00:12:17Z" level=debug msg="completed keyexchange" Nov 06 00:12:17 volumio go-librespot[29929]: time="2025-11-06T00:12:17Z" level=debug msg="completed challenge" Nov 06 00:12:17 volumio go-librespot[29929]: time="2025-11-06T00:12:17Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 06 00:12:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 00:12:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 00:12:19 volumio volumio[28922]: info: CoreCommandRouter::volumioGetState Nov 06 00:12:19 volumio volumio[28922]: info: CorePlayQueue::getTrack 0 Nov 06 00:12:19 volumio volumio[28922]: info: Listing playlists Nov 06 00:12:19 volumio volumio[28922]: info: Listing playlists Nov 06 00:12:20 volumio volumio[28922]: info: Initializing connection to go-librespot Websocket Nov 06 00:12:20 volumio volumio[28922]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 00:12:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 249. Nov 06 00:12:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 00:12:20 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 00:12:20 volumio go-librespot[29938]: go-librespot daemon starting... Nov 06 00:12:20 volumio go-librespot[29939]: time="2025-11-06T00:12:20Z" level=info msg="running go-librespot 0.4.0" Nov 06 00:12:20 volumio go-librespot[29939]: time="2025-11-06T00:12:20Z" level=debug msg="app state loaded" Nov 06 00:12:20 volumio go-librespot[29939]: time="2025-11-06T00:12:20Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 00:12:20 volumio go-librespot[29939]: time="2025-11-06T00:12:20Z" 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]" Nov 06 00:12:20 volumio go-librespot[29939]: time="2025-11-06T00:12:20Z" 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]" Nov 06 00:12:20 volumio go-librespot[29939]: time="2025-11-06T00:12:20Z" 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]" Nov 06 00:12:20 volumio go-librespot[29939]: time="2025-11-06T00:12:20Z" level=info msg="zeroconf server listening on port 43415" Nov 06 00:12:20 volumio go-librespot[29939]: time="2025-11-06T00:12:20Z" level=debug msg="obtained new client token: AABt/Lpra9rvdDfh4ZzZxdCtDDls09EGdLS/LlthmtGWsbWmguba0wSTHoOyaERNgLPU+oEBTcqsmROS20Ksyf5R8nH64GM0Muno/S8ur+IHxgQOOWmoFoF26Dyg858rUTx9JEF/zPrOy1zEs82yMdWWoWpeHbIzPZHxWb/xDsWL6MeOtfGAWRlfISSi9HVf8X9GIxpXvzYx2bvKj37tzCyXnNONHkekHKLEgBj1BCtQZ0tzx0/n9do=" Nov 06 00:12:20 volumio go-librespot[29939]: time="2025-11-06T00:12:20Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 06 00:12:20 volumio go-librespot[29939]: time="2025-11-06T00:12:20Z" level=debug msg="completed keyexchange" Nov 06 00:12:20 volumio go-librespot[29939]: time="2025-11-06T00:12:20Z" level=debug msg="completed challenge" Nov 06 00:12:20 volumio go-librespot[29939]: time="2025-11-06T00:12:20Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 06 00:12:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 00:12:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 00:12:23 volumio volumio[28922]: info: Initializing connection to go-librespot Websocket Nov 06 00:12:23 volumio volumio[28922]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 00:12:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 250. Nov 06 00:12:24 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 00:12:24 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 00:12:24 volumio go-librespot[29961]: go-librespot daemon starting... Nov 06 00:12:24 volumio go-librespot[29962]: time="2025-11-06T00:12:24Z" level=info msg="running go-librespot 0.4.0" Nov 06 00:12:24 volumio go-librespot[29962]: time="2025-11-06T00:12:24Z" level=debug msg="app state loaded" Nov 06 00:12:24 volumio go-librespot[29962]: time="2025-11-06T00:12:24Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 00:12:24 volumio go-librespot[29962]: time="2025-11-06T00:12:24Z" 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]" Nov 06 00:12:24 volumio go-librespot[29962]: time="2025-11-06T00:12:24Z" 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]" Nov 06 00:12:24 volumio go-librespot[29962]: time="2025-11-06T00:12:24Z" 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]" Nov 06 00:12:24 volumio go-librespot[29962]: time="2025-11-06T00:12:24Z" level=info msg="zeroconf server listening on port 39127" Nov 06 00:12:24 volumio go-librespot[29962]: time="2025-11-06T00:12:24Z" level=debug msg="obtained new client token: AABTiMJuw7/BYVaTTNXpBkABXsIMlh17nHG2Kt6FtI3WTh2YYjU8bMbp3WFhhGJ66HxZ6LCc4bMzd7EBESUUpXGYsYFeydNgfTOJpML9hgkptKjXOGGz7/80DMxGDTa+xifKxmWEGgci0fkTjUj9u1PZLIdIF/A/P6jul9g/hsuXKKammBaLOw8d5Tw98o0Nbui3filfLIUUcEFibqsEHWXMmLAxqCoemutENCgGWEqDLaHLpbXz" Nov 06 00:12:24 volumio go-librespot[29962]: time="2025-11-06T00:12:24Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 06 00:12:24 volumio go-librespot[29962]: time="2025-11-06T00:12:24Z" level=debug msg="completed keyexchange" Nov 06 00:12:24 volumio go-librespot[29962]: time="2025-11-06T00:12:24Z" level=debug msg="completed challenge" Nov 06 00:12:24 volumio go-librespot[29962]: time="2025-11-06T00:12:24Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 06 00:12:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 00:12:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 00:12:26 volumio volumio[28922]: info: Initializing connection to go-librespot Websocket Nov 06 00:12:26 volumio volumio[28922]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 00:12:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 251. Nov 06 00:12:27 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 00:12:27 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 00:12:27 volumio go-librespot[29969]: go-librespot daemon starting... Nov 06 00:12:27 volumio go-librespot[29970]: time="2025-11-06T00:12:27Z" level=info msg="running go-librespot 0.4.0" Nov 06 00:12:27 volumio go-librespot[29970]: time="2025-11-06T00:12:27Z" level=debug msg="app state loaded" Nov 06 00:12:27 volumio go-librespot[29970]: time="2025-11-06T00:12:27Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 00:12:27 volumio go-librespot[29970]: time="2025-11-06T00:12:27Z" 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]" Nov 06 00:12:27 volumio go-librespot[29970]: time="2025-11-06T00:12:27Z" 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]" Nov 06 00:12:27 volumio go-librespot[29970]: time="2025-11-06T00:12:27Z" 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]" Nov 06 00:12:27 volumio go-librespot[29970]: time="2025-11-06T00:12:27Z" level=info msg="zeroconf server listening on port 45187" Nov 06 00:12:27 volumio go-librespot[29970]: time="2025-11-06T00:12:27Z" level=debug msg="obtained new client token: AAA8XCkizmL5wnCxzLIFsDnxSgSZy9sbiAUpxe95Fo4Mj5P/DdnYXvejeA6i29o52KiyBWfcEnTLtWtIfS5U8FwcXlhsbENNno+Gb6EJX0RvtptKw1WrMZbTQZ2eH5dp0CE2SrsOtF6e/MYXBmzk/58zo6z8odcGcEfmMHS2DyE5vxVXPzKtSOmliuyB1njC02DBDu/23qMuiPdOeg15ip8hamtyAFTcJrxV3OvTFl65IVYKIXTfVSw=" Nov 06 00:12:27 volumio go-librespot[29970]: time="2025-11-06T00:12:27Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 06 00:12:27 volumio go-librespot[29970]: time="2025-11-06T00:12:27Z" level=debug msg="completed keyexchange" Nov 06 00:12:27 volumio go-librespot[29970]: time="2025-11-06T00:12:27Z" level=debug msg="completed challenge" Nov 06 00:12:27 volumio go-librespot[29970]: time="2025-11-06T00:12:27Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 06 00:12:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 00:12:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 00:12:29 volumio volumio[28922]: info: Initializing connection to go-librespot Websocket Nov 06 00:12:29 volumio volumio[28922]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 00:12:29 volumio volumio[28922]: info: CoreCommandRouter::volumioGetState Nov 06 00:12:29 volumio volumio[28922]: info: CorePlayQueue::getTrack 0 Nov 06 00:12:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 252. Nov 06 00:12:31 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 00:12:31 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 00:12:31 volumio go-librespot[29980]: go-librespot daemon starting... Nov 06 00:12:31 volumio go-librespot[29981]: time="2025-11-06T00:12:31Z" level=info msg="running go-librespot 0.4.0" Nov 06 00:12:31 volumio go-librespot[29981]: time="2025-11-06T00:12:31Z" level=debug msg="app state loaded" Nov 06 00:12:31 volumio go-librespot[29981]: time="2025-11-06T00:12:31Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 00:12:31 volumio go-librespot[29981]: time="2025-11-06T00:12:31Z" 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]" Nov 06 00:12:31 volumio go-librespot[29981]: time="2025-11-06T00:12:31Z" 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]" Nov 06 00:12:31 volumio go-librespot[29981]: time="2025-11-06T00:12:31Z" 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]" Nov 06 00:12:31 volumio go-librespot[29981]: time="2025-11-06T00:12:31Z" level=info msg="zeroconf server listening on port 38597" Nov 06 00:12:31 volumio go-librespot[29981]: time="2025-11-06T00:12:31Z" level=debug msg="obtained new client token: AAB734/xMPjwOczbDEiGNzrnA89cfhp0KKgPBsNOgs9cIUzdoxI4GVDkLunMe5S3Tjla2d7zaCUpZjMJcQIT3X/BcXD8shLCmJ3h7Pk2pXfRHjk+WSxWL55ZCo5xWVumNTmspx1TxiKhAbGUE1rjLTVWua+fVrO3BXoKubdSuEyi7+cgCpC6bA+2wJWNEauGLaAWGV9fu5btHmSSGzDM0uYaATy6j+QStWOO3GfOCW+GkpDr8up4" Nov 06 00:12:31 volumio go-librespot[29981]: time="2025-11-06T00:12:31Z" 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" Nov 06 00:12:31 volumio go-librespot[29981]: time="2025-11-06T00:12:31Z" level=debug msg="connected to ap-gew1.spotify.com:443" Nov 06 00:12:31 volumio go-librespot[29981]: time="2025-11-06T00:12:31Z" level=debug msg="completed keyexchange" Nov 06 00:12:31 volumio go-librespot[29981]: time="2025-11-06T00:12:31Z" level=debug msg="completed challenge" Nov 06 00:12:31 volumio go-librespot[29981]: time="2025-11-06T00:12:31Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 06 00:12:31 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 00:12:31 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 00:12:32 volumio volumio[28922]: info: Initializing connection to go-librespot Websocket Nov 06 00:12:32 volumio volumio[28922]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 00:12:34 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 253. Nov 06 00:12:34 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 00:12:34 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 00:12:34 volumio go-librespot[30002]: go-librespot daemon starting... Nov 06 00:12:34 volumio go-librespot[30003]: time="2025-11-06T00:12:34Z" level=info msg="running go-librespot 0.4.0" Nov 06 00:12:34 volumio go-librespot[30003]: time="2025-11-06T00:12:34Z" level=debug msg="app state loaded" Nov 06 00:12:34 volumio go-librespot[30003]: time="2025-11-06T00:12:34Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 00:12:34 volumio go-librespot[30003]: time="2025-11-06T00:12:34Z" 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]" Nov 06 00:12:34 volumio go-librespot[30003]: time="2025-11-06T00:12:34Z" 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]" Nov 06 00:12:34 volumio go-librespot[30003]: time="2025-11-06T00:12:34Z" 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]" Nov 06 00:12:34 volumio go-librespot[30003]: time="2025-11-06T00:12:34Z" level=info msg="zeroconf server listening on port 45865" Nov 06 00:12:34 volumio go-librespot[30003]: time="2025-11-06T00:12:34Z" level=debug msg="obtained new client token: AAA7dxOu0NQwvL0oGeqlEHXSTb6+VyscU+bMMj904jAROGCzQri2uyQsTof0pbQa70QT2qbD1L22XzC2aBiU/wDAbyIow8asfVwablDAneJoJDGg0JNdSM3ASucDM2ivQo+Qg6tbipDjL4SkNEQs/cgods8RrtWEQlXvKdBF13XIN5fhuZHpvd+tt7p4i+FJBA2nA/Ly+UObz0IimdMixSWSuODx2Dq79He34JNy5bJuPih9h3Bjq5M=" Nov 06 00:12:34 volumio go-librespot[30003]: time="2025-11-06T00:12:34Z" 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" Nov 06 00:12:34 volumio go-librespot[30003]: time="2025-11-06T00:12:34Z" level=debug msg="connected to ap-gew1.spotify.com:443" Nov 06 00:12:34 volumio go-librespot[30003]: time="2025-11-06T00:12:34Z" level=debug msg="completed keyexchange" Nov 06 00:12:34 volumio go-librespot[30003]: time="2025-11-06T00:12:34Z" level=debug msg="completed challenge" Nov 06 00:12:34 volumio go-librespot[30003]: time="2025-11-06T00:12:34Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 06 00:12:34 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 00:12:34 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 00:12:35 volumio volumio[28922]: info: Initializing connection to go-librespot Websocket Nov 06 00:12:35 volumio volumio[28922]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 00:12:38 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 254. Nov 06 00:12:38 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 00:12:38 volumio volumio[28922]: info: Initializing connection to go-librespot Websocket Nov 06 00:12:38 volumio volumio[28922]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 00:12:38 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 00:12:38 volumio go-librespot[30010]: go-librespot daemon starting... Nov 06 00:12:38 volumio go-librespot[30011]: time="2025-11-06T00:12:38Z" level=info msg="running go-librespot 0.4.0" Nov 06 00:12:38 volumio go-librespot[30011]: time="2025-11-06T00:12:38Z" level=debug msg="app state loaded" Nov 06 00:12:38 volumio go-librespot[30011]: time="2025-11-06T00:12:38Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 00:12:38 volumio go-librespot[30011]: time="2025-11-06T00:12:38Z" 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]" Nov 06 00:12:38 volumio go-librespot[30011]: time="2025-11-06T00:12:38Z" 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]" Nov 06 00:12:38 volumio go-librespot[30011]: time="2025-11-06T00:12:38Z" 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]" Nov 06 00:12:38 volumio go-librespot[30011]: time="2025-11-06T00:12:38Z" level=info msg="zeroconf server listening on port 46537" Nov 06 00:12:38 volumio go-librespot[30011]: time="2025-11-06T00:12:38Z" level=debug msg="obtained new client token: AAAUmRLA0PtJNYrmd4dddc4UZ3pV5RtiIY62z6uGuT/dJZKwefm/5CbeQpMeuvGsAh1AH4H5nu7/vEM6+yTR7xkQCeIaqhGovJV3yiJtQyqEldqK2lAluBKbubifqDVXV/t5dItbiQ+O8cQEPwaDQvTfhoatoNVdNPMm+rR+ANmPxUoH5YBgIsX7jOkvh6ytzURnXg+FR6xv3XIEJbUXI0mYPgtQscxBbbtA0lQWsNZVcDUwG0A/" Nov 06 00:12:38 volumio go-librespot[30011]: time="2025-11-06T00:12:38Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 06 00:12:38 volumio go-librespot[30011]: time="2025-11-06T00:12:38Z" level=debug msg="completed keyexchange" Nov 06 00:12:38 volumio go-librespot[30011]: time="2025-11-06T00:12:38Z" level=debug msg="completed challenge" Nov 06 00:12:38 volumio go-librespot[30011]: time="2025-11-06T00:12:38Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 06 00:12:38 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 00:12:38 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 00:12:39 volumio volumio[28922]: info: CoreCommandRouter::volumioGetState Nov 06 00:12:39 volumio volumio[28922]: info: CorePlayQueue::getTrack 0 Nov 06 00:12:39 volumio volumio[28922]: info: Listing playlists Nov 06 00:12:39 volumio volumio[28922]: info: Listing playlists Nov 06 00:12:41 volumio volumio[28922]: info: Initializing connection to go-librespot Websocket Nov 06 00:12:41 volumio volumio[28922]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 00:12:41 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 255. Nov 06 00:12:41 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 00:12:41 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 00:12:41 volumio go-librespot[30019]: go-librespot daemon starting... Nov 06 00:12:41 volumio go-librespot[30020]: time="2025-11-06T00:12:41Z" level=info msg="running go-librespot 0.4.0" Nov 06 00:12:41 volumio go-librespot[30020]: time="2025-11-06T00:12:41Z" level=debug msg="app state loaded" Nov 06 00:12:41 volumio go-librespot[30020]: time="2025-11-06T00:12:41Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 00:12:41 volumio go-librespot[30020]: time="2025-11-06T00:12:41Z" 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]" Nov 06 00:12:41 volumio go-librespot[30020]: time="2025-11-06T00:12:41Z" 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]" Nov 06 00:12:41 volumio go-librespot[30020]: time="2025-11-06T00:12:41Z" 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]" Nov 06 00:12:41 volumio go-librespot[30020]: time="2025-11-06T00:12:41Z" level=info msg="zeroconf server listening on port 33083" Nov 06 00:12:41 volumio go-librespot[30020]: time="2025-11-06T00:12:41Z" level=debug msg="obtained new client token: AACVZLyEy2lzRIBLF2BF7qLLYVdp2+xXCDDaj2rJvs6Y34D4YHwoZqp8DrfE0HwgG25dLBf4uB52N2I+j89Io+qUWOYBSuJAYN4zIRsBf1iA/Ne+K8Ucm2NWp4AI0pr8baxMSJvuOPVhVOWqAKMTmOOOgQztg63CJuzk7o+004A4hGBVCmTlcNoqEs6VpEkihjRJaoQ47RQiBWbceZmUqu1DAxyHglCxqO11NbyAPVhiGf+4cEY1aT4=" Nov 06 00:12:41 volumio go-librespot[30020]: time="2025-11-06T00:12:41Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 06 00:12:41 volumio go-librespot[30020]: time="2025-11-06T00:12:41Z" level=debug msg="completed keyexchange" Nov 06 00:12:41 volumio go-librespot[30020]: time="2025-11-06T00:12:41Z" level=debug msg="completed challenge" Nov 06 00:12:41 volumio go-librespot[30020]: time="2025-11-06T00:12:41Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 06 00:12:41 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 00:12:41 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 00:12:44 volumio volumio[28922]: info: Initializing connection to go-librespot Websocket Nov 06 00:12:44 volumio volumio[28922]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 00:12:45 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 256. Nov 06 00:12:45 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 00:12:45 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 00:12:45 volumio go-librespot[30041]: go-librespot daemon starting... Nov 06 00:12:45 volumio go-librespot[30042]: time="2025-11-06T00:12:45Z" level=info msg="running go-librespot 0.4.0" Nov 06 00:12:45 volumio go-librespot[30042]: time="2025-11-06T00:12:45Z" level=debug msg="app state loaded" Nov 06 00:12:45 volumio go-librespot[30042]: time="2025-11-06T00:12:45Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 00:12:45 volumio go-librespot[30042]: time="2025-11-06T00:12:45Z" 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]" Nov 06 00:12:45 volumio go-librespot[30042]: time="2025-11-06T00:12:45Z" 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]" Nov 06 00:12:45 volumio go-librespot[30042]: time="2025-11-06T00:12:45Z" 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]" Nov 06 00:12:45 volumio go-librespot[30042]: time="2025-11-06T00:12:45Z" level=info msg="zeroconf server listening on port 42763" Nov 06 00:12:45 volumio go-librespot[30042]: time="2025-11-06T00:12:45Z" level=debug msg="obtained new client token: AAD7y/DCDp29sft/REff6KQKZ0qUxIKsDWL93+AU8EKlYx/LRRv+V1CZaebc8npq8CqG8woEpEN9C720tqOCIdwYma8rN+G6jLq9BCmFoxXCxIHwdIyWUyrLaNSdRX7q5qvEuNexOxLIOwpah1wt/bVdWp2zU4P7P8mKCdBBAX6BPOXZ6EkrL65FvTt3avVz2WE/VqjS6oRtDCqiTfiMn88l0JLpM+VKCglHmOtjr6htarEjwcZj" Nov 06 00:12:45 volumio go-librespot[30042]: time="2025-11-06T00:12:45Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 06 00:12:45 volumio go-librespot[30042]: time="2025-11-06T00:12:45Z" level=debug msg="completed keyexchange" Nov 06 00:12:45 volumio go-librespot[30042]: time="2025-11-06T00:12:45Z" level=debug msg="completed challenge" Nov 06 00:12:45 volumio go-librespot[30042]: time="2025-11-06T00:12:45Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 06 00:12:45 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 00:12:45 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 00:12:47 volumio volumio[28922]: info: Initializing connection to go-librespot Websocket Nov 06 00:12:47 volumio volumio[28922]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 00:12:48 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 257. Nov 06 00:12:48 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 00:12:48 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 00:12:48 volumio go-librespot[30049]: go-librespot daemon starting... Nov 06 00:12:48 volumio go-librespot[30050]: time="2025-11-06T00:12:48Z" level=info msg="running go-librespot 0.4.0" Nov 06 00:12:48 volumio go-librespot[30050]: time="2025-11-06T00:12:48Z" level=debug msg="app state loaded" Nov 06 00:12:48 volumio go-librespot[30050]: time="2025-11-06T00:12:48Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 00:12:48 volumio go-librespot[30050]: time="2025-11-06T00:12:48Z" 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]" Nov 06 00:12:48 volumio go-librespot[30050]: time="2025-11-06T00:12:48Z" 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]" Nov 06 00:12:48 volumio go-librespot[30050]: time="2025-11-06T00:12:48Z" 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]" Nov 06 00:12:48 volumio go-librespot[30050]: time="2025-11-06T00:12:48Z" level=info msg="zeroconf server listening on port 35741" Nov 06 00:12:48 volumio go-librespot[30050]: time="2025-11-06T00:12:48Z" level=debug msg="obtained new client token: AAA1SIRWGItHtuj9MA8Qrn7sLXx7xwdFy25flAI7zbwsby9Pj4uBHmxzb60dvZmW8ipF1i4lveHAdSZUwkxPeYFtZPiy5khbyWgp6+l/oiawUzDeOe///+IcHDTl7fE6ujxUYboBVeMx6NIreNoH+aORaUtm1qDnzeF5AcNzcP0LZgCwy4ndITRrJiAV9nOtLtp6sejfhlfDHf0WDhTsYzIrQ64Ob2PSULdNeIqppB9t9BkL327xcc0=" Nov 06 00:12:48 volumio go-librespot[30050]: time="2025-11-06T00:12:48Z" 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" Nov 06 00:12:48 volumio go-librespot[30050]: time="2025-11-06T00:12:48Z" level=debug msg="connected to ap-gew1.spotify.com:443" Nov 06 00:12:48 volumio go-librespot[30050]: time="2025-11-06T00:12:48Z" level=debug msg="completed keyexchange" Nov 06 00:12:48 volumio go-librespot[30050]: time="2025-11-06T00:12:48Z" level=debug msg="completed challenge" Nov 06 00:12:48 volumio go-librespot[30050]: time="2025-11-06T00:12:48Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 06 00:12:48 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 00:12:48 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 00:12:49 volumio volumio[28922]: info: CoreCommandRouter::volumioGetState Nov 06 00:12:49 volumio volumio[28922]: info: CorePlayQueue::getTrack 0 Nov 06 00:12:50 volumio volumio[28922]: info: Initializing connection to go-librespot Websocket Nov 06 00:12:50 volumio volumio[28922]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 00:12:52 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 258. Nov 06 00:12:52 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 00:12:52 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 00:12:52 volumio go-librespot[30061]: go-librespot daemon starting... Nov 06 00:12:52 volumio go-librespot[30062]: time="2025-11-06T00:12:52Z" level=info msg="running go-librespot 0.4.0" Nov 06 00:12:52 volumio go-librespot[30062]: time="2025-11-06T00:12:52Z" level=debug msg="app state loaded" Nov 06 00:12:52 volumio go-librespot[30062]: time="2025-11-06T00:12:52Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 00:12:52 volumio go-librespot[30062]: time="2025-11-06T00:12:52Z" 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]" Nov 06 00:12:52 volumio go-librespot[30062]: time="2025-11-06T00:12:52Z" 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]" Nov 06 00:12:52 volumio go-librespot[30062]: time="2025-11-06T00:12:52Z" 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]" Nov 06 00:12:52 volumio go-librespot[30062]: time="2025-11-06T00:12:52Z" level=info msg="zeroconf server listening on port 42909" Nov 06 00:12:52 volumio go-librespot[30062]: time="2025-11-06T00:12:52Z" level=debug msg="obtained new client token: AADy304b65tYO6bvk7iDK39jI1D3UbnFEOtswgjOz5tigtaPicP6DC8Xg6KsP2y+ySo+LKKf2CP4tJlOY9MZyAPuUlX2pYOppnL14/w+7FamlR8mVyFUYV/47R0f/08Hf+QZUR9aHzVrt7Slq9oDrztopAW1SeAqSRr5PrvH+iY3sUFs7NRaScWJT/oDrd3oSl7b++USUB9L5OxJeQR9sdU52ThVQHDws1mhf5vNRksiZG5hzvLs" Nov 06 00:12:52 volumio go-librespot[30062]: time="2025-11-06T00:12:52Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 06 00:12:52 volumio go-librespot[30062]: time="2025-11-06T00:12:52Z" level=debug msg="completed keyexchange" Nov 06 00:12:52 volumio go-librespot[30062]: time="2025-11-06T00:12:52Z" level=debug msg="completed challenge" Nov 06 00:12:52 volumio go-librespot[30062]: time="2025-11-06T00:12:52Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 06 00:12:52 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 00:12:52 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 00:12:53 volumio volumio[28922]: info: Initializing connection to go-librespot Websocket Nov 06 00:12:53 volumio volumio[28922]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 00:12:55 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 259. Nov 06 00:12:55 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 00:12:55 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 00:12:55 volumio go-librespot[30083]: go-librespot daemon starting... Nov 06 00:12:55 volumio go-librespot[30084]: time="2025-11-06T00:12:55Z" level=info msg="running go-librespot 0.4.0" Nov 06 00:12:55 volumio go-librespot[30084]: time="2025-11-06T00:12:55Z" level=debug msg="app state loaded" Nov 06 00:12:55 volumio go-librespot[30084]: time="2025-11-06T00:12:55Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 00:12:55 volumio go-librespot[30084]: time="2025-11-06T00:12:55Z" 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]" Nov 06 00:12:55 volumio go-librespot[30084]: time="2025-11-06T00:12:55Z" 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]" Nov 06 00:12:55 volumio go-librespot[30084]: time="2025-11-06T00:12:55Z" 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]" Nov 06 00:12:55 volumio go-librespot[30084]: time="2025-11-06T00:12:55Z" level=info msg="zeroconf server listening on port 38297" Nov 06 00:12:55 volumio go-librespot[30084]: time="2025-11-06T00:12:55Z" level=debug msg="obtained new client token: AAAWu0oFX+XFi7l4XTVYTxauZC/FOrBumtA4HoeFPm1oEEOh5NrtQQ2bde9UB+EVa66Y0SLCI4i0tTuTr1qA+qL2mplB94jSrUYb4ynWyKlz2F9hojh6YfTph8YTkX2MDGIcevmwfGr8Fj89syZKvowObQaWvn8UDslUHVPiTGwooIrLHd7Se71tM37wmFZqW/DPZ1ESz9ME8qTnoYiwJCr1Fk9Q2IQoTMyP7FlOPb9xTAeD9wdad24=" Nov 06 00:12:55 volumio go-librespot[30084]: time="2025-11-06T00:12:55Z" 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" Nov 06 00:12:55 volumio go-librespot[30084]: time="2025-11-06T00:12:55Z" level=debug msg="connected to ap-gew1.spotify.com:443" Nov 06 00:12:55 volumio go-librespot[30084]: time="2025-11-06T00:12:55Z" level=debug msg="completed keyexchange" Nov 06 00:12:55 volumio go-librespot[30084]: time="2025-11-06T00:12:55Z" level=debug msg="completed challenge" Nov 06 00:12:55 volumio go-librespot[30084]: time="2025-11-06T00:12:55Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 06 00:12:55 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 00:12:55 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 00:12:56 volumio volumio[28922]: info: Initializing connection to go-librespot Websocket Nov 06 00:12:56 volumio volumio[28922]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 00:12:59 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 260. Nov 06 00:12:59 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 00:12:59 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 00:12:59 volumio go-librespot[30092]: go-librespot daemon starting... Nov 06 00:12:59 volumio go-librespot[30093]: time="2025-11-06T00:12:59Z" level=info msg="running go-librespot 0.4.0" Nov 06 00:12:59 volumio go-librespot[30093]: time="2025-11-06T00:12:59Z" level=debug msg="app state loaded" Nov 06 00:12:59 volumio go-librespot[30093]: time="2025-11-06T00:12:59Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 00:12:59 volumio volumio[28922]: info: Initializing connection to go-librespot Websocket Nov 06 00:12:59 volumio go-librespot[30093]: time="2025-11-06T00:12:59Z" level=debug msg="new websocket client" Nov 06 00:12:59 volumio volumio[28922]: info: Connection to go-librespot Websocket established Nov 06 00:12:59 volumio go-librespot[30093]: time="2025-11-06T00:12:59Z" 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]" Nov 06 00:12:59 volumio go-librespot[30093]: time="2025-11-06T00:12:59Z" 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]" Nov 06 00:12:59 volumio go-librespot[30093]: time="2025-11-06T00:12:59Z" 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]" Nov 06 00:12:59 volumio go-librespot[30093]: time="2025-11-06T00:12:59Z" level=info msg="zeroconf server listening on port 34267" Nov 06 00:12:59 volumio volumio[28922]: info: CoreCommandRouter::volumioGetState Nov 06 00:12:59 volumio volumio[28922]: info: CorePlayQueue::getTrack 0 Nov 06 00:12:59 volumio volumio[28922]: info: Listing playlists Nov 06 00:12:59 volumio volumio[28922]: info: Listing playlists Nov 06 00:12:59 volumio go-librespot[30093]: time="2025-11-06T00:12:59Z" level=debug msg="obtained new client token: AADGFt0TKgwgl8TT6gNu+KmTmfD9Mmc3S2UzOw1oBqm9X2nXJV6vw9riUNG4KjfP7ZCcFdjN9/tMlj6HVQHSqBxeCyeEXziEg8kipewj0ZXpDei9quAHiF/ajCCGviCdqvIZ8JCX29EwXWE3zrBxdg8KSxyIKxfSKUn+vc6YCt9aCU/ptJindJ2MJvtR9a9jcGgkU2+0b0DB94DvZWlPdYVWwiqO3o1NSoe/nIdtB5Cf13XIf25U" Nov 06 00:12:59 volumio go-librespot[30093]: time="2025-11-06T00:12:59Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 06 00:12:59 volumio go-librespot[30093]: time="2025-11-06T00:12:59Z" level=debug msg="completed keyexchange" Nov 06 00:12:59 volumio go-librespot[30093]: time="2025-11-06T00:12:59Z" level=debug msg="completed challenge" Nov 06 00:12:59 volumio go-librespot[30093]: time="2025-11-06T00:12:59Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 06 00:12:59 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 00:12:59 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 00:12:59 volumio volumio[28922]: info: Connection to go-librespot Websocket closed Nov 06 00:13:02 volumio volumio[28922]: info: Getting Spotify volume Nov 06 00:13:02 volumio volumio[28922]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 06 00:13:02 volumio volumio[28922]: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 00:13:02 volumio volumio[28922]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Nov 06 00:13:02 volumio volumio[28922]: errno: -111, Nov 06 00:13:02 volumio volumio[28922]: code: 'ECONNREFUSED', Nov 06 00:13:02 volumio volumio[28922]: syscall: 'connect', Nov 06 00:13:02 volumio volumio[28922]: address: '127.0.0.1', Nov 06 00:13:02 volumio volumio[28922]: port: 9879, Nov 06 00:13:02 volumio volumio[28922]: response: undefined Nov 06 00:13:02 volumio volumio[28922]: } Nov 06 00:13:02 volumio volumio[28922]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 06 00:13:02 volumio sudo[30114]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-11-06 00:12' Nov 06 00:13:02 volumio sudo[30114]: 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"