Oct 25 01:30:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1297. Oct 25 01:30:02 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 25 01:30:02 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 25 01:30:02 volumio go-librespot[31324]: go-librespot daemon starting... Oct 25 01:30:02 volumio go-librespot[31325]: time="2025-10-25T01:30:02+01:00" level=info msg="running go-librespot 0.4.0" Oct 25 01:30:02 volumio go-librespot[31325]: time="2025-10-25T01:30:02+01:00" level=debug msg="app state loaded" Oct 25 01:30:02 volumio go-librespot[31325]: time="2025-10-25T01:30:02+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 25 01:30:02 volumio go-librespot[31325]: time="2025-10-25T01:30:02+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 25 01:30:02 volumio go-librespot[31325]: time="2025-10-25T01:30:02+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 25 01:30:02 volumio go-librespot[31325]: time="2025-10-25T01:30:02+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 25 01:30:02 volumio go-librespot[31325]: time="2025-10-25T01:30:02+01:00" level=info msg="zeroconf server listening on port 33703" Oct 25 01:30:02 volumio go-librespot[31325]: time="2025-10-25T01:30:02+01:00" level=debug msg="obtained new client token: AACLzQ/nj3NtlIJ9xBf/Prj9gNzYYFIFi1/DicUx679VHkGGC1dMPgq7uS4ggS/HQ0SfTYIgK9QbyA0pNQrSamsXYu477HN4xdMzww1rBlmosU8uyH+Al7j5SewzT6Mx/TXG4vv+knziWaFeU7q1I595HwMpUHq5FDrgH+kmlgJOMRW7MjDZ2FgIuww6CR+Etuoymgib64VW5QzlAe5XZF63BigKaTUzdiLraKwafDD3vwoYj/fgGRc=" Oct 25 01:30:02 volumio go-librespot[31325]: time="2025-10-25T01:30:02+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 25 01:30:02 volumio go-librespot[31325]: time="2025-10-25T01:30:02+01:00" level=debug msg="completed keyexchange" Oct 25 01:30:02 volumio go-librespot[31325]: time="2025-10-25T01:30:02+01:00" level=debug msg="completed challenge" Oct 25 01:30:02 volumio go-librespot[31325]: time="2025-10-25T01:30:02+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 25 01:30:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 25 01:30:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 25 01:30:02 volumio volumio[30621]: info: Initializing connection to go-librespot Websocket Oct 25 01:30:02 volumio volumio[30621]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 25 01:30:05 volumio volumio[30621]: info: Initializing connection to go-librespot Websocket Oct 25 01:30:05 volumio volumio[30621]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 25 01:30:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1298. Oct 25 01:30:05 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 25 01:30:05 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 25 01:30:05 volumio go-librespot[31335]: go-librespot daemon starting... Oct 25 01:30:05 volumio go-librespot[31336]: time="2025-10-25T01:30:05+01:00" level=info msg="running go-librespot 0.4.0" Oct 25 01:30:05 volumio go-librespot[31336]: time="2025-10-25T01:30:05+01:00" level=debug msg="app state loaded" Oct 25 01:30:05 volumio go-librespot[31336]: time="2025-10-25T01:30:05+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 25 01:30:06 volumio go-librespot[31336]: time="2025-10-25T01:30:06+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 25 01:30:06 volumio go-librespot[31336]: time="2025-10-25T01:30:06+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 25 01:30:06 volumio go-librespot[31336]: time="2025-10-25T01:30:06+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 25 01:30:06 volumio go-librespot[31336]: time="2025-10-25T01:30:06+01:00" level=info msg="zeroconf server listening on port 36877" Oct 25 01:30:06 volumio go-librespot[31336]: time="2025-10-25T01:30:06+01:00" level=debug msg="obtained new client token: AADMXgfjnAGwAXrpvooElQQ9ZZfO7C2hr9ipI8sFXdFjru6DB/L6Gqt7OM7VjlqVlpobn9oAPib3aGepAuH8q8zS+ejH2KKmnLjX6ZDlFzMdIjRcDgpJXNhwYOE3n8u/XTGlI5hCCTHOyEfNcbsmsEqRCUh3y+ycxjVGfEN9/9z61mfnnlYaHG3efOmTQotFK35ntCGDYsSvMjkhhIyTA7s/JRy0LSHy5S0OTbpnFHfTduUPxTqu" Oct 25 01:30:06 volumio go-librespot[31336]: time="2025-10-25T01:30:06+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 25 01:30:06 volumio go-librespot[31336]: time="2025-10-25T01:30:06+01:00" level=debug msg="completed keyexchange" Oct 25 01:30:06 volumio go-librespot[31336]: time="2025-10-25T01:30:06+01:00" level=debug msg="completed challenge" Oct 25 01:30:06 volumio go-librespot[31336]: time="2025-10-25T01:30:06+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 25 01:30:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 25 01:30:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 25 01:30:08 volumio volumio[30621]: info: Initializing connection to go-librespot Websocket Oct 25 01:30:08 volumio volumio[30621]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 25 01:30:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1299. Oct 25 01:30:09 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 25 01:30:09 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 25 01:30:09 volumio go-librespot[31357]: go-librespot daemon starting... Oct 25 01:30:09 volumio go-librespot[31358]: time="2025-10-25T01:30:09+01:00" level=info msg="running go-librespot 0.4.0" Oct 25 01:30:09 volumio go-librespot[31358]: time="2025-10-25T01:30:09+01:00" level=debug msg="app state loaded" Oct 25 01:30:09 volumio go-librespot[31358]: time="2025-10-25T01:30:09+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 25 01:30:09 volumio go-librespot[31358]: time="2025-10-25T01:30:09+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 25 01:30:09 volumio go-librespot[31358]: time="2025-10-25T01:30:09+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 25 01:30:09 volumio go-librespot[31358]: time="2025-10-25T01:30:09+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 25 01:30:09 volumio go-librespot[31358]: time="2025-10-25T01:30:09+01:00" level=info msg="zeroconf server listening on port 42827" Oct 25 01:30:09 volumio go-librespot[31358]: time="2025-10-25T01:30:09+01:00" level=debug msg="obtained new client token: AADSsX3KQ66tTgop4tXmDWEU44BDKCjTb8CEli/mfxOpyrV2eOCW+GyLkgY4yShK+GzV8gzSlgTgstamgx84mPfDH1cOes1WYFAOs2g9k2TTd1Knc2xzODdqwMmNbFJc2G3LHSFm6QJLs5UkiaNw+YAqcjs739PtANCgmHxqLpHk+WW0nTH9SrEVPeJ8DI7iFbqK+Kl68ptMi+lOzqKs26cIsiBY3iTzfrqP7jv8lVQYm1vabZCNX4M=" Oct 25 01:30:09 volumio go-librespot[31358]: time="2025-10-25T01:30:09+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 25 01:30:09 volumio go-librespot[31358]: time="2025-10-25T01:30:09+01:00" level=debug msg="completed keyexchange" Oct 25 01:30:09 volumio go-librespot[31358]: time="2025-10-25T01:30:09+01:00" level=debug msg="completed challenge" Oct 25 01:30:09 volumio go-librespot[31358]: time="2025-10-25T01:30:09+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 25 01:30:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 25 01:30:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 25 01:30:11 volumio volumio[30621]: info: Initializing connection to go-librespot Websocket Oct 25 01:30:11 volumio volumio[30621]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 25 01:30:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1300. Oct 25 01:30:12 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 25 01:30:12 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 25 01:30:12 volumio go-librespot[31365]: go-librespot daemon starting... Oct 25 01:30:12 volumio go-librespot[31366]: time="2025-10-25T01:30:12+01:00" level=info msg="running go-librespot 0.4.0" Oct 25 01:30:12 volumio go-librespot[31366]: time="2025-10-25T01:30:12+01:00" level=debug msg="app state loaded" Oct 25 01:30:12 volumio go-librespot[31366]: time="2025-10-25T01:30:12+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 25 01:30:13 volumio go-librespot[31366]: time="2025-10-25T01:30:13+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 25 01:30:13 volumio go-librespot[31366]: time="2025-10-25T01:30:13+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 25 01:30:13 volumio go-librespot[31366]: time="2025-10-25T01:30:13+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 25 01:30:13 volumio go-librespot[31366]: time="2025-10-25T01:30:13+01:00" level=info msg="zeroconf server listening on port 33887" Oct 25 01:30:13 volumio go-librespot[31366]: time="2025-10-25T01:30:13+01:00" level=debug msg="obtained new client token: AAAqMoFD2uCGrrxf3D8mbzuiGs/rt9Ok9QKF4xL16AIT9/bKEBO7ZMrPtk3y28nuCxXV2UEA3g8dZLn5oMUE6q8eDUcWaPEEPIh4ATRROa5MEwLpN2+8Pnno7l4mn9heTRnjZmQ9REYw5umhV2knndyUmAfpliym9Znk+IemNBtngsB01Fdd9lS5iY2+7YckfocULkhty4qsf90DPGGrs9PVcl1RwWjmWIupAF9rIA4F2mvkkxG2" Oct 25 01:30:13 volumio go-librespot[31366]: time="2025-10-25T01:30:13+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 25 01:30:13 volumio go-librespot[31366]: time="2025-10-25T01:30:13+01:00" level=debug msg="completed keyexchange" Oct 25 01:30:13 volumio go-librespot[31366]: time="2025-10-25T01:30:13+01:00" level=debug msg="completed challenge" Oct 25 01:30:13 volumio go-librespot[31366]: time="2025-10-25T01:30:13+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 25 01:30:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 25 01:30:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 25 01:30:14 volumio volumio[30621]: info: Initializing connection to go-librespot Websocket Oct 25 01:30:14 volumio volumio[30621]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 25 01:30:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1301. Oct 25 01:30:16 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 25 01:30:16 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 25 01:30:16 volumio go-librespot[31374]: go-librespot daemon starting... Oct 25 01:30:16 volumio go-librespot[31375]: time="2025-10-25T01:30:16+01:00" level=info msg="running go-librespot 0.4.0" Oct 25 01:30:16 volumio go-librespot[31375]: time="2025-10-25T01:30:16+01:00" level=debug msg="app state loaded" Oct 25 01:30:16 volumio go-librespot[31375]: time="2025-10-25T01:30:16+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 25 01:30:16 volumio go-librespot[31375]: time="2025-10-25T01:30:16+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 25 01:30:16 volumio go-librespot[31375]: time="2025-10-25T01:30:16+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 25 01:30:16 volumio go-librespot[31375]: time="2025-10-25T01:30:16+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 25 01:30:16 volumio go-librespot[31375]: time="2025-10-25T01:30:16+01:00" level=info msg="zeroconf server listening on port 36321" Oct 25 01:30:16 volumio go-librespot[31375]: time="2025-10-25T01:30:16+01:00" level=debug msg="obtained new client token: AABp064b3BMd68qXtIKmms5mJTiYByjfnMbPy+nf/1c9W9JOw4al85NOcImmn1RxD0Itm7JoWLT4mY4ZPtCEdd7AWGGDvQluo1S31iMHpQa+TW5383BJAzz0SoRkjJHT6nGpcszK/l3hRcjjMsmvAE8w5R6kmLs+4rYMWLHm+Jf1NPhKayV7gxX7AfXBu/eooYN1UiLS6jvO3O2jQqw3sIodpLxH3bfKQ6f6hFsKJ8AqzEZhSVL+l8c=" Oct 25 01:30:16 volumio go-librespot[31375]: time="2025-10-25T01:30:16+01:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.65.9:4070: connect: connection refused" Oct 25 01:30:16 volumio go-librespot[31375]: time="2025-10-25T01:30:16+01:00" level=debug msg="connected to ap-gew1.spotify.com:443" Oct 25 01:30:16 volumio go-librespot[31375]: time="2025-10-25T01:30:16+01:00" level=debug msg="completed keyexchange" Oct 25 01:30:16 volumio go-librespot[31375]: time="2025-10-25T01:30:16+01:00" level=debug msg="completed challenge" Oct 25 01:30:16 volumio go-librespot[31375]: time="2025-10-25T01:30:16+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 25 01:30:16 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 25 01:30:16 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 25 01:30:17 volumio volumio[30621]: info: Initializing connection to go-librespot Websocket Oct 25 01:30:17 volumio volumio[30621]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 25 01:30:19 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1302. Oct 25 01:30:19 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 25 01:30:19 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 25 01:30:19 volumio go-librespot[31396]: go-librespot daemon starting... Oct 25 01:30:19 volumio go-librespot[31397]: time="2025-10-25T01:30:19+01:00" level=info msg="running go-librespot 0.4.0" Oct 25 01:30:19 volumio go-librespot[31397]: time="2025-10-25T01:30:19+01:00" level=debug msg="app state loaded" Oct 25 01:30:19 volumio go-librespot[31397]: time="2025-10-25T01:30:19+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 25 01:30:20 volumio go-librespot[31397]: time="2025-10-25T01:30:20+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 25 01:30:20 volumio go-librespot[31397]: time="2025-10-25T01:30:20+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 25 01:30:20 volumio go-librespot[31397]: time="2025-10-25T01:30:20+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 25 01:30:20 volumio go-librespot[31397]: time="2025-10-25T01:30:20+01:00" level=info msg="zeroconf server listening on port 37915" Oct 25 01:30:20 volumio go-librespot[31397]: time="2025-10-25T01:30:20+01:00" level=debug msg="obtained new client token: AAA7UUFdqf7R4m6eM7JHKm7+t9XmY5G2kOfaac0PD5peBIxoEfVNbjwsxDF5MaiCjtGgW6p11YwDcV/0/SBz1CY+MoD0R1ZtjYV+FqluYBWKgqKBhkiQZ87oHFidVAgd8/DTRwKNQzNMtjQ2L9BQnmDIvLnWNTsbub4dExk3yoyAEDrLoUetcCZcpq+meVYs9E3jrZk2Spvt0btYRTrU7rcmfRntOX01vFmpiddv2zFxVMRvVBzt" Oct 25 01:30:20 volumio go-librespot[31397]: time="2025-10-25T01:30:20+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 25 01:30:20 volumio go-librespot[31397]: time="2025-10-25T01:30:20+01:00" level=debug msg="completed keyexchange" Oct 25 01:30:20 volumio go-librespot[31397]: time="2025-10-25T01:30:20+01:00" level=debug msg="completed challenge" Oct 25 01:30:20 volumio go-librespot[31397]: time="2025-10-25T01:30:20+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 25 01:30:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 25 01:30:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 25 01:30:20 volumio volumio[30621]: info: Initializing connection to go-librespot Websocket Oct 25 01:30:20 volumio volumio[30621]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 25 01:30:23 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1303. Oct 25 01:30:23 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 25 01:30:23 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 25 01:30:23 volumio go-librespot[31404]: go-librespot daemon starting... Oct 25 01:30:23 volumio go-librespot[31405]: time="2025-10-25T01:30:23+01:00" level=info msg="running go-librespot 0.4.0" Oct 25 01:30:23 volumio go-librespot[31405]: time="2025-10-25T01:30:23+01:00" level=debug msg="app state loaded" Oct 25 01:30:23 volumio go-librespot[31405]: time="2025-10-25T01:30:23+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 25 01:30:23 volumio go-librespot[31405]: time="2025-10-25T01:30:23+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 25 01:30:23 volumio go-librespot[31405]: time="2025-10-25T01:30:23+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 25 01:30:23 volumio go-librespot[31405]: time="2025-10-25T01:30:23+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 25 01:30:23 volumio go-librespot[31405]: time="2025-10-25T01:30:23+01:00" level=info msg="zeroconf server listening on port 36845" Oct 25 01:30:23 volumio go-librespot[31405]: time="2025-10-25T01:30:23+01:00" level=debug msg="obtained new client token: AAA0kgByCfjDAob/e+wnWmzLqP28IUBRzzT8lc8IZSKI4PJsIQ3YMWkGC3981AaYapDMwGkoA8/wRUfnqbfChzCenRVFolsjVtcqAbl2KBrlPD5j7EFrMLNOjKkyh9VSTKKODO0OXgzkI4+y5YKj7pZYPlvR3GzXr07BjYB2fkDM9tekKD7jRV4vvyD4E7GoaxW+nKNW7hIAcdaaf6xi6JZI+8oqxf132a8VTiP6TG4SvE3tFOkqBl4=" Oct 25 01:30:23 volumio go-librespot[31405]: time="2025-10-25T01:30:23+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 25 01:30:23 volumio go-librespot[31405]: time="2025-10-25T01:30:23+01:00" level=debug msg="completed keyexchange" Oct 25 01:30:23 volumio go-librespot[31405]: time="2025-10-25T01:30:23+01:00" level=debug msg="completed challenge" Oct 25 01:30:23 volumio go-librespot[31405]: time="2025-10-25T01:30:23+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 25 01:30:23 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 25 01:30:23 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 25 01:30:23 volumio volumio[30621]: info: Initializing connection to go-librespot Websocket Oct 25 01:30:23 volumio volumio[30621]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 25 01:30:26 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1304. Oct 25 01:30:26 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 25 01:30:26 volumio volumio[30621]: info: Initializing connection to go-librespot Websocket Oct 25 01:30:26 volumio volumio[30621]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 25 01:30:26 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 25 01:30:26 volumio go-librespot[31415]: go-librespot daemon starting... Oct 25 01:30:26 volumio go-librespot[31416]: time="2025-10-25T01:30:26+01:00" level=info msg="running go-librespot 0.4.0" Oct 25 01:30:26 volumio go-librespot[31416]: time="2025-10-25T01:30:26+01:00" level=debug msg="app state loaded" Oct 25 01:30:26 volumio go-librespot[31416]: time="2025-10-25T01:30:26+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 25 01:30:27 volumio go-librespot[31416]: time="2025-10-25T01:30:27+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 25 01:30:27 volumio go-librespot[31416]: time="2025-10-25T01:30:27+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 25 01:30:27 volumio go-librespot[31416]: time="2025-10-25T01:30:27+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 25 01:30:27 volumio go-librespot[31416]: time="2025-10-25T01:30:27+01:00" level=info msg="zeroconf server listening on port 41493" Oct 25 01:30:27 volumio go-librespot[31416]: time="2025-10-25T01:30:27+01:00" level=debug msg="obtained new client token: AADIVYEA5xiZ+vwZ5zScAEZsThwn0p3jNOildVCmoWmEg1dwBAANkwHL4+ilviYOBt+9evMvd14P41d41TnYhG70gs9B8rju/50CPemLTAFxYRrEANhJ5niFknI42tDkDe1cOqM/vzDobJ8Ox1POtDZPdJ+znmOS6oFxgFaXVfRSLoBCrPUxGQ6REaNc4DfecuVcy+kUikXWx3XaBWt1N23q5u2QLvTdcj9SQz/wFCYZAIuCfQEO" Oct 25 01:30:27 volumio go-librespot[31416]: time="2025-10-25T01:30:27+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 25 01:30:27 volumio go-librespot[31416]: time="2025-10-25T01:30:27+01:00" level=debug msg="completed keyexchange" Oct 25 01:30:27 volumio go-librespot[31416]: time="2025-10-25T01:30:27+01:00" level=debug msg="completed challenge" Oct 25 01:30:27 volumio go-librespot[31416]: time="2025-10-25T01:30:27+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 25 01:30:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 25 01:30:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 25 01:30:29 volumio volumio[30621]: info: Initializing connection to go-librespot Websocket Oct 25 01:30:29 volumio volumio[30621]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 25 01:30:30 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1305. Oct 25 01:30:30 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 25 01:30:30 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 25 01:30:30 volumio go-librespot[31437]: go-librespot daemon starting... Oct 25 01:30:30 volumio go-librespot[31438]: time="2025-10-25T01:30:30+01:00" level=info msg="running go-librespot 0.4.0" Oct 25 01:30:30 volumio go-librespot[31438]: time="2025-10-25T01:30:30+01:00" level=debug msg="app state loaded" Oct 25 01:30:30 volumio go-librespot[31438]: time="2025-10-25T01:30:30+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 25 01:30:30 volumio go-librespot[31438]: time="2025-10-25T01:30:30+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 25 01:30:30 volumio go-librespot[31438]: time="2025-10-25T01:30:30+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 25 01:30:30 volumio go-librespot[31438]: time="2025-10-25T01:30:30+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 25 01:30:30 volumio go-librespot[31438]: time="2025-10-25T01:30:30+01:00" level=info msg="zeroconf server listening on port 45401" Oct 25 01:30:30 volumio go-librespot[31438]: time="2025-10-25T01:30:30+01:00" level=debug msg="obtained new client token: AADFmpbTEyqQkll0h8XcMn4Ev0TUupj+SmvZcXwLU3ad/flZpd4E+RrDMOzFFyBykdMa2pH5vHoXvL+gMAxaek+1G0YZ+n7fn1HsDd7O5DvnD4oYBrkeJPMKeUV/Rs+iRPg0x6k9Map1gaP0GDAOEC1WbC2dSCyb5/6dto699AqtL6vz4dCwU4sO6TGnvURzR4IBCgPjJwa9jPYjPbo3L92u9CNH6EvmaFmo4/KpwDXg8g/C7oxIElo=" Oct 25 01:30:30 volumio go-librespot[31438]: time="2025-10-25T01:30:30+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 25 01:30:30 volumio go-librespot[31438]: time="2025-10-25T01:30:30+01:00" level=debug msg="completed keyexchange" Oct 25 01:30:30 volumio go-librespot[31438]: time="2025-10-25T01:30:30+01:00" level=debug msg="completed challenge" Oct 25 01:30:30 volumio go-librespot[31438]: time="2025-10-25T01:30:30+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 25 01:30:30 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 25 01:30:30 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 25 01:30:32 volumio volumio[30621]: info: Initializing connection to go-librespot Websocket Oct 25 01:30:32 volumio volumio[30621]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 25 01:30:33 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1306. Oct 25 01:30:33 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 25 01:30:33 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 25 01:30:33 volumio go-librespot[31446]: go-librespot daemon starting... Oct 25 01:30:33 volumio go-librespot[31447]: time="2025-10-25T01:30:33+01:00" level=info msg="running go-librespot 0.4.0" Oct 25 01:30:33 volumio go-librespot[31447]: time="2025-10-25T01:30:33+01:00" level=debug msg="app state loaded" Oct 25 01:30:33 volumio go-librespot[31447]: time="2025-10-25T01:30:33+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 25 01:30:34 volumio go-librespot[31447]: time="2025-10-25T01:30:34+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 25 01:30:34 volumio go-librespot[31447]: time="2025-10-25T01:30:34+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 25 01:30:34 volumio go-librespot[31447]: time="2025-10-25T01:30:34+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 25 01:30:34 volumio go-librespot[31447]: time="2025-10-25T01:30:34+01:00" level=info msg="zeroconf server listening on port 46383" Oct 25 01:30:34 volumio go-librespot[31447]: time="2025-10-25T01:30:34+01:00" level=debug msg="obtained new client token: AADS4c4Ya8/SG6WD4BJvab78Du9/wbsuGBYz0K2ZkmoaPisN+ngsoxz3F8Vk6R1QsprKCmdOXNdltVyjMAUN5XP8OUXqcHj3anieaK1YUb/4+hQBqAU2JtePtWZzEoJpLtVjRgkuiFyHSnGyZPhW5byoAvcKMQFaul9tSNnvbeWxii/tP20RGEY2gbBbJew7aAPtLDS/r+QgYBXRGSZDCOPrA7maePPD6wW6ngUIqDdeENWvItLu" Oct 25 01:30:34 volumio go-librespot[31447]: time="2025-10-25T01:30:34+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 25 01:30:34 volumio go-librespot[31447]: time="2025-10-25T01:30:34+01:00" level=debug msg="completed keyexchange" Oct 25 01:30:34 volumio go-librespot[31447]: time="2025-10-25T01:30:34+01:00" level=debug msg="completed challenge" Oct 25 01:30:34 volumio go-librespot[31447]: time="2025-10-25T01:30:34+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 25 01:30:34 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 25 01:30:34 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 25 01:30:35 volumio volumio[30621]: info: Initializing connection to go-librespot Websocket Oct 25 01:30:35 volumio volumio[30621]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 25 01:30:37 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1307. Oct 25 01:30:37 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 25 01:30:37 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 25 01:30:37 volumio go-librespot[31468]: go-librespot daemon starting... Oct 25 01:30:37 volumio go-librespot[31469]: time="2025-10-25T01:30:37+01:00" level=info msg="running go-librespot 0.4.0" Oct 25 01:30:37 volumio go-librespot[31469]: time="2025-10-25T01:30:37+01:00" level=debug msg="app state loaded" Oct 25 01:30:37 volumio go-librespot[31469]: time="2025-10-25T01:30:37+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 25 01:30:37 volumio go-librespot[31469]: time="2025-10-25T01:30:37+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 25 01:30:37 volumio go-librespot[31469]: time="2025-10-25T01:30:37+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 25 01:30:37 volumio go-librespot[31469]: time="2025-10-25T01:30:37+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 25 01:30:37 volumio go-librespot[31469]: time="2025-10-25T01:30:37+01:00" level=info msg="zeroconf server listening on port 46217" Oct 25 01:30:37 volumio go-librespot[31469]: time="2025-10-25T01:30:37+01:00" level=debug msg="obtained new client token: AADK1Stfjo+5D8HpGNlEDc/OY2muflqRom+OKnzoMzL8hRhJ9WQRYzBaXmK0kknpS2bhVRv2bCETEkig/IBOCj1js9eIouDn048YIilwdVSonPwAhhVaVbCa4E2QQeF7Fo5bhNxNCIrf2pDOJ3nbx5vqzD8BZDBw/NJW4dkT/xXn0BALLSlgvPuTLt9/qysBRmptHrUMevraBZcbkC6GqXixvC+UQCFWV5mf0IIrKTAFsKPRxOgRJpU=" Oct 25 01:30:37 volumio go-librespot[31469]: time="2025-10-25T01:30:37+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 25 01:30:37 volumio go-librespot[31469]: time="2025-10-25T01:30:37+01:00" level=debug msg="completed keyexchange" Oct 25 01:30:37 volumio go-librespot[31469]: time="2025-10-25T01:30:37+01:00" level=debug msg="completed challenge" Oct 25 01:30:37 volumio go-librespot[31469]: time="2025-10-25T01:30:37+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 25 01:30:37 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 25 01:30:37 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 25 01:30:38 volumio volumio[30621]: info: Initializing connection to go-librespot Websocket Oct 25 01:30:38 volumio volumio[30621]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 25 01:30:40 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1308. Oct 25 01:30:40 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 25 01:30:40 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 25 01:30:40 volumio go-librespot[31476]: go-librespot daemon starting... Oct 25 01:30:40 volumio go-librespot[31477]: time="2025-10-25T01:30:40+01:00" level=info msg="running go-librespot 0.4.0" Oct 25 01:30:40 volumio go-librespot[31477]: time="2025-10-25T01:30:40+01:00" level=debug msg="app state loaded" Oct 25 01:30:40 volumio go-librespot[31477]: time="2025-10-25T01:30:40+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 25 01:30:41 volumio go-librespot[31477]: time="2025-10-25T01:30:41+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 25 01:30:41 volumio go-librespot[31477]: time="2025-10-25T01:30:41+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 25 01:30:41 volumio go-librespot[31477]: time="2025-10-25T01:30:41+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 25 01:30:41 volumio go-librespot[31477]: time="2025-10-25T01:30:41+01:00" level=info msg="zeroconf server listening on port 42513" Oct 25 01:30:41 volumio go-librespot[31477]: time="2025-10-25T01:30:41+01:00" level=debug msg="obtained new client token: AADytt/q3ta4j4VgHobHuXG5fsgrFmfPFbQceagcZ0LmaE6E+2+T9qvtN/OZCqMUV6myLWGoB9VDqtw7gJcq6oPkWrW27gyiwLEUSLgHSI+ek68Vbr1rjAZuPn1sAjUVtdCV6RhpuEUQLiw0S1dB5SI8N/weOA31B3l6DwEjAwwlsxXDlvDXTePfRM2SrXivQqh8wh+vsY/6veSRdtxxS8taweDc4etFRKFwWUb0B8+dO/fjJ4TX" Oct 25 01:30:41 volumio go-librespot[31477]: time="2025-10-25T01:30:41+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 25 01:30:41 volumio go-librespot[31477]: time="2025-10-25T01:30:41+01:00" level=debug msg="completed keyexchange" Oct 25 01:30:41 volumio go-librespot[31477]: time="2025-10-25T01:30:41+01:00" level=debug msg="completed challenge" Oct 25 01:30:41 volumio go-librespot[31477]: time="2025-10-25T01:30:41+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 25 01:30:41 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 25 01:30:41 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 25 01:30:41 volumio volumio[30621]: info: Initializing connection to go-librespot Websocket Oct 25 01:30:41 volumio volumio[30621]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 25 01:30:44 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1309. Oct 25 01:30:44 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 25 01:30:44 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 25 01:30:44 volumio go-librespot[31487]: go-librespot daemon starting... Oct 25 01:30:44 volumio go-librespot[31488]: time="2025-10-25T01:30:44+01:00" level=info msg="running go-librespot 0.4.0" Oct 25 01:30:44 volumio go-librespot[31488]: time="2025-10-25T01:30:44+01:00" level=debug msg="app state loaded" Oct 25 01:30:44 volumio go-librespot[31488]: time="2025-10-25T01:30:44+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 25 01:30:44 volumio go-librespot[31488]: time="2025-10-25T01:30:44+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 25 01:30:44 volumio go-librespot[31488]: time="2025-10-25T01:30:44+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 25 01:30:44 volumio go-librespot[31488]: time="2025-10-25T01:30:44+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 25 01:30:44 volumio go-librespot[31488]: time="2025-10-25T01:30:44+01:00" level=info msg="zeroconf server listening on port 46785" Oct 25 01:30:44 volumio go-librespot[31488]: time="2025-10-25T01:30:44+01:00" level=debug msg="obtained new client token: AAAo5XBco8nzXMK7tPqxSL/++eQzXyCoPYxSjx/x5PZa9IC1N1c+fvOhCZHP0CR1GwHrziBTvESyNoUko+ldUJ55H3RDZ8sujxXjLvdNtI5hElhpvqLo9hj/UD3iKv9fNfJLzzbPLyYK6jdDKOhSC62RMmtBHd0au6w7waSHdJMs0AtpgbPNgcC8LHilewyz0f9/tKBnlNh6ieeJkkGmDphqI9VpqJXJLdUX1vaoW3SGCZkNQAmdjw0=" Oct 25 01:30:44 volumio go-librespot[31488]: time="2025-10-25T01:30:44+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 25 01:30:44 volumio go-librespot[31488]: time="2025-10-25T01:30:44+01:00" level=debug msg="completed keyexchange" Oct 25 01:30:44 volumio go-librespot[31488]: time="2025-10-25T01:30:44+01:00" level=debug msg="completed challenge" Oct 25 01:30:44 volumio go-librespot[31488]: time="2025-10-25T01:30:44+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 25 01:30:44 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 25 01:30:44 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 25 01:30:44 volumio volumio[30621]: info: Initializing connection to go-librespot Websocket Oct 25 01:30:44 volumio volumio[30621]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 25 01:30:47 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1310. Oct 25 01:30:47 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 25 01:30:47 volumio volumio[30621]: info: Initializing connection to go-librespot Websocket Oct 25 01:30:47 volumio volumio[30621]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 25 01:30:47 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 25 01:30:47 volumio go-librespot[31509]: go-librespot daemon starting... Oct 25 01:30:47 volumio go-librespot[31510]: time="2025-10-25T01:30:47+01:00" level=info msg="running go-librespot 0.4.0" Oct 25 01:30:47 volumio go-librespot[31510]: time="2025-10-25T01:30:47+01:00" level=debug msg="app state loaded" Oct 25 01:30:47 volumio go-librespot[31510]: time="2025-10-25T01:30:47+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 25 01:30:48 volumio go-librespot[31510]: time="2025-10-25T01:30:48+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 25 01:30:48 volumio go-librespot[31510]: time="2025-10-25T01:30:48+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 25 01:30:48 volumio go-librespot[31510]: time="2025-10-25T01:30:48+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 25 01:30:48 volumio go-librespot[31510]: time="2025-10-25T01:30:48+01:00" level=info msg="zeroconf server listening on port 41539" Oct 25 01:30:48 volumio go-librespot[31510]: time="2025-10-25T01:30:48+01:00" level=debug msg="obtained new client token: AACH9IV79qNO8GOlnmLBQO531j3hwa4U44xY3Ur3O6XVT1Rz33GFHjaDhIdmM5QhYe1V9i9kAqLWv4oNhnKofgPQ/x0inZLEwjBuSr20aYTUBKhJunpPg8faSIKdNQ9Cb5Rh4lduF9FNCkGUlXPMuS2JaUh00F3I7VN9wxrXRJTMSUIlv1JPU+oqjkFi2O1YdcqKLzeKVXYXhW3hvz87gvBvlOdMFj5fYD6bk7LfRGTMqZfNmdVw" Oct 25 01:30:48 volumio go-librespot[31510]: time="2025-10-25T01:30:48+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 25 01:30:48 volumio go-librespot[31510]: time="2025-10-25T01:30:48+01:00" level=debug msg="completed keyexchange" Oct 25 01:30:48 volumio go-librespot[31510]: time="2025-10-25T01:30:48+01:00" level=debug msg="completed challenge" Oct 25 01:30:48 volumio go-librespot[31510]: time="2025-10-25T01:30:48+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 25 01:30:48 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 25 01:30:48 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 25 01:30:50 volumio volumio[30621]: info: Initializing connection to go-librespot Websocket Oct 25 01:30:50 volumio volumio[30621]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 25 01:30:51 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1311. Oct 25 01:30:51 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 25 01:30:51 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 25 01:30:51 volumio go-librespot[31517]: go-librespot daemon starting... Oct 25 01:30:51 volumio go-librespot[31518]: time="2025-10-25T01:30:51+01:00" level=info msg="running go-librespot 0.4.0" Oct 25 01:30:51 volumio go-librespot[31518]: time="2025-10-25T01:30:51+01:00" level=debug msg="app state loaded" Oct 25 01:30:51 volumio go-librespot[31518]: time="2025-10-25T01:30:51+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 25 01:30:51 volumio go-librespot[31518]: time="2025-10-25T01:30:51+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 25 01:30:51 volumio go-librespot[31518]: time="2025-10-25T01:30:51+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 25 01:30:51 volumio go-librespot[31518]: time="2025-10-25T01:30:51+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 25 01:30:51 volumio go-librespot[31518]: time="2025-10-25T01:30:51+01:00" level=info msg="zeroconf server listening on port 34585" Oct 25 01:30:51 volumio go-librespot[31518]: time="2025-10-25T01:30:51+01:00" level=debug msg="obtained new client token: AAAMfXPjmaz1Issf7YGrt6WOf93TCAnHi1JRmbaDSr6SvMFvephSqNG2NX3EZEUrbJrAtQ+wCuRNBXDijzoLD6ZMCCJyc2R8SpdElHV/WdZru5EpbZibP0JwklBIjl1jnhAUkvo2a19q6o41Glg/bhVGW7QFN8Ya0bAaBMsmY+WYB8fk1tW82BY6qBHuKHffSL4FORbexbXaeRafPl+fjXw4ch4LfNa8pxXzCYHPBtEQFEcdwIgoaKw=" Oct 25 01:30:51 volumio go-librespot[31518]: time="2025-10-25T01:30:51+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 25 01:30:51 volumio go-librespot[31518]: time="2025-10-25T01:30:51+01:00" level=debug msg="completed keyexchange" Oct 25 01:30:51 volumio go-librespot[31518]: time="2025-10-25T01:30:51+01:00" level=debug msg="completed challenge" Oct 25 01:30:51 volumio go-librespot[31518]: time="2025-10-25T01:30:51+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 25 01:30:51 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 25 01:30:51 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 25 01:30:53 volumio volumio[30621]: info: Initializing connection to go-librespot Websocket Oct 25 01:30:53 volumio volumio[30621]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 25 01:30:54 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1312. Oct 25 01:30:54 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 25 01:30:54 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 25 01:30:54 volumio go-librespot[31526]: go-librespot daemon starting... Oct 25 01:30:54 volumio go-librespot[31527]: time="2025-10-25T01:30:54+01:00" level=info msg="running go-librespot 0.4.0" Oct 25 01:30:54 volumio go-librespot[31527]: time="2025-10-25T01:30:54+01:00" level=debug msg="app state loaded" Oct 25 01:30:54 volumio go-librespot[31527]: time="2025-10-25T01:30:54+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 25 01:30:55 volumio go-librespot[31527]: time="2025-10-25T01:30:55+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 25 01:30:55 volumio go-librespot[31527]: time="2025-10-25T01:30:55+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 25 01:30:55 volumio go-librespot[31527]: time="2025-10-25T01:30:55+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 25 01:30:55 volumio go-librespot[31527]: time="2025-10-25T01:30:55+01:00" level=info msg="zeroconf server listening on port 44191" Oct 25 01:30:55 volumio go-librespot[31527]: time="2025-10-25T01:30:55+01:00" level=debug msg="obtained new client token: AAAswKX+gWEwmunRKyflln1gYstCE2lm8KNeyEMy38mNCHzyJ15dFO0cT+2CJf4TpkpiJt97WWOBWw7jbM99iAh+F7HRTh4tGADuHL7cY8MRd5xZqH/Y8LeLxFu6fb3yGVzyhNd1cFCfiIcEBCWs2b1aZLTaEWCjyd+1BTWhTi9prrb6EFkK2naXuLaj8I/RFr4Eszl8CBGod5LkvHNN9MQmWNkaJatgmpRv4CfsvunRtZYCEQ5r" Oct 25 01:30:55 volumio go-librespot[31527]: time="2025-10-25T01:30:55+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 25 01:30:55 volumio go-librespot[31527]: time="2025-10-25T01:30:55+01:00" level=debug msg="completed keyexchange" Oct 25 01:30:55 volumio go-librespot[31527]: time="2025-10-25T01:30:55+01:00" level=debug msg="completed challenge" Oct 25 01:30:55 volumio go-librespot[31527]: time="2025-10-25T01:30:55+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 25 01:30:55 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 25 01:30:55 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 25 01:30:56 volumio volumio[30621]: info: Initializing connection to go-librespot Websocket Oct 25 01:30:56 volumio volumio[30621]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 25 01:30:58 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1313. Oct 25 01:30:58 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 25 01:30:58 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 25 01:30:58 volumio go-librespot[31548]: go-librespot daemon starting... Oct 25 01:30:58 volumio go-librespot[31549]: time="2025-10-25T01:30:58+01:00" level=info msg="running go-librespot 0.4.0" Oct 25 01:30:58 volumio go-librespot[31549]: time="2025-10-25T01:30:58+01:00" level=debug msg="app state loaded" Oct 25 01:30:58 volumio go-librespot[31549]: time="2025-10-25T01:30:58+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 25 01:30:58 volumio go-librespot[31549]: time="2025-10-25T01:30:58+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 25 01:30:58 volumio go-librespot[31549]: time="2025-10-25T01:30:58+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 25 01:30:58 volumio go-librespot[31549]: time="2025-10-25T01:30:58+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 25 01:30:58 volumio go-librespot[31549]: time="2025-10-25T01:30:58+01:00" level=info msg="zeroconf server listening on port 43727" Oct 25 01:30:58 volumio go-librespot[31549]: time="2025-10-25T01:30:58+01:00" level=debug msg="obtained new client token: AACmr5WRK0XfbsP2c0K1eMyZRsixakOEV9IBBfzYNWaqYmfZP3+9kL479aO8UvbU7dI8Vw4zlOKjAEmDV+S076WUA1p5UutD6XBMp+mFIisKJYNh94Y+ODiIGH9W3cdq4UcWnQWpoAKTLsJ+XMfnTlOrLDUoH6dfWVJgX2bJHO4bzQs/1LcYRYFtKl8tc963mdwGuqH6p13nl5+QUuC6xfaZCuzLZlCIdhXL+YB1DSk5iP2ftI2bI40=" Oct 25 01:30:58 volumio go-librespot[31549]: time="2025-10-25T01:30:58+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 25 01:30:58 volumio go-librespot[31549]: time="2025-10-25T01:30:58+01:00" level=debug msg="completed keyexchange" Oct 25 01:30:58 volumio go-librespot[31549]: time="2025-10-25T01:30:58+01:00" level=debug msg="completed challenge" Oct 25 01:30:58 volumio go-librespot[31549]: time="2025-10-25T01:30:58+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 25 01:30:58 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 25 01:30:58 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 25 01:30:59 volumio volumio[30621]: info: Initializing connection to go-librespot Websocket Oct 25 01:30:59 volumio volumio[30621]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 25 01:31:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1314. Oct 25 01:31:01 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 25 01:31:01 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 25 01:31:01 volumio go-librespot[31556]: go-librespot daemon starting... Oct 25 01:31:01 volumio go-librespot[31557]: time="2025-10-25T01:31:01+01:00" level=info msg="running go-librespot 0.4.0" Oct 25 01:31:01 volumio go-librespot[31557]: time="2025-10-25T01:31:01+01:00" level=debug msg="app state loaded" Oct 25 01:31:01 volumio go-librespot[31557]: time="2025-10-25T01:31:01+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 25 01:31:02 volumio go-librespot[31557]: time="2025-10-25T01:31:02+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 25 01:31:02 volumio go-librespot[31557]: time="2025-10-25T01:31:02+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 25 01:31:02 volumio go-librespot[31557]: time="2025-10-25T01:31:02+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 25 01:31:02 volumio go-librespot[31557]: time="2025-10-25T01:31:02+01:00" level=info msg="zeroconf server listening on port 34923" Oct 25 01:31:02 volumio go-librespot[31557]: time="2025-10-25T01:31:02+01:00" level=debug msg="obtained new client token: AAB3304zBNIKJkmcUkjwPCh/7WQ+Bt5YbpP3LFcCsPtjuOmsGk0o7PIdI5sixg3Z6M2e6qQKyZ4EUYqtxk/cQ1RXqcxp1zS0iuS6p1tFkOsjlYXB9Hbr2U5x0HOYQrsFGG8U/BbqNlBXPcT9uJXo8H84NatQJag6m40gTckPiM9TZVbX9ffEwiqQcnEquzxdSecYQPffMvgsk995y3P+7lqQvweO5Z16WDCSFtz9+66G8Fw5QzeC" Oct 25 01:31:02 volumio go-librespot[31557]: time="2025-10-25T01:31:02+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 25 01:31:02 volumio go-librespot[31557]: time="2025-10-25T01:31:02+01:00" level=debug msg="completed keyexchange" Oct 25 01:31:02 volumio go-librespot[31557]: time="2025-10-25T01:31:02+01:00" level=debug msg="completed challenge" Oct 25 01:31:02 volumio go-librespot[31557]: time="2025-10-25T01:31:02+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 25 01:31:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 25 01:31:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 25 01:31:02 volumio volumio[30621]: info: Initializing connection to go-librespot Websocket Oct 25 01:31:02 volumio volumio[30621]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 25 01:31:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1315. Oct 25 01:31:05 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 25 01:31:05 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 25 01:31:05 volumio go-librespot[31567]: go-librespot daemon starting... Oct 25 01:31:05 volumio go-librespot[31568]: time="2025-10-25T01:31:05+01:00" level=info msg="running go-librespot 0.4.0" Oct 25 01:31:05 volumio go-librespot[31568]: time="2025-10-25T01:31:05+01:00" level=debug msg="app state loaded" Oct 25 01:31:05 volumio go-librespot[31568]: time="2025-10-25T01:31:05+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 25 01:31:05 volumio go-librespot[31568]: time="2025-10-25T01:31:05+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 25 01:31:05 volumio go-librespot[31568]: time="2025-10-25T01:31:05+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 25 01:31:05 volumio go-librespot[31568]: time="2025-10-25T01:31:05+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 25 01:31:05 volumio go-librespot[31568]: time="2025-10-25T01:31:05+01:00" level=info msg="zeroconf server listening on port 38281" Oct 25 01:31:05 volumio go-librespot[31568]: time="2025-10-25T01:31:05+01:00" level=debug msg="obtained new client token: AADM/LvNNmuviDLDEjM9jRVR+gUb+qSnbn7h4yrHtU5ZmwWIuo8JgBYXcay3hxFd1M36j8gVG+rbc9cx/5n5DChGoOWGlijCrEVMpUvXektCDWCv/GmdUQo68sz5so17L58zWPKtxglGjge/r3SQ2l8Kf88lBFdQAhHkWfDKx2gNdZiFK7dSuJ/V7hmVPzuDDRfAnAFhOX0jaP9pSADe7Ik293QhTPNyzUtvo0Dr186iGdx9e4eKL28=" Oct 25 01:31:05 volumio go-librespot[31568]: time="2025-10-25T01:31:05+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 25 01:31:05 volumio go-librespot[31568]: time="2025-10-25T01:31:05+01:00" level=debug msg="completed keyexchange" Oct 25 01:31:05 volumio go-librespot[31568]: time="2025-10-25T01:31:05+01:00" level=debug msg="completed challenge" Oct 25 01:31:05 volumio go-librespot[31568]: time="2025-10-25T01:31:05+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 25 01:31:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 25 01:31:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 25 01:31:05 volumio volumio[30621]: info: Initializing connection to go-librespot Websocket Oct 25 01:31:05 volumio volumio[30621]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 25 01:31:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1316. Oct 25 01:31:08 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 25 01:31:08 volumio volumio[30621]: info: Initializing connection to go-librespot Websocket Oct 25 01:31:08 volumio volumio[30621]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 25 01:31:08 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 25 01:31:08 volumio go-librespot[31589]: go-librespot daemon starting... Oct 25 01:31:08 volumio go-librespot[31590]: time="2025-10-25T01:31:08+01:00" level=info msg="running go-librespot 0.4.0" Oct 25 01:31:08 volumio go-librespot[31590]: time="2025-10-25T01:31:08+01:00" level=debug msg="app state loaded" Oct 25 01:31:08 volumio go-librespot[31590]: time="2025-10-25T01:31:08+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 25 01:31:09 volumio go-librespot[31590]: time="2025-10-25T01:31:09+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 25 01:31:09 volumio go-librespot[31590]: time="2025-10-25T01:31:09+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 25 01:31:09 volumio go-librespot[31590]: time="2025-10-25T01:31:09+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 25 01:31:09 volumio go-librespot[31590]: time="2025-10-25T01:31:09+01:00" level=info msg="zeroconf server listening on port 39763" Oct 25 01:31:09 volumio go-librespot[31590]: time="2025-10-25T01:31:09+01:00" level=debug msg="obtained new client token: AAAsSHItto95pEsdm2gz9VyCd8u5dslLn3AGeYH9D/iFGy6R8ARU9ehkcdSyX5V4DhEjmGJClJV+Ol7Q0buf/c9egnf4u4fcKZDx/31Q/HPtxNxBCAEgb1VA5bCnEWSA2LBk9Zvf6qawGx0lkU+sHBOa1Vb0zzRuoO9C7dB726PUs0nL2OsvNzWG2ewRoZruOBIJaKvC9pRPZ2dT4v5zeeFmf4DJfDCOXUhaa0YyhPVTh1Of14VC" Oct 25 01:31:09 volumio go-librespot[31590]: time="2025-10-25T01:31:09+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 25 01:31:09 volumio go-librespot[31590]: time="2025-10-25T01:31:09+01:00" level=debug msg="completed keyexchange" Oct 25 01:31:09 volumio go-librespot[31590]: time="2025-10-25T01:31:09+01:00" level=debug msg="completed challenge" Oct 25 01:31:09 volumio go-librespot[31590]: time="2025-10-25T01:31:09+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 25 01:31:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 25 01:31:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 25 01:31:11 volumio volumio[30621]: info: Initializing connection to go-librespot Websocket Oct 25 01:31:11 volumio volumio[30621]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 25 01:31:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1317. Oct 25 01:31:12 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 25 01:31:12 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 25 01:31:12 volumio go-librespot[31597]: go-librespot daemon starting... Oct 25 01:31:12 volumio go-librespot[31598]: time="2025-10-25T01:31:12+01:00" level=info msg="running go-librespot 0.4.0" Oct 25 01:31:12 volumio go-librespot[31598]: time="2025-10-25T01:31:12+01:00" level=debug msg="app state loaded" Oct 25 01:31:12 volumio go-librespot[31598]: time="2025-10-25T01:31:12+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 25 01:31:12 volumio go-librespot[31598]: time="2025-10-25T01:31:12+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 25 01:31:12 volumio go-librespot[31598]: time="2025-10-25T01:31:12+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 25 01:31:12 volumio go-librespot[31598]: time="2025-10-25T01:31:12+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 25 01:31:12 volumio go-librespot[31598]: time="2025-10-25T01:31:12+01:00" level=info msg="zeroconf server listening on port 34065" Oct 25 01:31:12 volumio go-librespot[31598]: time="2025-10-25T01:31:12+01:00" level=debug msg="obtained new client token: AACDuJX3m6TtSMf4WPx6hLTdQ68cb+JWZZkG8Rl0ZeK/bXgz/anrinqoWMI4yHrfvYWI0+jSTx9/XNQUyKDDNkJ2BSm7+WBVnQGz0LnFPoHdYRw0Dh19zWOZ9niRBuLe70O/uvKnsBkd4IkwrfZt0SQxCDK7uzQgyhfj44bsmsDZ2ta/0BkVWoPtcc3Mor/FgDvALuJc9Jc2vjGjBDEpeYhIN9I5IydVtGzOzrrb2eewSkwcWSFZLrU=" Oct 25 01:31:12 volumio go-librespot[31598]: time="2025-10-25T01:31:12+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 25 01:31:12 volumio go-librespot[31598]: time="2025-10-25T01:31:12+01:00" level=debug msg="completed keyexchange" Oct 25 01:31:12 volumio go-librespot[31598]: time="2025-10-25T01:31:12+01:00" level=debug msg="completed challenge" Oct 25 01:31:12 volumio go-librespot[31598]: time="2025-10-25T01:31:12+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 25 01:31:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 25 01:31:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 25 01:31:13 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Oct 25 01:31:14 volumio volumio[30621]: info: Initializing connection to go-librespot Websocket Oct 25 01:31:14 volumio volumio[30621]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 25 01:31:15 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1318. Oct 25 01:31:15 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 25 01:31:15 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 25 01:31:15 volumio go-librespot[31607]: go-librespot daemon starting... Oct 25 01:31:15 volumio go-librespot[31608]: time="2025-10-25T01:31:15+01:00" level=info msg="running go-librespot 0.4.0" Oct 25 01:31:15 volumio go-librespot[31608]: time="2025-10-25T01:31:15+01:00" level=debug msg="app state loaded" Oct 25 01:31:15 volumio go-librespot[31608]: time="2025-10-25T01:31:15+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 25 01:31:16 volumio go-librespot[31608]: time="2025-10-25T01:31:16+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 25 01:31:16 volumio go-librespot[31608]: time="2025-10-25T01:31:16+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 25 01:31:16 volumio go-librespot[31608]: time="2025-10-25T01:31:16+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 25 01:31:16 volumio go-librespot[31608]: time="2025-10-25T01:31:16+01:00" level=info msg="zeroconf server listening on port 37887" Oct 25 01:31:16 volumio go-librespot[31608]: time="2025-10-25T01:31:16+01:00" level=debug msg="obtained new client token: AABgq8m6nM+AJuM0WuhWq+WUnCw1oAYx2NpxDc6rD6QFdY7MLScl7lIcrMKcneTWCVJhPJw3uZLYfQEN6Ddb07oETBUvvjpJU3NUX3OvNxaUhI1u6t1y+x0UcEiUmb8e9jP6KxwVxwHN9ylhhW30rp+eBBTuv5hM4p3gPzdwQAWBBHcGN8XqLe2VBU46lPj1icdk/JAUMxmRFMH42dPPREqUA99jPZyJs5cz9wvjRYQebPa5BgP6" Oct 25 01:31:16 volumio go-librespot[31608]: time="2025-10-25T01:31:16+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 25 01:31:16 volumio go-librespot[31608]: time="2025-10-25T01:31:16+01:00" level=debug msg="completed keyexchange" Oct 25 01:31:16 volumio go-librespot[31608]: time="2025-10-25T01:31:16+01:00" level=debug msg="completed challenge" Oct 25 01:31:16 volumio go-librespot[31608]: time="2025-10-25T01:31:16+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 25 01:31:16 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 25 01:31:16 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 25 01:31:17 volumio volumio[30621]: info: Initializing connection to go-librespot Websocket Oct 25 01:31:17 volumio volumio[30621]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 25 01:31:18 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Oct 25 01:31:19 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1319. Oct 25 01:31:19 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 25 01:31:19 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 25 01:31:19 volumio go-librespot[31629]: go-librespot daemon starting... Oct 25 01:31:19 volumio go-librespot[31630]: time="2025-10-25T01:31:19+01:00" level=info msg="running go-librespot 0.4.0" Oct 25 01:31:19 volumio go-librespot[31630]: time="2025-10-25T01:31:19+01:00" level=debug msg="app state loaded" Oct 25 01:31:19 volumio go-librespot[31630]: time="2025-10-25T01:31:19+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 25 01:31:19 volumio go-librespot[31630]: time="2025-10-25T01:31:19+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 25 01:31:19 volumio go-librespot[31630]: time="2025-10-25T01:31:19+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 25 01:31:19 volumio go-librespot[31630]: time="2025-10-25T01:31:19+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 25 01:31:19 volumio go-librespot[31630]: time="2025-10-25T01:31:19+01:00" level=info msg="zeroconf server listening on port 33257" Oct 25 01:31:19 volumio go-librespot[31630]: time="2025-10-25T01:31:19+01:00" level=debug msg="obtained new client token: AAAXEXzd46r3CYtU04Fk6zSF6pZfQJl6e3YUI6KJZ09Ig40BPDykDWSLr21y6z/4nHTCpLKOaS5gOUqBFN+iDgVoHsvyC+wFMjtKeYGFFZgsXUoj6wSialI4mYyWIEiGN4K/9RjBVo8OYhXPbTFD9gvyNqf9egFQiVSFI7M6Lv1R0cNArYM7gR8lyMYm3T3pscYcPGLVNbYamT1OSVq2vLZRtuFxa5q0N1c55vYhdAG8MqFNeLhJfn0=" Oct 25 01:31:19 volumio go-librespot[31630]: time="2025-10-25T01:31:19+01:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.65.9:4070: connect: connection refused" Oct 25 01:31:19 volumio go-librespot[31630]: time="2025-10-25T01:31:19+01:00" level=debug msg="connected to ap-gew1.spotify.com:443" Oct 25 01:31:19 volumio go-librespot[31630]: time="2025-10-25T01:31:19+01:00" level=debug msg="completed keyexchange" Oct 25 01:31:19 volumio go-librespot[31630]: time="2025-10-25T01:31:19+01:00" level=debug msg="completed challenge" Oct 25 01:31:19 volumio go-librespot[31630]: time="2025-10-25T01:31:19+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 25 01:31:19 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 25 01:31:19 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 25 01:31:20 volumio volumio[30621]: info: Initializing connection to go-librespot Websocket Oct 25 01:31:20 volumio volumio[30621]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 25 01:31:22 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1320. Oct 25 01:31:22 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 25 01:31:22 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 25 01:31:22 volumio go-librespot[31637]: go-librespot daemon starting... Oct 25 01:31:22 volumio go-librespot[31638]: time="2025-10-25T01:31:22+01:00" level=info msg="running go-librespot 0.4.0" Oct 25 01:31:22 volumio go-librespot[31638]: time="2025-10-25T01:31:22+01:00" level=debug msg="app state loaded" Oct 25 01:31:22 volumio go-librespot[31638]: time="2025-10-25T01:31:22+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 25 01:31:23 volumio go-librespot[31638]: time="2025-10-25T01:31:23+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 25 01:31:23 volumio go-librespot[31638]: time="2025-10-25T01:31:23+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 25 01:31:23 volumio go-librespot[31638]: time="2025-10-25T01:31:23+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 25 01:31:23 volumio go-librespot[31638]: time="2025-10-25T01:31:23+01:00" level=info msg="zeroconf server listening on port 36863" Oct 25 01:31:23 volumio go-librespot[31638]: time="2025-10-25T01:31:23+01:00" level=debug msg="obtained new client token: AABxw1MP5pHopKtT2N17sBrvD9tWSpihEyHbPwfx6g3EmSQoMfndpfLEU1wsqU2TlXnqUbymkq49gGlxDdmaxXfHPfiEcr1l38e+l31aIBDFufcx6Mn0czhFaQP6iTtcNXP52xpyjdSXJbd7xwqklyawafVadeFuzKkVKLTWSJQ01Lt484Igk4sL28QREP5UR4Roi6FU0JfFBjc1ACMV+nTSzQ0cc0o3F+x2VuB888hVuf5shxZE" Oct 25 01:31:23 volumio go-librespot[31638]: time="2025-10-25T01:31:23+01:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.65.9:4070: connect: connection refused" Oct 25 01:31:23 volumio go-librespot[31638]: time="2025-10-25T01:31:23+01:00" level=debug msg="connected to ap-gew1.spotify.com:443" Oct 25 01:31:23 volumio go-librespot[31638]: time="2025-10-25T01:31:23+01:00" level=debug msg="completed keyexchange" Oct 25 01:31:23 volumio go-librespot[31638]: time="2025-10-25T01:31:23+01:00" level=debug msg="completed challenge" Oct 25 01:31:23 volumio go-librespot[31638]: time="2025-10-25T01:31:23+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 25 01:31:23 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 25 01:31:23 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 25 01:31:23 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Oct 25 01:31:23 volumio volumio[30621]: info: Initializing connection to go-librespot Websocket Oct 25 01:31:23 volumio volumio[30621]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 25 01:31:26 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1321. Oct 25 01:31:26 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 25 01:31:26 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 25 01:31:26 volumio go-librespot[31648]: go-librespot daemon starting... Oct 25 01:31:26 volumio go-librespot[31649]: time="2025-10-25T01:31:26+01:00" level=info msg="running go-librespot 0.4.0" Oct 25 01:31:26 volumio go-librespot[31649]: time="2025-10-25T01:31:26+01:00" level=debug msg="app state loaded" Oct 25 01:31:26 volumio go-librespot[31649]: time="2025-10-25T01:31:26+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 25 01:31:26 volumio go-librespot[31649]: time="2025-10-25T01:31:26+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 25 01:31:26 volumio go-librespot[31649]: time="2025-10-25T01:31:26+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 25 01:31:26 volumio go-librespot[31649]: time="2025-10-25T01:31:26+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 25 01:31:26 volumio go-librespot[31649]: time="2025-10-25T01:31:26+01:00" level=info msg="zeroconf server listening on port 35423" Oct 25 01:31:26 volumio go-librespot[31649]: time="2025-10-25T01:31:26+01:00" level=debug msg="obtained new client token: AACiDVjxlUp4QLJgE+sZHoDiLt/A2tAaI7qs6PIEkeEAIqj+0V2depH6uJkWS91T56wEZrKw7lBiC9hvzXzzjKq2EkxaRiC0dYjTfBdd5u8TCz4RapLTYy38JWlRmmmB5er/jCM+Qem9nXis/O5pytg9XMWpDmEe1uozJGqg261/T2aOfUzg1puYw6UKZMIfsF7GHYUEufntlZ54EaTevrcmloJX8nMTZkNXfrv5yHvQt5pfwZ4WEv0=" Oct 25 01:31:26 volumio go-librespot[31649]: time="2025-10-25T01:31:26+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 25 01:31:26 volumio go-librespot[31649]: time="2025-10-25T01:31:26+01:00" level=debug msg="completed keyexchange" Oct 25 01:31:26 volumio go-librespot[31649]: time="2025-10-25T01:31:26+01:00" level=debug msg="completed challenge" Oct 25 01:31:26 volumio go-librespot[31649]: time="2025-10-25T01:31:26+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 25 01:31:26 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 25 01:31:26 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 25 01:31:26 volumio volumio[30621]: info: Initializing connection to go-librespot Websocket Oct 25 01:31:26 volumio volumio[30621]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 25 01:31:28 volumio kernel: nfs: server 192.168.1.3 not responding, timed out Oct 25 01:31:29 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1322. Oct 25 01:31:29 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 25 01:31:29 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 25 01:31:29 volumio go-librespot[31670]: go-librespot daemon starting... Oct 25 01:31:29 volumio go-librespot[31671]: time="2025-10-25T01:31:29+01:00" level=info msg="running go-librespot 0.4.0" Oct 25 01:31:29 volumio go-librespot[31671]: time="2025-10-25T01:31:29+01:00" level=debug msg="app state loaded" Oct 25 01:31:29 volumio go-librespot[31671]: time="2025-10-25T01:31:29+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 25 01:31:29 volumio volumio[30621]: info: Initializing connection to go-librespot Websocket Oct 25 01:31:29 volumio go-librespot[31671]: time="2025-10-25T01:31:29+01:00" level=debug msg="new websocket client" Oct 25 01:31:29 volumio volumio[30621]: info: Connection to go-librespot Websocket established Oct 25 01:31:30 volumio go-librespot[31671]: time="2025-10-25T01:31:30+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Oct 25 01:31:30 volumio go-librespot[31671]: time="2025-10-25T01:31:30+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 25 01:31:30 volumio go-librespot[31671]: time="2025-10-25T01:31:30+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 25 01:31:30 volumio go-librespot[31671]: time="2025-10-25T01:31:30+01:00" level=info msg="zeroconf server listening on port 33583" Oct 25 01:31:30 volumio go-librespot[31671]: time="2025-10-25T01:31:30+01:00" level=debug msg="obtained new client token: AABuLdCtCB0IYzxILezC2PqnLBmimLRX+/OxNVYaCDrCZyUKTFQJ/xzvIUa5OxpyjMe6zNbcp5chBYaSokse3NCI7v7lTOjQWWxmNJOktNcrWBTQwGd6Uf0GK2OUktnSIJxIe1iRnJGWUCF+bukidZ1zflOF3tnULI21pdI+NNodhINPnC+Mm1Y3vH3/Z/e0SZDc9QCZHBrKwEUS9ewL3uQnL7RwaRGBXJxq9h0WDUk7ECtmOO3n" Oct 25 01:31:30 volumio go-librespot[31671]: time="2025-10-25T01:31:30+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 25 01:31:30 volumio go-librespot[31671]: time="2025-10-25T01:31:30+01:00" level=debug msg="completed keyexchange" Oct 25 01:31:30 volumio go-librespot[31671]: time="2025-10-25T01:31:30+01:00" level=debug msg="completed challenge" Oct 25 01:31:30 volumio go-librespot[31671]: time="2025-10-25T01:31:30+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Oct 25 01:31:30 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 25 01:31:30 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 25 01:31:30 volumio volumio[30621]: info: Connection to go-librespot Websocket closed Oct 25 01:31:32 volumio volumio[30621]: info: Getting Spotify volume Oct 25 01:31:32 volumio volumio[30621]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 25 01:31:32 volumio volumio[30621]: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 25 01:31:32 volumio volumio[30621]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Oct 25 01:31:32 volumio volumio[30621]: errno: -111, Oct 25 01:31:32 volumio volumio[30621]: code: 'ECONNREFUSED', Oct 25 01:31:32 volumio volumio[30621]: syscall: 'connect', Oct 25 01:31:32 volumio volumio[30621]: address: '127.0.0.1', Oct 25 01:31:32 volumio volumio[30621]: port: 9879, Oct 25 01:31:32 volumio volumio[30621]: response: undefined Oct 25 01:31:32 volumio volumio[30621]: } Oct 25 01:31:32 volumio volumio[30621]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 25 01:31:33 volumio sudo[31693]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-10-25 01:30' Oct 25 01:31:33 volumio sudo[31693]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="c22b72a8acb34a699a626adb0a09a6b88ee7233a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="40400d6a6fa57cf20e36499e510876b3654e9c4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Oct 4 16:40:08 UTC 2025" VOLUMIO_VERSION="4.061" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="7f8ace80645f85765fa10c72d1769d04"