Feb 25 03:35:00 yamaha sudo[17914]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Feb 25 03:35:00 yamaha go-librespot[17867]: time="2026-02-25T03:35:00+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 25 03:35:00 yamaha sudo[17914]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:35:00 yamaha go-librespot[17867]: time="2026-02-25T03:35:00+01:00" level=debug msg="completed keyexchange" Feb 25 03:35:00 yamaha go-librespot[17867]: time="2026-02-25T03:35:00+01:00" level=debug msg="completed challenge" Feb 25 03:35:00 yamaha go-librespot[17867]: time="2026-02-25T03:35:00+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:35:00 yamaha sudo[17914]: pam_unix(sudo:session): session closed for user root Feb 25 03:35:00 yamaha volumio[17762]: command failed: No such device (-19) Feb 25 03:35:00 yamaha volumio[17762]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Feb 25 03:35:00 yamaha volumio[17762]: command failed: No such device (-19) Feb 25 03:35:00 yamaha volumio[17762]: info: VolumeController:: Volume=86 Mute =false Feb 25 03:35:00 yamaha volumio[17762]: info: CoreStateMachine::pushState Feb 25 03:35:00 yamaha volumio[17762]: info: CorePlayQueue::getTrack 0 Feb 25 03:35:00 yamaha volumio[17762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 03:35:00 yamaha volumio[17762]: info: CoreCommandRouter::volumioPushState Feb 25 03:35:00 yamaha volumio[17762]: info: CoreStateMachine::updateTrackBlock Feb 25 03:35:00 yamaha volumio[17762]: info: CorePlayQueue::getTrackBlock Feb 25 03:35:00 yamaha volumio[17762]: info: CoreCommandRouter::volumioRetrievevolume Feb 25 03:35:00 yamaha sudo[17934]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 25 03:35:00 yamaha sudo[17934]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:35:00 yamaha sudo[17934]: pam_unix(sudo:session): session closed for user root Feb 25 03:35:00 yamaha sudo[17936]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 25 03:35:00 yamaha sudo[17936]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:35:00 yamaha volumio[17762]: info: Reloading queue from file Feb 25 03:35:00 yamaha go-librespot[17867]: time="2026-02-25T03:35:00+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Feb 25 03:35:00 yamaha volumio[17762]: 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:35:00 yamaha sudo[17936]: pam_unix(sudo:session): session closed for user root Feb 25 03:35:00 yamaha go-librespot[17867]: time="2026-02-25T03:35:00+01:00" level=debug msg="completed keyexchange" Feb 25 03:35:00 yamaha go-librespot[17867]: time="2026-02-25T03:35:00+01:00" level=debug msg="completed challenge" Feb 25 03:35:00 yamaha volumio[17762]: info: CoreStateMachine::setRepeat null single undefined Feb 25 03:35:00 yamaha volumio[17762]: info: CoreStateMachine::pushState Feb 25 03:35:00 yamaha volumio[17762]: info: CorePlayQueue::getTrack 0 Feb 25 03:35:00 yamaha volumio[17762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 03:35:00 yamaha go-librespot[17867]: time="2026-02-25T03:35:00+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:35:00 yamaha volumio[17762]: info: CoreCommandRouter::volumioPushState Feb 25 03:35:00 yamaha volumio[17762]: info: CoreStateMachine::setRandom null Feb 25 03:35:00 yamaha volumio[17762]: info: CoreStateMachine::pushState Feb 25 03:35:00 yamaha volumio[17762]: info: CorePlayQueue::getTrack 0 Feb 25 03:35:00 yamaha volumio[17762]: info: CoreCommandRouter::volumioPushState Feb 25 03:35:00 yamaha volumio[17762]: info: Setting Device type: Raspberry PI Feb 25 03:35:00 yamaha volumio-remote-updater[629]: [2026-02-25 03:35:00] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1771986899 101 Feb 25 03:35:00 yamaha volumio[17762]: 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:35:00 yamaha volumio[17762]: info: VolumeController:: Volume=86 Mute =false Feb 25 03:35:00 yamaha volumio[17762]: info: CoreStateMachine::pushState Feb 25 03:35:00 yamaha volumio[17762]: info: CorePlayQueue::getTrack 0 Feb 25 03:35:00 yamaha volumio[17762]: info: CoreCommandRouter::volumioPushState Feb 25 03:35:00 yamaha volumio[17762]: 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:35:01 yamaha volumio[17762]: 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:35:01 yamaha volumio[17762]: info: Completed loading Core Plugins Feb 25 03:35:01 yamaha volumio[17762]: info: Preparing to generate the ALSA configuration file Feb 25 03:35:01 yamaha sudo[17951]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 25 03:35:01 yamaha sudo[17951]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:35:01 yamaha volumio[17762]: info: Discovery: adding 67ba7d11-5499-4b1d-a75e-1a431a777f52 Feb 25 03:35:01 yamaha volumio[17762]: info: Discovery: Found device Yamaha Feb 25 03:35:01 yamaha volumio[17762]: info: CoreCommandRouter::volumioGetState Feb 25 03:35:01 yamaha go-librespot[17867]: time="2026-02-25T03:35:01+01:00" level=debug msg="connected to ap-gew4.spotify.com:80" Feb 25 03:35:01 yamaha volumio[17762]: info: CorePlayQueue::getTrack 0 Feb 25 03:35:01 yamaha go-librespot[17867]: time="2026-02-25T03:35:01+01:00" level=debug msg="completed keyexchange" Feb 25 03:35:01 yamaha go-librespot[17867]: time="2026-02-25T03:35:01+01:00" level=debug msg="completed challenge" Feb 25 03:35:01 yamaha volumio[17762]: info: Discovery: this is already registered, 67ba7d11-5499-4b1d-a75e-1a431a777f52 Feb 25 03:35:01 yamaha volumio[17762]: info: Discovery: Found device Yamaha Feb 25 03:35:01 yamaha volumio[17762]: info: CoreCommandRouter::volumioGetState Feb 25 03:35:01 yamaha volumio[17762]: info: CorePlayQueue::getTrack 0 Feb 25 03:35:01 yamaha go-librespot[17867]: time="2026-02-25T03:35:01+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:35:01 yamaha volumio[17762]: info: Asound.conf file unchanged, so no further update is needed Feb 25 03:35:01 yamaha volumio[17762]: info: Output device has changed, restarting MPD Feb 25 03:35:01 yamaha sudo[17951]: pam_unix(sudo:session): session closed for user root Feb 25 03:35:01 yamaha volumio[17762]: info: Output device has changed, restarting Shairport Sync Feb 25 03:35:01 yamaha volumio[17762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:35:01 yamaha volumio[17762]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:35:01 yamaha sudo[17954]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 25 03:35:01 yamaha sudo[17954]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:35:01 yamaha sudo[17954]: pam_unix(sudo:session): session closed for user root Feb 25 03:35:01 yamaha volumio[17762]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 25 03:35:01 yamaha sudo[17956]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 25 03:35:01 yamaha sudo[17956]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:35:01 yamaha volumio[17762]: info: ___________ START PLUGINS ___________ Feb 25 03:35:01 yamaha volumio[17762]: info: ControllerMpd::onStart: Initializing MPD Feb 25 03:35:01 yamaha volumio[17762]: info: Creating MPD Configuration file Feb 25 03:35:01 yamaha volumio[17762]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 03:35:01 yamaha volumio[17762]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 25 03:35:01 yamaha volumio[17762]: info: [1771986901642] CoreMusicLibrary::Adding element Media Servers Feb 25 03:35:01 yamaha volumio[17762]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 25 03:35:01 yamaha systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 25 03:35:01 yamaha volumio[17762]: info: UPNP Browser: Client initialized successfully Feb 25 03:35:01 yamaha sudo[17964]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Feb 25 03:35:01 yamaha sudo[17966]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 25 03:35:01 yamaha sudo[17966]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:35:01 yamaha sudo[17966]: pam_unix(sudo:session): session closed for user root Feb 25 03:35:01 yamaha sudo[17964]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:35:01 yamaha volumio[17762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:35:01 yamaha volumio[17762]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:35:01 yamaha sudo[17968]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 25 03:35:01 yamaha sudo[17968]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:35:01 yamaha systemd[1]: mpd.service: Deactivated successfully. Feb 25 03:35:01 yamaha systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 25 03:35:01 yamaha systemd[1]: mpd.service: Consumed 7.399s CPU time. Feb 25 03:35:01 yamaha systemd[1]: mpd.socket: Deactivated successfully. Feb 25 03:35:01 yamaha systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 25 03:35:01 yamaha systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 25 03:35:01 yamaha volumio[17762]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 25 03:35:01 yamaha systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 25 03:35:01 yamaha volumio[17762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:35:01 yamaha volumio[17762]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:35:01 yamaha systemd[1]: Starting mpd.service - Music Player Daemon... Feb 25 03:35:01 yamaha go-librespot[17867]: time="2026-02-25T03:35:01+01:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 25 03:35:02 yamaha sudo[17964]: pam_unix(sudo:session): session closed for user root Feb 25 03:35:02 yamaha systemd[1]: mpd.service: Deactivated successfully. Feb 25 03:35:02 yamaha systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 25 03:35:02 yamaha systemd[1]: mpd.socket: Deactivated successfully. Feb 25 03:35:02 yamaha volumio[17762]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 25 03:35:02 yamaha systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 25 03:35:02 yamaha systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 25 03:35:02 yamaha volumio[17762]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 25 03:35:02 yamaha volumio[17762]: info: [1771986902074] CoreMusicLibrary::Adding element Last_100 Feb 25 03:35:02 yamaha volumio[17762]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 25 03:35:02 yamaha volumio[17762]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 25 03:35:02 yamaha volumio[17762]: info: [1771986902093] CoreMusicLibrary::Adding element Webradio Feb 25 03:35:02 yamaha volumio[17762]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 25 03:35:02 yamaha go-librespot[17867]: time="2026-02-25T03:35:02+01:00" level=debug msg="completed keyexchange" Feb 25 03:35:02 yamaha go-librespot[17867]: time="2026-02-25T03:35:02+01:00" level=debug msg="completed challenge" Feb 25 03:35:02 yamaha volumio[17762]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 25 03:35:02 yamaha systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 25 03:35:02 yamaha volumio[17762]: info: Initializing BBC Radios Feb 25 03:35:02 yamaha systemd[1]: Starting mpd.service - Music Player Daemon... Feb 25 03:35:02 yamaha go-librespot[17867]: time="2026-02-25T03:35:02+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:35:02 yamaha volumio[17762]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 25 03:35:02 yamaha volumio[17762]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:35:02 yamaha volumio[17762]: info: Adding Calm Radio to Browse Sources Feb 25 03:35:02 yamaha volumio[17762]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 25 03:35:02 yamaha volumio[17762]: info: [1771986902404] CoreMusicLibrary::Adding element Calm Radio Feb 25 03:35:02 yamaha volumio[17762]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 25 03:35:02 yamaha volumio[17762]: Cannot find translation for source Calm Radio Feb 25 03:35:02 yamaha volumio[17762]: info: Creating Spotify config file Feb 25 03:35:02 yamaha volumio[17762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:35:02 yamaha sudo[17984]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 25 03:35:02 yamaha sudo[17984]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 25 03:35:02 yamaha sudo[17984]: pam_unix(sudo:session): session closed for user root Feb 25 03:35:02 yamaha volumio[17876]: Starting albumart workers Feb 25 03:35:02 yamaha go-librespot[17867]: time="2026-02-25T03:35:02+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:35:03 yamaha volumio[17878]: Starting albumart workers Feb 25 03:35:03 yamaha go-librespot[17867]: time="2026-02-25T03:35:03+01:00" level=debug msg="connected to ap-gae2.spotify.com:80" Feb 25 03:35:03 yamaha volumio[17877]: Starting albumart workers Feb 25 03:35:03 yamaha go-librespot[17867]: time="2026-02-25T03:35:03+01:00" level=debug msg="completed keyexchange" Feb 25 03:35:03 yamaha go-librespot[17867]: time="2026-02-25T03:35:03+01:00" level=debug msg="completed challenge" Feb 25 03:35:03 yamaha go-librespot[17867]: time="2026-02-25T03:35:03+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:35:03 yamaha volumio[17762]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 25 03:35:03 yamaha volumio[17762]: info: [1771986903529] CoreMusicLibrary::Adding element 80s80s Radio Feb 25 03:35:03 yamaha volumio[17762]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 25 03:35:03 yamaha volumio[17762]: Cannot find translation for source Calm Radio Feb 25 03:35:03 yamaha volumio[17762]: Cannot find translation for source 80s80s Radio Feb 25 03:35:03 yamaha volumio[17762]: info: Volumio Calling Home Feb 25 03:35:04 yamaha go-librespot[17867]: time="2026-02-25T03:35:04+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 25 03:35:04 yamaha go-librespot[17867]: time="2026-02-25T03:35:04+01:00" level=debug msg="completed keyexchange" Feb 25 03:35:04 yamaha go-librespot[17867]: time="2026-02-25T03:35:04+01:00" level=debug msg="completed challenge" Feb 25 03:35:04 yamaha go-librespot[17867]: time="2026-02-25T03:35:04+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:35:04 yamaha go-librespot[17867]: time="2026-02-25T03:35:04+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:35:04 yamaha systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 03:35:04 yamaha systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 03:35:04 yamaha systemd[1]: go-librespot-daemon.service: Consumed 1.087s CPU time. Feb 25 03:35:04 yamaha volumio[17762]: info: Cannot mount NAS Ugreen at system boot, trial number 3 ,retrying in 5 seconds Feb 25 03:35:05 yamaha volumio[17762]: info: MPD Permissions set Feb 25 03:35:05 yamaha volumio[17762]: info: MPD Permissions set Feb 25 03:35:05 yamaha volumio[17762]: info: Upmpdcli Daemon Started Feb 25 03:35:05 yamaha volumio[17762]: info: Volumio called home Feb 25 03:35:05 yamaha volumio[17762]: info: Spotify config file written Feb 25 03:35:05 yamaha sudo[18023]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 25 03:35:05 yamaha sudo[18023]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:35:05 yamaha systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:35:05 yamaha systemd[1]: go-librespot-daemon.service: Consumed 1.087s CPU time. Feb 25 03:35:05 yamaha systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:35:05 yamaha go-librespot[18025]: go-librespot daemon starting... Feb 25 03:35:05 yamaha sudo[18023]: pam_unix(sudo:session): session closed for user root Feb 25 03:35:05 yamaha go-librespot[18026]: time="2026-02-25T03:35:05+01:00" level=info msg="running go-librespot 0.6.2" Feb 25 03:35:05 yamaha go-librespot[18026]: time="2026-02-25T03:35:05+01:00" level=debug msg="app state loaded" Feb 25 03:35:05 yamaha go-librespot[18026]: time="2026-02-25T03:35:05+01:00" level=debug msg="stored credentials not found" Feb 25 03:35:05 yamaha go-librespot[18026]: time="2026-02-25T03:35:05+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 25 03:35:05 yamaha volumio[17762]: 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:35:05 yamaha volumio[17762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:35:05 yamaha volumio[17762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:35:05 yamaha volumio[17762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:35:05 yamaha volumio[17762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:35:05 yamaha volumio[17762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:35:05 yamaha volumio[17762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:35:05 yamaha volumio[17762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:35:05 yamaha volumio[17762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:35:05 yamaha volumio[17762]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:35:05 yamaha volumio[17762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:35:05 yamaha volumio[17762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:35:05 yamaha volumio[17762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:35:05 yamaha volumio[17762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:35:05 yamaha volumio[17762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:35:05 yamaha volumio[17762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:35:05 yamaha volumio[17762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:35:05 yamaha volumio[17762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:35:05 yamaha volumio[17762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:35:05 yamaha volumio[17762]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:35:05 yamaha volumio[17762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:35:05 yamaha volumio[17762]: info: No need to fix Spotify hosts Feb 25 03:35:06 yamaha go-librespot[18026]: time="2026-02-25T03:35:06+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:35:06 yamaha go-librespot[18026]: time="2026-02-25T03:35:06+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:35:06 yamaha go-librespot[18026]: time="2026-02-25T03:35:06+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:35:06 yamaha go-librespot[18026]: time="2026-02-25T03:35:06+01:00" level=info msg="zeroconf server listening on port 42905" Feb 25 03:35:06 yamaha go-librespot[18026]: time="2026-02-25T03:35:06+01:00" level=debug msg="obtained new client token: AADcSuXgv0JagiLY0/+lJkw9VWg8Z+2zZkiJEpRCIFCcwvv2pq7PfCMM7AgTNiLoPg73DTK65QeE2GGJZX+AnPnTO/M4K4kvxQDDtcqOgRKRNaK0zwUw6pE5f4Cb3AbQRtj0pOvvTGBIBAx5SF7VhSrRlB3ezoXT8f3jXrX2+GGaoo9s6JykMRqODobBfktgjvdtT55HVN7ADKjernAT7qKKcr+u6O61yMKrPSg4jo2dokByj27lg1tz" Feb 25 03:35:06 yamaha go-librespot[18026]: time="2026-02-25T03:35:06+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 25 03:35:06 yamaha volumio[17762]: info: Starting Shairport Sync Feb 25 03:35:06 yamaha go-librespot[18026]: time="2026-02-25T03:35:06+01:00" level=debug msg="completed keyexchange" Feb 25 03:35:06 yamaha go-librespot[18026]: time="2026-02-25T03:35:06+01:00" level=debug msg="completed challenge" Feb 25 03:35:06 yamaha volumio[17762]: info: Starting Shairport Sync Feb 25 03:35:06 yamaha volumio[17762]: info: Starting Shairport Sync Feb 25 03:35:06 yamaha go-librespot[18026]: time="2026-02-25T03:35:06+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:35:06 yamaha sudo[18047]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 25 03:35:06 yamaha sudo[18047]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:35:06 yamaha sudo[18049]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 25 03:35:06 yamaha sudo[18049]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:35:06 yamaha sudo[18051]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 25 03:35:06 yamaha sudo[18051]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:35:06 yamaha volumio[17762]: info: New Spotify access tokenBQA_rDOsBi... Feb 25 03:35:06 yamaha volumio[17762]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 25 03:35:06 yamaha systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 25 03:35:06 yamaha systemd[1]: shairport-sync.service: Deactivated successfully. Feb 25 03:35:06 yamaha systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 25 03:35:06 yamaha systemd[1]: shairport-sync.service: Consumed 2.478s CPU time. Feb 25 03:35:06 yamaha systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 25 03:35:06 yamaha sudo[18047]: pam_unix(sudo:session): session closed for user root Feb 25 03:35:06 yamaha sudo[18051]: pam_unix(sudo:session): session closed for user root Feb 25 03:35:06 yamaha sudo[18049]: pam_unix(sudo:session): session closed for user root Feb 25 03:35:07 yamaha go-librespot[18026]: time="2026-02-25T03:35:07+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Feb 25 03:35:07 yamaha volumio[17762]: info: Shairport-Sync Started Feb 25 03:35:07 yamaha volumio[17762]: Error adding Membership: Error: addMembership EINVAL Feb 25 03:35:07 yamaha volumio[17762]: info: Shairport-Sync Started Feb 25 03:35:07 yamaha volumio[17762]: info: Shairport-Sync Started Feb 25 03:35:07 yamaha go-librespot[18026]: time="2026-02-25T03:35:07+01:00" level=debug msg="completed keyexchange" Feb 25 03:35:07 yamaha go-librespot[18026]: time="2026-02-25T03:35:07+01:00" level=debug msg="completed challenge" Feb 25 03:35:07 yamaha volumio[17762]: info: CoreCommandRouter::volumioGetState Feb 25 03:35:07 yamaha volumio[17762]: info: CorePlayQueue::getTrack 0 Feb 25 03:35:07 yamaha go-librespot[18026]: time="2026-02-25T03:35:07+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:35:07 yamaha volumio[17762]: 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:35:07 yamaha volumio[17762]: info: Spotify Successfully logged in Feb 25 03:35:07 yamaha volumio[17762]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 25 03:35:07 yamaha volumio[17762]: info: [1771986907177] CoreMusicLibrary::Adding element Spotify Feb 25 03:35:07 yamaha volumio[17762]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 25 03:35:07 yamaha volumio[17762]: Cannot find translation for source Calm Radio Feb 25 03:35:07 yamaha volumio[17762]: Cannot find translation for source 80s80s Radio Feb 25 03:35:07 yamaha volumio[17762]: Cannot find translation for source Spotify Feb 25 03:35:07 yamaha go-librespot[18026]: time="2026-02-25T03:35:07+01:00" level=debug msg="connected to ap-gew4.spotify.com:80" Feb 25 03:35:07 yamaha go-librespot[18026]: time="2026-02-25T03:35:07+01:00" level=debug msg="completed keyexchange" Feb 25 03:35:07 yamaha go-librespot[18026]: time="2026-02-25T03:35:07+01:00" level=debug msg="completed challenge" Feb 25 03:35:07 yamaha go-librespot[18026]: time="2026-02-25T03:35:07+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:35:08 yamaha go-librespot[18026]: time="2026-02-25T03:35:08+01:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 25 03:35:08 yamaha go-librespot[18026]: time="2026-02-25T03:35:08+01:00" level=debug msg="completed keyexchange" Feb 25 03:35:08 yamaha go-librespot[18026]: time="2026-02-25T03:35:08+01:00" level=debug msg="completed challenge" Feb 25 03:35:08 yamaha go-librespot[18026]: time="2026-02-25T03:35:08+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:35:09 yamaha mpd[17999]: 2026-02-25T03:35:09 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 25 03:35:09 yamaha volumio[17762]: info: go-librespot daemon successfully initialized Feb 25 03:35:09 yamaha go-librespot[18026]: time="2026-02-25T03:35:09+01:00" level=warning msg="failed to connect to AP ap-gue1.spotify.com:443, retrying with a different AP" error="dial tcp 34.158.255.62:443: connect: connection refused" Feb 25 03:35:09 yamaha go-librespot[18026]: time="2026-02-25T03:35:09+01:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:80, retrying with a different AP" error="dial tcp 104.199.65.9:80: connect: connection refused" Feb 25 03:35:09 yamaha go-librespot[18026]: time="2026-02-25T03:35:09+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 25 03:35:09 yamaha go-librespot[18026]: time="2026-02-25T03:35:09+01:00" level=debug msg="completed keyexchange" Feb 25 03:35:09 yamaha go-librespot[18026]: time="2026-02-25T03:35:09+01:00" level=debug msg="completed challenge" Feb 25 03:35:09 yamaha systemd[1]: Started mpd.service - Music Player Daemon. Feb 25 03:35:09 yamaha go-librespot[18026]: time="2026-02-25T03:35:09+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:35:09 yamaha sudo[17968]: pam_unix(sudo:session): session closed for user root Feb 25 03:35:09 yamaha sudo[17956]: pam_unix(sudo:session): session closed for user root Feb 25 03:35:09 yamaha volumio[17762]: info: Completed starting Core Plugins Feb 25 03:35:09 yamaha volumio[17762]: info: ------------------------------------------- Feb 25 03:35:09 yamaha volumio[17762]: info: ----- MyVolumio plugins startup ---- Feb 25 03:35:09 yamaha volumio[17762]: info: ------------------------------------------- Feb 25 03:35:09 yamaha volumio[17762]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 25 03:35:09 yamaha volumio[17762]: error: MPD error: The expression evaluated to a falsy value: Feb 25 03:35:09 yamaha volumio[17762]: assert.ok(self.idling) Feb 25 03:35:09 yamaha volumio[17762]: error: The expression evaluated to a falsy value: Feb 25 03:35:09 yamaha volumio[17762]: assert.ok(self.idling) Feb 25 03:35:09 yamaha volumio[17762]: error: updateQueue error: null Feb 25 03:35:09 yamaha volumio[17762]: info: MPD running with PID17999 Feb 25 03:35:09 yamaha volumio[17762]: ,establishing connection Feb 25 03:35:09 yamaha volumio[17762]: error: updateQueue error: null Feb 25 03:35:09 yamaha go-librespot[18026]: time="2026-02-25T03:35:09+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Feb 25 03:35:09 yamaha go-librespot[18026]: time="2026-02-25T03:35:09+01:00" level=debug msg="completed keyexchange" Feb 25 03:35:09 yamaha go-librespot[18026]: time="2026-02-25T03:35:09+01:00" level=debug msg="completed challenge" Feb 25 03:35:09 yamaha volumio[17762]: info: Cannot mount NAS Ugreen at system boot, trial number 4 ,retrying in 5 seconds Feb 25 03:35:09 yamaha go-librespot[18026]: time="2026-02-25T03:35:09+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:35:09 yamaha go-librespot[18026]: time="2026-02-25T03:35:09+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:35:09 yamaha systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 03:35:09 yamaha systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 03:35:09 yamaha systemd[1]: go-librespot-daemon.service: Consumed 1.015s CPU time. Feb 25 03:35:12 yamaha volumio[17762]: info: Initializing connection to go-librespot Websocket Feb 25 03:35:12 yamaha volumio[17762]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 25 03:35:13 yamaha systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16. Feb 25 03:35:13 yamaha systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:35:13 yamaha systemd[1]: go-librespot-daemon.service: Consumed 1.015s CPU time. Feb 25 03:35:13 yamaha systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:35:13 yamaha go-librespot[18090]: go-librespot daemon starting... Feb 25 03:35:13 yamaha go-librespot[18091]: time="2026-02-25T03:35:13+01:00" level=info msg="running go-librespot 0.6.2" Feb 25 03:35:13 yamaha go-librespot[18091]: time="2026-02-25T03:35:13+01:00" level=debug msg="app state loaded" Feb 25 03:35:13 yamaha go-librespot[18091]: time="2026-02-25T03:35:13+01:00" level=debug msg="stored credentials not found" Feb 25 03:35:13 yamaha go-librespot[18091]: time="2026-02-25T03:35:13+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 25 03:35:13 yamaha go-librespot[18091]: time="2026-02-25T03:35:13+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:35:13 yamaha go-librespot[18091]: time="2026-02-25T03:35:13+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:35:13 yamaha go-librespot[18091]: time="2026-02-25T03:35:13+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:35:13 yamaha go-librespot[18091]: time="2026-02-25T03:35:13+01:00" level=info msg="zeroconf server listening on port 40745" Feb 25 03:35:13 yamaha go-librespot[18091]: time="2026-02-25T03:35:13+01:00" level=debug msg="obtained new client token: AADSeR33svwnQpO/VmVGVyjN7IQya+QVAbqxDGWtIJD9Wk69spLyfMr27Rwl5tb8ZeSyC/DkyOSkh/NXEq27D1tMi5NqLELqU62VDS1u3KZPzSg39kqUcxY6cuzzOQ/PYEASxZwa3CKB38xlwmd5EKA8RUe5vH9qPNfpBgbE4i32s24R3JgSmUaxCG2hIpvxQYnEg0KTwRQD2EqOU+HilBrHSySvnQDVtd8E94g4Z7sj0kkXP6yXqjFD" Feb 25 03:35:13 yamaha go-librespot[18091]: time="2026-02-25T03:35:13+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 25 03:35:13 yamaha go-librespot[18091]: time="2026-02-25T03:35:13+01:00" level=debug msg="completed keyexchange" Feb 25 03:35:13 yamaha go-librespot[18091]: time="2026-02-25T03:35:13+01:00" level=debug msg="completed challenge" Feb 25 03:35:13 yamaha go-librespot[18091]: time="2026-02-25T03:35:13+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:35:14 yamaha go-librespot[18091]: time="2026-02-25T03:35:14+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Feb 25 03:35:14 yamaha go-librespot[18091]: time="2026-02-25T03:35:14+01:00" level=debug msg="completed keyexchange" Feb 25 03:35:14 yamaha go-librespot[18091]: time="2026-02-25T03:35:14+01:00" level=debug msg="completed challenge" Feb 25 03:35:14 yamaha go-librespot[18091]: time="2026-02-25T03:35:14+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:35:14 yamaha volumio[17762]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 25 03:35:15 yamaha volumio[17762]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Feb 25 03:35:15 yamaha go-librespot[18091]: time="2026-02-25T03:35:15+01:00" level=debug msg="connected to ap-gew4.spotify.com:80" Feb 25 03:35:15 yamaha go-librespot[18091]: time="2026-02-25T03:35:15+01:00" level=debug msg="completed keyexchange" Feb 25 03:35:15 yamaha go-librespot[18091]: time="2026-02-25T03:35:15+01:00" level=debug msg="completed challenge" Feb 25 03:35:15 yamaha go-librespot[18091]: time="2026-02-25T03:35:15+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:35:15 yamaha volumio[17762]: info: Initializing connection to go-librespot Websocket Feb 25 03:35:15 yamaha go-librespot[18091]: time="2026-02-25T03:35:15+01:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 25 03:35:15 yamaha go-librespot[18091]: time="2026-02-25T03:35:15+01:00" level=debug msg="completed keyexchange" Feb 25 03:35:15 yamaha go-librespot[18091]: time="2026-02-25T03:35:15+01:00" level=debug msg="completed challenge" Feb 25 03:35:16 yamaha go-librespot[18091]: time="2026-02-25T03:35:16+01:00" level=debug msg="new websocket client" Feb 25 03:35:16 yamaha volumio[17762]: info: Connection to go-librespot Websocket established Feb 25 03:35:16 yamaha go-librespot[18091]: time="2026-02-25T03:35:16+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:35:16 yamaha go-librespot[18091]: time="2026-02-25T03:35:16+01:00" level=debug msg="connected to ap-gue1.spotify.com:443" Feb 25 03:35:16 yamaha go-librespot[18091]: time="2026-02-25T03:35:16+01:00" level=debug msg="completed keyexchange" Feb 25 03:35:16 yamaha go-librespot[18091]: time="2026-02-25T03:35:16+01:00" level=debug msg="completed challenge" Feb 25 03:35:16 yamaha go-librespot[18091]: time="2026-02-25T03:35:16+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:35:17 yamaha go-librespot[18091]: time="2026-02-25T03:35:17+01:00" level=debug msg="connected to ap-gae2.spotify.com:80" Feb 25 03:35:17 yamaha volumio[17762]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 25 03:35:17 yamaha volumio[17762]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 25 03:35:17 yamaha volumio[17762]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 25 03:35:17 yamaha volumio[17762]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 25 03:35:17 yamaha volumio[17762]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 25 03:35:17 yamaha volumio[17762]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 25 03:35:17 yamaha volumio[17762]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 25 03:35:17 yamaha volumio[17762]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 25 03:35:17 yamaha volumio[17762]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 25 03:35:17 yamaha volumio[17762]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 25 03:35:17 yamaha volumio[17762]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 25 03:35:17 yamaha volumio[17762]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 25 03:35:17 yamaha volumio[17762]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 25 03:35:17 yamaha volumio[17762]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 25 03:35:17 yamaha volumio[17762]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 25 03:35:17 yamaha volumio[17762]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 25 03:35:17 yamaha volumio[17762]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 25 03:35:17 yamaha volumio[17762]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 25 03:35:17 yamaha volumio[17762]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 25 03:35:17 yamaha volumio[17762]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 25 03:35:17 yamaha volumio[17762]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 25 03:35:17 yamaha volumio[17762]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 25 03:35:17 yamaha volumio[17762]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 25 03:35:17 yamaha volumio[17762]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 25 03:35:17 yamaha volumio[17762]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 25 03:35:17 yamaha volumio[17762]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 25 03:35:17 yamaha volumio[17762]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 25 03:35:17 yamaha volumio[17762]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 25 03:35:17 yamaha volumio[17762]: info: Adding plugin bluetooth to MyMusic Plugins Feb 25 03:35:17 yamaha go-librespot[18091]: time="2026-02-25T03:35:17+01:00" level=debug msg="completed keyexchange" Feb 25 03:35:17 yamaha go-librespot[18091]: time="2026-02-25T03:35:17+01:00" level=debug msg="completed challenge" Feb 25 03:35:17 yamaha volumio[17762]: info: Adding plugin multiroom to MyMusic Plugins Feb 25 03:35:17 yamaha volumio[17762]: info: Adding plugin metavolumio to MyMusic Plugins Feb 25 03:35:17 yamaha volumio[17762]: info: Adding plugin cd_controller to MyMusic Plugins Feb 25 03:35:17 yamaha volumio[17762]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 25 03:35:17 yamaha volumio[17762]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 25 03:35:17 yamaha volumio[17762]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 25 03:35:17 yamaha volumio[17762]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 25 03:35:18 yamaha go-librespot[18091]: time="2026-02-25T03:35:18+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:35:18 yamaha go-librespot[18091]: time="2026-02-25T03:35:18+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:35:18 yamaha systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 03:35:18 yamaha systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 03:35:19 yamaha volumio[17762]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 25 03:35:19 yamaha volumio[17762]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 25 03:35:19 yamaha volumio[17762]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:35:19 yamaha volumio[17762]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:35:19 yamaha volumio[17762]: info: Starting MyVolumio Remote Streaming Endpoints Feb 25 03:35:19 yamaha volumio[17762]: info: MyVolumio login type: Token Feb 25 03:35:19 yamaha volumio[17762]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 25 03:35:19 yamaha volumio[17762]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 25 03:35:21 yamaha systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17. Feb 25 03:35:21 yamaha systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:35:21 yamaha systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:35:21 yamaha go-librespot[18115]: go-librespot daemon starting... Feb 25 03:35:21 yamaha go-librespot[18116]: time="2026-02-25T03:35:21+01:00" level=info msg="running go-librespot 0.6.2" Feb 25 03:35:21 yamaha go-librespot[18116]: time="2026-02-25T03:35:21+01:00" level=debug msg="app state loaded" Feb 25 03:35:21 yamaha go-librespot[18116]: time="2026-02-25T03:35:21+01:00" level=debug msg="stored credentials not found" Feb 25 03:35:21 yamaha go-librespot[18116]: time="2026-02-25T03:35:21+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 25 03:35:21 yamaha volumio[17762]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 25 03:35:21 yamaha volumio[17762]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 25 03:35:21 yamaha volumio[17762]: info: Streaming services startup Feb 25 03:35:21 yamaha volumio[17762]: info: Starting Streaming Daemon Feb 25 03:35:21 yamaha sudo[18125]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 25 03:35:21 yamaha volumio[17762]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 25 03:35:21 yamaha sudo[18125]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:35:21 yamaha sudo[18125]: pam_unix(sudo:session): session closed for user root Feb 25 03:35:21 yamaha volumio[17762]: info: Getting Spotify volume Feb 25 03:35:21 yamaha volumio[17762]: info: Connection to go-librespot Websocket closed Feb 25 03:35:21 yamaha volumio[17762]: error: Cannot start Volumio Streaming Daemon Feb 25 03:35:21 yamaha volumio[17762]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 25 03:35:21 yamaha volumio[17762]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 25 03:35:21 yamaha go-librespot[18116]: time="2026-02-25T03:35:21+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:35:21 yamaha go-librespot[18116]: time="2026-02-25T03:35:21+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:35:21 yamaha go-librespot[18116]: time="2026-02-25T03:35:21+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:35:21 yamaha volumio[17762]: 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:35:21 yamaha go-librespot[18116]: time="2026-02-25T03:35:21+01:00" level=info msg="zeroconf server listening on port 41593" Feb 25 03:35:22 yamaha go-librespot[18116]: time="2026-02-25T03:35:22+01:00" level=debug msg="obtained new client token: AAAZkAsCoJN5/3m1HpHuccOxCVgq/MbBtpYwomhhPDTSWCzqfzbcmiVQe1k/Z4l8y2MTbJuQ+j1AyVvBRdUvcodSoDVdlJUOuODODtlVd17tqfgOQ8z2a6e6WTWxhPIBN5ZL5vt+ScVGQ9AxnV2yn0chQs05QEQCiQsNqJmqMw0oAQshfn3yIumQdsl9X+NU60sgAvccqFd73bP9HiO72JGuc2DAkFzHUxrylsZ5g3F0mHBk5jlFgQ==" Feb 25 03:35:22 yamaha go-librespot[18116]: time="2026-02-25T03:35:22+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 25 03:35:22 yamaha go-librespot[18116]: time="2026-02-25T03:35:22+01:00" level=debug msg="completed keyexchange" Feb 25 03:35:22 yamaha go-librespot[18116]: time="2026-02-25T03:35:22+01:00" level=debug msg="completed challenge" Feb 25 03:35:22 yamaha volumio[17762]: info: CoreCommandRouter::volumioGetState Feb 25 03:35:22 yamaha volumio[17762]: info: CorePlayQueue::getTrack 0 Feb 25 03:35:22 yamaha go-librespot[18116]: time="2026-02-25T03:35:22+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:35:22 yamaha volumio[17762]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 25 03:35:22 yamaha volumio[17762]: SPOTIFY: SPOTIFY VOLUME undefined Feb 25 03:35:22 yamaha volumio[17762]: SPOTIFY: VOLUMIO VOLUME 86 Feb 25 03:35:22 yamaha volumio[17762]: info: Aligning Spotify Volume to Volumio Volume Feb 25 03:35:22 yamaha volumio[17762]: info: CoreCommandRouter::volumioGetState Feb 25 03:35:22 yamaha volumio[17762]: info: CorePlayQueue::getTrack 0 Feb 25 03:35:22 yamaha volumio[17762]: info: Setting Spotify Volume from Volumio: 86 Feb 25 03:35:22 yamaha volumio[17762]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Feb 25 03:35:22 yamaha go-librespot[18116]: time="2026-02-25T03:35:22+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Feb 25 03:35:22 yamaha go-librespot[18116]: time="2026-02-25T03:35:22+01:00" level=debug msg="completed keyexchange" Feb 25 03:35:22 yamaha go-librespot[18116]: time="2026-02-25T03:35:22+01:00" level=debug msg="completed challenge" Feb 25 03:35:22 yamaha go-librespot[18116]: time="2026-02-25T03:35:22+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:35:23 yamaha volumio[17762]: info: MyVolumio token set successfully Feb 25 03:35:23 yamaha volumio[17762]: info: MYVOLUMIO: Adding device Feb 25 03:35:23 yamaha volumio[17762]: info: MYVOLUMIO: Evaluating Server Feb 25 03:35:23 yamaha go-librespot[18116]: time="2026-02-25T03:35:23+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:35:23 yamaha go-librespot[18116]: time="2026-02-25T03:35:23+01:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 25 03:35:23 yamaha go-librespot[18116]: time="2026-02-25T03:35:23+01:00" level=debug msg="completed keyexchange" Feb 25 03:35:23 yamaha go-librespot[18116]: time="2026-02-25T03:35:23+01:00" level=debug msg="completed challenge" Feb 25 03:35:23 yamaha volumio[17762]: info: MyVolumio status changed Feb 25 03:35:23 yamaha volumio[17762]: info: Streaming services startup Feb 25 03:35:23 yamaha volumio[17762]: info: Starting Streaming Daemon Feb 25 03:35:23 yamaha volumio[17762]: info: Removing browser output: myVolumio user plan is not superstar Feb 25 03:35:23 yamaha volumio[17762]: info: Removing audio output: Feb 25 03:35:23 yamaha volumio[17762]: info: Stoppping Tunnel 1 Feb 25 03:35:23 yamaha sudo[18153]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 25 03:35:23 yamaha sudo[18153]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:35:23 yamaha go-librespot[18116]: time="2026-02-25T03:35:23+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:35:23 yamaha sudo[18155]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Feb 25 03:35:23 yamaha sudo[18155]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:35:23 yamaha sudo[18153]: pam_unix(sudo:session): session closed for user root Feb 25 03:35:23 yamaha volumio[17762]: SPOTIFY: SETTING SPOTIFY VOLUME 86 Feb 25 03:35:23 yamaha volumio[17762]: info: Sending Spotify command with payload to local API: /player/volume Feb 25 03:35:23 yamaha volumio[17762]: info: Setting Geolocation for MyVolumio to eu8 Feb 25 03:35:23 yamaha volumio[17762]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:35:23 yamaha volumio[17762]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:35:23 yamaha volumio[17762]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:35:23 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:35:23 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:35:23 yamaha volumio[17762]: error: Cannot start Volumio Streaming Daemon Feb 25 03:35:23 yamaha volumio[17762]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 25 03:35:23 yamaha volumio[17762]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 25 03:35:23 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:35:23 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:35:23 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:35:23 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:35:23 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:35:23 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:35:23 yamaha sudo[18155]: pam_unix(sudo:session): session closed for user root Feb 25 03:35:23 yamaha volumio[17762]: info: Remote SSH Stopped Feb 25 03:35:24 yamaha volumio[17762]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Feb 25 03:35:24 yamaha go-librespot[18116]: time="2026-02-25T03:35:24+01:00" level=debug msg="connected to ap-gew1.spotify.com:443" Feb 25 03:35:24 yamaha go-librespot[18116]: time="2026-02-25T03:35:24+01:00" level=debug msg="completed keyexchange" Feb 25 03:35:24 yamaha go-librespot[18116]: time="2026-02-25T03:35:24+01:00" level=debug msg="completed challenge" Feb 25 03:35:24 yamaha go-librespot[18116]: time="2026-02-25T03:35:24+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:35:24 yamaha volumio[17762]: info: Updating MyVolumio device info Feb 25 03:35:24 yamaha volumio[17762]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:35:24 yamaha volumio[17762]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:35:24 yamaha volumio[17762]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:35:24 yamaha volumio[17762]: info: Initializing connection to go-librespot Websocket Feb 25 03:35:24 yamaha go-librespot[18116]: time="2026-02-25T03:35:24+01:00" level=debug msg="new websocket client" Feb 25 03:35:24 yamaha volumio[17762]: info: Connection to go-librespot Websocket established Feb 25 03:35:25 yamaha volumio[17762]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Feb 25 03:35:25 yamaha go-librespot[18116]: time="2026-02-25T03:35:25+01:00" level=debug msg="connected to ap-gae2.spotify.com:80" Feb 25 03:35:25 yamaha go-librespot[18116]: time="2026-02-25T03:35:25+01:00" level=debug msg="completed keyexchange" Feb 25 03:35:25 yamaha go-librespot[18116]: time="2026-02-25T03:35:25+01:00" level=debug msg="completed challenge" Feb 25 03:35:25 yamaha go-librespot[18116]: time="2026-02-25T03:35:25+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:35:26 yamaha go-librespot[18116]: time="2026-02-25T03:35:26+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 25 03:35:26 yamaha go-librespot[18116]: time="2026-02-25T03:35:26+01:00" level=debug msg="completed keyexchange" Feb 25 03:35:26 yamaha go-librespot[18116]: time="2026-02-25T03:35:26+01:00" level=debug msg="completed challenge" Feb 25 03:35:26 yamaha go-librespot[18116]: time="2026-02-25T03:35:26+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:35:26 yamaha go-librespot[18116]: time="2026-02-25T03:35:26+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:35:26 yamaha volumio[17762]: info: Connection to go-librespot Websocket closed Feb 25 03:35:26 yamaha systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 03:35:26 yamaha volumio[17762]: error: Failed to send command to Spotify local API: /player/volume: Error: socket hang up Feb 25 03:35:26 yamaha systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 03:35:26 yamaha volumio[17762]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 25 03:35:26 yamaha volumio[17762]: Error: socket hang up Feb 25 03:35:26 yamaha volumio[17762]: at connResetException (node:internal/errors:720:14) Feb 25 03:35:26 yamaha volumio[17762]: at Socket.socketOnEnd (node:_http_client:519:23) Feb 25 03:35:26 yamaha volumio[17762]: at Socket.emit (node:events:526:35) Feb 25 03:35:26 yamaha volumio[17762]: at endReadableNT (node:internal/streams/readable:1376:12) Feb 25 03:35:26 yamaha volumio[17762]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Feb 25 03:35:26 yamaha volumio[17762]: code: 'ECONNRESET', Feb 25 03:35:26 yamaha volumio[17762]: response: undefined Feb 25 03:35:26 yamaha volumio[17762]: } Feb 25 03:35:26 yamaha volumio[17762]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 25 03:35:27 yamaha sudo[18172]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-25 03:34' Feb 25 03:35:27 yamaha sudo[18172]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:35:27 yamaha sudo[18172]: pam_unix(sudo:session): session closed for user root Feb 25 03:35:28 yamaha volumio-remote-updater[629]: [2026-02-25 03:35:28] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 25 03:35:28 yamaha volumio-remote-updater[629]: [2026-02-25 03:35:28] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 25 03:35:28 yamaha volumio5-onboarding[1284]: time=2026-02-25T03:35:28.015+01:00 level=ERROR msg="failed reading message" component=volumio/socket error="websocket: close 1006 (abnormal closure): unexpected EOF" Feb 25 03:35:28 yamaha systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 25 03:35:28 yamaha volumio5-onboarding[1284]: time=2026-02-25T03:35:28.024+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:35:28 yamaha systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 25 03:35:28 yamaha systemd[1]: volumio.service: Consumed 56.064s CPU time. Feb 25 03:35:28 yamaha systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 25 03:35:28 yamaha systemd[1]: dynamicswap.service: Deactivated successfully. Feb 25 03:35:28 yamaha systemd[1]: volumio.service: Scheduled restart job, restart counter is at 211. Feb 25 03:35:28 yamaha systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 25 03:35:28 yamaha systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 25 03:35:28 yamaha systemd[1]: volumio.service: Consumed 56.064s CPU time. Feb 25 03:35:28 yamaha systemd[1]: Started volumio.service - Volumio Backend Module. Feb 25 03:35:28 yamaha systemd[1]: dynamicswap.service: Deactivated successfully. Feb 25 03:35:29 yamaha volumio5-onboarding[1284]: time=2026-02-25T03:35:29.029+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:35:29 yamaha systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 18. Feb 25 03:35:29 yamaha systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:35:29 yamaha systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:35:29 yamaha go-librespot[18203]: go-librespot daemon starting... Feb 25 03:35:29 yamaha go-librespot[18204]: time="2026-02-25T03:35:29+01:00" level=info msg="running go-librespot 0.6.2" Feb 25 03:35:29 yamaha go-librespot[18204]: time="2026-02-25T03:35:29+01:00" level=debug msg="app state loaded" Feb 25 03:35:29 yamaha go-librespot[18204]: time="2026-02-25T03:35:29+01:00" level=debug msg="stored credentials not found" Feb 25 03:35:29 yamaha go-librespot[18204]: time="2026-02-25T03:35:29+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 25 03:35:29 yamaha go-librespot[18204]: time="2026-02-25T03:35:29+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 25 03:35:29 yamaha go-librespot[18204]: time="2026-02-25T03:35:29+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:35:29 yamaha go-librespot[18204]: time="2026-02-25T03:35:29+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:35:29 yamaha go-librespot[18204]: time="2026-02-25T03:35:29+01:00" level=info msg="zeroconf server listening on port 34331" Feb 25 03:35:29 yamaha go-librespot[18204]: time="2026-02-25T03:35:29+01:00" level=debug msg="obtained new client token: AABuf10VcKuDOD4kcutAYykg/+bPv5VqlrcbdeSTn2ncTlqfAl8fmwtNR29Cl+vh+zdJcTC13NyJHgdtIwY25ouEMzqMaab4PAL7+zOlmGt/oFMUk93LOYWCFOZbIRbH/gX7usunW5h7/NmFa4Lz+tmBmsmvdadNxYYox0izfQYbBhEoU9OR+5BdFMBtdUTIiBoNDPpC0028naG1zoS6yjI5Xx5jSMcmLrNzq8nmA132p1ZKufOI7wgs" Feb 25 03:35:29 yamaha go-librespot[18204]: time="2026-02-25T03:35:29+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 25 03:35:30 yamaha go-librespot[18204]: time="2026-02-25T03:35:30+01:00" level=debug msg="completed keyexchange" Feb 25 03:35:30 yamaha go-librespot[18204]: time="2026-02-25T03:35:30+01:00" level=debug msg="completed challenge" Feb 25 03:35:30 yamaha volumio5-onboarding[1284]: time=2026-02-25T03:35:30.034+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:35:30 yamaha go-librespot[18204]: time="2026-02-25T03:35:30+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:35:30 yamaha go-librespot[18204]: time="2026-02-25T03:35:30+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Feb 25 03:35:30 yamaha go-librespot[18204]: time="2026-02-25T03:35:30+01:00" level=debug msg="completed keyexchange" Feb 25 03:35:30 yamaha go-librespot[18204]: time="2026-02-25T03:35:30+01:00" level=debug msg="completed challenge" Feb 25 03:35:30 yamaha go-librespot[18204]: time="2026-02-25T03:35:30+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:35:31 yamaha volumio5-onboarding[1284]: time=2026-02-25T03:35:31.037+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:35:31 yamaha go-librespot[18204]: time="2026-02-25T03:35:31+01:00" level=debug msg="connected to ap-gew4.spotify.com:80" Feb 25 03:35:31 yamaha go-librespot[18204]: time="2026-02-25T03:35:31+01:00" level=debug msg="completed keyexchange" Feb 25 03:35:31 yamaha go-librespot[18204]: time="2026-02-25T03:35:31+01:00" level=debug msg="completed challenge" Feb 25 03:35:31 yamaha go-librespot[18204]: time="2026-02-25T03:35:31+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:35:31 yamaha go-librespot[18204]: time="2026-02-25T03:35:31+01:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 25 03:35:31 yamaha volumio[18188]: info: ------------------------------------------- Feb 25 03:35:31 yamaha volumio[18188]: info: ----- Volumio3 ---- Feb 25 03:35:32 yamaha volumio[18188]: info: ------------------------------------------- Feb 25 03:35:32 yamaha volumio[18188]: info: ----- System startup ---- Feb 25 03:35:32 yamaha volumio[18188]: info: ------------------------------------------- Feb 25 03:35:32 yamaha go-librespot[18204]: time="2026-02-25T03:35:32+01:00" level=debug msg="completed keyexchange" Feb 25 03:35:32 yamaha go-librespot[18204]: time="2026-02-25T03:35:32+01:00" level=debug msg="completed challenge" Feb 25 03:35:32 yamaha go-librespot[18204]: time="2026-02-25T03:35:32+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:35:32 yamaha go-librespot[18204]: time="2026-02-25T03:35:32+01:00" level=debug msg="connected to ap-gue1.spotify.com:443" Feb 25 03:35:32 yamaha go-librespot[18204]: time="2026-02-25T03:35:32+01:00" level=debug msg="completed keyexchange" Feb 25 03:35:32 yamaha go-librespot[18204]: time="2026-02-25T03:35:32+01:00" level=debug msg="completed challenge" Feb 25 03:35:33 yamaha volumio-remote-updater[629]: [2026-02-25 03:35:33] [connect] Successful connection Feb 25 03:35:33 yamaha go-librespot[18204]: time="2026-02-25T03:35:33+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:35:33 yamaha volumio[18188]: info: MYVOLUMIO Environment detected Feb 25 03:35:33 yamaha volumio[18188]: info: Plugin folders cleanup Feb 25 03:35:33 yamaha volumio[18188]: info: Scanning into folder /volumio/app/plugins/ Feb 25 03:35:33 yamaha volumio[18188]: info: Scanning category audio_interface Feb 25 03:35:33 yamaha volumio[18188]: info: Scanning category miscellanea Feb 25 03:35:33 yamaha volumio[18188]: info: Scanning category music_service Feb 25 03:35:33 yamaha volumio[18188]: info: Scanning category plugins.json Feb 25 03:35:33 yamaha volumio[18188]: info: Scanning category system_controller Feb 25 03:35:33 yamaha volumio[18188]: info: Scanning category user_interface Feb 25 03:35:33 yamaha volumio[18188]: info: Scanning into folder /data/plugins/ Feb 25 03:35:33 yamaha volumio[18188]: info: Scanning category music_service Feb 25 03:35:33 yamaha volumio[18188]: info: Plugin folders cleanup completed Feb 25 03:35:33 yamaha volumio[18188]: info: ------------------------------------------- Feb 25 03:35:33 yamaha volumio[18188]: info: ----- Core plugins startup ---- Feb 25 03:35:33 yamaha volumio[18188]: info: ------------------------------------------- Feb 25 03:35:33 yamaha volumio[18188]: info: Loading plugins from folder /volumio/app/plugins/ Feb 25 03:35:33 yamaha volumio[18188]: info: Adding plugin upnp to MyMusic Plugins Feb 25 03:35:33 yamaha volumio[18188]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 25 03:35:33 yamaha volumio[18188]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 25 03:35:33 yamaha volumio[18188]: info: Loading plugins from folder /data/plugins/ Feb 25 03:35:33 yamaha volumio[18188]: info: Loading plugin "system"... Feb 25 03:35:33 yamaha go-librespot[18204]: time="2026-02-25T03:35:33+01:00" level=debug msg="connected to ap-gew1.spotify.com:80" Feb 25 03:35:33 yamaha go-librespot[18204]: time="2026-02-25T03:35:33+01:00" level=debug msg="completed keyexchange" Feb 25 03:35:33 yamaha go-librespot[18204]: time="2026-02-25T03:35:33+01:00" level=debug msg="completed challenge" Feb 25 03:35:33 yamaha go-librespot[18204]: time="2026-02-25T03:35:33+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:35:33 yamaha go-librespot[18204]: time="2026-02-25T03:35:33+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:35:33 yamaha volumio[18188]: info: Loading plugin "appearance"... Feb 25 03:35:33 yamaha systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 03:35:33 yamaha systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 03:35:35 yamaha volumio[18188]: info: Loading plugin "network"... Feb 25 03:35:35 yamaha volumio[18188]: info: Refreshing Cached IP Addresses Feb 25 03:35:35 yamaha sudo[18228]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 25 03:35:35 yamaha sudo[18228]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:35:35 yamaha volumio[18188]: info: Loading plugin "services"... Feb 25 03:35:35 yamaha sudo[18230]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 25 03:35:35 yamaha sudo[18230]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:35:35 yamaha volumio[18188]: info: Loading plugin "volumio5onboarding"... Feb 25 03:35:35 yamaha sudo[18228]: pam_unix(sudo:session): session closed for user root Feb 25 03:35:35 yamaha sudo[18237]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 25 03:35:35 yamaha sudo[18237]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:35:35 yamaha volumio[18188]: info: Loading plugin "alsa_controller"... Feb 25 03:35:35 yamaha sudo[18230]: pam_unix(sudo:session): session closed for user root Feb 25 03:35:35 yamaha sudo[18237]: pam_unix(sudo:session): session closed for user root Feb 25 03:35:35 yamaha volumio[18188]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 25 03:35:35 yamaha volumio[18188]: info: Loading plugin "wizard"... Feb 25 03:35:35 yamaha volumio[18188]: info: Loading plugin "networkfs"... Feb 25 03:35:36 yamaha volumio[18188]: info: Cannot mount NAS Ugreen at system boot, trial number 1 ,retrying in 5 seconds Feb 25 03:35:36 yamaha volumio[18188]: info: Starting Udev Watcher for removable devices Feb 25 03:35:36 yamaha volumio[18188]: info: Ignoring mount for partition: boot Feb 25 03:35:36 yamaha volumio[18188]: info: Ignoring mount for partition: volumio Feb 25 03:35:36 yamaha volumio[18188]: info: Ignoring mount for partition: volumio_data Feb 25 03:35:36 yamaha volumio[18188]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 25 03:35:36 yamaha volumio[18188]: info: Loading plugin "volumio_command_line_client"... Feb 25 03:35:36 yamaha volumio[18188]: info: Loading plugin "upnp"... Feb 25 03:35:36 yamaha volumio[18188]: info: [1771986936132] Starting Upmpd Daemon Feb 25 03:35:36 yamaha volumio[18188]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 25 03:35:36 yamaha volumio[18188]: info: Loading plugin "my_music"... Feb 25 03:35:36 yamaha volumio[18188]: info: Loading plugin "mpd"... Feb 25 03:35:36 yamaha systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 19. Feb 25 03:35:36 yamaha systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:35:36 yamaha systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:35:36 yamaha go-librespot[18265]: go-librespot daemon starting... Feb 25 03:35:36 yamaha volumio[18188]: info: Loading plugin "upnp_browser"... Feb 25 03:35:36 yamaha go-librespot[18266]: time="2026-02-25T03:35:36+01:00" level=info msg="running go-librespot 0.6.2" Feb 25 03:35:36 yamaha go-librespot[18266]: time="2026-02-25T03:35:36+01:00" level=debug msg="app state loaded" Feb 25 03:35:36 yamaha go-librespot[18266]: time="2026-02-25T03:35:36+01:00" level=debug msg="stored credentials not found" Feb 25 03:35:36 yamaha go-librespot[18266]: time="2026-02-25T03:35:36+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 25 03:35:37 yamaha go-librespot[18266]: time="2026-02-25T03:35:37+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:35:37 yamaha go-librespot[18266]: time="2026-02-25T03:35:37+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:35:37 yamaha go-librespot[18266]: time="2026-02-25T03:35:37+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:35:37 yamaha go-librespot[18266]: time="2026-02-25T03:35:37+01:00" level=info msg="zeroconf server listening on port 40535" Feb 25 03:35:37 yamaha go-librespot[18266]: time="2026-02-25T03:35:37+01:00" level=debug msg="obtained new client token: AADbLWvxHlqdUj/cBeQJqlv+IQziKhw9V7ud2vFj3JjDWxTLfYnXIpiTGPTfILNLtWetQ4WHK0yzeWzFGkSVFr5zCy9vvhcOz3wEpumRMV/nbYYk+3S1uvjJLnyEqO9bGn0a+kRh28ogwLvPoyxEvm6g4vb0hVqK+6diT/07bOB5o7om9qbOaTtnJGfDhdJ4obZ7l3k1KQ/zLJ8mQR2FKEEz8o0DhYJauRhEviq5Uhj//sB9rENNAo3n" Feb 25 03:35:37 yamaha go-librespot[18266]: time="2026-02-25T03:35:37+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 25 03:35:37 yamaha go-librespot[18266]: time="2026-02-25T03:35:37+01:00" level=debug msg="completed keyexchange" Feb 25 03:35:37 yamaha go-librespot[18266]: time="2026-02-25T03:35:37+01:00" level=debug msg="completed challenge" Feb 25 03:35:37 yamaha go-librespot[18266]: time="2026-02-25T03:35:37+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:35:38 yamaha go-librespot[18266]: time="2026-02-25T03:35:38+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:35:38 yamaha go-librespot[18266]: time="2026-02-25T03:35:38+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:35:38 yamaha go-librespot[18266]: time="2026-02-25T03:35:38+01:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 25 03:35:38 yamaha go-librespot[18266]: time="2026-02-25T03:35:38+01:00" level=debug msg="completed keyexchange" Feb 25 03:35:38 yamaha go-librespot[18266]: time="2026-02-25T03:35:38+01:00" level=debug msg="completed challenge" Feb 25 03:35:38 yamaha go-librespot[18266]: time="2026-02-25T03:35:38+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:35:39 yamaha go-librespot[18266]: time="2026-02-25T03:35:39+01:00" level=debug msg="connected to ap-gue1.spotify.com:443" Feb 25 03:35:39 yamaha go-librespot[18266]: time="2026-02-25T03:35:39+01:00" level=debug msg="completed keyexchange" Feb 25 03:35:39 yamaha go-librespot[18266]: time="2026-02-25T03:35:39+01:00" level=debug msg="completed challenge" Feb 25 03:35:39 yamaha go-librespot[18266]: time="2026-02-25T03:35:39+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:35:39 yamaha volumio[18188]: info: Starting UPNP Browser Feb 25 03:35:39 yamaha volumio[18188]: info: Loading plugin "alarm-clock"... Feb 25 03:35:39 yamaha volumio[18188]: info: Loading plugin "airplay_emulation"... Feb 25 03:35:39 yamaha volumio[18188]: info: Starting Shairport Sync Feb 25 03:35:39 yamaha volumio[18188]: info: Loading plugin "last_100"... Feb 25 03:35:39 yamaha volumio[18188]: info: Loading plugin "webradio"... Feb 25 03:35:39 yamaha go-librespot[18266]: time="2026-02-25T03:35:39+01:00" level=debug msg="connected to ap-gew1.spotify.com:80" Feb 25 03:35:39 yamaha go-librespot[18266]: time="2026-02-25T03:35:39+01:00" level=debug msg="completed keyexchange" Feb 25 03:35:39 yamaha go-librespot[18266]: time="2026-02-25T03:35:39+01:00" level=debug msg="completed challenge" Feb 25 03:35:39 yamaha go-librespot[18266]: time="2026-02-25T03:35:39+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:35:40 yamaha volumio[18188]: info: Loading plugin "i2s_dacs"... Feb 25 03:35:40 yamaha volumio[18188]: info: I2S DAC not set, start Auto-detection Feb 25 03:35:40 yamaha volumio[18188]: info: Loading plugin "volumiodiscovery"... Feb 25 03:35:40 yamaha volumio[18188]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 25 03:35:40 yamaha volumio[18188]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 25 03:35:40 yamaha volumio[18188]: *** WARNING *** For more information see Feb 25 03:35:40 yamaha volumio[18188]: *** 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:35:40 yamaha volumio[18188]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 25 03:35:40 yamaha node[18188]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 25 03:35:40 yamaha volumio[18188]: *** WARNING *** For more information see Feb 25 03:35:40 yamaha node[18188]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 25 03:35:40 yamaha node[18188]: *** WARNING *** For more information see Feb 25 03:35:40 yamaha node[18188]: *** 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:35:40 yamaha node[18188]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 25 03:35:40 yamaha node[18188]: *** WARNING *** For more information see Feb 25 03:35:40 yamaha volumio[18188]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 25 03:35:40 yamaha volumio[18188]: info: Discovery: Started advertising with name: Yamaha Feb 25 03:35:40 yamaha volumio[18188]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 25 03:35:40 yamaha volumio[18188]: info: Loading plugin "calmradio"... Feb 25 03:35:40 yamaha go-librespot[18266]: time="2026-02-25T03:35:40+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:35:40 yamaha go-librespot[18266]: time="2026-02-25T03:35:40+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:35:40 yamaha go-librespot[18266]: time="2026-02-25T03:35:40+01:00" level=debug msg="connected to ap-gew4.spotify.com:80" Feb 25 03:35:40 yamaha go-librespot[18266]: time="2026-02-25T03:35:40+01:00" level=debug msg="completed keyexchange" Feb 25 03:35:40 yamaha go-librespot[18266]: time="2026-02-25T03:35:40+01:00" level=debug msg="completed challenge" Feb 25 03:35:40 yamaha go-librespot[18266]: time="2026-02-25T03:35:40+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:35:41 yamaha go-librespot[18266]: time="2026-02-25T03:35:41+01:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 25 03:35:41 yamaha go-librespot[18266]: time="2026-02-25T03:35:41+01:00" level=debug msg="completed keyexchange" Feb 25 03:35:41 yamaha go-librespot[18266]: time="2026-02-25T03:35:41+01:00" level=debug msg="completed challenge" Feb 25 03:35:41 yamaha go-librespot[18266]: time="2026-02-25T03:35:41+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:35:41 yamaha go-librespot[18266]: time="2026-02-25T03:35:41+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:35:41 yamaha systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 03:35:41 yamaha systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 03:35:42 yamaha volumio5-onboarding[1284]: time=2026-02-25T03:35:42.039+01:00 level=WARN msg="reconnection attempt failed" component=volumio/socket error="read tcp 127.0.0.1:45726->127.0.0.1:3000: i/o timeout" Feb 25 03:35:42 yamaha volumio[18188]: info: Loading plugin "spop"... Feb 25 03:35:44 yamaha volumio[18188]: info: Loading plugin "outputs"... Feb 25 03:35:44 yamaha volumio[18188]: info: Loading plugin "albumart"... Feb 25 03:35:44 yamaha volumio[18188]: info: Plugin example_plugin is not enabled Feb 25 03:35:44 yamaha volumio[18188]: info: Loading plugin "inputs"... Feb 25 03:35:44 yamaha volumio[18188]: info: Loading plugin "updater_comm"... Feb 25 03:35:44 yamaha volumio[18188]: info: Plugin mpdemulation is not enabled Feb 25 03:35:44 yamaha volumio[18188]: info: Loading plugin "rest_api"... Feb 25 03:35:44 yamaha volumio[18188]: info: Loading plugin "websocket"... Feb 25 03:35:44 yamaha volumio[18188]: info: Starting Socket.io Server version 1.7.4 Feb 25 03:35:44 yamaha volumio[18188]: info: Loading plugin "80s80s"... Feb 25 03:35:44 yamaha systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 20. Feb 25 03:35:44 yamaha systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:35:44 yamaha systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:35:44 yamaha go-librespot[18288]: go-librespot daemon starting... Feb 25 03:35:44 yamaha go-librespot[18289]: time="2026-02-25T03:35:44+01:00" level=info msg="running go-librespot 0.6.2" Feb 25 03:35:44 yamaha go-librespot[18289]: time="2026-02-25T03:35:44+01:00" level=debug msg="app state loaded" Feb 25 03:35:44 yamaha go-librespot[18289]: time="2026-02-25T03:35:44+01:00" level=debug msg="stored credentials not found" Feb 25 03:35:44 yamaha volumio[18188]: info: Applying required configuration parameters for plugin 80s80s Feb 25 03:35:44 yamaha volumio[18188]: info: [1771986944917] [80s80s] API delay: 30 Feb 25 03:35:44 yamaha go-librespot[18289]: time="2026-02-25T03:35:44+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 25 03:35:44 yamaha volumio[18188]: info: Loading i18n strings for locale nl Feb 25 03:35:44 yamaha volumio[18188]: Updating browse sources language Feb 25 03:35:44 yamaha volumio[18188]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 25 03:35:45 yamaha volumio[18278]: Forking 3 albumart workers Feb 25 03:35:45 yamaha volumio[18188]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 03:35:45 yamaha volumio[18188]: info: CoreCommandRouter::initPlayerControls Feb 25 03:35:45 yamaha volumio[18188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:35:45 yamaha volumio[18188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:35:45 yamaha volumio[18188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:35:45 yamaha volumio[18188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:35:45 yamaha volumio[18188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:35:45 yamaha volumio[18188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:35:45 yamaha volumio[18188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:35:45 yamaha volumio[18188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:35:45 yamaha volumio[18188]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 03:35:45 yamaha volumio[18188]: Express server listening on port 3000 Feb 25 03:35:45 yamaha volumio[18188]: [Metrics] WebUI: 14s 370.71ms Feb 25 03:35:45 yamaha volumio[18188]: info: CoreStateMachine::resetVolumioState Feb 25 03:35:45 yamaha volumio[18188]: info: CoreStateMachine::getcurrentVolume Feb 25 03:35:45 yamaha volumio[18188]: info: CoreCommandRouter::volumioRetrievevolume Feb 25 03:35:45 yamaha go-librespot[18289]: time="2026-02-25T03:35:45+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:35:45 yamaha go-librespot[18289]: time="2026-02-25T03:35:45+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:35:45 yamaha go-librespot[18289]: time="2026-02-25T03:35:45+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:35:45 yamaha volumio-remote-updater[629]: [2026-02-25 03:35:45] [connect] Successful connection Feb 25 03:35:45 yamaha go-librespot[18289]: time="2026-02-25T03:35:45+01:00" level=info msg="zeroconf server listening on port 44179" Feb 25 03:35:45 yamaha volumio[18188]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:35:45 yamaha volumio[18188]: info: Cannot mount NAS Ugreen at system boot, trial number 2 ,retrying in 5 seconds Feb 25 03:35:45 yamaha go-librespot[18289]: time="2026-02-25T03:35:45+01:00" level=debug msg="obtained new client token: AADJRAV5/PHPHjmEHraZZHTVGuIAh99ALPr43/j7cZpNiHCOGg+vRkD1V9eJnWmvbTJWLmQTZ/T0LRFYpaRyl/1wRHnFtnrusVHxomujQdwLjxQhz4uV85WQZ8SFXesvuhQNdnTFIvkvuP1d64ewFhSd64YLT/+NV3KtQwV4Go4vcrHJR+RnPFidmFivIBMm0EOfwe5RV1XqFpMtcwAdxi8sUwGVig3PXuLKwDO/wR+XIRG5QQrp1RP4" Feb 25 03:35:45 yamaha volumio[18188]: info: Volumio Network Manager: Network status updated: 1 Feb 25 03:35:45 yamaha go-librespot[18289]: time="2026-02-25T03:35:45+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 25 03:35:45 yamaha go-librespot[18289]: time="2026-02-25T03:35:45+01:00" level=debug msg="completed keyexchange" Feb 25 03:35:45 yamaha go-librespot[18289]: time="2026-02-25T03:35:45+01:00" level=debug msg="completed challenge" Feb 25 03:35:45 yamaha go-librespot[18289]: time="2026-02-25T03:35:45+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:35:45 yamaha volumio[18188]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Feb 25 03:35:45 yamaha volumio[18188]: wlan0 Interface doesn't support scanning. Feb 25 03:35:45 yamaha volumio[18188]: info: Cannot use regular scanning, forcing with ap-force Feb 25 03:35:45 yamaha sudo[18337]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Feb 25 03:35:45 yamaha sudo[18337]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:35:45 yamaha sudo[18337]: pam_unix(sudo:session): session closed for user root Feb 25 03:35:45 yamaha volumio[18188]: command failed: No such device (-19) Feb 25 03:35:45 yamaha volumio[18188]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Feb 25 03:35:45 yamaha volumio[18188]: command failed: No such device (-19) Feb 25 03:35:46 yamaha volumio[18188]: info: VolumeController:: Volume=86 Mute =false Feb 25 03:35:46 yamaha volumio[18188]: info: CoreStateMachine::pushState Feb 25 03:35:46 yamaha volumio[18188]: info: CorePlayQueue::getTrack 0 Feb 25 03:35:46 yamaha volumio[18188]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 03:35:46 yamaha volumio[18188]: info: CoreCommandRouter::volumioPushState Feb 25 03:35:46 yamaha volumio[18188]: info: CoreStateMachine::updateTrackBlock Feb 25 03:35:46 yamaha volumio[18188]: info: CorePlayQueue::getTrackBlock Feb 25 03:35:46 yamaha volumio[18188]: info: CoreCommandRouter::volumioRetrievevolume Feb 25 03:35:46 yamaha go-librespot[18289]: time="2026-02-25T03:35:46+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:35:46 yamaha go-librespot[18289]: time="2026-02-25T03:35:46+01:00" level=debug msg="connected to ap-gew4.spotify.com:80" Feb 25 03:35:46 yamaha sudo[18357]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 25 03:35:46 yamaha sudo[18357]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:35:46 yamaha go-librespot[18289]: time="2026-02-25T03:35:46+01:00" level=debug msg="completed keyexchange" Feb 25 03:35:46 yamaha go-librespot[18289]: time="2026-02-25T03:35:46+01:00" level=debug msg="completed challenge" Feb 25 03:35:46 yamaha go-librespot[18289]: time="2026-02-25T03:35:46+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:35:46 yamaha sudo[18357]: pam_unix(sudo:session): session closed for user root Feb 25 03:35:46 yamaha sudo[18359]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 25 03:35:46 yamaha sudo[18359]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:35:46 yamaha sudo[18359]: pam_unix(sudo:session): session closed for user root Feb 25 03:35:46 yamaha volumio[18188]: info: Reloading queue from file Feb 25 03:35:46 yamaha volumio[18188]: 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:35:46 yamaha volumio[18188]: info: CoreStateMachine::setRepeat null single undefined Feb 25 03:35:46 yamaha volumio[18188]: info: CoreStateMachine::pushState Feb 25 03:35:46 yamaha volumio[18188]: info: CorePlayQueue::getTrack 0 Feb 25 03:35:46 yamaha volumio[18188]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 03:35:46 yamaha volumio[18188]: info: CoreCommandRouter::volumioPushState Feb 25 03:35:46 yamaha volumio[18188]: info: CoreStateMachine::setRandom null Feb 25 03:35:46 yamaha volumio[18188]: info: CoreStateMachine::pushState Feb 25 03:35:46 yamaha volumio[18188]: info: CorePlayQueue::getTrack 0 Feb 25 03:35:46 yamaha volumio[18188]: info: CoreCommandRouter::volumioPushState Feb 25 03:35:46 yamaha volumio[18188]: info: Setting Device type: Raspberry PI Feb 25 03:35:46 yamaha volumio-remote-updater[629]: [2026-02-25 03:35:46] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1771986945 101 Feb 25 03:35:46 yamaha volumio[18188]: 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:35:46 yamaha volumio[18188]: info: VolumeController:: Volume=86 Mute =false Feb 25 03:35:46 yamaha volumio[18188]: info: CoreStateMachine::pushState Feb 25 03:35:46 yamaha volumio[18188]: info: CorePlayQueue::getTrack 0 Feb 25 03:35:46 yamaha volumio[18188]: info: CoreCommandRouter::volumioPushState Feb 25 03:35:46 yamaha volumio[18188]: 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:35:47 yamaha go-librespot[18289]: time="2026-02-25T03:35:47+01:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 25 03:35:47 yamaha volumio[18188]: 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:35:47 yamaha volumio[18188]: info: Discovery: adding 67ba7d11-5499-4b1d-a75e-1a431a777f52 Feb 25 03:35:47 yamaha volumio[18188]: info: Discovery: Found device Yamaha Feb 25 03:35:47 yamaha volumio[18188]: info: CoreCommandRouter::volumioGetState Feb 25 03:35:47 yamaha volumio[18188]: info: CorePlayQueue::getTrack 0 Feb 25 03:35:47 yamaha volumio[18188]: info: Discovery: this is already registered, 67ba7d11-5499-4b1d-a75e-1a431a777f52 Feb 25 03:35:47 yamaha volumio[18188]: info: Discovery: Found device Yamaha Feb 25 03:35:47 yamaha volumio[18188]: info: CoreCommandRouter::volumioGetState Feb 25 03:35:47 yamaha volumio[18188]: info: CorePlayQueue::getTrack 0 Feb 25 03:35:47 yamaha volumio[18188]: 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:35:47 yamaha go-librespot[18289]: time="2026-02-25T03:35:47+01:00" level=debug msg="completed keyexchange" Feb 25 03:35:47 yamaha go-librespot[18289]: time="2026-02-25T03:35:47+01:00" level=debug msg="completed challenge" Feb 25 03:35:47 yamaha volumio[18188]: info: Completed loading Core Plugins Feb 25 03:35:47 yamaha volumio[18188]: info: Preparing to generate the ALSA configuration file Feb 25 03:35:47 yamaha sudo[18374]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 25 03:35:47 yamaha sudo[18374]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:35:47 yamaha volumio[18188]: 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:35:47 yamaha go-librespot[18289]: time="2026-02-25T03:35:47+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:35:47 yamaha sudo[18374]: pam_unix(sudo:session): session closed for user root Feb 25 03:35:47 yamaha volumio[18188]: 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:35:47 yamaha volumio[18188]: info: Upmpdcli Daemon Started Feb 25 03:35:47 yamaha volumio[18188]: info: Asound.conf file unchanged, so no further update is needed Feb 25 03:35:47 yamaha volumio[18188]: info: Output device has changed, restarting MPD Feb 25 03:35:47 yamaha volumio[18188]: info: Output device has changed, restarting Shairport Sync Feb 25 03:35:47 yamaha volumio[18188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:35:47 yamaha volumio[18188]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:35:47 yamaha volumio[18188]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 25 03:35:47 yamaha volumio[18188]: info: ___________ START PLUGINS ___________ Feb 25 03:35:47 yamaha sudo[18377]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 25 03:35:47 yamaha sudo[18379]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 25 03:35:47 yamaha sudo[18377]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:35:47 yamaha sudo[18379]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:35:47 yamaha sudo[18377]: pam_unix(sudo:session): session closed for user root Feb 25 03:35:47 yamaha volumio[18188]: info: ControllerMpd::onStart: Initializing MPD Feb 25 03:35:47 yamaha volumio[18188]: info: Creating MPD Configuration file Feb 25 03:35:47 yamaha sudo[18386]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Feb 25 03:35:47 yamaha volumio[18188]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 03:35:47 yamaha sudo[18386]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:35:47 yamaha volumio[18188]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 25 03:35:47 yamaha volumio[18188]: info: [1771986947741] CoreMusicLibrary::Adding element Media Servers Feb 25 03:35:47 yamaha volumio[18188]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 25 03:35:47 yamaha systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 25 03:35:47 yamaha sudo[18389]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 25 03:35:47 yamaha sudo[18389]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:35:47 yamaha volumio[18188]: info: UPNP Browser: Client initialized successfully Feb 25 03:35:47 yamaha sudo[18386]: pam_unix(sudo:session): session closed for user root Feb 25 03:35:47 yamaha sudo[18389]: pam_unix(sudo:session): session closed for user root Feb 25 03:35:47 yamaha systemd[1]: mpd.service: Deactivated successfully. Feb 25 03:35:47 yamaha systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 25 03:35:47 yamaha systemd[1]: mpd.service: Consumed 7.441s CPU time. Feb 25 03:35:47 yamaha sudo[18391]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 25 03:35:47 yamaha systemd[1]: mpd.socket: Deactivated successfully. Feb 25 03:35:47 yamaha systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 25 03:35:47 yamaha systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 25 03:35:47 yamaha sudo[18391]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:35:47 yamaha volumio[18188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:35:47 yamaha volumio[18188]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:35:47 yamaha systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 25 03:35:48 yamaha go-librespot[18289]: time="2026-02-25T03:35:48+01:00" level=debug msg="connected to ap-gue1.spotify.com:443" Feb 25 03:35:48 yamaha systemd[1]: Starting mpd.service - Music Player Daemon... Feb 25 03:35:48 yamaha volumio[18188]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 25 03:35:48 yamaha volumio[18188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:35:48 yamaha volumio[18188]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:35:48 yamaha systemd[1]: mpd.service: Deactivated successfully. Feb 25 03:35:48 yamaha systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 25 03:35:48 yamaha systemd[1]: mpd.socket: Deactivated successfully. Feb 25 03:35:48 yamaha systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 25 03:35:48 yamaha systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 25 03:35:48 yamaha go-librespot[18289]: time="2026-02-25T03:35:48+01:00" level=debug msg="completed keyexchange" Feb 25 03:35:48 yamaha go-librespot[18289]: time="2026-02-25T03:35:48+01:00" level=debug msg="completed challenge" Feb 25 03:35:48 yamaha volumio[18188]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 25 03:35:48 yamaha volumio[18188]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 25 03:35:48 yamaha volumio[18188]: info: [1771986948157] CoreMusicLibrary::Adding element Last_100 Feb 25 03:35:48 yamaha volumio[18188]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 25 03:35:48 yamaha volumio[18188]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 25 03:35:48 yamaha volumio[18188]: info: [1771986948166] CoreMusicLibrary::Adding element Webradio Feb 25 03:35:48 yamaha volumio[18188]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 25 03:35:48 yamaha volumio[18188]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 25 03:35:48 yamaha volumio[18188]: info: Initializing BBC Radios Feb 25 03:35:48 yamaha systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 25 03:35:48 yamaha systemd[1]: Starting mpd.service - Music Player Daemon... Feb 25 03:35:48 yamaha go-librespot[18289]: time="2026-02-25T03:35:48+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:35:48 yamaha volumio[18188]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 25 03:35:48 yamaha volumio[18188]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:35:48 yamaha volumio[18188]: info: Adding Calm Radio to Browse Sources Feb 25 03:35:48 yamaha volumio[18188]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 25 03:35:48 yamaha volumio[18188]: info: [1771986948433] CoreMusicLibrary::Adding element Calm Radio Feb 25 03:35:48 yamaha volumio[18188]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 25 03:35:48 yamaha volumio[18188]: Cannot find translation for source Calm Radio Feb 25 03:35:48 yamaha volumio[18188]: info: Creating Spotify config file Feb 25 03:35:48 yamaha volumio[18188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:35:48 yamaha sudo[18409]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 25 03:35:48 yamaha sudo[18409]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 25 03:35:48 yamaha sudo[18409]: pam_unix(sudo:session): session closed for user root Feb 25 03:35:49 yamaha volumio[18299]: Starting albumart workers Feb 25 03:35:49 yamaha go-librespot[18289]: time="2026-02-25T03:35:49+01:00" level=debug msg="connected to ap-gae2.spotify.com:80" Feb 25 03:35:49 yamaha volumio[18297]: Starting albumart workers Feb 25 03:35:49 yamaha volumio[18298]: Starting albumart workers Feb 25 03:35:49 yamaha go-librespot[18289]: time="2026-02-25T03:35:49+01:00" level=debug msg="completed keyexchange" Feb 25 03:35:49 yamaha go-librespot[18289]: time="2026-02-25T03:35:49+01:00" level=debug msg="completed challenge" Feb 25 03:35:49 yamaha volumio[18188]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 25 03:35:49 yamaha volumio[18188]: info: [1771986949447] CoreMusicLibrary::Adding element 80s80s Radio Feb 25 03:35:49 yamaha volumio[18188]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 25 03:35:49 yamaha volumio[18188]: Cannot find translation for source Calm Radio Feb 25 03:35:49 yamaha volumio[18188]: Cannot find translation for source 80s80s Radio Feb 25 03:35:49 yamaha volumio[18188]: info: Volumio Calling Home Feb 25 03:35:49 yamaha go-librespot[18289]: time="2026-02-25T03:35:49+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:35:50 yamaha go-librespot[18289]: time="2026-02-25T03:35:50+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:35:50 yamaha go-librespot[18289]: time="2026-02-25T03:35:50+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Feb 25 03:35:50 yamaha go-librespot[18289]: time="2026-02-25T03:35:50+01:00" level=debug msg="completed keyexchange" Feb 25 03:35:50 yamaha go-librespot[18289]: time="2026-02-25T03:35:50+01:00" level=debug msg="completed challenge" Feb 25 03:35:50 yamaha go-librespot[18289]: time="2026-02-25T03:35:50+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:35:50 yamaha go-librespot[18289]: time="2026-02-25T03:35:50+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:35:50 yamaha systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 03:35:50 yamaha systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 03:35:50 yamaha systemd[1]: go-librespot-daemon.service: Consumed 1.108s CPU time. Feb 25 03:35:50 yamaha volumio[18188]: 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:35:50 yamaha volumio[18188]: info: MPD Permissions set Feb 25 03:35:50 yamaha volumio[18188]: info: MPD Permissions set Feb 25 03:35:50 yamaha volumio[18188]: info: Cannot mount NAS Ugreen at system boot, trial number 3 ,retrying in 5 seconds Feb 25 03:35:50 yamaha volumio[18188]: 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:35:50 yamaha volumio[18188]: info: Volumio called home Feb 25 03:35:50 yamaha volumio[18188]: info: Spotify config file written Feb 25 03:35:51 yamaha sudo[18427]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 25 03:35:51 yamaha sudo[18427]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:35:51 yamaha systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:35:51 yamaha systemd[1]: go-librespot-daemon.service: Consumed 1.108s CPU time. Feb 25 03:35:51 yamaha systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:35:51 yamaha go-librespot[18441]: go-librespot daemon starting... Feb 25 03:35:51 yamaha sudo[18427]: pam_unix(sudo:session): session closed for user root Feb 25 03:35:51 yamaha go-librespot[18442]: time="2026-02-25T03:35:51+01:00" level=info msg="running go-librespot 0.6.2" Feb 25 03:35:51 yamaha go-librespot[18442]: time="2026-02-25T03:35:51+01:00" level=debug msg="app state loaded" Feb 25 03:35:51 yamaha go-librespot[18442]: time="2026-02-25T03:35:51+01:00" level=debug msg="stored credentials not found" Feb 25 03:35:51 yamaha go-librespot[18442]: time="2026-02-25T03:35:51+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 25 03:35:51 yamaha volumio[18188]: 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:35:51 yamaha volumio[18188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:35:51 yamaha volumio[18188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:35:51 yamaha volumio[18188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:35:51 yamaha volumio[18188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:35:51 yamaha volumio[18188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:35:51 yamaha volumio[18188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:35:51 yamaha volumio[18188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:35:51 yamaha volumio[18188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:35:51 yamaha volumio[18188]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:35:51 yamaha volumio[18188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:35:51 yamaha volumio[18188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:35:51 yamaha volumio[18188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:35:51 yamaha volumio[18188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:35:51 yamaha volumio[18188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:35:51 yamaha volumio[18188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:35:51 yamaha volumio[18188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:35:51 yamaha volumio[18188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:35:51 yamaha volumio[18188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:35:51 yamaha volumio[18188]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:35:51 yamaha volumio[18188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:35:51 yamaha volumio[18188]: info: No need to fix Spotify hosts Feb 25 03:35:51 yamaha go-librespot[18442]: time="2026-02-25T03:35:51+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:35:51 yamaha go-librespot[18442]: time="2026-02-25T03:35:51+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:35:51 yamaha go-librespot[18442]: time="2026-02-25T03:35:51+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:35:52 yamaha go-librespot[18442]: time="2026-02-25T03:35:52+01:00" level=info msg="zeroconf server listening on port 39821" Feb 25 03:35:52 yamaha go-librespot[18442]: time="2026-02-25T03:35:52+01:00" level=debug msg="obtained new client token: AABb464BgcEMu9jP+vjg4obvDfAbDyylNYZdQAbDAP537BQUhwh3eTHuOvZEjt7r3ruEOhxfcCGfs+tu/sLrGZZ8RXFLzq/UNorii28MWxkppHdNNBIawUDF60qR3MWUMovWqJk6FcQXcsLLtQkud7v2/AoNs/bgPdm3xfq3tw4k0fEkh77+nw+SWXwF3CfbkEa38II7BdhPv2qUk6S5tU6xM8CCmoiwBKzM44z3jJlcvvzuYTcACg==" Feb 25 03:35:52 yamaha go-librespot[18442]: time="2026-02-25T03:35:52+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 25 03:35:52 yamaha go-librespot[18442]: time="2026-02-25T03:35:52+01:00" level=debug msg="completed keyexchange" Feb 25 03:35:52 yamaha go-librespot[18442]: time="2026-02-25T03:35:52+01:00" level=debug msg="completed challenge" Feb 25 03:35:52 yamaha go-librespot[18442]: time="2026-02-25T03:35:52+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:35:52 yamaha volumio[18188]: 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:35:52 yamaha volumio[18188]: info: New Spotify access tokenBQAnroNzqU... Feb 25 03:35:52 yamaha volumio[18188]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 25 03:35:52 yamaha volumio[18188]: 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:35:52 yamaha volumio[18188]: info: Starting Shairport Sync Feb 25 03:35:52 yamaha volumio[18188]: info: Starting Shairport Sync Feb 25 03:35:52 yamaha volumio[18188]: info: Starting Shairport Sync Feb 25 03:35:52 yamaha sudo[18470]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 25 03:35:52 yamaha sudo[18470]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:35:52 yamaha sudo[18472]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 25 03:35:52 yamaha sudo[18472]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:35:52 yamaha go-librespot[18442]: time="2026-02-25T03:35:52+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Feb 25 03:35:52 yamaha go-librespot[18442]: time="2026-02-25T03:35:52+01:00" level=debug msg="completed keyexchange" Feb 25 03:35:52 yamaha go-librespot[18442]: time="2026-02-25T03:35:52+01:00" level=debug msg="completed challenge" Feb 25 03:35:52 yamaha sudo[18474]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 25 03:35:52 yamaha sudo[18474]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:35:52 yamaha go-librespot[18442]: time="2026-02-25T03:35:52+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:35:52 yamaha volumio[18188]: 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:35:52 yamaha systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 25 03:35:52 yamaha systemd[1]: shairport-sync.service: Deactivated successfully. Feb 25 03:35:52 yamaha systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 25 03:35:52 yamaha systemd[1]: shairport-sync.service: Consumed 2.478s CPU time. Feb 25 03:35:53 yamaha systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 25 03:35:53 yamaha volumio[18188]: 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:35:53 yamaha sudo[18470]: pam_unix(sudo:session): session closed for user root Feb 25 03:35:53 yamaha sudo[18474]: pam_unix(sudo:session): session closed for user root Feb 25 03:35:53 yamaha sudo[18472]: pam_unix(sudo:session): session closed for user root Feb 25 03:35:53 yamaha volumio[18188]: info: CoreCommandRouter::volumioGetState Feb 25 03:35:53 yamaha volumio[18188]: info: CorePlayQueue::getTrack 0 Feb 25 03:35:53 yamaha volumio[18188]: info: Shairport-Sync Started Feb 25 03:35:53 yamaha volumio[18188]: Error adding Membership: Error: addMembership EINVAL Feb 25 03:35:53 yamaha volumio[18188]: 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:35:53 yamaha volumio[18188]: info: Shairport-Sync Started Feb 25 03:35:53 yamaha volumio[18188]: info: Shairport-Sync Started Feb 25 03:35:53 yamaha volumio[18188]: 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:35:53 yamaha volumio[18188]: 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:35:53 yamaha volumio[18188]: info: Spotify Successfully logged in Feb 25 03:35:53 yamaha volumio[18188]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 25 03:35:53 yamaha volumio[18188]: info: [1771986953303] CoreMusicLibrary::Adding element Spotify Feb 25 03:35:53 yamaha volumio[18188]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 25 03:35:53 yamaha volumio[18188]: Cannot find translation for source Calm Radio Feb 25 03:35:53 yamaha volumio[18188]: Cannot find translation for source 80s80s Radio Feb 25 03:35:53 yamaha volumio[18188]: Cannot find translation for source Spotify Feb 25 03:35:53 yamaha go-librespot[18442]: time="2026-02-25T03:35:53+01:00" level=debug msg="connected to ap-gew4.spotify.com:80" Feb 25 03:35:53 yamaha go-librespot[18442]: time="2026-02-25T03:35:53+01:00" level=debug msg="completed keyexchange" Feb 25 03:35:53 yamaha go-librespot[18442]: time="2026-02-25T03:35:53+01:00" level=debug msg="completed challenge" Feb 25 03:35:53 yamaha go-librespot[18442]: time="2026-02-25T03:35:53+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:35:54 yamaha go-librespot[18442]: time="2026-02-25T03:35:54+01:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Feb 25 03:35:54 yamaha go-librespot[18442]: time="2026-02-25T03:35:54+01:00" level=debug msg="completed keyexchange" Feb 25 03:35:54 yamaha go-librespot[18442]: time="2026-02-25T03:35:54+01:00" level=debug msg="completed challenge" Feb 25 03:35:54 yamaha go-librespot[18442]: time="2026-02-25T03:35:54+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:35:54 yamaha go-librespot[18442]: time="2026-02-25T03:35:54+01:00" level=debug msg="connected to ap-gew1.spotify.com:443" Feb 25 03:35:54 yamaha go-librespot[18442]: time="2026-02-25T03:35:54+01:00" level=debug msg="completed keyexchange" Feb 25 03:35:54 yamaha go-librespot[18442]: time="2026-02-25T03:35:54+01:00" level=debug msg="completed challenge" Feb 25 03:35:55 yamaha go-librespot[18442]: time="2026-02-25T03:35:55+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:35:55 yamaha mpd[18421]: 2026-02-25T03:35:55 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 25 03:35:55 yamaha volumio[18188]: info: go-librespot daemon successfully initialized Feb 25 03:35:55 yamaha systemd[1]: Started mpd.service - Music Player Daemon. Feb 25 03:35:55 yamaha sudo[18379]: pam_unix(sudo:session): session closed for user root Feb 25 03:35:55 yamaha sudo[18391]: pam_unix(sudo:session): session closed for user root Feb 25 03:35:55 yamaha volumio[18188]: info: Completed starting Core Plugins Feb 25 03:35:55 yamaha volumio[18188]: info: ------------------------------------------- Feb 25 03:35:55 yamaha volumio[18188]: info: ----- MyVolumio plugins startup ---- Feb 25 03:35:55 yamaha volumio[18188]: info: ------------------------------------------- Feb 25 03:35:55 yamaha volumio[18188]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 25 03:35:55 yamaha go-librespot[18442]: time="2026-02-25T03:35:55+01:00" level=debug msg="connected to ap-gae2.spotify.com:80" Feb 25 03:35:55 yamaha systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Feb 25 03:35:55 yamaha volumio[18188]: error: MPD error: The expression evaluated to a falsy value: Feb 25 03:35:55 yamaha volumio[18188]: assert.ok(self.idling) Feb 25 03:35:55 yamaha volumio[18188]: error: The expression evaluated to a falsy value: Feb 25 03:35:55 yamaha volumio[18188]: assert.ok(self.idling) Feb 25 03:35:55 yamaha volumio[18188]: info: Cannot mount NAS Ugreen at system boot, trial number 4 ,retrying in 5 seconds Feb 25 03:35:55 yamaha volumio[18188]: info: MPD running with PID18421 Feb 25 03:35:55 yamaha volumio[18188]: ,establishing connection Feb 25 03:35:55 yamaha volumio[18188]: error: updateQueue error: null Feb 25 03:35:55 yamaha volumio[18188]: error: updateQueue error: null Feb 25 03:35:55 yamaha go-librespot[18442]: time="2026-02-25T03:35:55+01:00" level=debug msg="completed keyexchange" Feb 25 03:35:55 yamaha go-librespot[18442]: time="2026-02-25T03:35:55+01:00" level=debug msg="completed challenge" Feb 25 03:35:56 yamaha go-librespot[18442]: time="2026-02-25T03:35:56+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:35:56 yamaha go-librespot[18442]: time="2026-02-25T03:35:56+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:35:56 yamaha systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 03:35:56 yamaha systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 03:35:56 yamaha systemd[1]: go-librespot-daemon.service: Consumed 1.105s CPU time. Feb 25 03:35:57 yamaha systemd[1]: setdatetime-helper.service: Deactivated successfully. Feb 25 03:35:57 yamaha systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Feb 25 03:35:57 yamaha systemd[1]: setdatetime-helper.service: Consumed 1.714s CPU time. Feb 25 03:35:58 yamaha volumio[18188]: info: Initializing connection to go-librespot Websocket Feb 25 03:35:58 yamaha volumio[18188]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 25 03:35:59 yamaha systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 21. Feb 25 03:35:59 yamaha systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:35:59 yamaha systemd[1]: go-librespot-daemon.service: Consumed 1.105s CPU time. Feb 25 03:35:59 yamaha systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:35:59 yamaha go-librespot[18539]: go-librespot daemon starting... Feb 25 03:35:59 yamaha go-librespot[18540]: time="2026-02-25T03:35:59+01:00" level=info msg="running go-librespot 0.6.2" Feb 25 03:35:59 yamaha go-librespot[18540]: time="2026-02-25T03:35:59+01:00" level=debug msg="app state loaded" Feb 25 03:35:59 yamaha go-librespot[18540]: time="2026-02-25T03:35:59+01:00" level=debug msg="stored credentials not found" Feb 25 03:35:59 yamaha go-librespot[18540]: time="2026-02-25T03:35:59+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 25 03:35:59 yamaha go-librespot[18540]: time="2026-02-25T03:35:59+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 25 03:35:59 yamaha go-librespot[18540]: time="2026-02-25T03:35:59+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:35:59 yamaha go-librespot[18540]: time="2026-02-25T03:35:59+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:35:59 yamaha go-librespot[18540]: time="2026-02-25T03:35:59+01:00" level=info msg="zeroconf server listening on port 37153" Feb 25 03:35:59 yamaha go-librespot[18540]: time="2026-02-25T03:35:59+01:00" level=debug msg="obtained new client token: AAD38YFuCNIfVomu82XJhumB9kZkikwmKFlliWD29+Gb6G5iACRqYZZc5MzErLIO/JAVMx9SpnO0570/bnwEPxbmIdf+2Oru7XmYGOIKWPh9hDjjEatcUFDpVKzmHMM5Bu+irHpLoeFwjW8EcO/GxXB5vrM9uKl4CcSGFHMhjKdFySnSjvHM4GHiMKKx3TvNZ0+OA4pcP0m8cOk69Pp1KTCWClfEgBVqZKCAthMqWD4Sdhdfii97kBou" Feb 25 03:35:59 yamaha go-librespot[18540]: time="2026-02-25T03:35:59+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:35:59 yamaha go-librespot[18540]: time="2026-02-25T03:35:59+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:35:59 yamaha go-librespot[18540]: time="2026-02-25T03:35:59+01:00" level=debug msg="connected to ap-gew4.spotify.com:80" Feb 25 03:35:59 yamaha go-librespot[18540]: time="2026-02-25T03:35:59+01:00" level=debug msg="completed keyexchange" Feb 25 03:35:59 yamaha go-librespot[18540]: time="2026-02-25T03:35:59+01:00" level=debug msg="completed challenge" Feb 25 03:36:00 yamaha go-librespot[18540]: time="2026-02-25T03:36:00+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:36:00 yamaha go-librespot[18540]: time="2026-02-25T03:36:00+01:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 25 03:36:00 yamaha go-librespot[18540]: time="2026-02-25T03:36:00+01:00" level=debug msg="completed keyexchange" Feb 25 03:36:00 yamaha go-librespot[18540]: time="2026-02-25T03:36:00+01:00" level=debug msg="completed challenge" Feb 25 03:36:00 yamaha volumio[18188]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Feb 25 03:36:00 yamaha go-librespot[18540]: time="2026-02-25T03:36:00+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:36:01 yamaha volumio[18188]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 25 03:36:01 yamaha go-librespot[18540]: time="2026-02-25T03:36:01+01:00" level=debug msg="connected to ap-gew1.spotify.com:443" Feb 25 03:36:01 yamaha go-librespot[18540]: time="2026-02-25T03:36:01+01:00" level=debug msg="completed keyexchange" Feb 25 03:36:01 yamaha go-librespot[18540]: time="2026-02-25T03:36:01+01:00" level=debug msg="completed challenge" Feb 25 03:36:01 yamaha go-librespot[18540]: time="2026-02-25T03:36:01+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 25 03:36:01 yamaha volumio[18188]: info: Initializing connection to go-librespot Websocket Feb 25 03:36:02 yamaha go-librespot[18540]: time="2026-02-25T03:36:02+01:00" level=debug msg="connected to ap-gae2.spotify.com:80" Feb 25 03:36:02 yamaha go-librespot[18540]: time="2026-02-25T03:36:02+01:00" level=debug msg="new websocket client" Feb 25 03:36:02 yamaha volumio[18188]: info: Connection to go-librespot Websocket established Feb 25 03:36:02 yamaha go-librespot[18540]: time="2026-02-25T03:36:02+01:00" level=debug msg="completed keyexchange" Feb 25 03:36:02 yamaha go-librespot[18540]: time="2026-02-25T03:36:02+01:00" level=debug msg="completed challenge" Feb 25 03:36:02 yamaha go-librespot[18540]: time="2026-02-25T03:36:02+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:36:03 yamaha go-librespot[18540]: time="2026-02-25T03:36:03+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:36:03 yamaha go-librespot[18540]: time="2026-02-25T03:36:03+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Feb 25 03:36:03 yamaha go-librespot[18540]: time="2026-02-25T03:36:03+01:00" level=debug msg="completed keyexchange" Feb 25 03:36:03 yamaha go-librespot[18540]: time="2026-02-25T03:36:03+01:00" level=debug msg="completed challenge" Feb 25 03:36:03 yamaha go-librespot[18540]: time="2026-02-25T03:36:03+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:36:03 yamaha go-librespot[18540]: time="2026-02-25T03:36:03+01:00" level=debug msg="connected to ap-gew4.spotify.com:80" Feb 25 03:36:03 yamaha go-librespot[18540]: time="2026-02-25T03:36:03+01:00" level=debug msg="completed keyexchange" Feb 25 03:36:03 yamaha go-librespot[18540]: time="2026-02-25T03:36:03+01:00" level=debug msg="completed challenge" Feb 25 03:36:03 yamaha go-librespot[18540]: time="2026-02-25T03:36:03+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:36:03 yamaha go-librespot[18540]: time="2026-02-25T03:36:03+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:36:03 yamaha systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 03:36:03 yamaha systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 03:36:03 yamaha volumio[18188]: info: Connection to go-librespot Websocket closed Feb 25 03:36:04 yamaha volumio[18188]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 25 03:36:04 yamaha volumio[18188]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 25 03:36:04 yamaha volumio[18188]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 25 03:36:04 yamaha volumio[18188]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 25 03:36:04 yamaha volumio[18188]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 25 03:36:04 yamaha volumio[18188]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 25 03:36:04 yamaha volumio[18188]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 25 03:36:04 yamaha volumio[18188]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 25 03:36:04 yamaha volumio[18188]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 25 03:36:04 yamaha volumio[18188]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 25 03:36:04 yamaha volumio[18188]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 25 03:36:04 yamaha volumio[18188]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 25 03:36:04 yamaha volumio[18188]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 25 03:36:04 yamaha volumio[18188]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 25 03:36:04 yamaha volumio[18188]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 25 03:36:04 yamaha volumio[18188]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 25 03:36:04 yamaha volumio[18188]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 25 03:36:04 yamaha volumio[18188]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 25 03:36:04 yamaha volumio[18188]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 25 03:36:04 yamaha volumio[18188]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 25 03:36:04 yamaha volumio[18188]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 25 03:36:04 yamaha volumio[18188]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 25 03:36:04 yamaha volumio[18188]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 25 03:36:04 yamaha volumio[18188]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 25 03:36:04 yamaha volumio[18188]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 25 03:36:04 yamaha volumio[18188]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 25 03:36:04 yamaha volumio[18188]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 25 03:36:04 yamaha volumio[18188]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 25 03:36:04 yamaha volumio[18188]: info: Adding plugin bluetooth to MyMusic Plugins Feb 25 03:36:04 yamaha volumio[18188]: info: Adding plugin multiroom to MyMusic Plugins Feb 25 03:36:04 yamaha volumio[18188]: info: Adding plugin metavolumio to MyMusic Plugins Feb 25 03:36:04 yamaha volumio[18188]: info: Adding plugin cd_controller to MyMusic Plugins Feb 25 03:36:04 yamaha volumio[18188]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 25 03:36:04 yamaha volumio[18188]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 25 03:36:04 yamaha volumio[18188]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 25 03:36:04 yamaha volumio[18188]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 25 03:36:05 yamaha volumio[18188]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 25 03:36:05 yamaha volumio[18188]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 25 03:36:05 yamaha volumio[18188]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:36:05 yamaha volumio[18188]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:36:05 yamaha volumio[18188]: info: Starting MyVolumio Remote Streaming Endpoints Feb 25 03:36:05 yamaha volumio[18188]: info: MyVolumio login type: Token Feb 25 03:36:05 yamaha volumio[18188]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 25 03:36:05 yamaha volumio[18188]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 25 03:36:06 yamaha systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22. Feb 25 03:36:06 yamaha systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:36:07 yamaha systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:36:07 yamaha go-librespot[18564]: go-librespot daemon starting... Feb 25 03:36:07 yamaha go-librespot[18565]: time="2026-02-25T03:36:07+01:00" level=info msg="running go-librespot 0.6.2" Feb 25 03:36:07 yamaha go-librespot[18565]: time="2026-02-25T03:36:07+01:00" level=debug msg="app state loaded" Feb 25 03:36:07 yamaha go-librespot[18565]: time="2026-02-25T03:36:07+01:00" level=debug msg="stored credentials not found" Feb 25 03:36:07 yamaha go-librespot[18565]: time="2026-02-25T03:36:07+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 25 03:36:07 yamaha go-librespot[18565]: time="2026-02-25T03:36:07+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:36:07 yamaha go-librespot[18565]: time="2026-02-25T03:36:07+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:36:07 yamaha go-librespot[18565]: time="2026-02-25T03:36:07+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:36:07 yamaha go-librespot[18565]: time="2026-02-25T03:36:07+01:00" level=info msg="zeroconf server listening on port 39097" Feb 25 03:36:07 yamaha volumio[18188]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 25 03:36:07 yamaha volumio[18188]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 25 03:36:07 yamaha volumio[18188]: info: Streaming services startup Feb 25 03:36:07 yamaha volumio[18188]: info: Starting Streaming Daemon Feb 25 03:36:07 yamaha go-librespot[18565]: time="2026-02-25T03:36:07+01:00" level=debug msg="obtained new client token: AADAr90an2tPBmvQ4fWuaspEH5NfFbblLPdR4gAqd4yu/XwtV0oh/5/jgAi/KNaETVwFQpD5ySZl66BJmT4AXG4rYAzlud/p97kIsl7sJrg2nxAFtvGHNrQaIpvv2r9MmfQyI73f+BB+nrSQHBWiETTQTFFf0AK4AGyLVUJy3XswoUrTLpnZmoev0OZvbQSqExuRXUdZ/ZXAwqq6c2mimG2q0nRVQnLXNXbxOdbU4JinOjEKIq35Fffa" Feb 25 03:36:07 yamaha go-librespot[18565]: time="2026-02-25T03:36:07+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 25 03:36:07 yamaha volumio[18188]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 25 03:36:07 yamaha sudo[18575]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 25 03:36:07 yamaha sudo[18575]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:36:07 yamaha go-librespot[18565]: time="2026-02-25T03:36:07+01:00" level=debug msg="completed keyexchange" Feb 25 03:36:07 yamaha go-librespot[18565]: time="2026-02-25T03:36:07+01:00" level=debug msg="completed challenge" Feb 25 03:36:07 yamaha go-librespot[18565]: time="2026-02-25T03:36:07+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:36:07 yamaha sudo[18575]: pam_unix(sudo:session): session closed for user root Feb 25 03:36:07 yamaha volumio[18188]: info: Getting Spotify volume Feb 25 03:36:07 yamaha volumio[18188]: info: Initializing connection to go-librespot Websocket Feb 25 03:36:08 yamaha volumio[18188]: error: Cannot start Volumio Streaming Daemon Feb 25 03:36:08 yamaha volumio[18188]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 25 03:36:08 yamaha volumio[18188]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 25 03:36:08 yamaha go-librespot[18565]: time="2026-02-25T03:36:08+01:00" level=debug msg="new websocket client" Feb 25 03:36:08 yamaha volumio[18188]: 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:36:08 yamaha volumio[18188]: info: Connection to go-librespot Websocket established Feb 25 03:36:08 yamaha volumio[18188]: info: CoreCommandRouter::volumioGetState Feb 25 03:36:08 yamaha volumio[18188]: info: CorePlayQueue::getTrack 0 Feb 25 03:36:08 yamaha volumio[18188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 25 03:36:08 yamaha volumio[18188]: SPOTIFY: SPOTIFY VOLUME undefined Feb 25 03:36:08 yamaha volumio[18188]: SPOTIFY: VOLUMIO VOLUME 86 Feb 25 03:36:08 yamaha volumio[18188]: info: Aligning Spotify Volume to Volumio Volume Feb 25 03:36:08 yamaha volumio[18188]: info: CoreCommandRouter::volumioGetState Feb 25 03:36:08 yamaha volumio[18188]: info: CorePlayQueue::getTrack 0 Feb 25 03:36:08 yamaha volumio[18188]: info: Setting Spotify Volume from Volumio: 86 Feb 25 03:36:08 yamaha go-librespot[18565]: time="2026-02-25T03:36:08+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Feb 25 03:36:08 yamaha go-librespot[18565]: time="2026-02-25T03:36:08+01:00" level=debug msg="completed keyexchange" Feb 25 03:36:08 yamaha go-librespot[18565]: time="2026-02-25T03:36:08+01:00" level=debug msg="completed challenge" Feb 25 03:36:08 yamaha go-librespot[18565]: time="2026-02-25T03:36:08+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:36:08 yamaha volumio[18188]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Feb 25 03:36:09 yamaha go-librespot[18565]: time="2026-02-25T03:36:09+01:00" level=debug msg="connected to ap-gew4.spotify.com:80" Feb 25 03:36:09 yamaha go-librespot[18565]: time="2026-02-25T03:36:09+01:00" level=debug msg="completed keyexchange" Feb 25 03:36:09 yamaha go-librespot[18565]: time="2026-02-25T03:36:09+01:00" level=debug msg="completed challenge" Feb 25 03:36:09 yamaha go-librespot[18565]: time="2026-02-25T03:36:09+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:36:09 yamaha volumio[18188]: info: MyVolumio token set successfully Feb 25 03:36:09 yamaha volumio[18188]: info: MYVOLUMIO: Adding device Feb 25 03:36:09 yamaha volumio[18188]: info: MYVOLUMIO: Evaluating Server Feb 25 03:36:09 yamaha go-librespot[18565]: time="2026-02-25T03:36:09+01:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 25 03:36:09 yamaha go-librespot[18565]: time="2026-02-25T03:36:09+01:00" level=debug msg="completed keyexchange" Feb 25 03:36:09 yamaha go-librespot[18565]: time="2026-02-25T03:36:09+01:00" level=debug msg="completed challenge" Feb 25 03:36:09 yamaha go-librespot[18565]: time="2026-02-25T03:36:09+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:36:10 yamaha volumio[18188]: SPOTIFY: SETTING SPOTIFY VOLUME 86 Feb 25 03:36:10 yamaha volumio[18188]: info: Sending Spotify command with payload to local API: /player/volume Feb 25 03:36:10 yamaha volumio[18188]: info: MyVolumio status changed Feb 25 03:36:10 yamaha volumio[18188]: info: Streaming services startup Feb 25 03:36:10 yamaha volumio[18188]: info: Starting Streaming Daemon Feb 25 03:36:10 yamaha volumio[18188]: info: Removing browser output: myVolumio user plan is not superstar Feb 25 03:36:10 yamaha volumio[18188]: info: Removing audio output: Feb 25 03:36:10 yamaha volumio[18188]: info: Stoppping Tunnel 1 Feb 25 03:36:10 yamaha sudo[18602]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 25 03:36:10 yamaha sudo[18602]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:36:10 yamaha sudo[18604]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Feb 25 03:36:10 yamaha sudo[18604]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:36:10 yamaha volumio[18188]: info: Setting Geolocation for MyVolumio to eu8 Feb 25 03:36:10 yamaha volumio[18188]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:36:10 yamaha volumio[18188]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:36:10 yamaha volumio[18188]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:36:10 yamaha sudo[18602]: pam_unix(sudo:session): session closed for user root Feb 25 03:36:10 yamaha volumio[18188]: error: Cannot start Volumio Streaming Daemon Feb 25 03:36:10 yamaha volumio[18188]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 25 03:36:10 yamaha volumio[18188]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 25 03:36:10 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:36:10 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:36:10 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:36:10 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:36:10 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:36:10 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:36:10 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:36:10 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:36:10 yamaha sudo[18604]: pam_unix(sudo:session): session closed for user root Feb 25 03:36:10 yamaha volumio[18188]: info: Remote SSH Stopped Feb 25 03:36:10 yamaha go-librespot[18565]: time="2026-02-25T03:36:10+01:00" level=debug msg="connected to ap-gue1.spotify.com:443" Feb 25 03:36:10 yamaha go-librespot[18565]: time="2026-02-25T03:36:10+01:00" level=debug msg="completed keyexchange" Feb 25 03:36:10 yamaha go-librespot[18565]: time="2026-02-25T03:36:10+01:00" level=debug msg="completed challenge" Feb 25 03:36:10 yamaha volumio[18188]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Feb 25 03:36:10 yamaha go-librespot[18565]: time="2026-02-25T03:36:10+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:36:11 yamaha volumio[18188]: info: Getting Spotify volume Feb 25 03:36:11 yamaha volumio[18188]: 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:36:11 yamaha volumio[18188]: info: CoreCommandRouter::volumioGetState Feb 25 03:36:11 yamaha volumio[18188]: info: CorePlayQueue::getTrack 0 Feb 25 03:36:11 yamaha volumio[18188]: info: Updating MyVolumio device info Feb 25 03:36:11 yamaha volumio[18188]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:36:11 yamaha volumio[18188]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:36:11 yamaha volumio[18188]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:36:11 yamaha volumio[18188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 25 03:36:11 yamaha go-librespot[18565]: time="2026-02-25T03:36:11+01:00" level=debug msg="connected to ap-gae2.spotify.com:80" Feb 25 03:36:11 yamaha go-librespot[18565]: time="2026-02-25T03:36:11+01:00" level=debug msg="completed keyexchange" Feb 25 03:36:11 yamaha go-librespot[18565]: time="2026-02-25T03:36:11+01:00" level=debug msg="completed challenge" Feb 25 03:36:11 yamaha volumio[18188]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Feb 25 03:36:12 yamaha go-librespot[18565]: time="2026-02-25T03:36:12+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:36:12 yamaha go-librespot[18565]: time="2026-02-25T03:36:12+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:36:12 yamaha systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 03:36:12 yamaha systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 03:36:12 yamaha volumio[18188]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 25 03:36:12 yamaha volumio[18188]: Error: socket hang up Feb 25 03:36:12 yamaha volumio[18188]: at connResetException (node:internal/errors:720:14) Feb 25 03:36:12 yamaha volumio[18188]: at Socket.socketOnEnd (node:_http_client:519:23) Feb 25 03:36:12 yamaha volumio[18188]: at Socket.emit (node:events:526:35) Feb 25 03:36:12 yamaha volumio[18188]: at endReadableNT (node:internal/streams/readable:1376:12) Feb 25 03:36:12 yamaha volumio[18188]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Feb 25 03:36:12 yamaha volumio[18188]: code: 'ECONNRESET', Feb 25 03:36:12 yamaha volumio[18188]: response: undefined Feb 25 03:36:12 yamaha volumio[18188]: } Feb 25 03:36:12 yamaha volumio[18188]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 25 03:36:13 yamaha sudo[18621]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-25 03:35' Feb 25 03:36:13 yamaha sudo[18621]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:36:13 yamaha sudo[18621]: pam_unix(sudo:session): session closed for user root Feb 25 03:36:13 yamaha volumio5-onboarding[1284]: time=2026-02-25T03:36:13.989+01:00 level=ERROR msg="failed reading message" component=volumio/socket error="websocket: close 1006 (abnormal closure): unexpected EOF" Feb 25 03:36:13 yamaha volumio5-onboarding[1284]: time=2026-02-25T03:36:13.993+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:36:13 yamaha volumio-remote-updater[629]: [2026-02-25 03:36:13] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 25 03:36:13 yamaha volumio-remote-updater[629]: [2026-02-25 03:36:13] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 25 03:36:13 yamaha systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 25 03:36:14 yamaha systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 25 03:36:14 yamaha systemd[1]: volumio.service: Consumed 56.957s CPU time. Feb 25 03:36:14 yamaha systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 25 03:36:14 yamaha systemd[1]: dynamicswap.service: Deactivated successfully. Feb 25 03:36:14 yamaha systemd[1]: volumio.service: Scheduled restart job, restart counter is at 212. Feb 25 03:36:14 yamaha systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 25 03:36:14 yamaha systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 25 03:36:14 yamaha systemd[1]: volumio.service: Consumed 56.957s CPU time. Feb 25 03:36:14 yamaha systemd[1]: Started volumio.service - Volumio Backend Module. Feb 25 03:36:14 yamaha systemd[1]: dynamicswap.service: Deactivated successfully. Feb 25 03:36:14 yamaha volumio5-onboarding[1284]: time=2026-02-25T03:36:14.997+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:36:15 yamaha systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 23. Feb 25 03:36:15 yamaha systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:36:15 yamaha systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:36:15 yamaha go-librespot[18652]: go-librespot daemon starting... Feb 25 03:36:15 yamaha go-librespot[18653]: time="2026-02-25T03:36:15+01:00" level=info msg="running go-librespot 0.6.2" Feb 25 03:36:15 yamaha go-librespot[18653]: time="2026-02-25T03:36:15+01:00" level=debug msg="app state loaded" Feb 25 03:36:15 yamaha go-librespot[18653]: time="2026-02-25T03:36:15+01:00" level=debug msg="stored credentials not found" Feb 25 03:36:15 yamaha go-librespot[18653]: time="2026-02-25T03:36:15+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 25 03:36:15 yamaha go-librespot[18653]: time="2026-02-25T03:36:15+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 25 03:36:15 yamaha go-librespot[18653]: time="2026-02-25T03:36:15+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:36:15 yamaha go-librespot[18653]: time="2026-02-25T03:36:15+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:36:15 yamaha go-librespot[18653]: time="2026-02-25T03:36:15+01:00" level=info msg="zeroconf server listening on port 34835" Feb 25 03:36:15 yamaha go-librespot[18653]: time="2026-02-25T03:36:15+01:00" level=debug msg="obtained new client token: AACqRjPbJOWvbW6zHo1ns5Iuu8ILFkg1BuCOmmzCulguIxvKRBqu0CvUHT30cXt34/9iRgpRE+OqAGn8AUsEq+BTtSCf741/KBoH2/OMhN74VSa7SyiF1xRCxCUHTLsrVyDaqN4CAhbU3ZX5lUqTa7XRcIPE4I7tiIxPH0rQ+4bBw/PJCeVOdgxeqvY4UixkpYMcYUu9wkZAPOkOxvRnpHWIWNcKoIWe7KMdzl0jN4jWLNr3r9C6EjBF" Feb 25 03:36:16 yamaha volumio5-onboarding[1284]: time=2026-02-25T03:36:16.000+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:36:16 yamaha go-librespot[18653]: time="2026-02-25T03:36:16+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 25 03:36:16 yamaha go-librespot[18653]: time="2026-02-25T03:36:16+01:00" level=debug msg="completed keyexchange" Feb 25 03:36:16 yamaha go-librespot[18653]: time="2026-02-25T03:36:16+01:00" level=debug msg="completed challenge" Feb 25 03:36:16 yamaha go-librespot[18653]: time="2026-02-25T03:36:16+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:36:16 yamaha go-librespot[18653]: time="2026-02-25T03:36:16+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Feb 25 03:36:16 yamaha go-librespot[18653]: time="2026-02-25T03:36:16+01:00" level=debug msg="completed keyexchange" Feb 25 03:36:16 yamaha go-librespot[18653]: time="2026-02-25T03:36:16+01:00" level=debug msg="completed challenge" Feb 25 03:36:16 yamaha go-librespot[18653]: time="2026-02-25T03:36:16+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:36:17 yamaha volumio5-onboarding[1284]: time=2026-02-25T03:36:17.003+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:36:17 yamaha go-librespot[18653]: time="2026-02-25T03:36:17+01:00" level=debug msg="connected to ap-gew4.spotify.com:80" Feb 25 03:36:17 yamaha go-librespot[18653]: time="2026-02-25T03:36:17+01:00" level=debug msg="completed keyexchange" Feb 25 03:36:17 yamaha go-librespot[18653]: time="2026-02-25T03:36:17+01:00" level=debug msg="completed challenge" Feb 25 03:36:17 yamaha go-librespot[18653]: time="2026-02-25T03:36:17+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:36:17 yamaha go-librespot[18653]: time="2026-02-25T03:36:17+01:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Feb 25 03:36:17 yamaha volumio[18637]: info: ------------------------------------------- Feb 25 03:36:17 yamaha volumio[18637]: info: ----- Volumio3 ---- Feb 25 03:36:17 yamaha volumio[18637]: info: ------------------------------------------- Feb 25 03:36:17 yamaha volumio[18637]: info: ----- System startup ---- Feb 25 03:36:17 yamaha volumio[18637]: info: ------------------------------------------- Feb 25 03:36:18 yamaha go-librespot[18653]: time="2026-02-25T03:36:18+01:00" level=debug msg="completed keyexchange" Feb 25 03:36:18 yamaha go-librespot[18653]: time="2026-02-25T03:36:18+01:00" level=debug msg="completed challenge" Feb 25 03:36:18 yamaha go-librespot[18653]: time="2026-02-25T03:36:18+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:36:18 yamaha go-librespot[18653]: time="2026-02-25T03:36:18+01:00" level=debug msg="connected to ap-gew1.spotify.com:443" Feb 25 03:36:18 yamaha go-librespot[18653]: time="2026-02-25T03:36:18+01:00" level=debug msg="completed keyexchange" Feb 25 03:36:18 yamaha go-librespot[18653]: time="2026-02-25T03:36:18+01:00" level=debug msg="completed challenge" Feb 25 03:36:18 yamaha go-librespot[18653]: time="2026-02-25T03:36:18+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:36:18 yamaha volumio-remote-updater[629]: [2026-02-25 03:36:18] [connect] Successful connection Feb 25 03:36:19 yamaha volumio[18637]: info: MYVOLUMIO Environment detected Feb 25 03:36:19 yamaha volumio[18637]: info: Plugin folders cleanup Feb 25 03:36:19 yamaha volumio[18637]: info: Scanning into folder /volumio/app/plugins/ Feb 25 03:36:19 yamaha volumio[18637]: info: Scanning category audio_interface Feb 25 03:36:19 yamaha volumio[18637]: info: Scanning category miscellanea Feb 25 03:36:19 yamaha volumio[18637]: info: Scanning category music_service Feb 25 03:36:19 yamaha volumio[18637]: info: Scanning category plugins.json Feb 25 03:36:19 yamaha volumio[18637]: info: Scanning category system_controller Feb 25 03:36:19 yamaha volumio[18637]: info: Scanning category user_interface Feb 25 03:36:19 yamaha volumio[18637]: info: Scanning into folder /data/plugins/ Feb 25 03:36:19 yamaha volumio[18637]: info: Scanning category music_service Feb 25 03:36:19 yamaha volumio[18637]: info: Plugin folders cleanup completed Feb 25 03:36:19 yamaha volumio[18637]: info: ------------------------------------------- Feb 25 03:36:19 yamaha volumio[18637]: info: ----- Core plugins startup ---- Feb 25 03:36:19 yamaha volumio[18637]: info: ------------------------------------------- Feb 25 03:36:19 yamaha volumio[18637]: info: Loading plugins from folder /volumio/app/plugins/ Feb 25 03:36:19 yamaha volumio[18637]: info: Adding plugin upnp to MyMusic Plugins Feb 25 03:36:19 yamaha volumio[18637]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 25 03:36:19 yamaha volumio[18637]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 25 03:36:19 yamaha go-librespot[18653]: time="2026-02-25T03:36:19+01:00" level=debug msg="connected to ap-gae2.spotify.com:80" Feb 25 03:36:19 yamaha volumio[18637]: info: Loading plugins from folder /data/plugins/ Feb 25 03:36:19 yamaha volumio[18637]: info: Loading plugin "system"... Feb 25 03:36:19 yamaha volumio[18637]: info: Loading plugin "appearance"... Feb 25 03:36:20 yamaha go-librespot[18653]: time="2026-02-25T03:36:20+01:00" level=debug msg="completed keyexchange" Feb 25 03:36:20 yamaha go-librespot[18653]: time="2026-02-25T03:36:20+01:00" level=debug msg="completed challenge" Feb 25 03:36:20 yamaha go-librespot[18653]: time="2026-02-25T03:36:20+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:36:20 yamaha go-librespot[18653]: time="2026-02-25T03:36: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:36:20 yamaha systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 03:36:20 yamaha systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 03:36:21 yamaha volumio[18637]: info: Loading plugin "network"... Feb 25 03:36:21 yamaha volumio[18637]: info: Refreshing Cached IP Addresses Feb 25 03:36:21 yamaha sudo[18676]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 25 03:36:21 yamaha sudo[18676]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:36:21 yamaha sudo[18678]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 25 03:36:21 yamaha volumio[18637]: info: Loading plugin "services"... Feb 25 03:36:21 yamaha sudo[18678]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:36:21 yamaha volumio[18637]: info: Loading plugin "volumio5onboarding"... Feb 25 03:36:21 yamaha sudo[18676]: pam_unix(sudo:session): session closed for user root Feb 25 03:36:21 yamaha sudo[18685]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 25 03:36:21 yamaha sudo[18685]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:36:21 yamaha sudo[18678]: pam_unix(sudo:session): session closed for user root Feb 25 03:36:21 yamaha volumio[18637]: info: Loading plugin "alsa_controller"... Feb 25 03:36:21 yamaha sudo[18685]: pam_unix(sudo:session): session closed for user root Feb 25 03:36:21 yamaha volumio[18637]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 25 03:36:21 yamaha volumio[18637]: info: Loading plugin "wizard"... Feb 25 03:36:21 yamaha volumio[18637]: info: Loading plugin "networkfs"... Feb 25 03:36:21 yamaha volumio[18637]: info: Cannot mount NAS Ugreen at system boot, trial number 1 ,retrying in 5 seconds Feb 25 03:36:22 yamaha volumio[18637]: info: Starting Udev Watcher for removable devices Feb 25 03:36:22 yamaha volumio[18637]: info: Ignoring mount for partition: boot Feb 25 03:36:22 yamaha volumio[18637]: info: Ignoring mount for partition: volumio Feb 25 03:36:22 yamaha volumio[18637]: info: Ignoring mount for partition: volumio_data Feb 25 03:36:22 yamaha volumio[18637]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 25 03:36:22 yamaha volumio[18637]: info: Loading plugin "volumio_command_line_client"... Feb 25 03:36:22 yamaha volumio[18637]: info: Loading plugin "upnp"... Feb 25 03:36:22 yamaha volumio[18637]: info: [1771986982093] Starting Upmpd Daemon Feb 25 03:36:22 yamaha volumio[18637]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 25 03:36:22 yamaha volumio[18637]: info: Loading plugin "my_music"... Feb 25 03:36:22 yamaha volumio[18637]: info: Loading plugin "mpd"... Feb 25 03:36:22 yamaha volumio[18637]: info: Loading plugin "upnp_browser"... Feb 25 03:36:23 yamaha systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 24. Feb 25 03:36:23 yamaha systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:36:24 yamaha systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:36:24 yamaha go-librespot[18714]: go-librespot daemon starting... Feb 25 03:36:24 yamaha go-librespot[18715]: time="2026-02-25T03:36:24+01:00" level=info msg="running go-librespot 0.6.2" Feb 25 03:36:24 yamaha go-librespot[18715]: time="2026-02-25T03:36:24+01:00" level=debug msg="app state loaded" Feb 25 03:36:24 yamaha go-librespot[18715]: time="2026-02-25T03:36:24+01:00" level=debug msg="stored credentials not found" Feb 25 03:36:24 yamaha go-librespot[18715]: time="2026-02-25T03:36:24+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 25 03:36:24 yamaha go-librespot[18715]: time="2026-02-25T03:36: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:36:24 yamaha go-librespot[18715]: time="2026-02-25T03:36: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:36:24 yamaha go-librespot[18715]: time="2026-02-25T03:36: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:36:24 yamaha go-librespot[18715]: time="2026-02-25T03:36:24+01:00" level=info msg="zeroconf server listening on port 38585" Feb 25 03:36:24 yamaha go-librespot[18715]: time="2026-02-25T03:36:24+01:00" level=debug msg="obtained new client token: AACGRLql9rWnXB1hS7CfAzCAUxUgaFDwpZtj/TCUZEPXoDWa9d7+i1PHsNj2C7ieoSIXCMHg4QPxy0JhsZzIFXVKRoWPQKNCuJjHV1Gd2GpUQzZ/myIfFS75FMZuI1Ey5IiFaiY3ijc7s4pAK0AMfUbPylL+Rptqe06FjHe1aQjBDCnh4CQ3hmw8W92wWUXnjhFZFjWO2pCfmrcUW2nPwH6dIPppY7pAbxJYaCNSANIe4FfB3m7lw491" Feb 25 03:36:24 yamaha go-librespot[18715]: time="2026-02-25T03:36:24+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 25 03:36:24 yamaha go-librespot[18715]: time="2026-02-25T03:36:24+01:00" level=debug msg="completed keyexchange" Feb 25 03:36:24 yamaha go-librespot[18715]: time="2026-02-25T03:36:24+01:00" level=debug msg="completed challenge" Feb 25 03:36:24 yamaha go-librespot[18715]: time="2026-02-25T03:36:24+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:36:25 yamaha go-librespot[18715]: time="2026-02-25T03:36:25+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Feb 25 03:36:25 yamaha go-librespot[18715]: time="2026-02-25T03:36:25+01:00" level=debug msg="completed keyexchange" Feb 25 03:36:25 yamaha go-librespot[18715]: time="2026-02-25T03:36:25+01:00" level=debug msg="completed challenge" Feb 25 03:36:25 yamaha go-librespot[18715]: time="2026-02-25T03:36:25+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:36:25 yamaha volumio[18637]: info: Starting UPNP Browser Feb 25 03:36:25 yamaha volumio[18637]: info: Loading plugin "alarm-clock"... Feb 25 03:36:25 yamaha volumio[18637]: info: Loading plugin "airplay_emulation"... Feb 25 03:36:25 yamaha volumio[18637]: info: Starting Shairport Sync Feb 25 03:36:25 yamaha volumio[18637]: info: Loading plugin "last_100"... Feb 25 03:36:25 yamaha volumio[18637]: info: Loading plugin "webradio"... Feb 25 03:36:25 yamaha go-librespot[18715]: time="2026-02-25T03:36:25+01:00" level=debug msg="connected to ap-gew4.spotify.com:80" Feb 25 03:36:26 yamaha go-librespot[18715]: time="2026-02-25T03:36:26+01:00" level=debug msg="completed keyexchange" Feb 25 03:36:26 yamaha go-librespot[18715]: time="2026-02-25T03:36:26+01:00" level=debug msg="completed challenge" Feb 25 03:36:26 yamaha go-librespot[18715]: time="2026-02-25T03:36:26+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:36:26 yamaha volumio[18637]: info: Loading plugin "i2s_dacs"... Feb 25 03:36:26 yamaha volumio[18637]: info: I2S DAC not set, start Auto-detection Feb 25 03:36:26 yamaha volumio[18637]: info: Loading plugin "volumiodiscovery"... Feb 25 03:36:26 yamaha volumio[18637]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 25 03:36:26 yamaha volumio[18637]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 25 03:36:26 yamaha volumio[18637]: *** WARNING *** For more information see Feb 25 03:36:26 yamaha volumio[18637]: *** 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:36:26 yamaha volumio[18637]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 25 03:36:26 yamaha volumio[18637]: *** WARNING *** For more information see Feb 25 03:36:26 yamaha node[18637]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 25 03:36:26 yamaha node[18637]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 25 03:36:26 yamaha node[18637]: *** WARNING *** For more information see Feb 25 03:36:26 yamaha node[18637]: *** 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:36:26 yamaha node[18637]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 25 03:36:26 yamaha node[18637]: *** WARNING *** For more information see Feb 25 03:36:26 yamaha volumio[18637]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 25 03:36:26 yamaha volumio[18637]: info: Discovery: Started advertising with name: Yamaha Feb 25 03:36:26 yamaha volumio[18637]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 25 03:36:26 yamaha volumio[18637]: info: Loading plugin "calmradio"... Feb 25 03:36:26 yamaha go-librespot[18715]: time="2026-02-25T03:36:26+01:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Feb 25 03:36:28 yamaha volumio5-onboarding[1284]: time=2026-02-25T03:36:28.008+01:00 level=WARN msg="reconnection attempt failed" component=volumio/socket error="read tcp 127.0.0.1:36458->127.0.0.1:3000: i/o timeout" Feb 25 03:36:28 yamaha go-librespot[18715]: time="2026-02-25T03:36:28+01:00" level=debug msg="completed keyexchange" Feb 25 03:36:28 yamaha go-librespot[18715]: time="2026-02-25T03:36:28+01:00" level=debug msg="completed challenge" Feb 25 03:36:28 yamaha go-librespot[18715]: time="2026-02-25T03:36:28+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:36:28 yamaha volumio[18637]: info: Loading plugin "spop"... Feb 25 03:36:28 yamaha go-librespot[18715]: time="2026-02-25T03:36:28+01:00" level=debug msg="connected to ap-gew1.spotify.com:443" Feb 25 03:36:28 yamaha go-librespot[18715]: time="2026-02-25T03:36:28+01:00" level=debug msg="completed keyexchange" Feb 25 03:36:28 yamaha go-librespot[18715]: time="2026-02-25T03:36:28+01:00" level=debug msg="completed challenge" Feb 25 03:36:28 yamaha go-librespot[18715]: time="2026-02-25T03:36:28+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:36:29 yamaha go-librespot[18715]: time="2026-02-25T03:36:29+01:00" level=debug msg="connected to ap-gae2.spotify.com:80" Feb 25 03:36:29 yamaha go-librespot[18715]: time="2026-02-25T03:36:29+01:00" level=debug msg="completed keyexchange" Feb 25 03:36:29 yamaha go-librespot[18715]: time="2026-02-25T03:36:29+01:00" level=debug msg="completed challenge" Feb 25 03:36:29 yamaha go-librespot[18715]: time="2026-02-25T03:36:29+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:36:29 yamaha go-librespot[18715]: time="2026-02-25T03:36: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:36:29 yamaha systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 03:36:29 yamaha systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 03:36:30 yamaha volumio[18637]: info: Loading plugin "outputs"... Feb 25 03:36:30 yamaha volumio[18637]: info: Loading plugin "albumart"... Feb 25 03:36:30 yamaha volumio[18637]: info: Plugin example_plugin is not enabled Feb 25 03:36:30 yamaha volumio[18637]: info: Loading plugin "inputs"... Feb 25 03:36:30 yamaha volumio[18637]: info: Loading plugin "updater_comm"... Feb 25 03:36:30 yamaha volumio[18637]: info: Plugin mpdemulation is not enabled Feb 25 03:36:30 yamaha volumio[18637]: info: Loading plugin "rest_api"... Feb 25 03:36:30 yamaha volumio[18637]: info: Loading plugin "websocket"... Feb 25 03:36:30 yamaha volumio[18637]: info: Starting Socket.io Server version 1.7.4 Feb 25 03:36:30 yamaha volumio[18637]: info: Loading plugin "80s80s"... Feb 25 03:36:30 yamaha volumio[18637]: info: Applying required configuration parameters for plugin 80s80s Feb 25 03:36:30 yamaha volumio[18637]: info: [1771986990964] [80s80s] API delay: 30 Feb 25 03:36:30 yamaha volumio[18637]: info: Loading i18n strings for locale nl Feb 25 03:36:31 yamaha volumio[18637]: Updating browse sources language Feb 25 03:36:31 yamaha volumio[18637]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 25 03:36:31 yamaha volumio[18728]: Forking 3 albumart workers Feb 25 03:36:31 yamaha volumio[18637]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 03:36:31 yamaha volumio[18637]: info: CoreCommandRouter::initPlayerControls Feb 25 03:36:31 yamaha volumio[18637]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:36:31 yamaha volumio[18637]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:36:31 yamaha volumio[18637]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:36:31 yamaha volumio[18637]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:36:31 yamaha volumio[18637]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:36:31 yamaha volumio[18637]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:36:31 yamaha volumio[18637]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:36:31 yamaha volumio[18637]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:36:31 yamaha volumio[18637]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 03:36:31 yamaha volumio[18637]: Express server listening on port 3000 Feb 25 03:36:31 yamaha volumio[18637]: [Metrics] WebUI: 14s 371.08ms Feb 25 03:36:31 yamaha volumio[18637]: info: CoreStateMachine::resetVolumioState Feb 25 03:36:31 yamaha volumio[18637]: info: CoreStateMachine::getcurrentVolume Feb 25 03:36:31 yamaha volumio[18637]: info: CoreCommandRouter::volumioRetrievevolume Feb 25 03:36:31 yamaha volumio-remote-updater[629]: [2026-02-25 03:36:31] [connect] Successful connection Feb 25 03:36:31 yamaha volumio[18637]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:36:31 yamaha volumio[18637]: info: Cannot mount NAS Ugreen at system boot, trial number 2 ,retrying in 5 seconds Feb 25 03:36:31 yamaha volumio[18637]: info: Volumio Network Manager: Network status updated: 1 Feb 25 03:36:31 yamaha volumio[18637]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Feb 25 03:36:31 yamaha volumio[18637]: wlan0 Interface doesn't support scanning. Feb 25 03:36:31 yamaha volumio[18637]: info: Cannot use regular scanning, forcing with ap-force Feb 25 03:36:31 yamaha sudo[18775]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Feb 25 03:36:31 yamaha sudo[18775]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:36:31 yamaha sudo[18775]: pam_unix(sudo:session): session closed for user root Feb 25 03:36:31 yamaha volumio[18637]: command failed: No such device (-19) Feb 25 03:36:31 yamaha volumio[18637]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Feb 25 03:36:31 yamaha volumio[18637]: command failed: No such device (-19) Feb 25 03:36:32 yamaha volumio[18637]: info: VolumeController:: Volume=86 Mute =false Feb 25 03:36:32 yamaha volumio[18637]: info: CoreStateMachine::pushState Feb 25 03:36:32 yamaha volumio[18637]: info: CorePlayQueue::getTrack 0 Feb 25 03:36:32 yamaha volumio[18637]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 03:36:32 yamaha volumio[18637]: info: CoreCommandRouter::volumioPushState Feb 25 03:36:32 yamaha volumio[18637]: info: CoreStateMachine::updateTrackBlock Feb 25 03:36:32 yamaha volumio[18637]: info: CorePlayQueue::getTrackBlock Feb 25 03:36:32 yamaha volumio[18637]: info: CoreCommandRouter::volumioRetrievevolume Feb 25 03:36:32 yamaha sudo[18796]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 25 03:36:32 yamaha sudo[18796]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:36:32 yamaha sudo[18796]: pam_unix(sudo:session): session closed for user root Feb 25 03:36:32 yamaha sudo[18798]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 25 03:36:32 yamaha sudo[18798]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:36:32 yamaha sudo[18798]: pam_unix(sudo:session): session closed for user root Feb 25 03:36:32 yamaha volumio[18637]: info: Reloading queue from file Feb 25 03:36:32 yamaha volumio[18637]: 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:36:32 yamaha volumio[18637]: info: CoreStateMachine::setRepeat null single undefined Feb 25 03:36:32 yamaha volumio[18637]: info: CoreStateMachine::pushState Feb 25 03:36:32 yamaha volumio[18637]: info: CorePlayQueue::getTrack 0 Feb 25 03:36:32 yamaha volumio[18637]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 03:36:32 yamaha volumio[18637]: info: CoreCommandRouter::volumioPushState Feb 25 03:36:32 yamaha volumio[18637]: info: CoreStateMachine::setRandom null Feb 25 03:36:32 yamaha volumio[18637]: info: CoreStateMachine::pushState Feb 25 03:36:32 yamaha volumio[18637]: info: CorePlayQueue::getTrack 0 Feb 25 03:36:32 yamaha volumio[18637]: info: CoreCommandRouter::volumioPushState Feb 25 03:36:32 yamaha volumio[18637]: info: Setting Device type: Raspberry PI Feb 25 03:36:32 yamaha volumio-remote-updater[629]: [2026-02-25 03:36:32] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1771986991 101 Feb 25 03:36:32 yamaha volumio[18637]: 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:36:32 yamaha volumio[18637]: info: VolumeController:: Volume=86 Mute =false Feb 25 03:36:32 yamaha volumio[18637]: info: CoreStateMachine::pushState Feb 25 03:36:32 yamaha volumio[18637]: info: CorePlayQueue::getTrack 0 Feb 25 03:36:32 yamaha volumio[18637]: info: CoreCommandRouter::volumioPushState Feb 25 03:36:32 yamaha volumio[18637]: 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:36:32 yamaha volumio[18637]: 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:36:32 yamaha systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 25. Feb 25 03:36:32 yamaha systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:36:33 yamaha volumio[18637]: info: Discovery: adding 67ba7d11-5499-4b1d-a75e-1a431a777f52 Feb 25 03:36:33 yamaha volumio[18637]: info: Discovery: Found device Yamaha Feb 25 03:36:33 yamaha volumio[18637]: info: CoreCommandRouter::volumioGetState Feb 25 03:36:33 yamaha volumio[18637]: info: CorePlayQueue::getTrack 0 Feb 25 03:36:33 yamaha volumio[18637]: info: Discovery: this is already registered, 67ba7d11-5499-4b1d-a75e-1a431a777f52 Feb 25 03:36:33 yamaha volumio[18637]: info: Discovery: Found device Yamaha Feb 25 03:36:33 yamaha volumio[18637]: info: CoreCommandRouter::volumioGetState Feb 25 03:36:33 yamaha volumio[18637]: info: CorePlayQueue::getTrack 0 Feb 25 03:36:33 yamaha volumio[18637]: info: Completed loading Core Plugins Feb 25 03:36:33 yamaha volumio[18637]: info: Preparing to generate the ALSA configuration file Feb 25 03:36:33 yamaha systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:36:33 yamaha sudo[18813]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 25 03:36:33 yamaha go-librespot[18814]: go-librespot daemon starting... Feb 25 03:36:33 yamaha sudo[18813]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:36:33 yamaha go-librespot[18815]: time="2026-02-25T03:36:33+01:00" level=info msg="running go-librespot 0.6.2" Feb 25 03:36:33 yamaha go-librespot[18815]: time="2026-02-25T03:36:33+01:00" level=debug msg="app state loaded" Feb 25 03:36:33 yamaha go-librespot[18815]: time="2026-02-25T03:36:33+01:00" level=debug msg="stored credentials not found" Feb 25 03:36:33 yamaha sudo[18813]: pam_unix(sudo:session): session closed for user root Feb 25 03:36:33 yamaha go-librespot[18815]: time="2026-02-25T03:36:33+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 25 03:36:33 yamaha volumio[18637]: info: Upmpdcli Daemon Started Feb 25 03:36:33 yamaha volumio[18637]: info: Asound.conf file unchanged, so no further update is needed Feb 25 03:36:33 yamaha volumio[18637]: info: Output device has changed, restarting MPD Feb 25 03:36:33 yamaha volumio[18637]: info: Output device has changed, restarting Shairport Sync Feb 25 03:36:33 yamaha volumio[18637]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:36:33 yamaha volumio[18637]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:36:33 yamaha sudo[18827]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 25 03:36:33 yamaha sudo[18827]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:36:33 yamaha volumio[18637]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 25 03:36:33 yamaha volumio[18637]: info: ___________ START PLUGINS ___________ Feb 25 03:36:33 yamaha sudo[18825]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 25 03:36:33 yamaha sudo[18825]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:36:33 yamaha systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 25 03:36:33 yamaha sudo[18825]: pam_unix(sudo:session): session closed for user root Feb 25 03:36:33 yamaha volumio[18637]: info: ControllerMpd::onStart: Initializing MPD Feb 25 03:36:33 yamaha volumio[18637]: info: Creating MPD Configuration file Feb 25 03:36:33 yamaha systemd[1]: mpd.service: Deactivated successfully. Feb 25 03:36:33 yamaha systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 25 03:36:33 yamaha systemd[1]: mpd.service: Consumed 7.453s CPU time. Feb 25 03:36:33 yamaha systemd[1]: mpd.socket: Deactivated successfully. Feb 25 03:36:33 yamaha systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 25 03:36:33 yamaha systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 25 03:36:33 yamaha volumio[18637]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 03:36:33 yamaha volumio[18637]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 25 03:36:33 yamaha sudo[18838]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 25 03:36:33 yamaha systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 25 03:36:33 yamaha volumio[18637]: info: [1771986993737] CoreMusicLibrary::Adding element Media Servers Feb 25 03:36:33 yamaha sudo[18838]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:36:33 yamaha volumio[18637]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 25 03:36:33 yamaha sudo[18836]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Feb 25 03:36:33 yamaha systemd[1]: Starting mpd.service - Music Player Daemon... Feb 25 03:36:33 yamaha sudo[18836]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:36:33 yamaha sudo[18838]: pam_unix(sudo:session): session closed for user root Feb 25 03:36:33 yamaha volumio[18637]: info: UPNP Browser: Client initialized successfully Feb 25 03:36:33 yamaha go-librespot[18815]: time="2026-02-25T03:36: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:36:33 yamaha go-librespot[18815]: time="2026-02-25T03:36: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:36:33 yamaha go-librespot[18815]: time="2026-02-25T03:36: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:36:33 yamaha sudo[18841]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 25 03:36:33 yamaha volumio[18637]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:36:33 yamaha volumio[18637]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:36:33 yamaha sudo[18841]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:36:33 yamaha go-librespot[18815]: time="2026-02-25T03:36:33+01:00" level=info msg="zeroconf server listening on port 39253" Feb 25 03:36:34 yamaha volumio[18637]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 25 03:36:34 yamaha volumio[18637]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:36:34 yamaha volumio[18637]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:36:34 yamaha go-librespot[18815]: time="2026-02-25T03:36:34+01:00" level=debug msg="obtained new client token: AAAIGKnTYmnCecPYLEqZkJvRAXJM/CBRcTuFokt304wmEOSkAzRh08hTXgEQyiEdHBwJj1EU8IeZIm7ULsKCrONC+Ig3ckEl5gN9y13nk+L9Q8f4RjDDore3yx/HhMEH28pT+Z6cAw36crv/9i2PBLo8bS/z7eX0jOfeyGl0jpyrQCM3u9rAlGisYPHPZlHgVsPHKS4+jjAW/t5CdqNRfNww/oDqcKFzHOqvcEaYOivZd7SYoF/GXQ==" Feb 25 03:36:34 yamaha systemd[1]: mpd.service: Deactivated successfully. Feb 25 03:36:34 yamaha systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 25 03:36:34 yamaha systemd[1]: mpd.socket: Deactivated successfully. Feb 25 03:36:34 yamaha systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 25 03:36:34 yamaha systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 25 03:36:34 yamaha sudo[18836]: pam_unix(sudo:session): session closed for user root Feb 25 03:36:34 yamaha go-librespot[18815]: time="2026-02-25T03:36:34+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:36:34 yamaha go-librespot[18815]: time="2026-02-25T03:36:34+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Feb 25 03:36:34 yamaha volumio[18637]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 25 03:36:34 yamaha volumio[18637]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 25 03:36:34 yamaha volumio[18637]: info: [1771986994185] CoreMusicLibrary::Adding element Last_100 Feb 25 03:36:34 yamaha volumio[18637]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 25 03:36:34 yamaha volumio[18637]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 25 03:36:34 yamaha volumio[18637]: info: [1771986994210] CoreMusicLibrary::Adding element Webradio Feb 25 03:36:34 yamaha volumio[18637]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 25 03:36:34 yamaha systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 25 03:36:34 yamaha go-librespot[18815]: time="2026-02-25T03:36:34+01:00" level=debug msg="completed keyexchange" Feb 25 03:36:34 yamaha systemd[1]: Starting mpd.service - Music Player Daemon... Feb 25 03:36:34 yamaha go-librespot[18815]: time="2026-02-25T03:36:34+01:00" level=debug msg="completed challenge" Feb 25 03:36:34 yamaha volumio[18637]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 25 03:36:34 yamaha volumio[18637]: info: Initializing BBC Radios Feb 25 03:36:34 yamaha go-librespot[18815]: time="2026-02-25T03:36:34+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:36:34 yamaha volumio[18637]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 25 03:36:34 yamaha volumio[18637]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:36:34 yamaha volumio[18637]: info: Adding Calm Radio to Browse Sources Feb 25 03:36:34 yamaha volumio[18637]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 25 03:36:34 yamaha volumio[18637]: info: [1771986994507] CoreMusicLibrary::Adding element Calm Radio Feb 25 03:36:34 yamaha volumio[18637]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 25 03:36:34 yamaha volumio[18637]: Cannot find translation for source Calm Radio Feb 25 03:36:34 yamaha volumio[18637]: info: Creating Spotify config file Feb 25 03:36:34 yamaha volumio[18637]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:36:34 yamaha sudo[18856]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 25 03:36:34 yamaha sudo[18856]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 25 03:36:34 yamaha sudo[18856]: pam_unix(sudo:session): session closed for user root Feb 25 03:36:34 yamaha go-librespot[18815]: time="2026-02-25T03:36:34+01:00" level=debug msg="connected to ap-gew4.spotify.com:80" Feb 25 03:36:34 yamaha go-librespot[18815]: time="2026-02-25T03:36:34+01:00" level=debug msg="completed keyexchange" Feb 25 03:36:34 yamaha go-librespot[18815]: time="2026-02-25T03:36:34+01:00" level=debug msg="completed challenge" Feb 25 03:36:34 yamaha go-librespot[18815]: time="2026-02-25T03:36:34+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:36:35 yamaha volumio[18738]: Starting albumart workers Feb 25 03:36:35 yamaha volumio[18739]: Starting albumart workers Feb 25 03:36:35 yamaha volumio[18740]: Starting albumart workers Feb 25 03:36:35 yamaha volumio[18637]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 25 03:36:35 yamaha volumio[18637]: info: [1771986995591] CoreMusicLibrary::Adding element 80s80s Radio Feb 25 03:36:35 yamaha volumio[18637]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 25 03:36:35 yamaha volumio[18637]: Cannot find translation for source Calm Radio Feb 25 03:36:35 yamaha volumio[18637]: Cannot find translation for source 80s80s Radio Feb 25 03:36:35 yamaha go-librespot[18815]: time="2026-02-25T03:36:35+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:36:35 yamaha volumio[18637]: info: Volumio Calling Home Feb 25 03:36:35 yamaha go-librespot[18815]: time="2026-02-25T03:36:35+01:00" level=debug msg="connected to ap-gue1.spotify.com:443" Feb 25 03:36:35 yamaha go-librespot[18815]: time="2026-02-25T03:36:35+01:00" level=debug msg="completed keyexchange" Feb 25 03:36:35 yamaha go-librespot[18815]: time="2026-02-25T03:36:35+01:00" level=debug msg="completed challenge" Feb 25 03:36:35 yamaha go-librespot[18815]: time="2026-02-25T03:36:35+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:36:36 yamaha go-librespot[18815]: time="2026-02-25T03:36:36+01:00" level=debug msg="connected to ap-gew1.spotify.com:80" Feb 25 03:36:36 yamaha go-librespot[18815]: time="2026-02-25T03:36:36+01:00" level=debug msg="completed keyexchange" Feb 25 03:36:36 yamaha go-librespot[18815]: time="2026-02-25T03:36:36+01:00" level=debug msg="completed challenge" Feb 25 03:36:36 yamaha go-librespot[18815]: time="2026-02-25T03:36:36+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:36:37 yamaha go-librespot[18815]: time="2026-02-25T03:36:37+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 25 03:36:37 yamaha volumio[18637]: info: Cannot mount NAS Ugreen at system boot, trial number 3 ,retrying in 5 seconds Feb 25 03:36:37 yamaha go-librespot[18815]: time="2026-02-25T03:36:37+01:00" level=debug msg="completed keyexchange" Feb 25 03:36:37 yamaha go-librespot[18815]: time="2026-02-25T03:36:37+01:00" level=debug msg="completed challenge" Feb 25 03:36:37 yamaha go-librespot[18815]: time="2026-02-25T03:36:37+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:36:37 yamaha volumio[18637]: error: Upnp client error: Error: This socket has been ended by the other party Feb 25 03:36:37 yamaha volumio[18637]: info: MPD Permissions set Feb 25 03:36:37 yamaha volumio[18637]: info: MPD Permissions set Feb 25 03:36:37 yamaha volumio[18637]: info: Volumio called home Feb 25 03:36:37 yamaha volumio[18637]: info: Spotify config file written Feb 25 03:36:37 yamaha sudo[18888]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 25 03:36:37 yamaha sudo[18888]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:36:37 yamaha systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Feb 25 03:36:37 yamaha systemd[1]: go-librespot-daemon.service: Deactivated successfully. Feb 25 03:36:37 yamaha systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:36:37 yamaha systemd[1]: go-librespot-daemon.service: Consumed 1.114s CPU time. Feb 25 03:36:37 yamaha systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:36:37 yamaha go-librespot[18897]: go-librespot daemon starting... Feb 25 03:36:37 yamaha sudo[18888]: pam_unix(sudo:session): session closed for user root Feb 25 03:36:37 yamaha go-librespot[18898]: time="2026-02-25T03:36:37+01:00" level=info msg="running go-librespot 0.6.2" Feb 25 03:36:37 yamaha go-librespot[18898]: time="2026-02-25T03:36:37+01:00" level=debug msg="app state loaded" Feb 25 03:36:37 yamaha go-librespot[18898]: time="2026-02-25T03:36:37+01:00" level=debug msg="stored credentials not found" Feb 25 03:36:38 yamaha go-librespot[18898]: time="2026-02-25T03:36:37+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 25 03:36:38 yamaha volumio[18637]: 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:36:38 yamaha volumio[18637]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:36:38 yamaha volumio[18637]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:36:38 yamaha volumio[18637]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:36:38 yamaha volumio[18637]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:36:38 yamaha volumio[18637]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:36:38 yamaha volumio[18637]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:36:38 yamaha volumio[18637]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:36:38 yamaha volumio[18637]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:36:38 yamaha volumio[18637]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:36:38 yamaha volumio[18637]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:36:38 yamaha volumio[18637]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:36:38 yamaha volumio[18637]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:36:38 yamaha volumio[18637]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:36:38 yamaha volumio[18637]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:36:38 yamaha volumio[18637]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:36:38 yamaha volumio[18637]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:36:38 yamaha volumio[18637]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:36:38 yamaha volumio[18637]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:36:38 yamaha volumio[18637]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:36:38 yamaha volumio[18637]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:36:38 yamaha volumio[18637]: info: No need to fix Spotify hosts Feb 25 03:36:38 yamaha go-librespot[18898]: time="2026-02-25T03:36:38+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:36:38 yamaha go-librespot[18898]: time="2026-02-25T03:36:38+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:36:38 yamaha go-librespot[18898]: time="2026-02-25T03:36:38+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:36:38 yamaha go-librespot[18898]: time="2026-02-25T03:36:38+01:00" level=info msg="zeroconf server listening on port 45581" Feb 25 03:36:38 yamaha go-librespot[18898]: time="2026-02-25T03:36:38+01:00" level=debug msg="obtained new client token: AAAhSi0esmMzHJLHd/JJIoIqSYhhkWQp2/bVPBCh7d3m8hLfg5M3df2RLBGSeg1sEgtovEl9DGHjpLvfVIL4h8jxWNhmhYzVrUgMChehUn5oxTV60ER6qKoWhp+To3HbLCc/imakFpOmO1sbd7cvqAXGHrYySu1JDayLDL7tHttrg1LhHFnewJoVxVhOyygcDX6rO5ZhnuYStukxN11lMFrHTt7VZQ7LCLTFOyyzxIHP9lXcwdSzr0ZK" Feb 25 03:36:38 yamaha go-librespot[18898]: time="2026-02-25T03:36:38+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 25 03:36:38 yamaha volumio[18637]: info: New Spotify access tokenBQCm7pnEJo... Feb 25 03:36:38 yamaha volumio[18637]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 25 03:36:38 yamaha volumio[18637]: info: Starting Shairport Sync Feb 25 03:36:38 yamaha volumio[18637]: info: Starting Shairport Sync Feb 25 03:36:38 yamaha volumio[18637]: info: Starting Shairport Sync Feb 25 03:36:38 yamaha go-librespot[18898]: time="2026-02-25T03:36:38+01:00" level=debug msg="completed keyexchange" Feb 25 03:36:38 yamaha go-librespot[18898]: time="2026-02-25T03:36:38+01:00" level=debug msg="completed challenge" Feb 25 03:36:38 yamaha sudo[18921]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 25 03:36:38 yamaha sudo[18921]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:36:38 yamaha sudo[18919]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 25 03:36:38 yamaha sudo[18919]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:36:38 yamaha go-librespot[18898]: time="2026-02-25T03:36:38+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:36:38 yamaha sudo[18923]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 25 03:36:39 yamaha sudo[18923]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:36:39 yamaha systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 25 03:36:39 yamaha systemd[1]: shairport-sync.service: Deactivated successfully. Feb 25 03:36:39 yamaha systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 25 03:36:39 yamaha systemd[1]: shairport-sync.service: Consumed 2.570s CPU time. Feb 25 03:36:39 yamaha volumio[18637]: info: CoreCommandRouter::volumioGetState Feb 25 03:36:39 yamaha volumio[18637]: info: CorePlayQueue::getTrack 0 Feb 25 03:36:39 yamaha systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 25 03:36:39 yamaha sudo[18921]: pam_unix(sudo:session): session closed for user root Feb 25 03:36:39 yamaha sudo[18919]: pam_unix(sudo:session): session closed for user root Feb 25 03:36:39 yamaha sudo[18923]: pam_unix(sudo:session): session closed for user root Feb 25 03:36:39 yamaha volumio[18637]: info: Shairport-Sync Started Feb 25 03:36:39 yamaha volumio[18637]: Error adding Membership: Error: addMembership EINVAL Feb 25 03:36:39 yamaha volumio[18637]: 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:36:39 yamaha volumio[18637]: info: Spotify Successfully logged in Feb 25 03:36:39 yamaha volumio[18637]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 25 03:36:39 yamaha volumio[18637]: info: [1771986999306] CoreMusicLibrary::Adding element Spotify Feb 25 03:36:39 yamaha volumio[18637]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 25 03:36:39 yamaha volumio[18637]: Cannot find translation for source Calm Radio Feb 25 03:36:39 yamaha volumio[18637]: Cannot find translation for source 80s80s Radio Feb 25 03:36:39 yamaha volumio[18637]: Cannot find translation for source Spotify Feb 25 03:36:39 yamaha volumio[18637]: info: Shairport-Sync Started Feb 25 03:36:39 yamaha volumio[18637]: info: Shairport-Sync Started Feb 25 03:36:39 yamaha go-librespot[18898]: time="2026-02-25T03:36:39+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Feb 25 03:36:39 yamaha go-librespot[18898]: time="2026-02-25T03:36:39+01:00" level=debug msg="completed keyexchange" Feb 25 03:36:39 yamaha go-librespot[18898]: time="2026-02-25T03:36:39+01:00" level=debug msg="completed challenge" Feb 25 03:36:39 yamaha go-librespot[18898]: time="2026-02-25T03:36:39+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:36:40 yamaha go-librespot[18898]: time="2026-02-25T03:36:40+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:36:40 yamaha go-librespot[18898]: time="2026-02-25T03:36:40+01:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 25 03:36:40 yamaha go-librespot[18898]: time="2026-02-25T03:36:40+01:00" level=debug msg="completed keyexchange" Feb 25 03:36:40 yamaha go-librespot[18898]: time="2026-02-25T03:36:40+01:00" level=debug msg="completed challenge" Feb 25 03:36:40 yamaha go-librespot[18898]: time="2026-02-25T03:36:40+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:36:41 yamaha go-librespot[18898]: time="2026-02-25T03:36:41+01:00" level=debug msg="connected to ap-gue1.spotify.com:443" Feb 25 03:36:41 yamaha mpd[18871]: 2026-02-25T03:36:41 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 25 03:36:41 yamaha go-librespot[18898]: time="2026-02-25T03:36:41+01:00" level=debug msg="completed keyexchange" Feb 25 03:36:41 yamaha go-librespot[18898]: time="2026-02-25T03:36:41+01:00" level=debug msg="completed challenge" Feb 25 03:36:41 yamaha go-librespot[18898]: time="2026-02-25T03:36:41+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:36:41 yamaha systemd[1]: Started mpd.service - Music Player Daemon. Feb 25 03:36:41 yamaha sudo[18841]: pam_unix(sudo:session): session closed for user root Feb 25 03:36:41 yamaha sudo[18827]: pam_unix(sudo:session): session closed for user root Feb 25 03:36:41 yamaha volumio[18637]: info: Completed starting Core Plugins Feb 25 03:36:41 yamaha volumio[18637]: info: ------------------------------------------- Feb 25 03:36:41 yamaha volumio[18637]: info: ----- MyVolumio plugins startup ---- Feb 25 03:36:41 yamaha volumio[18637]: info: ------------------------------------------- Feb 25 03:36:41 yamaha volumio[18637]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 25 03:36:41 yamaha volumio[18637]: error: MPD error: The expression evaluated to a falsy value: Feb 25 03:36:41 yamaha volumio[18637]: assert.ok(self.idling) Feb 25 03:36:41 yamaha volumio[18637]: error: The expression evaluated to a falsy value: Feb 25 03:36:41 yamaha volumio[18637]: assert.ok(self.idling) Feb 25 03:36:41 yamaha volumio[18637]: error: updateQueue error: null Feb 25 03:36:41 yamaha volumio[18637]: info: MPD running with PID18871 Feb 25 03:36:41 yamaha volumio[18637]: ,establishing connection Feb 25 03:36:41 yamaha volumio[18637]: error: updateQueue error: null Feb 25 03:36:41 yamaha volumio[18637]: info: go-librespot daemon successfully initialized Feb 25 03:36:42 yamaha go-librespot[18898]: time="2026-02-25T03:36:42+01:00" level=debug msg="connected to ap-gae2.spotify.com:80" Feb 25 03:36:42 yamaha volumio[18637]: info: Cannot mount NAS Ugreen at system boot, trial number 4 ,retrying in 5 seconds Feb 25 03:36:42 yamaha go-librespot[18898]: time="2026-02-25T03:36:42+01:00" level=debug msg="completed keyexchange" Feb 25 03:36:42 yamaha go-librespot[18898]: time="2026-02-25T03:36:42+01:00" level=debug msg="completed challenge" Feb 25 03:36:42 yamaha go-librespot[18898]: time="2026-02-25T03:36:42+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:36:43 yamaha go-librespot[18898]: time="2026-02-25T03:36:43+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 25 03:36:43 yamaha go-librespot[18898]: time="2026-02-25T03:36:43+01:00" level=debug msg="completed keyexchange" Feb 25 03:36:43 yamaha go-librespot[18898]: time="2026-02-25T03:36:43+01:00" level=debug msg="completed challenge" Feb 25 03:36:43 yamaha go-librespot[18898]: time="2026-02-25T03:36:43+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:36:43 yamaha go-librespot[18898]: time="2026-02-25T03:36:43+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:36:43 yamaha systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 03:36:43 yamaha systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 03:36:43 yamaha systemd[1]: go-librespot-daemon.service: Consumed 1.048s CPU time. Feb 25 03:36:44 yamaha volumio[18637]: info: Initializing connection to go-librespot Websocket Feb 25 03:36:44 yamaha volumio[18637]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 25 03:36:46 yamaha systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Feb 25 03:36:46 yamaha systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:36:46 yamaha systemd[1]: go-librespot-daemon.service: Consumed 1.048s CPU time. Feb 25 03:36:46 yamaha systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:36:46 yamaha go-librespot[18963]: go-librespot daemon starting... Feb 25 03:36:46 yamaha go-librespot[18964]: time="2026-02-25T03:36:46+01:00" level=info msg="running go-librespot 0.6.2" Feb 25 03:36:46 yamaha go-librespot[18964]: time="2026-02-25T03:36:46+01:00" level=debug msg="app state loaded" Feb 25 03:36:46 yamaha go-librespot[18964]: time="2026-02-25T03:36:46+01:00" level=debug msg="stored credentials not found" Feb 25 03:36:46 yamaha go-librespot[18964]: time="2026-02-25T03:36:46+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 25 03:36:46 yamaha go-librespot[18964]: time="2026-02-25T03:36:46+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 25 03:36:46 yamaha go-librespot[18964]: time="2026-02-25T03:36:46+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:36:46 yamaha go-librespot[18964]: time="2026-02-25T03:36:46+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:36:46 yamaha go-librespot[18964]: time="2026-02-25T03:36:46+01:00" level=info msg="zeroconf server listening on port 40937" Feb 25 03:36:46 yamaha go-librespot[18964]: time="2026-02-25T03:36:46+01:00" level=debug msg="obtained new client token: AACIX94AxeL8K/f+/U6YAT7WP1/2ptNR5gIfekbF3rU7AUCZE0xWIbt6rDED3hlXCUarFUKz3w9wcBE31S4+8q9XF+18vr7jYo4GBsVcQnUkLrW7UfW3SxBg8SGdmW+nlMLIr7Tzs/RdX9XwdrQo7S029u/QGoP8IdIJrCZq54x5dGiVEEpYz7ua9OPNgaiNZtan5ot5or+QO2oARX7O8uLMVx/GWign7Kh58efnZEjs1rqLKro+Jnm5" Feb 25 03:36:46 yamaha volumio[18637]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 25 03:36:46 yamaha go-librespot[18964]: time="2026-02-25T03:36:46+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:36:46 yamaha go-librespot[18964]: time="2026-02-25T03:36:46+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Feb 25 03:36:46 yamaha go-librespot[18964]: time="2026-02-25T03:36:46+01:00" level=debug msg="completed keyexchange" Feb 25 03:36:46 yamaha go-librespot[18964]: time="2026-02-25T03:36:46+01:00" level=debug msg="completed challenge" Feb 25 03:36:47 yamaha go-librespot[18964]: time="2026-02-25T03:36:47+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:36:47 yamaha volumio[18637]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Feb 25 03:36:47 yamaha go-librespot[18964]: time="2026-02-25T03:36:47+01:00" level=debug msg="connected to ap-gew4.spotify.com:80" Feb 25 03:36:47 yamaha go-librespot[18964]: time="2026-02-25T03:36:47+01:00" level=debug msg="completed keyexchange" Feb 25 03:36:47 yamaha go-librespot[18964]: time="2026-02-25T03:36:47+01:00" level=debug msg="completed challenge" Feb 25 03:36:47 yamaha go-librespot[18964]: time="2026-02-25T03:36:47+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:36:48 yamaha volumio[18637]: info: Initializing connection to go-librespot Websocket Feb 25 03:36:48 yamaha go-librespot[18964]: time="2026-02-25T03:36:48+01:00" level=debug msg="new websocket client" Feb 25 03:36:48 yamaha volumio[18637]: info: Connection to go-librespot Websocket established Feb 25 03:36:48 yamaha go-librespot[18964]: time="2026-02-25T03:36:48+01:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 25 03:36:48 yamaha go-librespot[18964]: time="2026-02-25T03:36:48+01:00" level=debug msg="completed keyexchange" Feb 25 03:36:48 yamaha go-librespot[18964]: time="2026-02-25T03:36:48+01:00" level=debug msg="completed challenge" Feb 25 03:36:48 yamaha go-librespot[18964]: time="2026-02-25T03:36:48+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:36:49 yamaha go-librespot[18964]: time="2026-02-25T03:36:49+01:00" level=warning msg="failed to connect to AP ap-gue1.spotify.com:443, retrying with a different AP" error="dial tcp 34.158.255.62:443: connect: connection refused" Feb 25 03:36:49 yamaha go-librespot[18964]: time="2026-02-25T03:36:49+01:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:80, retrying with a different AP" error="dial tcp 104.199.65.9:80: connect: connection refused" Feb 25 03:36:49 yamaha go-librespot[18964]: time="2026-02-25T03:36:49+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 25 03:36:49 yamaha go-librespot[18964]: time="2026-02-25T03:36:49+01:00" level=debug msg="completed keyexchange" Feb 25 03:36:49 yamaha go-librespot[18964]: time="2026-02-25T03:36:49+01:00" level=debug msg="completed challenge" Feb 25 03:36:49 yamaha go-librespot[18964]: time="2026-02-25T03:36:49+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:36:49 yamaha go-librespot[18964]: time="2026-02-25T03:36:49+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Feb 25 03:36:49 yamaha go-librespot[18964]: time="2026-02-25T03:36:49+01:00" level=debug msg="completed keyexchange" Feb 25 03:36:49 yamaha go-librespot[18964]: time="2026-02-25T03:36:49+01:00" level=debug msg="completed challenge" Feb 25 03:36:49 yamaha go-librespot[18964]: time="2026-02-25T03:36:49+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:36:49 yamaha volumio[18637]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 25 03:36:49 yamaha volumio[18637]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 25 03:36:49 yamaha volumio[18637]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 25 03:36:49 yamaha volumio[18637]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 25 03:36:49 yamaha volumio[18637]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 25 03:36:49 yamaha volumio[18637]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 25 03:36:49 yamaha volumio[18637]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 25 03:36:49 yamaha volumio[18637]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 25 03:36:49 yamaha volumio[18637]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 25 03:36:49 yamaha volumio[18637]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 25 03:36:49 yamaha volumio[18637]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 25 03:36:49 yamaha volumio[18637]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 25 03:36:49 yamaha volumio[18637]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 25 03:36:49 yamaha volumio[18637]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 25 03:36:49 yamaha volumio[18637]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 25 03:36:49 yamaha volumio[18637]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 25 03:36:49 yamaha volumio[18637]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 25 03:36:49 yamaha volumio[18637]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 25 03:36:49 yamaha volumio[18637]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 25 03:36:49 yamaha volumio[18637]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 25 03:36:49 yamaha volumio[18637]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 25 03:36:49 yamaha volumio[18637]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 25 03:36:49 yamaha volumio[18637]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 25 03:36:49 yamaha volumio[18637]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 25 03:36:49 yamaha volumio[18637]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 25 03:36:49 yamaha volumio[18637]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 25 03:36:49 yamaha volumio[18637]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 25 03:36:49 yamaha volumio[18637]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 25 03:36:49 yamaha volumio[18637]: info: Adding plugin bluetooth to MyMusic Plugins Feb 25 03:36:49 yamaha volumio[18637]: info: Adding plugin multiroom to MyMusic Plugins Feb 25 03:36:49 yamaha volumio[18637]: info: Adding plugin metavolumio to MyMusic Plugins Feb 25 03:36:49 yamaha volumio[18637]: info: Adding plugin cd_controller to MyMusic Plugins Feb 25 03:36:49 yamaha volumio[18637]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 25 03:36:49 yamaha volumio[18637]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 25 03:36:49 yamaha volumio[18637]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 25 03:36:49 yamaha volumio[18637]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 25 03:36:50 yamaha go-librespot[18964]: time="2026-02-25T03:36:50+01:00" level=debug msg="connected to ap-gew4.spotify.com:80" Feb 25 03:36:50 yamaha go-librespot[18964]: time="2026-02-25T03:36:50+01:00" level=debug msg="completed keyexchange" Feb 25 03:36:50 yamaha go-librespot[18964]: time="2026-02-25T03:36:50+01:00" level=debug msg="completed challenge" Feb 25 03:36:50 yamaha go-librespot[18964]: time="2026-02-25T03:36:50+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:36:50 yamaha go-librespot[18964]: time="2026-02-25T03:36:50+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:36:50 yamaha systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 03:36:50 yamaha systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 03:36:51 yamaha volumio[18637]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 25 03:36:51 yamaha volumio[18637]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 25 03:36:51 yamaha volumio[18637]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:36:51 yamaha volumio[18637]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:36:51 yamaha volumio[18637]: info: Starting MyVolumio Remote Streaming Endpoints Feb 25 03:36:51 yamaha volumio[18637]: info: MyVolumio login type: Token Feb 25 03:36:51 yamaha volumio[18637]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 25 03:36:51 yamaha volumio[18637]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 25 03:36:53 yamaha volumio[18637]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 25 03:36:53 yamaha volumio[18637]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 25 03:36:53 yamaha volumio[18637]: info: Streaming services startup Feb 25 03:36:53 yamaha volumio[18637]: info: Starting Streaming Daemon Feb 25 03:36:53 yamaha sudo[18990]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 25 03:36:53 yamaha volumio[18637]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 25 03:36:53 yamaha sudo[18990]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:36:53 yamaha systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Feb 25 03:36:53 yamaha sudo[18990]: pam_unix(sudo:session): session closed for user root Feb 25 03:36:53 yamaha systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:36:53 yamaha systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:36:53 yamaha go-librespot[18996]: go-librespot daemon starting... Feb 25 03:36:53 yamaha volumio[18637]: info: Getting Spotify volume Feb 25 03:36:53 yamaha go-librespot[18997]: time="2026-02-25T03:36:53+01:00" level=info msg="running go-librespot 0.6.2" Feb 25 03:36:53 yamaha go-librespot[18997]: time="2026-02-25T03:36:53+01:00" level=debug msg="app state loaded" Feb 25 03:36:53 yamaha go-librespot[18997]: time="2026-02-25T03:36:53+01:00" level=debug msg="stored credentials not found" Feb 25 03:36:53 yamaha volumio[18637]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 25 03:36:53 yamaha go-librespot[18997]: time="2026-02-25T03:36:53+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 25 03:36:53 yamaha volumio[18637]: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 25 03:36:53 yamaha volumio[18637]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Feb 25 03:36:53 yamaha volumio[18637]: errno: -111, Feb 25 03:36:53 yamaha volumio[18637]: code: 'ECONNREFUSED', Feb 25 03:36:53 yamaha volumio[18637]: syscall: 'connect', Feb 25 03:36:53 yamaha volumio[18637]: address: '127.0.0.1', Feb 25 03:36:53 yamaha volumio[18637]: port: 9879, Feb 25 03:36:53 yamaha volumio[18637]: response: undefined Feb 25 03:36:53 yamaha volumio[18637]: } Feb 25 03:36:53 yamaha volumio[18637]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 25 03:36:54 yamaha go-librespot[18997]: time="2026-02-25T03:36:54+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:36:54 yamaha go-librespot[18997]: time="2026-02-25T03:36:54+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:36:54 yamaha go-librespot[18997]: time="2026-02-25T03:36:54+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:36:54 yamaha go-librespot[18997]: time="2026-02-25T03:36:54+01:00" level=info msg="zeroconf server listening on port 46569" Feb 25 03:36:54 yamaha go-librespot[18997]: time="2026-02-25T03:36:54+01:00" level=debug msg="obtained new client token: AAA1ItuhDE8C1lb3JsRFpob0gOxcVe2jA9P0UR3qj7yBfH/EtHXZrKscSzYzCiHdLYknrYbwyelDHozQDzMaKgp5QGX3l4h2A/Xq552DGv+1fQ6tD4DgKrVHWp7IG9zCt6B6IJr3ccZ2/KhufxRTuEVA72A5mxJLiAz42+09zJQdIB6LszIHIqzjRt6GS1EsyhaJcGAht4EFO+Evv5vBjyMUZIFSuebfqMfZ3UusC63U0RCWT4xcwDaY" Feb 25 03:36:54 yamaha go-librespot[18997]: time="2026-02-25T03:36:54+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:36:54 yamaha go-librespot[18997]: time="2026-02-25T03:36:54+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Feb 25 03:36:54 yamaha go-librespot[18997]: time="2026-02-25T03:36:54+01:00" level=debug msg="completed keyexchange" Feb 25 03:36:54 yamaha go-librespot[18997]: time="2026-02-25T03:36:54+01:00" level=debug msg="completed challenge" Feb 25 03:36:54 yamaha go-librespot[18997]: time="2026-02-25T03:36:54+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:36:55 yamaha go-librespot[18997]: time="2026-02-25T03:36:55+01:00" level=debug msg="connected to ap-gew4.spotify.com:80" Feb 25 03:36:55 yamaha go-librespot[18997]: time="2026-02-25T03:36:55+01:00" level=debug msg="completed keyexchange" Feb 25 03:36:55 yamaha go-librespot[18997]: time="2026-02-25T03:36:55+01:00" level=debug msg="completed challenge" Feb 25 03:36:55 yamaha go-librespot[18997]: time="2026-02-25T03:36:55+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 03:36:55 yamaha sudo[19021]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-25 03:35' Feb 25 03:36:55 yamaha sudo[19021]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="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"