Feb 25 03:25:00 yamaha go-librespot[12332]: go-librespot daemon starting... Feb 25 03:25:00 yamaha systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:25:00 yamaha go-librespot[12333]: time="2026-02-25T03:25:00+01:00" level=info msg="running go-librespot 0.6.2" Feb 25 03:25:00 yamaha go-librespot[12333]: time="2026-02-25T03:25:00+01:00" level=debug msg="app state loaded" Feb 25 03:25:00 yamaha go-librespot[12333]: time="2026-02-25T03:25:00+01:00" level=debug msg="stored credentials not found" Feb 25 03:25:00 yamaha volumio[12096]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Feb 25 03:25:00 yamaha go-librespot[12333]: time="2026-02-25T03:25:00+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 25 03:25:00 yamaha volumio[12096]: info: MPD Permissions set Feb 25 03:25:00 yamaha volumio[12096]: info: MPD Permissions set Feb 25 03:25:00 yamaha volumio[12096]: info: Volumio called home Feb 25 03:25:00 yamaha volumio[12096]: info: Spotify config file written Feb 25 03:25:00 yamaha sudo[12349]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 25 03:25:00 yamaha sudo[12349]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:25:00 yamaha go-librespot[12333]: time="2026-02-25T03:25:00+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 25 03:25:00 yamaha go-librespot[12333]: time="2026-02-25T03:25:00+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 25 03:25:00 yamaha go-librespot[12333]: time="2026-02-25T03:25:00+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 25 03:25:00 yamaha go-librespot[12333]: time="2026-02-25T03:25:00+01:00" level=info msg="zeroconf server listening on port 42001" Feb 25 03:25:00 yamaha systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Feb 25 03:25:00 yamaha systemd[1]: go-librespot-daemon.service: Deactivated successfully. Feb 25 03:25:00 yamaha systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:25:00 yamaha systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:25:00 yamaha go-librespot[12351]: go-librespot daemon starting... Feb 25 03:25:00 yamaha sudo[12349]: pam_unix(sudo:session): session closed for user root Feb 25 03:25:01 yamaha go-librespot[12353]: time="2026-02-25T03:25:01+01:00" level=info msg="running go-librespot 0.6.2" Feb 25 03:25:01 yamaha go-librespot[12353]: time="2026-02-25T03:25:01+01:00" level=debug msg="app state loaded" Feb 25 03:25:01 yamaha go-librespot[12353]: time="2026-02-25T03:25:01+01:00" level=debug msg="stored credentials not found" Feb 25 03:25:01 yamaha go-librespot[12353]: time="2026-02-25T03:25:01+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 25 03:25:01 yamaha volumio[12096]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Feb 25 03:25:01 yamaha volumio[12096]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:25:01 yamaha volumio[12096]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:25:01 yamaha volumio[12096]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:25:01 yamaha volumio[12096]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:25:01 yamaha volumio[12096]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:25:01 yamaha volumio[12096]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:25:01 yamaha volumio[12096]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:25:01 yamaha volumio[12096]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:25:01 yamaha volumio[12096]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:25:01 yamaha volumio[12096]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:25:01 yamaha volumio[12096]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:25:01 yamaha volumio[12096]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:25:01 yamaha volumio[12096]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:25:01 yamaha volumio[12096]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:25:01 yamaha volumio[12096]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:25:01 yamaha volumio[12096]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:25:01 yamaha volumio[12096]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:25:01 yamaha volumio[12096]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:25:01 yamaha volumio[12096]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:25:01 yamaha volumio[12096]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:25:01 yamaha volumio[12096]: info: No need to fix Spotify hosts Feb 25 03:25:01 yamaha volumio[12096]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 3 Feb 25 03:25:01 yamaha go-librespot[12353]: time="2026-02-25T03:25:01+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 25 03:25:01 yamaha go-librespot[12353]: time="2026-02-25T03:25:01+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 25 03:25:01 yamaha go-librespot[12353]: time="2026-02-25T03:25:01+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 25 03:25:01 yamaha go-librespot[12353]: time="2026-02-25T03:25:01+01:00" level=info msg="zeroconf server listening on port 39085" Feb 25 03:25:01 yamaha volumio[12096]: info: New Spotify access tokenBQDDpHTHtC... Feb 25 03:25:01 yamaha volumio[12096]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 25 03:25:01 yamaha volumio[12096]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 3 Feb 25 03:25:01 yamaha volumio[12096]: info: Starting Shairport Sync Feb 25 03:25:01 yamaha go-librespot[12353]: time="2026-02-25T03:25:01+01:00" level=debug msg="obtained new client token: AACXteaOopMpJxaHJ8pWdT0VgAa6D1qkP+XnJt86J+af0TyJw5H2ta+6IjVJMpejrXcPnSF4vrVAvZu1uGB0jvK+d0PxV9ZcAnxCVxqh4xxhODXaPYm05KHzdL8CnvXCkJrIbsy85QI2Gr/Uz13uz0j/tgC6z32YwWsnkzCFxvulXV7FJFk9NB4kllKeC38c0glqMg7gW3qQRUDDu2JR0bN0BLbPKs0yRsqVb/xlcsxqByPHBVpNl9HK" Feb 25 03:25:01 yamaha volumio[12096]: info: Starting Shairport Sync Feb 25 03:25:01 yamaha volumio[12096]: info: Starting Shairport Sync Feb 25 03:25:01 yamaha go-librespot[12353]: time="2026-02-25T03:25:01+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 25 03:25:01 yamaha sudo[12374]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 25 03:25:01 yamaha sudo[12374]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:25:01 yamaha volumio[12096]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 3 Feb 25 03:25:01 yamaha go-librespot[12353]: time="2026-02-25T03:25:01+01:00" level=debug msg="completed keyexchange" Feb 25 03:25:01 yamaha go-librespot[12353]: time="2026-02-25T03:25:01+01:00" level=debug msg="completed challenge" Feb 25 03:25:01 yamaha sudo[12378]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 25 03:25:01 yamaha sudo[12378]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:25:01 yamaha go-librespot[12353]: time="2026-02-25T03:25:01+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:25:01 yamaha sudo[12376]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 25 03:25:01 yamaha sudo[12376]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:25:02 yamaha systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 25 03:25:02 yamaha systemd[1]: shairport-sync.service: Deactivated successfully. Feb 25 03:25:02 yamaha systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 25 03:25:02 yamaha systemd[1]: shairport-sync.service: Consumed 2.457s CPU time. Feb 25 03:25:02 yamaha systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 25 03:25:02 yamaha sudo[12378]: pam_unix(sudo:session): session closed for user root Feb 25 03:25:02 yamaha volumio[12096]: info: CoreCommandRouter::volumioGetState Feb 25 03:25:02 yamaha sudo[12376]: pam_unix(sudo:session): session closed for user root Feb 25 03:25:02 yamaha volumio[12096]: info: CorePlayQueue::getTrack 0 Feb 25 03:25:02 yamaha sudo[12374]: pam_unix(sudo:session): session closed for user root Feb 25 03:25:02 yamaha volumio[12096]: info: Shairport-Sync Started Feb 25 03:25:02 yamaha volumio[12096]: Error adding Membership: Error: addMembership EINVAL Feb 25 03:25:02 yamaha volumio[12096]: info: Shairport-Sync Started Feb 25 03:25:02 yamaha volumio[12096]: info: Shairport-Sync Started Feb 25 03:25:02 yamaha volumio[12096]: SPOTIFY: User informations: {"country":"IN","display_name":"struikjan","email":"jan.struik@icloud.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/struikjan"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/struikjan","id":"struikjan","images":[],"product":"free","type":"user","uri":"spotify:user:struikjan"} Feb 25 03:25:02 yamaha volumio[12096]: info: Spotify Successfully logged in Feb 25 03:25:02 yamaha volumio[12096]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 25 03:25:02 yamaha volumio[12096]: info: [1771986302326] CoreMusicLibrary::Adding element Spotify Feb 25 03:25:02 yamaha volumio[12096]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 25 03:25:02 yamaha volumio[12096]: Cannot find translation for source Calm Radio Feb 25 03:25:02 yamaha volumio[12096]: Cannot find translation for source 80s80s Radio Feb 25 03:25:02 yamaha volumio[12096]: Cannot find translation for source Spotify Feb 25 03:25:02 yamaha go-librespot[12353]: time="2026-02-25T03:25:02+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Feb 25 03:25:02 yamaha go-librespot[12353]: time="2026-02-25T03:25:02+01:00" level=debug msg="completed keyexchange" Feb 25 03:25:02 yamaha go-librespot[12353]: time="2026-02-25T03:25:02+01:00" level=debug msg="completed challenge" Feb 25 03:25:02 yamaha go-librespot[12353]: time="2026-02-25T03:25:02+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:25:03 yamaha go-librespot[12353]: time="2026-02-25T03:25:03+01:00" level=debug msg="connected to ap-gew4.spotify.com:80" Feb 25 03:25:03 yamaha go-librespot[12353]: time="2026-02-25T03:25:03+01:00" level=debug msg="completed keyexchange" Feb 25 03:25:03 yamaha go-librespot[12353]: time="2026-02-25T03:25:03+01:00" level=debug msg="completed challenge" Feb 25 03:25:03 yamaha go-librespot[12353]: time="2026-02-25T03:25:03+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:25:03 yamaha go-librespot[12353]: time="2026-02-25T03:25:03+01:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 25 03:25:03 yamaha go-librespot[12353]: time="2026-02-25T03:25:03+01:00" level=debug msg="completed keyexchange" Feb 25 03:25:03 yamaha go-librespot[12353]: time="2026-02-25T03:25:03+01:00" level=debug msg="completed challenge" Feb 25 03:25:03 yamaha go-librespot[12353]: time="2026-02-25T03:25:03+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:25:04 yamaha mpd[12316]: 2026-02-25T03:25:04 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 25 03:25:04 yamaha systemd[1]: Started mpd.service - Music Player Daemon. Feb 25 03:25:04 yamaha sudo[12286]: pam_unix(sudo:session): session closed for user root Feb 25 03:25:04 yamaha sudo[12274]: pam_unix(sudo:session): session closed for user root Feb 25 03:25:04 yamaha volumio[12096]: info: Completed starting Core Plugins Feb 25 03:25:04 yamaha volumio[12096]: info: ------------------------------------------- Feb 25 03:25:04 yamaha volumio[12096]: info: ----- MyVolumio plugins startup ---- Feb 25 03:25:04 yamaha volumio[12096]: info: ------------------------------------------- Feb 25 03:25:04 yamaha volumio[12096]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 25 03:25:04 yamaha volumio[12096]: info: go-librespot daemon successfully initialized Feb 25 03:25:04 yamaha go-librespot[12353]: time="2026-02-25T03:25:04+01:00" level=debug msg="connected to ap-gue1.spotify.com:443" Feb 25 03:25:04 yamaha go-librespot[12353]: time="2026-02-25T03:25:04+01:00" level=debug msg="completed keyexchange" Feb 25 03:25:04 yamaha go-librespot[12353]: time="2026-02-25T03:25:04+01:00" level=debug msg="completed challenge" Feb 25 03:25:04 yamaha volumio[12096]: error: MPD error: The expression evaluated to a falsy value: Feb 25 03:25:04 yamaha volumio[12096]: assert.ok(self.idling) Feb 25 03:25:04 yamaha volumio[12096]: error: The expression evaluated to a falsy value: Feb 25 03:25:04 yamaha volumio[12096]: assert.ok(self.idling) Feb 25 03:25:04 yamaha volumio[12096]: error: updateQueue error: null Feb 25 03:25:04 yamaha volumio[12096]: info: MPD running with PID12316 Feb 25 03:25:04 yamaha volumio[12096]: ,establishing connection Feb 25 03:25:04 yamaha volumio[12096]: error: updateQueue error: null Feb 25 03:25:04 yamaha go-librespot[12353]: time="2026-02-25T03:25:04+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:25:04 yamaha volumio[12096]: info: Cannot mount NAS Ugreen at system boot, trial number 4 ,retrying in 5 seconds Feb 25 03:25:05 yamaha go-librespot[12353]: time="2026-02-25T03:25:05+01:00" level=debug msg="connected to ap-gew1.spotify.com:80" Feb 25 03:25:05 yamaha go-librespot[12353]: time="2026-02-25T03:25:05+01:00" level=debug msg="completed keyexchange" Feb 25 03:25:05 yamaha go-librespot[12353]: time="2026-02-25T03:25:05+01:00" level=debug msg="completed challenge" Feb 25 03:25:05 yamaha go-librespot[12353]: time="2026-02-25T03:25:05+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:25:05 yamaha go-librespot[12353]: time="2026-02-25T03:25:05+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:25:05 yamaha systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 03:25:05 yamaha systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 03:25:05 yamaha systemd[1]: go-librespot-daemon.service: Consumed 1.023s CPU time. Feb 25 03:25:07 yamaha volumio[12096]: info: Initializing connection to go-librespot Websocket Feb 25 03:25:07 yamaha volumio[12096]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 25 03:25:08 yamaha systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Feb 25 03:25:08 yamaha systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:25:08 yamaha systemd[1]: go-librespot-daemon.service: Consumed 1.023s CPU time. Feb 25 03:25:08 yamaha systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:25:08 yamaha go-librespot[12418]: go-librespot daemon starting... Feb 25 03:25:08 yamaha go-librespot[12419]: time="2026-02-25T03:25:08+01:00" level=info msg="running go-librespot 0.6.2" Feb 25 03:25:08 yamaha go-librespot[12419]: time="2026-02-25T03:25:08+01:00" level=debug msg="app state loaded" Feb 25 03:25:08 yamaha go-librespot[12419]: time="2026-02-25T03:25:08+01:00" level=debug msg="stored credentials not found" Feb 25 03:25:08 yamaha go-librespot[12419]: time="2026-02-25T03:25:08+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 25 03:25:09 yamaha go-librespot[12419]: time="2026-02-25T03:25:09+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 25 03:25:09 yamaha go-librespot[12419]: time="2026-02-25T03:25:09+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 25 03:25:09 yamaha go-librespot[12419]: time="2026-02-25T03:25:09+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 25 03:25:09 yamaha go-librespot[12419]: time="2026-02-25T03:25:09+01:00" level=info msg="zeroconf server listening on port 38867" Feb 25 03:25:09 yamaha go-librespot[12419]: time="2026-02-25T03:25:09+01:00" level=debug msg="obtained new client token: AADEs50yQe0bmAacPiAm91MNSVe+2UZMlVLuQ1Yy030hCR3s4M8Py76lOMVa/OtCXx+IunkdtyVkXep58CHhkt41XcfxH/H1I0eSXiOtZS565Y0N31l9tv2CjUZ+ItJjzglasbIEjXbGoc+WfqfUvNQPwUZ3W3s3bGSOXXrCueFfmDrhY/VLI5gYoK6EqRcrwQKvb3XGE3pLuuB+7NS/fqoqPk8kO2/kNHqg3RkaVsoy3XuD9ew3i1/8" Feb 25 03:25:09 yamaha go-librespot[12419]: time="2026-02-25T03:25:09+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 25 03:25:09 yamaha go-librespot[12419]: time="2026-02-25T03:25:09+01:00" level=debug msg="completed keyexchange" Feb 25 03:25:09 yamaha go-librespot[12419]: time="2026-02-25T03:25:09+01:00" level=debug msg="completed challenge" Feb 25 03:25:09 yamaha go-librespot[12419]: time="2026-02-25T03:25:09+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:25:09 yamaha volumio[12096]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Feb 25 03:25:10 yamaha volumio[12096]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 25 03:25:10 yamaha go-librespot[12419]: time="2026-02-25T03:25:10+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Feb 25 03:25:10 yamaha go-librespot[12419]: time="2026-02-25T03:25:10+01:00" level=debug msg="completed keyexchange" Feb 25 03:25:10 yamaha go-librespot[12419]: time="2026-02-25T03:25:10+01:00" level=debug msg="completed challenge" Feb 25 03:25:10 yamaha go-librespot[12419]: time="2026-02-25T03:25:10+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:25:10 yamaha go-librespot[12419]: time="2026-02-25T03:25:10+01:00" level=debug msg="connected to ap-gew4.spotify.com:80" Feb 25 03:25:10 yamaha go-librespot[12419]: time="2026-02-25T03:25:10+01:00" level=debug msg="completed keyexchange" Feb 25 03:25:10 yamaha go-librespot[12419]: time="2026-02-25T03:25:10+01:00" level=debug msg="completed challenge" Feb 25 03:25:10 yamaha go-librespot[12419]: time="2026-02-25T03:25:10+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:25:11 yamaha volumio[12096]: info: Initializing connection to go-librespot Websocket Feb 25 03:25:11 yamaha go-librespot[12419]: time="2026-02-25T03:25:11+01:00" level=debug msg="new websocket client" Feb 25 03:25:11 yamaha volumio[12096]: info: Connection to go-librespot Websocket established Feb 25 03:25:11 yamaha go-librespot[12419]: time="2026-02-25T03:25:11+01:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 25 03:25:11 yamaha go-librespot[12419]: time="2026-02-25T03:25:11+01:00" level=debug msg="completed keyexchange" Feb 25 03:25:11 yamaha go-librespot[12419]: time="2026-02-25T03:25:11+01:00" level=debug msg="completed challenge" Feb 25 03:25:11 yamaha go-librespot[12419]: time="2026-02-25T03:25:11+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:25:12 yamaha go-librespot[12419]: time="2026-02-25T03:25:12+01:00" level=debug msg="connected to ap-gue1.spotify.com:443" Feb 25 03:25:12 yamaha go-librespot[12419]: time="2026-02-25T03:25:12+01:00" level=debug msg="completed keyexchange" Feb 25 03:25:12 yamaha go-librespot[12419]: time="2026-02-25T03:25:12+01:00" level=debug msg="completed challenge" Feb 25 03:25:12 yamaha go-librespot[12419]: time="2026-02-25T03:25:12+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:25:13 yamaha volumio[12096]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 25 03:25:13 yamaha volumio[12096]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 25 03:25:13 yamaha volumio[12096]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 25 03:25:13 yamaha volumio[12096]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 25 03:25:13 yamaha volumio[12096]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 25 03:25:13 yamaha volumio[12096]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 25 03:25:13 yamaha volumio[12096]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 25 03:25:13 yamaha volumio[12096]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 25 03:25:13 yamaha volumio[12096]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 25 03:25:13 yamaha volumio[12096]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 25 03:25:13 yamaha volumio[12096]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 25 03:25:13 yamaha volumio[12096]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 25 03:25:13 yamaha volumio[12096]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 25 03:25:13 yamaha volumio[12096]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 25 03:25:13 yamaha go-librespot[12419]: time="2026-02-25T03:25:13+01:00" level=debug msg="connected to ap-gew1.spotify.com:80" Feb 25 03:25:13 yamaha volumio[12096]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 25 03:25:13 yamaha volumio[12096]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 25 03:25:13 yamaha volumio[12096]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 25 03:25:13 yamaha volumio[12096]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 25 03:25:13 yamaha volumio[12096]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 25 03:25:13 yamaha volumio[12096]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 25 03:25:13 yamaha volumio[12096]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 25 03:25:13 yamaha volumio[12096]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 25 03:25:13 yamaha volumio[12096]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 25 03:25:13 yamaha volumio[12096]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 25 03:25:13 yamaha volumio[12096]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 25 03:25:13 yamaha volumio[12096]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 25 03:25:13 yamaha volumio[12096]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 25 03:25:13 yamaha volumio[12096]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 25 03:25:13 yamaha volumio[12096]: info: Adding plugin bluetooth to MyMusic Plugins Feb 25 03:25:13 yamaha volumio[12096]: info: Adding plugin multiroom to MyMusic Plugins Feb 25 03:25:13 yamaha volumio[12096]: info: Adding plugin metavolumio to MyMusic Plugins Feb 25 03:25:13 yamaha volumio[12096]: info: Adding plugin cd_controller to MyMusic Plugins Feb 25 03:25:13 yamaha volumio[12096]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 25 03:25:13 yamaha volumio[12096]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 25 03:25:13 yamaha volumio[12096]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 25 03:25:13 yamaha volumio[12096]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 25 03:25:13 yamaha go-librespot[12419]: time="2026-02-25T03:25:13+01:00" level=debug msg="completed keyexchange" Feb 25 03:25:13 yamaha go-librespot[12419]: time="2026-02-25T03:25:13+01:00" level=debug msg="completed challenge" Feb 25 03:25:13 yamaha go-librespot[12419]: time="2026-02-25T03:25:13+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:25:13 yamaha go-librespot[12419]: time="2026-02-25T03:25:13+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:25:13 yamaha systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 03:25:13 yamaha systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 03:25:14 yamaha volumio[12096]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 25 03:25:14 yamaha volumio[12096]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 25 03:25:14 yamaha volumio[12096]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:25:14 yamaha volumio[12096]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:25:14 yamaha volumio[12096]: info: Starting MyVolumio Remote Streaming Endpoints Feb 25 03:25:14 yamaha volumio[12096]: info: MyVolumio login type: Token Feb 25 03:25:14 yamaha volumio[12096]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 25 03:25:14 yamaha volumio[12096]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 25 03:25:16 yamaha systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Feb 25 03:25:16 yamaha systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:25:16 yamaha systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:25:16 yamaha go-librespot[12443]: go-librespot daemon starting... Feb 25 03:25:16 yamaha go-librespot[12444]: time="2026-02-25T03:25:16+01:00" level=info msg="running go-librespot 0.6.2" Feb 25 03:25:16 yamaha go-librespot[12444]: time="2026-02-25T03:25:16+01:00" level=debug msg="app state loaded" Feb 25 03:25:16 yamaha go-librespot[12444]: time="2026-02-25T03:25:16+01:00" level=debug msg="stored credentials not found" Feb 25 03:25:16 yamaha go-librespot[12444]: time="2026-02-25T03:25:16+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 25 03:25:16 yamaha volumio[12096]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 25 03:25:16 yamaha volumio[12096]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 25 03:25:16 yamaha volumio[12096]: info: Streaming services startup Feb 25 03:25:16 yamaha volumio[12096]: info: Starting Streaming Daemon Feb 25 03:25:16 yamaha sudo[12453]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 25 03:25:16 yamaha volumio[12096]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 25 03:25:16 yamaha sudo[12453]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:25:16 yamaha sudo[12453]: pam_unix(sudo:session): session closed for user root Feb 25 03:25:16 yamaha volumio[12096]: info: Getting Spotify volume Feb 25 03:25:16 yamaha go-librespot[12444]: time="2026-02-25T03:25:16+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 25 03:25:16 yamaha go-librespot[12444]: time="2026-02-25T03:25:16+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 25 03:25:16 yamaha go-librespot[12444]: time="2026-02-25T03:25:16+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 25 03:25:16 yamaha go-librespot[12444]: time="2026-02-25T03:25:16+01:00" level=info msg="zeroconf server listening on port 42189" Feb 25 03:25:16 yamaha volumio[12096]: info: Connection to go-librespot Websocket closed Feb 25 03:25:16 yamaha volumio[12096]: error: Cannot start Volumio Streaming Daemon Feb 25 03:25:16 yamaha volumio[12096]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 25 03:25:16 yamaha volumio[12096]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 25 03:25:17 yamaha volumio[12096]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Feb 25 03:25:17 yamaha go-librespot[12444]: time="2026-02-25T03:25:17+01:00" level=debug msg="obtained new client token: AABrmuSuHNXPEXcayDhlXTyUk1ZqOIPbB3d2K/nYqnPQNz9clJX6X3zaknYYr73b2R4y05h7DTmR7l56j4JkdK3Z9PKUOppbuMbQLLL6oONej6D931NHDF/Poml593RRke4zbtcClGLsmuhc28Sy3ti3PvK7vdho/0434ENohbttRXo65BJePrTvXHkAo4i8YkVYCvqSHytw7oXPO5Z4GlTqiUG3Wc8l7nLlmmqSbNziIT0X/BWJow==" Feb 25 03:25:17 yamaha go-librespot[12444]: time="2026-02-25T03:25:17+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 25 03:25:17 yamaha go-librespot[12444]: time="2026-02-25T03:25:17+01:00" level=debug msg="completed keyexchange" Feb 25 03:25:17 yamaha go-librespot[12444]: time="2026-02-25T03:25:17+01:00" level=debug msg="completed challenge" Feb 25 03:25:17 yamaha go-librespot[12444]: time="2026-02-25T03:25:17+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:25:17 yamaha volumio[12096]: info: CoreCommandRouter::volumioGetState Feb 25 03:25:17 yamaha volumio[12096]: info: CorePlayQueue::getTrack 0 Feb 25 03:25:17 yamaha volumio[12096]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 25 03:25:17 yamaha volumio[12096]: SPOTIFY: SPOTIFY VOLUME undefined Feb 25 03:25:17 yamaha volumio[12096]: SPOTIFY: VOLUMIO VOLUME 86 Feb 25 03:25:17 yamaha volumio[12096]: info: Aligning Spotify Volume to Volumio Volume Feb 25 03:25:17 yamaha volumio[12096]: info: CoreCommandRouter::volumioGetState Feb 25 03:25:17 yamaha volumio[12096]: info: CorePlayQueue::getTrack 0 Feb 25 03:25:17 yamaha volumio[12096]: info: Setting Spotify Volume from Volumio: 86 Feb 25 03:25:17 yamaha go-librespot[12444]: time="2026-02-25T03:25:17+01:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:443, retrying with a different AP" error="dial tcp 34.158.1.133:443: connect: connection refused" Feb 25 03:25:17 yamaha go-librespot[12444]: time="2026-02-25T03:25:17+01:00" level=debug msg="connected to ap-gew4.spotify.com:80" Feb 25 03:25:17 yamaha volumio[12096]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Feb 25 03:25:17 yamaha go-librespot[12444]: time="2026-02-25T03:25:17+01:00" level=debug msg="completed keyexchange" Feb 25 03:25:17 yamaha go-librespot[12444]: time="2026-02-25T03:25:17+01:00" level=debug msg="completed challenge" Feb 25 03:25:17 yamaha go-librespot[12444]: time="2026-02-25T03:25:17+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:25:18 yamaha volumio[12096]: info: MyVolumio token set successfully Feb 25 03:25:18 yamaha volumio[12096]: info: MYVOLUMIO: Adding device Feb 25 03:25:18 yamaha volumio[12096]: info: MYVOLUMIO: Evaluating Server Feb 25 03:25:18 yamaha go-librespot[12444]: time="2026-02-25T03:25:18+01:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 25 03:25:18 yamaha go-librespot[12444]: time="2026-02-25T03:25:18+01:00" level=debug msg="completed keyexchange" Feb 25 03:25:18 yamaha go-librespot[12444]: time="2026-02-25T03:25:18+01:00" level=debug msg="completed challenge" Feb 25 03:25:18 yamaha volumio[12096]: info: MyVolumio status changed Feb 25 03:25:18 yamaha volumio[12096]: info: Streaming services startup Feb 25 03:25:18 yamaha volumio[12096]: info: Starting Streaming Daemon Feb 25 03:25:18 yamaha go-librespot[12444]: time="2026-02-25T03:25:18+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:25:18 yamaha volumio[12096]: info: Removing browser output: myVolumio user plan is not superstar Feb 25 03:25:18 yamaha volumio[12096]: info: Removing audio output: Feb 25 03:25:18 yamaha volumio[12096]: info: Stoppping Tunnel 1 Feb 25 03:25:18 yamaha sudo[12482]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 25 03:25:18 yamaha sudo[12482]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:25:18 yamaha sudo[12484]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Feb 25 03:25:18 yamaha sudo[12484]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:25:19 yamaha systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 25 03:25:19 yamaha systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 25 03:25:19 yamaha sudo[12482]: pam_unix(sudo:session): session closed for user root Feb 25 03:25:19 yamaha volumio[12096]: error: Cannot start Volumio Streaming Daemon Feb 25 03:25:19 yamaha volumio[12096]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 25 03:25:19 yamaha volumio[12096]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 25 03:25:19 yamaha volumio[12096]: info: Setting Geolocation for MyVolumio to eu8 Feb 25 03:25:19 yamaha volumio[12096]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:25:19 yamaha volumio[12096]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:25:19 yamaha volumio[12096]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:25:19 yamaha systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 25 03:25:19 yamaha systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 25 03:25:19 yamaha systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 25 03:25:19 yamaha systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 25 03:25:19 yamaha volumio[12096]: SPOTIFY: SETTING SPOTIFY VOLUME 86 Feb 25 03:25:19 yamaha volumio[12096]: info: Sending Spotify command with payload to local API: /player/volume Feb 25 03:25:19 yamaha sudo[12484]: pam_unix(sudo:session): session closed for user root Feb 25 03:25:19 yamaha volumio[12096]: info: Remote SSH Stopped Feb 25 03:25:19 yamaha volumio[12096]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Feb 25 03:25:19 yamaha go-librespot[12444]: time="2026-02-25T03:25:19+01:00" level=debug msg="connected to ap-gue1.spotify.com:443" Feb 25 03:25:19 yamaha go-librespot[12444]: time="2026-02-25T03:25:19+01:00" level=debug msg="completed keyexchange" Feb 25 03:25:19 yamaha go-librespot[12444]: time="2026-02-25T03:25:19+01:00" level=debug msg="completed challenge" Feb 25 03:25:19 yamaha go-librespot[12444]: time="2026-02-25T03:25:19+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:25:19 yamaha volumio[12096]: info: Initializing connection to go-librespot Websocket Feb 25 03:25:19 yamaha go-librespot[12444]: time="2026-02-25T03:25:19+01:00" level=debug msg="new websocket client" Feb 25 03:25:19 yamaha volumio[12096]: info: Connection to go-librespot Websocket established Feb 25 03:25:20 yamaha volumio[12096]: info: Updating MyVolumio device info Feb 25 03:25:20 yamaha volumio[12096]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:25:20 yamaha volumio[12096]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:25:20 yamaha volumio[12096]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:25:20 yamaha go-librespot[12444]: time="2026-02-25T03:25:20+01:00" level=debug msg="connected to ap-gew1.spotify.com:80" Feb 25 03:25:20 yamaha volumio[12096]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Feb 25 03:25:20 yamaha go-librespot[12444]: time="2026-02-25T03:25:20+01:00" level=debug msg="completed keyexchange" Feb 25 03:25:20 yamaha go-librespot[12444]: time="2026-02-25T03:25:20+01:00" level=debug msg="completed challenge" Feb 25 03:25:20 yamaha go-librespot[12444]: time="2026-02-25T03:25:20+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:25:20 yamaha go-librespot[12444]: time="2026-02-25T03:25:20+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 25 03:25:20 yamaha go-librespot[12444]: time="2026-02-25T03:25:20+01:00" level=debug msg="completed keyexchange" Feb 25 03:25:20 yamaha go-librespot[12444]: time="2026-02-25T03:25:20+01:00" level=debug msg="completed challenge" Feb 25 03:25:20 yamaha go-librespot[12444]: time="2026-02-25T03:25:20+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:25:20 yamaha go-librespot[12444]: time="2026-02-25T03:25:20+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:25:20 yamaha volumio[12096]: info: Connection to go-librespot Websocket closed Feb 25 03:25:20 yamaha systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 03:25:20 yamaha volumio[12096]: error: Failed to send command to Spotify local API: /player/volume: Error: socket hang up Feb 25 03:25:20 yamaha systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 03:25:21 yamaha volumio[12096]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 25 03:25:21 yamaha volumio[12096]: Error: socket hang up Feb 25 03:25:21 yamaha volumio[12096]: at connResetException (node:internal/errors:720:14) Feb 25 03:25:21 yamaha volumio[12096]: at Socket.socketOnEnd (node:_http_client:519:23) Feb 25 03:25:21 yamaha volumio[12096]: at Socket.emit (node:events:526:35) Feb 25 03:25:21 yamaha volumio[12096]: at endReadableNT (node:internal/streams/readable:1376:12) Feb 25 03:25:21 yamaha volumio[12096]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Feb 25 03:25:21 yamaha volumio[12096]: code: 'ECONNRESET', Feb 25 03:25:21 yamaha volumio[12096]: response: undefined Feb 25 03:25:21 yamaha volumio[12096]: } Feb 25 03:25:21 yamaha volumio[12096]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 25 03:25:22 yamaha sudo[12502]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-25 03:24' Feb 25 03:25:22 yamaha sudo[12502]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:25:22 yamaha sudo[12502]: pam_unix(sudo:session): session closed for user root Feb 25 03:25:23 yamaha volumio-remote-updater[629]: [2026-02-25 03:25:23] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 25 03:25:23 yamaha volumio-remote-updater[629]: [2026-02-25 03:25:23] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 25 03:25:23 yamaha systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 25 03:25:23 yamaha volumio5-onboarding[1284]: time=2026-02-25T03:25:23.100+01:00 level=ERROR msg="failed reading message" component=volumio/socket error="websocket: close 1006 (abnormal closure): unexpected EOF" Feb 25 03:25:23 yamaha volumio5-onboarding[1284]: time=2026-02-25T03:25:23.114+01:00 level=WARN msg="reconnection attempt failed" component=volumio/socket error="dial tcp 127.0.0.1:3000: connect: connection refused" Feb 25 03:25:23 yamaha systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 25 03:25:23 yamaha systemd[1]: volumio.service: Consumed 57.653s CPU time. Feb 25 03:25:23 yamaha systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 25 03:25:23 yamaha systemd[1]: dynamicswap.service: Deactivated successfully. Feb 25 03:25:23 yamaha systemd[1]: volumio.service: Scheduled restart job, restart counter is at 197. Feb 25 03:25:23 yamaha systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 25 03:25:23 yamaha systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 25 03:25:23 yamaha systemd[1]: volumio.service: Consumed 57.653s CPU time. Feb 25 03:25:23 yamaha systemd[1]: Started volumio.service - Volumio Backend Module. Feb 25 03:25:23 yamaha systemd[1]: dynamicswap.service: Deactivated successfully. Feb 25 03:25:24 yamaha volumio5-onboarding[1284]: time=2026-02-25T03:25:24.117+01:00 level=WARN msg="reconnection attempt failed" component=volumio/socket error="dial tcp 127.0.0.1:3000: connect: connection refused" Feb 25 03:25:24 yamaha systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Feb 25 03:25:24 yamaha systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:25:24 yamaha systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:25:24 yamaha go-librespot[12533]: go-librespot daemon starting... Feb 25 03:25:24 yamaha go-librespot[12534]: time="2026-02-25T03:25:24+01:00" level=info msg="running go-librespot 0.6.2" Feb 25 03:25:24 yamaha go-librespot[12534]: time="2026-02-25T03:25:24+01:00" level=debug msg="app state loaded" Feb 25 03:25:24 yamaha go-librespot[12534]: time="2026-02-25T03:25:24+01:00" level=debug msg="stored credentials not found" Feb 25 03:25:24 yamaha go-librespot[12534]: time="2026-02-25T03:25:24+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 25 03:25:24 yamaha go-librespot[12534]: time="2026-02-25T03:25:24+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 25 03:25:24 yamaha go-librespot[12534]: time="2026-02-25T03:25:24+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 25 03:25:24 yamaha go-librespot[12534]: time="2026-02-25T03:25:24+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 25 03:25:24 yamaha go-librespot[12534]: time="2026-02-25T03:25:24+01:00" level=info msg="zeroconf server listening on port 40315" Feb 25 03:25:24 yamaha go-librespot[12534]: time="2026-02-25T03:25:24+01:00" level=debug msg="obtained new client token: AAD19QIpGm9sh21uaoui84JX6qtzsbkuTSA+dk6dJJgxW1oMEBv5WuLPhNT2ThrwXq2THHE1GDHa+kAQWDdsKQVeHnUN1hk88UF7WUk4JlnOyHlqV217xhkPqvjZlbUdWZUcQgsz3iEalvTsMKfRuS2cNwLp8IHMg5oQxYnNJgIPS/ZNQL1Ku5wZYhqe053wxVHc754q8gQsjYI2UnNLqmT5IKpgnNPVLcv3RAJmY2LOO8KPKpNQLzXz" Feb 25 03:25:25 yamaha go-librespot[12534]: time="2026-02-25T03:25:25+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 25 03:25:25 yamaha go-librespot[12534]: time="2026-02-25T03:25:25+01:00" level=debug msg="completed keyexchange" Feb 25 03:25:25 yamaha go-librespot[12534]: time="2026-02-25T03:25:25+01:00" level=debug msg="completed challenge" Feb 25 03:25:25 yamaha go-librespot[12534]: time="2026-02-25T03:25:25+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:25:25 yamaha volumio5-onboarding[1284]: time=2026-02-25T03:25:25.122+01:00 level=WARN msg="reconnection attempt failed" component=volumio/socket error="dial tcp 127.0.0.1:3000: connect: connection refused" Feb 25 03:25:25 yamaha go-librespot[12534]: time="2026-02-25T03:25:25+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Feb 25 03:25:25 yamaha go-librespot[12534]: time="2026-02-25T03:25:25+01:00" level=debug msg="completed keyexchange" Feb 25 03:25:25 yamaha go-librespot[12534]: time="2026-02-25T03:25:25+01:00" level=debug msg="completed challenge" Feb 25 03:25:25 yamaha go-librespot[12534]: time="2026-02-25T03:25:25+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:25:26 yamaha volumio5-onboarding[1284]: time=2026-02-25T03:25:26.125+01:00 level=WARN msg="reconnection attempt failed" component=volumio/socket error="dial tcp 127.0.0.1:3000: connect: connection refused" Feb 25 03:25:26 yamaha go-librespot[12534]: time="2026-02-25T03:25:26+01:00" level=debug msg="connected to ap-gew4.spotify.com:80" Feb 25 03:25:26 yamaha go-librespot[12534]: time="2026-02-25T03:25:26+01:00" level=debug msg="completed keyexchange" Feb 25 03:25:26 yamaha go-librespot[12534]: time="2026-02-25T03:25:26+01:00" level=debug msg="completed challenge" Feb 25 03:25:26 yamaha go-librespot[12534]: time="2026-02-25T03:25:26+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:25:26 yamaha go-librespot[12534]: time="2026-02-25T03:25:26+01:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Feb 25 03:25:27 yamaha go-librespot[12534]: time="2026-02-25T03:25:27+01:00" level=debug msg="completed keyexchange" Feb 25 03:25:27 yamaha go-librespot[12534]: time="2026-02-25T03:25:27+01:00" level=debug msg="completed challenge" Feb 25 03:25:27 yamaha go-librespot[12534]: time="2026-02-25T03:25:27+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:25:27 yamaha volumio[12518]: info: ------------------------------------------- Feb 25 03:25:27 yamaha volumio[12518]: info: ----- Volumio3 ---- Feb 25 03:25:27 yamaha volumio[12518]: info: ------------------------------------------- Feb 25 03:25:27 yamaha volumio[12518]: info: ----- System startup ---- Feb 25 03:25:27 yamaha volumio[12518]: info: ------------------------------------------- Feb 25 03:25:27 yamaha go-librespot[12534]: time="2026-02-25T03:25:27+01:00" level=debug msg="connected to ap-gew1.spotify.com:443" Feb 25 03:25:27 yamaha go-librespot[12534]: time="2026-02-25T03:25:27+01:00" level=debug msg="completed keyexchange" Feb 25 03:25:27 yamaha go-librespot[12534]: time="2026-02-25T03:25:27+01:00" level=debug msg="completed challenge" Feb 25 03:25:27 yamaha go-librespot[12534]: time="2026-02-25T03:25:27+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:25:28 yamaha volumio-remote-updater[629]: [2026-02-25 03:25:28] [connect] Successful connection Feb 25 03:25:28 yamaha go-librespot[12534]: time="2026-02-25T03:25:28+01:00" level=debug msg="connected to ap-gae2.spotify.com:80" Feb 25 03:25:28 yamaha volumio[12518]: info: MYVOLUMIO Environment detected Feb 25 03:25:28 yamaha go-librespot[12534]: time="2026-02-25T03:25:28+01:00" level=debug msg="completed keyexchange" Feb 25 03:25:28 yamaha go-librespot[12534]: time="2026-02-25T03:25:28+01:00" level=debug msg="completed challenge" Feb 25 03:25:28 yamaha volumio[12518]: info: Plugin folders cleanup Feb 25 03:25:28 yamaha volumio[12518]: info: Scanning into folder /volumio/app/plugins/ Feb 25 03:25:28 yamaha volumio[12518]: info: Scanning category audio_interface Feb 25 03:25:28 yamaha volumio[12518]: info: Scanning category miscellanea Feb 25 03:25:28 yamaha volumio[12518]: info: Scanning category music_service Feb 25 03:25:28 yamaha volumio[12518]: info: Scanning category plugins.json Feb 25 03:25:28 yamaha volumio[12518]: info: Scanning category system_controller Feb 25 03:25:28 yamaha volumio[12518]: info: Scanning category user_interface Feb 25 03:25:28 yamaha volumio[12518]: info: Scanning into folder /data/plugins/ Feb 25 03:25:28 yamaha volumio[12518]: info: Scanning category music_service Feb 25 03:25:28 yamaha volumio[12518]: info: Plugin folders cleanup completed Feb 25 03:25:28 yamaha volumio[12518]: info: ------------------------------------------- Feb 25 03:25:28 yamaha volumio[12518]: info: ----- Core plugins startup ---- Feb 25 03:25:28 yamaha volumio[12518]: info: ------------------------------------------- Feb 25 03:25:28 yamaha volumio[12518]: info: Loading plugins from folder /volumio/app/plugins/ Feb 25 03:25:28 yamaha volumio[12518]: info: Adding plugin upnp to MyMusic Plugins Feb 25 03:25:28 yamaha volumio[12518]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 25 03:25:28 yamaha volumio[12518]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 25 03:25:28 yamaha volumio[12518]: info: Loading plugins from folder /data/plugins/ Feb 25 03:25:28 yamaha volumio[12518]: info: Loading plugin "system"... Feb 25 03:25:29 yamaha volumio[12518]: info: Loading plugin "appearance"... Feb 25 03:25:29 yamaha go-librespot[12534]: time="2026-02-25T03:25:29+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:25:29 yamaha go-librespot[12534]: time="2026-02-25T03:25:29+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:25:29 yamaha systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 03:25:29 yamaha systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 03:25:30 yamaha volumio[12518]: info: Loading plugin "network"... Feb 25 03:25:30 yamaha volumio[12518]: info: Refreshing Cached IP Addresses Feb 25 03:25:31 yamaha sudo[12556]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 25 03:25:31 yamaha sudo[12556]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:25:31 yamaha volumio[12518]: info: Loading plugin "services"... Feb 25 03:25:31 yamaha sudo[12558]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 25 03:25:31 yamaha sudo[12558]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:25:31 yamaha volumio[12518]: info: Loading plugin "volumio5onboarding"... Feb 25 03:25:31 yamaha sudo[12556]: pam_unix(sudo:session): session closed for user root Feb 25 03:25:31 yamaha volumio[12518]: info: Loading plugin "alsa_controller"... Feb 25 03:25:31 yamaha sudo[12558]: pam_unix(sudo:session): session closed for user root Feb 25 03:25:31 yamaha sudo[12570]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 25 03:25:31 yamaha sudo[12570]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:25:31 yamaha sudo[12570]: pam_unix(sudo:session): session closed for user root Feb 25 03:25:31 yamaha volumio[12518]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 25 03:25:31 yamaha volumio[12518]: info: Loading plugin "wizard"... Feb 25 03:25:31 yamaha volumio[12518]: info: Loading plugin "networkfs"... Feb 25 03:25:31 yamaha volumio[12518]: info: Cannot mount NAS Ugreen at system boot, trial number 1 ,retrying in 5 seconds Feb 25 03:25:31 yamaha volumio[12518]: info: Starting Udev Watcher for removable devices Feb 25 03:25:31 yamaha volumio[12518]: info: Ignoring mount for partition: boot Feb 25 03:25:31 yamaha volumio[12518]: info: Ignoring mount for partition: volumio Feb 25 03:25:31 yamaha volumio[12518]: info: Ignoring mount for partition: volumio_data Feb 25 03:25:31 yamaha volumio[12518]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 25 03:25:31 yamaha volumio[12518]: info: Loading plugin "volumio_command_line_client"... Feb 25 03:25:31 yamaha volumio[12518]: info: Loading plugin "upnp"... Feb 25 03:25:31 yamaha volumio[12518]: info: [1771986331444] Starting Upmpd Daemon Feb 25 03:25:31 yamaha volumio[12518]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 25 03:25:31 yamaha volumio[12518]: info: Loading plugin "my_music"... Feb 25 03:25:31 yamaha volumio[12518]: info: Loading plugin "mpd"... Feb 25 03:25:32 yamaha volumio[12518]: info: Loading plugin "upnp_browser"... Feb 25 03:25:32 yamaha systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Feb 25 03:25:32 yamaha systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:25:32 yamaha systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:25:32 yamaha go-librespot[12593]: go-librespot daemon starting... Feb 25 03:25:32 yamaha go-librespot[12594]: time="2026-02-25T03:25:32+01:00" level=info msg="running go-librespot 0.6.2" Feb 25 03:25:32 yamaha go-librespot[12594]: time="2026-02-25T03:25:32+01:00" level=debug msg="app state loaded" Feb 25 03:25:32 yamaha go-librespot[12594]: time="2026-02-25T03:25:32+01:00" level=debug msg="stored credentials not found" Feb 25 03:25:32 yamaha go-librespot[12594]: time="2026-02-25T03:25:32+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 25 03:25:32 yamaha go-librespot[12594]: time="2026-02-25T03:25:32+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 25 03:25:32 yamaha go-librespot[12594]: time="2026-02-25T03:25:32+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 25 03:25:32 yamaha go-librespot[12594]: time="2026-02-25T03:25:32+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 25 03:25:32 yamaha go-librespot[12594]: time="2026-02-25T03:25:32+01:00" level=info msg="zeroconf server listening on port 33109" Feb 25 03:25:32 yamaha go-librespot[12594]: time="2026-02-25T03:25:32+01:00" level=debug msg="obtained new client token: AAD2gr1/tCx1zAoCbGkb4OCPWiFo874TvhLt6ElI2nxGlrY+LASyrbb2e2PdCDdftcCzDZwUQTLLyQPWn+PJf+XjOXiD9KXcM9Lanqu+jERHBwuFKvm2tLHPc19Ts1W4iyQI4f41HUA6f5UGwN51kMUJh9lar0TW++sdrH32KFWdC+o5224BZwAOn3RMLkQKtCcnVAUDrgRE6KqA86aHGs+i5dFJegJVrVEUc/6U/FItdjAnE25ptC0q" Feb 25 03:25:32 yamaha go-librespot[12594]: time="2026-02-25T03:25:32+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 25 03:25:33 yamaha go-librespot[12594]: time="2026-02-25T03:25:33+01:00" level=debug msg="completed keyexchange" Feb 25 03:25:33 yamaha go-librespot[12594]: time="2026-02-25T03:25:33+01:00" level=debug msg="completed challenge" Feb 25 03:25:33 yamaha go-librespot[12594]: time="2026-02-25T03:25:33+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:25:33 yamaha go-librespot[12594]: time="2026-02-25T03:25:33+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Feb 25 03:25:33 yamaha go-librespot[12594]: time="2026-02-25T03:25:33+01:00" level=debug msg="completed keyexchange" Feb 25 03:25:33 yamaha go-librespot[12594]: time="2026-02-25T03:25:33+01:00" level=debug msg="completed challenge" Feb 25 03:25:33 yamaha go-librespot[12594]: time="2026-02-25T03:25:33+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:25:34 yamaha go-librespot[12594]: time="2026-02-25T03:25:34+01:00" level=debug msg="connected to ap-gew4.spotify.com:80" Feb 25 03:25:34 yamaha go-librespot[12594]: time="2026-02-25T03:25:34+01:00" level=debug msg="completed keyexchange" Feb 25 03:25:34 yamaha go-librespot[12594]: time="2026-02-25T03:25:34+01:00" level=debug msg="completed challenge" Feb 25 03:25:34 yamaha go-librespot[12594]: time="2026-02-25T03:25:34+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:25:34 yamaha volumio[12518]: info: Starting UPNP Browser Feb 25 03:25:34 yamaha volumio[12518]: info: Loading plugin "alarm-clock"... Feb 25 03:25:34 yamaha go-librespot[12594]: time="2026-02-25T03:25:34+01:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 25 03:25:35 yamaha go-librespot[12594]: time="2026-02-25T03:25:35+01:00" level=debug msg="completed keyexchange" Feb 25 03:25:35 yamaha go-librespot[12594]: time="2026-02-25T03:25:35+01:00" level=debug msg="completed challenge" Feb 25 03:25:35 yamaha volumio[12518]: info: Loading plugin "airplay_emulation"... Feb 25 03:25:35 yamaha volumio[12518]: info: Starting Shairport Sync Feb 25 03:25:35 yamaha volumio[12518]: info: Loading plugin "last_100"... Feb 25 03:25:35 yamaha go-librespot[12594]: time="2026-02-25T03:25:35+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:25:35 yamaha volumio[12518]: info: Loading plugin "webradio"... Feb 25 03:25:35 yamaha volumio[12518]: info: Loading plugin "i2s_dacs"... Feb 25 03:25:35 yamaha volumio[12518]: info: I2S DAC not set, start Auto-detection Feb 25 03:25:35 yamaha volumio[12518]: info: Loading plugin "volumiodiscovery"... Feb 25 03:25:35 yamaha volumio[12518]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 25 03:25:35 yamaha volumio[12518]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 25 03:25:35 yamaha volumio[12518]: *** WARNING *** For more information see Feb 25 03:25:35 yamaha volumio[12518]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 25 03:25:35 yamaha volumio[12518]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 25 03:25:35 yamaha volumio[12518]: *** WARNING *** For more information see Feb 25 03:25:35 yamaha node[12518]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 25 03:25:35 yamaha node[12518]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 25 03:25:35 yamaha node[12518]: *** WARNING *** For more information see Feb 25 03:25:35 yamaha node[12518]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 25 03:25:35 yamaha node[12518]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 25 03:25:35 yamaha node[12518]: *** WARNING *** For more information see Feb 25 03:25:35 yamaha volumio[12518]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 25 03:25:35 yamaha volumio[12518]: info: Discovery: Started advertising with name: Yamaha Feb 25 03:25:35 yamaha volumio[12518]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 25 03:25:35 yamaha volumio[12518]: info: Loading plugin "calmradio"... Feb 25 03:25:35 yamaha go-librespot[12594]: time="2026-02-25T03:25:35+01:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:443, retrying with a different AP" error="dial tcp 104.199.65.9:443: connect: connection refused" Feb 25 03:25:35 yamaha go-librespot[12594]: time="2026-02-25T03:25:35+01:00" level=debug msg="connected to ap-gae2.spotify.com:80" Feb 25 03:25:36 yamaha go-librespot[12594]: time="2026-02-25T03:25:36+01:00" level=debug msg="completed keyexchange" Feb 25 03:25:36 yamaha go-librespot[12594]: time="2026-02-25T03:25:36+01:00" level=debug msg="completed challenge" Feb 25 03:25:36 yamaha go-librespot[12594]: time="2026-02-25T03:25:36+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:25:37 yamaha volumio5-onboarding[1284]: time=2026-02-25T03:25:37.133+01:00 level=WARN msg="reconnection attempt failed" component=volumio/socket error="read tcp 127.0.0.1:46692->127.0.0.1:3000: i/o timeout" Feb 25 03:25:37 yamaha go-librespot[12594]: time="2026-02-25T03:25:37+01:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.1.133:4070: connect: connection refused" Feb 25 03:25:37 yamaha go-librespot[12594]: time="2026-02-25T03:25:37+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Feb 25 03:25:37 yamaha go-librespot[12594]: time="2026-02-25T03:25:37+01:00" level=debug msg="completed keyexchange" Feb 25 03:25:37 yamaha go-librespot[12594]: time="2026-02-25T03:25:37+01:00" level=debug msg="completed challenge" Feb 25 03:25:37 yamaha go-librespot[12594]: time="2026-02-25T03:25:37+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:25:37 yamaha go-librespot[12594]: time="2026-02-25T03:25:37+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:25:37 yamaha systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 03:25:37 yamaha systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 03:25:37 yamaha volumio[12518]: info: Loading plugin "spop"... Feb 25 03:25:39 yamaha volumio[12518]: info: Loading plugin "outputs"... Feb 25 03:25:39 yamaha volumio[12518]: info: Loading plugin "albumart"... Feb 25 03:25:39 yamaha volumio[12518]: info: Plugin example_plugin is not enabled Feb 25 03:25:39 yamaha volumio[12518]: info: Loading plugin "inputs"... Feb 25 03:25:39 yamaha volumio[12518]: info: Loading plugin "updater_comm"... Feb 25 03:25:39 yamaha volumio[12518]: info: Plugin mpdemulation is not enabled Feb 25 03:25:39 yamaha volumio[12518]: info: Loading plugin "rest_api"... Feb 25 03:25:39 yamaha volumio[12518]: info: Loading plugin "websocket"... Feb 25 03:25:39 yamaha volumio[12518]: info: Starting Socket.io Server version 1.7.4 Feb 25 03:25:39 yamaha volumio[12518]: info: Loading plugin "80s80s"... Feb 25 03:25:40 yamaha volumio[12518]: info: Applying required configuration parameters for plugin 80s80s Feb 25 03:25:40 yamaha volumio[12518]: info: [1771986340113] [80s80s] API delay: 30 Feb 25 03:25:40 yamaha volumio[12518]: info: Loading i18n strings for locale nl Feb 25 03:25:40 yamaha volumio[12518]: Updating browse sources language Feb 25 03:25:40 yamaha volumio[12518]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 25 03:25:40 yamaha volumio[12606]: Forking 3 albumart workers Feb 25 03:25:40 yamaha volumio[12518]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 03:25:40 yamaha volumio[12518]: info: CoreCommandRouter::initPlayerControls Feb 25 03:25:40 yamaha volumio[12518]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:25:40 yamaha volumio[12518]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:25:40 yamaha volumio[12518]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:25:40 yamaha volumio[12518]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:25:40 yamaha volumio[12518]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:25:40 yamaha volumio[12518]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:25:40 yamaha volumio[12518]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:25:40 yamaha volumio[12518]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:25:40 yamaha systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Feb 25 03:25:40 yamaha systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:25:40 yamaha volumio[12518]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 03:25:40 yamaha volumio[12518]: Express server listening on port 3000 Feb 25 03:25:40 yamaha volumio[12518]: [Metrics] WebUI: 14s 145.49ms Feb 25 03:25:40 yamaha systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:25:40 yamaha go-librespot[12627]: go-librespot daemon starting... Feb 25 03:25:40 yamaha volumio[12518]: info: CoreStateMachine::resetVolumioState Feb 25 03:25:40 yamaha volumio[12518]: info: CoreStateMachine::getcurrentVolume Feb 25 03:25:40 yamaha volumio[12518]: info: CoreCommandRouter::volumioRetrievevolume Feb 25 03:25:40 yamaha volumio-remote-updater[629]: [2026-02-25 03:25:40] [connect] Successful connection Feb 25 03:25:40 yamaha go-librespot[12640]: time="2026-02-25T03:25:40+01:00" level=info msg="running go-librespot 0.6.2" Feb 25 03:25:40 yamaha go-librespot[12640]: time="2026-02-25T03:25:40+01:00" level=debug msg="app state loaded" Feb 25 03:25:40 yamaha go-librespot[12640]: time="2026-02-25T03:25:40+01:00" level=debug msg="stored credentials not found" Feb 25 03:25:40 yamaha volumio[12518]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:25:40 yamaha go-librespot[12640]: time="2026-02-25T03:25:40+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 25 03:25:40 yamaha volumio[12518]: info: Cannot mount NAS Ugreen at system boot, trial number 2 ,retrying in 5 seconds Feb 25 03:25:40 yamaha volumio[12518]: info: Volumio Network Manager: Network status updated: 1 Feb 25 03:25:41 yamaha volumio[12518]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Feb 25 03:25:41 yamaha volumio[12518]: wlan0 Interface doesn't support scanning. Feb 25 03:25:41 yamaha volumio[12518]: info: Cannot use regular scanning, forcing with ap-force Feb 25 03:25:41 yamaha sudo[12663]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Feb 25 03:25:41 yamaha sudo[12663]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:25:41 yamaha go-librespot[12640]: time="2026-02-25T03:25:41+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 25 03:25:41 yamaha go-librespot[12640]: time="2026-02-25T03:25:41+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 25 03:25:41 yamaha go-librespot[12640]: time="2026-02-25T03:25:41+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 25 03:25:41 yamaha go-librespot[12640]: time="2026-02-25T03:25:41+01:00" level=info msg="zeroconf server listening on port 33781" Feb 25 03:25:41 yamaha sudo[12663]: pam_unix(sudo:session): session closed for user root Feb 25 03:25:41 yamaha volumio[12518]: command failed: No such device (-19) Feb 25 03:25:41 yamaha volumio[12518]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Feb 25 03:25:41 yamaha volumio[12518]: command failed: No such device (-19) Feb 25 03:25:41 yamaha go-librespot[12640]: time="2026-02-25T03:25:41+01:00" level=debug msg="obtained new client token: AABFI17wFfMTJSJLaZUUml3iv62MT35mynYF0iUOtlvI3xEywwhLkp7X745qitWUx/ihC8zMDi8caC3+xJ86sDta0Knslnee7ylBXvXl000MJ9GrZOPzVILT3RzMNc6pm3Bvl7iJpQq/DRvi/ESN5TlxhWP2mNlq/ZbdYK1RBJYGyKgzfvWEyncIN8jiutu/TZcBih8SSr2bcBrpEGXayVEk6Mf0OsfhAe518v5gTSMsOmgZTrEiETqF" Feb 25 03:25:41 yamaha go-librespot[12640]: time="2026-02-25T03:25:41+01:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.1.133:4070: connect: connection refused" Feb 25 03:25:41 yamaha go-librespot[12640]: time="2026-02-25T03:25:41+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Feb 25 03:25:41 yamaha go-librespot[12640]: time="2026-02-25T03:25:41+01:00" level=debug msg="completed keyexchange" Feb 25 03:25:41 yamaha go-librespot[12640]: time="2026-02-25T03:25:41+01:00" level=debug msg="completed challenge" Feb 25 03:25:41 yamaha go-librespot[12640]: time="2026-02-25T03:25:41+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:25:41 yamaha volumio[12518]: info: VolumeController:: Volume=86 Mute =false Feb 25 03:25:41 yamaha volumio[12518]: info: CoreStateMachine::pushState Feb 25 03:25:41 yamaha volumio[12518]: info: CorePlayQueue::getTrack 0 Feb 25 03:25:41 yamaha volumio[12518]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 03:25:41 yamaha volumio[12518]: info: CoreCommandRouter::volumioPushState Feb 25 03:25:41 yamaha volumio[12518]: info: CoreStateMachine::updateTrackBlock Feb 25 03:25:41 yamaha volumio[12518]: info: CorePlayQueue::getTrackBlock Feb 25 03:25:41 yamaha volumio[12518]: info: CoreCommandRouter::volumioRetrievevolume Feb 25 03:25:41 yamaha sudo[12684]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 25 03:25:41 yamaha sudo[12684]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:25:41 yamaha sudo[12686]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 25 03:25:41 yamaha sudo[12684]: pam_unix(sudo:session): session closed for user root Feb 25 03:25:41 yamaha sudo[12686]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:25:41 yamaha sudo[12686]: pam_unix(sudo:session): session closed for user root Feb 25 03:25:41 yamaha volumio[12518]: info: Reloading queue from file Feb 25 03:25:42 yamaha volumio[12518]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Feb 25 03:25:42 yamaha volumio[12518]: info: CoreStateMachine::setRepeat null single undefined Feb 25 03:25:42 yamaha volumio[12518]: info: CoreStateMachine::pushState Feb 25 03:25:42 yamaha volumio[12518]: info: CorePlayQueue::getTrack 0 Feb 25 03:25:42 yamaha volumio[12518]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 03:25:42 yamaha volumio[12518]: info: CoreCommandRouter::volumioPushState Feb 25 03:25:42 yamaha volumio[12518]: info: CoreStateMachine::setRandom null Feb 25 03:25:42 yamaha volumio[12518]: info: CoreStateMachine::pushState Feb 25 03:25:42 yamaha volumio[12518]: info: CorePlayQueue::getTrack 0 Feb 25 03:25:42 yamaha volumio[12518]: info: CoreCommandRouter::volumioPushState Feb 25 03:25:42 yamaha go-librespot[12640]: time="2026-02-25T03:25:42+01:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:80, retrying with a different AP" error="dial tcp 34.158.1.133:80: connect: connection refused" Feb 25 03:25:42 yamaha volumio[12518]: info: Setting Device type: Raspberry PI Feb 25 03:25:42 yamaha volumio-remote-updater[629]: [2026-02-25 03:25:42] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1771986340 101 Feb 25 03:25:42 yamaha volumio[12518]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 1 Feb 25 03:25:42 yamaha go-librespot[12640]: time="2026-02-25T03:25:42+01:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 25 03:25:42 yamaha volumio[12518]: info: VolumeController:: Volume=86 Mute =false Feb 25 03:25:42 yamaha volumio[12518]: info: CoreStateMachine::pushState Feb 25 03:25:42 yamaha volumio[12518]: info: CorePlayQueue::getTrack 0 Feb 25 03:25:42 yamaha volumio[12518]: info: CoreCommandRouter::volumioPushState Feb 25 03:25:42 yamaha go-librespot[12640]: time="2026-02-25T03:25:42+01:00" level=debug msg="completed keyexchange" Feb 25 03:25:42 yamaha go-librespot[12640]: time="2026-02-25T03:25:42+01:00" level=debug msg="completed challenge" Feb 25 03:25:42 yamaha volumio[12518]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Feb 25 03:25:42 yamaha volumio[12518]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Feb 25 03:25:42 yamaha go-librespot[12640]: time="2026-02-25T03:25:42+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:25:42 yamaha volumio[12518]: info: Discovery: adding 67ba7d11-5499-4b1d-a75e-1a431a777f52 Feb 25 03:25:42 yamaha volumio[12518]: info: Discovery: Found device Yamaha Feb 25 03:25:42 yamaha volumio[12518]: info: CoreCommandRouter::volumioGetState Feb 25 03:25:42 yamaha volumio[12518]: info: CorePlayQueue::getTrack 0 Feb 25 03:25:42 yamaha volumio[12518]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Feb 25 03:25:42 yamaha volumio[12518]: info: Completed loading Core Plugins Feb 25 03:25:42 yamaha sudo[12699]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 25 03:25:42 yamaha volumio[12518]: info: Preparing to generate the ALSA configuration file Feb 25 03:25:42 yamaha sudo[12699]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:25:42 yamaha volumio[12518]: info: Discovery: this is already registered, 67ba7d11-5499-4b1d-a75e-1a431a777f52 Feb 25 03:25:42 yamaha volumio[12518]: info: Discovery: Found device Yamaha Feb 25 03:25:42 yamaha volumio[12518]: info: CoreCommandRouter::volumioGetState Feb 25 03:25:42 yamaha volumio[12518]: info: CorePlayQueue::getTrack 0 Feb 25 03:25:42 yamaha sudo[12699]: pam_unix(sudo:session): session closed for user root Feb 25 03:25:42 yamaha volumio[12518]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Feb 25 03:25:42 yamaha volumio[12518]: info: Upmpdcli Daemon Started Feb 25 03:25:42 yamaha volumio[12518]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Feb 25 03:25:42 yamaha volumio[12518]: info: Asound.conf file unchanged, so no further update is needed Feb 25 03:25:42 yamaha volumio[12518]: info: Output device has changed, restarting MPD Feb 25 03:25:42 yamaha volumio[12518]: info: Output device has changed, restarting Shairport Sync Feb 25 03:25:42 yamaha volumio[12518]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:25:42 yamaha volumio[12518]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:25:42 yamaha volumio[12518]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 25 03:25:42 yamaha sudo[12704]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 25 03:25:42 yamaha sudo[12704]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:25:42 yamaha volumio[12518]: info: ___________ START PLUGINS ___________ Feb 25 03:25:43 yamaha sudo[12706]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 25 03:25:43 yamaha sudo[12706]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:25:43 yamaha sudo[12704]: pam_unix(sudo:session): session closed for user root Feb 25 03:25:43 yamaha volumio[12518]: info: ControllerMpd::onStart: Initializing MPD Feb 25 03:25:43 yamaha volumio[12518]: info: Creating MPD Configuration file Feb 25 03:25:43 yamaha volumio[12518]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 03:25:43 yamaha volumio[12518]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 25 03:25:43 yamaha volumio[12518]: info: [1771986343136] CoreMusicLibrary::Adding element Media Servers Feb 25 03:25:43 yamaha volumio[12518]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 25 03:25:43 yamaha systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 25 03:25:43 yamaha go-librespot[12640]: time="2026-02-25T03:25:43+01:00" level=debug msg="connected to ap-gue1.spotify.com:443" Feb 25 03:25:43 yamaha sudo[12714]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Feb 25 03:25:43 yamaha volumio[12518]: info: UPNP Browser: Client initialized successfully Feb 25 03:25:43 yamaha sudo[12714]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:25:43 yamaha sudo[12716]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 25 03:25:43 yamaha sudo[12716]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:25:43 yamaha sudo[12718]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 25 03:25:43 yamaha sudo[12718]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:25:43 yamaha systemd[1]: mpd.service: Deactivated successfully. Feb 25 03:25:43 yamaha sudo[12716]: pam_unix(sudo:session): session closed for user root Feb 25 03:25:43 yamaha systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 25 03:25:43 yamaha systemd[1]: mpd.service: Consumed 7.370s CPU time. Feb 25 03:25:43 yamaha systemd[1]: mpd.socket: Deactivated successfully. Feb 25 03:25:43 yamaha go-librespot[12640]: time="2026-02-25T03:25:43+01:00" level=debug msg="completed keyexchange" Feb 25 03:25:43 yamaha go-librespot[12640]: time="2026-02-25T03:25:43+01:00" level=debug msg="completed challenge" Feb 25 03:25:43 yamaha systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 25 03:25:43 yamaha systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 25 03:25:43 yamaha volumio[12518]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:25:43 yamaha volumio[12518]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:25:43 yamaha systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 25 03:25:43 yamaha systemd[1]: Starting mpd.service - Music Player Daemon... Feb 25 03:25:43 yamaha go-librespot[12640]: time="2026-02-25T03:25:43+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:25:43 yamaha volumio[12518]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 25 03:25:43 yamaha volumio[12518]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:25:43 yamaha volumio[12518]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:25:43 yamaha sudo[12714]: pam_unix(sudo:session): session closed for user root Feb 25 03:25:43 yamaha systemd[1]: mpd.service: Deactivated successfully. Feb 25 03:25:43 yamaha systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 25 03:25:43 yamaha systemd[1]: mpd.socket: Deactivated successfully. Feb 25 03:25:43 yamaha systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 25 03:25:43 yamaha volumio[12518]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 25 03:25:43 yamaha systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 25 03:25:43 yamaha volumio[12518]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 25 03:25:43 yamaha volumio[12518]: info: [1771986343592] CoreMusicLibrary::Adding element Last_100 Feb 25 03:25:43 yamaha volumio[12518]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 25 03:25:43 yamaha volumio[12518]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 25 03:25:43 yamaha volumio[12518]: info: [1771986343611] CoreMusicLibrary::Adding element Webradio Feb 25 03:25:43 yamaha volumio[12518]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 25 03:25:43 yamaha volumio[12518]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 25 03:25:43 yamaha volumio[12518]: info: Initializing BBC Radios Feb 25 03:25:43 yamaha systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 25 03:25:43 yamaha systemd[1]: Starting mpd.service - Music Player Daemon... Feb 25 03:25:43 yamaha volumio[12518]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 25 03:25:43 yamaha volumio[12518]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:25:43 yamaha volumio[12518]: info: Adding Calm Radio to Browse Sources Feb 25 03:25:43 yamaha volumio[12518]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 25 03:25:43 yamaha volumio[12518]: info: [1771986343899] CoreMusicLibrary::Adding element Calm Radio Feb 25 03:25:43 yamaha volumio[12518]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 25 03:25:43 yamaha volumio[12518]: Cannot find translation for source Calm Radio Feb 25 03:25:44 yamaha volumio[12518]: info: Creating Spotify config file Feb 25 03:25:44 yamaha volumio[12518]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:25:44 yamaha volumio[12618]: Starting albumart workers Feb 25 03:25:44 yamaha go-librespot[12640]: time="2026-02-25T03:25:44+01:00" level=debug msg="connected to ap-gae2.spotify.com:80" Feb 25 03:25:44 yamaha sudo[12735]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 25 03:25:44 yamaha sudo[12735]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 25 03:25:44 yamaha sudo[12735]: pam_unix(sudo:session): session closed for user root Feb 25 03:25:44 yamaha volumio[12616]: Starting albumart workers Feb 25 03:25:44 yamaha go-librespot[12640]: time="2026-02-25T03:25:44+01:00" level=debug msg="completed keyexchange" Feb 25 03:25:44 yamaha go-librespot[12640]: time="2026-02-25T03:25:44+01:00" level=debug msg="completed challenge" Feb 25 03:25:44 yamaha volumio[12617]: Starting albumart workers Feb 25 03:25:44 yamaha go-librespot[12640]: time="2026-02-25T03:25:44+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:25:45 yamaha volumio[12518]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 25 03:25:45 yamaha volumio[12518]: info: [1771986345135] CoreMusicLibrary::Adding element 80s80s Radio Feb 25 03:25:45 yamaha volumio[12518]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 25 03:25:45 yamaha volumio[12518]: Cannot find translation for source Calm Radio Feb 25 03:25:45 yamaha volumio[12518]: Cannot find translation for source 80s80s Radio Feb 25 03:25:45 yamaha volumio[12518]: info: Volumio Calling Home Feb 25 03:25:45 yamaha go-librespot[12640]: time="2026-02-25T03:25:45+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 25 03:25:45 yamaha go-librespot[12640]: time="2026-02-25T03:25:45+01:00" level=debug msg="completed keyexchange" Feb 25 03:25:45 yamaha go-librespot[12640]: time="2026-02-25T03:25:45+01:00" level=debug msg="completed challenge" Feb 25 03:25:45 yamaha go-librespot[12640]: time="2026-02-25T03:25:45+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:25:45 yamaha go-librespot[12640]: time="2026-02-25T03:25:45+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Feb 25 03:25:45 yamaha go-librespot[12640]: time="2026-02-25T03:25:45+01:00" level=debug msg="completed keyexchange" Feb 25 03:25:45 yamaha go-librespot[12640]: time="2026-02-25T03:25:45+01:00" level=debug msg="completed challenge" Feb 25 03:25:45 yamaha go-librespot[12640]: time="2026-02-25T03:25:45+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:25:45 yamaha go-librespot[12640]: time="2026-02-25T03:25:45+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:25:45 yamaha systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 03:25:45 yamaha systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 03:25:45 yamaha systemd[1]: go-librespot-daemon.service: Consumed 1.110s CPU time. Feb 25 03:25:46 yamaha volumio[12518]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Feb 25 03:25:46 yamaha volumio[12518]: info: Cannot mount NAS Ugreen at system boot, trial number 3 ,retrying in 5 seconds Feb 25 03:25:46 yamaha volumio[12518]: info: MPD Permissions set Feb 25 03:25:46 yamaha volumio[12518]: info: MPD Permissions set Feb 25 03:25:46 yamaha volumio[12518]: info: Volumio called home Feb 25 03:25:46 yamaha volumio[12518]: info: Spotify config file written Feb 25 03:25:46 yamaha sudo[12771]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 25 03:25:46 yamaha sudo[12771]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:25:46 yamaha systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:25:46 yamaha systemd[1]: go-librespot-daemon.service: Consumed 1.110s CPU time. Feb 25 03:25:47 yamaha systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:25:47 yamaha go-librespot[12773]: go-librespot daemon starting... Feb 25 03:25:47 yamaha sudo[12771]: pam_unix(sudo:session): session closed for user root Feb 25 03:25:47 yamaha go-librespot[12774]: time="2026-02-25T03:25:47+01:00" level=info msg="running go-librespot 0.6.2" Feb 25 03:25:47 yamaha go-librespot[12774]: time="2026-02-25T03:25:47+01:00" level=debug msg="app state loaded" Feb 25 03:25:47 yamaha go-librespot[12774]: time="2026-02-25T03:25:47+01:00" level=debug msg="stored credentials not found" Feb 25 03:25:47 yamaha go-librespot[12774]: time="2026-02-25T03:25:47+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 25 03:25:47 yamaha volumio[12518]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Feb 25 03:25:47 yamaha volumio[12518]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:25:47 yamaha volumio[12518]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:25:47 yamaha volumio[12518]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:25:47 yamaha volumio[12518]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:25:47 yamaha volumio[12518]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:25:47 yamaha volumio[12518]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:25:47 yamaha volumio[12518]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:25:47 yamaha volumio[12518]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:25:47 yamaha volumio[12518]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:25:47 yamaha volumio[12518]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:25:47 yamaha volumio[12518]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:25:47 yamaha volumio[12518]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:25:47 yamaha volumio[12518]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:25:47 yamaha volumio[12518]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:25:47 yamaha volumio[12518]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:25:47 yamaha volumio[12518]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:25:47 yamaha volumio[12518]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:25:47 yamaha volumio[12518]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:25:47 yamaha volumio[12518]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:25:47 yamaha volumio[12518]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:25:47 yamaha volumio[12518]: info: No need to fix Spotify hosts Feb 25 03:25:47 yamaha go-librespot[12774]: time="2026-02-25T03:25:47+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 25 03:25:47 yamaha go-librespot[12774]: time="2026-02-25T03:25:47+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 25 03:25:47 yamaha go-librespot[12774]: time="2026-02-25T03:25:47+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 25 03:25:47 yamaha go-librespot[12774]: time="2026-02-25T03:25:47+01:00" level=info msg="zeroconf server listening on port 34945" Feb 25 03:25:47 yamaha go-librespot[12774]: time="2026-02-25T03:25:47+01:00" level=debug msg="obtained new client token: AAArW8n2R/MGnKhGBccKyqtAb4MOOzdLipIFVCBUE5JpSeJSZrCLS7Y9aSIUVPGWm7Lbfz/H9BeBtmjNXh63wrTapB1Z1sX3bN+U9h1p4Vkwb7ljZI1KYHUMs8uQ9TIqLxOGXMF8Ezm8d4DS62U1syjtuH0dK14eX+iXfwFGzRo6qHlqM8Sch2aVWcTXPuiMs53y5p1YOrMLCSPtD3txSeRQGdkMrWihZ2FXeVBivIndPHZDzrz5UjCq" Feb 25 03:25:47 yamaha volumio[12518]: info: New Spotify access tokenBQDnoEnyH0... Feb 25 03:25:47 yamaha volumio[12518]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 25 03:25:47 yamaha go-librespot[12774]: time="2026-02-25T03:25:47+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 25 03:25:47 yamaha go-librespot[12774]: time="2026-02-25T03:25:47+01:00" level=debug msg="completed keyexchange" Feb 25 03:25:47 yamaha go-librespot[12774]: time="2026-02-25T03:25:47+01:00" level=debug msg="completed challenge" Feb 25 03:25:47 yamaha volumio[12518]: info: Starting Shairport Sync Feb 25 03:25:47 yamaha volumio[12518]: info: Starting Shairport Sync Feb 25 03:25:47 yamaha go-librespot[12774]: time="2026-02-25T03:25:47+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:25:47 yamaha volumio[12518]: info: Starting Shairport Sync Feb 25 03:25:47 yamaha sudo[12795]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 25 03:25:47 yamaha sudo[12795]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:25:47 yamaha sudo[12797]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 25 03:25:47 yamaha sudo[12797]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:25:48 yamaha sudo[12799]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 25 03:25:48 yamaha sudo[12799]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:25:48 yamaha systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 25 03:25:48 yamaha volumio[12518]: info: CoreCommandRouter::volumioGetState Feb 25 03:25:48 yamaha volumio[12518]: info: CorePlayQueue::getTrack 0 Feb 25 03:25:48 yamaha systemd[1]: shairport-sync.service: Deactivated successfully. Feb 25 03:25:48 yamaha systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 25 03:25:48 yamaha systemd[1]: shairport-sync.service: Consumed 2.497s CPU time. Feb 25 03:25:48 yamaha volumio[12518]: SPOTIFY: User informations: {"country":"IN","display_name":"struikjan","email":"jan.struik@icloud.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/struikjan"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/struikjan","id":"struikjan","images":[],"product":"free","type":"user","uri":"spotify:user:struikjan"} Feb 25 03:25:48 yamaha volumio[12518]: info: Spotify Successfully logged in Feb 25 03:25:48 yamaha volumio[12518]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 25 03:25:48 yamaha systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 25 03:25:48 yamaha volumio[12518]: info: [1771986348287] CoreMusicLibrary::Adding element Spotify Feb 25 03:25:48 yamaha volumio[12518]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 25 03:25:48 yamaha volumio[12518]: Cannot find translation for source Calm Radio Feb 25 03:25:48 yamaha volumio[12518]: Cannot find translation for source 80s80s Radio Feb 25 03:25:48 yamaha volumio[12518]: Cannot find translation for source Spotify Feb 25 03:25:48 yamaha sudo[12795]: pam_unix(sudo:session): session closed for user root Feb 25 03:25:48 yamaha sudo[12797]: pam_unix(sudo:session): session closed for user root Feb 25 03:25:48 yamaha sudo[12799]: pam_unix(sudo:session): session closed for user root Feb 25 03:25:48 yamaha volumio[12518]: info: Shairport-Sync Started Feb 25 03:25:48 yamaha volumio[12518]: Error adding Membership: Error: addMembership EINVAL Feb 25 03:25:48 yamaha volumio[12518]: info: Shairport-Sync Started Feb 25 03:25:48 yamaha volumio[12518]: info: Shairport-Sync Started Feb 25 03:25:48 yamaha go-librespot[12774]: time="2026-02-25T03:25:48+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Feb 25 03:25:48 yamaha go-librespot[12774]: time="2026-02-25T03:25:48+01:00" level=debug msg="completed keyexchange" Feb 25 03:25:48 yamaha go-librespot[12774]: time="2026-02-25T03:25:48+01:00" level=debug msg="completed challenge" Feb 25 03:25:48 yamaha go-librespot[12774]: time="2026-02-25T03:25:48+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:25:49 yamaha go-librespot[12774]: time="2026-02-25T03:25:49+01:00" level=debug msg="connected to ap-gew4.spotify.com:80" Feb 25 03:25:49 yamaha go-librespot[12774]: time="2026-02-25T03:25:49+01:00" level=debug msg="completed keyexchange" Feb 25 03:25:49 yamaha go-librespot[12774]: time="2026-02-25T03:25:49+01:00" level=debug msg="completed challenge" Feb 25 03:25:49 yamaha go-librespot[12774]: time="2026-02-25T03:25:49+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:25:49 yamaha go-librespot[12774]: time="2026-02-25T03:25:49+01:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 25 03:25:49 yamaha go-librespot[12774]: time="2026-02-25T03:25:49+01:00" level=debug msg="completed keyexchange" Feb 25 03:25:49 yamaha go-librespot[12774]: time="2026-02-25T03:25:49+01:00" level=debug msg="completed challenge" Feb 25 03:25:50 yamaha go-librespot[12774]: time="2026-02-25T03:25:50+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:25:50 yamaha mpd[12748]: 2026-02-25T03:25:50 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 25 03:25:50 yamaha go-librespot[12774]: time="2026-02-25T03:25:50+01:00" level=debug msg="connected to ap-gue1.spotify.com:443" Feb 25 03:25:50 yamaha systemd[1]: Started mpd.service - Music Player Daemon. Feb 25 03:25:50 yamaha sudo[12706]: pam_unix(sudo:session): session closed for user root Feb 25 03:25:50 yamaha sudo[12718]: pam_unix(sudo:session): session closed for user root Feb 25 03:25:50 yamaha volumio[12518]: info: go-librespot daemon successfully initialized Feb 25 03:25:50 yamaha go-librespot[12774]: time="2026-02-25T03:25:50+01:00" level=debug msg="completed keyexchange" Feb 25 03:25:50 yamaha go-librespot[12774]: time="2026-02-25T03:25:50+01:00" level=debug msg="completed challenge" Feb 25 03:25:50 yamaha volumio[12518]: info: Completed starting Core Plugins Feb 25 03:25:50 yamaha volumio[12518]: info: ------------------------------------------- Feb 25 03:25:50 yamaha volumio[12518]: info: ----- MyVolumio plugins startup ---- Feb 25 03:25:50 yamaha volumio[12518]: info: ------------------------------------------- Feb 25 03:25:50 yamaha volumio[12518]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 25 03:25:50 yamaha go-librespot[12774]: time="2026-02-25T03:25:50+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:25:51 yamaha volumio[12518]: error: MPD error: The expression evaluated to a falsy value: Feb 25 03:25:51 yamaha volumio[12518]: assert.ok(self.idling) Feb 25 03:25:51 yamaha volumio[12518]: error: The expression evaluated to a falsy value: Feb 25 03:25:51 yamaha volumio[12518]: assert.ok(self.idling) Feb 25 03:25:51 yamaha volumio[12518]: info: MPD running with PID12748 Feb 25 03:25:51 yamaha volumio[12518]: ,establishing connection Feb 25 03:25:51 yamaha volumio[12518]: error: updateQueue error: null Feb 25 03:25:51 yamaha volumio[12518]: error: updateQueue error: null Feb 25 03:25:51 yamaha volumio[12518]: info: Cannot mount NAS Ugreen at system boot, trial number 4 ,retrying in 5 seconds Feb 25 03:25:51 yamaha go-librespot[12774]: time="2026-02-25T03:25:51+01:00" level=debug msg="connected to ap-gew1.spotify.com:80" Feb 25 03:25:51 yamaha go-librespot[12774]: time="2026-02-25T03:25:51+01:00" level=debug msg="completed keyexchange" Feb 25 03:25:51 yamaha go-librespot[12774]: time="2026-02-25T03:25:51+01:00" level=debug msg="completed challenge" Feb 25 03:25:51 yamaha go-librespot[12774]: time="2026-02-25T03:25:51+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:25:51 yamaha go-librespot[12774]: time="2026-02-25T03:25:51+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:25:51 yamaha systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 03:25:51 yamaha systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 03:25:51 yamaha systemd[1]: go-librespot-daemon.service: Consumed 1.018s CPU time. Feb 25 03:25:53 yamaha volumio[12518]: info: Initializing connection to go-librespot Websocket Feb 25 03:25:53 yamaha volumio[12518]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 25 03:25:54 yamaha systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Feb 25 03:25:54 yamaha systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:25:54 yamaha systemd[1]: go-librespot-daemon.service: Consumed 1.018s CPU time. Feb 25 03:25:54 yamaha systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:25:54 yamaha go-librespot[12839]: go-librespot daemon starting... Feb 25 03:25:54 yamaha systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Feb 25 03:25:54 yamaha go-librespot[12840]: time="2026-02-25T03:25:54+01:00" level=info msg="running go-librespot 0.6.2" Feb 25 03:25:54 yamaha go-librespot[12840]: time="2026-02-25T03:25:54+01:00" level=debug msg="app state loaded" Feb 25 03:25:54 yamaha go-librespot[12840]: time="2026-02-25T03:25:54+01:00" level=debug msg="stored credentials not found" Feb 25 03:25:54 yamaha go-librespot[12840]: time="2026-02-25T03:25:54+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 25 03:25:55 yamaha go-librespot[12840]: time="2026-02-25T03:25:55+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 25 03:25:55 yamaha go-librespot[12840]: time="2026-02-25T03:25:55+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 25 03:25:55 yamaha go-librespot[12840]: time="2026-02-25T03:25:55+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 25 03:25:55 yamaha go-librespot[12840]: time="2026-02-25T03:25:55+01:00" level=info msg="zeroconf server listening on port 42297" Feb 25 03:25:55 yamaha go-librespot[12840]: time="2026-02-25T03:25:55+01:00" level=debug msg="obtained new client token: AADmUZsllZr8tRQX+OWB3Uiueke0EeRhnh7Lvq1rdeX0QIgyb6ab949t66XEHlOU5Hfmp+9esMWBJfJHO2emsEhZ2YhMyzDxD9YmCOYUMhWLvb9fhdbZqHT1I5Gp+3wF2VzK0ixtYJOsW7U7grgnI47speukl7oZTpi/361Z6wx2E/ZBkBbch3Hqk6D10+yp9Ar2nLDqTHK47/SyUr/y57lqLgdjDwWxqij/76TnaKPv2K/GpOXEkhSP" Feb 25 03:25:55 yamaha go-librespot[12840]: time="2026-02-25T03:25:55+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 25 03:25:55 yamaha go-librespot[12840]: time="2026-02-25T03:25:55+01:00" level=debug msg="completed keyexchange" Feb 25 03:25:55 yamaha go-librespot[12840]: time="2026-02-25T03:25:55+01:00" level=debug msg="completed challenge" Feb 25 03:25:55 yamaha go-librespot[12840]: time="2026-02-25T03:25:55+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:25:56 yamaha go-librespot[12840]: time="2026-02-25T03:25:56+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Feb 25 03:25:56 yamaha go-librespot[12840]: time="2026-02-25T03:25:56+01:00" level=debug msg="completed keyexchange" Feb 25 03:25:56 yamaha go-librespot[12840]: time="2026-02-25T03:25:56+01:00" level=debug msg="completed challenge" Feb 25 03:25:56 yamaha go-librespot[12840]: time="2026-02-25T03:25:56+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:25:56 yamaha volumio[12518]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 25 03:25:56 yamaha volumio[12518]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Feb 25 03:25:56 yamaha go-librespot[12840]: time="2026-02-25T03:25:56+01:00" level=debug msg="connected to ap-gew4.spotify.com:80" Feb 25 03:25:56 yamaha go-librespot[12840]: time="2026-02-25T03:25:56+01:00" level=debug msg="completed keyexchange" Feb 25 03:25:56 yamaha go-librespot[12840]: time="2026-02-25T03:25:56+01:00" level=debug msg="completed challenge" Feb 25 03:25:56 yamaha go-librespot[12840]: time="2026-02-25T03:25:56+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:25:56 yamaha systemd[1]: setdatetime-helper.service: Deactivated successfully. Feb 25 03:25:56 yamaha systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Feb 25 03:25:56 yamaha systemd[1]: setdatetime-helper.service: Consumed 1.850s CPU time. Feb 25 03:25:56 yamaha volumio[12518]: info: Initializing connection to go-librespot Websocket Feb 25 03:25:57 yamaha go-librespot[12840]: time="2026-02-25T03:25:57+01:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Feb 25 03:25:57 yamaha go-librespot[12840]: time="2026-02-25T03:25:57+01:00" level=debug msg="completed keyexchange" Feb 25 03:25:57 yamaha go-librespot[12840]: time="2026-02-25T03:25:57+01:00" level=debug msg="completed challenge" Feb 25 03:25:57 yamaha go-librespot[12840]: time="2026-02-25T03:25:57+01:00" level=debug msg="new websocket client" Feb 25 03:25:57 yamaha volumio[12518]: info: Connection to go-librespot Websocket established Feb 25 03:25:57 yamaha go-librespot[12840]: time="2026-02-25T03:25:57+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:25:58 yamaha go-librespot[12840]: time="2026-02-25T03:25:58+01:00" level=debug msg="connected to ap-gew1.spotify.com:443" Feb 25 03:25:58 yamaha go-librespot[12840]: time="2026-02-25T03:25:58+01:00" level=debug msg="completed keyexchange" Feb 25 03:25:58 yamaha go-librespot[12840]: time="2026-02-25T03:25:58+01:00" level=debug msg="completed challenge" Feb 25 03:25:58 yamaha go-librespot[12840]: time="2026-02-25T03:25:58+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:25:59 yamaha go-librespot[12840]: time="2026-02-25T03:25:59+01:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:80, retrying with a different AP" error="dial tcp 104.199.241.202:80: connect: connection refused" Feb 25 03:25:59 yamaha go-librespot[12840]: time="2026-02-25T03:25:59+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 25 03:25:59 yamaha go-librespot[12840]: time="2026-02-25T03:25:59+01:00" level=debug msg="completed keyexchange" Feb 25 03:25:59 yamaha go-librespot[12840]: time="2026-02-25T03:25:59+01:00" level=debug msg="completed challenge" Feb 25 03:25:59 yamaha go-librespot[12840]: time="2026-02-25T03:25:59+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:25:59 yamaha go-librespot[12840]: time="2026-02-25T03:25:59+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:25:59 yamaha systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 03:25:59 yamaha systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 03:25:59 yamaha volumio[12518]: info: Connection to go-librespot Websocket closed Feb 25 03:25:59 yamaha volumio[12518]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 25 03:25:59 yamaha volumio[12518]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 25 03:25:59 yamaha volumio[12518]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 25 03:25:59 yamaha volumio[12518]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 25 03:25:59 yamaha volumio[12518]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 25 03:25:59 yamaha volumio[12518]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 25 03:25:59 yamaha volumio[12518]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 25 03:25:59 yamaha volumio[12518]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 25 03:25:59 yamaha volumio[12518]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 25 03:25:59 yamaha volumio[12518]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 25 03:25:59 yamaha volumio[12518]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 25 03:25:59 yamaha volumio[12518]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 25 03:25:59 yamaha volumio[12518]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 25 03:25:59 yamaha volumio[12518]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 25 03:25:59 yamaha volumio[12518]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 25 03:25:59 yamaha volumio[12518]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 25 03:25:59 yamaha volumio[12518]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 25 03:25:59 yamaha volumio[12518]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 25 03:25:59 yamaha volumio[12518]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 25 03:25:59 yamaha volumio[12518]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 25 03:25:59 yamaha volumio[12518]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 25 03:25:59 yamaha volumio[12518]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 25 03:25:59 yamaha volumio[12518]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 25 03:25:59 yamaha volumio[12518]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 25 03:25:59 yamaha volumio[12518]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 25 03:25:59 yamaha volumio[12518]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 25 03:25:59 yamaha volumio[12518]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 25 03:25:59 yamaha volumio[12518]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 25 03:25:59 yamaha volumio[12518]: info: Adding plugin bluetooth to MyMusic Plugins Feb 25 03:25:59 yamaha volumio[12518]: info: Adding plugin multiroom to MyMusic Plugins Feb 25 03:25:59 yamaha volumio[12518]: info: Adding plugin metavolumio to MyMusic Plugins Feb 25 03:25:59 yamaha volumio[12518]: info: Adding plugin cd_controller to MyMusic Plugins Feb 25 03:25:59 yamaha volumio[12518]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 25 03:25:59 yamaha volumio[12518]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 25 03:25:59 yamaha volumio[12518]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 25 03:25:59 yamaha volumio[12518]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 25 03:26:01 yamaha volumio[12518]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 25 03:26:01 yamaha volumio[12518]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 25 03:26:01 yamaha volumio[12518]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:26:01 yamaha volumio[12518]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:26:01 yamaha volumio[12518]: info: Starting MyVolumio Remote Streaming Endpoints Feb 25 03:26:01 yamaha volumio[12518]: info: MyVolumio login type: Token Feb 25 03:26:01 yamaha volumio[12518]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 25 03:26:01 yamaha volumio[12518]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 25 03:26:02 yamaha systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Feb 25 03:26:02 yamaha systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:26:02 yamaha systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:26:02 yamaha go-librespot[12891]: go-librespot daemon starting... Feb 25 03:26:02 yamaha go-librespot[12892]: time="2026-02-25T03:26:02+01:00" level=info msg="running go-librespot 0.6.2" Feb 25 03:26:02 yamaha go-librespot[12892]: time="2026-02-25T03:26:02+01:00" level=debug msg="app state loaded" Feb 25 03:26:02 yamaha go-librespot[12892]: time="2026-02-25T03:26:02+01:00" level=debug msg="stored credentials not found" Feb 25 03:26:02 yamaha go-librespot[12892]: time="2026-02-25T03:26:02+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 25 03:26:02 yamaha go-librespot[12892]: time="2026-02-25T03:26:02+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 25 03:26:02 yamaha go-librespot[12892]: time="2026-02-25T03:26:02+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 25 03:26:02 yamaha go-librespot[12892]: time="2026-02-25T03:26:02+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 25 03:26:02 yamaha go-librespot[12892]: time="2026-02-25T03:26:02+01:00" level=info msg="zeroconf server listening on port 37745" Feb 25 03:26:02 yamaha volumio[12518]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 25 03:26:02 yamaha volumio[12518]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 25 03:26:02 yamaha volumio[12518]: info: Streaming services startup Feb 25 03:26:02 yamaha volumio[12518]: info: Starting Streaming Daemon Feb 25 03:26:02 yamaha sudo[12902]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 25 03:26:02 yamaha sudo[12902]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:26:02 yamaha go-librespot[12892]: time="2026-02-25T03:26:02+01:00" level=debug msg="obtained new client token: AAB2p4Hd/Cyqu1IGU/grBetyf6DUjmHS/o7LIK/aKIjUiio/55LoVRhAoyaif/JjywnJ/pA/hsDLC2W1AZ0CNUp9HhSZx2ID3pXRLST2f+Dzw3+sfiX9pd137TqiE6XvLKpKSVoeIem/4QlBavYNmS9jBvbM5K8tijf9+eeskRM3O2/5sqrkxzttphDkpJeHA2eCjMNhtM+poYFscAa6qd3ZMKX2COnKHC1TAGsd4Gphvt1in+pdF4Pd" Feb 25 03:26:02 yamaha volumio[12518]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 25 03:26:03 yamaha go-librespot[12892]: time="2026-02-25T03:26:03+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 25 03:26:03 yamaha sudo[12902]: pam_unix(sudo:session): session closed for user root Feb 25 03:26:03 yamaha go-librespot[12892]: time="2026-02-25T03:26:03+01:00" level=debug msg="completed keyexchange" Feb 25 03:26:03 yamaha go-librespot[12892]: time="2026-02-25T03:26:03+01:00" level=debug msg="completed challenge" Feb 25 03:26:03 yamaha go-librespot[12892]: time="2026-02-25T03:26:03+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:26:03 yamaha volumio[12518]: info: Getting Spotify volume Feb 25 03:26:03 yamaha volumio[12518]: info: Initializing connection to go-librespot Websocket Feb 25 03:26:03 yamaha volumio[12518]: error: Cannot start Volumio Streaming Daemon Feb 25 03:26:03 yamaha volumio[12518]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 25 03:26:03 yamaha volumio[12518]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 25 03:26:03 yamaha go-librespot[12892]: time="2026-02-25T03:26:03+01:00" level=debug msg="new websocket client" Feb 25 03:26:03 yamaha volumio[12518]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Feb 25 03:26:03 yamaha volumio[12518]: info: Connection to go-librespot Websocket established Feb 25 03:26:03 yamaha go-librespot[12892]: time="2026-02-25T03:26:03+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Feb 25 03:26:03 yamaha go-librespot[12892]: time="2026-02-25T03:26:03+01:00" level=debug msg="completed keyexchange" Feb 25 03:26:03 yamaha go-librespot[12892]: time="2026-02-25T03:26:03+01:00" level=debug msg="completed challenge" Feb 25 03:26:03 yamaha volumio[12518]: info: CoreCommandRouter::volumioGetState Feb 25 03:26:03 yamaha go-librespot[12892]: time="2026-02-25T03:26:03+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:26:03 yamaha volumio[12518]: info: CorePlayQueue::getTrack 0 Feb 25 03:26:03 yamaha volumio[12518]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 25 03:26:03 yamaha volumio[12518]: SPOTIFY: SPOTIFY VOLUME undefined Feb 25 03:26:03 yamaha volumio[12518]: SPOTIFY: VOLUMIO VOLUME 86 Feb 25 03:26:03 yamaha volumio[12518]: info: Aligning Spotify Volume to Volumio Volume Feb 25 03:26:03 yamaha volumio[12518]: info: CoreCommandRouter::volumioGetState Feb 25 03:26:03 yamaha volumio[12518]: info: CorePlayQueue::getTrack 0 Feb 25 03:26:03 yamaha volumio[12518]: info: Setting Spotify Volume from Volumio: 86 Feb 25 03:26:03 yamaha volumio[12518]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Feb 25 03:26:04 yamaha go-librespot[12892]: time="2026-02-25T03:26:04+01:00" level=debug msg="connected to ap-gew4.spotify.com:80" Feb 25 03:26:04 yamaha go-librespot[12892]: time="2026-02-25T03:26:04+01:00" level=debug msg="completed keyexchange" Feb 25 03:26:04 yamaha go-librespot[12892]: time="2026-02-25T03:26:04+01:00" level=debug msg="completed challenge" Feb 25 03:26:04 yamaha go-librespot[12892]: time="2026-02-25T03:26:04+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:26:04 yamaha volumio[12518]: info: MyVolumio token set successfully Feb 25 03:26:04 yamaha volumio[12518]: info: MYVOLUMIO: Adding device Feb 25 03:26:04 yamaha volumio[12518]: info: MYVOLUMIO: Evaluating Server Feb 25 03:26:04 yamaha go-librespot[12892]: time="2026-02-25T03:26:04+01:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 25 03:26:05 yamaha go-librespot[12892]: time="2026-02-25T03:26:05+01:00" level=debug msg="completed keyexchange" Feb 25 03:26:05 yamaha go-librespot[12892]: time="2026-02-25T03:26:05+01:00" level=debug msg="completed challenge" Feb 25 03:26:05 yamaha volumio[12518]: info: MyVolumio status changed Feb 25 03:26:05 yamaha volumio[12518]: info: Streaming services startup Feb 25 03:26:05 yamaha volumio[12518]: info: Starting Streaming Daemon Feb 25 03:26:05 yamaha volumio[12518]: info: Removing browser output: myVolumio user plan is not superstar Feb 25 03:26:05 yamaha volumio[12518]: info: Removing audio output: Feb 25 03:26:05 yamaha volumio[12518]: info: Stoppping Tunnel 1 Feb 25 03:26:05 yamaha sudo[12929]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 25 03:26:05 yamaha sudo[12929]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:26:05 yamaha sudo[12931]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Feb 25 03:26:05 yamaha sudo[12931]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:26:05 yamaha go-librespot[12892]: time="2026-02-25T03:26:05+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:26:05 yamaha sudo[12929]: pam_unix(sudo:session): session closed for user root Feb 25 03:26:05 yamaha volumio[12518]: info: Setting Geolocation for MyVolumio to eu2 Feb 25 03:26:05 yamaha volumio[12518]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:26:05 yamaha volumio[12518]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:26:05 yamaha volumio[12518]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:26:05 yamaha systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 25 03:26:05 yamaha systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 25 03:26:05 yamaha volumio[12518]: SPOTIFY: SETTING SPOTIFY VOLUME 86 Feb 25 03:26:05 yamaha volumio[12518]: info: Sending Spotify command with payload to local API: /player/volume Feb 25 03:26:05 yamaha systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 25 03:26:05 yamaha systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 25 03:26:05 yamaha volumio[12518]: error: Cannot start Volumio Streaming Daemon Feb 25 03:26:05 yamaha volumio[12518]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 25 03:26:05 yamaha volumio[12518]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 25 03:26:05 yamaha systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 25 03:26:05 yamaha systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 25 03:26:05 yamaha sudo[12931]: pam_unix(sudo:session): session closed for user root Feb 25 03:26:05 yamaha volumio[12518]: info: Remote SSH Stopped Feb 25 03:26:05 yamaha volumio[12518]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Feb 25 03:26:05 yamaha go-librespot[12892]: time="2026-02-25T03:26:05+01:00" level=debug msg="connected to ap-gue1.spotify.com:443" Feb 25 03:26:05 yamaha go-librespot[12892]: time="2026-02-25T03:26:05+01:00" level=debug msg="completed keyexchange" Feb 25 03:26:05 yamaha go-librespot[12892]: time="2026-02-25T03:26:05+01:00" level=debug msg="completed challenge" Feb 25 03:26:06 yamaha go-librespot[12892]: time="2026-02-25T03:26:06+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:26:06 yamaha volumio[12518]: info: Updating MyVolumio device info Feb 25 03:26:06 yamaha volumio[12518]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:26:06 yamaha volumio[12518]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:26:06 yamaha volumio[12518]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:26:06 yamaha volumio[12518]: info: Getting Spotify volume Feb 25 03:26:06 yamaha volumio[12518]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Feb 25 03:26:06 yamaha volumio[12518]: info: CoreCommandRouter::volumioGetState Feb 25 03:26:06 yamaha volumio[12518]: info: CorePlayQueue::getTrack 0 Feb 25 03:26:06 yamaha volumio[12518]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 25 03:26:06 yamaha go-librespot[12892]: time="2026-02-25T03:26:06+01:00" level=debug msg="connected to ap-gew1.spotify.com:80" Feb 25 03:26:06 yamaha go-librespot[12892]: time="2026-02-25T03:26:06+01:00" level=debug msg="completed keyexchange" Feb 25 03:26:06 yamaha go-librespot[12892]: time="2026-02-25T03:26:06+01:00" level=debug msg="completed challenge" Feb 25 03:26:06 yamaha go-librespot[12892]: time="2026-02-25T03:26:06+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:26:06 yamaha go-librespot[12892]: time="2026-02-25T03:26:06+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:26:06 yamaha systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 03:26:06 yamaha systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 03:26:06 yamaha volumio[12518]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 25 03:26:06 yamaha volumio[12518]: Error: socket hang up Feb 25 03:26:06 yamaha volumio[12518]: at connResetException (node:internal/errors:720:14) Feb 25 03:26:06 yamaha volumio[12518]: at Socket.socketOnEnd (node:_http_client:519:23) Feb 25 03:26:06 yamaha volumio[12518]: at Socket.emit (node:events:526:35) Feb 25 03:26:06 yamaha volumio[12518]: at endReadableNT (node:internal/streams/readable:1376:12) Feb 25 03:26:06 yamaha volumio[12518]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Feb 25 03:26:06 yamaha volumio[12518]: code: 'ECONNRESET', Feb 25 03:26:06 yamaha volumio[12518]: response: undefined Feb 25 03:26:06 yamaha volumio[12518]: } Feb 25 03:26:06 yamaha volumio[12518]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 25 03:26:08 yamaha sudo[12948]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-25 03:25' Feb 25 03:26:08 yamaha sudo[12948]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:26:08 yamaha sudo[12948]: pam_unix(sudo:session): session closed for user root Feb 25 03:26:08 yamaha volumio-remote-updater[629]: [2026-02-25 03:26:08] [error] handle_read_frame error: asio.system:104 (Connection reset by peer) Feb 25 03:26:08 yamaha volumio-remote-updater[629]: [2026-02-25 03:26:08] [disconnect] Disconnect close local:[1006,Connection reset by peer] remote:[1006] Feb 25 03:26:08 yamaha volumio5-onboarding[1284]: time=2026-02-25T03:26:08.546+01:00 level=ERROR msg="failed reading message" component=volumio/socket error="read tcp 127.0.0.1:43908->127.0.0.1:3000: read: connection reset by peer" Feb 25 03:26:08 yamaha volumio5-onboarding[1284]: time=2026-02-25T03:26:08.551+01:00 level=WARN msg="reconnection attempt failed" component=volumio/socket error="dial tcp 127.0.0.1:3000: connect: connection refused" Feb 25 03:26:08 yamaha systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 25 03:26:08 yamaha systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 25 03:26:08 yamaha systemd[1]: volumio.service: Consumed 57.620s CPU time. Feb 25 03:26:08 yamaha systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 25 03:26:08 yamaha systemd[1]: dynamicswap.service: Deactivated successfully. Feb 25 03:26:08 yamaha systemd[1]: volumio.service: Scheduled restart job, restart counter is at 198. Feb 25 03:26:08 yamaha systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 25 03:26:08 yamaha systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 25 03:26:08 yamaha systemd[1]: volumio.service: Consumed 57.620s CPU time. Feb 25 03:26:08 yamaha systemd[1]: Started volumio.service - Volumio Backend Module. Feb 25 03:26:08 yamaha systemd[1]: dynamicswap.service: Deactivated successfully. Feb 25 03:26:09 yamaha volumio5-onboarding[1284]: time=2026-02-25T03:26:09.554+01:00 level=WARN msg="reconnection attempt failed" component=volumio/socket error="dial tcp 127.0.0.1:3000: connect: connection refused" Feb 25 03:26:09 yamaha systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Feb 25 03:26:09 yamaha systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:26:09 yamaha systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:26:09 yamaha go-librespot[12981]: go-librespot daemon starting... Feb 25 03:26:09 yamaha go-librespot[12982]: time="2026-02-25T03:26:09+01:00" level=info msg="running go-librespot 0.6.2" Feb 25 03:26:09 yamaha go-librespot[12982]: time="2026-02-25T03:26:09+01:00" level=debug msg="app state loaded" Feb 25 03:26:09 yamaha go-librespot[12982]: time="2026-02-25T03:26:09+01:00" level=debug msg="stored credentials not found" Feb 25 03:26:09 yamaha go-librespot[12982]: time="2026-02-25T03:26:09+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 25 03:26:10 yamaha go-librespot[12982]: time="2026-02-25T03:26:10+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 25 03:26:10 yamaha go-librespot[12982]: time="2026-02-25T03:26:10+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 25 03:26:10 yamaha go-librespot[12982]: time="2026-02-25T03:26:10+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 25 03:26:10 yamaha go-librespot[12982]: time="2026-02-25T03:26:10+01:00" level=info msg="zeroconf server listening on port 38543" Feb 25 03:26:10 yamaha go-librespot[12982]: time="2026-02-25T03:26:10+01:00" level=debug msg="obtained new client token: AACx3KgcC/Mx7N7XgRCzC3H+Ybc6wKWrNe+lmZncESa30cFt4EU/DiUsh2XRJnQJHaZXaYZp6CHs98+HImhZWNFX5CaExYZTg3MjtKemYtax9Nw2Nyg6OZo+bx08nEDJKhyHAFVyiY7h7OtSfjKsW+MfrY7e9WNVIOtC231oFAnQ3yVU9i02/a+w2Y/Qb2BbqR4rFyxgQ+egCWAnwZwF1qdRacw7fUkOztRJRjikprFUxwGBZNIajyZ7" Feb 25 03:26:10 yamaha go-librespot[12982]: time="2026-02-25T03:26:10+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 25 03:26:10 yamaha go-librespot[12982]: time="2026-02-25T03:26:10+01:00" level=debug msg="completed keyexchange" Feb 25 03:26:10 yamaha go-librespot[12982]: time="2026-02-25T03:26:10+01:00" level=debug msg="completed challenge" Feb 25 03:26:10 yamaha go-librespot[12982]: time="2026-02-25T03:26:10+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:26:10 yamaha volumio5-onboarding[1284]: time=2026-02-25T03:26:10.556+01:00 level=WARN msg="reconnection attempt failed" component=volumio/socket error="dial tcp 127.0.0.1:3000: connect: connection refused" Feb 25 03:26:11 yamaha go-librespot[12982]: time="2026-02-25T03:26:11+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Feb 25 03:26:11 yamaha go-librespot[12982]: time="2026-02-25T03:26:11+01:00" level=debug msg="completed keyexchange" Feb 25 03:26:11 yamaha go-librespot[12982]: time="2026-02-25T03:26:11+01:00" level=debug msg="completed challenge" Feb 25 03:26:11 yamaha go-librespot[12982]: time="2026-02-25T03:26:11+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:26:11 yamaha volumio5-onboarding[1284]: time=2026-02-25T03:26:11.562+01:00 level=WARN msg="reconnection attempt failed" component=volumio/socket error="dial tcp 127.0.0.1:3000: connect: connection refused" Feb 25 03:26:11 yamaha go-librespot[12982]: time="2026-02-25T03:26:11+01:00" level=debug msg="connected to ap-gew4.spotify.com:80" Feb 25 03:26:11 yamaha go-librespot[12982]: time="2026-02-25T03:26:11+01:00" level=debug msg="completed keyexchange" Feb 25 03:26:11 yamaha go-librespot[12982]: time="2026-02-25T03:26:11+01:00" level=debug msg="completed challenge" Feb 25 03:26:11 yamaha go-librespot[12982]: time="2026-02-25T03:26:11+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:26:12 yamaha go-librespot[12982]: time="2026-02-25T03:26:12+01:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 25 03:26:12 yamaha go-librespot[12982]: time="2026-02-25T03:26:12+01:00" level=debug msg="completed keyexchange" Feb 25 03:26:12 yamaha go-librespot[12982]: time="2026-02-25T03:26:12+01:00" level=debug msg="completed challenge" Feb 25 03:26:12 yamaha volumio[12966]: info: ------------------------------------------- Feb 25 03:26:12 yamaha volumio[12966]: info: ----- Volumio3 ---- Feb 25 03:26:12 yamaha volumio[12966]: info: ------------------------------------------- Feb 25 03:26:12 yamaha volumio[12966]: info: ----- System startup ---- Feb 25 03:26:12 yamaha volumio[12966]: info: ------------------------------------------- Feb 25 03:26:12 yamaha go-librespot[12982]: time="2026-02-25T03:26:12+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:26:13 yamaha go-librespot[12982]: time="2026-02-25T03:26:13+01:00" level=debug msg="connected to ap-gew1.spotify.com:443" Feb 25 03:26:13 yamaha go-librespot[12982]: time="2026-02-25T03:26:13+01:00" level=debug msg="completed keyexchange" Feb 25 03:26:13 yamaha go-librespot[12982]: time="2026-02-25T03:26:13+01:00" level=debug msg="completed challenge" Feb 25 03:26:13 yamaha go-librespot[12982]: time="2026-02-25T03:26:13+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:26:13 yamaha volumio-remote-updater[629]: [2026-02-25 03:26:13] [connect] Successful connection Feb 25 03:26:13 yamaha volumio[12966]: info: MYVOLUMIO Environment detected Feb 25 03:26:14 yamaha go-librespot[12982]: time="2026-02-25T03:26:14+01:00" level=debug msg="connected to ap-gae2.spotify.com:80" Feb 25 03:26:14 yamaha volumio[12966]: info: Plugin folders cleanup Feb 25 03:26:14 yamaha volumio[12966]: info: Scanning into folder /volumio/app/plugins/ Feb 25 03:26:14 yamaha volumio[12966]: info: Scanning category audio_interface Feb 25 03:26:14 yamaha volumio[12966]: info: Scanning category miscellanea Feb 25 03:26:14 yamaha volumio[12966]: info: Scanning category music_service Feb 25 03:26:14 yamaha volumio[12966]: info: Scanning category plugins.json Feb 25 03:26:14 yamaha volumio[12966]: info: Scanning category system_controller Feb 25 03:26:14 yamaha volumio[12966]: info: Scanning category user_interface Feb 25 03:26:14 yamaha volumio[12966]: info: Scanning into folder /data/plugins/ Feb 25 03:26:14 yamaha volumio[12966]: info: Scanning category music_service Feb 25 03:26:14 yamaha volumio[12966]: info: Plugin folders cleanup completed Feb 25 03:26:14 yamaha volumio[12966]: info: ------------------------------------------- Feb 25 03:26:14 yamaha volumio[12966]: info: ----- Core plugins startup ---- Feb 25 03:26:14 yamaha volumio[12966]: info: ------------------------------------------- Feb 25 03:26:14 yamaha volumio[12966]: info: Loading plugins from folder /volumio/app/plugins/ Feb 25 03:26:14 yamaha volumio[12966]: info: Adding plugin upnp to MyMusic Plugins Feb 25 03:26:14 yamaha volumio[12966]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 25 03:26:14 yamaha volumio[12966]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 25 03:26:14 yamaha volumio[12966]: info: Loading plugins from folder /data/plugins/ Feb 25 03:26:14 yamaha volumio[12966]: info: Loading plugin "system"... Feb 25 03:26:14 yamaha go-librespot[12982]: time="2026-02-25T03:26:14+01:00" level=debug msg="completed keyexchange" Feb 25 03:26:14 yamaha go-librespot[12982]: time="2026-02-25T03:26:14+01:00" level=debug msg="completed challenge" Feb 25 03:26:14 yamaha volumio[12966]: info: Loading plugin "appearance"... Feb 25 03:26:14 yamaha go-librespot[12982]: time="2026-02-25T03:26:14+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:26:14 yamaha go-librespot[12982]: time="2026-02-25T03:26:14+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:26:14 yamaha systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 03:26:14 yamaha systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 03:26:16 yamaha volumio[12966]: info: Loading plugin "network"... Feb 25 03:26:16 yamaha volumio[12966]: info: Refreshing Cached IP Addresses Feb 25 03:26:16 yamaha sudo[13005]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 25 03:26:16 yamaha sudo[13005]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:26:16 yamaha volumio[12966]: info: Loading plugin "services"... Feb 25 03:26:16 yamaha sudo[13007]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 25 03:26:16 yamaha sudo[13007]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:26:16 yamaha sudo[13005]: pam_unix(sudo:session): session closed for user root Feb 25 03:26:16 yamaha volumio[12966]: info: Loading plugin "volumio5onboarding"... Feb 25 03:26:16 yamaha sudo[13014]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 25 03:26:16 yamaha volumio[12966]: info: Loading plugin "alsa_controller"... Feb 25 03:26:16 yamaha sudo[13014]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:26:16 yamaha sudo[13007]: pam_unix(sudo:session): session closed for user root Feb 25 03:26:16 yamaha sudo[13014]: pam_unix(sudo:session): session closed for user root Feb 25 03:26:16 yamaha volumio[12966]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 25 03:26:16 yamaha volumio[12966]: info: Loading plugin "wizard"... Feb 25 03:26:16 yamaha volumio[12966]: info: Loading plugin "networkfs"... Feb 25 03:26:16 yamaha volumio[12966]: info: Cannot mount NAS Ugreen at system boot, trial number 1 ,retrying in 5 seconds Feb 25 03:26:16 yamaha volumio[12966]: info: Starting Udev Watcher for removable devices Feb 25 03:26:16 yamaha volumio[12966]: info: Ignoring mount for partition: boot Feb 25 03:26:16 yamaha volumio[12966]: info: Ignoring mount for partition: volumio Feb 25 03:26:16 yamaha volumio[12966]: info: Ignoring mount for partition: volumio_data Feb 25 03:26:16 yamaha volumio[12966]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 25 03:26:16 yamaha volumio[12966]: info: Loading plugin "volumio_command_line_client"... Feb 25 03:26:16 yamaha volumio[12966]: info: Loading plugin "upnp"... Feb 25 03:26:16 yamaha volumio[12966]: info: [1771986376731] Starting Upmpd Daemon Feb 25 03:26:16 yamaha volumio[12966]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 25 03:26:16 yamaha volumio[12966]: info: Loading plugin "my_music"... Feb 25 03:26:16 yamaha volumio[12966]: info: Loading plugin "mpd"... Feb 25 03:26:17 yamaha volumio[12966]: info: Loading plugin "upnp_browser"... Feb 25 03:26:17 yamaha systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Feb 25 03:26:17 yamaha systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:26:17 yamaha systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:26:17 yamaha go-librespot[13042]: go-librespot daemon starting... Feb 25 03:26:17 yamaha go-librespot[13043]: time="2026-02-25T03:26:17+01:00" level=info msg="running go-librespot 0.6.2" Feb 25 03:26:17 yamaha go-librespot[13043]: time="2026-02-25T03:26:17+01:00" level=debug msg="app state loaded" Feb 25 03:26:17 yamaha go-librespot[13043]: time="2026-02-25T03:26:17+01:00" level=debug msg="stored credentials not found" Feb 25 03:26:17 yamaha go-librespot[13043]: time="2026-02-25T03:26:17+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 25 03:26:18 yamaha go-librespot[13043]: time="2026-02-25T03:26:18+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 25 03:26:18 yamaha go-librespot[13043]: time="2026-02-25T03:26:18+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 25 03:26:18 yamaha go-librespot[13043]: time="2026-02-25T03:26:18+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 25 03:26:18 yamaha go-librespot[13043]: time="2026-02-25T03:26:18+01:00" level=info msg="zeroconf server listening on port 37421" Feb 25 03:26:18 yamaha go-librespot[13043]: time="2026-02-25T03:26:18+01:00" level=debug msg="obtained new client token: AADiZCLdok11monVB5l76b+o1gc7ge/r0eNNzWOjF+ITvrLHPfGAdDSdHeJQmjluUybXanyhHhqHXj2vCswtCo6UM4k+aBxKY3v79rRaDH+8CMCRuTttKvNM3lCzCkNsvzTXg8uG5i4RDLD7CabSmI3aiWERoLyLEi+Rur8nFKcukDStmZ+i1wURw9kpm/EpJESGcq8n6sAsHjyvHgXrspNVbNuiqjodkNQ8FyTLsS+2Vb58KBAvFStS" Feb 25 03:26:18 yamaha go-librespot[13043]: time="2026-02-25T03:26:18+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 25 03:26:18 yamaha go-librespot[13043]: time="2026-02-25T03:26:18+01:00" level=debug msg="completed keyexchange" Feb 25 03:26:18 yamaha go-librespot[13043]: time="2026-02-25T03:26:18+01:00" level=debug msg="completed challenge" Feb 25 03:26:18 yamaha go-librespot[13043]: time="2026-02-25T03:26:18+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:26:19 yamaha go-librespot[13043]: time="2026-02-25T03:26:19+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Feb 25 03:26:19 yamaha go-librespot[13043]: time="2026-02-25T03:26:19+01:00" level=debug msg="completed keyexchange" Feb 25 03:26:19 yamaha go-librespot[13043]: time="2026-02-25T03:26:19+01:00" level=debug msg="completed challenge" Feb 25 03:26:19 yamaha go-librespot[13043]: time="2026-02-25T03:26:19+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:26:19 yamaha go-librespot[13043]: time="2026-02-25T03:26:19+01:00" level=debug msg="connected to ap-gew4.spotify.com:80" Feb 25 03:26:19 yamaha go-librespot[13043]: time="2026-02-25T03:26:19+01:00" level=debug msg="completed keyexchange" Feb 25 03:26:19 yamaha go-librespot[13043]: time="2026-02-25T03:26:19+01:00" level=debug msg="completed challenge" Feb 25 03:26:19 yamaha go-librespot[13043]: time="2026-02-25T03:26:19+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:26:20 yamaha volumio[12966]: info: Starting UPNP Browser Feb 25 03:26:20 yamaha volumio[12966]: info: Loading plugin "alarm-clock"... Feb 25 03:26:20 yamaha go-librespot[13043]: time="2026-02-25T03:26:20+01:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp 104.154.127.247:4070: connect: connection refused" Feb 25 03:26:20 yamaha go-librespot[13043]: time="2026-02-25T03:26:20+01:00" level=debug msg="connected to ap-gew1.spotify.com:443" Feb 25 03:26:20 yamaha volumio[12966]: info: Loading plugin "airplay_emulation"... Feb 25 03:26:20 yamaha volumio[12966]: info: Starting Shairport Sync Feb 25 03:26:20 yamaha go-librespot[13043]: time="2026-02-25T03:26:20+01:00" level=debug msg="completed keyexchange" Feb 25 03:26:20 yamaha go-librespot[13043]: time="2026-02-25T03:26:20+01:00" level=debug msg="completed challenge" Feb 25 03:26:20 yamaha volumio[12966]: info: Loading plugin "last_100"... Feb 25 03:26:20 yamaha volumio[12966]: info: Loading plugin "webradio"... Feb 25 03:26:20 yamaha go-librespot[13043]: time="2026-02-25T03:26:20+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:26:20 yamaha volumio[12966]: info: Loading plugin "i2s_dacs"... Feb 25 03:26:20 yamaha volumio[12966]: info: I2S DAC not set, start Auto-detection Feb 25 03:26:20 yamaha volumio[12966]: info: Loading plugin "volumiodiscovery"... Feb 25 03:26:20 yamaha volumio[12966]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 25 03:26:20 yamaha volumio[12966]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 25 03:26:20 yamaha volumio[12966]: *** WARNING *** For more information see Feb 25 03:26:20 yamaha volumio[12966]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 25 03:26:20 yamaha volumio[12966]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 25 03:26:20 yamaha volumio[12966]: *** WARNING *** For more information see Feb 25 03:26:20 yamaha node[12966]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 25 03:26:20 yamaha node[12966]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 25 03:26:20 yamaha node[12966]: *** WARNING *** For more information see Feb 25 03:26:20 yamaha node[12966]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 25 03:26:20 yamaha node[12966]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 25 03:26:20 yamaha node[12966]: *** WARNING *** For more information see Feb 25 03:26:20 yamaha volumio[12966]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 25 03:26:20 yamaha volumio[12966]: info: Discovery: Started advertising with name: Yamaha Feb 25 03:26:20 yamaha volumio[12966]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 25 03:26:20 yamaha volumio[12966]: info: Loading plugin "calmradio"... Feb 25 03:26:21 yamaha go-librespot[13043]: time="2026-02-25T03:26:21+01:00" level=debug msg="connected to ap-gae2.spotify.com:80" Feb 25 03:26:21 yamaha go-librespot[13043]: time="2026-02-25T03:26:21+01:00" level=debug msg="completed keyexchange" Feb 25 03:26:21 yamaha go-librespot[13043]: time="2026-02-25T03:26:21+01:00" level=debug msg="completed challenge" Feb 25 03:26:21 yamaha go-librespot[13043]: time="2026-02-25T03:26:21+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:26:22 yamaha go-librespot[13043]: time="2026-02-25T03:26:22+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 25 03:26:22 yamaha go-librespot[13043]: time="2026-02-25T03:26:22+01:00" level=debug msg="completed keyexchange" Feb 25 03:26:22 yamaha go-librespot[13043]: time="2026-02-25T03:26:22+01:00" level=debug msg="completed challenge" Feb 25 03:26:22 yamaha go-librespot[13043]: time="2026-02-25T03:26:22+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:26:22 yamaha go-librespot[13043]: time="2026-02-25T03:26:22+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:26:22 yamaha systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 03:26:22 yamaha systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 03:26:22 yamaha volumio5-onboarding[1284]: time=2026-02-25T03:26:22.566+01:00 level=WARN msg="reconnection attempt failed" component=volumio/socket error="read tcp 127.0.0.1:47796->127.0.0.1:3000: i/o timeout" Feb 25 03:26:22 yamaha volumio[12966]: info: Loading plugin "spop"... Feb 25 03:26:24 yamaha volumio[12966]: info: Loading plugin "outputs"... Feb 25 03:26:24 yamaha volumio[12966]: info: Loading plugin "albumart"... Feb 25 03:26:24 yamaha volumio[12966]: info: Plugin example_plugin is not enabled Feb 25 03:26:24 yamaha volumio[12966]: info: Loading plugin "inputs"... Feb 25 03:26:24 yamaha volumio[12966]: info: Loading plugin "updater_comm"... Feb 25 03:26:24 yamaha volumio[12966]: info: Plugin mpdemulation is not enabled Feb 25 03:26:24 yamaha volumio[12966]: info: Loading plugin "rest_api"... Feb 25 03:26:24 yamaha volumio[12966]: info: Loading plugin "websocket"... Feb 25 03:26:25 yamaha volumio[12966]: info: Starting Socket.io Server version 1.7.4 Feb 25 03:26:25 yamaha volumio[12966]: info: Loading plugin "80s80s"... Feb 25 03:26:25 yamaha systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Feb 25 03:26:25 yamaha systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:26:25 yamaha volumio[12966]: info: Applying required configuration parameters for plugin 80s80s Feb 25 03:26:25 yamaha volumio[12966]: info: [1771986385531] [80s80s] API delay: 30 Feb 25 03:26:25 yamaha volumio[12966]: info: Loading i18n strings for locale nl Feb 25 03:26:25 yamaha systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:26:25 yamaha go-librespot[13065]: go-librespot daemon starting... Feb 25 03:26:25 yamaha volumio[12966]: Updating browse sources language Feb 25 03:26:25 yamaha volumio[12966]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 25 03:26:25 yamaha go-librespot[13066]: time="2026-02-25T03:26:25+01:00" level=info msg="running go-librespot 0.6.2" Feb 25 03:26:25 yamaha go-librespot[13066]: time="2026-02-25T03:26:25+01:00" level=debug msg="app state loaded" Feb 25 03:26:25 yamaha go-librespot[13066]: time="2026-02-25T03:26:25+01:00" level=debug msg="stored credentials not found" Feb 25 03:26:25 yamaha go-librespot[13066]: time="2026-02-25T03:26:25+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 25 03:26:25 yamaha volumio[13055]: Forking 3 albumart workers Feb 25 03:26:25 yamaha volumio[12966]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 03:26:25 yamaha volumio[12966]: info: CoreCommandRouter::initPlayerControls Feb 25 03:26:25 yamaha volumio[12966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:26:25 yamaha volumio[12966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:26:25 yamaha volumio[12966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:26:25 yamaha volumio[12966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:26:25 yamaha volumio[12966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:26:25 yamaha volumio[12966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:26:25 yamaha volumio[12966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:26:25 yamaha volumio[12966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:26:25 yamaha volumio[12966]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 03:26:26 yamaha volumio[12966]: Express server listening on port 3000 Feb 25 03:26:26 yamaha volumio[12966]: [Metrics] WebUI: 14s 441.63ms Feb 25 03:26:26 yamaha volumio-remote-updater[629]: [2026-02-25 03:26:26] [connect] Successful connection Feb 25 03:26:26 yamaha volumio[12966]: info: CoreStateMachine::resetVolumioState Feb 25 03:26:26 yamaha volumio[12966]: info: CoreStateMachine::getcurrentVolume Feb 25 03:26:26 yamaha volumio[12966]: info: CoreCommandRouter::volumioRetrievevolume Feb 25 03:26:26 yamaha volumio[12966]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:26:26 yamaha volumio[12966]: info: Cannot mount NAS Ugreen at system boot, trial number 2 ,retrying in 5 seconds Feb 25 03:26:26 yamaha volumio[12966]: info: Volumio Network Manager: Network status updated: 1 Feb 25 03:26:26 yamaha go-librespot[13066]: time="2026-02-25T03:26:26+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 25 03:26:26 yamaha go-librespot[13066]: time="2026-02-25T03:26:26+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 25 03:26:26 yamaha go-librespot[13066]: time="2026-02-25T03:26:26+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 25 03:26:26 yamaha go-librespot[13066]: time="2026-02-25T03:26:26+01:00" level=info msg="zeroconf server listening on port 39527" Feb 25 03:26:26 yamaha volumio[12966]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Feb 25 03:26:26 yamaha volumio[12966]: wlan0 Interface doesn't support scanning. Feb 25 03:26:26 yamaha volumio[12966]: info: Cannot use regular scanning, forcing with ap-force Feb 25 03:26:26 yamaha go-librespot[13066]: time="2026-02-25T03:26:26+01:00" level=debug msg="obtained new client token: AABBk2kgKIPZViFN+mRHQEM+H5Fi4JegtUlO2nfNFRDVNoxGgKG/ECcptvoZYYHqhhvyx87VBpHajJWowu1JNk4hCWvPEZ05tNf7o2DhLQXB6wF504lF2aMLYXmisAzz5+hNEOA3PnKepzUvMtb1+NJ0LsxfddH5YWEx20cd4jkg8vIMWck3xZAya88vdjPaRxQ1MrmXMZgjLNQ2Mesqy8pJzUgA9CW6eDEs7XexVCcyYjxCGB2hikKr" Feb 25 03:26:26 yamaha go-librespot[13066]: time="2026-02-25T03:26:26+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 25 03:26:26 yamaha sudo[13116]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Feb 25 03:26:26 yamaha sudo[13116]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:26:26 yamaha go-librespot[13066]: time="2026-02-25T03:26:26+01:00" level=debug msg="completed keyexchange" Feb 25 03:26:26 yamaha go-librespot[13066]: time="2026-02-25T03:26:26+01:00" level=debug msg="completed challenge" Feb 25 03:26:26 yamaha sudo[13116]: pam_unix(sudo:session): session closed for user root Feb 25 03:26:26 yamaha go-librespot[13066]: time="2026-02-25T03:26:26+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:26:26 yamaha volumio[12966]: command failed: No such device (-19) Feb 25 03:26:26 yamaha volumio[12966]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Feb 25 03:26:26 yamaha volumio[12966]: command failed: No such device (-19) Feb 25 03:26:26 yamaha volumio[12966]: info: VolumeController:: Volume=86 Mute =false Feb 25 03:26:26 yamaha volumio[12966]: info: CoreStateMachine::pushState Feb 25 03:26:26 yamaha volumio[12966]: info: CorePlayQueue::getTrack 0 Feb 25 03:26:26 yamaha volumio[12966]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 03:26:26 yamaha volumio[12966]: info: CoreCommandRouter::volumioPushState Feb 25 03:26:26 yamaha volumio[12966]: info: CoreStateMachine::updateTrackBlock Feb 25 03:26:26 yamaha volumio[12966]: info: CorePlayQueue::getTrackBlock Feb 25 03:26:26 yamaha volumio[12966]: info: CoreCommandRouter::volumioRetrievevolume Feb 25 03:26:27 yamaha sudo[13132]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 25 03:26:27 yamaha sudo[13132]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:26:27 yamaha sudo[13134]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 25 03:26:27 yamaha sudo[13132]: pam_unix(sudo:session): session closed for user root Feb 25 03:26:27 yamaha sudo[13134]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:26:27 yamaha sudo[13134]: pam_unix(sudo:session): session closed for user root Feb 25 03:26:27 yamaha go-librespot[13066]: time="2026-02-25T03:26:27+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Feb 25 03:26:27 yamaha go-librespot[13066]: time="2026-02-25T03:26:27+01:00" level=debug msg="completed keyexchange" Feb 25 03:26:27 yamaha go-librespot[13066]: time="2026-02-25T03:26:27+01:00" level=debug msg="completed challenge" Feb 25 03:26:27 yamaha volumio[12966]: info: Reloading queue from file Feb 25 03:26:27 yamaha go-librespot[13066]: time="2026-02-25T03:26:27+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:26:27 yamaha volumio[12966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Feb 25 03:26:27 yamaha volumio[12966]: info: CoreStateMachine::setRepeat null single undefined Feb 25 03:26:27 yamaha volumio[12966]: info: CoreStateMachine::pushState Feb 25 03:26:27 yamaha volumio[12966]: info: CorePlayQueue::getTrack 0 Feb 25 03:26:27 yamaha volumio[12966]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 03:26:27 yamaha volumio[12966]: info: CoreCommandRouter::volumioPushState Feb 25 03:26:27 yamaha volumio[12966]: info: CoreStateMachine::setRandom null Feb 25 03:26:27 yamaha volumio[12966]: info: CoreStateMachine::pushState Feb 25 03:26:27 yamaha volumio[12966]: info: CorePlayQueue::getTrack 0 Feb 25 03:26:27 yamaha volumio[12966]: info: CoreCommandRouter::volumioPushState Feb 25 03:26:27 yamaha volumio[12966]: info: Setting Device type: Raspberry PI Feb 25 03:26:27 yamaha volumio-remote-updater[629]: [2026-02-25 03:26:27] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1771986386 101 Feb 25 03:26:27 yamaha volumio[12966]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 1 Feb 25 03:26:27 yamaha volumio[12966]: info: VolumeController:: Volume=86 Mute =false Feb 25 03:26:27 yamaha volumio[12966]: info: CoreStateMachine::pushState Feb 25 03:26:27 yamaha volumio[12966]: info: CorePlayQueue::getTrack 0 Feb 25 03:26:27 yamaha volumio[12966]: info: CoreCommandRouter::volumioPushState Feb 25 03:26:27 yamaha volumio[12966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Feb 25 03:26:27 yamaha volumio[12966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Feb 25 03:26:27 yamaha volumio[12966]: info: Discovery: adding 67ba7d11-5499-4b1d-a75e-1a431a777f52 Feb 25 03:26:27 yamaha volumio[12966]: info: Discovery: Found device Yamaha Feb 25 03:26:27 yamaha volumio[12966]: info: CoreCommandRouter::volumioGetState Feb 25 03:26:27 yamaha volumio[12966]: info: CorePlayQueue::getTrack 0 Feb 25 03:26:27 yamaha go-librespot[13066]: time="2026-02-25T03:26:27+01:00" level=debug msg="connected to ap-gew4.spotify.com:80" Feb 25 03:26:27 yamaha volumio[12966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Feb 25 03:26:27 yamaha go-librespot[13066]: time="2026-02-25T03:26:27+01:00" level=debug msg="completed keyexchange" Feb 25 03:26:27 yamaha go-librespot[13066]: time="2026-02-25T03:26:27+01:00" level=debug msg="completed challenge" Feb 25 03:26:27 yamaha volumio[12966]: info: Completed loading Core Plugins Feb 25 03:26:27 yamaha volumio[12966]: info: Preparing to generate the ALSA configuration file Feb 25 03:26:27 yamaha go-librespot[13066]: time="2026-02-25T03:26:27+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:26:27 yamaha sudo[13149]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 25 03:26:27 yamaha sudo[13149]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:26:27 yamaha volumio[12966]: info: Discovery: this is already registered, 67ba7d11-5499-4b1d-a75e-1a431a777f52 Feb 25 03:26:27 yamaha volumio[12966]: info: Discovery: Found device Yamaha Feb 25 03:26:27 yamaha volumio[12966]: info: CoreCommandRouter::volumioGetState Feb 25 03:26:27 yamaha volumio[12966]: info: CorePlayQueue::getTrack 0 Feb 25 03:26:28 yamaha volumio[12966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Feb 25 03:26:28 yamaha volumio[12966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Feb 25 03:26:28 yamaha sudo[13149]: pam_unix(sudo:session): session closed for user root Feb 25 03:26:28 yamaha volumio[12966]: info: Upmpdcli Daemon Started Feb 25 03:26:28 yamaha volumio[12966]: info: Asound.conf file unchanged, so no further update is needed Feb 25 03:26:28 yamaha volumio[12966]: info: Output device has changed, restarting MPD Feb 25 03:26:28 yamaha volumio[12966]: info: Output device has changed, restarting Shairport Sync Feb 25 03:26:28 yamaha volumio[12966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:26:28 yamaha volumio[12966]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:26:28 yamaha sudo[13152]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 25 03:26:28 yamaha sudo[13152]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:26:28 yamaha volumio[12966]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 25 03:26:28 yamaha sudo[13152]: pam_unix(sudo:session): session closed for user root Feb 25 03:26:28 yamaha volumio[12966]: info: ___________ START PLUGINS ___________ Feb 25 03:26:28 yamaha sudo[13154]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 25 03:26:28 yamaha sudo[13154]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:26:28 yamaha volumio[12966]: info: ControllerMpd::onStart: Initializing MPD Feb 25 03:26:28 yamaha volumio[12966]: info: Creating MPD Configuration file Feb 25 03:26:28 yamaha sudo[13162]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Feb 25 03:26:28 yamaha sudo[13162]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:26:28 yamaha systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 25 03:26:28 yamaha sudo[13164]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 25 03:26:28 yamaha sudo[13164]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:26:28 yamaha volumio[12966]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 03:26:28 yamaha sudo[13164]: pam_unix(sudo:session): session closed for user root Feb 25 03:26:28 yamaha volumio[12966]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 25 03:26:28 yamaha volumio[12966]: info: [1771986388490] CoreMusicLibrary::Adding element Media Servers Feb 25 03:26:28 yamaha volumio[12966]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 25 03:26:28 yamaha sudo[13167]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 25 03:26:28 yamaha sudo[13167]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:26:28 yamaha systemd[1]: mpd.service: Deactivated successfully. Feb 25 03:26:28 yamaha systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 25 03:26:28 yamaha systemd[1]: mpd.service: Consumed 7.261s CPU time. Feb 25 03:26:28 yamaha sudo[13162]: pam_unix(sudo:session): session closed for user root Feb 25 03:26:28 yamaha systemd[1]: mpd.socket: Deactivated successfully. Feb 25 03:26:28 yamaha systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 25 03:26:28 yamaha systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 25 03:26:28 yamaha go-librespot[13066]: time="2026-02-25T03:26:28+01:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 25 03:26:28 yamaha volumio[12966]: info: UPNP Browser: Client initialized successfully Feb 25 03:26:28 yamaha volumio[12966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:26:28 yamaha volumio[12966]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:26:28 yamaha systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 25 03:26:28 yamaha systemd[1]: Starting mpd.service - Music Player Daemon... Feb 25 03:26:28 yamaha go-librespot[13066]: time="2026-02-25T03:26:28+01:00" level=debug msg="completed keyexchange" Feb 25 03:26:28 yamaha go-librespot[13066]: time="2026-02-25T03:26:28+01:00" level=debug msg="completed challenge" Feb 25 03:26:28 yamaha volumio[12966]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 25 03:26:28 yamaha volumio[12966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:26:28 yamaha volumio[12966]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:26:28 yamaha systemd[1]: mpd.service: Deactivated successfully. Feb 25 03:26:28 yamaha systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 25 03:26:28 yamaha systemd[1]: mpd.socket: Deactivated successfully. Feb 25 03:26:28 yamaha systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 25 03:26:28 yamaha systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 25 03:26:28 yamaha volumio[12966]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 25 03:26:28 yamaha volumio[12966]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 25 03:26:28 yamaha volumio[12966]: info: [1771986388842] CoreMusicLibrary::Adding element Last_100 Feb 25 03:26:28 yamaha volumio[12966]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 25 03:26:28 yamaha go-librespot[13066]: time="2026-02-25T03:26:28+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:26:28 yamaha volumio[12966]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 25 03:26:28 yamaha volumio[12966]: info: [1771986388863] CoreMusicLibrary::Adding element Webradio Feb 25 03:26:28 yamaha volumio[12966]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 25 03:26:28 yamaha systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 25 03:26:28 yamaha volumio[12966]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 25 03:26:28 yamaha systemd[1]: Starting mpd.service - Music Player Daemon... Feb 25 03:26:28 yamaha volumio[12966]: info: Initializing BBC Radios Feb 25 03:26:29 yamaha volumio[12966]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 25 03:26:29 yamaha volumio[12966]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:26:29 yamaha volumio[12966]: info: Adding Calm Radio to Browse Sources Feb 25 03:26:29 yamaha volumio[12966]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 25 03:26:29 yamaha volumio[12966]: info: [1771986389152] CoreMusicLibrary::Adding element Calm Radio Feb 25 03:26:29 yamaha volumio[12966]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 25 03:26:29 yamaha volumio[12966]: Cannot find translation for source Calm Radio Feb 25 03:26:29 yamaha volumio[12966]: info: Creating Spotify config file Feb 25 03:26:29 yamaha volumio[12966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:26:29 yamaha sudo[13182]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 25 03:26:29 yamaha sudo[13182]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 25 03:26:29 yamaha sudo[13182]: pam_unix(sudo:session): session closed for user root Feb 25 03:26:29 yamaha go-librespot[13066]: time="2026-02-25T03:26:29+01:00" level=debug msg="connected to ap-gue1.spotify.com:443" Feb 25 03:26:29 yamaha volumio[13076]: Starting albumart workers Feb 25 03:26:29 yamaha go-librespot[13066]: time="2026-02-25T03:26:29+01:00" level=debug msg="completed keyexchange" Feb 25 03:26:29 yamaha go-librespot[13066]: time="2026-02-25T03:26:29+01:00" level=debug msg="completed challenge" Feb 25 03:26:29 yamaha go-librespot[13066]: time="2026-02-25T03:26:29+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:26:29 yamaha volumio[13074]: Starting albumart workers Feb 25 03:26:29 yamaha volumio[13075]: Starting albumart workers Feb 25 03:26:30 yamaha volumio[12966]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 25 03:26:30 yamaha volumio[12966]: info: [1771986390176] CoreMusicLibrary::Adding element 80s80s Radio Feb 25 03:26:30 yamaha volumio[12966]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 25 03:26:30 yamaha volumio[12966]: Cannot find translation for source Calm Radio Feb 25 03:26:30 yamaha volumio[12966]: Cannot find translation for source 80s80s Radio Feb 25 03:26:30 yamaha volumio[12966]: info: Volumio Calling Home Feb 25 03:26:30 yamaha go-librespot[13066]: time="2026-02-25T03:26:30+01:00" level=debug msg="connected to ap-gae2.spotify.com:80" Feb 25 03:26:30 yamaha go-librespot[13066]: time="2026-02-25T03:26:30+01:00" level=debug msg="completed keyexchange" Feb 25 03:26:30 yamaha go-librespot[13066]: time="2026-02-25T03:26:30+01:00" level=debug msg="completed challenge" Feb 25 03:26:30 yamaha go-librespot[13066]: time="2026-02-25T03:26:30+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 25 03:26:30 yamaha go-librespot[13066]: time="2026-02-25T03:26:30+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 25 03:26:30 yamaha systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 03:26:30 yamaha systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 03:26:30 yamaha systemd[1]: go-librespot-daemon.service: Consumed 1.113s CPU time. Feb 25 03:26:31 yamaha volumio[12966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Feb 25 03:26:31 yamaha volumio[12966]: info: Cannot mount NAS Ugreen at system boot, trial number 3 ,retrying in 5 seconds Feb 25 03:26:31 yamaha volumio[12966]: info: MPD Permissions set Feb 25 03:26:31 yamaha volumio[12966]: info: MPD Permissions set Feb 25 03:26:31 yamaha volumio[12966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Feb 25 03:26:32 yamaha volumio[12966]: info: Volumio called home Feb 25 03:26:32 yamaha volumio[12966]: info: Spotify config file written Feb 25 03:26:32 yamaha sudo[13214]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 25 03:26:32 yamaha sudo[13214]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:26:32 yamaha systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:26:32 yamaha systemd[1]: go-librespot-daemon.service: Consumed 1.113s CPU time. Feb 25 03:26:32 yamaha systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:26:32 yamaha go-librespot[13217]: go-librespot daemon starting... Feb 25 03:26:32 yamaha sudo[13214]: pam_unix(sudo:session): session closed for user root Feb 25 03:26:32 yamaha go-librespot[13223]: time="2026-02-25T03:26:32+01:00" level=info msg="running go-librespot 0.6.2" Feb 25 03:26:32 yamaha go-librespot[13223]: time="2026-02-25T03:26:32+01:00" level=debug msg="app state loaded" Feb 25 03:26:32 yamaha go-librespot[13223]: time="2026-02-25T03:26:32+01:00" level=debug msg="stored credentials not found" Feb 25 03:26:32 yamaha go-librespot[13223]: time="2026-02-25T03:26:32+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 25 03:26:32 yamaha volumio[12966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Feb 25 03:26:32 yamaha volumio[12966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:26:32 yamaha volumio[12966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:26:32 yamaha volumio[12966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:26:32 yamaha volumio[12966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:26:32 yamaha volumio[12966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:26:32 yamaha volumio[12966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:26:32 yamaha volumio[12966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:26:32 yamaha volumio[12966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:26:32 yamaha volumio[12966]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:26:32 yamaha volumio[12966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:26:32 yamaha volumio[12966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:26:32 yamaha volumio[12966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:26:32 yamaha volumio[12966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:26:32 yamaha volumio[12966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:26:32 yamaha volumio[12966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:26:32 yamaha volumio[12966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:26:32 yamaha volumio[12966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:26:32 yamaha volumio[12966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:26:32 yamaha volumio[12966]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:26:32 yamaha volumio[12966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:26:32 yamaha volumio[12966]: info: No need to fix Spotify hosts Feb 25 03:26:33 yamaha go-librespot[13223]: time="2026-02-25T03:26:33+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 25 03:26:33 yamaha go-librespot[13223]: time="2026-02-25T03:26:33+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 25 03:26:33 yamaha go-librespot[13223]: time="2026-02-25T03:26:33+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 25 03:26:33 yamaha go-librespot[13223]: time="2026-02-25T03:26:33+01:00" level=info msg="zeroconf server listening on port 38601" Feb 25 03:26:33 yamaha volumio[12966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 3 Feb 25 03:26:33 yamaha go-librespot[13223]: time="2026-02-25T03:26:33+01:00" level=debug msg="obtained new client token: AAClxrvdrT+FP1LdyDh/mRkGuxPBKZkWxt1fmBJKtAuzVYdfcafwO84bcL2W7zG5t4D3OUHQifRtgB5Wc4qcsLofcavkxHhNwglT5z5yi+m4sv3WsFynLIbPabAYkYwtARQFpIbgJAFsz2DFpZXd2u/rDUnKioIeqN8ClZ8JV9OuPCT2g0rcr2E+5MtfAtwPV1wPdE1fhjD9uFdWKWAoikvExCyzmVW7YZ3kFXh6+7JIkYktwqK5IQ==" Feb 25 03:26:33 yamaha volumio[12966]: info: New Spotify access tokenBQB-bJC8Dg... Feb 25 03:26:33 yamaha volumio[12966]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 25 03:26:33 yamaha go-librespot[13223]: time="2026-02-25T03:26:33+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 25 03:26:33 yamaha go-librespot[13223]: time="2026-02-25T03:26:33+01:00" level=debug msg="completed keyexchange" Feb 25 03:26:33 yamaha go-librespot[13223]: time="2026-02-25T03:26:33+01:00" level=debug msg="completed challenge" Feb 25 03:26:33 yamaha volumio[12966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 3 Feb 25 03:26:33 yamaha volumio[12966]: info: Starting Shairport Sync Feb 25 03:26:33 yamaha go-librespot[13223]: time="2026-02-25T03:26:33+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:26:33 yamaha volumio[12966]: info: Starting Shairport Sync Feb 25 03:26:33 yamaha volumio[12966]: info: Starting Shairport Sync Feb 25 03:26:33 yamaha sudo[13244]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 25 03:26:33 yamaha sudo[13246]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 25 03:26:33 yamaha sudo[13246]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:26:33 yamaha sudo[13244]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:26:33 yamaha sudo[13248]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 25 03:26:33 yamaha sudo[13248]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:26:33 yamaha systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 25 03:26:33 yamaha systemd[1]: shairport-sync.service: Deactivated successfully. Feb 25 03:26:33 yamaha systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 25 03:26:33 yamaha systemd[1]: shairport-sync.service: Consumed 2.509s CPU time. Feb 25 03:26:33 yamaha volumio[12966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 3 Feb 25 03:26:33 yamaha systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 25 03:26:33 yamaha sudo[13246]: pam_unix(sudo:session): session closed for user root Feb 25 03:26:33 yamaha volumio[12966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 3 Feb 25 03:26:33 yamaha systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 25 03:26:33 yamaha systemd[1]: shairport-sync.service: Deactivated successfully. Feb 25 03:26:33 yamaha systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 25 03:26:33 yamaha volumio[12966]: info: CoreCommandRouter::volumioGetState Feb 25 03:26:33 yamaha volumio[12966]: info: CorePlayQueue::getTrack 0 Feb 25 03:26:33 yamaha volumio[12966]: info: Shairport-Sync Started Feb 25 03:26:33 yamaha volumio[12966]: Error adding Membership: Error: addMembership EINVAL Feb 25 03:26:33 yamaha volumio[12966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 3 Feb 25 03:26:33 yamaha systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 25 03:26:33 yamaha sudo[13244]: pam_unix(sudo:session): session closed for user root Feb 25 03:26:33 yamaha sudo[13248]: pam_unix(sudo:session): session closed for user root Feb 25 03:26:33 yamaha go-librespot[13223]: time="2026-02-25T03:26:33+01:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:443, retrying with a different AP" error="dial tcp 34.158.1.133:443: connect: connection refused" Feb 25 03:26:33 yamaha volumio[12966]: SPOTIFY: User informations: {"country":"IN","display_name":"struikjan","email":"jan.struik@icloud.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/struikjan"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/struikjan","id":"struikjan","images":[],"product":"free","type":"user","uri":"spotify:user:struikjan"} Feb 25 03:26:33 yamaha go-librespot[13223]: time="2026-02-25T03:26:33+01:00" level=debug msg="connected to ap-gew4.spotify.com:80" Feb 25 03:26:33 yamaha volumio[12966]: info: Spotify Successfully logged in Feb 25 03:26:33 yamaha volumio[12966]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 25 03:26:33 yamaha volumio[12966]: info: [1771986393938] CoreMusicLibrary::Adding element Spotify Feb 25 03:26:33 yamaha volumio[12966]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 25 03:26:33 yamaha volumio[12966]: Cannot find translation for source Calm Radio Feb 25 03:26:33 yamaha volumio[12966]: Cannot find translation for source 80s80s Radio Feb 25 03:26:33 yamaha volumio[12966]: Cannot find translation for source Spotify Feb 25 03:26:33 yamaha go-librespot[13223]: time="2026-02-25T03:26:33+01:00" level=debug msg="completed keyexchange" Feb 25 03:26:33 yamaha go-librespot[13223]: time="2026-02-25T03:26:33+01:00" level=debug msg="completed challenge" Feb 25 03:26:33 yamaha go-librespot[13223]: time="2026-02-25T03:26:33+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:26:34 yamaha volumio[12966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 3 Feb 25 03:26:34 yamaha volumio[12966]: info: Shairport-Sync Started Feb 25 03:26:34 yamaha volumio[12966]: info: Shairport-Sync Started Feb 25 03:26:34 yamaha volumio[12966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 3 Feb 25 03:26:34 yamaha volumio[12966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 3 Feb 25 03:26:34 yamaha volumio[12966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 3 Feb 25 03:26:34 yamaha volumio[12966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 3 Feb 25 03:26:34 yamaha volumio[12966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 3 Feb 25 03:26:34 yamaha volumio[12966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 3 Feb 25 03:26:34 yamaha volumio[12966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 3 Feb 25 03:26:34 yamaha volumio[12966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 3 Feb 25 03:26:34 yamaha go-librespot[13223]: time="2026-02-25T03:26:34+01:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 25 03:26:34 yamaha go-librespot[13223]: time="2026-02-25T03:26:34+01:00" level=debug msg="completed keyexchange" Feb 25 03:26:34 yamaha go-librespot[13223]: time="2026-02-25T03:26:34+01:00" level=debug msg="completed challenge" Feb 25 03:26:34 yamaha go-librespot[13223]: time="2026-02-25T03:26:34+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:26:35 yamaha go-librespot[13223]: time="2026-02-25T03:26:35+01:00" level=debug msg="connected to ap-gue1.spotify.com:443" Feb 25 03:26:36 yamaha mpd[13197]: 2026-02-25T03:26:36 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 25 03:26:36 yamaha volumio[12966]: info: go-librespot daemon successfully initialized Feb 25 03:26:36 yamaha systemd[1]: Started mpd.service - Music Player Daemon. Feb 25 03:26:36 yamaha sudo[13167]: pam_unix(sudo:session): session closed for user root Feb 25 03:26:36 yamaha sudo[13154]: pam_unix(sudo:session): session closed for user root Feb 25 03:26:36 yamaha volumio[12966]: info: Completed starting Core Plugins Feb 25 03:26:36 yamaha volumio[12966]: info: ------------------------------------------- Feb 25 03:26:36 yamaha volumio[12966]: info: ----- MyVolumio plugins startup ---- Feb 25 03:26:36 yamaha volumio[12966]: info: ------------------------------------------- Feb 25 03:26:36 yamaha volumio[12966]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 25 03:26:36 yamaha volumio[12966]: error: MPD error: The expression evaluated to a falsy value: Feb 25 03:26:36 yamaha volumio[12966]: assert.ok(self.idling) Feb 25 03:26:36 yamaha volumio[12966]: error: The expression evaluated to a falsy value: Feb 25 03:26:36 yamaha volumio[12966]: assert.ok(self.idling) Feb 25 03:26:36 yamaha volumio[12966]: info: Cannot mount NAS Ugreen at system boot, trial number 4 ,retrying in 5 seconds Feb 25 03:26:36 yamaha volumio[12966]: info: MPD running with PID13197 Feb 25 03:26:36 yamaha volumio[12966]: ,establishing connection Feb 25 03:26:36 yamaha volumio[12966]: error: updateQueue error: null Feb 25 03:26:36 yamaha volumio[12966]: error: updateQueue error: null Feb 25 03:26:36 yamaha go-librespot[13223]: time="2026-02-25T03:26:36+01:00" level=debug msg="completed keyexchange" Feb 25 03:26:36 yamaha go-librespot[13223]: time="2026-02-25T03:26:36+01:00" level=debug msg="completed challenge" Feb 25 03:26:36 yamaha go-librespot[13223]: time="2026-02-25T03:26:36+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:26:37 yamaha go-librespot[13223]: time="2026-02-25T03:26:37+01:00" level=debug msg="connected to ap-gew1.spotify.com:80" Feb 25 03:26:37 yamaha go-librespot[13223]: time="2026-02-25T03:26:37+01:00" level=debug msg="completed keyexchange" Feb 25 03:26:37 yamaha go-librespot[13223]: time="2026-02-25T03:26:37+01:00" level=debug msg="completed challenge" Feb 25 03:26:37 yamaha go-librespot[13223]: time="2026-02-25T03:26:37+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:26:38 yamaha go-librespot[13223]: time="2026-02-25T03:26:38+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 25 03:26:38 yamaha go-librespot[13223]: time="2026-02-25T03:26:38+01:00" level=debug msg="completed keyexchange" Feb 25 03:26:38 yamaha go-librespot[13223]: time="2026-02-25T03:26:38+01:00" level=debug msg="completed challenge" Feb 25 03:26:38 yamaha go-librespot[13223]: time="2026-02-25T03:26:38+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:26:38 yamaha go-librespot[13223]: time="2026-02-25T03:26:38+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:26:38 yamaha systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 03:26:38 yamaha systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 03:26:38 yamaha systemd[1]: go-librespot-daemon.service: Consumed 1.014s CPU time. Feb 25 03:26:39 yamaha volumio[12966]: info: Initializing connection to go-librespot Websocket Feb 25 03:26:39 yamaha volumio[12966]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 25 03:26:41 yamaha systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11. Feb 25 03:26:41 yamaha systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:26:41 yamaha systemd[1]: go-librespot-daemon.service: Consumed 1.014s CPU time. Feb 25 03:26:41 yamaha systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:26:41 yamaha go-librespot[13292]: go-librespot daemon starting... Feb 25 03:26:41 yamaha go-librespot[13293]: time="2026-02-25T03:26:41+01:00" level=info msg="running go-librespot 0.6.2" Feb 25 03:26:41 yamaha go-librespot[13293]: time="2026-02-25T03:26:41+01:00" level=debug msg="app state loaded" Feb 25 03:26:41 yamaha go-librespot[13293]: time="2026-02-25T03:26:41+01:00" level=debug msg="stored credentials not found" Feb 25 03:26:41 yamaha volumio[12966]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 25 03:26:41 yamaha go-librespot[13293]: time="2026-02-25T03:26:41+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 25 03:26:41 yamaha volumio[12966]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Feb 25 03:26:41 yamaha go-librespot[13293]: time="2026-02-25T03:26:41+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 25 03:26:41 yamaha go-librespot[13293]: time="2026-02-25T03:26:41+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 25 03:26:41 yamaha go-librespot[13293]: time="2026-02-25T03:26:41+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 25 03:26:41 yamaha go-librespot[13293]: time="2026-02-25T03:26:41+01:00" level=info msg="zeroconf server listening on port 38315" Feb 25 03:26:41 yamaha go-librespot[13293]: time="2026-02-25T03:26:41+01:00" level=debug msg="obtained new client token: AAASBXpAjTqAAo9lFgJXqa0lDL5vTOwc8F7ZgR59MWNjNi4bagF1kj923whBvjhcLmlHnRE4PuILMM50dNrgl2pyiUzjDmjpmsmqlkUExNYhzkxkhkeaNmkc1h9NPSzo8JUVPeQYpi7XW0nvEHmuvE6ks1CRmIcDIy1TY1SW3NaBvpwvAlkl2uHQZKKqKMoMuve90iFQ24kE2+uxjlY6YgCq3vs2RrBfwJp/mOhzcT9vcJGIGKTkaLmZ" Feb 25 03:26:41 yamaha go-librespot[13293]: time="2026-02-25T03:26:41+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 25 03:26:42 yamaha go-librespot[13293]: time="2026-02-25T03:26:42+01:00" level=debug msg="completed keyexchange" Feb 25 03:26:42 yamaha go-librespot[13293]: time="2026-02-25T03:26:42+01:00" level=debug msg="completed challenge" Feb 25 03:26:42 yamaha go-librespot[13293]: time="2026-02-25T03:26:42+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:26:42 yamaha go-librespot[13293]: time="2026-02-25T03:26:42+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Feb 25 03:26:42 yamaha go-librespot[13293]: time="2026-02-25T03:26:42+01:00" level=debug msg="completed keyexchange" Feb 25 03:26:42 yamaha go-librespot[13293]: time="2026-02-25T03:26:42+01:00" level=debug msg="completed challenge" Feb 25 03:26:42 yamaha go-librespot[13293]: time="2026-02-25T03:26:42+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:26:43 yamaha volumio[12966]: info: Initializing connection to go-librespot Websocket Feb 25 03:26:43 yamaha go-librespot[13293]: time="2026-02-25T03:26:43+01:00" level=debug msg="new websocket client" Feb 25 03:26:43 yamaha volumio[12966]: info: Connection to go-librespot Websocket established Feb 25 03:26:43 yamaha go-librespot[13293]: time="2026-02-25T03:26:43+01:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:80, retrying with a different AP" error="dial tcp 34.158.1.133:80: connect: connection refused" Feb 25 03:26:43 yamaha go-librespot[13293]: time="2026-02-25T03:26:43+01:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 25 03:26:43 yamaha go-librespot[13293]: time="2026-02-25T03:26:43+01:00" level=debug msg="completed keyexchange" Feb 25 03:26:43 yamaha go-librespot[13293]: time="2026-02-25T03:26:43+01:00" level=debug msg="completed challenge" Feb 25 03:26:43 yamaha go-librespot[13293]: time="2026-02-25T03:26:43+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:26:44 yamaha go-librespot[13293]: time="2026-02-25T03:26:44+01:00" level=debug msg="connected to ap-gue1.spotify.com:443" Feb 25 03:26:44 yamaha go-librespot[13293]: time="2026-02-25T03:26:44+01:00" level=debug msg="completed keyexchange" Feb 25 03:26:44 yamaha go-librespot[13293]: time="2026-02-25T03:26:44+01:00" level=debug msg="completed challenge" Feb 25 03:26:44 yamaha go-librespot[13293]: time="2026-02-25T03:26:44+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:26:44 yamaha volumio[12966]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 25 03:26:44 yamaha volumio[12966]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 25 03:26:44 yamaha volumio[12966]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 25 03:26:44 yamaha volumio[12966]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 25 03:26:44 yamaha volumio[12966]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 25 03:26:44 yamaha volumio[12966]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 25 03:26:44 yamaha volumio[12966]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 25 03:26:44 yamaha volumio[12966]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 25 03:26:44 yamaha volumio[12966]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 25 03:26:44 yamaha volumio[12966]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 25 03:26:44 yamaha volumio[12966]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 25 03:26:44 yamaha volumio[12966]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 25 03:26:44 yamaha volumio[12966]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 25 03:26:44 yamaha volumio[12966]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 25 03:26:44 yamaha volumio[12966]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 25 03:26:44 yamaha volumio[12966]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 25 03:26:44 yamaha volumio[12966]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 25 03:26:44 yamaha volumio[12966]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 25 03:26:44 yamaha volumio[12966]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 25 03:26:44 yamaha volumio[12966]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 25 03:26:44 yamaha volumio[12966]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 25 03:26:44 yamaha volumio[12966]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 25 03:26:44 yamaha volumio[12966]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 25 03:26:44 yamaha volumio[12966]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 25 03:26:44 yamaha volumio[12966]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 25 03:26:44 yamaha volumio[12966]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 25 03:26:44 yamaha volumio[12966]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 25 03:26:44 yamaha volumio[12966]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 25 03:26:44 yamaha volumio[12966]: info: Adding plugin bluetooth to MyMusic Plugins Feb 25 03:26:44 yamaha volumio[12966]: info: Adding plugin multiroom to MyMusic Plugins Feb 25 03:26:44 yamaha volumio[12966]: info: Adding plugin metavolumio to MyMusic Plugins Feb 25 03:26:44 yamaha volumio[12966]: info: Adding plugin cd_controller to MyMusic Plugins Feb 25 03:26:44 yamaha volumio[12966]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 25 03:26:44 yamaha volumio[12966]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 25 03:26:44 yamaha volumio[12966]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 25 03:26:44 yamaha volumio[12966]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 25 03:26:45 yamaha go-librespot[13293]: time="2026-02-25T03:26:45+01:00" level=debug msg="connected to ap-gae2.spotify.com:80" Feb 25 03:26:45 yamaha go-librespot[13293]: time="2026-02-25T03:26:45+01:00" level=debug msg="completed keyexchange" Feb 25 03:26:45 yamaha go-librespot[13293]: time="2026-02-25T03:26:45+01:00" level=debug msg="completed challenge" Feb 25 03:26:45 yamaha go-librespot[13293]: time="2026-02-25T03:26:45+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:26:46 yamaha go-librespot[13293]: time="2026-02-25T03:26:46+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 25 03:26:46 yamaha go-librespot[13293]: time="2026-02-25T03:26:46+01:00" level=debug msg="completed keyexchange" Feb 25 03:26:46 yamaha go-librespot[13293]: time="2026-02-25T03:26:46+01:00" level=debug msg="completed challenge" Feb 25 03:26:46 yamaha go-librespot[13293]: time="2026-02-25T03:26:46+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:26:46 yamaha go-librespot[13293]: time="2026-02-25T03:26:46+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:26:46 yamaha systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 03:26:46 yamaha systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 03:26:46 yamaha volumio[12966]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 25 03:26:46 yamaha volumio[12966]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 25 03:26:46 yamaha volumio[12966]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:26:46 yamaha volumio[12966]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:26:46 yamaha volumio[12966]: info: Starting MyVolumio Remote Streaming Endpoints Feb 25 03:26:46 yamaha volumio[12966]: info: MyVolumio login type: Token Feb 25 03:26:46 yamaha volumio[12966]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 25 03:26:46 yamaha volumio[12966]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 25 03:26:48 yamaha volumio[12966]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 25 03:26:48 yamaha volumio[12966]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 25 03:26:48 yamaha volumio[12966]: info: Streaming services startup Feb 25 03:26:48 yamaha volumio[12966]: info: Starting Streaming Daemon Feb 25 03:26:48 yamaha sudo[13317]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 25 03:26:48 yamaha sudo[13317]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:26:48 yamaha volumio[12966]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 25 03:26:48 yamaha sudo[13317]: pam_unix(sudo:session): session closed for user root Feb 25 03:26:48 yamaha volumio[12966]: info: Getting Spotify volume Feb 25 03:26:48 yamaha volumio[12966]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 25 03:26:48 yamaha volumio[12966]: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 25 03:26:48 yamaha volumio[12966]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Feb 25 03:26:48 yamaha volumio[12966]: errno: -111, Feb 25 03:26:48 yamaha volumio[12966]: code: 'ECONNREFUSED', Feb 25 03:26:48 yamaha volumio[12966]: syscall: 'connect', Feb 25 03:26:48 yamaha volumio[12966]: address: '127.0.0.1', Feb 25 03:26:48 yamaha volumio[12966]: port: 9879, Feb 25 03:26:48 yamaha volumio[12966]: response: undefined Feb 25 03:26:48 yamaha volumio[12966]: } Feb 25 03:26:48 yamaha volumio[12966]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 25 03:26:49 yamaha systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12. Feb 25 03:26:49 yamaha systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:26:49 yamaha systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:26:49 yamaha go-librespot[13337]: go-librespot daemon starting... Feb 25 03:26:49 yamaha go-librespot[13338]: time="2026-02-25T03:26:49+01:00" level=info msg="running go-librespot 0.6.2" Feb 25 03:26:49 yamaha go-librespot[13338]: time="2026-02-25T03:26:49+01:00" level=debug msg="app state loaded" Feb 25 03:26:49 yamaha go-librespot[13338]: time="2026-02-25T03:26:49+01:00" level=debug msg="stored credentials not found" Feb 25 03:26:49 yamaha go-librespot[13338]: time="2026-02-25T03:26:49+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 25 03:26:50 yamaha go-librespot[13338]: time="2026-02-25T03:26:50+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 25 03:26:50 yamaha go-librespot[13338]: time="2026-02-25T03:26:50+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 25 03:26:50 yamaha go-librespot[13338]: time="2026-02-25T03:26:50+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 25 03:26:50 yamaha go-librespot[13338]: time="2026-02-25T03:26:50+01:00" level=info msg="zeroconf server listening on port 45439" Feb 25 03:26:50 yamaha go-librespot[13338]: time="2026-02-25T03:26:50+01:00" level=debug msg="obtained new client token: AAD+5ZpP7TMtQsvH1Ssev/C6mMtBzqFy3ePWCrNWOrEqXacAo0ZdiVAv97sapXP6y30dP7+UvC7+xRAHH8dI/XloUHhGgmdIT00NAktsy7Ge7KKrJ1uGk+VxYtVnPrUn28WcL8TouvwAMJZZF0xxDH2vib2cMKm/gzRB7+1MLRxR8CvLUov/ET8UDIzFDYGjh5c3bkAL7R1cVN8xdfV9TPEOrbt8Ja17o17IjuQa8D2ZbDWZ6ifQDA==" Feb 25 03:26:50 yamaha sudo[13350]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-25 03:25' Feb 25 03:26:50 yamaha sudo[13350]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:26:50 yamaha go-librespot[13338]: time="2026-02-25T03:26:50+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 25 03:26:50 yamaha go-librespot[13338]: time="2026-02-25T03:26:50+01:00" level=debug msg="completed keyexchange" Feb 25 03:26:50 yamaha go-librespot[13338]: time="2026-02-25T03:26:50+01:00" level=debug msg="completed challenge" Feb 25 03:26:50 yamaha go-librespot[13338]: time="2026-02-25T03:26:50+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " 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="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:07:07 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"