Oct 23 15:47:01 volumio volumio[12426]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Oct 23 15:47:01 volumio volumio[12426]: info: Initializing connection to go-librespot Websocket Oct 23 15:47:01 volumio volumio[12426]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 23 15:47:02 volumio volumio[12426]: info: Cannot mount NAS NAS at system boot, trial number 3 ,retrying in 5 seconds Oct 23 15:47:02 volumio sudo[12709]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 23 15:47:02 volumio sudo[12709]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:47:02 volumio sudo[12711]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 23 15:47:02 volumio sudo[12709]: pam_unix(sudo:session): session closed for user root Oct 23 15:47:02 volumio sudo[12711]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:47:02 volumio sudo[12711]: pam_unix(sudo:session): session closed for user root Oct 23 15:47:02 volumio sudo[12715]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 23 15:47:02 volumio sudo[12715]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:47:02 volumio sudo[12715]: pam_unix(sudo:session): session closed for user root Oct 23 15:47:02 volumio volumio[12426]: info: Upmpdcli Daemon Started Oct 23 15:47:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 242. Oct 23 15:47:02 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 23 15:47:02 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 23 15:47:02 volumio go-librespot[12717]: go-librespot daemon starting... Oct 23 15:47:02 volumio go-librespot[12718]: time="2025-10-23T15:47:02+01:00" level=info msg="running go-librespot 0.4.0" Oct 23 15:47:02 volumio go-librespot[12718]: time="2025-10-23T15:47:02+01:00" level=debug msg="app state loaded" Oct 23 15:47:02 volumio go-librespot[12718]: time="2025-10-23T15:47:02+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 23 15:47:03 volumio go-librespot[12718]: time="2025-10-23T15:47:03+01:00" 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 23 15:47:03 volumio go-librespot[12718]: time="2025-10-23T15:47:03+01:00" 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 23 15:47:03 volumio go-librespot[12718]: time="2025-10-23T15:47:03+01:00" 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 23 15:47:03 volumio go-librespot[12718]: time="2025-10-23T15:47:03+01:00" level=info msg="zeroconf server listening on port 32791" Oct 23 15:47:03 volumio go-librespot[12718]: time="2025-10-23T15:47:03+01:00" level=debug msg="obtained new client token: AABrFi/1P4Jt5OG4wD9vB+DPd92wTs8TsIQG3+JADhHNVSjNhLLM3ZnZSLm1Qk4WMGfWi0BAaE9oUKpujV4YEwruVH0QcQSs5t5FYaOch7zcxC9iiA4syobx9Sfnw6uBfTKcX7lMr2V3t0Xcfsr+/x7SEQr+R1HmrD9/OC3O4NJ2w+HBA31TVhJvBypvOxWMzxCcltrMGpGyhMJH1JaxiGS056ib8+DYmrfrtkHNrFMWnaFFJDGE" Oct 23 15:47:03 volumio go-librespot[12718]: time="2025-10-23T15:47:03+01:00" 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 23 15:47:03 volumio go-librespot[12718]: time="2025-10-23T15:47:03+01:00" level=debug msg="connected to ap-gew1.spotify.com:443" Oct 23 15:47:03 volumio go-librespot[12718]: time="2025-10-23T15:47:03+01:00" level=debug msg="completed keyexchange" Oct 23 15:47:03 volumio go-librespot[12718]: time="2025-10-23T15:47:03+01:00" level=debug msg="completed challenge" Oct 23 15:47:03 volumio go-librespot[12718]: time="2025-10-23T15:47:03+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 23 15:47:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 23 15:47:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 23 15:47:04 volumio volumio[12426]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Oct 23 15:47:04 volumio volumio[12426]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Oct 23 15:47:04 volumio volumio[12426]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Oct 23 15:47:04 volumio volumio[12426]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Oct 23 15:47:04 volumio volumio[12426]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Oct 23 15:47:04 volumio volumio[12426]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Oct 23 15:47:04 volumio volumio[12426]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Oct 23 15:47:04 volumio volumio[12426]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Oct 23 15:47:04 volumio volumio[12426]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Oct 23 15:47:04 volumio volumio[12426]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Oct 23 15:47:04 volumio volumio[12426]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Oct 23 15:47:04 volumio volumio[12426]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Oct 23 15:47:04 volumio volumio[12426]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Oct 23 15:47:04 volumio volumio[12426]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Oct 23 15:47:04 volumio volumio[12426]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Oct 23 15:47:04 volumio volumio[12426]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Oct 23 15:47:04 volumio volumio[12426]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Oct 23 15:47:04 volumio volumio[12426]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Oct 23 15:47:04 volumio volumio[12426]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Oct 23 15:47:04 volumio volumio[12426]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Oct 23 15:47:04 volumio volumio[12426]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Oct 23 15:47:04 volumio volumio[12426]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Oct 23 15:47:04 volumio volumio[12426]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Oct 23 15:47:04 volumio volumio[12426]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Oct 23 15:47:04 volumio volumio[12426]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Oct 23 15:47:04 volumio volumio[12426]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Oct 23 15:47:04 volumio volumio[12426]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Oct 23 15:47:04 volumio volumio[12426]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Oct 23 15:47:04 volumio volumio[12426]: info: Adding plugin bluetooth to MyMusic Plugins Oct 23 15:47:04 volumio volumio[12426]: info: Adding plugin multiroom to MyMusic Plugins Oct 23 15:47:04 volumio volumio[12426]: info: Adding plugin metavolumio to MyMusic Plugins Oct 23 15:47:04 volumio volumio[12426]: info: Adding plugin cd_controller to MyMusic Plugins Oct 23 15:47:04 volumio volumio[12426]: info: Adding plugin qobuzconnect to MyMusic Plugins Oct 23 15:47:04 volumio volumio[12426]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 23 15:47:04 volumio volumio[12426]: info: Adding plugin tidalconnect to MyMusic Plugins Oct 23 15:47:04 volumio volumio[12426]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 23 15:47:05 volumio volumio[12426]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 23 15:47:05 volumio volumio[12426]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 23 15:47:05 volumio volumio[12426]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 23 15:47:05 volumio volumio[12426]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 23 15:47:05 volumio volumio[12426]: info: Starting MyVolumio Remote Streaming Endpoints Oct 23 15:47:05 volumio volumio[12426]: info: MyVolumio login type: Token Oct 23 15:47:05 volumio volumio[12426]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 23 15:47:05 volumio volumio[12426]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Oct 23 15:47:05 volumio volumio[12426]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 23 15:47:05 volumio volumio[12426]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 23 15:47:05 volumio volumio[12426]: info: Streaming services startup Oct 23 15:47:05 volumio volumio[12426]: info: Starting Streaming Daemon Oct 23 15:47:05 volumio sudo[12726]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 23 15:47:05 volumio sudo[12726]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:47:05 volumio volumio[12426]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Oct 23 15:47:05 volumio sudo[12726]: pam_unix(sudo:session): session closed for user root Oct 23 15:47:05 volumio volumio[12426]: info: Initializing connection to go-librespot Websocket Oct 23 15:47:05 volumio volumio[12426]: error: Cannot start Volumio Streaming Daemon Oct 23 15:47:05 volumio volumio[12426]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 23 15:47:05 volumio volumio[12426]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 23 15:47:05 volumio volumio[12426]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 23 15:47:05 volumio volumio[12426]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Oct 23 15:47:06 volumio volumio[12426]: info: MyVolumio token set successfully Oct 23 15:47:06 volumio volumio[12426]: info: MYVOLUMIO: Adding device Oct 23 15:47:06 volumio volumio[12426]: info: MYVOLUMIO: Evaluating Server Oct 23 15:47:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 243. Oct 23 15:47:06 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 23 15:47:06 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 23 15:47:06 volumio go-librespot[12734]: go-librespot daemon starting... Oct 23 15:47:06 volumio go-librespot[12735]: time="2025-10-23T15:47:06+01:00" level=info msg="running go-librespot 0.4.0" Oct 23 15:47:06 volumio go-librespot[12735]: time="2025-10-23T15:47:06+01:00" level=debug msg="app state loaded" Oct 23 15:47:06 volumio go-librespot[12735]: time="2025-10-23T15:47:06+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 23 15:47:06 volumio volumio[12426]: info: MyVolumio status changed Oct 23 15:47:06 volumio volumio[12426]: info: Streaming services startup Oct 23 15:47:06 volumio volumio[12426]: info: Starting Streaming Daemon Oct 23 15:47:06 volumio volumio[12426]: info: Removing browser output: myVolumio user plan is not superstar Oct 23 15:47:06 volumio volumio[12426]: info: Removing audio output: Oct 23 15:47:06 volumio volumio[12426]: info: Stoppping Tunnel 1 Oct 23 15:47:06 volumio sudo[12760]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Oct 23 15:47:06 volumio sudo[12760]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:47:06 volumio sudo[12758]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 23 15:47:06 volumio sudo[12758]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 23 15:47:06 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 23 15:47:06 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 23 15:47:06 volumio sudo[12758]: pam_unix(sudo:session): session closed for user root Oct 23 15:47:06 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 23 15:47:06 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 23 15:47:06 volumio volumio[12426]: error: Cannot start Volumio Streaming Daemon Oct 23 15:47:06 volumio volumio[12426]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 23 15:47:06 volumio volumio[12426]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 23 15:47:06 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 23 15:47:06 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 23 15:47:06 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 23 15:47:06 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 23 15:47:06 volumio sudo[12760]: pam_unix(sudo:session): session closed for user root Oct 23 15:47:06 volumio volumio[12426]: info: Remote SSH Stopped Oct 23 15:47:06 volumio go-librespot[12735]: time="2025-10-23T15:47:06+01:00" 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 23 15:47:06 volumio go-librespot[12735]: time="2025-10-23T15:47:06+01:00" 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 23 15:47:06 volumio go-librespot[12735]: time="2025-10-23T15:47:06+01:00" 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 23 15:47:06 volumio go-librespot[12735]: time="2025-10-23T15:47:06+01:00" level=info msg="zeroconf server listening on port 34685" Oct 23 15:47:06 volumio go-librespot[12735]: time="2025-10-23T15:47:06+01:00" level=debug msg="obtained new client token: AAAvnMKq4zk+q9vIYfawx2f508kX7fYyQlw6s4g6bdZ1WMkbQ9+XU5gUWmuhU5L7qFquivkBIdpWesiwNSRsX4yuY+eMV2NZpKGF7G8akctNiTmL4FzZgrEOA+ydc4SI/LIirS1wlnB9AzTsm1eegay3xAnl7xKHqrnhPxZylEDp7zUic6oh1h7CafMAtfTluc3ylCZZx3OvWs1rXym4giWw20vOEqIFwvemJAHHYQyx3M90oAg0bfQ=" Oct 23 15:47:06 volumio go-librespot[12735]: time="2025-10-23T15:47:06+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 23 15:47:06 volumio go-librespot[12735]: time="2025-10-23T15:47:06+01:00" level=debug msg="completed keyexchange" Oct 23 15:47:06 volumio go-librespot[12735]: time="2025-10-23T15:47:06+01:00" level=debug msg="completed challenge" Oct 23 15:47:06 volumio go-librespot[12735]: time="2025-10-23T15:47:06+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 23 15:47:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 23 15:47:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 23 15:47:06 volumio volumio[12426]: info: Setting Geolocation for MyVolumio to eu3 Oct 23 15:47:06 volumio volumio[12426]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 23 15:47:06 volumio volumio[12426]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 23 15:47:06 volumio volumio[12426]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 23 15:47:07 volumio volumio[12426]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Oct 23 15:47:07 volumio volumio[12426]: info: Cannot mount NAS NAS at system boot, trial number 4 ,retrying in 5 seconds Oct 23 15:47:07 volumio volumio[12426]: info: Updating MyVolumio device info Oct 23 15:47:07 volumio volumio[12426]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 23 15:47:07 volumio volumio[12426]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 23 15:47:07 volumio volumio[12426]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 23 15:47:08 volumio volumio[12426]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Oct 23 15:47:08 volumio volumio[12426]: info: Initializing connection to go-librespot Websocket Oct 23 15:47:08 volumio volumio[12426]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 23 15:47:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 244. Oct 23 15:47:09 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 23 15:47:09 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 23 15:47:09 volumio go-librespot[12763]: go-librespot daemon starting... Oct 23 15:47:09 volumio go-librespot[12764]: time="2025-10-23T15:47:09+01:00" level=info msg="running go-librespot 0.4.0" Oct 23 15:47:09 volumio go-librespot[12764]: time="2025-10-23T15:47:09+01:00" level=debug msg="app state loaded" Oct 23 15:47:09 volumio go-librespot[12764]: time="2025-10-23T15:47:09+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 23 15:47:10 volumio go-librespot[12764]: time="2025-10-23T15:47:10+01:00" 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 23 15:47:10 volumio go-librespot[12764]: time="2025-10-23T15:47:10+01:00" 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 23 15:47:10 volumio go-librespot[12764]: time="2025-10-23T15:47:10+01:00" 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 23 15:47:10 volumio go-librespot[12764]: time="2025-10-23T15:47:10+01:00" level=info msg="zeroconf server listening on port 39299" Oct 23 15:47:10 volumio go-librespot[12764]: time="2025-10-23T15:47:10+01:00" level=debug msg="obtained new client token: AACGzB2NGCHuTee0Xt957KYXvgDNPVmIC/W4Zv9ksGA0J30CUDYD3fWIZ+Stb6Pgz/nDRrQlrWsmnzDUFJ5zFYge4kcZ4oHdlRCcOoDVMvuIHQOUIi6D7SiLfQOjkv1bx6c8Aytj3igNwmsucn7avHVoWNMimExTHHoon0MEbUVnxWbnoSKQgEBbyATYfwEd+b2pL+cbWV+VplelynNb27OhdzjbghAYzWFzTQlS+8Ohxu3HP0LH" Oct 23 15:47:10 volumio go-librespot[12764]: time="2025-10-23T15:47:10+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 23 15:47:10 volumio go-librespot[12764]: time="2025-10-23T15:47:10+01:00" level=debug msg="completed keyexchange" Oct 23 15:47:10 volumio go-librespot[12764]: time="2025-10-23T15:47:10+01:00" level=debug msg="completed challenge" Oct 23 15:47:10 volumio go-librespot[12764]: time="2025-10-23T15:47:10+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 23 15:47:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 23 15:47:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 23 15:47:11 volumio volumio[12426]: info: MYVOLUMIO: Adding device Oct 23 15:47:11 volumio volumio[12426]: info: MYVOLUMIO: Evaluating Server Oct 23 15:47:11 volumio volumio[12426]: info: Initializing connection to go-librespot Websocket Oct 23 15:47:11 volumio volumio[12426]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 23 15:47:11 volumio volumio[12426]: info: Setting Geolocation for MyVolumio to eu3 Oct 23 15:47:11 volumio volumio[12426]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 23 15:47:11 volumio volumio[12426]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 23 15:47:11 volumio volumio[12426]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 23 15:47:11 volumio volumio[12426]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Oct 23 15:47:12 volumio volumio[12426]: info: Updating MyVolumio device info Oct 23 15:47:12 volumio volumio[12426]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 23 15:47:12 volumio volumio[12426]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 23 15:47:12 volumio volumio[12426]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 23 15:47:12 volumio volumio[12426]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Oct 23 15:47:13 volumio volumio[12426]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Oct 23 15:47:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 245. Oct 23 15:47:13 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 23 15:47:13 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 23 15:47:13 volumio go-librespot[12800]: go-librespot daemon starting... Oct 23 15:47:13 volumio go-librespot[12801]: time="2025-10-23T15:47:13+01:00" level=info msg="running go-librespot 0.4.0" Oct 23 15:47:13 volumio go-librespot[12801]: time="2025-10-23T15:47:13+01:00" level=debug msg="app state loaded" Oct 23 15:47:13 volumio go-librespot[12801]: time="2025-10-23T15:47:13+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 23 15:47:13 volumio go-librespot[12801]: time="2025-10-23T15:47:13+01:00" 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 23 15:47:13 volumio go-librespot[12801]: time="2025-10-23T15:47:13+01:00" 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 23 15:47:13 volumio go-librespot[12801]: time="2025-10-23T15:47:13+01:00" 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 23 15:47:13 volumio go-librespot[12801]: time="2025-10-23T15:47:13+01:00" level=info msg="zeroconf server listening on port 46067" Oct 23 15:47:13 volumio go-librespot[12801]: time="2025-10-23T15:47:13+01:00" level=debug msg="obtained new client token: AACIRS7be/xnab6T9OPirzR2fxAMouC4D4gL/yGIVckrX/X43/t0ftDicL5FZkKNck3ybGMRGQ1o1BFqYWLlurJmzgoe8F1a6tq+64nLrzGtoj1qy070qt8z/T3xTxG07afbpruXwLw99kGReqYzWQR2swrO7+A1i62IwGzitRGAMVfLck3t9OnrRl3nvsz8I3O1GuuwubvlCudvJCI1eVHxDGynItG0PPCt5JiBVsEtJ2lTj/JTSus=" Oct 23 15:47:13 volumio go-librespot[12801]: time="2025-10-23T15:47:13+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 23 15:47:13 volumio go-librespot[12801]: time="2025-10-23T15:47:13+01:00" level=debug msg="completed keyexchange" Oct 23 15:47:13 volumio go-librespot[12801]: time="2025-10-23T15:47:13+01:00" level=debug msg="completed challenge" Oct 23 15:47:13 volumio go-librespot[12801]: time="2025-10-23T15:47:13+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 23 15:47:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 23 15:47:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 23 15:47:14 volumio volumio[12426]: info: Initializing connection to go-librespot Websocket Oct 23 15:47:14 volumio volumio[12426]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 23 15:47:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 246. Oct 23 15:47:16 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 23 15:47:16 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 23 15:47:16 volumio go-librespot[12808]: go-librespot daemon starting... Oct 23 15:47:16 volumio go-librespot[12809]: time="2025-10-23T15:47:16+01:00" level=info msg="running go-librespot 0.4.0" Oct 23 15:47:16 volumio go-librespot[12809]: time="2025-10-23T15:47:16+01:00" level=debug msg="app state loaded" Oct 23 15:47:16 volumio go-librespot[12809]: time="2025-10-23T15:47:16+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 23 15:47:17 volumio go-librespot[12809]: time="2025-10-23T15:47:17+01:00" 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 23 15:47:17 volumio go-librespot[12809]: time="2025-10-23T15:47:17+01:00" 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 23 15:47:17 volumio go-librespot[12809]: time="2025-10-23T15:47:17+01:00" 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 23 15:47:17 volumio go-librespot[12809]: time="2025-10-23T15:47:17+01:00" level=info msg="zeroconf server listening on port 36717" Oct 23 15:47:17 volumio go-librespot[12809]: time="2025-10-23T15:47:17+01:00" level=debug msg="obtained new client token: AADjo6DHkdKpMVy0jPoiUgqIpy2Qx+jCj5Z2CV8gQvzJLfv4N6mZvc/MmD/n9GeVpjOk5RhMS0h4Y2JqCmr8/MBxBopY+FVR0Jm0b+MKC5B4VWnaTwssIOy5ROnKeYsr2RfaKVwnrVAaa6TCKJpk3w7YAhxLTuDGjsYzTtgElojJej35gRPLoqfBSw+RSY3aNl7eY2JGn6JNHkdLt8M+kon3gF1T2XOuE2QWNtsdn72xj9SehFGc" Oct 23 15:47:17 volumio go-librespot[12809]: time="2025-10-23T15:47:17+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 23 15:47:17 volumio go-librespot[12809]: time="2025-10-23T15:47:17+01:00" level=debug msg="completed keyexchange" Oct 23 15:47:17 volumio go-librespot[12809]: time="2025-10-23T15:47:17+01:00" level=debug msg="completed challenge" Oct 23 15:47:17 volumio go-librespot[12809]: time="2025-10-23T15:47:17+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 23 15:47:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 23 15:47:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 23 15:47:17 volumio volumio[12426]: info: Initializing connection to go-librespot Websocket Oct 23 15:47:17 volumio volumio[12426]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 23 15:47:20 volumio volumio[12426]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 23 15:47:20 volumio volumio[12426]: 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 23 15:47:20 volumio volumio[12426]: info: CoreCommandRouter::volumioGetState Oct 23 15:47:20 volumio volumio[12426]: info: CorePlayQueue::getTrack 0 Oct 23 15:47:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 247. Oct 23 15:47:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 23 15:47:20 volumio volumio[12426]: info: Initializing connection to go-librespot Websocket Oct 23 15:47:20 volumio volumio[12426]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 23 15:47:20 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 23 15:47:20 volumio go-librespot[12819]: go-librespot daemon starting... Oct 23 15:47:20 volumio go-librespot[12820]: time="2025-10-23T15:47:20+01:00" level=info msg="running go-librespot 0.4.0" Oct 23 15:47:20 volumio go-librespot[12820]: time="2025-10-23T15:47:20+01:00" level=debug msg="app state loaded" Oct 23 15:47:20 volumio go-librespot[12820]: time="2025-10-23T15:47:20+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 23 15:47:20 volumio go-librespot[12820]: time="2025-10-23T15:47:20+01:00" 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 23 15:47:20 volumio go-librespot[12820]: time="2025-10-23T15:47:20+01:00" 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 23 15:47:20 volumio go-librespot[12820]: time="2025-10-23T15:47:20+01:00" 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 23 15:47:20 volumio go-librespot[12820]: time="2025-10-23T15:47:20+01:00" level=info msg="zeroconf server listening on port 35795" Oct 23 15:47:20 volumio go-librespot[12820]: time="2025-10-23T15:47:20+01:00" level=debug msg="obtained new client token: AAAigUJ/WaxKUzRVBzQ5vFWQcrI0NhrzzANqUjBGNvJWDkZToafXp7iht4jh1KU3dnyUjDZbkjo+9PO9eiS2XdhOZBFBzyEVscQ9+kL+gnCDxOWRwXp0oZTDRvN/tBB1onuquB5GAo5tWQ4AmYYneiXX1zcPdkw68ty62+Jv0h9eQGJ6vV8JsGkVhPvnaKSWW/AY8C9VkoYJMehfKQilwuOySD/exCDnuGOM8TRWVmPL3BjSrnhGIuc=" Oct 23 15:47:20 volumio go-librespot[12820]: time="2025-10-23T15:47:20+01:00" 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 23 15:47:20 volumio go-librespot[12820]: time="2025-10-23T15:47:20+01:00" level=debug msg="connected to ap-gew1.spotify.com:443" Oct 23 15:47:20 volumio go-librespot[12820]: time="2025-10-23T15:47:20+01:00" level=debug msg="completed keyexchange" Oct 23 15:47:20 volumio go-librespot[12820]: time="2025-10-23T15:47:20+01:00" level=debug msg="completed challenge" Oct 23 15:47:20 volumio go-librespot[12820]: time="2025-10-23T15:47:20+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 23 15:47:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 23 15:47:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 23 15:47:23 volumio volumio[12426]: info: Initializing connection to go-librespot Websocket Oct 23 15:47:23 volumio volumio[12426]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 23 15:47:23 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 248. Oct 23 15:47:23 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 23 15:47:23 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 23 15:47:23 volumio go-librespot[12841]: go-librespot daemon starting... Oct 23 15:47:23 volumio go-librespot[12842]: time="2025-10-23T15:47:23+01:00" level=info msg="running go-librespot 0.4.0" Oct 23 15:47:23 volumio go-librespot[12842]: time="2025-10-23T15:47:23+01:00" level=debug msg="app state loaded" Oct 23 15:47:23 volumio go-librespot[12842]: time="2025-10-23T15:47:23+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 23 15:47:24 volumio go-librespot[12842]: time="2025-10-23T15:47:24+01:00" 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 23 15:47:24 volumio go-librespot[12842]: time="2025-10-23T15:47:24+01:00" 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 23 15:47:24 volumio go-librespot[12842]: time="2025-10-23T15:47:24+01:00" 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 23 15:47:24 volumio go-librespot[12842]: time="2025-10-23T15:47:24+01:00" level=info msg="zeroconf server listening on port 42001" Oct 23 15:47:24 volumio go-librespot[12842]: time="2025-10-23T15:47:24+01:00" level=debug msg="obtained new client token: AAA9m3f1EarEqJunh6HDyU8aK6Q7g6QF++M5d0cuntKHX95GbivW6FXdG2EwXd4Xvsnl5tRvkrehevEryEjqzZ8zydZA9K9Z1vG3acfdgaMMVq+jxOKUK/+BhvxzkdAbceJMlT7GmxPk6uV2SQmxcgaNwynLUnZfBqoSQTu9HxnmtNu8XtG0kEUHnYt0/o4MiMG2OarEkThzHnDXO1JJdIGEEqu3ZshMCm8J5MYaYlRHpp8rnyLe" Oct 23 15:47:24 volumio go-librespot[12842]: time="2025-10-23T15:47:24+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 23 15:47:24 volumio go-librespot[12842]: time="2025-10-23T15:47:24+01:00" level=debug msg="completed keyexchange" Oct 23 15:47:24 volumio go-librespot[12842]: time="2025-10-23T15:47:24+01:00" level=debug msg="completed challenge" Oct 23 15:47:24 volumio go-librespot[12842]: time="2025-10-23T15:47:24+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 23 15:47:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 23 15:47:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 23 15:47:25 volumio volumio[12426]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 15:47:25 volumio volumio[12426]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 15:47:25 volumio volumio[12426]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 23 15:47:25 volumio volumio[12426]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 23 15:47:25 volumio volumio[12426]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 23 15:47:25 volumio volumio[12426]: info: CoreCommandRouter::volumioGetBrowseSources Oct 23 15:47:25 volumio volumio[12426]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 23 15:47:25 volumio volumio[12426]: error: MyVolumio Plugin failed to authenticate in a timely fashion Oct 23 15:47:25 volumio volumio[12426]: info: Completed starting MyVolumio Plugin Oct 23 15:47:25 volumio volumio[12426]: [Metrics] CommandRouter: 33s 384.51ms Oct 23 15:47:25 volumio volumio[12426]: info: CoreCommandRouter::volumiosetStartupVolume Oct 23 15:47:25 volumio volumio[12426]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 15:47:25 volumio volumio[12426]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 23 15:47:25 volumio volumio[12426]: info: CoreCommandRouter::Close All Modals sent Oct 23 15:47:25 volumio volumio[12426]: info: CoreCommandRouter::Close All Modals sent Oct 23 15:47:25 volumio volumio[12426]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 23 15:47:25 volumio volumio[12426]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 23 15:47:25 volumio volumio[12426]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 23 15:47:25 volumio volumio-remote-updater[896]: Test mode disabled Oct 23 15:47:25 volumio volumio-remote-updater[896]: Alpha mode disabled Oct 23 15:47:25 volumio volumio-remote-updater[896]: Alpha legacy test mode disabled Oct 23 15:47:25 volumio volumio[12426]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Oct 23 15:47:25 volumio volumio[12426]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 23 15:47:25 volumio volumio[12426]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 23 15:47:26 volumio volumio[12426]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 23 15:47:26 volumio volumio[12426]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 23 15:47:26 volumio volumio[12426]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 23 15:47:26 volumio volumio[12426]: info: Initializing connection to go-librespot Websocket Oct 23 15:47:26 volumio volumio[12426]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 23 15:47:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 249. Oct 23 15:47:27 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 23 15:47:27 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 23 15:47:27 volumio go-librespot[12858]: go-librespot daemon starting... Oct 23 15:47:27 volumio go-librespot[12859]: time="2025-10-23T15:47:27+01:00" level=info msg="running go-librespot 0.4.0" Oct 23 15:47:27 volumio go-librespot[12859]: time="2025-10-23T15:47:27+01:00" level=debug msg="app state loaded" Oct 23 15:47:27 volumio go-librespot[12859]: time="2025-10-23T15:47:27+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 23 15:47:27 volumio go-librespot[12859]: time="2025-10-23T15:47:27+01:00" 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 23 15:47:27 volumio go-librespot[12859]: time="2025-10-23T15:47:27+01:00" 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 23 15:47:27 volumio go-librespot[12859]: time="2025-10-23T15:47:27+01:00" 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 23 15:47:27 volumio go-librespot[12859]: time="2025-10-23T15:47:27+01:00" level=info msg="zeroconf server listening on port 38931" Oct 23 15:47:27 volumio go-librespot[12859]: time="2025-10-23T15:47:27+01:00" level=debug msg="obtained new client token: AACa1jydmBJW5BqtnRRPH2yzn0NsqBgUnhU+FgmR4xmyExMBKcwhylObNNNb+InHPm3V1uHRQocfT6KZsAR2fCyyHpzYDQmGkNHXGXOvPgXHUdTrz8fYBaCSRBC6HCYbfVfYVqE0W3QsQ7yKQPLpwDR8U3IT1i0PvNj4BaWbX3lTp5VT5UA5eoIQgltptapQYGTKJT6jWD4qdoEokBLy8RrG4qpMLD5Gsg7X7yP7vcnsz1dIE9Kxlf0=" Oct 23 15:47:27 volumio go-librespot[12859]: time="2025-10-23T15:47:27+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 23 15:47:27 volumio go-librespot[12859]: time="2025-10-23T15:47:27+01:00" level=debug msg="completed keyexchange" Oct 23 15:47:27 volumio go-librespot[12859]: time="2025-10-23T15:47:27+01:00" level=debug msg="completed challenge" Oct 23 15:47:27 volumio go-librespot[12859]: time="2025-10-23T15:47:27+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 23 15:47:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 23 15:47:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 23 15:47:29 volumio volumio[12426]: info: Initializing connection to go-librespot Websocket Oct 23 15:47:29 volumio volumio[12426]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 23 15:47:30 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 250. Oct 23 15:47:30 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 23 15:47:30 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 23 15:47:30 volumio go-librespot[12867]: go-librespot daemon starting... Oct 23 15:47:30 volumio go-librespot[12868]: time="2025-10-23T15:47:30+01:00" level=info msg="running go-librespot 0.4.0" Oct 23 15:47:30 volumio go-librespot[12868]: time="2025-10-23T15:47:30+01:00" level=debug msg="app state loaded" Oct 23 15:47:30 volumio go-librespot[12868]: time="2025-10-23T15:47:30+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 23 15:47:31 volumio go-librespot[12868]: time="2025-10-23T15:47:31+01:00" 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 23 15:47:31 volumio go-librespot[12868]: time="2025-10-23T15:47:31+01:00" 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 23 15:47:31 volumio go-librespot[12868]: time="2025-10-23T15:47:31+01:00" 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 23 15:47:31 volumio go-librespot[12868]: time="2025-10-23T15:47:31+01:00" level=info msg="zeroconf server listening on port 40983" Oct 23 15:47:31 volumio go-librespot[12868]: time="2025-10-23T15:47:31+01:00" level=debug msg="obtained new client token: AADf/4IOcPpa7ifw3y+Iwf0z88nZY9BbxfOoEEUXG7OqGkkPdTxWrGSjaH23xV5rKJeJDkGxA8ZSkV4GiHRzFCy0OxgAUSr4gf78Gkf0BNRShk5esGyc0SXM3Njau+UsfUr/QqB1tY7I34qca769XUlKis7dV1X2u4svUu+rfYTrV//IZmWUVVpCxGl+H7n0eb83h13ct5Duv1PF9OU0UxGwUGZT/QcVVaxDi4vgelObhE5FZUOs" Oct 23 15:47:31 volumio go-librespot[12868]: time="2025-10-23T15:47:31+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 23 15:47:31 volumio go-librespot[12868]: time="2025-10-23T15:47:31+01:00" level=debug msg="completed keyexchange" Oct 23 15:47:31 volumio go-librespot[12868]: time="2025-10-23T15:47:31+01:00" level=debug msg="completed challenge" Oct 23 15:47:31 volumio go-librespot[12868]: time="2025-10-23T15:47:31+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 23 15:47:31 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 23 15:47:31 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 23 15:47:32 volumio volumio[12426]: info: BOOT COMPLETED Oct 23 15:47:32 volumio volumio[12426]: info: Initializing connection to go-librespot Websocket Oct 23 15:47:32 volumio volumio[12426]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 23 15:47:34 volumio nmbd[1166]: [2025/10/23 15:47:34.152934, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Oct 23 15:47:34 volumio nmbd[1166]: Packet send failed to 192.168.1.255(138) ERRNO=Network is unreachable Oct 23 15:47:34 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 251. Oct 23 15:47:34 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 23 15:47:34 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 23 15:47:34 volumio go-librespot[12889]: go-librespot daemon starting... Oct 23 15:47:34 volumio go-librespot[12890]: time="2025-10-23T15:47:34+01:00" level=info msg="running go-librespot 0.4.0" Oct 23 15:47:34 volumio go-librespot[12890]: time="2025-10-23T15:47:34+01:00" level=debug msg="app state loaded" Oct 23 15:47:34 volumio go-librespot[12890]: time="2025-10-23T15:47:34+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 23 15:47:34 volumio go-librespot[12890]: time="2025-10-23T15:47:34+01:00" 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 23 15:47:34 volumio go-librespot[12890]: time="2025-10-23T15:47:34+01:00" 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 23 15:47:34 volumio go-librespot[12890]: time="2025-10-23T15:47:34+01:00" 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 23 15:47:34 volumio go-librespot[12890]: time="2025-10-23T15:47:34+01:00" level=info msg="zeroconf server listening on port 41311" Oct 23 15:47:34 volumio go-librespot[12890]: time="2025-10-23T15:47:34+01:00" level=debug msg="obtained new client token: AAAmCwAV/QDETA40sdHX4gT5YztTi1IdUrPdSb3GlNdkl1sCEkuCwrv3y9VP7KYVh+DKY70sPPCZleLZ2uqePO7rz98RLNVCwqdkA55XC72PMaP3kJVUbP1XPSVKG1GmKCPgF90DbcXJGSSSSoEYRsyX4yXZtkAa53Ruqv/t6tOujGFzN12tVxmKL4MlIys3uZWfbWphc0KSToR8RDioRjog7fMPEx1NOaAeUTa8MExXXHVOGa0i4nY=" Oct 23 15:47:34 volumio go-librespot[12890]: time="2025-10-23T15:47:34+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 23 15:47:34 volumio go-librespot[12890]: time="2025-10-23T15:47:34+01:00" level=debug msg="completed keyexchange" Oct 23 15:47:34 volumio go-librespot[12890]: time="2025-10-23T15:47:34+01:00" level=debug msg="completed challenge" Oct 23 15:47:34 volumio go-librespot[12890]: time="2025-10-23T15:47:34+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 23 15:47:34 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 23 15:47:34 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 23 15:47:35 volumio volumio[12426]: info: Initializing connection to go-librespot Websocket Oct 23 15:47:35 volumio volumio[12426]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 23 15:47:37 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 252. Oct 23 15:47:37 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 23 15:47:37 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 23 15:47:37 volumio go-librespot[12898]: go-librespot daemon starting... Oct 23 15:47:37 volumio go-librespot[12899]: time="2025-10-23T15:47:37+01:00" level=info msg="running go-librespot 0.4.0" Oct 23 15:47:37 volumio go-librespot[12899]: time="2025-10-23T15:47:37+01:00" level=debug msg="app state loaded" Oct 23 15:47:37 volumio go-librespot[12899]: time="2025-10-23T15:47:37+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 23 15:47:38 volumio go-librespot[12899]: time="2025-10-23T15:47:38+01:00" 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 23 15:47:38 volumio go-librespot[12899]: time="2025-10-23T15:47:38+01:00" 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 23 15:47:38 volumio go-librespot[12899]: time="2025-10-23T15:47:38+01:00" 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 23 15:47:38 volumio go-librespot[12899]: time="2025-10-23T15:47:38+01:00" level=info msg="zeroconf server listening on port 37479" Oct 23 15:47:38 volumio go-librespot[12899]: time="2025-10-23T15:47:38+01:00" level=debug msg="obtained new client token: AACEyaexvSf/JczWmbMltiqUP2+M1J2tqUK0g1nxDRskYCWvzMp8yqmvwjZyysSHMyT8nyNetQohQ2C3+jA11Oy+MrCPHGvEBYRW6haMLyqZF2nePPVzfJlcwzK7LaTrq2pnaPyMBf8jxXk1x2ePec6jEgbDwN0h8n6Ytsm+IoM+BMIrEOk4X/v5EG2hRbEgrGwrvAgMXaO9ZT4N7lFYKZ5sg4A1gdUwVZp5yHsKht8HL5s+N39B" Oct 23 15:47:38 volumio go-librespot[12899]: time="2025-10-23T15:47:38+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 23 15:47:38 volumio go-librespot[12899]: time="2025-10-23T15:47:38+01:00" level=debug msg="completed keyexchange" Oct 23 15:47:38 volumio go-librespot[12899]: time="2025-10-23T15:47:38+01:00" level=debug msg="completed challenge" Oct 23 15:47:38 volumio go-librespot[12899]: time="2025-10-23T15:47:38+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 23 15:47:38 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 23 15:47:38 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 23 15:47:38 volumio volumio[12426]: info: Initializing connection to go-librespot Websocket Oct 23 15:47:38 volumio volumio[12426]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 23 15:47:41 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 253. Oct 23 15:47:41 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 23 15:47:41 volumio volumio[12426]: info: Initializing connection to go-librespot Websocket Oct 23 15:47:41 volumio volumio[12426]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 23 15:47:41 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 23 15:47:41 volumio go-librespot[12909]: go-librespot daemon starting... Oct 23 15:47:41 volumio go-librespot[12910]: time="2025-10-23T15:47:41+01:00" level=info msg="running go-librespot 0.4.0" Oct 23 15:47:41 volumio go-librespot[12910]: time="2025-10-23T15:47:41+01:00" level=debug msg="app state loaded" Oct 23 15:47:41 volumio go-librespot[12910]: time="2025-10-23T15:47:41+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 23 15:47:41 volumio go-librespot[12910]: time="2025-10-23T15:47:41+01:00" 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 23 15:47:41 volumio go-librespot[12910]: time="2025-10-23T15:47:41+01:00" 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 23 15:47:41 volumio go-librespot[12910]: time="2025-10-23T15:47:41+01:00" 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 23 15:47:41 volumio go-librespot[12910]: time="2025-10-23T15:47:41+01:00" level=info msg="zeroconf server listening on port 42075" Oct 23 15:47:41 volumio go-librespot[12910]: time="2025-10-23T15:47:41+01:00" level=debug msg="obtained new client token: AADyAkm3HT7rr8olHR+E0899sfivRtALTBKIBtdHNZ/pNT8AAhfF5raBo0PP05WS5mf1eB83YatFftVwq4i90km35oWqAWrTEKJBFzGiCtg/2Vdb+EarnCgeA0nkYZDD/jjwE3j25CyO9NKY9h5be5fpr/iyXOFoDHR+Foa/Hq5lQT7240QMZIvyx+piMrExGsXr0RAaqdxiTeHYbDRFNvdi3/SpnaptDHxBq4f0/+H2k0vd/DLkG7M=" Oct 23 15:47:41 volumio go-librespot[12910]: time="2025-10-23T15:47:41+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 23 15:47:41 volumio go-librespot[12910]: time="2025-10-23T15:47:41+01:00" level=debug msg="completed keyexchange" Oct 23 15:47:41 volumio go-librespot[12910]: time="2025-10-23T15:47:41+01:00" level=debug msg="completed challenge" Oct 23 15:47:41 volumio go-librespot[12910]: time="2025-10-23T15:47:41+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 23 15:47:41 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 23 15:47:41 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 23 15:47:44 volumio volumio[12426]: info: Initializing connection to go-librespot Websocket Oct 23 15:47:44 volumio volumio[12426]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 23 15:47:44 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 254. Oct 23 15:47:44 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 23 15:47:44 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 23 15:47:44 volumio go-librespot[12931]: go-librespot daemon starting... Oct 23 15:47:44 volumio go-librespot[12932]: time="2025-10-23T15:47:44+01:00" level=info msg="running go-librespot 0.4.0" Oct 23 15:47:44 volumio go-librespot[12932]: time="2025-10-23T15:47:44+01:00" level=debug msg="app state loaded" Oct 23 15:47:44 volumio go-librespot[12932]: time="2025-10-23T15:47:44+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 23 15:47:45 volumio go-librespot[12932]: time="2025-10-23T15:47:45+01:00" 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 23 15:47:45 volumio go-librespot[12932]: time="2025-10-23T15:47:45+01:00" 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 23 15:47:45 volumio go-librespot[12932]: time="2025-10-23T15:47:45+01:00" 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 23 15:47:45 volumio go-librespot[12932]: time="2025-10-23T15:47:45+01:00" level=info msg="zeroconf server listening on port 32769" Oct 23 15:47:45 volumio go-librespot[12932]: time="2025-10-23T15:47:45+01:00" level=debug msg="obtained new client token: AAAZQ8qTTIO6bZueog3c6GS8kQ+8se6ADbQirD6vs28474MI9yTFfC9Ytc7pXo+AOTGxZ6Nq58b7sTXrzhnzmF6jMTMwiMCB52ZEI3owIeTWZjqhlybQc4ticp0yVc/tLTeS2N0GvZu7tMCUGk9vl7w/Lsjvym+Y3cVKYiPVT3db1JlF31fwA6mLo5WqB3fjJsE4qHT542u/gnCPxXMyGkHV3d+TtKt2KVJbIglL7iAKNwVTIh+Z" Oct 23 15:47:45 volumio go-librespot[12932]: time="2025-10-23T15:47:45+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 23 15:47:45 volumio go-librespot[12932]: time="2025-10-23T15:47:45+01:00" level=debug msg="completed keyexchange" Oct 23 15:47:45 volumio go-librespot[12932]: time="2025-10-23T15:47:45+01:00" level=debug msg="completed challenge" Oct 23 15:47:45 volumio go-librespot[12932]: time="2025-10-23T15:47:45+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 23 15:47:45 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 23 15:47:45 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 23 15:47:47 volumio volumio[12426]: info: Initializing connection to go-librespot Websocket Oct 23 15:47:47 volumio volumio[12426]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 23 15:47:48 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 255. Oct 23 15:47:48 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 23 15:47:48 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 23 15:47:48 volumio go-librespot[12939]: go-librespot daemon starting... Oct 23 15:47:48 volumio go-librespot[12940]: time="2025-10-23T15:47:48+01:00" level=info msg="running go-librespot 0.4.0" Oct 23 15:47:48 volumio go-librespot[12940]: time="2025-10-23T15:47:48+01:00" level=debug msg="app state loaded" Oct 23 15:47:48 volumio go-librespot[12940]: time="2025-10-23T15:47:48+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 23 15:47:48 volumio go-librespot[12940]: time="2025-10-23T15:47:48+01:00" 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 23 15:47:48 volumio go-librespot[12940]: time="2025-10-23T15:47:48+01:00" 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 23 15:47:48 volumio go-librespot[12940]: time="2025-10-23T15:47:48+01:00" 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 23 15:47:48 volumio go-librespot[12940]: time="2025-10-23T15:47:48+01:00" level=info msg="zeroconf server listening on port 35265" Oct 23 15:47:48 volumio go-librespot[12940]: time="2025-10-23T15:47:48+01:00" level=debug msg="obtained new client token: AABLiF2To3kR2uJDw5z/gGKj6NDza7iosO/iADeplAhbAw7xLatmkChFsPWxIQJ+ZUskoKulL4r26qbKZ2oRHG4xXxBkrbd9KdPpa5KhEl65cEOhHklFsZr/aiiyvqmiEmJaIKuC0yAS6ch5MD9909MeIR2DZVyamOmpr8pgZyw2u7Oia5ujRY7f8Evw/rLXjqoqrJoZNZH0J8cb65vL3HqzcHLC3xBqxHIku55kWRow6Spp5NWw1Bs=" Oct 23 15:47:48 volumio go-librespot[12940]: time="2025-10-23T15:47:48+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 23 15:47:48 volumio go-librespot[12940]: time="2025-10-23T15:47:48+01:00" level=debug msg="completed keyexchange" Oct 23 15:47:48 volumio go-librespot[12940]: time="2025-10-23T15:47:48+01:00" level=debug msg="completed challenge" Oct 23 15:47:48 volumio go-librespot[12940]: time="2025-10-23T15:47:48+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 23 15:47:48 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 23 15:47:48 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 23 15:47:50 volumio volumio[12426]: info: Initializing connection to go-librespot Websocket Oct 23 15:47:50 volumio volumio[12426]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 23 15:47:51 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 256. Oct 23 15:47:51 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 23 15:47:51 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 23 15:47:51 volumio go-librespot[12947]: go-librespot daemon starting... Oct 23 15:47:51 volumio go-librespot[12948]: time="2025-10-23T15:47:51+01:00" level=info msg="running go-librespot 0.4.0" Oct 23 15:47:51 volumio go-librespot[12948]: time="2025-10-23T15:47:51+01:00" level=debug msg="app state loaded" Oct 23 15:47:51 volumio go-librespot[12948]: time="2025-10-23T15:47:51+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 23 15:47:52 volumio go-librespot[12948]: time="2025-10-23T15:47:52+01:00" 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 23 15:47:52 volumio go-librespot[12948]: time="2025-10-23T15:47:52+01:00" 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 23 15:47:52 volumio go-librespot[12948]: time="2025-10-23T15:47:52+01:00" 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 23 15:47:52 volumio go-librespot[12948]: time="2025-10-23T15:47:52+01:00" level=info msg="zeroconf server listening on port 46453" Oct 23 15:47:52 volumio go-librespot[12948]: time="2025-10-23T15:47:52+01:00" level=debug msg="obtained new client token: AABxVcBMvgeC8z9sXpPZKYWCwGTjoD924Eg/PL9L1PQ/vjnq+911+kG+wo/2DruEijkIuuQDnCBOH+v8y/Nhl0WvO0kSY6QusJrGPKab69/lCozjZ5Cq8A0oFqQqA4WmRQEXxHBEEkJ8oNXaCK2zB+EQeO7F+xY8TkJI3sqUKH8vrbeEhh20H5fWo2kQmpGZ8/Nh7uXFkOwdnzL0JYCbD3zgpKvtRbBTl27gldfp5DrX/puJ0g9Y" Oct 23 15:47:52 volumio go-librespot[12948]: time="2025-10-23T15:47:52+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 23 15:47:52 volumio go-librespot[12948]: time="2025-10-23T15:47:52+01:00" level=debug msg="completed keyexchange" Oct 23 15:47:52 volumio go-librespot[12948]: time="2025-10-23T15:47:52+01:00" level=debug msg="completed challenge" Oct 23 15:47:52 volumio go-librespot[12948]: time="2025-10-23T15:47:52+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 23 15:47:52 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 23 15:47:52 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 23 15:47:53 volumio volumio[12426]: info: Initializing connection to go-librespot Websocket Oct 23 15:47:53 volumio volumio[12426]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 23 15:47:55 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 257. Oct 23 15:47:55 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 23 15:47:55 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 23 15:47:55 volumio go-librespot[12970]: go-librespot daemon starting... Oct 23 15:47:55 volumio go-librespot[12971]: time="2025-10-23T15:47:55+01:00" level=info msg="running go-librespot 0.4.0" Oct 23 15:47:55 volumio go-librespot[12971]: time="2025-10-23T15:47:55+01:00" level=debug msg="app state loaded" Oct 23 15:47:55 volumio go-librespot[12971]: time="2025-10-23T15:47:55+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 23 15:47:55 volumio go-librespot[12971]: time="2025-10-23T15:47:55+01:00" 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 23 15:47:55 volumio go-librespot[12971]: time="2025-10-23T15:47:55+01:00" 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 23 15:47:55 volumio go-librespot[12971]: time="2025-10-23T15:47:55+01:00" 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 23 15:47:55 volumio go-librespot[12971]: time="2025-10-23T15:47:55+01:00" level=info msg="zeroconf server listening on port 38893" Oct 23 15:47:55 volumio go-librespot[12971]: time="2025-10-23T15:47:55+01:00" level=debug msg="obtained new client token: AADx6yPdFJXsI0IqGQXLJs8TkdB4tJaQWpN8WPS+PqCcqamtRoNS8vuo0v4l7iYbiY5NnM5AxA92FG1yT4oBaBvErnHEO7Q5SnjuQUaUTB+veaIiFmeki7jnZVohlt5EPs5VGWnS3ZOsdws9vB7/EoTYGPHiitZKkfeObRPrFmzV/raAE7/YIhWIVIjC9Ve0jTiouP0JyTR58JTYQI7LbASE8bgAmmo6YtUr114dUC1apZm3vKoABAg=" Oct 23 15:47:55 volumio go-librespot[12971]: time="2025-10-23T15:47:55+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 23 15:47:55 volumio go-librespot[12971]: time="2025-10-23T15:47:55+01:00" level=debug msg="completed keyexchange" Oct 23 15:47:55 volumio go-librespot[12971]: time="2025-10-23T15:47:55+01:00" level=debug msg="completed challenge" Oct 23 15:47:55 volumio go-librespot[12971]: time="2025-10-23T15:47:55+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 23 15:47:55 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 23 15:47:55 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 23 15:47:56 volumio volumio[12426]: info: Initializing connection to go-librespot Websocket Oct 23 15:47:56 volumio volumio[12426]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 23 15:47:58 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 258. Oct 23 15:47:58 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 23 15:47:58 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 23 15:47:58 volumio go-librespot[12979]: go-librespot daemon starting... Oct 23 15:47:58 volumio go-librespot[12980]: time="2025-10-23T15:47:58+01:00" level=info msg="running go-librespot 0.4.0" Oct 23 15:47:58 volumio go-librespot[12980]: time="2025-10-23T15:47:58+01:00" level=debug msg="app state loaded" Oct 23 15:47:58 volumio go-librespot[12980]: time="2025-10-23T15:47:58+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 23 15:47:59 volumio go-librespot[12980]: time="2025-10-23T15:47:59+01:00" 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 23 15:47:59 volumio go-librespot[12980]: time="2025-10-23T15:47:59+01:00" 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 23 15:47:59 volumio go-librespot[12980]: time="2025-10-23T15:47:59+01:00" 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 23 15:47:59 volumio go-librespot[12980]: time="2025-10-23T15:47:59+01:00" level=info msg="zeroconf server listening on port 41205" Oct 23 15:47:59 volumio go-librespot[12980]: time="2025-10-23T15:47:59+01:00" level=debug msg="obtained new client token: AABCfHVdGVNY0uurAVrNO7E61lYPI5/gsLxUhC6wRWCaNH/XIyrFfXrHGr/ZboD4PkuaAwZAaEhKr6dO/XYM1cDTGYFtIxeB+TSUfH4RehX6Ut1QTsPj7Nhv4M9j7l/Bx2GSKzlvTTjl3bIadHIDhPb2kVHkvVCEOzd5MwAsoV8XD5QfDx/MLxa2mmO9foVM+8TDMjsqBdGregJqah/aebXYzW0CSQSB41c3UsBFqV+eF09r2IxH" Oct 23 15:47:59 volumio go-librespot[12980]: time="2025-10-23T15:47:59+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 23 15:47:59 volumio go-librespot[12980]: time="2025-10-23T15:47:59+01:00" level=debug msg="completed keyexchange" Oct 23 15:47:59 volumio go-librespot[12980]: time="2025-10-23T15:47:59+01:00" level=debug msg="completed challenge" Oct 23 15:47:59 volumio go-librespot[12980]: time="2025-10-23T15:47:59+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 23 15:47:59 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 23 15:47:59 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 23 15:47:59 volumio volumio[12426]: info: Initializing connection to go-librespot Websocket Oct 23 15:47:59 volumio volumio[12426]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 23 15:48:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 259. Oct 23 15:48:02 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 23 15:48:02 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 23 15:48:02 volumio go-librespot[12990]: go-librespot daemon starting... Oct 23 15:48:02 volumio go-librespot[12991]: time="2025-10-23T15:48:02+01:00" level=info msg="running go-librespot 0.4.0" Oct 23 15:48:02 volumio go-librespot[12991]: time="2025-10-23T15:48:02+01:00" level=debug msg="app state loaded" Oct 23 15:48:02 volumio go-librespot[12991]: time="2025-10-23T15:48:02+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 23 15:48:02 volumio volumio[12426]: info: Initializing connection to go-librespot Websocket Oct 23 15:48:02 volumio go-librespot[12991]: time="2025-10-23T15:48:02+01:00" level=debug msg="new websocket client" Oct 23 15:48:02 volumio volumio[12426]: info: Connection to go-librespot Websocket established Oct 23 15:48:02 volumio go-librespot[12991]: time="2025-10-23T15:48:02+01:00" 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 23 15:48:02 volumio go-librespot[12991]: time="2025-10-23T15:48:02+01:00" 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 23 15:48:02 volumio go-librespot[12991]: time="2025-10-23T15:48:02+01:00" 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 23 15:48:02 volumio go-librespot[12991]: time="2025-10-23T15:48:02+01:00" level=info msg="zeroconf server listening on port 43233" Oct 23 15:48:02 volumio go-librespot[12991]: time="2025-10-23T15:48:02+01:00" level=debug msg="obtained new client token: AADBi1x33e+iEkq5R3MHO0s/220vP7I48mvJk+3bKVS107VgPC4xRDN75SbDsEcTrihC7F4TWSF1ogCrgEzPnNA2TwFEMSTV2Oj8Zo66fuOQKlsdWaxU8km6aF9uXbS5GFcJ4nGAtstn/XoGro9a/A0R9UAyuzbhnDw8cVbg6uXj4cxiO29mZBO34FCxEoLSgLZjcd+gHA0D65NnrEZJphDJuyTNrHikK2Es+fyL/w8QybynEYOwDZY=" Oct 23 15:48:02 volumio go-librespot[12991]: time="2025-10-23T15:48:02+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 23 15:48:02 volumio go-librespot[12991]: time="2025-10-23T15:48:02+01:00" level=debug msg="completed keyexchange" Oct 23 15:48:02 volumio go-librespot[12991]: time="2025-10-23T15:48:02+01:00" level=debug msg="completed challenge" Oct 23 15:48:02 volumio go-librespot[12991]: time="2025-10-23T15:48:02+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 23 15:48:02 volumio volumio[12426]: info: Connection to go-librespot Websocket closed Oct 23 15:48:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 23 15:48:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 23 15:48:05 volumio volumio[12426]: info: Getting Spotify volume Oct 23 15:48:05 volumio volumio[12426]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 23 15:48:05 volumio volumio[12426]: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 23 15:48:05 volumio volumio[12426]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Oct 23 15:48:05 volumio volumio[12426]: errno: -111, Oct 23 15:48:05 volumio volumio[12426]: code: 'ECONNREFUSED', Oct 23 15:48:05 volumio volumio[12426]: syscall: 'connect', Oct 23 15:48:05 volumio volumio[12426]: address: '127.0.0.1', Oct 23 15:48:05 volumio volumio[12426]: port: 9879, Oct 23 15:48:05 volumio volumio[12426]: response: undefined Oct 23 15:48:05 volumio volumio[12426]: } Oct 23 15:48:05 volumio volumio[12426]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 23 15:48:05 volumio sudo[13027]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-10-23 15:47' Oct 23 15:48:05 volumio sudo[13027]: 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"