Dec 21 16:18:00 volumio1920x1080 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Dec 21 16:18:00 volumio1920x1080 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 21 16:18:00 volumio1920x1080 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 21 16:18:00 volumio1920x1080 go-librespot[1856]: go-librespot daemon starting... Dec 21 16:18:00 volumio1920x1080 go-librespot[1857]: time="2025-12-21T16:18:00+01:00" level=info msg="running go-librespot 0.4.0" Dec 21 16:18:00 volumio1920x1080 go-librespot[1857]: time="2025-12-21T16:18:00+01:00" level=debug msg="app state loaded" Dec 21 16:18:00 volumio1920x1080 go-librespot[1857]: time="2025-12-21T16:18:00+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 21 16:18:00 volumio1920x1080 go-librespot[1857]: time="2025-12-21T16:18:00+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 21 16:18:00 volumio1920x1080 go-librespot[1857]: time="2025-12-21T16:18:00+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 21 16:18:00 volumio1920x1080 go-librespot[1857]: time="2025-12-21T16:18:00+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 21 16:18:00 volumio1920x1080 go-librespot[1857]: time="2025-12-21T16:18:00+01:00" level=info msg="zeroconf server listening on port 37277" Dec 21 16:18:00 volumio1920x1080 go-librespot[1857]: time="2025-12-21T16:18:00+01:00" level=debug msg="obtained new client token: AACYt8a8pKPuJxoeGR3RPk20DxsCqP6y/oekrqPgWTa7gbf8AkQkrBhY1ejyCAnVfNXNc3pY9wG6yjSDYRhzXMz2++vpkUR4gboim55LYygiUL3VbKEpIir+K+a3l8LaR96whQJ6le0fQ1NF3V8EQ7sHod/3Myo2CdbwMen2FSjw2GHTIj1UNQz9Uqh0h2rzN0mw2O4eG9qT5EDnAELFY6zjXGHlYfZuWbO4qw6v21kgHwB7NK0h6gcWPg==" Dec 21 16:18:00 volumio1920x1080 go-librespot[1857]: time="2025-12-21T16:18:00+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 21 16:18:00 volumio1920x1080 go-librespot[1857]: time="2025-12-21T16:18:00+01:00" level=debug msg="completed keyexchange" Dec 21 16:18:00 volumio1920x1080 go-librespot[1857]: time="2025-12-21T16:18:00+01:00" level=debug msg="completed challenge" Dec 21 16:18:01 volumio1920x1080 go-librespot[1857]: time="2025-12-21T16:18:01+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Dec 21 16:18:01 volumio1920x1080 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 21 16:18:01 volumio1920x1080 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 21 16:18:02 volumio1920x1080 volumio[1072]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 21 16:18:02 volumio1920x1080 volumio[1072]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 21 16:18:02 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 16:18:02 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 16:18:02 volumio1920x1080 volumio[1072]: info: Starting MyVolumio Remote Streaming Endpoints Dec 21 16:18:02 volumio1920x1080 volumio[1072]: info: MyVolumio login type: Token Dec 21 16:18:02 volumio1920x1080 volumio[1072]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 21 16:18:02 volumio1920x1080 volumio[1072]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Dec 21 16:18:04 volumio1920x1080 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Dec 21 16:18:04 volumio1920x1080 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 21 16:18:04 volumio1920x1080 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 21 16:18:04 volumio1920x1080 go-librespot[1865]: go-librespot daemon starting... Dec 21 16:18:04 volumio1920x1080 go-librespot[1866]: time="2025-12-21T16:18:04+01:00" level=info msg="running go-librespot 0.4.0" Dec 21 16:18:04 volumio1920x1080 go-librespot[1866]: time="2025-12-21T16:18:04+01:00" level=debug msg="app state loaded" Dec 21 16:18:04 volumio1920x1080 go-librespot[1866]: time="2025-12-21T16:18:04+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 21 16:18:04 volumio1920x1080 go-librespot[1866]: time="2025-12-21T16:18:04+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Dec 21 16:18:04 volumio1920x1080 go-librespot[1866]: time="2025-12-21T16:18:04+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Dec 21 16:18:04 volumio1920x1080 go-librespot[1866]: time="2025-12-21T16:18:04+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Dec 21 16:18:04 volumio1920x1080 go-librespot[1866]: time="2025-12-21T16:18:04+01:00" level=info msg="zeroconf server listening on port 44795" Dec 21 16:18:04 volumio1920x1080 go-librespot[1866]: time="2025-12-21T16:18:04+01:00" level=debug msg="obtained new client token: AAAChBwufvl22yzFJcpsBemmJjrQtmnd+Ay+u1JT4UIM7qqMS1Myt0WRK/mFpjbvCHDUYfFtabCcBslgQlcIzLX5/JuBvMCgt+JBJIcBUUko9zD72+wmrvQH2VDCzFEembyxFb+aV62NnU8Gc8oXXk7FvGnIi3VoFLY5JnnVkxm3fvIcn09Mx87PDaBiGV+09dP+MJtVZjVnzjieiPjhAWpiWwg2ckG+kQQm8RfWl3nyX/64o5OmUw/CiQ==" Dec 21 16:18:04 volumio1920x1080 go-librespot[1866]: time="2025-12-21T16:18:04+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 21 16:18:04 volumio1920x1080 go-librespot[1866]: time="2025-12-21T16:18:04+01:00" level=debug msg="completed keyexchange" Dec 21 16:18:04 volumio1920x1080 go-librespot[1866]: time="2025-12-21T16:18:04+01:00" level=debug msg="completed challenge" Dec 21 16:18:04 volumio1920x1080 go-librespot[1866]: time="2025-12-21T16:18:04+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Dec 21 16:18:04 volumio1920x1080 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 21 16:18:04 volumio1920x1080 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 21 16:18:06 volumio1920x1080 volumio[1072]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Dec 21 16:18:06 volumio1920x1080 volumio[1072]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Dec 21 16:18:06 volumio1920x1080 volumio[1072]: info: Streaming services startup Dec 21 16:18:06 volumio1920x1080 volumio[1072]: info: Starting Streaming Daemon Dec 21 16:18:06 volumio1920x1080 volumio[1072]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Dec 21 16:18:06 volumio1920x1080 sudo[1874]: volumio : unable to resolve host volumio1920x1080: System error Dec 21 16:18:06 volumio1920x1080 sudo[1874]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 21 16:18:06 volumio1920x1080 sudo[1874]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 16:18:06 volumio1920x1080 sudo[1874]: pam_unix(sudo:session): session closed for user root Dec 21 16:18:07 volumio1920x1080 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Dec 21 16:18:07 volumio1920x1080 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 21 16:18:07 volumio1920x1080 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 21 16:18:07 volumio1920x1080 go-librespot[1880]: go-librespot daemon starting... Dec 21 16:18:07 volumio1920x1080 go-librespot[1881]: time="2025-12-21T16:18:07+01:00" level=info msg="running go-librespot 0.4.0" Dec 21 16:18:07 volumio1920x1080 go-librespot[1881]: time="2025-12-21T16:18:07+01:00" level=debug msg="app state loaded" Dec 21 16:18:07 volumio1920x1080 go-librespot[1881]: time="2025-12-21T16:18:07+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 21 16:18:08 volumio1920x1080 go-librespot[1881]: time="2025-12-21T16:18:08+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Dec 21 16:18:08 volumio1920x1080 go-librespot[1881]: time="2025-12-21T16:18:08+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Dec 21 16:18:08 volumio1920x1080 go-librespot[1881]: time="2025-12-21T16:18:08+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Dec 21 16:18:08 volumio1920x1080 go-librespot[1881]: time="2025-12-21T16:18:08+01:00" level=info msg="zeroconf server listening on port 34137" Dec 21 16:18:08 volumio1920x1080 go-librespot[1881]: time="2025-12-21T16:18:08+01:00" level=debug msg="obtained new client token: AAAuZJ/NeL+GnryL3mUpUNt6bNjamv0WdtnQIIt7yq2Kuqw7JkeB/E9BeiHyAXqf69VikDhWVRXsPeJwxmcEH9XOOUMAU27Mu/r0am2TRRBYCGrWnvv+dN/Jw7mTkvGMYnANwRRI+/1hWX9egyUbo6siTl1J9NC5XLNq22tpo/NWC+efFhzQiS9bRal5SreeS3+RAHiBPP3j3Zg7pQonphkqiSfWh7CU7l+SzgqJFz3gDfxu0rXEggx2oQ==" Dec 21 16:18:08 volumio1920x1080 go-librespot[1881]: time="2025-12-21T16:18:08+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 21 16:18:08 volumio1920x1080 go-librespot[1881]: time="2025-12-21T16:18:08+01:00" level=debug msg="completed keyexchange" Dec 21 16:18:08 volumio1920x1080 go-librespot[1881]: time="2025-12-21T16:18:08+01:00" level=debug msg="completed challenge" Dec 21 16:18:08 volumio1920x1080 go-librespot[1881]: time="2025-12-21T16:18:08+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Dec 21 16:18:08 volumio1920x1080 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 21 16:18:08 volumio1920x1080 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 21 16:18:08 volumio1920x1080 systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 4. Dec 21 16:18:08 volumio1920x1080 systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 21 16:18:08 volumio1920x1080 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 21 16:18:08 volumio1920x1080 upmpdcli[1888]: Could not open config: /tmp/upmpdcli.conf Dec 21 16:18:08 volumio1920x1080 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 21 16:18:08 volumio1920x1080 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 21 16:18:08 volumio1920x1080 volumio[1072]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 21 16:18:08 volumio1920x1080 volumio[1072]: verbose: New Socket.io Connection to 192.168.1.99:3000 from 192.168.1.24 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 14 Dec 21 16:18:09 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 21 16:18:09 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 16:18:09 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 21 16:18:09 volumio1920x1080 volumio[1072]: verbose: New Socket.io Connection to 192.168.1.99:3000 from 192.168.1.24 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 15 Dec 21 16:18:10 volumio1920x1080 volumio[1072]: error: Cannot start Volumio Streaming Daemon Dec 21 16:18:10 volumio1920x1080 volumio[1072]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 21 16:18:10 volumio1920x1080 volumio[1072]: sudo: unable to resolve host volumio1920x1080: System error Dec 21 16:18:10 volumio1920x1080 volumio[1072]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 21 16:18:11 volumio1920x1080 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Dec 21 16:18:11 volumio1920x1080 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 21 16:18:11 volumio1920x1080 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 21 16:18:11 volumio1920x1080 go-librespot[1906]: go-librespot daemon starting... Dec 21 16:18:11 volumio1920x1080 go-librespot[1907]: time="2025-12-21T16:18:11+01:00" level=info msg="running go-librespot 0.4.0" Dec 21 16:18:11 volumio1920x1080 go-librespot[1907]: time="2025-12-21T16:18:11+01:00" level=debug msg="app state loaded" Dec 21 16:18:11 volumio1920x1080 go-librespot[1907]: time="2025-12-21T16:18:11+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 21 16:18:12 volumio1920x1080 go-librespot[1907]: time="2025-12-21T16:18:12+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 21 16:18:12 volumio1920x1080 go-librespot[1907]: time="2025-12-21T16:18:12+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 21 16:18:12 volumio1920x1080 go-librespot[1907]: time="2025-12-21T16:18:12+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 21 16:18:12 volumio1920x1080 go-librespot[1907]: time="2025-12-21T16:18:12+01:00" level=info msg="zeroconf server listening on port 44193" Dec 21 16:18:12 volumio1920x1080 go-librespot[1907]: time="2025-12-21T16:18:12+01:00" level=debug msg="obtained new client token: AAAgHLGKp2snIjtVZmQFoR36hsT59SDDEGZo2qE46yQ7UjO4ZDNzZc+j4uIQkSGbP+KKugnqLX4nEn4vhSugAj4ikM7Q4MECvRCIvfwtmyGPD6OZsgpL/CMbYY4QcmvzV/W9SjZ25d44pnNsTIX/YQW7Rq3cCW3Le09E+97w1JDcS4+8yZoLFAYsFGvtwpr2wekJtxnm/Uxi7hN+/jjW6odUBSjo9epBxmUU4j2sWF+2CixR3jRi0cE=" Dec 21 16:18:12 volumio1920x1080 go-librespot[1907]: time="2025-12-21T16:18:12+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 21 16:18:13 volumio1920x1080 go-librespot[1907]: time="2025-12-21T16:18:13+01:00" level=debug msg="completed keyexchange" Dec 21 16:18:13 volumio1920x1080 go-librespot[1907]: time="2025-12-21T16:18:13+01:00" level=debug msg="completed challenge" Dec 21 16:18:13 volumio1920x1080 go-librespot[1907]: time="2025-12-21T16:18:13+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Dec 21 16:18:13 volumio1920x1080 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 21 16:18:13 volumio1920x1080 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 21 16:18:14 volumio1920x1080 volumio[1072]: info: Initializing connection to go-librespot Websocket Dec 21 16:18:16 volumio1920x1080 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Dec 21 16:18:16 volumio1920x1080 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 21 16:18:16 volumio1920x1080 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 21 16:18:16 volumio1920x1080 go-librespot[1916]: go-librespot daemon starting... Dec 21 16:18:16 volumio1920x1080 go-librespot[1917]: time="2025-12-21T16:18:16+01:00" level=info msg="running go-librespot 0.4.0" Dec 21 16:18:16 volumio1920x1080 go-librespot[1917]: time="2025-12-21T16:18:16+01:00" level=debug msg="app state loaded" Dec 21 16:18:16 volumio1920x1080 go-librespot[1917]: time="2025-12-21T16:18:16+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 21 16:18:17 volumio1920x1080 volumio[1072]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 21 16:18:17 volumio1920x1080 volumio[1072]: verbose: New Socket.io Connection to 192.168.1.99:3000 from 192.168.1.24 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 16 Dec 21 16:18:17 volumio1920x1080 go-librespot[1917]: time="2025-12-21T16:18:17+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 21 16:18:17 volumio1920x1080 go-librespot[1917]: time="2025-12-21T16:18:17+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 21 16:18:17 volumio1920x1080 go-librespot[1917]: time="2025-12-21T16:18:17+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 21 16:18:17 volumio1920x1080 go-librespot[1917]: time="2025-12-21T16:18:17+01:00" level=info msg="zeroconf server listening on port 35419" Dec 21 16:18:17 volumio1920x1080 go-librespot[1917]: time="2025-12-21T16:18:17+01:00" level=debug msg="obtained new client token: AACjXjjORPLefGoQnytUr1HcAbErLmO0Mb+HQpH1A0mKOBLjC6zUr0xCF6PnTgRnOi+UnZpiUwPSw8+OGP9VeKr0e2LbHweInhVJ2R37w/cU7l7Lm0t7b87nZ0yRKA7gL+KY1ZDazTSR091xsn/Ywbu2eVonpvqQRd0lHPJZbbxDDfBfQ7DwFYtjngSbzKVG0sVAGo+o5xDsy+/RJBlMPgy8CbDNDG73H7tzbAupAfokf1hJb4vP1xw=" Dec 21 16:18:17 volumio1920x1080 go-librespot[1917]: time="2025-12-21T16:18:17+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 21 16:18:17 volumio1920x1080 go-librespot[1917]: time="2025-12-21T16:18:17+01:00" level=debug msg="completed keyexchange" Dec 21 16:18:17 volumio1920x1080 go-librespot[1917]: time="2025-12-21T16:18:17+01:00" level=debug msg="completed challenge" Dec 21 16:18:17 volumio1920x1080 go-librespot[1917]: time="2025-12-21T16:18:17+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Dec 21 16:18:17 volumio1920x1080 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 21 16:18:17 volumio1920x1080 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 21 16:18:18 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 21 16:18:20 volumio1920x1080 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Dec 21 16:18:20 volumio1920x1080 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 21 16:18:20 volumio1920x1080 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 21 16:18:20 volumio1920x1080 go-librespot[1939]: go-librespot daemon starting... Dec 21 16:18:20 volumio1920x1080 go-librespot[1940]: time="2025-12-21T16:18:20+01:00" level=info msg="running go-librespot 0.4.0" Dec 21 16:18:20 volumio1920x1080 go-librespot[1940]: time="2025-12-21T16:18:20+01:00" level=debug msg="app state loaded" Dec 21 16:18:20 volumio1920x1080 go-librespot[1940]: time="2025-12-21T16:18:20+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 21 16:18:20 volumio1920x1080 go-librespot[1940]: time="2025-12-21T16:18:20+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 21 16:18:20 volumio1920x1080 go-librespot[1940]: time="2025-12-21T16:18:20+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 21 16:18:20 volumio1920x1080 go-librespot[1940]: time="2025-12-21T16:18:20+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 21 16:18:21 volumio1920x1080 go-librespot[1940]: time="2025-12-21T16:18:21+01:00" level=info msg="zeroconf server listening on port 46269" Dec 21 16:18:21 volumio1920x1080 go-librespot[1940]: time="2025-12-21T16:18:21+01:00" level=debug msg="obtained new client token: AACkfs82LuBAN1pzN7q9Q/kkP7iNF7Y2GPesTEGQ80QasurRt0F3iZApDGdVqSyCeHfgAAC7xKBQzFBtHMQAmaEzMjEQTqonza2eAOgtx/pZ6cBH/0uaad/dD0o00PVQQUrzk7QApZhQcr7BwcFKv86WM8YkmdO+4d2RQnUZPtgs74OqwrrqeTGVZeeasrQKmQmoLkXVnaIekcXXd7k2Enm5wyj/PFqfJj3m8mQRkpu4XnLxrJRq1E8=" Dec 21 16:18:21 volumio1920x1080 go-librespot[1940]: time="2025-12-21T16:18:21+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 21 16:18:21 volumio1920x1080 go-librespot[1940]: time="2025-12-21T16:18:21+01:00" level=debug msg="completed keyexchange" Dec 21 16:18:21 volumio1920x1080 go-librespot[1940]: time="2025-12-21T16:18:21+01:00" level=debug msg="completed challenge" Dec 21 16:18:21 volumio1920x1080 go-librespot[1940]: time="2025-12-21T16:18:21+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Dec 21 16:18:21 volumio1920x1080 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 21 16:18:21 volumio1920x1080 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 21 16:18:22 volumio1920x1080 volumio-remote-updater[653]: Test mode enabled Dec 21 16:18:22 volumio1920x1080 volumio-remote-updater[653]: Alpha mode disabled Dec 21 16:18:22 volumio1920x1080 volumio-remote-updater[653]: Alpha legacy test mode disabled Dec 21 16:18:22 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Dec 21 16:18:22 volumio1920x1080 volumio[1072]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/142.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 17 Dec 21 16:18:22 volumio1920x1080 volumio[1072]: info: Initializing connection to go-librespot Websocket Dec 21 16:18:22 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 16:18:22 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 16:18:22 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 21 16:18:22 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 21 16:18:22 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 21 16:18:22 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::volumioGetBrowseSources Dec 21 16:18:22 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 21 16:18:23 volumio1920x1080 systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 5. Dec 21 16:18:23 volumio1920x1080 systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 21 16:18:23 volumio1920x1080 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 21 16:18:23 volumio1920x1080 upmpdcli[1948]: Could not open config: /tmp/upmpdcli.conf Dec 21 16:18:23 volumio1920x1080 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 21 16:18:23 volumio1920x1080 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 21 16:18:24 volumio1920x1080 volumio[1072]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Dec 21 16:18:24 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 21 16:18:24 volumio1920x1080 volumio[1072]: verbose: New Socket.io Connection to 192.168.1.99:3000 from 192.168.1.24 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 18 Dec 21 16:18:24 volumio1920x1080 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Dec 21 16:18:24 volumio1920x1080 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 21 16:18:24 volumio1920x1080 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 21 16:18:24 volumio1920x1080 go-librespot[1949]: go-librespot daemon starting... Dec 21 16:18:24 volumio1920x1080 go-librespot[1950]: time="2025-12-21T16:18:24+01:00" level=info msg="running go-librespot 0.4.0" Dec 21 16:18:24 volumio1920x1080 go-librespot[1950]: time="2025-12-21T16:18:24+01:00" level=debug msg="app state loaded" Dec 21 16:18:24 volumio1920x1080 go-librespot[1950]: time="2025-12-21T16:18:24+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 21 16:18:24 volumio1920x1080 go-librespot[1950]: time="2025-12-21T16:18:24+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 21 16:18:24 volumio1920x1080 go-librespot[1950]: time="2025-12-21T16:18:24+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 21 16:18:24 volumio1920x1080 go-librespot[1950]: time="2025-12-21T16:18:24+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 21 16:18:24 volumio1920x1080 go-librespot[1950]: time="2025-12-21T16:18:24+01:00" level=info msg="zeroconf server listening on port 43009" Dec 21 16:18:24 volumio1920x1080 go-librespot[1950]: time="2025-12-21T16:18:24+01:00" level=debug msg="obtained new client token: AABUbq1gTsdPnEkaH8uCr/r1zJD2eLbgfE0uDEgRk6SRLBCk7ZuCIBaC+fIiUf0NfkUPhMibq7B5QSUia/TFsx9Sps2TT3/lTIOW4J9KEicAz5sfhA6N5hOzTF2gNXK9V8f8pJ9V7yJeeqPw9LrjB6ZXaygltKQr+FvMNZKiC/btOBaWGtdLKTCoblnBXeCtjVbvpS42bpEkKpCrArUsk+dXzvgzSkc3kUayrtBvKbEYuWDW7FnWNhhjBg==" Dec 21 16:18:24 volumio1920x1080 go-librespot[1950]: time="2025-12-21T16:18:24+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 21 16:18:25 volumio1920x1080 go-librespot[1950]: time="2025-12-21T16:18:25+01:00" level=debug msg="completed keyexchange" Dec 21 16:18:25 volumio1920x1080 go-librespot[1950]: time="2025-12-21T16:18:25+01:00" level=debug msg="completed challenge" Dec 21 16:18:25 volumio1920x1080 go-librespot[1950]: time="2025-12-21T16:18:25+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Dec 21 16:18:25 volumio1920x1080 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 21 16:18:25 volumio1920x1080 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 21 16:18:25 volumio1920x1080 volumio[1072]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Dec 21 16:18:25 volumio1920x1080 volumio[1072]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Dec 21 16:18:25 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Dec 21 16:18:25 volumio1920x1080 volumio[1072]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 21 16:18:25 volumio1920x1080 volumio[1072]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 19 Dec 21 16:18:26 volumio1920x1080 volumio[1072]: info: Listing playlists Dec 21 16:18:26 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::volumioGetQueue Dec 21 16:18:26 volumio1920x1080 volumio[1072]: info: CoreStateMachine::getQueue Dec 21 16:18:26 volumio1920x1080 volumio[1072]: info: CorePlayQueue::getQueue Dec 21 16:18:26 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::volumioGetVisibleSources Dec 21 16:18:26 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 21 16:18:26 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::volumioGetState Dec 21 16:18:26 volumio1920x1080 volumio[1072]: info: CorePlayQueue::getTrack 0 Dec 21 16:18:26 volumio1920x1080 volumio[1072]: verbose: New Socket.io Connection to 192.168.1.99:3000 from 192.168.1.24 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 20 Dec 21 16:18:26 volumio1920x1080 volumio[1072]: error: MyVolumio Plugin failed to authenticate in a timely fashion Dec 21 16:18:26 volumio1920x1080 volumio[1072]: info: Completed starting MyVolumio Plugin Dec 21 16:18:26 volumio1920x1080 volumio[1072]: [Metrics] CommandRouter: 71s 870.10ms Dec 21 16:18:26 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::volumiosetStartupVolume Dec 21 16:18:26 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 16:18:26 volumio1920x1080 volumio[1072]: info: VolumeController:: Setting startup Volume 35 Dec 21 16:18:26 volumio1920x1080 volumio[1072]: info: VolumeController::SetAlsaVolume35 Dec 21 16:18:26 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 16:18:26 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::Close All Modals sent Dec 21 16:18:26 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::Close All Modals sent Dec 21 16:18:26 volumio1920x1080 volumio[1072]: info: CoreStateMachine::pushState Dec 21 16:18:26 volumio1920x1080 volumio[1072]: info: CorePlayQueue::getTrack 0 Dec 21 16:18:26 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 21 16:18:26 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::volumioPushState Dec 21 16:18:28 volumio1920x1080 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11. Dec 21 16:18:28 volumio1920x1080 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 21 16:18:28 volumio1920x1080 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 21 16:18:28 volumio1920x1080 go-librespot[1961]: go-librespot daemon starting... Dec 21 16:18:28 volumio1920x1080 go-librespot[1962]: time="2025-12-21T16:18:28+01:00" level=info msg="running go-librespot 0.4.0" Dec 21 16:18:28 volumio1920x1080 go-librespot[1962]: time="2025-12-21T16:18:28+01:00" level=debug msg="app state loaded" Dec 21 16:18:28 volumio1920x1080 go-librespot[1962]: time="2025-12-21T16:18:28+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 21 16:18:28 volumio1920x1080 go-librespot[1962]: time="2025-12-21T16:18:28+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 21 16:18:28 volumio1920x1080 go-librespot[1962]: time="2025-12-21T16:18:28+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 21 16:18:28 volumio1920x1080 go-librespot[1962]: time="2025-12-21T16:18:28+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 21 16:18:28 volumio1920x1080 go-librespot[1962]: time="2025-12-21T16:18:28+01:00" level=info msg="zeroconf server listening on port 39655" Dec 21 16:18:28 volumio1920x1080 go-librespot[1962]: time="2025-12-21T16:18:28+01:00" level=debug msg="obtained new client token: AACIgnr2Mnb282IFVciIVR/lnfkapEAKkXVz3ekRNIpsEP1/HB2dzEkxuPRQaFUieBEfJ08CPgJy/qsdlfE4n2nJPCmPz5RoaN2SPS4EqdOxAuN2n2eyq3RvWsztgTg4j+Q9uUicc9bLapQzEDubrVgx16Hwt5U7XKOxH6FLsMyiTUJWvZ6VL0CCwgwL5oSdwImkThtmNbWTxFYrs1ahNfJlNEUz6moOEnNAAZgdZXBpikNLOv94QO7jdQ==" Dec 21 16:18:28 volumio1920x1080 go-librespot[1962]: time="2025-12-21T16:18:28+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 21 16:18:28 volumio1920x1080 go-librespot[1962]: time="2025-12-21T16:18:28+01:00" level=debug msg="completed keyexchange" Dec 21 16:18:28 volumio1920x1080 go-librespot[1962]: time="2025-12-21T16:18:28+01:00" level=debug msg="completed challenge" Dec 21 16:18:28 volumio1920x1080 go-librespot[1962]: time="2025-12-21T16:18:28+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Dec 21 16:18:28 volumio1920x1080 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 21 16:18:28 volumio1920x1080 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 21 16:18:32 volumio1920x1080 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12. Dec 21 16:18:32 volumio1920x1080 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 21 16:18:32 volumio1920x1080 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 21 16:18:32 volumio1920x1080 go-librespot[1984]: go-librespot daemon starting... Dec 21 16:18:32 volumio1920x1080 go-librespot[1985]: time="2025-12-21T16:18:32+01:00" level=info msg="running go-librespot 0.4.0" Dec 21 16:18:32 volumio1920x1080 go-librespot[1985]: time="2025-12-21T16:18:32+01:00" level=debug msg="app state loaded" Dec 21 16:18:32 volumio1920x1080 go-librespot[1985]: time="2025-12-21T16:18:32+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 21 16:18:32 volumio1920x1080 kernel: xhci_hcd 0000:01:00.0: ERROR Transfer event for disabled endpoint slot 2 ep 1 Dec 21 16:18:32 volumio1920x1080 kernel: xhci_hcd 0000:01:00.0: @0000000428d16a80 00000000 00000000 0e000000 02028000 Dec 21 16:18:32 volumio1920x1080 go-librespot[1985]: time="2025-12-21T16:18:32+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 21 16:18:32 volumio1920x1080 go-librespot[1985]: time="2025-12-21T16:18:32+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 21 16:18:32 volumio1920x1080 go-librespot[1985]: time="2025-12-21T16:18:32+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 21 16:18:32 volumio1920x1080 go-librespot[1985]: time="2025-12-21T16:18:32+01:00" level=info msg="zeroconf server listening on port 38255" Dec 21 16:18:32 volumio1920x1080 go-librespot[1985]: time="2025-12-21T16:18:32+01:00" level=debug msg="obtained new client token: AAAOWmzX6blrLcoSTeYPyWy5tOabbIgvq2yTNHG3RkBNFKbFcko/s3Ec4V74mPNl/HRhhKqcCAUmlML4llTQC9NwMazp0BvfYgRS7WWrt9hXWQvHhsI+amCsCudu+gSPXkvwOv0ogoJAnvYm3u7ujLjd0Bz6SCrgvdcI/pkgd3nQ9hhmiRLaImUm3UkIQG97QkquULA8RmftWPXkPTtOJvH5b0d65Il8ClXJ7/aIS4AvIWg1Nx19793Xgw==" Dec 21 16:18:32 volumio1920x1080 go-librespot[1985]: time="2025-12-21T16:18:32+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 21 16:18:32 volumio1920x1080 go-librespot[1985]: time="2025-12-21T16:18:32+01:00" level=debug msg="completed keyexchange" Dec 21 16:18:32 volumio1920x1080 go-librespot[1985]: time="2025-12-21T16:18:32+01:00" level=debug msg="completed challenge" Dec 21 16:18:32 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 21 16:18:32 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 21 16:18:32 volumio1920x1080 go-librespot[1985]: time="2025-12-21T16:18:32+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Dec 21 16:18:32 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 21 16:18:32 volumio1920x1080 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 21 16:18:32 volumio1920x1080 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 21 16:18:32 volumio1920x1080 volumio[1072]: info: Initializing connection to go-librespot Websocket Dec 21 16:18:34 volumio1920x1080 volumio[1072]: info: BOOT COMPLETED Dec 21 16:18:36 volumio1920x1080 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13. Dec 21 16:18:36 volumio1920x1080 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 21 16:18:36 volumio1920x1080 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 21 16:18:36 volumio1920x1080 go-librespot[1996]: go-librespot daemon starting... Dec 21 16:18:36 volumio1920x1080 go-librespot[1997]: time="2025-12-21T16:18:36+01:00" level=info msg="running go-librespot 0.4.0" Dec 21 16:18:36 volumio1920x1080 go-librespot[1997]: time="2025-12-21T16:18:36+01:00" level=debug msg="app state loaded" Dec 21 16:18:36 volumio1920x1080 go-librespot[1997]: time="2025-12-21T16:18:36+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 21 16:18:36 volumio1920x1080 go-librespot[1997]: time="2025-12-21T16:18:36+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 21 16:18:36 volumio1920x1080 go-librespot[1997]: time="2025-12-21T16:18:36+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 21 16:18:36 volumio1920x1080 go-librespot[1997]: time="2025-12-21T16:18:36+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 21 16:18:36 volumio1920x1080 go-librespot[1997]: time="2025-12-21T16:18:36+01:00" level=info msg="zeroconf server listening on port 42693" Dec 21 16:18:36 volumio1920x1080 go-librespot[1997]: time="2025-12-21T16:18:36+01:00" level=debug msg="obtained new client token: AADsuuzm08bHzQ58q/DoFv23DC7FFU9/kNhrKHmgQBDRZIr4jYX9lYFMnJ7qpEVBTwaoJcu08vnE0d+PVjxTV/bRUo6ENfPje2NgfiMB1r8YXbGXW/8Dj7tEJSkLUJTCzKYaCwsclPamt9xLGUOYneShF7GNKBluv9aaLqOa/Y7Vxyaa4Qlmz6tUnZHRvbNh0ieDNmz3E4lSpSKQAWxciQp0uXH2HC0TpyZd3Qy+D2biVIqH7CJIuhhsaA==" Dec 21 16:18:36 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 21 16:18:36 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 21 16:18:36 volumio1920x1080 volumio[1072]: info: Discovery: Getting this device information Dec 21 16:18:36 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::volumioGetState Dec 21 16:18:36 volumio1920x1080 volumio[1072]: info: CorePlayQueue::getTrack 0 Dec 21 16:18:36 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 21 16:18:36 volumio1920x1080 go-librespot[1997]: time="2025-12-21T16:18:36+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 21 16:18:36 volumio1920x1080 go-librespot[1997]: time="2025-12-21T16:18:36+01:00" level=debug msg="completed keyexchange" Dec 21 16:18:36 volumio1920x1080 go-librespot[1997]: time="2025-12-21T16:18:36+01:00" level=debug msg="completed challenge" Dec 21 16:18:36 volumio1920x1080 volumio[1072]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 21 16:18:36 volumio1920x1080 go-librespot[1997]: time="2025-12-21T16:18:36+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Dec 21 16:18:36 volumio1920x1080 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 21 16:18:36 volumio1920x1080 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 21 16:18:39 volumio1920x1080 systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 6. Dec 21 16:18:39 volumio1920x1080 systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 21 16:18:39 volumio1920x1080 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 21 16:18:39 volumio1920x1080 upmpdcli[2008]: Could not open config: /tmp/upmpdcli.conf Dec 21 16:18:39 volumio1920x1080 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 21 16:18:39 volumio1920x1080 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 21 16:18:40 volumio1920x1080 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14. Dec 21 16:18:40 volumio1920x1080 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 21 16:18:40 volumio1920x1080 go-librespot[2009]: go-librespot daemon starting... Dec 21 16:18:40 volumio1920x1080 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 21 16:18:40 volumio1920x1080 go-librespot[2010]: time="2025-12-21T16:18:40+01:00" level=info msg="running go-librespot 0.4.0" Dec 21 16:18:40 volumio1920x1080 go-librespot[2010]: time="2025-12-21T16:18:40+01:00" level=debug msg="app state loaded" Dec 21 16:18:40 volumio1920x1080 go-librespot[2010]: time="2025-12-21T16:18:40+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 21 16:18:40 volumio1920x1080 go-librespot[2010]: time="2025-12-21T16:18:40+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 21 16:18:40 volumio1920x1080 go-librespot[2010]: time="2025-12-21T16:18:40+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 21 16:18:40 volumio1920x1080 go-librespot[2010]: time="2025-12-21T16:18:40+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 21 16:18:40 volumio1920x1080 go-librespot[2010]: time="2025-12-21T16:18:40+01:00" level=info msg="zeroconf server listening on port 38871" Dec 21 16:18:40 volumio1920x1080 go-librespot[2010]: time="2025-12-21T16:18:40+01:00" level=debug msg="obtained new client token: AAB0O/mT+OdS4BDBHGf6npGZcniNF5g/qCTB1S9tbCAGLyIQvgOcygALMwo1LD/auW6iXbwJ3ZQifxGNBP42GJqDMVYiUa9AiFq/f5Z+/1nO36DGky5mPHYWgCttLwNcQKVCshD1aEgtRjEu7s4JkHi59YlI5fInm2Rzt9rxd059ZRn0wVQNdPqJ47HaeEafuEu8u4Vtnmo90lVmq0vqJBmLCkE7qOOi8uMIrfRO8C16uEBLnk17Yjp8SA==" Dec 21 16:18:40 volumio1920x1080 go-librespot[2010]: time="2025-12-21T16:18:40+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 21 16:18:40 volumio1920x1080 go-librespot[2010]: time="2025-12-21T16:18:40+01:00" level=debug msg="completed keyexchange" Dec 21 16:18:40 volumio1920x1080 go-librespot[2010]: time="2025-12-21T16:18:40+01:00" level=debug msg="completed challenge" Dec 21 16:18:40 volumio1920x1080 go-librespot[2010]: time="2025-12-21T16:18:40+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Dec 21 16:18:40 volumio1920x1080 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 21 16:18:40 volumio1920x1080 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 21 16:18:43 volumio1920x1080 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15. Dec 21 16:18:43 volumio1920x1080 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 21 16:18:43 volumio1920x1080 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 21 16:18:43 volumio1920x1080 go-librespot[2032]: go-librespot daemon starting... Dec 21 16:18:43 volumio1920x1080 go-librespot[2033]: time="2025-12-21T16:18:43+01:00" level=info msg="running go-librespot 0.4.0" Dec 21 16:18:43 volumio1920x1080 go-librespot[2033]: time="2025-12-21T16:18:43+01:00" level=debug msg="app state loaded" Dec 21 16:18:43 volumio1920x1080 go-librespot[2033]: time="2025-12-21T16:18:43+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 21 16:18:44 volumio1920x1080 go-librespot[2033]: time="2025-12-21T16:18:44+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Dec 21 16:18:44 volumio1920x1080 go-librespot[2033]: time="2025-12-21T16:18:44+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Dec 21 16:18:44 volumio1920x1080 go-librespot[2033]: time="2025-12-21T16:18:44+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Dec 21 16:18:44 volumio1920x1080 go-librespot[2033]: time="2025-12-21T16:18:44+01:00" level=info msg="zeroconf server listening on port 44651" Dec 21 16:18:44 volumio1920x1080 go-librespot[2033]: time="2025-12-21T16:18:44+01:00" level=debug msg="obtained new client token: AAD8CoK0u5Uv1Zgd0o0wcW0xq922jqQH4/lrZ6J9044KKp1R46RTQwsm22/k7ely/yov8BjSotBmg3/aS7cOSPGxrK085GsXNJ1/VAFgrdYF/fTgp9+EmQUJV5YSzlVF9GFb40Np5NhMxPjH9CWtTRoa1T+L5QWzpjEs2IVqtQp8ySejV2Rdfkg3sm4mOKaQlXdX9B0Y+Zz2CAcllH3bHgtihI95vC60jTfmzC46me01Q8rLt0jUYTDvbA==" Dec 21 16:18:44 volumio1920x1080 go-librespot[2033]: time="2025-12-21T16:18:44+01:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.1.133:4070: connect: connection refused" Dec 21 16:18:44 volumio1920x1080 go-librespot[2033]: time="2025-12-21T16:18:44+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Dec 21 16:18:44 volumio1920x1080 go-librespot[2033]: time="2025-12-21T16:18:44+01:00" level=debug msg="completed keyexchange" Dec 21 16:18:44 volumio1920x1080 go-librespot[2033]: time="2025-12-21T16:18:44+01:00" level=debug msg="completed challenge" Dec 21 16:18:44 volumio1920x1080 go-librespot[2033]: time="2025-12-21T16:18:44+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Dec 21 16:18:44 volumio1920x1080 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 21 16:18:44 volumio1920x1080 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 21 16:18:45 volumio1920x1080 volumio[1072]: info: Initializing connection to go-librespot Websocket Dec 21 16:18:46 volumio1920x1080 volumio[1072]: verbose: New Socket.io Connection to 192.168.1.99:3000 from 192.168.1.24 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 20 Dec 21 16:18:46 volumio1920x1080 volumio[1072]: info: MyVolumio token set successfully Dec 21 16:18:46 volumio1920x1080 volumio[1072]: info: MYVOLUMIO: Adding device Dec 21 16:18:46 volumio1920x1080 volumio[1072]: info: MYVOLUMIO: Evaluating Server Dec 21 16:18:46 volumio1920x1080 volumio[1072]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 21 16:18:47 volumio1920x1080 volumio[1072]: info: MyVolumio Plan changed: premium Dec 21 16:18:47 volumio1920x1080 volumio[1072]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Dec 21 16:18:47 volumio1920x1080 volumio[1072]: info: Removing browser output: myVolumio user plan is not superstar Dec 21 16:18:47 volumio1920x1080 volumio[1072]: info: Removing audio output: Dec 21 16:18:47 volumio1920x1080 volumio[1072]: info: MYVOLUMIO: Adding device Dec 21 16:18:47 volumio1920x1080 volumio[1072]: info: MYVOLUMIO: Evaluating Server Dec 21 16:18:47 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::volumioGetState Dec 21 16:18:47 volumio1920x1080 volumio[1072]: info: CorePlayQueue::getTrack 0 Dec 21 16:18:47 volumio1920x1080 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16. Dec 21 16:18:47 volumio1920x1080 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 21 16:18:47 volumio1920x1080 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 21 16:18:47 volumio1920x1080 go-librespot[2060]: go-librespot daemon starting... Dec 21 16:18:47 volumio1920x1080 go-librespot[2061]: time="2025-12-21T16:18:47+01:00" level=info msg="running go-librespot 0.4.0" Dec 21 16:18:47 volumio1920x1080 go-librespot[2061]: time="2025-12-21T16:18:47+01:00" level=debug msg="app state loaded" Dec 21 16:18:47 volumio1920x1080 go-librespot[2061]: time="2025-12-21T16:18:47+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 21 16:18:47 volumio1920x1080 go-librespot[2061]: time="2025-12-21T16:18:47+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 21 16:18:47 volumio1920x1080 go-librespot[2061]: time="2025-12-21T16:18:47+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 21 16:18:47 volumio1920x1080 go-librespot[2061]: time="2025-12-21T16:18:47+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 21 16:18:47 volumio1920x1080 go-librespot[2061]: time="2025-12-21T16:18:47+01:00" level=info msg="zeroconf server listening on port 35647" Dec 21 16:18:48 volumio1920x1080 go-librespot[2061]: time="2025-12-21T16:18:48+01:00" level=debug msg="obtained new client token: AACCDgQ6kJ2QdbnyOnLbJsPQDh1GjEDAe4jMueBV51PEQ3XFLRzxxnaTjeTabEr7mlCwep1hawTwxlDMs8pVy4zLxOtc8OhofNEYrb9pyqTIp2gwbX+uQ6b7InY7tSL25GB/OL7PrfLec94unaOBVVNe1xcSaawt4OaWaJCBDp+EYjILhFyuxhma0VV5VtA3DHxBIW8vHxtpr5CFDtFWkoVusZ/8e3461U5VhmlOSVBbx8Ttd32Zo1Q=" Dec 21 16:18:48 volumio1920x1080 go-librespot[2061]: time="2025-12-21T16:18:48+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 21 16:18:48 volumio1920x1080 volumio[1072]: info: Setting Geolocation for MyVolumio to eu8 Dec 21 16:18:48 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 16:18:48 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 16:18:48 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 16:18:48 volumio1920x1080 volumio[1072]: verbose: New Socket.io Connection to 192.168.1.99:3000 from 192.168.1.24 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 21 Dec 21 16:18:48 volumio1920x1080 volumio[1072]: info: Setting Geolocation for MyVolumio to eu9 Dec 21 16:18:48 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 16:18:48 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 16:18:48 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 16:18:48 volumio1920x1080 volumio[1072]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/142.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 22 Dec 21 16:18:48 volumio1920x1080 volumio[1072]: info: Remote config written successfully Dec 21 16:18:48 volumio1920x1080 volumio[1072]: info: Starting Tunnel 1 Dec 21 16:18:48 volumio1920x1080 volumio[1072]: info: Starting Tunnel Connection Checker Dec 21 16:18:48 volumio1920x1080 volumio[1072]: info: Successfully Added MyVolumio device Dec 21 16:18:48 volumio1920x1080 volumio[1072]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Dec 21 16:18:48 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Dec 21 16:18:48 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 21 16:18:48 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 21 16:18:48 volumio1920x1080 volumio[1072]: info: Discovery: Getting this device information Dec 21 16:18:48 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::volumioGetState Dec 21 16:18:48 volumio1920x1080 volumio[1072]: info: CorePlayQueue::getTrack 0 Dec 21 16:18:48 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 21 16:18:48 volumio1920x1080 volumio[1072]: info: Listing playlists Dec 21 16:18:48 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::volumioGetQueue Dec 21 16:18:48 volumio1920x1080 volumio[1072]: info: CoreStateMachine::getQueue Dec 21 16:18:48 volumio1920x1080 volumio[1072]: info: CorePlayQueue::getQueue Dec 21 16:18:48 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::volumioGetVisibleSources Dec 21 16:18:48 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 21 16:18:48 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::volumioGetState Dec 21 16:18:48 volumio1920x1080 volumio[1072]: info: CorePlayQueue::getTrack 0 Dec 21 16:18:48 volumio1920x1080 volumio[1072]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Dec 21 16:18:48 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Dec 21 16:18:48 volumio1920x1080 volumio[1072]: info: Listing playlists Dec 21 16:18:48 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::volumioGetQueue Dec 21 16:18:48 volumio1920x1080 volumio[1072]: info: CoreStateMachine::getQueue Dec 21 16:18:48 volumio1920x1080 volumio[1072]: info: CorePlayQueue::getQueue Dec 21 16:18:48 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::volumioGetVisibleSources Dec 21 16:18:48 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 21 16:18:48 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::volumioGetState Dec 21 16:18:48 volumio1920x1080 volumio[1072]: info: CorePlayQueue::getTrack 0 Dec 21 16:18:49 volumio1920x1080 volumio[1072]: info: MYVolumio Device enabled Dec 21 16:18:49 volumio1920x1080 volumio[1072]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Dec 21 16:18:49 volumio1920x1080 volumio[1072]: info: MyVolumio status changed Dec 21 16:18:49 volumio1920x1080 volumio[1072]: info: Streaming services startup Dec 21 16:18:49 volumio1920x1080 volumio[1072]: info: Starting Streaming Daemon Dec 21 16:18:49 volumio1920x1080 sudo[2088]: volumio : unable to resolve host volumio1920x1080: System error Dec 21 16:18:49 volumio1920x1080 sudo[2088]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 21 16:18:49 volumio1920x1080 sudo[2088]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 16:18:49 volumio1920x1080 volumio[1072]: info: Successfully Added MyVolumio device Dec 21 16:18:49 volumio1920x1080 sudo[2088]: pam_unix(sudo:session): session closed for user root Dec 21 16:18:49 volumio1920x1080 volumio[1072]: info: Updating MyVolumio device info Dec 21 16:18:49 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 16:18:49 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 16:18:49 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 16:18:49 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Dec 21 16:18:49 volumio1920x1080 volumio[1072]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Dec 21 16:18:49 volumio1920x1080 go-librespot[2061]: time="2025-12-21T16:18:49+01:00" level=debug msg="completed keyexchange" Dec 21 16:18:49 volumio1920x1080 go-librespot[2061]: time="2025-12-21T16:18:49+01:00" level=debug msg="completed challenge" Dec 21 16:18:49 volumio1920x1080 go-librespot[2061]: time="2025-12-21T16:18:49+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Dec 21 16:18:49 volumio1920x1080 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 21 16:18:49 volumio1920x1080 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 21 16:18:50 volumio1920x1080 volumio[1072]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart Dec 21 16:18:50 volumio1920x1080 volumio[1072]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Dec 21 16:18:53 volumio1920x1080 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17. Dec 21 16:18:53 volumio1920x1080 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 21 16:18:53 volumio1920x1080 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 21 16:18:53 volumio1920x1080 go-librespot[2104]: go-librespot daemon starting... Dec 21 16:18:53 volumio1920x1080 go-librespot[2105]: time="2025-12-21T16:18:53+01:00" level=info msg="running go-librespot 0.4.0" Dec 21 16:18:53 volumio1920x1080 go-librespot[2105]: time="2025-12-21T16:18:53+01:00" level=debug msg="app state loaded" Dec 21 16:18:53 volumio1920x1080 go-librespot[2105]: time="2025-12-21T16:18:53+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 21 16:18:53 volumio1920x1080 go-librespot[2105]: time="2025-12-21T16:18:53+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 21 16:18:53 volumio1920x1080 go-librespot[2105]: time="2025-12-21T16:18:53+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 21 16:18:53 volumio1920x1080 go-librespot[2105]: time="2025-12-21T16:18:53+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 21 16:18:53 volumio1920x1080 go-librespot[2105]: time="2025-12-21T16:18:53+01:00" level=info msg="zeroconf server listening on port 37221" Dec 21 16:18:53 volumio1920x1080 go-librespot[2105]: time="2025-12-21T16:18:53+01:00" level=debug msg="obtained new client token: AADDzLOpsp7fDVsQ01+SmsGQKQG+Gqv8+kMl7C+cPD/czoBFruXBlERczFUYUelMrZhvaQr/qrZPVZnDuldkkdcCKgYeCH2DCMhpgr4utNnSoVygX9pA9DDzk1LIlMVG/WzSkQdGPVT4Yvu5jSuxUuQfxmLUmFFp0yGtgnKW5DNIfhixraO6JCAAqDgUO2Il797qh9yCt0dmyLRk33t8c5JW+S+Fso/MO4bceH8y1FTBsLU9tGAZzUkTKA==" Dec 21 16:18:53 volumio1920x1080 go-librespot[2105]: time="2025-12-21T16:18:53+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 21 16:18:53 volumio1920x1080 go-librespot[2105]: time="2025-12-21T16:18:53+01:00" level=debug msg="completed keyexchange" Dec 21 16:18:53 volumio1920x1080 go-librespot[2105]: time="2025-12-21T16:18:53+01:00" level=debug msg="completed challenge" Dec 21 16:18:53 volumio1920x1080 go-librespot[2105]: time="2025-12-21T16:18:53+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Dec 21 16:18:53 volumio1920x1080 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 21 16:18:53 volumio1920x1080 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 21 16:18:54 volumio1920x1080 systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 7. Dec 21 16:18:54 volumio1920x1080 systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 21 16:18:54 volumio1920x1080 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 21 16:18:54 volumio1920x1080 upmpdcli[2119]: Could not open config: /tmp/upmpdcli.conf Dec 21 16:18:54 volumio1920x1080 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 21 16:18:54 volumio1920x1080 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 21 16:18:56 volumio1920x1080 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 18. Dec 21 16:18:56 volumio1920x1080 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 21 16:18:56 volumio1920x1080 volumio[1072]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Dec 21 16:18:56 volumio1920x1080 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 21 16:18:56 volumio1920x1080 go-librespot[2127]: go-librespot daemon starting... Dec 21 16:18:56 volumio1920x1080 go-librespot[2130]: time="2025-12-21T16:18:56+01:00" level=info msg="running go-librespot 0.4.0" Dec 21 16:18:56 volumio1920x1080 go-librespot[2130]: time="2025-12-21T16:18:56+01:00" level=debug msg="app state loaded" Dec 21 16:18:56 volumio1920x1080 go-librespot[2130]: time="2025-12-21T16:18:56+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 21 16:18:57 volumio1920x1080 sudo[2131]: volumio : unable to resolve host volumio1920x1080: System error Dec 21 16:18:57 volumio1920x1080 sudo[2131]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Dec 21 16:18:57 volumio1920x1080 sudo[2131]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 16:18:57 volumio1920x1080 sudo[2131]: pam_unix(sudo:session): session closed for user root Dec 21 16:18:57 volumio1920x1080 go-librespot[2130]: time="2025-12-21T16:18:57+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 21 16:18:57 volumio1920x1080 go-librespot[2130]: time="2025-12-21T16:18:57+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 21 16:18:57 volumio1920x1080 go-librespot[2130]: time="2025-12-21T16:18:57+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 21 16:18:57 volumio1920x1080 go-librespot[2130]: time="2025-12-21T16:18:57+01:00" level=info msg="zeroconf server listening on port 39093" Dec 21 16:18:57 volumio1920x1080 go-librespot[2130]: time="2025-12-21T16:18:57+01:00" level=debug msg="obtained new client token: AAB8kp6wIQAnraCMALdufHsrnJHjAxiL+6Cll4J79V+FsDnCObY0iurkblQMjhE2rDZFDwGhOdlmSYwcLeFXkUkqJhi6S+AtV/mByj14GNDx9L70nIzB9eYPAkIKatZbnaRKqFqosph+GUTH+Gc2CVdyOntCZZg5O2AYvJ/BMmMENp7EDL9nQHImrlalRECVBiRH2If+DxOxtGs1DVLsQNIn8aXGl51gSWOJXaeKYqopIyXNNbT5NrhEvg==" Dec 21 16:18:57 volumio1920x1080 go-librespot[2130]: time="2025-12-21T16:18:57+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 21 16:18:57 volumio1920x1080 go-librespot[2130]: time="2025-12-21T16:18:57+01:00" level=debug msg="completed keyexchange" Dec 21 16:18:57 volumio1920x1080 go-librespot[2130]: time="2025-12-21T16:18:57+01:00" level=debug msg="completed challenge" Dec 21 16:18:57 volumio1920x1080 volumio[1072]: sudo: unable to resolve host volumio1920x1080: System error Dec 21 16:18:57 volumio1920x1080 go-librespot[2130]: time="2025-12-21T16:18:57+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Dec 21 16:18:57 volumio1920x1080 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 21 16:18:57 volumio1920x1080 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 21 16:18:57 volumio1920x1080 volumio[1072]: info: MRS: MultiRoom plugin initialized Dec 21 16:18:57 volumio1920x1080 volumio[1072]: info: MRS: STOPPING SNAPCLIENT Dec 21 16:18:57 volumio1920x1080 volumio[1072]: info: MRS: Snap server stop Dec 21 16:18:57 volumio1920x1080 volumio[1072]: info: MRS: STOPPING volumioStreaming Dec 21 16:18:57 volumio1920x1080 volumio[1072]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Dec 21 16:18:57 volumio1920x1080 sudo[2155]: volumio : unable to resolve host volumio1920x1080: System error Dec 21 16:18:57 volumio1920x1080 sudo[2155]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Dec 21 16:18:57 volumio1920x1080 sudo[2155]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 16:18:57 volumio1920x1080 sudo[2157]: volumio : unable to resolve host volumio1920x1080: System error Dec 21 16:18:57 volumio1920x1080 sudo[2159]: volumio : unable to resolve host volumio1920x1080: System error Dec 21 16:18:57 volumio1920x1080 sudo[2157]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Dec 21 16:18:57 volumio1920x1080 sudo[2157]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 16:18:57 volumio1920x1080 sudo[2161]: volumio : unable to resolve host volumio1920x1080: System error Dec 21 16:18:57 volumio1920x1080 sudo[2159]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Dec 21 16:18:57 volumio1920x1080 sudo[2159]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 16:18:57 volumio1920x1080 sudo[2161]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Dec 21 16:18:57 volumio1920x1080 sudo[2161]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 16:18:57 volumio1920x1080 volumio[1072]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Dec 21 16:18:57 volumio1920x1080 sudo[2161]: pam_unix(sudo:session): session closed for user root Dec 21 16:18:57 volumio1920x1080 sudo[2155]: pam_unix(sudo:session): session closed for user root Dec 21 16:18:57 volumio1920x1080 volumio[1072]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Dec 21 16:18:57 volumio1920x1080 sudo[2157]: pam_unix(sudo:session): session closed for user root Dec 21 16:18:57 volumio1920x1080 sudo[2159]: pam_unix(sudo:session): session closed for user root Dec 21 16:18:57 volumio1920x1080 volumio[1072]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Dec 21 16:18:58 volumio1920x1080 volumio[1072]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Dec 21 16:18:58 volumio1920x1080 volumio[1072]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Dec 21 16:18:58 volumio1920x1080 volumio[1072]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Dec 21 16:18:58 volumio1920x1080 volumio[1072]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Dec 21 16:18:58 volumio1920x1080 volumio[1072]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Dec 21 16:18:58 volumio1920x1080 volumio[1072]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Dec 21 16:18:58 volumio1920x1080 volumio[1072]: info: Preparing to generate the ALSA configuration file Dec 21 16:18:58 volumio1920x1080 volumio[1072]: error: Cannot start Volumio Streaming Daemon Dec 21 16:18:58 volumio1920x1080 volumio[1072]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 21 16:18:58 volumio1920x1080 volumio[1072]: sudo: unable to resolve host volumio1920x1080: System error Dec 21 16:18:58 volumio1920x1080 volumio[1072]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 21 16:18:58 volumio1920x1080 volumio[1072]: info: Initializing connection to go-librespot Websocket Dec 21 16:18:58 volumio1920x1080 volumio[1072]: info: Updating MyVolumio device info Dec 21 16:18:58 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 16:18:58 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 16:18:58 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 16:18:58 volumio1920x1080 volumio[1072]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Dec 21 16:18:58 volumio1920x1080 volumio[1072]: info: Reading ALSA contributions from plugins. Dec 21 16:18:58 volumio1920x1080 volumio[1072]: info: MRS: Removed streaming files Dec 21 16:18:58 volumio1920x1080 volumio[1072]: info: MRS: volumioStreaming STOPPED Dec 21 16:18:58 volumio1920x1080 volumio[1072]: info: MRS: SNAPSERVER STOPPED Dec 21 16:18:58 volumio1920x1080 volumio[1072]: info: MRS: SNAPCLIENT STOPPED Dec 21 16:18:58 volumio1920x1080 volumio[1072]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 21 16:18:59 volumio1920x1080 volumio[1072]: info: Successfully Updated MyVolumio device Dec 21 16:18:59 volumio1920x1080 sudo[2173]: volumio : unable to resolve host volumio1920x1080: System error Dec 21 16:18:59 volumio1920x1080 sudo[2173]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Dec 21 16:18:59 volumio1920x1080 sudo[2173]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 16:18:59 volumio1920x1080 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. Dec 21 16:18:59 volumio1920x1080 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. Dec 21 16:18:59 volumio1920x1080 systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Dec 21 16:18:59 volumio1920x1080 sudo[2173]: pam_unix(sudo:session): session closed for user root Dec 21 16:18:59 volumio1920x1080 autossh[2176]: port set to 0, monitoring disabled Dec 21 16:18:59 volumio1920x1080 autossh[2176]: starting ssh (count 1) Dec 21 16:18:59 volumio1920x1080 autossh[2176]: ssh child pid is 2179 Dec 21 16:19:00 volumio1920x1080 volumio[1072]: info: Asound.conf file unchanged, so no further update is needed Dec 21 16:19:00 volumio1920x1080 volumio[1072]: info: Output device has changed, restarting MPD Dec 21 16:19:00 volumio1920x1080 volumiossh-tunnel[2179]: Warning: Permanently added '[eu9.myvolumio.org]:2222' (RSA) to the list of known hosts. Dec 21 16:19:00 volumio1920x1080 volumio[1072]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 21 16:19:00 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 16:19:00 volumio1920x1080 sudo[2181]: volumio : unable to resolve host volumio1920x1080: System error Dec 21 16:19:00 volumio1920x1080 sudo[2181]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 21 16:19:00 volumio1920x1080 sudo[2181]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 16:19:00 volumio1920x1080 sudo[2181]: pam_unix(sudo:session): session closed for user root Dec 21 16:19:00 volumio1920x1080 volumio[1072]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Dec 21 16:19:00 volumio1920x1080 volumio[1072]: info: [MyVolumio PluginManager] Plugin bluetooth disabled by user. Not starting Dec 21 16:19:00 volumio1920x1080 volumio[1072]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Dec 21 16:19:00 volumio1920x1080 volumio[1072]: info: [MyVolumio PluginManager] Plugin multiroom disabled by user. Not starting Dec 21 16:19:00 volumio1920x1080 volumio[1072]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Dec 21 16:19:00 volumio1920x1080 volumio[1072]: info: Adding METAVOLUMIO REST API Endpoints Dec 21 16:19:00 volumio1920x1080 volumio[1072]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Dec 21 16:19:00 volumio1920x1080 volumio[1072]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Dec 21 16:19:00 volumio1920x1080 volumio[1072]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Dec 21 16:19:00 volumio1920x1080 volumio[1072]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Dec 21 16:19:00 volumio1920x1080 volumio[1072]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Dec 21 16:19:00 volumio1920x1080 volumio[1072]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Dec 21 16:19:00 volumio1920x1080 volumio[1072]: info: [MyVolumio PluginManager] Plugin cd_controller disabled by user. Not starting Dec 21 16:19:00 volumio1920x1080 volumio[1072]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Dec 21 16:19:00 volumio1920x1080 volumio[1072]: info: [MyVolumio PluginManager] Plugin smart_inputs disabled by user. Not starting Dec 21 16:19:00 volumio1920x1080 volumio[1072]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Dec 21 16:19:00 volumio1920x1080 sudo[2183]: volumio : unable to resolve host volumio1920x1080: System error Dec 21 16:19:00 volumio1920x1080 sudo[2183]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 21 16:19:00 volumio1920x1080 sudo[2183]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 16:19:00 volumio1920x1080 volumio[1072]: error: Hi Res Audio Failed Login: Missing Login Data Dec 21 16:19:00 volumio1920x1080 volumio[1072]: info: Adding HIGHRESAUDIO REST API Endpoints Dec 21 16:19:00 volumio1920x1080 volumio[1072]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Dec 21 16:19:00 volumio1920x1080 volumio[1072]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Dec 21 16:19:00 volumio1920x1080 volumio[1072]: info: Refreshing TIDAL token Dec 21 16:19:00 volumio1920x1080 volumio[1072]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Dec 21 16:19:00 volumio1920x1080 volumio[1072]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Dec 21 16:19:00 volumio1920x1080 volumio[1072]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect Dec 21 16:19:00 volumio1920x1080 volumio[1072]: info: [MyVolumio PluginManager] Plugin qobuzconnect disabled by user. Not starting Dec 21 16:19:00 volumio1920x1080 volumio[1072]: info: Stopping AccessToken refresher cron for QOBUZ Dec 21 16:19:00 volumio1920x1080 systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 21 16:19:00 volumio1920x1080 sudo[2189]: volumio : unable to resolve host volumio1920x1080: System error Dec 21 16:19:00 volumio1920x1080 sudo[2189]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 21 16:19:00 volumio1920x1080 sudo[2189]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 16:19:00 volumio1920x1080 volumio[1072]: info: AccessToken refresher cron started for QOBUZ Dec 21 16:19:00 volumio1920x1080 volumio[1072]: info: Adding QOBUZ REST API Endpoints Dec 21 16:19:00 volumio1920x1080 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 19. Dec 21 16:19:00 volumio1920x1080 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 21 16:19:00 volumio1920x1080 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 21 16:19:00 volumio1920x1080 go-librespot[2191]: go-librespot daemon starting... Dec 21 16:19:00 volumio1920x1080 systemd[1]: mpd.service: Deactivated successfully. Dec 21 16:19:00 volumio1920x1080 systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 21 16:19:00 volumio1920x1080 systemd[1]: mpd.service: Consumed 4.101s CPU time. Dec 21 16:19:00 volumio1920x1080 systemd[1]: mpd.socket: Deactivated successfully. Dec 21 16:19:00 volumio1920x1080 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 21 16:19:00 volumio1920x1080 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 21 16:19:00 volumio1920x1080 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 21 16:19:00 volumio1920x1080 go-librespot[2192]: time="2025-12-21T16:19:00+01:00" level=info msg="running go-librespot 0.4.0" Dec 21 16:19:00 volumio1920x1080 go-librespot[2192]: time="2025-12-21T16:19:00+01:00" level=debug msg="app state loaded" Dec 21 16:19:00 volumio1920x1080 go-librespot[2192]: time="2025-12-21T16:19:00+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 21 16:19:00 volumio1920x1080 systemd[1]: Starting mpd.service - Music Player Daemon... Dec 21 16:19:00 volumio1920x1080 sudo[2189]: pam_unix(sudo:session): session closed for user root Dec 21 16:19:00 volumio1920x1080 volumio[1072]: info: Successfully Updated MyVolumio device Dec 21 16:19:00 volumio1920x1080 sudo[2199]: root : unable to resolve host volumio1920x1080: System error Dec 21 16:19:00 volumio1920x1080 sudo[2199]: sudo: unable to resolve host volumio1920x1080: System error Dec 21 16:19:00 volumio1920x1080 sudo[2199]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 21 16:19:00 volumio1920x1080 sudo[2199]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 21 16:19:00 volumio1920x1080 sudo[2199]: pam_unix(sudo:session): session closed for user root Dec 21 16:19:00 volumio1920x1080 go-librespot[2192]: time="2025-12-21T16:19:00+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Dec 21 16:19:00 volumio1920x1080 go-librespot[2192]: time="2025-12-21T16:19:00+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Dec 21 16:19:00 volumio1920x1080 go-librespot[2192]: time="2025-12-21T16:19:00+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Dec 21 16:19:00 volumio1920x1080 go-librespot[2192]: time="2025-12-21T16:19:00+01:00" level=info msg="zeroconf server listening on port 36053" Dec 21 16:19:00 volumio1920x1080 volumio[1072]: info: Remote SSH Started Dec 21 16:19:00 volumio1920x1080 go-librespot[2192]: time="2025-12-21T16:19:00+01:00" level=debug msg="obtained new client token: AACyx6+APPCa6sSPl1N4OSyeVPrBogMso6XT4+Qo+i24hJjAw8sLAHtZsq7ME8Ylgz3+G5Dm+RSNvs+vjga9AaAp2XEaUoxPQ3FiFTHP9FBG/gGyz1+iiQtZ0FFS5oBTrgv7N05/zg6akm+a72dU4U9zqyNi1Bkmn7SARMEZMnverSRWh/BDoNPxB0K2atHaCwmZDxD1rGyPH8ThlIJH2SImxmQJr50mQ8zcMcVCuJI41ezwHN8JKaNo2A==" Dec 21 16:19:01 volumio1920x1080 go-librespot[2192]: time="2025-12-21T16:19:01+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 21 16:19:01 volumio1920x1080 go-librespot[2192]: time="2025-12-21T16:19:01+01:00" level=debug msg="completed keyexchange" Dec 21 16:19:01 volumio1920x1080 go-librespot[2192]: time="2025-12-21T16:19:01+01:00" level=debug msg="completed challenge" Dec 21 16:19:01 volumio1920x1080 go-librespot[2192]: time="2025-12-21T16:19:01+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Dec 21 16:19:01 volumio1920x1080 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 21 16:19:01 volumio1920x1080 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 21 16:19:01 volumio1920x1080 volumio[1072]: info: MPD Permissions set Dec 21 16:19:01 volumio1920x1080 volumio[1072]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 19 Dec 21 16:19:01 volumio1920x1080 volumio[1072]: info: Initializing connection to go-librespot Websocket Dec 21 16:19:01 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 16:19:01 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 16:19:01 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 16:19:01 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 16:19:01 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 16:19:01 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 16:19:01 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 16:19:01 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 16:19:02 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 16:19:02 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 16:19:02 volumio1920x1080 mpd[2203]: decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 21 16:19:02 volumio1920x1080 mpd[2203]: output: No 'audio_output' defined in config file Dec 21 16:19:02 volumio1920x1080 mpd[2203]: output: Successfully detected a sndio audio device Dec 21 16:19:02 volumio1920x1080 mpd[2203]: zeroconf: No global port, disabling zeroconf Dec 21 16:19:02 volumio1920x1080 systemd[1]: Started mpd.service - Music Player Daemon. Dec 21 16:19:02 volumio1920x1080 sudo[2183]: pam_unix(sudo:session): session closed for user root Dec 21 16:19:02 volumio1920x1080 volumio[1072]: info: Access Token successfully retrieved Dec 21 16:19:02 volumio1920x1080 volumio[1072]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 21 16:19:02 volumio1920x1080 volumio[1072]: error: updateQueue error: null Dec 21 16:19:03 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::volumioGetState Dec 21 16:19:03 volumio1920x1080 volumio[1072]: info: CorePlayQueue::getTrack 0 Dec 21 16:19:03 volumio1920x1080 volumio[1072]: info: Successfully retrieved User Session From TIDAL Dec 21 16:19:03 volumio1920x1080 volumio[1072]: info: Successfully retrieved User Subscription From TIDAL Dec 21 16:19:03 volumio1920x1080 volumio[1072]: info: Adding TIDAL to Browse Sources Dec 21 16:19:03 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 21 16:19:03 volumio1920x1080 volumio[1072]: info: [1766330343548] CoreMusicLibrary::Adding element TIDAL Dec 21 16:19:03 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 21 16:19:03 volumio1920x1080 volumio[1072]: Cannot find translation for source FM/DAB Radio Dec 21 16:19:03 volumio1920x1080 volumio[1072]: Cannot find translation for source Spotify Dec 21 16:19:03 volumio1920x1080 volumio[1072]: Cannot find translation for source TIDAL Dec 21 16:19:03 volumio1920x1080 volumio[1072]: info: Adding TIDAL REST API Endpoints Dec 21 16:19:03 volumio1920x1080 volumio[1072]: error: Unable to stop TidalConnect service: Dec 21 16:19:03 volumio1920x1080 volumio[1072]: info: TidalConnect service stoped! Dec 21 16:19:03 volumio1920x1080 volumio[1072]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 21 16:19:03 volumio1920x1080 volumio[1072]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 21 16:19:03 volumio1920x1080 sudo[2234]: volumio : unable to resolve host volumio1920x1080: System error Dec 21 16:19:03 volumio1920x1080 sudo[2234]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 21 16:19:03 volumio1920x1080 sudo[2234]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 16:19:04 volumio1920x1080 systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Dec 21 16:19:04 volumio1920x1080 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 20. Dec 21 16:19:04 volumio1920x1080 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 21 16:19:04 volumio1920x1080 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 21 16:19:04 volumio1920x1080 go-librespot[2238]: go-librespot daemon starting... Dec 21 16:19:04 volumio1920x1080 sudo[2234]: pam_unix(sudo:session): session closed for user root Dec 21 16:19:04 volumio1920x1080 go-librespot[2239]: time="2025-12-21T16:19:04+01:00" level=info msg="running go-librespot 0.4.0" Dec 21 16:19:04 volumio1920x1080 go-librespot[2239]: time="2025-12-21T16:19:04+01:00" level=debug msg="app state loaded" Dec 21 16:19:04 volumio1920x1080 go-librespot[2239]: time="2025-12-21T16:19:04+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 21 16:19:04 volumio1920x1080 volumio[1072]: info: Executing endpoint tc_getconfig Dec 21 16:19:04 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 21 16:19:04 volumio1920x1080 vtcs[2237]: STARTING TidalConnect services, version: 1.5.2.56 Dec 21 16:19:04 volumio1920x1080 vtcs[2237]: STARTED TidalConnect services. Dec 21 16:19:04 volumio1920x1080 volumio[1072]: info: Executing endpoint tc_connect Dec 21 16:19:04 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Dec 21 16:19:04 volumio1920x1080 volumio[1072]: info: Connecting to TidalConnect Dec 21 16:19:04 volumio1920x1080 go-librespot[2239]: time="2025-12-21T16:19:04+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Dec 21 16:19:04 volumio1920x1080 go-librespot[2239]: time="2025-12-21T16:19:04+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Dec 21 16:19:04 volumio1920x1080 go-librespot[2239]: time="2025-12-21T16:19:04+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Dec 21 16:19:04 volumio1920x1080 go-librespot[2239]: time="2025-12-21T16:19:04+01:00" level=info msg="zeroconf server listening on port 36149" Dec 21 16:19:04 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::servicePushState Dec 21 16:19:04 volumio1920x1080 volumio[1072]: info: CoreStateMachine::pushState Dec 21 16:19:04 volumio1920x1080 volumio[1072]: info: CorePlayQueue::getTrack 0 Dec 21 16:19:04 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::volumioPushState Dec 21 16:19:04 volumio1920x1080 volumio[1072]: info: CorePlayQueue::getTrack 0 Dec 21 16:19:04 volumio1920x1080 volumio[1072]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Dec 21 16:19:04 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::servicePushState Dec 21 16:19:04 volumio1920x1080 volumio[1072]: info: CoreStateMachine::pushState Dec 21 16:19:04 volumio1920x1080 volumio[1072]: info: CorePlayQueue::getTrack 0 Dec 21 16:19:04 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::volumioPushState Dec 21 16:19:04 volumio1920x1080 volumio[1072]: info: CorePlayQueue::getTrack 0 Dec 21 16:19:04 volumio1920x1080 volumio[1072]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Dec 21 16:19:04 volumio1920x1080 go-librespot[2239]: time="2025-12-21T16:19:04+01:00" level=debug msg="obtained new client token: AABYj/YVVe5erT2FJ5xLsMyzf+8LTNOyIwj8Iu4dAPX10UUvnk13KryhyODk6ytpCUwCs41Vhd97moZvVB+RwV8fH3iA1T6uw9tK1IenjLRCNvKc1doTNDmZR28SPgBib1+AfPlkVa+m2EyhuikEbUxwbBItS3pEhOono7vd+svE5SG6nTXcWNwrnPilK9xYDRFfE2lDa2SGoNgoWp96Qm2g35cG7N6qNeA8Ue4G5pBVchJIS+LAgsgD/A==" Dec 21 16:19:04 volumio1920x1080 go-librespot[2239]: time="2025-12-21T16:19:04+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 21 16:19:04 volumio1920x1080 go-librespot[2239]: time="2025-12-21T16:19:04+01:00" level=debug msg="completed keyexchange" Dec 21 16:19:04 volumio1920x1080 go-librespot[2239]: time="2025-12-21T16:19:04+01:00" level=debug msg="completed challenge" Dec 21 16:19:04 volumio1920x1080 go-librespot[2239]: time="2025-12-21T16:19:04+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Dec 21 16:19:04 volumio1920x1080 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 21 16:19:04 volumio1920x1080 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 21 16:19:05 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 21 16:19:05 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 21 16:19:05 volumio1920x1080 volumio[1072]: info: Discovery: Getting this device information Dec 21 16:19:05 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::volumioGetState Dec 21 16:19:05 volumio1920x1080 volumio[1072]: info: CorePlayQueue::getTrack 0 Dec 21 16:19:05 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 21 16:19:05 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 21 16:19:05 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Dec 21 16:19:05 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Dec 21 16:19:05 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Dec 21 16:19:05 volumio1920x1080 sudo[2262]: volumio : unable to resolve host volumio1920x1080: System error Dec 21 16:19:05 volumio1920x1080 sudo[2262]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Dec 21 16:19:05 volumio1920x1080 sudo[2262]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 16:19:05 volumio1920x1080 sudo[2267]: volumio : unable to resolve host volumio1920x1080: System error Dec 21 16:19:05 volumio1920x1080 sudo[2267]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 21 16:19:05 volumio1920x1080 sudo[2267]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 16:19:05 volumio1920x1080 sudo[2273]: volumio : unable to resolve host volumio1920x1080: System error Dec 21 16:19:05 volumio1920x1080 sudo[2262]: pam_unix(sudo:session): session closed for user root Dec 21 16:19:05 volumio1920x1080 sudo[2267]: pam_unix(sudo:session): session closed for user root Dec 21 16:19:05 volumio1920x1080 sudo[2278]: volumio : unable to resolve host volumio1920x1080: System error Dec 21 16:19:05 volumio1920x1080 volumio[1072]: info: Initializing connection to go-librespot Websocket Dec 21 16:19:05 volumio1920x1080 sudo[2273]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 21 16:19:05 volumio1920x1080 sudo[2287]: volumio : unable to resolve host volumio1920x1080: System error Dec 21 16:19:05 volumio1920x1080 sudo[2273]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 16:19:05 volumio1920x1080 sudo[2278]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 21 16:19:05 volumio1920x1080 sudo[2287]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 21 16:19:05 volumio1920x1080 sudo[2278]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 16:19:05 volumio1920x1080 sudo[2273]: pam_unix(sudo:session): session closed for user root Dec 21 16:19:05 volumio1920x1080 sudo[2283]: volumio : unable to resolve host volumio1920x1080: System error Dec 21 16:19:05 volumio1920x1080 volumio[1072]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 21 16:19:05 volumio1920x1080 sudo[2287]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 16:19:05 volumio1920x1080 sudo[2283]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 21 16:19:05 volumio1920x1080 sudo[2278]: pam_unix(sudo:session): session closed for user root Dec 21 16:19:05 volumio1920x1080 sudo[2283]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 16:19:05 volumio1920x1080 sudo[2283]: pam_unix(sudo:session): session closed for user root Dec 21 16:19:05 volumio1920x1080 sudo[2287]: pam_unix(sudo:session): session closed for user root Dec 21 16:19:06 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 21 16:19:06 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 21 16:19:06 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 21 16:19:06 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 21 16:19:06 volumio1920x1080 volumio[1072]: info: Discovery: Getting this device information Dec 21 16:19:06 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::volumioGetState Dec 21 16:19:06 volumio1920x1080 volumio[1072]: info: CorePlayQueue::getTrack 0 Dec 21 16:19:06 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 21 16:19:06 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 21 16:19:06 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 21 16:19:06 volumio1920x1080 volumio[1072]: info: Discovery: Getting this device information Dec 21 16:19:06 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::volumioGetState Dec 21 16:19:06 volumio1920x1080 volumio[1072]: info: CorePlayQueue::getTrack 0 Dec 21 16:19:06 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 21 16:19:06 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::volumioGetState Dec 21 16:19:06 volumio1920x1080 volumio[1072]: info: CorePlayQueue::getTrack 0 Dec 21 16:19:06 volumio1920x1080 volumio[1072]: verbose: New Socket.io Connection to 192.168.1.99:3000 from 192.168.1.24 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 16 Dec 21 16:19:06 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 21 16:19:06 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 21 16:19:06 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 21 16:19:06 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 21 16:19:07 volumio1920x1080 volumio[1072]: error: Unable to start TidalConnect service: Dec 21 16:19:07 volumio1920x1080 volumio[1072]: info: TidalConnect service started! Dec 21 16:19:07 volumio1920x1080 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 21. Dec 21 16:19:07 volumio1920x1080 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 21 16:19:07 volumio1920x1080 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 21 16:19:07 volumio1920x1080 go-librespot[2298]: go-librespot daemon starting... Dec 21 16:19:07 volumio1920x1080 go-librespot[2299]: time="2025-12-21T16:19:07+01:00" level=info msg="running go-librespot 0.4.0" Dec 21 16:19:07 volumio1920x1080 go-librespot[2299]: time="2025-12-21T16:19:07+01:00" level=debug msg="app state loaded" Dec 21 16:19:07 volumio1920x1080 go-librespot[2299]: time="2025-12-21T16:19:07+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 21 16:19:08 volumio1920x1080 go-librespot[2299]: time="2025-12-21T16:19:08+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 21 16:19:08 volumio1920x1080 go-librespot[2299]: time="2025-12-21T16:19:08+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 21 16:19:08 volumio1920x1080 go-librespot[2299]: time="2025-12-21T16:19:08+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 21 16:19:08 volumio1920x1080 go-librespot[2299]: time="2025-12-21T16:19:08+01:00" level=info msg="zeroconf server listening on port 37043" Dec 21 16:19:08 volumio1920x1080 go-librespot[2299]: time="2025-12-21T16:19:08+01:00" level=debug msg="obtained new client token: AADf8BNvGdNY/flWDKceQ2h4j6Ab+U5GrvUK6PEoWtNQ7D9WVHwA1FxrCLEcOo1a2iHhxNgm/Jk0JEVAEuEkJFIfBlO5cJrJCrk8hFdrW/6WxcxPpDEqmRLWmjxic5zSDa0QikJAqD1B16IKJQ0LGe+x7bN5PekixOZBbPmylmNbLGiVxD3tHxYr6RfCVpA3ZKXe+T+PkKPkOksUAmAWqS52WMqLrHh0wBZIA+TPqta9rfqnaP1CS9q+aQ==" Dec 21 16:19:08 volumio1920x1080 go-librespot[2299]: time="2025-12-21T16:19:08+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 21 16:19:08 volumio1920x1080 go-librespot[2299]: time="2025-12-21T16:19:08+01:00" level=debug msg="completed keyexchange" Dec 21 16:19:08 volumio1920x1080 go-librespot[2299]: time="2025-12-21T16:19:08+01:00" level=debug msg="completed challenge" Dec 21 16:19:08 volumio1920x1080 go-librespot[2299]: time="2025-12-21T16:19:08+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Dec 21 16:19:08 volumio1920x1080 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 21 16:19:08 volumio1920x1080 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 21 16:19:08 volumio1920x1080 volumio[1072]: info: Initializing connection to go-librespot Websocket Dec 21 16:19:08 volumio1920x1080 volumio[1072]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 21 16:19:09 volumio1920x1080 sudo[2311]: volumio : unable to resolve host volumio1920x1080: System error Dec 21 16:19:09 volumio1920x1080 sudo[2312]: volumio : unable to resolve host volumio1920x1080: System error Dec 21 16:19:09 volumio1920x1080 sudo[2312]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 21 16:19:09 volumio1920x1080 sudo[2312]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 16:19:09 volumio1920x1080 sudo[2311]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 21 16:19:09 volumio1920x1080 sudo[2311]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 16:19:09 volumio1920x1080 sudo[2311]: pam_unix(sudo:session): session closed for user root Dec 21 16:19:09 volumio1920x1080 sudo[2312]: pam_unix(sudo:session): session closed for user root Dec 21 16:19:09 volumio1920x1080 volumio[1072]: verbose: New Socket.io Connection to 192.168.1.99 from 192.168.1.24 UA: Mozilla/5.0 (Linux; Android 16; CPH2581 Build/BP2A.250605.015; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.34 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 15 Dec 21 16:19:09 volumio1920x1080 systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 8. Dec 21 16:19:09 volumio1920x1080 systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 21 16:19:09 volumio1920x1080 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 21 16:19:09 volumio1920x1080 upmpdcli[2318]: Could not open config: /tmp/upmpdcli.conf Dec 21 16:19:09 volumio1920x1080 sudo[2320]: volumio : unable to resolve host volumio1920x1080: System error Dec 21 16:19:09 volumio1920x1080 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 21 16:19:09 volumio1920x1080 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 21 16:19:09 volumio1920x1080 sudo[2322]: volumio : unable to resolve host volumio1920x1080: System error Dec 21 16:19:09 volumio1920x1080 sudo[2320]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 21 16:19:09 volumio1920x1080 sudo[2320]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 16:19:09 volumio1920x1080 sudo[2322]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 21 16:19:09 volumio1920x1080 sudo[2322]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 16:19:09 volumio1920x1080 sudo[2320]: pam_unix(sudo:session): session closed for user root Dec 21 16:19:09 volumio1920x1080 sudo[2322]: pam_unix(sudo:session): session closed for user root Dec 21 16:19:09 volumio1920x1080 volumio[1072]: verbose: New Socket.io Connection to 192.168.1.99 from 192.168.1.24 UA: Mozilla/5.0 (Linux; Android 16; CPH2581 Build/BP2A.250605.015; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.34 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 16 Dec 21 16:19:10 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 21 16:19:10 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::volumioGetVisibleSources Dec 21 16:19:10 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 21 16:19:10 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::volumioGetState Dec 21 16:19:10 volumio1920x1080 volumio[1072]: info: CorePlayQueue::getTrack 0 Dec 21 16:19:10 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 21 16:19:10 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 21 16:19:10 volumio1920x1080 volumio[1072]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 21 16:19:10 volumio1920x1080 volumio[1072]: info: Received Get System Info Dec 21 16:19:10 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 21 16:19:10 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 21 16:19:10 volumio1920x1080 volumio[1072]: info: Discovery: Getting this device information Dec 21 16:19:10 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::volumioGetState Dec 21 16:19:10 volumio1920x1080 volumio[1072]: info: CorePlayQueue::getTrack 0 Dec 21 16:19:10 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 21 16:19:10 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::volumioGetState Dec 21 16:19:10 volumio1920x1080 volumio[1072]: info: CorePlayQueue::getTrack 0 Dec 21 16:19:10 volumio1920x1080 volumio[1072]: info: Listing playlists Dec 21 16:19:10 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 21 16:19:10 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 21 16:19:10 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 21 16:19:11 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 21 16:19:11 volumio1920x1080 volumio[1072]: info: Received Get System Info Dec 21 16:19:11 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 21 16:19:11 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 21 16:19:11 volumio1920x1080 volumio[1072]: info: Discovery: Getting this device information Dec 21 16:19:11 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::volumioGetState Dec 21 16:19:11 volumio1920x1080 volumio[1072]: info: CorePlayQueue::getTrack 0 Dec 21 16:19:11 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 21 16:19:11 volumio1920x1080 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22. Dec 21 16:19:11 volumio1920x1080 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 21 16:19:11 volumio1920x1080 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 21 16:19:11 volumio1920x1080 go-librespot[2343]: go-librespot daemon starting... Dec 21 16:19:11 volumio1920x1080 go-librespot[2344]: time="2025-12-21T16:19:11+01:00" level=info msg="running go-librespot 0.4.0" Dec 21 16:19:11 volumio1920x1080 go-librespot[2344]: time="2025-12-21T16:19:11+01:00" level=debug msg="app state loaded" Dec 21 16:19:11 volumio1920x1080 go-librespot[2344]: time="2025-12-21T16:19:11+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 21 16:19:11 volumio1920x1080 volumio[1072]: info: Initializing connection to go-librespot Websocket Dec 21 16:19:11 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 21 16:19:11 volumio1920x1080 go-librespot[2344]: time="2025-12-21T16:19:11+01:00" level=debug msg="new websocket client" Dec 21 16:19:11 volumio1920x1080 volumio[1072]: info: Connection to go-librespot Websocket established Dec 21 16:19:11 volumio1920x1080 go-librespot[2344]: time="2025-12-21T16:19:11+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 21 16:19:11 volumio1920x1080 go-librespot[2344]: time="2025-12-21T16:19:11+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 21 16:19:11 volumio1920x1080 go-librespot[2344]: time="2025-12-21T16:19:11+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 21 16:19:11 volumio1920x1080 go-librespot[2344]: time="2025-12-21T16:19:11+01:00" level=info msg="zeroconf server listening on port 41531" Dec 21 16:19:12 volumio1920x1080 go-librespot[2344]: time="2025-12-21T16:19:12+01:00" level=debug msg="obtained new client token: AACK0Zf5vIONsN324z1XzNSnQEcmoX48oJCQAgO86rGkqY/PTdmyemjD17lKiAoTEjbpwcoQXVULx77ZAuJi+ZYWIFJKmKCCGLhHK70I3RtVJqQ5opM+2qdMaFfaAew9icXN0RQrGha6qmcx+o6lJN+KRr69C+YsWm/iS4ynI3/rXJFPfBQu9xhHsVD5Zroj+yc7ogQSzmw58hq+NwKNhD1jxd8sLAx/o2m0f7yFIQwwNRDKuaHnKK8=" Dec 21 16:19:12 volumio1920x1080 go-librespot[2344]: time="2025-12-21T16:19:12+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 21 16:19:12 volumio1920x1080 go-librespot[2344]: time="2025-12-21T16:19:12+01:00" level=debug msg="completed keyexchange" Dec 21 16:19:12 volumio1920x1080 go-librespot[2344]: time="2025-12-21T16:19:12+01:00" level=debug msg="completed challenge" Dec 21 16:19:12 volumio1920x1080 go-librespot[2344]: time="2025-12-21T16:19:12+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Dec 21 16:19:12 volumio1920x1080 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 21 16:19:12 volumio1920x1080 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 21 16:19:12 volumio1920x1080 volumio[1072]: info: Connection to go-librespot Websocket closed Dec 21 16:19:12 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 21 16:19:12 volumio1920x1080 volumio[1072]: info: Received Get System Info Dec 21 16:19:12 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 21 16:19:12 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 21 16:19:12 volumio1920x1080 volumio[1072]: info: Discovery: Getting this device information Dec 21 16:19:12 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::volumioGetState Dec 21 16:19:12 volumio1920x1080 volumio[1072]: info: CorePlayQueue::getTrack 0 Dec 21 16:19:12 volumio1920x1080 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 21 16:19:14 volumio1920x1080 volumio[1072]: info: Getting Spotify volume Dec 21 16:19:15 volumio1920x1080 volumio[1072]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 21 16:19:15 volumio1920x1080 volumio[1072]: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 21 16:19:15 volumio1920x1080 volumio[1072]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Dec 21 16:19:15 volumio1920x1080 volumio[1072]: errno: -111, Dec 21 16:19:15 volumio1920x1080 volumio[1072]: code: 'ECONNREFUSED', Dec 21 16:19:15 volumio1920x1080 volumio[1072]: syscall: 'connect', Dec 21 16:19:15 volumio1920x1080 volumio[1072]: address: '127.0.0.1', Dec 21 16:19:15 volumio1920x1080 volumio[1072]: port: 9879, Dec 21 16:19:15 volumio1920x1080 volumio[1072]: response: undefined Dec 21 16:19:15 volumio1920x1080 volumio[1072]: } Dec 21 16:19:15 volumio1920x1080 volumio[1072]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 21 16:19:15 volumio1920x1080 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 23. Dec 21 16:19:15 volumio1920x1080 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 21 16:19:16 volumio1920x1080 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 21 16:19:16 volumio1920x1080 go-librespot[2363]: go-librespot daemon starting... Dec 21 16:19:16 volumio1920x1080 go-librespot[2364]: time="2025-12-21T16:19:16+01:00" level=info msg="running go-librespot 0.4.0" Dec 21 16:19:16 volumio1920x1080 go-librespot[2364]: time="2025-12-21T16:19:16+01:00" level=debug msg="app state loaded" Dec 21 16:19:16 volumio1920x1080 go-librespot[2364]: time="2025-12-21T16:19:16+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 21 16:19:16 volumio1920x1080 go-librespot[2364]: time="2025-12-21T16:19:16+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Dec 21 16:19:16 volumio1920x1080 go-librespot[2364]: time="2025-12-21T16:19:16+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Dec 21 16:19:16 volumio1920x1080 go-librespot[2364]: time="2025-12-21T16:19:16+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Dec 21 16:19:16 volumio1920x1080 go-librespot[2364]: time="2025-12-21T16:19:16+01:00" level=info msg="zeroconf server listening on port 40741" Dec 21 16:19:16 volumio1920x1080 go-librespot[2364]: time="2025-12-21T16:19:16+01:00" level=debug msg="obtained new client token: AACNz0Rph0CHKnPKD7MKWKRSMGvPgQ+7FnLuRsk1f7hfjyGqX2aabFBPyp9j4cy2shWTF5BjpFe/4m22MlYOy3EkesR1UUs/6ofwyo1u60B8cmxNO4GDq5dOMI/ujGhhyvIIFnuZYkgQgUY+vaYnpZHdpqPVumqUCz+tDzFDgsNEF2gCOBaD8R7KSvOAL765BDUeZULL4P6nZIsp3VUgqYi7kr2iTha1iR1BM8ZONWsPJWwoTsivdjOQow==" Dec 21 16:19:16 volumio1920x1080 go-librespot[2364]: time="2025-12-21T16:19:16+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 21 16:19:16 volumio1920x1080 go-librespot[2364]: time="2025-12-21T16:19:16+01:00" level=debug msg="completed keyexchange" Dec 21 16:19:16 volumio1920x1080 go-librespot[2364]: time="2025-12-21T16:19:16+01:00" level=debug msg="completed challenge" Dec 21 16:19:16 volumio1920x1080 go-librespot[2364]: time="2025-12-21T16:19:16+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Dec 21 16:19:16 volumio1920x1080 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 21 16:19:16 volumio1920x1080 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 21 16:19:18 volumio1920x1080 sudo[2374]: volumio : unable to resolve host volumio1920x1080: System error Dec 21 16:19:18 volumio1920x1080 sudo[2374]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-21 16:18' Dec 21 16:19:18 volumio1920x1080 sudo[2374]: 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="5250573efb99f2852fa27ea7e92def8586093039" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="d9e2b6784b9276d5a676bf564ce7524ad160509b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 19 15:53:15 UTC 2025" VOLUMIO_VERSION="4.082" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="47d7740e8c55792fe06a847eb7ad6b29"