Nov 06 02:01:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 368. Nov 06 02:01:00 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 02:01:00 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 02:01:00 volumio go-librespot[1394]: go-librespot daemon starting... Nov 06 02:01:00 volumio go-librespot[1395]: time="2025-11-06T02:01:00Z" level=info msg="running go-librespot 0.4.0" Nov 06 02:01:00 volumio go-librespot[1395]: time="2025-11-06T02:01:00Z" level=debug msg="app state loaded" Nov 06 02:01:00 volumio go-librespot[1395]: time="2025-11-06T02:01:00Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 02:01:00 volumio go-librespot[1395]: time="2025-11-06T02:01: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-gae2.spotify.com:80]" Nov 06 02:01:00 volumio go-librespot[1395]: time="2025-11-06T02:01:00Z" 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 02:01:00 volumio go-librespot[1395]: time="2025-11-06T02:01:00Z" 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 02:01:00 volumio go-librespot[1395]: time="2025-11-06T02:01:00Z" level=info msg="zeroconf server listening on port 33907" Nov 06 02:01:00 volumio go-librespot[1395]: time="2025-11-06T02:01:00Z" level=debug msg="obtained new client token: AACsUGIqzy8uxkvSHbbmPA9/CwVts47OeHoV87PbnABvEgqnihMcQ4r7C5s5N8LtUZ68MI8nJyiCbaXu0UvTWCQZA71R065HuWL0JDR6Zx8Ddn2ZaLSudfnQVv6NSbe3L3rzVPZIGNBrMS/wR/7lnycCgNS+EzO0PDs08le0ygoBzNDV6+1WnfhKQqbAouKGvl0mHDWhNJRmVVRXep7I/wPdGh/OaOiMIS6u+kyDzQ/HZ0CLN9vyJsA=" Nov 06 02:01:00 volumio go-librespot[1395]: time="2025-11-06T02:01:00Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 06 02:01:00 volumio go-librespot[1395]: time="2025-11-06T02:01:00Z" level=debug msg="completed keyexchange" Nov 06 02:01:00 volumio go-librespot[1395]: time="2025-11-06T02:01:00Z" level=debug msg="completed challenge" Nov 06 02:01:00 volumio go-librespot[1395]: time="2025-11-06T02:01:00Z" 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 02:01:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 02:01:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 02:01:00 volumio volumio[978]: info: Initializing connection to go-librespot Websocket Nov 06 02:01:00 volumio volumio[978]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 02:01:03 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 06 02:01:03 volumio volumio[978]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Nov 06 02:01:03 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Nov 06 02:01:03 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Nov 06 02:01:03 volumio volumio[978]: info: Initializing connection to go-librespot Websocket Nov 06 02:01:03 volumio volumio[978]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 02:01:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 369. Nov 06 02:01:03 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 02:01:03 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 02:01:03 volumio go-librespot[1405]: go-librespot daemon starting... Nov 06 02:01:03 volumio go-librespot[1406]: time="2025-11-06T02:01:03Z" level=info msg="running go-librespot 0.4.0" Nov 06 02:01:03 volumio go-librespot[1406]: time="2025-11-06T02:01:03Z" level=debug msg="app state loaded" Nov 06 02:01:03 volumio go-librespot[1406]: time="2025-11-06T02:01:03Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 02:01:03 volumio go-librespot[1406]: time="2025-11-06T02:01: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-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 06 02:01:03 volumio go-librespot[1406]: time="2025-11-06T02:01:03Z" 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 02:01:03 volumio go-librespot[1406]: time="2025-11-06T02:01:03Z" 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 02:01:03 volumio go-librespot[1406]: time="2025-11-06T02:01:03Z" level=info msg="zeroconf server listening on port 33505" Nov 06 02:01:03 volumio go-librespot[1406]: time="2025-11-06T02:01:03Z" level=debug msg="obtained new client token: AABoPXxxUSYCdDBTNpOxZMeJiW/SCITM/JNuNqsKc0Ag/goUFDOnDs4bDPsvUNeuvhjHkdy/hxwS963hcJPa1DBbSxRBle2jq0j2KzvrCcYLFkJNGn/ECEXHAXgTM8luomFkcE06ZhIaD1ZqPYV9gkXVxFyEfxqoDuU4oCA1Q6NG2RJW77SHF9WXt2OmMhqCRUXrIGx8oK5cRd7Bp90Y4jzxzmINkhCBZGS0RW/0VfuzXFgW6oiHeYw=" Nov 06 02:01:03 volumio go-librespot[1406]: time="2025-11-06T02:01:03Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 06 02:01:04 volumio go-librespot[1406]: time="2025-11-06T02:01:04Z" level=debug msg="completed keyexchange" Nov 06 02:01:04 volumio go-librespot[1406]: time="2025-11-06T02:01:04Z" level=debug msg="completed challenge" Nov 06 02:01:04 volumio go-librespot[1406]: time="2025-11-06T02:01:04Z" 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 02:01:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 02:01:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 02:01:06 volumio volumio[978]: info: Initializing connection to go-librespot Websocket Nov 06 02:01:06 volumio volumio[978]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 02:01:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 370. Nov 06 02:01:07 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 02:01:07 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 02:01:07 volumio go-librespot[1428]: go-librespot daemon starting... Nov 06 02:01:07 volumio go-librespot[1429]: time="2025-11-06T02:01:07Z" level=info msg="running go-librespot 0.4.0" Nov 06 02:01:07 volumio go-librespot[1429]: time="2025-11-06T02:01:07Z" level=debug msg="app state loaded" Nov 06 02:01:07 volumio go-librespot[1429]: time="2025-11-06T02:01:07Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 02:01:07 volumio go-librespot[1429]: time="2025-11-06T02:01: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]" Nov 06 02:01:07 volumio go-librespot[1429]: time="2025-11-06T02:01: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]" Nov 06 02:01:07 volumio go-librespot[1429]: time="2025-11-06T02:01: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]" Nov 06 02:01:07 volumio go-librespot[1429]: time="2025-11-06T02:01:07Z" level=info msg="zeroconf server listening on port 32941" Nov 06 02:01:07 volumio go-librespot[1429]: time="2025-11-06T02:01:07Z" level=debug msg="obtained new client token: AAD7DwMLvHsoxt6BbfqUc8v3D4Q15iiHuit/lOhJlrfsrBttaUGdi96x0F189T0A+UtKraaJ7OS9UjVb9woUhpCLvGD/AwHgSfupzQK8WAYqgTDJhONSxJRnA6CQPksuSAjrK0jkpkIeBECtTnEFK8CJEwPGNoCLPQ6NwFOj9SIiUheGu4R0FSvUKRR9QDYTcR/vdU6zDuCKM+qitepUu8d9eTLunMMiNM61PTla7nL1wSaG5uruIJQ=" Nov 06 02:01:07 volumio go-librespot[1429]: time="2025-11-06T02:01:07Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 06 02:01:07 volumio go-librespot[1429]: time="2025-11-06T02:01:07Z" level=debug msg="completed keyexchange" Nov 06 02:01:07 volumio go-librespot[1429]: time="2025-11-06T02:01:07Z" level=debug msg="completed challenge" Nov 06 02:01:07 volumio go-librespot[1429]: time="2025-11-06T02:01:07Z" 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 02:01:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 02:01:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 02:01:08 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 06 02:01:08 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 06 02:01:08 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Nov 06 02:01:08 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Nov 06 02:01:08 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Nov 06 02:01:08 volumio volumio[978]: info: CoreCommandRouter::volumioGetBrowseSources Nov 06 02:01:08 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 06 02:01:08 volumio volumio[978]: error: MyVolumio Plugin failed to authenticate in a timely fashion Nov 06 02:01:08 volumio volumio[978]: info: Completed starting MyVolumio Plugin Nov 06 02:01:08 volumio volumio[978]: [Metrics] CommandRouter: 32s 71.97ms Nov 06 02:01:08 volumio volumio[978]: info: CoreCommandRouter::volumiosetStartupVolume Nov 06 02:01:08 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 06 02:01:08 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 06 02:01:08 volumio volumio[978]: info: CoreCommandRouter::Close All Modals sent Nov 06 02:01:08 volumio volumio[978]: info: CoreCommandRouter::Close All Modals sent Nov 06 02:01:09 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 06 02:01:09 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 06 02:01:09 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 06 02:01:09 volumio volumio-remote-updater[874]: Test mode disabled Nov 06 02:01:09 volumio volumio-remote-updater[874]: Alpha mode disabled Nov 06 02:01:09 volumio volumio-remote-updater[874]: Alpha legacy test mode disabled Nov 06 02:01:09 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Nov 06 02:01:09 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Nov 06 02:01:09 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 06 02:01:09 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Nov 06 02:01:09 volumio volumio[978]: info: Initializing connection to go-librespot Websocket Nov 06 02:01:09 volumio volumio[978]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 02:01:09 volumio volumio[978]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Nov 06 02:01:09 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Nov 06 02:01:09 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Nov 06 02:01:09 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Nov 06 02:01:09 volumio volumio[978]: info: Listing playlists Nov 06 02:01:09 volumio volumio[978]: info: Listing playlists Nov 06 02:01:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 371. Nov 06 02:01:10 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 02:01:10 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 02:01:10 volumio go-librespot[1446]: go-librespot daemon starting... Nov 06 02:01:10 volumio go-librespot[1447]: time="2025-11-06T02:01:10Z" level=info msg="running go-librespot 0.4.0" Nov 06 02:01:10 volumio go-librespot[1447]: time="2025-11-06T02:01:10Z" level=debug msg="app state loaded" Nov 06 02:01:10 volumio go-librespot[1447]: time="2025-11-06T02:01:10Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 02:01:10 volumio go-librespot[1447]: time="2025-11-06T02:01: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-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 06 02:01:10 volumio go-librespot[1447]: time="2025-11-06T02:01:10Z" 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 02:01:10 volumio go-librespot[1447]: time="2025-11-06T02:01:10Z" 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 02:01:10 volumio go-librespot[1447]: time="2025-11-06T02:01:10Z" level=info msg="zeroconf server listening on port 38527" Nov 06 02:01:10 volumio go-librespot[1447]: time="2025-11-06T02:01:10Z" level=debug msg="obtained new client token: AAAjEKU0KlzmEoLAyPr+sMKi7V30BkJHVLsfh211JU48ed5VYSJu0y4r1Hak1hreCUzImkxwhZtW/+NsflXtjgrFTTZ0I7WegjOE5+o/cOCuHll+mYSZrdVBAAzophPxc47hHOI4oo14coD1LswD5agB3f3URZhgOy1rWgB9zojuGz4/cJ8HfyoYJzyH8hQW1CVasyHIdkcGl5unxcUCVOXwodLYroD6YR38aUO34Fe7Z+r8IdfrNpo=" Nov 06 02:01:10 volumio go-librespot[1447]: time="2025-11-06T02:01:10Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 06 02:01:11 volumio go-librespot[1447]: time="2025-11-06T02:01:11Z" level=debug msg="completed keyexchange" Nov 06 02:01:11 volumio go-librespot[1447]: time="2025-11-06T02:01:11Z" level=debug msg="completed challenge" Nov 06 02:01:11 volumio go-librespot[1447]: time="2025-11-06T02:01:11Z" 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 02:01:11 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 02:01:11 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 02:01:12 volumio volumio[978]: info: Initializing connection to go-librespot Websocket Nov 06 02:01:12 volumio volumio[978]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 02:01:14 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 372. Nov 06 02:01:14 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 02:01:14 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 02:01:14 volumio go-librespot[1454]: go-librespot daemon starting... Nov 06 02:01:14 volumio go-librespot[1455]: time="2025-11-06T02:01:14Z" level=info msg="running go-librespot 0.4.0" Nov 06 02:01:14 volumio go-librespot[1455]: time="2025-11-06T02:01:14Z" level=debug msg="app state loaded" Nov 06 02:01:14 volumio go-librespot[1455]: time="2025-11-06T02:01:14Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 02:01:14 volumio go-librespot[1455]: time="2025-11-06T02:01: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]" Nov 06 02:01:14 volumio go-librespot[1455]: time="2025-11-06T02:01: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]" Nov 06 02:01:14 volumio go-librespot[1455]: time="2025-11-06T02:01: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]" Nov 06 02:01:14 volumio go-librespot[1455]: time="2025-11-06T02:01:14Z" level=info msg="zeroconf server listening on port 35187" Nov 06 02:01:14 volumio go-librespot[1455]: time="2025-11-06T02:01:14Z" level=debug msg="obtained new client token: AABiocxP7K7o9tmVpSwlirhlZHOrR7pp77drr54d38QVt02X1eEw0gi0SRlte59t3BbySuZgD7QXZr6amWbBN7+G6horwIhDTd37YJkMqU2DIFpV0fPiw9OkiQFhVAwQJXREQekYMfTMgit78kX7O3yHVRo7QJgPwaMM8k+4nmYEewt50C89rDPfgMvB/BAms487En+pisLDRejMCdiuepomECy6VUxThrOSEmIN2onUMWgsAvTQRFE=" Nov 06 02:01:14 volumio go-librespot[1455]: time="2025-11-06T02:01:14Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 06 02:01:14 volumio go-librespot[1455]: time="2025-11-06T02:01:14Z" level=debug msg="completed keyexchange" Nov 06 02:01:14 volumio go-librespot[1455]: time="2025-11-06T02:01:14Z" level=debug msg="completed challenge" Nov 06 02:01:14 volumio go-librespot[1455]: time="2025-11-06T02:01:14Z" 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 02:01:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 02:01:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 02:01:15 volumio volumio[978]: info: BOOT COMPLETED Nov 06 02:01:15 volumio volumio[978]: info: Initializing connection to go-librespot Websocket Nov 06 02:01:15 volumio volumio[978]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 02:01:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 373. Nov 06 02:01:17 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 02:01:17 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 02:01:17 volumio go-librespot[1480]: go-librespot daemon starting... Nov 06 02:01:17 volumio go-librespot[1481]: time="2025-11-06T02:01:17Z" level=info msg="running go-librespot 0.4.0" Nov 06 02:01:17 volumio go-librespot[1481]: time="2025-11-06T02:01:17Z" level=debug msg="app state loaded" Nov 06 02:01:17 volumio go-librespot[1481]: time="2025-11-06T02:01:17Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 02:01:17 volumio go-librespot[1481]: time="2025-11-06T02:01: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 02:01:17 volumio go-librespot[1481]: time="2025-11-06T02:01: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 02:01:17 volumio go-librespot[1481]: time="2025-11-06T02:01: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 02:01:17 volumio go-librespot[1481]: time="2025-11-06T02:01:17Z" level=info msg="zeroconf server listening on port 38843" Nov 06 02:01:17 volumio go-librespot[1481]: time="2025-11-06T02:01:17Z" level=debug msg="obtained new client token: AAC3RE+XG2MLzWYA90MsIGyMJW0e6yG2BQ5aAt71mxNu8d7p3KtOl3BumsV0V87Ni0oqwC8omTcz3PMvyVAhO81ieGihEH1y/sIUocCQOYHAqo1WVMivRS1iHnwfIc5whjTn5Xfl3DxhC8Y//Yuj+VafhE/bvbwLHEkedhkzqm2VqmZp8Th0Xf8jZ5YKys2x/SRgPg1+YetFuEVEkJaRHbsXOINg9hgKUgqJfhPW1hSOEzKdGAa3E/Y=" Nov 06 02:01:17 volumio go-librespot[1481]: time="2025-11-06T02:01:17Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 06 02:01:18 volumio go-librespot[1481]: time="2025-11-06T02:01:18Z" level=debug msg="completed keyexchange" Nov 06 02:01:18 volumio go-librespot[1481]: time="2025-11-06T02:01:18Z" level=debug msg="completed challenge" Nov 06 02:01:18 volumio go-librespot[1481]: time="2025-11-06T02:01:18Z" 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 02:01:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 02:01:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 02:01:18 volumio volumio[978]: info: Initializing connection to go-librespot Websocket Nov 06 02:01:18 volumio volumio[978]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 02:01:19 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Nov 06 02:01:19 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Nov 06 02:01:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 374. Nov 06 02:01:21 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 02:01:21 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 02:01:21 volumio go-librespot[1489]: go-librespot daemon starting... Nov 06 02:01:21 volumio go-librespot[1492]: time="2025-11-06T02:01:21Z" level=info msg="running go-librespot 0.4.0" Nov 06 02:01:21 volumio go-librespot[1492]: time="2025-11-06T02:01:21Z" level=debug msg="app state loaded" Nov 06 02:01:21 volumio go-librespot[1492]: time="2025-11-06T02:01:21Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 02:01:21 volumio go-librespot[1492]: time="2025-11-06T02:01: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]" Nov 06 02:01:21 volumio go-librespot[1492]: time="2025-11-06T02:01: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]" Nov 06 02:01:21 volumio go-librespot[1492]: time="2025-11-06T02:01: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]" Nov 06 02:01:21 volumio go-librespot[1492]: time="2025-11-06T02:01:21Z" level=info msg="zeroconf server listening on port 45455" Nov 06 02:01:21 volumio go-librespot[1492]: time="2025-11-06T02:01:21Z" level=debug msg="obtained new client token: AAD1L8ZzGNm1ug8sNKJJd/NPWdc5ZAXnZf13T6074CeI8TWGzHdc9GyJXsHv589+xo0NMhtEpcyj2bdqieIBTAWFCVdnj1Q6GWnj9hGoQqckSpzWtVBf0UdGmOJFRJUTIYnrviz23lArFn0N4HgTgqQs1eBVqWtBm+AlDKeC2FGq2ICRifZ6BBpNItKKyj9fSXd7bsa8wgva4wu5z3giwXXxRlee9VJndUHJaF5PIGUFPelYSJzY3Fk=" Nov 06 02:01:21 volumio go-librespot[1492]: time="2025-11-06T02:01:21Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 06 02:01:21 volumio go-librespot[1492]: time="2025-11-06T02:01:21Z" level=debug msg="completed keyexchange" Nov 06 02:01:21 volumio go-librespot[1492]: time="2025-11-06T02:01:21Z" level=debug msg="completed challenge" Nov 06 02:01:21 volumio go-librespot[1492]: time="2025-11-06T02:01:21Z" 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 02:01:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 02:01:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 02:01:21 volumio volumio[978]: info: Initializing connection to go-librespot Websocket Nov 06 02:01:21 volumio volumio[978]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 02:01:24 volumio volumio[978]: info: Initializing connection to go-librespot Websocket Nov 06 02:01:24 volumio volumio[978]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 02:01:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 375. Nov 06 02:01:24 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 02:01:24 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 02:01:24 volumio go-librespot[1504]: go-librespot daemon starting... Nov 06 02:01:24 volumio go-librespot[1505]: time="2025-11-06T02:01:24Z" level=info msg="running go-librespot 0.4.0" Nov 06 02:01:24 volumio go-librespot[1505]: time="2025-11-06T02:01:24Z" level=debug msg="app state loaded" Nov 06 02:01:24 volumio go-librespot[1505]: time="2025-11-06T02:01:24Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 02:01:24 volumio go-librespot[1505]: time="2025-11-06T02:01: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-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Nov 06 02:01:24 volumio go-librespot[1505]: time="2025-11-06T02:01:24Z" 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 02:01:24 volumio go-librespot[1505]: time="2025-11-06T02:01:24Z" 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 02:01:24 volumio go-librespot[1505]: time="2025-11-06T02:01:24Z" level=info msg="zeroconf server listening on port 33949" Nov 06 02:01:24 volumio go-librespot[1505]: time="2025-11-06T02:01:24Z" level=debug msg="obtained new client token: AABPeMJjk0gw4uPqhDaR3ECr7Mso9Eykj+mKAX2xNsIUqwtZ4AyYb9+epV6BlEgciUBcJ4qgzp2fZhdYFA3au2iDZcFZ4O2QBJ52cuIuKecSqMFHhUbZCjZtfxFUhX10T7v0u8T1VdpUKyyLkjRYmR4M2AroECeCRmJejO5pef+uzRIgTxpI+5ofi0FtEWx54D896ti5H8o9zurYbwh/+QpaWX26oWLGKlwjO5gMdZfovXFiVNvwz/U=" Nov 06 02:01:25 volumio go-librespot[1505]: time="2025-11-06T02:01:25Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 06 02:01:25 volumio go-librespot[1505]: time="2025-11-06T02:01:25Z" level=debug msg="completed keyexchange" Nov 06 02:01:25 volumio go-librespot[1505]: time="2025-11-06T02:01:25Z" level=debug msg="completed challenge" Nov 06 02:01:25 volumio go-librespot[1505]: time="2025-11-06T02:01:25Z" 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 02:01:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 02:01:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 02:01:27 volumio volumio[978]: info: Initializing connection to go-librespot Websocket Nov 06 02:01:27 volumio volumio[978]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 02:01:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 376. Nov 06 02:01:28 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 02:01:28 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 02:01:28 volumio go-librespot[1528]: go-librespot daemon starting... Nov 06 02:01:28 volumio go-librespot[1529]: time="2025-11-06T02:01:28Z" level=info msg="running go-librespot 0.4.0" Nov 06 02:01:28 volumio go-librespot[1529]: time="2025-11-06T02:01:28Z" level=debug msg="app state loaded" Nov 06 02:01:28 volumio go-librespot[1529]: time="2025-11-06T02:01:28Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 02:01:28 volumio go-librespot[1529]: time="2025-11-06T02:01: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]" Nov 06 02:01:28 volumio go-librespot[1529]: time="2025-11-06T02:01: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]" Nov 06 02:01:28 volumio go-librespot[1529]: time="2025-11-06T02:01: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]" Nov 06 02:01:28 volumio go-librespot[1529]: time="2025-11-06T02:01:28Z" level=info msg="zeroconf server listening on port 42985" Nov 06 02:01:28 volumio go-librespot[1529]: time="2025-11-06T02:01:28Z" level=debug msg="obtained new client token: AAA/Safu4vvjiRgE/IPq8R6dJ5Qa6MKpi9Zew0fPtyJx+pmhYf/sSDiE2Sa3OpmjEi148ZRKvlPszL493DNdnGQaQzRYAah8vPA3Q2n0LOURB64a0NDVN1hgQwoXHrTfXHXipcKQcu9SK8b8A7GD5eiE+wr95eEFmOjKWrrcPS+PfvU3psjcz0tgDBssJJJo9TQ4KNZR6BtAu0x9e636k2+eiZ/rQBoXegg/XpsJqGUYS1IFBmtVeng=" Nov 06 02:01:28 volumio go-librespot[1529]: time="2025-11-06T02:01:28Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 06 02:01:28 volumio go-librespot[1529]: time="2025-11-06T02:01:28Z" level=debug msg="completed keyexchange" Nov 06 02:01:28 volumio go-librespot[1529]: time="2025-11-06T02:01:28Z" level=debug msg="completed challenge" Nov 06 02:01:28 volumio go-librespot[1529]: time="2025-11-06T02:01:28Z" 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 02:01:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 02:01:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 02:01:28 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Nov 06 02:01:29 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Nov 06 02:01:29 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Nov 06 02:01:29 volumio volumio[978]: info: Listing playlists Nov 06 02:01:29 volumio volumio[978]: info: Listing playlists Nov 06 02:01:30 volumio volumio[978]: info: Initializing connection to go-librespot Websocket Nov 06 02:01:30 volumio volumio[978]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 02:01:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 377. Nov 06 02:01:31 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 02:01:31 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 02:01:31 volumio go-librespot[1536]: go-librespot daemon starting... Nov 06 02:01:31 volumio go-librespot[1537]: time="2025-11-06T02:01:31Z" level=info msg="running go-librespot 0.4.0" Nov 06 02:01:31 volumio go-librespot[1537]: time="2025-11-06T02:01:31Z" level=debug msg="app state loaded" Nov 06 02:01:31 volumio go-librespot[1537]: time="2025-11-06T02:01:31Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 02:01:31 volumio go-librespot[1537]: time="2025-11-06T02:01: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]" Nov 06 02:01:31 volumio go-librespot[1537]: time="2025-11-06T02:01: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]" Nov 06 02:01:31 volumio go-librespot[1537]: time="2025-11-06T02:01: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]" Nov 06 02:01:31 volumio go-librespot[1537]: time="2025-11-06T02:01:31Z" level=info msg="zeroconf server listening on port 34705" Nov 06 02:01:31 volumio go-librespot[1537]: time="2025-11-06T02:01:31Z" level=debug msg="obtained new client token: AAB0adv7wA8BjiNUY3qMo2hQAhSu6nueSFxJSAKGVIe8G1WCdjjf/GjlosfeQYzJJhZXegIrdEkHVzxrmcnpwI6vEs6L2xHWpaWprbcS/RA35KRswdyvyAQz1VqQWgbIlyKA4aZoEIAdKPIo6FHq/smaZGVxGYk/whciLy9ITzyP/1QxjnssveDbP0F6FClokFfnJM/Im84U8uNOpTkwuTV816viOPa9ojVO3eu3QGxflxskD/oyu90=" Nov 06 02:01:31 volumio go-librespot[1537]: time="2025-11-06T02:01:31Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 06 02:01:31 volumio go-librespot[1537]: time="2025-11-06T02:01:31Z" level=debug msg="completed keyexchange" Nov 06 02:01:31 volumio go-librespot[1537]: time="2025-11-06T02:01:31Z" level=debug msg="completed challenge" Nov 06 02:01:32 volumio go-librespot[1537]: time="2025-11-06T02:01:32Z" 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 02:01:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 02:01:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 02:01:33 volumio volumio[978]: info: Initializing connection to go-librespot Websocket Nov 06 02:01:33 volumio volumio[978]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 02:01:34 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Nov 06 02:01:35 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 378. Nov 06 02:01:35 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 02:01:35 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 02:01:35 volumio go-librespot[1544]: go-librespot daemon starting... Nov 06 02:01:35 volumio go-librespot[1545]: time="2025-11-06T02:01:35Z" level=info msg="running go-librespot 0.4.0" Nov 06 02:01:35 volumio go-librespot[1545]: time="2025-11-06T02:01:35Z" level=debug msg="app state loaded" Nov 06 02:01:35 volumio go-librespot[1545]: time="2025-11-06T02:01:35Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 02:01:35 volumio go-librespot[1545]: time="2025-11-06T02:01:35Z" 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 02:01:35 volumio go-librespot[1545]: time="2025-11-06T02:01:35Z" 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 02:01:35 volumio go-librespot[1545]: time="2025-11-06T02:01:35Z" 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 02:01:35 volumio go-librespot[1545]: time="2025-11-06T02:01:35Z" level=info msg="zeroconf server listening on port 42453" Nov 06 02:01:35 volumio go-librespot[1545]: time="2025-11-06T02:01:35Z" level=debug msg="obtained new client token: AAC37OvFFlRzdG510pTMLjH5xU9QrUA6lSexivtm9jvW0XBDyqdtRC36TCJMKRa6ZKY/LzDzkXT9+a231TiWWKGA5EXQMtE4RLXmg6TNMorZmNyztEaVjdGywKKsjGny2CTIHoB/B2ahSlV2DOKyNiZYCl335fIkVpvCORsy3nunkpHPpr+4u4IAQdYG1LuD5Yf7RCYvANp9q5hvoPDfaJtYbkEMoBkKTCAlr2Hzg2LXPGWxfiztjl4=" Nov 06 02:01:35 volumio go-librespot[1545]: time="2025-11-06T02:01:35Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 06 02:01:35 volumio go-librespot[1545]: time="2025-11-06T02:01:35Z" level=debug msg="completed keyexchange" Nov 06 02:01:35 volumio go-librespot[1545]: time="2025-11-06T02:01:35Z" level=debug msg="completed challenge" Nov 06 02:01:35 volumio go-librespot[1545]: time="2025-11-06T02:01:35Z" 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 02:01:35 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 02:01:35 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 02:01:36 volumio volumio[978]: info: Initializing connection to go-librespot Websocket Nov 06 02:01:36 volumio volumio[978]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 02:01:38 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 379. Nov 06 02:01:38 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 02:01:38 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 02:01:38 volumio go-librespot[1566]: go-librespot daemon starting... Nov 06 02:01:38 volumio go-librespot[1567]: time="2025-11-06T02:01:38Z" level=info msg="running go-librespot 0.4.0" Nov 06 02:01:38 volumio go-librespot[1567]: time="2025-11-06T02:01:38Z" level=debug msg="app state loaded" Nov 06 02:01:38 volumio go-librespot[1567]: time="2025-11-06T02:01:38Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 02:01:38 volumio go-librespot[1567]: time="2025-11-06T02:01: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 02:01:38 volumio go-librespot[1567]: time="2025-11-06T02:01: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 02:01:38 volumio go-librespot[1567]: time="2025-11-06T02:01: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 02:01:38 volumio go-librespot[1567]: time="2025-11-06T02:01:38Z" level=info msg="zeroconf server listening on port 35869" Nov 06 02:01:38 volumio go-librespot[1567]: time="2025-11-06T02:01:38Z" level=debug msg="obtained new client token: AABy+03wubPBilEohB3XxUKoOblbjDQbFO/c2dOmrTPoPJYKmtkCXL4qY3ACCyiZ3F5S8A9oWvq0d/V5qX22gtuu/7miJoc877lwI4vx0WmT6edydAfCTJEA+kX3TTzoHkh59TIFcY8ciQQYr43h9x7EJnFqVJZxVNNiGEnw76hPD1ismE7TNAuEiI8m0Z4xJ0ECa99Iyhw1Q2xeOGw9V3mp9X8h+thRIT+hWS2vQ4+XJQdBetgOOn4=" Nov 06 02:01:38 volumio go-librespot[1567]: time="2025-11-06T02:01:38Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 06 02:01:38 volumio go-librespot[1567]: time="2025-11-06T02:01:38Z" level=debug msg="completed keyexchange" Nov 06 02:01:38 volumio go-librespot[1567]: time="2025-11-06T02:01:38Z" level=debug msg="completed challenge" Nov 06 02:01:39 volumio go-librespot[1567]: time="2025-11-06T02:01:39Z" 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 02:01:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 02:01:39 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 02:01:39 volumio volumio[978]: info: Initializing connection to go-librespot Websocket Nov 06 02:01:39 volumio volumio[978]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 02:01:39 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Nov 06 02:01:39 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Nov 06 02:01:42 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 380. Nov 06 02:01:42 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 02:01:42 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 02:01:42 volumio go-librespot[1575]: go-librespot daemon starting... Nov 06 02:01:42 volumio go-librespot[1576]: time="2025-11-06T02:01:42Z" level=info msg="running go-librespot 0.4.0" Nov 06 02:01:42 volumio go-librespot[1576]: time="2025-11-06T02:01:42Z" level=debug msg="app state loaded" Nov 06 02:01:42 volumio go-librespot[1576]: time="2025-11-06T02:01:42Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 02:01:42 volumio go-librespot[1576]: time="2025-11-06T02:01: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-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 06 02:01:42 volumio go-librespot[1576]: time="2025-11-06T02:01:42Z" 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 02:01:42 volumio go-librespot[1576]: time="2025-11-06T02:01:42Z" 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 02:01:42 volumio go-librespot[1576]: time="2025-11-06T02:01:42Z" level=info msg="zeroconf server listening on port 41069" Nov 06 02:01:42 volumio go-librespot[1576]: time="2025-11-06T02:01:42Z" level=debug msg="obtained new client token: AABToIBD/r5ou8Mqhgj9D4rDwvPlRq9ZojTMM5W6FEHV8J3P+UlEfKTe8Ivl7UNarp/KYkC6Vjkd6d7csv7BbjQFsQU2YfyZ8Q0HiGdRu0ksfyn+IxlGxOAfxN6EgEYjVnAjhkYvX5jUVWhVQjgZjRYK/BjAbIviPBpwBzxXyOEt0W/ZU3+UovFUgjdgGVEWBXZGwOctzJze8SJcsuDxlNa8SbUwKOV9cHm56x2u4O22Ba326xlquJQ=" Nov 06 02:01:42 volumio go-librespot[1576]: time="2025-11-06T02:01:42Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 06 02:01:42 volumio go-librespot[1576]: time="2025-11-06T02:01:42Z" level=debug msg="completed keyexchange" Nov 06 02:01:42 volumio go-librespot[1576]: time="2025-11-06T02:01:42Z" level=debug msg="completed challenge" Nov 06 02:01:42 volumio go-librespot[1576]: time="2025-11-06T02:01:42Z" 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 02:01:42 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 02:01:42 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 02:01:42 volumio volumio[978]: info: Initializing connection to go-librespot Websocket Nov 06 02:01:42 volumio volumio[978]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 02:01:44 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Nov 06 02:01:45 volumio volumio[978]: info: Initializing connection to go-librespot Websocket Nov 06 02:01:45 volumio volumio[978]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 02:01:45 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 381. Nov 06 02:01:45 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 02:01:45 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 02:01:45 volumio go-librespot[1593]: go-librespot daemon starting... Nov 06 02:01:45 volumio go-librespot[1594]: time="2025-11-06T02:01:45Z" level=info msg="running go-librespot 0.4.0" Nov 06 02:01:45 volumio go-librespot[1594]: time="2025-11-06T02:01:45Z" level=debug msg="app state loaded" Nov 06 02:01:45 volumio go-librespot[1594]: time="2025-11-06T02:01:45Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 02:01:45 volumio go-librespot[1594]: time="2025-11-06T02:01: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-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Nov 06 02:01:45 volumio go-librespot[1594]: time="2025-11-06T02:01:45Z" 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 02:01:45 volumio go-librespot[1594]: time="2025-11-06T02:01:45Z" 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 02:01:45 volumio go-librespot[1594]: time="2025-11-06T02:01:45Z" level=info msg="zeroconf server listening on port 40811" Nov 06 02:01:45 volumio go-librespot[1594]: time="2025-11-06T02:01:45Z" level=debug msg="obtained new client token: AAAPX5bFhewZvUKUkNKgPFDY/1zlxFawIuU/EI3/OC0CBcDoMtEMCqJddONbc6Qjf57AmvZ0Hs1Dhqb5HkME88DgHr8L5FuNYzl5JXUIurtCUSz+V+rBW7h4kwoGJcyq6dlNXMFrV0ory4yAzCErEEBlYqlsIIchCi/I4MGzg31JY7TGPR+lUUzGe7fKILOKUZVnfTh0enTXPYPtkxHd8JwoA6vb1vvyU6BLP8k8DswnmqaAHdvrlZ4=" Nov 06 02:01:45 volumio go-librespot[1594]: time="2025-11-06T02:01:45Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 06 02:01:46 volumio go-librespot[1594]: time="2025-11-06T02:01:46Z" level=debug msg="completed keyexchange" Nov 06 02:01:46 volumio go-librespot[1594]: time="2025-11-06T02:01:46Z" level=debug msg="completed challenge" Nov 06 02:01:46 volumio go-librespot[1594]: time="2025-11-06T02:01:46Z" 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 02:01:46 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 02:01:46 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 02:01:48 volumio volumio[978]: info: Initializing connection to go-librespot Websocket Nov 06 02:01:48 volumio volumio[978]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 02:01:49 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 382. Nov 06 02:01:49 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 02:01:49 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 02:01:49 volumio go-librespot[1616]: go-librespot daemon starting... Nov 06 02:01:49 volumio go-librespot[1617]: time="2025-11-06T02:01:49Z" level=info msg="running go-librespot 0.4.0" Nov 06 02:01:49 volumio go-librespot[1617]: time="2025-11-06T02:01:49Z" level=debug msg="app state loaded" Nov 06 02:01:49 volumio go-librespot[1617]: time="2025-11-06T02:01:49Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 02:01:49 volumio go-librespot[1617]: time="2025-11-06T02:01: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-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Nov 06 02:01:49 volumio go-librespot[1617]: time="2025-11-06T02:01:49Z" 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 02:01:49 volumio go-librespot[1617]: time="2025-11-06T02:01:49Z" 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 02:01:49 volumio go-librespot[1617]: time="2025-11-06T02:01:49Z" level=info msg="zeroconf server listening on port 34391" Nov 06 02:01:49 volumio go-librespot[1617]: time="2025-11-06T02:01:49Z" level=debug msg="obtained new client token: AADEcffHYgOuVe9BeG7VTohz/egsh371jXWGpkGySC0piCCs90wfepADehFLsO1NxgD1/D333iZgIm1AA8VCQQbYwR4NipkDWPAs+HDWKchzNJfcnmfkPNGeIloLiZU/cuQr7mbGbLr3ZRmh6KcsmeKaxEPSqEzln7vBusq8ogxb9YFgcjRbrLZ0e046D8HXAdJpP9YAREoFpo6o6tRZWg2HpQrZ9xNf16MwjZr5rJSOQV8xg9Q+zUE=" Nov 06 02:01:49 volumio go-librespot[1617]: time="2025-11-06T02:01:49Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 06 02:01:49 volumio go-librespot[1617]: time="2025-11-06T02:01:49Z" level=debug msg="completed keyexchange" Nov 06 02:01:49 volumio go-librespot[1617]: time="2025-11-06T02:01:49Z" level=debug msg="completed challenge" Nov 06 02:01:49 volumio go-librespot[1617]: time="2025-11-06T02:01:49Z" 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 02:01:49 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 02:01:49 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 02:01:49 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Nov 06 02:01:49 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Nov 06 02:01:49 volumio volumio[978]: info: Listing playlists Nov 06 02:01:49 volumio volumio[978]: info: Listing playlists Nov 06 02:01:51 volumio volumio[978]: info: Initializing connection to go-librespot Websocket Nov 06 02:01:51 volumio volumio[978]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 02:01:52 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Nov 06 02:01:52 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 383. Nov 06 02:01:52 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 02:01:52 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 02:01:52 volumio go-librespot[1624]: go-librespot daemon starting... Nov 06 02:01:52 volumio go-librespot[1625]: time="2025-11-06T02:01:52Z" level=info msg="running go-librespot 0.4.0" Nov 06 02:01:52 volumio go-librespot[1625]: time="2025-11-06T02:01:52Z" level=debug msg="app state loaded" Nov 06 02:01:52 volumio go-librespot[1625]: time="2025-11-06T02:01:52Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 02:01:52 volumio go-librespot[1625]: time="2025-11-06T02:01: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-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 06 02:01:52 volumio go-librespot[1625]: time="2025-11-06T02:01:52Z" 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 02:01:52 volumio go-librespot[1625]: time="2025-11-06T02:01:52Z" 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 02:01:52 volumio go-librespot[1625]: time="2025-11-06T02:01:52Z" level=info msg="zeroconf server listening on port 46855" Nov 06 02:01:52 volumio go-librespot[1625]: time="2025-11-06T02:01:52Z" level=debug msg="obtained new client token: AADpipqtV5L0U+1ut1outu988p0ysgBZex0LWF82ClgNfXUtnEyEPrzCWxo0uFwKOgVPEE+LBGGsu5Zkccsx5wmbbDcV0RhQqiTIqYA0FA4oRwr6ZbiMwHrilgqHc3FVeKNoYtS2OYfsN7Yykw7UwTe/BDwMh+vCMbjH52kBTTz3hQyI+9/aMZcJvKwdfHdbU23nYBbaToSMU4fgAgprMTcTEI3R+0odbjidvHE3p0qf8cmHogSPSzQ=" Nov 06 02:01:52 volumio go-librespot[1625]: time="2025-11-06T02:01:52Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 06 02:01:53 volumio go-librespot[1625]: time="2025-11-06T02:01:53Z" level=debug msg="completed keyexchange" Nov 06 02:01:53 volumio go-librespot[1625]: time="2025-11-06T02:01:53Z" level=debug msg="completed challenge" Nov 06 02:01:53 volumio go-librespot[1625]: time="2025-11-06T02:01:53Z" 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 02:01:53 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 02:01:53 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 02:01:54 volumio volumio[978]: info: Initializing connection to go-librespot Websocket Nov 06 02:01:54 volumio volumio[978]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 02:01:56 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 384. Nov 06 02:01:56 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 02:01:56 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 02:01:56 volumio go-librespot[1632]: go-librespot daemon starting... Nov 06 02:01:56 volumio go-librespot[1633]: time="2025-11-06T02:01:56Z" level=info msg="running go-librespot 0.4.0" Nov 06 02:01:56 volumio go-librespot[1633]: time="2025-11-06T02:01:56Z" level=debug msg="app state loaded" Nov 06 02:01:56 volumio go-librespot[1633]: time="2025-11-06T02:01:56Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 02:01:56 volumio go-librespot[1633]: time="2025-11-06T02:01:56Z" 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 02:01:56 volumio go-librespot[1633]: time="2025-11-06T02:01:56Z" 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 02:01:56 volumio go-librespot[1633]: time="2025-11-06T02:01:56Z" 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 02:01:56 volumio go-librespot[1633]: time="2025-11-06T02:01:56Z" level=info msg="zeroconf server listening on port 35209" Nov 06 02:01:56 volumio go-librespot[1633]: time="2025-11-06T02:01:56Z" level=debug msg="obtained new client token: AAC6LyAvLkkoDbvSsxiUxds692wHWpXv+6pwCNwQ/rWwxSiARIGNgaeEBKUyMgEbgjSjngirtuQ1fjipT8im28Uil2YXtT+WZS6SqQUEFmi2CT/Son2Lzm5u3gAmP6ZEPuZBKe/ZO2NYfcGFsSXSMf4BYhX7fKKOcpSz5NbkU5aGikNKBZNK3I5oqcsTh0z9a1OR6CnWNaH7NKvh8ji5mTtLU1hvQItyBEUmhimGXw8R305DHbYfR/A=" Nov 06 02:01:56 volumio go-librespot[1633]: time="2025-11-06T02:01:56Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 06 02:01:56 volumio go-librespot[1633]: time="2025-11-06T02:01:56Z" level=debug msg="completed keyexchange" Nov 06 02:01:56 volumio go-librespot[1633]: time="2025-11-06T02:01:56Z" level=debug msg="completed challenge" Nov 06 02:01:56 volumio go-librespot[1633]: time="2025-11-06T02:01:56Z" 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 02:01:56 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 02:01:56 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 02:01:57 volumio volumio[978]: info: Initializing connection to go-librespot Websocket Nov 06 02:01:57 volumio volumio[978]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 02:01:59 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 385. Nov 06 02:01:59 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 02:01:59 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 02:01:59 volumio go-librespot[1654]: go-librespot daemon starting... Nov 06 02:01:59 volumio go-librespot[1655]: time="2025-11-06T02:01:59Z" level=info msg="running go-librespot 0.4.0" Nov 06 02:01:59 volumio go-librespot[1655]: time="2025-11-06T02:01:59Z" level=debug msg="app state loaded" Nov 06 02:01:59 volumio go-librespot[1655]: time="2025-11-06T02:01:59Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 02:01:59 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Nov 06 02:01:59 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Nov 06 02:01:59 volumio go-librespot[1655]: time="2025-11-06T02:01: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 02:01:59 volumio go-librespot[1655]: time="2025-11-06T02:01: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 02:01:59 volumio go-librespot[1655]: time="2025-11-06T02:01: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 02:01:59 volumio go-librespot[1655]: time="2025-11-06T02:01:59Z" level=info msg="zeroconf server listening on port 39523" Nov 06 02:01:59 volumio go-librespot[1655]: time="2025-11-06T02:01:59Z" level=debug msg="obtained new client token: AAAcGzWoYwuE07YtO2gscfmRsEB5X4cTNZWwJAKa+Ftg+4PHGoWkIPN/WoTVF8xk0Pn+EABkMZ28bXoDlbZWlPQPgqMLFdPrtqvGbSijmwWtDuOst2d2VZc0eGylZxt53IicVF3CGnXb4Na82AWe0d/d+rXhrhUN6HFsM7hneb25e4yJrSX0jro7LyK4IWXRNqunK2tf9L2XKgYGCdgFt4pWu9wAoDV6tbITZWIu53bq/CxBAIrvopU=" Nov 06 02:01:59 volumio go-librespot[1655]: time="2025-11-06T02:01:59Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 06 02:02:00 volumio go-librespot[1655]: time="2025-11-06T02:02:00Z" level=debug msg="completed keyexchange" Nov 06 02:02:00 volumio go-librespot[1655]: time="2025-11-06T02:02:00Z" level=debug msg="completed challenge" Nov 06 02:02:00 volumio go-librespot[1655]: time="2025-11-06T02:02:00Z" 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 02:02:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 02:02:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 02:02:00 volumio volumio[978]: info: Initializing connection to go-librespot Websocket Nov 06 02:02:00 volumio volumio[978]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 02:02:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 386. Nov 06 02:02:03 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 02:02:03 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 02:02:03 volumio go-librespot[1666]: go-librespot daemon starting... Nov 06 02:02:03 volumio go-librespot[1667]: time="2025-11-06T02:02:03Z" level=info msg="running go-librespot 0.4.0" Nov 06 02:02:03 volumio go-librespot[1667]: time="2025-11-06T02:02:03Z" level=debug msg="app state loaded" Nov 06 02:02:03 volumio go-librespot[1667]: time="2025-11-06T02:02:03Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 02:02:03 volumio go-librespot[1667]: time="2025-11-06T02:02: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-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 06 02:02:03 volumio go-librespot[1667]: time="2025-11-06T02:02:03Z" 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 02:02:03 volumio go-librespot[1667]: time="2025-11-06T02:02:03Z" 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 02:02:03 volumio go-librespot[1667]: time="2025-11-06T02:02:03Z" level=info msg="zeroconf server listening on port 39287" Nov 06 02:02:03 volumio go-librespot[1667]: time="2025-11-06T02:02:03Z" level=debug msg="obtained new client token: AACbCnvG/Gufp44bPD9yYBaMgOj5KG8tfHgse7Er+40nC4e7eY0b5ayoR9cRvtMn8yzsohIWdR47qCqlFkGb9JkmlWTAPI6qAIWBcJ6d5WZiBAWAsZEk9KW1ytc2H8iSHGFib3hoBM09SVM3GmS6AgYHNbo1t3IfDzB8Q0imZBefHqMgeh/9kbA3hSZGErBULEndxOMKKw8BTrsAlRTe3etV+qNHVlcoUd3yIgoVimeQHGVha7E4W48=" Nov 06 02:02:03 volumio go-librespot[1667]: time="2025-11-06T02:02:03Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 06 02:02:03 volumio go-librespot[1667]: time="2025-11-06T02:02:03Z" level=debug msg="completed keyexchange" Nov 06 02:02:03 volumio go-librespot[1667]: time="2025-11-06T02:02:03Z" level=debug msg="completed challenge" Nov 06 02:02:03 volumio go-librespot[1667]: time="2025-11-06T02:02: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 02:02:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 02:02:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 02:02:03 volumio volumio[978]: info: Initializing connection to go-librespot Websocket Nov 06 02:02:03 volumio volumio[978]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 02:02:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 387. Nov 06 02:02:06 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 02:02:06 volumio volumio[978]: info: Initializing connection to go-librespot Websocket Nov 06 02:02:06 volumio volumio[978]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 02:02:06 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 02:02:06 volumio go-librespot[1674]: go-librespot daemon starting... Nov 06 02:02:06 volumio go-librespot[1675]: time="2025-11-06T02:02:06Z" level=info msg="running go-librespot 0.4.0" Nov 06 02:02:06 volumio go-librespot[1675]: time="2025-11-06T02:02:06Z" level=debug msg="app state loaded" Nov 06 02:02:06 volumio go-librespot[1675]: time="2025-11-06T02:02:06Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 02:02:06 volumio go-librespot[1675]: time="2025-11-06T02:02: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-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 06 02:02:06 volumio go-librespot[1675]: time="2025-11-06T02:02:06Z" 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 02:02:06 volumio go-librespot[1675]: time="2025-11-06T02:02:06Z" 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 02:02:06 volumio go-librespot[1675]: time="2025-11-06T02:02:06Z" level=info msg="zeroconf server listening on port 32847" Nov 06 02:02:07 volumio go-librespot[1675]: time="2025-11-06T02:02:07Z" level=debug msg="obtained new client token: AADTzgpg5e5WUhAoZb3mhL5YBg5XXx6V8RjBRloG2Pq8zJHTJ1f6NeL8+e86hIENaI8UrotGzmKZWrxQLDsvtQajyc7EGehp4iARwtxixlmbAsYvB+O1ZyfrtCol13ZFxWSCJ+V+/bf2vyymFVihYTk0JX6Q+y6XoVUOnGcfJdIQsWVCKfcvvby323JyFM7rNYqDBfO+0vgbjEWxMU6lR6sCoXMzTPwm5D2QQvWwuBpY/Q4Z+xYF" Nov 06 02:02:07 volumio go-librespot[1675]: time="2025-11-06T02:02:07Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 06 02:02:07 volumio go-librespot[1675]: time="2025-11-06T02:02:07Z" level=debug msg="completed keyexchange" Nov 06 02:02:07 volumio go-librespot[1675]: time="2025-11-06T02:02:07Z" level=debug msg="completed challenge" Nov 06 02:02:07 volumio go-librespot[1675]: time="2025-11-06T02:02:07Z" 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 02:02:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 02:02:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 02:02:08 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Nov 06 02:02:09 volumio volumio[978]: info: Initializing connection to go-librespot Websocket Nov 06 02:02:09 volumio volumio[978]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 02:02:09 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Nov 06 02:02:09 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Nov 06 02:02:09 volumio volumio[978]: info: Listing playlists Nov 06 02:02:09 volumio volumio[978]: info: Listing playlists Nov 06 02:02:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 388. Nov 06 02:02:10 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 02:02:10 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 02:02:10 volumio go-librespot[1696]: go-librespot daemon starting... Nov 06 02:02:10 volumio go-librespot[1697]: time="2025-11-06T02:02:10Z" level=info msg="running go-librespot 0.4.0" Nov 06 02:02:10 volumio go-librespot[1697]: time="2025-11-06T02:02:10Z" level=debug msg="app state loaded" Nov 06 02:02:10 volumio go-librespot[1697]: time="2025-11-06T02:02:10Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 02:02:10 volumio go-librespot[1697]: time="2025-11-06T02:02: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]" Nov 06 02:02:10 volumio go-librespot[1697]: time="2025-11-06T02:02: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]" Nov 06 02:02:10 volumio go-librespot[1697]: time="2025-11-06T02:02: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]" Nov 06 02:02:10 volumio go-librespot[1697]: time="2025-11-06T02:02:10Z" level=info msg="zeroconf server listening on port 41431" Nov 06 02:02:10 volumio go-librespot[1697]: time="2025-11-06T02:02:10Z" level=debug msg="obtained new client token: AABG/HHSw1O2bU7f/IvGHM7vdIxUYifSuvdmGECby6nlsKIc/zvMJ65WFhYr4wfRLEEcVyt9K7NzmOykkXBkhMrxFbnN/uaEeIcCCXjJIaBrrLbjSvEoY80SJ72QXj6Bi1zWaml2K19675dMfsXYuVr6evbVzFfsSJEpPdz4G+w3G81t25dYvxyxVaP4rsm5eRVg52CJkGN2g7v8qbwP7jzjnQiubV0yaG60RbvDgFAcYRSKtIrvoco=" Nov 06 02:02:10 volumio go-librespot[1697]: time="2025-11-06T02:02:10Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 06 02:02:10 volumio go-librespot[1697]: time="2025-11-06T02:02:10Z" level=debug msg="completed keyexchange" Nov 06 02:02:10 volumio go-librespot[1697]: time="2025-11-06T02:02:10Z" level=debug msg="completed challenge" Nov 06 02:02:10 volumio go-librespot[1697]: time="2025-11-06T02:02: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 02:02:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 02:02:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 02:02:12 volumio volumio[978]: info: Initializing connection to go-librespot Websocket Nov 06 02:02:12 volumio volumio[978]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 02:02:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 389. Nov 06 02:02:13 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 02:02:13 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 02:02:13 volumio go-librespot[1704]: go-librespot daemon starting... Nov 06 02:02:13 volumio go-librespot[1705]: time="2025-11-06T02:02:13Z" level=info msg="running go-librespot 0.4.0" Nov 06 02:02:13 volumio go-librespot[1705]: time="2025-11-06T02:02:13Z" level=debug msg="app state loaded" Nov 06 02:02:13 volumio go-librespot[1705]: time="2025-11-06T02:02:13Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 02:02:13 volumio go-librespot[1705]: time="2025-11-06T02:02: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-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Nov 06 02:02:13 volumio go-librespot[1705]: time="2025-11-06T02:02:13Z" 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 02:02:13 volumio go-librespot[1705]: time="2025-11-06T02:02:13Z" 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 02:02:13 volumio go-librespot[1705]: time="2025-11-06T02:02:13Z" level=info msg="zeroconf server listening on port 46541" Nov 06 02:02:13 volumio go-librespot[1705]: time="2025-11-06T02:02:13Z" level=debug msg="obtained new client token: AADvaTvsIcNPL8NtkgHJrdEeQ5Eulsfklf+nKjddilTrE+irOQ3QLzhmaFdi/kxkR+b5L7/vXIRzOEqRxCDJzPumBURG9dwofoTPg5i2gjTrYIn8h+4pa46ivwrvP0zYofoS7tddPtgH8pZ80XH4IXRBGIOfi9N1uU28cMDFU0Cc+nfF3SkQRhdoEyBE8RynUK5Ed1l0BGC/Ki/zy0aHPRTNEkpgpXHqjfV4nmb0139n3A4Iw1kTzYg=" Nov 06 02:02:13 volumio go-librespot[1705]: time="2025-11-06T02:02:13Z" 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 02:02:14 volumio go-librespot[1705]: time="2025-11-06T02:02:14Z" level=debug msg="connected to ap-gew1.spotify.com:443" Nov 06 02:02:14 volumio go-librespot[1705]: time="2025-11-06T02:02:14Z" level=debug msg="completed keyexchange" Nov 06 02:02:14 volumio go-librespot[1705]: time="2025-11-06T02:02:14Z" level=debug msg="completed challenge" Nov 06 02:02:14 volumio go-librespot[1705]: time="2025-11-06T02:02:14Z" 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 02:02:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 02:02:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 02:02:15 volumio volumio[978]: info: Initializing connection to go-librespot Websocket Nov 06 02:02:15 volumio volumio[978]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 02:02:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 390. Nov 06 02:02:17 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 02:02:17 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 02:02:17 volumio go-librespot[1726]: go-librespot daemon starting... Nov 06 02:02:17 volumio go-librespot[1727]: time="2025-11-06T02:02:17Z" level=info msg="running go-librespot 0.4.0" Nov 06 02:02:17 volumio go-librespot[1727]: time="2025-11-06T02:02:17Z" level=debug msg="app state loaded" Nov 06 02:02:17 volumio go-librespot[1727]: time="2025-11-06T02:02:17Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 02:02:17 volumio go-librespot[1727]: time="2025-11-06T02:02: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-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 06 02:02:17 volumio go-librespot[1727]: time="2025-11-06T02:02:17Z" 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 02:02:17 volumio go-librespot[1727]: time="2025-11-06T02:02:17Z" 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 02:02:17 volumio go-librespot[1727]: time="2025-11-06T02:02:17Z" level=info msg="zeroconf server listening on port 38147" Nov 06 02:02:17 volumio go-librespot[1727]: time="2025-11-06T02:02:17Z" level=debug msg="obtained new client token: AAAKwiNScv/peiIhkpcda+rFLd/YNHqF5cZJ7Zdo7dVrv0exS5RTraIw3WDUnMolRROJCXwRP9jmwFZ5ja3uqf8Gx6QJ2EfwPanTk/9TcOtRNAo8Toa2sCMRp09KBOfIiSTneabL6Puwg3tPNS7A1CPeUToRuu5h6Sz7yr3LyrIy/9xR0Ie3sZO0GQfCzWtMUkzVnk9u1pqIevOqCHM9SWYbXrvOYgEcM1BUUuZPgs1PRYj5qMFgCCI=" Nov 06 02:02:17 volumio go-librespot[1727]: time="2025-11-06T02:02:17Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 06 02:02:17 volumio go-librespot[1727]: time="2025-11-06T02:02:17Z" level=debug msg="completed keyexchange" Nov 06 02:02:17 volumio go-librespot[1727]: time="2025-11-06T02:02:17Z" level=debug msg="completed challenge" Nov 06 02:02:17 volumio go-librespot[1727]: time="2025-11-06T02:02: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 02:02:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 02:02:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 02:02:18 volumio volumio[978]: info: Initializing connection to go-librespot Websocket Nov 06 02:02:18 volumio volumio[978]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 02:02:19 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Nov 06 02:02:19 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Nov 06 02:02:19 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Nov 06 02:02:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 391. Nov 06 02:02:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 02:02:20 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 02:02:20 volumio go-librespot[1735]: go-librespot daemon starting... Nov 06 02:02:20 volumio go-librespot[1736]: time="2025-11-06T02:02:20Z" level=info msg="running go-librespot 0.4.0" Nov 06 02:02:20 volumio go-librespot[1736]: time="2025-11-06T02:02:20Z" level=debug msg="app state loaded" Nov 06 02:02:20 volumio go-librespot[1736]: time="2025-11-06T02:02:20Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 02:02:20 volumio go-librespot[1736]: time="2025-11-06T02:02: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 02:02:20 volumio go-librespot[1736]: time="2025-11-06T02:02: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 02:02:20 volumio go-librespot[1736]: time="2025-11-06T02:02: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 02:02:20 volumio go-librespot[1736]: time="2025-11-06T02:02:20Z" level=info msg="zeroconf server listening on port 41119" Nov 06 02:02:20 volumio go-librespot[1736]: time="2025-11-06T02:02:20Z" level=debug msg="obtained new client token: AACTOgKt4NaxEeOc3kvF4gXOgK2ZqwRVkA7OLg2kYLLdi2UR88zyknlw5+OnTY12pPiuP1myVyE5GgueLCbifDDfa5GoSEUV3DHtsGjggjlXD0gZSeyoVI1WiP1Z3RHu5NPiak7CON/t1yB54ycdY9XNokP1NQJiQS9JpoZg3RrIeeI8nkdkShNrAX4rjRDE5Y6okBqtEJcuDY0YFVDS0gBzoSvNF6NuEQWZ3jCN8ptxyu7oUraBMxM=" Nov 06 02:02:20 volumio go-librespot[1736]: time="2025-11-06T02:02:20Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 06 02:02:21 volumio go-librespot[1736]: time="2025-11-06T02:02:21Z" level=debug msg="completed keyexchange" Nov 06 02:02:21 volumio go-librespot[1736]: time="2025-11-06T02:02:21Z" level=debug msg="completed challenge" Nov 06 02:02:21 volumio go-librespot[1736]: time="2025-11-06T02:02:21Z" 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 02:02:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 02:02:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 02:02:21 volumio volumio[978]: info: Initializing connection to go-librespot Websocket Nov 06 02:02:21 volumio volumio[978]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 02:02:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 392. Nov 06 02:02:24 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 02:02:24 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 02:02:24 volumio go-librespot[1747]: go-librespot daemon starting... Nov 06 02:02:24 volumio go-librespot[1748]: time="2025-11-06T02:02:24Z" level=info msg="running go-librespot 0.4.0" Nov 06 02:02:24 volumio go-librespot[1748]: time="2025-11-06T02:02:24Z" level=debug msg="app state loaded" Nov 06 02:02:24 volumio go-librespot[1748]: time="2025-11-06T02:02:24Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 02:02:24 volumio go-librespot[1748]: time="2025-11-06T02:02: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-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Nov 06 02:02:24 volumio go-librespot[1748]: time="2025-11-06T02:02:24Z" 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 02:02:24 volumio go-librespot[1748]: time="2025-11-06T02:02:24Z" 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 02:02:24 volumio go-librespot[1748]: time="2025-11-06T02:02:24Z" level=info msg="zeroconf server listening on port 45777" Nov 06 02:02:24 volumio go-librespot[1748]: time="2025-11-06T02:02:24Z" level=debug msg="obtained new client token: AABCN9j72oAQ5eTP+xvPlWcLMKAtM81auKIinrqIGRc4SXLgYTRXJCL9vsG0XejplMzuSicdUgxsTJZMvmjfDq7J+OiA6FqdxyebrVlAEP4rLJKltao5qv4bE6Zy7aj/IUpHSZn+r7tzFLoFNph7uqC7PhsqUAy1WE8sy6Y+3QuNZS7UGFQmVb+bpHkWHCdovcfPMZ8V5ByAysv2oKZ96WKgUTcAxeOJ6nSWX5zETFss2picj8WtpSw=" Nov 06 02:02:24 volumio go-librespot[1748]: time="2025-11-06T02:02:24Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 06 02:02:24 volumio go-librespot[1748]: time="2025-11-06T02:02:24Z" level=debug msg="completed keyexchange" Nov 06 02:02:24 volumio go-librespot[1748]: time="2025-11-06T02:02:24Z" level=debug msg="completed challenge" Nov 06 02:02:24 volumio go-librespot[1748]: time="2025-11-06T02:02: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 02:02:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 02:02:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 02:02:24 volumio volumio[978]: info: Initializing connection to go-librespot Websocket Nov 06 02:02:24 volumio volumio[978]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 02:02:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 393. Nov 06 02:02:27 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 02:02:27 volumio volumio[978]: info: Initializing connection to go-librespot Websocket Nov 06 02:02:27 volumio volumio[978]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 02:02:27 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 02:02:27 volumio go-librespot[1769]: go-librespot daemon starting... Nov 06 02:02:27 volumio go-librespot[1770]: time="2025-11-06T02:02:27Z" level=info msg="running go-librespot 0.4.0" Nov 06 02:02:27 volumio go-librespot[1770]: time="2025-11-06T02:02:27Z" level=debug msg="app state loaded" Nov 06 02:02:27 volumio go-librespot[1770]: time="2025-11-06T02:02:27Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 02:02:27 volumio go-librespot[1770]: time="2025-11-06T02:02: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-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 06 02:02:27 volumio go-librespot[1770]: time="2025-11-06T02:02:27Z" 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 02:02:27 volumio go-librespot[1770]: time="2025-11-06T02:02:27Z" 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 02:02:27 volumio go-librespot[1770]: time="2025-11-06T02:02:27Z" level=info msg="zeroconf server listening on port 32951" Nov 06 02:02:27 volumio go-librespot[1770]: time="2025-11-06T02:02:27Z" level=debug msg="obtained new client token: AABAGcPjM3kvxoNsZa9Q/ajV5xW0YEoOrzx0i2QyEQ1gwlepuWS450vKgJkeHxBmdmU2q4gRTre3bAXSw5dfumeZt/zlr5jUzgqSB2xYm1Wefa+z3xhIYsxhbW1UNQSV9fsPYoqglomMwgHeavY5cj0ZUJ+oSHe3rkK+O/KNzxALK96ECZOd+FDBsQios1rGGvPgQgkIfCK/tRPdLLsJz3mx9JLCYTfOR8gj/+/2ql3MdLENI6fUyqk=" Nov 06 02:02:27 volumio go-librespot[1770]: time="2025-11-06T02:02:27Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 06 02:02:28 volumio go-librespot[1770]: time="2025-11-06T02:02:28Z" level=debug msg="completed keyexchange" Nov 06 02:02:28 volumio go-librespot[1770]: time="2025-11-06T02:02:28Z" level=debug msg="completed challenge" Nov 06 02:02:28 volumio go-librespot[1770]: time="2025-11-06T02:02:28Z" 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 02:02:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 02:02:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 02:02:29 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Nov 06 02:02:29 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Nov 06 02:02:29 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Nov 06 02:02:29 volumio volumio[978]: info: Listing playlists Nov 06 02:02:29 volumio volumio[978]: info: Listing playlists Nov 06 02:02:30 volumio volumio[978]: info: Initializing connection to go-librespot Websocket Nov 06 02:02:30 volumio volumio[978]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 02:02:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 394. Nov 06 02:02:31 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 02:02:31 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 02:02:31 volumio go-librespot[1778]: go-librespot daemon starting... Nov 06 02:02:31 volumio go-librespot[1779]: time="2025-11-06T02:02:31Z" level=info msg="running go-librespot 0.4.0" Nov 06 02:02:31 volumio go-librespot[1779]: time="2025-11-06T02:02:31Z" level=debug msg="app state loaded" Nov 06 02:02:31 volumio go-librespot[1779]: time="2025-11-06T02:02:31Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 02:02:31 volumio go-librespot[1779]: time="2025-11-06T02:02:31Z" 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 02:02:31 volumio go-librespot[1779]: time="2025-11-06T02:02:31Z" 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 02:02:31 volumio go-librespot[1779]: time="2025-11-06T02:02:31Z" 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 02:02:31 volumio go-librespot[1779]: time="2025-11-06T02:02:31Z" level=info msg="zeroconf server listening on port 42429" Nov 06 02:02:31 volumio go-librespot[1779]: time="2025-11-06T02:02:31Z" level=debug msg="obtained new client token: AADncOOq1yLpU8RJ/l7Beoz30BOYOv8+FawYhBhyGklR+mSTAztXFO1P2px828NiSncN7nOb3J88cQn3o4sGMB9GiSOuZZvvREdqauAoSSbnzXQUbFus6u0DuKN1MEeEAFCwri11K9bCOvoY3YN8Pq8g32bzXH/YZKg8LEQtmGtJ5E09KingesssZQao448IyI5+Gb9Oo3SQQzUKbZZiM7HBCR2/6Zs5qkJ14x9JTSQP8nlKM6AohAU=" Nov 06 02:02:31 volumio go-librespot[1779]: time="2025-11-06T02:02:31Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 06 02:02:31 volumio go-librespot[1779]: time="2025-11-06T02:02:31Z" level=debug msg="completed keyexchange" Nov 06 02:02:31 volumio go-librespot[1779]: time="2025-11-06T02:02:31Z" level=debug msg="completed challenge" Nov 06 02:02:31 volumio go-librespot[1779]: time="2025-11-06T02:02: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 02:02:31 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 02:02:31 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 02:02:33 volumio volumio[978]: info: Initializing connection to go-librespot Websocket Nov 06 02:02:33 volumio volumio[978]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 02:02:34 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 395. Nov 06 02:02:34 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 02:02:34 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 02:02:34 volumio go-librespot[1787]: go-librespot daemon starting... Nov 06 02:02:34 volumio go-librespot[1788]: time="2025-11-06T02:02:34Z" level=info msg="running go-librespot 0.4.0" Nov 06 02:02:34 volumio go-librespot[1788]: time="2025-11-06T02:02:34Z" level=debug msg="app state loaded" Nov 06 02:02:34 volumio go-librespot[1788]: time="2025-11-06T02:02:34Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 02:02:34 volumio go-librespot[1788]: time="2025-11-06T02:02: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-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 06 02:02:34 volumio go-librespot[1788]: time="2025-11-06T02:02:34Z" 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 02:02:34 volumio go-librespot[1788]: time="2025-11-06T02:02:34Z" 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 02:02:34 volumio go-librespot[1788]: time="2025-11-06T02:02:34Z" level=info msg="zeroconf server listening on port 32809" Nov 06 02:02:34 volumio go-librespot[1788]: time="2025-11-06T02:02:34Z" level=debug msg="obtained new client token: AAB4mwppLvrX3keF0vtU8PeN265fuCJIB4t/XjVxDgFALs5VSsd3zBqa8k2l1uyBwudx9+Zuh6yGC0DtQf4pv+ufoKU5LUwhYjIZK3wC4IseaeGy8cyftXQgjwsx2pedGukNFjCrFtIKD9Ha9Ky0Kd1DRd6k61xxGTN8Cz4k3RK4Qz22nc/wEo75EVvuK/M5v9/Zs5sgYi+ERkKs1hqtufxsG4GuXmS9+gsZl8upN7Ief8/dOKSzO5c=" Nov 06 02:02:34 volumio go-librespot[1788]: time="2025-11-06T02:02:34Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 06 02:02:34 volumio go-librespot[1788]: time="2025-11-06T02:02:34Z" level=debug msg="completed keyexchange" Nov 06 02:02:34 volumio go-librespot[1788]: time="2025-11-06T02:02:34Z" level=debug msg="completed challenge" Nov 06 02:02:35 volumio go-librespot[1788]: time="2025-11-06T02:02:35Z" 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 02:02:35 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 02:02:35 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 02:02:36 volumio volumio[978]: info: Initializing connection to go-librespot Websocket Nov 06 02:02:36 volumio volumio[978]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 02:02:38 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 396. Nov 06 02:02:38 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 02:02:38 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 02:02:38 volumio go-librespot[1810]: go-librespot daemon starting... Nov 06 02:02:38 volumio go-librespot[1811]: time="2025-11-06T02:02:38Z" level=info msg="running go-librespot 0.4.0" Nov 06 02:02:38 volumio go-librespot[1811]: time="2025-11-06T02:02:38Z" level=debug msg="app state loaded" Nov 06 02:02:38 volumio go-librespot[1811]: time="2025-11-06T02:02:38Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 02:02:38 volumio go-librespot[1811]: time="2025-11-06T02:02:38Z" 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 02:02:38 volumio go-librespot[1811]: time="2025-11-06T02:02:38Z" 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 02:02:38 volumio go-librespot[1811]: time="2025-11-06T02:02:38Z" 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 02:02:38 volumio go-librespot[1811]: time="2025-11-06T02:02:38Z" level=info msg="zeroconf server listening on port 43471" Nov 06 02:02:38 volumio go-librespot[1811]: time="2025-11-06T02:02:38Z" level=debug msg="obtained new client token: AACWd3Du635KPq78OLJHXxyBzsRdb2S0KuPE2UtS7xqdwfWzKUVEQHgFAiJCa7F66FmSXorsYdJmIwzgpaFjiFeZktTJx9BP2IeUH51D75lor8ucIX4Pa5CHVIVxZIDG0VlcxbrxggKUFxJUCSmIhMMQ7TG/RXeVhl+lWF9Ds/J5vLns2t/ynIvabLWemw58p6eKvZAkR8WSQh7LWtxaCqQRY+jz11eWw2OvZDmHzbD9q5wRIRR462w=" Nov 06 02:02:38 volumio go-librespot[1811]: time="2025-11-06T02:02:38Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 06 02:02:38 volumio go-librespot[1811]: time="2025-11-06T02:02:38Z" level=debug msg="completed keyexchange" Nov 06 02:02:38 volumio go-librespot[1811]: time="2025-11-06T02:02:38Z" level=debug msg="completed challenge" Nov 06 02:02:38 volumio go-librespot[1811]: time="2025-11-06T02:02: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 02:02:38 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 02:02:38 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 02:02:39 volumio volumio[978]: info: Initializing connection to go-librespot Websocket Nov 06 02:02:39 volumio volumio[978]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 02:02:39 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Nov 06 02:02:39 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Nov 06 02:02:41 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Nov 06 02:02:41 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 397. Nov 06 02:02:41 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 02:02:41 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 02:02:41 volumio go-librespot[1820]: go-librespot daemon starting... Nov 06 02:02:41 volumio go-librespot[1821]: time="2025-11-06T02:02:41Z" level=info msg="running go-librespot 0.4.0" Nov 06 02:02:41 volumio go-librespot[1821]: time="2025-11-06T02:02:41Z" level=debug msg="app state loaded" Nov 06 02:02:41 volumio go-librespot[1821]: time="2025-11-06T02:02:41Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 02:02:41 volumio go-librespot[1821]: time="2025-11-06T02:02:41Z" 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 02:02:41 volumio go-librespot[1821]: time="2025-11-06T02:02:41Z" 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 02:02:41 volumio go-librespot[1821]: time="2025-11-06T02:02:41Z" 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 02:02:41 volumio go-librespot[1821]: time="2025-11-06T02:02:41Z" level=info msg="zeroconf server listening on port 44269" Nov 06 02:02:41 volumio go-librespot[1821]: time="2025-11-06T02:02:41Z" level=debug msg="obtained new client token: AAAbmJV06oZU6qAwVOv2QS4MygFtUXAEXIjgN2qtzL/j0w38cUL6v/CotQprJhoUsKC0kbY+fNJBpUhFO9zwt/sAQGQGg6nOz5ndM7CwT4uArhytw8uwQlbDvLvTScOn7qD6F+8jdFU51DmhsgzHorH66ZcI8OizVjrj3yY0gOf0d3pFuGf6gquiKqDcYTQ5coblXnowgdl+JcWt9MqzFkZoG9RoXkJWKzPq7f6GZtjv9qxN+fnKaSg=" Nov 06 02:02:41 volumio go-librespot[1821]: time="2025-11-06T02:02:41Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 06 02:02:42 volumio go-librespot[1821]: time="2025-11-06T02:02:42Z" level=debug msg="completed keyexchange" Nov 06 02:02:42 volumio go-librespot[1821]: time="2025-11-06T02:02:42Z" level=debug msg="completed challenge" Nov 06 02:02:42 volumio go-librespot[1821]: time="2025-11-06T02:02:42Z" 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 02:02:42 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 02:02:42 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 02:02:42 volumio volumio[978]: info: Initializing connection to go-librespot Websocket Nov 06 02:02:42 volumio volumio[978]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 02:02:45 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 398. Nov 06 02:02:45 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 02:02:45 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 02:02:45 volumio go-librespot[1831]: go-librespot daemon starting... Nov 06 02:02:45 volumio go-librespot[1832]: time="2025-11-06T02:02:45Z" level=info msg="running go-librespot 0.4.0" Nov 06 02:02:45 volumio go-librespot[1832]: time="2025-11-06T02:02:45Z" level=debug msg="app state loaded" Nov 06 02:02:45 volumio go-librespot[1832]: time="2025-11-06T02:02:45Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 02:02:45 volumio go-librespot[1832]: time="2025-11-06T02:02: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 02:02:45 volumio go-librespot[1832]: time="2025-11-06T02:02: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 02:02:45 volumio go-librespot[1832]: time="2025-11-06T02:02: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 02:02:45 volumio go-librespot[1832]: time="2025-11-06T02:02:45Z" level=info msg="zeroconf server listening on port 35399" Nov 06 02:02:45 volumio go-librespot[1832]: time="2025-11-06T02:02:45Z" level=debug msg="obtained new client token: AADVe2G0ZlAb/6ptLWQarExWx43ZW47HTyhaDfejE4D3Rb6EWnWPXdBwxc92opAisrmD8J9k7stB9V5a8GkNLyWkHk4thGRAkjIE74yk5utC+CpxeiodNGhC1xrGfVV6fAPBu6Mg1oM/rVdZm6VoNCtkXH2r+4qajps14pPJBxHOSglDNDfc+V0WGkgGTfCepCKfN4QTnQcc3SdTvSfROLYNb0E+WuKkYa9ojoJsN93vbERAEdpRHQI=" Nov 06 02:02:45 volumio go-librespot[1832]: time="2025-11-06T02:02:45Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 06 02:02:45 volumio go-librespot[1832]: time="2025-11-06T02:02:45Z" level=debug msg="completed keyexchange" Nov 06 02:02:45 volumio go-librespot[1832]: time="2025-11-06T02:02:45Z" level=debug msg="completed challenge" Nov 06 02:02:45 volumio go-librespot[1832]: time="2025-11-06T02:02: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 02:02:45 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 02:02:45 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 02:02:45 volumio volumio[978]: info: Initializing connection to go-librespot Websocket Nov 06 02:02:45 volumio volumio[978]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 02:02:48 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 399. Nov 06 02:02:48 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 02:02:48 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 02:02:48 volumio go-librespot[1854]: go-librespot daemon starting... Nov 06 02:02:48 volumio volumio[978]: info: Initializing connection to go-librespot Websocket Nov 06 02:02:48 volumio go-librespot[1856]: time="2025-11-06T02:02:48Z" level=info msg="running go-librespot 0.4.0" Nov 06 02:02:48 volumio go-librespot[1856]: time="2025-11-06T02:02:48Z" level=debug msg="app state loaded" Nov 06 02:02:48 volumio go-librespot[1856]: time="2025-11-06T02:02:48Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 02:02:48 volumio go-librespot[1856]: time="2025-11-06T02:02:48Z" level=debug msg="new websocket client" Nov 06 02:02:48 volumio volumio[978]: info: Connection to go-librespot Websocket established Nov 06 02:02:48 volumio go-librespot[1856]: time="2025-11-06T02:02: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 02:02:48 volumio go-librespot[1856]: time="2025-11-06T02:02: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 02:02:48 volumio go-librespot[1856]: time="2025-11-06T02:02: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 02:02:48 volumio go-librespot[1856]: time="2025-11-06T02:02:48Z" level=info msg="zeroconf server listening on port 40023" Nov 06 02:02:48 volumio go-librespot[1856]: time="2025-11-06T02:02:48Z" level=debug msg="obtained new client token: AADQWf6AHBfCBc+RrO/d4ACegNOql0+hy2Yz+c7YcQexWghmxFEHMzQod833JjsyoMSti1z7GXoe6hqEoEWh84X+DX1nytkWB4TQXv2OhGO/wA2b7EBObaefI+cNJVXKotBunrOWB8dt3tM5618EjebcPy768q9p0xGM2PhGwV4116PbRdIe6nMwzlL/heqLeZr4fSad62ducOBuxmxBVDgpMNVMu8i8KAEWqV1TXFTJEc3gdJa1TgA=" Nov 06 02:02:48 volumio go-librespot[1856]: time="2025-11-06T02:02:48Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 06 02:02:49 volumio go-librespot[1856]: time="2025-11-06T02:02:49Z" level=debug msg="completed keyexchange" Nov 06 02:02:49 volumio go-librespot[1856]: time="2025-11-06T02:02:49Z" level=debug msg="completed challenge" Nov 06 02:02:49 volumio go-librespot[1856]: time="2025-11-06T02:02:49Z" 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 02:02:49 volumio volumio[978]: info: Connection to go-librespot Websocket closed Nov 06 02:02:49 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 02:02:49 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 02:02:49 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Nov 06 02:02:49 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Nov 06 02:02:49 volumio volumio[978]: info: Listing playlists Nov 06 02:02:49 volumio volumio[978]: info: Listing playlists Nov 06 02:02:51 volumio volumio[978]: info: Getting Spotify volume Nov 06 02:02:51 volumio volumio[978]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 06 02:02:51 volumio volumio[978]: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 02:02:51 volumio volumio[978]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Nov 06 02:02:51 volumio volumio[978]: errno: -111, Nov 06 02:02:51 volumio volumio[978]: code: 'ECONNREFUSED', Nov 06 02:02:51 volumio volumio[978]: syscall: 'connect', Nov 06 02:02:51 volumio volumio[978]: address: '127.0.0.1', Nov 06 02:02:51 volumio volumio[978]: port: 9879, Nov 06 02:02:51 volumio volumio[978]: response: undefined Nov 06 02:02:51 volumio volumio[978]: } Nov 06 02:02:51 volumio volumio[978]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 06 02:02:51 volumio sudo[1878]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-11-06 02:01' Nov 06 02:02:51 volumio sudo[1878]: 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"