Nov 06 04:47:00 volumio volumio[23489]: info: MyVolumio status changed Nov 06 04:47:00 volumio volumio[23489]: info: Streaming services startup Nov 06 04:47:00 volumio volumio[23489]: info: Starting Streaming Daemon Nov 06 04:47:00 volumio volumio[23489]: info: Removing browser output: myVolumio user plan is not superstar Nov 06 04:47:00 volumio volumio[23489]: info: Removing audio output: Nov 06 04:47:00 volumio volumio[23489]: info: Stoppping Tunnel 1 Nov 06 04:47:00 volumio sudo[23809]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 06 04:47:00 volumio sudo[23809]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 06 04:47:00 volumio sudo[23811]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Nov 06 04:47:00 volumio sudo[23811]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 06 04:47:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 401. Nov 06 04:47:00 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 04:47:00 volumio sudo[23809]: pam_unix(sudo:session): session closed for user root Nov 06 04:47:00 volumio volumio[23489]: error: Cannot start Volumio Streaming Daemon Nov 06 04:47:00 volumio volumio[23489]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Nov 06 04:47:00 volumio volumio[23489]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Nov 06 04:47:00 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 04:47:00 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. Nov 06 04:47:00 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. Nov 06 04:47:00 volumio go-librespot[23814]: go-librespot daemon starting... Nov 06 04:47:00 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. Nov 06 04:47:00 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. Nov 06 04:47:00 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. Nov 06 04:47:00 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. Nov 06 04:47:00 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. Nov 06 04:47:00 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. Nov 06 04:47:00 volumio sudo[23811]: pam_unix(sudo:session): session closed for user root Nov 06 04:47:00 volumio go-librespot[23815]: time="2025-11-06T04:47:00Z" level=info msg="running go-librespot 0.4.0" Nov 06 04:47:00 volumio go-librespot[23815]: time="2025-11-06T04:47:00Z" level=debug msg="app state loaded" Nov 06 04:47:00 volumio volumio[23489]: info: Remote SSH Stopped Nov 06 04:47:00 volumio go-librespot[23815]: time="2025-11-06T04:47:00Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 04:47:00 volumio go-librespot[23815]: time="2025-11-06T04:47:00Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 06 04:47:00 volumio go-librespot[23815]: time="2025-11-06T04:47:00Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 06 04:47:00 volumio go-librespot[23815]: time="2025-11-06T04:47:00Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 06 04:47:00 volumio go-librespot[23815]: time="2025-11-06T04:47:00Z" level=info msg="zeroconf server listening on port 36475" Nov 06 04:47:00 volumio go-librespot[23815]: time="2025-11-06T04:47:00Z" level=debug msg="obtained new client token: AABkAHk48o5I6fsIg+rt0qwXi9HLf2YcH674KKjvRoXsj4pqQNEsuPuWfVTYxuC4CWqx723XNOONkDTs9m6PCV2zHcw/YNztdPuNy9tEUxFOOqwQ5RTshevXsMpgMS6SIGmiChDKGNvmTRc5w79nfpgDtdcCrTYnfZ/OCmR1CZkEhb9BRgY/jqvLXnYSCJsJuxl8J+gn1qJUdsVVezGOYX4/vLZmWsvmJAGVZJABklUcMSvrzG7sCGc=" Nov 06 04:47:00 volumio go-librespot[23815]: time="2025-11-06T04:47:00Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 06 04:47:00 volumio go-librespot[23815]: time="2025-11-06T04:47:00Z" level=debug msg="completed keyexchange" Nov 06 04:47:00 volumio go-librespot[23815]: time="2025-11-06T04:47:00Z" level=debug msg="completed challenge" Nov 06 04:47:00 volumio volumio[23489]: info: Setting Geolocation for MyVolumio to eu3 Nov 06 04:47:00 volumio volumio[23489]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 06 04:47:00 volumio volumio[23489]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 06 04:47:00 volumio volumio[23489]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 06 04:47:00 volumio go-librespot[23815]: time="2025-11-06T04:47:00Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 06 04:47:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 04:47:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 04:47:00 volumio volumio[23489]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Nov 06 04:47:00 volumio volumio[23489]: info: CoreCommandRouter::volumioGetState Nov 06 04:47:00 volumio volumio[23489]: info: CorePlayQueue::getTrack 0 Nov 06 04:47:01 volumio volumio[23489]: info: Updating MyVolumio device info Nov 06 04:47:01 volumio volumio[23489]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 06 04:47:01 volumio volumio[23489]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 06 04:47:01 volumio volumio[23489]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 06 04:47:01 volumio volumio[23489]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Nov 06 04:47:02 volumio volumio[23489]: info: Initializing connection to go-librespot Websocket Nov 06 04:47:02 volumio volumio[23489]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 04:47:02 volumio volumio[23489]: info: Cannot mount NAS NAS at system boot, trial number 4 ,retrying in 5 seconds Nov 06 04:47:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 402. Nov 06 04:47:03 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 04:47:03 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 04:47:03 volumio go-librespot[23822]: go-librespot daemon starting... Nov 06 04:47:03 volumio go-librespot[23823]: time="2025-11-06T04:47:03Z" level=info msg="running go-librespot 0.4.0" Nov 06 04:47:03 volumio go-librespot[23823]: time="2025-11-06T04:47:03Z" level=debug msg="app state loaded" Nov 06 04:47:03 volumio go-librespot[23823]: time="2025-11-06T04:47:03Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 04:47:03 volumio go-librespot[23823]: time="2025-11-06T04:47:03Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 06 04:47:03 volumio go-librespot[23823]: time="2025-11-06T04:47:03Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 06 04:47:03 volumio go-librespot[23823]: time="2025-11-06T04:47:03Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 06 04:47:03 volumio go-librespot[23823]: time="2025-11-06T04:47:03Z" level=info msg="zeroconf server listening on port 40513" Nov 06 04:47:03 volumio go-librespot[23823]: time="2025-11-06T04:47:03Z" level=debug msg="obtained new client token: AAB4WfEuprpGSo2a5yB+nQCn8ktUfDyx3bvwsoZy2GoBehOEP8JM/Tsc2d59paol4JavzQ94OkVPt74q+dZyaOgyr8Y0pLmEPzJyDfKNbro45S9FdURgRJT/cXql9exRSn5v/Jkw3owvjf7rbflB0Nz2keIb9Rx/CENZPyLJEkeRpf3oDWEYr5q0lw4eN2hPyOBroFHmCCz8zVqDS0ovAoBRnqvyJe59hcRw0YCrH5wENtDWHALYE+g=" Nov 06 04:47:03 volumio go-librespot[23823]: time="2025-11-06T04:47:03Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 06 04:47:03 volumio go-librespot[23823]: time="2025-11-06T04:47:03Z" level=debug msg="completed keyexchange" Nov 06 04:47:03 volumio go-librespot[23823]: time="2025-11-06T04:47:03Z" level=debug msg="completed challenge" Nov 06 04:47:03 volumio go-librespot[23823]: time="2025-11-06T04:47:03Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 06 04:47:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 04:47:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 04:47:04 volumio volumio[23489]: info: MYVOLUMIO: Adding device Nov 06 04:47:04 volumio volumio[23489]: info: MYVOLUMIO: Evaluating Server Nov 06 04:47:05 volumio volumio[23489]: info: Setting Geolocation for MyVolumio to eu3 Nov 06 04:47:05 volumio volumio[23489]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 06 04:47:05 volumio volumio[23489]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 06 04:47:05 volumio volumio[23489]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 06 04:47:05 volumio volumio[23489]: info: Initializing connection to go-librespot Websocket Nov 06 04:47:05 volumio volumio[23489]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 04:47:05 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Nov 06 04:47:05 volumio volumio[23489]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Nov 06 04:47:06 volumio volumio[23489]: info: Updating MyVolumio device info Nov 06 04:47:06 volumio volumio[23489]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 06 04:47:06 volumio volumio[23489]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 06 04:47:06 volumio volumio[23489]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 06 04:47:06 volumio volumio[23489]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Nov 06 04:47:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 403. Nov 06 04:47:07 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 04:47:07 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 04:47:07 volumio go-librespot[23846]: go-librespot daemon starting... Nov 06 04:47:07 volumio go-librespot[23847]: time="2025-11-06T04:47:07Z" level=info msg="running go-librespot 0.4.0" Nov 06 04:47:07 volumio go-librespot[23847]: time="2025-11-06T04:47:07Z" level=debug msg="app state loaded" Nov 06 04:47:07 volumio go-librespot[23847]: time="2025-11-06T04:47:07Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 04:47:07 volumio go-librespot[23847]: time="2025-11-06T04:47:07Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Nov 06 04:47:07 volumio go-librespot[23847]: time="2025-11-06T04:47:07Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Nov 06 04:47:07 volumio go-librespot[23847]: time="2025-11-06T04:47:07Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Nov 06 04:47:07 volumio go-librespot[23847]: time="2025-11-06T04:47:07Z" level=info msg="zeroconf server listening on port 38385" Nov 06 04:47:07 volumio go-librespot[23847]: time="2025-11-06T04:47:07Z" level=debug msg="obtained new client token: AAB3nVNVQyAann5+pMCflU5KkZTkNSGkpMxZkU7P8UJrZInxK5eCFuzt3Ut5RdGwMZDZXEezZnAhzyfYO7tBTJkeWFUm6f6/TgJoxeKe/m8uwMXG4VV+i1Bi93IbI6qyjbPqBjMA4BGEqVHnl3cWJ+eCjoOkvjN1EKqFmneAy23S0yB7ggY+nBRKC1J99pK5XtPE8+EwJQ616YjPD6m0gcGoC4Lmw4losDivExbHCpguukPxfITm" Nov 06 04:47:07 volumio go-librespot[23847]: time="2025-11-06T04:47:07Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 06 04:47:07 volumio volumio[23489]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Nov 06 04:47:07 volumio go-librespot[23847]: time="2025-11-06T04:47:07Z" level=debug msg="completed keyexchange" Nov 06 04:47:07 volumio go-librespot[23847]: time="2025-11-06T04:47:07Z" level=debug msg="completed challenge" Nov 06 04:47:07 volumio go-librespot[23847]: time="2025-11-06T04:47:07Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 06 04:47:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 04:47:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 04:47:08 volumio volumio[23489]: info: Initializing connection to go-librespot Websocket Nov 06 04:47:08 volumio volumio[23489]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 04:47:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 404. Nov 06 04:47:10 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 04:47:10 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 04:47:10 volumio go-librespot[23868]: go-librespot daemon starting... Nov 06 04:47:10 volumio go-librespot[23869]: time="2025-11-06T04:47:10Z" level=info msg="running go-librespot 0.4.0" Nov 06 04:47:10 volumio go-librespot[23869]: time="2025-11-06T04:47:10Z" level=debug msg="app state loaded" Nov 06 04:47:10 volumio go-librespot[23869]: time="2025-11-06T04:47:10Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 04:47:10 volumio volumio[23489]: info: CoreCommandRouter::volumioGetState Nov 06 04:47:10 volumio volumio[23489]: info: CorePlayQueue::getTrack 0 Nov 06 04:47:10 volumio volumio[23489]: info: Listing playlists Nov 06 04:47:10 volumio volumio[23489]: info: Listing playlists Nov 06 04:47:10 volumio go-librespot[23869]: time="2025-11-06T04:47:10Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Nov 06 04:47:10 volumio go-librespot[23869]: time="2025-11-06T04:47:10Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Nov 06 04:47:10 volumio go-librespot[23869]: time="2025-11-06T04:47:10Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Nov 06 04:47:10 volumio go-librespot[23869]: time="2025-11-06T04:47:10Z" level=info msg="zeroconf server listening on port 33205" Nov 06 04:47:10 volumio go-librespot[23869]: time="2025-11-06T04:47:10Z" level=debug msg="obtained new client token: AACk+DyW7DDqT6ZKREksWIpMyDzy6rbav10/JD82ZIfsGUSkhky20SfeP5s32196Pot2BoVuSU+1SCAVgMr6QZ/C0ctuliVS1k8ux5aESJLz7Cyo25QaK+uV/aVrctfdcn1tZc8nSec5TYnJXFJrBdtI9WK6Bp3kf913hlh5gzqj0zPGwGq0eQNOUYdr3jvQpLDWxv8O1p+qWRkGTK0C6K0N/HD6dCaRxvWjeKzsV+IORTYO1GZANrY=" Nov 06 04:47:10 volumio go-librespot[23869]: time="2025-11-06T04:47:10Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 06 04:47:10 volumio go-librespot[23869]: time="2025-11-06T04:47:10Z" level=debug msg="completed keyexchange" Nov 06 04:47:10 volumio go-librespot[23869]: time="2025-11-06T04:47:10Z" level=debug msg="completed challenge" Nov 06 04:47:11 volumio go-librespot[23869]: time="2025-11-06T04:47:11Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 06 04:47:11 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 04:47:11 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 04:47:11 volumio volumio[23489]: info: Initializing connection to go-librespot Websocket Nov 06 04:47:11 volumio volumio[23489]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 04:47:13 volumio volumio[23489]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 06 04:47:13 volumio volumio[23489]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Nov 06 04:47:13 volumio volumio[23489]: info: CoreCommandRouter::volumioGetState Nov 06 04:47:13 volumio volumio[23489]: info: CorePlayQueue::getTrack 0 Nov 06 04:47:14 volumio volumio[23489]: info: Initializing connection to go-librespot Websocket Nov 06 04:47:14 volumio volumio[23489]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 04:47:14 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 405. Nov 06 04:47:14 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 04:47:14 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 04:47:14 volumio go-librespot[23880]: go-librespot daemon starting... Nov 06 04:47:14 volumio go-librespot[23881]: time="2025-11-06T04:47:14Z" level=info msg="running go-librespot 0.4.0" Nov 06 04:47:14 volumio go-librespot[23881]: time="2025-11-06T04:47:14Z" level=debug msg="app state loaded" Nov 06 04:47:14 volumio go-librespot[23881]: time="2025-11-06T04:47:14Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 04:47:14 volumio go-librespot[23881]: time="2025-11-06T04:47:14Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 06 04:47:14 volumio go-librespot[23881]: time="2025-11-06T04:47:14Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 06 04:47:14 volumio go-librespot[23881]: time="2025-11-06T04:47:14Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 06 04:47:14 volumio go-librespot[23881]: time="2025-11-06T04:47:14Z" level=info msg="zeroconf server listening on port 45707" Nov 06 04:47:14 volumio go-librespot[23881]: time="2025-11-06T04:47:14Z" level=debug msg="obtained new client token: AACNvisYzLcMRon/luLep+/Ru9HYiNFCNBmQTNlsepvMV/e6bFN9gZ4M0w8ndE8YniGlNi5P7/9wQjhV0H0+t7nfhqF2kEjzYp9hlsFUAgetHsTQPpHm6OIv+xjPxxJ5RfNILGtM/IHLJVG9AJmhZavjwJj9QQHKA5EOBcc0dO9AV5ekNBwLXkWqcjYSIuxx0Y4+BIVmFp5T62qQoaU/KqeOLYdVnvona8p9pNrHBLn/PLp7EHxcxkg=" Nov 06 04:47:14 volumio go-librespot[23881]: time="2025-11-06T04:47:14Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 06 04:47:14 volumio go-librespot[23881]: time="2025-11-06T04:47:14Z" level=debug msg="completed keyexchange" Nov 06 04:47:14 volumio go-librespot[23881]: time="2025-11-06T04:47:14Z" level=debug msg="completed challenge" Nov 06 04:47:14 volumio go-librespot[23881]: time="2025-11-06T04:47:14Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 06 04:47:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 04:47:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 04:47:15 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Nov 06 04:47:17 volumio volumio[23489]: info: Initializing connection to go-librespot Websocket Nov 06 04:47:17 volumio volumio[23489]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 04:47:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 406. Nov 06 04:47:17 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 04:47:17 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 04:47:17 volumio go-librespot[23903]: go-librespot daemon starting... Nov 06 04:47:17 volumio go-librespot[23904]: time="2025-11-06T04:47:17Z" level=info msg="running go-librespot 0.4.0" Nov 06 04:47:17 volumio go-librespot[23904]: time="2025-11-06T04:47:17Z" level=debug msg="app state loaded" Nov 06 04:47:17 volumio go-librespot[23904]: time="2025-11-06T04:47:17Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 04:47:17 volumio go-librespot[23904]: time="2025-11-06T04:47:17Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 06 04:47:17 volumio go-librespot[23904]: time="2025-11-06T04:47:17Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 06 04:47:17 volumio go-librespot[23904]: time="2025-11-06T04:47:17Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 06 04:47:17 volumio go-librespot[23904]: time="2025-11-06T04:47:17Z" level=info msg="zeroconf server listening on port 44907" Nov 06 04:47:17 volumio go-librespot[23904]: time="2025-11-06T04:47:17Z" level=debug msg="obtained new client token: AADyOsdY/OX3ngW/re1TyqSzDhds/7JpaFGs3BgIoSJC3/QTW+yE/Pj3QXyEiRyCaVAr+EjRCBj2g3InQ8O+xy1SRIt0Jq1Y1tv+l1g8MqzebmKf3orpOCvno5CewVDjHYIFEHJUmUzmqoDKUVKuK8HIR+h9WyAtxUABPxKs+fAhquH+1E7p4au75jqIat2GarBGKL4gi1rQKkPnxOG8DaHdK7YmEehe376+iqP3kTisCj6y3/swuF4=" Nov 06 04:47:17 volumio go-librespot[23904]: time="2025-11-06T04:47:17Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 06 04:47:18 volumio go-librespot[23904]: time="2025-11-06T04:47:18Z" level=debug msg="completed keyexchange" Nov 06 04:47:18 volumio go-librespot[23904]: time="2025-11-06T04:47:18Z" level=debug msg="completed challenge" Nov 06 04:47:18 volumio go-librespot[23904]: time="2025-11-06T04:47:18Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 06 04:47:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 04:47:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 04:47:18 volumio volumio[23489]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 06 04:47:18 volumio volumio[23489]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 06 04:47:18 volumio volumio[23489]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Nov 06 04:47:18 volumio volumio[23489]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Nov 06 04:47:18 volumio volumio[23489]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Nov 06 04:47:18 volumio volumio[23489]: info: CoreCommandRouter::volumioGetBrowseSources Nov 06 04:47:18 volumio volumio[23489]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 06 04:47:19 volumio volumio[23489]: error: MyVolumio Plugin failed to authenticate in a timely fashion Nov 06 04:47:19 volumio volumio[23489]: info: Completed starting MyVolumio Plugin Nov 06 04:47:19 volumio volumio[23489]: [Metrics] CommandRouter: 32s 32.07ms Nov 06 04:47:19 volumio volumio[23489]: info: CoreCommandRouter::volumiosetStartupVolume Nov 06 04:47:19 volumio volumio[23489]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 06 04:47:19 volumio volumio[23489]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 06 04:47:19 volumio volumio[23489]: info: CoreCommandRouter::Close All Modals sent Nov 06 04:47:19 volumio volumio[23489]: info: CoreCommandRouter::Close All Modals sent Nov 06 04:47:19 volumio volumio[23489]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 06 04:47:19 volumio volumio[23489]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 06 04:47:19 volumio volumio[23489]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 06 04:47:19 volumio volumio-remote-updater[874]: Test mode disabled Nov 06 04:47:19 volumio volumio-remote-updater[874]: Alpha mode disabled Nov 06 04:47:19 volumio volumio-remote-updater[874]: Alpha legacy test mode disabled Nov 06 04:47:19 volumio volumio[23489]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Nov 06 04:47:19 volumio volumio[23489]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Nov 06 04:47:19 volumio volumio[23489]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Nov 06 04:47:20 volumio volumio[23489]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Nov 06 04:47:20 volumio volumio[23489]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 06 04:47:20 volumio volumio[23489]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Nov 06 04:47:20 volumio volumio[23489]: info: Initializing connection to go-librespot Websocket Nov 06 04:47:20 volumio volumio[23489]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 04:47:20 volumio volumio[23489]: info: CoreCommandRouter::volumioGetState Nov 06 04:47:20 volumio volumio[23489]: info: CorePlayQueue::getTrack 0 Nov 06 04:47:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 407. Nov 06 04:47:21 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 04:47:21 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 04:47:21 volumio go-librespot[23920]: go-librespot daemon starting... Nov 06 04:47:21 volumio go-librespot[23921]: time="2025-11-06T04:47:21Z" level=info msg="running go-librespot 0.4.0" Nov 06 04:47:21 volumio go-librespot[23921]: time="2025-11-06T04:47:21Z" level=debug msg="app state loaded" Nov 06 04:47:21 volumio go-librespot[23921]: time="2025-11-06T04:47:21Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 04:47:21 volumio go-librespot[23921]: time="2025-11-06T04:47:21Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 06 04:47:21 volumio go-librespot[23921]: time="2025-11-06T04:47:21Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 06 04:47:21 volumio go-librespot[23921]: time="2025-11-06T04:47:21Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 06 04:47:21 volumio go-librespot[23921]: time="2025-11-06T04:47:21Z" level=info msg="zeroconf server listening on port 40473" Nov 06 04:47:21 volumio go-librespot[23921]: time="2025-11-06T04:47:21Z" level=debug msg="obtained new client token: AABiDSDG/9R28bO3Wn+Gi6mz8ZbRfPVdj+/aTye/XMv2OMPBVa2IpqSoiKHyQPvbCj299I9vPBNjxCGt8RnmEyepMrxtmLWxD3jDFVQlUV9HhmS2mWbLA+hpcha3wu9F2TElo2ijyqnRUIo5uZg6b5Hh1n6YnC8cc3fTyofvMxM4IwIflJyHPTq/ai0miaA8RqPogPKUz6l4LHHbuykxhSU9j2mx8wGl/pDKAVl5s13uBrFa72tUIPU=" Nov 06 04:47:21 volumio go-librespot[23921]: time="2025-11-06T04:47:21Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 06 04:47:21 volumio go-librespot[23921]: time="2025-11-06T04:47:21Z" level=debug msg="completed keyexchange" Nov 06 04:47:21 volumio go-librespot[23921]: time="2025-11-06T04:47:21Z" level=debug msg="completed challenge" Nov 06 04:47:21 volumio go-librespot[23921]: time="2025-11-06T04:47:21Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 06 04:47:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 04:47:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 04:47:23 volumio volumio[23489]: info: Initializing connection to go-librespot Websocket Nov 06 04:47:23 volumio volumio[23489]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 04:47:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 408. Nov 06 04:47:24 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 04:47:24 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 04:47:24 volumio go-librespot[23928]: go-librespot daemon starting... Nov 06 04:47:24 volumio go-librespot[23929]: time="2025-11-06T04:47:24Z" level=info msg="running go-librespot 0.4.0" Nov 06 04:47:24 volumio go-librespot[23929]: time="2025-11-06T04:47:24Z" level=debug msg="app state loaded" Nov 06 04:47:24 volumio go-librespot[23929]: time="2025-11-06T04:47:24Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 04:47:24 volumio go-librespot[23929]: time="2025-11-06T04:47:24Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 06 04:47:24 volumio go-librespot[23929]: time="2025-11-06T04:47:24Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 06 04:47:24 volumio go-librespot[23929]: time="2025-11-06T04:47:24Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 06 04:47:24 volumio go-librespot[23929]: time="2025-11-06T04:47:24Z" level=info msg="zeroconf server listening on port 36587" Nov 06 04:47:24 volumio go-librespot[23929]: time="2025-11-06T04:47:24Z" level=debug msg="obtained new client token: AABrGg7zr/PB992Muzucd7Jaw11iznzH57avpBEfxTOX4IzQxbJ+G9Tcq91g+AD8U0Fn5KE65000MD1ZL5S5NNXbr8Nr4Wigw4z3erSBbs79GIzgXmuTvra13y69WuCs4xisEwvzemDFvAwmJswUGHLudSA3UywwGQ7qP+7OxDnUlzRAwkHt8yt9CmEUvW8CrR4NCTgysxLpsj1d/JLpJ6SjYUq069NsAoTp9G6ZpWuPR3E+bf0wfH0=" Nov 06 04:47:24 volumio go-librespot[23929]: time="2025-11-06T04:47:24Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 06 04:47:24 volumio go-librespot[23929]: time="2025-11-06T04:47:24Z" level=debug msg="completed keyexchange" Nov 06 04:47:24 volumio go-librespot[23929]: time="2025-11-06T04:47:24Z" level=debug msg="completed challenge" Nov 06 04:47:25 volumio go-librespot[23929]: time="2025-11-06T04:47:25Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 06 04:47:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 04:47:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 04:47:25 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Nov 06 04:47:26 volumio volumio[23489]: info: BOOT COMPLETED Nov 06 04:47:26 volumio volumio[23489]: info: Initializing connection to go-librespot Websocket Nov 06 04:47:26 volumio volumio[23489]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 04:47:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 409. Nov 06 04:47:28 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 04:47:28 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 04:47:28 volumio go-librespot[23950]: go-librespot daemon starting... Nov 06 04:47:28 volumio go-librespot[23951]: time="2025-11-06T04:47:28Z" level=info msg="running go-librespot 0.4.0" Nov 06 04:47:28 volumio go-librespot[23951]: time="2025-11-06T04:47:28Z" level=debug msg="app state loaded" Nov 06 04:47:28 volumio go-librespot[23951]: time="2025-11-06T04:47:28Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 04:47:28 volumio go-librespot[23951]: time="2025-11-06T04:47:28Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Nov 06 04:47:28 volumio go-librespot[23951]: time="2025-11-06T04:47:28Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Nov 06 04:47:28 volumio go-librespot[23951]: time="2025-11-06T04:47:28Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Nov 06 04:47:28 volumio go-librespot[23951]: time="2025-11-06T04:47:28Z" level=info msg="zeroconf server listening on port 37001" Nov 06 04:47:28 volumio go-librespot[23951]: time="2025-11-06T04:47:28Z" level=debug msg="obtained new client token: AAAJ5v6bH6Y0hH4YP3xEx/FcIk0UGlunIfBWkkok87GXZn/wHTjFo8h5llXeBnoQMjIjaGoqfaQtTpn2C/4NAfNjQGNwK2DdeiZkWX/i6tFUnw/AahUQkBxlzoSNFSkiCTQ1bblBtKwHJ2ywej+6gGtKKPOWvrbyYMB3FnlL2fj9VRR9Ov32+XXulZDX+tc0bh5jMs17W9fet38si8IsUtETDeypYCj4WJob/w8F7EN/3esAPM1Hd1c=" Nov 06 04:47:28 volumio go-librespot[23951]: time="2025-11-06T04:47:28Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 06 04:47:28 volumio go-librespot[23951]: time="2025-11-06T04:47:28Z" level=debug msg="completed keyexchange" Nov 06 04:47:28 volumio go-librespot[23951]: time="2025-11-06T04:47:28Z" level=debug msg="completed challenge" Nov 06 04:47:28 volumio go-librespot[23951]: time="2025-11-06T04:47:28Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 06 04:47:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 04:47:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 04:47:29 volumio volumio[23489]: info: Initializing connection to go-librespot Websocket Nov 06 04:47:29 volumio volumio[23489]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 04:47:30 volumio volumio[23489]: info: CoreCommandRouter::volumioGetState Nov 06 04:47:30 volumio volumio[23489]: info: CorePlayQueue::getTrack 0 Nov 06 04:47:30 volumio volumio[23489]: info: Listing playlists Nov 06 04:47:30 volumio volumio[23489]: info: Listing playlists Nov 06 04:47:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 410. Nov 06 04:47:31 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 04:47:31 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 04:47:31 volumio go-librespot[23960]: go-librespot daemon starting... Nov 06 04:47:31 volumio go-librespot[23961]: time="2025-11-06T04:47:31Z" level=info msg="running go-librespot 0.4.0" Nov 06 04:47:31 volumio go-librespot[23961]: time="2025-11-06T04:47:31Z" level=debug msg="app state loaded" Nov 06 04:47:31 volumio go-librespot[23961]: time="2025-11-06T04:47:31Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 04:47:31 volumio go-librespot[23961]: time="2025-11-06T04:47:31Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 06 04:47:31 volumio go-librespot[23961]: time="2025-11-06T04:47:31Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 06 04:47:31 volumio go-librespot[23961]: time="2025-11-06T04:47:31Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 06 04:47:31 volumio go-librespot[23961]: time="2025-11-06T04:47:31Z" level=info msg="zeroconf server listening on port 42609" Nov 06 04:47:31 volumio go-librespot[23961]: time="2025-11-06T04:47:31Z" level=debug msg="obtained new client token: AAAUmKY7L0hgkynk5uYhAvkV5zbZJ8Uzcp/5c1M3XxrWRGrR7YuTBiwdymkZlAssLM01nAP2VeuwEMZkTamxWmykYJjie8VN1Qh8dMMLZSa7vBTuWVogkIK0O+GsbkjNo4QkvIJ2Du6qSF82gf1tCDydAeiRFos0sQ0FzauId7DJNsp2AJrZYfsvJbPhWvr4f3mkEsdQ9trVE24kOBZbw889RmxcSEVAH0yG7LjKUJUDnZci8Xq9tt4=" Nov 06 04:47:31 volumio go-librespot[23961]: time="2025-11-06T04:47:31Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 06 04:47:32 volumio go-librespot[23961]: time="2025-11-06T04:47:32Z" level=debug msg="completed keyexchange" Nov 06 04:47:32 volumio go-librespot[23961]: time="2025-11-06T04:47:32Z" level=debug msg="completed challenge" Nov 06 04:47:32 volumio go-librespot[23961]: time="2025-11-06T04:47:32Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 06 04:47:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 04:47:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 04:47:32 volumio volumio[23489]: info: Initializing connection to go-librespot Websocket Nov 06 04:47:32 volumio volumio[23489]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 04:47:35 volumio volumio[23489]: info: Initializing connection to go-librespot Websocket Nov 06 04:47:35 volumio volumio[23489]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 04:47:35 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 411. Nov 06 04:47:35 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 04:47:35 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 04:47:35 volumio go-librespot[23971]: go-librespot daemon starting... Nov 06 04:47:35 volumio go-librespot[23972]: time="2025-11-06T04:47:35Z" level=info msg="running go-librespot 0.4.0" Nov 06 04:47:35 volumio go-librespot[23972]: time="2025-11-06T04:47:35Z" level=debug msg="app state loaded" Nov 06 04:47:35 volumio go-librespot[23972]: time="2025-11-06T04:47:35Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 04:47:35 volumio go-librespot[23972]: time="2025-11-06T04:47:35Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Nov 06 04:47:35 volumio go-librespot[23972]: time="2025-11-06T04:47:35Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Nov 06 04:47:35 volumio go-librespot[23972]: time="2025-11-06T04:47:35Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Nov 06 04:47:35 volumio go-librespot[23972]: time="2025-11-06T04:47:35Z" level=info msg="zeroconf server listening on port 40937" Nov 06 04:47:35 volumio go-librespot[23972]: time="2025-11-06T04:47:35Z" level=debug msg="obtained new client token: AACZLI6EQaDgKGTQAiHQvs0sLlWuN7H1sjM630u7tvzLIJ5gYymK19TaGe9OOymmAVuOnkolhL0p8zkwBiN/lbiaUiaDDvfD49KGDCVBOYfKEG9ox7LBzdY8PaR9iv6oZ1x4BZzEo2nTPpbaVtZPpGjhU+pJBeciURcvegahu/I9Fz7Uc1xy+GeNukk1pedcsxl6K1mp6QoeAH+6YOMyaQFYBYFR3h/KyB3oby3LuC/caRyYUSUqRSc=" Nov 06 04:47:35 volumio go-librespot[23972]: time="2025-11-06T04:47:35Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 06 04:47:35 volumio go-librespot[23972]: time="2025-11-06T04:47:35Z" level=debug msg="completed keyexchange" Nov 06 04:47:35 volumio go-librespot[23972]: time="2025-11-06T04:47:35Z" level=debug msg="completed challenge" Nov 06 04:47:35 volumio go-librespot[23972]: time="2025-11-06T04:47:35Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 06 04:47:35 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 04:47:35 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 04:47:35 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Nov 06 04:47:38 volumio volumio[23489]: info: Initializing connection to go-librespot Websocket Nov 06 04:47:38 volumio volumio[23489]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 04:47:38 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 412. Nov 06 04:47:38 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 04:47:38 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 04:47:38 volumio go-librespot[23993]: go-librespot daemon starting... Nov 06 04:47:38 volumio go-librespot[23994]: time="2025-11-06T04:47:38Z" level=info msg="running go-librespot 0.4.0" Nov 06 04:47:38 volumio go-librespot[23994]: time="2025-11-06T04:47:38Z" level=debug msg="app state loaded" Nov 06 04:47:38 volumio go-librespot[23994]: time="2025-11-06T04:47:38Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 04:47:38 volumio go-librespot[23994]: time="2025-11-06T04:47:38Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 06 04:47:38 volumio go-librespot[23994]: time="2025-11-06T04:47:38Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 06 04:47:38 volumio go-librespot[23994]: time="2025-11-06T04:47:38Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 06 04:47:38 volumio go-librespot[23994]: time="2025-11-06T04:47:38Z" level=info msg="zeroconf server listening on port 37153" Nov 06 04:47:38 volumio go-librespot[23994]: time="2025-11-06T04:47:38Z" level=debug msg="obtained new client token: AACQZL8dKCJG0Rq5fUmi93EvdFKKZb7YFqRtyOAjUNxNsYgDi6dTsIxAi+t9K/MHWnTzwYCv25kGhsNOlz3lWyne0Jj8PEsQaHpiegc67eJ0cUz/R3JPh0Y7CGoUlF02/mgMmsRISvCJkAamw4iVn8sutEhvT1OzImqclXZx25B/+FZ6kKbVT6rvu40EZZgaJKJoTOehCbZ7enA/j+BZcvie5XvLSL36ISR4j3pWOW6cmRwK1ZCGaY8=" Nov 06 04:47:38 volumio go-librespot[23994]: time="2025-11-06T04:47:38Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 06 04:47:38 volumio go-librespot[23994]: time="2025-11-06T04:47:38Z" level=debug msg="completed keyexchange" Nov 06 04:47:38 volumio go-librespot[23994]: time="2025-11-06T04:47:38Z" level=debug msg="completed challenge" Nov 06 04:47:39 volumio go-librespot[23994]: time="2025-11-06T04:47:39Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 06 04:47:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 04:47:39 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 04:47:40 volumio volumio[23489]: info: CoreCommandRouter::volumioGetState Nov 06 04:47:40 volumio volumio[23489]: info: CorePlayQueue::getTrack 0 Nov 06 04:47:41 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Nov 06 04:47:41 volumio volumio[23489]: info: Initializing connection to go-librespot Websocket Nov 06 04:47:41 volumio volumio[23489]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 04:47:42 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 413. Nov 06 04:47:42 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 04:47:42 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 04:47:42 volumio go-librespot[24001]: go-librespot daemon starting... Nov 06 04:47:42 volumio go-librespot[24002]: time="2025-11-06T04:47:42Z" level=info msg="running go-librespot 0.4.0" Nov 06 04:47:42 volumio go-librespot[24002]: time="2025-11-06T04:47:42Z" level=debug msg="app state loaded" Nov 06 04:47:42 volumio go-librespot[24002]: time="2025-11-06T04:47:42Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 04:47:42 volumio go-librespot[24002]: time="2025-11-06T04:47:42Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 06 04:47:42 volumio go-librespot[24002]: time="2025-11-06T04:47:42Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 06 04:47:42 volumio go-librespot[24002]: time="2025-11-06T04:47:42Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 06 04:47:42 volumio go-librespot[24002]: time="2025-11-06T04:47:42Z" level=info msg="zeroconf server listening on port 44479" Nov 06 04:47:42 volumio go-librespot[24002]: time="2025-11-06T04:47:42Z" level=debug msg="obtained new client token: AACmyh0gtC3cEM6jc3BrWqo93losMiA1ab531+X4qm+o+fm3j+w+Uc6hVLu0ylSmXOatFAaDO/GGqF+mwAToVmWDmm/Zcn+DxWYRmb/Yv80Yg5wpaOfz1BCYqljvlnQHerMcaSfdCYks6bAZF7TcffvA3zJ4CviL7guIX5Vmg5C2t34L06gLZ9x8VooWwyWLZcdSrNztwMO4o1fN0QFwpOdJtMwHFfcRDsJ+k+erOyp6MNRRhp47UxY=" Nov 06 04:47:42 volumio go-librespot[24002]: time="2025-11-06T04:47:42Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 06 04:47:42 volumio go-librespot[24002]: time="2025-11-06T04:47:42Z" level=debug msg="completed keyexchange" Nov 06 04:47:42 volumio go-librespot[24002]: time="2025-11-06T04:47:42Z" level=debug msg="completed challenge" Nov 06 04:47:42 volumio go-librespot[24002]: time="2025-11-06T04:47:42Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 06 04:47:42 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 04:47:42 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 04:47:44 volumio volumio[23489]: info: Initializing connection to go-librespot Websocket Nov 06 04:47:44 volumio volumio[23489]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 04:47:45 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 414. Nov 06 04:47:45 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 04:47:45 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 04:47:45 volumio go-librespot[24009]: go-librespot daemon starting... Nov 06 04:47:45 volumio go-librespot[24010]: time="2025-11-06T04:47:45Z" level=info msg="running go-librespot 0.4.0" Nov 06 04:47:45 volumio go-librespot[24010]: time="2025-11-06T04:47:45Z" level=debug msg="app state loaded" Nov 06 04:47:45 volumio go-librespot[24010]: time="2025-11-06T04:47:45Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 04:47:45 volumio go-librespot[24010]: time="2025-11-06T04:47:45Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Nov 06 04:47:45 volumio go-librespot[24010]: time="2025-11-06T04:47:45Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Nov 06 04:47:45 volumio go-librespot[24010]: time="2025-11-06T04:47:45Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Nov 06 04:47:45 volumio go-librespot[24010]: time="2025-11-06T04:47:45Z" level=info msg="zeroconf server listening on port 34083" Nov 06 04:47:45 volumio go-librespot[24010]: time="2025-11-06T04:47:45Z" level=debug msg="obtained new client token: AACAIXq4BRInGANvXn1Ej5BEMzbVBGXjrXpfDkY+xdkORTPDSPqViY58jXQnbI9hge9uAKOZnll5e14zcC4TdJF2q/QFDugHzj7obVpWv6QxRjsmYvsNTaCxIAdwjngjADWiMh7h1QOZ92nSID/u1MBbe8/MPfEjrFfxqd6PgNFfjoElMIoCOGqtsz8NCaHVw70gByjOodHu0Ncio7PAsjw4bIqjSsWkYpOjuWTgnuP4NvJgmVOIMFM=" Nov 06 04:47:45 volumio go-librespot[24010]: time="2025-11-06T04:47:45Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 06 04:47:46 volumio go-librespot[24010]: time="2025-11-06T04:47:46Z" level=debug msg="completed keyexchange" Nov 06 04:47:46 volumio go-librespot[24010]: time="2025-11-06T04:47:46Z" level=debug msg="completed challenge" Nov 06 04:47:46 volumio go-librespot[24010]: time="2025-11-06T04:47:46Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 06 04:47:46 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 04:47:46 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 04:47:47 volumio volumio[23489]: info: Initializing connection to go-librespot Websocket Nov 06 04:47:47 volumio volumio[23489]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 04:47:48 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Nov 06 04:47:49 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 415. Nov 06 04:47:49 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 04:47:49 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 04:47:49 volumio go-librespot[24031]: go-librespot daemon starting... Nov 06 04:47:49 volumio go-librespot[24032]: time="2025-11-06T04:47:49Z" level=info msg="running go-librespot 0.4.0" Nov 06 04:47:49 volumio go-librespot[24032]: time="2025-11-06T04:47:49Z" level=debug msg="app state loaded" Nov 06 04:47:49 volumio go-librespot[24032]: time="2025-11-06T04:47:49Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 04:47:49 volumio go-librespot[24032]: time="2025-11-06T04:47:49Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 06 04:47:49 volumio go-librespot[24032]: time="2025-11-06T04:47:49Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 06 04:47:49 volumio go-librespot[24032]: time="2025-11-06T04:47:49Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 06 04:47:49 volumio go-librespot[24032]: time="2025-11-06T04:47:49Z" level=info msg="zeroconf server listening on port 40405" Nov 06 04:47:49 volumio go-librespot[24032]: time="2025-11-06T04:47:49Z" level=debug msg="obtained new client token: AADMZxlbNWCYQHJZcMRbQ+dW2pUXNbxCQVtB4Ke+mJWgMTvS8Sr6Ur5fNU5phmdiB6XNRkp3rhVfWPMcbEP5WRQXp3v9ddIIaLrqjOrYm39+mocXfF7RC4QRdNlDkfW51XG38ssOd+AESVthCYe3SIjRbY8lUdmd403IVM0WXY222HLYL54o0OF8zGc03Z/j7zpR63XYr+SwzV+758GW9wxmen61cPkTc1aAlbTgLPmkysIjCve4D+Y=" Nov 06 04:47:49 volumio go-librespot[24032]: time="2025-11-06T04:47:49Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 06 04:47:49 volumio go-librespot[24032]: time="2025-11-06T04:47:49Z" level=debug msg="completed keyexchange" Nov 06 04:47:49 volumio go-librespot[24032]: time="2025-11-06T04:47:49Z" level=debug msg="completed challenge" Nov 06 04:47:49 volumio go-librespot[24032]: time="2025-11-06T04:47:49Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 06 04:47:49 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 04:47:49 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 04:47:50 volumio volumio[23489]: info: Initializing connection to go-librespot Websocket Nov 06 04:47:50 volumio volumio[23489]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 04:47:50 volumio volumio[23489]: info: CoreCommandRouter::volumioGetState Nov 06 04:47:50 volumio volumio[23489]: info: CorePlayQueue::getTrack 0 Nov 06 04:47:50 volumio volumio[23489]: info: Listing playlists Nov 06 04:47:50 volumio volumio[23489]: info: Listing playlists Nov 06 04:47:52 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 416. Nov 06 04:47:52 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 04:47:52 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 04:47:52 volumio go-librespot[24040]: go-librespot daemon starting... Nov 06 04:47:52 volumio go-librespot[24041]: time="2025-11-06T04:47:52Z" level=info msg="running go-librespot 0.4.0" Nov 06 04:47:52 volumio go-librespot[24041]: time="2025-11-06T04:47:52Z" level=debug msg="app state loaded" Nov 06 04:47:52 volumio go-librespot[24041]: time="2025-11-06T04:47:52Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 04:47:52 volumio go-librespot[24041]: time="2025-11-06T04:47:52Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Nov 06 04:47:52 volumio go-librespot[24041]: time="2025-11-06T04:47:52Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Nov 06 04:47:52 volumio go-librespot[24041]: time="2025-11-06T04:47:52Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Nov 06 04:47:52 volumio go-librespot[24041]: time="2025-11-06T04:47:52Z" level=info msg="zeroconf server listening on port 46723" Nov 06 04:47:52 volumio go-librespot[24041]: time="2025-11-06T04:47:52Z" level=debug msg="obtained new client token: AAD5v1DnL5952bAIpD0wK5cQQSmf11FYcazSlUW563VK/uwtlhk8t+gnkuQ3K7QgF4D/3rqhMPzTmWexDR9P79+uQvAxr4Wpvp1W/QLb3DRXQvv42r43cKJXNvl4E6cDroX9hUDj/B2+XFWsqVCAlC5cHrjpgPwsZV2EjQTycuo3yY9DK8u77xphEb1V0SCBjX3o9V4SfJ6emnPhCH/8wi3xViybJjyGT2LGl6WBQtOWEjCnRhLKr7k=" Nov 06 04:47:52 volumio go-librespot[24041]: time="2025-11-06T04:47:52Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 06 04:47:53 volumio go-librespot[24041]: time="2025-11-06T04:47:53Z" level=debug msg="completed keyexchange" Nov 06 04:47:53 volumio go-librespot[24041]: time="2025-11-06T04:47:53Z" level=debug msg="completed challenge" Nov 06 04:47:53 volumio go-librespot[24041]: time="2025-11-06T04:47:53Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 06 04:47:53 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 04:47:53 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 04:47:53 volumio volumio[23489]: info: Initializing connection to go-librespot Websocket Nov 06 04:47:53 volumio volumio[23489]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 04:47:56 volumio volumio[23489]: info: Initializing connection to go-librespot Websocket Nov 06 04:47:56 volumio volumio[23489]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 04:47:56 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 417. Nov 06 04:47:56 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 04:47:56 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 04:47:56 volumio go-librespot[24051]: go-librespot daemon starting... Nov 06 04:47:56 volumio go-librespot[24052]: time="2025-11-06T04:47:56Z" level=info msg="running go-librespot 0.4.0" Nov 06 04:47:56 volumio go-librespot[24052]: time="2025-11-06T04:47:56Z" level=debug msg="app state loaded" Nov 06 04:47:56 volumio go-librespot[24052]: time="2025-11-06T04:47:56Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 04:47:56 volumio go-librespot[24052]: time="2025-11-06T04:47:56Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 06 04:47:56 volumio go-librespot[24052]: time="2025-11-06T04:47:56Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 06 04:47:56 volumio go-librespot[24052]: time="2025-11-06T04:47:56Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 06 04:47:56 volumio go-librespot[24052]: time="2025-11-06T04:47:56Z" level=info msg="zeroconf server listening on port 43623" Nov 06 04:47:56 volumio go-librespot[24052]: time="2025-11-06T04:47:56Z" level=debug msg="obtained new client token: AAChjEn9wLbrGi4idC6aGCuSPRrJ+aSM135DujgIPO6cA0+8DtK0fLRiZhztrOaZISgbeiU+R/2MWamWHvtINrtQG2pp//RAGeksEa/k5nNN2vSmnaWJpKrvUiDQxH5YUskfOvlSzxBV7SdEQDJTjkDeYffZ2BweRpcYULOkSHOrAs8EbgZ6w6gf/VaGtjnPWKVBGKDS1cxlMp3JZ5SJAaqZcyhdKczzmUvQmKgUsvwaphhUnQ4HvXw=" Nov 06 04:47:56 volumio go-librespot[24052]: time="2025-11-06T04:47:56Z" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.65.9:4070: connect: connection refused" Nov 06 04:47:56 volumio go-librespot[24052]: time="2025-11-06T04:47:56Z" level=debug msg="connected to ap-gew1.spotify.com:443" Nov 06 04:47:56 volumio go-librespot[24052]: time="2025-11-06T04:47:56Z" level=debug msg="completed keyexchange" Nov 06 04:47:56 volumio go-librespot[24052]: time="2025-11-06T04:47:56Z" level=debug msg="completed challenge" Nov 06 04:47:56 volumio go-librespot[24052]: time="2025-11-06T04:47:56Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 06 04:47:56 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 04:47:56 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 04:47:59 volumio volumio[23489]: info: Initializing connection to go-librespot Websocket Nov 06 04:47:59 volumio volumio[23489]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 04:47:59 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 418. Nov 06 04:47:59 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 04:47:59 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 04:47:59 volumio go-librespot[24074]: go-librespot daemon starting... Nov 06 04:47:59 volumio go-librespot[24075]: time="2025-11-06T04:47:59Z" level=info msg="running go-librespot 0.4.0" Nov 06 04:47:59 volumio go-librespot[24075]: time="2025-11-06T04:47:59Z" level=debug msg="app state loaded" Nov 06 04:47:59 volumio go-librespot[24075]: time="2025-11-06T04:47:59Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 04:47:59 volumio go-librespot[24075]: time="2025-11-06T04:47:59Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 06 04:47:59 volumio go-librespot[24075]: time="2025-11-06T04:47:59Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 06 04:47:59 volumio go-librespot[24075]: time="2025-11-06T04:47:59Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 06 04:47:59 volumio go-librespot[24075]: time="2025-11-06T04:47:59Z" level=info msg="zeroconf server listening on port 41895" Nov 06 04:47:59 volumio go-librespot[24075]: time="2025-11-06T04:47:59Z" level=debug msg="obtained new client token: AABbzRqrR1RMAe5+9EXEaZdb3Q8M+5Vs+L4z5641ackyE4n0i4J3Ssa8PSP2gfeQOJPFSXWUDzub2HSM6/m7MCr7299GPKJ+LWTeCXAjMX6q/fHCdNKBSIbwA2u3n7ZryjkWZFUoM2xCeAcxek4vGoLgJS0LfLEsHYmKzWcgqCmxhK8vTnlvD4HYZB4JX9xh/AK9Fvfs8rfCOPujpE5ekkKCsJzUcy62ebNfY+Wm2xKaGf0Lr5eYC5s=" Nov 06 04:47:59 volumio go-librespot[24075]: time="2025-11-06T04:47:59Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 06 04:48:00 volumio go-librespot[24075]: time="2025-11-06T04:48:00Z" level=debug msg="completed keyexchange" Nov 06 04:48:00 volumio go-librespot[24075]: time="2025-11-06T04:48:00Z" level=debug msg="completed challenge" Nov 06 04:48:00 volumio go-librespot[24075]: time="2025-11-06T04:48:00Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 06 04:48:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 04:48:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 04:48:00 volumio volumio[23489]: info: CoreCommandRouter::volumioGetState Nov 06 04:48:00 volumio volumio[23489]: info: CorePlayQueue::getTrack 0 Nov 06 04:48:02 volumio volumio[23489]: info: Initializing connection to go-librespot Websocket Nov 06 04:48:02 volumio volumio[23489]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 04:48:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 419. Nov 06 04:48:03 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 04:48:03 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 04:48:03 volumio go-librespot[24082]: go-librespot daemon starting... Nov 06 04:48:03 volumio go-librespot[24083]: time="2025-11-06T04:48:03Z" level=info msg="running go-librespot 0.4.0" Nov 06 04:48:03 volumio go-librespot[24083]: time="2025-11-06T04:48:03Z" level=debug msg="app state loaded" Nov 06 04:48:03 volumio go-librespot[24083]: time="2025-11-06T04:48:03Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 04:48:03 volumio go-librespot[24083]: time="2025-11-06T04:48:03Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 06 04:48:03 volumio go-librespot[24083]: time="2025-11-06T04:48:03Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 06 04:48:03 volumio go-librespot[24083]: time="2025-11-06T04:48:03Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 06 04:48:03 volumio go-librespot[24083]: time="2025-11-06T04:48:03Z" level=info msg="zeroconf server listening on port 46583" Nov 06 04:48:03 volumio go-librespot[24083]: time="2025-11-06T04:48:03Z" level=debug msg="obtained new client token: AAAse0wpoKfXD1IZTKhWs6xqFSgeavlZlVrrA4c5gR8E+FxQOa3ZWaY+To33OpGD2I6rtEyTKKeYQlhd7lL+kRw+z3oRl6+PBL3Lg39U+sRkpB539Jt5Sxw3/JESla1FqwAjz/PvkxseidAYDi1MjomLKmWaKu+ULBfuJ4UNWMt/XAVzVE70BsPQk7p+A21sDND7ChwGH2O7mrcN47hPy2FEKgT55Sq2u+22NdWK8zj/PLh3lj9jVew=" Nov 06 04:48:03 volumio go-librespot[24083]: time="2025-11-06T04:48:03Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 06 04:48:03 volumio go-librespot[24083]: time="2025-11-06T04:48:03Z" level=debug msg="completed keyexchange" Nov 06 04:48:03 volumio go-librespot[24083]: time="2025-11-06T04:48:03Z" level=debug msg="completed challenge" Nov 06 04:48:03 volumio go-librespot[24083]: time="2025-11-06T04:48:03Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 06 04:48:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 04:48:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 04:48:04 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Nov 06 04:48:05 volumio volumio[23489]: info: Initializing connection to go-librespot Websocket Nov 06 04:48:05 volumio volumio[23489]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 04:48:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 420. Nov 06 04:48:06 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 04:48:06 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 04:48:06 volumio go-librespot[24090]: go-librespot daemon starting... Nov 06 04:48:06 volumio go-librespot[24091]: time="2025-11-06T04:48:06Z" level=info msg="running go-librespot 0.4.0" Nov 06 04:48:06 volumio go-librespot[24091]: time="2025-11-06T04:48:06Z" level=debug msg="app state loaded" Nov 06 04:48:06 volumio go-librespot[24091]: time="2025-11-06T04:48:06Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 04:48:06 volumio go-librespot[24091]: time="2025-11-06T04:48:06Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 06 04:48:06 volumio go-librespot[24091]: time="2025-11-06T04:48:06Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 06 04:48:06 volumio go-librespot[24091]: time="2025-11-06T04:48:06Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 06 04:48:06 volumio go-librespot[24091]: time="2025-11-06T04:48:06Z" level=info msg="zeroconf server listening on port 39287" Nov 06 04:48:06 volumio go-librespot[24091]: time="2025-11-06T04:48:06Z" level=debug msg="obtained new client token: AAC2/n2+P8M0yNPHrdVWMVdnkWKjUiVL9xTsDvD2WeFZCwgnTl69xFgDlTOkxREutVt2k4tkjPikeYsYIHWYtVkVpJ9AQ91H+znGHOhbvAE76vISG01Q+LC+BPkagW6gB5iSZOC4ddntOemd6ahoLWMEq1zItJnidjR1n/MSJvIGthTQoiBxpfnpuC72U3l0NRLIbIVJiKHMu12b3O/JZrHmAL90iNsnRH8WIZPIUm5ENJUWxqZbFE8=" Nov 06 04:48:06 volumio go-librespot[24091]: time="2025-11-06T04:48:06Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 06 04:48:07 volumio go-librespot[24091]: time="2025-11-06T04:48:07Z" level=debug msg="completed keyexchange" Nov 06 04:48:07 volumio go-librespot[24091]: time="2025-11-06T04:48:07Z" level=debug msg="completed challenge" Nov 06 04:48:07 volumio go-librespot[24091]: time="2025-11-06T04:48:07Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 06 04:48:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 04:48:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 04:48:08 volumio volumio[23489]: info: Initializing connection to go-librespot Websocket Nov 06 04:48:08 volumio volumio[23489]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 04:48:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 421. Nov 06 04:48:10 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 04:48:10 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 04:48:10 volumio go-librespot[24114]: go-librespot daemon starting... Nov 06 04:48:10 volumio go-librespot[24115]: time="2025-11-06T04:48:10Z" level=info msg="running go-librespot 0.4.0" Nov 06 04:48:10 volumio go-librespot[24115]: time="2025-11-06T04:48:10Z" level=debug msg="app state loaded" Nov 06 04:48:10 volumio go-librespot[24115]: time="2025-11-06T04:48:10Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 04:48:10 volumio go-librespot[24115]: time="2025-11-06T04:48:10Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 06 04:48:10 volumio go-librespot[24115]: time="2025-11-06T04:48:10Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 06 04:48:10 volumio go-librespot[24115]: time="2025-11-06T04:48:10Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 06 04:48:10 volumio go-librespot[24115]: time="2025-11-06T04:48:10Z" level=info msg="zeroconf server listening on port 46711" Nov 06 04:48:10 volumio go-librespot[24115]: time="2025-11-06T04:48:10Z" level=debug msg="obtained new client token: AAArVrNLwneGneVY6pzAt2EjhUyxlNRJcMkUHgGkNbRKMju8VyesPprIcUWY1Q+evyfEEY8Yq5UF/ctSzH3+Ua/NCUUeD+1RJ/rKLZh2Zsos0+ewS/yfIIMkEghSH3/3lqXOLXtxozvDCHegYfVcRzUWoIxPG+q5vrKF1PbwbGAn/heTrTSmF+HyoaKn/qW0KFbmC8P7cb+LfWhiyWsqTgHGvSDSQEpgt76g1bdOqs7Qv+HN49sVtWY=" Nov 06 04:48:10 volumio go-librespot[24115]: time="2025-11-06T04:48:10Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 06 04:48:10 volumio go-librespot[24115]: time="2025-11-06T04:48:10Z" level=debug msg="completed keyexchange" Nov 06 04:48:10 volumio go-librespot[24115]: time="2025-11-06T04:48:10Z" level=debug msg="completed challenge" Nov 06 04:48:10 volumio go-librespot[24115]: time="2025-11-06T04:48:10Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 06 04:48:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 04:48:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 04:48:10 volumio volumio[23489]: info: CoreCommandRouter::volumioGetState Nov 06 04:48:10 volumio volumio[23489]: info: CorePlayQueue::getTrack 0 Nov 06 04:48:10 volumio volumio[23489]: info: Listing playlists Nov 06 04:48:10 volumio volumio[23489]: info: Listing playlists Nov 06 04:48:11 volumio volumio[23489]: info: Initializing connection to go-librespot Websocket Nov 06 04:48:11 volumio volumio[23489]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 04:48:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 422. Nov 06 04:48:13 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 04:48:13 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 04:48:13 volumio go-librespot[24126]: go-librespot daemon starting... Nov 06 04:48:13 volumio go-librespot[24127]: time="2025-11-06T04:48:13Z" level=info msg="running go-librespot 0.4.0" Nov 06 04:48:13 volumio go-librespot[24127]: time="2025-11-06T04:48:13Z" level=debug msg="app state loaded" Nov 06 04:48:13 volumio go-librespot[24127]: time="2025-11-06T04:48:13Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 04:48:13 volumio go-librespot[24127]: time="2025-11-06T04:48:13Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 06 04:48:13 volumio go-librespot[24127]: time="2025-11-06T04:48:13Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 06 04:48:13 volumio go-librespot[24127]: time="2025-11-06T04:48:13Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 06 04:48:13 volumio go-librespot[24127]: time="2025-11-06T04:48:13Z" level=info msg="zeroconf server listening on port 40241" Nov 06 04:48:13 volumio go-librespot[24127]: time="2025-11-06T04:48:13Z" level=debug msg="obtained new client token: AAAVCruqC4aSoyvIKJM8sJBZPZrK0BsN5X9kf7g+qJOqPwbtl9uhCuVd1DQQEVj4QLI6vl3eeKx/hensmOyth2ydvUI/gh2990uLZaC4dGU0WpeTbPd5UYjjuJo6wogyIrGctNE9dmpwhacUcgdUsW/d80kKV2AEdCtWkhE5ESWw5tkJNr+ugnMk6YiUh6EzvW8a/NrWKL25nl55RjxD8FzrKX0geynxvbknbVmQrOypV23lVn5eBfU=" Nov 06 04:48:13 volumio go-librespot[24127]: time="2025-11-06T04:48:13Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 06 04:48:14 volumio go-librespot[24127]: time="2025-11-06T04:48:14Z" level=debug msg="completed keyexchange" Nov 06 04:48:14 volumio go-librespot[24127]: time="2025-11-06T04:48:14Z" level=debug msg="completed challenge" Nov 06 04:48:14 volumio go-librespot[24127]: time="2025-11-06T04:48:14Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 06 04:48:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 04:48:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 04:48:14 volumio volumio[23489]: info: Initializing connection to go-librespot Websocket Nov 06 04:48:14 volumio volumio[23489]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 04:48:14 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Nov 06 04:48:17 volumio volumio[23489]: info: Initializing connection to go-librespot Websocket Nov 06 04:48:17 volumio volumio[23489]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 04:48:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 423. Nov 06 04:48:17 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 04:48:17 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 04:48:17 volumio go-librespot[24134]: go-librespot daemon starting... Nov 06 04:48:17 volumio go-librespot[24135]: time="2025-11-06T04:48:17Z" level=info msg="running go-librespot 0.4.0" Nov 06 04:48:17 volumio go-librespot[24135]: time="2025-11-06T04:48:17Z" level=debug msg="app state loaded" Nov 06 04:48:17 volumio go-librespot[24135]: time="2025-11-06T04:48:17Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 04:48:17 volumio go-librespot[24135]: time="2025-11-06T04:48:17Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 06 04:48:17 volumio go-librespot[24135]: time="2025-11-06T04:48:17Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 06 04:48:17 volumio go-librespot[24135]: time="2025-11-06T04:48:17Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 06 04:48:17 volumio go-librespot[24135]: time="2025-11-06T04:48:17Z" level=info msg="zeroconf server listening on port 34253" Nov 06 04:48:17 volumio go-librespot[24135]: time="2025-11-06T04:48:17Z" level=debug msg="obtained new client token: AAAid9Q5cDhQnajT5X+xfabKDntObOYuhaXKz1ICigDWHrdGhj4qQYAG84KjrIDNtEzjO6/weqML9KsKyiVP8DwhFykCKtikXwEa2UsqaQbJgIcpcc+2BA/+0DjSksUirlZEHQnxCUFjyibjdsSunb7cUrY6eH8h5miIURUIa22OoaxZxtBtGRPZ73fC12g/KbPSDB/kWwQ3aLgSx2a2nwmJQzTNsRFC+0mlpiYoiZOPh2br8FPJ0do=" Nov 06 04:48:17 volumio go-librespot[24135]: time="2025-11-06T04:48:17Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 06 04:48:17 volumio go-librespot[24135]: time="2025-11-06T04:48:17Z" level=debug msg="completed keyexchange" Nov 06 04:48:17 volumio go-librespot[24135]: time="2025-11-06T04:48:17Z" level=debug msg="completed challenge" Nov 06 04:48:17 volumio go-librespot[24135]: time="2025-11-06T04:48:17Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 06 04:48:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 04:48:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 04:48:20 volumio volumio[23489]: info: Initializing connection to go-librespot Websocket Nov 06 04:48:20 volumio volumio[23489]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 04:48:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 424. Nov 06 04:48:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 04:48:20 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 04:48:20 volumio go-librespot[24156]: go-librespot daemon starting... Nov 06 04:48:20 volumio go-librespot[24157]: time="2025-11-06T04:48:20Z" level=info msg="running go-librespot 0.4.0" Nov 06 04:48:20 volumio go-librespot[24157]: time="2025-11-06T04:48:20Z" level=debug msg="app state loaded" Nov 06 04:48:20 volumio go-librespot[24157]: time="2025-11-06T04:48:20Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 04:48:20 volumio volumio[23489]: info: CoreCommandRouter::volumioGetState Nov 06 04:48:20 volumio volumio[23489]: info: CorePlayQueue::getTrack 0 Nov 06 04:48:20 volumio go-librespot[24157]: time="2025-11-06T04:48:20Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 06 04:48:20 volumio go-librespot[24157]: time="2025-11-06T04:48:20Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 06 04:48:20 volumio go-librespot[24157]: time="2025-11-06T04:48:20Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 06 04:48:20 volumio go-librespot[24157]: time="2025-11-06T04:48:20Z" level=info msg="zeroconf server listening on port 36031" Nov 06 04:48:20 volumio go-librespot[24157]: time="2025-11-06T04:48:20Z" level=debug msg="obtained new client token: AABWobfMMtliQqCZnpd7Br/DwVSVHtfbsBL8lj+HPIlgsaA0meeRnqgshQWLJwqpZeN2J2D6FL/a5pgCCSicOXd8SV20g7PijUp0d2FQc6fZQZ82slhOgsAcWMhYIJ14bYAuLphD3Eqx4rH6JXixN7TuWByffsHWlBm9YxpZVBoLA8tDOnNkUneA8lhR4qytzIRz5SDEJe4/yDvThKIfZnQ/omoVckbq0C6ICCbEcY4qrI/qcJVEYkg=" Nov 06 04:48:20 volumio go-librespot[24157]: time="2025-11-06T04:48:20Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 06 04:48:21 volumio go-librespot[24157]: time="2025-11-06T04:48:21Z" level=debug msg="completed keyexchange" Nov 06 04:48:21 volumio go-librespot[24157]: time="2025-11-06T04:48:21Z" level=debug msg="completed challenge" Nov 06 04:48:21 volumio go-librespot[24157]: time="2025-11-06T04:48:21Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 06 04:48:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 04:48:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 04:48:23 volumio volumio[23489]: info: Initializing connection to go-librespot Websocket Nov 06 04:48:23 volumio volumio[23489]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 04:48:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 425. Nov 06 04:48:24 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 04:48:24 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 04:48:24 volumio go-librespot[24165]: go-librespot daemon starting... Nov 06 04:48:24 volumio go-librespot[24166]: time="2025-11-06T04:48:24Z" level=info msg="running go-librespot 0.4.0" Nov 06 04:48:24 volumio go-librespot[24166]: time="2025-11-06T04:48:24Z" level=debug msg="app state loaded" Nov 06 04:48:24 volumio go-librespot[24166]: time="2025-11-06T04:48:24Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 04:48:24 volumio go-librespot[24166]: time="2025-11-06T04:48:24Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 06 04:48:24 volumio go-librespot[24166]: time="2025-11-06T04:48:24Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 06 04:48:24 volumio go-librespot[24166]: time="2025-11-06T04:48:24Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 06 04:48:24 volumio go-librespot[24166]: time="2025-11-06T04:48:24Z" level=info msg="zeroconf server listening on port 39887" Nov 06 04:48:24 volumio go-librespot[24166]: time="2025-11-06T04:48:24Z" level=debug msg="obtained new client token: AABp+vTy07NZilNWu0KKPRzVCDKuJrZe6tq88aRmQGgGQRbUeCeY5tAN1kUVVQ9jqnQG4ZBP+UPW9eelUHzg96HC+sOhG+Uu5cGvEaXB/GDEPK5AN2CW2+1roMyzHTCfqF5r0IIBgEJx4PYODZLorKP8ASAiUQKKzc5p1x2uh8DW8K2BSR+xJuZLA6dnKxWtGDs1c3m9+TT1aX+ldvlm3UODTiI5leOHeingVuauCfu7a2HNjgE76Qc=" Nov 06 04:48:24 volumio go-librespot[24166]: time="2025-11-06T04:48:24Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 06 04:48:24 volumio go-librespot[24166]: time="2025-11-06T04:48:24Z" level=debug msg="completed keyexchange" Nov 06 04:48:24 volumio go-librespot[24166]: time="2025-11-06T04:48:24Z" level=debug msg="completed challenge" Nov 06 04:48:24 volumio go-librespot[24166]: time="2025-11-06T04:48:24Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 06 04:48:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 04:48:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 04:48:26 volumio volumio[23489]: info: Initializing connection to go-librespot Websocket Nov 06 04:48:26 volumio volumio[23489]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 04:48:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 426. Nov 06 04:48:27 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 04:48:27 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 04:48:27 volumio go-librespot[24187]: go-librespot daemon starting... Nov 06 04:48:27 volumio go-librespot[24188]: time="2025-11-06T04:48:27Z" level=info msg="running go-librespot 0.4.0" Nov 06 04:48:27 volumio go-librespot[24188]: time="2025-11-06T04:48:27Z" level=debug msg="app state loaded" Nov 06 04:48:27 volumio go-librespot[24188]: time="2025-11-06T04:48:27Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 04:48:27 volumio go-librespot[24188]: time="2025-11-06T04:48: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-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Nov 06 04:48:27 volumio go-librespot[24188]: time="2025-11-06T04:48:27Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Nov 06 04:48:27 volumio go-librespot[24188]: time="2025-11-06T04:48:27Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Nov 06 04:48:27 volumio go-librespot[24188]: time="2025-11-06T04:48:27Z" level=info msg="zeroconf server listening on port 36975" Nov 06 04:48:27 volumio go-librespot[24188]: time="2025-11-06T04:48:27Z" level=debug msg="obtained new client token: AACZS3XTC/2wd4cjBd5IbDwARu02+LySoa+/NSG15xAZy300grLqsiYBslEMC97AELZdRYuIB+kZMZ78yVUD7/auDwkPVLXnlpoNn7HJ6XIg+YusfGF4a+91Q68dCJdf3keuGM9JLDMbvzlT5qTBzHtApSWUF6pn2JZojJBdY6hoDXNih+WcsXrBcYK+GPyLYnuRkuBcbVUupazLKFF4MMvOsaet8EVjsoceb0pU1NtNIBcy5LdvyY4=" Nov 06 04:48:27 volumio go-librespot[24188]: time="2025-11-06T04:48:27Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 06 04:48:28 volumio go-librespot[24188]: time="2025-11-06T04:48:28Z" level=debug msg="completed keyexchange" Nov 06 04:48:28 volumio go-librespot[24188]: time="2025-11-06T04:48:28Z" level=debug msg="completed challenge" Nov 06 04:48:28 volumio go-librespot[24188]: time="2025-11-06T04:48:28Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 06 04:48:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 04:48:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 04:48:28 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Nov 06 04:48:29 volumio volumio[23489]: info: Initializing connection to go-librespot Websocket Nov 06 04:48:29 volumio volumio[23489]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 04:48:30 volumio volumio[23489]: info: CoreCommandRouter::volumioGetState Nov 06 04:48:30 volumio volumio[23489]: info: CorePlayQueue::getTrack 0 Nov 06 04:48:30 volumio volumio[23489]: info: Listing playlists Nov 06 04:48:30 volumio volumio[23489]: info: Listing playlists Nov 06 04:48:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 427. Nov 06 04:48:31 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 04:48:31 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 04:48:31 volumio go-librespot[24196]: go-librespot daemon starting... Nov 06 04:48:31 volumio go-librespot[24197]: time="2025-11-06T04:48:31Z" level=info msg="running go-librespot 0.4.0" Nov 06 04:48:31 volumio go-librespot[24197]: time="2025-11-06T04:48:31Z" level=debug msg="app state loaded" Nov 06 04:48:31 volumio go-librespot[24197]: time="2025-11-06T04:48:31Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 04:48:31 volumio go-librespot[24197]: time="2025-11-06T04:48:31Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 06 04:48:31 volumio go-librespot[24197]: time="2025-11-06T04:48:31Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 06 04:48:31 volumio go-librespot[24197]: time="2025-11-06T04:48:31Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 06 04:48:31 volumio go-librespot[24197]: time="2025-11-06T04:48:31Z" level=info msg="zeroconf server listening on port 44273" Nov 06 04:48:31 volumio go-librespot[24197]: time="2025-11-06T04:48:31Z" level=debug msg="obtained new client token: AAAfwO4vvVRJCxFY8Ayhl6kJIGVtoJBoYhmhr3uioECbkHL1yZp8V8kBSMXhw0Yahu25aOXNTVGMXEwWOmaCm8a14SOHIER0/85hbw5oUcv+4n26ptGjs9xUJkyJNENnK8css/CQ0WRZ957WbX7YfYfk+gRT/Q37QQ7GU7tfpPHD1PpV6gXvilDSTKSDL7rYPAHa8zNBCCZkjw8jYMTURPli8wHbQZ+pqwib6nm2V/QiDziNH0aIDx8=" Nov 06 04:48:31 volumio go-librespot[24197]: time="2025-11-06T04:48:31Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 06 04:48:31 volumio go-librespot[24197]: time="2025-11-06T04:48:31Z" level=debug msg="completed keyexchange" Nov 06 04:48:31 volumio go-librespot[24197]: time="2025-11-06T04:48:31Z" level=debug msg="completed challenge" Nov 06 04:48:31 volumio go-librespot[24197]: time="2025-11-06T04:48:31Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 06 04:48:31 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 04:48:31 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 04:48:32 volumio volumio[23489]: info: Initializing connection to go-librespot Websocket Nov 06 04:48:32 volumio volumio[23489]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 04:48:34 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 428. Nov 06 04:48:34 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 04:48:34 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 04:48:34 volumio go-librespot[24208]: go-librespot daemon starting... Nov 06 04:48:34 volumio go-librespot[24209]: time="2025-11-06T04:48:34Z" level=info msg="running go-librespot 0.4.0" Nov 06 04:48:34 volumio go-librespot[24209]: time="2025-11-06T04:48:34Z" level=debug msg="app state loaded" Nov 06 04:48:34 volumio go-librespot[24209]: time="2025-11-06T04:48:34Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 04:48:34 volumio go-librespot[24209]: time="2025-11-06T04:48:34Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 06 04:48:34 volumio go-librespot[24209]: time="2025-11-06T04:48:34Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 06 04:48:34 volumio go-librespot[24209]: time="2025-11-06T04:48:34Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 06 04:48:34 volumio go-librespot[24209]: time="2025-11-06T04:48:34Z" level=info msg="zeroconf server listening on port 35183" Nov 06 04:48:34 volumio go-librespot[24209]: time="2025-11-06T04:48:34Z" level=debug msg="obtained new client token: AAD035azZqtBQDnTTY3lNZgwA1Ax1wgfSNLb+9SdkFbw0A+ARwu+vnjEjG0UYAQltOZbdD62E5Qw0/rMSCGN8nmeOwFN2TWCJ3X61gQEF0+GwdE6jf17kUUqMr1bY92S5htB84hX6aozCTnyJR7RAOPcLdQwg1J0qN1W0z/gZOkNNbmKB5DeebaHox1KcxGZ5ma+6IkwBlbvSkL29W7WGzYy6PxHft8Q+xMBuP/LdtQvhcI/JggNyrY=" Nov 06 04:48:34 volumio go-librespot[24209]: time="2025-11-06T04:48:34Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 06 04:48:34 volumio go-librespot[24209]: time="2025-11-06T04:48:34Z" level=debug msg="completed keyexchange" Nov 06 04:48:34 volumio go-librespot[24209]: time="2025-11-06T04:48:34Z" level=debug msg="completed challenge" Nov 06 04:48:35 volumio go-librespot[24209]: time="2025-11-06T04:48:35Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 06 04:48:35 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 04:48:35 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 04:48:35 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Nov 06 04:48:35 volumio volumio[23489]: info: Initializing connection to go-librespot Websocket Nov 06 04:48:35 volumio volumio[23489]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 04:48:38 volumio volumio[23489]: info: Initializing connection to go-librespot Websocket Nov 06 04:48:38 volumio volumio[23489]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 04:48:38 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 429. Nov 06 04:48:38 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 04:48:38 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 04:48:38 volumio go-librespot[24230]: go-librespot daemon starting... Nov 06 04:48:38 volumio go-librespot[24231]: time="2025-11-06T04:48:38Z" level=info msg="running go-librespot 0.4.0" Nov 06 04:48:38 volumio go-librespot[24231]: time="2025-11-06T04:48:38Z" level=debug msg="app state loaded" Nov 06 04:48:38 volumio go-librespot[24231]: time="2025-11-06T04:48:38Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 04:48:38 volumio go-librespot[24231]: time="2025-11-06T04:48:38Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 06 04:48:38 volumio go-librespot[24231]: time="2025-11-06T04:48:38Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 06 04:48:38 volumio go-librespot[24231]: time="2025-11-06T04:48:38Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 06 04:48:38 volumio go-librespot[24231]: time="2025-11-06T04:48:38Z" level=info msg="zeroconf server listening on port 46623" Nov 06 04:48:38 volumio go-librespot[24231]: time="2025-11-06T04:48:38Z" level=debug msg="obtained new client token: AABVcnxNYRBruv8+QI/tF8OYaNEUTvg1/s7YiHkFtuQlfhe57TM8mgxOXporeteG18h2r97a2rmauAzdCnwo1YkfTehMkY5R2CnIYYbW8QW5+ovvitZeVnq//uEKCa2r4zAzQewMcWipi0DhAI13OJFu7zLiQdr0M8WlNJYIxM/MJPNbe4OT0uNuu64VmGjGbOdFWLrNsEQ/vOHrYqonvgHBYsoSHV34d3Hn4+QtZF4N/vGsEaCZxHk=" Nov 06 04:48:38 volumio go-librespot[24231]: time="2025-11-06T04:48:38Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 06 04:48:38 volumio go-librespot[24231]: time="2025-11-06T04:48:38Z" level=debug msg="completed keyexchange" Nov 06 04:48:38 volumio go-librespot[24231]: time="2025-11-06T04:48:38Z" level=debug msg="completed challenge" Nov 06 04:48:38 volumio go-librespot[24231]: time="2025-11-06T04:48:38Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 06 04:48:38 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 04:48:38 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 04:48:40 volumio volumio[23489]: info: CoreCommandRouter::volumioGetState Nov 06 04:48:40 volumio volumio[23489]: info: CorePlayQueue::getTrack 0 Nov 06 04:48:41 volumio volumio[23489]: info: Initializing connection to go-librespot Websocket Nov 06 04:48:41 volumio volumio[23489]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 04:48:41 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 430. Nov 06 04:48:41 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 04:48:41 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 04:48:41 volumio go-librespot[24238]: go-librespot daemon starting... Nov 06 04:48:41 volumio go-librespot[24239]: time="2025-11-06T04:48:41Z" level=info msg="running go-librespot 0.4.0" Nov 06 04:48:41 volumio go-librespot[24239]: time="2025-11-06T04:48:41Z" level=debug msg="app state loaded" Nov 06 04:48:41 volumio go-librespot[24239]: time="2025-11-06T04:48:41Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 04:48:41 volumio go-librespot[24239]: time="2025-11-06T04:48:41Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 06 04:48:41 volumio go-librespot[24239]: time="2025-11-06T04:48:41Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 06 04:48:41 volumio go-librespot[24239]: time="2025-11-06T04:48:41Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 06 04:48:41 volumio go-librespot[24239]: time="2025-11-06T04:48:41Z" level=info msg="zeroconf server listening on port 36233" Nov 06 04:48:41 volumio go-librespot[24239]: time="2025-11-06T04:48:41Z" level=debug msg="obtained new client token: AABsgj/aLF12zp5Io7WdmmuAOfy+rcfHcHIrqjxiCDH3yvsEbaSIfP9R/Z2Wh4Eey8MAtafd/mEwWukNBmPwQ3/sfpBxRaWfnglyeV0n3WIMFnUq55LxoLO07HJSohUgMMQWqT6MWofKzBCDkf6n1LbYJjaUToLAbOc7q7kdHx6bKxTPtr4DhA7aGDWF3Sy32HSDGMxdFODNc+sn+wl9VP8Xelx2UvJd1dWr+HdSQ1OQUvQak/I3YWA=" Nov 06 04:48:41 volumio go-librespot[24239]: time="2025-11-06T04:48:41Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 06 04:48:42 volumio go-librespot[24239]: time="2025-11-06T04:48:42Z" level=debug msg="completed keyexchange" Nov 06 04:48:42 volumio go-librespot[24239]: time="2025-11-06T04:48:42Z" level=debug msg="completed challenge" Nov 06 04:48:42 volumio go-librespot[24239]: time="2025-11-06T04:48:42Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 06 04:48:42 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 04:48:42 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 04:48:44 volumio volumio[23489]: info: Initializing connection to go-librespot Websocket Nov 06 04:48:44 volumio volumio[23489]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 04:48:45 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 431. Nov 06 04:48:45 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 04:48:45 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 04:48:45 volumio go-librespot[24246]: go-librespot daemon starting... Nov 06 04:48:45 volumio go-librespot[24247]: time="2025-11-06T04:48:45Z" level=info msg="running go-librespot 0.4.0" Nov 06 04:48:45 volumio go-librespot[24247]: time="2025-11-06T04:48:45Z" level=debug msg="app state loaded" Nov 06 04:48:45 volumio go-librespot[24247]: time="2025-11-06T04:48:45Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 04:48:45 volumio go-librespot[24247]: time="2025-11-06T04:48:45Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 06 04:48:45 volumio go-librespot[24247]: time="2025-11-06T04:48:45Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 06 04:48:45 volumio go-librespot[24247]: time="2025-11-06T04:48:45Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 06 04:48:45 volumio go-librespot[24247]: time="2025-11-06T04:48:45Z" level=info msg="zeroconf server listening on port 44347" Nov 06 04:48:45 volumio go-librespot[24247]: time="2025-11-06T04:48:45Z" level=debug msg="obtained new client token: AADX6M0lEOsTv7Rd4cO4mGQyh8y/WEPus437p6YRu5dJ1+vCKlmR0MqxHIcRI921+806v1xq6cMvJS1gUKmbRkpDZMIsEJxQ3/rir/zM98lpf7WNQrOYhQJ4BkFOgk1aybk8HCL39C1SO5ygtbDmXi07qE2ESRv5+1CW1GnDJwA8EJS4UlGTWAySpvfh92NWyN/lHey+XBdUZttZnsbNBv04WkKk6GBR7/5hf1d0UhXK8USjjfChnkQ=" Nov 06 04:48:45 volumio go-librespot[24247]: time="2025-11-06T04:48:45Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 06 04:48:45 volumio go-librespot[24247]: time="2025-11-06T04:48:45Z" level=debug msg="completed keyexchange" Nov 06 04:48:45 volumio go-librespot[24247]: time="2025-11-06T04:48:45Z" level=debug msg="completed challenge" Nov 06 04:48:45 volumio go-librespot[24247]: time="2025-11-06T04:48:45Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 06 04:48:45 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 04:48:45 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 04:48:47 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Nov 06 04:48:47 volumio volumio[23489]: info: Initializing connection to go-librespot Websocket Nov 06 04:48:47 volumio volumio[23489]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 04:48:49 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 432. Nov 06 04:48:49 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 04:48:49 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 04:48:49 volumio go-librespot[24268]: go-librespot daemon starting... Nov 06 04:48:49 volumio go-librespot[24269]: time="2025-11-06T04:48:49Z" level=info msg="running go-librespot 0.4.0" Nov 06 04:48:49 volumio go-librespot[24269]: time="2025-11-06T04:48:49Z" level=debug msg="app state loaded" Nov 06 04:48:49 volumio go-librespot[24269]: time="2025-11-06T04:48:49Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 04:48:49 volumio go-librespot[24269]: time="2025-11-06T04:48:49Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 06 04:48:49 volumio go-librespot[24269]: time="2025-11-06T04:48:49Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 06 04:48:49 volumio go-librespot[24269]: time="2025-11-06T04:48:49Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 06 04:48:49 volumio go-librespot[24269]: time="2025-11-06T04:48:49Z" level=info msg="zeroconf server listening on port 45463" Nov 06 04:48:49 volumio go-librespot[24269]: time="2025-11-06T04:48:49Z" level=debug msg="obtained new client token: AAC5czGPUoyLBiTv3DDD3bczX+a2xbye4hM+Cvb2lAiDFiwU+exMSDTDeohAZohsiCvdAvZwQ497wuZx/wjacwrGjOa4Kqw+FGBmCMLq3Rv41iyTkaBGjYfnwujTefS10WnSKopDkS5iUxDg/CyeLQP7r/GLYmwQOMQP15cJ8R60moVnVrih1UPk858j0uKeraw5mKJd9xPm2ExiP7bvbqM6KL+hx1P4j+LGbp0HxQtuDgCLXIQU" Nov 06 04:48:49 volumio go-librespot[24269]: time="2025-11-06T04:48:49Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 06 04:48:49 volumio go-librespot[24269]: time="2025-11-06T04:48:49Z" level=debug msg="completed keyexchange" Nov 06 04:48:49 volumio go-librespot[24269]: time="2025-11-06T04:48:49Z" level=debug msg="completed challenge" Nov 06 04:48:49 volumio go-librespot[24269]: time="2025-11-06T04:48:49Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 06 04:48:49 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 04:48:49 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 04:48:50 volumio volumio[23489]: info: Initializing connection to go-librespot Websocket Nov 06 04:48:50 volumio volumio[23489]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 04:48:50 volumio volumio[23489]: info: CoreCommandRouter::volumioGetState Nov 06 04:48:50 volumio volumio[23489]: info: CorePlayQueue::getTrack 0 Nov 06 04:48:50 volumio volumio[23489]: info: Listing playlists Nov 06 04:48:50 volumio volumio[23489]: info: Listing playlists Nov 06 04:48:52 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 433. Nov 06 04:48:52 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 04:48:52 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 04:48:52 volumio go-librespot[24277]: go-librespot daemon starting... Nov 06 04:48:52 volumio go-librespot[24278]: time="2025-11-06T04:48:52Z" level=info msg="running go-librespot 0.4.0" Nov 06 04:48:52 volumio go-librespot[24278]: time="2025-11-06T04:48:52Z" level=debug msg="app state loaded" Nov 06 04:48:52 volumio go-librespot[24278]: time="2025-11-06T04:48:52Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 04:48:52 volumio go-librespot[24278]: time="2025-11-06T04:48:52Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 06 04:48:52 volumio go-librespot[24278]: time="2025-11-06T04:48:52Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 06 04:48:52 volumio go-librespot[24278]: time="2025-11-06T04:48:52Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 06 04:48:52 volumio go-librespot[24278]: time="2025-11-06T04:48:52Z" level=info msg="zeroconf server listening on port 33117" Nov 06 04:48:52 volumio go-librespot[24278]: time="2025-11-06T04:48:52Z" level=debug msg="obtained new client token: AABei5LPFQKbwAKJ7N9MbVngkyGDwgjND6MD+K1v6zvOVOC9uKyZQ83ppJgoOaNb/hwTRsPIIH+OLXRfL4LErQPBQOCJNUII9P2XtsWqr1mVpbABjGEclmQEZyLReCqfqGz9WCSg5Q/VMMd1sTiOP7rZPK3hPbVgrA5dxlwenObKZdfVzx3Yudw6M9TPSoVuGFJc+KAyTjARupAXFMmjbt9GlUZ1LrDQg1cLDXK/xoUu1bYrUOam8BY=" Nov 06 04:48:52 volumio go-librespot[24278]: time="2025-11-06T04:48:52Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 06 04:48:52 volumio go-librespot[24278]: time="2025-11-06T04:48:52Z" level=debug msg="completed keyexchange" Nov 06 04:48:52 volumio go-librespot[24278]: time="2025-11-06T04:48:52Z" level=debug msg="completed challenge" Nov 06 04:48:52 volumio go-librespot[24278]: time="2025-11-06T04:48:52Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 06 04:48:52 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 04:48:52 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 04:48:53 volumio volumio[23489]: info: Initializing connection to go-librespot Websocket Nov 06 04:48:53 volumio volumio[23489]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 04:48:56 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 434. Nov 06 04:48:56 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 06 04:48:56 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 06 04:48:56 volumio go-librespot[24289]: go-librespot daemon starting... Nov 06 04:48:56 volumio go-librespot[24290]: time="2025-11-06T04:48:56Z" level=info msg="running go-librespot 0.4.0" Nov 06 04:48:56 volumio go-librespot[24290]: time="2025-11-06T04:48:56Z" level=debug msg="app state loaded" Nov 06 04:48:56 volumio go-librespot[24290]: time="2025-11-06T04:48:56Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 04:48:56 volumio go-librespot[24290]: time="2025-11-06T04:48:56Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Nov 06 04:48:56 volumio go-librespot[24290]: time="2025-11-06T04:48:56Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Nov 06 04:48:56 volumio go-librespot[24290]: time="2025-11-06T04:48:56Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Nov 06 04:48:56 volumio go-librespot[24290]: time="2025-11-06T04:48:56Z" level=info msg="zeroconf server listening on port 34357" Nov 06 04:48:56 volumio go-librespot[24290]: time="2025-11-06T04:48:56Z" level=debug msg="obtained new client token: AAB/lfrUmuQkS4lHhBX35/Y/jf/dWBb4lYQINGzR6VWTnGLO1P8q9Kq4rky90l0BPi+7E/pBlMDX3ucbCQy0SsOTPazWa1joAW4CCYm4K7Sv0a9hULn6N90nCEAc18OQ0DhuxMI2xpH2pqnkHDJZQML8cEinXOPV7pPjz5iFU6Q8Mac1jpXoJ3vGOcs6S7KVAn17Pr8U2QG56BH19RWDjPE879pTn+JA2WrGahebiBSgmOkOWe63" Nov 06 04:48:56 volumio go-librespot[24290]: time="2025-11-06T04:48:56Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 06 04:48:56 volumio volumio[23489]: info: Initializing connection to go-librespot Websocket Nov 06 04:48:56 volumio go-librespot[24290]: time="2025-11-06T04:48:56Z" level=debug msg="new websocket client" Nov 06 04:48:56 volumio volumio[23489]: info: Connection to go-librespot Websocket established Nov 06 04:48:56 volumio go-librespot[24290]: time="2025-11-06T04:48:56Z" level=debug msg="completed keyexchange" Nov 06 04:48:56 volumio go-librespot[24290]: time="2025-11-06T04:48:56Z" level=debug msg="completed challenge" Nov 06 04:48:56 volumio go-librespot[24290]: time="2025-11-06T04:48:56Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 06 04:48:56 volumio volumio[23489]: info: Connection to go-librespot Websocket closed Nov 06 04:48:56 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 04:48:56 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 04:48:59 volumio volumio[23489]: info: Getting Spotify volume Nov 06 04:48:59 volumio volumio[23489]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 06 04:48:59 volumio volumio[23489]: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 04:48:59 volumio volumio[23489]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Nov 06 04:48:59 volumio volumio[23489]: errno: -111, Nov 06 04:48:59 volumio volumio[23489]: code: 'ECONNREFUSED', Nov 06 04:48:59 volumio volumio[23489]: syscall: 'connect', Nov 06 04:48:59 volumio volumio[23489]: address: '127.0.0.1', Nov 06 04:48:59 volumio volumio[23489]: port: 9879, Nov 06 04:48:59 volumio volumio[23489]: response: undefined Nov 06 04:48:59 volumio volumio[23489]: } Nov 06 04:48:59 volumio volumio[23489]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 06 04:48:59 volumio sudo[24325]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-11-06 04:47' Nov 06 04:48:59 volumio sudo[24325]: 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"