Feb 19 01:09:00 volumio go-librespot[11368]: time="2026-02-19T01:09:00+07:00" level=info msg="zeroconf server listening on port 43631" Feb 19 01:09:00 volumio volumio[11131]: info: Discovery: adding 921648ed-0557-4d70-9927-1420682efcc7 Feb 19 01:09:00 volumio volumio[11131]: info: Discovery: Found device Volumio Feb 19 01:09:00 volumio volumio[11131]: info: CoreCommandRouter::volumioGetState Feb 19 01:09:00 volumio volumio[11131]: info: CorePlayQueue::getTrack 0 Feb 19 01:09:00 volumio volumio[11131]: info: Discovery: this is already registered, 921648ed-0557-4d70-9927-1420682efcc7 Feb 19 01:09:00 volumio volumio[11131]: info: Discovery: Found device Volumio Feb 19 01:09:00 volumio volumio[11131]: info: CoreCommandRouter::volumioGetState Feb 19 01:09:00 volumio volumio[11131]: info: CorePlayQueue::getTrack 0 Feb 19 01:09:00 volumio volumio[11131]: info: MPD Permissions set Feb 19 01:09:00 volumio volumio[11131]: info: MPD Permissions set Feb 19 01:09:00 volumio volumio[11131]: info: Upmpdcli Daemon Started Feb 19 01:09:00 volumio volumio[11131]: info: Spotify config file written Feb 19 01:09:00 volumio volumio[11131]: 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 19 01:09:00 volumio sudo[11388]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 19 01:09:00 volumio sudo[11388]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 19 01:09:00 volumio volumio[11131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 01:09:00 volumio volumio[11131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 01:09:00 volumio volumio[11131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 01:09:00 volumio volumio[11131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 01:09:00 volumio volumio[11131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 01:09:00 volumio volumio[11131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 01:09:00 volumio volumio[11131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 01:09:00 volumio volumio[11131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 01:09:00 volumio volumio[11131]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 01:09:00 volumio volumio[11131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 01:09:00 volumio volumio[11131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 01:09:00 volumio volumio[11131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 01:09:00 volumio volumio[11131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 01:09:00 volumio volumio[11131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 01:09:00 volumio volumio[11131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 01:09:00 volumio volumio[11131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 01:09:00 volumio volumio[11131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 01:09:00 volumio volumio[11131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 01:09:00 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Feb 19 01:09:00 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Feb 19 01:09:00 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 19 01:09:00 volumio volumio[11131]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 01:09:00 volumio volumio[11131]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 01:09:00 volumio volumio[11131]: info: No need to fix Spotify hosts Feb 19 01:09:01 volumio go-librespot[11400]: go-librespot daemon starting... Feb 19 01:09:01 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 19 01:09:01 volumio sudo[11388]: pam_unix(sudo:session): session closed for user root Feb 19 01:09:01 volumio go-librespot[11401]: time="2026-02-19T01:09:01+07:00" level=info msg="running go-librespot 0.4.0" Feb 19 01:09:01 volumio go-librespot[11401]: time="2026-02-19T01:09:01+07:00" level=debug msg="app state loaded" Feb 19 01:09:01 volumio go-librespot[11401]: time="2026-02-19T01:09:01+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 19 01:09:01 volumio go-librespot[11401]: time="2026-02-19T01:09:01+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 19 01:09:01 volumio go-librespot[11401]: time="2026-02-19T01:09:01+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 19 01:09:01 volumio go-librespot[11401]: time="2026-02-19T01:09:01+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 19 01:09:01 volumio go-librespot[11401]: time="2026-02-19T01:09:01+07:00" level=info msg="zeroconf server listening on port 43891" Feb 19 01:09:01 volumio volumio[11131]: info: Volumio called home Feb 19 01:09:01 volumio volumio[11131]: info: Starting Shairport Sync Feb 19 01:09:01 volumio volumio[11131]: info: Starting Shairport Sync Feb 19 01:09:01 volumio volumio[11131]: info: Starting Shairport Sync Feb 19 01:09:01 volumio sudo[11415]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 19 01:09:01 volumio sudo[11415]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 19 01:09:01 volumio sudo[11417]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 19 01:09:01 volumio sudo[11417]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 19 01:09:01 volumio sudo[11419]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 19 01:09:01 volumio sudo[11419]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 19 01:09:02 volumio go-librespot[11401]: time="2026-02-19T01:09:02+07:00" level=debug msg="obtained new client token: AABJnCSItccN4rHexYozfdXGOqJAPcS1igC+MSyiwxM1JsV0Lxs83iUQcPGTepBK5GuFDa3GGiOITVBzioyY807fjHKlHqdU7kyOdnv9PYvCk4+0ne4zzmrd+upMfp4VPz4OOBDvAQVDbmArbXeewOVGXg7LBB36g5eel/qtxWZ6DNibWSzgjRQHbLbHe3I9yKFv8/6sD2W+pAX3iOMawy7ybmDT0l+3glcn8GaBRXzCcws2YPBZ9BY=" Feb 19 01:09:02 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 19 01:09:02 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 19 01:09:02 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 19 01:09:02 volumio systemd[1]: shairport-sync.service: Consumed 6.514s CPU time. Feb 19 01:09:02 volumio go-librespot[11401]: time="2026-02-19T01:09:02+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 19 01:09:02 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 19 01:09:02 volumio sudo[11419]: pam_unix(sudo:session): session closed for user root Feb 19 01:09:02 volumio sudo[11415]: pam_unix(sudo:session): session closed for user root Feb 19 01:09:02 volumio sudo[11417]: pam_unix(sudo:session): session closed for user root Feb 19 01:09:02 volumio go-librespot[11401]: time="2026-02-19T01:09:02+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 19 01:09:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 19 01:09:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 19 01:09:02 volumio volumio[11131]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 19 01:09:02 volumio volumio[11131]: SPOTIFY: BQBClT1n523LESY-oA3C85j-mjq7_N-B0C5fOS3p5gDuqvHoL9p16FbdH_T60uRIor8M3b73gcWTTIUqHNQKoeVOK7ynWcujIgTBLzi__tJxkiEJ1Z3lo2OF7ZppQCnVKMFYFe9dtlzo33CNqYxQe_jGUCEmxHdmqYRI-uk_uBiGhKC_LN5gO4CrEGZISCNmI2WJl8-nOu3xM3nuw0Da3Y6w2QqQinxZggwP69Jx7GKZhqVEefWMrVE10qeTBcuPXcB-EajC__eW1tAj86QoUGJZudvBBjv3sB57Rgit1p1FZj2YfRv3Vh2y Feb 19 01:09:02 volumio volumio[11131]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 19 01:09:02 volumio volumio[11131]: info: New Spotify access token = BQBClT1n523LESY-oA3C85j-mjq7_N-B0C5fOS3p5gDuqvHoL9p16FbdH_T60uRIor8M3b73gcWTTIUqHNQKoeVOK7ynWcujIgTBLzi__tJxkiEJ1Z3lo2OF7ZppQCnVKMFYFe9dtlzo33CNqYxQe_jGUCEmxHdmqYRI-uk_uBiGhKC_LN5gO4CrEGZISCNmI2WJl8-nOu3xM3nuw0Da3Y6w2QqQinxZggwP69Jx7GKZhqVEefWMrVE10qeTBcuPXcB-EajC__eW1tAj86QoUGJZudvBBjv3sB57Rgit1p1FZj2YfRv3Vh2y Feb 19 01:09:02 volumio volumio[11131]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 19 01:09:02 volumio volumio[11131]: info: Shairport-Sync Started Feb 19 01:09:02 volumio volumio[11131]: Error adding Membership: Error: addMembership EINVAL Feb 19 01:09:02 volumio volumio[11131]: info: Shairport-Sync Started Feb 19 01:09:02 volumio volumio[11131]: info: Shairport-Sync Started Feb 19 01:09:02 volumio volumio[11131]: info: CoreCommandRouter::volumioGetState Feb 19 01:09:02 volumio volumio[11131]: info: CorePlayQueue::getTrack 0 Feb 19 01:09:02 volumio volumio[11131]: 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 19 01:09:02 volumio volumio[11131]: info: Spotify Successfully logged in Feb 19 01:09:02 volumio volumio[11131]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 19 01:09:02 volumio volumio[11131]: info: [1771438142852] CoreMusicLibrary::Adding element Spotify Feb 19 01:09:02 volumio volumio[11131]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 19 01:09:02 volumio volumio[11131]: Cannot find translation for source YouTube Music Feb 19 01:09:02 volumio volumio[11131]: Cannot find translation for source Spotify Feb 19 01:09:03 volumio volumio[11131]: info: [yt-cast-receiver] DIAL server listening on port 8098 Feb 19 01:09:03 volumio volumio[11131]: info: CoreCommandRouter::volumioRetrievevolume Feb 19 01:09:03 volumio volumio[11131]: info: VolumeController:: Volume=100 Mute =false Feb 19 01:09:03 volumio volumio[11131]: info: CoreCommandRouter::volumioGetState Feb 19 01:09:03 volumio volumio[11131]: info: CorePlayQueue::getTrack 0 Feb 19 01:09:03 volumio volumio[11131]: info: CoreStateMachine::pushState Feb 19 01:09:03 volumio volumio[11131]: info: CorePlayQueue::getTrack 0 Feb 19 01:09:03 volumio volumio[11131]: info: CoreCommandRouter::volumioPushState Feb 19 01:09:04 volumio volumio[11131]: info: go-librespot daemon successfully initialized Feb 19 01:09:04 volumio mpd[11360]: 2026-02-19T01:09:04 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 19 01:09:04 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 19 01:09:04 volumio sudo[11331]: pam_unix(sudo:session): session closed for user root Feb 19 01:09:04 volumio sudo[11321]: pam_unix(sudo:session): session closed for user root Feb 19 01:09:05 volumio volumio[11131]: error: MPD error: The expression evaluated to a falsy value: Feb 19 01:09:05 volumio volumio[11131]: assert.ok(self.idling) Feb 19 01:09:05 volumio volumio[11131]: error: The expression evaluated to a falsy value: Feb 19 01:09:05 volumio volumio[11131]: assert.ok(self.idling) Feb 19 01:09:05 volumio volumio[11131]: info: MPD running with PID11360 Feb 19 01:09:05 volumio volumio[11131]: ,establishing connection Feb 19 01:09:05 volumio volumio[11131]: error: updateQueue error: null Feb 19 01:09:05 volumio volumio[11131]: info: Completed starting Core Plugins Feb 19 01:09:05 volumio volumio[11131]: info: ------------------------------------------- Feb 19 01:09:05 volumio volumio[11131]: info: ----- MyVolumio plugins startup ---- Feb 19 01:09:05 volumio volumio[11131]: info: ------------------------------------------- Feb 19 01:09:05 volumio volumio[11131]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 19 01:09:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Feb 19 01:09:05 volumio volumio[11131]: error: updateQueue error: null Feb 19 01:09:05 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 19 01:09:05 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 19 01:09:05 volumio go-librespot[11461]: go-librespot daemon starting... Feb 19 01:09:05 volumio go-librespot[11462]: time="2026-02-19T01:09:05+07:00" level=info msg="running go-librespot 0.4.0" Feb 19 01:09:05 volumio go-librespot[11462]: time="2026-02-19T01:09:05+07:00" level=debug msg="app state loaded" Feb 19 01:09:05 volumio go-librespot[11462]: time="2026-02-19T01:09:05+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 19 01:09:05 volumio go-librespot[11462]: time="2026-02-19T01:09:05+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 19 01:09:05 volumio go-librespot[11462]: time="2026-02-19T01:09:05+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 19 01:09:05 volumio go-librespot[11462]: time="2026-02-19T01:09:05+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 19 01:09:05 volumio go-librespot[11462]: time="2026-02-19T01:09:05+07:00" level=info msg="zeroconf server listening on port 45901" Feb 19 01:09:06 volumio go-librespot[11462]: time="2026-02-19T01:09:06+07:00" level=debug msg="obtained new client token: AABYXe3ZyVdxRmaBIfGtk48fxIiMDTPoPdoO5MbND4RgHMn5MN11Ws+wHYh3d5j/L/62BMZBdDBG1aBn0NZKNB/9rm7kxQVj0z7EoRQTGGUr2txpowskXv1L9W97j4dNcWtDWtb/5NbaonlHRUB0Ze+IXBM3DdT8i8wMNLe6XteZVXaiN9TUhKMFUEF1Ujc7HvqBz698uqCa5VFy77g0/+BzUc0xtdvpiJQPoMO0QgHWgwxygQ6nCAY=" Feb 19 01:09:06 volumio go-librespot[11462]: time="2026-02-19T01:09:06+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 19 01:09:06 volumio go-librespot[11462]: time="2026-02-19T01:09:06+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 19 01:09:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 19 01:09:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 19 01:09:07 volumio volumio[11131]: info: Initializing connection to go-librespot Websocket Feb 19 01:09:07 volumio volumio[11131]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 19 01:09:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Feb 19 01:09:09 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 19 01:09:09 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 19 01:09:09 volumio go-librespot[11469]: go-librespot daemon starting... Feb 19 01:09:09 volumio go-librespot[11470]: time="2026-02-19T01:09:09+07:00" level=info msg="running go-librespot 0.4.0" Feb 19 01:09:09 volumio go-librespot[11470]: time="2026-02-19T01:09:09+07:00" level=debug msg="app state loaded" Feb 19 01:09:09 volumio go-librespot[11470]: time="2026-02-19T01:09:09+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 19 01:09:10 volumio volumio[11131]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 19 01:09:10 volumio go-librespot[11470]: time="2026-02-19T01:09:10+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 19 01:09:10 volumio go-librespot[11470]: time="2026-02-19T01:09:10+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 19 01:09:10 volumio go-librespot[11470]: time="2026-02-19T01:09:10+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 19 01:09:10 volumio go-librespot[11470]: time="2026-02-19T01:09:10+07:00" level=info msg="zeroconf server listening on port 32863" Feb 19 01:09:10 volumio go-librespot[11470]: time="2026-02-19T01:09:10+07:00" level=debug msg="obtained new client token: AAAHsgtttiS8RhNNe/J8n5lf//9ud8RRlS03sLnFe7QpoFTXO/gsbevMFeC4gBHN/tuyKPS0jPILaMiOiyPAjxtGYfev90rxNzup/FZYqFEOtgAsKwxnbkVsylE8TuGqDS2YyXw8WPQu653731Fuu+dzWcGampjzOKIQ5o1nOrbBW+MQti2sPKWjUgQtR7bOfltgwknICuAFE1P9qo21xOTwxo/H2GhCOj3RjMkYTG8gjZnBtGq2pUTz3A==" Feb 19 01:09:10 volumio go-librespot[11470]: time="2026-02-19T01:09:10+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 19 01:09:10 volumio go-librespot[11470]: time="2026-02-19T01:09:10+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 19 01:09:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 19 01:09:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 19 01:09:10 volumio volumio[11131]: info: Initializing connection to go-librespot Websocket Feb 19 01:09:10 volumio volumio[11131]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 19 01:09:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Feb 19 01:09:13 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 19 01:09:13 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 19 01:09:13 volumio go-librespot[11478]: go-librespot daemon starting... Feb 19 01:09:13 volumio go-librespot[11479]: time="2026-02-19T01:09:13+07:00" level=info msg="running go-librespot 0.4.0" Feb 19 01:09:13 volumio go-librespot[11479]: time="2026-02-19T01:09:13+07:00" level=debug msg="app state loaded" Feb 19 01:09:13 volumio go-librespot[11479]: time="2026-02-19T01:09:13+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 19 01:09:13 volumio volumio[11131]: info: Initializing connection to go-librespot Websocket Feb 19 01:09:13 volumio go-librespot[11479]: time="2026-02-19T01:09:13+07:00" level=debug msg="new websocket client" Feb 19 01:09:13 volumio volumio[11131]: info: Connection to go-librespot Websocket established Feb 19 01:09:14 volumio go-librespot[11479]: time="2026-02-19T01:09:14+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 19 01:09:14 volumio go-librespot[11479]: time="2026-02-19T01:09:14+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 19 01:09:14 volumio go-librespot[11479]: time="2026-02-19T01:09:14+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 19 01:09:14 volumio go-librespot[11479]: time="2026-02-19T01:09:14+07:00" level=info msg="zeroconf server listening on port 44565" Feb 19 01:09:14 volumio volumio[11131]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 19 01:09:14 volumio volumio[11131]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 19 01:09:14 volumio volumio[11131]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 19 01:09:14 volumio volumio[11131]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 19 01:09:14 volumio volumio[11131]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 19 01:09:14 volumio volumio[11131]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 19 01:09:14 volumio volumio[11131]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 19 01:09:14 volumio volumio[11131]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 19 01:09:14 volumio volumio[11131]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 19 01:09:14 volumio volumio[11131]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 19 01:09:14 volumio volumio[11131]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 19 01:09:14 volumio volumio[11131]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 19 01:09:14 volumio volumio[11131]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 19 01:09:14 volumio volumio[11131]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 19 01:09:14 volumio volumio[11131]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 19 01:09:14 volumio volumio[11131]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 19 01:09:14 volumio volumio[11131]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 19 01:09:14 volumio volumio[11131]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 19 01:09:14 volumio volumio[11131]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 19 01:09:14 volumio volumio[11131]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 19 01:09:14 volumio volumio[11131]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 19 01:09:14 volumio volumio[11131]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 19 01:09:14 volumio volumio[11131]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 19 01:09:14 volumio volumio[11131]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 19 01:09:14 volumio volumio[11131]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 19 01:09:14 volumio volumio[11131]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 19 01:09:14 volumio volumio[11131]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 19 01:09:14 volumio volumio[11131]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 19 01:09:14 volumio volumio[11131]: info: Adding plugin bluetooth to MyMusic Plugins Feb 19 01:09:14 volumio volumio[11131]: info: Adding plugin multiroom to MyMusic Plugins Feb 19 01:09:14 volumio volumio[11131]: info: Adding plugin metavolumio to MyMusic Plugins Feb 19 01:09:14 volumio volumio[11131]: info: Adding plugin cd_controller to MyMusic Plugins Feb 19 01:09:14 volumio volumio[11131]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 19 01:09:14 volumio volumio[11131]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 19 01:09:14 volumio volumio[11131]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 19 01:09:14 volumio volumio[11131]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 19 01:09:14 volumio go-librespot[11479]: time="2026-02-19T01:09:14+07:00" level=debug msg="obtained new client token: AAAiSptXSwaW7ihuv/blVLDVhMaIwP5V6OFNM/f774F0cuUve/8Cg69OSywS7pK80wkpgqDH9h2a9jgruCcNbcNbk34/p5kedQs1jWqrNBUMrOgn4PajLRN3y5G/084mAfQT3tZk8PhRHnqMj1SV4QkvjDWVFhd+WS4QzY9Jmzt7IU8dtJgbtwbeRuljQFih9QQdfBTNxzKLUizeAmhxwsV/EHss+oYsGWpPiPF0dnU4/1iTeQhqu3yHKA==" Feb 19 01:09:14 volumio go-librespot[11479]: time="2026-02-19T01:09:14+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 19 01:09:14 volumio go-librespot[11479]: time="2026-02-19T01:09:14+07:00" level=debug msg="completed keyexchange" Feb 19 01:09:14 volumio go-librespot[11479]: time="2026-02-19T01:09:14+07:00" level=debug msg="completed challenge" Feb 19 01:09:14 volumio go-librespot[11479]: time="2026-02-19T01:09:14+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 19 01:09:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 19 01:09:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 19 01:09:16 volumio volumio[11131]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 19 01:09:16 volumio volumio[11131]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 19 01:09:16 volumio volumio[11131]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 01:09:16 volumio volumio[11131]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 01:09:16 volumio volumio[11131]: info: Starting MyVolumio Remote Streaming Endpoints Feb 19 01:09:16 volumio volumio[11131]: info: MyVolumio login type: Token Feb 19 01:09:16 volumio volumio[11131]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 19 01:09:16 volumio volumio[11131]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 19 01:09:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Feb 19 01:09:17 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 19 01:09:17 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 19 01:09:17 volumio go-librespot[11501]: go-librespot daemon starting... Feb 19 01:09:17 volumio go-librespot[11502]: time="2026-02-19T01:09:17+07:00" level=info msg="running go-librespot 0.4.0" Feb 19 01:09:17 volumio go-librespot[11502]: time="2026-02-19T01:09:17+07:00" level=debug msg="app state loaded" Feb 19 01:09:17 volumio go-librespot[11502]: time="2026-02-19T01:09:17+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 19 01:09:17 volumio volumio[11131]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 19 01:09:17 volumio volumio[11131]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 19 01:09:17 volumio volumio[11131]: info: Streaming services startup Feb 19 01:09:17 volumio volumio[11131]: info: Starting Streaming Daemon Feb 19 01:09:18 volumio sudo[11510]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 19 01:09:18 volumio volumio[11131]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 19 01:09:18 volumio sudo[11510]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 19 01:09:18 volumio sudo[11510]: pam_unix(sudo:session): session closed for user root Feb 19 01:09:18 volumio volumio[11131]: info: Getting Spotify volume Feb 19 01:09:18 volumio volumio[11131]: info: Connection to go-librespot Websocket closed Feb 19 01:09:18 volumio volumio[11131]: error: Cannot start Volumio Streaming Daemon Feb 19 01:09:18 volumio volumio[11131]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 19 01:09:18 volumio volumio[11131]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 19 01:09:18 volumio volumio[11131]: 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 19 01:09:18 volumio go-librespot[11502]: time="2026-02-19T01:09:18+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 19 01:09:18 volumio go-librespot[11502]: time="2026-02-19T01:09:18+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 19 01:09:18 volumio go-librespot[11502]: time="2026-02-19T01:09:18+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 19 01:09:18 volumio volumio[11131]: info: CoreCommandRouter::volumioGetState Feb 19 01:09:18 volumio volumio[11131]: info: CorePlayQueue::getTrack 0 Feb 19 01:09:18 volumio go-librespot[11502]: time="2026-02-19T01:09:18+07:00" level=info msg="zeroconf server listening on port 40911" Feb 19 01:09:18 volumio volumio[11131]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 19 01:09:18 volumio volumio[11131]: SPOTIFY: SPOTIFY VOLUME undefined Feb 19 01:09:18 volumio volumio[11131]: SPOTIFY: VOLUMIO VOLUME 100 Feb 19 01:09:18 volumio volumio[11131]: info: Aligning Spotify Volume to Volumio Volume Feb 19 01:09:18 volumio volumio[11131]: info: CoreCommandRouter::volumioGetState Feb 19 01:09:18 volumio volumio[11131]: info: CorePlayQueue::getTrack 0 Feb 19 01:09:18 volumio volumio[11131]: info: Setting Spotify Volume from Volumio: 100 Feb 19 01:09:18 volumio go-librespot[11502]: time="2026-02-19T01:09:18+07:00" level=debug msg="obtained new client token: AACe7vxoTq6eA2VdP8251IFfega4Icn0ul8yirf1o8uab/C2OUN+CGiV9s/nRaA+In+5q1oqne+su5RedWV1077BZFXczY7wISzsVt0WzpOZgHgSPpmwS2MhOZ89yfZM6ej3FhjA0ZgPtWMmbAktVdQPbfwLFoGpaWt2XC/dUF9585VQ4QA6p7X/zKw40q7OJUqgqzV3UnndloIRHDbnGlV/TtIQr4/3r5MCYdOvJluJq2+CFJCBbeTGAQ==" Feb 19 01:09:18 volumio go-librespot[11502]: time="2026-02-19T01:09:18+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 19 01:09:19 volumio go-librespot[11502]: time="2026-02-19T01:09:19+07:00" level=debug msg="completed keyexchange" Feb 19 01:09:19 volumio go-librespot[11502]: time="2026-02-19T01:09:19+07:00" level=debug msg="completed challenge" Feb 19 01:09:19 volumio go-librespot[11502]: time="2026-02-19T01:09:19+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 19 01:09:19 volumio volumio[11131]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 19 01:09:19 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 19 01:09:19 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 19 01:09:19 volumio volumio[11131]: Error: socket hang up Feb 19 01:09:19 volumio volumio[11131]: at connResetException (node:internal/errors:720:14) Feb 19 01:09:19 volumio volumio[11131]: at Socket.socketOnEnd (node:_http_client:519:23) Feb 19 01:09:19 volumio volumio[11131]: at Socket.emit (node:events:526:35) Feb 19 01:09:19 volumio volumio[11131]: at endReadableNT (node:internal/streams/readable:1376:12) Feb 19 01:09:19 volumio volumio[11131]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Feb 19 01:09:19 volumio volumio[11131]: code: 'ECONNRESET', Feb 19 01:09:19 volumio volumio[11131]: response: undefined Feb 19 01:09:19 volumio volumio[11131]: } Feb 19 01:09:19 volumio volumio[11131]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 19 01:09:20 volumio sudo[11530]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-19 01:08' Feb 19 01:09:20 volumio sudo[11530]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 19 01:09:20 volumio sudo[11530]: pam_unix(sudo:session): session closed for user root Feb 19 01:09:20 volumio volumio-remote-updater[25144]: [2026-02-19 01:09:20] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 19 01:09:20 volumio volumio-remote-updater[25144]: [2026-02-19 01:09:20] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 19 01:09:20 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 19 01:09:20 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 19 01:09:20 volumio systemd[1]: volumio.service: Consumed 55.378s CPU time. Feb 19 01:09:20 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 19 01:09:21 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 22676. Feb 19 01:09:21 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 19 01:09:21 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 19 01:09:21 volumio systemd[1]: volumio.service: Consumed 55.378s CPU time. Feb 19 01:09:21 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Feb 19 01:09:22 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Feb 19 01:09:22 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 19 01:09:22 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 19 01:09:22 volumio go-librespot[11557]: go-librespot daemon starting... Feb 19 01:09:22 volumio go-librespot[11558]: time="2026-02-19T01:09:22+07:00" level=info msg="running go-librespot 0.4.0" Feb 19 01:09:22 volumio go-librespot[11558]: time="2026-02-19T01:09:22+07:00" level=debug msg="app state loaded" Feb 19 01:09:22 volumio go-librespot[11558]: time="2026-02-19T01:09:22+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 19 01:09:22 volumio go-librespot[11558]: time="2026-02-19T01:09:22+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 19 01:09:22 volumio go-librespot[11558]: time="2026-02-19T01:09:22+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 19 01:09:22 volumio go-librespot[11558]: time="2026-02-19T01:09:22+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 19 01:09:22 volumio go-librespot[11558]: time="2026-02-19T01:09:22+07:00" level=info msg="zeroconf server listening on port 39427" Feb 19 01:09:23 volumio go-librespot[11558]: time="2026-02-19T01:09:23+07:00" level=debug msg="obtained new client token: AAA9us+xKxvxcChCTwDFn5CBxnsIf3wlDzf1pZ6VxKEAQFXCLblziWCXljFHdYcJICZl3u1+jeAgqtNJ/wiKBrV8/dCKFmVJHtLam3ks72Tn0DPey/jk8PYYJRB5Z/uG/DggP4M0WvtKC9Td9caGRu3StiCBSQpi2QgMCXlnmfkGBjJb+7NK+nUx1yHegDj4SWP+Si2Qgug2pvrpgpv6qEKSVALeZ/Zw5Z+TGSgjqIs94oe7lYgSB9k=" Feb 19 01:09:23 volumio go-librespot[11558]: time="2026-02-19T01:09:23+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 19 01:09:23 volumio go-librespot[11558]: time="2026-02-19T01:09:23+07:00" level=debug msg="connected to ap-gae2.spotify.com:443" Feb 19 01:09:23 volumio go-librespot[11558]: time="2026-02-19T01:09:23+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 19 01:09:23 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 19 01:09:23 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 19 01:09:24 volumio volumio[11547]: info: ------------------------------------------- Feb 19 01:09:24 volumio volumio[11547]: info: ----- Volumio3 ---- Feb 19 01:09:24 volumio volumio[11547]: info: ------------------------------------------- Feb 19 01:09:24 volumio volumio[11547]: info: ----- System startup ---- Feb 19 01:09:24 volumio volumio[11547]: info: ------------------------------------------- Feb 19 01:09:25 volumio volumio-remote-updater[25144]: [2026-02-19 01:09:25] [connect] Successful connection Feb 19 01:09:26 volumio volumio[11547]: info: MYVOLUMIO Environment detected Feb 19 01:09:26 volumio volumio[11547]: info: Plugin folders cleanup Feb 19 01:09:26 volumio volumio[11547]: info: Scanning into folder /volumio/app/plugins/ Feb 19 01:09:26 volumio volumio[11547]: info: Scanning category audio_interface Feb 19 01:09:26 volumio volumio[11547]: info: Scanning category miscellanea Feb 19 01:09:26 volumio volumio[11547]: info: Scanning category music_service Feb 19 01:09:26 volumio volumio[11547]: info: Scanning category plugins.json Feb 19 01:09:26 volumio volumio[11547]: info: Scanning category system_controller Feb 19 01:09:26 volumio volumio[11547]: info: Scanning category user_interface Feb 19 01:09:26 volumio volumio[11547]: info: Scanning into folder /data/plugins/ Feb 19 01:09:26 volumio volumio[11547]: info: Scanning category music_service Feb 19 01:09:26 volumio volumio[11547]: info: Plugin folders cleanup completed Feb 19 01:09:26 volumio volumio[11547]: info: ------------------------------------------- Feb 19 01:09:26 volumio volumio[11547]: info: ----- Core plugins startup ---- Feb 19 01:09:26 volumio volumio[11547]: info: ------------------------------------------- Feb 19 01:09:26 volumio volumio[11547]: info: Loading plugins from folder /volumio/app/plugins/ Feb 19 01:09:26 volumio volumio[11547]: info: Adding plugin upnp to MyMusic Plugins Feb 19 01:09:26 volumio volumio[11547]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 19 01:09:26 volumio volumio[11547]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 19 01:09:26 volumio volumio[11547]: info: Loading plugins from folder /data/plugins/ Feb 19 01:09:26 volumio volumio[11547]: info: Loading plugin "system"... Feb 19 01:09:26 volumio volumio[11547]: info: Loading plugin "appearance"... Feb 19 01:09:26 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Feb 19 01:09:26 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 19 01:09:26 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 19 01:09:26 volumio go-librespot[11578]: go-librespot daemon starting... Feb 19 01:09:26 volumio go-librespot[11579]: time="2026-02-19T01:09:26+07:00" level=info msg="running go-librespot 0.4.0" Feb 19 01:09:26 volumio go-librespot[11579]: time="2026-02-19T01:09:26+07:00" level=debug msg="app state loaded" Feb 19 01:09:26 volumio go-librespot[11579]: time="2026-02-19T01:09:26+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 19 01:09:27 volumio go-librespot[11579]: time="2026-02-19T01:09:27+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 19 01:09:27 volumio go-librespot[11579]: time="2026-02-19T01:09:27+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 19 01:09:27 volumio go-librespot[11579]: time="2026-02-19T01:09:27+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 19 01:09:27 volumio go-librespot[11579]: time="2026-02-19T01:09:27+07:00" level=info msg="zeroconf server listening on port 42481" Feb 19 01:09:27 volumio go-librespot[11579]: time="2026-02-19T01:09:27+07:00" level=debug msg="obtained new client token: AAAzUcB0KErn3/uM3Qqz0exH87EPvDiKLB+4P9Q7cL1RjRNw/x1vhc1eU3xijXMNnP5aI7pl83vyJty3x9U937WuheTCxHpH0ssy+bdd9dXUI2TBnSWwRqox/NtPf3UoBOxyJYMUF0/4s0FVckNuerbzOx8dS13Gy+tYJtg2nVumBmD7OI+un54MebO8PuoswgsSIpd6HeuyWdNxMmT8/5lMOhgSJX9PZXbpgw71WCjBwaEYT87R/Fk7Ow==" Feb 19 01:09:27 volumio go-librespot[11579]: time="2026-02-19T01:09:27+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 19 01:09:27 volumio go-librespot[11579]: time="2026-02-19T01:09:27+07:00" level=debug msg="connected to ap-gae2.spotify.com:443" Feb 19 01:09:27 volumio go-librespot[11579]: time="2026-02-19T01:09:27+07:00" level=debug msg="completed keyexchange" Feb 19 01:09:27 volumio go-librespot[11579]: time="2026-02-19T01:09:27+07:00" level=debug msg="completed challenge" Feb 19 01:09:27 volumio go-librespot[11579]: time="2026-02-19T01:09:27+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 19 01:09:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 19 01:09:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 19 01:09:28 volumio volumio[11547]: info: Loading plugin "network"... Feb 19 01:09:28 volumio volumio[11547]: info: Refreshing Cached IP Addresses Feb 19 01:09:28 volumio sudo[11587]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 19 01:09:28 volumio sudo[11587]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 19 01:09:28 volumio sudo[11589]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 19 01:09:28 volumio sudo[11589]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 19 01:09:28 volumio sudo[11587]: pam_unix(sudo:session): session closed for user root Feb 19 01:09:28 volumio volumio[11547]: info: Loading plugin "services"... Feb 19 01:09:28 volumio sudo[11589]: pam_unix(sudo:session): session closed for user root Feb 19 01:09:28 volumio volumio[11547]: info: Loading plugin "alsa_controller"... Feb 19 01:09:28 volumio sudo[11597]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 19 01:09:28 volumio sudo[11597]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 19 01:09:28 volumio volumio[11547]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 19 01:09:28 volumio volumio[11547]: info: Loading plugin "wizard"... Feb 19 01:09:28 volumio volumio[11547]: info: Loading plugin "networkfs"... Feb 19 01:09:28 volumio volumio[11547]: info: Starting Udev Watcher for removable devices Feb 19 01:09:28 volumio volumio[11547]: info: Ignoring mount for partition: boot Feb 19 01:09:28 volumio volumio[11547]: info: Ignoring mount for partition: volumio Feb 19 01:09:28 volumio volumio[11547]: info: Ignoring mount for partition: volumio_data Feb 19 01:09:28 volumio volumio[11547]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 19 01:09:28 volumio volumio[11547]: info: Loading plugin "volumio_command_line_client"... Feb 19 01:09:28 volumio volumio[11547]: info: Loading plugin "upnp"... Feb 19 01:09:28 volumio volumio[11547]: info: [1771438168672] Starting Upmpd Daemon Feb 19 01:09:28 volumio volumio[11547]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 19 01:09:28 volumio volumio[11547]: info: Loading plugin "my_music"... Feb 19 01:09:28 volumio volumio[11547]: info: Loading plugin "mpd"... Feb 19 01:09:29 volumio volumio[11547]: info: Loading plugin "upnp_browser"... Feb 19 01:09:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Feb 19 01:09:31 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 19 01:09:31 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 19 01:09:31 volumio go-librespot[11621]: go-librespot daemon starting... Feb 19 01:09:31 volumio go-librespot[11622]: time="2026-02-19T01:09:31+07:00" level=info msg="running go-librespot 0.4.0" Feb 19 01:09:31 volumio go-librespot[11622]: time="2026-02-19T01:09:31+07:00" level=debug msg="app state loaded" Feb 19 01:09:31 volumio go-librespot[11622]: time="2026-02-19T01:09:31+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 19 01:09:31 volumio sudo[11597]: pam_unix(sudo:session): session closed for user root Feb 19 01:09:31 volumio go-librespot[11622]: time="2026-02-19T01:09:31+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 19 01:09:31 volumio go-librespot[11622]: time="2026-02-19T01:09:31+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 19 01:09:31 volumio go-librespot[11622]: time="2026-02-19T01:09:31+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 19 01:09:31 volumio go-librespot[11622]: time="2026-02-19T01:09:31+07:00" level=info msg="zeroconf server listening on port 40197" Feb 19 01:09:31 volumio go-librespot[11622]: time="2026-02-19T01:09:31+07:00" level=debug msg="obtained new client token: AACuQKspgioZ0Tw6Xz6q4jOQlIAtiJzlQ1uzQDhiZL91dr3mpQpiF6UQjv3ak3tBBuJe8VHfh2ZpXCUUmObE5lH/bDz5HHNwb9D2gi+/R0h0R9jBp5uanb20ytk12M48DLQInPkztJwHkFv9nNjzqpNyxw+WwAfrhk0cczLVEL9Qb5wPZTq7LIrzIzuCwQhR/3VlfxM2yOjUjUBUUG0ld70p03e25b1Csz6vuD/o1o15anccmHpnOZNYow==" Feb 19 01:09:32 volumio go-librespot[11622]: time="2026-02-19T01:09:32+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 19 01:09:32 volumio go-librespot[11622]: time="2026-02-19T01:09:32+07:00" level=debug msg="completed keyexchange" Feb 19 01:09:32 volumio go-librespot[11622]: time="2026-02-19T01:09:32+07:00" level=debug msg="completed challenge" Feb 19 01:09:32 volumio volumio[11547]: info: Starting UPNP Browser Feb 19 01:09:32 volumio go-librespot[11622]: time="2026-02-19T01:09:32+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 19 01:09:32 volumio volumio[11547]: info: Loading plugin "alarm-clock"... Feb 19 01:09:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 19 01:09:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 19 01:09:32 volumio volumio[11547]: info: Loading plugin "airplay_emulation"... Feb 19 01:09:32 volumio volumio[11547]: info: Starting Shairport Sync Feb 19 01:09:32 volumio volumio[11547]: info: Loading plugin "last_100"... Feb 19 01:09:32 volumio volumio[11547]: info: Loading plugin "webradio"... Feb 19 01:09:32 volumio volumio[11547]: info: Loading plugin "i2s_dacs"... Feb 19 01:09:32 volumio volumio[11547]: info: Loading plugin "volumiodiscovery"... Feb 19 01:09:32 volumio volumio[11547]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 19 01:09:32 volumio volumio[11547]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 19 01:09:32 volumio node[11547]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 19 01:09:32 volumio volumio[11547]: *** WARNING *** For more information see Feb 19 01:09:32 volumio volumio[11547]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 19 01:09:32 volumio volumio[11547]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 19 01:09:32 volumio volumio[11547]: *** WARNING *** For more information see Feb 19 01:09:32 volumio node[11547]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 19 01:09:32 volumio node[11547]: *** WARNING *** For more information see Feb 19 01:09:32 volumio node[11547]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 19 01:09:32 volumio node[11547]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 19 01:09:32 volumio node[11547]: *** WARNING *** For more information see Feb 19 01:09:32 volumio volumio[11547]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 19 01:09:32 volumio volumio[11547]: info: Discovery: Started advertising with name: Volumio Feb 19 01:09:33 volumio volumio[11547]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 19 01:09:33 volumio volumio[11547]: info: Loading plugin "spop"... Feb 19 01:09:34 volumio volumio[11547]: info: Loading plugin "ytcr"... Feb 19 01:09:35 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Feb 19 01:09:35 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 19 01:09:35 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 19 01:09:35 volumio go-librespot[11632]: go-librespot daemon starting... Feb 19 01:09:35 volumio go-librespot[11633]: time="2026-02-19T01:09:35+07:00" level=info msg="running go-librespot 0.4.0" Feb 19 01:09:35 volumio go-librespot[11633]: time="2026-02-19T01:09:35+07:00" level=debug msg="app state loaded" Feb 19 01:09:35 volumio go-librespot[11633]: time="2026-02-19T01:09:35+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 19 01:09:36 volumio go-librespot[11633]: time="2026-02-19T01:09:36+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 19 01:09:36 volumio go-librespot[11633]: time="2026-02-19T01:09:36+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 19 01:09:36 volumio go-librespot[11633]: time="2026-02-19T01:09:36+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 19 01:09:36 volumio go-librespot[11633]: time="2026-02-19T01:09:36+07:00" level=info msg="zeroconf server listening on port 35693" Feb 19 01:09:36 volumio go-librespot[11633]: time="2026-02-19T01:09:36+07:00" level=debug msg="obtained new client token: AABkcebAfbvTjk5UmqmTU5sTTVVaeDQovwuS1bwpX3Jcso0Rq1aQPnffiSjqvobNF4FyuJNE4rsQlT4spvKjNqrey5lbeObobJjIcpmC8fhKzdui6aOTkLRn6Kiy6pJIdITsoCETtz91+bPzt28gSOXEWiKVo+jFysBy4mLaAn9RdozPGxCx/8qRp+nxa3N2cD9J3Vbquk56w+jRLdS3pDxmlTNo7GVfbXXpRfn8EcNjDbvprC+3YTSZjw==" Feb 19 01:09:36 volumio go-librespot[11633]: time="2026-02-19T01:09:36+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 19 01:09:36 volumio go-librespot[11633]: time="2026-02-19T01:09:36+07:00" level=debug msg="connected to ap-gae2.spotify.com:443" Feb 19 01:09:36 volumio go-librespot[11633]: time="2026-02-19T01:09:36+07:00" level=debug msg="completed keyexchange" Feb 19 01:09:36 volumio go-librespot[11633]: time="2026-02-19T01:09:36+07:00" level=debug msg="completed challenge" Feb 19 01:09:36 volumio go-librespot[11633]: time="2026-02-19T01:09:36+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 19 01:09:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 19 01:09:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 19 01:09:37 volumio volumio[11547]: info: Loading plugin "ytmusic"... Feb 19 01:09:38 volumio volumio-remote-updater[25144]: [2026-02-19 01:09:38] [connect] Successful connection Feb 19 01:09:38 volumio volumio[11547]: info: Loading plugin "outputs"... Feb 19 01:09:38 volumio volumio[11547]: info: Loading plugin "albumart"... Feb 19 01:09:38 volumio volumio[11547]: info: Plugin example_plugin is not enabled Feb 19 01:09:38 volumio volumio[11547]: info: Loading plugin "inputs"... Feb 19 01:09:38 volumio volumio[11547]: info: Loading plugin "updater_comm"... Feb 19 01:09:38 volumio volumio[11547]: info: Plugin mpdemulation is not enabled Feb 19 01:09:38 volumio volumio[11547]: info: Loading plugin "rest_api"... Feb 19 01:09:38 volumio volumio[11547]: info: Loading plugin "websocket"... Feb 19 01:09:38 volumio volumio[11547]: info: Starting Socket.io Server version 1.7.4 Feb 19 01:09:38 volumio volumio[11547]: info: Loading plugin "RoonBridge"... Feb 19 01:09:39 volumio volumio[11547]: info: Applying required configuration parameters for plugin RoonBridge Feb 19 01:09:39 volumio volumio[11547]: info: Loading i18n strings for locale en Feb 19 01:09:39 volumio volumio[11547]: Updating browse sources language Feb 19 01:09:39 volumio volumio[11547]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 19 01:09:39 volumio volumio[11547]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 19 01:09:39 volumio volumio[11547]: info: CoreCommandRouter::initPlayerControls Feb 19 01:09:39 volumio volumio[11547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 01:09:39 volumio volumio[11547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 01:09:39 volumio volumio[11547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 01:09:39 volumio volumio[11547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 01:09:39 volumio volumio[11547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 01:09:39 volumio volumio[11547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 01:09:39 volumio volumio[11547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 01:09:39 volumio volumio[11547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 01:09:39 volumio volumio[11547]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 19 01:09:39 volumio volumio[11547]: Express server listening on port 3000 Feb 19 01:09:39 volumio volumio[11547]: [Metrics] WebUI: 16s 25.42ms Feb 19 01:09:39 volumio volumio[11654]: Forking 3 albumart workers Feb 19 01:09:39 volumio volumio[11547]: info: CoreStateMachine::resetVolumioState Feb 19 01:09:39 volumio volumio[11547]: info: CoreStateMachine::getcurrentVolume Feb 19 01:09:39 volumio volumio[11547]: info: CoreCommandRouter::volumioRetrievevolume Feb 19 01:09:39 volumio sudo[11679]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 19 01:09:39 volumio sudo[11679]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 19 01:09:39 volumio sudo[11679]: pam_unix(sudo:session): session closed for user root Feb 19 01:09:40 volumio sudo[11682]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 19 01:09:40 volumio sudo[11682]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 19 01:09:40 volumio sudo[11682]: pam_unix(sudo:session): session closed for user root Feb 19 01:09:40 volumio volumio[11547]: info: Volumio Network Manager: Network status updated: 1 Feb 19 01:09:40 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Feb 19 01:09:40 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 19 01:09:40 volumio go-librespot[11701]: go-librespot daemon starting... Feb 19 01:09:40 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 19 01:09:40 volumio go-librespot[11705]: time="2026-02-19T01:09:40+07:00" level=info msg="running go-librespot 0.4.0" Feb 19 01:09:40 volumio go-librespot[11705]: time="2026-02-19T01:09:40+07:00" level=debug msg="app state loaded" Feb 19 01:09:40 volumio go-librespot[11705]: time="2026-02-19T01:09:40+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 19 01:09:40 volumio volumio[11547]: info: VolumeController:: Volume=100 Mute =false Feb 19 01:09:40 volumio volumio[11547]: info: CoreStateMachine::pushState Feb 19 01:09:40 volumio volumio[11547]: info: CorePlayQueue::getTrack 0 Feb 19 01:09:40 volumio volumio[11547]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 19 01:09:40 volumio volumio[11547]: info: CoreCommandRouter::volumioPushState Feb 19 01:09:40 volumio volumio[11547]: info: CoreStateMachine::updateTrackBlock Feb 19 01:09:40 volumio volumio[11547]: info: CorePlayQueue::getTrackBlock Feb 19 01:09:40 volumio volumio[11547]: info: CoreCommandRouter::volumioRetrievevolume Feb 19 01:09:40 volumio volumio-remote-updater[25144]: [2026-02-19 01:09:40] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1771438178 101 Feb 19 01:09:40 volumio volumio[11547]: 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 19 01:09:40 volumio volumio[11547]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 01:09:40 volumio volumio[11547]: info: Reloading queue from file Feb 19 01:09:40 volumio volumio[11547]: info: CoreStateMachine::setRepeat null single undefined Feb 19 01:09:40 volumio volumio[11547]: info: CoreStateMachine::pushState Feb 19 01:09:40 volumio volumio[11547]: info: CorePlayQueue::getTrack 0 Feb 19 01:09:40 volumio volumio[11547]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 19 01:09:40 volumio volumio[11547]: info: CoreCommandRouter::volumioPushState Feb 19 01:09:40 volumio volumio[11547]: info: CoreStateMachine::setRandom null Feb 19 01:09:40 volumio volumio[11547]: info: CoreStateMachine::pushState Feb 19 01:09:40 volumio volumio[11547]: info: CorePlayQueue::getTrack 0 Feb 19 01:09:40 volumio volumio[11547]: info: CoreCommandRouter::volumioPushState Feb 19 01:09:40 volumio volumio[11547]: info: Setting Device type: Raspberry PI Feb 19 01:09:40 volumio volumio[11547]: info: Completed loading Core Plugins Feb 19 01:09:40 volumio volumio[11547]: info: Preparing to generate the ALSA configuration file Feb 19 01:09:40 volumio go-librespot[11705]: time="2026-02-19T01:09:40+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 19 01:09:40 volumio go-librespot[11705]: time="2026-02-19T01:09:40+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 19 01:09:40 volumio go-librespot[11705]: time="2026-02-19T01:09:40+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 19 01:09:40 volumio go-librespot[11705]: time="2026-02-19T01:09:40+07:00" level=info msg="zeroconf server listening on port 46083" Feb 19 01:09:40 volumio volumio[11547]: info: VolumeController:: Volume=100 Mute =false Feb 19 01:09:40 volumio volumio[11547]: info: CoreStateMachine::pushState Feb 19 01:09:40 volumio volumio[11547]: info: CorePlayQueue::getTrack 0 Feb 19 01:09:40 volumio volumio[11547]: info: CoreCommandRouter::volumioPushState Feb 19 01:09:41 volumio volumio[11547]: info: Asound.conf file unchanged, so no further update is needed Feb 19 01:09:41 volumio volumio[11547]: info: Output device has changed, restarting MPD Feb 19 01:09:41 volumio sudo[11723]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 19 01:09:41 volumio sudo[11723]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 19 01:09:41 volumio volumio[11547]: info: Output device has changed, restarting Shairport Sync Feb 19 01:09:41 volumio volumio[11547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 01:09:41 volumio volumio[11547]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 01:09:41 volumio sudo[11723]: pam_unix(sudo:session): session closed for user root Feb 19 01:09:41 volumio sudo[11728]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 19 01:09:41 volumio sudo[11728]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 19 01:09:41 volumio go-librespot[11705]: time="2026-02-19T01:09:41+07:00" level=debug msg="obtained new client token: AADN1NqAiUIU+KajKa/mccyUnqOMQDbajkzPyuGEhqYTve2iE5uNu1pl1KlYd572XnxVbTCB08dfpZ4nKMYeNY7j5WGmRwq8Gub4n8gf4aDFTlf9Uto8oTmTrYUdmZlytJHO9Ue1RPB27cQU7aKopjBYC2zjN6C12JHp5xLl3WmD8Kv81DXycBErG0ro/FNFYrPA5VJJWecrowomLtVDfbK1qGMLKtG8A7J3SpuW+ga78dqtz9qbG+k=" Feb 19 01:09:41 volumio sudo[11726]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 19 01:09:41 volumio sudo[11726]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 19 01:09:41 volumio sudo[11726]: pam_unix(sudo:session): session closed for user root Feb 19 01:09:41 volumio volumio[11547]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 19 01:09:41 volumio volumio[11547]: info: ___________ START PLUGINS ___________ Feb 19 01:09:41 volumio volumio[11547]: info: ControllerMpd::onStart: Initializing MPD Feb 19 01:09:41 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 19 01:09:41 volumio go-librespot[11705]: time="2026-02-19T01:09:41+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 19 01:09:41 volumio volumio[11547]: info: Creating MPD Configuration file Feb 19 01:09:41 volumio go-librespot[11705]: time="2026-02-19T01:09:41+07:00" level=debug msg="completed keyexchange" Feb 19 01:09:41 volumio go-librespot[11705]: time="2026-02-19T01:09:41+07:00" level=debug msg="completed challenge" Feb 19 01:09:41 volumio volumio[11547]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 19 01:09:41 volumio volumio[11547]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 19 01:09:41 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 19 01:09:41 volumio volumio[11547]: info: [1771438181427] CoreMusicLibrary::Adding element Media Servers Feb 19 01:09:41 volumio volumio[11547]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 19 01:09:41 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 19 01:09:41 volumio systemd[1]: mpd.service: Consumed 7.081s CPU time. Feb 19 01:09:41 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 19 01:09:41 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 19 01:09:41 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 19 01:09:41 volumio sudo[11738]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 19 01:09:41 volumio sudo[11738]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 19 01:09:41 volumio go-librespot[11705]: time="2026-02-19T01:09: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 19 01:09:41 volumio volumio[11547]: info: UPNP Browser: Client initialized successfully Feb 19 01:09:41 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 19 01:09:41 volumio sudo[11736]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 19 01:09:41 volumio sudo[11736]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 19 01:09:41 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 19 01:09:41 volumio sudo[11736]: pam_unix(sudo:session): session closed for user root Feb 19 01:09:41 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 19 01:09:41 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 19 01:09:41 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 19 01:09:41 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 19 01:09:41 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 19 01:09:41 volumio volumio[11547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 01:09:41 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 19 01:09:41 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 19 01:09:41 volumio volumio[11547]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 01:09:41 volumio volumio[11547]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 19 01:09:41 volumio volumio[11547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 01:09:41 volumio volumio[11547]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 01:09:41 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 19 01:09:41 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 19 01:09:41 volumio volumio[11547]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 19 01:09:41 volumio volumio[11547]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 19 01:09:41 volumio volumio[11547]: info: [1771438181868] CoreMusicLibrary::Adding element Last_100 Feb 19 01:09:41 volumio volumio[11547]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 19 01:09:41 volumio volumio[11547]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 19 01:09:41 volumio volumio[11547]: info: [1771438181880] CoreMusicLibrary::Adding element Webradio Feb 19 01:09:41 volumio volumio[11547]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 19 01:09:41 volumio volumio[11547]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 19 01:09:41 volumio volumio[11547]: info: Initializing BBC Radios Feb 19 01:09:42 volumio volumio[11547]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 19 01:09:42 volumio volumio[11547]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 01:09:42 volumio volumio[11547]: info: Creating Spotify config file Feb 19 01:09:42 volumio volumio[11547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 01:09:42 volumio sudo[11750]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 19 01:09:42 volumio sudo[11750]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 19 01:09:42 volumio sudo[11750]: pam_unix(sudo:session): session closed for user root Feb 19 01:09:43 volumio volumio[11669]: Starting albumart workers Feb 19 01:09:43 volumio volumio[11670]: Starting albumart workers Feb 19 01:09:43 volumio volumio[11667]: Starting albumart workers Feb 19 01:09:43 volumio volumio[11547]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 19 01:09:43 volumio volumio[11547]: info: [1771438183472] CoreMusicLibrary::Adding element YouTube Music Feb 19 01:09:43 volumio volumio[11547]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 19 01:09:43 volumio volumio[11547]: Cannot find translation for source YouTube Music Feb 19 01:09:43 volumio volumio[11547]: info: Volumio Calling Home Feb 19 01:09:43 volumio sudo[11769]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Feb 19 01:09:43 volumio sudo[11769]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 19 01:09:43 volumio sudo[11769]: pam_unix(sudo:session): session closed for user root Feb 19 01:09:44 volumio volumio[11547]: info: Discovery: adding 921648ed-0557-4d70-9927-1420682efcc7 Feb 19 01:09:44 volumio volumio[11547]: info: Discovery: Found device Volumio Feb 19 01:09:44 volumio volumio[11547]: info: CoreCommandRouter::volumioGetState Feb 19 01:09:44 volumio volumio[11547]: info: CorePlayQueue::getTrack 0 Feb 19 01:09:44 volumio volumio[11547]: info: MPD Permissions set Feb 19 01:09:44 volumio volumio[11547]: info: MPD Permissions set Feb 19 01:09:44 volumio volumio[11547]: info: Upmpdcli Daemon Started Feb 19 01:09:44 volumio volumio[11547]: info: Discovery: this is already registered, 921648ed-0557-4d70-9927-1420682efcc7 Feb 19 01:09:44 volumio volumio[11547]: info: Discovery: Found device Volumio Feb 19 01:09:44 volumio volumio[11547]: info: CoreCommandRouter::volumioGetState Feb 19 01:09:44 volumio volumio[11547]: info: CorePlayQueue::getTrack 0 Feb 19 01:09:44 volumio volumio[11547]: info: Volumio called home Feb 19 01:09:44 volumio volumio[11547]: info: Spotify config file written Feb 19 01:09:44 volumio volumio[11547]: 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 19 01:09:44 volumio volumio[11547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 01:09:44 volumio volumio[11547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 01:09:44 volumio volumio[11547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 01:09:44 volumio volumio[11547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 01:09:44 volumio volumio[11547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 01:09:44 volumio volumio[11547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 01:09:44 volumio volumio[11547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 01:09:44 volumio volumio[11547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 01:09:44 volumio sudo[11775]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 19 01:09:44 volumio sudo[11775]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 19 01:09:44 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Feb 19 01:09:44 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 19 01:09:44 volumio volumio[11547]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 01:09:44 volumio volumio[11547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 01:09:44 volumio volumio[11547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 01:09:44 volumio volumio[11547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 01:09:44 volumio volumio[11547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 01:09:44 volumio volumio[11547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 01:09:44 volumio volumio[11547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 01:09:44 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 19 01:09:44 volumio go-librespot[11788]: go-librespot daemon starting... Feb 19 01:09:44 volumio volumio[11547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 01:09:44 volumio volumio[11547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 01:09:44 volumio volumio[11547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 01:09:44 volumio go-librespot[11789]: time="2026-02-19T01:09:44+07:00" level=info msg="running go-librespot 0.4.0" Feb 19 01:09:44 volumio go-librespot[11789]: time="2026-02-19T01:09:44+07:00" level=debug msg="app state loaded" Feb 19 01:09:44 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Feb 19 01:09:44 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Feb 19 01:09:44 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 19 01:09:45 volumio volumio[11547]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 01:09:45 volumio volumio[11547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 01:09:45 volumio volumio[11547]: info: No need to fix Spotify hosts Feb 19 01:09:45 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 19 01:09:45 volumio go-librespot[11800]: go-librespot daemon starting... Feb 19 01:09:45 volumio sudo[11775]: pam_unix(sudo:session): session closed for user root Feb 19 01:09:45 volumio go-librespot[11801]: time="2026-02-19T01:09:45+07:00" level=info msg="running go-librespot 0.4.0" Feb 19 01:09:45 volumio go-librespot[11801]: time="2026-02-19T01:09:45+07:00" level=debug msg="app state loaded" Feb 19 01:09:45 volumio go-librespot[11801]: time="2026-02-19T01:09:45+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 19 01:09:45 volumio go-librespot[11801]: time="2026-02-19T01:09: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-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 19 01:09:45 volumio go-librespot[11801]: time="2026-02-19T01:09:45+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 19 01:09:45 volumio go-librespot[11801]: time="2026-02-19T01:09:45+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 19 01:09:45 volumio go-librespot[11801]: time="2026-02-19T01:09:45+07:00" level=info msg="zeroconf server listening on port 34739" Feb 19 01:09:46 volumio volumio[11547]: info: Starting Shairport Sync Feb 19 01:09:46 volumio volumio[11547]: info: Starting Shairport Sync Feb 19 01:09:46 volumio volumio[11547]: info: Starting Shairport Sync Feb 19 01:09:46 volumio go-librespot[11801]: time="2026-02-19T01:09:46+07:00" level=debug msg="obtained new client token: AACCOF+jCZkBoyhowKUGBiYerG06tBAT29F9AAQrNnzpYxFBlczhq1KcEpm4b1ag7kbFpc0w+GKQC2ZlrplNxr0c5roAowaAQXO1qDdCEHt8Il+MkP/DQJ64/jkJ8XafbwOi8xKsrPtiyaw8kBivy2BT3plkBJCxpGCqZyYDxqMsu8U8l+eS2o0a5L3P4F25iuWWgMTupqBRHo6/UElDFDodtDLjRo6RmsqWXKRjux2HU7L3Zgr4Q1Y=" Feb 19 01:09:46 volumio sudo[11822]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 19 01:09:46 volumio sudo[11822]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 19 01:09:46 volumio sudo[11827]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 19 01:09:46 volumio sudo[11827]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 19 01:09:46 volumio go-librespot[11801]: time="2026-02-19T01:09:46+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 19 01:09:46 volumio sudo[11824]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 19 01:09:46 volumio sudo[11824]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 19 01:09:46 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 19 01:09:46 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 19 01:09:46 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 19 01:09:46 volumio systemd[1]: shairport-sync.service: Consumed 2.281s CPU time. Feb 19 01:09:46 volumio go-librespot[11801]: time="2026-02-19T01:09:46+07:00" level=debug msg="completed keyexchange" Feb 19 01:09:46 volumio go-librespot[11801]: time="2026-02-19T01:09:46+07:00" level=debug msg="completed challenge" Feb 19 01:09:46 volumio go-librespot[11801]: time="2026-02-19T01:09: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 19 01:09:46 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 19 01:09:46 volumio sudo[11822]: pam_unix(sudo:session): session closed for user root Feb 19 01:09:46 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 19 01:09:46 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 19 01:09:46 volumio sudo[11824]: pam_unix(sudo:session): session closed for user root Feb 19 01:09:46 volumio sudo[11827]: pam_unix(sudo:session): session closed for user root Feb 19 01:09:46 volumio volumio[11547]: info: Shairport-Sync Started Feb 19 01:09:46 volumio volumio[11547]: Error adding Membership: Error: addMembership EINVAL Feb 19 01:09:46 volumio volumio[11547]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 19 01:09:46 volumio volumio[11547]: SPOTIFY: BQCkUQYlmMsr9NMP3SxTKKEjh2q2W5AgCrNmqXRW510pMxDw4THIPA8tO06tSNrQ5bymfETruq04MTYmJMA7EosdE5Nkf-Ouji1YDpp7Li4TSSBjiOI7lMhAZw2TXLjBotlCSVIc5wN60D4fNfpAH4gCdy6SNxNlM0o1lQK5ZvzV728NYQOs7r7FvBVgyLfyKeO8ZAcTTZrD8fEkYZeaWRXDXgWOZY-ZDR0VJnyrRcKe0h4ztv3_ks8pnyqYMGxRxfRTnjztyLEM7ZIiOddAznO9d9KeoqCbEAKvqgCjKjuiprzeCc7n7Zgk Feb 19 01:09:46 volumio volumio[11547]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 19 01:09:46 volumio volumio[11547]: info: New Spotify access token = BQCkUQYlmMsr9NMP3SxTKKEjh2q2W5AgCrNmqXRW510pMxDw4THIPA8tO06tSNrQ5bymfETruq04MTYmJMA7EosdE5Nkf-Ouji1YDpp7Li4TSSBjiOI7lMhAZw2TXLjBotlCSVIc5wN60D4fNfpAH4gCdy6SNxNlM0o1lQK5ZvzV728NYQOs7r7FvBVgyLfyKeO8ZAcTTZrD8fEkYZeaWRXDXgWOZY-ZDR0VJnyrRcKe0h4ztv3_ks8pnyqYMGxRxfRTnjztyLEM7ZIiOddAznO9d9KeoqCbEAKvqgCjKjuiprzeCc7n7Zgk Feb 19 01:09:46 volumio volumio[11547]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 19 01:09:46 volumio volumio[11547]: info: CoreCommandRouter::volumioGetState Feb 19 01:09:46 volumio volumio[11547]: info: CorePlayQueue::getTrack 0 Feb 19 01:09:46 volumio volumio[11547]: info: Shairport-Sync Started Feb 19 01:09:46 volumio volumio[11547]: info: Shairport-Sync Started Feb 19 01:09:47 volumio volumio[11547]: 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 19 01:09:47 volumio volumio[11547]: info: Spotify Successfully logged in Feb 19 01:09:47 volumio volumio[11547]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 19 01:09:47 volumio volumio[11547]: info: [1771438187020] CoreMusicLibrary::Adding element Spotify Feb 19 01:09:47 volumio volumio[11547]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 19 01:09:47 volumio volumio[11547]: Cannot find translation for source YouTube Music Feb 19 01:09:47 volumio volumio[11547]: Cannot find translation for source Spotify Feb 19 01:09:47 volumio volumio[11547]: info: [yt-cast-receiver] DIAL server listening on port 8098 Feb 19 01:09:47 volumio volumio[11547]: info: CoreCommandRouter::volumioRetrievevolume Feb 19 01:09:47 volumio volumio[11547]: info: VolumeController:: Volume=100 Mute =false Feb 19 01:09:47 volumio volumio[11547]: info: CoreCommandRouter::volumioGetState Feb 19 01:09:47 volumio volumio[11547]: info: CorePlayQueue::getTrack 0 Feb 19 01:09:47 volumio volumio[11547]: info: CoreStateMachine::pushState Feb 19 01:09:47 volumio volumio[11547]: info: CorePlayQueue::getTrack 0 Feb 19 01:09:47 volumio volumio[11547]: info: CoreCommandRouter::volumioPushState Feb 19 01:09:49 volumio volumio[11547]: info: go-librespot daemon successfully initialized Feb 19 01:09:49 volumio mpd[11767]: 2026-02-19T01:09:49 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 19 01:09:49 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 19 01:09:49 volumio sudo[11728]: pam_unix(sudo:session): session closed for user root Feb 19 01:09:49 volumio sudo[11738]: pam_unix(sudo:session): session closed for user root Feb 19 01:09:49 volumio volumio[11547]: error: MPD error: The expression evaluated to a falsy value: Feb 19 01:09:49 volumio volumio[11547]: assert.ok(self.idling) Feb 19 01:09:49 volumio volumio[11547]: error: The expression evaluated to a falsy value: Feb 19 01:09:49 volumio volumio[11547]: assert.ok(self.idling) Feb 19 01:09:49 volumio volumio[11547]: error: updateQueue error: null Feb 19 01:09:49 volumio volumio[11547]: info: MPD running with PID11767 Feb 19 01:09:49 volumio volumio[11547]: ,establishing connection Feb 19 01:09:49 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Feb 19 01:09:49 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 19 01:09:49 volumio volumio[11547]: info: Completed starting Core Plugins Feb 19 01:09:49 volumio volumio[11547]: info: ------------------------------------------- Feb 19 01:09:49 volumio volumio[11547]: info: ----- MyVolumio plugins startup ---- Feb 19 01:09:49 volumio volumio[11547]: info: ------------------------------------------- Feb 19 01:09:49 volumio volumio[11547]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 19 01:09:49 volumio volumio[11547]: error: updateQueue error: null Feb 19 01:09:49 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 19 01:09:49 volumio go-librespot[11865]: go-librespot daemon starting... Feb 19 01:09:49 volumio go-librespot[11866]: time="2026-02-19T01:09:49+07:00" level=info msg="running go-librespot 0.4.0" Feb 19 01:09:49 volumio go-librespot[11866]: time="2026-02-19T01:09:49+07:00" level=debug msg="app state loaded" Feb 19 01:09:49 volumio go-librespot[11866]: time="2026-02-19T01:09:49+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 19 01:09:50 volumio go-librespot[11866]: time="2026-02-19T01:09:50+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 19 01:09:50 volumio go-librespot[11866]: time="2026-02-19T01:09:50+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 19 01:09:50 volumio go-librespot[11866]: time="2026-02-19T01:09:50+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 19 01:09:50 volumio go-librespot[11866]: time="2026-02-19T01:09:50+07:00" level=info msg="zeroconf server listening on port 45943" Feb 19 01:09:50 volumio go-librespot[11866]: time="2026-02-19T01:09:50+07:00" level=debug msg="obtained new client token: AAC8F1CGnAGqGvAtxNu0xj4ivv5B1wPSfHMhYsFfWo1xNCmMvvApxFO8+Rzpjbp9k24Mdc4LQzQl48rDy+5J++ZyOgL7Ku0J0ACRlWGIufMw0VbFIlniLhvgFLzqxFZ6zOwTDga4NTrRI3UNiJ8J5SaLlwjdaYrxQRahrdHEORBWkKRoFKjjv+kXgMgHH743tpsJNa5VlP7AByVpN9s2bIOV77jjbPa/leGFIoRek+o10n11GBDVUo5fKA==" Feb 19 01:09:50 volumio go-librespot[11866]: time="2026-02-19T01:09:50+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 19 01:09:50 volumio go-librespot[11866]: time="2026-02-19T01:09:50+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 19 01:09:50 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 19 01:09:50 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 19 01:09:52 volumio volumio[11547]: info: Initializing connection to go-librespot Websocket Feb 19 01:09:52 volumio volumio[11547]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 19 01:09:53 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Feb 19 01:09:53 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 19 01:09:53 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 19 01:09:53 volumio go-librespot[11874]: go-librespot daemon starting... Feb 19 01:09:53 volumio go-librespot[11875]: time="2026-02-19T01:09:53+07:00" level=info msg="running go-librespot 0.4.0" Feb 19 01:09:53 volumio go-librespot[11875]: time="2026-02-19T01:09:53+07:00" level=debug msg="app state loaded" Feb 19 01:09:53 volumio go-librespot[11875]: time="2026-02-19T01:09:53+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 19 01:09:54 volumio go-librespot[11875]: time="2026-02-19T01:09: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 19 01:09:54 volumio go-librespot[11875]: time="2026-02-19T01:09: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 19 01:09:54 volumio go-librespot[11875]: time="2026-02-19T01:09: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 19 01:09:54 volumio go-librespot[11875]: time="2026-02-19T01:09:54+07:00" level=info msg="zeroconf server listening on port 34849" Feb 19 01:09:54 volumio volumio[11547]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 19 01:09:54 volumio go-librespot[11875]: time="2026-02-19T01:09:54+07:00" level=debug msg="obtained new client token: AABpxiHxkxKUygo0df4ZPhp3aHpEKK9rKzWKUr3pFRcQt9NP+1cYmdNCITi7aTpL9UreSckLARIHH22XARjl819UDkIOyBFxaNfwuhXoWmmC1MS2lNmqUvm08PCd2UjTz464LgZPxJ3sl4NSqNn21yN8yESDsA1aT4fbHYpopuORy/zLUt8OCd0CjZBp5zWeJrI5MMeWd2nIVYdMJqaj4A2oiSy15XicHrZ1pKKLMQN1DLzDAa7JK1S2Cw==" Feb 19 01:09:54 volumio go-librespot[11875]: time="2026-02-19T01:09:54+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 19 01:09:54 volumio go-librespot[11875]: time="2026-02-19T01:09:54+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 19 01:09:54 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 19 01:09:54 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 19 01:09:55 volumio volumio[11547]: info: Initializing connection to go-librespot Websocket Feb 19 01:09:55 volumio volumio[11547]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 19 01:09:57 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Feb 19 01:09:57 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 19 01:09:57 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 19 01:09:57 volumio go-librespot[11883]: go-librespot daemon starting... Feb 19 01:09:57 volumio go-librespot[11884]: time="2026-02-19T01:09:57+07:00" level=info msg="running go-librespot 0.4.0" Feb 19 01:09:57 volumio go-librespot[11884]: time="2026-02-19T01:09:57+07:00" level=debug msg="app state loaded" Feb 19 01:09:57 volumio go-librespot[11884]: time="2026-02-19T01:09:57+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 19 01:09:58 volumio volumio[11547]: info: Initializing connection to go-librespot Websocket Feb 19 01:09:58 volumio go-librespot[11884]: time="2026-02-19T01:09:58+07:00" level=debug msg="new websocket client" Feb 19 01:09:58 volumio volumio[11547]: info: Connection to go-librespot Websocket established Feb 19 01:09:58 volumio go-librespot[11884]: time="2026-02-19T01:09:58+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 19 01:09:58 volumio go-librespot[11884]: time="2026-02-19T01:09:58+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 19 01:09:58 volumio go-librespot[11884]: time="2026-02-19T01:09:58+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 19 01:09:58 volumio go-librespot[11884]: time="2026-02-19T01:09:58+07:00" level=info msg="zeroconf server listening on port 39979" Feb 19 01:09:58 volumio volumio[11547]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 19 01:09:58 volumio volumio[11547]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 19 01:09:58 volumio volumio[11547]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 19 01:09:58 volumio volumio[11547]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 19 01:09:58 volumio volumio[11547]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 19 01:09:58 volumio volumio[11547]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 19 01:09:58 volumio volumio[11547]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 19 01:09:58 volumio volumio[11547]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 19 01:09:58 volumio volumio[11547]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 19 01:09:58 volumio volumio[11547]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 19 01:09:58 volumio volumio[11547]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 19 01:09:58 volumio volumio[11547]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 19 01:09:58 volumio volumio[11547]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 19 01:09:58 volumio volumio[11547]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 19 01:09:58 volumio volumio[11547]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 19 01:09:58 volumio volumio[11547]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 19 01:09:58 volumio volumio[11547]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 19 01:09:58 volumio volumio[11547]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 19 01:09:58 volumio volumio[11547]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 19 01:09:58 volumio volumio[11547]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 19 01:09:58 volumio volumio[11547]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 19 01:09:58 volumio volumio[11547]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 19 01:09:58 volumio volumio[11547]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 19 01:09:58 volumio volumio[11547]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 19 01:09:58 volumio volumio[11547]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 19 01:09:58 volumio volumio[11547]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 19 01:09:58 volumio volumio[11547]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 19 01:09:58 volumio volumio[11547]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 19 01:09:58 volumio volumio[11547]: info: Adding plugin bluetooth to MyMusic Plugins Feb 19 01:09:58 volumio volumio[11547]: info: Adding plugin multiroom to MyMusic Plugins Feb 19 01:09:58 volumio volumio[11547]: info: Adding plugin metavolumio to MyMusic Plugins Feb 19 01:09:58 volumio volumio[11547]: info: Adding plugin cd_controller to MyMusic Plugins Feb 19 01:09:58 volumio volumio[11547]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 19 01:09:58 volumio volumio[11547]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 19 01:09:58 volumio volumio[11547]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 19 01:09:58 volumio volumio[11547]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 19 01:09:58 volumio go-librespot[11884]: time="2026-02-19T01:09:58+07:00" level=debug msg="obtained new client token: AAC8urxG8BJ7TTIfL0C/J6E0k2g4py55e6oEJY0l7xiX7MZM6GzWzEbsXyF2A509Z4g1cY8ZCjqni9pyTDpJzWryELb+5Jrood0p4zPlirWW5O1ClrQhrSJs7bf1CLnXz8WeO5utSPIhgqm7UerEAvxmULuOSCTh1N6+10+4H7UCgqH6kTweyiG2EHwr1xZNLBotZuXsKJVBgoC3B7ZFXscMpMWs/cgrROUcidwUNFEDz6ccF9c0Qy38qg==" Feb 19 01:09:58 volumio go-librespot[11884]: time="2026-02-19T01:09:58+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 19 01:09:58 volumio go-librespot[11884]: time="2026-02-19T01:09:58+07:00" level=debug msg="completed keyexchange" Feb 19 01:09:58 volumio go-librespot[11884]: time="2026-02-19T01:09:58+07:00" level=debug msg="completed challenge" Feb 19 01:09:58 volumio go-librespot[11884]: time="2026-02-19T01:09:58+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 19 01:09:58 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 19 01:09:58 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 19 01:10:00 volumio volumio[11547]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 19 01:10:00 volumio volumio[11547]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 19 01:10:00 volumio volumio[11547]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 01:10:00 volumio volumio[11547]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 01:10:00 volumio volumio[11547]: info: Starting MyVolumio Remote Streaming Endpoints Feb 19 01:10:00 volumio volumio[11547]: info: MyVolumio login type: Token Feb 19 01:10:00 volumio volumio[11547]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 19 01:10:00 volumio volumio[11547]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 19 01:10:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Feb 19 01:10:01 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 19 01:10:01 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 19 01:10:01 volumio go-librespot[11905]: go-librespot daemon starting... Feb 19 01:10:01 volumio go-librespot[11906]: time="2026-02-19T01:10:01+07:00" level=info msg="running go-librespot 0.4.0" Feb 19 01:10:01 volumio go-librespot[11906]: time="2026-02-19T01:10:01+07:00" level=debug msg="app state loaded" Feb 19 01:10:01 volumio go-librespot[11906]: time="2026-02-19T01:10:01+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 19 01:10:02 volumio volumio[11547]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 19 01:10:02 volumio volumio[11547]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 19 01:10:02 volumio volumio[11547]: info: Streaming services startup Feb 19 01:10:02 volumio volumio[11547]: info: Starting Streaming Daemon Feb 19 01:10:02 volumio sudo[11913]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 19 01:10:02 volumio sudo[11913]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 19 01:10:02 volumio volumio[11547]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 19 01:10:02 volumio sudo[11913]: pam_unix(sudo:session): session closed for user root Feb 19 01:10:02 volumio volumio[11547]: info: Getting Spotify volume Feb 19 01:10:02 volumio volumio[11547]: info: Connection to go-librespot Websocket closed Feb 19 01:10:02 volumio volumio[11547]: error: Cannot start Volumio Streaming Daemon Feb 19 01:10:02 volumio volumio[11547]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 19 01:10:02 volumio volumio[11547]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 19 01:10:02 volumio volumio[11547]: 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 19 01:10:02 volumio go-librespot[11906]: time="2026-02-19T01:10:02+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 19 01:10:02 volumio go-librespot[11906]: time="2026-02-19T01:10:02+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 19 01:10:02 volumio go-librespot[11906]: time="2026-02-19T01:10:02+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 19 01:10:02 volumio go-librespot[11906]: time="2026-02-19T01:10:02+07:00" level=info msg="zeroconf server listening on port 33079" Feb 19 01:10:02 volumio volumio[11547]: info: CoreCommandRouter::volumioGetState Feb 19 01:10:02 volumio volumio[11547]: info: CorePlayQueue::getTrack 0 Feb 19 01:10:02 volumio volumio[11547]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 19 01:10:02 volumio volumio[11547]: SPOTIFY: SPOTIFY VOLUME undefined Feb 19 01:10:02 volumio volumio[11547]: SPOTIFY: VOLUMIO VOLUME 100 Feb 19 01:10:02 volumio volumio[11547]: info: Aligning Spotify Volume to Volumio Volume Feb 19 01:10:02 volumio volumio[11547]: info: CoreCommandRouter::volumioGetState Feb 19 01:10:02 volumio volumio[11547]: info: CorePlayQueue::getTrack 0 Feb 19 01:10:02 volumio volumio[11547]: info: Setting Spotify Volume from Volumio: 100 Feb 19 01:10:02 volumio go-librespot[11906]: time="2026-02-19T01:10:02+07:00" level=debug msg="obtained new client token: AADveu4kro9cJv3YAEUDJCgq17jB6X1DFkA7ecovrR39G7ft86ESL3yr087yIcdbh5sBY2f9lpgYCwDHpCt5qgoVmrNYfzBBjY9GqLOSyUiP2KkET9jSbPjBzLCpFAbuRyjwj4ox865subJwk6u2BEPr5h7OFetB/LujnaYe8sT+q1+DAdYW+7LnaMrMYsDWDLDcVMfqThhpwIT2N1Bm0eY8k/k8Oo/QkuDxb4rbNplY1m7467ZuPmmQHg==" Feb 19 01:10:02 volumio go-librespot[11906]: time="2026-02-19T01:10:02+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 19 01:10:02 volumio go-librespot[11906]: time="2026-02-19T01:10:02+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 19 01:10:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 19 01:10:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 19 01:10:02 volumio volumio[11547]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 19 01:10:02 volumio volumio[11547]: Error: socket hang up Feb 19 01:10:02 volumio volumio[11547]: at connResetException (node:internal/errors:720:14) Feb 19 01:10:02 volumio volumio[11547]: at Socket.socketOnEnd (node:_http_client:519:23) Feb 19 01:10:02 volumio volumio[11547]: at Socket.emit (node:events:526:35) Feb 19 01:10:02 volumio volumio[11547]: at endReadableNT (node:internal/streams/readable:1376:12) Feb 19 01:10:02 volumio volumio[11547]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Feb 19 01:10:02 volumio volumio[11547]: code: 'ECONNRESET', Feb 19 01:10:02 volumio volumio[11547]: response: undefined Feb 19 01:10:02 volumio volumio[11547]: } Feb 19 01:10:03 volumio volumio[11547]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 19 01:10:04 volumio sudo[11935]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-19 01:09' Feb 19 01:10:04 volumio sudo[11935]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 19 01:10:04 volumio sudo[11935]: pam_unix(sudo:session): session closed for user root Feb 19 01:10:04 volumio volumio-remote-updater[25144]: [2026-02-19 01:10:04] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 19 01:10:04 volumio volumio-remote-updater[25144]: [2026-02-19 01:10:04] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 19 01:10:04 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 19 01:10:04 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 19 01:10:04 volumio systemd[1]: volumio.service: Consumed 55.510s CPU time. Feb 19 01:10:04 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 19 01:10:04 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 19 01:10:04 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 22677. Feb 19 01:10:04 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 19 01:10:04 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 19 01:10:04 volumio systemd[1]: volumio.service: Consumed 55.510s CPU time. Feb 19 01:10:04 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Feb 19 01:10:05 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 19 01:10:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Feb 19 01:10:06 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 19 01:10:06 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 19 01:10:06 volumio go-librespot[11962]: go-librespot daemon starting... Feb 19 01:10:06 volumio go-librespot[11963]: time="2026-02-19T01:10:06+07:00" level=info msg="running go-librespot 0.4.0" Feb 19 01:10:06 volumio go-librespot[11963]: time="2026-02-19T01:10:06+07:00" level=debug msg="app state loaded" Feb 19 01:10:06 volumio go-librespot[11963]: time="2026-02-19T01:10:06+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 19 01:10:06 volumio go-librespot[11963]: time="2026-02-19T01:10:06+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 19 01:10:06 volumio go-librespot[11963]: time="2026-02-19T01:10:06+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 19 01:10:06 volumio go-librespot[11963]: time="2026-02-19T01:10:06+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 19 01:10:06 volumio go-librespot[11963]: time="2026-02-19T01:10:06+07:00" level=info msg="zeroconf server listening on port 44099" Feb 19 01:10:06 volumio go-librespot[11963]: time="2026-02-19T01:10:06+07:00" level=debug msg="obtained new client token: AADKv5j9V0LnFXFuHcZCEuWw41yEdnMdvNf08UalhbKG8EaQoDIeVjbk4CUw1K7Z6N1pnf3WVclX8+snUJJyRMNeI9zPbpqyGQG5mKpKSQFTZhHwg4vPCPJIvjgPrMjVSOvo3Ap1sk9k+V5OpehlwsfZHpqbvWAGMuLTFQHH9nuC6l1lJBkZ9tikWDkU6UvPFH69Xzval2R7c7eo2WLP4q6b2aMiK/TzoyA+oJCYyTYGySbCSBv+M+CPtw==" Feb 19 01:10:07 volumio go-librespot[11963]: time="2026-02-19T01:10:07+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 19 01:10:07 volumio go-librespot[11963]: time="2026-02-19T01:10:07+07:00" level=debug msg="connected to ap-gae2.spotify.com:443" Feb 19 01:10:07 volumio go-librespot[11963]: time="2026-02-19T01:10:07+07:00" level=debug msg="completed keyexchange" Feb 19 01:10:07 volumio go-librespot[11963]: time="2026-02-19T01:10:07+07:00" level=debug msg="completed challenge" Feb 19 01:10:07 volumio go-librespot[11963]: time="2026-02-19T01:10:07+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 19 01:10:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 19 01:10:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 19 01:10:08 volumio volumio[11947]: info: ------------------------------------------- Feb 19 01:10:08 volumio volumio[11947]: info: ----- Volumio3 ---- Feb 19 01:10:08 volumio volumio[11947]: info: ------------------------------------------- Feb 19 01:10:08 volumio volumio[11947]: info: ----- System startup ---- Feb 19 01:10:08 volumio volumio[11947]: info: ------------------------------------------- Feb 19 01:10:09 volumio volumio-remote-updater[25144]: [2026-02-19 01:10:09] [connect] Successful connection Feb 19 01:10:09 volumio volumio[11947]: info: MYVOLUMIO Environment detected Feb 19 01:10:10 volumio volumio[11947]: info: Plugin folders cleanup Feb 19 01:10:10 volumio volumio[11947]: info: Scanning into folder /volumio/app/plugins/ Feb 19 01:10:10 volumio volumio[11947]: info: Scanning category audio_interface Feb 19 01:10:10 volumio volumio[11947]: info: Scanning category miscellanea Feb 19 01:10:10 volumio volumio[11947]: info: Scanning category music_service Feb 19 01:10:10 volumio volumio[11947]: info: Scanning category plugins.json Feb 19 01:10:10 volumio volumio[11947]: info: Scanning category system_controller Feb 19 01:10:10 volumio volumio[11947]: info: Scanning category user_interface Feb 19 01:10:10 volumio volumio[11947]: info: Scanning into folder /data/plugins/ Feb 19 01:10:10 volumio volumio[11947]: info: Scanning category music_service Feb 19 01:10:10 volumio volumio[11947]: info: Plugin folders cleanup completed Feb 19 01:10:10 volumio volumio[11947]: info: ------------------------------------------- Feb 19 01:10:10 volumio volumio[11947]: info: ----- Core plugins startup ---- Feb 19 01:10:10 volumio volumio[11947]: info: ------------------------------------------- Feb 19 01:10:10 volumio volumio[11947]: info: Loading plugins from folder /volumio/app/plugins/ Feb 19 01:10:10 volumio volumio[11947]: info: Adding plugin upnp to MyMusic Plugins Feb 19 01:10:10 volumio volumio[11947]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 19 01:10:10 volumio volumio[11947]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 19 01:10:10 volumio volumio[11947]: info: Loading plugins from folder /data/plugins/ Feb 19 01:10:10 volumio volumio[11947]: info: Loading plugin "system"... Feb 19 01:10:10 volumio volumio[11947]: info: Loading plugin "appearance"... Feb 19 01:10:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Feb 19 01:10:10 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 19 01:10:10 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 19 01:10:10 volumio go-librespot[11983]: go-librespot daemon starting... Feb 19 01:10:10 volumio go-librespot[11984]: time="2026-02-19T01:10:10+07:00" level=info msg="running go-librespot 0.4.0" Feb 19 01:10:10 volumio go-librespot[11984]: time="2026-02-19T01:10:10+07:00" level=debug msg="app state loaded" Feb 19 01:10:10 volumio go-librespot[11984]: time="2026-02-19T01:10:10+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 19 01:10:11 volumio go-librespot[11984]: time="2026-02-19T01:10:11+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 19 01:10:11 volumio go-librespot[11984]: time="2026-02-19T01:10:11+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 19 01:10:11 volumio go-librespot[11984]: time="2026-02-19T01:10:11+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 19 01:10:11 volumio go-librespot[11984]: time="2026-02-19T01:10:11+07:00" level=info msg="zeroconf server listening on port 37897" Feb 19 01:10:11 volumio go-librespot[11984]: time="2026-02-19T01:10:11+07:00" level=debug msg="obtained new client token: AADtFSdHC6YhHYhwldI10XFxaC7Comlf4zFcInQ3ShG441lMvFQxVztlJCOI79Xj05oVLBc/X2HqrV0/N/pJexUwbMBzQIauium5/aPnHEVx1LlQr2ntR7mj1BmpCNbZOU6UQ2MqTZKphKdcIgh7MntYbMxk0f2cnOwxtrrbhzqAUFaVkb1tr/M3tVhXMxau3hl6y4IXsGdYo3XDaVISl7DWPYAyyy4YF95ms/Dn+ag89dy0SjKHM1t3sQ==" Feb 19 01:10:11 volumio go-librespot[11984]: time="2026-02-19T01:10:11+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 19 01:10:11 volumio go-librespot[11984]: time="2026-02-19T01:10:11+07:00" level=debug msg="completed keyexchange" Feb 19 01:10:11 volumio go-librespot[11984]: time="2026-02-19T01:10:11+07:00" level=debug msg="completed challenge" Feb 19 01:10:11 volumio go-librespot[11984]: time="2026-02-19T01:10:11+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 19 01:10:11 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 19 01:10:11 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 19 01:10:12 volumio volumio[11947]: info: Loading plugin "network"... Feb 19 01:10:12 volumio volumio[11947]: info: Refreshing Cached IP Addresses Feb 19 01:10:12 volumio sudo[11993]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 19 01:10:12 volumio sudo[11993]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 19 01:10:12 volumio sudo[11995]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 19 01:10:12 volumio volumio[11947]: info: Loading plugin "services"... Feb 19 01:10:12 volumio sudo[11993]: pam_unix(sudo:session): session closed for user root Feb 19 01:10:12 volumio sudo[11995]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 19 01:10:12 volumio volumio[11947]: info: Loading plugin "alsa_controller"... Feb 19 01:10:12 volumio sudo[11995]: pam_unix(sudo:session): session closed for user root Feb 19 01:10:12 volumio sudo[12002]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 19 01:10:12 volumio sudo[12002]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 19 01:10:12 volumio volumio[11947]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 19 01:10:12 volumio volumio[11947]: info: Loading plugin "wizard"... Feb 19 01:10:12 volumio volumio[11947]: info: Loading plugin "networkfs"... Feb 19 01:10:12 volumio volumio[11947]: info: Starting Udev Watcher for removable devices Feb 19 01:10:12 volumio volumio[11947]: info: Ignoring mount for partition: boot Feb 19 01:10:12 volumio volumio[11947]: info: Ignoring mount for partition: volumio Feb 19 01:10:12 volumio volumio[11947]: info: Ignoring mount for partition: volumio_data Feb 19 01:10:12 volumio volumio[11947]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 19 01:10:12 volumio volumio[11947]: info: Loading plugin "volumio_command_line_client"... Feb 19 01:10:12 volumio volumio[11947]: info: Loading plugin "upnp"... Feb 19 01:10:12 volumio volumio[11947]: info: [1771438212606] Starting Upmpd Daemon Feb 19 01:10:12 volumio volumio[11947]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 19 01:10:12 volumio volumio[11947]: info: Loading plugin "my_music"... Feb 19 01:10:12 volumio volumio[11947]: info: Loading plugin "mpd"... Feb 19 01:10:13 volumio volumio[11947]: info: Loading plugin "upnp_browser"... Feb 19 01:10:15 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Feb 19 01:10:15 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 19 01:10:15 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 19 01:10:15 volumio go-librespot[12027]: go-librespot daemon starting... Feb 19 01:10:15 volumio sudo[12002]: pam_unix(sudo:session): session closed for user root Feb 19 01:10:15 volumio go-librespot[12028]: time="2026-02-19T01:10:15+07:00" level=info msg="running go-librespot 0.4.0" Feb 19 01:10:15 volumio go-librespot[12028]: time="2026-02-19T01:10:15+07:00" level=debug msg="app state loaded" Feb 19 01:10:15 volumio go-librespot[12028]: time="2026-02-19T01:10:15+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 19 01:10:15 volumio go-librespot[12028]: time="2026-02-19T01:10:15+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 19 01:10:15 volumio go-librespot[12028]: time="2026-02-19T01:10:15+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 19 01:10:15 volumio go-librespot[12028]: time="2026-02-19T01:10:15+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 19 01:10:15 volumio go-librespot[12028]: time="2026-02-19T01:10:15+07:00" level=info msg="zeroconf server listening on port 45491" Feb 19 01:10:15 volumio go-librespot[12028]: time="2026-02-19T01:10:15+07:00" level=debug msg="obtained new client token: AADxWOf3fgYzBW/hkaI9YhM8hYNrs7kBovNBonILF5XdOh+hydXwgXt4p5MMpxA5wT892SthspBEWRQU4TL4lK+7R8ZWpHcqlbE0l0Ci7rswb86TbK3JAYYbR9uoR8I39Qg6/jgC8HCNH/lern9UBPpnxX55IT0oCOOKQMxAVW6KrdhSsMM5yP5K5xq8zMspWMIlL2ZkgdMSwEc3ZNSbjAEvpCZBebBQZUO1MBjd/n0ZCV/2gCDo/j7cgw==" Feb 19 01:10:16 volumio go-librespot[12028]: time="2026-02-19T01:10:16+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 19 01:10:16 volumio volumio[11947]: info: Starting UPNP Browser Feb 19 01:10:16 volumio volumio[11947]: info: Loading plugin "alarm-clock"... Feb 19 01:10:16 volumio go-librespot[12028]: time="2026-02-19T01:10:16+07:00" level=debug msg="completed keyexchange" Feb 19 01:10:16 volumio go-librespot[12028]: time="2026-02-19T01:10:16+07:00" level=debug msg="completed challenge" Feb 19 01:10:16 volumio go-librespot[12028]: time="2026-02-19T01:10:16+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 19 01:10:16 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 19 01:10:16 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 19 01:10:16 volumio volumio[11947]: info: Loading plugin "airplay_emulation"... Feb 19 01:10:16 volumio volumio[11947]: info: Starting Shairport Sync Feb 19 01:10:16 volumio volumio[11947]: info: Loading plugin "last_100"... Feb 19 01:10:16 volumio volumio[11947]: info: Loading plugin "webradio"... Feb 19 01:10:16 volumio volumio[11947]: info: Loading plugin "i2s_dacs"... Feb 19 01:10:16 volumio volumio[11947]: info: Loading plugin "volumiodiscovery"... Feb 19 01:10:16 volumio volumio[11947]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 19 01:10:16 volumio volumio[11947]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 19 01:10:16 volumio node[11947]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 19 01:10:16 volumio volumio[11947]: *** WARNING *** For more information see Feb 19 01:10:16 volumio volumio[11947]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 19 01:10:16 volumio volumio[11947]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 19 01:10:16 volumio volumio[11947]: *** WARNING *** For more information see Feb 19 01:10:16 volumio node[11947]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 19 01:10:16 volumio node[11947]: *** WARNING *** For more information see Feb 19 01:10:16 volumio node[11947]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 19 01:10:16 volumio node[11947]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 19 01:10:16 volumio node[11947]: *** WARNING *** For more information see Feb 19 01:10:16 volumio volumio[11947]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 19 01:10:16 volumio volumio[11947]: info: Discovery: Started advertising with name: Volumio Feb 19 01:10:16 volumio volumio[11947]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 19 01:10:16 volumio volumio[11947]: info: Loading plugin "spop"... Feb 19 01:10:18 volumio volumio[11947]: info: Loading plugin "ytcr"... Feb 19 01:10:19 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Feb 19 01:10:19 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 19 01:10:19 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 19 01:10:19 volumio go-librespot[12037]: go-librespot daemon starting... Feb 19 01:10:19 volumio go-librespot[12038]: time="2026-02-19T01:10:19+07:00" level=info msg="running go-librespot 0.4.0" Feb 19 01:10:19 volumio go-librespot[12038]: time="2026-02-19T01:10:19+07:00" level=debug msg="app state loaded" Feb 19 01:10:19 volumio go-librespot[12038]: time="2026-02-19T01:10:19+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 19 01:10:20 volumio go-librespot[12038]: time="2026-02-19T01:10:20+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 19 01:10:20 volumio go-librespot[12038]: time="2026-02-19T01:10:20+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 19 01:10:20 volumio go-librespot[12038]: time="2026-02-19T01:10:20+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 19 01:10:20 volumio go-librespot[12038]: time="2026-02-19T01:10:20+07:00" level=info msg="zeroconf server listening on port 35305" Feb 19 01:10:20 volumio go-librespot[12038]: time="2026-02-19T01:10:20+07:00" level=debug msg="obtained new client token: AABgo0Ku/0W7DF0T+E8ILle2UO0+f4ba9hVloebN8zZUAj4V/nel1LB7xOt2UorN1e60STYXUHdS/SkOQN6EE1VYJ0/x8GKFlOOndw2HxfwBlnsofnAA+eTGFbEVKpO78Bfr1h2w+Zj9cPfEkHVYDrugiJHfPM7guCENumJj7uatZYnkknKftujx/0VrX+k27HdtC4ERNuou6d/ugK0eA5h8QJHVYIdaJaygGyXP4/TsLL/EScIdo0aimw==" Feb 19 01:10:20 volumio go-librespot[12038]: time="2026-02-19T01:10:20+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 19 01:10:20 volumio go-librespot[12038]: time="2026-02-19T01:10:20+07:00" level=debug msg="completed keyexchange" Feb 19 01:10:20 volumio go-librespot[12038]: time="2026-02-19T01:10:20+07:00" level=debug msg="completed challenge" Feb 19 01:10:20 volumio go-librespot[12038]: time="2026-02-19T01:10:20+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 19 01:10:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 19 01:10:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 19 01:10:21 volumio volumio[11947]: info: Loading plugin "ytmusic"... Feb 19 01:10:22 volumio volumio-remote-updater[25144]: [2026-02-19 01:10:22] [connect] Successful connection Feb 19 01:10:22 volumio volumio[11947]: info: Loading plugin "outputs"... Feb 19 01:10:22 volumio volumio[11947]: info: Loading plugin "albumart"... Feb 19 01:10:22 volumio volumio[11947]: info: Plugin example_plugin is not enabled Feb 19 01:10:22 volumio volumio[11947]: info: Loading plugin "inputs"... Feb 19 01:10:22 volumio volumio[11947]: info: Loading plugin "updater_comm"... Feb 19 01:10:22 volumio volumio[11947]: info: Plugin mpdemulation is not enabled Feb 19 01:10:22 volumio volumio[11947]: info: Loading plugin "rest_api"... Feb 19 01:10:22 volumio volumio[11947]: info: Loading plugin "websocket"... Feb 19 01:10:22 volumio volumio[11947]: info: Starting Socket.io Server version 1.7.4 Feb 19 01:10:22 volumio volumio[11947]: info: Loading plugin "RoonBridge"... Feb 19 01:10:23 volumio volumio[11947]: info: Applying required configuration parameters for plugin RoonBridge Feb 19 01:10:23 volumio volumio[11947]: info: Loading i18n strings for locale en Feb 19 01:10:23 volumio volumio[11947]: Updating browse sources language Feb 19 01:10:23 volumio volumio[11947]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 19 01:10:23 volumio volumio[12059]: Forking 3 albumart workers Feb 19 01:10:23 volumio volumio[11947]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 19 01:10:23 volumio volumio[11947]: info: CoreCommandRouter::initPlayerControls Feb 19 01:10:23 volumio volumio[11947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 01:10:23 volumio volumio[11947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 01:10:23 volumio volumio[11947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 01:10:23 volumio volumio[11947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 01:10:23 volumio volumio[11947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 01:10:23 volumio volumio[11947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 01:10:23 volumio volumio[11947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 01:10:23 volumio volumio[11947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 01:10:23 volumio volumio[11947]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 19 01:10:23 volumio volumio[11947]: Express server listening on port 3000 Feb 19 01:10:23 volumio volumio[11947]: [Metrics] WebUI: 16s 2.27ms Feb 19 01:10:23 volumio volumio[11947]: info: CoreStateMachine::resetVolumioState Feb 19 01:10:23 volumio volumio[11947]: info: CoreStateMachine::getcurrentVolume Feb 19 01:10:23 volumio volumio[11947]: info: CoreCommandRouter::volumioRetrievevolume Feb 19 01:10:23 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Feb 19 01:10:23 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 19 01:10:23 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 19 01:10:23 volumio go-librespot[12101]: go-librespot daemon starting... Feb 19 01:10:23 volumio sudo[12105]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 19 01:10:24 volumio sudo[12105]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 19 01:10:24 volumio sudo[12107]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 19 01:10:24 volumio go-librespot[12108]: time="2026-02-19T01:10:24+07:00" level=info msg="running go-librespot 0.4.0" Feb 19 01:10:24 volumio go-librespot[12108]: time="2026-02-19T01:10:24+07:00" level=debug msg="app state loaded" Feb 19 01:10:24 volumio sudo[12107]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 19 01:10:24 volumio go-librespot[12108]: time="2026-02-19T01:10:24+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 19 01:10:24 volumio sudo[12107]: pam_unix(sudo:session): session closed for user root Feb 19 01:10:24 volumio sudo[12105]: pam_unix(sudo:session): session closed for user root Feb 19 01:10:24 volumio volumio[11947]: info: Volumio Network Manager: Network status updated: 1 Feb 19 01:10:24 volumio volumio[11947]: info: VolumeController:: Volume=100 Mute =false Feb 19 01:10:24 volumio volumio[11947]: info: CoreStateMachine::pushState Feb 19 01:10:24 volumio volumio[11947]: info: CorePlayQueue::getTrack 0 Feb 19 01:10:24 volumio volumio[11947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 19 01:10:24 volumio volumio[11947]: info: CoreCommandRouter::volumioPushState Feb 19 01:10:24 volumio volumio[11947]: info: CoreStateMachine::updateTrackBlock Feb 19 01:10:24 volumio volumio[11947]: info: CorePlayQueue::getTrackBlock Feb 19 01:10:24 volumio volumio[11947]: info: CoreCommandRouter::volumioRetrievevolume Feb 19 01:10:24 volumio go-librespot[12108]: time="2026-02-19T01:10:24+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 19 01:10:24 volumio go-librespot[12108]: time="2026-02-19T01:10:24+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 19 01:10:24 volumio go-librespot[12108]: time="2026-02-19T01:10:24+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 19 01:10:24 volumio go-librespot[12108]: time="2026-02-19T01:10:24+07:00" level=info msg="zeroconf server listening on port 40909" Feb 19 01:10:24 volumio volumio-remote-updater[25144]: [2026-02-19 01:10:24] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1771438222 101 Feb 19 01:10:24 volumio volumio[11947]: 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 19 01:10:24 volumio volumio[11947]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 01:10:24 volumio volumio[11947]: info: Reloading queue from file Feb 19 01:10:24 volumio volumio[11947]: info: CoreStateMachine::setRepeat null single undefined Feb 19 01:10:24 volumio volumio[11947]: info: CoreStateMachine::pushState Feb 19 01:10:24 volumio volumio[11947]: info: CorePlayQueue::getTrack 0 Feb 19 01:10:24 volumio volumio[11947]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 19 01:10:24 volumio volumio[11947]: info: CoreCommandRouter::volumioPushState Feb 19 01:10:24 volumio volumio[11947]: info: CoreStateMachine::setRandom null Feb 19 01:10:24 volumio volumio[11947]: info: CoreStateMachine::pushState Feb 19 01:10:24 volumio volumio[11947]: info: CorePlayQueue::getTrack 0 Feb 19 01:10:24 volumio volumio[11947]: info: CoreCommandRouter::volumioPushState Feb 19 01:10:24 volumio volumio[11947]: info: Setting Device type: Raspberry PI Feb 19 01:10:24 volumio volumio[11947]: info: Completed loading Core Plugins Feb 19 01:10:24 volumio volumio[11947]: info: Preparing to generate the ALSA configuration file Feb 19 01:10:24 volumio go-librespot[12108]: time="2026-02-19T01:10:24+07:00" level=debug msg="obtained new client token: AADZj1Mcl9VUPQvoPDG0aKDl3/U5Lu8xc48gA/E8oK1czOSmioHyhGJQPZYpVtqfV2z0/yM4O2QWd+BtDYhvKh4F0SpSNJIOPFTxdm8bCKW2jnx7E7TNIl9JqMY13bMdn5FPDspNpofphA1ibDF86HnIcUtTxE/9CRGWch6BDhYiassVJJOypgl3K+zyv49Tv/fs9dYMb3of9o7Nhl5To7i5JfiToJe21xSGcI1oMaGx7cP0NvEw+sVoCw==" Feb 19 01:10:25 volumio volumio[11947]: info: VolumeController:: Volume=100 Mute =false Feb 19 01:10:25 volumio volumio[11947]: info: CoreStateMachine::pushState Feb 19 01:10:25 volumio volumio[11947]: info: CorePlayQueue::getTrack 0 Feb 19 01:10:25 volumio go-librespot[12108]: time="2026-02-19T01:10:25+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 19 01:10:25 volumio volumio[11947]: info: CoreCommandRouter::volumioPushState Feb 19 01:10:25 volumio go-librespot[12108]: time="2026-02-19T01:10:25+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 19 01:10:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 19 01:10:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 19 01:10:25 volumio volumio[11947]: info: Asound.conf file unchanged, so no further update is needed Feb 19 01:10:25 volumio volumio[11947]: info: Output device has changed, restarting MPD Feb 19 01:10:25 volumio sudo[12127]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 19 01:10:25 volumio sudo[12127]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 19 01:10:25 volumio volumio[11947]: info: Output device has changed, restarting Shairport Sync Feb 19 01:10:25 volumio volumio[11947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 01:10:25 volumio volumio[11947]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 01:10:25 volumio sudo[12130]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 19 01:10:25 volumio sudo[12130]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 19 01:10:25 volumio sudo[12127]: pam_unix(sudo:session): session closed for user root Feb 19 01:10:25 volumio sudo[12130]: pam_unix(sudo:session): session closed for user root Feb 19 01:10:25 volumio volumio[11947]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 19 01:10:25 volumio volumio[11947]: info: ___________ START PLUGINS ___________ Feb 19 01:10:25 volumio sudo[12132]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 19 01:10:25 volumio sudo[12132]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 19 01:10:25 volumio volumio[11947]: info: ControllerMpd::onStart: Initializing MPD Feb 19 01:10:25 volumio volumio[11947]: info: Creating MPD Configuration file Feb 19 01:10:25 volumio volumio[11947]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 19 01:10:25 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 19 01:10:25 volumio volumio[11947]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 19 01:10:25 volumio volumio[11947]: info: [1771438225471] CoreMusicLibrary::Adding element Media Servers Feb 19 01:10:25 volumio volumio[11947]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 19 01:10:25 volumio sudo[12140]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 19 01:10:25 volumio sudo[12140]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 19 01:10:25 volumio sudo[12140]: pam_unix(sudo:session): session closed for user root Feb 19 01:10:25 volumio volumio[11947]: info: UPNP Browser: Client initialized successfully Feb 19 01:10:25 volumio sudo[12142]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 19 01:10:25 volumio sudo[12142]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 19 01:10:25 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 19 01:10:25 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 19 01:10:25 volumio systemd[1]: mpd.service: Consumed 7.214s CPU time. Feb 19 01:10:25 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 19 01:10:25 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 19 01:10:25 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 19 01:10:25 volumio volumio[11947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 01:10:25 volumio volumio[11947]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 01:10:25 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 19 01:10:25 volumio volumio[11947]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 19 01:10:25 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 19 01:10:25 volumio volumio[11947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 01:10:25 volumio volumio[11947]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 01:10:25 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 19 01:10:25 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 19 01:10:25 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 19 01:10:25 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 19 01:10:25 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 19 01:10:25 volumio volumio[11947]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 19 01:10:25 volumio volumio[11947]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 19 01:10:25 volumio volumio[11947]: info: [1771438225828] CoreMusicLibrary::Adding element Last_100 Feb 19 01:10:25 volumio volumio[11947]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 19 01:10:25 volumio volumio[11947]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 19 01:10:25 volumio volumio[11947]: info: [1771438225842] CoreMusicLibrary::Adding element Webradio Feb 19 01:10:25 volumio volumio[11947]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 19 01:10:25 volumio volumio[11947]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 19 01:10:25 volumio volumio[11947]: info: Initializing BBC Radios Feb 19 01:10:25 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 19 01:10:25 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 19 01:10:26 volumio volumio[11947]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 19 01:10:26 volumio volumio[11947]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 01:10:26 volumio volumio[11947]: info: Creating Spotify config file Feb 19 01:10:26 volumio volumio[11947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 01:10:26 volumio sudo[12159]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 19 01:10:26 volumio sudo[12159]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 19 01:10:26 volumio sudo[12159]: pam_unix(sudo:session): session closed for user root Feb 19 01:10:26 volumio volumio[12070]: Starting albumart workers Feb 19 01:10:26 volumio volumio[12071]: Starting albumart workers Feb 19 01:10:27 volumio volumio[12069]: Starting albumart workers Feb 19 01:10:27 volumio volumio[11947]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 19 01:10:27 volumio volumio[11947]: info: [1771438227512] CoreMusicLibrary::Adding element YouTube Music Feb 19 01:10:27 volumio volumio[11947]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 19 01:10:27 volumio volumio[11947]: Cannot find translation for source YouTube Music Feb 19 01:10:27 volumio volumio[11947]: info: Volumio Calling Home Feb 19 01:10:27 volumio sudo[12173]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Feb 19 01:10:27 volumio sudo[12173]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 19 01:10:27 volumio sudo[12173]: pam_unix(sudo:session): session closed for user root Feb 19 01:10:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Feb 19 01:10:28 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 19 01:10:28 volumio volumio[11947]: info: Discovery: adding 921648ed-0557-4d70-9927-1420682efcc7 Feb 19 01:10:28 volumio volumio[11947]: info: Discovery: Found device Volumio Feb 19 01:10:28 volumio volumio[11947]: info: CoreCommandRouter::volumioGetState Feb 19 01:10:28 volumio volumio[11947]: info: CorePlayQueue::getTrack 0 Feb 19 01:10:28 volumio volumio[11947]: info: Discovery: this is already registered, 921648ed-0557-4d70-9927-1420682efcc7 Feb 19 01:10:28 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 19 01:10:28 volumio go-librespot[12178]: go-librespot daemon starting... Feb 19 01:10:28 volumio volumio[11947]: info: Discovery: Found device Volumio Feb 19 01:10:28 volumio volumio[11947]: info: CoreCommandRouter::volumioGetState Feb 19 01:10:28 volumio volumio[11947]: info: CorePlayQueue::getTrack 0 Feb 19 01:10:28 volumio volumio[11947]: info: MPD Permissions set Feb 19 01:10:28 volumio volumio[11947]: info: MPD Permissions set Feb 19 01:10:28 volumio go-librespot[12180]: time="2026-02-19T01:10:28+07:00" level=info msg="running go-librespot 0.4.0" Feb 19 01:10:28 volumio volumio[11947]: info: Upmpdcli Daemon Started Feb 19 01:10:28 volumio go-librespot[12180]: time="2026-02-19T01:10:28+07:00" level=debug msg="app state loaded" Feb 19 01:10:28 volumio go-librespot[12180]: time="2026-02-19T01:10:28+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 19 01:10:28 volumio volumio[11947]: info: Spotify config file written Feb 19 01:10:28 volumio volumio[11947]: 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 19 01:10:28 volumio volumio[11947]: info: Volumio called home Feb 19 01:10:28 volumio sudo[12193]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 19 01:10:28 volumio sudo[12193]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 19 01:10:28 volumio volumio[11947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 01:10:28 volumio volumio[11947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 01:10:28 volumio volumio[11947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 01:10:28 volumio volumio[11947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 01:10:28 volumio volumio[11947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 01:10:28 volumio volumio[11947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 01:10:28 volumio volumio[11947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 01:10:28 volumio volumio[11947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 01:10:28 volumio volumio[11947]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 01:10:28 volumio volumio[11947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 01:10:28 volumio volumio[11947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 01:10:28 volumio volumio[11947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 01:10:28 volumio volumio[11947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 01:10:28 volumio volumio[11947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 01:10:28 volumio volumio[11947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 01:10:28 volumio volumio[11947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 01:10:28 volumio volumio[11947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 01:10:28 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Feb 19 01:10:28 volumio volumio[11947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 01:10:28 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Feb 19 01:10:28 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 19 01:10:29 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 19 01:10:29 volumio volumio[11947]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 01:10:29 volumio volumio[11947]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 19 01:10:29 volumio go-librespot[12214]: go-librespot daemon starting... Feb 19 01:10:29 volumio volumio[11947]: info: No need to fix Spotify hosts Feb 19 01:10:29 volumio sudo[12193]: pam_unix(sudo:session): session closed for user root Feb 19 01:10:29 volumio go-librespot[12219]: time="2026-02-19T01:10:29+07:00" level=info msg="running go-librespot 0.4.0" Feb 19 01:10:29 volumio go-librespot[12219]: time="2026-02-19T01:10:29+07:00" level=debug msg="app state loaded" Feb 19 01:10:29 volumio go-librespot[12219]: time="2026-02-19T01:10:29+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 19 01:10:29 volumio go-librespot[12219]: time="2026-02-19T01:10: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 19 01:10:29 volumio go-librespot[12219]: time="2026-02-19T01:10: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 19 01:10:29 volumio go-librespot[12219]: time="2026-02-19T01:10: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 19 01:10:29 volumio go-librespot[12219]: time="2026-02-19T01:10:29+07:00" level=info msg="zeroconf server listening on port 41383" Feb 19 01:10:29 volumio volumio[11947]: info: Starting Shairport Sync Feb 19 01:10:29 volumio volumio[11947]: info: Starting Shairport Sync Feb 19 01:10:29 volumio go-librespot[12219]: time="2026-02-19T01:10:29+07:00" level=debug msg="obtained new client token: AAAoygVMboGaAlCtZqyCP0IekmmNMnBZM/TzGAqFtBTUbeP/G67Xe+1836sY7XVWQKrNxmN7mXP6IqWasIC8cmAyYKzqnim4agCeAimD4h+yPknmLhkYESGUHkgmIi+iXceehNce3XOMglp6neziKpKyA/VE3MLV9wKWONlm21cE3kXTJTvNQ17VKX27TMv3lImMCW+E30UZDYraT+ftyCy+JynGpuCw4ZtmUXue7xpM2yfxBKe4bcwe6w==" Feb 19 01:10:30 volumio volumio[11947]: info: Starting Shairport Sync Feb 19 01:10:30 volumio sudo[12228]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 19 01:10:30 volumio sudo[12228]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 19 01:10:30 volumio sudo[12230]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 19 01:10:30 volumio sudo[12230]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 19 01:10:30 volumio sudo[12233]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 19 01:10:30 volumio sudo[12233]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 19 01:10:30 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 19 01:10:30 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 19 01:10:30 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 19 01:10:30 volumio go-librespot[12219]: time="2026-02-19T01:10:30+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 19 01:10:30 volumio systemd[1]: shairport-sync.service: Consumed 2.288s CPU time. Feb 19 01:10:30 volumio go-librespot[12219]: time="2026-02-19T01:10:30+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 19 01:10:30 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 19 01:10:30 volumio sudo[12228]: pam_unix(sudo:session): session closed for user root Feb 19 01:10:30 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 19 01:10:30 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 19 01:10:30 volumio volumio[11947]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 19 01:10:30 volumio volumio[11947]: SPOTIFY: BQA1Ec_a5dz2fUzNmGLfQCD7XSFkNA7jZ-tOue6Di-dC7j9fbCJByVMVff_9hzMWa4t0Sqna81wcn6tZGiyw5BAbh5Fj8qsZVf1SgNA-VlVRibvqgIy8inds3sTjzBqt3F-DjRmkuHHe4_Wwe3v9ZmPJfyi0YKBwEaQjHDWsB9ci1_KH9O3I2DfnIpmW7SU3DeoclUqxF5JE40KrKurqMF_gg55Dcw00isHq7WcuJm2JCQjAdNEv28QPSwWBXYIHT8hdtIZYzOEtFq2h1X1muuCGg6Bb5-XwdSo3qXgegLVW0f8LwkPsJqHo Feb 19 01:10:30 volumio volumio[11947]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 19 01:10:30 volumio volumio[11947]: info: New Spotify access token = BQA1Ec_a5dz2fUzNmGLfQCD7XSFkNA7jZ-tOue6Di-dC7j9fbCJByVMVff_9hzMWa4t0Sqna81wcn6tZGiyw5BAbh5Fj8qsZVf1SgNA-VlVRibvqgIy8inds3sTjzBqt3F-DjRmkuHHe4_Wwe3v9ZmPJfyi0YKBwEaQjHDWsB9ci1_KH9O3I2DfnIpmW7SU3DeoclUqxF5JE40KrKurqMF_gg55Dcw00isHq7WcuJm2JCQjAdNEv28QPSwWBXYIHT8hdtIZYzOEtFq2h1X1muuCGg6Bb5-XwdSo3qXgegLVW0f8LwkPsJqHo Feb 19 01:10:30 volumio volumio[11947]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 19 01:10:30 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 19 01:10:30 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 19 01:10:30 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 19 01:10:30 volumio volumio[11947]: info: Shairport-Sync Started Feb 19 01:10:30 volumio volumio[11947]: Error adding Membership: Error: addMembership EINVAL Feb 19 01:10:30 volumio volumio[11947]: info: CoreCommandRouter::volumioGetState Feb 19 01:10:30 volumio volumio[11947]: info: CorePlayQueue::getTrack 0 Feb 19 01:10:30 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 19 01:10:30 volumio sudo[12230]: pam_unix(sudo:session): session closed for user root Feb 19 01:10:30 volumio sudo[12233]: pam_unix(sudo:session): session closed for user root Feb 19 01:10:30 volumio volumio[11947]: info: Shairport-Sync Started Feb 19 01:10:30 volumio volumio[11947]: info: Shairport-Sync Started Feb 19 01:10:30 volumio volumio[11947]: 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 19 01:10:30 volumio volumio[11947]: info: Spotify Successfully logged in Feb 19 01:10:30 volumio volumio[11947]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 19 01:10:30 volumio volumio[11947]: info: [1771438230921] CoreMusicLibrary::Adding element Spotify Feb 19 01:10:30 volumio volumio[11947]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 19 01:10:30 volumio volumio[11947]: Cannot find translation for source YouTube Music Feb 19 01:10:30 volumio volumio[11947]: Cannot find translation for source Spotify Feb 19 01:10:31 volumio volumio[11947]: info: [yt-cast-receiver] DIAL server listening on port 8098 Feb 19 01:10:31 volumio volumio[11947]: info: CoreCommandRouter::volumioRetrievevolume Feb 19 01:10:31 volumio volumio[11947]: info: VolumeController:: Volume=100 Mute =false Feb 19 01:10:31 volumio volumio[11947]: info: CoreCommandRouter::volumioGetState Feb 19 01:10:31 volumio volumio[11947]: info: CorePlayQueue::getTrack 0 Feb 19 01:10:31 volumio volumio[11947]: info: CoreStateMachine::pushState Feb 19 01:10:31 volumio volumio[11947]: info: CorePlayQueue::getTrack 0 Feb 19 01:10:31 volumio volumio[11947]: info: CoreCommandRouter::volumioPushState Feb 19 01:10:32 volumio volumio[11947]: info: go-librespot daemon successfully initialized Feb 19 01:10:33 volumio mpd[12171]: 2026-02-19T01:10:33 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 19 01:10:33 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 19 01:10:33 volumio sudo[12142]: pam_unix(sudo:session): session closed for user root Feb 19 01:10:33 volumio sudo[12132]: pam_unix(sudo:session): session closed for user root Feb 19 01:10:33 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Feb 19 01:10:33 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 19 01:10:33 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 19 01:10:33 volumio go-librespot[12287]: go-librespot daemon starting... Feb 19 01:10:33 volumio go-librespot[12288]: time="2026-02-19T01:10:33+07:00" level=info msg="running go-librespot 0.4.0" Feb 19 01:10:33 volumio go-librespot[12288]: time="2026-02-19T01:10:33+07:00" level=debug msg="app state loaded" Feb 19 01:10:33 volumio go-librespot[12288]: time="2026-02-19T01:10:33+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 19 01:10:33 volumio volumio[11947]: error: MPD error: The expression evaluated to a falsy value: Feb 19 01:10:33 volumio volumio[11947]: assert.ok(self.idling) Feb 19 01:10:33 volumio volumio[11947]: error: The expression evaluated to a falsy value: Feb 19 01:10:33 volumio volumio[11947]: assert.ok(self.idling) Feb 19 01:10:33 volumio volumio[11947]: info: MPD running with PID12171 Feb 19 01:10:33 volumio volumio[11947]: ,establishing connection Feb 19 01:10:33 volumio volumio[11947]: error: updateQueue error: null Feb 19 01:10:33 volumio volumio[11947]: info: Completed starting Core Plugins Feb 19 01:10:33 volumio volumio[11947]: info: ------------------------------------------- Feb 19 01:10:33 volumio volumio[11947]: info: ----- MyVolumio plugins startup ---- Feb 19 01:10:33 volumio volumio[11947]: info: ------------------------------------------- Feb 19 01:10:33 volumio volumio[11947]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 19 01:10:33 volumio volumio[11947]: error: updateQueue error: null Feb 19 01:10:33 volumio go-librespot[12288]: time="2026-02-19T01:10:33+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 19 01:10:33 volumio go-librespot[12288]: time="2026-02-19T01:10:33+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 19 01:10:33 volumio go-librespot[12288]: time="2026-02-19T01:10:33+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 19 01:10:34 volumio go-librespot[12288]: time="2026-02-19T01:10:34+07:00" level=info msg="zeroconf server listening on port 40925" Feb 19 01:10:34 volumio go-librespot[12288]: time="2026-02-19T01:10:34+07:00" level=debug msg="obtained new client token: AACoL6DQaEOf6l2bxhCWOj809sSfRBg2WvLe8eRJM6x1UxvTx3h8LNrhx+lF5hty+wkbPtsEUNgxcqNqUhL2BFxpAAtNccFQbro8ilyzgB9cikFY5/lhMoML+MFPaGC+QZdtbhEfIXKndJrjNdJ0CJx0cIjEQYfLJADA4+dsm5BYo+Bkr4//SLszrenJ4wDzSU8lz9zdokLBF1gWEoGx0TZrHnd/z6Y/JdciTgTm+eN7V9iZeO8/zFM=" Feb 19 01:10:34 volumio go-librespot[12288]: time="2026-02-19T01:10:34+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 19 01:10:34 volumio go-librespot[12288]: time="2026-02-19T01:10:34+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 19 01:10:34 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 19 01:10:34 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 19 01:10:35 volumio volumio[11947]: info: Initializing connection to go-librespot Websocket Feb 19 01:10:35 volumio volumio[11947]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 19 01:10:37 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Feb 19 01:10:37 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 19 01:10:37 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 19 01:10:37 volumio go-librespot[12295]: go-librespot daemon starting... Feb 19 01:10:37 volumio go-librespot[12296]: time="2026-02-19T01:10:37+07:00" level=info msg="running go-librespot 0.4.0" Feb 19 01:10:37 volumio go-librespot[12296]: time="2026-02-19T01:10:37+07:00" level=debug msg="app state loaded" Feb 19 01:10:37 volumio go-librespot[12296]: time="2026-02-19T01:10:37+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 19 01:10:38 volumio go-librespot[12296]: time="2026-02-19T01:10:38+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 19 01:10:38 volumio go-librespot[12296]: time="2026-02-19T01:10:38+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 19 01:10:38 volumio go-librespot[12296]: time="2026-02-19T01:10:38+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 19 01:10:38 volumio go-librespot[12296]: time="2026-02-19T01:10:38+07:00" level=info msg="zeroconf server listening on port 39477" Feb 19 01:10:38 volumio volumio[11947]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 19 01:10:38 volumio go-librespot[12296]: time="2026-02-19T01:10:38+07:00" level=debug msg="obtained new client token: AAA6T+pVkUq+tdBAG4rTQPKXaivxEB9WqdJRn/eWjzmxaQC8ck/9BDFL9HrvGhNnh9oY9nUG57op/4NxmODBjZWxija737udlP9v2cOyIhzC0Q/YpMFsB9aEmdsdNoDLh8wOCXG+qgKvAEfiklOFJ1XEibwJb14bxD32sQZnwc20yguIHnZUP5K6FwN1czW3oGTcc/3XyMHIaHeQKxT3HuPuMy2kMnUo7xtjVf2JQIyGfNG9zdcUmOypwg==" Feb 19 01:10:38 volumio go-librespot[12296]: time="2026-02-19T01:10:38+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 19 01:10:38 volumio go-librespot[12296]: time="2026-02-19T01:10:38+07:00" level=debug msg="completed keyexchange" Feb 19 01:10:38 volumio go-librespot[12296]: time="2026-02-19T01:10:38+07:00" level=debug msg="completed challenge" Feb 19 01:10:38 volumio go-librespot[12296]: time="2026-02-19T01:10:38+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 19 01:10:38 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 19 01:10:38 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 19 01:10:38 volumio volumio[11947]: info: Initializing connection to go-librespot Websocket Feb 19 01:10:38 volumio volumio[11947]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 19 01:10:41 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Feb 19 01:10:41 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 19 01:10:41 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 19 01:10:41 volumio go-librespot[12303]: go-librespot daemon starting... Feb 19 01:10:41 volumio go-librespot[12304]: time="2026-02-19T01:10:41+07:00" level=info msg="running go-librespot 0.4.0" Feb 19 01:10:41 volumio go-librespot[12304]: time="2026-02-19T01:10:41+07:00" level=debug msg="app state loaded" Feb 19 01:10:41 volumio go-librespot[12304]: time="2026-02-19T01:10:41+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 19 01:10:41 volumio volumio[11947]: info: Initializing connection to go-librespot Websocket Feb 19 01:10:41 volumio go-librespot[12304]: time="2026-02-19T01:10:41+07:00" level=debug msg="new websocket client" Feb 19 01:10:42 volumio volumio[11947]: info: Connection to go-librespot Websocket established Feb 19 01:10:42 volumio volumio[11947]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 19 01:10:42 volumio volumio[11947]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 19 01:10:42 volumio volumio[11947]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 19 01:10:42 volumio volumio[11947]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 19 01:10:42 volumio volumio[11947]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 19 01:10:42 volumio volumio[11947]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 19 01:10:42 volumio volumio[11947]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 19 01:10:42 volumio volumio[11947]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 19 01:10:42 volumio volumio[11947]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 19 01:10:42 volumio volumio[11947]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 19 01:10:42 volumio volumio[11947]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 19 01:10:42 volumio volumio[11947]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 19 01:10:42 volumio volumio[11947]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 19 01:10:42 volumio volumio[11947]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 19 01:10:42 volumio volumio[11947]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 19 01:10:42 volumio volumio[11947]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 19 01:10:42 volumio volumio[11947]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 19 01:10:42 volumio volumio[11947]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 19 01:10:42 volumio volumio[11947]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 19 01:10:42 volumio volumio[11947]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 19 01:10:42 volumio volumio[11947]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 19 01:10:42 volumio volumio[11947]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 19 01:10:42 volumio volumio[11947]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 19 01:10:42 volumio volumio[11947]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 19 01:10:42 volumio volumio[11947]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 19 01:10:42 volumio volumio[11947]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 19 01:10:42 volumio volumio[11947]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 19 01:10:42 volumio volumio[11947]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 19 01:10:42 volumio volumio[11947]: info: Adding plugin bluetooth to MyMusic Plugins Feb 19 01:10:42 volumio volumio[11947]: info: Adding plugin multiroom to MyMusic Plugins Feb 19 01:10:42 volumio volumio[11947]: info: Adding plugin metavolumio to MyMusic Plugins Feb 19 01:10:42 volumio volumio[11947]: info: Adding plugin cd_controller to MyMusic Plugins Feb 19 01:10:42 volumio volumio[11947]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 19 01:10:42 volumio volumio[11947]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 19 01:10:42 volumio volumio[11947]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 19 01:10:42 volumio volumio[11947]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 19 01:10:42 volumio go-librespot[12304]: time="2026-02-19T01:10: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-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 19 01:10:42 volumio go-librespot[12304]: time="2026-02-19T01:10:42+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 19 01:10:42 volumio go-librespot[12304]: time="2026-02-19T01:10:42+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 19 01:10:42 volumio go-librespot[12304]: time="2026-02-19T01:10:42+07:00" level=info msg="zeroconf server listening on port 42355" Feb 19 01:10:42 volumio go-librespot[12304]: time="2026-02-19T01:10:42+07:00" level=debug msg="obtained new client token: AACOD2Q9NUiQeXX6b5m48vTxi5Jlk9Oof8l1ABq5DZR0YL3rgtVPnCuJmcKmIDuCsMseve475O3DMgxpdvlWXWQrWsBo4G3SLgrNNimSmm2ZvPLi28vbMz9ZInYipyhp69YbDeM7IJsoRKXCykmRfJKVEGnx13UUm2T2j9qwumYzKvXUy4XQ4PDISE6B49vJoAZxliJJWZAES7iU7pNjjtyquWoH8Y0GiCki8/6FQEGsZ37ryn0Vg/YHnQ==" Feb 19 01:10:42 volumio go-librespot[12304]: time="2026-02-19T01:10:42+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 19 01:10:42 volumio go-librespot[12304]: time="2026-02-19T01:10:42+07:00" level=debug msg="completed keyexchange" Feb 19 01:10:42 volumio go-librespot[12304]: time="2026-02-19T01:10:42+07:00" level=debug msg="completed challenge" Feb 19 01:10:42 volumio go-librespot[12304]: time="2026-02-19T01:10:42+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 19 01:10:42 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 19 01:10:42 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 19 01:10:44 volumio volumio[11947]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 19 01:10:44 volumio volumio[11947]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 19 01:10:44 volumio volumio[11947]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 01:10:44 volumio volumio[11947]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 19 01:10:44 volumio volumio[11947]: info: Starting MyVolumio Remote Streaming Endpoints Feb 19 01:10:44 volumio volumio[11947]: info: MyVolumio login type: Token Feb 19 01:10:44 volumio volumio[11947]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 19 01:10:44 volumio volumio[11947]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 19 01:10:45 volumio volumio[11947]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 19 01:10:45 volumio volumio[11947]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 19 01:10:45 volumio volumio[11947]: info: Streaming services startup Feb 19 01:10:45 volumio volumio[11947]: info: Starting Streaming Daemon Feb 19 01:10:45 volumio sudo[12327]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 19 01:10:45 volumio sudo[12327]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 19 01:10:45 volumio volumio[11947]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 19 01:10:46 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Feb 19 01:10:46 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 19 01:10:46 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 19 01:10:46 volumio volumio[11947]: info: Getting Spotify volume Feb 19 01:10:46 volumio go-librespot[12333]: go-librespot daemon starting... Feb 19 01:10:46 volumio sudo[12327]: pam_unix(sudo:session): session closed for user root Feb 19 01:10:46 volumio volumio[11947]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 19 01:10:46 volumio go-librespot[12334]: time="2026-02-19T01:10:46+07:00" level=info msg="running go-librespot 0.4.0" Feb 19 01:10:46 volumio go-librespot[12334]: time="2026-02-19T01:10:46+07:00" level=debug msg="app state loaded" Feb 19 01:10:46 volumio go-librespot[12334]: time="2026-02-19T01:10:46+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 19 01:10:46 volumio volumio[11947]: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 19 01:10:46 volumio volumio[11947]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Feb 19 01:10:46 volumio volumio[11947]: errno: -111, Feb 19 01:10:46 volumio volumio[11947]: code: 'ECONNREFUSED', Feb 19 01:10:46 volumio volumio[11947]: syscall: 'connect', Feb 19 01:10:46 volumio volumio[11947]: address: '127.0.0.1', Feb 19 01:10:46 volumio volumio[11947]: port: 9879, Feb 19 01:10:46 volumio volumio[11947]: response: undefined Feb 19 01:10:46 volumio volumio[11947]: } Feb 19 01:10:46 volumio volumio[11947]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 19 01:10:46 volumio go-librespot[12334]: time="2026-02-19T01:10:46+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 19 01:10:46 volumio go-librespot[12334]: time="2026-02-19T01:10:46+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 19 01:10:46 volumio go-librespot[12334]: time="2026-02-19T01:10:46+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 19 01:10:46 volumio go-librespot[12334]: time="2026-02-19T01:10:46+07:00" level=info msg="zeroconf server listening on port 38771" Feb 19 01:10:46 volumio go-librespot[12334]: time="2026-02-19T01:10:46+07:00" level=debug msg="obtained new client token: AADWIhM6vIjuYv3UqjFcAivA89gbkGA5mgFuk3PGC1f4CvkEvYFiRsm1pIVNtMb83fXk5e6CwqgOrS380/aG6R1/b9szcxHuTxt31qcS0Ly7ppe/h58GgZzPfelDMy9xKCGeuXhYHAV2BTRHysG17nLQ6yGpXYV3iXbPsGtl/0Ibbi3YnrIUjkFEB79nFD7jh6eRXdoFCdPa0bxY1096mJ+t9+bjXCvAk3SGzNg+i0EyfkejiDFXnJibOA==" Feb 19 01:10:47 volumio go-librespot[12334]: time="2026-02-19T01:10:47+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 19 01:10:47 volumio go-librespot[12334]: time="2026-02-19T01:10:47+07:00" level=debug msg="completed keyexchange" Feb 19 01:10:47 volumio go-librespot[12334]: time="2026-02-19T01:10:47+07:00" level=debug msg="completed challenge" Feb 19 01:10:47 volumio go-librespot[12334]: time="2026-02-19T01:10: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 19 01:10:47 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 19 01:10:47 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 19 01:10:47 volumio sudo[12356]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-19 01:09' Feb 19 01:10:47 volumio sudo[12356]: 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"