Nov 05 21:55:00 volumio volumio[15414]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 05 21:55:00 volumio volumio[15414]: 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 05 21:55:00 volumio volumio[15414]: info: CoreCommandRouter::volumioGetState Nov 05 21:55:00 volumio volumio[15414]: info: CorePlayQueue::getTrack 0 Nov 05 21:55:00 volumio volumio[15414]: info: Initializing connection to go-librespot Websocket Nov 05 21:55:00 volumio volumio[15414]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 21:55:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 67. Nov 05 21:55:00 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 21:55:00 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 21:55:00 volumio go-librespot[15799]: go-librespot daemon starting... Nov 05 21:55:00 volumio go-librespot[15800]: time="2025-11-05T21:55:00Z" level=info msg="running go-librespot 0.4.0" Nov 05 21:55:00 volumio go-librespot[15800]: time="2025-11-05T21:55:00Z" level=debug msg="app state loaded" Nov 05 21:55:00 volumio go-librespot[15800]: time="2025-11-05T21:55:00Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 21:55:00 volumio go-librespot[15800]: time="2025-11-05T21:55: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 05 21:55:00 volumio go-librespot[15800]: time="2025-11-05T21:55: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 05 21:55:00 volumio go-librespot[15800]: time="2025-11-05T21:55: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 05 21:55:00 volumio go-librespot[15800]: time="2025-11-05T21:55:00Z" level=info msg="zeroconf server listening on port 46141" Nov 05 21:55:00 volumio go-librespot[15800]: time="2025-11-05T21:55:00Z" level=debug msg="obtained new client token: AADwzyKKNnpK4LqAXHhjo7Lg9khNuWn6dHfkMt6PbqzzTatYWSkyYFLLULbhbd0ayKnvuGj0laLG8vSYT+YYqrmU+xqDntI41PFwng4tsM0T2YB7WXrVT0Do3cyefW6b0aNe7Ah+9wTgbBtjw5GdTzNafXpB4tFSzDxKlwqFeEdnA6e8EBMRvbeM/wSHDsmE/oTjzDmD65z5rU4K0daQFWIxJgNkQAvRwR6RdGKSyvBo4QGPlzpPI8o=" Nov 05 21:55:00 volumio go-librespot[15800]: time="2025-11-05T21:55:00Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 21:55:01 volumio go-librespot[15800]: time="2025-11-05T21:55:01Z" level=debug msg="completed keyexchange" Nov 05 21:55:01 volumio go-librespot[15800]: time="2025-11-05T21:55:01Z" level=debug msg="completed challenge" Nov 05 21:55:01 volumio go-librespot[15800]: time="2025-11-05T21:55:01Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 21:55:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 21:55:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 21:55:03 volumio volumio[15414]: info: Initializing connection to go-librespot Websocket Nov 05 21:55:03 volumio volumio[15414]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 21:55:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 68. Nov 05 21:55:04 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 21:55:04 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 21:55:04 volumio go-librespot[15821]: go-librespot daemon starting... Nov 05 21:55:04 volumio go-librespot[15822]: time="2025-11-05T21:55:04Z" level=info msg="running go-librespot 0.4.0" Nov 05 21:55:04 volumio go-librespot[15822]: time="2025-11-05T21:55:04Z" level=debug msg="app state loaded" Nov 05 21:55:04 volumio go-librespot[15822]: time="2025-11-05T21:55:04Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 21:55:04 volumio go-librespot[15822]: time="2025-11-05T21:55:04Z" 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 05 21:55:04 volumio go-librespot[15822]: time="2025-11-05T21:55:04Z" 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 05 21:55:04 volumio go-librespot[15822]: time="2025-11-05T21:55:04Z" 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 05 21:55:04 volumio go-librespot[15822]: time="2025-11-05T21:55:04Z" level=info msg="zeroconf server listening on port 43151" Nov 05 21:55:04 volumio go-librespot[15822]: time="2025-11-05T21:55:04Z" level=debug msg="obtained new client token: AADO0hmZszlsA2M3hDg3PHmNFvuGr5ebZl+aV/jHA+IFQk51DucmaYXfgeubW5mFZxPm+DMztK/tD+1YKvV50h8x2s6Llyya1ln0zUm20Qz3psZzOvC38fpvQVrWwYCmo5gw6R3zDgorLWGqLidoRQrwJWC5ivkYn4emOxhoNR7BFEeH2dx6azgULz7j84ixj5tQXymUat1NkuiNBr0ct/uSf+e/PwrYeiVfObe8eco5XpebzvR3YEg=" Nov 05 21:55:04 volumio go-librespot[15822]: time="2025-11-05T21:55:04Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 21:55:04 volumio go-librespot[15822]: time="2025-11-05T21:55:04Z" level=debug msg="completed keyexchange" Nov 05 21:55:04 volumio go-librespot[15822]: time="2025-11-05T21:55:04Z" level=debug msg="completed challenge" Nov 05 21:55:04 volumio go-librespot[15822]: time="2025-11-05T21:55: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 05 21:55:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 21:55:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 21:55:05 volumio volumio[15414]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 05 21:55:05 volumio volumio[15414]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 05 21:55:05 volumio volumio[15414]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Nov 05 21:55:05 volumio volumio[15414]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Nov 05 21:55:05 volumio volumio[15414]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Nov 05 21:55:05 volumio volumio[15414]: info: CoreCommandRouter::volumioGetBrowseSources Nov 05 21:55:05 volumio volumio[15414]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 05 21:55:05 volumio volumio[15414]: error: MyVolumio Plugin failed to authenticate in a timely fashion Nov 05 21:55:05 volumio volumio[15414]: info: Completed starting MyVolumio Plugin Nov 05 21:55:05 volumio volumio[15414]: [Metrics] CommandRouter: 32s 52.50ms Nov 05 21:55:05 volumio volumio[15414]: info: CoreCommandRouter::volumiosetStartupVolume Nov 05 21:55:05 volumio volumio[15414]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 05 21:55:05 volumio volumio[15414]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 05 21:55:05 volumio volumio[15414]: info: CoreCommandRouter::Close All Modals sent Nov 05 21:55:05 volumio volumio[15414]: info: CoreCommandRouter::Close All Modals sent Nov 05 21:55:06 volumio volumio[15414]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 05 21:55:06 volumio volumio[15414]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 05 21:55:06 volumio volumio[15414]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 05 21:55:06 volumio volumio-remote-updater[874]: Test mode disabled Nov 05 21:55:06 volumio volumio-remote-updater[874]: Alpha mode disabled Nov 05 21:55:06 volumio volumio-remote-updater[874]: Alpha legacy test mode disabled Nov 05 21:55:06 volumio volumio[15414]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Nov 05 21:55:06 volumio volumio[15414]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Nov 05 21:55:06 volumio volumio[15414]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Nov 05 21:55:06 volumio volumio[15414]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Nov 05 21:55:06 volumio volumio[15414]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 05 21:55:06 volumio volumio[15414]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Nov 05 21:55:06 volumio volumio[15414]: info: Initializing connection to go-librespot Websocket Nov 05 21:55:06 volumio volumio[15414]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 21:55:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 69. Nov 05 21:55:07 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 21:55:07 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 21:55:07 volumio go-librespot[15839]: go-librespot daemon starting... Nov 05 21:55:07 volumio go-librespot[15840]: time="2025-11-05T21:55:07Z" level=info msg="running go-librespot 0.4.0" Nov 05 21:55:07 volumio go-librespot[15840]: time="2025-11-05T21:55:07Z" level=debug msg="app state loaded" Nov 05 21:55:07 volumio go-librespot[15840]: time="2025-11-05T21:55:07Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 21:55:07 volumio go-librespot[15840]: time="2025-11-05T21:55: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 05 21:55:07 volumio go-librespot[15840]: time="2025-11-05T21:55: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 05 21:55:07 volumio go-librespot[15840]: time="2025-11-05T21:55: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 05 21:55:07 volumio go-librespot[15840]: time="2025-11-05T21:55:07Z" level=info msg="zeroconf server listening on port 35675" Nov 05 21:55:07 volumio go-librespot[15840]: time="2025-11-05T21:55:07Z" level=debug msg="obtained new client token: AAAybkk6/O2nCsYEruqV1uoVgj/wfJpBmK0WEQCRGC388RNqR4wTr4Sc0kH/+1BliimoJcAPV1awJJMAqQZZEjF0KjfbHfiyrKFw4fWb4KAr93GFFoInGIVY3s8qVaV656f4p0TXJubKAfJW/nsxydOidd8nXrRKaP6QqetMM5BmHX39qg5R5MSE6hvG6NEX+rO8sdc7FKnvJMEqeDKhhU/tL4ppjWaA78p7+CAZ1Ty61U6raAd3Zto=" Nov 05 21:55:07 volumio go-librespot[15840]: time="2025-11-05T21:55:07Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 21:55:08 volumio go-librespot[15840]: time="2025-11-05T21:55:08Z" level=debug msg="completed keyexchange" Nov 05 21:55:08 volumio go-librespot[15840]: time="2025-11-05T21:55:08Z" level=debug msg="completed challenge" Nov 05 21:55:08 volumio go-librespot[15840]: time="2025-11-05T21:55:08Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 21:55:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 21:55:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 21:55:08 volumio volumio[15414]: info: CoreCommandRouter::volumioGetState Nov 05 21:55:08 volumio volumio[15414]: info: CorePlayQueue::getTrack 0 Nov 05 21:55:08 volumio volumio[15414]: info: Listing playlists Nov 05 21:55:08 volumio volumio[15414]: info: Listing playlists Nov 05 21:55:09 volumio volumio[15414]: info: Initializing connection to go-librespot Websocket Nov 05 21:55:09 volumio volumio[15414]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 21:55:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 70. Nov 05 21:55:11 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 21:55:11 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 21:55:11 volumio go-librespot[15848]: go-librespot daemon starting... Nov 05 21:55:11 volumio go-librespot[15849]: time="2025-11-05T21:55:11Z" level=info msg="running go-librespot 0.4.0" Nov 05 21:55:11 volumio go-librespot[15849]: time="2025-11-05T21:55:11Z" level=debug msg="app state loaded" Nov 05 21:55:11 volumio go-librespot[15849]: time="2025-11-05T21:55:11Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 21:55:11 volumio go-librespot[15849]: time="2025-11-05T21:55:11Z" 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 05 21:55:11 volumio go-librespot[15849]: time="2025-11-05T21:55:11Z" 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 05 21:55:11 volumio go-librespot[15849]: time="2025-11-05T21:55:11Z" 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 05 21:55:11 volumio go-librespot[15849]: time="2025-11-05T21:55:11Z" level=info msg="zeroconf server listening on port 38941" Nov 05 21:55:11 volumio go-librespot[15849]: time="2025-11-05T21:55:11Z" level=debug msg="obtained new client token: AADGcZQTN5MznXE/4hhI3iESbfux8hCpDjPimyXZpzCcR7OM7cL45UiQyvjkap0SvG1zbVAkwfAPugqiJstj5yvGcqErts01If+Mjej8g2hkRMRNAVbSG5g9RegFxNH7/GIkNHoYlHfiYQG4FPVt2egWW6bztWIRjIF+i64KNNHssnoliDYN70mhoPxhdsjPYri409O6fAQHnYhgLKk5JiYp39/+kpO/MnvETKEH9Zt2oI00PBgCh5s=" Nov 05 21:55:11 volumio go-librespot[15849]: time="2025-11-05T21:55:11Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 21:55:11 volumio go-librespot[15849]: time="2025-11-05T21:55:11Z" level=debug msg="completed keyexchange" Nov 05 21:55:11 volumio go-librespot[15849]: time="2025-11-05T21:55:11Z" level=debug msg="completed challenge" Nov 05 21:55:11 volumio go-librespot[15849]: time="2025-11-05T21:55: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 05 21:55:11 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 21:55:11 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 21:55:12 volumio volumio[15414]: info: BOOT COMPLETED Nov 05 21:55:12 volumio volumio[15414]: info: Initializing connection to go-librespot Websocket Nov 05 21:55:12 volumio volumio[15414]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 21:55:14 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 71. Nov 05 21:55:14 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 21:55:14 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 21:55:14 volumio go-librespot[15870]: go-librespot daemon starting... Nov 05 21:55:14 volumio go-librespot[15871]: time="2025-11-05T21:55:14Z" level=info msg="running go-librespot 0.4.0" Nov 05 21:55:14 volumio go-librespot[15871]: time="2025-11-05T21:55:14Z" level=debug msg="app state loaded" Nov 05 21:55:14 volumio go-librespot[15871]: time="2025-11-05T21:55:14Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 21:55:14 volumio go-librespot[15871]: time="2025-11-05T21:55:14Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Nov 05 21:55:14 volumio go-librespot[15871]: time="2025-11-05T21:55:14Z" 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 05 21:55:14 volumio go-librespot[15871]: time="2025-11-05T21:55:14Z" 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 05 21:55:14 volumio go-librespot[15871]: time="2025-11-05T21:55:14Z" level=info msg="zeroconf server listening on port 35057" Nov 05 21:55:14 volumio go-librespot[15871]: time="2025-11-05T21:55:14Z" level=debug msg="obtained new client token: AAA+N5XGSFypbBxw9l8wLUV3Z5Td5/TZ2taoPSm5U2zHRZYW22yVDIWfcd4Kcm1qZVJAFq4kR9acfqi3zAvvq3vhZKsyL7t8ZfI3ZjHXFhIcjM6bNOI6v4zHBunE06CsG9Z8p3ciGel4/YH3cuZxsqOc08PBIw2WZpCJsnmMinJFRIMw1dVDR3za2+lTkVp/Oehy+kxZ7TRB8vFrwMZv5HPG6Zlm+wVnDpF8+zrpeJuDZ0iza9TcHtY=" Nov 05 21:55:14 volumio go-librespot[15871]: time="2025-11-05T21:55:14Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 21:55:15 volumio go-librespot[15871]: time="2025-11-05T21:55:15Z" level=debug msg="completed keyexchange" Nov 05 21:55:15 volumio go-librespot[15871]: time="2025-11-05T21:55:15Z" level=debug msg="completed challenge" Nov 05 21:55:15 volumio go-librespot[15871]: time="2025-11-05T21:55:15Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 21:55:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 21:55:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 21:55:15 volumio volumio[15414]: info: Initializing connection to go-librespot Websocket Nov 05 21:55:15 volumio volumio[15414]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 21:55:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 72. Nov 05 21:55:18 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 21:55:18 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 21:55:18 volumio go-librespot[15878]: go-librespot daemon starting... Nov 05 21:55:18 volumio go-librespot[15879]: time="2025-11-05T21:55:18Z" level=info msg="running go-librespot 0.4.0" Nov 05 21:55:18 volumio go-librespot[15879]: time="2025-11-05T21:55:18Z" level=debug msg="app state loaded" Nov 05 21:55:18 volumio go-librespot[15879]: time="2025-11-05T21:55:18Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 21:55:18 volumio volumio[15414]: info: CoreCommandRouter::volumioGetState Nov 05 21:55:18 volumio volumio[15414]: info: CorePlayQueue::getTrack 0 Nov 05 21:55:18 volumio go-librespot[15879]: time="2025-11-05T21:55:18Z" 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 05 21:55:18 volumio go-librespot[15879]: time="2025-11-05T21:55:18Z" 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 05 21:55:18 volumio go-librespot[15879]: time="2025-11-05T21:55:18Z" 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 05 21:55:18 volumio go-librespot[15879]: time="2025-11-05T21:55:18Z" level=info msg="zeroconf server listening on port 39933" Nov 05 21:55:18 volumio go-librespot[15879]: time="2025-11-05T21:55:18Z" level=debug msg="obtained new client token: AABW2EPJanggOmd6zcpuFf5ddKPc2UfSFjC9QAC9tuKlFnBrxpIEysWRgJBNbrVNmxHlIG00ahe5i09aYnwVEBMKo+t4GmyhasfIu1bm6dvXglPilrThtab+cniHbmqwu72omEpWWix1HwBoKNmljceBzCieyp2LW23uF8ifdOa2UJLQ1w9fkdkL5UzIrLWyQ2eEWmtVDkGRhX9t3363UdB+WNJ95CdZPn072GsNKDK5rhkTfYeqvh0=" Nov 05 21:55:18 volumio go-librespot[15879]: time="2025-11-05T21:55:18Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 21:55:18 volumio go-librespot[15879]: time="2025-11-05T21:55:18Z" level=debug msg="completed keyexchange" Nov 05 21:55:18 volumio go-librespot[15879]: time="2025-11-05T21:55:18Z" level=debug msg="completed challenge" Nov 05 21:55:18 volumio go-librespot[15879]: time="2025-11-05T21:55: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 05 21:55:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 21:55:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 21:55:18 volumio volumio[15414]: info: Initializing connection to go-librespot Websocket Nov 05 21:55:18 volumio volumio[15414]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 21:55:21 volumio volumio[15414]: info: Initializing connection to go-librespot Websocket Nov 05 21:55:21 volumio volumio[15414]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 21:55:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 73. Nov 05 21:55:21 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 21:55:21 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 21:55:21 volumio go-librespot[15890]: go-librespot daemon starting... Nov 05 21:55:21 volumio go-librespot[15891]: time="2025-11-05T21:55:21Z" level=info msg="running go-librespot 0.4.0" Nov 05 21:55:21 volumio go-librespot[15891]: time="2025-11-05T21:55:21Z" level=debug msg="app state loaded" Nov 05 21:55:21 volumio go-librespot[15891]: time="2025-11-05T21:55:21Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 21:55:21 volumio go-librespot[15891]: time="2025-11-05T21:55: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-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 05 21:55:21 volumio go-librespot[15891]: time="2025-11-05T21:55:21Z" 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 05 21:55:21 volumio go-librespot[15891]: time="2025-11-05T21:55:21Z" 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 05 21:55:21 volumio go-librespot[15891]: time="2025-11-05T21:55:21Z" level=info msg="zeroconf server listening on port 43833" Nov 05 21:55:21 volumio go-librespot[15891]: time="2025-11-05T21:55:21Z" level=debug msg="obtained new client token: AAD/Ooz8viuCTeyzvgHmo7c8lOKdu7j4s/Tynty10DGqOl3DExrJ8Pm2NV44UncCm2D84PFbUE8+L3r5J+QL7Yr9hrqrtEmaewROweZQbIowABrZv4O0IUsXLDoEbVr8IsaW7gmnTPZEiihsPpPhSvmqyJHGoCx5u81zRVEumDstLQfkO/Ml1HCg7lM1D+xIk6wrHgGrTWB9cLaD2I0G4q7D5wknRbWsHUaA0DcNe5b9AG/mNT9lwcs=" Nov 05 21:55:21 volumio go-librespot[15891]: time="2025-11-05T21:55:21Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 21:55:22 volumio go-librespot[15891]: time="2025-11-05T21:55:22Z" level=debug msg="completed keyexchange" Nov 05 21:55:22 volumio go-librespot[15891]: time="2025-11-05T21:55:22Z" level=debug msg="completed challenge" Nov 05 21:55:22 volumio go-librespot[15891]: time="2025-11-05T21:55:22Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 21:55:22 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 21:55:22 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 21:55:24 volumio volumio[15414]: info: Initializing connection to go-librespot Websocket Nov 05 21:55:24 volumio volumio[15414]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 21:55:25 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 74. Nov 05 21:55:25 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 21:55:25 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 21:55:25 volumio go-librespot[15913]: go-librespot daemon starting... Nov 05 21:55:25 volumio go-librespot[15914]: time="2025-11-05T21:55:25Z" level=info msg="running go-librespot 0.4.0" Nov 05 21:55:25 volumio go-librespot[15914]: time="2025-11-05T21:55:25Z" level=debug msg="app state loaded" Nov 05 21:55:25 volumio go-librespot[15914]: time="2025-11-05T21:55:25Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 21:55:25 volumio go-librespot[15914]: time="2025-11-05T21:55:25Z" 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 05 21:55:25 volumio go-librespot[15914]: time="2025-11-05T21:55:25Z" 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 05 21:55:25 volumio go-librespot[15914]: time="2025-11-05T21:55:25Z" 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 05 21:55:25 volumio go-librespot[15914]: time="2025-11-05T21:55:25Z" level=info msg="zeroconf server listening on port 38697" Nov 05 21:55:25 volumio go-librespot[15914]: time="2025-11-05T21:55:25Z" level=debug msg="obtained new client token: AAB3GTfW1E+ffoQ5ohJnyjW0UyhUk6SXHEuWsDicu3af6Jg4x+qJ4h9mYFUeu2TaVZm/Isyw0sFLq1MJQQytDMjJZpiHeJnl7HeIeuMoKrPXhyP1mgJ2WiMrFdrkvEIB6fWMssNbOUeniATYHZ2pnjwNc5+8cFXnmtHMJ1C91D3NkDyYbUtu3vqiW36Czf/Ytzq4DAqkNOrWBuZtjKK9MZjMYPvQBhIyLg8J2Lc+P1l7/aAiJUUOt+g=" Nov 05 21:55:25 volumio go-librespot[15914]: time="2025-11-05T21:55:25Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 21:55:25 volumio go-librespot[15914]: time="2025-11-05T21:55:25Z" level=debug msg="completed keyexchange" Nov 05 21:55:25 volumio go-librespot[15914]: time="2025-11-05T21:55:25Z" level=debug msg="completed challenge" Nov 05 21:55:25 volumio go-librespot[15914]: time="2025-11-05T21:55: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 05 21:55:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 21:55:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 21:55:27 volumio volumio[15414]: info: Initializing connection to go-librespot Websocket Nov 05 21:55:27 volumio volumio[15414]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 21:55:28 volumio volumio[15414]: info: CoreCommandRouter::volumioGetState Nov 05 21:55:28 volumio volumio[15414]: info: CorePlayQueue::getTrack 0 Nov 05 21:55:28 volumio volumio[15414]: info: Listing playlists Nov 05 21:55:28 volumio volumio[15414]: info: Listing playlists Nov 05 21:55:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 75. Nov 05 21:55:28 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 21:55:28 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 21:55:28 volumio go-librespot[15921]: go-librespot daemon starting... Nov 05 21:55:28 volumio go-librespot[15922]: time="2025-11-05T21:55:28Z" level=info msg="running go-librespot 0.4.0" Nov 05 21:55:28 volumio go-librespot[15922]: time="2025-11-05T21:55:28Z" level=debug msg="app state loaded" Nov 05 21:55:28 volumio go-librespot[15922]: time="2025-11-05T21:55:28Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 21:55:28 volumio go-librespot[15922]: time="2025-11-05T21:55:28Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 05 21:55:28 volumio go-librespot[15922]: time="2025-11-05T21:55:28Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 05 21:55:28 volumio go-librespot[15922]: time="2025-11-05T21:55:28Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 05 21:55:28 volumio go-librespot[15922]: time="2025-11-05T21:55:28Z" level=info msg="zeroconf server listening on port 46797" Nov 05 21:55:28 volumio go-librespot[15922]: time="2025-11-05T21:55:28Z" level=debug msg="obtained new client token: AACoNPvYqv+TavGMfDyhz2FXFatXdE3wOFl/zBxJiYSuEKlgIiYNGzjVjVKA1ZbR6aVHuPUH2qI+G6SLugbuh3/gJgLQCUAYJc9f/DlAgSIXqBAZE+/wo0Ahrj5QWNzV33KAbE2eAIzD787AIx18r+fhBogCNztqdXghZuJ9y7gPD+p6oH17zRuNzjgStmYlNXyJlZXjuVWu1uPiUsXOtYxwhmWHwF7iOxtp+UGb85eU9ZhTWrTn9/o=" Nov 05 21:55:28 volumio go-librespot[15922]: time="2025-11-05T21:55:28Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 21:55:29 volumio go-librespot[15922]: time="2025-11-05T21:55:29Z" level=debug msg="completed keyexchange" Nov 05 21:55:29 volumio go-librespot[15922]: time="2025-11-05T21:55:29Z" level=debug msg="completed challenge" Nov 05 21:55:29 volumio go-librespot[15922]: time="2025-11-05T21:55:29Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 21:55:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 21:55:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 21:55:30 volumio volumio[15414]: info: Initializing connection to go-librespot Websocket Nov 05 21:55:30 volumio volumio[15414]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 21:55:32 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 76. Nov 05 21:55:32 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 21:55:32 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 21:55:32 volumio go-librespot[15929]: go-librespot daemon starting... Nov 05 21:55:32 volumio go-librespot[15930]: time="2025-11-05T21:55:32Z" level=info msg="running go-librespot 0.4.0" Nov 05 21:55:32 volumio go-librespot[15930]: time="2025-11-05T21:55:32Z" level=debug msg="app state loaded" Nov 05 21:55:32 volumio go-librespot[15930]: time="2025-11-05T21:55:32Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 21:55:32 volumio go-librespot[15930]: time="2025-11-05T21:55:32Z" 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 05 21:55:32 volumio go-librespot[15930]: time="2025-11-05T21:55:32Z" 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 05 21:55:32 volumio go-librespot[15930]: time="2025-11-05T21:55:32Z" 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 05 21:55:32 volumio go-librespot[15930]: time="2025-11-05T21:55:32Z" level=info msg="zeroconf server listening on port 45131" Nov 05 21:55:32 volumio go-librespot[15930]: time="2025-11-05T21:55:32Z" level=debug msg="obtained new client token: AAA76VVTo0OJ+0xX0trD1oXtCCUPaW6i3GitlOkilrsaZdVt/asfWn9B/PfJPhtb5LYzCriuDz4eOmBzSsI04frkg92c8VgbODKsKlvgROvvOkxQn3kFgt+Rf2KDwMQYfkhZg+3sjNfEP7FbvEcI4Yrbl41j1NeCG13A6dzINX1PPS1KdZRQaBdKex3k/cCJJ8qG4csOUsYBMSQy1mgtpLKjpemdIquBrpAKCRHRr9mcv8GxdIPXW7U=" Nov 05 21:55:32 volumio go-librespot[15930]: time="2025-11-05T21:55:32Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 21:55:32 volumio go-librespot[15930]: time="2025-11-05T21:55:32Z" level=debug msg="completed keyexchange" Nov 05 21:55:32 volumio go-librespot[15930]: time="2025-11-05T21:55:32Z" level=debug msg="completed challenge" Nov 05 21:55:32 volumio go-librespot[15930]: time="2025-11-05T21:55: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 05 21:55:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 21:55:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 21:55:33 volumio volumio[15414]: info: Initializing connection to go-librespot Websocket Nov 05 21:55:33 volumio volumio[15414]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 21:55:35 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 77. Nov 05 21:55:35 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 21:55:35 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 21:55:35 volumio go-librespot[15951]: go-librespot daemon starting... Nov 05 21:55:35 volumio go-librespot[15952]: time="2025-11-05T21:55:35Z" level=info msg="running go-librespot 0.4.0" Nov 05 21:55:35 volumio go-librespot[15952]: time="2025-11-05T21:55:35Z" level=debug msg="app state loaded" Nov 05 21:55:35 volumio go-librespot[15952]: time="2025-11-05T21:55:35Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 21:55:35 volumio go-librespot[15952]: time="2025-11-05T21:55:35Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 05 21:55:35 volumio go-librespot[15952]: time="2025-11-05T21:55:35Z" 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 05 21:55:35 volumio go-librespot[15952]: time="2025-11-05T21:55:35Z" 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 05 21:55:35 volumio go-librespot[15952]: time="2025-11-05T21:55:35Z" level=info msg="zeroconf server listening on port 45829" Nov 05 21:55:35 volumio go-librespot[15952]: time="2025-11-05T21:55:35Z" level=debug msg="obtained new client token: AAA/KPRQhEy/Vl6sUPQ4dsp34Jz0AjO0zFPB8hmP3bQNGFgx7gVltMLzjlbBVBeaox5b5IF8mbe6Vg0OUszo5E1+5QLhfnYufRIVgSTp5JLnth4oa02pWq7mNtpUESYTpr87pLnlkSYbO8RLfQp1NnjnSAeqyZkxrleECR7sBLZqe6i+khMEqcrAwc7yheV5g4MO0wSqvuLqOSgpTgYLDL6WVaGQwNVhGKC/nf2bPZmsF8e/67FX3Wc=" Nov 05 21:55:35 volumio go-librespot[15952]: time="2025-11-05T21:55:35Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 21:55:36 volumio go-librespot[15952]: time="2025-11-05T21:55:36Z" level=debug msg="completed keyexchange" Nov 05 21:55:36 volumio go-librespot[15952]: time="2025-11-05T21:55:36Z" level=debug msg="completed challenge" Nov 05 21:55:36 volumio go-librespot[15952]: time="2025-11-05T21:55:36Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 21:55:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 21:55:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 21:55:36 volumio volumio[15414]: info: Initializing connection to go-librespot Websocket Nov 05 21:55:36 volumio volumio[15414]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 21:55:38 volumio volumio[15414]: info: CoreCommandRouter::volumioGetState Nov 05 21:55:38 volumio volumio[15414]: info: CorePlayQueue::getTrack 0 Nov 05 21:55:39 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 78. Nov 05 21:55:39 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 21:55:39 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 21:55:39 volumio go-librespot[15959]: go-librespot daemon starting... Nov 05 21:55:39 volumio go-librespot[15960]: time="2025-11-05T21:55:39Z" level=info msg="running go-librespot 0.4.0" Nov 05 21:55:39 volumio go-librespot[15960]: time="2025-11-05T21:55:39Z" level=debug msg="app state loaded" Nov 05 21:55:39 volumio go-librespot[15960]: time="2025-11-05T21:55:39Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 21:55:39 volumio go-librespot[15960]: time="2025-11-05T21:55:39Z" 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 05 21:55:39 volumio go-librespot[15960]: time="2025-11-05T21:55:39Z" 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 05 21:55:39 volumio go-librespot[15960]: time="2025-11-05T21:55:39Z" 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 05 21:55:39 volumio go-librespot[15960]: time="2025-11-05T21:55:39Z" level=info msg="zeroconf server listening on port 38361" Nov 05 21:55:39 volumio go-librespot[15960]: time="2025-11-05T21:55:39Z" level=debug msg="obtained new client token: AAC6q2UxPFAyP0d6UwXa1bTVIsUcBgEZJpFnIgaHCkAC3BwHWDd5hbbx0Mnbi4aaN3ThxOeA9IQzntOTT45QDPYsdEkkDmhIM5c5WIw7iujkn+bFq68jVjJhQGWifsgFnS285YeHGslNx8rMO4BTcDdqw0To/iC/icZwj1r4P9nw5+GXGKiyMJVxtYLPOoqEoFulYaEjGzJvrmv2h22xXdul2n1X5RNjaK299YdJx+yWPJ9QQFl1q5Y=" Nov 05 21:55:39 volumio go-librespot[15960]: time="2025-11-05T21:55:39Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 21:55:39 volumio go-librespot[15960]: time="2025-11-05T21:55:39Z" level=debug msg="completed keyexchange" Nov 05 21:55:39 volumio go-librespot[15960]: time="2025-11-05T21:55:39Z" level=debug msg="completed challenge" Nov 05 21:55:39 volumio go-librespot[15960]: time="2025-11-05T21:55: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 05 21:55:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 21:55:39 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 21:55:39 volumio volumio[15414]: info: Initializing connection to go-librespot Websocket Nov 05 21:55:39 volumio volumio[15414]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 21:55:42 volumio volumio[15414]: info: Initializing connection to go-librespot Websocket Nov 05 21:55:42 volumio volumio[15414]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 21:55:42 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 79. Nov 05 21:55:42 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 21:55:42 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 21:55:42 volumio go-librespot[15971]: go-librespot daemon starting... Nov 05 21:55:42 volumio go-librespot[15972]: time="2025-11-05T21:55:42Z" level=info msg="running go-librespot 0.4.0" Nov 05 21:55:42 volumio go-librespot[15972]: time="2025-11-05T21:55:42Z" level=debug msg="app state loaded" Nov 05 21:55:42 volumio go-librespot[15972]: time="2025-11-05T21:55:42Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 21:55:42 volumio go-librespot[15972]: time="2025-11-05T21:55:42Z" 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 05 21:55:42 volumio go-librespot[15972]: time="2025-11-05T21:55:42Z" 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 05 21:55:42 volumio go-librespot[15972]: time="2025-11-05T21:55:42Z" 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 05 21:55:42 volumio go-librespot[15972]: time="2025-11-05T21:55:42Z" level=info msg="zeroconf server listening on port 35421" Nov 05 21:55:42 volumio go-librespot[15972]: time="2025-11-05T21:55:42Z" level=debug msg="obtained new client token: AAD5SAQnxslWxaWPQR1odarPmszCm2f0YRp4UvjZhM2QHd6c/kaEgVATdvgDzJlUkFWgdeaM5dE58GTRr9Ab89+8TDkLp/omM0ZPboIHp6fPkYykyCKkS7EK+v4MFlxTcAvZzia3JvLSMOImAOTryZB5ms3d+zO0vz9mJiy5WZ/dGxGIk2VZJKs6wNvkg9xsQEjOT0EK25564IzBiAqQ4BmZPdThie4UsfSv+M9zzKaWJAXc+FajxVw=" Nov 05 21:55:42 volumio go-librespot[15972]: time="2025-11-05T21:55:42Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 21:55:43 volumio go-librespot[15972]: time="2025-11-05T21:55:43Z" level=debug msg="completed keyexchange" Nov 05 21:55:43 volumio go-librespot[15972]: time="2025-11-05T21:55:43Z" level=debug msg="completed challenge" Nov 05 21:55:43 volumio go-librespot[15972]: time="2025-11-05T21:55:43Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 21:55:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 21:55:43 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 21:55:45 volumio volumio[15414]: info: Initializing connection to go-librespot Websocket Nov 05 21:55:45 volumio volumio[15414]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 21:55:46 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 80. Nov 05 21:55:46 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 21:55:46 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 21:55:46 volumio go-librespot[15993]: go-librespot daemon starting... Nov 05 21:55:46 volumio go-librespot[15994]: time="2025-11-05T21:55:46Z" level=info msg="running go-librespot 0.4.0" Nov 05 21:55:46 volumio go-librespot[15994]: time="2025-11-05T21:55:46Z" level=debug msg="app state loaded" Nov 05 21:55:46 volumio go-librespot[15994]: time="2025-11-05T21:55:46Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 21:55:46 volumio go-librespot[15994]: time="2025-11-05T21:55:46Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 05 21:55:46 volumio go-librespot[15994]: time="2025-11-05T21:55:46Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 05 21:55:46 volumio go-librespot[15994]: time="2025-11-05T21:55:46Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 05 21:55:46 volumio go-librespot[15994]: time="2025-11-05T21:55:46Z" level=info msg="zeroconf server listening on port 37105" Nov 05 21:55:46 volumio go-librespot[15994]: time="2025-11-05T21:55:46Z" level=debug msg="obtained new client token: AACbU1KTkrwpUYJZS7ZEYTmPcJGFugH6GiByyDAhBv8AIGh4IppTOdbK8TiiikbkAgJIzxDpaK9U68YKk8FPcLAFVJfOCTHuNxFhgGgrER0DAobopBjS3ACgfJDP78sVW8o0+4u5TR6k1CkMfSsbvh8/1MxfjIrbVbG4FZ/CywVJi5GRPXWcVwZqZtxOIXkTKnIvztbtjBfl08amY63LdADqvlD0EhrqL+DbketmINcxrI2sBVEfUXc=" Nov 05 21:55:46 volumio go-librespot[15994]: time="2025-11-05T21:55:46Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 21:55:46 volumio go-librespot[15994]: time="2025-11-05T21:55:46Z" level=debug msg="completed keyexchange" Nov 05 21:55:46 volumio go-librespot[15994]: time="2025-11-05T21:55:46Z" level=debug msg="completed challenge" Nov 05 21:55:46 volumio go-librespot[15994]: time="2025-11-05T21:55: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 05 21:55:46 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 21:55:46 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 21:55:48 volumio volumio[15414]: info: CoreCommandRouter::volumioGetState Nov 05 21:55:48 volumio volumio[15414]: info: CorePlayQueue::getTrack 0 Nov 05 21:55:48 volumio volumio[15414]: info: Listing playlists Nov 05 21:55:48 volumio volumio[15414]: info: Listing playlists Nov 05 21:55:48 volumio volumio[15414]: info: Initializing connection to go-librespot Websocket Nov 05 21:55:48 volumio volumio[15414]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 21:55:49 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 81. Nov 05 21:55:49 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 21:55:49 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 21:55:49 volumio go-librespot[16001]: go-librespot daemon starting... Nov 05 21:55:49 volumio go-librespot[16002]: time="2025-11-05T21:55:49Z" level=info msg="running go-librespot 0.4.0" Nov 05 21:55:49 volumio go-librespot[16002]: time="2025-11-05T21:55:49Z" level=debug msg="app state loaded" Nov 05 21:55:49 volumio go-librespot[16002]: time="2025-11-05T21:55:49Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 21:55:49 volumio go-librespot[16002]: time="2025-11-05T21:55: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 05 21:55:49 volumio go-librespot[16002]: time="2025-11-05T21:55: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 05 21:55:49 volumio go-librespot[16002]: time="2025-11-05T21:55: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 05 21:55:49 volumio go-librespot[16002]: time="2025-11-05T21:55:49Z" level=info msg="zeroconf server listening on port 39113" Nov 05 21:55:49 volumio go-librespot[16002]: time="2025-11-05T21:55:49Z" level=debug msg="obtained new client token: AAC2He370cT0SZCwjAoMO/Cx6Csu0fT4++Q2QyDkexPNSFbz3W1ucCx1TRc2G5bH7q9x1DVuduJdbzqarS3MwDdcCNUerf9PFxRsSmdLRZ881sDIwCfneLBSYpzKPfjnSTSVSyrGj8YNWAqcXk6h2PoMkxfih4T4+nwbZEqDWtFeyU0y3cfsyHAgiSRL8aGIk0gAN0l+vWt6fzHEcwJ+LqxnHJGjiNkL7QvaEU6AYTmd/BQCoM9J8WM=" Nov 05 21:55:49 volumio go-librespot[16002]: time="2025-11-05T21:55:49Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 21:55:50 volumio go-librespot[16002]: time="2025-11-05T21:55:50Z" level=debug msg="completed keyexchange" Nov 05 21:55:50 volumio go-librespot[16002]: time="2025-11-05T21:55:50Z" level=debug msg="completed challenge" Nov 05 21:55:50 volumio go-librespot[16002]: time="2025-11-05T21:55:50Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 21:55:50 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 21:55:50 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 21:55:51 volumio volumio[15414]: info: Initializing connection to go-librespot Websocket Nov 05 21:55:51 volumio volumio[15414]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 21:55:53 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 82. Nov 05 21:55:53 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 21:55:53 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 21:55:53 volumio go-librespot[16010]: go-librespot daemon starting... Nov 05 21:55:53 volumio go-librespot[16011]: time="2025-11-05T21:55:53Z" level=info msg="running go-librespot 0.4.0" Nov 05 21:55:53 volumio go-librespot[16011]: time="2025-11-05T21:55:53Z" level=debug msg="app state loaded" Nov 05 21:55:53 volumio go-librespot[16011]: time="2025-11-05T21:55:53Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 21:55:53 volumio go-librespot[16011]: time="2025-11-05T21:55:53Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 05 21:55:53 volumio go-librespot[16011]: time="2025-11-05T21:55:53Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 05 21:55:53 volumio go-librespot[16011]: time="2025-11-05T21:55:53Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 05 21:55:53 volumio go-librespot[16011]: time="2025-11-05T21:55:53Z" level=info msg="zeroconf server listening on port 43953" Nov 05 21:55:53 volumio go-librespot[16011]: time="2025-11-05T21:55:53Z" level=debug msg="obtained new client token: AADp4HqDhdLGCMGXdRsn32u+YUqfyl0KaChJTeLZ6e5Fnluu9LhEQY6Vejv0MSv829SlQ6EqUxjTEaux5ydYQJKABKKCJzZElf2nGGhqehccMxksNh1fFf+Y9DAwAJdqLrWyViodIb8wRkP/GM806An0tGh2WWE3pw0A5ioJdW2UqMKUUf9uzXeOLq9OGMkYJIhfbk2/ezSC1ZdXBks785/MMexH41zD5MrRWdXLZrUhJg5dbtjZ9NQ=" Nov 05 21:55:53 volumio go-librespot[16011]: time="2025-11-05T21:55:53Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 21:55:53 volumio go-librespot[16011]: time="2025-11-05T21:55:53Z" level=debug msg="completed keyexchange" Nov 05 21:55:53 volumio go-librespot[16011]: time="2025-11-05T21:55:53Z" level=debug msg="completed challenge" Nov 05 21:55:53 volumio go-librespot[16011]: time="2025-11-05T21:55: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 05 21:55:53 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 21:55:53 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 21:55:54 volumio volumio[15414]: info: Initializing connection to go-librespot Websocket Nov 05 21:55:54 volumio volumio[15414]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 21:55:56 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 83. Nov 05 21:55:56 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 21:55:56 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 21:55:56 volumio go-librespot[16032]: go-librespot daemon starting... Nov 05 21:55:56 volumio go-librespot[16033]: time="2025-11-05T21:55:56Z" level=info msg="running go-librespot 0.4.0" Nov 05 21:55:56 volumio go-librespot[16033]: time="2025-11-05T21:55:56Z" level=debug msg="app state loaded" Nov 05 21:55:56 volumio go-librespot[16033]: time="2025-11-05T21:55:56Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 21:55:56 volumio go-librespot[16033]: time="2025-11-05T21:55:56Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 05 21:55:56 volumio go-librespot[16033]: time="2025-11-05T21:55:56Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 05 21:55:56 volumio go-librespot[16033]: time="2025-11-05T21:55:56Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 05 21:55:56 volumio go-librespot[16033]: time="2025-11-05T21:55:56Z" level=info msg="zeroconf server listening on port 34187" Nov 05 21:55:56 volumio go-librespot[16033]: time="2025-11-05T21:55:56Z" level=debug msg="obtained new client token: AACAIiJhz9ljcrvE17aq1+7jdXtoqIli1hcTEQJpmWMOsTiJWLnrOz9vGUhWIhJMZtQ3YDWpOstcEPXMewme1vYMHYOz10YQDAjPSu8VFB8jRJO67ivipFotxBJVtdVKNZe82EqpYzydLs8GhunoXvplCDotKzK+y0bzQuTtdusOahwfokrUSHDs69uLG3cGNCU9Xlphtd25gbzdDMiuADFeYmMrg6bdvox3WGynG2/tcSa8CckDk6I=" Nov 05 21:55:57 volumio go-librespot[16033]: time="2025-11-05T21:55:57Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 21:55:57 volumio go-librespot[16033]: time="2025-11-05T21:55:57Z" level=debug msg="completed keyexchange" Nov 05 21:55:57 volumio go-librespot[16033]: time="2025-11-05T21:55:57Z" level=debug msg="completed challenge" Nov 05 21:55:57 volumio go-librespot[16033]: time="2025-11-05T21:55:57Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 05 21:55:57 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 21:55:57 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 21:55:57 volumio volumio[15414]: info: Initializing connection to go-librespot Websocket Nov 05 21:55:57 volumio volumio[15414]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 21:55:58 volumio volumio[15414]: info: CoreCommandRouter::volumioGetState Nov 05 21:55:58 volumio volumio[15414]: info: CorePlayQueue::getTrack 0 Nov 05 21:56:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 84. Nov 05 21:56:00 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 21:56:00 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 21:56:00 volumio go-librespot[16043]: go-librespot daemon starting... Nov 05 21:56:00 volumio go-librespot[16044]: time="2025-11-05T21:56:00Z" level=info msg="running go-librespot 0.4.0" Nov 05 21:56:00 volumio go-librespot[16044]: time="2025-11-05T21:56:00Z" level=debug msg="app state loaded" Nov 05 21:56:00 volumio go-librespot[16044]: time="2025-11-05T21:56:00Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 21:56:00 volumio go-librespot[16044]: time="2025-11-05T21:56:00Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Nov 05 21:56:00 volumio go-librespot[16044]: time="2025-11-05T21:56:00Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Nov 05 21:56:00 volumio go-librespot[16044]: time="2025-11-05T21:56:00Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Nov 05 21:56:00 volumio go-librespot[16044]: time="2025-11-05T21:56:00Z" level=info msg="zeroconf server listening on port 40517" Nov 05 21:56:00 volumio go-librespot[16044]: time="2025-11-05T21:56:00Z" level=debug msg="obtained new client token: AACIx9B7VAdwoaszKYXkwTsD7fToCYW2wA+Qy8YrD6zVbyvy1gzBKh+6oEI+rMzUl+BG1vdY2QIlI1szl9qv8KGXQ96kcZmHIGaTTntTVmUwyYfTvcdZen1p8K85W4nj8q2ep4XA8bHPWqMy5FLfNP6PYwh0chgCd0vailA2qoN60Go7Fqf5jp/VpMtMwIyQz5t6xHR2ZiU2yXdqlHKpKqvJgqYn7EKsKoH8SK1Ot1pMp8cATr0J8Yg=" Nov 05 21:56:00 volumio go-librespot[16044]: time="2025-11-05T21:56:00Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 21:56:00 volumio go-librespot[16044]: time="2025-11-05T21:56:00Z" level=debug msg="completed keyexchange" Nov 05 21:56:00 volumio go-librespot[16044]: time="2025-11-05T21:56:00Z" level=debug msg="completed challenge" Nov 05 21:56:00 volumio go-librespot[16044]: time="2025-11-05T21:56: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 05 21:56:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 21:56:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 21:56:00 volumio volumio[15414]: info: Initializing connection to go-librespot Websocket Nov 05 21:56:00 volumio volumio[15414]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 21:56:03 volumio volumio[15414]: info: Initializing connection to go-librespot Websocket Nov 05 21:56:03 volumio volumio[15414]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 21:56:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 85. Nov 05 21:56:03 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 21:56:03 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 21:56:03 volumio go-librespot[16052]: go-librespot daemon starting... Nov 05 21:56:03 volumio go-librespot[16053]: time="2025-11-05T21:56:03Z" level=info msg="running go-librespot 0.4.0" Nov 05 21:56:03 volumio go-librespot[16053]: time="2025-11-05T21:56:03Z" level=debug msg="app state loaded" Nov 05 21:56:03 volumio go-librespot[16053]: time="2025-11-05T21:56:03Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 21:56:03 volumio go-librespot[16053]: time="2025-11-05T21:56:03Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 05 21:56:03 volumio go-librespot[16053]: time="2025-11-05T21:56:03Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 05 21:56:03 volumio go-librespot[16053]: time="2025-11-05T21:56:03Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 05 21:56:03 volumio go-librespot[16053]: time="2025-11-05T21:56:03Z" level=info msg="zeroconf server listening on port 44915" Nov 05 21:56:03 volumio go-librespot[16053]: time="2025-11-05T21:56:03Z" level=debug msg="obtained new client token: AAB//Hlg173Q+/T4HjMXttgxCFtLfIRB92QCKph4C/yQnWWOjAM2OhdLR9jC87fJLXEpQZBChygKEaSiH1ea3MkFNjYtYJjBykdKelB2nMDvnUpITvYkoYssWVlQdqC5sC9I8o9md4uQFLwZme4ZWH6Diudy7w1LhIDCPKA5rvy9d5aDr4IO6QniL43HRLidFnRIfxdL3jFTpQs5P3X+PUQnukEtUbHlTBMFKMx9n5DMksH3Lq3pAzM=" Nov 05 21:56:03 volumio go-librespot[16053]: time="2025-11-05T21:56:03Z" 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 05 21:56:03 volumio go-librespot[16053]: time="2025-11-05T21:56:03Z" level=debug msg="connected to ap-gew1.spotify.com:443" Nov 05 21:56:03 volumio go-librespot[16053]: time="2025-11-05T21:56:03Z" level=debug msg="completed keyexchange" Nov 05 21:56:03 volumio go-librespot[16053]: time="2025-11-05T21:56:03Z" level=debug msg="completed challenge" Nov 05 21:56:04 volumio go-librespot[16053]: time="2025-11-05T21:56: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 05 21:56:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 21:56:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 21:56:06 volumio volumio[15414]: info: Initializing connection to go-librespot Websocket Nov 05 21:56:06 volumio volumio[15414]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 21:56:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 86. Nov 05 21:56:07 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 21:56:07 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 21:56:07 volumio go-librespot[16074]: go-librespot daemon starting... Nov 05 21:56:07 volumio go-librespot[16075]: time="2025-11-05T21:56:07Z" level=info msg="running go-librespot 0.4.0" Nov 05 21:56:07 volumio go-librespot[16075]: time="2025-11-05T21:56:07Z" level=debug msg="app state loaded" Nov 05 21:56:07 volumio go-librespot[16075]: time="2025-11-05T21:56:07Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 21:56:07 volumio go-librespot[16075]: time="2025-11-05T21:56: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 05 21:56:07 volumio go-librespot[16075]: time="2025-11-05T21:56: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 05 21:56:07 volumio go-librespot[16075]: time="2025-11-05T21:56: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 05 21:56:07 volumio go-librespot[16075]: time="2025-11-05T21:56:07Z" level=info msg="zeroconf server listening on port 41735" Nov 05 21:56:07 volumio go-librespot[16075]: time="2025-11-05T21:56:07Z" level=debug msg="obtained new client token: AABMXZBnKXO/LFQq6tI+w+PwXezvd9k2smbKYaN7Oe5LLLAu2JZs09eo5dFljhotfMCeBTbYLosBuBxlIyCWLyciR3xW6G6CC3+O0Oh9uHPxzVAJ4jpKEvmVqJBrqJnLBsMmf1Zzx40Kz1JYjf4MQgNc2XGoIcFSGGJaw0WNfL6b6PEAPLs3ckgJVTMChNk73tdMXmD7EBz6RfA+NMl8ZNVIaI/t65LZsTHvJep08tSFrHGDRwsYCAU=" Nov 05 21:56:07 volumio go-librespot[16075]: time="2025-11-05T21:56:07Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 21:56:07 volumio go-librespot[16075]: time="2025-11-05T21:56:07Z" level=debug msg="completed keyexchange" Nov 05 21:56:07 volumio go-librespot[16075]: time="2025-11-05T21:56:07Z" level=debug msg="completed challenge" Nov 05 21:56:07 volumio go-librespot[16075]: time="2025-11-05T21:56: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 05 21:56:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 21:56:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 21:56:08 volumio volumio[15414]: info: CoreCommandRouter::volumioGetState Nov 05 21:56:08 volumio volumio[15414]: info: CorePlayQueue::getTrack 0 Nov 05 21:56:08 volumio volumio[15414]: info: Listing playlists Nov 05 21:56:08 volumio volumio[15414]: info: Listing playlists Nov 05 21:56:09 volumio volumio[15414]: info: Initializing connection to go-librespot Websocket Nov 05 21:56:09 volumio volumio[15414]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 21:56:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 87. Nov 05 21:56:10 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 21:56:10 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 21:56:10 volumio go-librespot[16082]: go-librespot daemon starting... Nov 05 21:56:10 volumio go-librespot[16083]: time="2025-11-05T21:56:10Z" level=info msg="running go-librespot 0.4.0" Nov 05 21:56:10 volumio go-librespot[16083]: time="2025-11-05T21:56:10Z" level=debug msg="app state loaded" Nov 05 21:56:10 volumio go-librespot[16083]: time="2025-11-05T21:56:10Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 21:56:10 volumio go-librespot[16083]: time="2025-11-05T21:56: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 05 21:56:10 volumio go-librespot[16083]: time="2025-11-05T21:56: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 05 21:56:10 volumio go-librespot[16083]: time="2025-11-05T21:56: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 05 21:56:10 volumio go-librespot[16083]: time="2025-11-05T21:56:10Z" level=info msg="zeroconf server listening on port 34051" Nov 05 21:56:10 volumio go-librespot[16083]: time="2025-11-05T21:56:10Z" level=debug msg="obtained new client token: AAByLun4rW2xBisBC/OQxflbM0Rpln/038x5daZD9WdmHq5WHyo13740UlutgwyiVwhPtA7tFu+fKXlLtkwGvGqDpJUQRxvqukDp/4QQ8zoT9RgcSVbP+r4FraYpOSpExcBfVSKCDi2w5UwEjfagsOvCge+Uk1bSh9XmsboyOIbjK7qCHtHuJw3KtIn9EES3bVWsOh/wO7luD8+B74seN+wSt64LLC8Ox3lXo+zIrC9jR9Wbuw7Mtrs=" Nov 05 21:56:10 volumio go-librespot[16083]: time="2025-11-05T21:56:10Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 21:56:10 volumio go-librespot[16083]: time="2025-11-05T21:56:10Z" level=debug msg="completed keyexchange" Nov 05 21:56:10 volumio go-librespot[16083]: time="2025-11-05T21:56:10Z" level=debug msg="completed challenge" Nov 05 21:56:10 volumio go-librespot[16083]: time="2025-11-05T21:56: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 05 21:56:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 21:56:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 21:56:12 volumio volumio[15414]: info: Initializing connection to go-librespot Websocket Nov 05 21:56:12 volumio volumio[15414]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 21:56:14 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 88. Nov 05 21:56:14 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 21:56:14 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 21:56:14 volumio go-librespot[16090]: go-librespot daemon starting... Nov 05 21:56:14 volumio go-librespot[16091]: time="2025-11-05T21:56:14Z" level=info msg="running go-librespot 0.4.0" Nov 05 21:56:14 volumio go-librespot[16091]: time="2025-11-05T21:56:14Z" level=debug msg="app state loaded" Nov 05 21:56:14 volumio go-librespot[16091]: time="2025-11-05T21:56:14Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 21:56:14 volumio go-librespot[16091]: time="2025-11-05T21:56:14Z" 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 05 21:56:14 volumio go-librespot[16091]: time="2025-11-05T21:56:14Z" 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 05 21:56:14 volumio go-librespot[16091]: time="2025-11-05T21:56:14Z" 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 05 21:56:14 volumio go-librespot[16091]: time="2025-11-05T21:56:14Z" level=info msg="zeroconf server listening on port 44317" Nov 05 21:56:14 volumio go-librespot[16091]: time="2025-11-05T21:56:14Z" level=debug msg="obtained new client token: AAC5rl9tLJVBRXYUf779x+Z3zQgwEZo4gDlEAhUK+isk12Oi29q7dm45aonnI7/twZP1dNrj4jdXX5FkpclzkA2MiI83BfSFwF3pCM5GuTDD1Q+hicVR7b4Dn/Qyh0IxPkjJhFRaaxDdGHjtCyYL17fwJV8ZlPjgnXoG98MsHnxab2JGfojYSimEA3yHn6wbDI7o+m1pf+UML8sa7UAbkDLhtpd/7TqCto0+8+yE/iXnJdH9voyW" Nov 05 21:56:14 volumio go-librespot[16091]: time="2025-11-05T21:56:14Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 21:56:14 volumio go-librespot[16091]: time="2025-11-05T21:56:14Z" level=debug msg="completed keyexchange" Nov 05 21:56:14 volumio go-librespot[16091]: time="2025-11-05T21:56:14Z" level=debug msg="completed challenge" Nov 05 21:56:14 volumio go-librespot[16091]: time="2025-11-05T21:56: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 05 21:56:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 21:56:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 21:56:15 volumio volumio[15414]: info: Initializing connection to go-librespot Websocket Nov 05 21:56:15 volumio volumio[15414]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 21:56:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 89. Nov 05 21:56:17 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 21:56:17 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 21:56:17 volumio go-librespot[16112]: go-librespot daemon starting... Nov 05 21:56:17 volumio go-librespot[16113]: time="2025-11-05T21:56:17Z" level=info msg="running go-librespot 0.4.0" Nov 05 21:56:17 volumio go-librespot[16113]: time="2025-11-05T21:56:17Z" level=debug msg="app state loaded" Nov 05 21:56:17 volumio go-librespot[16113]: time="2025-11-05T21:56:17Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 21:56:17 volumio go-librespot[16113]: time="2025-11-05T21:56: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 05 21:56:17 volumio go-librespot[16113]: time="2025-11-05T21:56: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 05 21:56:17 volumio go-librespot[16113]: time="2025-11-05T21:56: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 05 21:56:17 volumio go-librespot[16113]: time="2025-11-05T21:56:17Z" level=info msg="zeroconf server listening on port 38651" Nov 05 21:56:17 volumio go-librespot[16113]: time="2025-11-05T21:56:17Z" level=debug msg="obtained new client token: AAC0fsMyao6Sf2UkMLI61Aib+X3lnkYwkPMyrIWb8J2V+fjCWVVxw+aw66w/aJMiBn4LPwCmSgrYfmp6/uKFPuO0oZqrsxrOCjaSW5+Nq1/D77bJTHy2lL8Fgi5yqe+vef1QvO/SEzD51sJrcO0HoyMiPdKxmn7pus1Hm0vpAY02Eu9BlWjqPZfJ+OzXWMDaAMJq4Uxoa1ErCyHpTBPIOnhQlJsHTHniBdXhPZ+NAPGkd+nnTz9cb6c=" Nov 05 21:56:17 volumio go-librespot[16113]: time="2025-11-05T21:56:17Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 21:56:17 volumio go-librespot[16113]: time="2025-11-05T21:56:17Z" level=debug msg="completed keyexchange" Nov 05 21:56:17 volumio go-librespot[16113]: time="2025-11-05T21:56:17Z" level=debug msg="completed challenge" Nov 05 21:56:17 volumio go-librespot[16113]: time="2025-11-05T21:56: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 05 21:56:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 21:56:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 21:56:18 volumio volumio[15414]: info: CoreCommandRouter::volumioGetState Nov 05 21:56:18 volumio volumio[15414]: info: CorePlayQueue::getTrack 0 Nov 05 21:56:18 volumio volumio[15414]: info: Initializing connection to go-librespot Websocket Nov 05 21:56:18 volumio volumio[15414]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 21:56:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 90. Nov 05 21:56:21 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 21:56:21 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 21:56:21 volumio go-librespot[16123]: go-librespot daemon starting... Nov 05 21:56:21 volumio go-librespot[16124]: time="2025-11-05T21:56:21Z" level=info msg="running go-librespot 0.4.0" Nov 05 21:56:21 volumio go-librespot[16124]: time="2025-11-05T21:56:21Z" level=debug msg="app state loaded" Nov 05 21:56:21 volumio go-librespot[16124]: time="2025-11-05T21:56:21Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 21:56:21 volumio go-librespot[16124]: time="2025-11-05T21:56: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 05 21:56:21 volumio go-librespot[16124]: time="2025-11-05T21:56: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 05 21:56:21 volumio go-librespot[16124]: time="2025-11-05T21:56: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 05 21:56:21 volumio go-librespot[16124]: time="2025-11-05T21:56:21Z" level=info msg="zeroconf server listening on port 44115" Nov 05 21:56:21 volumio go-librespot[16124]: time="2025-11-05T21:56:21Z" level=debug msg="obtained new client token: AACv/OHW5JB2ILAIq06rH+EadmU4kin4KJhV+Xmhdued2XBaH3sBdqmqk09anbgwaphrAnYbyLauXFyD80+hWoXeHfJnl9a6uplGiGVCxEKJ88ICyP5vlrXxBv4D9K2R8x2qRilWcc5BwGaQq0ZTldfFsqvetsogE7Jg2CasyP5td1BDGQTejMvcNeZXi3CRCwdxdhggT+JllEZaLxmk/uL/+gXKxx0C0VOT/4oOWZNVBx5ZwpIm" Nov 05 21:56:21 volumio go-librespot[16124]: time="2025-11-05T21:56:21Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 21:56:21 volumio go-librespot[16124]: time="2025-11-05T21:56:21Z" level=debug msg="completed keyexchange" Nov 05 21:56:21 volumio go-librespot[16124]: time="2025-11-05T21:56:21Z" level=debug msg="completed challenge" Nov 05 21:56:21 volumio go-librespot[16124]: time="2025-11-05T21:56: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 05 21:56:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 21:56:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 21:56:21 volumio volumio[15414]: info: Initializing connection to go-librespot Websocket Nov 05 21:56:21 volumio volumio[15414]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 21:56:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 91. Nov 05 21:56:24 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 05 21:56:24 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 05 21:56:24 volumio go-librespot[16146]: go-librespot daemon starting... Nov 05 21:56:24 volumio go-librespot[16147]: time="2025-11-05T21:56:24Z" level=info msg="running go-librespot 0.4.0" Nov 05 21:56:24 volumio go-librespot[16147]: time="2025-11-05T21:56:24Z" level=debug msg="app state loaded" Nov 05 21:56:24 volumio go-librespot[16147]: time="2025-11-05T21:56:24Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 05 21:56:24 volumio go-librespot[16147]: time="2025-11-05T21:56:24Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 05 21:56:24 volumio go-librespot[16147]: time="2025-11-05T21:56:24Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 05 21:56:24 volumio go-librespot[16147]: time="2025-11-05T21:56:24Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 05 21:56:24 volumio go-librespot[16147]: time="2025-11-05T21:56:24Z" level=info msg="zeroconf server listening on port 35763" Nov 05 21:56:24 volumio go-librespot[16147]: time="2025-11-05T21:56:24Z" level=debug msg="obtained new client token: AAC+LUejmx3Fvt1sAqeo5c4di/JoQkpyNQJK7xDT4z9VEjjLXSG8abNwvzSiuYf30BYTph6kS/mzuzDy/TIBPCt6Wzl7ng+Q3xVCnQ8GWJ0LhjNJMm9gSNR8WRxg8GHCoUWmSAY8Bbcj6lj75CmAb/xJRbsX6KMb28hqem/gNYOQ4lK/66+I+4+/JRzfg/5GU2AUdL53TuLNjncjUzMcsUPIrca8DidgLAGqMo2zF+KRCu/EWbU/ofc=" Nov 05 21:56:24 volumio go-librespot[16147]: time="2025-11-05T21:56:24Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 05 21:56:24 volumio volumio[15414]: info: Initializing connection to go-librespot Websocket Nov 05 21:56:24 volumio go-librespot[16147]: time="2025-11-05T21:56:24Z" level=debug msg="new websocket client" Nov 05 21:56:24 volumio volumio[15414]: info: Connection to go-librespot Websocket established Nov 05 21:56:24 volumio go-librespot[16147]: time="2025-11-05T21:56:24Z" level=debug msg="completed keyexchange" Nov 05 21:56:24 volumio go-librespot[16147]: time="2025-11-05T21:56:24Z" level=debug msg="completed challenge" Nov 05 21:56:24 volumio go-librespot[16147]: time="2025-11-05T21:56: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 05 21:56:24 volumio volumio[15414]: info: Connection to go-librespot Websocket closed Nov 05 21:56:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 05 21:56:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 05 21:56:27 volumio volumio[15414]: info: Getting Spotify volume Nov 05 21:56:27 volumio volumio[15414]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 05 21:56:27 volumio volumio[15414]: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 05 21:56:27 volumio volumio[15414]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Nov 05 21:56:27 volumio volumio[15414]: errno: -111, Nov 05 21:56:27 volumio volumio[15414]: code: 'ECONNREFUSED', Nov 05 21:56:27 volumio volumio[15414]: syscall: 'connect', Nov 05 21:56:27 volumio volumio[15414]: address: '127.0.0.1', Nov 05 21:56:27 volumio volumio[15414]: port: 9879, Nov 05 21:56:27 volumio volumio[15414]: response: undefined Nov 05 21:56:27 volumio volumio[15414]: } Nov 05 21:56:27 volumio volumio[15414]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 05 21:56:27 volumio sudo[16169]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-11-05 21:55' Nov 05 21:56:27 volumio sudo[16169]: 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"