Oct 26 21:33:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 659. Oct 26 21:33:01 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 26 21:33:01 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 26 21:33:01 volumio go-librespot[6710]: go-librespot daemon starting... Oct 26 21:33:01 volumio go-librespot[6711]: time="2025-10-26T21:33:01Z" level=info msg="running go-librespot 0.4.0" Oct 26 21:33:01 volumio go-librespot[6711]: time="2025-10-26T21:33:01Z" level=debug msg="app state loaded" Oct 26 21:33:01 volumio go-librespot[6711]: time="2025-10-26T21:33:01Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 26 21:33:01 volumio go-librespot[6711]: time="2025-10-26T21:33:01Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 26 21:33:01 volumio go-librespot[6711]: time="2025-10-26T21:33:01Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 26 21:33:01 volumio go-librespot[6711]: time="2025-10-26T21:33:01Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 26 21:33:01 volumio go-librespot[6711]: time="2025-10-26T21:33:01Z" level=info msg="zeroconf server listening on port 35631" Oct 26 21:33:01 volumio go-librespot[6711]: time="2025-10-26T21:33:01Z" level=debug msg="obtained new client token: AAAsmckt/6TcaAD3zU0VB4FCBUVGLVlP10naeozjsxuW6d2Ewmum5O4hJ7JiGAZejZVf+vz+PUFF8lYvFxKLPHsLk84t9kajfVcxJ7O1xfFHgnP1R/dfbhfq7vDobq3zrSg1yN4BZvZXeRmDnPpSNFQGi7maV3rBO247iKeDUi35f5PA38ATE6D+h/RfqNWxXxA1pBxz54jN2/MchAclWD/eCp4mGlHNPJatkEMcOCTqSuSM8566HRg=" Oct 26 21:33:01 volumio go-librespot[6711]: time="2025-10-26T21:33:01Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 26 21:33:01 volumio go-librespot[6711]: time="2025-10-26T21:33:01Z" level=debug msg="completed keyexchange" Oct 26 21:33:01 volumio go-librespot[6711]: time="2025-10-26T21:33:01Z" level=debug msg="completed challenge" Oct 26 21:33:01 volumio go-librespot[6711]: time="2025-10-26T21:33:01Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 26 21:33:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 26 21:33:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 26 21:33:02 volumio volumio[6372]: info: MYVOLUMIO: Adding device Oct 26 21:33:02 volumio volumio[6372]: info: MYVOLUMIO: Evaluating Server Oct 26 21:33:02 volumio volumio[6372]: info: Initializing connection to go-librespot Websocket Oct 26 21:33:02 volumio volumio[6372]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 26 21:33:03 volumio volumio[6372]: info: Setting Geolocation for MyVolumio to eu3 Oct 26 21:33:03 volumio volumio[6372]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 21:33:03 volumio volumio[6372]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 21:33:03 volumio volumio[6372]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 21:33:03 volumio volumio[6372]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Oct 26 21:33:04 volumio volumio[6372]: info: Updating MyVolumio device info Oct 26 21:33:04 volumio volumio[6372]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 21:33:04 volumio volumio[6372]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 21:33:04 volumio volumio[6372]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 21:33:04 volumio volumio[6372]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Oct 26 21:33:04 volumio volumio[6372]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Oct 26 21:33:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 660. Oct 26 21:33:05 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 26 21:33:05 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 26 21:33:05 volumio go-librespot[6747]: go-librespot daemon starting... Oct 26 21:33:05 volumio go-librespot[6748]: time="2025-10-26T21:33:05Z" level=info msg="running go-librespot 0.4.0" Oct 26 21:33:05 volumio go-librespot[6748]: time="2025-10-26T21:33:05Z" level=debug msg="app state loaded" Oct 26 21:33:05 volumio go-librespot[6748]: time="2025-10-26T21:33:05Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 26 21:33:05 volumio go-librespot[6748]: time="2025-10-26T21:33:05Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 26 21:33:05 volumio go-librespot[6748]: time="2025-10-26T21:33:05Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 26 21:33:05 volumio go-librespot[6748]: time="2025-10-26T21:33:05Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 26 21:33:05 volumio go-librespot[6748]: time="2025-10-26T21:33:05Z" level=info msg="zeroconf server listening on port 37347" Oct 26 21:33:05 volumio go-librespot[6748]: time="2025-10-26T21:33:05Z" level=debug msg="obtained new client token: AADWmWDdHcLHCVk9CZmB6GEMUcEqsGLcBvsrTE0bR3V1T7HL82WoT6EPaHeIw4knQTqoN9FnqYA3AsJj7VgMgbJYFP/FM58/SBsW3TvCSYfWrqvoFTXmWWX3GVPSY8b2YSc6n+aswp9OCbYSZhVMAa9MGLNf5HXodmDKaSaEglO1SGA8PQGSDjSsRT7+ZPZvn0Ef8Eq7Ctd/2gIDQ+AW1ZeJ5XeBXUlEjyO+xnmYFiPnEb1xNa0H" Oct 26 21:33:05 volumio go-librespot[6748]: time="2025-10-26T21:33:05Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 26 21:33:05 volumio go-librespot[6748]: time="2025-10-26T21:33:05Z" level=debug msg="completed keyexchange" Oct 26 21:33:05 volumio go-librespot[6748]: time="2025-10-26T21:33:05Z" level=debug msg="completed challenge" Oct 26 21:33:05 volumio go-librespot[6748]: time="2025-10-26T21:33:05Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 26 21:33:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 26 21:33:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 26 21:33:05 volumio volumio[6372]: info: Initializing connection to go-librespot Websocket Oct 26 21:33:05 volumio volumio[6372]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 26 21:33:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 661. Oct 26 21:33:08 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 26 21:33:08 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 26 21:33:08 volumio go-librespot[6755]: go-librespot daemon starting... Oct 26 21:33:08 volumio go-librespot[6756]: time="2025-10-26T21:33:08Z" level=info msg="running go-librespot 0.4.0" Oct 26 21:33:08 volumio go-librespot[6756]: time="2025-10-26T21:33:08Z" level=debug msg="app state loaded" Oct 26 21:33:08 volumio go-librespot[6756]: time="2025-10-26T21:33:08Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 26 21:33:08 volumio go-librespot[6756]: time="2025-10-26T21:33:08Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 26 21:33:08 volumio go-librespot[6756]: time="2025-10-26T21:33:08Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 26 21:33:08 volumio go-librespot[6756]: time="2025-10-26T21:33:08Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 26 21:33:08 volumio go-librespot[6756]: time="2025-10-26T21:33:08Z" level=info msg="zeroconf server listening on port 35611" Oct 26 21:33:08 volumio go-librespot[6756]: time="2025-10-26T21:33:08Z" level=debug msg="obtained new client token: AADyVNyTKMtdnUOfFt7GHjcrsPJYHy3Qjfi4Ck02Sm32RlI/gklAdhm5iZqp/SHoKEc9lDK5+BSHA7zItrQ8Fsq2lNKq6Zby7mpWNzaSzHbALa3dexbWKnFDX7K911q0sFZBXqeCfjZbPvmvi7D3C+KW3jxYCV0x3yWwc7kbT/sGYloEzkT8EouvtUo6ujY77f0G5ICsWcj1wB0OLKPpfuP6dHEfQSqcfe1fE6M9g/gS6FW8qA1uoLE=" Oct 26 21:33:08 volumio go-librespot[6756]: time="2025-10-26T21:33:08Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 26 21:33:08 volumio go-librespot[6756]: time="2025-10-26T21:33:08Z" level=debug msg="completed keyexchange" Oct 26 21:33:08 volumio go-librespot[6756]: time="2025-10-26T21:33:08Z" level=debug msg="completed challenge" Oct 26 21:33:08 volumio go-librespot[6756]: time="2025-10-26T21:33:08Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 26 21:33:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 26 21:33:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 26 21:33:08 volumio volumio[6372]: info: Initializing connection to go-librespot Websocket Oct 26 21:33:08 volumio volumio[6372]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 26 21:33:11 volumio volumio[6372]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 26 21:33:11 volumio volumio[6372]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Oct 26 21:33:11 volumio volumio[6372]: info: CoreCommandRouter::volumioGetState Oct 26 21:33:11 volumio volumio[6372]: info: CorePlayQueue::getTrack 0 Oct 26 21:33:11 volumio volumio[6372]: info: Initializing connection to go-librespot Websocket Oct 26 21:33:11 volumio volumio[6372]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 26 21:33:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 662. Oct 26 21:33:12 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 26 21:33:12 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 26 21:33:12 volumio go-librespot[6766]: go-librespot daemon starting... Oct 26 21:33:12 volumio go-librespot[6767]: time="2025-10-26T21:33:12Z" level=info msg="running go-librespot 0.4.0" Oct 26 21:33:12 volumio go-librespot[6767]: time="2025-10-26T21:33:12Z" level=debug msg="app state loaded" Oct 26 21:33:12 volumio go-librespot[6767]: time="2025-10-26T21:33:12Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 26 21:33:12 volumio go-librespot[6767]: time="2025-10-26T21:33:12Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 26 21:33:12 volumio go-librespot[6767]: time="2025-10-26T21:33:12Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 26 21:33:12 volumio go-librespot[6767]: time="2025-10-26T21:33:12Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 26 21:33:12 volumio go-librespot[6767]: time="2025-10-26T21:33:12Z" level=info msg="zeroconf server listening on port 34315" Oct 26 21:33:12 volumio go-librespot[6767]: time="2025-10-26T21:33:12Z" level=debug msg="obtained new client token: AABK1n3Cuhspveww+jg5C1FO7bIQ4ztwFf1QxdpJMtDpCIpYEpPfnPL2W3qPlnswzrCd3Ayp2WSN32pLj9WaqjCIjR+92qMzpqNYVzVmyWpcilquogKrQZz4qj/hwcTsVUy0YP9CgOOEsdYveK7/rRtaQUqnmuhfFeS+oSmlLElz3rrOJvJH8nybJoKOtiQItxEZAJdlwtA67F7Cn+6duwiiIZhTgSrrQS4mEPwXvIUH6yocQk/s" Oct 26 21:33:12 volumio go-librespot[6767]: time="2025-10-26T21:33:12Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 26 21:33:12 volumio go-librespot[6767]: time="2025-10-26T21:33:12Z" level=debug msg="completed keyexchange" Oct 26 21:33:12 volumio go-librespot[6767]: time="2025-10-26T21:33:12Z" level=debug msg="completed challenge" Oct 26 21:33:12 volumio go-librespot[6767]: time="2025-10-26T21:33:12Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 26 21:33:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 26 21:33:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 26 21:33:14 volumio volumio[6372]: info: Initializing connection to go-librespot Websocket Oct 26 21:33:14 volumio volumio[6372]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 26 21:33:15 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 663. Oct 26 21:33:15 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 26 21:33:15 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 26 21:33:15 volumio go-librespot[6795]: go-librespot daemon starting... Oct 26 21:33:15 volumio go-librespot[6796]: time="2025-10-26T21:33:15Z" level=info msg="running go-librespot 0.4.0" Oct 26 21:33:15 volumio go-librespot[6796]: time="2025-10-26T21:33:15Z" level=debug msg="app state loaded" Oct 26 21:33:15 volumio go-librespot[6796]: time="2025-10-26T21:33:15Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 26 21:33:15 volumio go-librespot[6796]: time="2025-10-26T21:33:15Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 26 21:33:15 volumio go-librespot[6796]: time="2025-10-26T21:33:15Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 26 21:33:15 volumio go-librespot[6796]: time="2025-10-26T21:33:15Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 26 21:33:15 volumio go-librespot[6796]: time="2025-10-26T21:33:15Z" level=info msg="zeroconf server listening on port 40445" Oct 26 21:33:15 volumio go-librespot[6796]: time="2025-10-26T21:33:15Z" level=debug msg="obtained new client token: AAAuKFF+r2uDqO+jBDXNFGQbbbiKuihCprEoEpCveH/3QHGS1u0jcdOBwn/gZZmtzU0QZXcdJl7enLESQ4E0JIwdZ+E67eg9CGdRcWWa217UfGFMZAEqSdoBLvaj2DSjAClZDw424E/9zT2hRb7OCoID7Uc+DivX1XxS0wJx67Mn58mp4vUdygh3ms6pwmvqu/mo56rtmbRB5+/bP7iHSyYbqmciU+it7Jp1lQy2ekmil4Te5Qeerr0=" Oct 26 21:33:15 volumio go-librespot[6796]: time="2025-10-26T21:33:15Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 26 21:33:15 volumio go-librespot[6796]: time="2025-10-26T21:33:15Z" level=debug msg="completed keyexchange" Oct 26 21:33:15 volumio go-librespot[6796]: time="2025-10-26T21:33:15Z" level=debug msg="completed challenge" Oct 26 21:33:15 volumio go-librespot[6796]: time="2025-10-26T21:33:15Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 26 21:33:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 26 21:33:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 26 21:33:16 volumio volumio[6372]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 21:33:16 volumio volumio[6372]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 21:33:16 volumio volumio[6372]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 26 21:33:16 volumio volumio[6372]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 26 21:33:16 volumio volumio[6372]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 26 21:33:16 volumio volumio[6372]: info: CoreCommandRouter::volumioGetBrowseSources Oct 26 21:33:16 volumio volumio[6372]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 26 21:33:16 volumio volumio[6372]: error: MyVolumio Plugin failed to authenticate in a timely fashion Oct 26 21:33:16 volumio volumio[6372]: info: Completed starting MyVolumio Plugin Oct 26 21:33:16 volumio volumio[6372]: [Metrics] CommandRouter: 33s 93.28ms Oct 26 21:33:16 volumio volumio[6372]: info: CoreCommandRouter::volumiosetStartupVolume Oct 26 21:33:16 volumio volumio[6372]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 21:33:16 volumio volumio[6372]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 21:33:16 volumio volumio[6372]: info: CoreCommandRouter::Close All Modals sent Oct 26 21:33:16 volumio volumio[6372]: info: CoreCommandRouter::Close All Modals sent Oct 26 21:33:17 volumio volumio[6372]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 26 21:33:17 volumio volumio[6372]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 21:33:17 volumio volumio[6372]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 26 21:33:17 volumio volumio[6372]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Oct 26 21:33:17 volumio volumio-remote-updater[874]: Test mode disabled Oct 26 21:33:17 volumio volumio-remote-updater[874]: Alpha mode disabled Oct 26 21:33:17 volumio volumio-remote-updater[874]: Alpha legacy test mode disabled Oct 26 21:33:17 volumio volumio[6372]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 26 21:33:17 volumio volumio[6372]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 26 21:33:17 volumio volumio[6372]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 26 21:33:17 volumio volumio[6372]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 26 21:33:17 volumio volumio[6372]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 26 21:33:17 volumio volumio[6372]: info: Initializing connection to go-librespot Websocket Oct 26 21:33:17 volumio volumio[6372]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 26 21:33:19 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 664. Oct 26 21:33:19 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 26 21:33:19 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 26 21:33:19 volumio go-librespot[6813]: go-librespot daemon starting... Oct 26 21:33:19 volumio go-librespot[6814]: time="2025-10-26T21:33:19Z" level=info msg="running go-librespot 0.4.0" Oct 26 21:33:19 volumio go-librespot[6814]: time="2025-10-26T21:33:19Z" level=debug msg="app state loaded" Oct 26 21:33:19 volumio go-librespot[6814]: time="2025-10-26T21:33:19Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 26 21:33:19 volumio go-librespot[6814]: time="2025-10-26T21:33:19Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 26 21:33:19 volumio go-librespot[6814]: time="2025-10-26T21:33:19Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 26 21:33:19 volumio go-librespot[6814]: time="2025-10-26T21:33:19Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 26 21:33:19 volumio go-librespot[6814]: time="2025-10-26T21:33:19Z" level=info msg="zeroconf server listening on port 36185" Oct 26 21:33:19 volumio go-librespot[6814]: time="2025-10-26T21:33:19Z" level=debug msg="obtained new client token: AABBJNxn8o1aAi1sIdTsI4WFKmNgR2VJ4qpQYXdlDsd/n+7XEC6+0uJw7Uo1mSsgKYScKQZvFujBjqpL9QJbk7QiEkQ6BT0xlf/Tw8pMG1NDusnGzS+efXidUxzwul6M4jxVTrzcPipFV7ko2r6W4UUzIF1JVrzq/4Vn9SFgnh37LxtGZeURoy9Pmu6200rQ9yE+IntJOQk49ofgvQk+wEqOxUjkY2qk5Mb7OYzTKwkCyWVhoqjc" Oct 26 21:33:19 volumio go-librespot[6814]: time="2025-10-26T21:33:19Z" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.65.9:4070: connect: connection refused" Oct 26 21:33:19 volumio go-librespot[6814]: time="2025-10-26T21:33:19Z" level=debug msg="connected to ap-gew1.spotify.com:443" Oct 26 21:33:19 volumio go-librespot[6814]: time="2025-10-26T21:33:19Z" level=debug msg="completed keyexchange" Oct 26 21:33:19 volumio go-librespot[6814]: time="2025-10-26T21:33:19Z" level=debug msg="completed challenge" Oct 26 21:33:19 volumio go-librespot[6814]: time="2025-10-26T21:33:19Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 26 21:33:19 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 26 21:33:19 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 26 21:33:20 volumio volumio[6372]: info: Initializing connection to go-librespot Websocket Oct 26 21:33:20 volumio volumio[6372]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 26 21:33:22 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 665. Oct 26 21:33:22 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 26 21:33:22 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 26 21:33:22 volumio go-librespot[6822]: go-librespot daemon starting... Oct 26 21:33:22 volumio go-librespot[6823]: time="2025-10-26T21:33:22Z" level=info msg="running go-librespot 0.4.0" Oct 26 21:33:22 volumio go-librespot[6823]: time="2025-10-26T21:33:22Z" level=debug msg="app state loaded" Oct 26 21:33:22 volumio go-librespot[6823]: time="2025-10-26T21:33:22Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 26 21:33:22 volumio go-librespot[6823]: time="2025-10-26T21:33:22Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 26 21:33:22 volumio go-librespot[6823]: time="2025-10-26T21:33:22Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 26 21:33:22 volumio go-librespot[6823]: time="2025-10-26T21:33:22Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 26 21:33:22 volumio go-librespot[6823]: time="2025-10-26T21:33:22Z" level=info msg="zeroconf server listening on port 39859" Oct 26 21:33:22 volumio go-librespot[6823]: time="2025-10-26T21:33:22Z" level=debug msg="obtained new client token: AABvTSxFsyZQ4pvZCFfCXXhM5W15ZrsPW3cQBHU2wQAfHsTRrm+gpx+/mUK00bDKofikGP3L/AyBUdQyhRd9jigGSpcvbsEfNinWHG4TYXRB9GlIcQzoUcCJI/R5MdPar4M1ehhMoA3pHNZJ21b9XWQkjqgaFqArk/oXDiRbQulHW05BK6uLtgnDCS9eERaZIq02YHSfLNPcJ92i4fmUxGYzgTVm30B/4Okn0amRP+2hw1icYDgarXk=" Oct 26 21:33:22 volumio go-librespot[6823]: time="2025-10-26T21:33:22Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 26 21:33:22 volumio go-librespot[6823]: time="2025-10-26T21:33:22Z" level=debug msg="completed keyexchange" Oct 26 21:33:22 volumio go-librespot[6823]: time="2025-10-26T21:33:22Z" level=debug msg="completed challenge" Oct 26 21:33:22 volumio go-librespot[6823]: time="2025-10-26T21:33:22Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 26 21:33:22 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 26 21:33:22 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 26 21:33:23 volumio volumio[6372]: info: BOOT COMPLETED Oct 26 21:33:23 volumio volumio[6372]: info: Initializing connection to go-librespot Websocket Oct 26 21:33:23 volumio volumio[6372]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 26 21:33:26 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 666. Oct 26 21:33:26 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 26 21:33:26 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 26 21:33:26 volumio go-librespot[6844]: go-librespot daemon starting... Oct 26 21:33:26 volumio go-librespot[6845]: time="2025-10-26T21:33:26Z" level=info msg="running go-librespot 0.4.0" Oct 26 21:33:26 volumio go-librespot[6845]: time="2025-10-26T21:33:26Z" level=debug msg="app state loaded" Oct 26 21:33:26 volumio go-librespot[6845]: time="2025-10-26T21:33:26Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 26 21:33:26 volumio go-librespot[6845]: time="2025-10-26T21:33:26Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 26 21:33:26 volumio go-librespot[6845]: time="2025-10-26T21:33:26Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 26 21:33:26 volumio go-librespot[6845]: time="2025-10-26T21:33:26Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 26 21:33:26 volumio go-librespot[6845]: time="2025-10-26T21:33:26Z" level=info msg="zeroconf server listening on port 34901" Oct 26 21:33:26 volumio go-librespot[6845]: time="2025-10-26T21:33:26Z" level=debug msg="obtained new client token: AAA/9ZqYtk/URARV6Kp9T0Vcz+UvryjBNjEFirmSoNaqGMC0UzU9Rs+Wh+LN9D06NSkZdcPZGjC7x2gNXCAprbd6UxnLi74Z8caaMVipyW6RoVYwsnuar3aDX3KHrtjWrTqagP0CoBkq31oYIZ8H4DE2HMnq1UcgHatXiQfRrH21EsTe7ZIdrZnzyD/Phq5w9Oa202C2PihS4Qtc3K63DxLUNDXLpwknYbmNSlSkhZU0hRPA34FJ" Oct 26 21:33:26 volumio go-librespot[6845]: time="2025-10-26T21:33:26Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 26 21:33:26 volumio go-librespot[6845]: time="2025-10-26T21:33:26Z" level=debug msg="completed keyexchange" Oct 26 21:33:26 volumio go-librespot[6845]: time="2025-10-26T21:33:26Z" level=debug msg="completed challenge" Oct 26 21:33:26 volumio go-librespot[6845]: time="2025-10-26T21:33:26Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 26 21:33:26 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 26 21:33:26 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 26 21:33:26 volumio volumio[6372]: info: Initializing connection to go-librespot Websocket Oct 26 21:33:26 volumio volumio[6372]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 26 21:33:29 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 667. Oct 26 21:33:29 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 26 21:33:29 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 26 21:33:29 volumio go-librespot[6858]: go-librespot daemon starting... Oct 26 21:33:29 volumio go-librespot[6859]: time="2025-10-26T21:33:29Z" level=info msg="running go-librespot 0.4.0" Oct 26 21:33:29 volumio go-librespot[6859]: time="2025-10-26T21:33:29Z" level=debug msg="app state loaded" Oct 26 21:33:29 volumio go-librespot[6859]: time="2025-10-26T21:33:29Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 26 21:33:29 volumio go-librespot[6859]: time="2025-10-26T21:33:29Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 26 21:33:29 volumio go-librespot[6859]: time="2025-10-26T21:33:29Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 26 21:33:29 volumio go-librespot[6859]: time="2025-10-26T21:33:29Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 26 21:33:29 volumio go-librespot[6859]: time="2025-10-26T21:33:29Z" level=info msg="zeroconf server listening on port 46859" Oct 26 21:33:29 volumio go-librespot[6859]: time="2025-10-26T21:33:29Z" level=debug msg="obtained new client token: AAAtPaZEfXH8Ul079V9tYG7Gnii5fIVOUSp5G3PYDbo+w4djfiSq4fGDWfLrzeNVhOx6RyJooTKaqotaN37fVrgTcTTXuJ6P0jtkQu54v9Zpl0JiTdg7xXajSDTTwZVgKA8JqnEPzuOJ9QJXLLOmdQ90vrpHXXbGcT1vnthMuun3TLptVNuAHBW53EPwWFrnRURS3CL8v+6cEmj9kNgMj/59tL5QIyN7DT6F8JZvogZEe6D10tPa/NQ=" Oct 26 21:33:29 volumio go-librespot[6859]: time="2025-10-26T21:33:29Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 26 21:33:29 volumio go-librespot[6859]: time="2025-10-26T21:33:29Z" level=debug msg="completed keyexchange" Oct 26 21:33:29 volumio go-librespot[6859]: time="2025-10-26T21:33:29Z" level=debug msg="completed challenge" Oct 26 21:33:29 volumio go-librespot[6859]: time="2025-10-26T21:33:29Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 26 21:33:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 26 21:33:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 26 21:33:29 volumio volumio[6372]: info: Initializing connection to go-librespot Websocket Oct 26 21:33:29 volumio volumio[6372]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 26 21:33:32 volumio volumio[6372]: info: Initializing connection to go-librespot Websocket Oct 26 21:33:32 volumio volumio[6372]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 26 21:33:33 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 668. Oct 26 21:33:33 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 26 21:33:33 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 26 21:33:33 volumio go-librespot[6869]: go-librespot daemon starting... Oct 26 21:33:33 volumio go-librespot[6870]: time="2025-10-26T21:33:33Z" level=info msg="running go-librespot 0.4.0" Oct 26 21:33:33 volumio go-librespot[6870]: time="2025-10-26T21:33:33Z" level=debug msg="app state loaded" Oct 26 21:33:33 volumio go-librespot[6870]: time="2025-10-26T21:33:33Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 26 21:33:33 volumio go-librespot[6870]: time="2025-10-26T21:33:33Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 26 21:33:33 volumio go-librespot[6870]: time="2025-10-26T21:33:33Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 26 21:33:33 volumio go-librespot[6870]: time="2025-10-26T21:33:33Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 26 21:33:33 volumio go-librespot[6870]: time="2025-10-26T21:33:33Z" level=info msg="zeroconf server listening on port 33275" Oct 26 21:33:33 volumio go-librespot[6870]: time="2025-10-26T21:33:33Z" level=debug msg="obtained new client token: AABj2uvhAlw6kP+6HSKJqZO5YxlAovvlQXAp1DHbPhidzt33yoZchVXctkzaM0S78SV4cnGerX7qKl7cQWKz947fqy6Nhgp8NgKxGV3Vl2Lt6lHHHp6PWY+5CikG6z94Ucr9v8KKZMPV6XAHwAa2ligKzMjj+gfYvhqLNZ7e7iCeaFqHW7KIYraHvFq3opLjXN03ZNBlIV71K7HXEUtzoZceKT+sh5WKLC0IqVHN0K2+gVF6j62g" Oct 26 21:33:33 volumio go-librespot[6870]: time="2025-10-26T21:33:33Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 26 21:33:33 volumio go-librespot[6870]: time="2025-10-26T21:33:33Z" level=debug msg="completed keyexchange" Oct 26 21:33:33 volumio go-librespot[6870]: time="2025-10-26T21:33:33Z" level=debug msg="completed challenge" Oct 26 21:33:33 volumio go-librespot[6870]: time="2025-10-26T21:33:33Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 26 21:33:33 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 26 21:33:33 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 26 21:33:35 volumio volumio[6372]: info: Initializing connection to go-librespot Websocket Oct 26 21:33:35 volumio volumio[6372]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 26 21:33:36 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 669. Oct 26 21:33:36 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 26 21:33:36 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 26 21:33:36 volumio go-librespot[6891]: go-librespot daemon starting... Oct 26 21:33:36 volumio go-librespot[6892]: time="2025-10-26T21:33:36Z" level=info msg="running go-librespot 0.4.0" Oct 26 21:33:36 volumio go-librespot[6892]: time="2025-10-26T21:33:36Z" level=debug msg="app state loaded" Oct 26 21:33:36 volumio go-librespot[6892]: time="2025-10-26T21:33:36Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 26 21:33:36 volumio go-librespot[6892]: time="2025-10-26T21:33:36Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 26 21:33:36 volumio go-librespot[6892]: time="2025-10-26T21:33:36Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 26 21:33:36 volumio go-librespot[6892]: time="2025-10-26T21:33:36Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 26 21:33:36 volumio go-librespot[6892]: time="2025-10-26T21:33:36Z" level=info msg="zeroconf server listening on port 33927" Oct 26 21:33:36 volumio go-librespot[6892]: time="2025-10-26T21:33:36Z" level=debug msg="obtained new client token: AAD7nMYwSc54Gu+kTDElXTvVyMsKSJbr29sGEImDEKWmcXpPevQjVa48ZPff1rdizpUUjkB9JFP6R5Yt/2JQeekz8ccGD6NiXmxNctin8TOzgzNEktDEe6MsBrBZzA4xSGdDTlU/xblyBNyv4/2ngwWBNjZBeFhxQd3F6a5JetrpQnZZ7vuGf/cqgl+nzX9/PnHcXx0u6pRS3itKcC2PNo9qyTp+3RnFINt6gLu5mrCA1wGW0oQb7C0=" Oct 26 21:33:36 volumio go-librespot[6892]: time="2025-10-26T21:33:36Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 26 21:33:36 volumio go-librespot[6892]: time="2025-10-26T21:33:36Z" level=debug msg="completed keyexchange" Oct 26 21:33:36 volumio go-librespot[6892]: time="2025-10-26T21:33:36Z" level=debug msg="completed challenge" Oct 26 21:33:36 volumio go-librespot[6892]: time="2025-10-26T21:33:36Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 26 21:33:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 26 21:33:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 26 21:33:38 volumio volumio[6372]: info: Initializing connection to go-librespot Websocket Oct 26 21:33:38 volumio volumio[6372]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 26 21:33:40 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 670. Oct 26 21:33:40 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 26 21:33:40 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 26 21:33:40 volumio go-librespot[6901]: go-librespot daemon starting... Oct 26 21:33:40 volumio go-librespot[6902]: time="2025-10-26T21:33:40Z" level=info msg="running go-librespot 0.4.0" Oct 26 21:33:40 volumio go-librespot[6902]: time="2025-10-26T21:33:40Z" level=debug msg="app state loaded" Oct 26 21:33:40 volumio go-librespot[6902]: time="2025-10-26T21:33:40Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 26 21:33:40 volumio go-librespot[6902]: time="2025-10-26T21:33:40Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 26 21:33:40 volumio go-librespot[6902]: time="2025-10-26T21:33:40Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 26 21:33:40 volumio go-librespot[6902]: time="2025-10-26T21:33:40Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 26 21:33:40 volumio go-librespot[6902]: time="2025-10-26T21:33:40Z" level=info msg="zeroconf server listening on port 39433" Oct 26 21:33:40 volumio go-librespot[6902]: time="2025-10-26T21:33:40Z" level=debug msg="obtained new client token: AABNdtCpHYGOsAD+BPjJxzd0gE/KPQvGPqVwR6Hj3DZcfOYR813HHEMYPSJxFiVtsxdpDeLhYn9GrjlnsDeJbQW9eBmA2KZ8kT+Og1gO/iTVUCJV+rEWnQ3tKM0Goy6T4KwcaPZLagTCbBuLebfg0ZqcAAlHz3sj47+865405JPv+F1YSSrYpsPrA0b2JDWVjWzXaMfKVW4lQbI+LpfOTMg2z8pfRf+kfhWCkK8cYPyLsilB0oV8" Oct 26 21:33:40 volumio go-librespot[6902]: time="2025-10-26T21:33:40Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 26 21:33:40 volumio go-librespot[6902]: time="2025-10-26T21:33:40Z" level=debug msg="completed keyexchange" Oct 26 21:33:40 volumio go-librespot[6902]: time="2025-10-26T21:33:40Z" level=debug msg="completed challenge" Oct 26 21:33:40 volumio go-librespot[6902]: time="2025-10-26T21:33:40Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 26 21:33:40 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 26 21:33:40 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 26 21:33:41 volumio volumio[6372]: info: Initializing connection to go-librespot Websocket Oct 26 21:33:41 volumio volumio[6372]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 26 21:33:43 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 671. Oct 26 21:33:43 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 26 21:33:43 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 26 21:33:43 volumio go-librespot[6915]: go-librespot daemon starting... Oct 26 21:33:43 volumio go-librespot[6916]: time="2025-10-26T21:33:43Z" level=info msg="running go-librespot 0.4.0" Oct 26 21:33:43 volumio go-librespot[6916]: time="2025-10-26T21:33:43Z" level=debug msg="app state loaded" Oct 26 21:33:43 volumio go-librespot[6916]: time="2025-10-26T21:33:43Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 26 21:33:43 volumio go-librespot[6916]: time="2025-10-26T21:33:43Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 26 21:33:43 volumio go-librespot[6916]: time="2025-10-26T21:33:43Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 26 21:33:43 volumio go-librespot[6916]: time="2025-10-26T21:33:43Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 26 21:33:43 volumio go-librespot[6916]: time="2025-10-26T21:33:43Z" level=info msg="zeroconf server listening on port 41497" Oct 26 21:33:43 volumio go-librespot[6916]: time="2025-10-26T21:33:43Z" level=debug msg="obtained new client token: AAAZXm1xKkj69qeF4IEoZ8IxD2Z8TDj8lO+xwcsCLqQZFPwgEevCIKHm9cR/nC0jyZHwFka1drsdB6wBxGe7PJX0OH1xM0YJLEpLau0tA6CSFNrP6P28aHoDkRWxVZad/Fc4hUUzXzIUdBq6CyCmvKhoy8R4TNs0UU/uwxFA1NcZPj8s+7bOQxQxJbd5jfo/ZD0Ec1uhEVPvuE1vCT2O3/e4TBi97gyj6ieZSPjDILVVqC4SuWum6W8=" Oct 26 21:33:43 volumio go-librespot[6916]: time="2025-10-26T21:33:43Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 26 21:33:43 volumio go-librespot[6916]: time="2025-10-26T21:33:43Z" level=debug msg="completed keyexchange" Oct 26 21:33:43 volumio go-librespot[6916]: time="2025-10-26T21:33:43Z" level=debug msg="completed challenge" Oct 26 21:33:43 volumio go-librespot[6916]: time="2025-10-26T21:33:43Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 26 21:33:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 26 21:33:43 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 26 21:33:44 volumio volumio[6372]: info: Initializing connection to go-librespot Websocket Oct 26 21:33:44 volumio volumio[6372]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 26 21:33:47 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 672. Oct 26 21:33:47 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 26 21:33:47 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 26 21:33:47 volumio go-librespot[6937]: go-librespot daemon starting... Oct 26 21:33:47 volumio go-librespot[6938]: time="2025-10-26T21:33:47Z" level=info msg="running go-librespot 0.4.0" Oct 26 21:33:47 volumio go-librespot[6938]: time="2025-10-26T21:33:47Z" level=debug msg="app state loaded" Oct 26 21:33:47 volumio go-librespot[6938]: time="2025-10-26T21:33:47Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 26 21:33:47 volumio go-librespot[6938]: time="2025-10-26T21:33:47Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 26 21:33:47 volumio go-librespot[6938]: time="2025-10-26T21:33:47Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 26 21:33:47 volumio go-librespot[6938]: time="2025-10-26T21:33:47Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 26 21:33:47 volumio go-librespot[6938]: time="2025-10-26T21:33:47Z" level=info msg="zeroconf server listening on port 44543" Oct 26 21:33:47 volumio go-librespot[6938]: time="2025-10-26T21:33:47Z" level=debug msg="obtained new client token: AADDg8p5ls1LksWYZY2HhHFu1d5ciisJzSi047HXm0ib9K/LkJNhLZYhgAKrp7isimKMNFrp8xfu1XcbUTjZDwv2UYCL9SHvx+rYv0zwl1F3XbrMC/tZcuYy7Yp86Qu0zj67WB6VWZ2IReX1feJm0ySmcXwkQ5+tA/LYO75ktEyYmPu1SOvQkhSozVAs5RDmA5HdEvmtQ1b9ZC1NRlVM0m52xD5jEQMj+cYOzj8kWasKEFMa76Qo" Oct 26 21:33:47 volumio go-librespot[6938]: time="2025-10-26T21:33:47Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 26 21:33:47 volumio go-librespot[6938]: time="2025-10-26T21:33:47Z" level=debug msg="completed keyexchange" Oct 26 21:33:47 volumio go-librespot[6938]: time="2025-10-26T21:33:47Z" level=debug msg="completed challenge" Oct 26 21:33:47 volumio go-librespot[6938]: time="2025-10-26T21:33:47Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 26 21:33:47 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 26 21:33:47 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 26 21:33:47 volumio volumio[6372]: info: Initializing connection to go-librespot Websocket Oct 26 21:33:47 volumio volumio[6372]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 26 21:33:50 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 673. Oct 26 21:33:50 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 26 21:33:50 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 26 21:33:50 volumio go-librespot[6945]: go-librespot daemon starting... Oct 26 21:33:50 volumio go-librespot[6946]: time="2025-10-26T21:33:50Z" level=info msg="running go-librespot 0.4.0" Oct 26 21:33:50 volumio go-librespot[6946]: time="2025-10-26T21:33:50Z" level=debug msg="app state loaded" Oct 26 21:33:50 volumio go-librespot[6946]: time="2025-10-26T21:33:50Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 26 21:33:50 volumio go-librespot[6946]: time="2025-10-26T21:33:50Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 26 21:33:50 volumio go-librespot[6946]: time="2025-10-26T21:33:50Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 26 21:33:50 volumio go-librespot[6946]: time="2025-10-26T21:33:50Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 26 21:33:50 volumio go-librespot[6946]: time="2025-10-26T21:33:50Z" level=info msg="zeroconf server listening on port 41983" Oct 26 21:33:50 volumio go-librespot[6946]: time="2025-10-26T21:33:50Z" level=debug msg="obtained new client token: AADv4OpL6VYmuyr5rmbk76tT/48YFZhXD/vlk1D1wMBsrE9YhyyDN1dy//tJeaBf0hBxFQ4XWIGXaJGeskbvt9SBxeoFgUJlkpFHGfstW02hhkirVR75tBuv9ziHiAhmsmZji5FilWcXb8D4f4tgsGUFdR/mEsts81CxjZhUTSWZEOoH1FRrGr34I/LVUPAzAN67oHv7vvi/g13n3ENJXLHidAzYNPXQbCQfDe5UbBWQjVZeCIKGrko=" Oct 26 21:33:50 volumio go-librespot[6946]: time="2025-10-26T21:33:50Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 26 21:33:50 volumio go-librespot[6946]: time="2025-10-26T21:33:50Z" level=debug msg="completed keyexchange" Oct 26 21:33:50 volumio go-librespot[6946]: time="2025-10-26T21:33:50Z" level=debug msg="completed challenge" Oct 26 21:33:50 volumio go-librespot[6946]: time="2025-10-26T21:33:50Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 26 21:33:50 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 26 21:33:50 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 26 21:33:50 volumio volumio[6372]: info: Initializing connection to go-librespot Websocket Oct 26 21:33:50 volumio volumio[6372]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 26 21:33:53 volumio volumio[6372]: info: Initializing connection to go-librespot Websocket Oct 26 21:33:53 volumio volumio[6372]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 26 21:33:54 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 674. Oct 26 21:33:54 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 26 21:33:54 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 26 21:33:54 volumio go-librespot[6956]: go-librespot daemon starting... Oct 26 21:33:54 volumio go-librespot[6957]: time="2025-10-26T21:33:54Z" level=info msg="running go-librespot 0.4.0" Oct 26 21:33:54 volumio go-librespot[6957]: time="2025-10-26T21:33:54Z" level=debug msg="app state loaded" Oct 26 21:33:54 volumio go-librespot[6957]: time="2025-10-26T21:33:54Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 26 21:33:54 volumio go-librespot[6957]: time="2025-10-26T21:33:54Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 26 21:33:54 volumio go-librespot[6957]: time="2025-10-26T21:33:54Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 26 21:33:54 volumio go-librespot[6957]: time="2025-10-26T21:33:54Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 26 21:33:54 volumio go-librespot[6957]: time="2025-10-26T21:33:54Z" level=info msg="zeroconf server listening on port 46671" Oct 26 21:33:54 volumio go-librespot[6957]: time="2025-10-26T21:33:54Z" level=debug msg="obtained new client token: AABcysnndUa3xRl6sjOIB0CVpLECAgQGjEZFBD0SlFkHVqYPXI9PER+U7CIbuZsH+5C98K/7OIQe+efCN8JZ3KjKIGjkICUVgmc97z5RD5raOyfeAfw5VkaXiYHMRkXpsT9SX/T1wUYAIKOaHK0aUI6FQabnlAXLmVj3UdcjY5NakzMPQwIjOde5ppFoHWGNpX4GJnQ0p8PrL15qz6kdPFTFgaLfoCEY9Xgh3t74bprDQkyMGj6F" Oct 26 21:33:54 volumio go-librespot[6957]: time="2025-10-26T21:33:54Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 26 21:33:55 volumio go-librespot[6957]: time="2025-10-26T21:33:55Z" level=debug msg="completed keyexchange" Oct 26 21:33:55 volumio go-librespot[6957]: time="2025-10-26T21:33:55Z" level=debug msg="completed challenge" Oct 26 21:33:55 volumio go-librespot[6957]: time="2025-10-26T21:33:55Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 26 21:33:55 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 26 21:33:55 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 26 21:33:56 volumio volumio[6372]: info: Initializing connection to go-librespot Websocket Oct 26 21:33:56 volumio volumio[6372]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 26 21:33:58 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 675. Oct 26 21:33:58 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 26 21:33:58 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 26 21:33:58 volumio go-librespot[6984]: go-librespot daemon starting... Oct 26 21:33:58 volumio go-librespot[6985]: time="2025-10-26T21:33:58Z" level=info msg="running go-librespot 0.4.0" Oct 26 21:33:58 volumio go-librespot[6985]: time="2025-10-26T21:33:58Z" level=debug msg="app state loaded" Oct 26 21:33:58 volumio go-librespot[6985]: time="2025-10-26T21:33:58Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 26 21:33:58 volumio go-librespot[6985]: time="2025-10-26T21:33:58Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 26 21:33:58 volumio go-librespot[6985]: time="2025-10-26T21:33:58Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 26 21:33:58 volumio go-librespot[6985]: time="2025-10-26T21:33:58Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 26 21:33:58 volumio go-librespot[6985]: time="2025-10-26T21:33:58Z" level=info msg="zeroconf server listening on port 42313" Oct 26 21:33:58 volumio go-librespot[6985]: time="2025-10-26T21:33:58Z" level=debug msg="obtained new client token: AACIEDg0ioICKj0Fi9MVTm5kP79TgihAQg1e1rX9B5jRAIISvVkwMs0H7mI62FPPwcaNwsaoSLmJri+reYLbdw8BBZo5xY4sU7t/1HROIu0QuGZbvm5ToKKMPYuIfm7eAyOuxv41d3uP3v7wEIWMpzflhmsq8YjDs/D/L1WxJ721/mGITJHJk0d/cUyuGF0VRaUjNlUPky/qUrDBL+5h4I2vASYU3Jv07x0mTVbEuno71Vt7uve+KkE=" Oct 26 21:33:58 volumio go-librespot[6985]: time="2025-10-26T21:33:58Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 26 21:33:58 volumio go-librespot[6985]: time="2025-10-26T21:33:58Z" level=debug msg="completed keyexchange" Oct 26 21:33:58 volumio go-librespot[6985]: time="2025-10-26T21:33:58Z" level=debug msg="completed challenge" Oct 26 21:33:58 volumio go-librespot[6985]: time="2025-10-26T21:33:58Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 26 21:33:58 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 26 21:33:58 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 26 21:33:59 volumio volumio[6372]: info: Initializing connection to go-librespot Websocket Oct 26 21:33:59 volumio volumio[6372]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 26 21:34:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 676. Oct 26 21:34:01 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 26 21:34:01 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 26 21:34:01 volumio go-librespot[6993]: go-librespot daemon starting... Oct 26 21:34:01 volumio go-librespot[6994]: time="2025-10-26T21:34:01Z" level=info msg="running go-librespot 0.4.0" Oct 26 21:34:01 volumio go-librespot[6994]: time="2025-10-26T21:34:01Z" level=debug msg="app state loaded" Oct 26 21:34:01 volumio go-librespot[6994]: time="2025-10-26T21:34:01Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 26 21:34:01 volumio go-librespot[6994]: time="2025-10-26T21:34:01Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 26 21:34:01 volumio go-librespot[6994]: time="2025-10-26T21:34:01Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 26 21:34:01 volumio go-librespot[6994]: time="2025-10-26T21:34:01Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 26 21:34:01 volumio go-librespot[6994]: time="2025-10-26T21:34:01Z" level=info msg="zeroconf server listening on port 37989" Oct 26 21:34:01 volumio go-librespot[6994]: time="2025-10-26T21:34:01Z" level=debug msg="obtained new client token: AACpJjMZd4/SUmDxrG1ibzvoPKQ6w5hssv+QpJ7G1+KTLzO6CzOZoZwwbogaEEM2Y01Zi+05a+/NXtR3keym9K24RH4SCRtfsovRgDQtCEv5NHRWF+AiWGxmdwdI9Ob9lGvQrN72owYtzM15m6srD2G2f0Ip/cPZkSwp+iozsuHlAmuIVb+aEo0ZM8sD3WPTJaG/c+T9y5KKwjODhf6wJ/qVXodSGTmDuqe6cz41KItgCKpzwr4KGKc=" Oct 26 21:34:02 volumio go-librespot[6994]: time="2025-10-26T21:34:02Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 26 21:34:02 volumio go-librespot[6994]: time="2025-10-26T21:34:02Z" level=debug msg="completed keyexchange" Oct 26 21:34:02 volumio go-librespot[6994]: time="2025-10-26T21:34:02Z" level=debug msg="completed challenge" Oct 26 21:34:02 volumio go-librespot[6994]: time="2025-10-26T21:34:02Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 26 21:34:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 26 21:34:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 26 21:34:02 volumio volumio[6372]: info: Initializing connection to go-librespot Websocket Oct 26 21:34:02 volumio volumio[6372]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 26 21:34:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 677. Oct 26 21:34:05 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 26 21:34:05 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 26 21:34:05 volumio go-librespot[7015]: go-librespot daemon starting... Oct 26 21:34:05 volumio go-librespot[7016]: time="2025-10-26T21:34:05Z" level=info msg="running go-librespot 0.4.0" Oct 26 21:34:05 volumio go-librespot[7016]: time="2025-10-26T21:34:05Z" level=debug msg="app state loaded" Oct 26 21:34:05 volumio go-librespot[7016]: time="2025-10-26T21:34:05Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 26 21:34:05 volumio go-librespot[7016]: time="2025-10-26T21:34:05Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 26 21:34:05 volumio go-librespot[7016]: time="2025-10-26T21:34:05Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 26 21:34:05 volumio go-librespot[7016]: time="2025-10-26T21:34:05Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 26 21:34:05 volumio go-librespot[7016]: time="2025-10-26T21:34:05Z" level=info msg="zeroconf server listening on port 35615" Oct 26 21:34:05 volumio go-librespot[7016]: time="2025-10-26T21:34:05Z" level=debug msg="obtained new client token: AAA4bndoxSv8Ci5azzs9bJwgq/xvJBnGCefOWQxjzRhGKUHrmRbvk3CR2eb8d20rnHYvTym3Erm6EFsmPdvgwmlXhxRHklZd8qtTvvfSbrO5krmms50OdrDapd8uLLqoRJw17q+/+wXRzemhCfjVLO9POnG0pt4+E9AIN21sgGU4yuUwI98u1copGckRqTmUNoYQ6wj3KmKk6HIhCEPvl19SDtGnemMHqhnlRG4l+xJGxSNsWjjJM9M=" Oct 26 21:34:05 volumio go-librespot[7016]: time="2025-10-26T21:34:05Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 26 21:34:05 volumio go-librespot[7016]: time="2025-10-26T21:34:05Z" level=debug msg="completed keyexchange" Oct 26 21:34:05 volumio go-librespot[7016]: time="2025-10-26T21:34:05Z" level=debug msg="completed challenge" Oct 26 21:34:05 volumio go-librespot[7016]: time="2025-10-26T21:34:05Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 26 21:34:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 26 21:34:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 26 21:34:06 volumio volumio[6372]: info: Initializing connection to go-librespot Websocket Oct 26 21:34:06 volumio volumio[6372]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 26 21:34:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 678. Oct 26 21:34:08 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 26 21:34:08 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 26 21:34:08 volumio go-librespot[7023]: go-librespot daemon starting... Oct 26 21:34:08 volumio go-librespot[7024]: time="2025-10-26T21:34:08Z" level=info msg="running go-librespot 0.4.0" Oct 26 21:34:08 volumio go-librespot[7024]: time="2025-10-26T21:34:08Z" level=debug msg="app state loaded" Oct 26 21:34:08 volumio go-librespot[7024]: time="2025-10-26T21:34:08Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 26 21:34:08 volumio go-librespot[7024]: time="2025-10-26T21:34:08Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 26 21:34:08 volumio go-librespot[7024]: time="2025-10-26T21:34:08Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 26 21:34:08 volumio go-librespot[7024]: time="2025-10-26T21:34:08Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 26 21:34:08 volumio go-librespot[7024]: time="2025-10-26T21:34:08Z" level=info msg="zeroconf server listening on port 35627" Oct 26 21:34:08 volumio go-librespot[7024]: time="2025-10-26T21:34:08Z" level=debug msg="obtained new client token: AACPotCPg8pUnoHmrVEE1Wn68ZhWEXR2RwVkLKNDKV8EDLBFaDi3y3BAbutgCJzJzL3ykGMIYIy/OxskSpf0f/F/ChNpXPj5B6Obq9TgZGi5Zzp20d0kpnfnboZhHKQBZqSwzi1FUTHUKnfPRe9qQx7U9HDJBRuzjPU1TgPrsL1RStaNGYuAHewoXQsPZ+fLCb3wwcP5R1A9jjvNDvEqxmaX5atyPR/+tgbX8fT0moGHJL6hQeetZdc=" Oct 26 21:34:08 volumio go-librespot[7024]: time="2025-10-26T21:34:08Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 26 21:34:09 volumio volumio[6372]: info: Initializing connection to go-librespot Websocket Oct 26 21:34:09 volumio go-librespot[7024]: time="2025-10-26T21:34:09Z" level=debug msg="new websocket client" Oct 26 21:34:09 volumio volumio[6372]: info: Connection to go-librespot Websocket established Oct 26 21:34:09 volumio go-librespot[7024]: time="2025-10-26T21:34:09Z" level=debug msg="completed keyexchange" Oct 26 21:34:09 volumio go-librespot[7024]: time="2025-10-26T21:34:09Z" level=debug msg="completed challenge" Oct 26 21:34:09 volumio go-librespot[7024]: time="2025-10-26T21:34:09Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 26 21:34:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 26 21:34:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 26 21:34:09 volumio volumio[6372]: info: Connection to go-librespot Websocket closed Oct 26 21:34:12 volumio volumio[6372]: info: Getting Spotify volume Oct 26 21:34:12 volumio volumio[6372]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 26 21:34:12 volumio volumio[6372]: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 26 21:34:12 volumio volumio[6372]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Oct 26 21:34:12 volumio volumio[6372]: errno: -111, Oct 26 21:34:12 volumio volumio[6372]: code: 'ECONNREFUSED', Oct 26 21:34:12 volumio volumio[6372]: syscall: 'connect', Oct 26 21:34:12 volumio volumio[6372]: address: '127.0.0.1', Oct 26 21:34:12 volumio volumio[6372]: port: 9879, Oct 26 21:34:12 volumio volumio[6372]: response: undefined Oct 26 21:34:12 volumio volumio[6372]: } Oct 26 21:34:12 volumio volumio[6372]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 26 21:34:12 volumio sudo[7048]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-10-26 21:33' Oct 26 21:34:12 volumio sudo[7048]: 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"