Feb 18 00:01:00 volumio volumio[5017]: info: Creating Spotify config file Feb 18 00:01:00 volumio volumio[5017]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:01:00 volumio sudo[5222]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 18 00:01:00 volumio sudo[5222]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 18 00:01:00 volumio sudo[5222]: pam_unix(sudo:session): session closed for user root Feb 18 00:01:01 volumio volumio[5148]: Starting albumart workers Feb 18 00:01:01 volumio volumio[5017]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 18 00:01:01 volumio volumio[5017]: info: [1771347661201] CoreMusicLibrary::Adding element YouTube Music Feb 18 00:01:01 volumio volumio[5017]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 00:01:01 volumio volumio[5017]: Cannot find translation for source YouTube Music Feb 18 00:01:01 volumio volumio[5147]: Starting albumart workers Feb 18 00:01:01 volumio volumio[5152]: Starting albumart workers Feb 18 00:01:01 volumio volumio[5017]: info: Volumio Calling Home Feb 18 00:01:01 volumio sudo[5243]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Feb 18 00:01:01 volumio sudo[5243]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 00:01:01 volumio sudo[5243]: pam_unix(sudo:session): session closed for user root Feb 18 00:01:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Feb 18 00:01:02 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 18 00:01:02 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 00:01:02 volumio go-librespot[5249]: go-librespot daemon starting... Feb 18 00:01:02 volumio volumio[5017]: info: Discovery: adding 921648ed-0557-4d70-9927-1420682efcc7 Feb 18 00:01:02 volumio volumio[5017]: info: Discovery: Found device Volumio Feb 18 00:01:02 volumio volumio[5017]: info: CoreCommandRouter::volumioGetState Feb 18 00:01:02 volumio volumio[5017]: info: CorePlayQueue::getTrack 0 Feb 18 00:01:02 volumio go-librespot[5250]: time="2026-02-18T00:01:02+07:00" level=info msg="running go-librespot 0.4.0" Feb 18 00:01:02 volumio go-librespot[5250]: time="2026-02-18T00:01:02+07:00" level=debug msg="app state loaded" Feb 18 00:01:02 volumio go-librespot[5250]: time="2026-02-18T00:01:02+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 00:01:02 volumio volumio[5017]: info: Discovery: this is already registered, 921648ed-0557-4d70-9927-1420682efcc7 Feb 18 00:01:02 volumio volumio[5017]: info: Discovery: Found device Volumio Feb 18 00:01:02 volumio volumio[5017]: info: CoreCommandRouter::volumioGetState Feb 18 00:01:02 volumio volumio[5017]: info: CorePlayQueue::getTrack 0 Feb 18 00:01:02 volumio volumio[5017]: info: MPD Permissions set Feb 18 00:01:02 volumio volumio[5017]: info: MPD Permissions set Feb 18 00:01:02 volumio volumio[5017]: info: Upmpdcli Daemon Started Feb 18 00:01:02 volumio volumio[5017]: info: Spotify config file written Feb 18 00:01:02 volumio volumio[5017]: 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 18 00:01:02 volumio sudo[5258]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 18 00:01:02 volumio sudo[5258]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 00:01:02 volumio volumio[5017]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:01:02 volumio volumio[5017]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:01:02 volumio volumio[5017]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:01:02 volumio volumio[5017]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:01:02 volumio volumio[5017]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:01:02 volumio volumio[5017]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:01:02 volumio volumio[5017]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:01:02 volumio volumio[5017]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:01:02 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Feb 18 00:01:02 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Feb 18 00:01:02 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 18 00:01:02 volumio volumio[5017]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 00:01:02 volumio volumio[5017]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:01:02 volumio volumio[5017]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:01:02 volumio volumio[5017]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:01:02 volumio volumio[5017]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:01:02 volumio volumio[5017]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:01:02 volumio volumio[5017]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:01:02 volumio volumio[5017]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:01:02 volumio volumio[5017]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:01:02 volumio volumio[5017]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:01:02 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 00:01:02 volumio go-librespot[5265]: go-librespot daemon starting... Feb 18 00:01:02 volumio sudo[5258]: pam_unix(sudo:session): session closed for user root Feb 18 00:01:02 volumio volumio[5017]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 00:01:02 volumio volumio[5017]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:01:02 volumio go-librespot[5267]: time="2026-02-18T00:01:02+07:00" level=info msg="running go-librespot 0.4.0" Feb 18 00:01:02 volumio go-librespot[5267]: time="2026-02-18T00:01:02+07:00" level=debug msg="app state loaded" Feb 18 00:01:02 volumio go-librespot[5267]: time="2026-02-18T00:01:02+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 00:01:02 volumio volumio[5017]: info: No need to fix Spotify hosts Feb 18 00:01:03 volumio volumio[5017]: info: Volumio called home Feb 18 00:01:03 volumio go-librespot[5267]: time="2026-02-18T00:01:03+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 18 00:01:03 volumio go-librespot[5267]: time="2026-02-18T00:01:03+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 18 00:01:03 volumio go-librespot[5267]: time="2026-02-18T00:01:03+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 18 00:01:03 volumio go-librespot[5267]: time="2026-02-18T00:01:03+07:00" level=info msg="zeroconf server listening on port 45507" Feb 18 00:01:03 volumio go-librespot[5267]: time="2026-02-18T00:01:03+07:00" level=debug msg="obtained new client token: AAD+x7dd8Or7rJAoHp68zQcgBoxTzWJLe3AbnW09rYkxC/tkXqO2dwhPUyenjug0dD8eI3V9eeBfRjZWABTes0xH6FNmUaDVG3Fkn9slO5TaQH5+mHezJDXbkOU5oW6Ve8M7iD9OS/dgfRo3TPsuYxGWQPT5QnJNJrsnLMvh+L09sG7AOUn6H+dFoUSm10xlfySduIrVzL+n7qA+w4Yr9XKI48d/kobtNwvBBfCtKC1L7GtvwikOv8ALHA==" Feb 18 00:01:04 volumio volumio[5017]: info: Starting Shairport Sync Feb 18 00:01:04 volumio volumio[5017]: info: Starting Shairport Sync Feb 18 00:01:04 volumio go-librespot[5267]: time="2026-02-18T00:01:04+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 00:01:04 volumio volumio[5017]: info: Starting Shairport Sync Feb 18 00:01:04 volumio sudo[5298]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 18 00:01:04 volumio sudo[5298]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 00:01:04 volumio sudo[5302]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 18 00:01:04 volumio sudo[5302]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 00:01:04 volumio go-librespot[5267]: time="2026-02-18T00:01:04+07:00" level=debug msg="completed keyexchange" Feb 18 00:01:04 volumio go-librespot[5267]: time="2026-02-18T00:01:04+07:00" level=debug msg="completed challenge" Feb 18 00:01:04 volumio sudo[5300]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 18 00:01:04 volumio sudo[5300]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 00:01:04 volumio go-librespot[5267]: time="2026-02-18T00:01:04+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 18 00:01:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 00:01:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 00:01:04 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 18 00:01:04 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 18 00:01:04 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 18 00:01:04 volumio systemd[1]: shairport-sync.service: Consumed 2.294s CPU time. Feb 18 00:01:04 volumio volumio[5017]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 18 00:01:04 volumio volumio[5017]: SPOTIFY: BQCS5jKjgko9akx1HRZajFqnxzhSEklfCMJlo4_3Xd1QndKrd8O8kaoiWE5wZBZEugJG9S_k57kX6xuS_DliwvNuWhdeKThF5qFCfEfwDx-jHFTKNSF48KlFb7kI1PBTGYSvwt43hntl_XrIJzoiUhSUHbLwkfHW90G9ZZg_Swo7sTqyyXvn5Zse_6nDaxGDd5R9RTT1sOhplsUO4-GpR12yyTU5BFtKeosbccBK7V4i-4X-4Pj3JKNNXaz6TTcQyEKmyDX3PWAs4fytIMHl9toX4qvQJCPmGeWMLVTeuNjmJSiFKMaCdYsd Feb 18 00:01:04 volumio volumio[5017]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 18 00:01:04 volumio volumio[5017]: info: New Spotify access token = BQCS5jKjgko9akx1HRZajFqnxzhSEklfCMJlo4_3Xd1QndKrd8O8kaoiWE5wZBZEugJG9S_k57kX6xuS_DliwvNuWhdeKThF5qFCfEfwDx-jHFTKNSF48KlFb7kI1PBTGYSvwt43hntl_XrIJzoiUhSUHbLwkfHW90G9ZZg_Swo7sTqyyXvn5Zse_6nDaxGDd5R9RTT1sOhplsUO4-GpR12yyTU5BFtKeosbccBK7V4i-4X-4Pj3JKNNXaz6TTcQyEKmyDX3PWAs4fytIMHl9toX4qvQJCPmGeWMLVTeuNjmJSiFKMaCdYsd Feb 18 00:01:04 volumio volumio[5017]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 18 00:01:04 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 18 00:01:04 volumio sudo[5300]: pam_unix(sudo:session): session closed for user root Feb 18 00:01:04 volumio sudo[5302]: pam_unix(sudo:session): session closed for user root Feb 18 00:01:04 volumio sudo[5298]: pam_unix(sudo:session): session closed for user root Feb 18 00:01:04 volumio volumio[5017]: info: CoreCommandRouter::volumioGetState Feb 18 00:01:04 volumio volumio[5017]: info: CorePlayQueue::getTrack 0 Feb 18 00:01:04 volumio volumio[5017]: info: Shairport-Sync Started Feb 18 00:01:04 volumio volumio[5017]: Error adding Membership: Error: addMembership EINVAL Feb 18 00:01:04 volumio volumio[5017]: info: Shairport-Sync Started Feb 18 00:01:04 volumio volumio[5017]: info: Shairport-Sync Started Feb 18 00:01:05 volumio volumio[5017]: 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 18 00:01:05 volumio volumio[5017]: info: Spotify Successfully logged in Feb 18 00:01:05 volumio volumio[5017]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 18 00:01:05 volumio volumio[5017]: info: [1771347665080] CoreMusicLibrary::Adding element Spotify Feb 18 00:01:05 volumio volumio[5017]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 00:01:05 volumio volumio[5017]: Cannot find translation for source YouTube Music Feb 18 00:01:05 volumio volumio[5017]: Cannot find translation for source Spotify Feb 18 00:01:05 volumio volumio[5017]: info: [yt-cast-receiver] DIAL server listening on port 8098 Feb 18 00:01:05 volumio volumio[5017]: info: CoreCommandRouter::volumioRetrievevolume Feb 18 00:01:05 volumio volumio[5017]: info: VolumeController:: Volume=100 Mute =false Feb 18 00:01:05 volumio volumio[5017]: info: CoreCommandRouter::volumioGetState Feb 18 00:01:05 volumio volumio[5017]: info: CorePlayQueue::getTrack 0 Feb 18 00:01:05 volumio volumio[5017]: info: CoreStateMachine::pushState Feb 18 00:01:05 volumio volumio[5017]: info: CorePlayQueue::getTrack 0 Feb 18 00:01:05 volumio volumio[5017]: info: CoreCommandRouter::volumioPushState Feb 18 00:01:07 volumio volumio[5017]: info: go-librespot daemon successfully initialized Feb 18 00:01:07 volumio mpd[5241]: 2026-02-18T00:01:07 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 18 00:01:07 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 18 00:01:07 volumio sudo[5214]: pam_unix(sudo:session): session closed for user root Feb 18 00:01:07 volumio sudo[5203]: pam_unix(sudo:session): session closed for user root Feb 18 00:01:07 volumio volumio[5017]: error: MPD error: The expression evaluated to a falsy value: Feb 18 00:01:07 volumio volumio[5017]: assert.ok(self.idling) Feb 18 00:01:07 volumio volumio[5017]: error: The expression evaluated to a falsy value: Feb 18 00:01:07 volumio volumio[5017]: assert.ok(self.idling) Feb 18 00:01:07 volumio volumio[5017]: info: MPD running with PID5241 Feb 18 00:01:07 volumio volumio[5017]: ,establishing connection Feb 18 00:01:07 volumio volumio[5017]: error: updateQueue error: null Feb 18 00:01:07 volumio volumio[5017]: info: Completed starting Core Plugins Feb 18 00:01:07 volumio volumio[5017]: info: ------------------------------------------- Feb 18 00:01:07 volumio volumio[5017]: info: ----- MyVolumio plugins startup ---- Feb 18 00:01:07 volumio volumio[5017]: info: ------------------------------------------- Feb 18 00:01:07 volumio volumio[5017]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 18 00:01:07 volumio volumio[5017]: error: updateQueue error: null Feb 18 00:01:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Feb 18 00:01:07 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 18 00:01:07 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 00:01:07 volumio go-librespot[5344]: go-librespot daemon starting... Feb 18 00:01:07 volumio go-librespot[5345]: time="2026-02-18T00:01:07+07:00" level=info msg="running go-librespot 0.4.0" Feb 18 00:01:07 volumio go-librespot[5345]: time="2026-02-18T00:01:07+07:00" level=debug msg="app state loaded" Feb 18 00:01:07 volumio go-librespot[5345]: time="2026-02-18T00:01:07+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 00:01:08 volumio go-librespot[5345]: time="2026-02-18T00:01: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-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 18 00:01:08 volumio go-librespot[5345]: time="2026-02-18T00:01:08+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 18 00:01:08 volumio go-librespot[5345]: time="2026-02-18T00:01:08+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 18 00:01:08 volumio go-librespot[5345]: time="2026-02-18T00:01:08+07:00" level=info msg="zeroconf server listening on port 43467" Feb 18 00:01:08 volumio go-librespot[5345]: time="2026-02-18T00:01:08+07:00" level=debug msg="obtained new client token: AAD0yj1kf/mHZxUucw1yQI4y8wv4lTV7IeTHuYsG5TgGlbkltAzJj5LpVXqUxMXpEdLxaUCHDJ29m+XKeS9IP1gBwXlRrDIaGd5kowt7Ejyni2Hb8+cyCoCqwOJ7IUGzYDuK+EcOQq67SFuBIp5W6Aj9kadLrIypNmJo5ElyG+jBEMetZhPt7Ti0L3zTequiir5sdwYlsIhnfeCgaUDRC0BMIU0r+/767D9hALlMGybPZcDWx5t5rmUNcQ==" Feb 18 00:01:08 volumio go-librespot[5345]: time="2026-02-18T00:01:08+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 00:01:08 volumio go-librespot[5345]: time="2026-02-18T00:01:08+07:00" level=debug msg="completed keyexchange" Feb 18 00:01:08 volumio go-librespot[5345]: time="2026-02-18T00:01:08+07:00" level=debug msg="completed challenge" Feb 18 00:01:09 volumio go-librespot[5345]: time="2026-02-18T00:01: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 18 00:01:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 00:01:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 00:01:10 volumio volumio[5017]: info: Initializing connection to go-librespot Websocket Feb 18 00:01:10 volumio volumio[5017]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 18 00:01:12 volumio volumio[5017]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 18 00:01:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Feb 18 00:01:12 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 18 00:01:12 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 00:01:12 volumio go-librespot[5352]: go-librespot daemon starting... Feb 18 00:01:12 volumio go-librespot[5353]: time="2026-02-18T00:01:12+07:00" level=info msg="running go-librespot 0.4.0" Feb 18 00:01:12 volumio go-librespot[5353]: time="2026-02-18T00:01:12+07:00" level=debug msg="app state loaded" Feb 18 00:01:12 volumio go-librespot[5353]: time="2026-02-18T00:01:12+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 00:01:13 volumio volumio[5017]: info: Initializing connection to go-librespot Websocket Feb 18 00:01:13 volumio go-librespot[5353]: time="2026-02-18T00:01:13+07:00" level=debug msg="new websocket client" Feb 18 00:01:13 volumio volumio[5017]: info: Connection to go-librespot Websocket established Feb 18 00:01:13 volumio go-librespot[5353]: time="2026-02-18T00:01: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-gew4.spotify.com:80]" Feb 18 00:01:13 volumio go-librespot[5353]: time="2026-02-18T00:01:13+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 18 00:01:13 volumio go-librespot[5353]: time="2026-02-18T00:01:13+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 18 00:01:13 volumio go-librespot[5353]: time="2026-02-18T00:01:13+07:00" level=info msg="zeroconf server listening on port 39673" Feb 18 00:01:13 volumio go-librespot[5353]: time="2026-02-18T00:01:13+07:00" level=debug msg="obtained new client token: AACWBLiyT4hqcRqS23p+MBC6HUWQcbF5PV/bpFYSJi0upRqAS73ZAB12L6uRQrWjCtSL2vbg4mADcXVA5Rl+ip2F+iXeoUmcOH/0WWHghkxMmzNn7sKL/L25kwauDLXaK2d/gth0gzyvoc/CDHfMDBER0wwtkPZbLJOzJODhAR2yu+TnyKqw9gMHF5kzm5aYRp1byLTd1/OIaD35Onet4FoT59XOrnW+okZhQZ2eCpYNewqUy8KK+6Np0w==" Feb 18 00:01:13 volumio go-librespot[5353]: time="2026-02-18T00:01:13+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 00:01:13 volumio go-librespot[5353]: time="2026-02-18T00:01:13+07:00" level=debug msg="completed keyexchange" Feb 18 00:01:13 volumio go-librespot[5353]: time="2026-02-18T00:01:13+07:00" level=debug msg="completed challenge" Feb 18 00:01:13 volumio go-librespot[5353]: time="2026-02-18T00:01:13+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 18 00:01:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 00:01:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 00:01:13 volumio volumio[5017]: info: Connection to go-librespot Websocket closed Feb 18 00:01:16 volumio volumio[5017]: info: Getting Spotify volume Feb 18 00:01:16 volumio volumio[5017]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 18 00:01:16 volumio volumio[5017]: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 18 00:01:16 volumio volumio[5017]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Feb 18 00:01:16 volumio volumio[5017]: errno: -111, Feb 18 00:01:16 volumio volumio[5017]: code: 'ECONNREFUSED', Feb 18 00:01:16 volumio volumio[5017]: syscall: 'connect', Feb 18 00:01:16 volumio volumio[5017]: address: '127.0.0.1', Feb 18 00:01:16 volumio volumio[5017]: port: 9879, Feb 18 00:01:16 volumio volumio[5017]: response: undefined Feb 18 00:01:16 volumio volumio[5017]: } Feb 18 00:01:16 volumio volumio[5017]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 18 00:01:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Feb 18 00:01:16 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 18 00:01:16 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 00:01:16 volumio go-librespot[5386]: go-librespot daemon starting... Feb 18 00:01:16 volumio go-librespot[5387]: time="2026-02-18T00:01:16+07:00" level=info msg="running go-librespot 0.4.0" Feb 18 00:01:16 volumio go-librespot[5387]: time="2026-02-18T00:01:16+07:00" level=debug msg="app state loaded" Feb 18 00:01:16 volumio go-librespot[5387]: time="2026-02-18T00:01:16+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 00:01:17 volumio go-librespot[5387]: time="2026-02-18T00:01: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 18 00:01:17 volumio go-librespot[5387]: time="2026-02-18T00:01: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 18 00:01:17 volumio go-librespot[5387]: time="2026-02-18T00:01: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 18 00:01:17 volumio go-librespot[5387]: time="2026-02-18T00:01:17+07:00" level=info msg="zeroconf server listening on port 33237" Feb 18 00:01:17 volumio go-librespot[5387]: time="2026-02-18T00:01:17+07:00" level=debug msg="obtained new client token: AABvxqtCigK/dX+8uMnOloygCEPdqBTAPm6db+R+fD/o0KFKYqvV3K5f2dGmYdDaiHW/qNq82F7opJFC2HEEmyQ5wJtMFZ+pgb0YZKw+I6PeO0b4zbLVylgqexPI3gokeknfZxya12Amu1ks+/R7QApBY7c1thW1DnmI0H20FbKDaQImdwHFxOKMWKHKXTtdp2S8c633agrKRemnz84tGgLQmzdNwZqga5OPYOhWWuWarOmdnTOW7Uuf7w==" Feb 18 00:01:17 volumio sudo[5396]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-18 00:00' Feb 18 00:01:17 volumio sudo[5396]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 00:01:17 volumio go-librespot[5387]: time="2026-02-18T00:01:17+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 00:01:17 volumio go-librespot[5387]: time="2026-02-18T00:01:17+07:00" level=debug msg="completed keyexchange" Feb 18 00:01:17 volumio go-librespot[5387]: time="2026-02-18T00:01:17+07:00" level=debug msg="completed challenge" Feb 18 00:01:17 volumio go-librespot[5387]: time="2026-02-18T00:01: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 18 00:01:17 volumio sudo[5396]: pam_unix(sudo:session): session closed for user root Feb 18 00:01:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 00:01:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 00:01:18 volumio volumio-remote-updater[25144]: [2026-02-18 00:01:18] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 18 00:01:18 volumio volumio-remote-updater[25144]: [2026-02-18 00:01:18] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 18 00:01:18 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 18 00:01:18 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 18 00:01:18 volumio systemd[1]: volumio.service: Consumed 49.285s CPU time. Feb 18 00:01:18 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 18 00:01:18 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 18 00:01:18 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 21093. Feb 18 00:01:18 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 18 00:01:18 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 18 00:01:18 volumio systemd[1]: volumio.service: Consumed 49.285s CPU time. Feb 18 00:01:18 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Feb 18 00:01:18 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 18 00:01:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Feb 18 00:01:21 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 18 00:01:21 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 00:01:21 volumio go-librespot[5431]: go-librespot daemon starting... Feb 18 00:01:21 volumio go-librespot[5432]: time="2026-02-18T00:01:21+07:00" level=info msg="running go-librespot 0.4.0" Feb 18 00:01:21 volumio go-librespot[5432]: time="2026-02-18T00:01:21+07:00" level=debug msg="app state loaded" Feb 18 00:01:21 volumio go-librespot[5432]: time="2026-02-18T00:01:21+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 00:01:21 volumio go-librespot[5432]: time="2026-02-18T00:01: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 18 00:01:21 volumio go-librespot[5432]: time="2026-02-18T00:01: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 18 00:01:21 volumio go-librespot[5432]: time="2026-02-18T00:01: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 18 00:01:21 volumio go-librespot[5432]: time="2026-02-18T00:01:21+07:00" level=info msg="zeroconf server listening on port 36667" Feb 18 00:01:21 volumio go-librespot[5432]: time="2026-02-18T00:01:21+07:00" level=debug msg="obtained new client token: AADzR5Owy9rlvQyXmDtj81XKVYZtZBKnrZZWx88/Vyvsc4CXk83DIUV5MkaCR1B2/a2QOOTDJRgjOE1s0XZOp/1jOhOXtrtIc6ra1y30xgDphQSgE7HfXERhsXrE+ZYkzPrr97Zwc5XqtLK2lj5/UsV025ks/fALftFWkjXUBVpbgMCs5P2PEIG1Y9GeEO8MNrKmxUVWPIuiw339y9UGc1PJRHyJn6z1jQmy0SOIqDtS/743Jod0I9hVdg==" Feb 18 00:01:22 volumio volumio[5415]: info: ------------------------------------------- Feb 18 00:01:22 volumio volumio[5415]: info: ----- Volumio3 ---- Feb 18 00:01:22 volumio volumio[5415]: info: ------------------------------------------- Feb 18 00:01:22 volumio volumio[5415]: info: ----- System startup ---- Feb 18 00:01:22 volumio volumio[5415]: info: ------------------------------------------- Feb 18 00:01:22 volumio go-librespot[5432]: time="2026-02-18T00:01:22+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 00:01:22 volumio go-librespot[5432]: time="2026-02-18T00:01:22+07:00" level=debug msg="completed keyexchange" Feb 18 00:01:22 volumio go-librespot[5432]: time="2026-02-18T00:01:22+07:00" level=debug msg="completed challenge" Feb 18 00:01:22 volumio go-librespot[5432]: time="2026-02-18T00:01: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 18 00:01:22 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 00:01:22 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 00:01:23 volumio volumio-remote-updater[25144]: [2026-02-18 00:01:23] [connect] Successful connection Feb 18 00:01:23 volumio volumio[5415]: info: MYVOLUMIO Environment detected Feb 18 00:01:23 volumio volumio[5415]: info: Plugin folders cleanup Feb 18 00:01:23 volumio volumio[5415]: info: Scanning into folder /volumio/app/plugins/ Feb 18 00:01:23 volumio volumio[5415]: info: Scanning category audio_interface Feb 18 00:01:23 volumio volumio[5415]: info: Scanning category miscellanea Feb 18 00:01:23 volumio volumio[5415]: info: Scanning category music_service Feb 18 00:01:23 volumio volumio[5415]: info: Scanning category plugins.json Feb 18 00:01:23 volumio volumio[5415]: info: Scanning category system_controller Feb 18 00:01:23 volumio volumio[5415]: info: Scanning category user_interface Feb 18 00:01:23 volumio volumio[5415]: info: Scanning into folder /data/plugins/ Feb 18 00:01:23 volumio volumio[5415]: info: Scanning category music_service Feb 18 00:01:23 volumio volumio[5415]: info: Plugin folders cleanup completed Feb 18 00:01:23 volumio volumio[5415]: info: ------------------------------------------- Feb 18 00:01:23 volumio volumio[5415]: info: ----- Core plugins startup ---- Feb 18 00:01:23 volumio volumio[5415]: info: ------------------------------------------- Feb 18 00:01:23 volumio volumio[5415]: info: Loading plugins from folder /volumio/app/plugins/ Feb 18 00:01:23 volumio volumio[5415]: info: Adding plugin upnp to MyMusic Plugins Feb 18 00:01:23 volumio volumio[5415]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 18 00:01:23 volumio volumio[5415]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 18 00:01:23 volumio volumio[5415]: info: Loading plugins from folder /data/plugins/ Feb 18 00:01:23 volumio volumio[5415]: info: Loading plugin "system"... Feb 18 00:01:23 volumio volumio[5415]: info: Loading plugin "appearance"... Feb 18 00:01:25 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Feb 18 00:01:25 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 18 00:01:25 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 00:01:25 volumio go-librespot[5453]: go-librespot daemon starting... Feb 18 00:01:25 volumio go-librespot[5454]: time="2026-02-18T00:01:25+07:00" level=info msg="running go-librespot 0.4.0" Feb 18 00:01:25 volumio go-librespot[5454]: time="2026-02-18T00:01:25+07:00" level=debug msg="app state loaded" Feb 18 00:01:25 volumio go-librespot[5454]: time="2026-02-18T00:01:25+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 00:01:25 volumio volumio[5415]: info: Loading plugin "network"... Feb 18 00:01:25 volumio volumio[5415]: info: Refreshing Cached IP Addresses Feb 18 00:01:25 volumio sudo[5461]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 18 00:01:25 volumio sudo[5461]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 00:01:25 volumio sudo[5463]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 18 00:01:25 volumio sudo[5463]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 00:01:25 volumio sudo[5461]: pam_unix(sudo:session): session closed for user root Feb 18 00:01:25 volumio sudo[5463]: pam_unix(sudo:session): session closed for user root Feb 18 00:01:25 volumio volumio[5415]: info: Loading plugin "services"... Feb 18 00:01:25 volumio volumio[5415]: info: Loading plugin "alsa_controller"... Feb 18 00:01:25 volumio sudo[5472]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 18 00:01:25 volumio sudo[5472]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 00:01:25 volumio volumio[5415]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 18 00:01:25 volumio volumio[5415]: info: Loading plugin "wizard"... Feb 18 00:01:25 volumio volumio[5415]: info: Loading plugin "networkfs"... Feb 18 00:01:25 volumio volumio[5415]: info: Starting Udev Watcher for removable devices Feb 18 00:01:25 volumio volumio[5415]: info: Ignoring mount for partition: boot Feb 18 00:01:25 volumio volumio[5415]: info: Ignoring mount for partition: volumio Feb 18 00:01:25 volumio volumio[5415]: info: Ignoring mount for partition: volumio_data Feb 18 00:01:25 volumio volumio[5415]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 18 00:01:25 volumio volumio[5415]: info: Loading plugin "volumio_command_line_client"... Feb 18 00:01:25 volumio volumio[5415]: info: Loading plugin "upnp"... Feb 18 00:01:25 volumio volumio[5415]: info: [1771347685979] Starting Upmpd Daemon Feb 18 00:01:25 volumio volumio[5415]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 18 00:01:25 volumio volumio[5415]: info: Loading plugin "my_music"... Feb 18 00:01:25 volumio volumio[5415]: info: Loading plugin "mpd"... Feb 18 00:01:26 volumio go-librespot[5454]: time="2026-02-18T00:01: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 18 00:01:26 volumio go-librespot[5454]: time="2026-02-18T00:01: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 18 00:01:26 volumio go-librespot[5454]: time="2026-02-18T00:01: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 18 00:01:26 volumio go-librespot[5454]: time="2026-02-18T00:01:26+07:00" level=info msg="zeroconf server listening on port 35793" Feb 18 00:01:26 volumio go-librespot[5454]: time="2026-02-18T00:01:26+07:00" level=debug msg="obtained new client token: AAA8LMN4mHcz60Oq3dz6LTwZaG5BRlE1NFDhqTcrq3B2QE9BVTHGnY1AAFJZIY99mxkaujNCdmt6me/1nMdR69B+13k6urQ1HZ6tB8/JQZwZ3YbuCnGGgqNlov1/Sspz14aIqY7KrMg9cDG1JNm6qWChLeD5BIL7PKYEJLZtyrHK0XHtg4m9jeXi2VkcrPVOTG5vzyHe1aFsY4TL6GD5iW3GINFVuOOTr1RSdCOsoB1Wg1ix8LG6ZGM=" Feb 18 00:01:26 volumio go-librespot[5454]: time="2026-02-18T00:01:26+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 00:01:26 volumio go-librespot[5454]: time="2026-02-18T00:01:26+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 18 00:01:26 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 00:01:26 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 00:01:26 volumio volumio[5415]: info: Loading plugin "upnp_browser"... Feb 18 00:01:28 volumio sudo[5472]: pam_unix(sudo:session): session closed for user root Feb 18 00:01:29 volumio volumio[5415]: info: Starting UPNP Browser Feb 18 00:01:29 volumio volumio[5415]: info: Loading plugin "alarm-clock"... Feb 18 00:01:29 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Feb 18 00:01:29 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 18 00:01:29 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 00:01:29 volumio go-librespot[5497]: go-librespot daemon starting... Feb 18 00:01:29 volumio go-librespot[5498]: time="2026-02-18T00:01:29+07:00" level=info msg="running go-librespot 0.4.0" Feb 18 00:01:29 volumio go-librespot[5498]: time="2026-02-18T00:01:29+07:00" level=debug msg="app state loaded" Feb 18 00:01:29 volumio go-librespot[5498]: time="2026-02-18T00:01:29+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 00:01:29 volumio volumio[5415]: info: Loading plugin "airplay_emulation"... Feb 18 00:01:29 volumio volumio[5415]: info: Starting Shairport Sync Feb 18 00:01:29 volumio volumio[5415]: info: Loading plugin "last_100"... Feb 18 00:01:29 volumio volumio[5415]: info: Loading plugin "webradio"... Feb 18 00:01:30 volumio volumio[5415]: info: Loading plugin "i2s_dacs"... Feb 18 00:01:30 volumio volumio[5415]: info: Loading plugin "volumiodiscovery"... Feb 18 00:01:30 volumio volumio[5415]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 18 00:01:30 volumio volumio[5415]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 18 00:01:30 volumio volumio[5415]: *** WARNING *** For more information see Feb 18 00:01:30 volumio volumio[5415]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 18 00:01:30 volumio volumio[5415]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 18 00:01:30 volumio volumio[5415]: *** WARNING *** For more information see Feb 18 00:01:30 volumio node[5415]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 18 00:01:30 volumio node[5415]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 18 00:01:30 volumio node[5415]: *** WARNING *** For more information see Feb 18 00:01:30 volumio node[5415]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 18 00:01:30 volumio node[5415]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 18 00:01:30 volumio node[5415]: *** WARNING *** For more information see Feb 18 00:01:30 volumio volumio[5415]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 18 00:01:30 volumio volumio[5415]: info: Discovery: Started advertising with name: Volumio Feb 18 00:01:30 volumio go-librespot[5498]: time="2026-02-18T00:01: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-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 18 00:01:30 volumio go-librespot[5498]: time="2026-02-18T00:01:30+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 18 00:01:30 volumio go-librespot[5498]: time="2026-02-18T00:01:30+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 18 00:01:30 volumio go-librespot[5498]: time="2026-02-18T00:01:30+07:00" level=info msg="zeroconf server listening on port 45429" Feb 18 00:01:30 volumio volumio[5415]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 18 00:01:30 volumio volumio[5415]: info: Loading plugin "spop"... Feb 18 00:01:30 volumio go-librespot[5498]: time="2026-02-18T00:01:30+07:00" level=debug msg="obtained new client token: AAAChSsDZUAa7bC8JkNtD8tidlnD41Pu6slhVc9CVCMZUCoKn6IJbBztFjZCDwTXMYTY+EvmWfNOS+lovcuZl5KoyEkgxHdCQjA/11AY6QAhZru0e3g+tqROI/BbO8xcdCrD/ppANs/0J8M8JG9/lO3fyxWIivhXXMfgbTbwSFTj0boMVH1Y1rBvsT37sAr+n9hUEVZyKNhlEFh2QorZxdKl+FQjboVyQvCRMDaFyOXpg9kyCKQoJ5mIpQ==" Feb 18 00:01:30 volumio go-librespot[5498]: time="2026-02-18T00:01:30+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 00:01:30 volumio go-librespot[5498]: time="2026-02-18T00:01:30+07:00" level=debug msg="completed keyexchange" Feb 18 00:01:30 volumio go-librespot[5498]: time="2026-02-18T00:01:30+07:00" level=debug msg="completed challenge" Feb 18 00:01:30 volumio go-librespot[5498]: time="2026-02-18T00:01:30+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 18 00:01:30 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 00:01:30 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 00:01:32 volumio volumio[5415]: info: Loading plugin "ytcr"... Feb 18 00:01:34 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Feb 18 00:01:34 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 18 00:01:34 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 00:01:34 volumio go-librespot[5508]: go-librespot daemon starting... Feb 18 00:01:34 volumio go-librespot[5509]: time="2026-02-18T00:01:34+07:00" level=info msg="running go-librespot 0.4.0" Feb 18 00:01:34 volumio go-librespot[5509]: time="2026-02-18T00:01:34+07:00" level=debug msg="app state loaded" Feb 18 00:01:34 volumio go-librespot[5509]: time="2026-02-18T00:01:34+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 00:01:34 volumio go-librespot[5509]: time="2026-02-18T00:01:34+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 18 00:01:34 volumio go-librespot[5509]: time="2026-02-18T00:01:34+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 18 00:01:34 volumio go-librespot[5509]: time="2026-02-18T00:01:34+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 18 00:01:34 volumio go-librespot[5509]: time="2026-02-18T00:01:34+07:00" level=info msg="zeroconf server listening on port 43299" Feb 18 00:01:34 volumio volumio[5415]: info: Loading plugin "ytmusic"... Feb 18 00:01:35 volumio go-librespot[5509]: time="2026-02-18T00:01:35+07:00" level=debug msg="obtained new client token: AABRfw+7XHO7f2J5DJ4A8b+os1Q89vxVntgoyT/crkTmLy0zNsHPwoHVTrpvc2iu41QnvRhs3ww2mh1d3NV6U0Wmjau8oFaNe+hjxl7bH6hakdrHE/Xss005kIHgdhC17ECxrbp+1nz4Ik49JV7zPcyW0Bpif0kgEqfTSLra+2IRO4gSI3OlpKUUJ4HGVp47iCh+VK0BaJRpYNOsrzOSnsqX+pZLVsqHG+15ymX1f2ftcYiCjpNzo0U5+A==" Feb 18 00:01:35 volumio go-librespot[5509]: time="2026-02-18T00:01:35+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 00:01:35 volumio go-librespot[5509]: time="2026-02-18T00:01:35+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 18 00:01:35 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 00:01:35 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 00:01:35 volumio volumio-remote-updater[25144]: [2026-02-18 00:01:35] [connect] Successful connection Feb 18 00:01:35 volumio volumio[5415]: info: Loading plugin "outputs"... Feb 18 00:01:35 volumio volumio[5415]: info: Loading plugin "albumart"... Feb 18 00:01:35 volumio volumio[5415]: info: Plugin example_plugin is not enabled Feb 18 00:01:35 volumio volumio[5415]: info: Loading plugin "inputs"... Feb 18 00:01:35 volumio volumio[5415]: info: Loading plugin "updater_comm"... Feb 18 00:01:36 volumio volumio[5415]: info: Plugin mpdemulation is not enabled Feb 18 00:01:36 volumio volumio[5415]: info: Loading plugin "rest_api"... Feb 18 00:01:36 volumio volumio[5415]: info: Loading plugin "websocket"... Feb 18 00:01:36 volumio volumio[5415]: info: Starting Socket.io Server version 1.7.4 Feb 18 00:01:36 volumio volumio[5415]: info: Loading plugin "RoonBridge"... Feb 18 00:01:36 volumio volumio[5415]: info: Applying required configuration parameters for plugin RoonBridge Feb 18 00:01:36 volumio volumio[5415]: info: Loading i18n strings for locale en Feb 18 00:01:36 volumio volumio[5415]: Updating browse sources language Feb 18 00:01:36 volumio volumio[5415]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 00:01:36 volumio volumio[5530]: Forking 3 albumart workers Feb 18 00:01:36 volumio volumio[5415]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 18 00:01:36 volumio volumio[5415]: info: CoreCommandRouter::initPlayerControls Feb 18 00:01:36 volumio volumio[5415]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:01:36 volumio volumio[5415]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:01:36 volumio volumio[5415]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:01:36 volumio volumio[5415]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:01:36 volumio volumio[5415]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:01:36 volumio volumio[5415]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:01:36 volumio volumio[5415]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:01:36 volumio volumio[5415]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:01:36 volumio volumio[5415]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 18 00:01:36 volumio volumio[5415]: Express server listening on port 3000 Feb 18 00:01:36 volumio volumio[5415]: [Metrics] WebUI: 15s 994.77ms Feb 18 00:01:37 volumio volumio[5415]: info: CoreStateMachine::resetVolumioState Feb 18 00:01:37 volumio volumio[5415]: info: CoreStateMachine::getcurrentVolume Feb 18 00:01:37 volumio volumio[5415]: info: CoreCommandRouter::volumioRetrievevolume Feb 18 00:01:37 volumio sudo[5572]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 18 00:01:37 volumio sudo[5572]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 00:01:37 volumio sudo[5572]: pam_unix(sudo:session): session closed for user root Feb 18 00:01:37 volumio sudo[5574]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 18 00:01:37 volumio sudo[5574]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 00:01:37 volumio sudo[5574]: pam_unix(sudo:session): session closed for user root Feb 18 00:01:37 volumio volumio[5415]: info: Volumio Network Manager: Network status updated: 1 Feb 18 00:01:37 volumio volumio[5415]: info: VolumeController:: Volume=100 Mute =false Feb 18 00:01:37 volumio volumio[5415]: info: CoreStateMachine::pushState Feb 18 00:01:37 volumio volumio[5415]: info: CorePlayQueue::getTrack 0 Feb 18 00:01:37 volumio volumio[5415]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 00:01:37 volumio volumio[5415]: info: CoreCommandRouter::volumioPushState Feb 18 00:01:37 volumio volumio[5415]: info: CoreStateMachine::updateTrackBlock Feb 18 00:01:37 volumio volumio[5415]: info: CorePlayQueue::getTrackBlock Feb 18 00:01:37 volumio volumio[5415]: info: CoreCommandRouter::volumioRetrievevolume Feb 18 00:01:37 volumio volumio-remote-updater[25144]: [2026-02-18 00:01:37] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1771347695 101 Feb 18 00:01:37 volumio volumio[5415]: 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 18 00:01:37 volumio volumio[5415]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 00:01:37 volumio volumio[5415]: info: Reloading queue from file Feb 18 00:01:37 volumio volumio[5415]: info: CoreStateMachine::setRepeat null single undefined Feb 18 00:01:37 volumio volumio[5415]: info: CoreStateMachine::pushState Feb 18 00:01:37 volumio volumio[5415]: info: CorePlayQueue::getTrack 0 Feb 18 00:01:37 volumio volumio[5415]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 00:01:37 volumio volumio[5415]: info: CoreCommandRouter::volumioPushState Feb 18 00:01:37 volumio volumio[5415]: info: CoreStateMachine::setRandom null Feb 18 00:01:37 volumio volumio[5415]: info: CoreStateMachine::pushState Feb 18 00:01:37 volumio volumio[5415]: info: CorePlayQueue::getTrack 0 Feb 18 00:01:37 volumio volumio[5415]: info: CoreCommandRouter::volumioPushState Feb 18 00:01:37 volumio volumio[5415]: info: Setting Device type: Raspberry PI Feb 18 00:01:37 volumio volumio[5415]: info: Completed loading Core Plugins Feb 18 00:01:37 volumio volumio[5415]: info: Preparing to generate the ALSA configuration file Feb 18 00:01:38 volumio volumio[5415]: info: VolumeController:: Volume=100 Mute =false Feb 18 00:01:38 volumio volumio[5415]: info: CoreStateMachine::pushState Feb 18 00:01:38 volumio volumio[5415]: info: CorePlayQueue::getTrack 0 Feb 18 00:01:38 volumio volumio[5415]: info: CoreCommandRouter::volumioPushState Feb 18 00:01:38 volumio volumio[5415]: info: Asound.conf file unchanged, so no further update is needed Feb 18 00:01:38 volumio volumio[5415]: info: Output device has changed, restarting MPD Feb 18 00:01:38 volumio volumio[5415]: info: Output device has changed, restarting Shairport Sync Feb 18 00:01:38 volumio volumio[5415]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:01:38 volumio volumio[5415]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 00:01:38 volumio sudo[5592]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 18 00:01:38 volumio sudo[5592]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 00:01:38 volumio sudo[5596]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 18 00:01:38 volumio sudo[5596]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 00:01:38 volumio sudo[5594]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 18 00:01:38 volumio volumio[5415]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 18 00:01:38 volumio sudo[5594]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 00:01:38 volumio volumio[5415]: info: ___________ START PLUGINS ___________ Feb 18 00:01:38 volumio sudo[5594]: pam_unix(sudo:session): session closed for user root Feb 18 00:01:38 volumio volumio[5415]: info: ControllerMpd::onStart: Initializing MPD Feb 18 00:01:38 volumio volumio[5415]: info: Creating MPD Configuration file Feb 18 00:01:38 volumio volumio[5415]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 18 00:01:38 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Feb 18 00:01:38 volumio volumio[5415]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 18 00:01:38 volumio volumio[5415]: info: [1771347698345] CoreMusicLibrary::Adding element Media Servers Feb 18 00:01:38 volumio volumio[5415]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 00:01:38 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 18 00:01:38 volumio volumio[5415]: info: UPNP Browser: Client initialized successfully Feb 18 00:01:38 volumio sudo[5606]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 18 00:01:38 volumio sudo[5606]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 00:01:38 volumio sudo[5606]: pam_unix(sudo:session): session closed for user root Feb 18 00:01:38 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 00:01:38 volumio sudo[5607]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 18 00:01:38 volumio go-librespot[5608]: go-librespot daemon starting... Feb 18 00:01:38 volumio sudo[5607]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 00:01:38 volumio volumio[5415]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:01:38 volumio volumio[5415]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 00:01:38 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 18 00:01:38 volumio sudo[5592]: pam_unix(sudo:session): session closed for user root Feb 18 00:01:38 volumio go-librespot[5610]: time="2026-02-18T00:01:38+07:00" level=info msg="running go-librespot 0.4.0" Feb 18 00:01:38 volumio go-librespot[5610]: time="2026-02-18T00:01:38+07:00" level=debug msg="app state loaded" Feb 18 00:01:38 volumio go-librespot[5610]: time="2026-02-18T00:01:38+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 00:01:38 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 18 00:01:38 volumio volumio[5415]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 18 00:01:38 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 18 00:01:38 volumio volumio[5415]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:01:38 volumio volumio[5415]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 00:01:38 volumio systemd[1]: mpd.service: Consumed 7.159s CPU time. Feb 18 00:01:38 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 18 00:01:38 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 18 00:01:38 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 18 00:01:38 volumio volumio[5415]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 18 00:01:38 volumio volumio[5415]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 18 00:01:38 volumio volumio[5415]: info: [1771347698764] CoreMusicLibrary::Adding element Last_100 Feb 18 00:01:38 volumio volumio[5415]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 00:01:38 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 18 00:01:38 volumio volumio[5415]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 18 00:01:38 volumio volumio[5415]: info: [1771347698798] CoreMusicLibrary::Adding element Webradio Feb 18 00:01:38 volumio volumio[5415]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 00:01:38 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 18 00:01:38 volumio volumio[5415]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 18 00:01:38 volumio volumio[5415]: info: Initializing BBC Radios Feb 18 00:01:39 volumio volumio[5415]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 18 00:01:39 volumio volumio[5415]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 00:01:39 volumio volumio[5415]: info: Creating Spotify config file Feb 18 00:01:39 volumio volumio[5415]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:01:39 volumio go-librespot[5610]: time="2026-02-18T00:01: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 18 00:01:39 volumio go-librespot[5610]: time="2026-02-18T00:01: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 18 00:01:39 volumio go-librespot[5610]: time="2026-02-18T00:01: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 18 00:01:39 volumio sudo[5626]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 18 00:01:39 volumio sudo[5626]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 18 00:01:39 volumio sudo[5626]: pam_unix(sudo:session): session closed for user root Feb 18 00:01:39 volumio go-librespot[5610]: time="2026-02-18T00:01:39+07:00" level=info msg="zeroconf server listening on port 43059" Feb 18 00:01:39 volumio go-librespot[5610]: time="2026-02-18T00:01:39+07:00" level=debug msg="obtained new client token: AABvuIiWTKgzTYz/fEhnX5z44SVe3FSAE1Z7buHhdkVAopXCr23CtFdUOeA8V+Eke0/S+qMB5GhFSqwR0Cp5c/ToeQ9rosflir0IchwvA5FdjIXrkGsg/9narQw0esAk1kzK/b4ce3gLJoyZ/lx5zESg1Uiduv3oTeJazY8e5GDb9XZ7a39R1tjkAaDdH9BUIGQR9wmXBQUCU0dp2N7H11VoepQOkT7PsSwgAp8ZU7bQMshRt5oceCoLAA==" Feb 18 00:01:39 volumio go-librespot[5610]: time="2026-02-18T00:01:39+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 00:01:39 volumio go-librespot[5610]: time="2026-02-18T00:01:39+07:00" level=debug msg="completed keyexchange" Feb 18 00:01:39 volumio go-librespot[5610]: time="2026-02-18T00:01:39+07:00" level=debug msg="completed challenge" Feb 18 00:01:39 volumio go-librespot[5610]: time="2026-02-18T00:01:39+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 18 00:01:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 00:01:39 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 00:01:40 volumio volumio[5541]: Starting albumart workers Feb 18 00:01:40 volumio volumio[5540]: Starting albumart workers Feb 18 00:01:40 volumio volumio[5415]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 18 00:01:40 volumio volumio[5415]: info: [1771347700613] CoreMusicLibrary::Adding element YouTube Music Feb 18 00:01:40 volumio volumio[5415]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 00:01:40 volumio volumio[5415]: Cannot find translation for source YouTube Music Feb 18 00:01:40 volumio volumio[5415]: info: Volumio Calling Home Feb 18 00:01:40 volumio sudo[5646]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Feb 18 00:01:40 volumio sudo[5646]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 00:01:40 volumio volumio[5542]: Starting albumart workers Feb 18 00:01:40 volumio sudo[5646]: pam_unix(sudo:session): session closed for user root Feb 18 00:01:41 volumio volumio[5415]: info: Discovery: adding 921648ed-0557-4d70-9927-1420682efcc7 Feb 18 00:01:41 volumio volumio[5415]: info: Discovery: Found device Volumio Feb 18 00:01:41 volumio volumio[5415]: info: CoreCommandRouter::volumioGetState Feb 18 00:01:41 volumio volumio[5415]: info: CorePlayQueue::getTrack 0 Feb 18 00:01:41 volumio volumio[5415]: info: MPD Permissions set Feb 18 00:01:41 volumio volumio[5415]: info: MPD Permissions set Feb 18 00:01:41 volumio volumio[5415]: info: Upmpdcli Daemon Started Feb 18 00:01:41 volumio volumio[5415]: info: Discovery: this is already registered, 921648ed-0557-4d70-9927-1420682efcc7 Feb 18 00:01:41 volumio volumio[5415]: info: Discovery: Found device Volumio Feb 18 00:01:41 volumio volumio[5415]: info: CoreCommandRouter::volumioGetState Feb 18 00:01:41 volumio volumio[5415]: info: CorePlayQueue::getTrack 0 Feb 18 00:01:41 volumio volumio[5415]: info: Volumio called home Feb 18 00:01:41 volumio volumio[5415]: info: Spotify config file written Feb 18 00:01:42 volumio sudo[5659]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 18 00:01:42 volumio sudo[5659]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 00:01:42 volumio volumio[5415]: 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 18 00:01:42 volumio volumio[5415]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:01:42 volumio volumio[5415]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:01:42 volumio volumio[5415]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:01:42 volumio volumio[5415]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:01:42 volumio volumio[5415]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:01:42 volumio volumio[5415]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:01:42 volumio volumio[5415]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:01:42 volumio volumio[5415]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:01:42 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 18 00:01:42 volumio volumio[5415]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 00:01:42 volumio volumio[5415]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:01:42 volumio volumio[5415]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:01:42 volumio volumio[5415]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:01:42 volumio volumio[5415]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:01:42 volumio volumio[5415]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:01:42 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 00:01:42 volumio volumio[5415]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:01:42 volumio go-librespot[5663]: go-librespot daemon starting... Feb 18 00:01:42 volumio volumio[5415]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:01:42 volumio volumio[5415]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:01:42 volumio volumio[5415]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:01:42 volumio sudo[5659]: pam_unix(sudo:session): session closed for user root Feb 18 00:01:42 volumio go-librespot[5667]: time="2026-02-18T00:01:42+07:00" level=info msg="running go-librespot 0.4.0" Feb 18 00:01:42 volumio go-librespot[5667]: time="2026-02-18T00:01:42+07:00" level=debug msg="app state loaded" Feb 18 00:01:42 volumio go-librespot[5667]: time="2026-02-18T00:01:42+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 00:01:42 volumio volumio[5415]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 00:01:42 volumio volumio[5415]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:01:42 volumio volumio[5415]: info: No need to fix Spotify hosts Feb 18 00:01:42 volumio go-librespot[5667]: time="2026-02-18T00:01:42+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 18 00:01:42 volumio go-librespot[5667]: time="2026-02-18T00:01:42+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 18 00:01:42 volumio go-librespot[5667]: time="2026-02-18T00:01:42+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 18 00:01:42 volumio go-librespot[5667]: time="2026-02-18T00:01:42+07:00" level=info msg="zeroconf server listening on port 32981" Feb 18 00:01:43 volumio go-librespot[5667]: time="2026-02-18T00:01:43+07:00" level=debug msg="obtained new client token: AAACdsxIRAPfDVDhiQp/9RkF5o3WpbAKPtlCD58ZpELilKZeko4HewiNeWobfrF2pCHwdjJWU51qjcBaO0qJzFPERFhpFEwjeylFHWLIkgQHSqyeE16gaa4e9QpMVWcSqHDgUno0hTr56uvq3rSV5uD7+Sfx/FIJKAu13MeZwcUGWwxhifR1so4UPl+KRL6hNEvVs6OjWRjZfZiRY8zkEd0TnnttPhpzthFJzeXm1HsiRAl/1hudnaY=" Feb 18 00:01:43 volumio volumio[5415]: info: Starting Shairport Sync Feb 18 00:01:43 volumio volumio[5415]: info: Starting Shairport Sync Feb 18 00:01:43 volumio volumio[5415]: info: Starting Shairport Sync Feb 18 00:01:43 volumio go-librespot[5667]: time="2026-02-18T00:01:43+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 00:01:43 volumio sudo[5694]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 18 00:01:43 volumio sudo[5694]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 00:01:43 volumio sudo[5696]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 18 00:01:43 volumio sudo[5696]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 00:01:43 volumio go-librespot[5667]: time="2026-02-18T00:01:43+07:00" level=debug msg="completed keyexchange" Feb 18 00:01:43 volumio go-librespot[5667]: time="2026-02-18T00:01:43+07:00" level=debug msg="completed challenge" Feb 18 00:01:43 volumio sudo[5698]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 18 00:01:43 volumio sudo[5698]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 00:01:43 volumio go-librespot[5667]: time="2026-02-18T00:01:43+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 18 00:01:43 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 18 00:01:43 volumio volumio[5415]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 18 00:01:43 volumio volumio[5415]: SPOTIFY: BQDSXKOc12_U3vOAyu-5QTQww9HnHiCCP0jHV-1cqF2w1GO-61Bc-Mpwv8jHPyXH2Pg-t72OpzEAHHwxRvWjbWmsGm644nl5846iw7BKXVxpG43RaA_kq7BfqTU_K_gFssf6PHempWFLzHfjsMjTtTQr-eIR3Eoeb3Bl10T-RzCyn9LDPRWaEXcom2tu2L5D0eudbWO_rAqid09O_UGFyWEW6f0GDWmf-0GOq0xW1DwFBRZDYFpZYkwwq1zSZSqwnoOoMxyqHmL8_aEbwKTU0N8ZsLXEjarz3Xe1Ba22lTE9QoMn3UtWW-A_ Feb 18 00:01:43 volumio volumio[5415]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 18 00:01:43 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 18 00:01:43 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 18 00:01:43 volumio systemd[1]: shairport-sync.service: Consumed 2.177s CPU time. Feb 18 00:01:43 volumio volumio[5415]: info: New Spotify access token = BQDSXKOc12_U3vOAyu-5QTQww9HnHiCCP0jHV-1cqF2w1GO-61Bc-Mpwv8jHPyXH2Pg-t72OpzEAHHwxRvWjbWmsGm644nl5846iw7BKXVxpG43RaA_kq7BfqTU_K_gFssf6PHempWFLzHfjsMjTtTQr-eIR3Eoeb3Bl10T-RzCyn9LDPRWaEXcom2tu2L5D0eudbWO_rAqid09O_UGFyWEW6f0GDWmf-0GOq0xW1DwFBRZDYFpZYkwwq1zSZSqwnoOoMxyqHmL8_aEbwKTU0N8ZsLXEjarz3Xe1Ba22lTE9QoMn3UtWW-A_ Feb 18 00:01:43 volumio volumio[5415]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 18 00:01:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 00:01:43 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 00:01:43 volumio volumio[5415]: info: CoreCommandRouter::volumioGetState Feb 18 00:01:43 volumio volumio[5415]: info: CorePlayQueue::getTrack 0 Feb 18 00:01:43 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 18 00:01:43 volumio sudo[5694]: pam_unix(sudo:session): session closed for user root Feb 18 00:01:43 volumio sudo[5696]: pam_unix(sudo:session): session closed for user root Feb 18 00:01:43 volumio volumio[5415]: info: Shairport-Sync Started Feb 18 00:01:43 volumio volumio[5415]: Error adding Membership: Error: addMembership EINVAL Feb 18 00:01:43 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 18 00:01:43 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 18 00:01:43 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 18 00:01:43 volumio volumio[5415]: info: Shairport-Sync Started Feb 18 00:01:43 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 18 00:01:43 volumio sudo[5698]: pam_unix(sudo:session): session closed for user root Feb 18 00:01:44 volumio volumio[5415]: info: Shairport-Sync Started Feb 18 00:01:44 volumio volumio[5415]: 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 18 00:01:44 volumio volumio[5415]: info: Spotify Successfully logged in Feb 18 00:01:44 volumio volumio[5415]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 18 00:01:44 volumio volumio[5415]: info: [1771347704084] CoreMusicLibrary::Adding element Spotify Feb 18 00:01:44 volumio volumio[5415]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 00:01:44 volumio volumio[5415]: Cannot find translation for source YouTube Music Feb 18 00:01:44 volumio volumio[5415]: Cannot find translation for source Spotify Feb 18 00:01:44 volumio volumio[5415]: info: [yt-cast-receiver] DIAL server listening on port 8098 Feb 18 00:01:44 volumio volumio[5415]: info: CoreCommandRouter::volumioRetrievevolume Feb 18 00:01:45 volumio volumio[5415]: info: VolumeController:: Volume=100 Mute =false Feb 18 00:01:45 volumio volumio[5415]: info: CoreCommandRouter::volumioGetState Feb 18 00:01:45 volumio volumio[5415]: info: CorePlayQueue::getTrack 0 Feb 18 00:01:45 volumio volumio[5415]: info: CoreStateMachine::pushState Feb 18 00:01:45 volumio volumio[5415]: info: CorePlayQueue::getTrack 0 Feb 18 00:01:45 volumio volumio[5415]: info: CoreCommandRouter::volumioPushState Feb 18 00:01:46 volumio volumio[5415]: info: go-librespot daemon successfully initialized Feb 18 00:01:46 volumio mpd[5644]: 2026-02-18T00:01:46 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 18 00:01:46 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 18 00:01:46 volumio sudo[5596]: pam_unix(sudo:session): session closed for user root Feb 18 00:01:46 volumio sudo[5607]: pam_unix(sudo:session): session closed for user root Feb 18 00:01:46 volumio volumio[5415]: error: MPD error: The expression evaluated to a falsy value: Feb 18 00:01:46 volumio volumio[5415]: assert.ok(self.idling) Feb 18 00:01:46 volumio volumio[5415]: error: The expression evaluated to a falsy value: Feb 18 00:01:46 volumio volumio[5415]: assert.ok(self.idling) Feb 18 00:01:46 volumio volumio[5415]: info: MPD running with PID5644 Feb 18 00:01:46 volumio volumio[5415]: ,establishing connection Feb 18 00:01:46 volumio volumio[5415]: error: updateQueue error: null Feb 18 00:01:46 volumio volumio[5415]: info: Completed starting Core Plugins Feb 18 00:01:46 volumio volumio[5415]: info: ------------------------------------------- Feb 18 00:01:46 volumio volumio[5415]: info: ----- MyVolumio plugins startup ---- Feb 18 00:01:46 volumio volumio[5415]: info: ------------------------------------------- Feb 18 00:01:46 volumio volumio[5415]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 18 00:01:46 volumio volumio[5415]: error: updateQueue error: null Feb 18 00:01:46 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Feb 18 00:01:46 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 18 00:01:46 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 00:01:46 volumio go-librespot[5739]: go-librespot daemon starting... Feb 18 00:01:46 volumio go-librespot[5740]: time="2026-02-18T00:01:46+07:00" level=info msg="running go-librespot 0.4.0" Feb 18 00:01:46 volumio go-librespot[5740]: time="2026-02-18T00:01:46+07:00" level=debug msg="app state loaded" Feb 18 00:01:46 volumio go-librespot[5740]: time="2026-02-18T00:01:46+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 00:01:47 volumio go-librespot[5740]: time="2026-02-18T00:01:47+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 18 00:01:47 volumio go-librespot[5740]: time="2026-02-18T00:01:47+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 18 00:01:47 volumio go-librespot[5740]: time="2026-02-18T00:01:47+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 18 00:01:47 volumio go-librespot[5740]: time="2026-02-18T00:01:47+07:00" level=info msg="zeroconf server listening on port 39103" Feb 18 00:01:47 volumio go-librespot[5740]: time="2026-02-18T00:01:47+07:00" level=debug msg="obtained new client token: AACX8OyUU014BUa3qeng8o+MwfbhE1zt1W0worirt95ivvfhyweYnGWH9lRjx44F4Udqo8bFEUXui700vtgHnFttDQSHg7VfGxn+NvkE+SpIyTlgeqnLAj2xpqbqSPi6Dp68g3XuhEv9NoD7Uul6Y/2o0oakLygO9+aiat712xQTTQ6MoZ8pYCgmRHWJutnNzV4Grq+j8KG1SdlkhHx+hnuIMeKRbSs2PvF1yj/Q+SgEP+jZWZp228MuJg==" Feb 18 00:01:47 volumio go-librespot[5740]: time="2026-02-18T00:01:47+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 00:01:47 volumio go-librespot[5740]: time="2026-02-18T00:01:47+07:00" level=debug msg="completed keyexchange" Feb 18 00:01:47 volumio go-librespot[5740]: time="2026-02-18T00:01:47+07:00" level=debug msg="completed challenge" Feb 18 00:01:47 volumio go-librespot[5740]: time="2026-02-18T00:01:47+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 18 00:01:47 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 00:01:47 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 00:01:49 volumio volumio[5415]: info: Initializing connection to go-librespot Websocket Feb 18 00:01:49 volumio volumio[5415]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 18 00:01:51 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Feb 18 00:01:51 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 18 00:01:51 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 00:01:51 volumio go-librespot[5747]: go-librespot daemon starting... Feb 18 00:01:51 volumio go-librespot[5748]: time="2026-02-18T00:01:51+07:00" level=info msg="running go-librespot 0.4.0" Feb 18 00:01:51 volumio go-librespot[5748]: time="2026-02-18T00:01:51+07:00" level=debug msg="app state loaded" Feb 18 00:01:51 volumio go-librespot[5748]: time="2026-02-18T00:01:51+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 00:01:51 volumio volumio[5415]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 18 00:01:51 volumio go-librespot[5748]: time="2026-02-18T00:01:51+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 18 00:01:51 volumio go-librespot[5748]: time="2026-02-18T00:01:51+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 18 00:01:51 volumio go-librespot[5748]: time="2026-02-18T00:01:51+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 18 00:01:51 volumio go-librespot[5748]: time="2026-02-18T00:01:51+07:00" level=info msg="zeroconf server listening on port 44939" Feb 18 00:01:51 volumio go-librespot[5748]: time="2026-02-18T00:01:51+07:00" level=debug msg="obtained new client token: AACqGLpSm6g5W2JF591cD/SerEz6DWjFVvARAPD8hueZvzRJuZSpzieQ3pxd1hlZJ+QJjgkbtYU/PbbLEncSt6AIoctbXYquR4MF7OiNQBrr0P3x0cQuMTabNF/eIrII2Qdz3DtU7wiWXwHzO0gINikjK4vgU2okbrP8LM04+lEC9pHgnVPVDZp8yJojF9dto3q1xuC/amUm0WFCvQUOa+P8dJrDmRMpEr7gShhd963XtBf92e/B4d3biQ==" Feb 18 00:01:51 volumio go-librespot[5748]: time="2026-02-18T00:01:51+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 00:01:51 volumio go-librespot[5748]: time="2026-02-18T00:01:51+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 18 00:01:51 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 00:01:51 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 00:01:52 volumio volumio[5415]: info: Initializing connection to go-librespot Websocket Feb 18 00:01:52 volumio volumio[5415]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 18 00:01:55 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11. Feb 18 00:01:55 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 18 00:01:55 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 00:01:55 volumio go-librespot[5755]: go-librespot daemon starting... Feb 18 00:01:55 volumio go-librespot[5756]: time="2026-02-18T00:01:55+07:00" level=info msg="running go-librespot 0.4.0" Feb 18 00:01:55 volumio go-librespot[5756]: time="2026-02-18T00:01:55+07:00" level=debug msg="app state loaded" Feb 18 00:01:55 volumio go-librespot[5756]: time="2026-02-18T00:01:55+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 00:01:55 volumio volumio[5415]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 18 00:01:55 volumio volumio[5415]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 18 00:01:55 volumio volumio[5415]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 18 00:01:55 volumio volumio[5415]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 18 00:01:55 volumio volumio[5415]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 18 00:01:55 volumio volumio[5415]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 18 00:01:55 volumio volumio[5415]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 18 00:01:55 volumio volumio[5415]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 18 00:01:55 volumio volumio[5415]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 18 00:01:55 volumio volumio[5415]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 18 00:01:55 volumio volumio[5415]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 18 00:01:55 volumio volumio[5415]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 18 00:01:55 volumio volumio[5415]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 18 00:01:55 volumio volumio[5415]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 18 00:01:55 volumio volumio[5415]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 18 00:01:55 volumio volumio[5415]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 18 00:01:55 volumio volumio[5415]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 18 00:01:55 volumio volumio[5415]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 18 00:01:55 volumio volumio[5415]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 18 00:01:55 volumio volumio[5415]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 18 00:01:55 volumio volumio[5415]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 18 00:01:55 volumio volumio[5415]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 18 00:01:55 volumio volumio[5415]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 18 00:01:55 volumio volumio[5415]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 18 00:01:55 volumio volumio[5415]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 18 00:01:55 volumio volumio[5415]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 18 00:01:55 volumio volumio[5415]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 18 00:01:55 volumio volumio[5415]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 18 00:01:55 volumio volumio[5415]: info: Adding plugin bluetooth to MyMusic Plugins Feb 18 00:01:55 volumio volumio[5415]: info: Adding plugin multiroom to MyMusic Plugins Feb 18 00:01:55 volumio volumio[5415]: info: Adding plugin metavolumio to MyMusic Plugins Feb 18 00:01:55 volumio volumio[5415]: info: Adding plugin cd_controller to MyMusic Plugins Feb 18 00:01:55 volumio volumio[5415]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 18 00:01:55 volumio volumio[5415]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 18 00:01:55 volumio volumio[5415]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 18 00:01:55 volumio volumio[5415]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 18 00:01:55 volumio go-librespot[5756]: time="2026-02-18T00:01: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 18 00:01:55 volumio go-librespot[5756]: time="2026-02-18T00:01:55+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 18 00:01:55 volumio go-librespot[5756]: time="2026-02-18T00:01:55+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 18 00:01:55 volumio go-librespot[5756]: time="2026-02-18T00:01:55+07:00" level=info msg="zeroconf server listening on port 46093" Feb 18 00:01:55 volumio go-librespot[5756]: time="2026-02-18T00:01:55+07:00" level=debug msg="obtained new client token: AADq2w1Wxi9GNo6xiULxjwa+3E3InL5f0+8IO2SNcDKPEMVZWMpta1gNcOyTmhi20p1T1OsGnixyGz7Ur7pvk8CUcJXUqrwXyKOMtRquKVs/LtlyrMFb7BA7EjmDQQ2z6qaZOYCZ3FooKzQC4Xsb6+lZvtVJumGaTQPNQYjjAdVCz6c5nYadaoMJtVCrBc+ciF9Zi67KBZY5j+VLyc/6EQKmQOQzknSBFeOatHbjsb6XJqd42+vCxWlA+g==" Feb 18 00:01:55 volumio go-librespot[5756]: time="2026-02-18T00:01:55+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 00:01:56 volumio go-librespot[5756]: time="2026-02-18T00:01:56+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 18 00:01:56 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 00:01:56 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 00:01:57 volumio volumio[5415]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 18 00:01:57 volumio volumio[5415]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 18 00:01:57 volumio volumio[5415]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 00:01:57 volumio volumio[5415]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 00:01:57 volumio volumio[5415]: info: Starting MyVolumio Remote Streaming Endpoints Feb 18 00:01:57 volumio volumio[5415]: info: MyVolumio login type: Token Feb 18 00:01:57 volumio volumio[5415]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 18 00:01:57 volumio volumio[5415]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 18 00:01:58 volumio volumio[5415]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 18 00:01:58 volumio volumio[5415]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 18 00:01:58 volumio volumio[5415]: info: Streaming services startup Feb 18 00:01:58 volumio volumio[5415]: info: Starting Streaming Daemon Feb 18 00:01:58 volumio sudo[5779]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 18 00:01:58 volumio sudo[5779]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 00:01:58 volumio volumio[5415]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 18 00:01:59 volumio sudo[5779]: pam_unix(sudo:session): session closed for user root Feb 18 00:01:59 volumio volumio[5415]: info: Initializing connection to go-librespot Websocket Feb 18 00:01:59 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12. Feb 18 00:01:59 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 18 00:01:59 volumio volumio[5415]: error: Cannot start Volumio Streaming Daemon Feb 18 00:01:59 volumio volumio[5415]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 18 00:01:59 volumio volumio[5415]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 18 00:01:59 volumio volumio[5415]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 18 00:01:59 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 00:01:59 volumio go-librespot[5785]: go-librespot daemon starting... Feb 18 00:01:59 volumio go-librespot[5786]: time="2026-02-18T00:01:59+07:00" level=info msg="running go-librespot 0.4.0" Feb 18 00:01:59 volumio go-librespot[5786]: time="2026-02-18T00:01:59+07:00" level=debug msg="app state loaded" Feb 18 00:01:59 volumio go-librespot[5786]: time="2026-02-18T00:01:59+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 00:01:59 volumio go-librespot[5786]: time="2026-02-18T00:01:59+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 18 00:01:59 volumio go-librespot[5786]: time="2026-02-18T00:01:59+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 18 00:01:59 volumio go-librespot[5786]: time="2026-02-18T00:01:59+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 18 00:01:59 volumio go-librespot[5786]: time="2026-02-18T00:01:59+07:00" level=info msg="zeroconf server listening on port 44049" Feb 18 00:01:59 volumio go-librespot[5786]: time="2026-02-18T00:01:59+07:00" level=debug msg="obtained new client token: AAB/LwwuaecX3yq2r8xIsHV6+hMFJnoEatgrNWdvFKZZ47fE1TrKlo56aN79hDb/8D5fxA1OUy3y6ldlpP+f8fd6+eFPQQCnoK2KSVeIRo/6+m6emA2T5P4X/BLCay3nlNRk2GNOofJQHz0h7WwX0IGGZ9QCi+6dl3xg/S+EqREZP2dHNKLdP2pkZ6E5rIMw+DCapaBt9LDoSHwnrshYLnYyeGD7haqeGqLx2/aJkNAEtozPnKBgDPw3bA==" Feb 18 00:01:59 volumio volumio[5415]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Feb 18 00:02:00 volumio go-librespot[5786]: time="2026-02-18T00:02:00+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 00:02:00 volumio go-librespot[5786]: time="2026-02-18T00:02:00+07:00" level=debug msg="completed keyexchange" Feb 18 00:02:00 volumio go-librespot[5786]: time="2026-02-18T00:02:00+07:00" level=debug msg="completed challenge" Feb 18 00:02:00 volumio go-librespot[5786]: time="2026-02-18T00:02: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 18 00:02:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 00:02:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 00:02:01 volumio volumio[5415]: info: MyVolumio token set successfully Feb 18 00:02:01 volumio volumio[5415]: info: MYVOLUMIO: Adding device Feb 18 00:02:01 volumio volumio[5415]: info: MYVOLUMIO: Evaluating Server Feb 18 00:02:01 volumio volumio[5415]: info: MyVolumio status changed Feb 18 00:02:01 volumio volumio[5415]: info: Streaming services startup Feb 18 00:02:01 volumio volumio[5415]: info: Starting Streaming Daemon Feb 18 00:02:01 volumio volumio[5415]: info: Removing browser output: myVolumio user plan is not superstar Feb 18 00:02:01 volumio volumio[5415]: info: Removing audio output: Feb 18 00:02:01 volumio volumio[5415]: info: Stoppping Tunnel 1 Feb 18 00:02:01 volumio sudo[5815]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 18 00:02:01 volumio sudo[5815]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 00:02:01 volumio sudo[5815]: pam_unix(sudo:session): session closed for user root Feb 18 00:02:01 volumio sudo[5818]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Feb 18 00:02:01 volumio sudo[5818]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 00:02:01 volumio volumio[5415]: error: Cannot start Volumio Streaming Daemon Feb 18 00:02:01 volumio volumio[5415]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 18 00:02:01 volumio volumio[5415]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 18 00:02:01 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 18 00:02:01 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 18 00:02:01 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 18 00:02:01 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 18 00:02:01 volumio volumio[5415]: info: Setting Geolocation for MyVolumio to as1 Feb 18 00:02:01 volumio volumio[5415]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 00:02:01 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 18 00:02:01 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 18 00:02:01 volumio volumio[5415]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 00:02:01 volumio volumio[5415]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 00:02:01 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 18 00:02:01 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 18 00:02:01 volumio sudo[5818]: pam_unix(sudo:session): session closed for user root Feb 18 00:02:01 volumio volumio[5415]: info: Remote SSH Stopped Feb 18 00:02:02 volumio volumio[5415]: info: Initializing connection to go-librespot Websocket Feb 18 00:02:02 volumio volumio[5415]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 18 00:02:02 volumio volumio[5415]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Feb 18 00:02:02 volumio volumio[5415]: info: Updating MyVolumio device info Feb 18 00:02:02 volumio volumio[5415]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 00:02:02 volumio volumio[5415]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 00:02:02 volumio volumio[5415]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 00:02:03 volumio volumio[5415]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Feb 18 00:02:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13. Feb 18 00:02:03 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 18 00:02:03 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 00:02:03 volumio go-librespot[5820]: go-librespot daemon starting... Feb 18 00:02:03 volumio go-librespot[5821]: time="2026-02-18T00:02:03+07:00" level=info msg="running go-librespot 0.4.0" Feb 18 00:02:03 volumio go-librespot[5821]: time="2026-02-18T00:02:03+07:00" level=debug msg="app state loaded" Feb 18 00:02:03 volumio go-librespot[5821]: time="2026-02-18T00:02:03+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 00:02:03 volumio go-librespot[5821]: time="2026-02-18T00:02:03+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 18 00:02:03 volumio go-librespot[5821]: time="2026-02-18T00:02:03+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 18 00:02:03 volumio go-librespot[5821]: time="2026-02-18T00:02:03+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 18 00:02:03 volumio go-librespot[5821]: time="2026-02-18T00:02:03+07:00" level=info msg="zeroconf server listening on port 40845" Feb 18 00:02:04 volumio go-librespot[5821]: time="2026-02-18T00:02:04+07:00" level=debug msg="obtained new client token: AACXl0NHpNUo7rUbrYsalz2BJJeoKCgyuGihK1hihPN0QFQOkGjUR+7ORCORotKSkUb6kqfaYVo+/i1fnIDVbRqgaWZIu6niYvE9TSIBaZ9y7gnUY+V18ru7nxIJII3yUUDx8zntKZm3vGpkLBe0QM1vAT+D4wElX3WGq1bACUvrrMjyFf1poAtzg+1HFa7Dx8p1rETxb9BPmoTmYUq9ph+liEO4kDYcYRveUDcAi6U9j6cIq6rVDrg=" Feb 18 00:02:04 volumio go-librespot[5821]: time="2026-02-18T00:02:04+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 00:02:04 volumio go-librespot[5821]: time="2026-02-18T00:02:04+07:00" level=debug msg="completed keyexchange" Feb 18 00:02:04 volumio go-librespot[5821]: time="2026-02-18T00:02:04+07:00" level=debug msg="completed challenge" Feb 18 00:02:04 volumio go-librespot[5821]: time="2026-02-18T00:02:04+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 18 00:02:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 00:02:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 00:02:05 volumio volumio[5415]: info: Initializing connection to go-librespot Websocket Feb 18 00:02:05 volumio volumio[5415]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 18 00:02:06 volumio volumio[5415]: info: MYVOLUMIO: Adding device Feb 18 00:02:06 volumio volumio[5415]: info: MYVOLUMIO: Evaluating Server Feb 18 00:02:07 volumio volumio[5415]: info: Setting Geolocation for MyVolumio to as1 Feb 18 00:02:07 volumio volumio[5415]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 00:02:07 volumio volumio[5415]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 00:02:07 volumio volumio[5415]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 00:02:07 volumio volumio[5415]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Feb 18 00:02:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14. Feb 18 00:02:07 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 18 00:02:07 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 00:02:07 volumio go-librespot[5860]: go-librespot daemon starting... Feb 18 00:02:07 volumio go-librespot[5861]: time="2026-02-18T00:02:07+07:00" level=info msg="running go-librespot 0.4.0" Feb 18 00:02:07 volumio go-librespot[5861]: time="2026-02-18T00:02:07+07:00" level=debug msg="app state loaded" Feb 18 00:02:07 volumio go-librespot[5861]: time="2026-02-18T00:02:07+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 00:02:08 volumio volumio[5415]: info: Updating MyVolumio device info Feb 18 00:02:08 volumio volumio[5415]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 00:02:08 volumio volumio[5415]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 00:02:08 volumio volumio[5415]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 00:02:08 volumio volumio[5415]: info: Initializing connection to go-librespot Websocket Feb 18 00:02:08 volumio go-librespot[5861]: time="2026-02-18T00:02:08+07:00" level=debug msg="new websocket client" Feb 18 00:02:08 volumio go-librespot[5861]: time="2026-02-18T00:02: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-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 18 00:02:08 volumio go-librespot[5861]: time="2026-02-18T00:02:08+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 18 00:02:08 volumio go-librespot[5861]: time="2026-02-18T00:02:08+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 18 00:02:08 volumio volumio[5415]: info: Connection to go-librespot Websocket established Feb 18 00:02:08 volumio go-librespot[5861]: time="2026-02-18T00:02:08+07:00" level=info msg="zeroconf server listening on port 43291" Feb 18 00:02:08 volumio go-librespot[5861]: time="2026-02-18T00:02:08+07:00" level=debug msg="obtained new client token: AAAqh3krARW/ydV/ipcR6uWHSl5aYKaiBiaXlN6pGlEXD4dodWccZLmdb2wzUTC8yF03URM5oyXB/0qz/ASkAfHg6c/7tRR+pxlzAZTVfeIPNqhm2u2M9nrz8ZezsBL+Xda9dA36JxFDE6YdppFnxkxVUNVafjg0fOY9u6xJDejcpaB/5Yfr3vnai8c97/3uHPdhPu8qRbGIXvUd0mcJA4ORO7dhoThl6erRDZHdoCXz9K596lYQG6feHw==" Feb 18 00:02:08 volumio volumio[5415]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Feb 18 00:02:08 volumio go-librespot[5861]: time="2026-02-18T00:02:08+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 00:02:08 volumio go-librespot[5861]: time="2026-02-18T00:02:08+07:00" level=debug msg="completed keyexchange" Feb 18 00:02:08 volumio go-librespot[5861]: time="2026-02-18T00:02:08+07:00" level=debug msg="completed challenge" Feb 18 00:02:08 volumio go-librespot[5861]: time="2026-02-18T00:02:08+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 18 00:02:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 00:02:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 00:02:08 volumio volumio[5415]: info: Connection to go-librespot Websocket closed Feb 18 00:02:10 volumio volumio[5415]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 18 00:02:10 volumio volumio[5415]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 00:02:10 volumio volumio[5415]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 18 00:02:10 volumio volumio-remote-updater[25144]: Test mode disabled Feb 18 00:02:10 volumio volumio-remote-updater[25144]: Alpha mode disabled Feb 18 00:02:10 volumio volumio-remote-updater[25144]: Alpha legacy test mode disabled Feb 18 00:02:10 volumio volumio[5415]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Feb 18 00:02:11 volumio volumio[5415]: info: Getting Spotify volume Feb 18 00:02:11 volumio volumio[5415]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 18 00:02:11 volumio volumio[5415]: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 18 00:02:11 volumio volumio[5415]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Feb 18 00:02:11 volumio volumio[5415]: errno: -111, Feb 18 00:02:11 volumio volumio[5415]: code: 'ECONNREFUSED', Feb 18 00:02:11 volumio volumio[5415]: syscall: 'connect', Feb 18 00:02:11 volumio volumio[5415]: address: '127.0.0.1', Feb 18 00:02:11 volumio volumio[5415]: port: 9879, Feb 18 00:02:11 volumio volumio[5415]: response: undefined Feb 18 00:02:11 volumio volumio[5415]: } Feb 18 00:02:11 volumio volumio[5415]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 18 00:02:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15. Feb 18 00:02:11 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 18 00:02:11 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 00:02:11 volumio go-librespot[5885]: go-librespot daemon starting... Feb 18 00:02:11 volumio go-librespot[5886]: time="2026-02-18T00:02:11+07:00" level=info msg="running go-librespot 0.4.0" Feb 18 00:02:11 volumio go-librespot[5886]: time="2026-02-18T00:02:11+07:00" level=debug msg="app state loaded" Feb 18 00:02:11 volumio go-librespot[5886]: time="2026-02-18T00:02:11+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 00:02:12 volumio go-librespot[5886]: time="2026-02-18T00:02:12+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 18 00:02:12 volumio go-librespot[5886]: time="2026-02-18T00:02:12+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 18 00:02:12 volumio go-librespot[5886]: time="2026-02-18T00:02:12+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 18 00:02:12 volumio go-librespot[5886]: time="2026-02-18T00:02:12+07:00" level=info msg="zeroconf server listening on port 34505" Feb 18 00:02:12 volumio go-librespot[5886]: time="2026-02-18T00:02:12+07:00" level=debug msg="obtained new client token: AABnD/qMgW4DUCw2DKeCak8XnxGPodYRfrxGDvGT95EpiWrGZabzNC/U6xcnJspb9xbjQY5VTXp7AOJJGa5HmoV5ZotzF3MDEXpt7Joo6Rxb/+M421DbDonM//GO7/CylV0Ba2934W2d8jVNGFfW51txIDEBNfohWQKaEHbP6ym95MUaJgwo1VoXzZ9WENEw/AWq8GwmZHHGmJkT5fC0GSDKzDG4F/6z5fbX/AeSACqSrlxL9CzPeeV0qA==" Feb 18 00:02:12 volumio sudo[5896]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-18 00:01' Feb 18 00:02:12 volumio sudo[5896]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 00:02:12 volumio go-librespot[5886]: time="2026-02-18T00:02:12+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 00:02:12 volumio go-librespot[5886]: time="2026-02-18T00:02:12+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 18 00:02:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 00:02:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 00:02:12 volumio sudo[5896]: pam_unix(sudo:session): session closed for user root Feb 18 00:02:13 volumio volumio-remote-updater[25144]: [2026-02-18 00:02:13] [error] handle_read_frame error: asio.system:104 (Connection reset by peer) Feb 18 00:02:13 volumio volumio-remote-updater[25144]: [2026-02-18 00:02:13] [disconnect] Disconnect close local:[1006,Connection reset by peer] remote:[1006] Feb 18 00:02:13 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 18 00:02:13 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 18 00:02:13 volumio systemd[1]: volumio.service: Consumed 58.244s CPU time. Feb 18 00:02:13 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 18 00:02:13 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 18 00:02:13 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 21094. Feb 18 00:02:13 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 18 00:02:13 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 18 00:02:13 volumio systemd[1]: volumio.service: Consumed 58.244s CPU time. Feb 18 00:02:13 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Feb 18 00:02:13 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 18 00:02:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16. Feb 18 00:02:16 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 18 00:02:16 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 00:02:16 volumio go-librespot[5925]: go-librespot daemon starting... Feb 18 00:02:16 volumio go-librespot[5926]: time="2026-02-18T00:02:16+07:00" level=info msg="running go-librespot 0.4.0" Feb 18 00:02:16 volumio go-librespot[5926]: time="2026-02-18T00:02:16+07:00" level=debug msg="app state loaded" Feb 18 00:02:16 volumio go-librespot[5926]: time="2026-02-18T00:02:16+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 00:02:16 volumio go-librespot[5926]: time="2026-02-18T00:02:16+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 18 00:02:16 volumio go-librespot[5926]: time="2026-02-18T00:02:16+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 18 00:02:16 volumio go-librespot[5926]: time="2026-02-18T00:02:16+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 18 00:02:16 volumio go-librespot[5926]: time="2026-02-18T00:02:16+07:00" level=info msg="zeroconf server listening on port 37947" Feb 18 00:02:16 volumio volumio[5910]: info: ------------------------------------------- Feb 18 00:02:17 volumio volumio[5910]: info: ----- Volumio3 ---- Feb 18 00:02:17 volumio volumio[5910]: info: ------------------------------------------- Feb 18 00:02:17 volumio volumio[5910]: info: ----- System startup ---- Feb 18 00:02:17 volumio volumio[5910]: info: ------------------------------------------- Feb 18 00:02:17 volumio go-librespot[5926]: time="2026-02-18T00:02:17+07:00" level=debug msg="obtained new client token: AACVaY42A66DF0aLxzAs+IQyLpVw9AQxFUmFiOGQKcqkiapWmZhWaVopdzNidNGEyVwYEmzNKYRUA9T8utVFjIVIBr5L30oEtSK/AIoTvW4kJSZmPf44gSNn3fPH0/ePw82pVgLCKCnQ2MHfcLY1A8y5Vx8JjffIEWx9VRHuETIGNyI2QlTje2zSDSGrewrOi3jFi0t32xwWY56UDYeGyZ+vLSN6iH5k9Y8kdNkMQtH06a0MXzt0foXxow==" Feb 18 00:02:17 volumio go-librespot[5926]: time="2026-02-18T00:02:17+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 00:02:17 volumio go-librespot[5926]: time="2026-02-18T00:02:17+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 18 00:02:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 00:02:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 00:02:18 volumio volumio-remote-updater[25144]: [2026-02-18 00:02:18] [connect] Successful connection Feb 18 00:02:18 volumio volumio[5910]: info: MYVOLUMIO Environment detected Feb 18 00:02:18 volumio volumio[5910]: info: Plugin folders cleanup Feb 18 00:02:18 volumio volumio[5910]: info: Scanning into folder /volumio/app/plugins/ Feb 18 00:02:18 volumio volumio[5910]: info: Scanning category audio_interface Feb 18 00:02:18 volumio volumio[5910]: info: Scanning category miscellanea Feb 18 00:02:18 volumio volumio[5910]: info: Scanning category music_service Feb 18 00:02:18 volumio volumio[5910]: info: Scanning category plugins.json Feb 18 00:02:18 volumio volumio[5910]: info: Scanning category system_controller Feb 18 00:02:18 volumio volumio[5910]: info: Scanning category user_interface Feb 18 00:02:18 volumio volumio[5910]: info: Scanning into folder /data/plugins/ Feb 18 00:02:18 volumio volumio[5910]: info: Scanning category music_service Feb 18 00:02:18 volumio volumio[5910]: info: Plugin folders cleanup completed Feb 18 00:02:18 volumio volumio[5910]: info: ------------------------------------------- Feb 18 00:02:18 volumio volumio[5910]: info: ----- Core plugins startup ---- Feb 18 00:02:18 volumio volumio[5910]: info: ------------------------------------------- Feb 18 00:02:18 volumio volumio[5910]: info: Loading plugins from folder /volumio/app/plugins/ Feb 18 00:02:18 volumio volumio[5910]: info: Adding plugin upnp to MyMusic Plugins Feb 18 00:02:18 volumio volumio[5910]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 18 00:02:18 volumio volumio[5910]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 18 00:02:18 volumio volumio[5910]: info: Loading plugins from folder /data/plugins/ Feb 18 00:02:18 volumio volumio[5910]: info: Loading plugin "system"... Feb 18 00:02:18 volumio volumio[5910]: info: Loading plugin "appearance"... Feb 18 00:02:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17. Feb 18 00:02:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 18 00:02:20 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 00:02:20 volumio go-librespot[5945]: go-librespot daemon starting... Feb 18 00:02:20 volumio go-librespot[5946]: time="2026-02-18T00:02:20+07:00" level=info msg="running go-librespot 0.4.0" Feb 18 00:02:20 volumio go-librespot[5946]: time="2026-02-18T00:02:20+07:00" level=debug msg="app state loaded" Feb 18 00:02:20 volumio go-librespot[5946]: time="2026-02-18T00:02:20+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 00:02:20 volumio volumio[5910]: info: Loading plugin "network"... Feb 18 00:02:20 volumio volumio[5910]: info: Refreshing Cached IP Addresses Feb 18 00:02:20 volumio sudo[5953]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 18 00:02:20 volumio sudo[5953]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 00:02:20 volumio sudo[5953]: pam_unix(sudo:session): session closed for user root Feb 18 00:02:20 volumio sudo[5956]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 18 00:02:20 volumio volumio[5910]: info: Loading plugin "services"... Feb 18 00:02:20 volumio sudo[5956]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 00:02:20 volumio volumio[5910]: info: Loading plugin "alsa_controller"... Feb 18 00:02:20 volumio sudo[5956]: pam_unix(sudo:session): session closed for user root Feb 18 00:02:20 volumio sudo[5964]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 18 00:02:20 volumio sudo[5964]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 00:02:20 volumio volumio[5910]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 18 00:02:20 volumio volumio[5910]: info: Loading plugin "wizard"... Feb 18 00:02:20 volumio volumio[5910]: info: Loading plugin "networkfs"... Feb 18 00:02:20 volumio volumio[5910]: info: Starting Udev Watcher for removable devices Feb 18 00:02:20 volumio volumio[5910]: info: Ignoring mount for partition: boot Feb 18 00:02:20 volumio volumio[5910]: info: Ignoring mount for partition: volumio Feb 18 00:02:20 volumio volumio[5910]: info: Ignoring mount for partition: volumio_data Feb 18 00:02:20 volumio volumio[5910]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 18 00:02:20 volumio volumio[5910]: info: Loading plugin "volumio_command_line_client"... Feb 18 00:02:20 volumio volumio[5910]: info: Loading plugin "upnp"... Feb 18 00:02:20 volumio volumio[5910]: info: [1771347740931] Starting Upmpd Daemon Feb 18 00:02:20 volumio volumio[5910]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 18 00:02:20 volumio volumio[5910]: info: Loading plugin "my_music"... Feb 18 00:02:20 volumio volumio[5910]: info: Loading plugin "mpd"... Feb 18 00:02:21 volumio go-librespot[5946]: time="2026-02-18T00:02: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 18 00:02:21 volumio go-librespot[5946]: time="2026-02-18T00:02:21+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 18 00:02:21 volumio go-librespot[5946]: time="2026-02-18T00:02:21+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 18 00:02:21 volumio go-librespot[5946]: time="2026-02-18T00:02:21+07:00" level=info msg="zeroconf server listening on port 46077" Feb 18 00:02:21 volumio go-librespot[5946]: time="2026-02-18T00:02:21+07:00" level=debug msg="obtained new client token: AABpgBScdk/PZ+B3C6qBB1NYhAe47ne8TeXiLCDmTyUu+n4aOTiBAvyvAorDxbrCaPwppnSdD8iEDe/U7x6BNmE7fpY6iUgiCVECd7RA7GDOQXLWK2ZNQVjf4qkcfuq9DhdW47Em4tlTAWo8ewE1PChcJC2Sc8cJ90UrDecvg5GPX90PpcYdQPZZ57TQrSseWlNCtMMIdpth2+47QbVd+XLn3JW4H+njA4zcWugImCZKG6ZoLYTvprQXoQ==" Feb 18 00:02:21 volumio go-librespot[5946]: time="2026-02-18T00:02:21+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 00:02:21 volumio go-librespot[5946]: time="2026-02-18T00:02:21+07:00" level=debug msg="completed keyexchange" Feb 18 00:02:21 volumio go-librespot[5946]: time="2026-02-18T00:02:21+07:00" level=debug msg="completed challenge" Feb 18 00:02:21 volumio volumio[5910]: info: Loading plugin "upnp_browser"... Feb 18 00:02:21 volumio go-librespot[5946]: time="2026-02-18T00:02:21+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 18 00:02:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 00:02:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 00:02:23 volumio sudo[5964]: pam_unix(sudo:session): session closed for user root Feb 18 00:02:24 volumio volumio[5910]: info: Starting UPNP Browser Feb 18 00:02:24 volumio volumio[5910]: info: Loading plugin "alarm-clock"... Feb 18 00:02:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 18. Feb 18 00:02:24 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 18 00:02:24 volumio volumio[5910]: info: Loading plugin "airplay_emulation"... Feb 18 00:02:24 volumio volumio[5910]: info: Starting Shairport Sync Feb 18 00:02:24 volumio volumio[5910]: info: Loading plugin "last_100"... Feb 18 00:02:24 volumio volumio[5910]: info: Loading plugin "webradio"... Feb 18 00:02:24 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 00:02:24 volumio go-librespot[5989]: go-librespot daemon starting... Feb 18 00:02:24 volumio go-librespot[5990]: time="2026-02-18T00:02:24+07:00" level=info msg="running go-librespot 0.4.0" Feb 18 00:02:24 volumio go-librespot[5990]: time="2026-02-18T00:02:24+07:00" level=debug msg="app state loaded" Feb 18 00:02:24 volumio go-librespot[5990]: time="2026-02-18T00:02:24+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 00:02:25 volumio volumio[5910]: info: Loading plugin "i2s_dacs"... Feb 18 00:02:25 volumio volumio[5910]: info: Loading plugin "volumiodiscovery"... Feb 18 00:02:25 volumio volumio[5910]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 18 00:02:25 volumio volumio[5910]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 18 00:02:25 volumio volumio[5910]: *** WARNING *** For more information see Feb 18 00:02:25 volumio volumio[5910]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 18 00:02:25 volumio volumio[5910]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 18 00:02:25 volumio volumio[5910]: *** WARNING *** For more information see Feb 18 00:02:25 volumio node[5910]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 18 00:02:25 volumio node[5910]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 18 00:02:25 volumio node[5910]: *** WARNING *** For more information see Feb 18 00:02:25 volumio node[5910]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 18 00:02:25 volumio node[5910]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 18 00:02:25 volumio node[5910]: *** WARNING *** For more information see Feb 18 00:02:25 volumio volumio[5910]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 18 00:02:25 volumio volumio[5910]: info: Discovery: Started advertising with name: Volumio Feb 18 00:02:25 volumio volumio[5910]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 18 00:02:25 volumio volumio[5910]: info: Loading plugin "spop"... Feb 18 00:02:25 volumio go-librespot[5990]: time="2026-02-18T00:02:25+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 18 00:02:25 volumio go-librespot[5990]: time="2026-02-18T00:02:25+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 18 00:02:25 volumio go-librespot[5990]: time="2026-02-18T00:02:25+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 18 00:02:25 volumio go-librespot[5990]: time="2026-02-18T00:02:25+07:00" level=info msg="zeroconf server listening on port 35429" Feb 18 00:02:25 volumio go-librespot[5990]: time="2026-02-18T00:02:25+07:00" level=debug msg="obtained new client token: AAACCwJ71p387ukILUxZQVKKyDDotrOFgHCbn7ex7pjrGBAMcJdhIHEsdht9ugXRkcyZMUnx1JN6MWPYN7MWfW6xypDwzINgdZIHrsr0GRDgkTu+l/YQKDrH1HrKze0wJxyDV6QgHCU1GZE8C+SNkE8gKBjcBM6y3grCtvuZI2Y43AKIg0BxBtMQG7jI4gwHqAZLICd0VenRLpgF+6cCarEmL+aI9grFrCBAKFQFrKRfRRa7qQtzv9sudA==" Feb 18 00:02:25 volumio go-librespot[5990]: time="2026-02-18T00:02:25+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 00:02:25 volumio go-librespot[5990]: time="2026-02-18T00:02:25+07:00" level=debug msg="completed keyexchange" Feb 18 00:02:25 volumio go-librespot[5990]: time="2026-02-18T00:02:25+07:00" level=debug msg="completed challenge" Feb 18 00:02:26 volumio go-librespot[5990]: time="2026-02-18T00:02:26+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 18 00:02:26 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 00:02:26 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 00:02:27 volumio volumio[5910]: info: Loading plugin "ytcr"... Feb 18 00:02:29 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 19. Feb 18 00:02:29 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 18 00:02:29 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 00:02:29 volumio go-librespot[6000]: go-librespot daemon starting... Feb 18 00:02:29 volumio go-librespot[6001]: time="2026-02-18T00:02:29+07:00" level=info msg="running go-librespot 0.4.0" Feb 18 00:02:29 volumio go-librespot[6001]: time="2026-02-18T00:02:29+07:00" level=debug msg="app state loaded" Feb 18 00:02:29 volumio go-librespot[6001]: time="2026-02-18T00:02:29+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 00:02:29 volumio go-librespot[6001]: time="2026-02-18T00:02:29+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 18 00:02:29 volumio go-librespot[6001]: time="2026-02-18T00:02:29+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 18 00:02:29 volumio go-librespot[6001]: time="2026-02-18T00:02:29+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 18 00:02:29 volumio go-librespot[6001]: time="2026-02-18T00:02:29+07:00" level=info msg="zeroconf server listening on port 42113" Feb 18 00:02:29 volumio volumio[5910]: info: Loading plugin "ytmusic"... Feb 18 00:02:29 volumio go-librespot[6001]: time="2026-02-18T00:02:29+07:00" level=debug msg="obtained new client token: AABFqeNpP+8sUohbSBNf1HozakZ4ga5Q9dwURie4S/hCMl3A6tGb3tbH/3ZXg4dCrFvdhSc880wk5ayL2q/1X1fdYRJuO95nnzY7XZdCJAx+2TNpybsRmQeBJ3FNm857xPe0sR9vTNHyqejjJ+kVS6cplelZB3XJqCJo7JnEVO3UVLHzkwApPTZPHoU/XHKlAfE/bCicRsdnY2rnugeaetFuK+jJHzo93LUHCH6um5oVHMhhmhc4iiUf6g==" Feb 18 00:02:30 volumio go-librespot[6001]: time="2026-02-18T00:02:30+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 00:02:30 volumio go-librespot[6001]: time="2026-02-18T00:02:30+07:00" level=debug msg="completed keyexchange" Feb 18 00:02:30 volumio go-librespot[6001]: time="2026-02-18T00:02:30+07:00" level=debug msg="completed challenge" Feb 18 00:02:30 volumio go-librespot[6001]: time="2026-02-18T00:02:30+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 18 00:02:30 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 00:02:30 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 00:02:30 volumio volumio-remote-updater[25144]: [2026-02-18 00:02:30] [connect] Successful connection Feb 18 00:02:30 volumio volumio[5910]: info: Loading plugin "outputs"... Feb 18 00:02:30 volumio volumio[5910]: info: Loading plugin "albumart"... Feb 18 00:02:30 volumio volumio[5910]: info: Plugin example_plugin is not enabled Feb 18 00:02:30 volumio volumio[5910]: info: Loading plugin "inputs"... Feb 18 00:02:30 volumio volumio[5910]: info: Loading plugin "updater_comm"... Feb 18 00:02:31 volumio volumio[5910]: info: Plugin mpdemulation is not enabled Feb 18 00:02:31 volumio volumio[5910]: info: Loading plugin "rest_api"... Feb 18 00:02:31 volumio volumio[5910]: info: Loading plugin "websocket"... Feb 18 00:02:31 volumio volumio[5910]: info: Starting Socket.io Server version 1.7.4 Feb 18 00:02:31 volumio volumio[5910]: info: Loading plugin "RoonBridge"... Feb 18 00:02:31 volumio volumio[5910]: info: Applying required configuration parameters for plugin RoonBridge Feb 18 00:02:31 volumio volumio[5910]: info: Loading i18n strings for locale en Feb 18 00:02:31 volumio volumio[5910]: Updating browse sources language Feb 18 00:02:31 volumio volumio[5910]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 00:02:31 volumio volumio[6022]: Forking 3 albumart workers Feb 18 00:02:32 volumio volumio[5910]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 18 00:02:32 volumio volumio[5910]: info: CoreCommandRouter::initPlayerControls Feb 18 00:02:32 volumio volumio[5910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:02:32 volumio volumio[5910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:02:32 volumio volumio[5910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:02:32 volumio volumio[5910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:02:32 volumio volumio[5910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:02:32 volumio volumio[5910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:02:32 volumio volumio[5910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:02:32 volumio volumio[5910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:02:32 volumio volumio[5910]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 18 00:02:32 volumio volumio[5910]: Express server listening on port 3000 Feb 18 00:02:32 volumio volumio[5910]: [Metrics] WebUI: 16s 59.64ms Feb 18 00:02:32 volumio volumio[5910]: info: CoreStateMachine::resetVolumioState Feb 18 00:02:32 volumio volumio[5910]: info: CoreStateMachine::getcurrentVolume Feb 18 00:02:32 volumio volumio[5910]: info: CoreCommandRouter::volumioRetrievevolume Feb 18 00:02:32 volumio sudo[6064]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 18 00:02:32 volumio sudo[6064]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 00:02:32 volumio sudo[6064]: pam_unix(sudo:session): session closed for user root Feb 18 00:02:32 volumio sudo[6066]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 18 00:02:32 volumio sudo[6066]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 00:02:32 volumio sudo[6066]: pam_unix(sudo:session): session closed for user root Feb 18 00:02:32 volumio volumio[5910]: info: Volumio Network Manager: Network status updated: 1 Feb 18 00:02:32 volumio volumio-remote-updater[25144]: [2026-02-18 00:02:32] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1771347750 101 Feb 18 00:02:32 volumio volumio[5910]: 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 18 00:02:32 volumio volumio[5910]: info: VolumeController:: Volume=100 Mute =false Feb 18 00:02:32 volumio volumio[5910]: info: CoreStateMachine::pushState Feb 18 00:02:32 volumio volumio[5910]: info: CorePlayQueue::getTrack 0 Feb 18 00:02:32 volumio volumio[5910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 00:02:32 volumio volumio[5910]: info: CoreCommandRouter::volumioPushState Feb 18 00:02:32 volumio volumio[5910]: info: CoreStateMachine::updateTrackBlock Feb 18 00:02:32 volumio volumio[5910]: info: CorePlayQueue::getTrackBlock Feb 18 00:02:32 volumio volumio[5910]: info: CoreCommandRouter::volumioRetrievevolume Feb 18 00:02:32 volumio volumio[5910]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 00:02:32 volumio volumio[5910]: info: Reloading queue from file Feb 18 00:02:32 volumio volumio[5910]: info: CoreStateMachine::setRepeat null single undefined Feb 18 00:02:32 volumio volumio[5910]: info: CoreStateMachine::pushState Feb 18 00:02:32 volumio volumio[5910]: info: CorePlayQueue::getTrack 0 Feb 18 00:02:32 volumio volumio[5910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 00:02:32 volumio volumio[5910]: info: CoreCommandRouter::volumioPushState Feb 18 00:02:32 volumio volumio[5910]: info: CoreStateMachine::setRandom null Feb 18 00:02:32 volumio volumio[5910]: info: CoreStateMachine::pushState Feb 18 00:02:32 volumio volumio[5910]: info: CorePlayQueue::getTrack 0 Feb 18 00:02:32 volumio volumio[5910]: info: CoreCommandRouter::volumioPushState Feb 18 00:02:32 volumio volumio[5910]: info: Setting Device type: Raspberry PI Feb 18 00:02:33 volumio volumio[5910]: info: Completed loading Core Plugins Feb 18 00:02:33 volumio volumio[5910]: info: Preparing to generate the ALSA configuration file Feb 18 00:02:33 volumio volumio[5910]: info: VolumeController:: Volume=100 Mute =false Feb 18 00:02:33 volumio volumio[5910]: info: CoreStateMachine::pushState Feb 18 00:02:33 volumio volumio[5910]: info: CorePlayQueue::getTrack 0 Feb 18 00:02:33 volumio volumio[5910]: info: CoreCommandRouter::volumioPushState Feb 18 00:02:33 volumio volumio[5910]: info: Asound.conf file unchanged, so no further update is needed Feb 18 00:02:33 volumio volumio[5910]: info: Output device has changed, restarting MPD Feb 18 00:02:33 volumio sudo[6083]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 18 00:02:33 volumio sudo[6083]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 00:02:33 volumio volumio[5910]: info: Output device has changed, restarting Shairport Sync Feb 18 00:02:33 volumio volumio[5910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:02:33 volumio volumio[5910]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 00:02:33 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 20. Feb 18 00:02:33 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 18 00:02:33 volumio volumio[5910]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 18 00:02:33 volumio volumio[5910]: info: ___________ START PLUGINS ___________ Feb 18 00:02:33 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 00:02:33 volumio volumio[5910]: info: ControllerMpd::onStart: Initializing MPD Feb 18 00:02:33 volumio go-librespot[6093]: go-librespot daemon starting... Feb 18 00:02:33 volumio volumio[5910]: info: Creating MPD Configuration file Feb 18 00:02:33 volumio sudo[6085]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 18 00:02:33 volumio sudo[6085]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 00:02:33 volumio sudo[6088]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 18 00:02:33 volumio sudo[6088]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 00:02:33 volumio sudo[6083]: pam_unix(sudo:session): session closed for user root Feb 18 00:02:33 volumio go-librespot[6094]: time="2026-02-18T00:02:33+07:00" level=info msg="running go-librespot 0.4.0" Feb 18 00:02:33 volumio go-librespot[6094]: time="2026-02-18T00:02:33+07:00" level=debug msg="app state loaded" Feb 18 00:02:33 volumio go-librespot[6094]: time="2026-02-18T00:02:33+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 00:02:33 volumio sudo[6085]: pam_unix(sudo:session): session closed for user root Feb 18 00:02:33 volumio volumio[5910]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 18 00:02:33 volumio volumio[5910]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 18 00:02:33 volumio volumio[5910]: info: [1771347753494] CoreMusicLibrary::Adding element Media Servers Feb 18 00:02:33 volumio volumio[5910]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 00:02:33 volumio sudo[6105]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 18 00:02:33 volumio sudo[6105]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 00:02:33 volumio sudo[6102]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 18 00:02:33 volumio sudo[6102]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 00:02:33 volumio sudo[6102]: pam_unix(sudo:session): session closed for user root Feb 18 00:02:33 volumio volumio[5910]: info: UPNP Browser: Client initialized successfully Feb 18 00:02:33 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 18 00:02:33 volumio volumio[5910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:02:33 volumio volumio[5910]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 00:02:33 volumio volumio[5910]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 18 00:02:33 volumio volumio[5910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:02:33 volumio volumio[5910]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 00:02:33 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 18 00:02:33 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 18 00:02:33 volumio systemd[1]: mpd.service: Consumed 7.089s CPU time. Feb 18 00:02:33 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 18 00:02:33 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 18 00:02:33 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 18 00:02:33 volumio volumio[5910]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 18 00:02:33 volumio volumio[5910]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 18 00:02:33 volumio volumio[5910]: info: [1771347753864] CoreMusicLibrary::Adding element Last_100 Feb 18 00:02:33 volumio volumio[5910]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 00:02:33 volumio volumio[5910]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 18 00:02:33 volumio volumio[5910]: info: [1771347753878] CoreMusicLibrary::Adding element Webradio Feb 18 00:02:33 volumio volumio[5910]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 00:02:33 volumio volumio[5910]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 18 00:02:33 volumio volumio[5910]: info: Initializing BBC Radios Feb 18 00:02:33 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 18 00:02:33 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 18 00:02:34 volumio volumio[5910]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 18 00:02:34 volumio volumio[5910]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 00:02:34 volumio go-librespot[6094]: time="2026-02-18T00:02:34+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 18 00:02:34 volumio go-librespot[6094]: time="2026-02-18T00:02:34+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 18 00:02:34 volumio go-librespot[6094]: time="2026-02-18T00:02:34+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 18 00:02:34 volumio volumio[5910]: info: Creating Spotify config file Feb 18 00:02:34 volumio volumio[5910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:02:34 volumio go-librespot[6094]: time="2026-02-18T00:02:34+07:00" level=info msg="zeroconf server listening on port 46523" Feb 18 00:02:34 volumio sudo[6119]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 18 00:02:34 volumio sudo[6119]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 18 00:02:34 volumio sudo[6119]: pam_unix(sudo:session): session closed for user root Feb 18 00:02:34 volumio go-librespot[6094]: time="2026-02-18T00:02:34+07:00" level=debug msg="obtained new client token: AABk69/3zWee4bnS6cq6TQ9UaxDaNuSE/ji4ymHu10VOtliahdcb37mQnaULj12V7SA4nLvstCiLwM5YrZRmmU9L2jnRpWpVQdCGVs4J/oypwej/a6PJ8ne5wEAo8c4kb7/FLGVy0ItV37hKl+OK1kXV+vx6Uxfwfcgsm5XgsefNbanOgqS2st0ZDYGyShfBx4a18Bp0GTgFnMd80R2zYPvmFogqUhXd8e9hSQURJ0LC/JyhE6xBW4X7Eg==" Feb 18 00:02:34 volumio go-librespot[6094]: time="2026-02-18T00:02:34+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 00:02:34 volumio go-librespot[6094]: time="2026-02-18T00:02:34+07:00" level=debug msg="completed keyexchange" Feb 18 00:02:34 volumio go-librespot[6094]: time="2026-02-18T00:02:34+07:00" level=debug msg="completed challenge" Feb 18 00:02:34 volumio go-librespot[6094]: time="2026-02-18T00:02:34+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 18 00:02:34 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 00:02:34 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 00:02:35 volumio volumio[5910]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 18 00:02:35 volumio volumio[5910]: info: [1771347755304] CoreMusicLibrary::Adding element YouTube Music Feb 18 00:02:35 volumio volumio[5910]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 00:02:35 volumio volumio[5910]: Cannot find translation for source YouTube Music Feb 18 00:02:35 volumio volumio[6034]: Starting albumart workers Feb 18 00:02:35 volumio volumio[5910]: info: Volumio Calling Home Feb 18 00:02:35 volumio sudo[6136]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Feb 18 00:02:35 volumio sudo[6136]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 00:02:35 volumio volumio[6033]: Starting albumart workers Feb 18 00:02:35 volumio sudo[6136]: pam_unix(sudo:session): session closed for user root Feb 18 00:02:35 volumio volumio[6032]: Starting albumart workers Feb 18 00:02:36 volumio volumio[5910]: info: Discovery: adding 921648ed-0557-4d70-9927-1420682efcc7 Feb 18 00:02:36 volumio volumio[5910]: info: Discovery: Found device Volumio Feb 18 00:02:36 volumio volumio[5910]: info: CoreCommandRouter::volumioGetState Feb 18 00:02:36 volumio volumio[5910]: info: CorePlayQueue::getTrack 0 Feb 18 00:02:36 volumio volumio[5910]: info: Discovery: this is already registered, 921648ed-0557-4d70-9927-1420682efcc7 Feb 18 00:02:36 volumio volumio[5910]: info: Discovery: Found device Volumio Feb 18 00:02:36 volumio volumio[5910]: info: CoreCommandRouter::volumioGetState Feb 18 00:02:36 volumio volumio[5910]: info: CorePlayQueue::getTrack 0 Feb 18 00:02:36 volumio volumio[5910]: info: MPD Permissions set Feb 18 00:02:36 volumio volumio[5910]: info: MPD Permissions set Feb 18 00:02:36 volumio volumio[5910]: info: Upmpdcli Daemon Started Feb 18 00:02:36 volumio volumio[5910]: info: Spotify config file written Feb 18 00:02:36 volumio sudo[6142]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 18 00:02:36 volumio sudo[6142]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 00:02:36 volumio volumio[5910]: 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 18 00:02:36 volumio volumio[5910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:02:36 volumio volumio[5910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:02:36 volumio volumio[5910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:02:36 volumio volumio[5910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:02:36 volumio volumio[5910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:02:36 volumio volumio[5910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:02:36 volumio volumio[5910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:02:36 volumio volumio[5910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:02:36 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 18 00:02:36 volumio volumio[5910]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 00:02:36 volumio volumio[5910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:02:36 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 00:02:36 volumio go-librespot[6149]: go-librespot daemon starting... Feb 18 00:02:36 volumio volumio[5910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:02:36 volumio volumio[5910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:02:36 volumio volumio[5910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:02:36 volumio volumio[5910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:02:36 volumio volumio[5910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:02:36 volumio volumio[5910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:02:36 volumio volumio[5910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:02:36 volumio volumio[5910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:02:36 volumio sudo[6142]: pam_unix(sudo:session): session closed for user root Feb 18 00:02:36 volumio go-librespot[6150]: time="2026-02-18T00:02:36+07:00" level=info msg="running go-librespot 0.4.0" Feb 18 00:02:36 volumio go-librespot[6150]: time="2026-02-18T00:02:36+07:00" level=debug msg="app state loaded" Feb 18 00:02:36 volumio go-librespot[6150]: time="2026-02-18T00:02:36+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 00:02:36 volumio volumio[5910]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 00:02:36 volumio volumio[5910]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 00:02:36 volumio volumio[5910]: info: No need to fix Spotify hosts Feb 18 00:02:37 volumio go-librespot[6150]: time="2026-02-18T00:02:37+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 18 00:02:37 volumio go-librespot[6150]: time="2026-02-18T00:02:37+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 18 00:02:37 volumio go-librespot[6150]: time="2026-02-18T00:02:37+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 18 00:02:37 volumio go-librespot[6150]: time="2026-02-18T00:02:37+07:00" level=info msg="zeroconf server listening on port 33149" Feb 18 00:02:37 volumio go-librespot[6150]: time="2026-02-18T00:02:37+07:00" level=debug msg="obtained new client token: AACivxMAHnGeAIyVXAoKrcpViraeAP/D4ppswz1Yn1NaXJnHppcYmp1CpgtWViYwYNPIXNk1FhWrQXb1PbS2z1TjOxcmvWjxYToKfoWBiTtDj/Dz/NW+iBRbfiUEOg4ZhSUqF7cnk7H513+qNryT+RugcjohW9n/EO27rt+jwi4uPB2MuE7P1yc6usP6iVt0uP1gevjPaSFDJKKbj/+sSNWID9PYXDsT3nv6MCg/Gc7mPoWqQnTd63qdLw==" Feb 18 00:02:37 volumio go-librespot[6150]: time="2026-02-18T00:02:37+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 00:02:37 volumio go-librespot[6150]: time="2026-02-18T00:02:37+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 18 00:02:37 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 00:02:37 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 00:02:38 volumio volumio[5910]: info: Volumio called home Feb 18 00:02:38 volumio volumio[5910]: info: Starting Shairport Sync Feb 18 00:02:38 volumio volumio[5910]: info: Starting Shairport Sync Feb 18 00:02:38 volumio volumio[5910]: info: Starting Shairport Sync Feb 18 00:02:38 volumio sudo[6182]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 18 00:02:38 volumio sudo[6184]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 18 00:02:38 volumio sudo[6184]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 00:02:38 volumio sudo[6182]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 00:02:38 volumio sudo[6186]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 18 00:02:38 volumio sudo[6186]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 00:02:38 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 18 00:02:38 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 18 00:02:38 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 18 00:02:38 volumio systemd[1]: shairport-sync.service: Consumed 2.340s CPU time. Feb 18 00:02:38 volumio volumio[5910]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 18 00:02:38 volumio volumio[5910]: SPOTIFY: BQAh1jherQAg3odmG5pLB-IUyKTEuUtajUQ8NenTZPAhr8I7uxxYFXPtWlen4UxqLhnlW8khcEnL1NmwT3mnPHYVr4iAEXVLVHp2Gpcfi0eRFt8O6JfNjsVc0SXGOoXUZxI0B8lM0Ec1A2YVgQ7JSRh651t3Rix4vJZ_qcLsGBWRXPy_BgDVvSCP_8BrzOnGuDwm5OTCLF_WpU7tMZn2zcmtERnkxAINNM6biSEcCC4OJ29aAzzEaGFTUxLvgN4VxWWJVYH06G1uxR2wH1ZHtQdE4ADjwVwOmRiNdh6CVWlsnlrvMfPVaYfV Feb 18 00:02:38 volumio volumio[5910]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 18 00:02:38 volumio volumio[5910]: info: New Spotify access token = BQAh1jherQAg3odmG5pLB-IUyKTEuUtajUQ8NenTZPAhr8I7uxxYFXPtWlen4UxqLhnlW8khcEnL1NmwT3mnPHYVr4iAEXVLVHp2Gpcfi0eRFt8O6JfNjsVc0SXGOoXUZxI0B8lM0Ec1A2YVgQ7JSRh651t3Rix4vJZ_qcLsGBWRXPy_BgDVvSCP_8BrzOnGuDwm5OTCLF_WpU7tMZn2zcmtERnkxAINNM6biSEcCC4OJ29aAzzEaGFTUxLvgN4VxWWJVYH06G1uxR2wH1ZHtQdE4ADjwVwOmRiNdh6CVWlsnlrvMfPVaYfV Feb 18 00:02:38 volumio volumio[5910]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 18 00:02:38 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 18 00:02:38 volumio sudo[6184]: pam_unix(sudo:session): session closed for user root Feb 18 00:02:38 volumio sudo[6182]: pam_unix(sudo:session): session closed for user root Feb 18 00:02:38 volumio sudo[6186]: pam_unix(sudo:session): session closed for user root Feb 18 00:02:38 volumio volumio[5910]: info: Shairport-Sync Started Feb 18 00:02:38 volumio volumio[5910]: Error adding Membership: Error: addMembership EINVAL Feb 18 00:02:38 volumio volumio[5910]: info: Shairport-Sync Started Feb 18 00:02:38 volumio volumio[5910]: info: Shairport-Sync Started Feb 18 00:02:38 volumio volumio[5910]: info: CoreCommandRouter::volumioGetState Feb 18 00:02:38 volumio volumio[5910]: info: CorePlayQueue::getTrack 0 Feb 18 00:02:39 volumio volumio[5910]: 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 18 00:02:39 volumio volumio[5910]: info: Spotify Successfully logged in Feb 18 00:02:39 volumio volumio[5910]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 18 00:02:39 volumio volumio[5910]: info: [1771347759104] CoreMusicLibrary::Adding element Spotify Feb 18 00:02:39 volumio volumio[5910]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 00:02:39 volumio volumio[5910]: Cannot find translation for source YouTube Music Feb 18 00:02:39 volumio volumio[5910]: Cannot find translation for source Spotify Feb 18 00:02:39 volumio volumio[5910]: info: [yt-cast-receiver] DIAL server listening on port 8098 Feb 18 00:02:39 volumio volumio[5910]: info: CoreCommandRouter::volumioRetrievevolume Feb 18 00:02:39 volumio volumio[5910]: info: VolumeController:: Volume=100 Mute =false Feb 18 00:02:39 volumio volumio[5910]: info: CoreCommandRouter::volumioGetState Feb 18 00:02:39 volumio volumio[5910]: info: CorePlayQueue::getTrack 0 Feb 18 00:02:39 volumio volumio[5910]: info: CoreStateMachine::pushState Feb 18 00:02:39 volumio volumio[5910]: info: CorePlayQueue::getTrack 0 Feb 18 00:02:39 volumio volumio[5910]: info: CoreCommandRouter::volumioPushState Feb 18 00:02:40 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 21. Feb 18 00:02:40 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 18 00:02:40 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 00:02:40 volumio go-librespot[6224]: go-librespot daemon starting... Feb 18 00:02:40 volumio go-librespot[6226]: time="2026-02-18T00:02:40+07:00" level=info msg="running go-librespot 0.4.0" Feb 18 00:02:40 volumio go-librespot[6226]: time="2026-02-18T00:02:40+07:00" level=debug msg="app state loaded" Feb 18 00:02:40 volumio go-librespot[6226]: time="2026-02-18T00:02:40+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 00:02:41 volumio volumio[5910]: info: go-librespot daemon successfully initialized Feb 18 00:02:41 volumio go-librespot[6226]: time="2026-02-18T00:02:41+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 18 00:02:41 volumio go-librespot[6226]: time="2026-02-18T00:02:41+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 18 00:02:41 volumio go-librespot[6226]: time="2026-02-18T00:02:41+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 18 00:02:41 volumio go-librespot[6226]: time="2026-02-18T00:02:41+07:00" level=info msg="zeroconf server listening on port 35975" Feb 18 00:02:41 volumio mpd[6133]: 2026-02-18T00:02:41 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 18 00:02:41 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 18 00:02:41 volumio sudo[6088]: pam_unix(sudo:session): session closed for user root Feb 18 00:02:41 volumio sudo[6105]: pam_unix(sudo:session): session closed for user root Feb 18 00:02:41 volumio go-librespot[6226]: time="2026-02-18T00:02:41+07:00" level=debug msg="obtained new client token: AAAapbX7wDQKHWrrWeTi0gU5OU1Eq5isWnZXP4kKbO7a/TW2JT1dzmV5MEycGDkgQjIc2WqhZnJo1UHneH5Gqhcn4NlFqGhd2iUYk4EghYv6Um1jnDx69s+9tN4cNw6B+K+9wDn8+8RR/wSAXR9nuFKyf6qVKHA27rhde4VEwTaU7Dhso2m22fjvZnIb5e3FYMs6vNmUe4dbDjWrSPbXnkxTYuvI1nNq/d5SdaQcRDGIaX62YwSbdb9TGQ==" Feb 18 00:02:41 volumio go-librespot[6226]: time="2026-02-18T00:02:41+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 00:02:41 volumio volumio[5910]: error: MPD error: The expression evaluated to a falsy value: Feb 18 00:02:41 volumio volumio[5910]: assert.ok(self.idling) Feb 18 00:02:41 volumio volumio[5910]: error: The expression evaluated to a falsy value: Feb 18 00:02:41 volumio volumio[5910]: assert.ok(self.idling) Feb 18 00:02:41 volumio volumio[5910]: info: MPD running with PID6133 Feb 18 00:02:41 volumio volumio[5910]: ,establishing connection Feb 18 00:02:41 volumio volumio[5910]: error: updateQueue error: null Feb 18 00:02:41 volumio go-librespot[6226]: time="2026-02-18T00:02:41+07:00" level=debug msg="completed keyexchange" Feb 18 00:02:41 volumio go-librespot[6226]: time="2026-02-18T00:02:41+07:00" level=debug msg="completed challenge" Feb 18 00:02:41 volumio volumio[5910]: info: Completed starting Core Plugins Feb 18 00:02:41 volumio volumio[5910]: info: ------------------------------------------- Feb 18 00:02:41 volumio volumio[5910]: info: ----- MyVolumio plugins startup ---- Feb 18 00:02:41 volumio volumio[5910]: info: ------------------------------------------- Feb 18 00:02:41 volumio volumio[5910]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 18 00:02:41 volumio volumio[5910]: error: updateQueue error: null Feb 18 00:02:41 volumio go-librespot[6226]: time="2026-02-18T00:02:41+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 18 00:02:41 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 00:02:41 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 00:02:44 volumio volumio[5910]: info: Initializing connection to go-librespot Websocket Feb 18 00:02:44 volumio volumio[5910]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 18 00:02:45 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22. Feb 18 00:02:45 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 18 00:02:45 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 00:02:45 volumio go-librespot[6238]: go-librespot daemon starting... Feb 18 00:02:45 volumio go-librespot[6239]: time="2026-02-18T00:02:45+07:00" level=info msg="running go-librespot 0.4.0" Feb 18 00:02:45 volumio go-librespot[6239]: time="2026-02-18T00:02:45+07:00" level=debug msg="app state loaded" Feb 18 00:02:45 volumio go-librespot[6239]: time="2026-02-18T00:02:45+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 00:02:45 volumio go-librespot[6239]: time="2026-02-18T00:02:45+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 18 00:02:45 volumio go-librespot[6239]: time="2026-02-18T00:02:45+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 18 00:02:45 volumio go-librespot[6239]: time="2026-02-18T00:02:45+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 18 00:02:45 volumio go-librespot[6239]: time="2026-02-18T00:02:45+07:00" level=info msg="zeroconf server listening on port 32909" Feb 18 00:02:45 volumio go-librespot[6239]: time="2026-02-18T00:02:45+07:00" level=debug msg="obtained new client token: AAAGrC1XBjoT8QbfvueERmhMUwNwuI9hLWFZ9JECX2kjVPLZL+C5k6R1qaEns8iscoqj1QNEwJ4gYMckqqd57v70GpOa5aS8pqheRYkQRZZKOz6I1c+x4b4tJv1MCPnzTNr/BXvErsp2FTbCynM8mOcaYQXIprDmPiV4j5t0NIGzCQffAXmR2/RcdkBeH3RgitKOHGgaSpwqU7XCzkJIzXmYL9w7KE6QNa8CF1576IO3RhxK7ecN6RzCEg==" Feb 18 00:02:45 volumio go-librespot[6239]: time="2026-02-18T00:02:45+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 00:02:46 volumio go-librespot[6239]: time="2026-02-18T00:02:46+07:00" level=debug msg="completed keyexchange" Feb 18 00:02:46 volumio go-librespot[6239]: time="2026-02-18T00:02:46+07:00" level=debug msg="completed challenge" Feb 18 00:02:46 volumio go-librespot[6239]: time="2026-02-18T00:02:46+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 18 00:02:46 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 00:02:46 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 00:02:46 volumio volumio[5910]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 18 00:02:47 volumio volumio[5910]: info: Initializing connection to go-librespot Websocket Feb 18 00:02:47 volumio volumio[5910]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 18 00:02:49 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 23. Feb 18 00:02:49 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 18 00:02:49 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 00:02:49 volumio go-librespot[6246]: go-librespot daemon starting... Feb 18 00:02:49 volumio go-librespot[6247]: time="2026-02-18T00:02:49+07:00" level=info msg="running go-librespot 0.4.0" Feb 18 00:02:49 volumio go-librespot[6247]: time="2026-02-18T00:02:49+07:00" level=debug msg="app state loaded" Feb 18 00:02:49 volumio go-librespot[6247]: time="2026-02-18T00:02:49+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 00:02:49 volumio go-librespot[6247]: time="2026-02-18T00:02:49+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 18 00:02:49 volumio go-librespot[6247]: time="2026-02-18T00:02:49+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 18 00:02:49 volumio go-librespot[6247]: time="2026-02-18T00:02:49+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 18 00:02:49 volumio go-librespot[6247]: time="2026-02-18T00:02:49+07:00" level=info msg="zeroconf server listening on port 45893" Feb 18 00:02:50 volumio go-librespot[6247]: time="2026-02-18T00:02:50+07:00" level=debug msg="obtained new client token: AAAOGrru5LQyj98NJcwdINZynGjPaFTRTrcz+/iJbVa0Mmq0bZfLOIDoVnBoOco8fmBF38k1j/NR9MzBJqiqhX3pCqV3l46ySjj6FWNXb7+yv2Z1n2mOADDtKDm51fF+0Xv+y+jfuk7Ehufrnf3u21qHzcg8StCXTBT3yNfYARKRY19BApgoDlK395W4funrZGG74ZyPXWGnAqbmBM4lysR86t295+mXrXL+U/aQLC2xAbhsQm1nEn4=" Feb 18 00:02:50 volumio volumio[5910]: info: Initializing connection to go-librespot Websocket Feb 18 00:02:50 volumio go-librespot[6247]: time="2026-02-18T00:02:50+07:00" level=debug msg="new websocket client" Feb 18 00:02:50 volumio volumio[5910]: info: Connection to go-librespot Websocket established Feb 18 00:02:50 volumio go-librespot[6247]: time="2026-02-18T00:02:50+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 18 00:02:50 volumio go-librespot[6247]: time="2026-02-18T00:02:50+07:00" level=debug msg="connected to ap-gae2.spotify.com:443" Feb 18 00:02:50 volumio go-librespot[6247]: time="2026-02-18T00:02:50+07:00" level=debug msg="completed keyexchange" Feb 18 00:02:50 volumio go-librespot[6247]: time="2026-02-18T00:02:50+07:00" level=debug msg="completed challenge" Feb 18 00:02:50 volumio go-librespot[6247]: time="2026-02-18T00:02:50+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 18 00:02:50 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 00:02:50 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 00:02:50 volumio volumio[5910]: info: Connection to go-librespot Websocket closed Feb 18 00:02:50 volumio volumio[5910]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 18 00:02:50 volumio volumio[5910]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 18 00:02:50 volumio volumio[5910]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 18 00:02:50 volumio volumio[5910]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 18 00:02:50 volumio volumio[5910]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 18 00:02:50 volumio volumio[5910]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 18 00:02:50 volumio volumio[5910]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 18 00:02:50 volumio volumio[5910]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 18 00:02:50 volumio volumio[5910]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 18 00:02:50 volumio volumio[5910]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 18 00:02:50 volumio volumio[5910]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 18 00:02:50 volumio volumio[5910]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 18 00:02:50 volumio volumio[5910]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 18 00:02:50 volumio volumio[5910]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 18 00:02:50 volumio volumio[5910]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 18 00:02:50 volumio volumio[5910]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 18 00:02:50 volumio volumio[5910]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 18 00:02:50 volumio volumio[5910]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 18 00:02:50 volumio volumio[5910]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 18 00:02:50 volumio volumio[5910]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 18 00:02:50 volumio volumio[5910]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 18 00:02:50 volumio volumio[5910]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 18 00:02:50 volumio volumio[5910]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 18 00:02:50 volumio volumio[5910]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 18 00:02:50 volumio volumio[5910]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 18 00:02:50 volumio volumio[5910]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 18 00:02:50 volumio volumio[5910]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 18 00:02:50 volumio volumio[5910]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 18 00:02:50 volumio volumio[5910]: info: Adding plugin bluetooth to MyMusic Plugins Feb 18 00:02:50 volumio volumio[5910]: info: Adding plugin multiroom to MyMusic Plugins Feb 18 00:02:50 volumio volumio[5910]: info: Adding plugin metavolumio to MyMusic Plugins Feb 18 00:02:50 volumio volumio[5910]: info: Adding plugin cd_controller to MyMusic Plugins Feb 18 00:02:50 volumio volumio[5910]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 18 00:02:50 volumio volumio[5910]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 18 00:02:50 volumio volumio[5910]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 18 00:02:50 volumio volumio[5910]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 18 00:02:52 volumio volumio[5910]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 18 00:02:52 volumio volumio[5910]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 18 00:02:52 volumio volumio[5910]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 00:02:52 volumio volumio[5910]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 00:02:52 volumio volumio[5910]: info: Starting MyVolumio Remote Streaming Endpoints Feb 18 00:02:52 volumio volumio[5910]: info: MyVolumio login type: Token Feb 18 00:02:52 volumio volumio[5910]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 18 00:02:52 volumio volumio[5910]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 18 00:02:53 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 24. Feb 18 00:02:53 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 18 00:02:53 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 00:02:53 volumio go-librespot[6268]: go-librespot daemon starting... Feb 18 00:02:53 volumio go-librespot[6269]: time="2026-02-18T00:02:53+07:00" level=info msg="running go-librespot 0.4.0" Feb 18 00:02:53 volumio go-librespot[6269]: time="2026-02-18T00:02:53+07:00" level=debug msg="app state loaded" Feb 18 00:02:53 volumio go-librespot[6269]: time="2026-02-18T00:02:53+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 00:02:54 volumio go-librespot[6269]: time="2026-02-18T00:02:54+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 18 00:02:54 volumio go-librespot[6269]: time="2026-02-18T00:02:54+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 18 00:02:54 volumio go-librespot[6269]: time="2026-02-18T00:02:54+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 18 00:02:54 volumio go-librespot[6269]: time="2026-02-18T00:02:54+07:00" level=info msg="zeroconf server listening on port 41479" Feb 18 00:02:54 volumio volumio[5910]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 18 00:02:54 volumio volumio[5910]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 18 00:02:54 volumio volumio[5910]: info: Streaming services startup Feb 18 00:02:54 volumio volumio[5910]: info: Starting Streaming Daemon Feb 18 00:02:54 volumio sudo[6277]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 18 00:02:54 volumio sudo[6277]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 00:02:54 volumio volumio[5910]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 18 00:02:54 volumio sudo[6277]: pam_unix(sudo:session): session closed for user root Feb 18 00:02:54 volumio go-librespot[6269]: time="2026-02-18T00:02:54+07:00" level=debug msg="obtained new client token: AAD5hFx/XZYK3oum1SstXEmU2lnDzcTKOq6RqX27M3vh4SDZN80ZRQI504t4vMiQczIZOVHsHn++Hk9y/jgzIhu+UwyogjR8ReGzz8AP1foFCxoHZVvRJ3gGzpCLiSCaMuxPWa7U69+VChrTsKRWWIe9OPdMQYbb44398zyg4L03P9+pyERFrauYx8xLSVrIpnxNaFrkmrSrhvmLkzDkMDefA0v6fi2/Ar0j8NKOBJgYWOyMBzjqOOxfnQ==" Feb 18 00:02:54 volumio volumio[5910]: info: Getting Spotify volume Feb 18 00:02:54 volumio volumio[5910]: info: Initializing connection to go-librespot Websocket Feb 18 00:02:54 volumio volumio[5910]: error: Cannot start Volumio Streaming Daemon Feb 18 00:02:54 volumio volumio[5910]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 18 00:02:54 volumio volumio[5910]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 18 00:02:54 volumio go-librespot[6269]: time="2026-02-18T00:02:54+07:00" level=debug msg="new websocket client" Feb 18 00:02:54 volumio volumio[5910]: info: Connection to go-librespot Websocket established Feb 18 00:02:54 volumio volumio[5910]: 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 18 00:02:54 volumio go-librespot[6269]: time="2026-02-18T00:02:54+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 18 00:02:54 volumio go-librespot[6269]: time="2026-02-18T00:02:54+07:00" level=debug msg="completed keyexchange" Feb 18 00:02:54 volumio go-librespot[6269]: time="2026-02-18T00:02:54+07:00" level=debug msg="completed challenge" Feb 18 00:02:54 volumio go-librespot[6269]: time="2026-02-18T00:02:54+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 18 00:02:54 volumio volumio[5910]: info: CoreCommandRouter::volumioGetState Feb 18 00:02:54 volumio volumio[5910]: info: CorePlayQueue::getTrack 0 Feb 18 00:02:54 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 00:02:54 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 00:02:54 volumio volumio[5910]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 18 00:02:54 volumio volumio[5910]: Error: socket hang up Feb 18 00:02:54 volumio volumio[5910]: at connResetException (node:internal/errors:720:14) Feb 18 00:02:54 volumio volumio[5910]: at Socket.socketOnEnd (node:_http_client:519:23) Feb 18 00:02:54 volumio volumio[5910]: at Socket.emit (node:events:526:35) Feb 18 00:02:54 volumio volumio[5910]: at endReadableNT (node:internal/streams/readable:1376:12) Feb 18 00:02:54 volumio volumio[5910]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Feb 18 00:02:54 volumio volumio[5910]: code: 'ECONNRESET', Feb 18 00:02:54 volumio volumio[5910]: response: undefined Feb 18 00:02:54 volumio volumio[5910]: } Feb 18 00:02:54 volumio volumio[5910]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 18 00:02:56 volumio sudo[6297]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-18 00:01' Feb 18 00:02:56 volumio sudo[6297]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="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"