Oct 28 13:29:00 volumio volumio[10598]: info: Completed starting Core Plugins Oct 28 13:29:00 volumio volumio[10598]: info: ------------------------------------------- Oct 28 13:29:00 volumio volumio[10598]: info: ----- MyVolumio plugins startup ---- Oct 28 13:29:00 volumio volumio[10598]: info: ------------------------------------------- Oct 28 13:29:00 volumio volumio[10598]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 28 13:29:01 volumio volumio-remote-updater[874]: [2025-10-28 13:29:01] [connect] Successful connection Oct 28 13:29:01 volumio volumio-remote-updater[874]: [2025-10-28 13:29:01] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1761658141 101 Oct 28 13:29:01 volumio volumio[10598]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 2 Oct 28 13:29:02 volumio volumio[10598]: info: Cannot mount NAS NAS at system boot, trial number 2 ,retrying in 5 seconds Oct 28 13:29:02 volumio volumio[10598]: info: go-librespot daemon successfully initialized Oct 28 13:29:02 volumio sudo[10857]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Oct 28 13:29:02 volumio sudo[10857]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 28 13:29:02 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Oct 28 13:29:02 volumio sudo[10860]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Oct 28 13:29:02 volumio sudo[10860]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 28 13:29:02 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Oct 28 13:29:02 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Oct 28 13:29:02 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Oct 28 13:29:02 volumio mpd_monitor.sh[10862]: MPD Monitor Service: Starting MPD Monitor Service Oct 28 13:29:02 volumio sudo[10857]: pam_unix(sudo:session): session closed for user root Oct 28 13:29:02 volumio volumio[10598]: info: Successfully started MPD Monitor Oct 28 13:29:02 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Oct 28 13:29:02 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Oct 28 13:29:02 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Oct 28 13:29:02 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Oct 28 13:29:02 volumio mpd_monitor.sh[10866]: MPD Monitor Service: Starting MPD Monitor Service Oct 28 13:29:02 volumio sudo[10860]: pam_unix(sudo:session): session closed for user root Oct 28 13:29:02 volumio volumio[10598]: info: Successfully started MPD Monitor Oct 28 13:29:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 120. Oct 28 13:29:02 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 13:29:02 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 13:29:02 volumio go-librespot[10870]: go-librespot daemon starting... Oct 28 13:29:02 volumio go-librespot[10871]: time="2025-10-28T13:29:02Z" level=info msg="running go-librespot 0.4.0" Oct 28 13:29:02 volumio go-librespot[10871]: time="2025-10-28T13:29:02Z" level=debug msg="app state loaded" Oct 28 13:29:02 volumio go-librespot[10871]: time="2025-10-28T13:29:02Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 13:29:02 volumio go-librespot[10871]: time="2025-10-28T13:29:02Z" 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 28 13:29:02 volumio go-librespot[10871]: time="2025-10-28T13:29:02Z" 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 28 13:29:02 volumio go-librespot[10871]: time="2025-10-28T13:29:02Z" 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 28 13:29:02 volumio go-librespot[10871]: time="2025-10-28T13:29:02Z" level=info msg="zeroconf server listening on port 44247" Oct 28 13:29:02 volumio go-librespot[10871]: time="2025-10-28T13:29:02Z" level=debug msg="obtained new client token: AADbzAUOw1CHXxTOAVQqa4Ude2crRjnQMzhcY/hkZO9jb1lRhpVKB48cglf4tgGXw7DZHJkkQEO60JVhBy2svuyncUC2FZKqda2PHUPwbn8RMeXKleO5lI5d5mXv9O78wN69GmxQO+7oeSoEGkANJBEE/vJ95uxSKE9GqfFjLH0SeXDRepU++uUHFMMnOUpBGYdOhMuFAJHw22ODx+hKd1hM/lR9bSn58tnJF3mIE3ckNydLxoCoJbI=" Oct 28 13:29:02 volumio go-librespot[10871]: time="2025-10-28T13:29:02Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 13:29:03 volumio go-librespot[10871]: time="2025-10-28T13:29:03Z" level=debug msg="completed keyexchange" Oct 28 13:29:03 volumio go-librespot[10871]: time="2025-10-28T13:29:03Z" level=debug msg="completed challenge" Oct 28 13:29:03 volumio go-librespot[10871]: time="2025-10-28T13:29:03Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 13:29:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 13:29:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 13:29:04 volumio volumio[10598]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Oct 28 13:29:05 volumio volumio[10598]: info: Initializing connection to go-librespot Websocket Oct 28 13:29:05 volumio volumio[10598]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 13:29:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 121. Oct 28 13:29:06 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 13:29:06 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 13:29:06 volumio go-librespot[10879]: go-librespot daemon starting... Oct 28 13:29:06 volumio go-librespot[10880]: time="2025-10-28T13:29:06Z" level=info msg="running go-librespot 0.4.0" Oct 28 13:29:06 volumio go-librespot[10880]: time="2025-10-28T13:29:06Z" level=debug msg="app state loaded" Oct 28 13:29:06 volumio go-librespot[10880]: time="2025-10-28T13:29:06Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 13:29:06 volumio go-librespot[10880]: time="2025-10-28T13:29:06Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 28 13:29:06 volumio go-librespot[10880]: time="2025-10-28T13:29:06Z" 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 28 13:29:06 volumio go-librespot[10880]: time="2025-10-28T13:29:06Z" 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 28 13:29:06 volumio go-librespot[10880]: time="2025-10-28T13:29:06Z" level=info msg="zeroconf server listening on port 37823" Oct 28 13:29:06 volumio go-librespot[10880]: time="2025-10-28T13:29:06Z" level=debug msg="obtained new client token: AABoy3tsno/hof/T/KwMhfQt4fm3M6Apu3sv0HZzkb3/tvEiPtx3z+uaO311CXi/YbtRpKgOTi2HQ5ItYS5Got/+x3QGVj4XuBQ0aczqggleB0GmsmugEvZeQcJGzQouG87PijVphKQ0x93Mw8/6c5Sbcx83gJlGmKUX3iOacKkE7YTAN9HlZbfurVS3mgxyrGXTHnQmyxFOny6Vm+HU7J1P9Kf/ZbS1PZAb1++ZmmwH2MeHPNYkgeg=" Oct 28 13:29:06 volumio go-librespot[10880]: time="2025-10-28T13:29:06Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 13:29:06 volumio go-librespot[10880]: time="2025-10-28T13:29:06Z" level=debug msg="completed keyexchange" Oct 28 13:29:06 volumio go-librespot[10880]: time="2025-10-28T13:29:06Z" level=debug msg="completed challenge" Oct 28 13:29:06 volumio go-librespot[10880]: time="2025-10-28T13:29:06Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 13:29:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 13:29:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 13:29:07 volumio volumio[10598]: info: Cannot mount NAS NAS at system boot, trial number 3 ,retrying in 5 seconds Oct 28 13:29:07 volumio sudo[10903]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 28 13:29:07 volumio sudo[10903]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 28 13:29:07 volumio sudo[10903]: pam_unix(sudo:session): session closed for user root Oct 28 13:29:07 volumio sudo[10905]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 28 13:29:07 volumio sudo[10905]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 28 13:29:07 volumio sudo[10908]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 28 13:29:07 volumio sudo[10908]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 28 13:29:07 volumio sudo[10905]: pam_unix(sudo:session): session closed for user root Oct 28 13:29:07 volumio sudo[10908]: pam_unix(sudo:session): session closed for user root Oct 28 13:29:07 volumio volumio[10598]: info: Upmpdcli Daemon Started Oct 28 13:29:08 volumio volumio[10598]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Oct 28 13:29:08 volumio volumio[10598]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Oct 28 13:29:08 volumio volumio[10598]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Oct 28 13:29:08 volumio volumio[10598]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Oct 28 13:29:08 volumio volumio[10598]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Oct 28 13:29:08 volumio volumio[10598]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Oct 28 13:29:08 volumio volumio[10598]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Oct 28 13:29:08 volumio volumio[10598]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Oct 28 13:29:08 volumio volumio[10598]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Oct 28 13:29:08 volumio volumio[10598]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Oct 28 13:29:08 volumio volumio[10598]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Oct 28 13:29:08 volumio volumio[10598]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Oct 28 13:29:08 volumio volumio[10598]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Oct 28 13:29:08 volumio volumio[10598]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Oct 28 13:29:08 volumio volumio[10598]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Oct 28 13:29:08 volumio volumio[10598]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Oct 28 13:29:08 volumio volumio[10598]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Oct 28 13:29:08 volumio volumio[10598]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Oct 28 13:29:08 volumio volumio[10598]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Oct 28 13:29:08 volumio volumio[10598]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Oct 28 13:29:08 volumio volumio[10598]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Oct 28 13:29:08 volumio volumio[10598]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Oct 28 13:29:08 volumio volumio[10598]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Oct 28 13:29:08 volumio volumio[10598]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Oct 28 13:29:08 volumio volumio[10598]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Oct 28 13:29:08 volumio volumio[10598]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Oct 28 13:29:08 volumio volumio[10598]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Oct 28 13:29:08 volumio volumio[10598]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Oct 28 13:29:08 volumio volumio[10598]: info: Adding plugin bluetooth to MyMusic Plugins Oct 28 13:29:08 volumio volumio[10598]: info: Adding plugin multiroom to MyMusic Plugins Oct 28 13:29:08 volumio volumio[10598]: info: Adding plugin metavolumio to MyMusic Plugins Oct 28 13:29:08 volumio volumio[10598]: info: Adding plugin cd_controller to MyMusic Plugins Oct 28 13:29:08 volumio volumio[10598]: info: Adding plugin qobuzconnect to MyMusic Plugins Oct 28 13:29:08 volumio volumio[10598]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 28 13:29:08 volumio volumio[10598]: info: Adding plugin tidalconnect to MyMusic Plugins Oct 28 13:29:08 volumio volumio[10598]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 28 13:29:08 volumio volumio[10598]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 28 13:29:08 volumio volumio[10598]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 28 13:29:08 volumio volumio[10598]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 28 13:29:08 volumio volumio[10598]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 28 13:29:08 volumio volumio[10598]: info: Starting MyVolumio Remote Streaming Endpoints Oct 28 13:29:08 volumio volumio[10598]: info: MyVolumio login type: Token Oct 28 13:29:08 volumio volumio[10598]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 28 13:29:08 volumio volumio[10598]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Oct 28 13:29:08 volumio volumio[10598]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 28 13:29:08 volumio volumio[10598]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 28 13:29:08 volumio volumio[10598]: info: Streaming services startup Oct 28 13:29:08 volumio volumio[10598]: info: Starting Streaming Daemon Oct 28 13:29:08 volumio sudo[10912]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 28 13:29:08 volumio sudo[10912]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 28 13:29:08 volumio volumio[10598]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Oct 28 13:29:08 volumio sudo[10912]: pam_unix(sudo:session): session closed for user root Oct 28 13:29:08 volumio volumio[10598]: info: Initializing connection to go-librespot Websocket Oct 28 13:29:08 volumio volumio[10598]: error: Cannot start Volumio Streaming Daemon Oct 28 13:29:08 volumio volumio[10598]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 28 13:29:08 volumio volumio[10598]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 28 13:29:08 volumio volumio[10598]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 13:29:09 volumio volumio[10598]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Oct 28 13:29:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 122. Oct 28 13:29:09 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 13:29:09 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 13:29:09 volumio go-librespot[10918]: go-librespot daemon starting... Oct 28 13:29:09 volumio go-librespot[10919]: time="2025-10-28T13:29:09Z" level=info msg="running go-librespot 0.4.0" Oct 28 13:29:09 volumio go-librespot[10919]: time="2025-10-28T13:29:09Z" level=debug msg="app state loaded" Oct 28 13:29:09 volumio go-librespot[10919]: time="2025-10-28T13:29:09Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 13:29:09 volumio volumio[10598]: info: MyVolumio token set successfully Oct 28 13:29:09 volumio volumio[10598]: info: MYVOLUMIO: Adding device Oct 28 13:29:09 volumio volumio[10598]: info: MYVOLUMIO: Evaluating Server Oct 28 13:29:09 volumio go-librespot[10919]: time="2025-10-28T13:29:09Z" 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 28 13:29:09 volumio go-librespot[10919]: time="2025-10-28T13:29:09Z" 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 28 13:29:09 volumio go-librespot[10919]: time="2025-10-28T13:29:09Z" 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 28 13:29:09 volumio go-librespot[10919]: time="2025-10-28T13:29:09Z" level=info msg="zeroconf server listening on port 37037" Oct 28 13:29:09 volumio go-librespot[10919]: time="2025-10-28T13:29:09Z" level=debug msg="obtained new client token: AACRzmqVZpujx5IGwBnCpUun3BK90XEtjCxMnu6EuSAWqgKt5TxLaplIZGYXjcuw0vIVmxYSmOJX8oHc7Kf68fRFbZp66DzcNLbNu44d/QSowW4d1WeQ07jmvlbnxv1bjf8u90/0/TSwsv4JmCjJKqrZNzuB0gv/6m2bbZSPaTD5JkLHEntEx/pAe90DnPO52AGH6AK0hNgad+Qx9RFZF5wQApIzS8EHgYBH7Is6J1tThth82axJWMo=" Oct 28 13:29:09 volumio volumio[10598]: info: MyVolumio status changed Oct 28 13:29:09 volumio volumio[10598]: info: Streaming services startup Oct 28 13:29:09 volumio volumio[10598]: info: Starting Streaming Daemon Oct 28 13:29:09 volumio volumio[10598]: info: Removing browser output: myVolumio user plan is not superstar Oct 28 13:29:09 volumio volumio[10598]: info: Removing audio output: Oct 28 13:29:09 volumio volumio[10598]: info: Stoppping Tunnel 1 Oct 28 13:29:09 volumio sudo[10944]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 28 13:29:09 volumio sudo[10944]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 28 13:29:09 volumio sudo[10946]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Oct 28 13:29:09 volumio sudo[10946]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 28 13:29:09 volumio sudo[10944]: pam_unix(sudo:session): session closed for user root Oct 28 13:29:09 volumio go-librespot[10919]: time="2025-10-28T13:29:09Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 13:29:09 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 28 13:29:09 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 28 13:29:09 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 28 13:29:09 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 28 13:29:10 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 28 13:29:10 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 28 13:29:10 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 28 13:29:10 volumio volumio[10598]: error: Cannot start Volumio Streaming Daemon Oct 28 13:29:10 volumio volumio[10598]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 28 13:29:10 volumio volumio[10598]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 28 13:29:10 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 28 13:29:10 volumio sudo[10946]: pam_unix(sudo:session): session closed for user root Oct 28 13:29:10 volumio volumio[10598]: info: Remote SSH Stopped Oct 28 13:29:10 volumio go-librespot[10919]: time="2025-10-28T13:29:10Z" level=debug msg="completed keyexchange" Oct 28 13:29:10 volumio go-librespot[10919]: time="2025-10-28T13:29:10Z" level=debug msg="completed challenge" Oct 28 13:29:10 volumio go-librespot[10919]: time="2025-10-28T13:29:10Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 13:29:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 13:29:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 13:29:10 volumio volumio[10598]: info: Setting Geolocation for MyVolumio to eu3 Oct 28 13:29:10 volumio volumio[10598]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 28 13:29:10 volumio volumio[10598]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 28 13:29:10 volumio volumio[10598]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 28 13:29:10 volumio volumio[10598]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Oct 28 13:29:11 volumio volumio[10598]: info: Updating MyVolumio device info Oct 28 13:29:11 volumio volumio[10598]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 28 13:29:11 volumio volumio[10598]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 28 13:29:11 volumio volumio[10598]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 28 13:29:11 volumio volumio[10598]: info: Initializing connection to go-librespot Websocket Oct 28 13:29:11 volumio volumio[10598]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 13:29:11 volumio volumio[10598]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Oct 28 13:29:12 volumio volumio[10598]: info: Cannot mount NAS NAS at system boot, trial number 4 ,retrying in 5 seconds Oct 28 13:29:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 123. Oct 28 13:29:13 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 13:29:13 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 13:29:13 volumio go-librespot[10949]: go-librespot daemon starting... Oct 28 13:29:13 volumio go-librespot[10950]: time="2025-10-28T13:29:13Z" level=info msg="running go-librespot 0.4.0" Oct 28 13:29:13 volumio go-librespot[10950]: time="2025-10-28T13:29:13Z" level=debug msg="app state loaded" Oct 28 13:29:13 volumio go-librespot[10950]: time="2025-10-28T13:29:13Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 13:29:13 volumio go-librespot[10950]: time="2025-10-28T13:29:13Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 28 13:29:13 volumio go-librespot[10950]: time="2025-10-28T13:29:13Z" 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 28 13:29:13 volumio go-librespot[10950]: time="2025-10-28T13:29:13Z" 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 28 13:29:13 volumio go-librespot[10950]: time="2025-10-28T13:29:13Z" level=info msg="zeroconf server listening on port 34745" Oct 28 13:29:13 volumio go-librespot[10950]: time="2025-10-28T13:29:13Z" level=debug msg="obtained new client token: AADL7ccsUlPPkj2wCgjjRTSL7RK06MnVjvTZnrOLLy9DVsc7JgeYkqyaWgKYUxTo4YXECmcZD1KlKEBxL1RD04YKVRTpAbL6Kqihgvwd/vg8LhLetzlonmzFCiImZEdBhnvdni/B53OuX0P47D0qDtuWjNZOt99PbRH3lzQN7tGNqJcBBjhBrAoN6M5NA4UUGNtQzhMmWMYGFJnu0G/y1Ocp1C0viJyweGIXYhusvZ9sO5h0bx3D6nU=" Oct 28 13:29:13 volumio go-librespot[10950]: time="2025-10-28T13:29:13Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 13:29:13 volumio go-librespot[10950]: time="2025-10-28T13:29:13Z" level=debug msg="completed keyexchange" Oct 28 13:29:13 volumio go-librespot[10950]: time="2025-10-28T13:29:13Z" level=debug msg="completed challenge" Oct 28 13:29:13 volumio go-librespot[10950]: time="2025-10-28T13:29:13Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 13:29:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 13:29:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 13:29:14 volumio volumio[10598]: info: MYVOLUMIO: Adding device Oct 28 13:29:14 volumio volumio[10598]: info: MYVOLUMIO: Evaluating Server Oct 28 13:29:14 volumio volumio[10598]: info: Initializing connection to go-librespot Websocket Oct 28 13:29:14 volumio volumio[10598]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 13:29:14 volumio volumio[10598]: info: Setting Geolocation for MyVolumio to eu3 Oct 28 13:29:14 volumio volumio[10598]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 28 13:29:14 volumio volumio[10598]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 28 13:29:14 volumio volumio[10598]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 28 13:29:15 volumio volumio[10598]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Oct 28 13:29:15 volumio volumio[10598]: info: Updating MyVolumio device info Oct 28 13:29:15 volumio volumio[10598]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 28 13:29:15 volumio volumio[10598]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 28 13:29:15 volumio volumio[10598]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 28 13:29:16 volumio volumio[10598]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Oct 28 13:29:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 124. Oct 28 13:29:16 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 13:29:16 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 13:29:16 volumio go-librespot[10972]: go-librespot daemon starting... Oct 28 13:29:16 volumio go-librespot[10973]: time="2025-10-28T13:29:16Z" level=info msg="running go-librespot 0.4.0" Oct 28 13:29:16 volumio go-librespot[10973]: time="2025-10-28T13:29:16Z" level=debug msg="app state loaded" Oct 28 13:29:16 volumio go-librespot[10973]: time="2025-10-28T13:29:16Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 13:29:16 volumio go-librespot[10973]: time="2025-10-28T13:29:16Z" 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 28 13:29:16 volumio go-librespot[10973]: time="2025-10-28T13:29:16Z" 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 28 13:29:16 volumio go-librespot[10973]: time="2025-10-28T13:29:16Z" 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 28 13:29:16 volumio go-librespot[10973]: time="2025-10-28T13:29:16Z" level=info msg="zeroconf server listening on port 41309" Oct 28 13:29:16 volumio go-librespot[10973]: time="2025-10-28T13:29:16Z" level=debug msg="obtained new client token: AAD+YGzBz9MGq1C0xoImKq22IlMkPDxIbj+ILQsENYun87rVfGyDeNWoKip71TwBPws8yTz0eZQx/aWxEEa0Eoo3t9EYnTZcpfuTDDgJLR9M5oypk9C/7ve0hUA3+LMWHrf+uE373bSvAVPq9e2Y7hL1zpAuz++hrOwwitVscjCPRxGINV5PMboEl9JJiuCnkkgk7K5HfXExCZY9dFnlC9hHE3EBq/2BzpOzZdTyWeBQG+ib2O5vYy0=" Oct 28 13:29:16 volumio go-librespot[10973]: time="2025-10-28T13:29:16Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 13:29:16 volumio go-librespot[10973]: time="2025-10-28T13:29:16Z" level=debug msg="completed keyexchange" Oct 28 13:29:16 volumio go-librespot[10973]: time="2025-10-28T13:29:16Z" level=debug msg="completed challenge" Oct 28 13:29:17 volumio go-librespot[10973]: time="2025-10-28T13:29:17Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 13:29:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 13:29:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 13:29:17 volumio volumio[10598]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Oct 28 13:29:17 volumio volumio[10598]: info: Initializing connection to go-librespot Websocket Oct 28 13:29:17 volumio volumio[10598]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 13:29:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 125. Oct 28 13:29:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 13:29:20 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 13:29:20 volumio go-librespot[10994]: go-librespot daemon starting... Oct 28 13:29:20 volumio go-librespot[10995]: time="2025-10-28T13:29:20Z" level=info msg="running go-librespot 0.4.0" Oct 28 13:29:20 volumio go-librespot[10995]: time="2025-10-28T13:29:20Z" level=debug msg="app state loaded" Oct 28 13:29:20 volumio go-librespot[10995]: time="2025-10-28T13:29:20Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 13:29:20 volumio go-librespot[10995]: time="2025-10-28T13:29:20Z" 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 28 13:29:20 volumio go-librespot[10995]: time="2025-10-28T13:29:20Z" 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 28 13:29:20 volumio go-librespot[10995]: time="2025-10-28T13:29:20Z" 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 28 13:29:20 volumio go-librespot[10995]: time="2025-10-28T13:29:20Z" level=info msg="zeroconf server listening on port 39841" Oct 28 13:29:20 volumio go-librespot[10995]: time="2025-10-28T13:29:20Z" level=debug msg="obtained new client token: AACEKLjDx9QanxzezvtOhW6Tuq/Pb/grlb9QaisfUnJhL5M89V45KQMx5bLavTHf33FFZH4diM3yQsI1nl/vJ90ijOt2EdhbopeWN6T4sEV+CXRhNARr5tqdlDTy6rUWeOCdiAc+tzED5tSFMwZTa8/tYaoaS+l801JklHflqx/aVhjvI7lKDk1qxAfkTTQz9ys40tbTRcA+/DmWHljkMnjI+1qJznMb8039xiftZ9QgVCn4sLOKn9o=" Oct 28 13:29:20 volumio go-librespot[10995]: time="2025-10-28T13:29:20Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 13:29:20 volumio go-librespot[10995]: time="2025-10-28T13:29:20Z" level=debug msg="completed keyexchange" Oct 28 13:29:20 volumio go-librespot[10995]: time="2025-10-28T13:29:20Z" level=debug msg="completed challenge" Oct 28 13:29:20 volumio go-librespot[10995]: time="2025-10-28T13:29:20Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 13:29:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 13:29:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 13:29:20 volumio volumio[10598]: info: Initializing connection to go-librespot Websocket Oct 28 13:29:20 volumio volumio[10598]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 13:29:23 volumio volumio[10598]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 28 13:29:23 volumio volumio[10598]: 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 28 13:29:23 volumio volumio[10598]: info: CoreCommandRouter::volumioGetState Oct 28 13:29:23 volumio volumio[10598]: info: CorePlayQueue::getTrack 0 Oct 28 13:29:23 volumio volumio[10598]: info: Initializing connection to go-librespot Websocket Oct 28 13:29:23 volumio volumio[10598]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 13:29:23 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 126. Oct 28 13:29:23 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 13:29:23 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 13:29:23 volumio go-librespot[11005]: go-librespot daemon starting... Oct 28 13:29:23 volumio go-librespot[11006]: time="2025-10-28T13:29:23Z" level=info msg="running go-librespot 0.4.0" Oct 28 13:29:23 volumio go-librespot[11006]: time="2025-10-28T13:29:23Z" level=debug msg="app state loaded" Oct 28 13:29:23 volumio go-librespot[11006]: time="2025-10-28T13:29:23Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 13:29:23 volumio go-librespot[11006]: time="2025-10-28T13:29:23Z" 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 28 13:29:23 volumio go-librespot[11006]: time="2025-10-28T13:29:23Z" 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 28 13:29:23 volumio go-librespot[11006]: time="2025-10-28T13:29:23Z" 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 28 13:29:23 volumio go-librespot[11006]: time="2025-10-28T13:29:23Z" level=info msg="zeroconf server listening on port 44359" Oct 28 13:29:23 volumio go-librespot[11006]: time="2025-10-28T13:29:23Z" level=debug msg="obtained new client token: AADz+g17zJ/z4N+Jne7HtfgQZxAbiiPVysUZOiGXkWt3nVECCPSngppRDbklftuN8vh/lYNKHwKQB3NSK9BdeS/YrJx8ry7Kkg0kTg0W0nTqRYmpOJtminF1elAcUGYnujDVQHWzYGCAugKSIgNik0DTNCRDn7WbYwu6bkdxj4ekI7ECQ0PNcfArrjfsPYVSuKOrF1RNUF/VVgyFKwfJK1X47PFJ6P8Uww/Rd50HEzjTHMr4t7g5mIc=" Oct 28 13:29:23 volumio go-librespot[11006]: time="2025-10-28T13:29:23Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 13:29:24 volumio go-librespot[11006]: time="2025-10-28T13:29:24Z" level=debug msg="completed keyexchange" Oct 28 13:29:24 volumio go-librespot[11006]: time="2025-10-28T13:29:24Z" level=debug msg="completed challenge" Oct 28 13:29:24 volumio go-librespot[11006]: time="2025-10-28T13:29:24Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 13:29:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 13:29:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 13:29:26 volumio volumio[10598]: info: Initializing connection to go-librespot Websocket Oct 28 13:29:26 volumio volumio[10598]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 13:29:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 127. Oct 28 13:29:27 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 13:29:27 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 13:29:27 volumio go-librespot[11027]: go-librespot daemon starting... Oct 28 13:29:27 volumio go-librespot[11028]: time="2025-10-28T13:29:27Z" level=info msg="running go-librespot 0.4.0" Oct 28 13:29:27 volumio go-librespot[11028]: time="2025-10-28T13:29:27Z" level=debug msg="app state loaded" Oct 28 13:29:27 volumio go-librespot[11028]: time="2025-10-28T13:29:27Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 13:29:27 volumio go-librespot[11028]: time="2025-10-28T13:29:27Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 28 13:29:27 volumio go-librespot[11028]: time="2025-10-28T13:29:27Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 28 13:29:27 volumio go-librespot[11028]: time="2025-10-28T13:29:27Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 28 13:29:27 volumio go-librespot[11028]: time="2025-10-28T13:29:27Z" level=info msg="zeroconf server listening on port 33741" Oct 28 13:29:27 volumio go-librespot[11028]: time="2025-10-28T13:29:27Z" level=debug msg="obtained new client token: AACRAteUF11tMOd7gNWAOWdIsKlFmoWaHDWSdmrUuf1kwDOarf8R45LcXX8uPPNmZnDhWAx4/z89NXzVLO7CIp3/djONHDU9LKnjP3LmkYcoXyK/k+FYoEtKhkz5zacnMWhPGUBUW0GfjwSJ09F35r+isaK8ach7L4UwvzYJu5obTmPDouMHCPfl/uwtkps25fSvJQoeN+qeNFxSvX4BD2/mCKvQtNU3li/+yPll/OJ7obFkYvcQ8mY=" Oct 28 13:29:27 volumio go-librespot[11028]: time="2025-10-28T13:29:27Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 13:29:27 volumio go-librespot[11028]: time="2025-10-28T13:29:27Z" level=debug msg="completed keyexchange" Oct 28 13:29:27 volumio go-librespot[11028]: time="2025-10-28T13:29:27Z" level=debug msg="completed challenge" Oct 28 13:29:27 volumio go-librespot[11028]: time="2025-10-28T13:29:27Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 13:29:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 13:29:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 13:29:28 volumio volumio[10598]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 13:29:28 volumio volumio[10598]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 13:29:28 volumio volumio[10598]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 28 13:29:28 volumio volumio[10598]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 28 13:29:28 volumio volumio[10598]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 28 13:29:28 volumio volumio[10598]: info: CoreCommandRouter::volumioGetBrowseSources Oct 28 13:29:28 volumio volumio[10598]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 28 13:29:28 volumio volumio[10598]: error: MyVolumio Plugin failed to authenticate in a timely fashion Oct 28 13:29:28 volumio volumio[10598]: info: Completed starting MyVolumio Plugin Oct 28 13:29:28 volumio volumio[10598]: [Metrics] CommandRouter: 32s 79.76ms Oct 28 13:29:28 volumio volumio[10598]: info: CoreCommandRouter::volumiosetStartupVolume Oct 28 13:29:28 volumio volumio[10598]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 13:29:28 volumio volumio[10598]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 28 13:29:28 volumio volumio[10598]: info: CoreCommandRouter::Close All Modals sent Oct 28 13:29:28 volumio volumio[10598]: info: CoreCommandRouter::Close All Modals sent Oct 28 13:29:29 volumio volumio[10598]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 28 13:29:29 volumio volumio[10598]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 28 13:29:29 volumio volumio[10598]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 28 13:29:29 volumio volumio-remote-updater[874]: Test mode disabled Oct 28 13:29:29 volumio volumio-remote-updater[874]: Alpha mode disabled Oct 28 13:29:29 volumio volumio-remote-updater[874]: Alpha legacy test mode disabled Oct 28 13:29:29 volumio volumio[10598]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Oct 28 13:29:29 volumio volumio[10598]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 28 13:29:29 volumio volumio[10598]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 28 13:29:29 volumio volumio[10598]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 28 13:29:29 volumio volumio[10598]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 28 13:29:29 volumio volumio[10598]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 28 13:29:29 volumio volumio[10598]: info: Initializing connection to go-librespot Websocket Oct 28 13:29:29 volumio volumio[10598]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 13:29:30 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 128. Oct 28 13:29:30 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 13:29:30 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 13:29:30 volumio go-librespot[11045]: go-librespot daemon starting... Oct 28 13:29:30 volumio go-librespot[11046]: time="2025-10-28T13:29:30Z" level=info msg="running go-librespot 0.4.0" Oct 28 13:29:30 volumio go-librespot[11046]: time="2025-10-28T13:29:30Z" level=debug msg="app state loaded" Oct 28 13:29:30 volumio go-librespot[11046]: time="2025-10-28T13:29:30Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 13:29:30 volumio go-librespot[11046]: time="2025-10-28T13:29:30Z" 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 28 13:29:30 volumio go-librespot[11046]: time="2025-10-28T13:29:30Z" 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 28 13:29:30 volumio go-librespot[11046]: time="2025-10-28T13:29:30Z" 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 28 13:29:30 volumio go-librespot[11046]: time="2025-10-28T13:29:30Z" level=info msg="zeroconf server listening on port 35573" Oct 28 13:29:30 volumio go-librespot[11046]: time="2025-10-28T13:29:30Z" level=debug msg="obtained new client token: AADgW5FrCMD3IzKYH4kdacXqSoOtVlqX1RSFYt0L60i661cRdW/U7EFr5wDz9OHoLVhA2JwucYDd3AswEiJWLpBZPFoBBaWqWsnthYXWbpKDRtQh64gA6d1z81S0X2xqOCdcilFnEtReYQ1NDx+MlS6eGmZJvwVNgoahKZRmd38T+hRM/OqYHvGJP2eugmqnve+095aKdyBUTL/oQt0sWEuNEtmlR3hfwY9fepl8kBnuGxia+zz+jJw=" Oct 28 13:29:30 volumio go-librespot[11046]: time="2025-10-28T13:29:30Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 13:29:31 volumio go-librespot[11046]: time="2025-10-28T13:29:31Z" level=debug msg="completed keyexchange" Oct 28 13:29:31 volumio go-librespot[11046]: time="2025-10-28T13:29:31Z" level=debug msg="completed challenge" Oct 28 13:29:31 volumio go-librespot[11046]: time="2025-10-28T13:29:31Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 13:29:31 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 13:29:31 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 13:29:32 volumio volumio[10598]: info: Initializing connection to go-librespot Websocket Oct 28 13:29:32 volumio volumio[10598]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 13:29:34 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 129. Oct 28 13:29:34 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 13:29:34 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 13:29:34 volumio go-librespot[11054]: go-librespot daemon starting... Oct 28 13:29:34 volumio go-librespot[11055]: time="2025-10-28T13:29:34Z" level=info msg="running go-librespot 0.4.0" Oct 28 13:29:34 volumio go-librespot[11055]: time="2025-10-28T13:29:34Z" level=debug msg="app state loaded" Oct 28 13:29:34 volumio go-librespot[11055]: time="2025-10-28T13:29:34Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 13:29:34 volumio go-librespot[11055]: time="2025-10-28T13:29:34Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 28 13:29:34 volumio go-librespot[11055]: time="2025-10-28T13:29:34Z" 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 28 13:29:34 volumio go-librespot[11055]: time="2025-10-28T13:29:34Z" 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 28 13:29:34 volumio go-librespot[11055]: time="2025-10-28T13:29:34Z" level=info msg="zeroconf server listening on port 43815" Oct 28 13:29:34 volumio go-librespot[11055]: time="2025-10-28T13:29:34Z" level=debug msg="obtained new client token: AACG4MVqg4QFMrg6NyMFvmGyalUb5bHeXEabUg171PLBWzQ5BaFA5pHdY6NM9/7RTDnMj1tbF5XxRcYIL+PuHiddIJ/bDQZlCjLdE466Bl5kwHztn2JUiqVTMwvpmznpexfwW4/jI7/f92p0zoS32it6n0wdG+uxctRozwrtE6OoN/35b51WYLAGBqB+zMsK2KeR7glIyf/9XSKSc7EpgmBsnTPB1H4JQVGLf33I7bXZfMFKB8ifKxc=" Oct 28 13:29:34 volumio go-librespot[11055]: time="2025-10-28T13:29:34Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 13:29:34 volumio go-librespot[11055]: time="2025-10-28T13:29:34Z" level=debug msg="completed keyexchange" Oct 28 13:29:34 volumio go-librespot[11055]: time="2025-10-28T13:29:34Z" level=debug msg="completed challenge" Oct 28 13:29:34 volumio go-librespot[11055]: time="2025-10-28T13:29:34Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 13:29:34 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 13:29:34 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 13:29:35 volumio volumio[10598]: info: BOOT COMPLETED Oct 28 13:29:35 volumio volumio[10598]: info: Initializing connection to go-librespot Websocket Oct 28 13:29:35 volumio volumio[10598]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 13:29:37 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 130. Oct 28 13:29:37 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 13:29:37 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 13:29:37 volumio go-librespot[11077]: go-librespot daemon starting... Oct 28 13:29:37 volumio go-librespot[11078]: time="2025-10-28T13:29:37Z" level=info msg="running go-librespot 0.4.0" Oct 28 13:29:37 volumio go-librespot[11078]: time="2025-10-28T13:29:37Z" level=debug msg="app state loaded" Oct 28 13:29:37 volumio go-librespot[11078]: time="2025-10-28T13:29:37Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 13:29:37 volumio go-librespot[11078]: time="2025-10-28T13:29:37Z" 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 28 13:29:37 volumio go-librespot[11078]: time="2025-10-28T13:29:37Z" 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 28 13:29:37 volumio go-librespot[11078]: time="2025-10-28T13:29:37Z" 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 28 13:29:37 volumio go-librespot[11078]: time="2025-10-28T13:29:37Z" level=info msg="zeroconf server listening on port 34855" Oct 28 13:29:38 volumio go-librespot[11078]: time="2025-10-28T13:29:38Z" level=debug msg="obtained new client token: AACI/jbFaJzxb1+Gki1DU9HqTxAnTHb6DrGGLuP2tTl0T4Cn1yIFHGsejgpC1D6w3Oe+wlQyOkPrLxKawHxp6/+PJtra1bANgEhzGLW5F9kXvUJ52F/JdQmF8bhBYSsz8RPwNpY3tzl//b/SpbJ6rXMsk8YsgyU3GnkQ4O058Q8oqB+AyFUCpvKfdOcw2wafQJ5H+INLlaNychGV9/reTPpMKsFhpPvhZTu17DWc/gIPPzHLyp93UfE=" Oct 28 13:29:38 volumio go-librespot[11078]: time="2025-10-28T13:29:38Z" 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 28 13:29:38 volumio go-librespot[11078]: time="2025-10-28T13:29:38Z" level=debug msg="connected to ap-gew1.spotify.com:443" Oct 28 13:29:38 volumio go-librespot[11078]: time="2025-10-28T13:29:38Z" level=debug msg="completed keyexchange" Oct 28 13:29:38 volumio go-librespot[11078]: time="2025-10-28T13:29:38Z" level=debug msg="completed challenge" Oct 28 13:29:38 volumio go-librespot[11078]: time="2025-10-28T13:29:38Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 13:29:38 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 13:29:38 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 13:29:38 volumio volumio[10598]: info: Initializing connection to go-librespot Websocket Oct 28 13:29:38 volumio volumio[10598]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 13:29:41 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 131. Oct 28 13:29:41 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 13:29:41 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 13:29:41 volumio go-librespot[11086]: go-librespot daemon starting... Oct 28 13:29:41 volumio go-librespot[11087]: time="2025-10-28T13:29:41Z" level=info msg="running go-librespot 0.4.0" Oct 28 13:29:41 volumio go-librespot[11087]: time="2025-10-28T13:29:41Z" level=debug msg="app state loaded" Oct 28 13:29:41 volumio go-librespot[11087]: time="2025-10-28T13:29:41Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 13:29:41 volumio go-librespot[11087]: time="2025-10-28T13:29:41Z" 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 28 13:29:41 volumio go-librespot[11087]: time="2025-10-28T13:29:41Z" 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 28 13:29:41 volumio go-librespot[11087]: time="2025-10-28T13:29:41Z" 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 28 13:29:41 volumio go-librespot[11087]: time="2025-10-28T13:29:41Z" level=info msg="zeroconf server listening on port 43167" Oct 28 13:29:41 volumio go-librespot[11087]: time="2025-10-28T13:29:41Z" level=debug msg="obtained new client token: AACkNF3MNr/TmADkdQYkVOLsyVhrTGkL2A7c5ZW8KO7Ey37MO/JQlEdhsuoW1kcSmb/MKxRzDBXI0zn8yQY9cM8hnfnpnxzd8ZFcPBRQecvXf7TJAoc6a6Rqu7shjcrlSnoIxcnhbAwodl1mUxKGEuWpjbx+b8POpdXioTiCT+lA6AVOBvP9zPuBKbZdnsCS8QhYhXdF/EG30pAg0Rl/gJljlCbdClvkOOMfzLDnkGCckdbNFkrUOII=" Oct 28 13:29:41 volumio go-librespot[11087]: time="2025-10-28T13:29:41Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 13:29:41 volumio go-librespot[11087]: time="2025-10-28T13:29:41Z" level=debug msg="completed keyexchange" Oct 28 13:29:41 volumio go-librespot[11087]: time="2025-10-28T13:29:41Z" level=debug msg="completed challenge" Oct 28 13:29:41 volumio go-librespot[11087]: time="2025-10-28T13:29:41Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 13:29:41 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 13:29:41 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 13:29:41 volumio volumio[10598]: info: Initializing connection to go-librespot Websocket Oct 28 13:29:41 volumio volumio[10598]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 13:29:44 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 132. Oct 28 13:29:44 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 13:29:44 volumio volumio[10598]: info: Initializing connection to go-librespot Websocket Oct 28 13:29:44 volumio volumio[10598]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 13:29:44 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 13:29:44 volumio go-librespot[11097]: go-librespot daemon starting... Oct 28 13:29:44 volumio go-librespot[11098]: time="2025-10-28T13:29:44Z" level=info msg="running go-librespot 0.4.0" Oct 28 13:29:44 volumio go-librespot[11098]: time="2025-10-28T13:29:44Z" level=debug msg="app state loaded" Oct 28 13:29:44 volumio go-librespot[11098]: time="2025-10-28T13:29:44Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 13:29:44 volumio go-librespot[11098]: time="2025-10-28T13:29:44Z" 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 28 13:29:44 volumio go-librespot[11098]: time="2025-10-28T13:29:44Z" 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 28 13:29:44 volumio go-librespot[11098]: time="2025-10-28T13:29:44Z" 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 28 13:29:44 volumio go-librespot[11098]: time="2025-10-28T13:29:44Z" level=info msg="zeroconf server listening on port 46121" Oct 28 13:29:44 volumio go-librespot[11098]: time="2025-10-28T13:29:44Z" level=debug msg="obtained new client token: AAAbM2oHOuXwH0rZmSLRyzPWHPiKM+nYktzzi1fTfXW+zYZP3oS0EpJ5AXtpBk1SdM7xpBMXcvDNoJTltjvBDMl+nT8mXb2GoXPJaFEe/jySnSG4GT3UcM4rZWQ0HeS+iI5R8TzJw6lkEQPJiluj2W0RPQFdovpTsmG5ObIkCAz8qiwFYl6YqPEFvQvApcDndJAWRZnBxJ/czIeW6tjzww3YxgVHo+++rvJdxYz2r8esMT/VKUWwH04=" Oct 28 13:29:44 volumio go-librespot[11098]: time="2025-10-28T13:29:44Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 13:29:45 volumio go-librespot[11098]: time="2025-10-28T13:29:45Z" level=debug msg="completed keyexchange" Oct 28 13:29:45 volumio go-librespot[11098]: time="2025-10-28T13:29:45Z" level=debug msg="completed challenge" Oct 28 13:29:45 volumio go-librespot[11098]: time="2025-10-28T13:29:45Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 13:29:45 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 13:29:45 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 13:29:47 volumio volumio[10598]: info: Initializing connection to go-librespot Websocket Oct 28 13:29:47 volumio volumio[10598]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 13:29:48 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 133. Oct 28 13:29:48 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 13:29:48 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 13:29:48 volumio go-librespot[11120]: go-librespot daemon starting... Oct 28 13:29:48 volumio go-librespot[11121]: time="2025-10-28T13:29:48Z" level=info msg="running go-librespot 0.4.0" Oct 28 13:29:48 volumio go-librespot[11121]: time="2025-10-28T13:29:48Z" level=debug msg="app state loaded" Oct 28 13:29:48 volumio go-librespot[11121]: time="2025-10-28T13:29:48Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 13:29:48 volumio go-librespot[11121]: time="2025-10-28T13:29:48Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 28 13:29:48 volumio go-librespot[11121]: time="2025-10-28T13:29:48Z" 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 28 13:29:48 volumio go-librespot[11121]: time="2025-10-28T13:29:48Z" 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 28 13:29:48 volumio go-librespot[11121]: time="2025-10-28T13:29:48Z" level=info msg="zeroconf server listening on port 46191" Oct 28 13:29:48 volumio go-librespot[11121]: time="2025-10-28T13:29:48Z" level=debug msg="obtained new client token: AABQcqXn+wrTka7A+h3mYdpci4VBVsIlmKWMt64uISFWe0SntzCvppbUJiR6Lp+e/ptJSOuuT+W+Uabl18nLxxK+5ayWSYbLa7LnZ64zvaRIZP7WywHYXbplBA5tqGiC0RuL+Ttg8OYtBYcFAEJTXhtnU1+H9T4+DYmZnrz1SjOPXWiNgFxzoUlBvY9078w4Ew1rmngJkzR5sd7X6B4J6X2g5PJLRnYQBh4Bxr4J5qBwDHLetKzmzIs=" Oct 28 13:29:48 volumio go-librespot[11121]: time="2025-10-28T13:29:48Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 13:29:48 volumio go-librespot[11121]: time="2025-10-28T13:29:48Z" level=debug msg="completed keyexchange" Oct 28 13:29:48 volumio go-librespot[11121]: time="2025-10-28T13:29:48Z" level=debug msg="completed challenge" Oct 28 13:29:48 volumio go-librespot[11121]: time="2025-10-28T13:29:48Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 13:29:48 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 13:29:48 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 13:29:50 volumio volumio[10598]: info: Initializing connection to go-librespot Websocket Oct 28 13:29:50 volumio volumio[10598]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 13:29:51 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 134. Oct 28 13:29:51 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 13:29:51 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 13:29:51 volumio go-librespot[11130]: go-librespot daemon starting... Oct 28 13:29:51 volumio go-librespot[11131]: time="2025-10-28T13:29:51Z" level=info msg="running go-librespot 0.4.0" Oct 28 13:29:51 volumio go-librespot[11131]: time="2025-10-28T13:29:51Z" level=debug msg="app state loaded" Oct 28 13:29:51 volumio go-librespot[11131]: time="2025-10-28T13:29:51Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 13:29:51 volumio go-librespot[11131]: time="2025-10-28T13:29:51Z" 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 28 13:29:51 volumio go-librespot[11131]: time="2025-10-28T13:29:51Z" 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 28 13:29:51 volumio go-librespot[11131]: time="2025-10-28T13:29:51Z" 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 28 13:29:51 volumio go-librespot[11131]: time="2025-10-28T13:29:51Z" level=info msg="zeroconf server listening on port 46565" Oct 28 13:29:51 volumio go-librespot[11131]: time="2025-10-28T13:29:51Z" level=debug msg="obtained new client token: AADnRlZktH8Ig0e3ARHBf8oqNUF9QVOIhA/D87DdESPfRwMpWE1Cy9kQqxZUvhz4fnYz2/bhF1UgjQZOXfxkG3iBVB7RuR4y/YExX9SxzDtcaM8syVuaeSrqrNg3+tDi22gA8/m1F9lmOpdiNmrw/rEi1/HOSm7USO+WjNDGVKpNP88h2mkvFVnPq1EoU/YxZqOimk8N0MvV7SzgXrB6GI23tyTMfrbrvYzrSw9tN4yb3uksb8yVybM=" Oct 28 13:29:51 volumio go-librespot[11131]: time="2025-10-28T13:29:51Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 13:29:52 volumio go-librespot[11131]: time="2025-10-28T13:29:52Z" level=debug msg="completed keyexchange" Oct 28 13:29:52 volumio go-librespot[11131]: time="2025-10-28T13:29:52Z" level=debug msg="completed challenge" Oct 28 13:29:52 volumio go-librespot[11131]: time="2025-10-28T13:29:52Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 13:29:52 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 13:29:52 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 13:29:53 volumio volumio[10598]: info: Initializing connection to go-librespot Websocket Oct 28 13:29:53 volumio volumio[10598]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 13:29:55 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 135. Oct 28 13:29:55 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 13:29:55 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 13:29:55 volumio go-librespot[11138]: go-librespot daemon starting... Oct 28 13:29:55 volumio go-librespot[11139]: time="2025-10-28T13:29:55Z" level=info msg="running go-librespot 0.4.0" Oct 28 13:29:55 volumio go-librespot[11139]: time="2025-10-28T13:29:55Z" level=debug msg="app state loaded" Oct 28 13:29:55 volumio go-librespot[11139]: time="2025-10-28T13:29:55Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 13:29:55 volumio go-librespot[11139]: time="2025-10-28T13:29:55Z" 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 28 13:29:55 volumio go-librespot[11139]: time="2025-10-28T13:29:55Z" 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 28 13:29:55 volumio go-librespot[11139]: time="2025-10-28T13:29:55Z" 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 28 13:29:55 volumio go-librespot[11139]: time="2025-10-28T13:29:55Z" level=info msg="zeroconf server listening on port 44921" Oct 28 13:29:55 volumio go-librespot[11139]: time="2025-10-28T13:29:55Z" level=debug msg="obtained new client token: AACBMAz9J4XmudqfowwLmsLcf4hKW+xDqIKWaiBgB+LX6TSRJ+ugJFjwyJWcmvGdWkg73FzH3TpBPlMD33Ve3ne1DAB8ah7toJb146jfC1G4qcVy+lOf2DJjRBwHE/6DEQw7TOkTUH1v4ZHtPMayvA/V9xHLeptg6/8W6bVuyVXxLKf2SFWGlImwm8Nalh3vfIe8e10WZ7jKyZ/JZzPZZ6uEKbiQ/vc6c6IhCJleQSVvsnkDtUVThEo=" Oct 28 13:29:55 volumio go-librespot[11139]: time="2025-10-28T13:29:55Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 13:29:55 volumio go-librespot[11139]: time="2025-10-28T13:29:55Z" level=debug msg="completed keyexchange" Oct 28 13:29:55 volumio go-librespot[11139]: time="2025-10-28T13:29:55Z" level=debug msg="completed challenge" Oct 28 13:29:55 volumio go-librespot[11139]: time="2025-10-28T13:29: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 28 13:29:55 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 13:29:55 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 13:29:56 volumio volumio[10598]: info: Initializing connection to go-librespot Websocket Oct 28 13:29:56 volumio volumio[10598]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 13:29:58 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 136. Oct 28 13:29:58 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 13:29:58 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 13:29:58 volumio go-librespot[11161]: go-librespot daemon starting... Oct 28 13:29:58 volumio go-librespot[11162]: time="2025-10-28T13:29:58Z" level=info msg="running go-librespot 0.4.0" Oct 28 13:29:58 volumio go-librespot[11162]: time="2025-10-28T13:29:58Z" level=debug msg="app state loaded" Oct 28 13:29:58 volumio go-librespot[11162]: time="2025-10-28T13:29:58Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 13:29:58 volumio go-librespot[11162]: time="2025-10-28T13:29: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-gae2.spotify.com:80]" Oct 28 13:29:58 volumio go-librespot[11162]: time="2025-10-28T13:29:58Z" 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 28 13:29:58 volumio go-librespot[11162]: time="2025-10-28T13:29:58Z" 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 28 13:29:58 volumio go-librespot[11162]: time="2025-10-28T13:29:58Z" level=info msg="zeroconf server listening on port 43925" Oct 28 13:29:58 volumio go-librespot[11162]: time="2025-10-28T13:29:58Z" level=debug msg="obtained new client token: AAD4UAgn3Qp6hdUfbP+SalU6hUEYjAPOSFM0FT0umXyHrxXJet4QV9Q2T6hHACVPwTaq5+0hB0YzrQaMOaSoEBx/061z3CfDUizbbFMq8l3l7bfIILh3T/h7ie4FimV6unukVEVWdrnF5MPKkYBuqaAeVkDpWs4pj6MPwIG5WilpDJka0MfNIIr1H56kTrM9g50hflFm0kb6kDL8hKezJ7RqJFdKgiLzEP2h8C9oY/nbgR3jrv81hwk=" Oct 28 13:29:59 volumio go-librespot[11162]: time="2025-10-28T13:29:59Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 13:29:59 volumio go-librespot[11162]: time="2025-10-28T13:29:59Z" level=debug msg="completed keyexchange" Oct 28 13:29:59 volumio go-librespot[11162]: time="2025-10-28T13:29:59Z" level=debug msg="completed challenge" Oct 28 13:29:59 volumio go-librespot[11162]: time="2025-10-28T13:29:59Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 13:29:59 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 13:29:59 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 13:29:59 volumio volumio[10598]: info: Initializing connection to go-librespot Websocket Oct 28 13:29:59 volumio volumio[10598]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 13:30:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 137. Oct 28 13:30:02 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 13:30:02 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 13:30:02 volumio go-librespot[11170]: go-librespot daemon starting... Oct 28 13:30:02 volumio go-librespot[11171]: time="2025-10-28T13:30:02Z" level=info msg="running go-librespot 0.4.0" Oct 28 13:30:02 volumio go-librespot[11171]: time="2025-10-28T13:30:02Z" level=debug msg="app state loaded" Oct 28 13:30:02 volumio go-librespot[11171]: time="2025-10-28T13:30:02Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 13:30:02 volumio go-librespot[11171]: time="2025-10-28T13:30:02Z" 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 28 13:30:02 volumio go-librespot[11171]: time="2025-10-28T13:30:02Z" 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 28 13:30:02 volumio go-librespot[11171]: time="2025-10-28T13:30:02Z" 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 28 13:30:02 volumio go-librespot[11171]: time="2025-10-28T13:30:02Z" level=info msg="zeroconf server listening on port 36357" Oct 28 13:30:02 volumio go-librespot[11171]: time="2025-10-28T13:30:02Z" level=debug msg="obtained new client token: AADra+Ws67S3iKmDjNeY6VZzgFWA7rjMZiy9M5s7R94sFz0v/7yNVitjNe0nbxBKGCR82Rm/4UOWzhkJ8Hdg19dkQEzf/lLiw5FTlQzDZuhVhvi44ju6WXqT9cjUcKerbZQVqHed+tr8mHHMGTPKVePtj7DWdoXN4YdSCEJ4+wSUi7T7+AmP+ny+PcyqcUJe5APy0ivUDVhEg5D8mGTNNF/Eab2BCpYHxardAm1RNzzITLCs53rURmQ=" Oct 28 13:30:02 volumio go-librespot[11171]: time="2025-10-28T13:30:02Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 13:30:02 volumio go-librespot[11171]: time="2025-10-28T13:30:02Z" level=debug msg="completed keyexchange" Oct 28 13:30:02 volumio go-librespot[11171]: time="2025-10-28T13:30:02Z" level=debug msg="completed challenge" Oct 28 13:30:02 volumio go-librespot[11171]: time="2025-10-28T13:30: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 28 13:30:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 13:30:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 13:30:02 volumio volumio[10598]: info: Initializing connection to go-librespot Websocket Oct 28 13:30:02 volumio volumio[10598]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 13:30:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 138. Oct 28 13:30:05 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 13:30:05 volumio volumio[10598]: info: Initializing connection to go-librespot Websocket Oct 28 13:30:05 volumio volumio[10598]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 13:30:05 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 13:30:05 volumio go-librespot[11181]: go-librespot daemon starting... Oct 28 13:30:05 volumio go-librespot[11182]: time="2025-10-28T13:30:05Z" level=info msg="running go-librespot 0.4.0" Oct 28 13:30:05 volumio go-librespot[11182]: time="2025-10-28T13:30:05Z" level=debug msg="app state loaded" Oct 28 13:30:05 volumio go-librespot[11182]: time="2025-10-28T13:30:05Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 13:30:05 volumio go-librespot[11182]: time="2025-10-28T13:30: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 28 13:30:05 volumio go-librespot[11182]: time="2025-10-28T13:30: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 28 13:30:05 volumio go-librespot[11182]: time="2025-10-28T13:30: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 28 13:30:05 volumio go-librespot[11182]: time="2025-10-28T13:30:05Z" level=info msg="zeroconf server listening on port 41775" Oct 28 13:30:05 volumio go-librespot[11182]: time="2025-10-28T13:30:05Z" level=debug msg="obtained new client token: AADObsh/PCS/5YauwNU/qZzgOJ0SX5QGHSD5nFRkqWi2mK6Tx5EycgcO7das5zv9axeO23DaneG37XnTJagOyUk0SqlqHCFYSKwUpJHpwzTHXEpeGsZrcnP6mUQ13q21z0zEJ12v37RGmvi8DHXvpwFrNfeGhv2qjgsVvJFxMsDtNVpT9INrIBugO+V225tIwYTHUq4hAeoRL6M5URDE1M5anxg9e3/jdwS8v70ev/h/ZJAOQw0nkHk=" Oct 28 13:30:05 volumio go-librespot[11182]: time="2025-10-28T13:30:05Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 13:30:06 volumio go-librespot[11182]: time="2025-10-28T13:30:06Z" level=debug msg="completed keyexchange" Oct 28 13:30:06 volumio go-librespot[11182]: time="2025-10-28T13:30:06Z" level=debug msg="completed challenge" Oct 28 13:30:06 volumio go-librespot[11182]: time="2025-10-28T13:30:06Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 13:30:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 13:30:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 13:30:08 volumio volumio[10598]: info: Initializing connection to go-librespot Websocket Oct 28 13:30:08 volumio volumio[10598]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 13:30:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 139. Oct 28 13:30:09 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 13:30:09 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 13:30:09 volumio go-librespot[11204]: go-librespot daemon starting... Oct 28 13:30:09 volumio go-librespot[11205]: time="2025-10-28T13:30:09Z" level=info msg="running go-librespot 0.4.0" Oct 28 13:30:09 volumio go-librespot[11205]: time="2025-10-28T13:30:09Z" level=debug msg="app state loaded" Oct 28 13:30:09 volumio go-librespot[11205]: time="2025-10-28T13:30:09Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 13:30:09 volumio go-librespot[11205]: time="2025-10-28T13:30:09Z" 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 28 13:30:09 volumio go-librespot[11205]: time="2025-10-28T13:30:09Z" 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 28 13:30:09 volumio go-librespot[11205]: time="2025-10-28T13:30:09Z" 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 28 13:30:09 volumio go-librespot[11205]: time="2025-10-28T13:30:09Z" level=info msg="zeroconf server listening on port 46777" Oct 28 13:30:09 volumio go-librespot[11205]: time="2025-10-28T13:30:09Z" level=debug msg="obtained new client token: AABXbUOnC0HLtyaRdeGOUKVOWivJJ+c2tHLYliTij3d/04rHNUC4Ty4PqNH9x7WBKmwtU2KJReSMmlCaTFyO1BBTscqeZFILx27HNZQDmdLmIZ1pOQha2Tck7l2rUAcu9zbvFntxqHMcEQed7DfP7YhKin/EDhIzEshUCy1USAVv6gF3bE3fUPM6IZTK8TGj/+OtTALKdckGsPCDnB494iKks1zO5V6pPbG3Ox+uLM/OVZa7oZVAL7g=" Oct 28 13:30:09 volumio go-librespot[11205]: time="2025-10-28T13:30:09Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 13:30:09 volumio go-librespot[11205]: time="2025-10-28T13:30:09Z" level=debug msg="completed keyexchange" Oct 28 13:30:09 volumio go-librespot[11205]: time="2025-10-28T13:30:09Z" level=debug msg="completed challenge" Oct 28 13:30:09 volumio go-librespot[11205]: time="2025-10-28T13:30: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 28 13:30:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 13:30:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 13:30:11 volumio volumio[10598]: info: Initializing connection to go-librespot Websocket Oct 28 13:30:11 volumio volumio[10598]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 13:30:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 140. Oct 28 13:30:12 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 13:30:12 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 13:30:12 volumio go-librespot[11213]: go-librespot daemon starting... Oct 28 13:30:12 volumio go-librespot[11214]: time="2025-10-28T13:30:12Z" level=info msg="running go-librespot 0.4.0" Oct 28 13:30:12 volumio go-librespot[11214]: time="2025-10-28T13:30:12Z" level=debug msg="app state loaded" Oct 28 13:30:12 volumio go-librespot[11214]: time="2025-10-28T13:30:12Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 13:30:12 volumio go-librespot[11214]: time="2025-10-28T13:30: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 28 13:30:12 volumio go-librespot[11214]: time="2025-10-28T13:30: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 28 13:30:12 volumio go-librespot[11214]: time="2025-10-28T13:30: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 28 13:30:12 volumio go-librespot[11214]: time="2025-10-28T13:30:12Z" level=info msg="zeroconf server listening on port 34665" Oct 28 13:30:12 volumio go-librespot[11214]: time="2025-10-28T13:30:12Z" level=debug msg="obtained new client token: AADg8gH6716GrtIkdP42IzIEBHM742iJDVrTnTY81EhIA+rSPOw6bKNAxnAImUZYTKauLc1ObaP+dP1yl4tx9Skec7kmPIrIOCwmlRtbJbubRhrw/DiplReWDzzQbOXPDzM60HgJr6vX3COe5oQXW297DEB7lmsvtrAnsiXB2q9aGNlTG+wzie8x0IXFy1RhCFykVF/hgurWqGoNaUZxtgh32G4FIX6AieCOdw0auR1Q29yRv1Tq1nw=" Oct 28 13:30:12 volumio go-librespot[11214]: time="2025-10-28T13:30:12Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 13:30:13 volumio go-librespot[11214]: time="2025-10-28T13:30:13Z" level=debug msg="completed keyexchange" Oct 28 13:30:13 volumio go-librespot[11214]: time="2025-10-28T13:30:13Z" level=debug msg="completed challenge" Oct 28 13:30:13 volumio go-librespot[11214]: time="2025-10-28T13:30:13Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 13:30:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 13:30:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 13:30:14 volumio volumio[10598]: info: Initializing connection to go-librespot Websocket Oct 28 13:30:14 volumio volumio[10598]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 13:30:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 141. Oct 28 13:30:16 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 13:30:16 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 13:30:16 volumio go-librespot[11222]: go-librespot daemon starting... Oct 28 13:30:16 volumio go-librespot[11223]: time="2025-10-28T13:30:16Z" level=info msg="running go-librespot 0.4.0" Oct 28 13:30:16 volumio go-librespot[11223]: time="2025-10-28T13:30:16Z" level=debug msg="app state loaded" Oct 28 13:30:16 volumio go-librespot[11223]: time="2025-10-28T13:30:16Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 13:30:16 volumio go-librespot[11223]: time="2025-10-28T13:30:16Z" 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 28 13:30:16 volumio go-librespot[11223]: time="2025-10-28T13:30:16Z" 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 28 13:30:16 volumio go-librespot[11223]: time="2025-10-28T13:30:16Z" 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 28 13:30:16 volumio go-librespot[11223]: time="2025-10-28T13:30:16Z" level=info msg="zeroconf server listening on port 44059" Oct 28 13:30:16 volumio go-librespot[11223]: time="2025-10-28T13:30:16Z" level=debug msg="obtained new client token: AABnBg3eGLc0lfpDSGYO91C9mxWoEPYFDI8D6NWBkbLo9csNYwAQIyE6F4eLsO5syV+neg6uWNIbvtmfMEjB8vM99A8R8vNk3yBDlW9MWO2og6adbAM7uytvvLQUJXNa3fhvfHG1Iwya8vQOU0iJxcGq6aMsy636xQ5JITKJJC3uvkYBTq81AdsrwyXi+eOM2mbIm5+BnMroin5s0P5O1c5e0VJzmuBHjMeEKFTSC0kdcNnjkuzRh3U=" Oct 28 13:30:16 volumio go-librespot[11223]: time="2025-10-28T13:30:16Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 13:30:16 volumio go-librespot[11223]: time="2025-10-28T13:30:16Z" level=debug msg="completed keyexchange" Oct 28 13:30:16 volumio go-librespot[11223]: time="2025-10-28T13:30:16Z" level=debug msg="completed challenge" Oct 28 13:30:16 volumio go-librespot[11223]: time="2025-10-28T13:30:16Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 13:30:16 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 13:30:16 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 13:30:17 volumio volumio[10598]: info: Initializing connection to go-librespot Websocket Oct 28 13:30:17 volumio volumio[10598]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 13:30:19 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 142. Oct 28 13:30:19 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 13:30:19 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 13:30:19 volumio go-librespot[11244]: go-librespot daemon starting... Oct 28 13:30:19 volumio go-librespot[11245]: time="2025-10-28T13:30:19Z" level=info msg="running go-librespot 0.4.0" Oct 28 13:30:19 volumio go-librespot[11245]: time="2025-10-28T13:30:19Z" level=debug msg="app state loaded" Oct 28 13:30:19 volumio go-librespot[11245]: time="2025-10-28T13:30:19Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 13:30:19 volumio go-librespot[11245]: time="2025-10-28T13:30: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 28 13:30:19 volumio go-librespot[11245]: time="2025-10-28T13:30: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 28 13:30:19 volumio go-librespot[11245]: time="2025-10-28T13:30: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 28 13:30:19 volumio go-librespot[11245]: time="2025-10-28T13:30:19Z" level=info msg="zeroconf server listening on port 44863" Oct 28 13:30:19 volumio go-librespot[11245]: time="2025-10-28T13:30:19Z" level=debug msg="obtained new client token: AAAZARLgdvK+Pv8vHyMifN0w1SsOdpS8HScJdvDdvAs7V4SN1oefulI+G3snNLkS6inVYWF5M2y2OnBvHbRVINY98wnZNppU6M6YjUNYfmKG2pKVjBBriaJ6/wLfnZU0SU6mLYJVx/zTMBvYv607tAD9U0pJj9PXhdneTBn4inx58EHlW8/BiiBAnyH/yzhwS8dz37AYwD1d/dOcXXlmd/hWUvzKgkiMRCwt+4g694HxUuk5NboFiYk=" Oct 28 13:30:19 volumio go-librespot[11245]: time="2025-10-28T13:30:19Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 13:30:20 volumio go-librespot[11245]: time="2025-10-28T13:30:20Z" level=debug msg="completed keyexchange" Oct 28 13:30:20 volumio go-librespot[11245]: time="2025-10-28T13:30:20Z" level=debug msg="completed challenge" Oct 28 13:30:20 volumio go-librespot[11245]: time="2025-10-28T13:30:20Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 13:30:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 13:30:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 13:30:20 volumio volumio[10598]: info: Initializing connection to go-librespot Websocket Oct 28 13:30:20 volumio volumio[10598]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 13:30:23 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 143. Oct 28 13:30:23 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 13:30:23 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 13:30:23 volumio go-librespot[11255]: go-librespot daemon starting... Oct 28 13:30:23 volumio go-librespot[11256]: time="2025-10-28T13:30:23Z" level=info msg="running go-librespot 0.4.0" Oct 28 13:30:23 volumio go-librespot[11256]: time="2025-10-28T13:30:23Z" level=debug msg="app state loaded" Oct 28 13:30:23 volumio go-librespot[11256]: time="2025-10-28T13:30:23Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 13:30:23 volumio go-librespot[11256]: time="2025-10-28T13:30:23Z" 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 28 13:30:23 volumio go-librespot[11256]: time="2025-10-28T13:30:23Z" 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 28 13:30:23 volumio go-librespot[11256]: time="2025-10-28T13:30:23Z" 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 28 13:30:23 volumio go-librespot[11256]: time="2025-10-28T13:30:23Z" level=info msg="zeroconf server listening on port 41377" Oct 28 13:30:23 volumio go-librespot[11256]: time="2025-10-28T13:30:23Z" level=debug msg="obtained new client token: AAB0MIgkB/ugvu0FmJxOfgm1qOQpsntgcHM/lO4wl8jMHQ1mq05BKuD8jPIsFPBJRHeSzw8AjRlpl5psgjZKVzLxKLYMMwrboJC1jAStZu5flN5h6p3SY81P9GxM1ofRaITQDA9Y1YV6t77YKBME9UnW6bZTTY7v3eD6Ww6pnwRlGYm+aAlsJe+cSOTH7/Lfm2VJCWj4s4a/abDIIF+rlKjO4kRhWJsScPnv0ObSdU0h5Ze7L6BilEA=" Oct 28 13:30:23 volumio go-librespot[11256]: time="2025-10-28T13:30:23Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 13:30:23 volumio go-librespot[11256]: time="2025-10-28T13:30:23Z" level=debug msg="completed keyexchange" Oct 28 13:30:23 volumio go-librespot[11256]: time="2025-10-28T13:30:23Z" level=debug msg="completed challenge" Oct 28 13:30:23 volumio go-librespot[11256]: time="2025-10-28T13:30:23Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 13:30:23 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 13:30:23 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 13:30:23 volumio volumio[10598]: info: Initializing connection to go-librespot Websocket Oct 28 13:30:23 volumio volumio[10598]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 13:30:26 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 144. Oct 28 13:30:26 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 13:30:26 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 13:30:26 volumio go-librespot[11263]: go-librespot daemon starting... Oct 28 13:30:26 volumio volumio[10598]: info: Initializing connection to go-librespot Websocket Oct 28 13:30:26 volumio volumio[10598]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 13:30:26 volumio go-librespot[11264]: time="2025-10-28T13:30:26Z" level=info msg="running go-librespot 0.4.0" Oct 28 13:30:26 volumio go-librespot[11264]: time="2025-10-28T13:30:26Z" level=debug msg="app state loaded" Oct 28 13:30:26 volumio go-librespot[11264]: time="2025-10-28T13:30:26Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 13:30:26 volumio go-librespot[11264]: time="2025-10-28T13:30: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 28 13:30:26 volumio go-librespot[11264]: time="2025-10-28T13:30: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 28 13:30:26 volumio go-librespot[11264]: time="2025-10-28T13:30: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 28 13:30:26 volumio go-librespot[11264]: time="2025-10-28T13:30:26Z" level=info msg="zeroconf server listening on port 44183" Oct 28 13:30:26 volumio go-librespot[11264]: time="2025-10-28T13:30:26Z" level=debug msg="obtained new client token: AADGeMLUHo1vtITDF32lVBCIaVP+NsiNJUem9+y8Rs3Mvqu52qlvv7MovbEOEXGdgm7Mmg6y7ysuLTSkws+3wTTwbroPhrQ8oXQYHV0vycDP8wJaNsMZrFZ4KkEUhuXPzS94uxauL0kHsY9DvhZ6iKBwnwpOOOKPoOaedfQRL5EyernRUJUB75Bq8367gPFfBOK6Cj3xQVi2QY4DXLqH2IYuI57FbsMmcP4dYdGLMODvTitzZiIgj2Y=" Oct 28 13:30:27 volumio go-librespot[11264]: time="2025-10-28T13:30:27Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 13:30:27 volumio go-librespot[11264]: time="2025-10-28T13:30:27Z" level=debug msg="completed keyexchange" Oct 28 13:30:27 volumio go-librespot[11264]: time="2025-10-28T13:30:27Z" level=debug msg="completed challenge" Oct 28 13:30:27 volumio go-librespot[11264]: time="2025-10-28T13:30:27Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 13:30:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 13:30:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 13:30:29 volumio volumio[10598]: info: Initializing connection to go-librespot Websocket Oct 28 13:30:29 volumio volumio[10598]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 13:30:30 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 145. Oct 28 13:30:30 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 13:30:30 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 13:30:30 volumio go-librespot[11286]: go-librespot daemon starting... Oct 28 13:30:30 volumio go-librespot[11287]: time="2025-10-28T13:30:30Z" level=info msg="running go-librespot 0.4.0" Oct 28 13:30:30 volumio go-librespot[11287]: time="2025-10-28T13:30:30Z" level=debug msg="app state loaded" Oct 28 13:30:30 volumio go-librespot[11287]: time="2025-10-28T13:30:30Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 13:30:30 volumio go-librespot[11287]: time="2025-10-28T13:30:30Z" 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 28 13:30:30 volumio go-librespot[11287]: time="2025-10-28T13:30:30Z" 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 28 13:30:30 volumio go-librespot[11287]: time="2025-10-28T13:30:30Z" 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 28 13:30:30 volumio go-librespot[11287]: time="2025-10-28T13:30:30Z" level=info msg="zeroconf server listening on port 45709" Oct 28 13:30:30 volumio go-librespot[11287]: time="2025-10-28T13:30:30Z" level=debug msg="obtained new client token: AADcvWkyP09o/fLv/e1or5t7c0lEJCgu7ofxV1zb4Id+xCnYwC4RxRZ0gF6tShWEYpc1LyF22dvJSMn6kqhWMSF2nAxa03yipPz9m55TC1ctc5xy8qrjLhWYmpei0Nd7u1kHAZBjmcouXkWUTrR71tKUDcrCcv++NuAOpd6lFoWJrghF3Cu5WAUSUs0Tmc8hVX3vy7AvYE7u7XNf1RCV9sVfNCzcfSjgxndcDMt1xKeURm7CFNOFp4k=" Oct 28 13:30:30 volumio go-librespot[11287]: time="2025-10-28T13:30:30Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 13:30:30 volumio go-librespot[11287]: time="2025-10-28T13:30:30Z" level=debug msg="completed keyexchange" Oct 28 13:30:30 volumio go-librespot[11287]: time="2025-10-28T13:30:30Z" level=debug msg="completed challenge" Oct 28 13:30:30 volumio go-librespot[11287]: time="2025-10-28T13:30:30Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 13:30:30 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 13:30:30 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 13:30:32 volumio volumio[10598]: info: Initializing connection to go-librespot Websocket Oct 28 13:30:32 volumio volumio[10598]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 13:30:33 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 146. Oct 28 13:30:33 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 13:30:33 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 13:30:33 volumio go-librespot[11294]: go-librespot daemon starting... Oct 28 13:30:33 volumio go-librespot[11295]: time="2025-10-28T13:30:33Z" level=info msg="running go-librespot 0.4.0" Oct 28 13:30:33 volumio go-librespot[11295]: time="2025-10-28T13:30:33Z" level=debug msg="app state loaded" Oct 28 13:30:33 volumio go-librespot[11295]: time="2025-10-28T13:30:33Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 13:30:33 volumio go-librespot[11295]: time="2025-10-28T13:30: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-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 28 13:30:33 volumio go-librespot[11295]: time="2025-10-28T13:30:33Z" 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 28 13:30:33 volumio go-librespot[11295]: time="2025-10-28T13:30:33Z" 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 28 13:30:33 volumio go-librespot[11295]: time="2025-10-28T13:30:33Z" level=info msg="zeroconf server listening on port 37393" Oct 28 13:30:33 volumio go-librespot[11295]: time="2025-10-28T13:30:33Z" level=debug msg="obtained new client token: AADzSLw+Bn9qMa5Yvtsglj4AlCN3dbSxVal/L6fMwt1E+XTRxGu1zfhOlymeOC+C69ZoHsw1iTAA1f462lGnMiTK87GjD3LtqN3YbTW54iwUmw8bFGerRcaHN9zPEL+HsMM+afZv2FsFRco9AcdN7XmswCKO9sH5WffHBtG4FQp9cuP5oGH5zAjjdoaMxQvNpTauSIYRlI7OmzVez4Zi9B9XW1rOM3vOovwv1m2kxtUxrxfMMY7uIs4=" Oct 28 13:30:33 volumio go-librespot[11295]: time="2025-10-28T13:30:33Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 13:30:34 volumio go-librespot[11295]: time="2025-10-28T13:30:34Z" level=debug msg="completed keyexchange" Oct 28 13:30:34 volumio go-librespot[11295]: time="2025-10-28T13:30:34Z" level=debug msg="completed challenge" Oct 28 13:30:34 volumio go-librespot[11295]: time="2025-10-28T13:30:34Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 13:30:34 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 13:30:34 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 13:30:35 volumio volumio[10598]: info: Initializing connection to go-librespot Websocket Oct 28 13:30:35 volumio volumio[10598]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 13:30:37 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 147. Oct 28 13:30:37 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 13:30:37 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 13:30:37 volumio go-librespot[11316]: go-librespot daemon starting... Oct 28 13:30:37 volumio go-librespot[11317]: time="2025-10-28T13:30:37Z" level=info msg="running go-librespot 0.4.0" Oct 28 13:30:37 volumio go-librespot[11317]: time="2025-10-28T13:30:37Z" level=debug msg="app state loaded" Oct 28 13:30:37 volumio go-librespot[11317]: time="2025-10-28T13:30:37Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 13:30:37 volumio go-librespot[11317]: time="2025-10-28T13:30:37Z" 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 28 13:30:37 volumio go-librespot[11317]: time="2025-10-28T13:30:37Z" 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 28 13:30:37 volumio go-librespot[11317]: time="2025-10-28T13:30:37Z" 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 28 13:30:37 volumio go-librespot[11317]: time="2025-10-28T13:30:37Z" level=info msg="zeroconf server listening on port 42955" Oct 28 13:30:37 volumio go-librespot[11317]: time="2025-10-28T13:30:37Z" level=debug msg="obtained new client token: AACNrgvI3ivF2joDYK08Mc47HtJZu0zX+1HnCW/stFaa41cRQDHwuklOifeKlXRjoVhzkr9EmkekVe+ANjCdDkwb80JhJikfhBaRXZCfRY81pqJEnHSTWnUX+ewswY3l3t2qUL8DeHgtq2R/QkU0E+AxqWfQVpQWr5rdLW/f0h+ig3v/OHrbKCTyN9OnSeLGRJyXCFo36i5OlclYP+cye2H3zc98ZCgjzd3uki28Jkp/a8y2h529yNI=" Oct 28 13:30:37 volumio go-librespot[11317]: time="2025-10-28T13:30:37Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 13:30:37 volumio go-librespot[11317]: time="2025-10-28T13:30:37Z" level=debug msg="completed keyexchange" Oct 28 13:30:37 volumio go-librespot[11317]: time="2025-10-28T13:30:37Z" level=debug msg="completed challenge" Oct 28 13:30:37 volumio go-librespot[11317]: time="2025-10-28T13:30:37Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 13:30:37 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 13:30:37 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 13:30:38 volumio volumio[10598]: info: Initializing connection to go-librespot Websocket Oct 28 13:30:38 volumio volumio[10598]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 13:30:40 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 148. Oct 28 13:30:40 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 13:30:40 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 13:30:40 volumio go-librespot[11325]: go-librespot daemon starting... Oct 28 13:30:40 volumio go-librespot[11326]: time="2025-10-28T13:30:40Z" level=info msg="running go-librespot 0.4.0" Oct 28 13:30:40 volumio go-librespot[11326]: time="2025-10-28T13:30:40Z" level=debug msg="app state loaded" Oct 28 13:30:40 volumio go-librespot[11326]: time="2025-10-28T13:30:40Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 13:30:40 volumio go-librespot[11326]: time="2025-10-28T13:30:40Z" 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 28 13:30:40 volumio go-librespot[11326]: time="2025-10-28T13:30:40Z" 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 28 13:30:40 volumio go-librespot[11326]: time="2025-10-28T13:30:40Z" 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 28 13:30:40 volumio go-librespot[11326]: time="2025-10-28T13:30:40Z" level=info msg="zeroconf server listening on port 41165" Oct 28 13:30:41 volumio go-librespot[11326]: time="2025-10-28T13:30:41Z" level=debug msg="obtained new client token: AABSwsm7MlTDOkYfyCH4ynWlJPQncsWz/QlFlzH9NLaEGzzPwnZlQSFZiHsxeFBI5Yc363Uf+Thz0DJrmzNkfL9Sc83To4kVTFyqql56EVxBTm9ieVCuun3naDVCzQ88FgnO+5hOSJzjMtkfhIQgZpj0mZKrp5s3ykMk7HtXkqyeFVZJqUqx6a1LdHEZ2aYmx4IURJvJ6HElfiLcytbk7DLYzXvqnZK/0uE3CA0h8S0gqMenNSYb" Oct 28 13:30:41 volumio go-librespot[11326]: time="2025-10-28T13:30:41Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 13:30:41 volumio go-librespot[11326]: time="2025-10-28T13:30:41Z" level=debug msg="completed keyexchange" Oct 28 13:30:41 volumio go-librespot[11326]: time="2025-10-28T13:30:41Z" level=debug msg="completed challenge" Oct 28 13:30:41 volumio go-librespot[11326]: time="2025-10-28T13:30:41Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 13:30:41 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 13:30:41 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 13:30:41 volumio volumio[10598]: info: Initializing connection to go-librespot Websocket Oct 28 13:30:41 volumio volumio[10598]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 13:30:44 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 149. Oct 28 13:30:44 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 13:30:44 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 13:30:44 volumio go-librespot[11336]: go-librespot daemon starting... Oct 28 13:30:44 volumio go-librespot[11337]: time="2025-10-28T13:30:44Z" level=info msg="running go-librespot 0.4.0" Oct 28 13:30:44 volumio go-librespot[11337]: time="2025-10-28T13:30:44Z" level=debug msg="app state loaded" Oct 28 13:30:44 volumio go-librespot[11337]: time="2025-10-28T13:30:44Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 13:30:44 volumio go-librespot[11337]: time="2025-10-28T13:30:44Z" 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 28 13:30:44 volumio go-librespot[11337]: time="2025-10-28T13:30:44Z" 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 28 13:30:44 volumio go-librespot[11337]: time="2025-10-28T13:30:44Z" 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 28 13:30:44 volumio go-librespot[11337]: time="2025-10-28T13:30:44Z" level=info msg="zeroconf server listening on port 39677" Oct 28 13:30:44 volumio go-librespot[11337]: time="2025-10-28T13:30:44Z" level=debug msg="obtained new client token: AABxsgzf2+u1bYpYG6x4Dk7Z8VvmaeWo019klo2QiS/r0R3v4YxaQIQCvQj4tnMjeh7eEXCLHgzMIVO9DAuak4w5A5395XbXiS8dkkpx3b+we3690SAaxpeV2aHUQXcTPA0zaFIAyUqjyVbHAgJwhbIGy6hlH3VmAGiKQIIkB63LdXtUKdsz00he7cb4dXP78Fk+0yGsqqL1L7E5Quqogl0YsGCM5MLvXC4gUIh1JUuFT7T4kQy0CXk=" Oct 28 13:30:44 volumio go-librespot[11337]: time="2025-10-28T13:30:44Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 13:30:44 volumio go-librespot[11337]: time="2025-10-28T13:30:44Z" level=debug msg="completed keyexchange" Oct 28 13:30:44 volumio go-librespot[11337]: time="2025-10-28T13:30:44Z" level=debug msg="completed challenge" Oct 28 13:30:44 volumio go-librespot[11337]: time="2025-10-28T13:30:44Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Oct 28 13:30:44 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 13:30:44 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 13:30:44 volumio volumio[10598]: info: Initializing connection to go-librespot Websocket Oct 28 13:30:44 volumio volumio[10598]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 13:30:47 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 150. Oct 28 13:30:47 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 28 13:30:47 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 28 13:30:47 volumio go-librespot[11361]: go-librespot daemon starting... Oct 28 13:30:47 volumio go-librespot[11362]: time="2025-10-28T13:30:47Z" level=info msg="running go-librespot 0.4.0" Oct 28 13:30:47 volumio go-librespot[11362]: time="2025-10-28T13:30:47Z" level=debug msg="app state loaded" Oct 28 13:30:47 volumio go-librespot[11362]: time="2025-10-28T13:30:47Z" level=info msg="api server listening on 127.0.0.1:9879" Oct 28 13:30:47 volumio volumio[10598]: info: Initializing connection to go-librespot Websocket Oct 28 13:30:47 volumio go-librespot[11362]: time="2025-10-28T13:30:47Z" level=debug msg="new websocket client" Oct 28 13:30:47 volumio volumio[10598]: info: Connection to go-librespot Websocket established Oct 28 13:30:47 volumio go-librespot[11362]: time="2025-10-28T13:30:47Z" 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 28 13:30:47 volumio go-librespot[11362]: time="2025-10-28T13:30:47Z" 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 28 13:30:47 volumio go-librespot[11362]: time="2025-10-28T13:30:47Z" 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 28 13:30:47 volumio go-librespot[11362]: time="2025-10-28T13:30:47Z" level=info msg="zeroconf server listening on port 36967" Oct 28 13:30:48 volumio go-librespot[11362]: time="2025-10-28T13:30:48Z" level=debug msg="obtained new client token: AADz4VVxkcWpKYJH3B0um879NxgUgbPkGcDA+2zN3oxHWRT0Q+PmIQhm9c18Fiki0ju+iqPk920FBKlr5z3jI2oJ9j6QrJULi5VgkZfwNKKNWb/dw1UEfGkn58EO4/MuFF7sVnDV4+qR1wOSnrAiucui+aKtbs2QMr6KDZoKbhGC4Fw7YwozQWopdlbu/l4uUdIEMDpWnssCmnmq9c18eNKvHI+obYU1Lbx/R56pzFJ8n2T9q6L6" Oct 28 13:30:48 volumio go-librespot[11362]: time="2025-10-28T13:30:48Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 28 13:30:48 volumio go-librespot[11362]: time="2025-10-28T13:30:48Z" level=debug msg="completed keyexchange" Oct 28 13:30:48 volumio go-librespot[11362]: time="2025-10-28T13:30:48Z" level=debug msg="completed challenge" Oct 28 13:30:48 volumio go-librespot[11362]: time="2025-10-28T13:30:48Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 28 13:30:48 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 28 13:30:48 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 28 13:30:48 volumio volumio[10598]: info: Connection to go-librespot Websocket closed Oct 28 13:30:50 volumio volumio[10598]: info: Getting Spotify volume Oct 28 13:30:50 volumio volumio[10598]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 28 13:30:50 volumio volumio[10598]: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 28 13:30:50 volumio volumio[10598]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Oct 28 13:30:50 volumio volumio[10598]: errno: -111, Oct 28 13:30:50 volumio volumio[10598]: code: 'ECONNREFUSED', Oct 28 13:30:50 volumio volumio[10598]: syscall: 'connect', Oct 28 13:30:50 volumio volumio[10598]: address: '127.0.0.1', Oct 28 13:30:50 volumio volumio[10598]: port: 9879, Oct 28 13:30:50 volumio volumio[10598]: response: undefined Oct 28 13:30:50 volumio volumio[10598]: } Oct 28 13:30:50 volumio volumio[10598]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 28 13:30:50 volumio sudo[11383]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-10-28 13:29' Oct 28 13:30:50 volumio sudo[11383]: 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"