Feb 17 22:57:00 volumio volumio[7382]: info: Discovery: this is already registered, 921648ed-0557-4d70-9927-1420682efcc7 Feb 17 22:57:00 volumio volumio[7382]: info: Discovery: Found device Volumio Feb 17 22:57:00 volumio volumio[7382]: info: CoreCommandRouter::volumioGetState Feb 17 22:57:00 volumio volumio[7382]: info: CorePlayQueue::getTrack 0 Feb 17 22:57:00 volumio volumio[7382]: info: MPD Permissions set Feb 17 22:57:00 volumio volumio[7382]: info: MPD Permissions set Feb 17 22:57:00 volumio volumio[7382]: info: Upmpdcli Daemon Started Feb 17 22:57:00 volumio volumio[7382]: info: Volumio called home Feb 17 22:57:00 volumio volumio[7382]: info: Spotify config file written Feb 17 22:57:00 volumio sudo[7617]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 17 22:57:00 volumio sudo[7617]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 22:57:00 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 22:57:00 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 22:57:00 volumio go-librespot[7619]: go-librespot daemon starting... Feb 17 22:57:00 volumio sudo[7617]: pam_unix(sudo:session): session closed for user root Feb 17 22:57:00 volumio go-librespot[7623]: time="2026-02-17T22:57:00+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 22:57:00 volumio go-librespot[7623]: time="2026-02-17T22:57:00+07:00" level=debug msg="app state loaded" Feb 17 22:57:00 volumio go-librespot[7623]: time="2026-02-17T22:57:00+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 22:57:00 volumio volumio[7382]: 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 17 22:57:00 volumio volumio[7382]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 22:57:00 volumio volumio[7382]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 22:57:00 volumio volumio[7382]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 22:57:00 volumio volumio[7382]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 22:57:00 volumio volumio[7382]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 22:57:00 volumio volumio[7382]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 22:57:00 volumio volumio[7382]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 22:57:00 volumio volumio[7382]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 22:57:01 volumio volumio[7382]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 22:57:01 volumio volumio[7382]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 22:57:01 volumio volumio[7382]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 22:57:01 volumio volumio[7382]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 22:57:01 volumio volumio[7382]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 22:57:01 volumio volumio[7382]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 22:57:01 volumio volumio[7382]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 22:57:01 volumio volumio[7382]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 22:57:01 volumio volumio[7382]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 22:57:01 volumio volumio[7382]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 22:57:01 volumio volumio[7382]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 22:57:01 volumio volumio[7382]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 22:57:01 volumio volumio[7382]: info: No need to fix Spotify hosts Feb 17 22:57:01 volumio go-librespot[7623]: time="2026-02-17T22:57:01+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 17 22:57:01 volumio go-librespot[7623]: time="2026-02-17T22:57:01+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 17 22:57:01 volumio go-librespot[7623]: time="2026-02-17T22:57:01+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 17 22:57:01 volumio go-librespot[7623]: time="2026-02-17T22:57:01+07:00" level=info msg="zeroconf server listening on port 45653" Feb 17 22:57:01 volumio go-librespot[7623]: time="2026-02-17T22:57:01+07:00" level=debug msg="obtained new client token: AACHTfdJ8r8ButrCwKMWU7vn0fr7ZnjhImuJ5WofWsvzxH8FkSF7dZWCLUBmKCjELIeimlgWeqFXAC6ub8HSDBOlWXUlabhunNLy1pGXZ6elBZXaVxrrF11pnRVY98ncuRygYUn/1HlxCyPTfMJpt0IqxV2JqD0cVG7JeMDDVQmc5LnG4X5a583z7HwSH9f5k+rIdSrcVXn6ENFIyjIy94JlqpXq67219prQy3EN/sHG2V5FUwbY0CnZhA==" Feb 17 22:57:01 volumio go-librespot[7623]: time="2026-02-17T22:57:01+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 17 22:57:01 volumio volumio[7382]: info: Starting Shairport Sync Feb 17 22:57:01 volumio volumio[7382]: info: Starting Shairport Sync Feb 17 22:57:01 volumio go-librespot[7623]: time="2026-02-17T22:57:01+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Feb 17 22:57:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 22:57:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 22:57:01 volumio volumio[7382]: info: Starting Shairport Sync Feb 17 22:57:01 volumio sudo[7657]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 17 22:57:01 volumio sudo[7657]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 22:57:01 volumio sudo[7660]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 17 22:57:01 volumio sudo[7660]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 22:57:01 volumio sudo[7661]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 17 22:57:01 volumio sudo[7661]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 22:57:01 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 17 22:57:01 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 17 22:57:01 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 17 22:57:01 volumio systemd[1]: shairport-sync.service: Consumed 2.206s CPU time. Feb 17 22:57:02 volumio volumio[7382]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 17 22:57:02 volumio volumio[7382]: SPOTIFY: BQCpijz7FZycV7sBsMD2vZpQE2wMVRmktMmMo7IxjUJ7JgSz-2ukeG4AUbVSRY7x3zL5nDf1IYy4VCqhde0qh_PS5nK9apTRyPVI2Om5EgC1Sx9K3lmMTHhLRlG5Y2NcA7yhmRDsKxZ0yxCmyH1tSoHysSN_QOFfGwudkmwVLdDotuE2GjVsI9Jj3zFHi9WSmdySL2EujCafwWced-Z1wBXdxB5GoZFttbhOjHPV8ztvIcvzQtO0SJcBI98qRcusfNse4tNIoxxVNAbVeJdgXoh5Zq-5T5E_95IdSyfSxZyaty2_9Wzbpn5q Feb 17 22:57:02 volumio volumio[7382]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 17 22:57:02 volumio volumio[7382]: info: New Spotify access token = BQCpijz7FZycV7sBsMD2vZpQE2wMVRmktMmMo7IxjUJ7JgSz-2ukeG4AUbVSRY7x3zL5nDf1IYy4VCqhde0qh_PS5nK9apTRyPVI2Om5EgC1Sx9K3lmMTHhLRlG5Y2NcA7yhmRDsKxZ0yxCmyH1tSoHysSN_QOFfGwudkmwVLdDotuE2GjVsI9Jj3zFHi9WSmdySL2EujCafwWced-Z1wBXdxB5GoZFttbhOjHPV8ztvIcvzQtO0SJcBI98qRcusfNse4tNIoxxVNAbVeJdgXoh5Zq-5T5E_95IdSyfSxZyaty2_9Wzbpn5q Feb 17 22:57:02 volumio volumio[7382]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 17 22:57:02 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 17 22:57:02 volumio sudo[7660]: pam_unix(sudo:session): session closed for user root Feb 17 22:57:02 volumio sudo[7657]: pam_unix(sudo:session): session closed for user root Feb 17 22:57:02 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 17 22:57:02 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 17 22:57:02 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 17 22:57:02 volumio volumio[7382]: info: Shairport-Sync Started Feb 17 22:57:02 volumio volumio[7382]: Error adding Membership: Error: addMembership EINVAL Feb 17 22:57:02 volumio volumio[7382]: info: Shairport-Sync Started Feb 17 22:57:02 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 17 22:57:02 volumio sudo[7661]: pam_unix(sudo:session): session closed for user root Feb 17 22:57:02 volumio volumio[7382]: info: CoreCommandRouter::volumioGetState Feb 17 22:57:02 volumio volumio[7382]: info: CorePlayQueue::getTrack 0 Feb 17 22:57:02 volumio volumio[7382]: info: Shairport-Sync Started Feb 17 22:57:02 volumio volumio[7382]: SPOTIFY: User informations: {"country":"PK","display_name":"215mmgea6kss3jxeqsqdquvji","email":"linh.ngotran@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/215mmgea6kss3jxeqsqdquvji"},"followers":{"href":null,"total":1},"href":"https://api.spotify.com/v1/users/215mmgea6kss3jxeqsqdquvji","id":"215mmgea6kss3jxeqsqdquvji","images":[],"product":"premium","type":"user","uri":"spotify:user:215mmgea6kss3jxeqsqdquvji"} Feb 17 22:57:02 volumio volumio[7382]: info: Spotify Successfully logged in Feb 17 22:57:02 volumio volumio[7382]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 17 22:57:02 volumio volumio[7382]: info: [1771343822684] CoreMusicLibrary::Adding element Spotify Feb 17 22:57:02 volumio volumio[7382]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 22:57:02 volumio volumio[7382]: Cannot find translation for source YouTube Music Feb 17 22:57:02 volumio volumio[7382]: Cannot find translation for source Spotify Feb 17 22:57:03 volumio volumio[7382]: info: [yt-cast-receiver] DIAL server listening on port 8098 Feb 17 22:57:03 volumio volumio[7382]: info: CoreCommandRouter::volumioRetrievevolume Feb 17 22:57:03 volumio volumio[7382]: info: VolumeController:: Volume=100 Mute =false Feb 17 22:57:03 volumio volumio[7382]: info: CoreCommandRouter::volumioGetState Feb 17 22:57:03 volumio volumio[7382]: info: CorePlayQueue::getTrack 0 Feb 17 22:57:03 volumio volumio[7382]: info: CoreStateMachine::pushState Feb 17 22:57:03 volumio volumio[7382]: info: CorePlayQueue::getTrack 0 Feb 17 22:57:03 volumio volumio[7382]: info: CoreCommandRouter::volumioPushState Feb 17 22:57:04 volumio volumio[7382]: info: go-librespot daemon successfully initialized Feb 17 22:57:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 26. Feb 17 22:57:04 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 22:57:04 volumio mpd[7607]: 2026-02-17T22:57:04 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 17 22:57:04 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 22:57:04 volumio go-librespot[7698]: go-librespot daemon starting... Feb 17 22:57:04 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 17 22:57:04 volumio sudo[7567]: pam_unix(sudo:session): session closed for user root Feb 17 22:57:04 volumio sudo[7578]: pam_unix(sudo:session): session closed for user root Feb 17 22:57:04 volumio go-librespot[7701]: time="2026-02-17T22:57:04+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 22:57:04 volumio go-librespot[7701]: time="2026-02-17T22:57:04+07:00" level=debug msg="app state loaded" Feb 17 22:57:04 volumio go-librespot[7701]: time="2026-02-17T22:57:04+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 22:57:05 volumio volumio[7382]: error: MPD error: The expression evaluated to a falsy value: Feb 17 22:57:05 volumio volumio[7382]: assert.ok(self.idling) Feb 17 22:57:05 volumio volumio[7382]: error: The expression evaluated to a falsy value: Feb 17 22:57:05 volumio volumio[7382]: assert.ok(self.idling) Feb 17 22:57:05 volumio volumio[7382]: info: MPD running with PID7607 Feb 17 22:57:05 volumio volumio[7382]: ,establishing connection Feb 17 22:57:05 volumio volumio[7382]: error: updateQueue error: null Feb 17 22:57:05 volumio volumio[7382]: info: Completed starting Core Plugins Feb 17 22:57:05 volumio volumio[7382]: info: ------------------------------------------- Feb 17 22:57:05 volumio volumio[7382]: info: ----- MyVolumio plugins startup ---- Feb 17 22:57:05 volumio volumio[7382]: info: ------------------------------------------- Feb 17 22:57:05 volumio volumio[7382]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 17 22:57:05 volumio volumio[7382]: error: updateQueue error: null Feb 17 22:57:05 volumio go-librespot[7701]: time="2026-02-17T22:57:05+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 17 22:57:05 volumio go-librespot[7701]: time="2026-02-17T22:57:05+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 17 22:57:05 volumio go-librespot[7701]: time="2026-02-17T22:57:05+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 17 22:57:05 volumio go-librespot[7701]: time="2026-02-17T22:57:05+07:00" level=info msg="zeroconf server listening on port 33275" Feb 17 22:57:05 volumio go-librespot[7701]: time="2026-02-17T22:57:05+07:00" level=debug msg="obtained new client token: AADxilF5Li8u/RQt6MDYuLHVN/Q1vQIMv+gxRY9IR180kgwuoOJghVcLpZRynXbImmXswcs7opryIxSxzeKBJUXOHa9XJJmcSJ5iA84mPCtXYM4l5IzPut5T9Z4ormTL9vdfFIUbSmlM4/zRvG3VGG+XVbkUB9sUBSuOCDyyw6fQUfmJId5mXj069qsWnPsDGe3owPyonmZgY3Mv/ScaKITHj2IPr+7gIw5fejapYUDhIb/H1dAhpoHEfw==" Feb 17 22:57:05 volumio go-librespot[7701]: time="2026-02-17T22:57:05+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 17 22:57:06 volumio go-librespot[7701]: time="2026-02-17T22:57:06+07:00" level=debug msg="completed keyexchange" Feb 17 22:57:06 volumio go-librespot[7701]: time="2026-02-17T22:57:06+07:00" level=debug msg="completed challenge" Feb 17 22:57:06 volumio go-librespot[7701]: time="2026-02-17T22:57:06+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 17 22:57:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 22:57:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 22:57:07 volumio volumio[7382]: info: Initializing connection to go-librespot Websocket Feb 17 22:57:07 volumio volumio[7382]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 17 22:57:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 27. Feb 17 22:57:09 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 22:57:09 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 22:57:09 volumio go-librespot[7712]: go-librespot daemon starting... Feb 17 22:57:09 volumio go-librespot[7713]: time="2026-02-17T22:57:09+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 22:57:09 volumio go-librespot[7713]: time="2026-02-17T22:57:09+07:00" level=debug msg="app state loaded" Feb 17 22:57:09 volumio go-librespot[7713]: time="2026-02-17T22:57:09+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 22:57:09 volumio go-librespot[7713]: time="2026-02-17T22:57:09+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 17 22:57:09 volumio go-librespot[7713]: time="2026-02-17T22:57:09+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 17 22:57:09 volumio go-librespot[7713]: time="2026-02-17T22:57:09+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 17 22:57:09 volumio go-librespot[7713]: time="2026-02-17T22:57:09+07:00" level=info msg="zeroconf server listening on port 45499" Feb 17 22:57:10 volumio volumio[7382]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 17 22:57:10 volumio go-librespot[7713]: time="2026-02-17T22:57:10+07:00" level=debug msg="obtained new client token: AADqpqie1D/PU/MLvEh2GeDRnLIpzgSuyojfVvdrr1a8GfQh/R95wd9vZhyO3kBEODHR/nwX3V+9ZtxLzk2t4nsbo95Vxda/h2OX0b2lOqeloc5lnFzPUzPeJbP7K3PDl+fBXQoeLktrn7Rttwfk+pDousl2tDXBYPY7awz8a8bRrShKXsngSjoml5snnlOoNbSgWuk7wtrR33wItrMjEaOLbSfyAHku7NRAC6viUsOmIG3+FJsMeoc=" Feb 17 22:57:10 volumio go-librespot[7713]: time="2026-02-17T22:57:10+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 17 22:57:10 volumio go-librespot[7713]: time="2026-02-17T22:57:10+07:00" level=debug msg="completed keyexchange" Feb 17 22:57:10 volumio go-librespot[7713]: time="2026-02-17T22:57:10+07:00" level=debug msg="completed challenge" Feb 17 22:57:10 volumio go-librespot[7713]: time="2026-02-17T22:57:10+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 17 22:57:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 22:57:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 22:57:10 volumio volumio[7382]: info: Initializing connection to go-librespot Websocket Feb 17 22:57:10 volumio volumio[7382]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 17 22:57:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 28. Feb 17 22:57:13 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 22:57:13 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 22:57:13 volumio go-librespot[7722]: go-librespot daemon starting... Feb 17 22:57:13 volumio go-librespot[7723]: time="2026-02-17T22:57:13+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 22:57:13 volumio go-librespot[7723]: time="2026-02-17T22:57:13+07:00" level=debug msg="app state loaded" Feb 17 22:57:13 volumio go-librespot[7723]: time="2026-02-17T22:57:13+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 22:57:13 volumio volumio[7382]: info: Initializing connection to go-librespot Websocket Feb 17 22:57:13 volumio go-librespot[7723]: time="2026-02-17T22:57:13+07:00" level=debug msg="new websocket client" Feb 17 22:57:13 volumio volumio[7382]: info: Connection to go-librespot Websocket established Feb 17 22:57:14 volumio volumio[7382]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 17 22:57:14 volumio volumio[7382]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 17 22:57:14 volumio volumio[7382]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 17 22:57:14 volumio volumio[7382]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 17 22:57:14 volumio volumio[7382]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 17 22:57:14 volumio volumio[7382]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 17 22:57:14 volumio volumio[7382]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 17 22:57:14 volumio volumio[7382]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 17 22:57:14 volumio volumio[7382]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 17 22:57:14 volumio volumio[7382]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 17 22:57:14 volumio volumio[7382]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 17 22:57:14 volumio volumio[7382]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 17 22:57:14 volumio volumio[7382]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 17 22:57:14 volumio volumio[7382]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 17 22:57:14 volumio volumio[7382]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 17 22:57:14 volumio volumio[7382]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 17 22:57:14 volumio volumio[7382]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 17 22:57:14 volumio volumio[7382]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 17 22:57:14 volumio volumio[7382]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 17 22:57:14 volumio volumio[7382]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 17 22:57:14 volumio volumio[7382]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 17 22:57:14 volumio volumio[7382]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 17 22:57:14 volumio volumio[7382]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 17 22:57:14 volumio volumio[7382]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 17 22:57:14 volumio volumio[7382]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 17 22:57:14 volumio volumio[7382]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 17 22:57:14 volumio volumio[7382]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 17 22:57:14 volumio volumio[7382]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 17 22:57:14 volumio go-librespot[7723]: time="2026-02-17T22:57:14+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 17 22:57:14 volumio go-librespot[7723]: time="2026-02-17T22:57:14+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 17 22:57:14 volumio go-librespot[7723]: time="2026-02-17T22:57:14+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 17 22:57:14 volumio volumio[7382]: info: Adding plugin bluetooth to MyMusic Plugins Feb 17 22:57:14 volumio volumio[7382]: info: Adding plugin multiroom to MyMusic Plugins Feb 17 22:57:14 volumio volumio[7382]: info: Adding plugin metavolumio to MyMusic Plugins Feb 17 22:57:14 volumio volumio[7382]: info: Adding plugin cd_controller to MyMusic Plugins Feb 17 22:57:14 volumio volumio[7382]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 17 22:57:14 volumio volumio[7382]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 17 22:57:14 volumio volumio[7382]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 17 22:57:14 volumio volumio[7382]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 17 22:57:14 volumio go-librespot[7723]: time="2026-02-17T22:57:14+07:00" level=info msg="zeroconf server listening on port 44099" Feb 17 22:57:14 volumio go-librespot[7723]: time="2026-02-17T22:57:14+07:00" level=debug msg="obtained new client token: AADvWIDaF9B36xCk39RUJmsvzEH+0c7rWPrzwkaFd1E+gZup1/CZdoQpYQy0O3GQ4ZE1FPX10SSfo/O3Dbljcuicd4R8K6UUlcI/GnfxjGEs/CRYw3u4Z/xfcODxQnyfYgfi/QaQIMKOTC4YPr4ipQ+8LMfzkSgl+JqHQzIjfih+WT5xIJqV2uRTVmlyUtzYCb2EJee0/66JiNq9HtA0DcOsUcLNTIwa4G+i0HGH2JmFZrtKkjUf7kocAQ==" Feb 17 22:57:14 volumio go-librespot[7723]: time="2026-02-17T22:57:14+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 17 22:57:14 volumio go-librespot[7723]: time="2026-02-17T22:57:14+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Feb 17 22:57:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 22:57:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 22:57:15 volumio volumio[7382]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 17 22:57:15 volumio volumio[7382]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 17 22:57:15 volumio volumio[7382]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 22:57:15 volumio volumio[7382]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 22:57:16 volumio volumio[7382]: info: Starting MyVolumio Remote Streaming Endpoints Feb 17 22:57:16 volumio volumio[7382]: info: MyVolumio login type: Token Feb 17 22:57:16 volumio volumio[7382]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 17 22:57:16 volumio volumio[7382]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 17 22:57:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 29. Feb 17 22:57:17 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 22:57:17 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 22:57:17 volumio go-librespot[7744]: go-librespot daemon starting... Feb 17 22:57:17 volumio go-librespot[7745]: time="2026-02-17T22:57:17+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 22:57:17 volumio go-librespot[7745]: time="2026-02-17T22:57:17+07:00" level=debug msg="app state loaded" Feb 17 22:57:17 volumio go-librespot[7745]: time="2026-02-17T22:57:17+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 22:57:17 volumio volumio[7382]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 17 22:57:17 volumio volumio[7382]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 17 22:57:17 volumio volumio[7382]: info: Streaming services startup Feb 17 22:57:17 volumio volumio[7382]: info: Starting Streaming Daemon Feb 17 22:57:17 volumio sudo[7752]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 17 22:57:17 volumio volumio[7382]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 17 22:57:17 volumio sudo[7752]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 22:57:18 volumio sudo[7752]: pam_unix(sudo:session): session closed for user root Feb 17 22:57:18 volumio volumio[7382]: info: Getting Spotify volume Feb 17 22:57:18 volumio volumio[7382]: info: Connection to go-librespot Websocket closed Feb 17 22:57:18 volumio volumio[7382]: error: Cannot start Volumio Streaming Daemon Feb 17 22:57:18 volumio volumio[7382]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 17 22:57:18 volumio volumio[7382]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 17 22:57:18 volumio volumio[7382]: 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 17 22:57:18 volumio go-librespot[7745]: time="2026-02-17T22:57:18+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 17 22:57:18 volumio go-librespot[7745]: time="2026-02-17T22:57:18+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 17 22:57:18 volumio go-librespot[7745]: time="2026-02-17T22:57:18+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 17 22:57:18 volumio go-librespot[7745]: time="2026-02-17T22:57:18+07:00" level=info msg="zeroconf server listening on port 42705" Feb 17 22:57:18 volumio volumio[7382]: info: CoreCommandRouter::volumioGetState Feb 17 22:57:18 volumio volumio[7382]: info: CorePlayQueue::getTrack 0 Feb 17 22:57:18 volumio volumio[7382]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 17 22:57:18 volumio volumio[7382]: SPOTIFY: SPOTIFY VOLUME undefined Feb 17 22:57:18 volumio volumio[7382]: SPOTIFY: VOLUMIO VOLUME 100 Feb 17 22:57:18 volumio volumio[7382]: info: Aligning Spotify Volume to Volumio Volume Feb 17 22:57:18 volumio volumio[7382]: info: CoreCommandRouter::volumioGetState Feb 17 22:57:18 volumio volumio[7382]: info: CorePlayQueue::getTrack 0 Feb 17 22:57:18 volumio volumio[7382]: info: Setting Spotify Volume from Volumio: 100 Feb 17 22:57:18 volumio go-librespot[7745]: time="2026-02-17T22:57:18+07:00" level=debug msg="obtained new client token: AADi2cxZ58ZKM541WfjExu11lX/0YbgTwczSvtTK1913z34Rt+uDVfI5CDhHmtkb5YD8eUUL3foaa9Y0LEqE16bNOLg1dGAmvldUTX7zr0h1brFW9nxn8kIyrrHG7Ua6rqEdJ/bt6ZsltvWaj4w++wAI4f8hfo3MyYH6g/ReRDlrDg2n4cOmU+Q+OZYhgon2mXk31d6UoLgr5faHV3v2E7fmdMPkN/uMr9s/6uC2U+FV6K8c7hxClenyGQ==" Feb 17 22:57:18 volumio go-librespot[7745]: time="2026-02-17T22:57:18+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 17 22:57:18 volumio go-librespot[7745]: time="2026-02-17T22:57:18+07:00" level=debug msg="completed keyexchange" Feb 17 22:57:18 volumio go-librespot[7745]: time="2026-02-17T22:57:18+07:00" level=debug msg="completed challenge" Feb 17 22:57:18 volumio go-librespot[7745]: time="2026-02-17T22:57:18+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 17 22:57:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 22:57:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 22:57:18 volumio volumio[7382]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 17 22:57:18 volumio volumio[7382]: Error: socket hang up Feb 17 22:57:18 volumio volumio[7382]: at connResetException (node:internal/errors:720:14) Feb 17 22:57:18 volumio volumio[7382]: at Socket.socketOnEnd (node:_http_client:519:23) Feb 17 22:57:18 volumio volumio[7382]: at Socket.emit (node:events:526:35) Feb 17 22:57:18 volumio volumio[7382]: at endReadableNT (node:internal/streams/readable:1376:12) Feb 17 22:57:18 volumio volumio[7382]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Feb 17 22:57:18 volumio volumio[7382]: code: 'ECONNRESET', Feb 17 22:57:18 volumio volumio[7382]: response: undefined Feb 17 22:57:18 volumio volumio[7382]: } Feb 17 22:57:18 volumio volumio[7382]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 17 22:57:20 volumio sudo[7773]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-17 22:56' Feb 17 22:57:20 volumio sudo[7773]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 22:57:20 volumio sudo[7773]: pam_unix(sudo:session): session closed for user root Feb 17 22:57:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 30. Feb 17 22:57:21 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 22:57:21 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 22:57:21 volumio go-librespot[7781]: go-librespot daemon starting... Feb 17 22:57:21 volumio go-librespot[7782]: time="2026-02-17T22:57:21+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 22:57:21 volumio go-librespot[7782]: time="2026-02-17T22:57:21+07:00" level=debug msg="app state loaded" Feb 17 22:57:21 volumio go-librespot[7782]: time="2026-02-17T22:57:21+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 22:57:22 volumio go-librespot[7782]: time="2026-02-17T22:57:22+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 17 22:57:22 volumio go-librespot[7782]: time="2026-02-17T22:57:22+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 17 22:57:22 volumio go-librespot[7782]: time="2026-02-17T22:57:22+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 17 22:57:22 volumio go-librespot[7782]: time="2026-02-17T22:57:22+07:00" level=info msg="zeroconf server listening on port 39385" Feb 17 22:57:22 volumio go-librespot[7782]: time="2026-02-17T22:57:22+07:00" level=debug msg="obtained new client token: AAAWg1atGmmBP8eCq9+CBGASjqxFnDeWbZfX0hbZtJBKsQFx68I6gXl04MVJxD7gdXwXhKj7h+U4dQGZ2drJfuDhA/UbsxvhM6ISTjzl78sTEExj21AOKZFDLTsolimT0l7brv9KWOIFsK5+XWV8PaypnDxoDJg+KoHcVJBlOleQl7O1AIELNn7qnF+Ox/f2MlaP0pe+Ac8W27+vufrpiGubsB8sS6yHtj0ODH673qLkrnj242SNheho3w==" Feb 17 22:57:22 volumio go-librespot[7782]: time="2026-02-17T22:57:22+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 17 22:57:22 volumio go-librespot[7782]: time="2026-02-17T22:57:22+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Feb 17 22:57:22 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 22:57:22 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 22:57:26 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 31. Feb 17 22:57:26 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 22:57:26 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 22:57:26 volumio go-librespot[7803]: go-librespot daemon starting... Feb 17 22:57:26 volumio go-librespot[7804]: time="2026-02-17T22:57:26+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 22:57:26 volumio go-librespot[7804]: time="2026-02-17T22:57:26+07:00" level=debug msg="app state loaded" Feb 17 22:57:26 volumio go-librespot[7804]: time="2026-02-17T22:57:26+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 22:57:26 volumio go-librespot[7804]: time="2026-02-17T22:57:26+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 17 22:57:26 volumio go-librespot[7804]: time="2026-02-17T22:57:26+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 17 22:57:26 volumio go-librespot[7804]: time="2026-02-17T22:57:26+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 17 22:57:26 volumio go-librespot[7804]: time="2026-02-17T22:57:26+07:00" level=info msg="zeroconf server listening on port 43079" Feb 17 22:57:26 volumio go-librespot[7804]: time="2026-02-17T22:57:26+07:00" level=debug msg="obtained new client token: AACxpDAlTSzuFU70/cUCpIhF4LcXQ7OnGHMiOwkKipUTuixR8SAs9RB9JwIQDBf81e++wiVvDmfLMyDaEEg+t43z4hvhnDQ7Q8e3DYBtjXwD+HnV86ZZ7XTolTQEkHPbTfGwbDftyPakfeDYCat2cIBCkutV60cMQYxZQf7Q/9d1kh+IRkWVHO+f5/FEONNA852i/mxRj/Qky91fmQbgbJ8i8119eaD8DTcVinQ1lVQJl/YxZPk+m6Rz+A==" Feb 17 22:57:27 volumio go-librespot[7804]: time="2026-02-17T22:57:27+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 17 22:57:27 volumio go-librespot[7804]: time="2026-02-17T22:57:27+07:00" level=debug msg="completed keyexchange" Feb 17 22:57:27 volumio go-librespot[7804]: time="2026-02-17T22:57:27+07:00" level=debug msg="completed challenge" Feb 17 22:57:27 volumio go-librespot[7804]: time="2026-02-17T22:57:27+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 17 22:57:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 22:57:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 22:57:30 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 32. Feb 17 22:57:30 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 22:57:30 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 22:57:30 volumio go-librespot[7811]: go-librespot daemon starting... Feb 17 22:57:30 volumio go-librespot[7812]: time="2026-02-17T22:57:30+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 22:57:30 volumio go-librespot[7812]: time="2026-02-17T22:57:30+07:00" level=debug msg="app state loaded" Feb 17 22:57:30 volumio go-librespot[7812]: time="2026-02-17T22:57:30+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 22:57:30 volumio go-librespot[7812]: time="2026-02-17T22:57:30+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 17 22:57:30 volumio go-librespot[7812]: time="2026-02-17T22:57:30+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 17 22:57:30 volumio go-librespot[7812]: time="2026-02-17T22:57:30+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 17 22:57:30 volumio go-librespot[7812]: time="2026-02-17T22:57:30+07:00" level=info msg="zeroconf server listening on port 44287" Feb 17 22:57:31 volumio go-librespot[7812]: time="2026-02-17T22:57:31+07:00" level=debug msg="obtained new client token: AAAe+6MUOO11637uwJm9Ju5bDGhylg+A7S2y2l/5irjwa1Ru3EGRV9o0UUSGaAmV67ZV40Ucx1R7EFuY12NujCbuq6rN6nCGukuSACaBqiGodrs4vPbH7wHgy8FCwgqiWRB9VKl/FbfunR1Z8SFo7FIWv5tjjsawS7bclwDWBc9xhM4X+gccvXL4XuaQQJ202M9pGr6oSdk8SubFg1pd90HSbeewKf/1YuxM2qUD3Q4PDUvC13uewow=" Feb 17 22:57:31 volumio volumio-remote-updater[25144]: [2026-02-17 22:57:31] [error] handle_read_frame error: asio.system:104 (Connection reset by peer) Feb 17 22:57:31 volumio volumio-remote-updater[25144]: [2026-02-17 22:57:31] [disconnect] Disconnect close local:[1006,Connection reset by peer] remote:[1006] Feb 17 22:57:31 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 17 22:57:31 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 17 22:57:31 volumio systemd[1]: volumio.service: Consumed 56.126s CPU time. Feb 17 22:57:31 volumio go-librespot[7812]: time="2026-02-17T22:57:31+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 17 22:57:31 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 17 22:57:31 volumio go-librespot[7812]: time="2026-02-17T22:57:31+07:00" level=debug msg="completed keyexchange" Feb 17 22:57:31 volumio go-librespot[7812]: time="2026-02-17T22:57:31+07:00" level=debug msg="completed challenge" Feb 17 22:57:31 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 17 22:57:31 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 21027. Feb 17 22:57:31 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 17 22:57:31 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 17 22:57:31 volumio systemd[1]: volumio.service: Consumed 56.126s CPU time. Feb 17 22:57:31 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Feb 17 22:57:31 volumio go-librespot[7812]: time="2026-02-17T22:57:31+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 17 22:57:31 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 17 22:57:31 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 22:57:31 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 22:57:34 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 33. Feb 17 22:57:34 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 22:57:34 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 22:57:34 volumio go-librespot[7849]: go-librespot daemon starting... Feb 17 22:57:34 volumio go-librespot[7850]: time="2026-02-17T22:57:34+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 22:57:34 volumio go-librespot[7850]: time="2026-02-17T22:57:34+07:00" level=debug msg="app state loaded" Feb 17 22:57:34 volumio go-librespot[7850]: time="2026-02-17T22:57:34+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 22:57:35 volumio volumio[7834]: info: ------------------------------------------- Feb 17 22:57:35 volumio volumio[7834]: info: ----- Volumio3 ---- Feb 17 22:57:35 volumio volumio[7834]: info: ------------------------------------------- Feb 17 22:57:35 volumio volumio[7834]: info: ----- System startup ---- Feb 17 22:57:35 volumio volumio[7834]: info: ------------------------------------------- Feb 17 22:57:35 volumio go-librespot[7850]: time="2026-02-17T22:57:35+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 17 22:57:35 volumio go-librespot[7850]: time="2026-02-17T22:57:35+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 17 22:57:35 volumio go-librespot[7850]: time="2026-02-17T22:57:35+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 17 22:57:35 volumio go-librespot[7850]: time="2026-02-17T22:57:35+07:00" level=info msg="zeroconf server listening on port 34933" Feb 17 22:57:35 volumio go-librespot[7850]: time="2026-02-17T22:57:35+07:00" level=debug msg="obtained new client token: AAB9EBhvnO+Mxu0VsXzkIeRZUyTtQ8PuUZneXJxqkd0NdTyR1Av7C0/ROAEhIauapLlaMW4X3EX0mdCLZcuCZ/5y4/HVrHQXSOJBdsQIShe51KeTGZSUIh6hAbLU+juDv/cC6bGrik/FHn+dSxyCQk03UGlhwilslSqmuwzMDpLi4u7YV/J6vdjaBebr71/TEGyUySGbvJsYA4Z6MMkA2wZQWXFFJDrKadX2EmgivRrt8J6+MbE4jFv45g==" Feb 17 22:57:35 volumio go-librespot[7850]: time="2026-02-17T22:57:35+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 17 22:57:35 volumio go-librespot[7850]: time="2026-02-17T22:57:35+07:00" level=debug msg="completed keyexchange" Feb 17 22:57:35 volumio go-librespot[7850]: time="2026-02-17T22:57:35+07:00" level=debug msg="completed challenge" Feb 17 22:57:35 volumio go-librespot[7850]: time="2026-02-17T22:57:35+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 17 22:57:35 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 22:57:35 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 22:57:36 volumio volumio-remote-updater[25144]: [2026-02-17 22:57:36] [connect] Successful connection Feb 17 22:57:36 volumio volumio[7834]: info: MYVOLUMIO Environment detected Feb 17 22:57:36 volumio volumio[7834]: info: Plugin folders cleanup Feb 17 22:57:36 volumio volumio[7834]: info: Scanning into folder /volumio/app/plugins/ Feb 17 22:57:36 volumio volumio[7834]: info: Scanning category audio_interface Feb 17 22:57:36 volumio volumio[7834]: info: Scanning category miscellanea Feb 17 22:57:36 volumio volumio[7834]: info: Scanning category music_service Feb 17 22:57:36 volumio volumio[7834]: info: Scanning category plugins.json Feb 17 22:57:36 volumio volumio[7834]: info: Scanning category system_controller Feb 17 22:57:36 volumio volumio[7834]: info: Scanning category user_interface Feb 17 22:57:36 volumio volumio[7834]: info: Scanning into folder /data/plugins/ Feb 17 22:57:36 volumio volumio[7834]: info: Scanning category music_service Feb 17 22:57:36 volumio volumio[7834]: info: Plugin folders cleanup completed Feb 17 22:57:36 volumio volumio[7834]: info: ------------------------------------------- Feb 17 22:57:36 volumio volumio[7834]: info: ----- Core plugins startup ---- Feb 17 22:57:36 volumio volumio[7834]: info: ------------------------------------------- Feb 17 22:57:36 volumio volumio[7834]: info: Loading plugins from folder /volumio/app/plugins/ Feb 17 22:57:36 volumio volumio[7834]: info: Adding plugin upnp to MyMusic Plugins Feb 17 22:57:36 volumio volumio[7834]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 17 22:57:36 volumio volumio[7834]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 17 22:57:36 volumio volumio[7834]: info: Loading plugins from folder /data/plugins/ Feb 17 22:57:36 volumio volumio[7834]: info: Loading plugin "system"... Feb 17 22:57:36 volumio volumio[7834]: info: Loading plugin "appearance"... Feb 17 22:57:38 volumio volumio[7834]: info: Loading plugin "network"... Feb 17 22:57:38 volumio volumio[7834]: info: Refreshing Cached IP Addresses Feb 17 22:57:38 volumio sudo[7871]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 17 22:57:38 volumio sudo[7871]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 22:57:38 volumio sudo[7873]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 17 22:57:38 volumio sudo[7871]: pam_unix(sudo:session): session closed for user root Feb 17 22:57:38 volumio sudo[7873]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 22:57:38 volumio volumio[7834]: info: Loading plugin "services"... Feb 17 22:57:38 volumio sudo[7873]: pam_unix(sudo:session): session closed for user root Feb 17 22:57:38 volumio volumio[7834]: info: Loading plugin "alsa_controller"... Feb 17 22:57:38 volumio sudo[7881]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 17 22:57:38 volumio sudo[7881]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 22:57:38 volumio volumio[7834]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 17 22:57:38 volumio volumio[7834]: info: Loading plugin "wizard"... Feb 17 22:57:38 volumio volumio[7834]: info: Loading plugin "networkfs"... Feb 17 22:57:38 volumio volumio[7834]: info: Starting Udev Watcher for removable devices Feb 17 22:57:38 volumio volumio[7834]: info: Ignoring mount for partition: boot Feb 17 22:57:38 volumio volumio[7834]: info: Ignoring mount for partition: volumio Feb 17 22:57:38 volumio volumio[7834]: info: Ignoring mount for partition: volumio_data Feb 17 22:57:38 volumio volumio[7834]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 17 22:57:38 volumio volumio[7834]: info: Loading plugin "volumio_command_line_client"... Feb 17 22:57:39 volumio volumio[7834]: info: Loading plugin "upnp"... Feb 17 22:57:39 volumio volumio[7834]: info: [1771343859017] Starting Upmpd Daemon Feb 17 22:57:39 volumio volumio[7834]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 17 22:57:39 volumio volumio[7834]: info: Loading plugin "my_music"... Feb 17 22:57:39 volumio volumio[7834]: info: Loading plugin "mpd"... Feb 17 22:57:39 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 34. Feb 17 22:57:39 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 22:57:39 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 22:57:39 volumio go-librespot[7904]: go-librespot daemon starting... Feb 17 22:57:39 volumio go-librespot[7905]: time="2026-02-17T22:57:39+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 22:57:39 volumio go-librespot[7905]: time="2026-02-17T22:57:39+07:00" level=debug msg="app state loaded" Feb 17 22:57:39 volumio go-librespot[7905]: time="2026-02-17T22:57:39+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 22:57:39 volumio go-librespot[7905]: time="2026-02-17T22:57:39+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 17 22:57:39 volumio go-librespot[7905]: time="2026-02-17T22:57:39+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 17 22:57:39 volumio go-librespot[7905]: time="2026-02-17T22:57:39+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 17 22:57:39 volumio volumio[7834]: info: Loading plugin "upnp_browser"... Feb 17 22:57:39 volumio go-librespot[7905]: time="2026-02-17T22:57:39+07:00" level=info msg="zeroconf server listening on port 41927" Feb 17 22:57:39 volumio go-librespot[7905]: time="2026-02-17T22:57:39+07:00" level=debug msg="obtained new client token: AAAOSgVmBBO6YOVVOH0GoBwMkYnHITgaxk0jL/ySbgcECMWsBsIZgh501kOoxYcGl1eKryBpabaeldw5eQckCgterdphp8zY8ZoTkVQoEs4NNzeSmbWiEI75SBngKhO/Ka5ksORSJAwVwELdSbZmmRRSh1AItF7JijJGLJzwujUKctXo7l+hbyxd/4TJauedxJXMdvUNgX9GcvAUbaFcPKv9AY2zYxs0EFWZG1boUUtsN8Afla4FobcBzA==" Feb 17 22:57:40 volumio go-librespot[7905]: time="2026-02-17T22:57:40+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 17 22:57:40 volumio go-librespot[7905]: time="2026-02-17T22:57:40+07:00" level=debug msg="completed keyexchange" Feb 17 22:57:40 volumio go-librespot[7905]: time="2026-02-17T22:57:40+07:00" level=debug msg="completed challenge" Feb 17 22:57:40 volumio go-librespot[7905]: time="2026-02-17T22:57:40+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 17 22:57:40 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 22:57:40 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 22:57:41 volumio sudo[7881]: pam_unix(sudo:session): session closed for user root Feb 17 22:57:42 volumio volumio[7834]: info: Starting UPNP Browser Feb 17 22:57:42 volumio volumio[7834]: info: Loading plugin "alarm-clock"... Feb 17 22:57:42 volumio volumio[7834]: info: Loading plugin "airplay_emulation"... Feb 17 22:57:43 volumio volumio[7834]: info: Starting Shairport Sync Feb 17 22:57:43 volumio volumio[7834]: info: Loading plugin "last_100"... Feb 17 22:57:43 volumio volumio[7834]: info: Loading plugin "webradio"... Feb 17 22:57:43 volumio volumio[7834]: info: Loading plugin "i2s_dacs"... Feb 17 22:57:43 volumio volumio[7834]: info: Loading plugin "volumiodiscovery"... Feb 17 22:57:43 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 35. Feb 17 22:57:43 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 22:57:43 volumio node[7834]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 17 22:57:43 volumio volumio[7834]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 17 22:57:43 volumio volumio[7834]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 17 22:57:43 volumio volumio[7834]: *** WARNING *** For more information see Feb 17 22:57:43 volumio volumio[7834]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 17 22:57:43 volumio volumio[7834]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 17 22:57:43 volumio volumio[7834]: *** WARNING *** For more information see Feb 17 22:57:43 volumio node[7834]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 17 22:57:43 volumio node[7834]: *** WARNING *** For more information see Feb 17 22:57:43 volumio node[7834]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 17 22:57:43 volumio node[7834]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 17 22:57:43 volumio node[7834]: *** WARNING *** For more information see Feb 17 22:57:43 volumio volumio[7834]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 17 22:57:43 volumio volumio[7834]: info: Discovery: Started advertising with name: Volumio Feb 17 22:57:43 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 22:57:43 volumio go-librespot[7918]: go-librespot daemon starting... Feb 17 22:57:43 volumio volumio[7834]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 17 22:57:43 volumio volumio[7834]: info: Loading plugin "spop"... Feb 17 22:57:43 volumio go-librespot[7921]: time="2026-02-17T22:57:43+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 22:57:43 volumio go-librespot[7921]: time="2026-02-17T22:57:43+07:00" level=debug msg="app state loaded" Feb 17 22:57:43 volumio go-librespot[7921]: time="2026-02-17T22:57:43+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 22:57:43 volumio go-librespot[7921]: time="2026-02-17T22:57:43+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 17 22:57:43 volumio go-librespot[7921]: time="2026-02-17T22:57:43+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 17 22:57:43 volumio go-librespot[7921]: time="2026-02-17T22:57:43+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 17 22:57:43 volumio go-librespot[7921]: time="2026-02-17T22:57:43+07:00" level=info msg="zeroconf server listening on port 42309" Feb 17 22:57:44 volumio go-librespot[7921]: time="2026-02-17T22:57:44+07:00" level=debug msg="obtained new client token: AACig6ptMVzI2CvSmCNOfj0fXg1Th+U9ixlpyBwYPbNZtQ29GemC+3ygHX3LZmpMFtuMFR43TaY4b6hzU28n5uaJTmvZ1j88Iri1D7RJjTkIYkfTgxkBOXNWoDIW8f4mL0RyfhaBw0pz7UNASBsNReshgv4guY1MJXswQtwEoeGxncF4m7DMagbzvIFwgdmfp6gLUA7d29yTGP2LBHwNrsVGd19kALPVQeN3QGYrNxmUZxGbRFC9OCI=" Feb 17 22:57:44 volumio go-librespot[7921]: time="2026-02-17T22:57:44+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 17 22:57:44 volumio go-librespot[7921]: time="2026-02-17T22:57:44+07:00" level=debug msg="completed keyexchange" Feb 17 22:57:44 volumio go-librespot[7921]: time="2026-02-17T22:57:44+07:00" level=debug msg="completed challenge" Feb 17 22:57:44 volumio go-librespot[7921]: time="2026-02-17T22:57:44+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 17 22:57:44 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 22:57:44 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 22:57:45 volumio volumio[7834]: info: Loading plugin "ytcr"... Feb 17 22:57:47 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 36. Feb 17 22:57:47 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 22:57:47 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 22:57:47 volumio go-librespot[7929]: go-librespot daemon starting... Feb 17 22:57:47 volumio volumio[7834]: info: Loading plugin "ytmusic"... Feb 17 22:57:47 volumio go-librespot[7930]: time="2026-02-17T22:57:47+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 22:57:47 volumio go-librespot[7930]: time="2026-02-17T22:57:47+07:00" level=debug msg="app state loaded" Feb 17 22:57:47 volumio go-librespot[7930]: time="2026-02-17T22:57:47+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 22:57:48 volumio go-librespot[7930]: time="2026-02-17T22:57:48+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 17 22:57:48 volumio go-librespot[7930]: time="2026-02-17T22:57:48+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 17 22:57:48 volumio go-librespot[7930]: time="2026-02-17T22:57:48+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 17 22:57:48 volumio go-librespot[7930]: time="2026-02-17T22:57:48+07:00" level=info msg="zeroconf server listening on port 37781" Feb 17 22:57:48 volumio go-librespot[7930]: time="2026-02-17T22:57:48+07:00" level=debug msg="obtained new client token: AABhv7q1c7BDBqFixU8+jtYaPkq/XmuKK+7vhbH3Gmf1CBdQvmxIj/KLfJudgbQ79zj5MjXKUqnHf5whBMTjiZcK4tougOgM8j9Nw2Xzan6XF25gPfNLHgQqKVgz5/DOK6GwYb+S6meXfzmt/foEOgUnKzd2THWsV0Hxxy4AkIEiPjDm719TtDOqf1EGhhhraZqoZe6eDjNvTRspLeaBkqcWO6vkMY2FXQA6fGEYeDjJl9jldxj+6HjKSA==" Feb 17 22:57:48 volumio volumio-remote-updater[25144]: [2026-02-17 22:57:48] [connect] Successful connection Feb 17 22:57:48 volumio go-librespot[7930]: time="2026-02-17T22:57:48+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 17 22:57:48 volumio go-librespot[7930]: time="2026-02-17T22:57:48+07:00" level=debug msg="completed keyexchange" Feb 17 22:57:48 volumio go-librespot[7930]: time="2026-02-17T22:57:48+07:00" level=debug msg="completed challenge" Feb 17 22:57:48 volumio volumio[7834]: info: Loading plugin "outputs"... Feb 17 22:57:48 volumio volumio[7834]: info: Loading plugin "albumart"... Feb 17 22:57:48 volumio go-librespot[7930]: time="2026-02-17T22:57:48+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 17 22:57:48 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 22:57:48 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 22:57:49 volumio volumio[7834]: info: Plugin example_plugin is not enabled Feb 17 22:57:49 volumio volumio[7834]: info: Loading plugin "inputs"... Feb 17 22:57:49 volumio volumio[7834]: info: Loading plugin "updater_comm"... Feb 17 22:57:49 volumio volumio[7834]: info: Plugin mpdemulation is not enabled Feb 17 22:57:49 volumio volumio[7834]: info: Loading plugin "rest_api"... Feb 17 22:57:49 volumio volumio[7834]: info: Loading plugin "websocket"... Feb 17 22:57:49 volumio volumio[7834]: info: Starting Socket.io Server version 1.7.4 Feb 17 22:57:49 volumio volumio[7834]: info: Loading plugin "RoonBridge"... Feb 17 22:57:49 volumio volumio[7834]: info: Applying required configuration parameters for plugin RoonBridge Feb 17 22:57:49 volumio volumio[7834]: info: Loading i18n strings for locale en Feb 17 22:57:49 volumio volumio[7834]: Updating browse sources language Feb 17 22:57:49 volumio volumio[7834]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 22:57:50 volumio volumio[7834]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 17 22:57:50 volumio volumio[7834]: info: CoreCommandRouter::initPlayerControls Feb 17 22:57:50 volumio volumio[7834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 22:57:50 volumio volumio[7834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 22:57:50 volumio volumio[7834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 22:57:50 volumio volumio[7834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 22:57:50 volumio volumio[7834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 22:57:50 volumio volumio[7834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 22:57:50 volumio volumio[7834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 22:57:50 volumio volumio[7834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 22:57:50 volumio volumio[7834]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 17 22:57:50 volumio volumio[7834]: Express server listening on port 3000 Feb 17 22:57:50 volumio volumio[7834]: [Metrics] WebUI: 15s 996.46ms Feb 17 22:57:50 volumio volumio[7834]: info: CoreStateMachine::resetVolumioState Feb 17 22:57:50 volumio volumio[7834]: info: CoreStateMachine::getcurrentVolume Feb 17 22:57:50 volumio volumio[7834]: info: CoreCommandRouter::volumioRetrievevolume Feb 17 22:57:50 volumio volumio[7951]: Forking 3 albumart workers Feb 17 22:57:50 volumio sudo[7968]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 17 22:57:50 volumio sudo[7968]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 22:57:50 volumio sudo[7968]: pam_unix(sudo:session): session closed for user root Feb 17 22:57:50 volumio sudo[7971]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 17 22:57:50 volumio sudo[7971]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 22:57:50 volumio sudo[7971]: pam_unix(sudo:session): session closed for user root Feb 17 22:57:50 volumio volumio[7834]: info: Volumio Network Manager: Network status updated: 1 Feb 17 22:57:50 volumio volumio[7834]: info: VolumeController:: Volume=100 Mute =false Feb 17 22:57:50 volumio volumio[7834]: info: CoreStateMachine::pushState Feb 17 22:57:50 volumio volumio[7834]: info: CorePlayQueue::getTrack 0 Feb 17 22:57:50 volumio volumio[7834]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 22:57:50 volumio volumio[7834]: info: CoreCommandRouter::volumioPushState Feb 17 22:57:50 volumio volumio[7834]: info: CoreStateMachine::updateTrackBlock Feb 17 22:57:50 volumio volumio[7834]: info: CorePlayQueue::getTrackBlock Feb 17 22:57:50 volumio volumio[7834]: info: CoreCommandRouter::volumioRetrievevolume Feb 17 22:57:50 volumio volumio-remote-updater[25144]: [2026-02-17 22:57:50] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1771343868 101 Feb 17 22:57:50 volumio volumio[7834]: 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 17 22:57:50 volumio volumio[7834]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 22:57:50 volumio volumio[7834]: info: Reloading queue from file Feb 17 22:57:50 volumio volumio[7834]: info: CoreStateMachine::setRepeat null single undefined Feb 17 22:57:50 volumio volumio[7834]: info: CoreStateMachine::pushState Feb 17 22:57:50 volumio volumio[7834]: info: CorePlayQueue::getTrack 0 Feb 17 22:57:50 volumio volumio[7834]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 22:57:50 volumio volumio[7834]: info: CoreCommandRouter::volumioPushState Feb 17 22:57:50 volumio volumio[7834]: info: CoreStateMachine::setRandom null Feb 17 22:57:50 volumio volumio[7834]: info: CoreStateMachine::pushState Feb 17 22:57:50 volumio volumio[7834]: info: CorePlayQueue::getTrack 0 Feb 17 22:57:50 volumio volumio[7834]: info: CoreCommandRouter::volumioPushState Feb 17 22:57:50 volumio volumio[7834]: info: Setting Device type: Raspberry PI Feb 17 22:57:51 volumio volumio[7834]: info: Completed loading Core Plugins Feb 17 22:57:51 volumio volumio[7834]: info: Preparing to generate the ALSA configuration file Feb 17 22:57:51 volumio volumio[7834]: info: VolumeController:: Volume=100 Mute =false Feb 17 22:57:51 volumio volumio[7834]: info: CoreStateMachine::pushState Feb 17 22:57:51 volumio volumio[7834]: info: CorePlayQueue::getTrack 0 Feb 17 22:57:51 volumio volumio[7834]: info: CoreCommandRouter::volumioPushState Feb 17 22:57:51 volumio volumio[7834]: info: Asound.conf file unchanged, so no further update is needed Feb 17 22:57:51 volumio volumio[7834]: info: Output device has changed, restarting MPD Feb 17 22:57:51 volumio volumio[7834]: info: Output device has changed, restarting Shairport Sync Feb 17 22:57:51 volumio volumio[7834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 22:57:51 volumio volumio[7834]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 22:57:51 volumio sudo[8013]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 17 22:57:51 volumio sudo[8013]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 22:57:51 volumio volumio[7834]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 17 22:57:51 volumio volumio[7834]: info: ___________ START PLUGINS ___________ Feb 17 22:57:51 volumio sudo[8015]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 17 22:57:51 volumio sudo[8017]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 17 22:57:51 volumio sudo[8017]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 22:57:51 volumio sudo[8015]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 22:57:51 volumio volumio[7834]: info: ControllerMpd::onStart: Initializing MPD Feb 17 22:57:51 volumio volumio[7834]: info: Creating MPD Configuration file Feb 17 22:57:51 volumio sudo[8015]: pam_unix(sudo:session): session closed for user root Feb 17 22:57:51 volumio sudo[8026]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 17 22:57:51 volumio sudo[8026]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 22:57:51 volumio volumio[7834]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 17 22:57:51 volumio volumio[7834]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 17 22:57:51 volumio volumio[7834]: info: [1771343871439] CoreMusicLibrary::Adding element Media Servers Feb 17 22:57:51 volumio volumio[7834]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 22:57:51 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 17 22:57:51 volumio sudo[8029]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 17 22:57:51 volumio sudo[8029]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 22:57:51 volumio sudo[8013]: pam_unix(sudo:session): session closed for user root Feb 17 22:57:51 volumio sudo[8026]: pam_unix(sudo:session): session closed for user root Feb 17 22:57:51 volumio volumio[7834]: info: UPNP Browser: Client initialized successfully Feb 17 22:57:51 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 17 22:57:51 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 17 22:57:51 volumio systemd[1]: mpd.service: Consumed 7.062s CPU time. Feb 17 22:57:51 volumio volumio[7834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 22:57:51 volumio volumio[7834]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 22:57:51 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 17 22:57:51 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 17 22:57:51 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 17 22:57:51 volumio volumio[7834]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 17 22:57:51 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 17 22:57:51 volumio volumio[7834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 22:57:51 volumio volumio[7834]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 22:57:51 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 17 22:57:51 volumio volumio[7834]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 17 22:57:51 volumio volumio[7834]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 17 22:57:51 volumio volumio[7834]: info: [1771343871786] CoreMusicLibrary::Adding element Last_100 Feb 17 22:57:51 volumio volumio[7834]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 22:57:51 volumio volumio[7834]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 17 22:57:51 volumio volumio[7834]: info: [1771343871822] CoreMusicLibrary::Adding element Webradio Feb 17 22:57:51 volumio volumio[7834]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 22:57:51 volumio volumio[7834]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 17 22:57:51 volumio volumio[7834]: info: Initializing BBC Radios Feb 17 22:57:52 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 37. Feb 17 22:57:52 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 22:57:52 volumio volumio[7834]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 17 22:57:52 volumio volumio[7834]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 22:57:52 volumio volumio[7834]: info: Creating Spotify config file Feb 17 22:57:52 volumio volumio[7834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 22:57:52 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 22:57:52 volumio go-librespot[8054]: go-librespot daemon starting... Feb 17 22:57:52 volumio sudo[8038]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 17 22:57:52 volumio sudo[8038]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 17 22:57:52 volumio sudo[8038]: pam_unix(sudo:session): session closed for user root Feb 17 22:57:52 volumio go-librespot[8055]: time="2026-02-17T22:57:52+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 22:57:53 volumio go-librespot[8055]: time="2026-02-17T22:57:53+07:00" level=info msg="zeroconf server listening on port 37501" Feb 17 22:57:53 volumio volumio[7967]: Starting albumart workers Feb 17 22:57:53 volumio volumio[7834]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 17 22:57:53 volumio volumio[7834]: info: [1771343873577] CoreMusicLibrary::Adding element YouTube Music Feb 17 22:57:53 volumio volumio[7834]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 22:57:53 volumio volumio[7834]: Cannot find translation for source YouTube Music Feb 17 22:57:53 volumio volumio[7834]: info: Volumio Calling Home Feb 17 22:57:53 volumio sudo[8065]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Feb 17 22:57:53 volumio sudo[8065]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 22:57:53 volumio volumio[7965]: Starting albumart workers Feb 17 22:57:53 volumio sudo[8065]: pam_unix(sudo:session): session closed for user root Feb 17 22:57:53 volumio volumio[7969]: Starting albumart workers Feb 17 22:57:54 volumio volumio[7834]: info: Discovery: adding 921648ed-0557-4d70-9927-1420682efcc7 Feb 17 22:57:54 volumio volumio[7834]: info: Discovery: Found device Volumio Feb 17 22:57:54 volumio volumio[7834]: info: CoreCommandRouter::volumioGetState Feb 17 22:57:54 volumio volumio[7834]: info: CorePlayQueue::getTrack 0 Feb 17 22:57:54 volumio volumio[7834]: info: Discovery: this is already registered, 921648ed-0557-4d70-9927-1420682efcc7 Feb 17 22:57:54 volumio volumio[7834]: info: Discovery: Found device Volumio Feb 17 22:57:54 volumio volumio[7834]: info: CoreCommandRouter::volumioGetState Feb 17 22:57:54 volumio volumio[7834]: info: CorePlayQueue::getTrack 0 Feb 17 22:57:54 volumio volumio[7834]: info: MPD Permissions set Feb 17 22:57:54 volumio volumio[7834]: info: MPD Permissions set Feb 17 22:57:54 volumio volumio[7834]: info: Upmpdcli Daemon Started Feb 17 22:57:54 volumio volumio[7834]: info: Volumio called home Feb 17 22:57:54 volumio volumio[7834]: info: Spotify config file written Feb 17 22:57:54 volumio sudo[8071]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 17 22:57:54 volumio sudo[8071]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 22:57:54 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Feb 17 22:57:54 volumio systemd[1]: go-librespot-daemon.service: Killing process 8060 (go-librespot) with signal SIGKILL. Feb 17 22:57:54 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Feb 17 22:57:54 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 22:57:55 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 22:57:55 volumio go-librespot[8073]: go-librespot daemon starting... Feb 17 22:57:55 volumio sudo[8071]: pam_unix(sudo:session): session closed for user root Feb 17 22:57:55 volumio volumio[7834]: 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 17 22:57:55 volumio volumio[7834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 22:57:55 volumio volumio[7834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 22:57:55 volumio volumio[7834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 22:57:55 volumio volumio[7834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 22:57:55 volumio volumio[7834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 22:57:55 volumio volumio[7834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 22:57:55 volumio go-librespot[8074]: time="2026-02-17T22:57:55+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 22:57:55 volumio go-librespot[8074]: time="2026-02-17T22:57:55+07:00" level=debug msg="app state loaded" Feb 17 22:57:55 volumio volumio[7834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 22:57:55 volumio volumio[7834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 22:57:55 volumio go-librespot[8074]: time="2026-02-17T22:57:55+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 22:57:55 volumio volumio[7834]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 22:57:55 volumio volumio[7834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 22:57:55 volumio volumio[7834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 22:57:55 volumio volumio[7834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 22:57:55 volumio volumio[7834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 22:57:55 volumio volumio[7834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 22:57:55 volumio volumio[7834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 22:57:55 volumio volumio[7834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 22:57:55 volumio volumio[7834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 22:57:55 volumio volumio[7834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 22:57:55 volumio volumio[7834]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 22:57:55 volumio volumio[7834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 22:57:55 volumio volumio[7834]: info: No need to fix Spotify hosts Feb 17 22:57:55 volumio go-librespot[8074]: time="2026-02-17T22:57:55+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 17 22:57:55 volumio go-librespot[8074]: time="2026-02-17T22:57:55+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 17 22:57:55 volumio go-librespot[8074]: time="2026-02-17T22:57:55+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 17 22:57:55 volumio go-librespot[8074]: time="2026-02-17T22:57:55+07:00" level=info msg="zeroconf server listening on port 37919" Feb 17 22:57:56 volumio go-librespot[8074]: time="2026-02-17T22:57:56+07:00" level=debug msg="obtained new client token: AAAGO2bzOXy56kkDCrUYZ6/MC6n+hpw/5dltaUgZ7iIjskooid31nxpZq7Wj7gmejauWPTXGOkLq2MElqnmDlyK42kCZ69JZBQH2xFSA6WwmHIyLC9IqwqISbxjx0bV69kpEUUeP9WGY+jx1JUiA27nzBbwR++aNZwzRK7La9016jU2U5TlzIQrZMqzHZTeqd5KXsqT7uAp4ZH9NkDRm65yuPNLtLjoGd2XHsxtkipj5r1IqkVnjDTc=" Feb 17 22:57:56 volumio go-librespot[8074]: time="2026-02-17T22:57:56+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 17 22:57:56 volumio go-librespot[8074]: time="2026-02-17T22:57:56+07:00" level=debug msg="completed keyexchange" Feb 17 22:57:56 volumio go-librespot[8074]: time="2026-02-17T22:57:56+07:00" level=debug msg="completed challenge" Feb 17 22:57:56 volumio volumio[7834]: info: Starting Shairport Sync Feb 17 22:57:56 volumio volumio[7834]: info: Starting Shairport Sync Feb 17 22:57:56 volumio go-librespot[8074]: time="2026-02-17T22:57:56+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 17 22:57:56 volumio volumio[7834]: info: Starting Shairport Sync Feb 17 22:57:56 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 22:57:56 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 22:57:56 volumio sudo[8111]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 17 22:57:56 volumio sudo[8111]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 22:57:56 volumio sudo[8113]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 17 22:57:56 volumio sudo[8113]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 22:57:56 volumio sudo[8116]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 17 22:57:56 volumio sudo[8116]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 22:57:56 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 17 22:57:56 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 17 22:57:56 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 17 22:57:56 volumio systemd[1]: shairport-sync.service: Consumed 2.356s CPU time. Feb 17 22:57:56 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 17 22:57:56 volumio sudo[8113]: pam_unix(sudo:session): session closed for user root Feb 17 22:57:56 volumio volumio[7834]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 17 22:57:56 volumio volumio[7834]: SPOTIFY: BQDyTP9okK-XCzPgm84c_nwTRBqm8591vdj_5UTsyRsNMyxsuXF6NkQti_4ynK-h72em0TXcviT-ol5RnzuWYpgbcQ-8n5RF1xlGDaRwx1GqeBXq4OdyYqAVVEHZAp8F0Rrvtla0sRhZuifTGNgtk3cdg5jrbMbV_hsXoZi1MiDU26N_YdRDrK3XZZ7ErK0U6oWRTYmDUalC9Iku1ybnOD69jDRKquMrDDK8BQYMKbVXQEv23mazeLrLLHC9LOuFyuWKqUbM5N2SqTpxkwt8mMD6_Z8y56IpDoTdjpaP4p9AZ1Y5biHM0Jug Feb 17 22:57:56 volumio volumio[7834]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 17 22:57:56 volumio volumio[7834]: info: New Spotify access token = BQDyTP9okK-XCzPgm84c_nwTRBqm8591vdj_5UTsyRsNMyxsuXF6NkQti_4ynK-h72em0TXcviT-ol5RnzuWYpgbcQ-8n5RF1xlGDaRwx1GqeBXq4OdyYqAVVEHZAp8F0Rrvtla0sRhZuifTGNgtk3cdg5jrbMbV_hsXoZi1MiDU26N_YdRDrK3XZZ7ErK0U6oWRTYmDUalC9Iku1ybnOD69jDRKquMrDDK8BQYMKbVXQEv23mazeLrLLHC9LOuFyuWKqUbM5N2SqTpxkwt8mMD6_Z8y56IpDoTdjpaP4p9AZ1Y5biHM0Jug Feb 17 22:57:56 volumio volumio[7834]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 17 22:57:56 volumio sudo[8111]: pam_unix(sudo:session): session closed for user root Feb 17 22:57:56 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 17 22:57:56 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 17 22:57:56 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 17 22:57:57 volumio volumio[7834]: info: Shairport-Sync Started Feb 17 22:57:57 volumio volumio[7834]: Error adding Membership: Error: addMembership EINVAL Feb 17 22:57:57 volumio volumio[7834]: info: Shairport-Sync Started Feb 17 22:57:57 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 17 22:57:57 volumio sudo[8116]: pam_unix(sudo:session): session closed for user root Feb 17 22:57:57 volumio volumio[7834]: info: CoreCommandRouter::volumioGetState Feb 17 22:57:57 volumio volumio[7834]: info: CorePlayQueue::getTrack 0 Feb 17 22:57:57 volumio volumio[7834]: info: Shairport-Sync Started Feb 17 22:57:57 volumio volumio[7834]: SPOTIFY: User informations: {"country":"PK","display_name":"215mmgea6kss3jxeqsqdquvji","email":"linh.ngotran@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/215mmgea6kss3jxeqsqdquvji"},"followers":{"href":null,"total":1},"href":"https://api.spotify.com/v1/users/215mmgea6kss3jxeqsqdquvji","id":"215mmgea6kss3jxeqsqdquvji","images":[],"product":"premium","type":"user","uri":"spotify:user:215mmgea6kss3jxeqsqdquvji"} Feb 17 22:57:57 volumio volumio[7834]: info: Spotify Successfully logged in Feb 17 22:57:57 volumio volumio[7834]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 17 22:57:57 volumio volumio[7834]: info: [1771343877412] CoreMusicLibrary::Adding element Spotify Feb 17 22:57:57 volumio volumio[7834]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 22:57:57 volumio volumio[7834]: Cannot find translation for source YouTube Music Feb 17 22:57:57 volumio volumio[7834]: Cannot find translation for source Spotify Feb 17 22:57:57 volumio volumio[7834]: info: [yt-cast-receiver] DIAL server listening on port 8098 Feb 17 22:57:57 volumio volumio[7834]: info: CoreCommandRouter::volumioRetrievevolume Feb 17 22:57:58 volumio volumio[7834]: info: VolumeController:: Volume=100 Mute =false Feb 17 22:57:58 volumio volumio[7834]: info: CoreCommandRouter::volumioGetState Feb 17 22:57:58 volumio volumio[7834]: info: CorePlayQueue::getTrack 0 Feb 17 22:57:58 volumio volumio[7834]: info: CoreStateMachine::pushState Feb 17 22:57:58 volumio volumio[7834]: info: CorePlayQueue::getTrack 0 Feb 17 22:57:58 volumio volumio[7834]: info: CoreCommandRouter::volumioPushState Feb 17 22:57:59 volumio mpd[8062]: 2026-02-17T22:57:59 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 17 22:57:59 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 17 22:57:59 volumio sudo[8017]: pam_unix(sudo:session): session closed for user root Feb 17 22:57:59 volumio sudo[8029]: pam_unix(sudo:session): session closed for user root Feb 17 22:57:59 volumio volumio[7834]: error: MPD error: The expression evaluated to a falsy value: Feb 17 22:57:59 volumio volumio[7834]: assert.ok(self.idling) Feb 17 22:57:59 volumio volumio[7834]: error: The expression evaluated to a falsy value: Feb 17 22:57:59 volumio volumio[7834]: assert.ok(self.idling) Feb 17 22:57:59 volumio volumio[7834]: info: go-librespot daemon successfully initialized Feb 17 22:57:59 volumio volumio[7834]: error: updateQueue error: null Feb 17 22:57:59 volumio volumio[7834]: info: MPD running with PID8062 Feb 17 22:57:59 volumio volumio[7834]: ,establishing connection Feb 17 22:57:59 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Feb 17 22:57:59 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 22:57:59 volumio volumio[7834]: info: Completed starting Core Plugins Feb 17 22:57:59 volumio volumio[7834]: info: ------------------------------------------- Feb 17 22:57:59 volumio volumio[7834]: info: ----- MyVolumio plugins startup ---- Feb 17 22:57:59 volumio volumio[7834]: info: ------------------------------------------- Feb 17 22:57:59 volumio volumio[7834]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 17 22:57:59 volumio volumio[7834]: error: updateQueue error: null Feb 17 22:57:59 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 22:57:59 volumio go-librespot[8157]: go-librespot daemon starting... Feb 17 22:57:59 volumio go-librespot[8158]: time="2026-02-17T22:57:59+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 22:57:59 volumio go-librespot[8158]: time="2026-02-17T22:57:59+07:00" level=debug msg="app state loaded" Feb 17 22:57:59 volumio go-librespot[8158]: time="2026-02-17T22:57:59+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 22:57:59 volumio nmbd[966]: [2026/02/17 22:57:59.782377, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Feb 17 22:57:59 volumio nmbd[966]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.23 for name WORKGROUP<1d>. Feb 17 22:57:59 volumio nmbd[966]: This response was from IP 192.168.1.237, reporting an IP address of 192.168.1.237. Feb 17 22:58:00 volumio go-librespot[8158]: time="2026-02-17T22:58:00+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 17 22:58:00 volumio go-librespot[8158]: time="2026-02-17T22:58:00+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 17 22:58:00 volumio go-librespot[8158]: time="2026-02-17T22:58:00+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 17 22:58:00 volumio go-librespot[8158]: time="2026-02-17T22:58:00+07:00" level=info msg="zeroconf server listening on port 41285" Feb 17 22:58:00 volumio go-librespot[8158]: time="2026-02-17T22:58:00+07:00" level=debug msg="obtained new client token: AADVAT3FMqy+m5mAPfmanwhipTxJEs3AIcuS0YysdGB9FQ1oWBWC5mbxGMe3t5qBdLzg40ok+t80ALoCUjkdyvvMG/ji14r8Q95GieoFRUUrWjzlR6XwjjvdfLXZbJzFVdt12EOBIbFqCSbkKkL2iY6Z72jFD0WdpD8hUNEfkJxOGwGfE78eFP2O4wZIiFLR89sxjIjQ4tqllMGBKo9htjB/LrA6lvbWM9R6a6fJl8iui/M4y2H5AWy+Rg==" Feb 17 22:58:00 volumio go-librespot[8158]: time="2026-02-17T22:58:00+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 17 22:58:00 volumio go-librespot[8158]: time="2026-02-17T22:58:00+07:00" level=debug msg="completed keyexchange" Feb 17 22:58:00 volumio go-librespot[8158]: time="2026-02-17T22:58:00+07:00" level=debug msg="completed challenge" Feb 17 22:58:00 volumio go-librespot[8158]: time="2026-02-17T22:58:00+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 17 22:58:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 22:58:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 22:58:02 volumio volumio[7834]: info: Initializing connection to go-librespot Websocket Feb 17 22:58:02 volumio volumio[7834]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 17 22:58:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Feb 17 22:58:03 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 22:58:03 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 22:58:03 volumio go-librespot[8166]: go-librespot daemon starting... Feb 17 22:58:03 volumio go-librespot[8167]: time="2026-02-17T22:58:03+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 22:58:03 volumio go-librespot[8167]: time="2026-02-17T22:58:03+07:00" level=debug msg="app state loaded" Feb 17 22:58:03 volumio go-librespot[8167]: time="2026-02-17T22:58:03+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 22:58:04 volumio volumio[7834]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 17 22:58:04 volumio go-librespot[8167]: time="2026-02-17T22:58:04+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 17 22:58:04 volumio go-librespot[8167]: time="2026-02-17T22:58:04+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 17 22:58:04 volumio go-librespot[8167]: time="2026-02-17T22:58:04+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 17 22:58:04 volumio go-librespot[8167]: time="2026-02-17T22:58:04+07:00" level=info msg="zeroconf server listening on port 45987" Feb 17 22:58:04 volumio go-librespot[8167]: time="2026-02-17T22:58:04+07:00" level=debug msg="obtained new client token: AABgp4NGOv96mxoUzKxEwrULdzqdTCHV7BElPmSFdyuyEiGKNyLPvPiqYyIklt9auD5U5egeHkvMUEzQCgI7OeKAZSHCq18gWunOy59ij8pEq+bpRuGwospZgZPpSEhUEigWcScXk8uPsOanNvX02hPoRQsKPEufD/lfXRvO5NZHjEjVt+Hkr7OM9pTCIBYu6lKPW0UewXyh0yyUv0koO/8ix30xJa0jMLhtR8mJUJtN7T2eLqi3cINp/Q==" Feb 17 22:58:04 volumio go-librespot[8167]: time="2026-02-17T22:58:04+07:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.241.202:4070: connect: connection refused" Feb 17 22:58:04 volumio go-librespot[8167]: time="2026-02-17T22:58:04+07:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:443, retrying with a different AP" error="dial tcp 104.199.241.202:443: connect: connection refused" Feb 17 22:58:04 volumio go-librespot[8167]: time="2026-02-17T22:58:04+07:00" level=debug msg="connected to ap-gae2.spotify.com:80" Feb 17 22:58:05 volumio go-librespot[8167]: time="2026-02-17T22:58:05+07:00" level=debug msg="completed keyexchange" Feb 17 22:58:05 volumio go-librespot[8167]: time="2026-02-17T22:58:05+07:00" level=debug msg="completed challenge" Feb 17 22:58:05 volumio go-librespot[8167]: time="2026-02-17T22:58:05+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 17 22:58:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 22:58:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 22:58:05 volumio volumio[7834]: info: Initializing connection to go-librespot Websocket Feb 17 22:58:05 volumio volumio[7834]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 17 22:58:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Feb 17 22:58:08 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 22:58:08 volumio volumio[7834]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 17 22:58:08 volumio volumio[7834]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 17 22:58:08 volumio volumio[7834]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 17 22:58:08 volumio volumio[7834]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 17 22:58:08 volumio volumio[7834]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 17 22:58:08 volumio volumio[7834]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 17 22:58:08 volumio volumio[7834]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 17 22:58:08 volumio volumio[7834]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 17 22:58:08 volumio volumio[7834]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 17 22:58:08 volumio volumio[7834]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 17 22:58:08 volumio volumio[7834]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 17 22:58:08 volumio volumio[7834]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 17 22:58:08 volumio volumio[7834]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 17 22:58:08 volumio volumio[7834]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 17 22:58:08 volumio volumio[7834]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 17 22:58:08 volumio volumio[7834]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 17 22:58:08 volumio volumio[7834]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 17 22:58:08 volumio volumio[7834]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 17 22:58:08 volumio volumio[7834]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 17 22:58:08 volumio volumio[7834]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 17 22:58:08 volumio volumio[7834]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 17 22:58:08 volumio volumio[7834]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 17 22:58:08 volumio volumio[7834]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 17 22:58:08 volumio volumio[7834]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 17 22:58:08 volumio volumio[7834]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 17 22:58:08 volumio volumio[7834]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 17 22:58:08 volumio volumio[7834]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 17 22:58:08 volumio volumio[7834]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 17 22:58:08 volumio volumio[7834]: info: Adding plugin bluetooth to MyMusic Plugins Feb 17 22:58:08 volumio volumio[7834]: info: Adding plugin multiroom to MyMusic Plugins Feb 17 22:58:08 volumio volumio[7834]: info: Adding plugin metavolumio to MyMusic Plugins Feb 17 22:58:08 volumio volumio[7834]: info: Adding plugin cd_controller to MyMusic Plugins Feb 17 22:58:08 volumio volumio[7834]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 17 22:58:08 volumio volumio[7834]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 17 22:58:08 volumio volumio[7834]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 17 22:58:08 volumio volumio[7834]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 17 22:58:08 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 22:58:08 volumio go-librespot[8175]: go-librespot daemon starting... Feb 17 22:58:08 volumio go-librespot[8176]: time="2026-02-17T22:58:08+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 22:58:08 volumio go-librespot[8176]: time="2026-02-17T22:58:08+07:00" level=debug msg="app state loaded" Feb 17 22:58:08 volumio go-librespot[8176]: time="2026-02-17T22:58:08+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 22:58:08 volumio go-librespot[8176]: time="2026-02-17T22:58:08+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 17 22:58:08 volumio go-librespot[8176]: time="2026-02-17T22:58:08+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 17 22:58:08 volumio go-librespot[8176]: time="2026-02-17T22:58:08+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 17 22:58:08 volumio go-librespot[8176]: time="2026-02-17T22:58:08+07:00" level=info msg="zeroconf server listening on port 38625" Feb 17 22:58:09 volumio go-librespot[8176]: time="2026-02-17T22:58:09+07:00" level=debug msg="obtained new client token: AAAsCowYm5KUk14AIxnKzl55I3a2kSr3bYWDOFLEH315rzkk+rEZ5c+fvySrRjMhYjmKv1Iwu78jbEe0qGFOMe3+lZ8zwu/i/inIlaMpY0du6LJc9yWXs+eh8KJj+BYSho7kmnysN+rU2BhVwjggwHrNPUqIeBwMvbrNrdt0oJqAliM8A23l0Om/ufv8+D+yNPPa3kE+K304lqCZ3DB+eRq3gZWAqWBPHbgTTmavZ1RG6v/7F4dSLTg=" Feb 17 22:58:09 volumio go-librespot[8176]: time="2026-02-17T22:58:09+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 17 22:58:09 volumio go-librespot[8176]: time="2026-02-17T22:58:09+07:00" level=debug msg="completed keyexchange" Feb 17 22:58:09 volumio go-librespot[8176]: time="2026-02-17T22:58:09+07:00" level=debug msg="completed challenge" Feb 17 22:58:09 volumio go-librespot[8176]: time="2026-02-17T22:58:09+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 17 22:58:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 22:58:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 22:58:10 volumio volumio[7834]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 17 22:58:10 volumio volumio[7834]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 17 22:58:10 volumio volumio[7834]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 22:58:10 volumio volumio[7834]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 22:58:10 volumio volumio[7834]: info: Starting MyVolumio Remote Streaming Endpoints Feb 17 22:58:10 volumio volumio[7834]: info: MyVolumio login type: Token Feb 17 22:58:10 volumio volumio[7834]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 17 22:58:10 volumio volumio[7834]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 17 22:58:12 volumio volumio[7834]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 17 22:58:12 volumio volumio[7834]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 17 22:58:12 volumio volumio[7834]: info: Streaming services startup Feb 17 22:58:12 volumio volumio[7834]: info: Starting Streaming Daemon Feb 17 22:58:12 volumio sudo[8198]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 17 22:58:12 volumio sudo[8198]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 22:58:12 volumio volumio[7834]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 17 22:58:12 volumio sudo[8198]: pam_unix(sudo:session): session closed for user root Feb 17 22:58:12 volumio volumio[7834]: info: Initializing connection to go-librespot Websocket Feb 17 22:58:12 volumio volumio[7834]: error: Cannot start Volumio Streaming Daemon Feb 17 22:58:12 volumio volumio[7834]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 17 22:58:12 volumio volumio[7834]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 17 22:58:12 volumio volumio[7834]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 17 22:58:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Feb 17 22:58:12 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 22:58:12 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 22:58:12 volumio go-librespot[8205]: go-librespot daemon starting... Feb 17 22:58:12 volumio go-librespot[8206]: time="2026-02-17T22:58:12+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 22:58:12 volumio go-librespot[8206]: time="2026-02-17T22:58:12+07:00" level=debug msg="app state loaded" Feb 17 22:58:12 volumio go-librespot[8206]: time="2026-02-17T22:58:12+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 22:58:13 volumio go-librespot[8206]: time="2026-02-17T22:58:13+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 17 22:58:13 volumio go-librespot[8206]: time="2026-02-17T22:58:13+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 17 22:58:13 volumio go-librespot[8206]: time="2026-02-17T22:58:13+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 17 22:58:13 volumio volumio[7834]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Feb 17 22:58:13 volumio go-librespot[8206]: time="2026-02-17T22:58:13+07:00" level=info msg="zeroconf server listening on port 43293" Feb 17 22:58:13 volumio go-librespot[8206]: time="2026-02-17T22:58:13+07:00" level=debug msg="obtained new client token: AAD2nFB8ujeygv7mfOK+sxIhOQyrz02iHGhkgA3ECN115BwcxBFLigAlUzgp1vxK9gl4VCGxtTZUAYi/377yIl5FMD3B32Z0L6fz0lOxC5cjIhkijAkXGxEws/uffvr3XNY9oAgC0C0fdwd138T7EI4gQ3/K1FTq/XFB9z/9C/mnHKRiYrjq6yX7udcM0ZFIvPzYlR+y38RFOz5CAKWOK4jtSzSknFa5CHolv7oErGVL9aidG5SVexmvew==" Feb 17 22:58:13 volumio go-librespot[8206]: time="2026-02-17T22:58:13+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 17 22:58:13 volumio go-librespot[8206]: time="2026-02-17T22:58:13+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Feb 17 22:58:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 22:58:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 22:58:13 volumio volumio[7834]: info: MyVolumio token set successfully Feb 17 22:58:13 volumio volumio[7834]: info: MYVOLUMIO: Adding device Feb 17 22:58:13 volumio volumio[7834]: info: MYVOLUMIO: Evaluating Server Feb 17 22:58:14 volumio volumio[7834]: info: MyVolumio status changed Feb 17 22:58:14 volumio volumio[7834]: info: Streaming services startup Feb 17 22:58:14 volumio volumio[7834]: info: Starting Streaming Daemon Feb 17 22:58:14 volumio volumio[7834]: info: Removing browser output: myVolumio user plan is not superstar Feb 17 22:58:14 volumio volumio[7834]: info: Removing audio output: Feb 17 22:58:14 volumio volumio[7834]: info: Stoppping Tunnel 1 Feb 17 22:58:14 volumio sudo[8235]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 17 22:58:14 volumio sudo[8235]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 22:58:14 volumio sudo[8235]: pam_unix(sudo:session): session closed for user root Feb 17 22:58:14 volumio volumio[7834]: error: Cannot start Volumio Streaming Daemon Feb 17 22:58:14 volumio volumio[7834]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 17 22:58:14 volumio volumio[7834]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 17 22:58:14 volumio sudo[8238]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Feb 17 22:58:14 volumio sudo[8238]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 22:58:14 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 17 22:58:14 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 17 22:58:14 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 17 22:58:14 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 17 22:58:14 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 17 22:58:14 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 17 22:58:14 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 17 22:58:14 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 17 22:58:14 volumio sudo[8238]: pam_unix(sudo:session): session closed for user root Feb 17 22:58:14 volumio volumio[7834]: info: Remote SSH Stopped Feb 17 22:58:15 volumio volumio[7834]: info: Initializing connection to go-librespot Websocket Feb 17 22:58:15 volumio volumio[7834]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 17 22:58:15 volumio volumio[7834]: info: Setting Geolocation for MyVolumio to as1 Feb 17 22:58:15 volumio volumio[7834]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 22:58:15 volumio volumio[7834]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 22:58:15 volumio volumio[7834]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 22:58:16 volumio volumio[7834]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Feb 17 22:58:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Feb 17 22:58:16 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 22:58:16 volumio volumio[7834]: info: Updating MyVolumio device info Feb 17 22:58:16 volumio volumio[7834]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 22:58:16 volumio volumio[7834]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 22:58:16 volumio volumio[7834]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 22:58:16 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 22:58:16 volumio go-librespot[8240]: go-librespot daemon starting... Feb 17 22:58:16 volumio go-librespot[8241]: time="2026-02-17T22:58:16+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 22:58:16 volumio go-librespot[8241]: time="2026-02-17T22:58:16+07:00" level=debug msg="app state loaded" Feb 17 22:58:16 volumio go-librespot[8241]: time="2026-02-17T22:58:16+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 22:58:17 volumio volumio[7834]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Feb 17 22:58:17 volumio go-librespot[8241]: time="2026-02-17T22:58:17+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 17 22:58:17 volumio go-librespot[8241]: time="2026-02-17T22:58:17+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 17 22:58:17 volumio go-librespot[8241]: time="2026-02-17T22:58:17+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 17 22:58:17 volumio go-librespot[8241]: time="2026-02-17T22:58:17+07:00" level=info msg="zeroconf server listening on port 40297" Feb 17 22:58:17 volumio go-librespot[8241]: time="2026-02-17T22:58:17+07:00" level=debug msg="obtained new client token: AABvVGBpI8ow/nZorZd6bWau0YOVmAZBIQjTd1gtRnC5I7TJVxXSbi5aE009IPvDjQynS0i/z2WEkGMV79GYvbPsOgOqQ8UKaFaxgG3cCm6/BfH4Z0pko/G+9MbhHJAvxrVGLQlWaw5mWwzW58r2sEGFPqiWouHS0FDiDFEsLcU5djsb0ZNf5+Xx8gMnVUAtZZpDyA4xoynrc5wfixajRf+Dhi48qDBQJ3oDC9lkQPfcwboES9AfVBaeDw==" Feb 17 22:58:17 volumio go-librespot[8241]: time="2026-02-17T22:58:17+07:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.241.202:4070: connect: connection refused" Feb 17 22:58:17 volumio go-librespot[8241]: time="2026-02-17T22:58:17+07:00" level=debug msg="connected to ap-gae2.spotify.com:443" Feb 17 22:58:17 volumio go-librespot[8241]: time="2026-02-17T22:58:17+07:00" level=debug msg="completed keyexchange" Feb 17 22:58:17 volumio go-librespot[8241]: time="2026-02-17T22:58:17+07:00" level=debug msg="completed challenge" Feb 17 22:58:17 volumio go-librespot[8241]: time="2026-02-17T22:58:17+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 17 22:58:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 22:58:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 22:58:18 volumio volumio[7834]: info: Initializing connection to go-librespot Websocket Feb 17 22:58:18 volumio volumio[7834]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 17 22:58:20 volumio volumio[7834]: info: MYVOLUMIO: Adding device Feb 17 22:58:20 volumio volumio[7834]: info: MYVOLUMIO: Evaluating Server Feb 17 22:58:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Feb 17 22:58:21 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 22:58:21 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 22:58:21 volumio go-librespot[8281]: go-librespot daemon starting... Feb 17 22:58:21 volumio go-librespot[8282]: time="2026-02-17T22:58:21+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 22:58:21 volumio go-librespot[8282]: time="2026-02-17T22:58:21+07:00" level=debug msg="app state loaded" Feb 17 22:58:21 volumio go-librespot[8282]: time="2026-02-17T22:58:21+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 22:58:21 volumio volumio[7834]: info: Initializing connection to go-librespot Websocket Feb 17 22:58:21 volumio go-librespot[8282]: time="2026-02-17T22:58:21+07:00" level=debug msg="new websocket client" Feb 17 22:58:21 volumio volumio[7834]: info: Connection to go-librespot Websocket established Feb 17 22:58:21 volumio go-librespot[8282]: time="2026-02-17T22:58:21+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 17 22:58:21 volumio go-librespot[8282]: time="2026-02-17T22:58:21+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 17 22:58:21 volumio go-librespot[8282]: time="2026-02-17T22:58:21+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 17 22:58:21 volumio go-librespot[8282]: time="2026-02-17T22:58:21+07:00" level=info msg="zeroconf server listening on port 36397" Feb 17 22:58:21 volumio go-librespot[8282]: time="2026-02-17T22:58:21+07:00" level=debug msg="obtained new client token: AACeIBXQu2xmm40+YWaPmSVsf1b/hZ/JAhtrdMaw2dcf/QHLDzhcseRgGW62cQDEmuk7+rqBMYz5io53Bx2bhbxEShXPMlZ5kjCDpwdrgrR2wsuZSGeiYsqF7h0wN2o/rma6cONcpINzYvoHmHh61Uble1oeexCMrik2WHXd93U4iGW1pAgPCcDT4ShEfVWYlVcYnh3qnzFARwIjn/EFS4XgBDUnAosxlfkxqtUmenzOgb8twvfIgtOM5A==" Feb 17 22:58:22 volumio go-librespot[8282]: time="2026-02-17T22:58:22+07:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.241.202:4070: connect: connection refused" Feb 17 22:58:22 volumio go-librespot[8282]: time="2026-02-17T22:58:22+07:00" level=debug msg="connected to ap-gae2.spotify.com:443" Feb 17 22:58:22 volumio go-librespot[8282]: time="2026-02-17T22:58:22+07:00" level=debug msg="completed keyexchange" Feb 17 22:58:22 volumio go-librespot[8282]: time="2026-02-17T22:58:22+07:00" level=debug msg="completed challenge" Feb 17 22:58:22 volumio go-librespot[8282]: time="2026-02-17T22:58:22+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 17 22:58:22 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 17 22:58:22 volumio volumio[7834]: info: Connection to go-librespot Websocket closed Feb 17 22:58:22 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 17 22:58:22 volumio volumio[7834]: info: Setting Geolocation for MyVolumio to as1 Feb 17 22:58:22 volumio volumio[7834]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 22:58:22 volumio volumio[7834]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 22:58:22 volumio volumio[7834]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 22:58:23 volumio volumio[7834]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Feb 17 22:58:23 volumio volumio[7834]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 17 22:58:23 volumio volumio[7834]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 22:58:23 volumio volumio[7834]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 17 22:58:23 volumio volumio[7834]: info: Updating MyVolumio device info Feb 17 22:58:23 volumio volumio[7834]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 22:58:23 volumio volumio[7834]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 22:58:23 volumio volumio[7834]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 22:58:23 volumio volumio-remote-updater[25144]: Test mode disabled Feb 17 22:58:23 volumio volumio-remote-updater[25144]: Alpha mode disabled Feb 17 22:58:23 volumio volumio-remote-updater[25144]: Alpha legacy test mode disabled Feb 17 22:58:23 volumio volumio[7834]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Feb 17 22:58:24 volumio volumio[7834]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Feb 17 22:58:24 volumio volumio[7834]: info: Getting Spotify volume Feb 17 22:58:24 volumio volumio[7834]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 17 22:58:24 volumio volumio[7834]: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 17 22:58:24 volumio volumio[7834]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Feb 17 22:58:24 volumio volumio[7834]: errno: -111, Feb 17 22:58:24 volumio volumio[7834]: code: 'ECONNREFUSED', Feb 17 22:58:24 volumio volumio[7834]: syscall: 'connect', Feb 17 22:58:24 volumio volumio[7834]: address: '127.0.0.1', Feb 17 22:58:24 volumio volumio[7834]: port: 9879, Feb 17 22:58:24 volumio volumio[7834]: response: undefined Feb 17 22:58:24 volumio volumio[7834]: } Feb 17 22:58:24 volumio volumio[7834]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 17 22:58:25 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Feb 17 22:58:25 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 17 22:58:25 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 17 22:58:25 volumio go-librespot[8306]: go-librespot daemon starting... Feb 17 22:58:25 volumio go-librespot[8307]: time="2026-02-17T22:58:25+07:00" level=info msg="running go-librespot 0.4.0" Feb 17 22:58:25 volumio go-librespot[8307]: time="2026-02-17T22:58:25+07:00" level=debug msg="app state loaded" Feb 17 22:58:25 volumio go-librespot[8307]: time="2026-02-17T22:58:25+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 17 22:58:25 volumio sudo[8316]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-17 22:57' Feb 17 22:58:25 volumio sudo[8316]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 22:58:26 volumio go-librespot[8307]: time="2026-02-17T22:58:26+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 17 22:58:26 volumio go-librespot[8307]: time="2026-02-17T22:58:26+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 17 22:58:26 volumio go-librespot[8307]: time="2026-02-17T22:58:26+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 17 22:58:26 volumio go-librespot[8307]: time="2026-02-17T22:58:26+07:00" level=info msg="zeroconf server listening on port 42179" 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="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Dec 25 09:13:19 UTC 2025" VOLUMIO_VERSION="4.084" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="792f09af7488531aecd1aadcaa9b175e"