Nov 04 18:47:00 volumio volumio[13516]: info: Initializing connection to go-librespot Websocket Nov 04 18:47:00 volumio volumio[13516]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 18:47:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 77. Nov 04 18:47:02 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 18:47:02 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 18:47:02 volumio go-librespot[14896]: go-librespot daemon starting... Nov 04 18:47:02 volumio go-librespot[14897]: time="2025-11-04T18:47:02Z" level=info msg="running go-librespot 0.4.0" Nov 04 18:47:02 volumio go-librespot[14897]: time="2025-11-04T18:47:02Z" level=debug msg="app state loaded" Nov 04 18:47:02 volumio go-librespot[14897]: time="2025-11-04T18:47:02Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 18:47:02 volumio go-librespot[14897]: time="2025-11-04T18:47:02Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Nov 04 18:47:02 volumio go-librespot[14897]: time="2025-11-04T18:47:02Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Nov 04 18:47:02 volumio go-librespot[14897]: time="2025-11-04T18:47:02Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Nov 04 18:47:02 volumio go-librespot[14897]: time="2025-11-04T18:47:02Z" level=info msg="zeroconf server listening on port 40343" Nov 04 18:47:02 volumio go-librespot[14897]: time="2025-11-04T18:47:02Z" level=debug msg="obtained new client token: AACRlN24dMQeBaDHKYaIX5N6K6a7fxske3wOGxHwtJK6R0IoaRUAZH49SlMPi2H2FMa3fdtreWrv8HLdbS/TMRq4+fapr0WcLtGtPU6aSDOQ6lVY6qG91wEqXK/4WK6d1tkH7hlsDrL6446UVf9DtQNKB/ecIfB6IBPbB3tx2igndJQXD0+kFG5ei6ZrZca9y/9KEs4FvzLsY3mK1ZuCNsbp4fHhzZN0qOMx1kOYJaHze1iuldbfjhA=" Nov 04 18:47:02 volumio go-librespot[14897]: time="2025-11-04T18:47:02Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 18:47:03 volumio go-librespot[14897]: time="2025-11-04T18:47:03Z" level=debug msg="completed keyexchange" Nov 04 18:47:03 volumio go-librespot[14897]: time="2025-11-04T18:47:03Z" level=debug msg="completed challenge" Nov 04 18:47:03 volumio go-librespot[14897]: time="2025-11-04T18: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 04 18:47:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 18:47:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 18:47:03 volumio volumio[13516]: info: Initializing connection to go-librespot Websocket Nov 04 18:47:03 volumio volumio[13516]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 18:47:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 78. Nov 04 18:47:06 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 18:47:06 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 18:47:06 volumio go-librespot[14905]: go-librespot daemon starting... Nov 04 18:47:06 volumio go-librespot[14906]: time="2025-11-04T18:47:06Z" level=info msg="running go-librespot 0.4.0" Nov 04 18:47:06 volumio go-librespot[14906]: time="2025-11-04T18:47:06Z" level=debug msg="app state loaded" Nov 04 18:47:06 volumio go-librespot[14906]: time="2025-11-04T18:47:06Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 18:47:06 volumio go-librespot[14906]: time="2025-11-04T18:47:06Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Nov 04 18:47:06 volumio go-librespot[14906]: time="2025-11-04T18:47:06Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Nov 04 18:47:06 volumio go-librespot[14906]: time="2025-11-04T18:47:06Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Nov 04 18:47:06 volumio go-librespot[14906]: time="2025-11-04T18:47:06Z" level=info msg="zeroconf server listening on port 36747" Nov 04 18:47:06 volumio go-librespot[14906]: time="2025-11-04T18:47:06Z" level=debug msg="obtained new client token: AAA8+G8JHHfqzTfQQMeJbr9DkKzSlFGa0v7sjKH+mgyDxuZLO2x6YqA8S6XRUFieQbFjIFzDeQ9Whx0Twy02eiAV52XRBbdUbY7n4GT45clXPBAl/quHtdT6kKqKuebiuqiZH9zYYWtnIVe7DfxEwRABxMN/kBXsu0+FqZuDdAI70xkvDSme6Q6V4LRKp1kh0G8NnXLsDzae4pxdCwImpJiDmVGLb9Uq0s2msP8gNqR6ULjoufHeLHU=" Nov 04 18:47:06 volumio go-librespot[14906]: time="2025-11-04T18:47:06Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 18:47:06 volumio go-librespot[14906]: time="2025-11-04T18:47:06Z" level=debug msg="completed keyexchange" Nov 04 18:47:06 volumio go-librespot[14906]: time="2025-11-04T18:47:06Z" level=debug msg="completed challenge" Nov 04 18:47:06 volumio go-librespot[14906]: time="2025-11-04T18:47:06Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 04 18:47:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 18:47:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 18:47:06 volumio volumio[13516]: info: Initializing connection to go-librespot Websocket Nov 04 18:47:06 volumio volumio[13516]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 18:47:08 volumio volumio[13516]: info: CoreCommandRouter::volumioGetState Nov 04 18:47:08 volumio volumio[13516]: info: CorePlayQueue::getTrack 0 Nov 04 18:47:08 volumio volumio[13516]: info: Listing playlists Nov 04 18:47:08 volumio volumio[13516]: info: Listing playlists Nov 04 18:47:09 volumio volumio[13516]: info: Initializing connection to go-librespot Websocket Nov 04 18:47:09 volumio volumio[13516]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 18:47:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 79. Nov 04 18:47:09 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 18:47:09 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 18:47:09 volumio go-librespot[14913]: go-librespot daemon starting... Nov 04 18:47:09 volumio go-librespot[14914]: time="2025-11-04T18:47:09Z" level=info msg="running go-librespot 0.4.0" Nov 04 18:47:09 volumio go-librespot[14914]: time="2025-11-04T18:47:09Z" level=debug msg="app state loaded" Nov 04 18:47:09 volumio go-librespot[14914]: time="2025-11-04T18:47:09Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 18:47:09 volumio go-librespot[14914]: time="2025-11-04T18:47:09Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Nov 04 18:47:09 volumio go-librespot[14914]: time="2025-11-04T18:47:09Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Nov 04 18:47:09 volumio go-librespot[14914]: time="2025-11-04T18:47:09Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Nov 04 18:47:09 volumio go-librespot[14914]: time="2025-11-04T18:47:09Z" level=info msg="zeroconf server listening on port 45427" Nov 04 18:47:09 volumio go-librespot[14914]: time="2025-11-04T18:47:09Z" level=debug msg="obtained new client token: AAC+GlMIYmpbPhg8w1u/j5sT3NjxWk3awky7xthvFg0dFTnHt+n/lp1hUeSRiMcRv5bavITdo+QQkMn7oiNo5bQhFFd+iAWx1BeALRX0X5HdmECI7ph3ejOTU4LTj0vkfGSjBypYvPPskCvl1ni9Jnp/KkMf5LxbMuT0kv+0yw7uSmMPbRUP6yb7IOSUlRNQTrXstH5eYQEw8M/TTlz2OqdzbezEOyCej6nA0pfZ1hPhWe36eYqmJFk=" Nov 04 18:47:09 volumio go-librespot[14914]: time="2025-11-04T18:47:09Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 18:47:10 volumio go-librespot[14914]: time="2025-11-04T18:47:10Z" level=debug msg="completed keyexchange" Nov 04 18:47:10 volumio go-librespot[14914]: time="2025-11-04T18:47:10Z" level=debug msg="completed challenge" Nov 04 18:47:10 volumio go-librespot[14914]: time="2025-11-04T18:47: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 04 18:47:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 18:47:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 18:47:12 volumio volumio[13516]: info: Initializing connection to go-librespot Websocket Nov 04 18:47:12 volumio volumio[13516]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 18:47:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 80. Nov 04 18:47:13 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 18:47:13 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 18:47:13 volumio go-librespot[14935]: go-librespot daemon starting... Nov 04 18:47:13 volumio go-librespot[14936]: time="2025-11-04T18:47:13Z" level=info msg="running go-librespot 0.4.0" Nov 04 18:47:13 volumio go-librespot[14936]: time="2025-11-04T18:47:13Z" level=debug msg="app state loaded" Nov 04 18:47:13 volumio go-librespot[14936]: time="2025-11-04T18:47:13Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 18:47:13 volumio go-librespot[14936]: time="2025-11-04T18:47:13Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 04 18:47:13 volumio go-librespot[14936]: time="2025-11-04T18:47:13Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 04 18:47:13 volumio go-librespot[14936]: time="2025-11-04T18:47:13Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 04 18:47:13 volumio go-librespot[14936]: time="2025-11-04T18:47:13Z" level=info msg="zeroconf server listening on port 42565" Nov 04 18:47:13 volumio go-librespot[14936]: time="2025-11-04T18:47:13Z" level=debug msg="obtained new client token: AAAW6Yo3cnFWjWI5IW7J4EyBr8vnFEYBbYfZJqFBncFV2mAWeMXrDCFIvxHeuhNqEwQ8pj1yxuBvMgbB6Ho6UeeZGEFpSBNKTRM3r8yAlOd8fPOfA3O7nZSO6Kb3pdV4baXcbaCvpOt54Eawm6V8MdoBWMPFnlitBpmy5bKca2bnNW43Y/zhN69a/C+mlK7YmQTYtObxuoMkTlooZVurX8GStec9UCTBB7FBpRMgB/WZWXh6IVD1Ppw=" Nov 04 18:47:13 volumio go-librespot[14936]: time="2025-11-04T18:47:13Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 18:47:13 volumio go-librespot[14936]: time="2025-11-04T18:47:13Z" level=debug msg="completed keyexchange" Nov 04 18:47:13 volumio go-librespot[14936]: time="2025-11-04T18:47:13Z" level=debug msg="completed challenge" Nov 04 18:47:13 volumio go-librespot[14936]: time="2025-11-04T18:47:13Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 04 18:47:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 18:47:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 18:47:15 volumio volumio[13516]: info: Initializing connection to go-librespot Websocket Nov 04 18:47:15 volumio volumio[13516]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 18:47:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 81. Nov 04 18:47:16 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 18:47:16 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 18:47:16 volumio go-librespot[14946]: go-librespot daemon starting... Nov 04 18:47:16 volumio go-librespot[14947]: time="2025-11-04T18:47:16Z" level=info msg="running go-librespot 0.4.0" Nov 04 18:47:16 volumio go-librespot[14947]: time="2025-11-04T18:47:16Z" level=debug msg="app state loaded" Nov 04 18:47:16 volumio go-librespot[14947]: time="2025-11-04T18:47:16Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 18:47:16 volumio go-librespot[14947]: time="2025-11-04T18:47:16Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 04 18:47:16 volumio go-librespot[14947]: time="2025-11-04T18:47:16Z" 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 04 18:47:16 volumio go-librespot[14947]: time="2025-11-04T18:47:16Z" 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 04 18:47:16 volumio go-librespot[14947]: time="2025-11-04T18:47:16Z" level=info msg="zeroconf server listening on port 39379" Nov 04 18:47:16 volumio go-librespot[14947]: time="2025-11-04T18:47:16Z" level=debug msg="obtained new client token: AADb92jB72VzQzJwO6Bhag35DzidPolUcnUjh8lwr8Qs/0SRMOyJ5tIcsNtN3pO1qEkEXTNZFfYEGWWDA4iEWSIrKcQY8X6Jy8VbWcymgANE5hCguvYRBT1klISMiCuyHaHDhH3UjVfYoPolD+az83MrZTeyjtfka/Gx+D1UNBZK6V4ENYBH1xr2a2xdG7po60F+eg2vnHybm8gyW0tlqDACYGVTj8W6hp1iuJFbtmCgvGXiidsQ7wI=" Nov 04 18:47:16 volumio go-librespot[14947]: time="2025-11-04T18:47:16Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 18:47:17 volumio go-librespot[14947]: time="2025-11-04T18:47:17Z" level=debug msg="completed keyexchange" Nov 04 18:47:17 volumio go-librespot[14947]: time="2025-11-04T18:47:17Z" level=debug msg="completed challenge" Nov 04 18:47:17 volumio go-librespot[14947]: time="2025-11-04T18:47: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 04 18:47:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 18:47:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 18:47:18 volumio volumio[13516]: info: CoreCommandRouter::volumioGetState Nov 04 18:47:18 volumio volumio[13516]: info: CorePlayQueue::getTrack 0 Nov 04 18:47:18 volumio volumio[13516]: info: Initializing connection to go-librespot Websocket Nov 04 18:47:18 volumio volumio[13516]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 18:47:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 82. Nov 04 18:47:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 18:47:20 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 18:47:20 volumio go-librespot[14954]: go-librespot daemon starting... Nov 04 18:47:20 volumio go-librespot[14955]: time="2025-11-04T18:47:20Z" level=info msg="running go-librespot 0.4.0" Nov 04 18:47:20 volumio go-librespot[14955]: time="2025-11-04T18:47:20Z" level=debug msg="app state loaded" Nov 04 18:47:20 volumio go-librespot[14955]: time="2025-11-04T18:47:20Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 18:47:20 volumio go-librespot[14955]: time="2025-11-04T18:47: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-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 04 18:47:20 volumio go-librespot[14955]: time="2025-11-04T18:47:20Z" 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 04 18:47:20 volumio go-librespot[14955]: time="2025-11-04T18:47:20Z" 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 04 18:47:20 volumio go-librespot[14955]: time="2025-11-04T18:47:20Z" level=info msg="zeroconf server listening on port 35177" Nov 04 18:47:20 volumio go-librespot[14955]: time="2025-11-04T18:47:20Z" level=debug msg="obtained new client token: AABVrVtzsWCUCqHxDf3mRIDa0cUO8qsjW83Ob+8RC3L8mop9hDQbye00URuoyRvfEsvCKGfGOcaYbGXL3aHaHPPpOuzBmH1vBMHsOFYM5ujjhv/tAdLvkcSwtXgZ+u8XR2t18fEoL620180MWBSl0FI8Y0NKM/SMFUBfR8r+GK8CuSA/Af72bn9ix5Vv35U+ZNK9qaVNA7agmCJwqtNoIHJ3zWbM/58++rVQFXqJqEPWwiaSGBI0NWs=" Nov 04 18:47:20 volumio go-librespot[14955]: time="2025-11-04T18:47:20Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 18:47:20 volumio go-librespot[14955]: time="2025-11-04T18:47:20Z" level=debug msg="completed keyexchange" Nov 04 18:47:20 volumio go-librespot[14955]: time="2025-11-04T18:47:20Z" level=debug msg="completed challenge" Nov 04 18:47:20 volumio go-librespot[14955]: time="2025-11-04T18:47:20Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 04 18:47:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 18:47:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 18:47:21 volumio volumio[13516]: info: Initializing connection to go-librespot Websocket Nov 04 18:47:21 volumio volumio[13516]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 18:47:23 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 83. Nov 04 18:47:23 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 18:47:23 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 18:47:23 volumio go-librespot[14977]: go-librespot daemon starting... Nov 04 18:47:23 volumio go-librespot[14978]: time="2025-11-04T18:47:23Z" level=info msg="running go-librespot 0.4.0" Nov 04 18:47:23 volumio go-librespot[14978]: time="2025-11-04T18:47:23Z" level=debug msg="app state loaded" Nov 04 18:47:23 volumio go-librespot[14978]: time="2025-11-04T18:47:23Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 18:47:23 volumio go-librespot[14978]: time="2025-11-04T18:47:23Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 04 18:47:23 volumio go-librespot[14978]: time="2025-11-04T18:47:23Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 04 18:47:23 volumio go-librespot[14978]: time="2025-11-04T18:47:23Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 04 18:47:23 volumio go-librespot[14978]: time="2025-11-04T18:47:23Z" level=info msg="zeroconf server listening on port 42205" Nov 04 18:47:23 volumio go-librespot[14978]: time="2025-11-04T18:47:23Z" level=debug msg="obtained new client token: AAA59nel3afGPxVVejUDJQS4sNCKBC929xJRJHg5fzNTUE63hqq5+27RgmX/KBBxI1wVTKGHHOftndnBx0kKPPcalOd7m5etpRgClmn2ukmWjOBaA5ElIoxKl8jxav3ONtwxT6gDFpjDSrwcq94Cn0UHr/n0091J+soLkJqn6U74ahO9UbJ4jicZGwx5Lajo6Z6jRrIdzD/tY3XSj9255m4Wi5+aBf/8Y2CTz9UiuFO2HLi9ZBDV7X4=" Nov 04 18:47:23 volumio go-librespot[14978]: time="2025-11-04T18:47:23Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 18:47:23 volumio go-librespot[14978]: time="2025-11-04T18:47:23Z" level=debug msg="completed keyexchange" Nov 04 18:47:24 volumio go-librespot[14978]: time="2025-11-04T18:47:23Z" level=debug msg="completed challenge" Nov 04 18:47:24 volumio go-librespot[14978]: time="2025-11-04T18:47: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 04 18:47:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 18:47:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 18:47:24 volumio volumio[13516]: info: Initializing connection to go-librespot Websocket Nov 04 18:47:24 volumio volumio[13516]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 18:47:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 84. Nov 04 18:47:27 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 18:47:27 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 18:47:27 volumio go-librespot[14985]: go-librespot daemon starting... Nov 04 18:47:27 volumio go-librespot[14986]: time="2025-11-04T18:47:27Z" level=info msg="running go-librespot 0.4.0" Nov 04 18:47:27 volumio go-librespot[14986]: time="2025-11-04T18:47:27Z" level=debug msg="app state loaded" Nov 04 18:47:27 volumio go-librespot[14986]: time="2025-11-04T18:47:27Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 18:47:27 volumio go-librespot[14986]: time="2025-11-04T18:47:27Z" 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 04 18:47:27 volumio go-librespot[14986]: time="2025-11-04T18:47:27Z" 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 04 18:47:27 volumio go-librespot[14986]: time="2025-11-04T18:47:27Z" 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 04 18:47:27 volumio go-librespot[14986]: time="2025-11-04T18:47:27Z" level=info msg="zeroconf server listening on port 44837" Nov 04 18:47:27 volumio go-librespot[14986]: time="2025-11-04T18:47:27Z" level=debug msg="obtained new client token: AADMVpSM9wfbYOJT8xSRMo01hAByElWomgu+f5MSNDMbmoe24vzln5TC4hpFrzlcG+mVE0xsr0nsvYzu2Z9HAwx4pyFRYcWUPBtvtmzexeDVKjAa4wK7mSIyGeuk5y6zyFCEeXD0ajkPuW46mCQpz95T0FjzEeBl86iJK1nRTO7Dgb28nuRRkIOvoet0XXN2C2Pc/GwBLtZLzYnM182ISSQiOyw0bMTrWsXKKKVdNUb+br7JVvYnIyU=" Nov 04 18:47:27 volumio go-librespot[14986]: time="2025-11-04T18:47:27Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 18:47:27 volumio go-librespot[14986]: time="2025-11-04T18:47:27Z" level=debug msg="completed keyexchange" Nov 04 18:47:27 volumio go-librespot[14986]: time="2025-11-04T18:47:27Z" level=debug msg="completed challenge" Nov 04 18:47:27 volumio go-librespot[14986]: time="2025-11-04T18:47:27Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 04 18:47:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 18:47:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 18:47:27 volumio volumio[13516]: info: Initializing connection to go-librespot Websocket Nov 04 18:47:27 volumio volumio[13516]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 18:47:28 volumio volumio[13516]: info: CoreCommandRouter::volumioGetState Nov 04 18:47:28 volumio volumio[13516]: info: CorePlayQueue::getTrack 0 Nov 04 18:47:28 volumio volumio[13516]: info: Listing playlists Nov 04 18:47:28 volumio volumio[13516]: info: Listing playlists Nov 04 18:47:30 volumio volumio[13516]: info: Initializing connection to go-librespot Websocket Nov 04 18:47:30 volumio volumio[13516]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 18:47:30 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 85. Nov 04 18:47:30 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 18:47:30 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 18:47:30 volumio go-librespot[14993]: go-librespot daemon starting... Nov 04 18:47:30 volumio go-librespot[14994]: time="2025-11-04T18:47:30Z" level=info msg="running go-librespot 0.4.0" Nov 04 18:47:30 volumio go-librespot[14994]: time="2025-11-04T18:47:30Z" level=debug msg="app state loaded" Nov 04 18:47:30 volumio go-librespot[14994]: time="2025-11-04T18:47:30Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 18:47:30 volumio go-librespot[14994]: time="2025-11-04T18:47:30Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 04 18:47:30 volumio go-librespot[14994]: time="2025-11-04T18:47:30Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 04 18:47:30 volumio go-librespot[14994]: time="2025-11-04T18:47:30Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 04 18:47:30 volumio go-librespot[14994]: time="2025-11-04T18:47:30Z" level=info msg="zeroconf server listening on port 46189" Nov 04 18:47:30 volumio go-librespot[14994]: time="2025-11-04T18:47:30Z" level=debug msg="obtained new client token: AADhvj1L95gjWdH+doqNc+U2Gs/eMjayFn7Iez8tfVUwWpxaB8Ep1wVWaHlqJwlHE2GyvLI52IIpC2nfggScCOWtwfgsERvhvztekH/VLNUWKivMLoOaYvYJQ4xMJOfwLKPN0btdfiOtIZO4LJjfnfIzY5OgFdF7xPLIYg+338vvuwUvJKY2cvh30vh1MSYVNQImgbesfTthe9Ut5uZKQCW/2naUFAhaz0uEh7RhF6FccAXX5HrUh9Q=" Nov 04 18:47:30 volumio go-librespot[14994]: time="2025-11-04T18:47:30Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 18:47:31 volumio go-librespot[14994]: time="2025-11-04T18:47:31Z" level=debug msg="completed keyexchange" Nov 04 18:47:31 volumio go-librespot[14994]: time="2025-11-04T18:47:31Z" level=debug msg="completed challenge" Nov 04 18:47:31 volumio go-librespot[14994]: time="2025-11-04T18:47: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 04 18:47:31 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 18:47:31 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 18:47:33 volumio volumio[13516]: info: Initializing connection to go-librespot Websocket Nov 04 18:47:33 volumio volumio[13516]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 18:47:34 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 86. Nov 04 18:47:34 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 18:47:34 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 18:47:34 volumio go-librespot[15015]: go-librespot daemon starting... Nov 04 18:47:34 volumio go-librespot[15016]: time="2025-11-04T18:47:34Z" level=info msg="running go-librespot 0.4.0" Nov 04 18:47:34 volumio go-librespot[15016]: time="2025-11-04T18:47:34Z" level=debug msg="app state loaded" Nov 04 18:47:34 volumio go-librespot[15016]: time="2025-11-04T18:47:34Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 18:47:34 volumio go-librespot[15016]: time="2025-11-04T18:47:34Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Nov 04 18:47:34 volumio go-librespot[15016]: time="2025-11-04T18:47:34Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Nov 04 18:47:34 volumio go-librespot[15016]: time="2025-11-04T18:47:34Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Nov 04 18:47:34 volumio go-librespot[15016]: time="2025-11-04T18:47:34Z" level=info msg="zeroconf server listening on port 45369" Nov 04 18:47:34 volumio go-librespot[15016]: time="2025-11-04T18:47:34Z" level=debug msg="obtained new client token: AACuh+VSLzqJwhnlAz6Vj++hbAFADtNBQz32l54/nqK7aPxE+d0v5AE9YWZjIBgZlmzz8IORG8hVfMaD1EEdyRW9gaOP5kERPK5y/n9NSdX9etMIm8vUmM2LS0PfM0xVr7V/wNRf+qL8bQlUUIttl+OYHHruYAgar+l3zZW+nXmbHJSyC0vVFQVgJf9v3rxQnSYl/ZcQYac2y+M7j079G1w+1xMjfYIFFzdUCrahKMyHBPNCTBjmzro=" Nov 04 18:47:34 volumio go-librespot[15016]: time="2025-11-04T18:47:34Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 18:47:34 volumio go-librespot[15016]: time="2025-11-04T18:47:34Z" level=debug msg="completed keyexchange" Nov 04 18:47:34 volumio go-librespot[15016]: time="2025-11-04T18:47:34Z" level=debug msg="completed challenge" Nov 04 18:47:34 volumio go-librespot[15016]: time="2025-11-04T18:47:34Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 04 18:47:34 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 18:47:34 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 18:47:36 volumio volumio[13516]: info: Initializing connection to go-librespot Websocket Nov 04 18:47:36 volumio volumio[13516]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 18:47:37 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 87. Nov 04 18:47:37 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 18:47:37 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 18:47:37 volumio go-librespot[15026]: go-librespot daemon starting... Nov 04 18:47:37 volumio go-librespot[15027]: time="2025-11-04T18:47:37Z" level=info msg="running go-librespot 0.4.0" Nov 04 18:47:37 volumio go-librespot[15027]: time="2025-11-04T18:47:37Z" level=debug msg="app state loaded" Nov 04 18:47:37 volumio go-librespot[15027]: time="2025-11-04T18:47:37Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 18:47:37 volumio go-librespot[15027]: time="2025-11-04T18:47:37Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 04 18:47:37 volumio go-librespot[15027]: time="2025-11-04T18:47:37Z" 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 04 18:47:37 volumio go-librespot[15027]: time="2025-11-04T18:47:37Z" 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 04 18:47:37 volumio go-librespot[15027]: time="2025-11-04T18:47:37Z" level=info msg="zeroconf server listening on port 43163" Nov 04 18:47:37 volumio go-librespot[15027]: time="2025-11-04T18:47:37Z" level=debug msg="obtained new client token: AACAjg0VUtVi6OfhdWh1QU6rEyirua3dyuXFKD4KiITkdKjlXEOxqFzp4FRqxW8y4LRpZUGbhLpq9XDWNch7bhhxLKsQsvryJ5FUgHXkrnPGrkzo0v0ZtZMq0yGI9yP0D6YRK0VVqNNSMjJUwL9T0t+i/swaCCxGpzcg8NJN1P7SwGhouXUD4aJ7quLNXZvF8BnhMg5pKfCzA1i5HFon4VxdFLDvCXXknF1IzJLk1DmONOHs0+3Au18=" Nov 04 18:47:37 volumio go-librespot[15027]: time="2025-11-04T18:47:37Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 18:47:38 volumio go-librespot[15027]: time="2025-11-04T18:47:38Z" level=debug msg="completed keyexchange" Nov 04 18:47:38 volumio go-librespot[15027]: time="2025-11-04T18:47:38Z" level=debug msg="completed challenge" Nov 04 18:47:38 volumio go-librespot[15027]: time="2025-11-04T18:47: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 04 18:47:38 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 18:47:38 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 18:47:38 volumio volumio[13516]: info: CoreCommandRouter::volumioGetState Nov 04 18:47:38 volumio volumio[13516]: info: CorePlayQueue::getTrack 0 Nov 04 18:47:39 volumio volumio[13516]: info: Initializing connection to go-librespot Websocket Nov 04 18:47:39 volumio volumio[13516]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 18:47:41 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 88. Nov 04 18:47:41 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 18:47:41 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 18:47:41 volumio go-librespot[15049]: go-librespot daemon starting... Nov 04 18:47:41 volumio go-librespot[15050]: time="2025-11-04T18:47:41Z" level=info msg="running go-librespot 0.4.0" Nov 04 18:47:41 volumio go-librespot[15050]: time="2025-11-04T18:47:41Z" level=debug msg="app state loaded" Nov 04 18:47:41 volumio go-librespot[15050]: time="2025-11-04T18:47:41Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 18:47:41 volumio go-librespot[15050]: time="2025-11-04T18:47: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-gew4.spotify.com:80]" Nov 04 18:47:41 volumio go-librespot[15050]: time="2025-11-04T18:47:41Z" 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 04 18:47:41 volumio go-librespot[15050]: time="2025-11-04T18:47:41Z" 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 04 18:47:41 volumio go-librespot[15050]: time="2025-11-04T18:47:41Z" level=info msg="zeroconf server listening on port 35859" Nov 04 18:47:41 volumio go-librespot[15050]: time="2025-11-04T18:47:41Z" level=debug msg="obtained new client token: AACTP9MYdpGDl3RIrxsgeEur2f4CfUhdDMc45QZoMOi9QdlRowiAQR12KPPK7wZWMD8uy0VR2U/pL0rSUccThr/1sWNm7zxevXI7MrTldSuezNlYGgWLssNzT4Vr/sU7BBqZlGO7EM1jOIQ14J5i/VkwW7+Xjjpo+ZIh4BzdUfm8CAadWSgPSQfAudV3hR2iZS/gTjOO6RbuInNXleGLZ1s1tL3AcI3rFXtCeHbVulS7jRXcO+pBP5Y=" Nov 04 18:47:41 volumio go-librespot[15050]: time="2025-11-04T18:47:41Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 18:47:41 volumio go-librespot[15050]: time="2025-11-04T18:47:41Z" level=debug msg="completed keyexchange" Nov 04 18:47:41 volumio go-librespot[15050]: time="2025-11-04T18:47:41Z" level=debug msg="completed challenge" Nov 04 18:47:41 volumio go-librespot[15050]: time="2025-11-04T18:47:41Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 04 18:47:41 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 18:47:41 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 18:47:42 volumio volumio[13516]: info: Initializing connection to go-librespot Websocket Nov 04 18:47:42 volumio volumio[13516]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 18:47:44 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 89. Nov 04 18:47:44 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 18:47:44 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 18:47:44 volumio go-librespot[15057]: go-librespot daemon starting... Nov 04 18:47:44 volumio go-librespot[15058]: time="2025-11-04T18:47:44Z" level=info msg="running go-librespot 0.4.0" Nov 04 18:47:44 volumio go-librespot[15058]: time="2025-11-04T18:47:44Z" level=debug msg="app state loaded" Nov 04 18:47:44 volumio go-librespot[15058]: time="2025-11-04T18:47:44Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 18:47:44 volumio go-librespot[15058]: time="2025-11-04T18:47:44Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 04 18:47:44 volumio go-librespot[15058]: time="2025-11-04T18:47:44Z" 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 04 18:47:44 volumio go-librespot[15058]: time="2025-11-04T18:47:44Z" 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 04 18:47:44 volumio go-librespot[15058]: time="2025-11-04T18:47:44Z" level=info msg="zeroconf server listening on port 43923" Nov 04 18:47:44 volumio go-librespot[15058]: time="2025-11-04T18:47:44Z" level=debug msg="obtained new client token: AADCy+4T0RFR93nE0Bc5lb7NDsfdNciC0sghTteq3ZuXoti1xz0BmGJWFmn9vvymUYI67NrZFWMr/DXit2PfvTWEC0XtMlw7vS9fjJo2IToOh7srQ4nmT+wbFnBk8DyDipbog5Rec03An/v/mLXPZuHR4f/H+mE81YcYfLVgoDOVMgp3HLdRtbGYttxbQSiYW6n6c9wEbCLPu45/r1j0nnD935ve0LhLyM0NKk3ZBAMKqcJgcqfxuqs=" Nov 04 18:47:44 volumio go-librespot[15058]: time="2025-11-04T18:47:44Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 18:47:44 volumio go-librespot[15058]: time="2025-11-04T18:47:44Z" level=debug msg="completed keyexchange" Nov 04 18:47:44 volumio go-librespot[15058]: time="2025-11-04T18:47:44Z" level=debug msg="completed challenge" Nov 04 18:47:45 volumio go-librespot[15058]: time="2025-11-04T18:47: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 04 18:47:45 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 18:47:45 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 18:47:45 volumio volumio[13516]: info: Initializing connection to go-librespot Websocket Nov 04 18:47:45 volumio volumio[13516]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 18:47:48 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 90. Nov 04 18:47:48 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 18:47:48 volumio volumio[13516]: info: CoreCommandRouter::volumioGetState Nov 04 18:47:48 volumio volumio[13516]: info: CorePlayQueue::getTrack 0 Nov 04 18:47:48 volumio volumio[13516]: info: Listing playlists Nov 04 18:47:48 volumio volumio[13516]: info: Listing playlists Nov 04 18:47:48 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 18:47:48 volumio go-librespot[15065]: go-librespot daemon starting... Nov 04 18:47:48 volumio go-librespot[15066]: time="2025-11-04T18:47:48Z" level=info msg="running go-librespot 0.4.0" Nov 04 18:47:48 volumio go-librespot[15066]: time="2025-11-04T18:47:48Z" level=debug msg="app state loaded" Nov 04 18:47:48 volumio go-librespot[15066]: time="2025-11-04T18:47:48Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 18:47:48 volumio go-librespot[15066]: time="2025-11-04T18:47:48Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Nov 04 18:47:48 volumio go-librespot[15066]: time="2025-11-04T18:47:48Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Nov 04 18:47:48 volumio go-librespot[15066]: time="2025-11-04T18:47:48Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Nov 04 18:47:48 volumio go-librespot[15066]: time="2025-11-04T18:47:48Z" level=info msg="zeroconf server listening on port 33509" Nov 04 18:47:48 volumio go-librespot[15066]: time="2025-11-04T18:47:48Z" level=debug msg="obtained new client token: AACWmSr7IvKuGXc1DuepekVfS6Yq9dDKgRtMbUCt9y8LqYIKzX0Ml7TDoi2wJ2BBk8IOT7dRlNeTGxjGO91Mge6xXWKs3srZ3Zj6YoB/36zd/JCDuxeA3QCyTzwyPzZF6Y1S8jSgdxbtgncPKyVeOtp8ZpExER9X/3DcmRQ3H6GO+dB8vLd6XpSECZG0VI11R1mYnsAld0TKxnIORIyjGETgXgOJg56cce1d5K6Z3mnsEW77QghXj7E=" Nov 04 18:47:48 volumio go-librespot[15066]: time="2025-11-04T18:47:48Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 18:47:48 volumio go-librespot[15066]: time="2025-11-04T18:47:48Z" level=debug msg="completed keyexchange" Nov 04 18:47:48 volumio go-librespot[15066]: time="2025-11-04T18:47:48Z" level=debug msg="completed challenge" Nov 04 18:47:48 volumio go-librespot[15066]: time="2025-11-04T18:47:48Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 04 18:47:48 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 18:47:48 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 18:47:48 volumio volumio[13516]: info: Initializing connection to go-librespot Websocket Nov 04 18:47:48 volumio volumio[13516]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 18:47:51 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 91. Nov 04 18:47:51 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 18:47:51 volumio volumio[13516]: info: Initializing connection to go-librespot Websocket Nov 04 18:47:51 volumio volumio[13516]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 18:47:51 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 18:47:51 volumio go-librespot[15087]: go-librespot daemon starting... Nov 04 18:47:51 volumio go-librespot[15088]: time="2025-11-04T18:47:51Z" level=info msg="running go-librespot 0.4.0" Nov 04 18:47:51 volumio go-librespot[15088]: time="2025-11-04T18:47:51Z" level=debug msg="app state loaded" Nov 04 18:47:51 volumio go-librespot[15088]: time="2025-11-04T18:47:51Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 18:47:51 volumio go-librespot[15088]: time="2025-11-04T18:47:51Z" 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 04 18:47:51 volumio go-librespot[15088]: time="2025-11-04T18:47:51Z" 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 04 18:47:51 volumio go-librespot[15088]: time="2025-11-04T18:47:51Z" 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 04 18:47:51 volumio go-librespot[15088]: time="2025-11-04T18:47:51Z" level=info msg="zeroconf server listening on port 34467" Nov 04 18:47:51 volumio go-librespot[15088]: time="2025-11-04T18:47:51Z" level=debug msg="obtained new client token: AADj3j2KnuWyP5OYRBGNycPKvT6F5/mYrE6EFGxhO5pn58wC1Kpcl8GBlrpDUYxE0xhlC5QXWVz11VXQ1K5y6Xaen5CNoRrkRMYOctjxwWZwIRdZ8HQS31MZ9UHSgj5VzL31KJPbEYAjUr6BkEurXEaZTqB+j8Crd9eAAl98yChM+Fw8vphLWONVWUfl4xCpXqXb4j8j/ErPfvG3TP5W2vKoxm/bUK6epCOpqdKKtIgYEnFMpBD6ZtQ=" Nov 04 18:47:51 volumio go-librespot[15088]: time="2025-11-04T18:47:51Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 18:47:52 volumio go-librespot[15088]: time="2025-11-04T18:47:52Z" level=debug msg="completed keyexchange" Nov 04 18:47:52 volumio go-librespot[15088]: time="2025-11-04T18:47:52Z" level=debug msg="completed challenge" Nov 04 18:47:52 volumio go-librespot[15088]: time="2025-11-04T18:47: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 04 18:47:52 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 18:47:52 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 18:47:54 volumio volumio[13516]: info: Initializing connection to go-librespot Websocket Nov 04 18:47:54 volumio volumio[13516]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 18:47:55 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 92. Nov 04 18:47:55 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 18:47:55 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 18:47:55 volumio go-librespot[15096]: go-librespot daemon starting... Nov 04 18:47:55 volumio go-librespot[15097]: time="2025-11-04T18:47:55Z" level=info msg="running go-librespot 0.4.0" Nov 04 18:47:55 volumio go-librespot[15097]: time="2025-11-04T18:47:55Z" level=debug msg="app state loaded" Nov 04 18:47:55 volumio go-librespot[15097]: time="2025-11-04T18:47:55Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 18:47:55 volumio go-librespot[15097]: time="2025-11-04T18:47:55Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 04 18:47:55 volumio go-librespot[15097]: time="2025-11-04T18:47:55Z" 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 04 18:47:55 volumio go-librespot[15097]: time="2025-11-04T18:47:55Z" 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 04 18:47:55 volumio go-librespot[15097]: time="2025-11-04T18:47:55Z" level=info msg="zeroconf server listening on port 40747" Nov 04 18:47:55 volumio go-librespot[15097]: time="2025-11-04T18:47:55Z" level=debug msg="obtained new client token: AADSE51Br9+rkHCTSNeXxzZYeDHfIzE4SAwIxDs/UMQSYn5OVScvPIgMjjVIY58Wjyz2E1iQsRykWdFQqTSugm7fDGRFYfzitQ7HKDugutOAdi/jbVpRLeOhBFu+Cs8rJjfnBhQ65k/+BsWbVzJqAalMzbF9Etgv6s3dVrPpr+ZNsU1xn1qmOcRJoVwy0H7Bx1C4c7RCrEmYXKruTgtKktVSz8OPjgWPr0YytUF17soE5vZ+gadylX0=" Nov 04 18:47:55 volumio go-librespot[15097]: time="2025-11-04T18:47:55Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 18:47:55 volumio go-librespot[15097]: time="2025-11-04T18:47:55Z" level=debug msg="completed keyexchange" Nov 04 18:47:55 volumio go-librespot[15097]: time="2025-11-04T18:47:55Z" level=debug msg="completed challenge" Nov 04 18:47:55 volumio go-librespot[15097]: time="2025-11-04T18:47:55Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 04 18:47:55 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 18:47:55 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 18:47:57 volumio volumio[13516]: info: Initializing connection to go-librespot Websocket Nov 04 18:47:57 volumio volumio[13516]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 18:47:58 volumio volumio[13516]: info: CoreCommandRouter::volumioGetState Nov 04 18:47:58 volumio volumio[13516]: info: CorePlayQueue::getTrack 0 Nov 04 18:47:58 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 93. Nov 04 18:47:58 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 18:47:58 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 18:47:58 volumio go-librespot[15107]: go-librespot daemon starting... Nov 04 18:47:58 volumio go-librespot[15108]: time="2025-11-04T18:47:58Z" level=info msg="running go-librespot 0.4.0" Nov 04 18:47:58 volumio go-librespot[15108]: time="2025-11-04T18:47:58Z" level=debug msg="app state loaded" Nov 04 18:47:58 volumio go-librespot[15108]: time="2025-11-04T18:47:58Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 18:47:58 volumio go-librespot[15108]: time="2025-11-04T18:47:58Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 04 18:47:58 volumio go-librespot[15108]: time="2025-11-04T18:47:58Z" 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 04 18:47:58 volumio go-librespot[15108]: time="2025-11-04T18:47:58Z" 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 04 18:47:58 volumio go-librespot[15108]: time="2025-11-04T18:47:58Z" level=info msg="zeroconf server listening on port 39489" Nov 04 18:47:58 volumio go-librespot[15108]: time="2025-11-04T18:47:58Z" level=debug msg="obtained new client token: AABkzPAdNBF7nWrBnSU1TEFYGWBsPCjTl8uX+H++hLFyC5NqSppTzCEk7jKLGK8PIAJOGclefRMe9pWuWKAuzWCGykuQybLU93fJmfoVLa+HpfAnZRrBX7y0QtgUWqx8xET4/n76HG878QNu8js4jQheRTHM0nJDYGhINzY8jGu49vr5/hRdeuPwZ7/M/2PQAr/iE4oJ6E8FVZsnav5nacTetrXkxkgk7pRXqxNCuV2uhQ0b0w0dF2k=" Nov 04 18:47:58 volumio go-librespot[15108]: time="2025-11-04T18:47:58Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 18:47:59 volumio go-librespot[15108]: time="2025-11-04T18:47:59Z" level=debug msg="completed keyexchange" Nov 04 18:47:59 volumio go-librespot[15108]: time="2025-11-04T18:47:59Z" level=debug msg="completed challenge" Nov 04 18:47:59 volumio go-librespot[15108]: time="2025-11-04T18:47:59Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 04 18:47:59 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 18:47:59 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 18:48:00 volumio volumio[13516]: info: Initializing connection to go-librespot Websocket Nov 04 18:48:00 volumio volumio[13516]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 18:48:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 94. Nov 04 18:48:02 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 18:48:02 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 18:48:02 volumio go-librespot[15130]: go-librespot daemon starting... Nov 04 18:48:02 volumio go-librespot[15131]: time="2025-11-04T18:48:02Z" level=info msg="running go-librespot 0.4.0" Nov 04 18:48:02 volumio go-librespot[15131]: time="2025-11-04T18:48:02Z" level=debug msg="app state loaded" Nov 04 18:48:02 volumio go-librespot[15131]: time="2025-11-04T18:48:02Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 18:48:02 volumio go-librespot[15131]: time="2025-11-04T18:48:02Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 04 18:48:02 volumio go-librespot[15131]: time="2025-11-04T18:48:02Z" 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 04 18:48:02 volumio go-librespot[15131]: time="2025-11-04T18:48:02Z" 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 04 18:48:02 volumio go-librespot[15131]: time="2025-11-04T18:48:02Z" level=info msg="zeroconf server listening on port 41895" Nov 04 18:48:02 volumio go-librespot[15131]: time="2025-11-04T18:48:02Z" level=debug msg="obtained new client token: AADksOAeZC1/LZvufwirr98FVndY7K1uLPdkIdA8cF9HPUjGOwHYj2Z9dL1CXYTK9ZWYM5X5GTGfJ/zPhG8J6WYCs8oIBCjNLqrlGTLT411bzVq6xqdb8TICh8b9xOCxAnQQTpVnRy/sxoaCq3HF+Iss3vi1N1XpcJbv7K4GAahaZF2BoZ20OJkITj7u+hlsdmoRk21eLi0e67fg8YIWAPSoK2Bvbu8GzsbfL/fvyhygc3VLjbcYOyc=" Nov 04 18:48:02 volumio go-librespot[15131]: time="2025-11-04T18:48:02Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 18:48:02 volumio go-librespot[15131]: time="2025-11-04T18:48:02Z" level=debug msg="completed keyexchange" Nov 04 18:48:02 volumio go-librespot[15131]: time="2025-11-04T18:48:02Z" level=debug msg="completed challenge" Nov 04 18:48:02 volumio go-librespot[15131]: time="2025-11-04T18:48:02Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 04 18:48:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 18:48:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 18:48:03 volumio volumio[13516]: info: Initializing connection to go-librespot Websocket Nov 04 18:48:03 volumio volumio[13516]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 18:48:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 95. Nov 04 18:48:05 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 18:48:05 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 18:48:05 volumio go-librespot[15138]: go-librespot daemon starting... Nov 04 18:48:05 volumio go-librespot[15139]: time="2025-11-04T18:48:05Z" level=info msg="running go-librespot 0.4.0" Nov 04 18:48:05 volumio go-librespot[15139]: time="2025-11-04T18:48:05Z" level=debug msg="app state loaded" Nov 04 18:48:05 volumio go-librespot[15139]: time="2025-11-04T18:48:05Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 18:48:05 volumio go-librespot[15139]: time="2025-11-04T18:48:05Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Nov 04 18:48:05 volumio go-librespot[15139]: time="2025-11-04T18:48:05Z" 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 04 18:48:05 volumio go-librespot[15139]: time="2025-11-04T18:48:05Z" 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 04 18:48:05 volumio go-librespot[15139]: time="2025-11-04T18:48:05Z" level=info msg="zeroconf server listening on port 45637" Nov 04 18:48:05 volumio go-librespot[15139]: time="2025-11-04T18:48:05Z" level=debug msg="obtained new client token: AAA/bMQ1kaBdDhlTQX2hNS/yLKVunRE6zU1QDbk+69R1G95iuX7dRqh0vXaqmCauvVEsyalHDnyD1HVuCdtao4oPF1imj0gDxh+/NQtZfvFS0VbE1tsb7YbQ6fVvOCq50CbLoQVCaEukg1aHs4UfXunOOR+pJu+RhZjDC5HoC5nJ/5XQntNYncbKZbx5JUlcP1eGzpdHhLv3QisN3xar2StWvZmdVg+FjSDGMIg4EeaRC+sVTpVhtmU=" Nov 04 18:48:05 volumio go-librespot[15139]: time="2025-11-04T18:48:05Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 18:48:06 volumio go-librespot[15139]: time="2025-11-04T18:48:06Z" level=debug msg="completed keyexchange" Nov 04 18:48:06 volumio go-librespot[15139]: time="2025-11-04T18:48:06Z" level=debug msg="completed challenge" Nov 04 18:48:06 volumio go-librespot[15139]: time="2025-11-04T18:48:06Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 04 18:48:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 18:48:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 18:48:06 volumio volumio[13516]: info: Initializing connection to go-librespot Websocket Nov 04 18:48:06 volumio volumio[13516]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 18:48:08 volumio volumio[13516]: info: CoreCommandRouter::volumioGetState Nov 04 18:48:08 volumio volumio[13516]: info: CorePlayQueue::getTrack 0 Nov 04 18:48:08 volumio volumio[13516]: info: Listing playlists Nov 04 18:48:08 volumio volumio[13516]: info: Listing playlists Nov 04 18:48:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 96. Nov 04 18:48:09 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 18:48:09 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 18:48:09 volumio go-librespot[15147]: go-librespot daemon starting... Nov 04 18:48:09 volumio go-librespot[15148]: time="2025-11-04T18:48:09Z" level=info msg="running go-librespot 0.4.0" Nov 04 18:48:09 volumio go-librespot[15148]: time="2025-11-04T18:48:09Z" level=debug msg="app state loaded" Nov 04 18:48:09 volumio go-librespot[15148]: time="2025-11-04T18:48:09Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 18:48:09 volumio go-librespot[15148]: time="2025-11-04T18:48:09Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 04 18:48:09 volumio go-librespot[15148]: time="2025-11-04T18:48:09Z" 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 04 18:48:09 volumio go-librespot[15148]: time="2025-11-04T18:48:09Z" 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 04 18:48:09 volumio go-librespot[15148]: time="2025-11-04T18:48:09Z" level=info msg="zeroconf server listening on port 40687" Nov 04 18:48:09 volumio go-librespot[15148]: time="2025-11-04T18:48:09Z" level=debug msg="obtained new client token: AAA5SMCc87Nj6E3Fjp6Cv5fxOn7SMSXRIl5b3I2d2BKcAzEWzjw2YAjWqy6M5irex6vflDUhJne/meVwm85my0s16AD/yEbHSlDq67FpNyxcc8tRRFXgUe/ckCoIIkd1VFriJGuVCRNVEqC+AHmPryI+LlHewzGU91eS6Az0f0zbBPZ+fe52fODQ84xcBICIApGHAdo++APIoG2kN4odZnO3OSZgjhlnj9eNBPaBRLqCDvKjcCdnq2s=" Nov 04 18:48:09 volumio go-librespot[15148]: time="2025-11-04T18:48:09Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 18:48:09 volumio go-librespot[15148]: time="2025-11-04T18:48:09Z" level=debug msg="completed keyexchange" Nov 04 18:48:09 volumio go-librespot[15148]: time="2025-11-04T18:48:09Z" level=debug msg="completed challenge" Nov 04 18:48:09 volumio go-librespot[15148]: time="2025-11-04T18:48:09Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 04 18:48:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 18:48:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 18:48:09 volumio volumio[13516]: info: Initializing connection to go-librespot Websocket Nov 04 18:48:09 volumio volumio[13516]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 18:48:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 97. Nov 04 18:48:12 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 18:48:12 volumio volumio[13516]: info: Initializing connection to go-librespot Websocket Nov 04 18:48:12 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 18:48:12 volumio go-librespot[15170]: go-librespot daemon starting... Nov 04 18:48:12 volumio volumio[13516]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 18:48:12 volumio go-librespot[15171]: time="2025-11-04T18:48:12Z" level=info msg="running go-librespot 0.4.0" Nov 04 18:48:12 volumio go-librespot[15171]: time="2025-11-04T18:48:12Z" level=debug msg="app state loaded" Nov 04 18:48:12 volumio go-librespot[15171]: time="2025-11-04T18:48:12Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 18:48:12 volumio go-librespot[15171]: time="2025-11-04T18:48:12Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 04 18:48:12 volumio go-librespot[15171]: time="2025-11-04T18:48:12Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 04 18:48:12 volumio go-librespot[15171]: time="2025-11-04T18:48:12Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 04 18:48:12 volumio go-librespot[15171]: time="2025-11-04T18:48:12Z" level=info msg="zeroconf server listening on port 34239" Nov 04 18:48:12 volumio go-librespot[15171]: time="2025-11-04T18:48:12Z" level=debug msg="obtained new client token: AADzNFz56Qba/W4lDJuOEzfWiNhLePErABHMEAxmor21mMfhQKoqCfv8mvVxdGWYilu5/hLdksYGr5zIs8rr4Vx/Y12nD+1TSn87l97eQQdroUaqHTRq0nQ+Zr6O4LFLc5GUo3hDRBrOlgPP2UZcdVufiR7iVFBzw0JpP/PoLW6VcSTD4ibm6DCK7Ybae2P853/QJKjFk/jnv4hRts4J5Q3npH6G+Y6+wxW7Ge1CVe9TLWnllRN4wzc=" Nov 04 18:48:12 volumio go-librespot[15171]: time="2025-11-04T18:48:12Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 18:48:13 volumio go-librespot[15171]: time="2025-11-04T18:48:13Z" level=debug msg="completed keyexchange" Nov 04 18:48:13 volumio go-librespot[15171]: time="2025-11-04T18:48:13Z" level=debug msg="completed challenge" Nov 04 18:48:13 volumio go-librespot[15171]: time="2025-11-04T18:48:13Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 04 18:48:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 18:48:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 18:48:15 volumio volumio[13516]: info: Initializing connection to go-librespot Websocket Nov 04 18:48:15 volumio volumio[13516]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 18:48:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 98. Nov 04 18:48:16 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 18:48:16 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 18:48:16 volumio go-librespot[15179]: go-librespot daemon starting... Nov 04 18:48:16 volumio go-librespot[15180]: time="2025-11-04T18:48:16Z" level=info msg="running go-librespot 0.4.0" Nov 04 18:48:16 volumio go-librespot[15180]: time="2025-11-04T18:48:16Z" level=debug msg="app state loaded" Nov 04 18:48:16 volumio go-librespot[15180]: time="2025-11-04T18:48:16Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 18:48:16 volumio go-librespot[15180]: time="2025-11-04T18:48:16Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Nov 04 18:48:16 volumio go-librespot[15180]: time="2025-11-04T18:48:16Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Nov 04 18:48:16 volumio go-librespot[15180]: time="2025-11-04T18:48:16Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Nov 04 18:48:16 volumio go-librespot[15180]: time="2025-11-04T18:48:16Z" level=info msg="zeroconf server listening on port 46375" Nov 04 18:48:16 volumio go-librespot[15180]: time="2025-11-04T18:48:16Z" level=debug msg="obtained new client token: AAA1bGuAQ4dem57ABIndAxbli4S1q7fus3pkksKzxk5ZM+0UK+bBmu8GzAf2LV+JHrrhWpqyf8QY/0OuFMsU50HUvjI1hSPYAgcuJ2bGdqv0AGQ6ja36H80TjY+PQ0cMauOrCrwjbku9OpktEcdQsIXJIWJofvezXl/I2mJ2OGwnfhY8f4hptEpf4HlcYUeSBxYyzFgeJJqWKmXRHbD9A9rdDulMfKKXOEoj5dkGw/Fm0kIePw/PIQk=" Nov 04 18:48:16 volumio go-librespot[15180]: time="2025-11-04T18:48:16Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 18:48:16 volumio go-librespot[15180]: time="2025-11-04T18:48:16Z" level=debug msg="completed keyexchange" Nov 04 18:48:16 volumio go-librespot[15180]: time="2025-11-04T18:48:16Z" level=debug msg="completed challenge" Nov 04 18:48:16 volumio go-librespot[15180]: time="2025-11-04T18:48:16Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 04 18:48:16 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 18:48:16 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 18:48:18 volumio volumio[13516]: info: CoreCommandRouter::volumioGetState Nov 04 18:48:18 volumio volumio[13516]: info: CorePlayQueue::getTrack 0 Nov 04 18:48:18 volumio volumio[13516]: info: Initializing connection to go-librespot Websocket Nov 04 18:48:18 volumio volumio[13516]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 18:48:19 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 99. Nov 04 18:48:19 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 18:48:19 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 18:48:19 volumio go-librespot[15190]: go-librespot daemon starting... Nov 04 18:48:19 volumio go-librespot[15191]: time="2025-11-04T18:48:19Z" level=info msg="running go-librespot 0.4.0" Nov 04 18:48:19 volumio go-librespot[15191]: time="2025-11-04T18:48:19Z" level=debug msg="app state loaded" Nov 04 18:48:19 volumio go-librespot[15191]: time="2025-11-04T18:48:19Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 18:48:19 volumio go-librespot[15191]: time="2025-11-04T18:48:19Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 04 18:48:19 volumio go-librespot[15191]: time="2025-11-04T18:48:19Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 04 18:48:19 volumio go-librespot[15191]: time="2025-11-04T18:48:19Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 04 18:48:19 volumio go-librespot[15191]: time="2025-11-04T18:48:19Z" level=info msg="zeroconf server listening on port 36523" Nov 04 18:48:19 volumio go-librespot[15191]: time="2025-11-04T18:48:19Z" level=debug msg="obtained new client token: AAD73N6t9RRYB64m0zK95CaFi0xXo6fCd18/pJfgWj9dNR9eRm42hEcFxeBcQ1aHbnZUVBjjFReSH29UEfgM/YjTnsfIF07jgVGfMTkh3QhtXD31hLlzyfR2z1n6PMAYz2LmNS6cMwdVkmZ7kPR4RgkQzbZc3bDtDIggYL8Z5hGiKaxGlfPYboPoHs0SE2t34UGQ3yT7YvhKrSpmKQb5YxByFpFx5xB40ASuwdZEfyGBxBIP4oKdMvI=" Nov 04 18:48:19 volumio go-librespot[15191]: time="2025-11-04T18:48:19Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 18:48:20 volumio go-librespot[15191]: time="2025-11-04T18:48:20Z" level=debug msg="completed keyexchange" Nov 04 18:48:20 volumio go-librespot[15191]: time="2025-11-04T18:48:20Z" level=debug msg="completed challenge" Nov 04 18:48:20 volumio go-librespot[15191]: time="2025-11-04T18:48:20Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 04 18:48:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 18:48:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 18:48:21 volumio volumio[13516]: info: Initializing connection to go-librespot Websocket Nov 04 18:48:21 volumio volumio[13516]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 18:48:23 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 100. Nov 04 18:48:23 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 18:48:23 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 18:48:23 volumio go-librespot[15214]: go-librespot daemon starting... Nov 04 18:48:23 volumio go-librespot[15215]: time="2025-11-04T18:48:23Z" level=info msg="running go-librespot 0.4.0" Nov 04 18:48:23 volumio go-librespot[15215]: time="2025-11-04T18:48:23Z" level=debug msg="app state loaded" Nov 04 18:48:23 volumio go-librespot[15215]: time="2025-11-04T18:48:23Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 18:48:23 volumio go-librespot[15215]: time="2025-11-04T18:48:23Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 04 18:48:23 volumio go-librespot[15215]: time="2025-11-04T18:48:23Z" 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 04 18:48:23 volumio go-librespot[15215]: time="2025-11-04T18:48:23Z" 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 04 18:48:23 volumio go-librespot[15215]: time="2025-11-04T18:48:23Z" level=info msg="zeroconf server listening on port 37347" Nov 04 18:48:23 volumio go-librespot[15215]: time="2025-11-04T18:48:23Z" level=debug msg="obtained new client token: AADiM0LTTko/uo/IXrtTyOsCK9nb+YjImNkG0gfVXBXDe+GX339K8qS7GhHFspoyuXbKEtZotgjIGRBKaDVzodwJZ/andXCtpQkjOIYVVSs+7zJ4+1iTzwKQs9nJNAcok7fKk0rXJTRtxmwwQKiSW3ZytuMkwcBV48iQzUtzVul54Ux9n1XOOhb0fImTHxJQt/WySvgsOKuSq7w1ZmkNgC0qL066YKdhLfgH7hWn6YATUJPUfCFSrQw=" Nov 04 18:48:23 volumio go-librespot[15215]: time="2025-11-04T18:48:23Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 18:48:23 volumio go-librespot[15215]: time="2025-11-04T18:48:23Z" level=debug msg="completed keyexchange" Nov 04 18:48:23 volumio go-librespot[15215]: time="2025-11-04T18:48:23Z" level=debug msg="completed challenge" Nov 04 18:48:23 volumio go-librespot[15215]: time="2025-11-04T18:48:23Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 04 18:48:23 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 18:48:23 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 18:48:24 volumio volumio[13516]: info: Initializing connection to go-librespot Websocket Nov 04 18:48:24 volumio volumio[13516]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 18:48:26 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 101. Nov 04 18:48:26 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 18:48:26 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 18:48:26 volumio go-librespot[15222]: go-librespot daemon starting... Nov 04 18:48:26 volumio go-librespot[15223]: time="2025-11-04T18:48:26Z" level=info msg="running go-librespot 0.4.0" Nov 04 18:48:26 volumio go-librespot[15223]: time="2025-11-04T18:48:26Z" level=debug msg="app state loaded" Nov 04 18:48:26 volumio go-librespot[15223]: time="2025-11-04T18:48:26Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 18:48:26 volumio go-librespot[15223]: time="2025-11-04T18:48:26Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 04 18:48:26 volumio go-librespot[15223]: time="2025-11-04T18:48:26Z" 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 04 18:48:26 volumio go-librespot[15223]: time="2025-11-04T18:48:26Z" 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 04 18:48:26 volumio go-librespot[15223]: time="2025-11-04T18:48:26Z" level=info msg="zeroconf server listening on port 45383" Nov 04 18:48:26 volumio go-librespot[15223]: time="2025-11-04T18:48:26Z" level=debug msg="obtained new client token: AACrIxoDbaGTQvJE3IK2/Bmr4QW+MKl2JMescMzcGwEV5vxH/njJA3T3RahRf02wGJX4TJtq+BRFCFZ5uFX1ny/tHnZqXDU1HMcG37qRMuRNZqm41kiRD7FH2U63M0n7OGIsBiWTSzQyg+RGBcUIz1wkHLzx3Ogn50HK+gmZYnsxRAs9r7ULbFRJd8bB5BHAX/qEwgcQbF2ESzsONurWbhk9RsbB67vSKEroGDkJbfudy4z3i2X6sdo=" Nov 04 18:48:26 volumio go-librespot[15223]: time="2025-11-04T18:48:26Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 18:48:27 volumio go-librespot[15223]: time="2025-11-04T18:48:27Z" level=debug msg="completed keyexchange" Nov 04 18:48:27 volumio go-librespot[15223]: time="2025-11-04T18:48:27Z" level=debug msg="completed challenge" Nov 04 18:48:27 volumio go-librespot[15223]: time="2025-11-04T18:48:27Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 04 18:48:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 18:48:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 18:48:27 volumio volumio[13516]: info: Initializing connection to go-librespot Websocket Nov 04 18:48:27 volumio volumio[13516]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 18:48:28 volumio volumio[13516]: info: CoreCommandRouter::volumioGetState Nov 04 18:48:28 volumio volumio[13516]: info: CorePlayQueue::getTrack 0 Nov 04 18:48:28 volumio volumio[13516]: info: Listing playlists Nov 04 18:48:28 volumio volumio[13516]: info: Listing playlists Nov 04 18:48:30 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 102. Nov 04 18:48:30 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 18:48:30 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 18:48:30 volumio go-librespot[15231]: go-librespot daemon starting... Nov 04 18:48:30 volumio go-librespot[15232]: time="2025-11-04T18:48:30Z" level=info msg="running go-librespot 0.4.0" Nov 04 18:48:30 volumio go-librespot[15232]: time="2025-11-04T18:48:30Z" level=debug msg="app state loaded" Nov 04 18:48:30 volumio go-librespot[15232]: time="2025-11-04T18:48:30Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 18:48:30 volumio go-librespot[15232]: time="2025-11-04T18:48:30Z" 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 04 18:48:30 volumio go-librespot[15232]: time="2025-11-04T18:48:30Z" 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 04 18:48:30 volumio go-librespot[15232]: time="2025-11-04T18:48:30Z" 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 04 18:48:30 volumio go-librespot[15232]: time="2025-11-04T18:48:30Z" level=info msg="zeroconf server listening on port 40707" Nov 04 18:48:30 volumio go-librespot[15232]: time="2025-11-04T18:48:30Z" level=debug msg="obtained new client token: AABImptLe15e1q6BK98HAUutV81FgIRzsuyQ7oisLkdXBc1vWYtoptj+KXaLqKug5tVrERC+p7tOwCcltapCY+EQijYoAH8CTYtLUwyyBaAvDzR+D5z7sXqVHCc2cpmdKARcrLXpEdvKg8/IWlrQIFwCNMdC96WGM9NffCwrLBDLXPs92XnHOs9+XrXD6h+25A/rm4VH0IxgCa2iZjSMYiGGIvPPwvaE4ItGga1G82sJgc6QlwLri7c=" Nov 04 18:48:30 volumio go-librespot[15232]: time="2025-11-04T18:48:30Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 18:48:30 volumio go-librespot[15232]: time="2025-11-04T18:48:30Z" level=debug msg="completed keyexchange" Nov 04 18:48:30 volumio go-librespot[15232]: time="2025-11-04T18:48:30Z" level=debug msg="completed challenge" Nov 04 18:48:30 volumio go-librespot[15232]: time="2025-11-04T18:48:30Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 04 18:48:30 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 18:48:30 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 18:48:30 volumio volumio[13516]: info: Initializing connection to go-librespot Websocket Nov 04 18:48:30 volumio volumio[13516]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 18:48:33 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 103. Nov 04 18:48:33 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 04 18:48:33 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 04 18:48:33 volumio go-librespot[15253]: go-librespot daemon starting... Nov 04 18:48:33 volumio go-librespot[15254]: time="2025-11-04T18:48:33Z" level=info msg="running go-librespot 0.4.0" Nov 04 18:48:33 volumio go-librespot[15254]: time="2025-11-04T18:48:33Z" level=debug msg="app state loaded" Nov 04 18:48:33 volumio go-librespot[15254]: time="2025-11-04T18:48:33Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 04 18:48:33 volumio volumio[13516]: info: Initializing connection to go-librespot Websocket Nov 04 18:48:33 volumio go-librespot[15254]: time="2025-11-04T18:48:33Z" level=debug msg="new websocket client" Nov 04 18:48:33 volumio volumio[13516]: info: Connection to go-librespot Websocket established Nov 04 18:48:33 volumio go-librespot[15254]: time="2025-11-04T18:48:33Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Nov 04 18:48:33 volumio go-librespot[15254]: time="2025-11-04T18:48:33Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 04 18:48:33 volumio go-librespot[15254]: time="2025-11-04T18:48:33Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 04 18:48:33 volumio go-librespot[15254]: time="2025-11-04T18:48:33Z" level=info msg="zeroconf server listening on port 44293" Nov 04 18:48:33 volumio go-librespot[15254]: time="2025-11-04T18:48:33Z" level=debug msg="obtained new client token: AABiQKjKyo1QDxR97p6nIyUL3G/Y+LLufyj4wbjvyCEp1+0qEWhkMAZ0pYVghBww41LNWUt7GmbtQ5p2bgssD0+YcIx95Goiikahlw23c0SpKCvc5jGaIy2zFGby5JPKK70k5vKY1MWwywVTf7mX8RqTELUem+1Q/5uVDwb559l7nkzOpKaOTl1q66Ux+ywPTQ87OxoTEVQ9wkcGO+BBckE6vjGwfNNBd7mlG81FCW/26NmpvzDPTJQ=" Nov 04 18:48:33 volumio go-librespot[15254]: time="2025-11-04T18:48:33Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 04 18:48:34 volumio go-librespot[15254]: time="2025-11-04T18:48:34Z" level=debug msg="completed keyexchange" Nov 04 18:48:34 volumio go-librespot[15254]: time="2025-11-04T18:48:34Z" level=debug msg="completed challenge" Nov 04 18:48:34 volumio go-librespot[15254]: time="2025-11-04T18:48:34Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Nov 04 18:48:34 volumio volumio[13516]: info: Connection to go-librespot Websocket closed Nov 04 18:48:34 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 04 18:48:34 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 04 18:48:36 volumio volumio[13516]: info: Getting Spotify volume Nov 04 18:48:36 volumio volumio[13516]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 04 18:48:36 volumio volumio[13516]: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 04 18:48:36 volumio volumio[13516]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Nov 04 18:48:36 volumio volumio[13516]: errno: -111, Nov 04 18:48:36 volumio volumio[13516]: code: 'ECONNREFUSED', Nov 04 18:48:36 volumio volumio[13516]: syscall: 'connect', Nov 04 18:48:36 volumio volumio[13516]: address: '127.0.0.1', Nov 04 18:48:36 volumio volumio[13516]: port: 9879, Nov 04 18:48:36 volumio volumio[13516]: response: undefined Nov 04 18:48:36 volumio volumio[13516]: } Nov 04 18:48:36 volumio volumio[13516]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 04 18:48:36 volumio sudo[15279]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-11-04 18:47' Nov 04 18:48:36 volumio sudo[15279]: 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"