Feb 26 02:14:00 volumio volumio5-onboarding[2061]: time=2026-02-26T02:14:00.084+07:00 level=WARN msg="reconnection attempt failed" component=volumio/socket error="read tcp 127.0.0.1:44546->127.0.0.1:3000: i/o timeout" Feb 26 02:14:01 volumio volumio[1646344]: info: Starting Shairport Sync Feb 26 02:14:01 volumio volumio[1646344]: info: Listing playlists Feb 26 02:14:01 volumio volumio[1646344]: info: Listing playlists Feb 26 02:14:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Feb 26 02:14:01 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:14:01 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:14:02 volumio go-librespot[1646859]: go-librespot daemon starting... Feb 26 02:14:02 volumio sudo[1646857]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 26 02:14:02 volumio sudo[1646857]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 02:14:03 volumio go-librespot[1646860]: time="2026-02-26T02:14:03+07:00" level=info msg="running go-librespot 0.4.0" Feb 26 02:14:03 volumio go-librespot[1646860]: time="2026-02-26T02:14:03+07:00" level=debug msg="app state loaded" Feb 26 02:14:03 volumio go-librespot[1646860]: time="2026-02-26T02:14:03+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 26 02:14:04 volumio go-librespot[1646860]: time="2026-02-26T02:14:04+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:14:04 volumio go-librespot[1646860]: time="2026-02-26T02:14:04+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:14:04 volumio go-librespot[1646860]: time="2026-02-26T02:14:04+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:14:04 volumio go-librespot[1646860]: time="2026-02-26T02:14:04+07:00" level=info msg="zeroconf server listening on port 32819" Feb 26 02:14:05 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 26 02:14:05 volumio go-librespot[1646860]: time="2026-02-26T02:14:05+07:00" level=debug msg="obtained new client token: AAAQM94IIFeHnxvH8qNuSLo95nZ584QOBan7eh943m93s3lBy4EDSj87dhiSeg8jop2+8HE8r1SFIBqLEtsV3fZcmDF+X3kyulDwrSe1PL98WlIQNBidZLAbc3PAPLbxgpVgJ+cAfhp7NPblVUHmproLkkJM7qP9BNrETlleHVnLQJnVUqX1NfrY7NG4ht8ihMBLtWs6tAZiZ4xwfaEJepK+Se17r9gxR1a0I3LyIKqq7FvrGqPUOoPNFRI=" Feb 26 02:14:05 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 26 02:14:05 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 26 02:14:05 volumio systemd[1]: shairport-sync.service: Consumed 1.326s CPU time. Feb 26 02:14:05 volumio go-librespot[1646860]: time="2026-02-26T02:14:05+07:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 26 02:14:05 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 26 02:14:05 volumio go-librespot[1646860]: time="2026-02-26T02:14:05+07:00" level=debug msg="completed keyexchange" Feb 26 02:14:05 volumio go-librespot[1646860]: time="2026-02-26T02:14:05+07:00" level=debug msg="completed challenge" Feb 26 02:14:05 volumio go-librespot[1646860]: time="2026-02-26T02:14:05+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:14:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 02:14:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 02:14:06 volumio sudo[1646857]: pam_unix(sudo:session): session closed for user root Feb 26 02:14:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Feb 26 02:14:09 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:14:09 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:14:09 volumio go-librespot[1646888]: go-librespot daemon starting... Feb 26 02:14:10 volumio go-librespot[1646893]: time="2026-02-26T02:14:10+07:00" level=info msg="running go-librespot 0.4.0" Feb 26 02:14:10 volumio go-librespot[1646893]: time="2026-02-26T02:14:10+07:00" level=debug msg="app state loaded" Feb 26 02:14:10 volumio go-librespot[1646893]: time="2026-02-26T02:14:10+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 26 02:14:11 volumio go-librespot[1646893]: time="2026-02-26T02:14:11+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:14:11 volumio go-librespot[1646893]: time="2026-02-26T02:14:11+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:14:11 volumio go-librespot[1646893]: time="2026-02-26T02:14:11+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:14:11 volumio go-librespot[1646893]: time="2026-02-26T02:14:11+07:00" level=info msg="zeroconf server listening on port 33985" Feb 26 02:14:11 volumio go-librespot[1646893]: time="2026-02-26T02:14:11+07:00" level=debug msg="obtained new client token: AACQc8WyaSvMvNrpD3i2mGMP3jfwzM7CO9u/10raMn+bvGZlDQ2jhvU8Q8+8yzab24lKyBCO2OO16MpZQ/V2snLuVjcP86bypEZ8HLrU5ZkQvNIb5tWZP7RAtknC7dlYuXcFF8KOqwLI6WBuU5Gks6veGnuj22qpflO30PsWML2GsK0fb9WpNcl5vOyV5o2ZjcYHxILwA2CruzylEwDUohJB1UyPnRTiX723rtB/MCarA2ML44+/A9L1FqU=" Feb 26 02:14:11 volumio go-librespot[1646893]: time="2026-02-26T02:14:11+07:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 26 02:14:12 volumio go-librespot[1646893]: time="2026-02-26T02:14:12+07:00" level=debug msg="completed keyexchange" Feb 26 02:14:12 volumio go-librespot[1646893]: time="2026-02-26T02:14:12+07:00" level=debug msg="completed challenge" Feb 26 02:14:12 volumio go-librespot[1646893]: time="2026-02-26T02:14:12+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:14:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 02:14:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 02:14:13 volumio volumio5-onboarding[2061]: time=2026-02-26T02:14:12.942+07:00 level=WARN msg="reconnection attempt failed" component=volumio/socket error="read tcp 127.0.0.1:39142->127.0.0.1:3000: i/o timeout" Feb 26 02:14:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Feb 26 02:14:16 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:14:16 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:14:16 volumio go-librespot[1646913]: go-librespot daemon starting... Feb 26 02:14:16 volumio go-librespot[1646915]: time="2026-02-26T02:14:16+07:00" level=info msg="running go-librespot 0.4.0" Feb 26 02:14:16 volumio go-librespot[1646915]: time="2026-02-26T02:14:16+07:00" level=debug msg="app state loaded" Feb 26 02:14:16 volumio go-librespot[1646915]: time="2026-02-26T02:14:16+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 26 02:14:18 volumio go-librespot[1646915]: time="2026-02-26T02:14: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-gew1.spotify.com:80]" Feb 26 02:14:18 volumio go-librespot[1646915]: time="2026-02-26T02:14:18+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:14:18 volumio go-librespot[1646915]: time="2026-02-26T02:14:18+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:14:18 volumio go-librespot[1646915]: time="2026-02-26T02:14:18+07:00" level=info msg="zeroconf server listening on port 33125" Feb 26 02:14:18 volumio volumio[1646344]: info: CoreCommandRouter::volumioGetState Feb 26 02:14:18 volumio volumio[1646344]: info: CorePlayQueue::getTrack 0 Feb 26 02:14:18 volumio go-librespot[1646915]: time="2026-02-26T02:14:18+07:00" level=debug msg="obtained new client token: AAB++57fUcjlOTU0riyrBEbQJFioXvUc9yT5voHwm/F3fJH1TVSHVtKfLbxdHfdWVIUG9mk0Ngol0yDfBsw1GXrQTRedzQzaoh1DPlrRrWXOOZrTdB52UsFvURYY7Bo4JXHHUrvM85K5i/NvVYkk3j9k/ufofx6uflZvAmtkrrFv5HjEInyS0RMqwez/x4qmyIOZKQxeV/HtWCFKCb/AEVNRGFQ4LhzE/ZpJaI27hbkhtQiJJq9C7sBHw7E=" Feb 26 02:14:18 volumio go-librespot[1646915]: time="2026-02-26T02:14:18+07:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 26 02:14:18 volumio go-librespot[1646915]: time="2026-02-26T02:14:18+07:00" level=debug msg="completed keyexchange" Feb 26 02:14:18 volumio go-librespot[1646915]: time="2026-02-26T02:14:18+07:00" level=debug msg="completed challenge" Feb 26 02:14:19 volumio go-librespot[1646915]: time="2026-02-26T02:14: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:14:19 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 02:14:19 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 02:14:21 volumio volumio[1646344]: error: MyVolumio Plugin failed to start in a timely fashion Feb 26 02:14:21 volumio volumio[1646344]: [Metrics] CommandRouter: 100s 244.12ms Feb 26 02:14:22 volumio volumio[1646344]: info: CoreCommandRouter::volumiosetStartupVolume Feb 26 02:14:22 volumio volumio[1646344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 02:14:22 volumio volumio[1646344]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 02:14:22 volumio volumio[1646344]: info: CoreCommandRouter::Close All Modals sent Feb 26 02:14:22 volumio volumio[1646344]: info: CoreCommandRouter::Close All Modals sent Feb 26 02:14:22 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Feb 26 02:14:23 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:14:23 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:14:23 volumio go-librespot[1646948]: go-librespot daemon starting... Feb 26 02:14:23 volumio go-librespot[1646950]: time="2026-02-26T02:14:23+07:00" level=info msg="running go-librespot 0.4.0" Feb 26 02:14:23 volumio go-librespot[1646950]: time="2026-02-26T02:14:23+07:00" level=debug msg="app state loaded" Feb 26 02:14:23 volumio go-librespot[1646950]: time="2026-02-26T02:14:23+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 26 02:14:24 volumio go-librespot[1646950]: time="2026-02-26T02:14: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:14:24 volumio go-librespot[1646950]: time="2026-02-26T02:14: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:14:24 volumio go-librespot[1646950]: time="2026-02-26T02:14: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:14:24 volumio go-librespot[1646950]: time="2026-02-26T02:14:24+07:00" level=info msg="zeroconf server listening on port 35811" Feb 26 02:14:24 volumio go-librespot[1646950]: time="2026-02-26T02:14:24+07:00" level=debug msg="obtained new client token: AABwlMMHJahCP3IuIINthGBX5FgECV3KxaBeFu6//O9KGKAHfPmiOpPNvfj/XGxsW8PCQ9Imdp6ERKLNddoKJntCfpHc2dClFF8vk9lMgOLrEJkKn/mlTfMJeJ3AY1Y14zsjtqPk8O8rnwvfX1sDuXrmCIrkhr3Sogv2ACBGcxTKnmC3gYaZIGeR4QcJQWBx6NSpKhfieyyYjVcH4KU/RGsKLeQc+hMmTbVZXwIi/cJH/0QWMMK1drHczwE=" Feb 26 02:14:24 volumio go-librespot[1646950]: time="2026-02-26T02:14:24+07:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 26 02:14:25 volumio go-librespot[1646950]: time="2026-02-26T02:14:25+07:00" level=debug msg="completed keyexchange" Feb 26 02:14:25 volumio go-librespot[1646950]: time="2026-02-26T02:14:25+07:00" level=debug msg="completed challenge" Feb 26 02:14:25 volumio volumio5-onboarding[2061]: time=2026-02-26T02:14:25.151+07:00 level=WARN msg="reconnection attempt failed" component=volumio/socket error="read tcp 127.0.0.1:33666->127.0.0.1:3000: i/o timeout" Feb 26 02:14:25 volumio go-librespot[1646950]: time="2026-02-26T02:14: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:14:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 02:14:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 02:14:26 volumio volumio[1646344]: info: CoreCommandRouter::volumioGetState Feb 26 02:14:26 volumio volumio[1646344]: info: CorePlayQueue::getTrack 0 Feb 26 02:14:26 volumio volumio[1646344]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 26 02:14:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Feb 26 02:14:28 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:14:28 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:14:28 volumio go-librespot[1646960]: go-librespot daemon starting... Feb 26 02:14:29 volumio go-librespot[1646961]: time="2026-02-26T02:14:29+07:00" level=info msg="running go-librespot 0.4.0" Feb 26 02:14:29 volumio go-librespot[1646961]: time="2026-02-26T02:14:29+07:00" level=debug msg="app state loaded" Feb 26 02:14:29 volumio go-librespot[1646961]: time="2026-02-26T02:14:29+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 26 02:14:30 volumio go-librespot[1646961]: time="2026-02-26T02:14: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 26 02:14:30 volumio go-librespot[1646961]: time="2026-02-26T02:14:30+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:14:30 volumio go-librespot[1646961]: time="2026-02-26T02:14:30+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:14:30 volumio go-librespot[1646961]: time="2026-02-26T02:14:30+07:00" level=info msg="zeroconf server listening on port 33853" Feb 26 02:14:31 volumio go-librespot[1646961]: time="2026-02-26T02:14:31+07:00" level=debug msg="obtained new client token: AAC4HD1l5F/58WG5sRbOQ2DeIoscCVLJ1OHApN5MOvRSETnuBVx5QaFpjboMRGwxxeAQw2owC6WoFd5aaginfB5eJogkC6/D/lV+xzctJcB5sn7evR7yLnMQvE/+NkL09CU+5VimVLXC12HlVd9P8IFtVyRSahXYt+eyB3HH0yzIElw55NX+5j20G5fTmbV3NUViwUIvdqJLZMZz+g80Hcn1ZYzeftUekKeIU7ZkcNhO25J0FucBy5fh" Feb 26 02:14:31 volumio go-librespot[1646961]: time="2026-02-26T02:14:31+07:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 26 02:14:31 volumio go-librespot[1646961]: time="2026-02-26T02:14:31+07:00" level=debug msg="completed keyexchange" Feb 26 02:14:31 volumio go-librespot[1646961]: time="2026-02-26T02:14:31+07:00" level=debug msg="completed challenge" Feb 26 02:14:31 volumio go-librespot[1646961]: time="2026-02-26T02:14: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:14:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 02:14:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 02:14:32 volumio volumio[1646344]: info: Shairport-Sync Started Feb 26 02:14:33 volumio volumio[1646344]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Feb 26 02:14:33 volumio volumio[1646344]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 26 02:14:34 volumio volumio[1646344]: aplay: device_list:274: no soundcards found... Feb 26 02:14:34 volumio volumio[1646344]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Feb 26 02:14:34 volumio volumio[1646344]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Feb 26 02:14:35 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11. Feb 26 02:14:35 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:14:35 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:14:35 volumio go-librespot[1646989]: go-librespot daemon starting... Feb 26 02:14:36 volumio go-librespot[1646990]: time="2026-02-26T02:14:36+07:00" level=info msg="running go-librespot 0.4.0" Feb 26 02:14:36 volumio go-librespot[1646990]: time="2026-02-26T02:14:36+07:00" level=debug msg="app state loaded" Feb 26 02:14:36 volumio go-librespot[1646990]: time="2026-02-26T02:14:36+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 26 02:14:37 volumio go-librespot[1646990]: time="2026-02-26T02:14:37+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:14:37 volumio go-librespot[1646990]: time="2026-02-26T02:14:37+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:14:37 volumio go-librespot[1646990]: time="2026-02-26T02:14:37+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:14:37 volumio go-librespot[1646990]: time="2026-02-26T02:14:37+07:00" level=info msg="zeroconf server listening on port 46197" Feb 26 02:14:37 volumio go-librespot[1646990]: time="2026-02-26T02:14:37+07:00" level=debug msg="obtained new client token: AABSKdO221Mo8u7se4QOk4+b+6g30fM3ixTU1BOFqkLdAHemyu6m5Q3w7yPRIAbfsttCss92wkin7u37SR1rmQ/pbr3WjsutDzHDAxrKErXsZW42YkOBTOHJ1grwjodW7KNqD0HcRMIPhPZdLFWxQhmdK/RoyWRwluqU7f28DmgqKGxrBTirxbLGH4s/GYWbzHmshLjoeibClgNk7/RLUc3/WPwj1MIhRGnXzo02cV7ydql61b5RABPi3Vo=" Feb 26 02:14:37 volumio go-librespot[1646990]: time="2026-02-26T02:14:37+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:14:37 volumio go-librespot[1646990]: time="2026-02-26T02:14:37+07:00" level=debug msg="connected to ap-gew4.spotify.com:443" Feb 26 02:14:38 volumio go-librespot[1646990]: time="2026-02-26T02:14:38+07:00" level=debug msg="completed keyexchange" Feb 26 02:14:38 volumio go-librespot[1646990]: time="2026-02-26T02:14:38+07:00" level=debug msg="completed challenge" Feb 26 02:14:38 volumio go-librespot[1646990]: time="2026-02-26T02:14:38+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:14:38 volumio volumio5-onboarding[2061]: time=2026-02-26T02:14:38.172+07:00 level=WARN msg="reconnection attempt failed" component=volumio/socket error="read tcp 127.0.0.1:54506->127.0.0.1:3000: i/o timeout" Feb 26 02:14:38 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 02:14:38 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 02:14:41 volumio volumio[1646344]: info: CoreCommandRouter::volumioGetState Feb 26 02:14:41 volumio volumio[1646344]: info: CorePlayQueue::getTrack 0 Feb 26 02:14:41 volumio volumio[1646344]: info: BOOT COMPLETED Feb 26 02:14:41 volumio volumio[1646344]: info: Initializing connection to go-librespot Websocket Feb 26 02:14:42 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12. Feb 26 02:14:42 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:14:42 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:14:43 volumio go-librespot[1647012]: go-librespot daemon starting... Feb 26 02:14:43 volumio go-librespot[1647019]: time="2026-02-26T02:14:43+07:00" level=info msg="running go-librespot 0.4.0" Feb 26 02:14:43 volumio go-librespot[1647019]: time="2026-02-26T02:14:43+07:00" level=debug msg="app state loaded" Feb 26 02:14:43 volumio go-librespot[1647019]: time="2026-02-26T02:14:43+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 26 02:14:43 volumio go-librespot[1647019]: time="2026-02-26T02:14:43+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:14:43 volumio go-librespot[1647019]: time="2026-02-26T02:14:43+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:14:43 volumio go-librespot[1647019]: time="2026-02-26T02:14:43+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:14:44 volumio go-librespot[1647019]: time="2026-02-26T02:14:44+07:00" level=info msg="zeroconf server listening on port 38247" Feb 26 02:14:44 volumio go-librespot[1647019]: time="2026-02-26T02:14:44+07:00" level=debug msg="obtained new client token: AACRQXb2F22Vx0EohSAVMEZyJ/9qp7MbIae1LIR6C8Szw9ESCCdvh3dShBZID4d46uSsrCzSrRiNoc+je6i23IH39g2seR5RLnDgQx0Kw0ijhDhhnuJhWn3M1xG1Jp52i5BWf2SVkhLhdBCAW6l6tIcYHk15Wjx7Q8TJjuFBGpHKHamnYHlbqPdwzUGrn7CSXfUD6MZ5c3f9yAkuIhBD1QwKXOR/GRF9JjPDDcTyGU8xIYUlytTZ6WFV" Feb 26 02:14:44 volumio go-librespot[1647019]: time="2026-02-26T02:14:44+07:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 26 02:14:44 volumio go-librespot[1647019]: time="2026-02-26T02:14:44+07:00" level=debug msg="completed keyexchange" Feb 26 02:14:44 volumio go-librespot[1647019]: time="2026-02-26T02:14:44+07:00" level=debug msg="completed challenge" Feb 26 02:14:44 volumio go-librespot[1647019]: time="2026-02-26T02:14:44+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:14:45 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 02:14:45 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 02:14:48 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13. Feb 26 02:14:48 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:14:48 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:14:48 volumio go-librespot[1647029]: go-librespot daemon starting... Feb 26 02:14:49 volumio go-librespot[1647030]: time="2026-02-26T02:14:49+07:00" level=info msg="running go-librespot 0.4.0" Feb 26 02:14:49 volumio go-librespot[1647030]: time="2026-02-26T02:14:49+07:00" level=debug msg="app state loaded" Feb 26 02:14:49 volumio go-librespot[1647030]: time="2026-02-26T02:14:49+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 26 02:14:49 volumio go-librespot[1647030]: time="2026-02-26T02:14:49+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:14:49 volumio go-librespot[1647030]: time="2026-02-26T02:14:49+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:14:49 volumio go-librespot[1647030]: time="2026-02-26T02:14:49+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:14:49 volumio go-librespot[1647030]: time="2026-02-26T02:14:49+07:00" level=info msg="zeroconf server listening on port 33345" Feb 26 02:14:49 volumio go-librespot[1647030]: time="2026-02-26T02:14:49+07:00" level=debug msg="obtained new client token: AABqrYAHn1ixHfKmhyGv5YgdSHabgVlvV1qvwPDFoAqu290q6piVtznUEPfxRk9pnqYa4cqjZsctmdG9mKncKhvO5Dkh/zyjcrJG2d0sLOFvSRgXkkBd57FaZ/pFbblnpJzw/7af4C6w0QHb7tWASSXklpPKxqN1dJ+WKyUhxMHjN/oywbQZuXPkEPNnc30M1B+eZuy8C96egfjUgZ3bolO8x/7lUTsw6qp24/ngDErxJdqxn4GJhFMtOWs=" Feb 26 02:14:49 volumio go-librespot[1647030]: time="2026-02-26T02:14:49+07:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 26 02:14:50 volumio go-librespot[1647030]: time="2026-02-26T02:14:50+07:00" level=debug msg="completed keyexchange" Feb 26 02:14:50 volumio go-librespot[1647030]: time="2026-02-26T02:14:50+07:00" level=debug msg="completed challenge" Feb 26 02:14:50 volumio go-librespot[1647030]: time="2026-02-26T02:14:50+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:14:50 volumio volumio5-onboarding[2061]: time=2026-02-26T02:14:50.324+07:00 level=WARN msg="reconnection attempt failed" component=volumio/socket error="read tcp 127.0.0.1:58110->127.0.0.1:3000: i/o timeout" Feb 26 02:14:50 volumio volumio[1646344]: info: camilladsp respawn in 100 ms (attempt 1/10) Feb 26 02:14:50 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 02:14:50 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 02:14:52 volumio volumio[1646344]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 26 02:14:53 volumio volumio[1646344]: Error [ERR_INTERNAL_ASSERTION]: This is caused by either a bug in Node.js or incorrect usage of Node.js internals. Feb 26 02:14:53 volumio volumio[1646344]: Please open an issue with this stack trace at https://github.com/nodejs/node/issues Feb 26 02:14:53 volumio volumio[1646344]: at new NodeError (node:internal/errors:405:5) Feb 26 02:14:53 volumio volumio[1646344]: at assert (node:internal/assert:14:11) Feb 26 02:14:53 volumio volumio[1646344]: at internalConnectMultiple (node:net:1118:3) Feb 26 02:14:53 volumio volumio[1646344]: at Timeout.internalConnectMultipleTimeout (node:net:1687:3) Feb 26 02:14:53 volumio volumio[1646344]: at listOnTimeout (node:internal/timers:575:11) Feb 26 02:14:53 volumio volumio[1646344]: at process.processTimers (node:internal/timers:514:7) { Feb 26 02:14:53 volumio volumio[1646344]: code: 'ERR_INTERNAL_ASSERTION' Feb 26 02:14:53 volumio volumio[1646344]: } Feb 26 02:14:53 volumio volumio[1646344]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 26 02:14:54 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14. Feb 26 02:14:54 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:14:54 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:14:54 volumio go-librespot[1647057]: go-librespot daemon starting... Feb 26 02:14:55 volumio go-librespot[1647058]: time="2026-02-26T02:14:55+07:00" level=info msg="running go-librespot 0.4.0" Feb 26 02:14:55 volumio go-librespot[1647058]: time="2026-02-26T02:14:55+07:00" level=debug msg="app state loaded" Feb 26 02:14:55 volumio go-librespot[1647058]: time="2026-02-26T02:14:55+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 26 02:14:55 volumio go-librespot[1647058]: time="2026-02-26T02:14:55+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:14:55 volumio go-librespot[1647058]: time="2026-02-26T02:14:55+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:14:55 volumio go-librespot[1647058]: time="2026-02-26T02:14:55+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:14:55 volumio go-librespot[1647058]: time="2026-02-26T02:14:55+07:00" level=info msg="zeroconf server listening on port 46509" Feb 26 02:14:55 volumio go-librespot[1647058]: time="2026-02-26T02:14:55+07:00" level=debug msg="obtained new client token: AABrlwOsu+A8pqqwhqLXx+32KQBLcTUkWoBmoXklvVQXc9O9DfQ155RNCPWAPUlcQqC2Mm44C3t9Own/cuHvZAaYMCBO+A57sN6nGeeAj8OfeLHrCtpzWNysKNZGw8NhDE0UtrLfIvSApHyfXE9pNvGdyghhLhARVSo/QjX5qyQqLQCJk2elVX/qbVRHnu+u1qbQoYJkwRNnPNbPkYr0Q4+jRXpfp5mWtmL0j8wqAXh8Q3g14Kyb26J8Ucg=" Feb 26 02:14:56 volumio go-librespot[1647058]: time="2026-02-26T02:14:56+07:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 26 02:14:56 volumio go-librespot[1647058]: time="2026-02-26T02:14:56+07:00" level=debug msg="completed keyexchange" Feb 26 02:14:56 volumio go-librespot[1647058]: time="2026-02-26T02:14:56+07:00" level=debug msg="completed challenge" Feb 26 02:14:56 volumio go-librespot[1647058]: time="2026-02-26T02:14:56+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:14:56 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 02:14:56 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 02:15:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15. Feb 26 02:15:00 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:15:00 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:15:00 volumio go-librespot[1647077]: go-librespot daemon starting... Feb 26 02:15:00 volumio go-librespot[1647078]: time="2026-02-26T02:15:00+07:00" level=info msg="running go-librespot 0.4.0" Feb 26 02:15:00 volumio go-librespot[1647078]: time="2026-02-26T02:15:00+07:00" level=debug msg="app state loaded" Feb 26 02:15:00 volumio go-librespot[1647078]: time="2026-02-26T02:15:00+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 26 02:15:01 volumio go-librespot[1647078]: time="2026-02-26T02:15:01+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:15:01 volumio go-librespot[1647078]: time="2026-02-26T02:15:01+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:15:01 volumio go-librespot[1647078]: time="2026-02-26T02:15:01+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:15:01 volumio go-librespot[1647078]: time="2026-02-26T02:15:01+07:00" level=info msg="zeroconf server listening on port 33879" Feb 26 02:15:01 volumio go-librespot[1647078]: time="2026-02-26T02:15:01+07:00" level=debug msg="obtained new client token: AAAYJA9BqZtRVsZpIGZz67Gl1xC6DUTKYs6TKNqF7AY44OJkleG/gw9YqTzMy7FUXVTaFcn0YpDDnjyKmugIg0zhlOWMaz2YarxX+dfIPK1xb4KhtsHg9DJArIU5tFL/f0r6voXkNAgl0OkemeMtAwnJIDzTXurZkfC89pAEZ42OpPfS+5+RVoeEQboi2e2K0ybF/wInvE5386Dl/TDdVWS7yr9vlEd1Sm2iz1r83uEDCh971ZT3KCbwwbU=" Feb 26 02:15:01 volumio go-librespot[1647078]: time="2026-02-26T02:15:01+07:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 26 02:15:01 volumio go-librespot[1647078]: time="2026-02-26T02:15:01+07:00" level=debug msg="completed keyexchange" Feb 26 02:15:02 volumio go-librespot[1647078]: time="2026-02-26T02:15:01+07:00" level=debug msg="completed challenge" Feb 26 02:15:02 volumio go-librespot[1647078]: time="2026-02-26T02:15:02+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:15:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 02:15:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 02:15:02 volumio volumio5-onboarding[2061]: time=2026-02-26T02:15:02.281+07:00 level=WARN msg="reconnection attempt failed" component=volumio/socket error="read tcp 127.0.0.1:37988->127.0.0.1:3000: i/o timeout" Feb 26 02:15:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16. Feb 26 02:15:05 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:15:06 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:15:06 volumio go-librespot[1647103]: go-librespot daemon starting... Feb 26 02:15:06 volumio go-librespot[1647104]: time="2026-02-26T02:15:06+07:00" level=info msg="running go-librespot 0.4.0" Feb 26 02:15:06 volumio go-librespot[1647104]: time="2026-02-26T02:15:06+07:00" level=debug msg="app state loaded" Feb 26 02:15:06 volumio go-librespot[1647104]: time="2026-02-26T02:15:06+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 26 02:15:08 volumio go-librespot[1647104]: time="2026-02-26T02:15:08+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:15:08 volumio go-librespot[1647104]: time="2026-02-26T02:15:08+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:15:08 volumio go-librespot[1647104]: time="2026-02-26T02:15:08+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:15:08 volumio go-librespot[1647104]: time="2026-02-26T02:15:08+07:00" level=info msg="zeroconf server listening on port 43473" Feb 26 02:15:08 volumio go-librespot[1647104]: time="2026-02-26T02:15:08+07:00" level=debug msg="obtained new client token: AAC8kAoDhPYT+hYeSfMo9Y3ctJDYrVdvZ2AGjXJvpS/DQ3XC/ukCx8rNNEgb0qhFiavqAJM3rMFgbgXvEeZcJrwABzxkIdRh5CNqQGHbg+ZJKSm7Yr334MJvRw5oc/4a/CXBZJ5wAjbrTuDuQ4g7d2QI7GBCxkZLoaoM5LxksuHxtymCF5FUmGOU9UMJ3dPmsc/E1XZOYJRePFy3+fd0m7IAU1W0ITzpEkSJtwPFQgsTntTTdxVYLU2+8hM=" Feb 26 02:15:08 volumio go-librespot[1647104]: time="2026-02-26T02:15:08+07:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 26 02:15:08 volumio go-librespot[1647104]: time="2026-02-26T02:15:08+07:00" level=debug msg="completed keyexchange" Feb 26 02:15:08 volumio go-librespot[1647104]: time="2026-02-26T02:15:08+07:00" level=debug msg="completed challenge" Feb 26 02:15:09 volumio go-librespot[1647104]: time="2026-02-26T02:15:09+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:15:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 02:15:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 02:15:11 volumio systemd[1]: mpd.service: start operation timed out. Terminating. Feb 26 02:15:11 volumio systemd[1]: mpd.service: Failed with result 'timeout'. Feb 26 02:15:11 volumio systemd[1]: Failed to start mpd.service - Music Player Daemon. Feb 26 02:15:11 volumio systemd[1]: mpd.service: Consumed 12.688s CPU time. Feb 26 02:15:11 volumio sudo[1646539]: pam_unix(sudo:session): session closed for user root Feb 26 02:15:11 volumio sudo[1646752]: pam_unix(sudo:session): session closed for user root Feb 26 02:15:11 volumio sudo[1646526]: pam_unix(sudo:session): session closed for user root Feb 26 02:15:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17. Feb 26 02:15:12 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:15:13 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:15:13 volumio go-librespot[1647115]: go-librespot daemon starting... Feb 26 02:15:13 volumio go-librespot[1647116]: time="2026-02-26T02:15:13+07:00" level=info msg="running go-librespot 0.4.0" Feb 26 02:15:13 volumio go-librespot[1647116]: time="2026-02-26T02:15:13+07:00" level=debug msg="app state loaded" Feb 26 02:15:13 volumio go-librespot[1647116]: time="2026-02-26T02:15:13+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 26 02:15:13 volumio sudo[1647122]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-26 02:14' Feb 26 02:15:13 volumio sudo[1647122]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 02:15:13 volumio go-librespot[1647116]: time="2026-02-26T02:15:13+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:15:13 volumio go-librespot[1647116]: time="2026-02-26T02:15:13+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:15:13 volumio go-librespot[1647116]: time="2026-02-26T02:15:13+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:15:13 volumio go-librespot[1647116]: time="2026-02-26T02:15:13+07:00" level=info msg="zeroconf server listening on port 37455" Feb 26 02:15:14 volumio go-librespot[1647116]: time="2026-02-26T02:15:14+07:00" level=debug msg="obtained new client token: AADqdbWb6ICrsTjeXB2J7gwRPPmdnZQ8Fz07cMc+GvdfkqqMyS/bJtWFarw8PpzjV1KWPq6RAMz7Q2DtswAIXxzQ9CMTyQNqZoaNjZ99z6Cq7Bemnrnr04zOTJ6cF42I4fD9fZU4QlEi3gtuzMJErmNwQp9IvpPiLNA2dLrdojayojInITVcfG4DifM+Wgzp1H0TcV2zhiltLrXWd7IQll7QppooobXb2weoS6QfZQofLpqLPHmvUhpB" Feb 26 02:15:14 volumio go-librespot[1647116]: time="2026-02-26T02:15:14+07:00" level=debug msg="connected to ap-gew4.spotify.com:4070" 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"