Oct 27 07:37:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 365.
Oct 27 07:37:00 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Oct 27 07:37:00 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Oct 27 07:37:00 volumio go-librespot[18605]: go-librespot daemon starting...
Oct 27 07:37:00 volumio go-librespot[18606]: time="2025-10-27T07:37:00Z" level=info msg="running go-librespot 0.4.0"
Oct 27 07:37:00 volumio go-librespot[18606]: time="2025-10-27T07:37:00Z" level=debug msg="app state loaded"
Oct 27 07:37:00 volumio go-librespot[18606]: time="2025-10-27T07:37:00Z" level=info msg="api server listening on 127.0.0.1:9879"
Oct 27 07:37:00 volumio go-librespot[18606]: time="2025-10-27T07:37:00Z" 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 27 07:37:00 volumio go-librespot[18606]: time="2025-10-27T07:37:00Z" 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 27 07:37:00 volumio go-librespot[18606]: time="2025-10-27T07:37:00Z" 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 27 07:37:00 volumio go-librespot[18606]: time="2025-10-27T07:37:00Z" level=info msg="zeroconf server listening on port 39043"
Oct 27 07:37:00 volumio go-librespot[18606]: time="2025-10-27T07:37:00Z" level=debug msg="obtained new client token: AADRc2W0c+mVIMMFq/Ux7iuhWvNGIXD/PyPgh+BoxeYYpLyyVyITlJnSK/+mWCksVPysauIE1CEtYqXqUJ/R30yv1FdZE+7gd8uE+iqNuaNke/I7ZFiI0j4ByTDzxax40wXlqkiON4AHrpnCOdfEPubnPPh0/1GOqaWb+3Eh0tMtWj8llnyO5d0HpiYNSAW2KBFxDF01XBTIV1reoa2jzKWKCen2PYG4OgZZMCvzKBmkkizQke4f"
Oct 27 07:37:00 volumio go-librespot[18606]: time="2025-10-27T07:37:00Z" level=debug msg="connected to ap-gew1.spotify.com:4070"
Oct 27 07:37:00 volumio go-librespot[18606]: time="2025-10-27T07:37:00Z" level=debug msg="completed keyexchange"
Oct 27 07:37:00 volumio go-librespot[18606]: time="2025-10-27T07:37:00Z" level=debug msg="completed challenge"
Oct 27 07:37:00 volumio go-librespot[18606]: time="2025-10-27T07:37:00Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction "
Oct 27 07:37:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Oct 27 07:37:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Oct 27 07:37:01 volumio volumio[17234]: info: Initializing connection to go-librespot Websocket
Oct 27 07:37:01 volumio volumio[17234]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Oct 27 07:37:01 volumio kernel: nfs: server 192.168.1.3 not responding, timed out
Oct 27 07:37:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 366.
Oct 27 07:37:03 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Oct 27 07:37:03 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Oct 27 07:37:03 volumio go-librespot[18613]: go-librespot daemon starting...
Oct 27 07:37:03 volumio go-librespot[18614]: time="2025-10-27T07:37:03Z" level=info msg="running go-librespot 0.4.0"
Oct 27 07:37:03 volumio go-librespot[18614]: time="2025-10-27T07:37:03Z" level=debug msg="app state loaded"
Oct 27 07:37:03 volumio go-librespot[18614]: time="2025-10-27T07:37:03Z" level=info msg="api server listening on 127.0.0.1:9879"
Oct 27 07:37:03 volumio go-librespot[18614]: time="2025-10-27T07:37:03Z" 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 27 07:37:03 volumio go-librespot[18614]: time="2025-10-27T07:37:03Z" 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 27 07:37:03 volumio go-librespot[18614]: time="2025-10-27T07:37:03Z" 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 27 07:37:03 volumio go-librespot[18614]: time="2025-10-27T07:37:03Z" level=info msg="zeroconf server listening on port 46421"
Oct 27 07:37:03 volumio go-librespot[18614]: time="2025-10-27T07:37:03Z" level=debug msg="obtained new client token: AAC99iU2lXLsPqAtqoFC6/MtCRYMCnGC1G33jsxzDfzCZQi5VPMHqKpgY3O4+a2ILIeluZKtHU+a4DpyQvrQTorcZ3sqFS7u5dwJtNNbvQKWpvpP3F/zDIc5GboctevM9gFr4yfW3OPqt7C/DuiXxvqMi3phVcSg7ehlwPzw5hOXd9NSzqKk6CGr4NAAnRV7OFztdgbPEwoKz9LsDIroNwu263Hc0wK1NMVRY9EcOKmrjwTZZCtpjMc="
Oct 27 07:37:03 volumio go-librespot[18614]: time="2025-10-27T07:37:03Z" level=debug msg="connected to ap-gew1.spotify.com:4070"
Oct 27 07:37:03 volumio go-librespot[18614]: time="2025-10-27T07:37:03Z" level=debug msg="completed keyexchange"
Oct 27 07:37:03 volumio go-librespot[18614]: time="2025-10-27T07:37:03Z" level=debug msg="completed challenge"
Oct 27 07:37:03 volumio go-librespot[18614]: time="2025-10-27T07:37:03Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction "
Oct 27 07:37:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Oct 27 07:37:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Oct 27 07:37:04 volumio volumio[17234]: info: Initializing connection to go-librespot Websocket
Oct 27 07:37:04 volumio volumio[17234]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Oct 27 07:37:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 367.
Oct 27 07:37:07 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Oct 27 07:37:07 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Oct 27 07:37:07 volumio go-librespot[18621]: go-librespot daemon starting...
Oct 27 07:37:07 volumio go-librespot[18622]: time="2025-10-27T07:37:07Z" level=info msg="running go-librespot 0.4.0"
Oct 27 07:37:07 volumio go-librespot[18622]: time="2025-10-27T07:37:07Z" level=debug msg="app state loaded"
Oct 27 07:37:07 volumio go-librespot[18622]: time="2025-10-27T07:37:07Z" level=info msg="api server listening on 127.0.0.1:9879"
Oct 27 07:37:07 volumio go-librespot[18622]: time="2025-10-27T07:37:07Z" 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 27 07:37:07 volumio go-librespot[18622]: time="2025-10-27T07:37:07Z" 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 27 07:37:07 volumio go-librespot[18622]: time="2025-10-27T07:37:07Z" 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 27 07:37:07 volumio go-librespot[18622]: time="2025-10-27T07:37:07Z" level=info msg="zeroconf server listening on port 40483"
Oct 27 07:37:07 volumio go-librespot[18622]: time="2025-10-27T07:37:07Z" level=debug msg="obtained new client token: AAAEao2U0F1oTx8ShWgANDAKX03G5sHe/R3eXUMHjc63qnb+tivIOerk8TBnyi5jdKqLJR7460IQY7fxNC03akFqUo8ia/b4XBwW3dUN5jX/ATVHpSOu0xo6OYa1tlPYUIrv0bRhH8cBCepuTeLiTAd2l6kI/GQmXI0uL8SeKHDV4nhgW/ok/dYGopSaNIket7mQj9rnJsxJLzGjq4QZ+OVrCrZEh5mVB30reFVVV+7CWjhBOKXQ"
Oct 27 07:37:07 volumio go-librespot[18622]: time="2025-10-27T07:37:07Z" level=debug msg="connected to ap-gew1.spotify.com:4070"
Oct 27 07:37:07 volumio go-librespot[18622]: time="2025-10-27T07:37:07Z" level=debug msg="completed keyexchange"
Oct 27 07:37:07 volumio go-librespot[18622]: time="2025-10-27T07:37:07Z" level=debug msg="completed challenge"
Oct 27 07:37:07 volumio go-librespot[18622]: time="2025-10-27T07:37:07Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction "
Oct 27 07:37:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Oct 27 07:37:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Oct 27 07:37:07 volumio volumio[17234]: info: Initializing connection to go-librespot Websocket
Oct 27 07:37:07 volumio volumio[17234]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Oct 27 07:37:07 volumio kernel: nfs: server 192.168.1.3 not responding, timed out
Oct 27 07:37:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 368.
Oct 27 07:37:10 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Oct 27 07:37:10 volumio volumio[17234]: info: Initializing connection to go-librespot Websocket
Oct 27 07:37:10 volumio volumio[17234]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Oct 27 07:37:10 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Oct 27 07:37:10 volumio go-librespot[18644]: go-librespot daemon starting...
Oct 27 07:37:10 volumio go-librespot[18645]: time="2025-10-27T07:37:10Z" level=info msg="running go-librespot 0.4.0"
Oct 27 07:37:10 volumio go-librespot[18645]: time="2025-10-27T07:37:10Z" level=debug msg="app state loaded"
Oct 27 07:37:10 volumio go-librespot[18645]: time="2025-10-27T07:37:10Z" level=info msg="api server listening on 127.0.0.1:9879"
Oct 27 07:37:10 volumio go-librespot[18645]: time="2025-10-27T07:37:10Z" 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 27 07:37:10 volumio go-librespot[18645]: time="2025-10-27T07:37:10Z" 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 27 07:37:10 volumio go-librespot[18645]: time="2025-10-27T07:37:10Z" 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 27 07:37:10 volumio go-librespot[18645]: time="2025-10-27T07:37:10Z" level=info msg="zeroconf server listening on port 33329"
Oct 27 07:37:10 volumio go-librespot[18645]: time="2025-10-27T07:37:10Z" level=debug msg="obtained new client token: AABQ1AROlDldZc4dIh+FxafEiFKxUa8cAgIw2PjIPpqqqhi/cyw+4sKkgJvsC1JKSaiKFxk/oUlzroIGmMddTVqYzb+ysi/7RP9rPk1P0u+ltS7Aj6lu/cEoIdJQEDyrtdguwtxwnnvV69R+a//CjeIGmd4ORFAmSR4F+WtfmUN2czh/UpyT2dQ+1fL7UA1kcI1xXiHAxxiTL7gBQ2W4yFVTQ8Q7LOrOJRUojtMW6vBrHDpfo4XCgkY="
Oct 27 07:37:10 volumio go-librespot[18645]: time="2025-10-27T07:37:10Z" level=debug msg="connected to ap-gew1.spotify.com:4070"
Oct 27 07:37:10 volumio go-librespot[18645]: time="2025-10-27T07:37:10Z" level=debug msg="completed keyexchange"
Oct 27 07:37:10 volumio go-librespot[18645]: time="2025-10-27T07:37:10Z" level=debug msg="completed challenge"
Oct 27 07:37:10 volumio go-librespot[18645]: time="2025-10-27T07:37:10Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction "
Oct 27 07:37:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Oct 27 07:37:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Oct 27 07:37:13 volumio volumio[17234]: info: Initializing connection to go-librespot Websocket
Oct 27 07:37:13 volumio volumio[17234]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Oct 27 07:37:14 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 369.
Oct 27 07:37:14 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Oct 27 07:37:14 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Oct 27 07:37:14 volumio go-librespot[18652]: go-librespot daemon starting...
Oct 27 07:37:14 volumio go-librespot[18653]: time="2025-10-27T07:37:14Z" level=info msg="running go-librespot 0.4.0"
Oct 27 07:37:14 volumio go-librespot[18653]: time="2025-10-27T07:37:14Z" level=debug msg="app state loaded"
Oct 27 07:37:14 volumio go-librespot[18653]: time="2025-10-27T07:37:14Z" level=info msg="api server listening on 127.0.0.1:9879"
Oct 27 07:37:14 volumio go-librespot[18653]: time="2025-10-27T07:37:14Z" 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 27 07:37:14 volumio go-librespot[18653]: time="2025-10-27T07:37:14Z" 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 27 07:37:14 volumio go-librespot[18653]: time="2025-10-27T07:37:14Z" 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 27 07:37:14 volumio go-librespot[18653]: time="2025-10-27T07:37:14Z" level=info msg="zeroconf server listening on port 43599"
Oct 27 07:37:14 volumio go-librespot[18653]: time="2025-10-27T07:37:14Z" level=debug msg="obtained new client token: AACjimVP7EeO/256qkG5OhavGcf5V1tHLEHl6tWFTIBVWaNNsdirz8UkPE4sAuARJ3/lv+bAazW8Hb9gydPuPQe8TSU6aKtPQDdoHn0+7G04hFVfPkAyQhxdLYG/g3JBROdCGw/crbbW6pJEoOCOw9dZ1lsHIgDtHRvkPM5STWs8iAS128n6s8xS5W4LNh/dVCp+6ozXYcEZzPfaCO5PhzE2AoVk0U7hWnElN2vs45/7jkVkbMKZ"
Oct 27 07:37:14 volumio go-librespot[18653]: time="2025-10-27T07:37:14Z" level=debug msg="connected to ap-gew1.spotify.com:4070"
Oct 27 07:37:14 volumio go-librespot[18653]: time="2025-10-27T07:37:14Z" level=debug msg="completed keyexchange"
Oct 27 07:37:14 volumio go-librespot[18653]: time="2025-10-27T07:37:14Z" level=debug msg="completed challenge"
Oct 27 07:37:14 volumio go-librespot[18653]: time="2025-10-27T07:37:14Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction "
Oct 27 07:37:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Oct 27 07:37:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Oct 27 07:37:16 volumio volumio[17234]: info: Initializing connection to go-librespot Websocket
Oct 27 07:37:16 volumio volumio[17234]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Oct 27 07:37:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 370.
Oct 27 07:37:17 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Oct 27 07:37:17 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Oct 27 07:37:17 volumio go-librespot[18664]: go-librespot daemon starting...
Oct 27 07:37:17 volumio go-librespot[18665]: time="2025-10-27T07:37:17Z" level=info msg="running go-librespot 0.4.0"
Oct 27 07:37:17 volumio go-librespot[18665]: time="2025-10-27T07:37:17Z" level=debug msg="app state loaded"
Oct 27 07:37:17 volumio go-librespot[18665]: time="2025-10-27T07:37:17Z" level=info msg="api server listening on 127.0.0.1:9879"
Oct 27 07:37:17 volumio go-librespot[18665]: time="2025-10-27T07:37: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]"
Oct 27 07:37:17 volumio go-librespot[18665]: time="2025-10-27T07:37: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]"
Oct 27 07:37:17 volumio go-librespot[18665]: time="2025-10-27T07:37: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]"
Oct 27 07:37:17 volumio go-librespot[18665]: time="2025-10-27T07:37:17Z" level=info msg="zeroconf server listening on port 40049"
Oct 27 07:37:17 volumio go-librespot[18665]: time="2025-10-27T07:37:17Z" level=debug msg="obtained new client token: AACRL1U5Jp+G+dJSbqUNChg5b97qI3ymkYudMLJI4XnM/NuIfUXxYEYygAcrTvCHEdRLXU+jA1a0UmtkCoKbgPZS2qu+OHOYx3y8u0BFDB50//VBgRIjXw8xCpzS8kBpyuTUZGX8rkybHfD5VhR64g1hhHXodljBwW+LzRueNKyf3ZSFBVrOx5MFTdH45ixwto4XVImsXb4k0QMLIfqwM7b84lIeSlM7BwWHq22RlUy6DiNuk61Ohm8="
Oct 27 07:37:17 volumio go-librespot[18665]: time="2025-10-27T07:37:17Z" level=debug msg="connected to ap-gew1.spotify.com:4070"
Oct 27 07:37:17 volumio go-librespot[18665]: time="2025-10-27T07:37:17Z" level=debug msg="completed keyexchange"
Oct 27 07:37:17 volumio go-librespot[18665]: time="2025-10-27T07:37:17Z" level=debug msg="completed challenge"
Oct 27 07:37:17 volumio go-librespot[18665]: time="2025-10-27T07:37:17Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction "
Oct 27 07:37:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Oct 27 07:37:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Oct 27 07:37:19 volumio volumio[17234]: info: Initializing connection to go-librespot Websocket
Oct 27 07:37:19 volumio volumio[17234]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Oct 27 07:37:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 371.
Oct 27 07:37:21 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Oct 27 07:37:21 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Oct 27 07:37:21 volumio go-librespot[18686]: go-librespot daemon starting...
Oct 27 07:37:21 volumio go-librespot[18687]: time="2025-10-27T07:37:21Z" level=info msg="running go-librespot 0.4.0"
Oct 27 07:37:21 volumio go-librespot[18687]: time="2025-10-27T07:37:21Z" level=debug msg="app state loaded"
Oct 27 07:37:21 volumio go-librespot[18687]: time="2025-10-27T07:37:21Z" level=info msg="api server listening on 127.0.0.1:9879"
Oct 27 07:37:21 volumio go-librespot[18687]: time="2025-10-27T07:37:21Z" 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 27 07:37:21 volumio go-librespot[18687]: time="2025-10-27T07:37:21Z" 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 27 07:37:21 volumio go-librespot[18687]: time="2025-10-27T07:37:21Z" 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 27 07:37:21 volumio go-librespot[18687]: time="2025-10-27T07:37:21Z" level=info msg="zeroconf server listening on port 37797"
Oct 27 07:37:21 volumio go-librespot[18687]: time="2025-10-27T07:37:21Z" level=debug msg="obtained new client token: AABLf+4gv7Ig1wKCV4vMB2im/i8fPYKlA9v2U9QDP2Nr4hyvBDZi7FlZ70XPNpFeQLerVf4s5hmjc8DMiybIhyOgsAeUJJKS4bHKroRtdzvsGsVCYwZjgTNWw/Vk+E0dU3OPCb1JHduDt5vIACU1RBwlKgdATKOPAvBVFlaJGmWKonR7JJ0ig6mX11egQbGfZgK/6EcCKdo4iOIN/rHPIv5HJF7C2pnQigphtk0uUiu7OKLrlAEt"
Oct 27 07:37:21 volumio go-librespot[18687]: time="2025-10-27T07:37:21Z" level=debug msg="connected to ap-gew1.spotify.com:4070"
Oct 27 07:37:21 volumio go-librespot[18687]: time="2025-10-27T07:37:21Z" level=debug msg="completed keyexchange"
Oct 27 07:37:21 volumio go-librespot[18687]: time="2025-10-27T07:37:21Z" level=debug msg="completed challenge"
Oct 27 07:37:21 volumio go-librespot[18687]: time="2025-10-27T07:37:21Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction "
Oct 27 07:37:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Oct 27 07:37:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Oct 27 07:37:22 volumio volumio[17234]: info: Initializing connection to go-librespot Websocket
Oct 27 07:37:22 volumio volumio[17234]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Oct 27 07:37:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 372.
Oct 27 07:37:24 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Oct 27 07:37:24 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Oct 27 07:37:24 volumio go-librespot[18694]: go-librespot daemon starting...
Oct 27 07:37:24 volumio go-librespot[18695]: time="2025-10-27T07:37:24Z" level=info msg="running go-librespot 0.4.0"
Oct 27 07:37:24 volumio go-librespot[18695]: time="2025-10-27T07:37:24Z" level=debug msg="app state loaded"
Oct 27 07:37:24 volumio go-librespot[18695]: time="2025-10-27T07:37:24Z" level=info msg="api server listening on 127.0.0.1:9879"
Oct 27 07:37:24 volumio go-librespot[18695]: time="2025-10-27T07:37: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]"
Oct 27 07:37:24 volumio go-librespot[18695]: time="2025-10-27T07:37: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]"
Oct 27 07:37:24 volumio go-librespot[18695]: time="2025-10-27T07:37: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]"
Oct 27 07:37:24 volumio go-librespot[18695]: time="2025-10-27T07:37:24Z" level=info msg="zeroconf server listening on port 45541"
Oct 27 07:37:24 volumio go-librespot[18695]: time="2025-10-27T07:37:24Z" level=debug msg="obtained new client token: AACZUKkO/0RFNoD4O3SAmEeqW6t3/YGyPaJ5IxYiojIQl8voF3CZ8DBqAXdYb1HLh5nw/+mcsFrWVB60W1lzuD8O7UYLz53d0JpBk/cCy2cKhgbL4r2ddnOIz3jxfCL3Fc7NctQSmQjTuZoO7Jak9bulBF9zP7QkTFkj+at+m8K5c4AtCNN5y2MOFUnK+RycL2d4NXS2/OCIx7lJ0DOsoC9MKwNjgRm/+4BfqmB0uFktvMnNDR3DZd8="
Oct 27 07:37:24 volumio go-librespot[18695]: time="2025-10-27T07:37:24Z" 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 27 07:37:24 volumio go-librespot[18695]: time="2025-10-27T07:37:24Z" level=debug msg="connected to ap-gew1.spotify.com:443"
Oct 27 07:37:24 volumio go-librespot[18695]: time="2025-10-27T07:37:24Z" level=debug msg="completed keyexchange"
Oct 27 07:37:24 volumio go-librespot[18695]: time="2025-10-27T07:37:24Z" level=debug msg="completed challenge"
Oct 27 07:37:24 volumio go-librespot[18695]: time="2025-10-27T07:37:24Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction "
Oct 27 07:37:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Oct 27 07:37:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Oct 27 07:37:25 volumio volumio[17234]: info: Initializing connection to go-librespot Websocket
Oct 27 07:37:25 volumio volumio[17234]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Oct 27 07:37:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 373.
Oct 27 07:37:28 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Oct 27 07:37:28 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Oct 27 07:37:28 volumio go-librespot[18716]: go-librespot daemon starting...
Oct 27 07:37:28 volumio go-librespot[18717]: time="2025-10-27T07:37:28Z" level=info msg="running go-librespot 0.4.0"
Oct 27 07:37:28 volumio go-librespot[18717]: time="2025-10-27T07:37:28Z" level=debug msg="app state loaded"
Oct 27 07:37:28 volumio go-librespot[18717]: time="2025-10-27T07:37:28Z" level=info msg="api server listening on 127.0.0.1:9879"
Oct 27 07:37:28 volumio go-librespot[18717]: time="2025-10-27T07:37:28Z" 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 27 07:37:28 volumio go-librespot[18717]: time="2025-10-27T07:37:28Z" 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 27 07:37:28 volumio go-librespot[18717]: time="2025-10-27T07:37:28Z" 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 27 07:37:28 volumio go-librespot[18717]: time="2025-10-27T07:37:28Z" level=info msg="zeroconf server listening on port 40359"
Oct 27 07:37:28 volumio go-librespot[18717]: time="2025-10-27T07:37:28Z" level=debug msg="obtained new client token: AAB1jj1tMJ7Ie6fhzIpr9ixYz1LXZVoRt548p9/Bi666ayNS4xu9hAu0hWBS6pmBFbeTOTsTBY8CO5b8w9onZ457OCsLY1ft9c2tjA9QIU/4mWYTkkH8wjJRDoaYCMzHJdz8z0EfrX/8zD0c9yMJBNk/xu8M+RIvfuMWs7zqtsDztcVwVSKY3xwnNP0vNa+Esud9+1VbSAUK3jV82fIXHBGad2r0fea/KMUmXX7vwQm7dH3TVv0k"
Oct 27 07:37:28 volumio go-librespot[18717]: time="2025-10-27T07:37:28Z" level=debug msg="connected to ap-gew1.spotify.com:4070"
Oct 27 07:37:28 volumio go-librespot[18717]: time="2025-10-27T07:37:28Z" level=debug msg="completed keyexchange"
Oct 27 07:37:28 volumio go-librespot[18717]: time="2025-10-27T07:37:28Z" level=debug msg="completed challenge"
Oct 27 07:37:28 volumio go-librespot[18717]: time="2025-10-27T07:37:28Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction "
Oct 27 07:37:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Oct 27 07:37:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Oct 27 07:37:28 volumio volumio[17234]: info: Initializing connection to go-librespot Websocket
Oct 27 07:37:28 volumio volumio[17234]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Oct 27 07:37:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 374.
Oct 27 07:37:31 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Oct 27 07:37:31 volumio volumio[17234]: info: Initializing connection to go-librespot Websocket
Oct 27 07:37:31 volumio volumio[17234]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Oct 27 07:37:31 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Oct 27 07:37:31 volumio go-librespot[18725]: go-librespot daemon starting...
Oct 27 07:37:31 volumio go-librespot[18726]: time="2025-10-27T07:37:31Z" level=info msg="running go-librespot 0.4.0"
Oct 27 07:37:31 volumio go-librespot[18726]: time="2025-10-27T07:37:31Z" level=debug msg="app state loaded"
Oct 27 07:37:31 volumio go-librespot[18726]: time="2025-10-27T07:37:31Z" level=info msg="api server listening on 127.0.0.1:9879"
Oct 27 07:37:31 volumio go-librespot[18726]: time="2025-10-27T07:37: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-gae2.spotify.com:80]"
Oct 27 07:37:31 volumio go-librespot[18726]: time="2025-10-27T07:37:31Z" 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 27 07:37:31 volumio go-librespot[18726]: time="2025-10-27T07:37:31Z" 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 27 07:37:31 volumio go-librespot[18726]: time="2025-10-27T07:37:31Z" level=info msg="zeroconf server listening on port 33843"
Oct 27 07:37:31 volumio go-librespot[18726]: time="2025-10-27T07:37:31Z" level=debug msg="obtained new client token: AADbrNsTFd2qdqUCXcW1Jv9bY2mKAKVdjBocWEG7zNhAh5vA2/FpI0duvoWECBAvtvbUZqDY8yywgZcPRHiHhYWFO05pIbOgKjnzBSVFctNFLtjaZA8XQim+TR40nseMXH2aNtBzZ11hh+kqLk13EC5cvQvKblCxS34qokiWHpihWcyDUqT+DH7las+N02uKqnTHpWte1zVFLmZ9/O4E9bDqVIdKbsZTK8h+GoApdxUOX5alucnumJg="
Oct 27 07:37:31 volumio go-librespot[18726]: time="2025-10-27T07:37:31Z" level=debug msg="connected to ap-gew1.spotify.com:4070"
Oct 27 07:37:31 volumio go-librespot[18726]: time="2025-10-27T07:37:31Z" level=debug msg="completed keyexchange"
Oct 27 07:37:31 volumio go-librespot[18726]: time="2025-10-27T07:37:31Z" level=debug msg="completed challenge"
Oct 27 07:37:31 volumio go-librespot[18726]: time="2025-10-27T07:37:31Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction "
Oct 27 07:37:31 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Oct 27 07:37:31 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Oct 27 07:37:34 volumio volumio[17234]: info: Initializing connection to go-librespot Websocket
Oct 27 07:37:34 volumio volumio[17234]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Oct 27 07:37:35 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 375.
Oct 27 07:37:35 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Oct 27 07:37:35 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Oct 27 07:37:35 volumio go-librespot[18736]: go-librespot daemon starting...
Oct 27 07:37:35 volumio go-librespot[18737]: time="2025-10-27T07:37:35Z" level=info msg="running go-librespot 0.4.0"
Oct 27 07:37:35 volumio go-librespot[18737]: time="2025-10-27T07:37:35Z" level=debug msg="app state loaded"
Oct 27 07:37:35 volumio go-librespot[18737]: time="2025-10-27T07:37:35Z" level=info msg="api server listening on 127.0.0.1:9879"
Oct 27 07:37:35 volumio go-librespot[18737]: time="2025-10-27T07:37:35Z" 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 27 07:37:35 volumio go-librespot[18737]: time="2025-10-27T07:37:35Z" 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 27 07:37:35 volumio go-librespot[18737]: time="2025-10-27T07:37:35Z" 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 27 07:37:35 volumio go-librespot[18737]: time="2025-10-27T07:37:35Z" level=info msg="zeroconf server listening on port 35219"
Oct 27 07:37:35 volumio go-librespot[18737]: time="2025-10-27T07:37:35Z" level=debug msg="obtained new client token: AAA2N+4Yx3ZxgWDmWYcSwJ8BC9oROHodspaz45sEfAFVj1nhWoeASAlRARx6C03wzUmqLWRr6qxaGGmfvX0w+S4/Cj6yPPIfEFLENJYY8vEYAFM83B81WvlZKumqbUicC4GJ8RYgIqO+ib/Zrw7erINiGxIBRk97WX3g4P9dqlLTLrQ3K2924WTHeY+hJo2dSXUDNIADaaR9xcRulHVdcdQ/F4TC2sOQwo2/NrchN42unXlR5LBT"
Oct 27 07:37:35 volumio go-librespot[18737]: time="2025-10-27T07:37:35Z" level=debug msg="connected to ap-gew1.spotify.com:4070"
Oct 27 07:37:35 volumio go-librespot[18737]: time="2025-10-27T07:37:35Z" level=debug msg="completed keyexchange"
Oct 27 07:37:35 volumio go-librespot[18737]: time="2025-10-27T07:37:35Z" level=debug msg="completed challenge"
Oct 27 07:37:35 volumio go-librespot[18737]: time="2025-10-27T07:37:35Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction "
Oct 27 07:37:35 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Oct 27 07:37:35 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Oct 27 07:37:37 volumio volumio[17234]: info: Initializing connection to go-librespot Websocket
Oct 27 07:37:37 volumio volumio[17234]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Oct 27 07:37:38 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 376.
Oct 27 07:37:38 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Oct 27 07:37:38 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Oct 27 07:37:38 volumio go-librespot[18758]: go-librespot daemon starting...
Oct 27 07:37:38 volumio go-librespot[18759]: time="2025-10-27T07:37:38Z" level=info msg="running go-librespot 0.4.0"
Oct 27 07:37:38 volumio go-librespot[18759]: time="2025-10-27T07:37:38Z" level=debug msg="app state loaded"
Oct 27 07:37:38 volumio go-librespot[18759]: time="2025-10-27T07:37:38Z" level=info msg="api server listening on 127.0.0.1:9879"
Oct 27 07:37:38 volumio go-librespot[18759]: time="2025-10-27T07:37: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]"
Oct 27 07:37:38 volumio go-librespot[18759]: time="2025-10-27T07:37: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]"
Oct 27 07:37:38 volumio go-librespot[18759]: time="2025-10-27T07:37: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]"
Oct 27 07:37:38 volumio go-librespot[18759]: time="2025-10-27T07:37:38Z" level=info msg="zeroconf server listening on port 38519"
Oct 27 07:37:38 volumio go-librespot[18759]: time="2025-10-27T07:37:38Z" level=debug msg="obtained new client token: AAACTcUDEJLNIGz4vDFb7gLiI+Y45+1nw7Ki/CUJrK1e/gWDIU4v0WeO2liaYT9nkioQ12SwNAivw8heUQEvCYuvJliS1zn8Q6OhfjXbzA3yCpJTSS85lGBWyihkd32o6hK4IpWKQ2RcXXvmW6oC9kP0FtxVrbMrntV/SpRBYYdyivcaDct4gXYWokMTu8QLJMPvFn/qK5pq1XJX1/pPyYn4yPVdtLFeuhDAldxncGlIMl0gfUi+2LY="
Oct 27 07:37:38 volumio go-librespot[18759]: time="2025-10-27T07:37:38Z" level=debug msg="connected to ap-gew1.spotify.com:4070"
Oct 27 07:37:39 volumio go-librespot[18759]: time="2025-10-27T07:37:39Z" level=debug msg="completed keyexchange"
Oct 27 07:37:39 volumio go-librespot[18759]: time="2025-10-27T07:37:39Z" level=debug msg="completed challenge"
Oct 27 07:37:39 volumio go-librespot[18759]: time="2025-10-27T07:37:39Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction "
Oct 27 07:37:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Oct 27 07:37:39 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Oct 27 07:37:40 volumio volumio[17234]: info: Initializing connection to go-librespot Websocket
Oct 27 07:37:40 volumio volumio[17234]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Oct 27 07:37:40 volumio kernel: nfs: server 192.168.1.3 not responding, timed out
Oct 27 07:37:42 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 377.
Oct 27 07:37:42 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Oct 27 07:37:42 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Oct 27 07:37:42 volumio go-librespot[18766]: go-librespot daemon starting...
Oct 27 07:37:42 volumio go-librespot[18767]: time="2025-10-27T07:37:42Z" level=info msg="running go-librespot 0.4.0"
Oct 27 07:37:42 volumio go-librespot[18767]: time="2025-10-27T07:37:42Z" level=debug msg="app state loaded"
Oct 27 07:37:42 volumio go-librespot[18767]: time="2025-10-27T07:37:42Z" level=info msg="api server listening on 127.0.0.1:9879"
Oct 27 07:37:42 volumio go-librespot[18767]: time="2025-10-27T07:37:42Z" 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 27 07:37:42 volumio go-librespot[18767]: time="2025-10-27T07:37:42Z" 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 27 07:37:42 volumio go-librespot[18767]: time="2025-10-27T07:37:42Z" 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 27 07:37:42 volumio go-librespot[18767]: time="2025-10-27T07:37:42Z" level=info msg="zeroconf server listening on port 44905"
Oct 27 07:37:42 volumio go-librespot[18767]: time="2025-10-27T07:37:42Z" level=debug msg="obtained new client token: AACVirLUTrZamCqcLpfrak/UTki1A70valh0+GEuSxFS3CFcnrsR4pKI6sLnYO9+cQT18LsVxrjg5FiI4ITcSbAAa1vM+tLnZXrg1xG7uA4H0gD2rnL6AghgLF8YXIecYgRBAldBandNvJh7DBzDZGOguY7II3wvQCLHfufZijB6cico12c7wTgOk05/z/FULS4EXmkBl6iPx+QEFvVrO9kNfU8+nnZ+pMn2aK4y8r0To8/qPZyR9wo="
Oct 27 07:37:42 volumio go-librespot[18767]: time="2025-10-27T07:37:42Z" level=debug msg="connected to ap-gew1.spotify.com:4070"
Oct 27 07:37:42 volumio go-librespot[18767]: time="2025-10-27T07:37:42Z" level=debug msg="completed keyexchange"
Oct 27 07:37:42 volumio go-librespot[18767]: time="2025-10-27T07:37:42Z" level=debug msg="completed challenge"
Oct 27 07:37:42 volumio go-librespot[18767]: time="2025-10-27T07:37:42Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction "
Oct 27 07:37:42 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Oct 27 07:37:42 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Oct 27 07:37:43 volumio volumio[17234]: info: Initializing connection to go-librespot Websocket
Oct 27 07:37:43 volumio volumio[17234]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Oct 27 07:37:46 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 378.
Oct 27 07:37:46 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Oct 27 07:37:46 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Oct 27 07:37:46 volumio go-librespot[18774]: go-librespot daemon starting...
Oct 27 07:37:46 volumio go-librespot[18775]: time="2025-10-27T07:37:46Z" level=info msg="running go-librespot 0.4.0"
Oct 27 07:37:46 volumio go-librespot[18775]: time="2025-10-27T07:37:46Z" level=debug msg="app state loaded"
Oct 27 07:37:46 volumio go-librespot[18775]: time="2025-10-27T07:37:46Z" level=info msg="api server listening on 127.0.0.1:9879"
Oct 27 07:37:46 volumio go-librespot[18775]: time="2025-10-27T07:37:46Z" 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 27 07:37:46 volumio go-librespot[18775]: time="2025-10-27T07:37:46Z" 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 27 07:37:46 volumio go-librespot[18775]: time="2025-10-27T07:37:46Z" 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 27 07:37:46 volumio go-librespot[18775]: time="2025-10-27T07:37:46Z" level=info msg="zeroconf server listening on port 34923"
Oct 27 07:37:46 volumio go-librespot[18775]: time="2025-10-27T07:37:46Z" level=debug msg="obtained new client token: AAB768KF4QfWJNXVtqJUIMe7gpPWL6R0MqGnerLsgooV2Xx1pSHx3dpy9LOz4Vt9NqEnwusnjcNHRRlHUf4BDufG/IA8fwjS1xhRuBtOqibx6UxB5onZGsFpul1XXJ9zB21xjnE7Xh3VWrpBWP2d6E3y32+jD55Q3P3rNSXbpiUDBZBVvjG0WGbMVpTNN6GV9cBINmVJQDiwsbQgrPmIK8q+TMHZGr7WZA6rnLkK3GSWDOvDVlBz"
Oct 27 07:37:46 volumio go-librespot[18775]: time="2025-10-27T07:37:46Z" level=debug msg="connected to ap-gew1.spotify.com:4070"
Oct 27 07:37:46 volumio go-librespot[18775]: time="2025-10-27T07:37:46Z" level=debug msg="completed keyexchange"
Oct 27 07:37:46 volumio go-librespot[18775]: time="2025-10-27T07:37:46Z" level=debug msg="completed challenge"
Oct 27 07:37:46 volumio go-librespot[18775]: time="2025-10-27T07:37:46Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction "
Oct 27 07:37:46 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Oct 27 07:37:46 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Oct 27 07:37:46 volumio volumio[17234]: info: Initializing connection to go-librespot Websocket
Oct 27 07:37:46 volumio volumio[17234]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Oct 27 07:37:46 volumio kernel: nfs: server 192.168.1.3 not responding, timed out
Oct 27 07:37:49 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 379.
Oct 27 07:37:49 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Oct 27 07:37:49 volumio volumio[17234]: info: Initializing connection to go-librespot Websocket
Oct 27 07:37:49 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Oct 27 07:37:49 volumio volumio[17234]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Oct 27 07:37:49 volumio go-librespot[18796]: go-librespot daemon starting...
Oct 27 07:37:49 volumio go-librespot[18797]: time="2025-10-27T07:37:49Z" level=info msg="running go-librespot 0.4.0"
Oct 27 07:37:49 volumio go-librespot[18797]: time="2025-10-27T07:37:49Z" level=debug msg="app state loaded"
Oct 27 07:37:49 volumio go-librespot[18797]: time="2025-10-27T07:37:49Z" level=info msg="api server listening on 127.0.0.1:9879"
Oct 27 07:37:49 volumio go-librespot[18797]: time="2025-10-27T07:37:49Z" 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 27 07:37:49 volumio go-librespot[18797]: time="2025-10-27T07:37:49Z" 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 27 07:37:49 volumio go-librespot[18797]: time="2025-10-27T07:37:49Z" 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 27 07:37:49 volumio go-librespot[18797]: time="2025-10-27T07:37:49Z" level=info msg="zeroconf server listening on port 41029"
Oct 27 07:37:49 volumio go-librespot[18797]: time="2025-10-27T07:37:49Z" level=debug msg="obtained new client token: AADiNsFYeenSKC0pEz5Shw4+Cp6SE0CqlSmhBfkrd5KyjWtXK8y44xd9KjfWTwMM9vCrb438kGd6/2TYnTG2BLsn79bzGl8QJX3OHRAvO2G1cQv4rAOSciGHVj5ZCvM0epw1LtBKULl1UcsRUgXoMyv/HUuLNXCbokwhTg+QBOkHydgTc+SWI/b2apudBWniGgD4wO53IrSqkV3VjiKNsl3ACpeLae4wIF0gHnJKro+ebpCucp2Dwr8="
Oct 27 07:37:49 volumio go-librespot[18797]: time="2025-10-27T07:37:49Z" level=debug msg="connected to ap-gew1.spotify.com:4070"
Oct 27 07:37:49 volumio go-librespot[18797]: time="2025-10-27T07:37:49Z" level=debug msg="completed keyexchange"
Oct 27 07:37:49 volumio go-librespot[18797]: time="2025-10-27T07:37:49Z" level=debug msg="completed challenge"
Oct 27 07:37:49 volumio go-librespot[18797]: time="2025-10-27T07:37:49Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction "
Oct 27 07:37:49 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Oct 27 07:37:49 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Oct 27 07:37:52 volumio volumio[17234]: info: Initializing connection to go-librespot Websocket
Oct 27 07:37:52 volumio volumio[17234]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Oct 27 07:37:53 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 380.
Oct 27 07:37:53 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Oct 27 07:37:53 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Oct 27 07:37:53 volumio go-librespot[18805]: go-librespot daemon starting...
Oct 27 07:37:53 volumio go-librespot[18806]: time="2025-10-27T07:37:53Z" level=info msg="running go-librespot 0.4.0"
Oct 27 07:37:53 volumio go-librespot[18806]: time="2025-10-27T07:37:53Z" level=debug msg="app state loaded"
Oct 27 07:37:53 volumio go-librespot[18806]: time="2025-10-27T07:37:53Z" level=info msg="api server listening on 127.0.0.1:9879"
Oct 27 07:37:53 volumio go-librespot[18806]: time="2025-10-27T07:37:53Z" 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 27 07:37:53 volumio go-librespot[18806]: time="2025-10-27T07:37:53Z" 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 27 07:37:53 volumio go-librespot[18806]: time="2025-10-27T07:37:53Z" 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 27 07:37:53 volumio go-librespot[18806]: time="2025-10-27T07:37:53Z" level=info msg="zeroconf server listening on port 41501"
Oct 27 07:37:53 volumio go-librespot[18806]: time="2025-10-27T07:37:53Z" level=debug msg="obtained new client token: AAAihZy8dCt/DAWwli4ceBNwfSqxVngS9O34iwPKRx2DAg2JvDLtZS+KN3u3WkUUgK3OCepyBDXUTpUKf2F2KGJhfv869pXdzk/r+JpY5nrXaJSkRn6x8IMgDkPuowWZK0+ZzvSChjdCxxi+Pq6F4RvmvczMHWkjy5W2AXcHLIlb3bMKGSznXvcbj0ziK/K7fyyuRo+JWhLDDzzADY7VdlSamqBKl+I85PzJNK8qsrX2/eLtjCuq"
Oct 27 07:37:53 volumio go-librespot[18806]: time="2025-10-27T07:37:53Z" level=debug msg="connected to ap-gew1.spotify.com:4070"
Oct 27 07:37:53 volumio go-librespot[18806]: time="2025-10-27T07:37:53Z" level=debug msg="completed keyexchange"
Oct 27 07:37:53 volumio go-librespot[18806]: time="2025-10-27T07:37:53Z" level=debug msg="completed challenge"
Oct 27 07:37:53 volumio go-librespot[18806]: time="2025-10-27T07:37:53Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction "
Oct 27 07:37:53 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Oct 27 07:37:53 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Oct 27 07:37:55 volumio volumio[17234]: info: Initializing connection to go-librespot Websocket
Oct 27 07:37:55 volumio volumio[17234]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Oct 27 07:37:56 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 381.
Oct 27 07:37:56 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Oct 27 07:37:56 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Oct 27 07:37:56 volumio go-librespot[18816]: go-librespot daemon starting...
Oct 27 07:37:56 volumio go-librespot[18817]: time="2025-10-27T07:37:56Z" level=info msg="running go-librespot 0.4.0"
Oct 27 07:37:56 volumio go-librespot[18817]: time="2025-10-27T07:37:56Z" level=debug msg="app state loaded"
Oct 27 07:37:56 volumio go-librespot[18817]: time="2025-10-27T07:37:56Z" level=info msg="api server listening on 127.0.0.1:9879"
Oct 27 07:37:56 volumio go-librespot[18817]: time="2025-10-27T07:37:56Z" 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 27 07:37:56 volumio go-librespot[18817]: time="2025-10-27T07:37:56Z" 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 27 07:37:56 volumio go-librespot[18817]: time="2025-10-27T07:37:56Z" 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 27 07:37:56 volumio go-librespot[18817]: time="2025-10-27T07:37:56Z" level=info msg="zeroconf server listening on port 37853"
Oct 27 07:37:56 volumio go-librespot[18817]: time="2025-10-27T07:37:56Z" level=debug msg="obtained new client token: AAAmQl0O6gysPoWrTvRbiRE5v91BipuHDM6vmVp71gHvh4t7sB+pQ7VGVi8Ot7OoFBwNsjaCFqqHQOoJBvacVr89MpBK3Ss2WJBKMQ/cXLilyBaGPuhxZg0XJ41C5VkTw0kEsRsvtAWLwhOaj0J3SiGhrKnCwaWrlWOZtKRZZIqj2c29fOebElJvRx7GM6JO2QIYBY+Wg7jiGWY+M+01z2PznWpILSYUMcTZEa1qYmcjUjuw23N9PbE="
Oct 27 07:37:56 volumio go-librespot[18817]: time="2025-10-27T07:37:56Z" level=debug msg="connected to ap-gew1.spotify.com:4070"
Oct 27 07:37:56 volumio go-librespot[18817]: time="2025-10-27T07:37:56Z" level=debug msg="completed keyexchange"
Oct 27 07:37:56 volumio go-librespot[18817]: time="2025-10-27T07:37:56Z" level=debug msg="completed challenge"
Oct 27 07:37:56 volumio go-librespot[18817]: time="2025-10-27T07:37:56Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction "
Oct 27 07:37:56 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Oct 27 07:37:56 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Oct 27 07:37:58 volumio volumio[17234]: info: Initializing connection to go-librespot Websocket
Oct 27 07:37:58 volumio volumio[17234]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Oct 27 07:38:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 382.
Oct 27 07:38:00 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Oct 27 07:38:00 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Oct 27 07:38:00 volumio go-librespot[18838]: go-librespot daemon starting...
Oct 27 07:38:00 volumio go-librespot[18839]: time="2025-10-27T07:38:00Z" level=info msg="running go-librespot 0.4.0"
Oct 27 07:38:00 volumio go-librespot[18839]: time="2025-10-27T07:38:00Z" level=debug msg="app state loaded"
Oct 27 07:38:00 volumio go-librespot[18839]: time="2025-10-27T07:38:00Z" level=info msg="api server listening on 127.0.0.1:9879"
Oct 27 07:38:00 volumio go-librespot[18839]: time="2025-10-27T07:38:00Z" 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 27 07:38:00 volumio go-librespot[18839]: time="2025-10-27T07:38:00Z" 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 27 07:38:00 volumio go-librespot[18839]: time="2025-10-27T07:38:00Z" 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 27 07:38:00 volumio go-librespot[18839]: time="2025-10-27T07:38:00Z" level=info msg="zeroconf server listening on port 39003"
Oct 27 07:38:00 volumio go-librespot[18839]: time="2025-10-27T07:38:00Z" level=debug msg="obtained new client token: AADWas8MUPMDVLREnsgpxzr1sRXa3KRr6uJzpyZ3JzjddjLR26QCFd48+nL/tuVxmI+H+lzs23YaaqBwgdDktI3UEf8qU1duYBx9LH6OOSjaVOT8zRSdgLBTV7QwNnbbytKcbPkctUKPyzs77KMnDxyz1bjf/RUVmyothng5n8MQHcp6YvJuoMgTbXBKKzgLfX8J9ZrCPUKK3CS+nrbehHOxLldL1ua2RELxrwh14sSElUyuaFUK"
Oct 27 07:38:00 volumio go-librespot[18839]: time="2025-10-27T07:38:00Z" level=debug msg="connected to ap-gew1.spotify.com:4070"
Oct 27 07:38:00 volumio go-librespot[18839]: time="2025-10-27T07:38:00Z" level=debug msg="completed keyexchange"
Oct 27 07:38:00 volumio go-librespot[18839]: time="2025-10-27T07:38:00Z" level=debug msg="completed challenge"
Oct 27 07:38:00 volumio go-librespot[18839]: time="2025-10-27T07:38:00Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction "
Oct 27 07:38:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Oct 27 07:38:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Oct 27 07:38:01 volumio volumio[17234]: info: Initializing connection to go-librespot Websocket
Oct 27 07:38:01 volumio volumio[17234]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Oct 27 07:38:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 383.
Oct 27 07:38:03 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Oct 27 07:38:03 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Oct 27 07:38:03 volumio go-librespot[18846]: go-librespot daemon starting...
Oct 27 07:38:03 volumio go-librespot[18847]: time="2025-10-27T07:38:03Z" level=info msg="running go-librespot 0.4.0"
Oct 27 07:38:03 volumio go-librespot[18847]: time="2025-10-27T07:38:03Z" level=debug msg="app state loaded"
Oct 27 07:38:03 volumio go-librespot[18847]: time="2025-10-27T07:38:03Z" level=info msg="api server listening on 127.0.0.1:9879"
Oct 27 07:38:03 volumio go-librespot[18847]: time="2025-10-27T07:38: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]"
Oct 27 07:38:03 volumio go-librespot[18847]: time="2025-10-27T07:38: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]"
Oct 27 07:38:03 volumio go-librespot[18847]: time="2025-10-27T07:38: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]"
Oct 27 07:38:03 volumio go-librespot[18847]: time="2025-10-27T07:38:03Z" level=info msg="zeroconf server listening on port 42571"
Oct 27 07:38:03 volumio go-librespot[18847]: time="2025-10-27T07:38:03Z" level=debug msg="obtained new client token: AADU5ZyUoLxTTaQ1PU3gzi7fyXKj4fWWEsxGbQSt/cJnnVXtLZ7heDMNvI7/g70XI7UqTrg1XoZrrGII2TJ4Oidi7f0Kaa2+Wg33m3Rd7cDhMKQuvQjwN7PHhRTrROjzs0tIG8HPXp7FJCxrnyRY/R5pTcCWcIBYkOZWWEq8ngAPufEOHgEJohTPljsMumZ95teXiQLsuihr3mTGDXU8arn0rqrjmNCWcIAHoXDw46s9kHbvhUmI22k="
Oct 27 07:38:03 volumio go-librespot[18847]: time="2025-10-27T07:38:03Z" level=debug msg="connected to ap-gew1.spotify.com:4070"
Oct 27 07:38:03 volumio go-librespot[18847]: time="2025-10-27T07:38:03Z" level=debug msg="completed keyexchange"
Oct 27 07:38:03 volumio go-librespot[18847]: time="2025-10-27T07:38:03Z" level=debug msg="completed challenge"
Oct 27 07:38:03 volumio go-librespot[18847]: time="2025-10-27T07:38:03Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction "
Oct 27 07:38:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Oct 27 07:38:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Oct 27 07:38:04 volumio volumio[17234]: info: Initializing connection to go-librespot Websocket
Oct 27 07:38:04 volumio volumio[17234]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Oct 27 07:38:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 384.
Oct 27 07:38:07 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Oct 27 07:38:07 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Oct 27 07:38:07 volumio go-librespot[18854]: go-librespot daemon starting...
Oct 27 07:38:07 volumio go-librespot[18855]: time="2025-10-27T07:38:07Z" level=info msg="running go-librespot 0.4.0"
Oct 27 07:38:07 volumio go-librespot[18855]: time="2025-10-27T07:38:07Z" level=debug msg="app state loaded"
Oct 27 07:38:07 volumio go-librespot[18855]: time="2025-10-27T07:38:07Z" level=info msg="api server listening on 127.0.0.1:9879"
Oct 27 07:38:07 volumio go-librespot[18855]: time="2025-10-27T07:38:07Z" 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 27 07:38:07 volumio go-librespot[18855]: time="2025-10-27T07:38:07Z" 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 27 07:38:07 volumio go-librespot[18855]: time="2025-10-27T07:38:07Z" 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 27 07:38:07 volumio go-librespot[18855]: time="2025-10-27T07:38:07Z" level=info msg="zeroconf server listening on port 43603"
Oct 27 07:38:07 volumio go-librespot[18855]: time="2025-10-27T07:38:07Z" level=debug msg="obtained new client token: AAB1m1JaePLOGxRiPFq3glMRyPwiLDbXsTskkKA0RgURN+IdAHB8n28J7EcfGu85SNBcSbo9TD94sUbpujsdCC6L2tMXHnXAF8KpMDR28IBGtPEjT3KcCqFXyJQSiz3Ee2tpUjzRWNCy++d0xN2GSDNdpqmANS14lio0/rgSxvrQW28vhUKcAQByGoqf+9BKsTEJ+kVKdRIeoVeBOlki7ehzrIca04sPlJ3WkGS7a3SQkrge/Zyq"
Oct 27 07:38:07 volumio go-librespot[18855]: time="2025-10-27T07:38:07Z" level=debug msg="connected to ap-gew1.spotify.com:4070"
Oct 27 07:38:07 volumio go-librespot[18855]: time="2025-10-27T07:38:07Z" level=debug msg="completed keyexchange"
Oct 27 07:38:07 volumio go-librespot[18855]: time="2025-10-27T07:38:07Z" level=debug msg="completed challenge"
Oct 27 07:38:07 volumio go-librespot[18855]: time="2025-10-27T07:38:07Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction "
Oct 27 07:38:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Oct 27 07:38:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Oct 27 07:38:07 volumio volumio[17234]: info: Initializing connection to go-librespot Websocket
Oct 27 07:38:07 volumio volumio[17234]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Oct 27 07:38:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 385.
Oct 27 07:38:10 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Oct 27 07:38:10 volumio volumio[17234]: info: Initializing connection to go-librespot Websocket
Oct 27 07:38:10 volumio volumio[17234]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Oct 27 07:38:10 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Oct 27 07:38:10 volumio go-librespot[18877]: go-librespot daemon starting...
Oct 27 07:38:10 volumio go-librespot[18878]: time="2025-10-27T07:38:10Z" level=info msg="running go-librespot 0.4.0"
Oct 27 07:38:10 volumio go-librespot[18878]: time="2025-10-27T07:38:10Z" level=debug msg="app state loaded"
Oct 27 07:38:10 volumio go-librespot[18878]: time="2025-10-27T07:38:10Z" level=info msg="api server listening on 127.0.0.1:9879"
Oct 27 07:38:10 volumio go-librespot[18878]: time="2025-10-27T07:38:10Z" 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 27 07:38:10 volumio go-librespot[18878]: time="2025-10-27T07:38:10Z" 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 27 07:38:10 volumio go-librespot[18878]: time="2025-10-27T07:38:10Z" 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 27 07:38:10 volumio go-librespot[18878]: time="2025-10-27T07:38:10Z" level=info msg="zeroconf server listening on port 45875"
Oct 27 07:38:10 volumio go-librespot[18878]: time="2025-10-27T07:38:10Z" level=debug msg="obtained new client token: AAA6V28sjpQ1UVTjjIK05P8S1+ySvTNg2KI2KpNKn8MI2bDeiU3tL4gI3Ar4gc+WFeila1QLXUOWxnAC1gv4Bk3hGvLLwzJD4BzX7cmkBKmygnTtXG5lgJ5XiYDkhxhVVjCp8drMnOJfqqVMPeAQnbaQ84YTwxdbrUvMkMhlDMPMp0U6mQcIi6V9tix6en8O4l6sVXJuMFqSEXicfSl2hUFeOJLfVoNMIMhk3c4/5X/QlfJlDV6DbJU="
Oct 27 07:38:10 volumio go-librespot[18878]: time="2025-10-27T07:38:10Z" level=debug msg="connected to ap-gew1.spotify.com:4070"
Oct 27 07:38:10 volumio go-librespot[18878]: time="2025-10-27T07:38:10Z" level=debug msg="completed keyexchange"
Oct 27 07:38:10 volumio go-librespot[18878]: time="2025-10-27T07:38:10Z" level=debug msg="completed challenge"
Oct 27 07:38:10 volumio go-librespot[18878]: time="2025-10-27T07:38:10Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction "
Oct 27 07:38:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Oct 27 07:38:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Oct 27 07:38:13 volumio volumio[17234]: info: Initializing connection to go-librespot Websocket
Oct 27 07:38:13 volumio volumio[17234]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Oct 27 07:38:14 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 386.
Oct 27 07:38:14 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Oct 27 07:38:14 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Oct 27 07:38:14 volumio go-librespot[18885]: go-librespot daemon starting...
Oct 27 07:38:14 volumio go-librespot[18886]: time="2025-10-27T07:38:14Z" level=info msg="running go-librespot 0.4.0"
Oct 27 07:38:14 volumio go-librespot[18886]: time="2025-10-27T07:38:14Z" level=debug msg="app state loaded"
Oct 27 07:38:14 volumio go-librespot[18886]: time="2025-10-27T07:38:14Z" level=info msg="api server listening on 127.0.0.1:9879"
Oct 27 07:38:14 volumio go-librespot[18886]: time="2025-10-27T07:38:14Z" 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 27 07:38:14 volumio go-librespot[18886]: time="2025-10-27T07:38:14Z" 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 27 07:38:14 volumio go-librespot[18886]: time="2025-10-27T07:38:14Z" 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 27 07:38:14 volumio go-librespot[18886]: time="2025-10-27T07:38:14Z" level=info msg="zeroconf server listening on port 36173"
Oct 27 07:38:14 volumio go-librespot[18886]: time="2025-10-27T07:38:14Z" level=debug msg="obtained new client token: AAC6drzY6TsDv6GR4GSJ9qg5ju6i3PM3fW7/A1tHbO1Xyl9QUQOQ9s+0d0poUrKheM7i6oa5RRpMDTzzYs0xA2LVUYULuiSMQ5EaCRjLcwk2IVtqD6ZMFMVjGin8O3vCdc6ZGzQYDejst3lQ/ktgMW+guCnrhyvv4I+8pU2t5IYKAkbs1kgyqJsIJdIx2CVZmtuelq1c5TIdiMvD36Mhzx3rlZGnGu7SWFXoxroZC1VnnTTzzbSb"
Oct 27 07:38:14 volumio go-librespot[18886]: time="2025-10-27T07:38:14Z" level=debug msg="connected to ap-gew1.spotify.com:4070"
Oct 27 07:38:14 volumio go-librespot[18886]: time="2025-10-27T07:38:14Z" level=debug msg="completed keyexchange"
Oct 27 07:38:14 volumio go-librespot[18886]: time="2025-10-27T07:38:14Z" level=debug msg="completed challenge"
Oct 27 07:38:14 volumio go-librespot[18886]: time="2025-10-27T07:38:14Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction "
Oct 27 07:38:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Oct 27 07:38:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Oct 27 07:38:16 volumio volumio[17234]: info: Initializing connection to go-librespot Websocket
Oct 27 07:38:16 volumio volumio[17234]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Oct 27 07:38:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 387.
Oct 27 07:38:17 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Oct 27 07:38:17 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Oct 27 07:38:17 volumio go-librespot[18896]: go-librespot daemon starting...
Oct 27 07:38:17 volumio go-librespot[18897]: time="2025-10-27T07:38:17Z" level=info msg="running go-librespot 0.4.0"
Oct 27 07:38:17 volumio go-librespot[18897]: time="2025-10-27T07:38:17Z" level=debug msg="app state loaded"
Oct 27 07:38:17 volumio go-librespot[18897]: time="2025-10-27T07:38:17Z" level=info msg="api server listening on 127.0.0.1:9879"
Oct 27 07:38:17 volumio go-librespot[18897]: time="2025-10-27T07:38:17Z" 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 27 07:38:17 volumio go-librespot[18897]: time="2025-10-27T07:38:17Z" 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 27 07:38:17 volumio go-librespot[18897]: time="2025-10-27T07:38:17Z" 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 27 07:38:17 volumio go-librespot[18897]: time="2025-10-27T07:38:17Z" level=info msg="zeroconf server listening on port 43819"
Oct 27 07:38:17 volumio go-librespot[18897]: time="2025-10-27T07:38:17Z" level=debug msg="obtained new client token: AAD3sHLpqXZAI0SsR5eSp4H8TKBKWkNfTMn1ahTBnCOvnypwZPrg3Uajg2/iL6tKY6T7KtlOs1U8P2qhH7Z4EGjprocGJB1bCkWjN6R2IMWn/+s8CBAO4XGyYtlccNTrxGG6lYCydstPgijJq7EpJMmeUBUSEc2cwxvYxZ6zg/G7Jw+0uzqUKpwtvuVb2QL0S6VJWo9jyXXJWzSvH42l/psFVjd1GZ9Jj/UO6tSqMZAkgN4FId6oTRI="
Oct 27 07:38:17 volumio go-librespot[18897]: time="2025-10-27T07:38:17Z" level=debug msg="connected to ap-gew1.spotify.com:4070"
Oct 27 07:38:17 volumio go-librespot[18897]: time="2025-10-27T07:38:17Z" level=debug msg="completed keyexchange"
Oct 27 07:38:17 volumio go-librespot[18897]: time="2025-10-27T07:38:17Z" level=debug msg="completed challenge"
Oct 27 07:38:17 volumio go-librespot[18897]: time="2025-10-27T07:38:17Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction "
Oct 27 07:38:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Oct 27 07:38:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Oct 27 07:38:19 volumio volumio[17234]: info: Initializing connection to go-librespot Websocket
Oct 27 07:38:19 volumio volumio[17234]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Oct 27 07:38:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 388.
Oct 27 07:38:21 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Oct 27 07:38:21 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Oct 27 07:38:21 volumio go-librespot[18919]: go-librespot daemon starting...
Oct 27 07:38:21 volumio go-librespot[18920]: time="2025-10-27T07:38:21Z" level=info msg="running go-librespot 0.4.0"
Oct 27 07:38:21 volumio go-librespot[18920]: time="2025-10-27T07:38:21Z" level=debug msg="app state loaded"
Oct 27 07:38:21 volumio go-librespot[18920]: time="2025-10-27T07:38:21Z" level=info msg="api server listening on 127.0.0.1:9879"
Oct 27 07:38:21 volumio go-librespot[18920]: time="2025-10-27T07:38:21Z" 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 27 07:38:21 volumio go-librespot[18920]: time="2025-10-27T07:38:21Z" 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 27 07:38:21 volumio go-librespot[18920]: time="2025-10-27T07:38:21Z" 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 27 07:38:21 volumio go-librespot[18920]: time="2025-10-27T07:38:21Z" level=info msg="zeroconf server listening on port 45191"
Oct 27 07:38:21 volumio go-librespot[18920]: time="2025-10-27T07:38:21Z" level=debug msg="obtained new client token: AAAfxuOpqlcVlEwWJHg5XBX7aZzfar4CNQpG38XiAQCjpPbvbANA+jBUasubIAb/pKvw2jMjRKlwztQJH2fkNtHIt13sycfHxcmhQSHpFn5UVXqhF1c16opCOtD+ZprXV+1v+rBMChqPca+rAFhfFF/4mgbovZ3IADtryzRDHZ2hhj4nX5hjTCT9aPWzcdn+Yac0VidNj0BWWQnSKYxMh0/sx9ygswfA7etOyAn8BXr7qg9Z52E3"
Oct 27 07:38:21 volumio go-librespot[18920]: time="2025-10-27T07:38:21Z" level=debug msg="connected to ap-gew1.spotify.com:4070"
Oct 27 07:38:21 volumio go-librespot[18920]: time="2025-10-27T07:38:21Z" level=debug msg="completed keyexchange"
Oct 27 07:38:21 volumio go-librespot[18920]: time="2025-10-27T07:38:21Z" level=debug msg="completed challenge"
Oct 27 07:38:21 volumio go-librespot[18920]: time="2025-10-27T07:38:21Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction "
Oct 27 07:38:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Oct 27 07:38:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Oct 27 07:38:22 volumio volumio[17234]: info: Initializing connection to go-librespot Websocket
Oct 27 07:38:22 volumio volumio[17234]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Oct 27 07:38:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 389.
Oct 27 07:38:24 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Oct 27 07:38:24 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Oct 27 07:38:24 volumio go-librespot[18928]: go-librespot daemon starting...
Oct 27 07:38:24 volumio go-librespot[18929]: time="2025-10-27T07:38:24Z" level=info msg="running go-librespot 0.4.0"
Oct 27 07:38:24 volumio go-librespot[18929]: time="2025-10-27T07:38:24Z" level=debug msg="app state loaded"
Oct 27 07:38:24 volumio go-librespot[18929]: time="2025-10-27T07:38:24Z" level=info msg="api server listening on 127.0.0.1:9879"
Oct 27 07:38:24 volumio go-librespot[18929]: time="2025-10-27T07:38:24Z" 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 27 07:38:24 volumio go-librespot[18929]: time="2025-10-27T07:38:24Z" 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 27 07:38:24 volumio go-librespot[18929]: time="2025-10-27T07:38:24Z" 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 27 07:38:24 volumio go-librespot[18929]: time="2025-10-27T07:38:24Z" level=info msg="zeroconf server listening on port 44849"
Oct 27 07:38:24 volumio go-librespot[18929]: time="2025-10-27T07:38:24Z" level=debug msg="obtained new client token: AADqXxgRV07ZaBU8Oq/1Qm6A8kpOm0B1WgsLXAX271K8ZXPeF8Tbc7cI/bd6ryWHD7WWqs2qL4UWSCyi32aZawicX31eHEjmV9CsgNKOX9liaJ9z5YrGsqAs+9aHUfh+8Wf/FK+MsVM8Ks7TI53mVLyqFt51QNt9xzyqxN6oFbSX2XCmt6kc+2Hmfy0zA19xgpFhk2ejZWlShgIFEnD6RatTBNYBL+zP7SPJG5s7X5eK4lTvKRC2iZQ="
Oct 27 07:38:24 volumio go-librespot[18929]: time="2025-10-27T07:38:24Z" level=debug msg="connected to ap-gew1.spotify.com:4070"
Oct 27 07:38:24 volumio go-librespot[18929]: time="2025-10-27T07:38:24Z" level=debug msg="completed keyexchange"
Oct 27 07:38:24 volumio go-librespot[18929]: time="2025-10-27T07:38:24Z" level=debug msg="completed challenge"
Oct 27 07:38:24 volumio go-librespot[18929]: time="2025-10-27T07:38:24Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction "
Oct 27 07:38:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Oct 27 07:38:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Oct 27 07:38:25 volumio volumio[17234]: info: Initializing connection to go-librespot Websocket
Oct 27 07:38:25 volumio volumio[17234]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Oct 27 07:38:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 390.
Oct 27 07:38:28 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Oct 27 07:38:28 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Oct 27 07:38:28 volumio go-librespot[18951]: go-librespot daemon starting...
Oct 27 07:38:28 volumio go-librespot[18952]: time="2025-10-27T07:38:28Z" level=info msg="running go-librespot 0.4.0"
Oct 27 07:38:28 volumio go-librespot[18952]: time="2025-10-27T07:38:28Z" level=debug msg="app state loaded"
Oct 27 07:38:28 volumio go-librespot[18952]: time="2025-10-27T07:38:28Z" level=info msg="api server listening on 127.0.0.1:9879"
Oct 27 07:38:28 volumio go-librespot[18952]: time="2025-10-27T07:38:28Z" 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 27 07:38:28 volumio go-librespot[18952]: time="2025-10-27T07:38:28Z" 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 27 07:38:28 volumio go-librespot[18952]: time="2025-10-27T07:38:28Z" 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 27 07:38:28 volumio go-librespot[18952]: time="2025-10-27T07:38:28Z" level=info msg="zeroconf server listening on port 35639"
Oct 27 07:38:28 volumio go-librespot[18952]: time="2025-10-27T07:38:28Z" level=debug msg="obtained new client token: AABthB0lz4P4vLGoigVyAn2SfuZ6j5g8AEnMnyArPEdSLCUkmscrZ91L3+aKtC7nTT4wZK/IYWDFPii2TehxbHpxuvfC9XBGFnNbPLSdhwqH7oXYrE2qpuwS+xfWOMSTva8+Ws0Ltui/xujuHd81pTN9OdQsS09FDjGsyIBX8OQy4LmKx2ly1IyIMdDE3G2aqgTXOqxmt7wxMisMDFFfLb3FDqvgykN8G9sMx/kDxOuKAOz7LHPW"
Oct 27 07:38:28 volumio go-librespot[18952]: time="2025-10-27T07:38:28Z" level=debug msg="connected to ap-gew1.spotify.com:4070"
Oct 27 07:38:28 volumio go-librespot[18952]: time="2025-10-27T07:38:28Z" level=debug msg="completed keyexchange"
Oct 27 07:38:28 volumio go-librespot[18952]: time="2025-10-27T07:38:28Z" level=debug msg="completed challenge"
Oct 27 07:38:28 volumio go-librespot[18952]: time="2025-10-27T07:38:28Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction "
Oct 27 07:38:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Oct 27 07:38:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Oct 27 07:38:28 volumio volumio[17234]: info: Initializing connection to go-librespot Websocket
Oct 27 07:38:28 volumio volumio[17234]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Oct 27 07:38:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 391.
Oct 27 07:38:31 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Oct 27 07:38:31 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Oct 27 07:38:31 volumio go-librespot[18960]: go-librespot daemon starting...
Oct 27 07:38:31 volumio go-librespot[18961]: time="2025-10-27T07:38:31Z" level=info msg="running go-librespot 0.4.0"
Oct 27 07:38:31 volumio go-librespot[18961]: time="2025-10-27T07:38:31Z" level=debug msg="app state loaded"
Oct 27 07:38:31 volumio go-librespot[18961]: time="2025-10-27T07:38:31Z" level=info msg="api server listening on 127.0.0.1:9879"
Oct 27 07:38:31 volumio volumio[17234]: info: Initializing connection to go-librespot Websocket
Oct 27 07:38:31 volumio go-librespot[18961]: time="2025-10-27T07:38:31Z" level=debug msg="new websocket client"
Oct 27 07:38:31 volumio volumio[17234]: info: Connection to go-librespot Websocket established
Oct 27 07:38:31 volumio go-librespot[18961]: time="2025-10-27T07:38: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-gae2.spotify.com:80]"
Oct 27 07:38:31 volumio go-librespot[18961]: time="2025-10-27T07:38:31Z" 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 27 07:38:31 volumio go-librespot[18961]: time="2025-10-27T07:38:31Z" 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 27 07:38:31 volumio go-librespot[18961]: time="2025-10-27T07:38:31Z" level=info msg="zeroconf server listening on port 42349"
Oct 27 07:38:31 volumio go-librespot[18961]: time="2025-10-27T07:38:31Z" level=debug msg="obtained new client token: AABwjsZZOF2T0UZkXadQe9yTcfPGHePAv7LdDofJ29ftIeBzwX/NwiXfWgiebIrVH3P5/T4RlmjMHBcrHtNk5HqZ+3IMRBza8hdXP5/TJOIf9UT17G+3UEKDsRVo22SERnvDMBeZ2BdAplRlfZ6u/e7rCneufe465su79RiLsN7a2esq9q5CAo99hryb3Flikxo5r6TEBWrH2z1gt/W+HOgh25ULYoXBrpuKnr72LLfZF2mhuhSBjDo="
Oct 27 07:38:31 volumio go-librespot[18961]: time="2025-10-27T07:38:31Z" level=debug msg="connected to ap-gew1.spotify.com:4070"
Oct 27 07:38:31 volumio go-librespot[18961]: time="2025-10-27T07:38:31Z" level=debug msg="completed keyexchange"
Oct 27 07:38:31 volumio go-librespot[18961]: time="2025-10-27T07:38:31Z" level=debug msg="completed challenge"
Oct 27 07:38:31 volumio go-librespot[18961]: time="2025-10-27T07:38:31Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction "
Oct 27 07:38:31 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Oct 27 07:38:31 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Oct 27 07:38:31 volumio volumio[17234]: info: Connection to go-librespot Websocket closed
Oct 27 07:38:34 volumio volumio[17234]: info: Getting Spotify volume
Oct 27 07:38:34 volumio volumio[17234]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Oct 27 07:38:34 volumio volumio[17234]: Error: connect ECONNREFUSED 127.0.0.1:9879
Oct 27 07:38:34 volumio volumio[17234]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) {
Oct 27 07:38:34 volumio volumio[17234]: errno: -111,
Oct 27 07:38:34 volumio volumio[17234]: code: 'ECONNREFUSED',
Oct 27 07:38:34 volumio volumio[17234]: syscall: 'connect',
Oct 27 07:38:34 volumio volumio[17234]: address: '127.0.0.1',
Oct 27 07:38:34 volumio volumio[17234]: port: 9879,
Oct 27 07:38:34 volumio volumio[17234]: response: undefined
Oct 27 07:38:34 volumio volumio[17234]: }
Oct 27 07:38:34 volumio volumio[17234]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Oct 27 07:38:34 volumio sudo[18985]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-10-27 07:37'
Oct 27 07:38:34 volumio sudo[18985]: 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"