Feb 26 02:11:00 volumio go-librespot[1645845]: time="2026-02-26T02:11:00+07: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]" Feb 26 02:11:00 volumio go-librespot[1645845]: time="2026-02-26T02:11:00+07: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]" Feb 26 02:11:00 volumio go-librespot[1645845]: time="2026-02-26T02:11:00+07: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]" Feb 26 02:11:00 volumio go-librespot[1645845]: time="2026-02-26T02:11:00+07:00" level=info msg="zeroconf server listening on port 42507" Feb 26 02:11:00 volumio go-librespot[1645845]: time="2026-02-26T02:11:00+07:00" level=debug msg="obtained new client token: AABJqVv959iylzJ1M3N2sAL9dlKCnR1gt7umPS8n3xQkWuwsa4IcXxwIYfBqAgscDTR/4qd10xfWJQjbaWrIuIiziiDgNLsx4/RRZLMxtg872yjwhikC3/4gURUJZy+wRL48VhIG5Zi5ZChlmtDJITFu1+xj2squj9lQtnuCLg3Tk+ej+SB6El92wIGN7cLzJJDQsM+XPj1ZbgNp5AeJyl+09JI6kNlBpB9Fa3xDtK/qDHP00iE3BvTvg58=" Feb 26 02:11:00 volumio go-librespot[1645845]: time="2026-02-26T02:11:00+07:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 26 02:11:01 volumio go-librespot[1645845]: time="2026-02-26T02:11:01+07:00" level=debug msg="completed keyexchange" Feb 26 02:11:01 volumio go-librespot[1645845]: time="2026-02-26T02:11:01+07:00" level=debug msg="completed challenge" Feb 26 02:11:01 volumio go-librespot[1645845]: time="2026-02-26T02:11:01+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Feb 26 02:11:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 02:11:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 02:11:02 volumio volumio[1645201]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 26 02:11:03 volumio volumio[1645201]: error: Cannot start Volumio Streaming Daemon Feb 26 02:11:03 volumio volumio[1645201]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 26 02:11:03 volumio volumio[1645201]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 26 02:11:03 volumio volumio[1645201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 02:11:03 volumio volumio[1645201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 02:11:03 volumio volumio[1645201]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Feb 26 02:11:03 volumio volumio[1645201]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 26 02:11:03 volumio volumio[1645201]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 26 02:11:03 volumio volumio[1645201]: info: CoreCommandRouter::volumioGetBrowseSources Feb 26 02:11:03 volumio volumio[1645201]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 26 02:11:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13. Feb 26 02:11:05 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:11:05 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:11:05 volumio go-librespot[1645872]: go-librespot daemon starting... Feb 26 02:11:05 volumio go-librespot[1645873]: time="2026-02-26T02:11:05+07:00" level=info msg="running go-librespot 0.4.0" Feb 26 02:11:05 volumio go-librespot[1645873]: time="2026-02-26T02:11:05+07:00" level=debug msg="app state loaded" Feb 26 02:11:05 volumio go-librespot[1645873]: time="2026-02-26T02:11:05+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 26 02:11:06 volumio go-librespot[1645873]: time="2026-02-26T02:11:06+07: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]" Feb 26 02:11:06 volumio go-librespot[1645873]: time="2026-02-26T02:11:06+07: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]" Feb 26 02:11:06 volumio go-librespot[1645873]: time="2026-02-26T02:11:06+07: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]" Feb 26 02:11:06 volumio go-librespot[1645873]: time="2026-02-26T02:11:06+07:00" level=info msg="zeroconf server listening on port 37191" Feb 26 02:11:06 volumio go-librespot[1645873]: time="2026-02-26T02:11:06+07:00" level=debug msg="obtained new client token: AAB6nr8E0lq2Qb5yEP3SkvQpTkjWsZmwlbCg0EH0zfDqAq2xsaDGOOVi3aChwsxQmDVLg4hY2tR6jMfofc9m0+MWWRZ4nIUhHCTqMGEgpVAqkx67GXPCi4JxwXTjqBw6tSr6oAmfwOp2ZnnYWGJklvNLakfQWHIuSICKryCqcOdVXIaCd3wmq5FczPg8w7m8txogcG3mW8QHuQIb4fOQoH9vv8T6fqO434XXGxus6OU9bdR3lDMCTFQUV1E=" Feb 26 02:11:07 volumio go-librespot[1645873]: time="2026-02-26T02:11:07+07:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 26 02:11:07 volumio go-librespot[1645873]: time="2026-02-26T02:11:07+07:00" level=debug msg="completed keyexchange" Feb 26 02:11:07 volumio go-librespot[1645873]: time="2026-02-26T02:11:07+07:00" level=debug msg="completed challenge" Feb 26 02:11:07 volumio go-librespot[1645873]: time="2026-02-26T02:11:07+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Feb 26 02:11:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 02:11:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 02:11:09 volumio volumio[1645201]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 26 02:11:10 volumio volumio[1645201]: info: CoreCommandRouter::volumioGetState Feb 26 02:11:10 volumio volumio[1645201]: info: CorePlayQueue::getTrack 0 Feb 26 02:11:10 volumio volumio[1645201]: info: CoreCommandRouter::volumioGetState Feb 26 02:11:10 volumio volumio[1645201]: info: CorePlayQueue::getTrack 0 Feb 26 02:11:10 volumio volumio[1645201]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Feb 26 02:11:10 volumio volumio[1645201]: error: MyVolumio Plugin failed to authenticate in a timely fashion Feb 26 02:11:10 volumio volumio[1645201]: info: Completed starting MyVolumio Plugin Feb 26 02:11:10 volumio volumio[1645201]: [Metrics] CommandRouter: 125s 577.35ms Feb 26 02:11:10 volumio volumio[1645201]: info: CoreCommandRouter::volumiosetStartupVolume Feb 26 02:11:10 volumio volumio[1645201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 02:11:10 volumio volumio[1645201]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 02:11:10 volumio volumio[1645201]: info: CoreCommandRouter::Close All Modals sent Feb 26 02:11:10 volumio volumio[1645201]: info: CoreCommandRouter::Close All Modals sent Feb 26 02:11:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14. Feb 26 02:11:11 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:11:11 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:11:11 volumio go-librespot[1645889]: go-librespot daemon starting... Feb 26 02:11:12 volumio go-librespot[1645890]: time="2026-02-26T02:11:12+07:00" level=info msg="running go-librespot 0.4.0" Feb 26 02:11:12 volumio go-librespot[1645890]: time="2026-02-26T02:11:12+07:00" level=debug msg="app state loaded" Feb 26 02:11:12 volumio go-librespot[1645890]: time="2026-02-26T02:11:12+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 26 02:11:12 volumio go-librespot[1645890]: time="2026-02-26T02:11:12+07: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]" Feb 26 02:11:12 volumio go-librespot[1645890]: time="2026-02-26T02:11:12+07: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]" Feb 26 02:11:12 volumio go-librespot[1645890]: time="2026-02-26T02:11:12+07: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]" Feb 26 02:11:12 volumio go-librespot[1645890]: time="2026-02-26T02:11:12+07:00" level=info msg="zeroconf server listening on port 37697" Feb 26 02:11:12 volumio go-librespot[1645890]: time="2026-02-26T02:11:12+07:00" level=debug msg="obtained new client token: AABndEKqaxvvqoE9xpMbM7b1JLbML7NXilAU35ekznvuVth1rzOSeU6vsOJngytlKE5t/Y7C+ncQU+Yzs1+Vh3IZwGQi+ah8WB3/XjqEfVCf36cVkgWEGPJxskuXhZkDYkAyrah7ZNca46FAzgDfKcdNPiWKISnZKiRTgtJzqh+6YC8JyMO0LkDr63ot5/pafHfiK8af1zLuUbM+OmtrvhRpBsT9w1A1DcSnlVebo2plaZoN91/xQtDU6vA=" Feb 26 02:11:13 volumio go-librespot[1645890]: time="2026-02-26T02:11:13+07:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 26 02:11:13 volumio go-librespot[1645890]: time="2026-02-26T02:11:13+07:00" level=debug msg="completed keyexchange" Feb 26 02:11:13 volumio go-librespot[1645890]: time="2026-02-26T02:11:13+07:00" level=debug msg="completed challenge" Feb 26 02:11:13 volumio go-librespot[1645890]: time="2026-02-26T02:11:13+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Feb 26 02:11:13 volumio volumio[1645201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Feb 26 02:11:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 02:11:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 02:11:13 volumio volumio[1645201]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 26 02:11:14 volumio volumio[1645201]: aplay: device_list:274: no soundcards found... Feb 26 02:11:14 volumio volumio[1645201]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Feb 26 02:11:14 volumio volumio[1645201]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Feb 26 02:11:14 volumio volumio[1645201]: info: Initializing connection to go-librespot Websocket Feb 26 02:11:14 volumio volumio[1645201]: info: Listing playlists Feb 26 02:11:14 volumio volumio[1645201]: info: Listing playlists Feb 26 02:11:16 volumio volumio[1645201]: info: camilladsp respawn in 100 ms (attempt 1/10) Feb 26 02:11:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15. Feb 26 02:11:17 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:11:17 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:11:17 volumio go-librespot[1645923]: go-librespot daemon starting... Feb 26 02:11:17 volumio go-librespot[1645925]: time="2026-02-26T02:11:17+07:00" level=info msg="running go-librespot 0.4.0" Feb 26 02:11:17 volumio go-librespot[1645925]: time="2026-02-26T02:11:17+07:00" level=debug msg="app state loaded" Feb 26 02:11:17 volumio go-librespot[1645925]: time="2026-02-26T02:11:17+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 26 02:11:18 volumio volumio[1645201]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Feb 26 02:11:18 volumio volumio[1645201]: info: FusionDsp - ---- read samplerate from file: 44100 Feb 26 02:11:18 volumio volumio[1645201]: info: camilladsp service terminated, instance 1 Feb 26 02:11:18 volumio go-librespot[1645925]: time="2026-02-26T02:11:18+07: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]" Feb 26 02:11:18 volumio go-librespot[1645925]: time="2026-02-26T02:11:18+07: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]" Feb 26 02:11:18 volumio go-librespot[1645925]: time="2026-02-26T02:11:18+07: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]" Feb 26 02:11:18 volumio go-librespot[1645925]: time="2026-02-26T02:11:18+07:00" level=info msg="zeroconf server listening on port 44017" Feb 26 02:11:18 volumio volumio[1645201]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 26 02:11:18 volumio go-librespot[1645925]: time="2026-02-26T02:11:18+07:00" level=debug msg="obtained new client token: AAB9BV1asl3W1f59AbtEbDJcz3tvXx04Dn2nHQW8O++CB6GaTH0jtaLtQvybYEha2FXOHZREwBo57QGdAQNmY+cGRQgay3Qw1tuWkwdidpER+EZTmBKZMKdviE3pVDucOZhQLFZajwRtFIp3/rN2o0mQIiuKicsegx88mhmNVFbjzk8rytNsOXU9JnGAH7q4lPEKsinA+zKgSivHJacDhBMFTc6X+m39CIYQqeZYN5gNKyN4M0XAXQuF240=" Feb 26 02:11:18 volumio volumio[1645201]: info: camilladsp service started and running in background, instance 1 Feb 26 02:11:18 volumio go-librespot[1645925]: time="2026-02-26T02:11:18+07:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 26 02:11:18 volumio go-librespot[1645925]: time="2026-02-26T02:11:18+07:00" level=debug msg="completed keyexchange" Feb 26 02:11:18 volumio go-librespot[1645925]: time="2026-02-26T02:11:18+07:00" level=debug msg="completed challenge" Feb 26 02:11:19 volumio go-librespot[1645925]: time="2026-02-26T02:11:19+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Feb 26 02:11:19 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 02:11:19 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 02:11:22 volumio volumio[1645201]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 26 02:11:22 volumio volumio[1645201]: info: BOOT COMPLETED Feb 26 02:11:22 volumio volumio[1645201]: info: CoreCommandRouter::volumioGetState Feb 26 02:11:22 volumio volumio[1645201]: info: CorePlayQueue::getTrack 0 Feb 26 02:11:22 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16. Feb 26 02:11:23 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:11:23 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:11:23 volumio go-librespot[1645948]: go-librespot daemon starting... Feb 26 02:11:23 volumio go-librespot[1645959]: time="2026-02-26T02:11:23+07:00" level=info msg="running go-librespot 0.4.0" Feb 26 02:11:23 volumio go-librespot[1645959]: time="2026-02-26T02:11:23+07:00" level=debug msg="app state loaded" Feb 26 02:11:23 volumio go-librespot[1645959]: time="2026-02-26T02:11:23+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 26 02:11:24 volumio go-librespot[1645959]: time="2026-02-26T02:11:24+07: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]" Feb 26 02:11:24 volumio go-librespot[1645959]: time="2026-02-26T02:11:24+07: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]" Feb 26 02:11:24 volumio go-librespot[1645959]: time="2026-02-26T02:11:24+07: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]" Feb 26 02:11:24 volumio go-librespot[1645959]: time="2026-02-26T02:11:24+07:00" level=info msg="zeroconf server listening on port 39457" Feb 26 02:11:24 volumio go-librespot[1645959]: time="2026-02-26T02:11:24+07:00" level=debug msg="obtained new client token: AAC3Aw7QYBUMVfHV6yW13ccBKNySDqy8xbGm9vv746viIBUtON6NQL0kWyWU+prZaowRsacXiXsC59RkyHxb4DWM1zh3vs65cQS8R+wGWQ2oFywoYObUhWM+JBtrkFEk6C2vr+9br1ZjtQEFIwASVccB0tpzS/zVC0FGmEU5OlECMkD5UBFRTmiDTA2UjDLmD4VrA+dVbNRF7TIQm7I+ukRe0/kidWCXK5MYYiXU6hPjYRm17prpHRqoM3I=" Feb 26 02:11:24 volumio go-librespot[1645959]: time="2026-02-26T02:11:24+07:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 26 02:11:25 volumio go-librespot[1645959]: time="2026-02-26T02:11:25+07:00" level=debug msg="completed keyexchange" Feb 26 02:11:25 volumio go-librespot[1645959]: time="2026-02-26T02:11:25+07:00" level=debug msg="completed challenge" Feb 26 02:11:25 volumio go-librespot[1645959]: time="2026-02-26T02:11:25+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Feb 26 02:11:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 02:11:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 02:11:26 volumio volumio[1645201]: info: camilladsp respawn in 100 ms (attempt 1/10) Feb 26 02:11:26 volumio volumio[1645201]: info: Initializing connection to go-librespot Websocket Feb 26 02:11:27 volumio volumio[1645201]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Feb 26 02:11:29 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17. Feb 26 02:11:29 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:11:29 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:11:29 volumio go-librespot[1645970]: go-librespot daemon starting... Feb 26 02:11:29 volumio go-librespot[1645971]: time="2026-02-26T02:11:29+07:00" level=info msg="running go-librespot 0.4.0" Feb 26 02:11:29 volumio go-librespot[1645971]: time="2026-02-26T02:11:29+07:00" level=debug msg="app state loaded" Feb 26 02:11:29 volumio go-librespot[1645971]: time="2026-02-26T02:11:29+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 26 02:11:30 volumio go-librespot[1645971]: time="2026-02-26T02:11:30+07: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]" Feb 26 02:11:30 volumio go-librespot[1645971]: time="2026-02-26T02:11:30+07: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]" Feb 26 02:11:30 volumio go-librespot[1645971]: time="2026-02-26T02:11:30+07: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]" Feb 26 02:11:30 volumio go-librespot[1645971]: time="2026-02-26T02:11:30+07:00" level=info msg="zeroconf server listening on port 37299" Feb 26 02:11:30 volumio go-librespot[1645971]: time="2026-02-26T02:11:30+07:00" level=debug msg="obtained new client token: AACNAM56MNv2Gj4g5RL/3ZrnXg8MERSoU7ldEopoOVCvb+Rc7cWyR+GMl+g4UsMWFr51g+dSWOLMRrwBZ/S+wkg1RS+1tWxbrPSJMkyLm0WAcnxZ3mot//n04oAVZPefEvhp9O4iv9B5eF4+CwZNOVxRxuofhSI4G0nnxCLwlXUjAfIFw1A86AAssYdCKD7i263r8O6x/qetmvc/jPI7qqFdPgUBE5nfBPjgN1yq/CmqcS1zKQ438MAF4G4=" Feb 26 02:11:31 volumio go-librespot[1645971]: time="2026-02-26T02:11:31+07:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 26 02:11:31 volumio systemd[1]: mpd.service: start operation timed out. Terminating. Feb 26 02:11:31 volumio systemd[1]: mpd.service: Failed with result 'timeout'. Feb 26 02:11:31 volumio systemd[1]: Failed to start mpd.service - Music Player Daemon. Feb 26 02:11:31 volumio go-librespot[1645971]: time="2026-02-26T02:11:31+07:00" level=debug msg="completed keyexchange" Feb 26 02:11:31 volumio go-librespot[1645971]: time="2026-02-26T02:11:31+07:00" level=debug msg="completed challenge" Feb 26 02:11:31 volumio systemd[1]: mpd.service: Consumed 15.110s CPU time. Feb 26 02:11:31 volumio go-librespot[1645971]: time="2026-02-26T02:11:31+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Feb 26 02:11:31 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 02:11:31 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 02:11:31 volumio sudo[1645593]: pam_unix(sudo:session): session closed for user root Feb 26 02:11:31 volumio sudo[1645389]: pam_unix(sudo:session): session closed for user root Feb 26 02:11:31 volumio sudo[1645374]: pam_unix(sudo:session): session closed for user root Feb 26 02:11:32 volumio volumio[1645201]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 26 02:11:32 volumio volumio[1645201]: info: camilladsp respawn in 200 ms (attempt 2/10) Feb 26 02:11:32 volumio volumio[1645201]: info: Cannot restart MPD: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Feb 26 02:11:32 volumio volumio[1645201]: Job for mpd.service failed because a timeout was exceeded. Feb 26 02:11:32 volumio volumio[1645201]: See "systemctl status mpd.service" and "journalctl -xeu mpd.service" for details. Feb 26 02:11:32 volumio volumio[1645201]: info: Cannot restart MPD: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Feb 26 02:11:32 volumio volumio[1645201]: Job for mpd.service failed because a timeout was exceeded. Feb 26 02:11:32 volumio volumio[1645201]: See "systemctl status mpd.service" and "journalctl -xeu mpd.service" for details. Feb 26 02:11:32 volumio volumio[1645201]: error: Cannot start MPD on system Start: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Feb 26 02:11:32 volumio volumio[1645201]: Job for mpd.service failed because a timeout was exceeded. Feb 26 02:11:32 volumio volumio[1645201]: See "systemctl status mpd.service" and "journalctl -xeu mpd.service" for details. Feb 26 02:11:32 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 26 02:11:33 volumio volumio[1645201]: info: CoreCommandRouter::volumioGetState Feb 26 02:11:33 volumio volumio[1645201]: info: CorePlayQueue::getTrack 0 Feb 26 02:11:33 volumio volumio[1645201]: info: CoreCommandRouter::volumioGetState Feb 26 02:11:33 volumio volumio[1645201]: info: CorePlayQueue::getTrack 0 Feb 26 02:11:33 volumio sudo[1645986]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 26 02:11:33 volumio sudo[1645986]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 26 02:11:33 volumio sudo[1645986]: pam_unix(sudo:session): session closed for user root Feb 26 02:11:33 volumio volumio[1645201]: info: Listing playlists Feb 26 02:11:33 volumio volumio[1645201]: info: Listing playlists Feb 26 02:11:33 volumio volumio[1645201]: info: CoreCommandRouter::volumioGetState Feb 26 02:11:33 volumio volumio[1645201]: info: CorePlayQueue::getTrack 0 Feb 26 02:11:35 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 18. Feb 26 02:11:35 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:11:35 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:11:35 volumio volumio[1645201]: info: camilladsp respawn in 400 ms (attempt 3/10) Feb 26 02:11:35 volumio go-librespot[1646013]: go-librespot daemon starting... Feb 26 02:11:35 volumio go-librespot[1646015]: time="2026-02-26T02:11:35+07:00" level=info msg="running go-librespot 0.4.0" Feb 26 02:11:35 volumio go-librespot[1646015]: time="2026-02-26T02:11:35+07:00" level=debug msg="app state loaded" Feb 26 02:11:35 volumio go-librespot[1646015]: time="2026-02-26T02:11:35+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 26 02:11:36 volumio go-librespot[1646015]: time="2026-02-26T02:11:36+07:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 26 02:11:36 volumio go-librespot[1646015]: time="2026-02-26T02:11:36+07:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 26 02:11:36 volumio go-librespot[1646015]: time="2026-02-26T02:11:36+07:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 26 02:11:36 volumio go-librespot[1646015]: time="2026-02-26T02:11:36+07:00" level=info msg="zeroconf server listening on port 34893" Feb 26 02:11:36 volumio go-librespot[1646015]: time="2026-02-26T02:11:36+07:00" level=debug msg="obtained new client token: AACNc+s07IkFJG5uCdv8BtiB1buH+3CUyf5s7LdgNQn3wuJOvVnVz5tnAgUP2jRyDyn1tZ3yRO8anXncdgNQU7CgPI8JK5LfksTBVvEmlF4KrT0mNnJa0dsDGVdD3p71w69nBJdklKr7cZjVwTbsr+nV7kQKj51Q/UGVZlQc2Hn4bNdYbtkzfVziIEyZaKygLGQ7jKGNT0+5abFmdmKzyJz3+JmLUSLS6WDxJZVWE+gf3AXt6eOEdP7C4/Y=" Feb 26 02:11:36 volumio go-librespot[1646015]: time="2026-02-26T02:11:36+07:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 26 02:11:36 volumio go-librespot[1646015]: time="2026-02-26T02:11:36+07:00" level=debug msg="completed keyexchange" Feb 26 02:11:36 volumio go-librespot[1646015]: time="2026-02-26T02:11:36+07:00" level=debug msg="completed challenge" Feb 26 02:11:37 volumio go-librespot[1646015]: time="2026-02-26T02:11:37+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Feb 26 02:11:37 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 02:11:37 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 02:11:38 volumio volumio[1645201]: info: Initializing connection to go-librespot Websocket Feb 26 02:11:40 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 19. Feb 26 02:11:40 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:11:40 volumio volumio[1645201]: info: MyVolumio token set successfully Feb 26 02:11:40 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:11:40 volumio volumio[1645201]: info: MYVOLUMIO: Adding device Feb 26 02:11:40 volumio volumio[1645201]: info: MYVOLUMIO: Evaluating Server Feb 26 02:11:41 volumio go-librespot[1646037]: go-librespot daemon starting... Feb 26 02:11:41 volumio go-librespot[1646039]: time="2026-02-26T02:11:41+07:00" level=info msg="running go-librespot 0.4.0" Feb 26 02:11:41 volumio go-librespot[1646039]: time="2026-02-26T02:11:41+07:00" level=debug msg="app state loaded" Feb 26 02:11:41 volumio go-librespot[1646039]: time="2026-02-26T02:11:41+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 26 02:11:41 volumio go-librespot[1646039]: time="2026-02-26T02:11:41+07: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]" Feb 26 02:11:41 volumio go-librespot[1646039]: time="2026-02-26T02:11:41+07: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]" Feb 26 02:11:41 volumio go-librespot[1646039]: time="2026-02-26T02:11:41+07: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]" Feb 26 02:11:41 volumio go-librespot[1646039]: time="2026-02-26T02:11:41+07:00" level=info msg="zeroconf server listening on port 39655" Feb 26 02:11:41 volumio go-librespot[1646039]: time="2026-02-26T02:11:41+07:00" level=debug msg="obtained new client token: AABv4J0QYK5g1q76U/Fc+DwOUabO8zMJnvKawSaTAw72VUwqa741jId6aqhIV+8xg8nHV+HJg8D9B6nGpoQYNTQ1xe99wsQoBHSV+Svy9elTVrWYjxkyC0dXASrZCgzJiXhgclss4AVbqVxRNo6pTup98KViTg2lz2t2gzlEvOdBSYmPUBMKdpOXJsKx+C4b3jGuyzxKaHrPbZTpGOwX5rpOW6onUO58U0e/e367eYGp+ILSpW9LVGLI0oo=" Feb 26 02:11:42 volumio go-librespot[1646039]: time="2026-02-26T02:11:42+07:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 26 02:11:42 volumio go-librespot[1646039]: time="2026-02-26T02:11:42+07:00" level=debug msg="completed keyexchange" Feb 26 02:11:42 volumio go-librespot[1646039]: time="2026-02-26T02:11:42+07:00" level=debug msg="completed challenge" Feb 26 02:11:42 volumio go-librespot[1646039]: time="2026-02-26T02:11:42+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Feb 26 02:11:42 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 02:11:42 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 02:11:43 volumio volumio[1645201]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 26 02:11:43 volumio volumio[1645201]: info: camilladsp respawn in 800 ms (attempt 4/10) Feb 26 02:11:43 volumio volumio[1645201]: info: CoreCommandRouter::volumioGetState Feb 26 02:11:43 volumio volumio[1645201]: info: CorePlayQueue::getTrack 0 Feb 26 02:11:43 volumio volumio[1645201]: info: Setting Geolocation for MyVolumio to as1 Feb 26 02:11:43 volumio volumio[1645201]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 02:11:43 volumio volumio[1645201]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 02:11:43 volumio volumio[1645201]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 02:11:46 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 20. Feb 26 02:11:46 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:11:46 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:11:46 volumio go-librespot[1646088]: go-librespot daemon starting... Feb 26 02:11:46 volumio volumio[1645201]: info: MyVolumio status changed Feb 26 02:11:46 volumio volumio[1645201]: info: Streaming services startup Feb 26 02:11:46 volumio go-librespot[1646090]: time="2026-02-26T02:11:46+07:00" level=info msg="running go-librespot 0.4.0" Feb 26 02:11:46 volumio go-librespot[1646090]: time="2026-02-26T02:11:46+07:00" level=debug msg="app state loaded" Feb 26 02:11:46 volumio volumio[1645201]: info: Starting Streaming Daemon Feb 26 02:11:46 volumio go-librespot[1646090]: time="2026-02-26T02:11:46+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 26 02:11:47 volumio volumio[1645201]: info: Removing browser output: myVolumio user plan is not superstar Feb 26 02:11:47 volumio volumio[1645201]: info: Removing audio output: Feb 26 02:11:47 volumio sudo[1646096]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 26 02:11:47 volumio sudo[1646096]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 02:11:48 volumio go-librespot[1646090]: time="2026-02-26T02:11:48+07: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]" Feb 26 02:11:48 volumio go-librespot[1646090]: time="2026-02-26T02:11:48+07: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]" Feb 26 02:11:48 volumio go-librespot[1646090]: time="2026-02-26T02:11:48+07: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]" Feb 26 02:11:48 volumio go-librespot[1646090]: time="2026-02-26T02:11:48+07:00" level=info msg="zeroconf server listening on port 35223" Feb 26 02:11:48 volumio go-librespot[1646090]: time="2026-02-26T02:11:48+07:00" level=debug msg="obtained new client token: AAAZluTglF0qhv2RaAmr5wX0amF+8sY18lSgah5pCPli/jc2IdoHd9a4VrBXz/fZvff0imH2pzvyUb89gr0gYMHfi0i+vpNleUABiQ3BKq4wmMExeKlZVgHBV31Wx0sMHfCVNoMVyEl+vpb7xxEuXU7emkvT2vm1/7HpMZ/o8N8oAZQHUUAUmI7Rf0LaNPK2wFyoEJyAtVt+QXtzvkLYpGd59qIioCRZ+PD3aBg3t+vqMnb9T7hk76Q8" Feb 26 02:11:48 volumio go-librespot[1646090]: time="2026-02-26T02:11:48+07:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 26 02:11:48 volumio volumio[1645201]: info: Stoppping Tunnel 1 Feb 26 02:11:48 volumio go-librespot[1646090]: time="2026-02-26T02:11:48+07:00" level=debug msg="completed keyexchange" Feb 26 02:11:48 volumio go-librespot[1646090]: time="2026-02-26T02:11:48+07:00" level=debug msg="completed challenge" Feb 26 02:11:48 volumio go-librespot[1646090]: time="2026-02-26T02:11:48+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Feb 26 02:11:48 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 02:11:48 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 02:11:49 volumio sudo[1646096]: pam_unix(sudo:session): session closed for user root Feb 26 02:11:50 volumio sudo[1646102]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Feb 26 02:11:50 volumio sudo[1646102]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 02:11:50 volumio volumio[1645201]: info: CoreCommandRouter::volumioGetState Feb 26 02:11:50 volumio volumio[1645201]: info: CorePlayQueue::getTrack 0 Feb 26 02:11:51 volumio volumio[1645201]: info: Updating MyVolumio device info Feb 26 02:11:51 volumio volumio[1645201]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 02:11:51 volumio volumio[1645201]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 02:11:51 volumio volumio[1645201]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 02:11:51 volumio volumio[1645201]: info: Initializing connection to go-librespot Websocket Feb 26 02:11:51 volumio volumio[1645201]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Feb 26 02:11:51 volumio volumio[1645201]: error: Cannot start Volumio Streaming Daemon Feb 26 02:11:51 volumio volumio[1645201]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 26 02:11:51 volumio volumio[1645201]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 26 02:11:51 volumio volumio[1645201]: info: camilladsp respawn in 1600 ms (attempt 5/10) Feb 26 02:11:51 volumio volumio[1645201]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 26 02:11:51 volumio volumio[1645201]: info: Listing playlists Feb 26 02:11:51 volumio volumio[1645201]: info: Listing playlists Feb 26 02:11:52 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 26 02:11:52 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 26 02:11:52 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 21. Feb 26 02:11:52 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:11:52 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:11:52 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 26 02:11:52 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 26 02:11:52 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 26 02:11:52 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 26 02:11:52 volumio go-librespot[1646107]: go-librespot daemon starting... Feb 26 02:11:52 volumio sudo[1646102]: pam_unix(sudo:session): session closed for user root Feb 26 02:11:52 volumio go-librespot[1646108]: time="2026-02-26T02:11:52+07:00" level=info msg="running go-librespot 0.4.0" Feb 26 02:11:52 volumio go-librespot[1646108]: time="2026-02-26T02:11:52+07:00" level=debug msg="app state loaded" Feb 26 02:11:52 volumio go-librespot[1646108]: time="2026-02-26T02:11:52+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 26 02:11:53 volumio go-librespot[1646108]: time="2026-02-26T02:11:53+07:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 26 02:11:53 volumio go-librespot[1646108]: time="2026-02-26T02:11:53+07:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 26 02:11:53 volumio go-librespot[1646108]: time="2026-02-26T02:11:53+07:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 26 02:11:53 volumio go-librespot[1646108]: time="2026-02-26T02:11:53+07:00" level=info msg="zeroconf server listening on port 33615" Feb 26 02:11:53 volumio go-librespot[1646108]: time="2026-02-26T02:11:53+07:00" level=debug msg="obtained new client token: AAC9spITPa72nYWwiHIEQJRPkh7uXla2n2x3yFx0tTvoz0COTfFVbI9alDrLUJaPdMgV9FkIrmpDd+ACVrLQlttWUEIjjZ/WN59jtudzv10QcXRPP17cQ5Zsv6HgTJbthBZ37HqZ1G+KEz/av1XatkTQeedABPHKQnWq8KjG+gTBZmBXBR/h7U59KZEim3oAZ0ZdVYsg0UySRTy7kUvexLnuykjZRm4avGnf/7O5QagbgIkdKCmmtdq405g=" Feb 26 02:11:53 volumio go-librespot[1646108]: time="2026-02-26T02:11:53+07: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" Feb 26 02:11:54 volumio go-librespot[1646108]: time="2026-02-26T02:11:54+07:00" level=debug msg="connected to ap-gew4.spotify.com:443" Feb 26 02:11:54 volumio go-librespot[1646108]: time="2026-02-26T02:11:54+07:00" level=debug msg="completed keyexchange" Feb 26 02:11:54 volumio go-librespot[1646108]: time="2026-02-26T02:11:54+07:00" level=debug msg="completed challenge" Feb 26 02:11:54 volumio go-librespot[1646108]: time="2026-02-26T02:11:54+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Feb 26 02:11:54 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 02:11:54 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 02:11:54 volumio volumio[1645201]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Feb 26 02:11:55 volumio volumio[1645201]: info: CoreCommandRouter::volumioGetState Feb 26 02:11:55 volumio volumio[1645201]: info: CorePlayQueue::getTrack 0 Feb 26 02:11:55 volumio volumio[1645201]: info: Initializing connection to go-librespot Websocket Feb 26 02:11:58 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22. Feb 26 02:11:58 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:11:58 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:11:58 volumio go-librespot[1646143]: go-librespot daemon starting... Feb 26 02:11:58 volumio volumio[1645201]: info: camilladsp respawn in 3200 ms (attempt 6/10) Feb 26 02:11:58 volumio go-librespot[1646144]: time="2026-02-26T02:11:58+07:00" level=info msg="running go-librespot 0.4.0" Feb 26 02:11:58 volumio go-librespot[1646144]: time="2026-02-26T02:11:58+07:00" level=debug msg="app state loaded" Feb 26 02:11:58 volumio go-librespot[1646144]: time="2026-02-26T02:11:58+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 26 02:11:58 volumio volumio[1645201]: info: Remote SSH Stopped Feb 26 02:11:59 volumio volumio[1645201]: info: MYVOLUMIO: Adding device Feb 26 02:11:59 volumio volumio[1645201]: info: MYVOLUMIO: Evaluating Server Feb 26 02:12:00 volumio go-librespot[1646144]: time="2026-02-26T02:12:00+07: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]" Feb 26 02:12:00 volumio go-librespot[1646144]: time="2026-02-26T02:12:00+07: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]" Feb 26 02:12:00 volumio go-librespot[1646144]: time="2026-02-26T02:12:00+07: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]" Feb 26 02:12:00 volumio go-librespot[1646144]: time="2026-02-26T02:12:00+07:00" level=info msg="zeroconf server listening on port 41905" Feb 26 02:12:00 volumio go-librespot[1646144]: time="2026-02-26T02:12:00+07:00" level=debug msg="obtained new client token: AADJJirZHCyCIrD+/O2U/RU31BjqEe6sSEHUJqx7eMB/QJTYzmYK8y43jmN1wR1/6kzmK8JjXMK9Pu529jqaCCRmwkYvc0fWi//1yV5DlTxFhQ74mWkj3DnsNC8wKTnExUlu4dJ9JATzGUqCJFK0P08+iK1RfoJEXVcYsvILWisZ3TzdhmpoFlbv8Ru7K8Z7Ci/K9AlIqQRs/hF3OLy/C4SI9l1NCF5prcqOX4Ut7cFSOTAhPxxVpUPi" Feb 26 02:12:00 volumio go-librespot[1646144]: time="2026-02-26T02:12:00+07:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 26 02:12:00 volumio go-librespot[1646144]: time="2026-02-26T02:12:00+07:00" level=debug msg="completed keyexchange" Feb 26 02:12:00 volumio go-librespot[1646144]: time="2026-02-26T02:12:00+07:00" level=debug msg="completed challenge" Feb 26 02:12:00 volumio go-librespot[1646144]: time="2026-02-26T02:12:00+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Feb 26 02:12:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 02:12:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 02:12:02 volumio volumio[1645201]: info: CoreCommandRouter::volumioGetState Feb 26 02:12:02 volumio volumio[1645201]: info: CorePlayQueue::getTrack 0 Feb 26 02:12:03 volumio volumio[1645201]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 26 02:12:03 volumio volumio[1645201]: info: Setting Geolocation for MyVolumio to as1 Feb 26 02:12:03 volumio volumio[1645201]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 02:12:03 volumio volumio[1645201]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 02:12:03 volumio volumio[1645201]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 02:12:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 23. Feb 26 02:12:04 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:12:04 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:12:04 volumio go-librespot[1646178]: go-librespot daemon starting... Feb 26 02:12:05 volumio go-librespot[1646186]: time="2026-02-26T02:12:05+07:00" level=info msg="running go-librespot 0.4.0" Feb 26 02:12:05 volumio go-librespot[1646186]: time="2026-02-26T02:12:05+07:00" level=debug msg="app state loaded" Feb 26 02:12:05 volumio go-librespot[1646186]: time="2026-02-26T02:12:05+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 26 02:12:05 volumio go-librespot[1646186]: time="2026-02-26T02:12:05+07: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]" Feb 26 02:12:05 volumio go-librespot[1646186]: time="2026-02-26T02:12:05+07: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]" Feb 26 02:12:05 volumio go-librespot[1646186]: time="2026-02-26T02:12:05+07: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]" Feb 26 02:12:05 volumio go-librespot[1646186]: time="2026-02-26T02:12:05+07:00" level=info msg="zeroconf server listening on port 45379" Feb 26 02:12:06 volumio go-librespot[1646186]: time="2026-02-26T02:12:06+07:00" level=debug msg="obtained new client token: AAAsfR/wiYqD9bINbHq4muj8rXPVKtaFqESkrPKawp0/8cvsXbSQhDxyLLGxoFbbvlHbSyo89uxOmSHX+ss41lLs9MSu+4EH6Lvj6/U5uZn3PN0EraWAfEY9FavMCabiL32aRNwYGeDR/3vQWxMgaB5XgyaA+VkZ0MREeZIu3GPNwIKAQOk3bH+qlwxt8u1L01H598eL1g1e5WuxWf3+OdnZLPerPw1rWswTW0VLkW0O9VlRD/gws7slkr8=" Feb 26 02:12:06 volumio go-librespot[1646186]: time="2026-02-26T02:12:06+07:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 26 02:12:06 volumio go-librespot[1646186]: time="2026-02-26T02:12:06+07:00" level=debug msg="completed keyexchange" Feb 26 02:12:06 volumio go-librespot[1646186]: time="2026-02-26T02:12:06+07:00" level=debug msg="completed challenge" Feb 26 02:12:06 volumio go-librespot[1646186]: time="2026-02-26T02:12:06+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Feb 26 02:12:06 volumio volumio[1645201]: info: Updating MyVolumio device info Feb 26 02:12:06 volumio volumio[1645201]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 02:12:06 volumio volumio[1645201]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 02:12:06 volumio volumio[1645201]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 02:12:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 02:12:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 02:12:06 volumio volumio[1645201]: info: CoreCommandRouter::volumioGetState Feb 26 02:12:06 volumio volumio[1645201]: info: CorePlayQueue::getTrack 0 Feb 26 02:12:07 volumio volumio[1645201]: info: Initializing connection to go-librespot Websocket Feb 26 02:12:07 volumio volumio[1645201]: info: CoreCommandRouter::volumioGetState Feb 26 02:12:07 volumio volumio[1645201]: info: CorePlayQueue::getTrack 0 Feb 26 02:12:07 volumio volumio[1645201]: info: camilladsp respawn in 6400 ms (attempt 7/10) Feb 26 02:12:07 volumio volumio[1645201]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 26 02:12:07 volumio volumio[1645201]: info: Listing playlists Feb 26 02:12:07 volumio volumio[1645201]: info: Listing playlists Feb 26 02:12:07 volumio volumio[1645201]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Feb 26 02:12:07 volumio volumio[1645201]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Feb 26 02:12:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 24. Feb 26 02:12:10 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:12:10 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:12:10 volumio go-librespot[1646205]: go-librespot daemon starting... Feb 26 02:12:10 volumio volumio[1645201]: info: Initializing connection to go-librespot Websocket Feb 26 02:12:10 volumio go-librespot[1646207]: time="2026-02-26T02:12:10+07:00" level=info msg="running go-librespot 0.4.0" Feb 26 02:12:10 volumio go-librespot[1646207]: time="2026-02-26T02:12:10+07:00" level=debug msg="app state loaded" Feb 26 02:12:10 volumio go-librespot[1646207]: time="2026-02-26T02:12:10+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 26 02:12:11 volumio go-librespot[1646207]: time="2026-02-26T02:12:11+07:00" level=debug msg="new websocket client" Feb 26 02:12:12 volumio go-librespot[1646207]: time="2026-02-26T02:12:12+07: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]" Feb 26 02:12:12 volumio go-librespot[1646207]: time="2026-02-26T02:12:12+07: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]" Feb 26 02:12:12 volumio go-librespot[1646207]: time="2026-02-26T02:12:12+07: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]" Feb 26 02:12:12 volumio go-librespot[1646207]: time="2026-02-26T02:12:12+07:00" level=info msg="zeroconf server listening on port 45131" Feb 26 02:12:12 volumio go-librespot[1646207]: time="2026-02-26T02:12:12+07:00" level=debug msg="obtained new client token: AAAusRiRg+poYvKI4cBtTFvKb1qHkrY/xGvTgGI3bDy0cw+gvxIL9Xz9h5wI2jfQxJ9nTT84Fq8NGDilpLd9Eu87TecaG4jCB6aPOwoqHyqzUz7IO+TjJ1q4rAQPbz0GlJ3wfrZqp1MUAhRXAIPl0XWklXn6qg5Pd8BIG+6c/Gr02KMB4w+CJiyOwzwuJ+k6lWX/LP27wEB3IcQ1H8N264+0TrMYbAue6zg7R4hdP8FLf58REBy2KYX5Svs=" Feb 26 02:12:12 volumio go-librespot[1646207]: time="2026-02-26T02:12:12+07:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 26 02:12:12 volumio go-librespot[1646207]: time="2026-02-26T02:12:12+07:00" level=debug msg="completed keyexchange" Feb 26 02:12:12 volumio go-librespot[1646207]: time="2026-02-26T02:12:12+07:00" level=debug msg="completed challenge" Feb 26 02:12:13 volumio volumio[1645201]: info: Connection to go-librespot Websocket established Feb 26 02:12:13 volumio go-librespot[1646207]: time="2026-02-26T02:12:13+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Feb 26 02:12:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 02:12:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 02:12:13 volumio volumio[1645201]: info: Connection to go-librespot Websocket closed Feb 26 02:12:14 volumio volumio[1645201]: info: CoreCommandRouter::volumioGetState Feb 26 02:12:14 volumio volumio[1645201]: info: CorePlayQueue::getTrack 0 Feb 26 02:12:15 volumio volumio[1645201]: info: camilladsp respawn in 10000 ms (attempt 8/10) Feb 26 02:12:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 25. Feb 26 02:12:16 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:12:16 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:12:17 volumio go-librespot[1646239]: go-librespot daemon starting... Feb 26 02:12:17 volumio go-librespot[1646240]: time="2026-02-26T02:12:17+07:00" level=info msg="running go-librespot 0.4.0" Feb 26 02:12:17 volumio go-librespot[1646240]: time="2026-02-26T02:12:17+07:00" level=debug msg="app state loaded" Feb 26 02:12:17 volumio go-librespot[1646240]: time="2026-02-26T02:12:17+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 26 02:12:17 volumio volumio[1645201]: info: Getting Spotify volume Feb 26 02:12:17 volumio go-librespot[1646240]: time="2026-02-26T02:12:17+07:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 26 02:12:17 volumio go-librespot[1646240]: time="2026-02-26T02:12:17+07:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 26 02:12:17 volumio go-librespot[1646240]: time="2026-02-26T02:12:17+07:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 26 02:12:17 volumio go-librespot[1646240]: time="2026-02-26T02:12:17+07:00" level=info msg="zeroconf server listening on port 44351" Feb 26 02:12:17 volumio go-librespot[1646240]: time="2026-02-26T02:12:17+07:00" level=debug msg="obtained new client token: AABgudLodXxtDaRoIV2Y1By3J887dCLA7cAAgoa7+77SZ5jm+ReVsjvmq37PO4w5rgwKD4i70Ckidho23gcW+1qbRL0lumO3klWLv5qgz5iZZNFRQI7tZAfn+36ZqvfFvdXIu9ep6XP3VfjAesTYPOQHO1mtYUmBuw9y5UV4EMXqrajIf7loVWi56dNR+cwmNGt3PcDYUNg3FmiJTbAkLy1Yt56IttRhCRHmPUCQPp2CgAlFHHP6Up9g8Y4=" Feb 26 02:12:18 volumio go-librespot[1646240]: time="2026-02-26T02:12:18+07:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 26 02:12:18 volumio go-librespot[1646240]: time="2026-02-26T02:12:18+07:00" level=debug msg="completed keyexchange" Feb 26 02:12:18 volumio go-librespot[1646240]: time="2026-02-26T02:12:18+07:00" level=debug msg="completed challenge" Feb 26 02:12:18 volumio volumio[1645201]: info: Initializing connection to go-librespot Websocket Feb 26 02:12:18 volumio go-librespot[1646240]: time="2026-02-26T02:12:18+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Feb 26 02:12:18 volumio volumio[1645201]: info: CoreCommandRouter::volumioGetState Feb 26 02:12:18 volumio volumio[1645201]: info: CorePlayQueue::getTrack 0 Feb 26 02:12:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 02:12:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 02:12:19 volumio volumio[1645201]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 26 02:12:19 volumio volumio[1645201]: Error: socket hang up Feb 26 02:12:19 volumio volumio[1645201]: at connResetException (node:internal/errors:720:14) Feb 26 02:12:19 volumio volumio[1645201]: at Socket.socketOnEnd (node:_http_client:519:23) Feb 26 02:12:19 volumio volumio[1645201]: at Socket.emit (node:events:526:35) Feb 26 02:12:19 volumio volumio[1645201]: at endReadableNT (node:internal/streams/readable:1376:12) Feb 26 02:12:19 volumio volumio[1645201]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Feb 26 02:12:19 volumio volumio[1645201]: code: 'ECONNRESET', Feb 26 02:12:19 volumio volumio[1645201]: response: undefined Feb 26 02:12:19 volumio volumio[1645201]: } Feb 26 02:12:19 volumio volumio[1645201]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 26 02:12:22 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 26. Feb 26 02:12:22 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:12:22 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:12:22 volumio go-librespot[1646263]: go-librespot daemon starting... Feb 26 02:12:22 volumio go-librespot[1646264]: time="2026-02-26T02:12:22+07:00" level=info msg="running go-librespot 0.4.0" Feb 26 02:12:22 volumio go-librespot[1646264]: time="2026-02-26T02:12:22+07:00" level=debug msg="app state loaded" Feb 26 02:12:22 volumio go-librespot[1646264]: time="2026-02-26T02:12:22+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 26 02:12:24 volumio go-librespot[1646264]: time="2026-02-26T02:12:24+07:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 26 02:12:24 volumio go-librespot[1646264]: time="2026-02-26T02:12:24+07:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 26 02:12:24 volumio go-librespot[1646264]: time="2026-02-26T02:12:24+07:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 26 02:12:24 volumio go-librespot[1646264]: time="2026-02-26T02:12:24+07:00" level=info msg="zeroconf server listening on port 35677" Feb 26 02:12:24 volumio go-librespot[1646264]: time="2026-02-26T02:12:24+07:00" level=debug msg="obtained new client token: AABQa4lxzBxfkxWjJxrjTW8znNaVPe0B6eu+j73J/ceSegmO38Vw/FswCMQhzPE+KOdji8sAGHFGYa8eEmdYeY6cOaRicZHGFpIiSVXJNZ4DSwthAPPT0XbMkGqVI6nw2HbctPX/TJzmM3pTlL37NDkA4rq/SoZEkI3UvR0PQEX9XxzrK0J/uM2ABj//yDLpG02I2KsPM7rzpQcYo3Fmaw8rJIZB4uB1OwiQpQJEoKAiK33NkbChe+ZoHuc=" Feb 26 02:12:24 volumio go-librespot[1646264]: time="2026-02-26T02:12:24+07:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 26 02:12:25 volumio go-librespot[1646264]: time="2026-02-26T02:12:25+07:00" level=debug msg="completed keyexchange" Feb 26 02:12:25 volumio go-librespot[1646264]: time="2026-02-26T02:12:25+07:00" level=debug msg="completed challenge" Feb 26 02:12:25 volumio go-librespot[1646264]: time="2026-02-26T02:12:25+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Feb 26 02:12:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 02:12:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 02:12:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 27. Feb 26 02:12:28 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:12:29 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:12:29 volumio sudo[1646292]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-26 02:11' Feb 26 02:12:29 volumio sudo[1646292]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 02:12:29 volumio go-librespot[1646293]: go-librespot daemon starting... PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:31:57 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6759e875c98b942866984e844891d6c1"