Dec 22 13:32:00 volumio volumio[1311]: info: Enabling plugin spop Dec 22 13:32:00 volumio volumio[1311]: info: Loading plugin "spop"... Dec 22 13:32:01 volumio volumio[1311]: info: PLUGIN START: spop Dec 22 13:32:01 volumio volumio[1311]: info: Creating Spotify config file Dec 22 13:32:01 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 13:32:01 volumio volumio[1311]: info: Done. Dec 22 13:32:01 volumio volumio[1311]: info: Spotify config file written Dec 22 13:32:01 volumio volumio[1311]: info: No need to fix Spotify hosts Dec 22 13:32:01 volumio sudo[9003]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Dec 22 13:32:01 volumio sudo[9003]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 13:32:01 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 22 13:32:01 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 22 13:32:01 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 22 13:32:01 volumio go-librespot[9005]: go-librespot daemon starting... Dec 22 13:32:01 volumio sudo[9003]: pam_unix(sudo:session): session closed for user root Dec 22 13:32:01 volumio go-librespot[9006]: time="2025-12-22T13:32:01+01:00" level=info msg="running go-librespot 0.4.0" Dec 22 13:32:01 volumio go-librespot[9006]: time="2025-12-22T13:32:01+01:00" level=debug msg="no app state found" Dec 22 13:32:01 volumio go-librespot[9006]: time="2025-12-22T13:32:01+01:00" level=debug msg="stored credentials not found" Dec 22 13:32:01 volumio go-librespot[9006]: time="2025-12-22T13:32:01+01:00" level=info msg="generated new device id: 5db6b365718bfff77b0094947e3bb0eefd7ad2b7" Dec 22 13:32:01 volumio go-librespot[9006]: time="2025-12-22T13:32:01+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 22 13:32:01 volumio go-librespot[9006]: time="2025-12-22T13:32:01+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 22 13:32:01 volumio go-librespot[9006]: time="2025-12-22T13:32:01+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 22 13:32:01 volumio go-librespot[9006]: time="2025-12-22T13:32:01+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 22 13:32:01 volumio go-librespot[9006]: time="2025-12-22T13:32:01+01:00" level=info msg="zeroconf server listening on port 44155" Dec 22 13:32:04 volumio volumio[1311]: info: go-librespot daemon successfully initialized Dec 22 13:32:06 volumio volumio[1311]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 22 13:32:07 volumio volumio[1311]: info: Initializing connection to go-librespot Websocket Dec 22 13:32:07 volumio go-librespot[9006]: time="2025-12-22T13:32:07+01:00" level=debug msg="new websocket client" Dec 22 13:32:07 volumio volumio[1311]: info: Connection to go-librespot Websocket established Dec 22 13:32:10 volumio volumio[1311]: info: Getting Spotify volume Dec 22 13:32:10 volumio volumio[1311]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Dec 22 13:32:10 volumio volumio[1311]: info: CoreCommandRouter::volumioGetState Dec 22 13:32:10 volumio volumio[1311]: info: CorePlayQueue::getTrack 0 Dec 22 13:32:19 volumio volumio[1311]: info: Received OAUTH Data Dec 22 13:32:19 volumio volumio[1311]: info: Executing Spotify Oauth Login Dec 22 13:32:19 volumio volumio[1311]: info: Saving Spotify Refresh Token Dec 22 13:32:19 volumio volumio[1311]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Dec 22 13:32:19 volumio volumio[1311]: SPOTIFY: BQAMYjGJkNwQpeIdyOPiVn2gqeImJldt9Af_9a_nbum2TNnWOmO59xUJ5H2BGnSpKgnCRgpkRXF01Q2xcuzsd4SemCgcHAHTvOUQKbEVaJz8I8nyNN0Y8s0reAFrmq2tP0Mfyu2Jy9xDn3x15BwnkQm1v-98gxnFvJpoppHLiT0yYWCus0JFGGi1_W39-Xp8OPPDX20n1sKzZY7Z2apOWqTr1DDpPUWlut980WHYkugzKuJ_4TKgSVWeNRxCbkgsFNaGmHN5R5lNOteeu3AGQq3WdeSvcKtKozRAn-bQ6t5C4pZMHB3wa7vP Dec 22 13:32:19 volumio volumio[1311]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Dec 22 13:32:19 volumio volumio[1311]: info: New Spotify access token = BQAMYjGJkNwQpeIdyOPiVn2gqeImJldt9Af_9a_nbum2TNnWOmO59xUJ5H2BGnSpKgnCRgpkRXF01Q2xcuzsd4SemCgcHAHTvOUQKbEVaJz8I8nyNN0Y8s0reAFrmq2tP0Mfyu2Jy9xDn3x15BwnkQm1v-98gxnFvJpoppHLiT0yYWCus0JFGGi1_W39-Xp8OPPDX20n1sKzZY7Z2apOWqTr1DDpPUWlut980WHYkugzKuJ_4TKgSVWeNRxCbkgsFNaGmHN5R5lNOteeu3AGQq3WdeSvcKtKozRAn-bQ6t5C4pZMHB3wa7vP Dec 22 13:32:19 volumio volumio[1311]: info: Spotify credentials grant success - running version from March 24, 2019 Dec 22 13:32:20 volumio volumio[1311]: SPOTIFY: User informations: {"country":"TR","display_name":"Florian","email":"florian@goezler.de","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/3136ot245q7ef5grbzukyn2yg4tq"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/3136ot245q7ef5grbzukyn2yg4tq","id":"3136ot245q7ef5grbzukyn2yg4tq","images":[],"product":"free","type":"user","uri":"spotify:user:3136ot245q7ef5grbzukyn2yg4tq"} Dec 22 13:32:20 volumio volumio[1311]: info: Creating Spotify config file Dec 22 13:32:20 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 13:32:20 volumio volumio[1311]: info: Spotify config file written Dec 22 13:32:20 volumio sudo[9047]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Dec 22 13:32:20 volumio sudo[9047]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 13:32:20 volumio sudo[9050]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 22 13:32:20 volumio sudo[9050]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 13:32:20 volumio sudo[9052]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 22 13:32:20 volumio sudo[9052]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 13:32:20 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Dec 22 13:32:20 volumio sudo[9052]: pam_unix(sudo:session): session closed for user root Dec 22 13:32:20 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Dec 22 13:32:20 volumio volumio[1311]: info: Connection to go-librespot Websocket closed Dec 22 13:32:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 22 13:32:20 volumio sudo[9050]: pam_unix(sudo:session): session closed for user root Dec 22 13:32:20 volumio volumio[1311]: verbose: New Socket.io Connection to 10.3.0.14 from 10.3.0.18 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Edg/143.0.0.0 Engine version: 3 Transport: polling Total Clients: 7 Dec 22 13:32:20 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 22 13:32:20 volumio go-librespot[9055]: go-librespot daemon starting... Dec 22 13:32:20 volumio sudo[9047]: pam_unix(sudo:session): session closed for user root Dec 22 13:32:20 volumio go-librespot[9056]: time="2025-12-22T13:32:20+01:00" level=info msg="running go-librespot 0.4.0" Dec 22 13:32:20 volumio go-librespot[9056]: time="2025-12-22T13:32:20+01:00" level=debug msg="app state loaded" Dec 22 13:32:20 volumio go-librespot[9056]: time="2025-12-22T13:32:20+01:00" level=debug msg="stored credentials not found" Dec 22 13:32:20 volumio go-librespot[9056]: time="2025-12-22T13:32:20+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 22 13:32:20 volumio volumio[1311]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Dec 22 13:32:20 volumio volumio[1311]: SPOTIFY: BQDNJ0xkTYgqPtFEhXumPimv6qnJDYtxly2e_96-tPO90X8f4oPj_uZGsuJVwCK5kA_Ca5Lk8Rux5tHoYPPMpvRHN8UZSu3E_viwgznMZA6Ir71wQZiUs4x_t8Q9MpZagQnte0ZeyF64jmmWzDcCqjb7OSaTDxmm7JsO0vNq_aeg7JUhwB8bIfja9RzzdnLkSQp23rireMjH4pUSbd06UfpyJWV6Kp6yVnVNBmNiOKyUczviCDsiueB1xmZBzCPzSnYvvffrCtjCpVa2hJfm2yQ710vIC9b6BVCG2ij8TYlrLzj2qL1gYvuZ Dec 22 13:32:20 volumio volumio[1311]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Dec 22 13:32:20 volumio volumio[1311]: info: New Spotify access token = BQDNJ0xkTYgqPtFEhXumPimv6qnJDYtxly2e_96-tPO90X8f4oPj_uZGsuJVwCK5kA_Ca5Lk8Rux5tHoYPPMpvRHN8UZSu3E_viwgznMZA6Ir71wQZiUs4x_t8Q9MpZagQnte0ZeyF64jmmWzDcCqjb7OSaTDxmm7JsO0vNq_aeg7JUhwB8bIfja9RzzdnLkSQp23rireMjH4pUSbd06UfpyJWV6Kp6yVnVNBmNiOKyUczviCDsiueB1xmZBzCPzSnYvvffrCtjCpVa2hJfm2yQ710vIC9b6BVCG2ij8TYlrLzj2qL1gYvuZ Dec 22 13:32:20 volumio volumio[1311]: info: Spotify credentials grant success - running version from March 24, 2019 Dec 22 13:32:20 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 22 13:32:20 volumio go-librespot[9056]: time="2025-12-22T13:32: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-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 22 13:32:20 volumio go-librespot[9056]: time="2025-12-22T13:32:20+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 22 13:32:20 volumio go-librespot[9056]: time="2025-12-22T13:32:20+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 22 13:32:20 volumio go-librespot[9056]: time="2025-12-22T13:32:20+01:00" level=info msg="zeroconf server listening on port 40225" Dec 22 13:32:20 volumio volumio[1311]: SPOTIFY: User informations: {"country":"TR","display_name":"Florian","email":"florian@goezler.de","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/3136ot245q7ef5grbzukyn2yg4tq"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/3136ot245q7ef5grbzukyn2yg4tq","id":"3136ot245q7ef5grbzukyn2yg4tq","images":[],"product":"free","type":"user","uri":"spotify:user:3136ot245q7ef5grbzukyn2yg4tq"} Dec 22 13:32:20 volumio volumio[1311]: info: Spotify Successfully logged in Dec 22 13:32:20 volumio volumio[1311]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 22 13:32:20 volumio volumio[1311]: info: [1766406740200] CoreMusicLibrary::Adding element Spotify Dec 22 13:32:20 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 22 13:32:20 volumio volumio[1311]: Cannot find translation for source Spotify Dec 22 13:32:20 volumio volumio[1311]: info: CoreCommandRouter::volumioGetVisibleSources Dec 22 13:32:20 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 22 13:32:20 volumio volumio[1311]: info: CoreCommandRouter::volumioGetState Dec 22 13:32:20 volumio volumio[1311]: info: CorePlayQueue::getTrack 0 Dec 22 13:32:20 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 22 13:32:20 volumio volumio[1311]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 22 13:32:20 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 22 13:32:20 volumio volumio[1311]: info: Received Get System Info Dec 22 13:32:20 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 22 13:32:20 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 22 13:32:20 volumio volumio[1311]: info: Discovery: Getting this device information Dec 22 13:32:20 volumio volumio[1311]: info: CoreCommandRouter::volumioGetState Dec 22 13:32:20 volumio volumio[1311]: info: CorePlayQueue::getTrack 0 Dec 22 13:32:20 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 22 13:32:20 volumio volumio[1311]: info: CoreCommandRouter::volumioGetState Dec 22 13:32:20 volumio volumio[1311]: info: CorePlayQueue::getTrack 0 Dec 22 13:32:20 volumio volumio[1311]: info: Listing playlists Dec 22 13:32:20 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 22 13:32:20 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 22 13:32:20 volumio go-librespot[9056]: time="2025-12-22T13:32:20+01:00" level=debug msg="obtained new client token: AAD+5RhDAYuxoeSSrpJNg+acKZvbDKLICPlFXopyVXjrrAKmph7bXhl+i1/oL2BDvNhl1/d9E0M+fPLniH0A0n4w/2aOQIMut8VQOWm7CJFTEHMJb6Ajl2H3Gu399IbkepYtdrTtb7qISY4KMQvpUVQLpqlnf8HMtN4Y8PXfYMJH9WW6srOly8O2MP1P/jI0zBs1b9PZgxihETWlsARKJ0/pj4SQODwAit6CX7vqhJcQcdyyjBDd40I=" Dec 22 13:32:20 volumio go-librespot[9056]: time="2025-12-22T13:32:20+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 22 13:32:20 volumio go-librespot[9056]: time="2025-12-22T13:32:20+01:00" level=debug msg="completed keyexchange" Dec 22 13:32:20 volumio go-librespot[9056]: time="2025-12-22T13:32:20+01:00" level=debug msg="completed challenge" Dec 22 13:32:20 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 22 13:32:20 volumio go-librespot[9056]: time="2025-12-22T13:32:20+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: TravelRestriction " Dec 22 13:32:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 22 13:32:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 22 13:32:21 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 22 13:32:22 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 22 13:32:22 volumio volumio[1311]: info: Received Get System Info Dec 22 13:32:22 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 22 13:32:22 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 22 13:32:22 volumio volumio[1311]: info: Discovery: Getting this device information Dec 22 13:32:22 volumio volumio[1311]: info: CoreCommandRouter::volumioGetState Dec 22 13:32:22 volumio volumio[1311]: info: CorePlayQueue::getTrack 0 Dec 22 13:32:22 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 22 13:32:22 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 22 13:32:22 volumio volumio[1311]: info: Received Get System Info Dec 22 13:32:22 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 22 13:32:22 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 22 13:32:22 volumio volumio[1311]: info: Discovery: Getting this device information Dec 22 13:32:22 volumio volumio[1311]: info: CoreCommandRouter::volumioGetState Dec 22 13:32:22 volumio volumio[1311]: info: CorePlayQueue::getTrack 0 Dec 22 13:32:22 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 22 13:32:23 volumio volumio[1311]: info: Initializing connection to go-librespot Websocket Dec 22 13:32:23 volumio volumio[1311]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 22 13:32:23 volumio volumio[1311]: info: go-librespot daemon successfully initialized Dec 22 13:32:23 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Dec 22 13:32:23 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 22 13:32:23 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 22 13:32:23 volumio go-librespot[9064]: go-librespot daemon starting... Dec 22 13:32:23 volumio go-librespot[9065]: time="2025-12-22T13:32:23+01:00" level=info msg="running go-librespot 0.4.0" Dec 22 13:32:23 volumio go-librespot[9065]: time="2025-12-22T13:32:23+01:00" level=debug msg="app state loaded" Dec 22 13:32:23 volumio go-librespot[9065]: time="2025-12-22T13:32:23+01:00" level=debug msg="stored credentials not found" Dec 22 13:32:23 volumio go-librespot[9065]: time="2025-12-22T13:32:23+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 22 13:32:23 volumio go-librespot[9065]: time="2025-12-22T13:32:23+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 22 13:32:23 volumio go-librespot[9065]: time="2025-12-22T13:32:23+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 22 13:32:23 volumio go-librespot[9065]: time="2025-12-22T13:32:23+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 22 13:32:23 volumio go-librespot[9065]: time="2025-12-22T13:32:23+01:00" level=info msg="zeroconf server listening on port 44021" Dec 22 13:32:23 volumio go-librespot[9065]: time="2025-12-22T13:32:23+01:00" level=debug msg="obtained new client token: AAAOzFlb5iaJ6zOJdAY1pp90hV/3pib8/m6/oe4DEMJMaXoZUMdZeq0oG0UiAbAc8HeJcg8u+TSxBNZo/z6RDi+7HgNvArAkvDI1b9sRNf0zK7gnaui0C6uuNAjxI7OEkiz9AWE1nm9xhqysLdH2Q3jZE0tsfJhxt+mp/NvtLMz/I100yK8lRgmAhWwT/SdFwick6O7PIewHvcj5MXLQAWhOtC3xEzoQHx7tyW9QOFTXUq3FI4djMwuQmg==" Dec 22 13:32:23 volumio go-librespot[9065]: time="2025-12-22T13:32:23+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 22 13:32:23 volumio go-librespot[9065]: time="2025-12-22T13:32:23+01:00" level=debug msg="completed keyexchange" Dec 22 13:32:23 volumio go-librespot[9065]: time="2025-12-22T13:32:23+01:00" level=debug msg="completed challenge" Dec 22 13:32:23 volumio go-librespot[9065]: time="2025-12-22T13:32:23+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: TravelRestriction " Dec 22 13:32:23 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 22 13:32:23 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 22 13:32:26 volumio volumio[1311]: info: Initializing connection to go-librespot Websocket Dec 22 13:32:26 volumio volumio[1311]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 22 13:32:26 volumio volumio[1311]: info: Initializing connection to go-librespot Websocket Dec 22 13:32:26 volumio volumio[1311]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 22 13:32:26 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Dec 22 13:32:26 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 22 13:32:26 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 22 13:32:26 volumio go-librespot[9086]: go-librespot daemon starting... Dec 22 13:32:26 volumio go-librespot[9087]: time="2025-12-22T13:32:26+01:00" level=info msg="running go-librespot 0.4.0" Dec 22 13:32:26 volumio go-librespot[9087]: time="2025-12-22T13:32:26+01:00" level=debug msg="app state loaded" Dec 22 13:32:26 volumio go-librespot[9087]: time="2025-12-22T13:32:26+01:00" level=debug msg="stored credentials not found" Dec 22 13:32:26 volumio go-librespot[9087]: time="2025-12-22T13:32:26+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 22 13:32:26 volumio go-librespot[9087]: time="2025-12-22T13:32:26+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 22 13:32:26 volumio go-librespot[9087]: time="2025-12-22T13:32:26+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 22 13:32:26 volumio go-librespot[9087]: time="2025-12-22T13:32:26+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 22 13:32:26 volumio go-librespot[9087]: time="2025-12-22T13:32:26+01:00" level=info msg="zeroconf server listening on port 40477" Dec 22 13:32:26 volumio go-librespot[9087]: time="2025-12-22T13:32:26+01:00" level=debug msg="obtained new client token: AADTmNylcK6ap2FGPKR1y5xDYNgAURX0rvo8ibW7d+44B9VpsikdxMCfL5KJ374K9sLqEMpst6zDBEVzMQJZ6Uz+26iYMPuYkfnSKeNBsCJIG5IVvChypNAHS8rkx9ZV0axPeTKcGYmr8g0tb6TD5kWOqC9s6QMOhB/TaOV3vGZdRcaT46VnHOlPijJVPJ/UaZIkwkJV/dHdqWgIkvPSooTbm17BKiroy3W4NAlalCJS1ngDiaGxaMCs1A==" Dec 22 13:32:26 volumio go-librespot[9087]: time="2025-12-22T13:32:26+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 22 13:32:26 volumio go-librespot[9087]: time="2025-12-22T13:32:26+01:00" level=debug msg="completed keyexchange" Dec 22 13:32:26 volumio go-librespot[9087]: time="2025-12-22T13:32:26+01:00" level=debug msg="completed challenge" Dec 22 13:32:26 volumio go-librespot[9087]: time="2025-12-22T13:32:26+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: TravelRestriction " Dec 22 13:32:26 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 22 13:32:26 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 22 13:32:29 volumio volumio[1311]: info: Initializing connection to go-librespot Websocket Dec 22 13:32:29 volumio volumio[1311]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 22 13:32:29 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Dec 22 13:32:29 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 22 13:32:29 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 22 13:32:29 volumio go-librespot[9094]: go-librespot daemon starting... Dec 22 13:32:29 volumio go-librespot[9095]: time="2025-12-22T13:32:29+01:00" level=info msg="running go-librespot 0.4.0" Dec 22 13:32:29 volumio go-librespot[9095]: time="2025-12-22T13:32:29+01:00" level=debug msg="app state loaded" Dec 22 13:32:29 volumio go-librespot[9095]: time="2025-12-22T13:32:29+01:00" level=debug msg="stored credentials not found" Dec 22 13:32:29 volumio go-librespot[9095]: time="2025-12-22T13:32:29+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 22 13:32:29 volumio go-librespot[9095]: time="2025-12-22T13:32:29+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 22 13:32:29 volumio go-librespot[9095]: time="2025-12-22T13:32:29+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 22 13:32:29 volumio go-librespot[9095]: time="2025-12-22T13:32:29+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 22 13:32:29 volumio go-librespot[9095]: time="2025-12-22T13:32:29+01:00" level=info msg="zeroconf server listening on port 38555" Dec 22 13:32:29 volumio go-librespot[9095]: time="2025-12-22T13:32:29+01:00" level=debug msg="obtained new client token: AADIzMBXQAURmDf8lnBVYN1iF8v0aZXGiJmuxrX0oeQNbfoYz3L0GvT3g5iBBX7UKVG9iRe4ZSNb1faUyAG2wqQ9odq9hO55JbT4lV5EUsYzT2/g1vkQ1Vgqou2BsPZ/70YCHA3/EYmTpRNy67i/RLeFfomTWu4vuKFylWDnu+P22SIffwYsrkZCRpX4ZxQmbndhZiVU5MVIius7G9/QRxFsbf6enS6TiLw6pWoXVfQZc8NyHSCMSFollA==" Dec 22 13:32:29 volumio go-librespot[9095]: time="2025-12-22T13:32:29+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 22 13:32:30 volumio go-librespot[9095]: time="2025-12-22T13:32:30+01:00" level=debug msg="completed keyexchange" Dec 22 13:32:30 volumio go-librespot[9095]: time="2025-12-22T13:32:30+01:00" level=debug msg="completed challenge" Dec 22 13:32:30 volumio go-librespot[9095]: time="2025-12-22T13:32:30+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: TravelRestriction " Dec 22 13:32:30 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 22 13:32:30 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 22 13:32:30 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 22 13:32:32 volumio volumio[1311]: info: Initializing connection to go-librespot Websocket Dec 22 13:32:32 volumio volumio[1311]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 22 13:32:33 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Dec 22 13:32:33 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 22 13:32:33 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 22 13:32:33 volumio go-librespot[9103]: go-librespot daemon starting... Dec 22 13:32:33 volumio go-librespot[9104]: time="2025-12-22T13:32:33+01:00" level=info msg="running go-librespot 0.4.0" Dec 22 13:32:33 volumio go-librespot[9104]: time="2025-12-22T13:32:33+01:00" level=debug msg="app state loaded" Dec 22 13:32:33 volumio go-librespot[9104]: time="2025-12-22T13:32:33+01:00" level=debug msg="stored credentials not found" Dec 22 13:32:33 volumio go-librespot[9104]: time="2025-12-22T13:32:33+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 22 13:32:33 volumio go-librespot[9104]: time="2025-12-22T13:32:33+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 22 13:32:33 volumio go-librespot[9104]: time="2025-12-22T13:32:33+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 22 13:32:33 volumio go-librespot[9104]: time="2025-12-22T13:32:33+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 22 13:32:33 volumio go-librespot[9104]: time="2025-12-22T13:32:33+01:00" level=info msg="zeroconf server listening on port 43635" Dec 22 13:32:33 volumio go-librespot[9104]: time="2025-12-22T13:32:33+01:00" level=debug msg="obtained new client token: AADvTRIN3WdmPU0pP+akX2NK9/M7zNAixQxyid7nAz20U7Q/5l3WGhI3zxWo44uKS9Kn5CiibUrvU04hHEr2aEMqHg/HVvOC7yynqwpcL/U5B9QmjFFY4omMpUtycjeZwbJVAjiLGbehCw4DZxus3t1bOMt1jlKkcAhXeT+IrUVTLVnybAnAneoMuhu2tPsFKUcn+37dUXfQ1s4oA+KTY65X0S0gIc6+N89NroBEGXJSbAmrk9+nHNo=" Dec 22 13:32:33 volumio go-librespot[9104]: time="2025-12-22T13:32:33+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 22 13:32:33 volumio go-librespot[9104]: time="2025-12-22T13:32:33+01:00" level=debug msg="completed keyexchange" Dec 22 13:32:33 volumio go-librespot[9104]: time="2025-12-22T13:32:33+01:00" level=debug msg="completed challenge" Dec 22 13:32:33 volumio go-librespot[9104]: time="2025-12-22T13:32:33+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: TravelRestriction " Dec 22 13:32:33 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 22 13:32:33 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 22 13:32:35 volumio volumio[1311]: info: Initializing connection to go-librespot Websocket Dec 22 13:32:35 volumio volumio[1311]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 22 13:32:36 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Dec 22 13:32:36 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 22 13:32:36 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 22 13:32:36 volumio go-librespot[9128]: go-librespot daemon starting... Dec 22 13:32:36 volumio go-librespot[9129]: time="2025-12-22T13:32:36+01:00" level=info msg="running go-librespot 0.4.0" Dec 22 13:32:36 volumio go-librespot[9129]: time="2025-12-22T13:32:36+01:00" level=debug msg="app state loaded" Dec 22 13:32:36 volumio go-librespot[9129]: time="2025-12-22T13:32:36+01:00" level=debug msg="stored credentials not found" Dec 22 13:32:36 volumio go-librespot[9129]: time="2025-12-22T13:32:36+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 22 13:32:36 volumio go-librespot[9129]: time="2025-12-22T13:32: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 22 13:32:36 volumio go-librespot[9129]: time="2025-12-22T13:32:36+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 22 13:32:36 volumio go-librespot[9129]: time="2025-12-22T13:32:36+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 22 13:32:36 volumio go-librespot[9129]: time="2025-12-22T13:32:36+01:00" level=info msg="zeroconf server listening on port 39051" Dec 22 13:32:36 volumio go-librespot[9129]: time="2025-12-22T13:32:36+01:00" level=debug msg="obtained new client token: AAD4EnpkbhsbG4sTrFtOhdj3n4GEp5gwRo9bsxuMVDQd/XB8o7bhPsCBsUKFFz0fzIvHRvchFduKlef7ATclZuzlpZRQyNJzJm9+2gzfwuYaAhCeaLBZFb/OlgupzIrJ3IpY1yyx7PIOH4oMIeqIsk511GuKEoTh3x9lyBJ4G2hHB9sZmXouToFEczzxJSxx3gxadIAxk4VacI1J8KDgyNqMYXyYsaSqTJ31IWQiPBvPoSet938H6nmkRg==" Dec 22 13:32:36 volumio go-librespot[9129]: time="2025-12-22T13:32:36+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 22 13:32:36 volumio go-librespot[9129]: time="2025-12-22T13:32:36+01:00" level=debug msg="completed keyexchange" Dec 22 13:32:36 volumio go-librespot[9129]: time="2025-12-22T13:32:36+01:00" level=debug msg="completed challenge" Dec 22 13:32:36 volumio go-librespot[9129]: time="2025-12-22T13:32:36+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: TravelRestriction " Dec 22 13:32:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 22 13:32:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 22 13:32:38 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 22 13:32:38 volumio volumio[1311]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 22 13:32:38 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 22 13:32:38 volumio volumio[1311]: info: Received Get System Version Dec 22 13:32:38 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 22 13:32:38 volumio volumio[1311]: info: Received Get System Info Dec 22 13:32:38 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 22 13:32:38 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 22 13:32:38 volumio volumio[1311]: info: Discovery: Getting this device information Dec 22 13:32:38 volumio volumio[1311]: info: CoreCommandRouter::volumioGetState Dec 22 13:32:38 volumio volumio[1311]: info: CorePlayQueue::getTrack 0 Dec 22 13:32:38 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 22 13:32:38 volumio volumio[1311]: info: Initializing connection to go-librespot Websocket Dec 22 13:32:38 volumio volumio[1311]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 22 13:32:39 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Dec 22 13:32:39 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 22 13:32:39 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 22 13:32:39 volumio go-librespot[9136]: go-librespot daemon starting... Dec 22 13:32:39 volumio go-librespot[9137]: time="2025-12-22T13:32:39+01:00" level=info msg="running go-librespot 0.4.0" Dec 22 13:32:39 volumio go-librespot[9137]: time="2025-12-22T13:32:39+01:00" level=debug msg="app state loaded" Dec 22 13:32:39 volumio go-librespot[9137]: time="2025-12-22T13:32:39+01:00" level=debug msg="stored credentials not found" Dec 22 13:32:39 volumio go-librespot[9137]: time="2025-12-22T13:32:39+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 22 13:32:39 volumio go-librespot[9137]: time="2025-12-22T13:32:39+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 22 13:32:39 volumio go-librespot[9137]: time="2025-12-22T13:32:39+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 22 13:32:39 volumio go-librespot[9137]: time="2025-12-22T13:32:39+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 22 13:32:39 volumio go-librespot[9137]: time="2025-12-22T13:32:39+01:00" level=info msg="zeroconf server listening on port 33379" Dec 22 13:32:39 volumio go-librespot[9137]: time="2025-12-22T13:32:39+01:00" level=debug msg="obtained new client token: AAD3kdHwix5HxeMGA6MeQEIboow4JE5Q9Zht7bY/ZjfOxgOuMYmugARzG8nhGaYh6CLjKsnzA5qPcqrZzdD9ib+nIPaiYxUDDU3ICBBrlIycaDtc2QWQc3Y7NPUVFvEJ5XKelAUz2oWRK1wmgW2oX/45rVk+irzpU5QtJK7bKzDq36nJEjNXemG+CoAN4vPF8QjjQYIUuzD1qhOo6gkMJstl7SoJSsuTmQWVBj17bg84dFO0ghji8jtqDQ==" Dec 22 13:32:39 volumio go-librespot[9137]: time="2025-12-22T13:32:39+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 22 13:32:40 volumio go-librespot[9137]: time="2025-12-22T13:32:40+01:00" level=debug msg="completed keyexchange" Dec 22 13:32:40 volumio go-librespot[9137]: time="2025-12-22T13:32:40+01:00" level=debug msg="completed challenge" Dec 22 13:32:40 volumio go-librespot[9137]: time="2025-12-22T13:32:40+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: TravelRestriction " Dec 22 13:32:40 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 22 13:32:40 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 22 13:32:41 volumio volumio[1311]: info: Initializing connection to go-librespot Websocket Dec 22 13:32:41 volumio volumio[1311]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 22 13:32:43 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Dec 22 13:32:43 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 22 13:32:43 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 22 13:32:43 volumio go-librespot[9144]: go-librespot daemon starting... Dec 22 13:32:43 volumio go-librespot[9145]: time="2025-12-22T13:32:43+01:00" level=info msg="running go-librespot 0.4.0" Dec 22 13:32:43 volumio go-librespot[9145]: time="2025-12-22T13:32:43+01:00" level=debug msg="app state loaded" Dec 22 13:32:43 volumio go-librespot[9145]: time="2025-12-22T13:32:43+01:00" level=debug msg="stored credentials not found" Dec 22 13:32:43 volumio go-librespot[9145]: time="2025-12-22T13:32:43+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 22 13:32:43 volumio go-librespot[9145]: time="2025-12-22T13:32:43+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 22 13:32:43 volumio go-librespot[9145]: time="2025-12-22T13:32:43+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 22 13:32:43 volumio go-librespot[9145]: time="2025-12-22T13:32:43+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 22 13:32:43 volumio go-librespot[9145]: time="2025-12-22T13:32:43+01:00" level=info msg="zeroconf server listening on port 43053" Dec 22 13:32:43 volumio go-librespot[9145]: time="2025-12-22T13:32:43+01:00" level=debug msg="obtained new client token: AABrcCkIU+ByyImnOY3h2c0pScJ+DsR5wS3gvK6HqWcetEudYFDh0dCyBJb9URSa2U2nSoGwJja6hWwkIBNlmhWbLKjtGXuhpC7kEUrkRtBi0VKU1fbMT/3gGhKV5CCNYpIF9LQ7WuX5q2RVGuwPwdDfq8B7E9kTFEDJlt8BfYP6/tqji2m7CdLdjejrJg2WL8C71xdG8fbZboYfSnPdud/OdBa7/B3KqlEWHXACNim67GhSBiRQYXc=" Dec 22 13:32:43 volumio go-librespot[9145]: time="2025-12-22T13:32:43+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 22 13:32:43 volumio go-librespot[9145]: time="2025-12-22T13:32:43+01:00" level=debug msg="completed keyexchange" Dec 22 13:32:43 volumio go-librespot[9145]: time="2025-12-22T13:32:43+01:00" level=debug msg="completed challenge" Dec 22 13:32:43 volumio go-librespot[9145]: time="2025-12-22T13:32:43+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: TravelRestriction " Dec 22 13:32:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 22 13:32:43 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 22 13:32:44 volumio volumio[1311]: info: Initializing connection to go-librespot Websocket Dec 22 13:32:44 volumio volumio[1311]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 22 13:32:46 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Dec 22 13:32:46 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 22 13:32:46 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 22 13:32:46 volumio go-librespot[9168]: go-librespot daemon starting... Dec 22 13:32:46 volumio go-librespot[9169]: time="2025-12-22T13:32:46+01:00" level=info msg="running go-librespot 0.4.0" Dec 22 13:32:46 volumio go-librespot[9169]: time="2025-12-22T13:32:46+01:00" level=debug msg="app state loaded" Dec 22 13:32:46 volumio go-librespot[9169]: time="2025-12-22T13:32:46+01:00" level=debug msg="stored credentials not found" Dec 22 13:32:46 volumio go-librespot[9169]: time="2025-12-22T13:32:46+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 22 13:32:46 volumio go-librespot[9169]: time="2025-12-22T13:32:46+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 22 13:32:46 volumio go-librespot[9169]: time="2025-12-22T13:32:46+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 22 13:32:46 volumio go-librespot[9169]: time="2025-12-22T13:32:46+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 22 13:32:46 volumio go-librespot[9169]: time="2025-12-22T13:32:46+01:00" level=info msg="zeroconf server listening on port 33871" Dec 22 13:32:46 volumio go-librespot[9169]: time="2025-12-22T13:32:46+01:00" level=debug msg="obtained new client token: AABz+Hnk8m4HSTWsaep7w2w/FJEH2ugcRcnjxD8LvtkAi06nt6/E7/Z5pUBaE+rYOAZKKpu5Vb5tbrceiG2MocPS1mYbMwgPP1OGeuAfpGRDeP2ISTf9gnXxry0c9XeQQ41lbtvjTe/Cp4ndIM7CddVwRqMAArjT8UIk1BLBkTspnlbY3X39FAeApDZp+JNaNxahRTJdjeL2p1Qp6IbOn6EikNrI80Yeu5TJPszN0NBWppKYyP9WMUuBBw==" Dec 22 13:32:46 volumio go-librespot[9169]: time="2025-12-22T13:32:46+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 22 13:32:46 volumio go-librespot[9169]: time="2025-12-22T13:32:46+01:00" level=debug msg="completed keyexchange" Dec 22 13:32:46 volumio go-librespot[9169]: time="2025-12-22T13:32:46+01:00" level=debug msg="completed challenge" Dec 22 13:32:46 volumio go-librespot[9169]: time="2025-12-22T13:32:46+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: TravelRestriction " Dec 22 13:32:46 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 22 13:32:46 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 22 13:32:47 volumio volumio[1311]: info: Initializing connection to go-librespot Websocket Dec 22 13:32:47 volumio volumio[1311]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 22 13:32:49 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Dec 22 13:32:49 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 22 13:32:49 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 22 13:32:49 volumio go-librespot[9176]: go-librespot daemon starting... Dec 22 13:32:49 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Dec 22 13:32:49 volumio go-librespot[9177]: time="2025-12-22T13:32:49+01:00" level=info msg="running go-librespot 0.4.0" Dec 22 13:32:49 volumio go-librespot[9177]: time="2025-12-22T13:32:49+01:00" level=debug msg="app state loaded" Dec 22 13:32:49 volumio go-librespot[9177]: time="2025-12-22T13:32:49+01:00" level=debug msg="stored credentials not found" Dec 22 13:32:49 volumio go-librespot[9177]: time="2025-12-22T13:32:49+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 22 13:32:49 volumio go-librespot[9177]: time="2025-12-22T13:32:49+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 22 13:32:49 volumio go-librespot[9177]: time="2025-12-22T13:32:49+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 22 13:32:49 volumio go-librespot[9177]: time="2025-12-22T13:32:49+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 22 13:32:49 volumio go-librespot[9177]: time="2025-12-22T13:32:49+01:00" level=info msg="zeroconf server listening on port 36775" Dec 22 13:32:49 volumio go-librespot[9177]: time="2025-12-22T13:32:49+01:00" level=debug msg="obtained new client token: AAAb4Wzw7ftU6oOS7+aFH2bIKTiUJBZ9lvkRLLKkE7Q+fXt9ZcmNTbVtPXeMs9PrJFEWBgARDBcHdd7+r5KmutdWYJ30LrFF6yrbDtuRq81Cfw9SLFjIRD6kZoDB1/jxJ9ELALxKLOj5P0OvmjqN974xUnMmHm6WJc+Ahnx0Bygvey4mfGGLVjpd2GWSjlAvVzDkWgTDnSbJCf8+Wb/Q/zxBYdtHUFz1X/9rmo3y2nDiL2Qb1960yilMiA==" Dec 22 13:32:49 volumio go-librespot[9177]: time="2025-12-22T13:32:49+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 22 13:32:49 volumio go-librespot[9177]: time="2025-12-22T13:32:49+01:00" level=debug msg="completed keyexchange" Dec 22 13:32:49 volumio go-librespot[9177]: time="2025-12-22T13:32:49+01:00" level=debug msg="completed challenge" Dec 22 13:32:49 volumio go-librespot[9177]: time="2025-12-22T13:32:49+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: TravelRestriction " Dec 22 13:32:49 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 22 13:32:49 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 22 13:32:49 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Dec 22 13:32:49 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Dec 22 13:32:50 volumio volumio[1311]: info: Initializing connection to go-librespot Websocket Dec 22 13:32:50 volumio volumio[1311]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 22 13:32:52 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Dec 22 13:32:52 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 22 13:32:52 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 22 13:32:52 volumio go-librespot[9209]: go-librespot daemon starting... Dec 22 13:32:52 volumio go-librespot[9210]: time="2025-12-22T13:32:52+01:00" level=info msg="running go-librespot 0.4.0" Dec 22 13:32:52 volumio go-librespot[9210]: time="2025-12-22T13:32:52+01:00" level=debug msg="app state loaded" Dec 22 13:32:52 volumio go-librespot[9210]: time="2025-12-22T13:32:52+01:00" level=debug msg="stored credentials not found" Dec 22 13:32:52 volumio go-librespot[9210]: time="2025-12-22T13:32:52+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 22 13:32:52 volumio go-librespot[9210]: time="2025-12-22T13:32:52+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 22 13:32:52 volumio go-librespot[9210]: time="2025-12-22T13:32:52+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 22 13:32:52 volumio go-librespot[9210]: time="2025-12-22T13:32:52+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 22 13:32:52 volumio go-librespot[9210]: time="2025-12-22T13:32:52+01:00" level=info msg="zeroconf server listening on port 37085" Dec 22 13:32:52 volumio go-librespot[9210]: time="2025-12-22T13:32:52+01:00" level=debug msg="obtained new client token: AAAWWQyV9oYJn3XA0SfhJPBa1xK/xR1RJMUE5hNnPGNz4ZIvmzDs/StHL0t7EJzE1kx+aFi2IzRRQ5IOX1SIUf5oQL2wg4jB7BgR5bgozYUw/Gykjv/vgihbiY/+Nv9DYDLPsneI23DPqlgPiLwU/vxsGp1hKq1W5yeuMb8l/jaxjNFB7oLZLmfgLp6FAF3zUVNZyB66ARavYevsrJR7IebYBa19p3PR7o2Yl1artFz45EHZOFaihRJ8Lw==" Dec 22 13:32:52 volumio go-librespot[9210]: time="2025-12-22T13:32:52+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 22 13:32:53 volumio go-librespot[9210]: time="2025-12-22T13:32:53+01:00" level=debug msg="completed keyexchange" Dec 22 13:32:53 volumio go-librespot[9210]: time="2025-12-22T13:32:53+01:00" level=debug msg="completed challenge" Dec 22 13:32:53 volumio go-librespot[9210]: time="2025-12-22T13:32:53+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: TravelRestriction " Dec 22 13:32:53 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 22 13:32:53 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 22 13:32:53 volumio volumio[1311]: info: Initializing connection to go-librespot Websocket Dec 22 13:32:53 volumio volumio[1311]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 22 13:32:56 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11. Dec 22 13:32:56 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 22 13:32:56 volumio volumio[1311]: info: Initializing connection to go-librespot Websocket Dec 22 13:32:56 volumio volumio[1311]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 22 13:32:56 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 22 13:32:56 volumio go-librespot[9234]: go-librespot daemon starting... Dec 22 13:32:56 volumio go-librespot[9235]: time="2025-12-22T13:32:56+01:00" level=info msg="running go-librespot 0.4.0" Dec 22 13:32:56 volumio go-librespot[9235]: time="2025-12-22T13:32:56+01:00" level=debug msg="app state loaded" Dec 22 13:32:56 volumio go-librespot[9235]: time="2025-12-22T13:32:56+01:00" level=debug msg="stored credentials not found" Dec 22 13:32:56 volumio go-librespot[9235]: time="2025-12-22T13:32:56+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 22 13:32:56 volumio go-librespot[9235]: time="2025-12-22T13:32:56+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 22 13:32:56 volumio go-librespot[9235]: time="2025-12-22T13:32:56+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 22 13:32:56 volumio go-librespot[9235]: time="2025-12-22T13:32:56+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 22 13:32:56 volumio go-librespot[9235]: time="2025-12-22T13:32:56+01:00" level=info msg="zeroconf server listening on port 37607" Dec 22 13:32:56 volumio go-librespot[9235]: time="2025-12-22T13:32:56+01:00" level=debug msg="obtained new client token: AADUXJuW4016OwHehdsYsrg5WDTbAMQ4bPjuZdUB9ch76SIk4CEsEyfqjm19+cfRJsLhu4oPtOSgbQv0nHJbfIi3jD77HCXeTr265A0Kh+n1HhxaaXvLZMTOCPpxwJmnsWUqDwl5PUgYbCiydFvKdNxAIsjx66F2kf5UN1wK49LZ5fKZLFcW4C31VSnVrDzWV/wcOXtqL3tClHmCWCHd/c13xcQZyua86jx2XQc3teno0OGIf9bN+8E=" Dec 22 13:32:56 volumio go-librespot[9235]: time="2025-12-22T13:32:56+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 22 13:32:56 volumio go-librespot[9235]: time="2025-12-22T13:32:56+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Dec 22 13:32:56 volumio go-librespot[9235]: time="2025-12-22T13:32:56+01:00" level=debug msg="completed keyexchange" Dec 22 13:32:56 volumio go-librespot[9235]: time="2025-12-22T13:32:56+01:00" level=debug msg="completed challenge" Dec 22 13:32:56 volumio go-librespot[9235]: time="2025-12-22T13:32:56+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: TravelRestriction " Dec 22 13:32:56 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 22 13:32:56 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 22 13:32:59 volumio volumio[1311]: info: Initializing connection to go-librespot Websocket Dec 22 13:32:59 volumio volumio[1311]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 22 13:32:59 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12. Dec 22 13:32:59 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 22 13:32:59 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 22 13:32:59 volumio go-librespot[9242]: go-librespot daemon starting... Dec 22 13:32:59 volumio go-librespot[9243]: time="2025-12-22T13:32:59+01:00" level=info msg="running go-librespot 0.4.0" Dec 22 13:32:59 volumio go-librespot[9243]: time="2025-12-22T13:32:59+01:00" level=debug msg="app state loaded" Dec 22 13:32:59 volumio go-librespot[9243]: time="2025-12-22T13:32:59+01:00" level=debug msg="stored credentials not found" Dec 22 13:32:59 volumio go-librespot[9243]: time="2025-12-22T13:32:59+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 22 13:32:59 volumio go-librespot[9243]: time="2025-12-22T13:32:59+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 22 13:32:59 volumio go-librespot[9243]: time="2025-12-22T13:32:59+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 22 13:32:59 volumio go-librespot[9243]: time="2025-12-22T13:32:59+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 22 13:32:59 volumio go-librespot[9243]: time="2025-12-22T13:32:59+01:00" level=info msg="zeroconf server listening on port 40123" Dec 22 13:32:59 volumio go-librespot[9243]: time="2025-12-22T13:32:59+01:00" level=debug msg="obtained new client token: AACzTCtScFaSz0McsZ1c2tbhl/z5UU/8ng/ncnltU28rf+59V/g16eoxcacmgP7q88z3bbQEs1aYVrbS5SJKPzigGK665cmVT8IdW5gjdUDVBa15jqMgRp/9KqHjiAjRv0+lILT3X88kCGaMFyYPnmzBtIgKU4c6Iez0sw0EBa6sGeONiw5igFKZWVRJUXL+h282Y6kQeivrGAct83fnYzvvZLSXvVmwtW8+1zvwVMPKYIvtYWL6E5lm3w==" Dec 22 13:32:59 volumio go-librespot[9243]: time="2025-12-22T13:32:59+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 22 13:32:59 volumio go-librespot[9243]: time="2025-12-22T13:32:59+01:00" level=debug msg="completed keyexchange" Dec 22 13:32:59 volumio go-librespot[9243]: time="2025-12-22T13:32:59+01:00" level=debug msg="completed challenge" Dec 22 13:32:59 volumio go-librespot[9243]: time="2025-12-22T13:32:59+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: TravelRestriction " Dec 22 13:32:59 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 22 13:32:59 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 22 13:33:02 volumio volumio[1311]: info: Initializing connection to go-librespot Websocket Dec 22 13:33:02 volumio volumio[1311]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 22 13:33:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13. Dec 22 13:33:02 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 22 13:33:02 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 22 13:33:02 volumio go-librespot[9250]: go-librespot daemon starting... Dec 22 13:33:02 volumio go-librespot[9251]: time="2025-12-22T13:33:02+01:00" level=info msg="running go-librespot 0.4.0" Dec 22 13:33:02 volumio go-librespot[9251]: time="2025-12-22T13:33:02+01:00" level=debug msg="app state loaded" Dec 22 13:33:02 volumio go-librespot[9251]: time="2025-12-22T13:33:02+01:00" level=debug msg="stored credentials not found" Dec 22 13:33:02 volumio go-librespot[9251]: time="2025-12-22T13:33:02+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 22 13:33:02 volumio go-librespot[9251]: time="2025-12-22T13:33:02+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 22 13:33:02 volumio go-librespot[9251]: time="2025-12-22T13:33:02+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 22 13:33:02 volumio go-librespot[9251]: time="2025-12-22T13:33:02+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 22 13:33:02 volumio go-librespot[9251]: time="2025-12-22T13:33:02+01:00" level=info msg="zeroconf server listening on port 42513" Dec 22 13:33:02 volumio go-librespot[9251]: time="2025-12-22T13:33:02+01:00" level=debug msg="obtained new client token: AAAuqKn5ipJhHAQ+DpsBx3/cG+slVC4vl1HMFvArKBd6SJSdTYEpeIdcVFdMPzoKMwFjtziYOOeUQ0dwigEtmb1jTxyWkQLDlUvqc6/OAixkZHkDToWZUsrDLUST7ADelq17j1v7kzlu5VjiaWF0Mci+xGwVlnB44zjOFdAf5PK4FgykOFpnGFv73kQ/ywwAg/1N1xVHPHtgJdcR+AdyDL+Wn9KDgSEa5eGNGIs6K6yDNY8RGSspp54lCA==" Dec 22 13:33:02 volumio go-librespot[9251]: time="2025-12-22T13:33:02+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 22 13:33:02 volumio go-librespot[9251]: time="2025-12-22T13:33:02+01:00" level=debug msg="completed keyexchange" Dec 22 13:33:02 volumio go-librespot[9251]: time="2025-12-22T13:33:02+01:00" level=debug msg="completed challenge" Dec 22 13:33:02 volumio go-librespot[9251]: time="2025-12-22T13:33:02+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: TravelRestriction " Dec 22 13:33:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 22 13:33:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 22 13:33:05 volumio volumio[1311]: info: Initializing connection to go-librespot Websocket Dec 22 13:33:05 volumio volumio[1311]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 22 13:33:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14. Dec 22 13:33:05 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 22 13:33:05 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 22 13:33:05 volumio go-librespot[9273]: go-librespot daemon starting... Dec 22 13:33:05 volumio go-librespot[9274]: time="2025-12-22T13:33:05+01:00" level=info msg="running go-librespot 0.4.0" Dec 22 13:33:05 volumio go-librespot[9274]: time="2025-12-22T13:33:05+01:00" level=debug msg="app state loaded" Dec 22 13:33:05 volumio go-librespot[9274]: time="2025-12-22T13:33:05+01:00" level=debug msg="stored credentials not found" Dec 22 13:33:05 volumio go-librespot[9274]: time="2025-12-22T13:33:05+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 22 13:33:05 volumio go-librespot[9274]: time="2025-12-22T13:33:05+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 22 13:33:05 volumio go-librespot[9274]: time="2025-12-22T13:33:05+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 22 13:33:05 volumio go-librespot[9274]: time="2025-12-22T13:33:05+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 22 13:33:05 volumio go-librespot[9274]: time="2025-12-22T13:33:05+01:00" level=info msg="zeroconf server listening on port 39535" Dec 22 13:33:05 volumio go-librespot[9274]: time="2025-12-22T13:33:05+01:00" level=debug msg="obtained new client token: AABIzGqAuPyKqn7qzqah/2EXKiZwTwkjl7Pivh+mgT7oMCUZtDh4gLPljm6XcoOjW+E78p/JU9eU37q6iALFhUnNnaWYYgy0gx8aEfmvcHzZ0vNKplcHgtRxEPLJ+LClpr6gCKYZZGlBMq+zZRSYJibEcwmc7cPFEe9uUm65H8J/RcqET85jHqGbEtKGlZqdhI7dl9wQ/qAVBTbrEI7c1EO9ThbNmv1Ah5bSEV1fh3JtPMOkIJdvYfHZxA==" Dec 22 13:33:06 volumio go-librespot[9274]: time="2025-12-22T13:33:06+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 22 13:33:06 volumio go-librespot[9274]: time="2025-12-22T13:33:06+01:00" level=debug msg="completed keyexchange" Dec 22 13:33:06 volumio go-librespot[9274]: time="2025-12-22T13:33:06+01:00" level=debug msg="completed challenge" Dec 22 13:33:06 volumio go-librespot[9274]: time="2025-12-22T13:33:06+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: TravelRestriction " Dec 22 13:33:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 22 13:33:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 22 13:33:08 volumio volumio[1311]: info: Initializing connection to go-librespot Websocket Dec 22 13:33:08 volumio volumio[1311]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 22 13:33:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15. Dec 22 13:33:09 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 22 13:33:09 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 22 13:33:09 volumio go-librespot[9281]: go-librespot daemon starting... Dec 22 13:33:09 volumio go-librespot[9282]: time="2025-12-22T13:33:09+01:00" level=info msg="running go-librespot 0.4.0" Dec 22 13:33:09 volumio go-librespot[9282]: time="2025-12-22T13:33:09+01:00" level=debug msg="app state loaded" Dec 22 13:33:09 volumio go-librespot[9282]: time="2025-12-22T13:33:09+01:00" level=debug msg="stored credentials not found" Dec 22 13:33:09 volumio go-librespot[9282]: time="2025-12-22T13:33:09+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 22 13:33:09 volumio go-librespot[9282]: time="2025-12-22T13:33:09+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 22 13:33:09 volumio go-librespot[9282]: time="2025-12-22T13:33:09+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 22 13:33:09 volumio go-librespot[9282]: time="2025-12-22T13:33:09+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 22 13:33:09 volumio go-librespot[9282]: time="2025-12-22T13:33:09+01:00" level=info msg="zeroconf server listening on port 37931" Dec 22 13:33:09 volumio go-librespot[9282]: time="2025-12-22T13:33:09+01:00" level=debug msg="obtained new client token: AACuPEwt2No4VsrW+FOGd0YaWgjntoA9UydUab5E5eLhOYAep0pM+MdN5bOXA79irgMJ0qcUGuAtDn27rh8DqoMq8TWsT/BE0ggn/0P28y3K0VkJ/kCORyhfRyklkDFtij0umVVit0j5xbRZIlXqqi6XDSqILukusKqnH+yNx905ajDpG8Yb+geU5932pqs+XUX4VD5vGIVgocCzis46rgbK8qyXQMlKN9NIybZbQrl6ek7GIZwgR2w=" Dec 22 13:33:09 volumio go-librespot[9282]: time="2025-12-22T13:33:09+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 22 13:33:09 volumio go-librespot[9282]: time="2025-12-22T13:33:09+01:00" level=debug msg="completed keyexchange" Dec 22 13:33:09 volumio go-librespot[9282]: time="2025-12-22T13:33:09+01:00" level=debug msg="completed challenge" Dec 22 13:33:09 volumio go-librespot[9282]: time="2025-12-22T13:33:09+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: TravelRestriction " Dec 22 13:33:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 22 13:33:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 22 13:33:09 volumio volumio[1311]: verbose: New Socket.io Connection to 10.3.0.14 from 10.3.0.18 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Edg/143.0.0.0 Engine version: 3 Transport: polling Total Clients: 7 Dec 22 13:33:09 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 22 13:33:09 volumio volumio[1311]: info: CoreCommandRouter::volumioGetVisibleSources Dec 22 13:33:09 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 22 13:33:09 volumio volumio[1311]: info: CoreCommandRouter::volumioGetState Dec 22 13:33:09 volumio volumio[1311]: info: CorePlayQueue::getTrack 0 Dec 22 13:33:09 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 22 13:33:09 volumio volumio[1311]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 22 13:33:09 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 22 13:33:09 volumio volumio[1311]: info: Received Get System Info Dec 22 13:33:09 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 22 13:33:09 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 22 13:33:09 volumio volumio[1311]: info: Discovery: Getting this device information Dec 22 13:33:09 volumio volumio[1311]: info: CoreCommandRouter::volumioGetState Dec 22 13:33:09 volumio volumio[1311]: info: CorePlayQueue::getTrack 0 Dec 22 13:33:09 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 22 13:33:09 volumio volumio[1311]: info: CoreCommandRouter::volumioGetState Dec 22 13:33:09 volumio volumio[1311]: info: CorePlayQueue::getTrack 0 Dec 22 13:33:09 volumio volumio[1311]: info: Listing playlists Dec 22 13:33:09 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 22 13:33:09 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 22 13:33:09 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 22 13:33:11 volumio volumio[1311]: info: Initializing connection to go-librespot Websocket Dec 22 13:33:11 volumio volumio[1311]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 22 13:33:11 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 22 13:33:11 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 22 13:33:11 volumio volumio[1311]: info: Received Get System Info Dec 22 13:33:11 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 22 13:33:11 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 22 13:33:11 volumio volumio[1311]: info: Discovery: Getting this device information Dec 22 13:33:11 volumio volumio[1311]: info: CoreCommandRouter::volumioGetState Dec 22 13:33:11 volumio volumio[1311]: info: CorePlayQueue::getTrack 0 Dec 22 13:33:11 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 22 13:33:12 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 22 13:33:12 volumio volumio[1311]: info: Received Get System Info Dec 22 13:33:12 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 22 13:33:12 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 22 13:33:12 volumio volumio[1311]: info: Discovery: Getting this device information Dec 22 13:33:12 volumio volumio[1311]: info: CoreCommandRouter::volumioGetState Dec 22 13:33:12 volumio volumio[1311]: info: CorePlayQueue::getTrack 0 Dec 22 13:33:12 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 22 13:33:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16. Dec 22 13:33:12 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 22 13:33:12 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 22 13:33:12 volumio go-librespot[9289]: go-librespot daemon starting... Dec 22 13:33:12 volumio go-librespot[9290]: time="2025-12-22T13:33:12+01:00" level=info msg="running go-librespot 0.4.0" Dec 22 13:33:12 volumio go-librespot[9290]: time="2025-12-22T13:33:12+01:00" level=debug msg="app state loaded" Dec 22 13:33:12 volumio go-librespot[9290]: time="2025-12-22T13:33:12+01:00" level=debug msg="stored credentials not found" Dec 22 13:33:12 volumio go-librespot[9290]: time="2025-12-22T13:33:12+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 22 13:33:12 volumio go-librespot[9290]: time="2025-12-22T13:33: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 22 13:33:12 volumio go-librespot[9290]: time="2025-12-22T13:33: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 22 13:33:12 volumio go-librespot[9290]: time="2025-12-22T13:33: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 22 13:33:12 volumio go-librespot[9290]: time="2025-12-22T13:33:12+01:00" level=info msg="zeroconf server listening on port 46027" Dec 22 13:33:12 volumio go-librespot[9290]: time="2025-12-22T13:33:12+01:00" level=debug msg="obtained new client token: AAAUAEWnHNS316XnRrIvek2fBBIhzz9Eh8m6NmQjS3tVrLxDA1S1zuoRTTDnHy0EYgno282V21cr+fzqXX+M+7VhvUrENO9g8W9udg3n910iBbOy7seIIk7HkoYjZsJwJyFhpuJ95FrBDLYj+Mr34jBQhaK2MpjXgzzCa+rrRLCOwSmrRnqihUoJCKXJJhaSsBI3ut9Lx8n5kLyt8vhfujbJPMb5kRmrJ1/nXebIhd/ApR69xP95Kb//1g==" Dec 22 13:33:12 volumio go-librespot[9290]: time="2025-12-22T13:33:12+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 22 13:33:12 volumio go-librespot[9290]: time="2025-12-22T13:33:12+01:00" level=debug msg="completed keyexchange" Dec 22 13:33:12 volumio go-librespot[9290]: time="2025-12-22T13:33:12+01:00" level=debug msg="completed challenge" Dec 22 13:33:12 volumio go-librespot[9290]: time="2025-12-22T13:33:12+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: TravelRestriction " Dec 22 13:33:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 22 13:33:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 22 13:33:14 volumio volumio[1311]: info: Initializing connection to go-librespot Websocket Dec 22 13:33:14 volumio volumio[1311]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 22 13:33:15 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17. Dec 22 13:33:15 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 22 13:33:15 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 22 13:33:15 volumio go-librespot[9314]: go-librespot daemon starting... Dec 22 13:33:15 volumio go-librespot[9315]: time="2025-12-22T13:33:15+01:00" level=info msg="running go-librespot 0.4.0" Dec 22 13:33:15 volumio go-librespot[9315]: time="2025-12-22T13:33:15+01:00" level=debug msg="app state loaded" Dec 22 13:33:15 volumio go-librespot[9315]: time="2025-12-22T13:33:15+01:00" level=debug msg="stored credentials not found" Dec 22 13:33:15 volumio go-librespot[9315]: time="2025-12-22T13:33:15+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 22 13:33:15 volumio go-librespot[9315]: time="2025-12-22T13:33:15+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 22 13:33:15 volumio go-librespot[9315]: time="2025-12-22T13:33:15+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 22 13:33:15 volumio go-librespot[9315]: time="2025-12-22T13:33:15+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 22 13:33:15 volumio go-librespot[9315]: time="2025-12-22T13:33:15+01:00" level=info msg="zeroconf server listening on port 37727" Dec 22 13:33:15 volumio go-librespot[9315]: time="2025-12-22T13:33:15+01:00" level=debug msg="obtained new client token: AAB28h51lk2xa8bfWno9Drnv0EMt0ohKWYMdd2Ck4HeSfCbQz+paRBdsxoHSFRhKiV/VWx15bz/dPE2+SsLUZJ7t5F7cQf6YFdI5ajjGUpdwzYT2snZMvivSPXZQs4iT4dGn622JFZaaX5Vyj/bxE8rdaiO7HnyOgqZhVTd4mRFfSenvdrEHcsdElqjw4ZPkKuxdQSmiblMl+qjW8LqR7/4wXWXtCFa/NlQ7D8julzQH6mFm5RqVL/Hmyw==" Dec 22 13:33:15 volumio go-librespot[9315]: time="2025-12-22T13:33:15+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 22 13:33:15 volumio go-librespot[9315]: time="2025-12-22T13:33:15+01:00" level=debug msg="completed keyexchange" Dec 22 13:33:15 volumio go-librespot[9315]: time="2025-12-22T13:33:15+01:00" level=debug msg="completed challenge" Dec 22 13:33:15 volumio go-librespot[9315]: time="2025-12-22T13:33:15+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: TravelRestriction " Dec 22 13:33:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 22 13:33:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 22 13:33:15 volumio volumio[1311]: info: CoreCommandRouter::volumioGetState Dec 22 13:33:15 volumio volumio[1311]: info: CorePlayQueue::getTrack 0 Dec 22 13:33:17 volumio volumio[1311]: info: Initializing connection to go-librespot Websocket Dec 22 13:33:17 volumio volumio[1311]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 22 13:33:18 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 22 13:33:18 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 22 13:33:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 18. Dec 22 13:33:18 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 22 13:33:18 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 22 13:33:18 volumio go-librespot[9323]: go-librespot daemon starting... Dec 22 13:33:18 volumio go-librespot[9324]: time="2025-12-22T13:33:18+01:00" level=info msg="running go-librespot 0.4.0" Dec 22 13:33:18 volumio go-librespot[9324]: time="2025-12-22T13:33:18+01:00" level=debug msg="app state loaded" Dec 22 13:33:18 volumio go-librespot[9324]: time="2025-12-22T13:33:18+01:00" level=debug msg="stored credentials not found" Dec 22 13:33:18 volumio go-librespot[9324]: time="2025-12-22T13:33:18+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 22 13:33:18 volumio go-librespot[9324]: time="2025-12-22T13:33:18+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 22 13:33:18 volumio go-librespot[9324]: time="2025-12-22T13:33:18+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 22 13:33:18 volumio go-librespot[9324]: time="2025-12-22T13:33:18+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 22 13:33:18 volumio go-librespot[9324]: time="2025-12-22T13:33:18+01:00" level=info msg="zeroconf server listening on port 41983" Dec 22 13:33:18 volumio go-librespot[9324]: time="2025-12-22T13:33:18+01:00" level=debug msg="obtained new client token: AACeMHi/AYXgnDRspnLox55CYnOpMYl1pzAdCJ9k+owXhV8d2GGTS5H86WulSfsf/ZU6jhmu/irH6oNgzFRj3A1Uaj2zpP0VYfxeBIyg4fSe/bilAykFRlGJ0yysigd0fgvYI8NPeSXkaBsgnSWmdaNApYTBaNe9HhjvTHk+3M5DDCsRJXUqu/8Fpu/adg5aeSEjst5Q21pUlbOq1lYNtloxbPb6/aNL49y1lhsmdRUEWxhdUU69zKbDsw==" Dec 22 13:33:18 volumio go-librespot[9324]: time="2025-12-22T13:33:18+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 22 13:33:19 volumio go-librespot[9324]: time="2025-12-22T13:33:19+01:00" level=debug msg="completed keyexchange" Dec 22 13:33:19 volumio go-librespot[9324]: time="2025-12-22T13:33:19+01:00" level=debug msg="completed challenge" Dec 22 13:33:19 volumio go-librespot[9324]: time="2025-12-22T13:33:19+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: TravelRestriction " Dec 22 13:33:19 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 22 13:33:19 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 22 13:33:19 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 22 13:33:20 volumio volumio[1311]: info: Initializing connection to go-librespot Websocket Dec 22 13:33:20 volumio volumio[1311]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 22 13:33:22 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 19. Dec 22 13:33:22 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 22 13:33:22 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 22 13:33:22 volumio go-librespot[9332]: go-librespot daemon starting... Dec 22 13:33:22 volumio go-librespot[9333]: time="2025-12-22T13:33:22+01:00" level=info msg="running go-librespot 0.4.0" Dec 22 13:33:22 volumio go-librespot[9333]: time="2025-12-22T13:33:22+01:00" level=debug msg="app state loaded" Dec 22 13:33:22 volumio go-librespot[9333]: time="2025-12-22T13:33:22+01:00" level=debug msg="stored credentials not found" Dec 22 13:33:22 volumio go-librespot[9333]: time="2025-12-22T13:33:22+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 22 13:33:22 volumio go-librespot[9333]: time="2025-12-22T13:33:22+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 22 13:33:22 volumio go-librespot[9333]: time="2025-12-22T13:33:22+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 22 13:33:22 volumio go-librespot[9333]: time="2025-12-22T13:33:22+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 22 13:33:22 volumio go-librespot[9333]: time="2025-12-22T13:33:22+01:00" level=info msg="zeroconf server listening on port 46157" Dec 22 13:33:22 volumio go-librespot[9333]: time="2025-12-22T13:33:22+01:00" level=debug msg="obtained new client token: AABXPwK5Bqj/uY9jaDQD8qR7PTfkNWMGWAnM3JzNcuoYMdW62Bebmhf7lmqM335BmlGs5yIWiGSwpWbn10FGMdk2nN4rSWPXtBJx4zJCY+jKjqS7kp9wQLxKVEc7Ep7YgcC1wAp+cbvn8/1npVk49rBDwFMLiaBZ8bmupPm3K9liK23ecMqDoSuhUBLjwL3BPAvXe3JzGfNWNmlEsZKsDVW74bKuoB8ccEG0JTfcGDbHHha4OieSYn0=" Dec 22 13:33:22 volumio go-librespot[9333]: time="2025-12-22T13:33:22+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 22 13:33:22 volumio go-librespot[9333]: time="2025-12-22T13:33:22+01:00" level=debug msg="completed keyexchange" Dec 22 13:33:22 volumio go-librespot[9333]: time="2025-12-22T13:33:22+01:00" level=debug msg="completed challenge" Dec 22 13:33:22 volumio go-librespot[9333]: time="2025-12-22T13:33:22+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: TravelRestriction " Dec 22 13:33:22 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 22 13:33:22 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 22 13:33:23 volumio volumio[1311]: info: Initializing connection to go-librespot Websocket Dec 22 13:33:23 volumio volumio[1311]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 22 13:33:25 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 20. Dec 22 13:33:25 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 22 13:33:25 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 22 13:33:25 volumio go-librespot[9356]: go-librespot daemon starting... Dec 22 13:33:25 volumio go-librespot[9357]: time="2025-12-22T13:33:25+01:00" level=info msg="running go-librespot 0.4.0" Dec 22 13:33:25 volumio go-librespot[9357]: time="2025-12-22T13:33:25+01:00" level=debug msg="app state loaded" Dec 22 13:33:25 volumio go-librespot[9357]: time="2025-12-22T13:33:25+01:00" level=debug msg="stored credentials not found" Dec 22 13:33:25 volumio go-librespot[9357]: time="2025-12-22T13:33:25+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 22 13:33:25 volumio go-librespot[9357]: time="2025-12-22T13:33:25+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 22 13:33:25 volumio go-librespot[9357]: time="2025-12-22T13:33:25+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 22 13:33:25 volumio go-librespot[9357]: time="2025-12-22T13:33:25+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 22 13:33:25 volumio go-librespot[9357]: time="2025-12-22T13:33:25+01:00" level=info msg="zeroconf server listening on port 43035" Dec 22 13:33:25 volumio go-librespot[9357]: time="2025-12-22T13:33:25+01:00" level=debug msg="obtained new client token: AAA6DTPkbXZ5lxr1Rbnci6+eaXe2IqxZngf9CIkQtj0kJ6BD22bNo6YwMO1KDjvTDPGS80gC+RA3E5mubMBf29ZljDR6tLv40Exo4Hz45rEId56MjzX8j5gU/omUXFQRBHhGn0XWsz1+7YCBB5u0u8xflSrLSfV/KRK4lwt1dI5dk9HiRjF2Brg02YMcgN/vsS5PMtmbhswNJQ9fa23l8qi+sMKB/J83w17kaQEd+VxD7sfrbeaDg7Yjow==" Dec 22 13:33:25 volumio go-librespot[9357]: time="2025-12-22T13:33:25+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 22 13:33:25 volumio go-librespot[9357]: time="2025-12-22T13:33:25+01:00" level=debug msg="completed keyexchange" Dec 22 13:33:25 volumio go-librespot[9357]: time="2025-12-22T13:33:25+01:00" level=debug msg="completed challenge" Dec 22 13:33:25 volumio go-librespot[9357]: time="2025-12-22T13:33:25+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: TravelRestriction " Dec 22 13:33:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 22 13:33:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 22 13:33:26 volumio volumio[1311]: info: Initializing connection to go-librespot Websocket Dec 22 13:33:26 volumio volumio[1311]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 22 13:33:27 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 22 13:33:27 volumio volumio[1311]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 22 13:33:27 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 22 13:33:27 volumio volumio[1311]: info: Received Get System Version Dec 22 13:33:27 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 22 13:33:27 volumio volumio[1311]: info: Received Get System Info Dec 22 13:33:27 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 22 13:33:27 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 22 13:33:27 volumio volumio[1311]: info: Discovery: Getting this device information Dec 22 13:33:27 volumio volumio[1311]: info: CoreCommandRouter::volumioGetState Dec 22 13:33:27 volumio volumio[1311]: info: CorePlayQueue::getTrack 0 Dec 22 13:33:27 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 22 13:33:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 21. Dec 22 13:33:28 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 22 13:33:28 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 22 13:33:28 volumio go-librespot[9364]: go-librespot daemon starting... Dec 22 13:33:28 volumio go-librespot[9365]: time="2025-12-22T13:33:28+01:00" level=info msg="running go-librespot 0.4.0" Dec 22 13:33:28 volumio go-librespot[9365]: time="2025-12-22T13:33:28+01:00" level=debug msg="app state loaded" Dec 22 13:33:28 volumio go-librespot[9365]: time="2025-12-22T13:33:28+01:00" level=debug msg="stored credentials not found" Dec 22 13:33:28 volumio go-librespot[9365]: time="2025-12-22T13:33:28+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 22 13:33:28 volumio go-librespot[9365]: time="2025-12-22T13:33: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 22 13:33:28 volumio go-librespot[9365]: time="2025-12-22T13:33:28+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 22 13:33:28 volumio go-librespot[9365]: time="2025-12-22T13:33:28+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 22 13:33:28 volumio go-librespot[9365]: time="2025-12-22T13:33:28+01:00" level=info msg="zeroconf server listening on port 33409" Dec 22 13:33:28 volumio go-librespot[9365]: time="2025-12-22T13:33:28+01:00" level=debug msg="obtained new client token: AAC/TF5s7zg0F+E8b/ZLGAyCYMir+zjQld6eyMcDS6wv7bLUw/tckD6rLu1mosRWhz5nCcFRBekgKCwQKhc0A8iyc/bRzCsYaUQFTnewIoaibTcgu8/ccLROQ4tcQc/D2Rou/VEq9MQZOBOv57bH4nzNV5euRX83kMzWoBFQZBSM9s+Qtj+TJ4hYB1Z7O4zBb29LroklSOxO8FEO7fYfHqFRUq+8aJZJ1DwpucuXchoX6ZyZ9zpa26w0Lg==" Dec 22 13:33:28 volumio go-librespot[9365]: time="2025-12-22T13:33:28+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 22 13:33:29 volumio go-librespot[9365]: time="2025-12-22T13:33:29+01:00" level=debug msg="completed keyexchange" Dec 22 13:33:29 volumio go-librespot[9365]: time="2025-12-22T13:33:29+01:00" level=debug msg="completed challenge" Dec 22 13:33:29 volumio go-librespot[9365]: time="2025-12-22T13:33:29+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: TravelRestriction " Dec 22 13:33:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 22 13:33:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 22 13:33:29 volumio volumio[1311]: info: Initializing connection to go-librespot Websocket Dec 22 13:33:29 volumio volumio[1311]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 22 13:33:31 volumio volumio[1311]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/ytmusic/volumio/bookworm/armhf Dec 22 13:33:32 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22. Dec 22 13:33:32 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 22 13:33:32 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 22 13:33:32 volumio go-librespot[9375]: go-librespot daemon starting... Dec 22 13:33:32 volumio go-librespot[9376]: time="2025-12-22T13:33:32+01:00" level=info msg="running go-librespot 0.4.0" Dec 22 13:33:32 volumio go-librespot[9376]: time="2025-12-22T13:33:32+01:00" level=debug msg="app state loaded" Dec 22 13:33:32 volumio go-librespot[9376]: time="2025-12-22T13:33:32+01:00" level=debug msg="stored credentials not found" Dec 22 13:33:32 volumio go-librespot[9376]: time="2025-12-22T13:33:32+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 22 13:33:32 volumio volumio[1311]: info: Initializing connection to go-librespot Websocket Dec 22 13:33:32 volumio go-librespot[9376]: time="2025-12-22T13:33:32+01:00" level=debug msg="new websocket client" Dec 22 13:33:32 volumio volumio[1311]: info: Connection to go-librespot Websocket established Dec 22 13:33:32 volumio go-librespot[9376]: time="2025-12-22T13:33: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 22 13:33:32 volumio go-librespot[9376]: time="2025-12-22T13:33:32+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 22 13:33:32 volumio go-librespot[9376]: time="2025-12-22T13:33:32+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 22 13:33:32 volumio go-librespot[9376]: time="2025-12-22T13:33:32+01:00" level=info msg="zeroconf server listening on port 44247" Dec 22 13:33:32 volumio go-librespot[9376]: time="2025-12-22T13:33:32+01:00" level=debug msg="obtained new client token: AAAospn0Bs7WuSa4jfQuLwRtfcv7XKe06HfnSAYWyqYUcAD5clFI/8rsd17us9RAMaYRvV4+bqcLhE4pHoQj++o0/sTkqp38h58qv6cBjCakfgBY49qevx+3yM/r7OKLSgExscvji5GYAyL6wf4nY3lZpLLSBQtyohAt3ztfs9ivOQbn4pNis1im0mB/rMk2VXeVJXlLgpvSyP5AstUeLA6SW1/lGiGlBU0B3KYDpZpyiFc6wAj7n+8=" Dec 22 13:33:32 volumio go-librespot[9376]: time="2025-12-22T13:33:32+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 22 13:33:32 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 22 13:33:32 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 22 13:33:32 volumio volumio[1311]: info: Discovery: Getting this device information Dec 22 13:33:32 volumio volumio[1311]: info: CoreCommandRouter::volumioGetState Dec 22 13:33:32 volumio volumio[1311]: info: CorePlayQueue::getTrack 0 Dec 22 13:33:32 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 22 13:33:32 volumio volumio[1311]: verbose: New Socket.io Connection to 10.3.0.14:3000 from 10.3.0.11 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Dec 22 13:33:32 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 22 13:33:32 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 22 13:33:33 volumio volumio[1311]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/ytmusic/volumio/bookworm/armhf Dec 22 13:33:33 volumio volumio[1311]: info: Folder /tmp/plugins removed Dec 22 13:33:33 volumio go-librespot[9376]: time="2025-12-22T13:33:33+01:00" level=debug msg="completed keyexchange" Dec 22 13:33:33 volumio go-librespot[9376]: time="2025-12-22T13:33:33+01:00" level=debug msg="completed challenge" Dec 22 13:33:33 volumio go-librespot[9376]: time="2025-12-22T13:33:33+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: TravelRestriction " Dec 22 13:33:33 volumio volumio[1311]: info: Connection to go-librespot Websocket closed Dec 22 13:33:33 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 22 13:33:33 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 22 13:33:33 volumio volumio[1311]: info: Check plugin dependencies Dec 22 13:33:33 volumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 22 13:33:33 volumio volumio[1311]: info: Checking if plugin already exists Dec 22 13:33:33 volumio volumio[1311]: info: Rename folder Dec 22 13:33:33 volumio volumio[1311]: info: Move to category Dec 22 13:33:33 volumio volumio[1311]: info: Folder /tmp/downloaded_plugin.zip removed Dec 22 13:33:35 volumio volumio[1311]: info: Checking if install.sh is present Dec 22 13:33:35 volumio volumio[1311]: info: Getting Spotify volume Dec 22 13:33:35 volumio volumio[1311]: info: Executing install.sh Dec 22 13:33:35 volumio volumio[1311]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 22 13:33:35 volumio sudo[9416]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/music_service/ytmusic/install.sh Dec 22 13:33:35 volumio sudo[9416]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 13:33:35 volumio sudo[9416]: pam_unix(sudo:session): session closed for user root Dec 22 13:33:35 volumio volumio[1311]: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 22 13:33:35 volumio volumio[1311]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Dec 22 13:33:35 volumio volumio[1311]: errno: -111, Dec 22 13:33:35 volumio volumio[1311]: code: 'ECONNREFUSED', Dec 22 13:33:35 volumio volumio[1311]: syscall: 'connect', Dec 22 13:33:35 volumio volumio[1311]: address: '127.0.0.1', Dec 22 13:33:35 volumio volumio[1311]: port: 9879, Dec 22 13:33:35 volumio volumio[1311]: response: undefined Dec 22 13:33:35 volumio volumio[1311]: } Dec 22 13:33:35 volumio volumio[1311]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 22 13:33:35 volumio sudo[9433]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-22 13:32' Dec 22 13:33:35 volumio sudo[9433]: 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"