Feb 11 12:54:00 volumio volumio[12354]: info: Getting Spotify volume Feb 11 12:54:00 volumio volumio[12354]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 11 12:54:00 volumio volumio[12354]: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 11 12:54:00 volumio volumio[12354]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Feb 11 12:54:00 volumio volumio[12354]: errno: -111, Feb 11 12:54:00 volumio volumio[12354]: code: 'ECONNREFUSED', Feb 11 12:54:00 volumio volumio[12354]: syscall: 'connect', Feb 11 12:54:00 volumio volumio[12354]: address: '127.0.0.1', Feb 11 12:54:00 volumio volumio[12354]: port: 9879, Feb 11 12:54:00 volumio volumio[12354]: response: undefined Feb 11 12:54:00 volumio volumio[12354]: } Feb 11 12:54:00 volumio volumio[12354]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 11 12:54:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Feb 11 12:54:00 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 11 12:54:00 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 11 12:54:00 volumio go-librespot[12696]: go-librespot daemon starting... Feb 11 12:54:00 volumio go-librespot[12705]: time="2026-02-11T12:54:00+07:00" level=info msg="running go-librespot 0.4.0" Feb 11 12:54:00 volumio go-librespot[12705]: time="2026-02-11T12:54:00+07:00" level=debug msg="app state loaded" Feb 11 12:54:00 volumio go-librespot[12705]: time="2026-02-11T12:54:00+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 11 12:54:01 volumio go-librespot[12705]: time="2026-02-11T12:54: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-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 11 12:54:01 volumio go-librespot[12705]: time="2026-02-11T12:54:01+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 11 12:54:01 volumio go-librespot[12705]: time="2026-02-11T12:54:01+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 11 12:54:01 volumio go-librespot[12705]: time="2026-02-11T12:54:01+07:00" level=info msg="zeroconf server listening on port 34507" Feb 11 12:54:01 volumio go-librespot[12705]: time="2026-02-11T12:54:01+07:00" level=debug msg="obtained new client token: AAAxKNVd293q2AWJuA6g4UWs41cIVTGd48dLkKAlHyxzBq3tQFjC17bOsERlJOXLp0i/SFqIQsF4ij3mOeJtqPT+Dbx2TyqiVVJaf+4hs+EW17BoX061xd+1sJHuWz8CzfcfgvAl5MEWUx74DbSEIL722myCe8JqfGE8gODt1rfjyjCh85+SmNJmcB53IplCwpdoLCSgZZvcmflen+wKFzcoOPRTKeOJZSH41JLmQP6MEAjxncdb4iVUjw==" Feb 11 12:54:01 volumio go-librespot[12705]: time="2026-02-11T12:54:01+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 11 12:54:01 volumio go-librespot[12705]: time="2026-02-11T12:54:01+07:00" level=debug msg="completed keyexchange" Feb 11 12:54:01 volumio go-librespot[12705]: time="2026-02-11T12:54:01+07:00" level=debug msg="completed challenge" Feb 11 12:54:02 volumio go-librespot[12705]: time="2026-02-11T12:54:02+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 11 12:54:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 11 12:54:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 11 12:54:02 volumio sudo[12731]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-11 12:53' Feb 11 12:54:02 volumio sudo[12731]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 12:54:02 volumio sudo[12731]: pam_unix(sudo:session): session closed for user root Feb 11 12:54:04 volumio volumio-remote-updater[643]: [2026-02-11 12:54:03] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 11 12:54:04 volumio volumio-remote-updater[643]: [2026-02-11 12:54:03] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 11 12:54:04 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 11 12:54:04 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 11 12:54:04 volumio systemd[1]: volumio.service: Consumed 49.390s CPU time. Feb 11 12:54:04 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 11 12:54:04 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 11 12:54:04 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 11764. Feb 11 12:54:04 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 11 12:54:04 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 11 12:54:04 volumio systemd[1]: volumio.service: Consumed 49.390s CPU time. Feb 11 12:54:04 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Feb 11 12:54:04 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 11 12:54:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Feb 11 12:54:05 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 11 12:54:05 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 11 12:54:05 volumio go-librespot[12762]: go-librespot daemon starting... Feb 11 12:54:05 volumio go-librespot[12763]: time="2026-02-11T12:54:05+07:00" level=info msg="running go-librespot 0.4.0" Feb 11 12:54:05 volumio go-librespot[12763]: time="2026-02-11T12:54:05+07:00" level=debug msg="app state loaded" Feb 11 12:54:05 volumio go-librespot[12763]: time="2026-02-11T12:54:05+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 11 12:54:05 volumio go-librespot[12763]: time="2026-02-11T12:54:05+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 11 12:54:05 volumio go-librespot[12763]: time="2026-02-11T12:54:05+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 11 12:54:05 volumio go-librespot[12763]: time="2026-02-11T12:54:05+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 11 12:54:05 volumio go-librespot[12763]: time="2026-02-11T12:54:05+07:00" level=info msg="zeroconf server listening on port 39653" Feb 11 12:54:05 volumio go-librespot[12763]: time="2026-02-11T12:54:05+07:00" level=debug msg="obtained new client token: AADB2QHIUydRxFr/kq4/j9TDwB3HMpHx7kLA3IxqUN5JVvM4GGrcc/mtzB1dxGWdjcEM38Y6F3T+3Njpwty5tMtifniOkt109XIvpQbYKaOXbhSEdywmdjBNco89XPShOBnrlbAP08KGSVKnnDxNqkczMfxdj5pGLOp5lze+ZYIQjNGr7YnvAS8URHocyZHFD4vOi6vMB81AURwYl9NRu0ciIRYTyEd740uf77UTklXXXmoqTU0u0b7qxA==" Feb 11 12:54:05 volumio go-librespot[12763]: time="2026-02-11T12:54:05+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 11 12:54:06 volumio go-librespot[12763]: time="2026-02-11T12:54:06+07:00" level=debug msg="completed keyexchange" Feb 11 12:54:06 volumio go-librespot[12763]: time="2026-02-11T12:54:06+07:00" level=debug msg="completed challenge" Feb 11 12:54:06 volumio go-librespot[12763]: time="2026-02-11T12:54:06+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 11 12:54:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 11 12:54:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 11 12:54:07 volumio volumio[12747]: info: ------------------------------------------- Feb 11 12:54:07 volumio volumio[12747]: info: ----- Volumio3 ---- Feb 11 12:54:07 volumio volumio[12747]: info: ------------------------------------------- Feb 11 12:54:07 volumio volumio[12747]: info: ----- System startup ---- Feb 11 12:54:07 volumio volumio[12747]: info: ------------------------------------------- Feb 11 12:54:09 volumio volumio-remote-updater[643]: [2026-02-11 12:54:08] [connect] Successful connection Feb 11 12:54:09 volumio volumio[12747]: info: MYVOLUMIO Environment detected Feb 11 12:54:09 volumio volumio[12747]: info: Plugin folders cleanup Feb 11 12:54:09 volumio volumio[12747]: info: Scanning into folder /volumio/app/plugins/ Feb 11 12:54:09 volumio volumio[12747]: info: Scanning category audio_interface Feb 11 12:54:09 volumio volumio[12747]: info: Scanning category miscellanea Feb 11 12:54:09 volumio volumio[12747]: info: Scanning category music_service Feb 11 12:54:09 volumio volumio[12747]: info: Scanning category plugins.json Feb 11 12:54:09 volumio volumio[12747]: info: Scanning category system_controller Feb 11 12:54:09 volumio volumio[12747]: info: Scanning category user_interface Feb 11 12:54:09 volumio volumio[12747]: info: Scanning into folder /data/plugins/ Feb 11 12:54:09 volumio volumio[12747]: info: Scanning category music_service Feb 11 12:54:09 volumio volumio[12747]: info: Plugin folders cleanup completed Feb 11 12:54:09 volumio volumio[12747]: info: ------------------------------------------- Feb 11 12:54:09 volumio volumio[12747]: info: ----- Core plugins startup ---- Feb 11 12:54:09 volumio volumio[12747]: info: ------------------------------------------- Feb 11 12:54:09 volumio volumio[12747]: info: Loading plugins from folder /volumio/app/plugins/ Feb 11 12:54:09 volumio volumio[12747]: info: Adding plugin upnp to MyMusic Plugins Feb 11 12:54:09 volumio volumio[12747]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 11 12:54:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Feb 11 12:54:09 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 11 12:54:09 volumio volumio[12747]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 11 12:54:09 volumio volumio[12747]: info: Loading plugins from folder /data/plugins/ Feb 11 12:54:09 volumio volumio[12747]: info: Loading plugin "system"... Feb 11 12:54:09 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 11 12:54:09 volumio go-librespot[12780]: go-librespot daemon starting... Feb 11 12:54:09 volumio volumio[12747]: info: Loading plugin "appearance"... Feb 11 12:54:09 volumio go-librespot[12782]: time="2026-02-11T12:54:09+07:00" level=info msg="running go-librespot 0.4.0" Feb 11 12:54:09 volumio go-librespot[12782]: time="2026-02-11T12:54:09+07:00" level=debug msg="app state loaded" Feb 11 12:54:09 volumio go-librespot[12782]: time="2026-02-11T12:54:09+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 11 12:54:09 volumio go-librespot[12782]: time="2026-02-11T12:54:09+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 11 12:54:09 volumio go-librespot[12782]: time="2026-02-11T12:54:09+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 11 12:54:09 volumio go-librespot[12782]: time="2026-02-11T12:54:09+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 11 12:54:09 volumio go-librespot[12782]: time="2026-02-11T12:54:09+07:00" level=info msg="zeroconf server listening on port 35265" Feb 11 12:54:10 volumio go-librespot[12782]: time="2026-02-11T12:54:10+07:00" level=debug msg="obtained new client token: AACB0UW8F6/4oxBcSS7wnq81CgKecFZHlazYywMgjceRf5Xe1ARtbWePVhZAEFlyoFGDw4a9WPBOn7zaxuPATVthahx6bUVZsPqsp9hYGmEoQ5k7TWPaLCp5l3WDh7sJMwOkSTKtJrDOabFGMMEfrp0qYOwN1+tfRPTEHd1XrTasZMGRh6fJ7JFHaoyVsB/q+4OUsZImpa0eqmo8HyG9BlsbE0oG5I24QzieV5xJ5quPLBBb0zEjLB8=" Feb 11 12:54:10 volumio go-librespot[12782]: time="2026-02-11T12:54:10+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 11 12:54:10 volumio go-librespot[12782]: time="2026-02-11T12:54:10+07:00" level=debug msg="completed keyexchange" Feb 11 12:54:10 volumio go-librespot[12782]: time="2026-02-11T12:54:10+07:00" level=debug msg="completed challenge" Feb 11 12:54:10 volumio go-librespot[12782]: time="2026-02-11T12:54:10+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 11 12:54:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 11 12:54:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 11 12:54:11 volumio volumio[12747]: info: Loading plugin "network"... Feb 11 12:54:11 volumio volumio[12747]: info: Refreshing Cached IP Addresses Feb 11 12:54:11 volumio sudo[12793]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 11 12:54:11 volumio sudo[12793]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 12:54:11 volumio sudo[12793]: pam_unix(sudo:session): session closed for user root Feb 11 12:54:11 volumio sudo[12795]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 11 12:54:11 volumio sudo[12795]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 12:54:11 volumio volumio[12747]: info: Loading plugin "services"... Feb 11 12:54:11 volumio volumio[12747]: info: Loading plugin "alsa_controller"... Feb 11 12:54:11 volumio sudo[12795]: pam_unix(sudo:session): session closed for user root Feb 11 12:54:11 volumio sudo[12803]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 11 12:54:11 volumio sudo[12803]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 12:54:11 volumio volumio[12747]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 11 12:54:11 volumio volumio[12747]: info: Loading plugin "wizard"... Feb 11 12:54:11 volumio volumio[12747]: info: Loading plugin "networkfs"... Feb 11 12:54:11 volumio volumio[12747]: info: Starting Udev Watcher for removable devices Feb 11 12:54:11 volumio volumio[12747]: info: Ignoring mount for partition: boot Feb 11 12:54:11 volumio volumio[12747]: info: Ignoring mount for partition: volumio Feb 11 12:54:11 volumio volumio[12747]: info: Ignoring mount for partition: volumio_data Feb 11 12:54:11 volumio volumio[12747]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 11 12:54:11 volumio volumio[12747]: info: Loading plugin "volumio_command_line_client"... Feb 11 12:54:11 volumio volumio[12747]: info: Loading plugin "upnp"... Feb 11 12:54:11 volumio volumio[12747]: info: [1770789251775] Starting Upmpd Daemon Feb 11 12:54:11 volumio volumio[12747]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 11 12:54:11 volumio volumio[12747]: info: Loading plugin "my_music"... Feb 11 12:54:11 volumio volumio[12747]: info: Loading plugin "mpd"... Feb 11 12:54:12 volumio volumio[12747]: info: Loading plugin "upnp_browser"... Feb 11 12:54:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Feb 11 12:54:13 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 11 12:54:13 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 11 12:54:13 volumio go-librespot[12827]: go-librespot daemon starting... Feb 11 12:54:13 volumio go-librespot[12828]: time="2026-02-11T12:54:13+07:00" level=info msg="running go-librespot 0.4.0" Feb 11 12:54:13 volumio go-librespot[12828]: time="2026-02-11T12:54:13+07:00" level=debug msg="app state loaded" Feb 11 12:54:13 volumio go-librespot[12828]: time="2026-02-11T12:54:13+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 11 12:54:14 volumio go-librespot[12828]: time="2026-02-11T12:54: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 11 12:54:14 volumio go-librespot[12828]: time="2026-02-11T12:54: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 11 12:54:14 volumio go-librespot[12828]: time="2026-02-11T12:54: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 11 12:54:14 volumio go-librespot[12828]: time="2026-02-11T12:54:14+07:00" level=info msg="zeroconf server listening on port 44983" Feb 11 12:54:14 volumio sudo[12803]: pam_unix(sudo:session): session closed for user root Feb 11 12:54:14 volumio go-librespot[12828]: time="2026-02-11T12:54:14+07:00" level=debug msg="obtained new client token: AACthrRkHLXpgEcfm3srL4NgB9IG8xlJXrmbtgqycrhCz4EFKsBZ1r0QPuv2wA5G+VVum5T2fdxeWZ7auU3QA2IfqgHA8kOBiMn8mjRQ+H32kSHgBo4VIVh1+MBpgaFykJDddqiI9J1OXMjl0OunRxW9OL0OjWno5gqUPKcINw/1VMUPaR0mu4Ua0+zT6v0jgXyBHz/Zk16MEpsPPe53ce60I245JA5sBV59GBL8s8lwPQqH4ywERuKL2Q==" Feb 11 12:54:14 volumio go-librespot[12828]: time="2026-02-11T12:54:14+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 11 12:54:15 volumio go-librespot[12828]: time="2026-02-11T12:54:15+07:00" level=debug msg="completed keyexchange" Feb 11 12:54:15 volumio go-librespot[12828]: time="2026-02-11T12:54:15+07:00" level=debug msg="completed challenge" Feb 11 12:54:15 volumio volumio[12747]: info: Starting UPNP Browser Feb 11 12:54:15 volumio volumio[12747]: info: Loading plugin "alarm-clock"... Feb 11 12:54:15 volumio go-librespot[12828]: time="2026-02-11T12:54:15+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 11 12:54:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 11 12:54:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 11 12:54:15 volumio volumio[12747]: info: Loading plugin "airplay_emulation"... Feb 11 12:54:15 volumio volumio[12747]: info: Starting Shairport Sync Feb 11 12:54:15 volumio volumio[12747]: info: Loading plugin "last_100"... Feb 11 12:54:15 volumio volumio[12747]: info: Loading plugin "webradio"... Feb 11 12:54:15 volumio volumio[12747]: info: Loading plugin "i2s_dacs"... Feb 11 12:54:15 volumio volumio[12747]: info: Loading plugin "volumiodiscovery"... Feb 11 12:54:16 volumio volumio[12747]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 11 12:54:16 volumio volumio[12747]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 11 12:54:16 volumio node[12747]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 11 12:54:16 volumio volumio[12747]: *** WARNING *** For more information see Feb 11 12:54:16 volumio volumio[12747]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 11 12:54:16 volumio volumio[12747]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 11 12:54:16 volumio volumio[12747]: *** WARNING *** For more information see Feb 11 12:54:16 volumio node[12747]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 11 12:54:16 volumio node[12747]: *** WARNING *** For more information see Feb 11 12:54:16 volumio node[12747]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 11 12:54:16 volumio node[12747]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 11 12:54:16 volumio node[12747]: *** WARNING *** For more information see Feb 11 12:54:16 volumio volumio[12747]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 11 12:54:16 volumio volumio[12747]: info: Discovery: Started advertising with name: Volumio Feb 11 12:54:16 volumio volumio[12747]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 11 12:54:16 volumio volumio[12747]: info: Loading plugin "spop"... Feb 11 12:54:17 volumio volumio[12747]: info: Loading plugin "ytcr"... Feb 11 12:54:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Feb 11 12:54:18 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 11 12:54:18 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 11 12:54:18 volumio go-librespot[12837]: go-librespot daemon starting... Feb 11 12:54:18 volumio go-librespot[12838]: time="2026-02-11T12:54:18+07:00" level=info msg="running go-librespot 0.4.0" Feb 11 12:54:18 volumio go-librespot[12838]: time="2026-02-11T12:54:18+07:00" level=debug msg="app state loaded" Feb 11 12:54:18 volumio go-librespot[12838]: time="2026-02-11T12:54:18+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 11 12:54:19 volumio go-librespot[12838]: time="2026-02-11T12:54:19+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 11 12:54:19 volumio go-librespot[12838]: time="2026-02-11T12:54:19+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 11 12:54:19 volumio go-librespot[12838]: time="2026-02-11T12:54:19+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 11 12:54:19 volumio go-librespot[12838]: time="2026-02-11T12:54:19+07:00" level=info msg="zeroconf server listening on port 41903" Feb 11 12:54:19 volumio go-librespot[12838]: time="2026-02-11T12:54:19+07:00" level=debug msg="obtained new client token: AADBZqB7MbgpcuIBBwsY9geYaTzDc1EOTLM5lU5s2CRMM7HQMxglw1DSKch2nsDdc0D0DkSKBUed/R29EhOPtefxSkzKRTieNmSmv/xaB7Vuwbq9n0ecj/MdHtk42767+rEk8be2+Eeq7+Dol1SNJVBgs8NW52TJGSeiSXerEYkmlSTgJxqfam0aXjxGrmqgtvqOrQ/5SIk0o8uRUwhYxTy8Gnu5VntyOTdf5qY1zT+SCnmrClRJUzke4Q==" Feb 11 12:54:19 volumio go-librespot[12838]: time="2026-02-11T12:54:19+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 11 12:54:19 volumio go-librespot[12838]: time="2026-02-11T12:54:19+07:00" level=debug msg="completed keyexchange" Feb 11 12:54:19 volumio go-librespot[12838]: time="2026-02-11T12:54:19+07:00" level=debug msg="completed challenge" Feb 11 12:54:19 volumio go-librespot[12838]: time="2026-02-11T12:54: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 11 12:54:19 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 11 12:54:19 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 11 12:54:20 volumio volumio[12747]: info: Loading plugin "ytmusic"... Feb 11 12:54:21 volumio volumio-remote-updater[643]: [2026-02-11 12:54:21] [connect] Successful connection Feb 11 12:54:21 volumio volumio[12747]: info: Loading plugin "outputs"... Feb 11 12:54:21 volumio volumio[12747]: info: Loading plugin "albumart"... Feb 11 12:54:21 volumio volumio[12747]: info: Plugin example_plugin is not enabled Feb 11 12:54:21 volumio volumio[12747]: info: Loading plugin "inputs"... Feb 11 12:54:21 volumio volumio[12747]: info: Loading plugin "updater_comm"... Feb 11 12:54:21 volumio volumio[12747]: info: Plugin mpdemulation is not enabled Feb 11 12:54:21 volumio volumio[12747]: info: Loading plugin "rest_api"... Feb 11 12:54:22 volumio volumio[12747]: info: Loading plugin "websocket"... Feb 11 12:54:22 volumio volumio[12747]: info: Starting Socket.io Server version 1.7.4 Feb 11 12:54:22 volumio volumio[12747]: info: Loading plugin "RoonBridge"... Feb 11 12:54:22 volumio volumio[12747]: info: Applying required configuration parameters for plugin RoonBridge Feb 11 12:54:22 volumio volumio[12747]: info: Loading i18n strings for locale en Feb 11 12:54:22 volumio volumio[12747]: Updating browse sources language Feb 11 12:54:22 volumio volumio[12747]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 12:54:22 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Feb 11 12:54:22 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 11 12:54:22 volumio volumio[12747]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 11 12:54:22 volumio volumio[12747]: info: CoreCommandRouter::initPlayerControls Feb 11 12:54:22 volumio volumio[12747]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 12:54:22 volumio volumio[12747]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 12:54:22 volumio volumio[12747]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 12:54:22 volumio volumio[12747]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 12:54:22 volumio volumio[12747]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 12:54:22 volumio volumio[12747]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 12:54:22 volumio volumio[12747]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 12:54:22 volumio volumio[12747]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 12:54:22 volumio volumio[12860]: Forking 3 albumart workers Feb 11 12:54:22 volumio volumio[12747]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 11 12:54:22 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 11 12:54:22 volumio volumio[12747]: Express server listening on port 3000 Feb 11 12:54:22 volumio volumio[12747]: [Metrics] WebUI: 16s 34.89ms Feb 11 12:54:22 volumio go-librespot[12870]: go-librespot daemon starting... Feb 11 12:54:22 volumio volumio[12747]: info: CoreStateMachine::resetVolumioState Feb 11 12:54:22 volumio volumio[12747]: info: CoreStateMachine::getcurrentVolume Feb 11 12:54:22 volumio volumio[12747]: info: CoreCommandRouter::volumioRetrievevolume Feb 11 12:54:22 volumio go-librespot[12872]: time="2026-02-11T12:54:22+07:00" level=info msg="running go-librespot 0.4.0" Feb 11 12:54:22 volumio go-librespot[12872]: time="2026-02-11T12:54:22+07:00" level=debug msg="app state loaded" Feb 11 12:54:22 volumio go-librespot[12872]: time="2026-02-11T12:54:22+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 11 12:54:23 volumio sudo[12909]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 11 12:54:23 volumio sudo[12909]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 12:54:23 volumio sudo[12909]: pam_unix(sudo:session): session closed for user root Feb 11 12:54:23 volumio sudo[12911]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 11 12:54:23 volumio sudo[12911]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 12:54:23 volumio sudo[12911]: pam_unix(sudo:session): session closed for user root Feb 11 12:54:23 volumio volumio[12747]: info: Volumio Network Manager: Network status updated: 1 Feb 11 12:54:23 volumio go-librespot[12872]: time="2026-02-11T12:54:23+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 11 12:54:23 volumio go-librespot[12872]: time="2026-02-11T12:54:23+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 11 12:54:23 volumio go-librespot[12872]: time="2026-02-11T12:54:23+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 11 12:54:23 volumio go-librespot[12872]: time="2026-02-11T12:54:23+07:00" level=info msg="zeroconf server listening on port 36999" Feb 11 12:54:23 volumio volumio[12747]: info: VolumeController:: Volume=100 Mute =false Feb 11 12:54:23 volumio volumio[12747]: info: CoreStateMachine::pushState Feb 11 12:54:23 volumio volumio[12747]: info: CorePlayQueue::getTrack 0 Feb 11 12:54:23 volumio volumio[12747]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 12:54:23 volumio volumio[12747]: info: CoreCommandRouter::volumioPushState Feb 11 12:54:23 volumio volumio[12747]: info: CoreStateMachine::updateTrackBlock Feb 11 12:54:23 volumio volumio[12747]: info: CorePlayQueue::getTrackBlock Feb 11 12:54:23 volumio volumio[12747]: info: CoreCommandRouter::volumioRetrievevolume Feb 11 12:54:23 volumio volumio-remote-updater[643]: [2026-02-11 12:54:23] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1770789261 101 Feb 11 12:54:23 volumio volumio[12747]: 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 11 12:54:23 volumio go-librespot[12872]: time="2026-02-11T12:54:23+07:00" level=debug msg="obtained new client token: AAD+ypeZfBkL1nPZhftY/0qqs45PmFoZgJiHkl9IL11XS7OSBVGdEaeaijofRUeLGsYfAPN90BtC90sJKLtkVHJPH1QiymRCdQ/spzyoxTlsi6Qyc5KtpoY8/Q0+GH/iYRo6aE/pob+ltVO1ZCHag4cSkKZut/Dr/+B0+cK8DhPlQn0tFrYGr1eHXVjyf7+9+Rvp01FB4lUoAeZQnjKcKDGVM9yhL5oFg9C7yqMqIVNVXet9+HmPJXGfpw==" Feb 11 12:54:23 volumio volumio[12747]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 12:54:23 volumio volumio[12747]: info: Reloading queue from file Feb 11 12:54:23 volumio volumio[12747]: info: CoreStateMachine::setRepeat null single undefined Feb 11 12:54:23 volumio volumio[12747]: info: CoreStateMachine::pushState Feb 11 12:54:23 volumio volumio[12747]: info: CorePlayQueue::getTrack 0 Feb 11 12:54:23 volumio volumio[12747]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 12:54:24 volumio volumio[12747]: info: CoreCommandRouter::volumioPushState Feb 11 12:54:24 volumio go-librespot[12872]: time="2026-02-11T12:54:24+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 11 12:54:24 volumio volumio[12747]: info: CoreStateMachine::setRandom null Feb 11 12:54:24 volumio volumio[12747]: info: CoreStateMachine::pushState Feb 11 12:54:24 volumio volumio[12747]: info: CorePlayQueue::getTrack 0 Feb 11 12:54:24 volumio volumio[12747]: info: CoreCommandRouter::volumioPushState Feb 11 12:54:24 volumio volumio[12747]: info: Setting Device type: Raspberry PI Feb 11 12:54:24 volumio volumio[12747]: info: Completed loading Core Plugins Feb 11 12:54:24 volumio volumio[12747]: info: Preparing to generate the ALSA configuration file Feb 11 12:54:24 volumio go-librespot[12872]: time="2026-02-11T12:54:24+07:00" level=debug msg="completed keyexchange" Feb 11 12:54:24 volumio go-librespot[12872]: time="2026-02-11T12:54:24+07:00" level=debug msg="completed challenge" Feb 11 12:54:24 volumio volumio[12747]: info: VolumeController:: Volume=100 Mute =false Feb 11 12:54:24 volumio volumio[12747]: info: CoreStateMachine::pushState Feb 11 12:54:24 volumio volumio[12747]: info: CorePlayQueue::getTrack 0 Feb 11 12:54:24 volumio volumio[12747]: info: CoreCommandRouter::volumioPushState Feb 11 12:54:24 volumio go-librespot[12872]: time="2026-02-11T12:54:24+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 11 12:54:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 11 12:54:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 11 12:54:24 volumio volumio[12747]: info: Asound.conf file unchanged, so no further update is needed Feb 11 12:54:24 volumio volumio[12747]: info: Output device has changed, restarting MPD Feb 11 12:54:24 volumio volumio[12747]: info: Output device has changed, restarting Shairport Sync Feb 11 12:54:24 volumio volumio[12747]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 12:54:24 volumio volumio[12747]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 12:54:24 volumio sudo[12931]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 11 12:54:24 volumio sudo[12931]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 12:54:24 volumio sudo[12933]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 11 12:54:24 volumio sudo[12933]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 12:54:24 volumio sudo[12933]: pam_unix(sudo:session): session closed for user root Feb 11 12:54:24 volumio sudo[12935]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 11 12:54:24 volumio sudo[12935]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 12:54:24 volumio volumio[12747]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 11 12:54:24 volumio volumio[12747]: info: ___________ START PLUGINS ___________ Feb 11 12:54:24 volumio volumio[12747]: info: ControllerMpd::onStart: Initializing MPD Feb 11 12:54:24 volumio volumio[12747]: info: Creating MPD Configuration file Feb 11 12:54:24 volumio sudo[12931]: pam_unix(sudo:session): session closed for user root Feb 11 12:54:24 volumio volumio[12747]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 11 12:54:24 volumio volumio[12747]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 11 12:54:24 volumio volumio[12747]: info: [1770789264595] CoreMusicLibrary::Adding element Media Servers Feb 11 12:54:24 volumio volumio[12747]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 12:54:24 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 11 12:54:24 volumio volumio[12747]: info: UPNP Browser: Client initialized successfully Feb 11 12:54:24 volumio sudo[12944]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 11 12:54:24 volumio sudo[12944]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 12:54:24 volumio sudo[12944]: pam_unix(sudo:session): session closed for user root Feb 11 12:54:24 volumio sudo[12946]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 11 12:54:24 volumio sudo[12946]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 12:54:24 volumio volumio[12747]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 12:54:24 volumio volumio[12747]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 12:54:24 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 11 12:54:24 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 11 12:54:24 volumio systemd[1]: mpd.service: Consumed 7.178s CPU time. Feb 11 12:54:24 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 11 12:54:24 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 11 12:54:24 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 11 12:54:24 volumio volumio[12747]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 11 12:54:24 volumio volumio[12747]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 12:54:24 volumio volumio[12747]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 12:54:24 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 11 12:54:24 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 11 12:54:24 volumio volumio[12747]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 11 12:54:24 volumio volumio[12747]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 11 12:54:24 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 11 12:54:24 volumio volumio[12747]: info: [1770789264947] CoreMusicLibrary::Adding element Last_100 Feb 11 12:54:24 volumio volumio[12747]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 12:54:24 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 11 12:54:24 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 11 12:54:24 volumio volumio[12747]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 11 12:54:24 volumio volumio[12747]: info: [1770789264965] CoreMusicLibrary::Adding element Webradio Feb 11 12:54:24 volumio volumio[12747]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 12:54:24 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 11 12:54:24 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 11 12:54:24 volumio volumio[12747]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 11 12:54:24 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 11 12:54:24 volumio volumio[12747]: info: Initializing BBC Radios Feb 11 12:54:25 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 11 12:54:25 volumio volumio[12747]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 11 12:54:25 volumio volumio[12747]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 12:54:25 volumio volumio[12747]: info: Creating Spotify config file Feb 11 12:54:25 volumio volumio[12747]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 12:54:25 volumio sudo[12962]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 11 12:54:25 volumio sudo[12962]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 11 12:54:25 volumio sudo[12962]: pam_unix(sudo:session): session closed for user root Feb 11 12:54:25 volumio volumio[12871]: Starting albumart workers Feb 11 12:54:26 volumio volumio[12874]: Starting albumart workers Feb 11 12:54:26 volumio volumio[12747]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 11 12:54:26 volumio volumio[12747]: info: [1770789266423] CoreMusicLibrary::Adding element YouTube Music Feb 11 12:54:26 volumio volumio[12747]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 12:54:26 volumio volumio[12747]: Cannot find translation for source YouTube Music Feb 11 12:54:26 volumio volumio[12747]: info: Volumio Calling Home Feb 11 12:54:26 volumio sudo[12977]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Feb 11 12:54:26 volumio sudo[12977]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 12:54:26 volumio volumio[12875]: Starting albumart workers Feb 11 12:54:26 volumio sudo[12977]: pam_unix(sudo:session): session closed for user root Feb 11 12:54:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Feb 11 12:54:27 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 11 12:54:27 volumio volumio[12747]: info: Discovery: adding 921648ed-0557-4d70-9927-1420682efcc7 Feb 11 12:54:27 volumio volumio[12747]: info: Discovery: Found device Volumio Feb 11 12:54:27 volumio volumio[12747]: info: CoreCommandRouter::volumioGetState Feb 11 12:54:27 volumio volumio[12747]: info: CorePlayQueue::getTrack 0 Feb 11 12:54:27 volumio volumio[12747]: info: Discovery: this is already registered, 921648ed-0557-4d70-9927-1420682efcc7 Feb 11 12:54:27 volumio volumio[12747]: info: Discovery: Found device Volumio Feb 11 12:54:27 volumio volumio[12747]: info: CoreCommandRouter::volumioGetState Feb 11 12:54:27 volumio volumio[12747]: info: CorePlayQueue::getTrack 0 Feb 11 12:54:27 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 11 12:54:27 volumio go-librespot[12982]: go-librespot daemon starting... Feb 11 12:54:27 volumio volumio[12747]: info: MPD Permissions set Feb 11 12:54:27 volumio volumio[12747]: info: MPD Permissions set Feb 11 12:54:27 volumio volumio[12747]: info: Upmpdcli Daemon Started Feb 11 12:54:27 volumio go-librespot[12983]: time="2026-02-11T12:54:27+07:00" level=info msg="running go-librespot 0.4.0" Feb 11 12:54:27 volumio go-librespot[12983]: time="2026-02-11T12:54:27+07:00" level=debug msg="app state loaded" Feb 11 12:54:27 volumio go-librespot[12983]: time="2026-02-11T12:54:27+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 11 12:54:27 volumio volumio[12747]: info: Spotify config file written Feb 11 12:54:27 volumio sudo[12990]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 11 12:54:27 volumio sudo[12990]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 12:54:28 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Feb 11 12:54:28 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Feb 11 12:54:28 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 11 12:54:28 volumio volumio[12747]: 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 11 12:54:28 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 11 12:54:28 volumio volumio[12747]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 12:54:28 volumio volumio[12747]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 12:54:28 volumio volumio[12747]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 12:54:28 volumio go-librespot[12999]: go-librespot daemon starting... Feb 11 12:54:28 volumio volumio[12747]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 12:54:28 volumio volumio[12747]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 12:54:28 volumio volumio[12747]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 12:54:28 volumio sudo[12990]: pam_unix(sudo:session): session closed for user root Feb 11 12:54:28 volumio volumio[12747]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 12:54:28 volumio volumio[12747]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 12:54:28 volumio volumio[12747]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 12:54:28 volumio volumio[12747]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 12:54:28 volumio volumio[12747]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 12:54:28 volumio volumio[12747]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 12:54:28 volumio volumio[12747]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 12:54:28 volumio volumio[12747]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 12:54:28 volumio volumio[12747]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 12:54:28 volumio volumio[12747]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 12:54:28 volumio volumio[12747]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 12:54:28 volumio volumio[12747]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 12:54:28 volumio go-librespot[13000]: time="2026-02-11T12:54:28+07:00" level=info msg="running go-librespot 0.4.0" Feb 11 12:54:28 volumio go-librespot[13000]: time="2026-02-11T12:54:28+07:00" level=debug msg="app state loaded" Feb 11 12:54:28 volumio go-librespot[13000]: time="2026-02-11T12:54:28+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 11 12:54:28 volumio volumio[12747]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 12:54:28 volumio volumio[12747]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 12:54:28 volumio volumio[12747]: info: No need to fix Spotify hosts Feb 11 12:54:28 volumio volumio[12747]: info: Volumio called home Feb 11 12:54:29 volumio go-librespot[13000]: time="2026-02-11T12:54: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-gew1.spotify.com:80]" Feb 11 12:54:29 volumio go-librespot[13000]: time="2026-02-11T12:54:29+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 11 12:54:29 volumio go-librespot[13000]: time="2026-02-11T12:54:29+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 11 12:54:29 volumio go-librespot[13000]: time="2026-02-11T12:54:29+07:00" level=info msg="zeroconf server listening on port 44505" Feb 11 12:54:29 volumio volumio[12747]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 11 12:54:29 volumio volumio[12747]: SPOTIFY: BQCsmkoYZ8-7RsbF7_1zYq9wavFrWIj-DeTRlbVVkTyypDCzkqHlQiIlqt3sD4xwBHdLURVLWzq04IUjDfkpXphJhXm27GMvqUyRyZhMFp3wAHY1C5OD4SbHT5axQb92svHq_GqNmpxRfN1AghPATblRfU-zFmzCS193d40TCvDKs12tzJG5qoDx-QepoYvt0DbxN1L3btzp8GAgytwvUVdN29A2VqVKra7oIofLYGMTv0nKjr_7V_PZ9YOma6FRIemHZW-WU0zxkt00az2v07iq9Y3tYugRXO6Lmkg-VBBsqpXmvv0C1k8O Feb 11 12:54:29 volumio volumio[12747]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 11 12:54:29 volumio volumio[12747]: info: New Spotify access token = BQCsmkoYZ8-7RsbF7_1zYq9wavFrWIj-DeTRlbVVkTyypDCzkqHlQiIlqt3sD4xwBHdLURVLWzq04IUjDfkpXphJhXm27GMvqUyRyZhMFp3wAHY1C5OD4SbHT5axQb92svHq_GqNmpxRfN1AghPATblRfU-zFmzCS193d40TCvDKs12tzJG5qoDx-QepoYvt0DbxN1L3btzp8GAgytwvUVdN29A2VqVKra7oIofLYGMTv0nKjr_7V_PZ9YOma6FRIemHZW-WU0zxkt00az2v07iq9Y3tYugRXO6Lmkg-VBBsqpXmvv0C1k8O Feb 11 12:54:29 volumio volumio[12747]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 11 12:54:29 volumio volumio[12747]: info: Starting Shairport Sync Feb 11 12:54:29 volumio go-librespot[13000]: time="2026-02-11T12:54:29+07:00" level=debug msg="obtained new client token: AACCX/eaonVq1P6LKFmB71MOBfWb1CyOxoubFe6cRAT8sP2whiUfr7slptiP/AMGA1ZcKKH9M03IZRPpGKssnqGovKyWKyejNj6zikkye3ak5ESfJBCSLiDCX/UC7oqE4iwz8aeNiqcVjIgqZLPiH7F9fYTh4hpGB8/6X7dk5I+zHSSH1VYz2l0M6bSD6alKx//OaZC9ITbnMOG0KquhcQ85el2FJJ+XMD+bLeLPuMIOHFdW0WE70s1cbg==" Feb 11 12:54:29 volumio volumio[12747]: info: Starting Shairport Sync Feb 11 12:54:29 volumio sudo[13031]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 11 12:54:29 volumio volumio[12747]: info: Starting Shairport Sync Feb 11 12:54:29 volumio sudo[13031]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 12:54:29 volumio go-librespot[13000]: time="2026-02-11T12:54:29+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 11 12:54:29 volumio sudo[13033]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 11 12:54:29 volumio sudo[13033]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 12:54:29 volumio sudo[13036]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 11 12:54:29 volumio sudo[13036]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 12:54:29 volumio go-librespot[13000]: time="2026-02-11T12:54:29+07:00" level=debug msg="completed keyexchange" Feb 11 12:54:29 volumio go-librespot[13000]: time="2026-02-11T12:54:29+07:00" level=debug msg="completed challenge" Feb 11 12:54:29 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 11 12:54:29 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 11 12:54:29 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 11 12:54:29 volumio systemd[1]: shairport-sync.service: Consumed 2.237s CPU time. Feb 11 12:54:29 volumio go-librespot[13000]: time="2026-02-11T12:54:29+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 11 12:54:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 11 12:54:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 11 12:54:29 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 11 12:54:29 volumio sudo[13033]: pam_unix(sudo:session): session closed for user root Feb 11 12:54:29 volumio sudo[13031]: pam_unix(sudo:session): session closed for user root Feb 11 12:54:29 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 11 12:54:29 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 11 12:54:29 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 11 12:54:29 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 11 12:54:29 volumio sudo[13036]: pam_unix(sudo:session): session closed for user root Feb 11 12:54:29 volumio volumio[12747]: info: CoreCommandRouter::volumioGetState Feb 11 12:54:29 volumio volumio[12747]: info: CorePlayQueue::getTrack 0 Feb 11 12:54:29 volumio volumio[12747]: info: Shairport-Sync Started Feb 11 12:54:29 volumio volumio[12747]: Error adding Membership: Error: addMembership EINVAL Feb 11 12:54:29 volumio volumio[12747]: info: Shairport-Sync Started Feb 11 12:54:30 volumio volumio[12747]: info: Shairport-Sync Started Feb 11 12:54:30 volumio volumio[12747]: 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 11 12:54:30 volumio volumio[12747]: info: Spotify Successfully logged in Feb 11 12:54:30 volumio volumio[12747]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 11 12:54:30 volumio volumio[12747]: info: [1770789270178] CoreMusicLibrary::Adding element Spotify Feb 11 12:54:30 volumio volumio[12747]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 12:54:30 volumio volumio[12747]: Cannot find translation for source YouTube Music Feb 11 12:54:30 volumio volumio[12747]: Cannot find translation for source Spotify Feb 11 12:54:30 volumio volumio[12747]: info: [yt-cast-receiver] DIAL server listening on port 8098 Feb 11 12:54:30 volumio volumio[12747]: info: CoreCommandRouter::volumioRetrievevolume Feb 11 12:54:31 volumio volumio[12747]: info: VolumeController:: Volume=100 Mute =false Feb 11 12:54:31 volumio volumio[12747]: info: CoreCommandRouter::volumioGetState Feb 11 12:54:31 volumio volumio[12747]: info: CorePlayQueue::getTrack 0 Feb 11 12:54:31 volumio volumio[12747]: info: CoreStateMachine::pushState Feb 11 12:54:31 volumio volumio[12747]: info: CorePlayQueue::getTrack 0 Feb 11 12:54:31 volumio volumio[12747]: info: CoreCommandRouter::volumioPushState Feb 11 12:54:32 volumio volumio[12747]: info: go-librespot daemon successfully initialized Feb 11 12:54:32 volumio mpd[12975]: 2026-02-11T12:54:32 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 11 12:54:32 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 11 12:54:32 volumio sudo[12946]: pam_unix(sudo:session): session closed for user root Feb 11 12:54:32 volumio sudo[12935]: pam_unix(sudo:session): session closed for user root Feb 11 12:54:32 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Feb 11 12:54:32 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 11 12:54:32 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 11 12:54:32 volumio go-librespot[13076]: go-librespot daemon starting... Feb 11 12:54:32 volumio go-librespot[13078]: time="2026-02-11T12:54:32+07:00" level=info msg="running go-librespot 0.4.0" Feb 11 12:54:32 volumio go-librespot[13078]: time="2026-02-11T12:54:32+07:00" level=debug msg="app state loaded" Feb 11 12:54:32 volumio go-librespot[13078]: time="2026-02-11T12:54:32+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 11 12:54:32 volumio volumio[12747]: error: MPD error: The expression evaluated to a falsy value: Feb 11 12:54:32 volumio volumio[12747]: assert.ok(self.idling) Feb 11 12:54:32 volumio volumio[12747]: error: The expression evaluated to a falsy value: Feb 11 12:54:32 volumio volumio[12747]: assert.ok(self.idling) Feb 11 12:54:32 volumio volumio[12747]: error: updateQueue error: null Feb 11 12:54:32 volumio volumio[12747]: info: MPD running with PID12975 Feb 11 12:54:32 volumio volumio[12747]: ,establishing connection Feb 11 12:54:32 volumio volumio[12747]: info: Completed starting Core Plugins Feb 11 12:54:32 volumio volumio[12747]: info: ------------------------------------------- Feb 11 12:54:32 volumio volumio[12747]: info: ----- MyVolumio plugins startup ---- Feb 11 12:54:32 volumio volumio[12747]: info: ------------------------------------------- Feb 11 12:54:32 volumio volumio[12747]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 11 12:54:33 volumio volumio[12747]: error: updateQueue error: null Feb 11 12:54:33 volumio go-librespot[13078]: time="2026-02-11T12:54: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-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 11 12:54:33 volumio go-librespot[13078]: time="2026-02-11T12:54:33+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 11 12:54:33 volumio go-librespot[13078]: time="2026-02-11T12:54:33+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 11 12:54:33 volumio go-librespot[13078]: time="2026-02-11T12:54:33+07:00" level=info msg="zeroconf server listening on port 38963" Feb 11 12:54:33 volumio go-librespot[13078]: time="2026-02-11T12:54:33+07:00" level=debug msg="obtained new client token: AACIQ3yao+q196hZ5pNbCpIys87s8StgbCSgLQG35uPayGG0hAsrfpGcx3ZSxwavYdHNVvJE1Q13ICGceQLpl9uBPZqDKGcdS7qEpJBDYSa2Nfy8y4LnyjPwjeonLbem8f9OSlK+TDtc7CQ3DegVECo01r8caaLoK4NTZrZnjo47Bu2/4dITTzpOCSmyHiySvVn9TsrjGFHDaEA5VVZ7P5Qhr3hycL6V7pt0v8lAyaliTd0g5P+vc8T59g==" Feb 11 12:54:33 volumio go-librespot[13078]: time="2026-02-11T12:54:33+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 11 12:54:33 volumio go-librespot[13078]: time="2026-02-11T12:54:33+07:00" level=debug msg="completed keyexchange" Feb 11 12:54:33 volumio go-librespot[13078]: time="2026-02-11T12:54:33+07:00" level=debug msg="completed challenge" Feb 11 12:54:33 volumio go-librespot[13078]: time="2026-02-11T12:54:33+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 11 12:54:33 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 11 12:54:33 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 11 12:54:35 volumio volumio[12747]: info: Initializing connection to go-librespot Websocket Feb 11 12:54:35 volumio volumio[12747]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 11 12:54:37 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Feb 11 12:54:37 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 11 12:54:37 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 11 12:54:37 volumio go-librespot[13085]: go-librespot daemon starting... Feb 11 12:54:37 volumio go-librespot[13086]: time="2026-02-11T12:54:37+07:00" level=info msg="running go-librespot 0.4.0" Feb 11 12:54:37 volumio go-librespot[13086]: time="2026-02-11T12:54:37+07:00" level=debug msg="app state loaded" Feb 11 12:54:37 volumio go-librespot[13086]: time="2026-02-11T12:54:37+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 11 12:54:37 volumio go-librespot[13086]: time="2026-02-11T12:54:37+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 11 12:54:37 volumio go-librespot[13086]: time="2026-02-11T12:54:37+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 11 12:54:37 volumio go-librespot[13086]: time="2026-02-11T12:54:37+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 11 12:54:37 volumio go-librespot[13086]: time="2026-02-11T12:54:37+07:00" level=info msg="zeroconf server listening on port 37437" Feb 11 12:54:37 volumio volumio[12747]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 11 12:54:37 volumio go-librespot[13086]: time="2026-02-11T12:54:37+07:00" level=debug msg="obtained new client token: AABehB5yUWNpEsHyMqrMVf+QSsdfprKdh5+3XfoLKtLvhv6qviIivh+CXD7EVHujuJTDKaapOFmqFbqWvFJlDIwUleUnfC7NiuewjsPhSbckoLKP0VI2ijHzF4Dfk2YoPD3oO8IPjUz8BvbevKaC3U9YVvuJyNdAIbgd9jANxv2qbmv4WzX6W8EOVrFxzgGAFsUViQ0fG2BkCKb6IoaeJW6MbqSY0A4y4ghFaOEbSnTB05dQ1ji8loxZTg==" Feb 11 12:54:38 volumio go-librespot[13086]: time="2026-02-11T12:54:38+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 11 12:54:38 volumio go-librespot[13086]: time="2026-02-11T12:54:38+07:00" level=debug msg="completed keyexchange" Feb 11 12:54:38 volumio go-librespot[13086]: time="2026-02-11T12:54:38+07:00" level=debug msg="completed challenge" Feb 11 12:54:38 volumio go-librespot[13086]: time="2026-02-11T12:54: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 11 12:54:38 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 11 12:54:38 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 11 12:54:38 volumio volumio[12747]: info: Initializing connection to go-librespot Websocket Feb 11 12:54:38 volumio volumio[12747]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 11 12:54:41 volumio volumio[12747]: info: Initializing connection to go-librespot Websocket Feb 11 12:54:41 volumio volumio[12747]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 11 12:54:41 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Feb 11 12:54:41 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 11 12:54:41 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 11 12:54:41 volumio go-librespot[13093]: go-librespot daemon starting... Feb 11 12:54:41 volumio go-librespot[13094]: time="2026-02-11T12:54:41+07:00" level=info msg="running go-librespot 0.4.0" Feb 11 12:54:41 volumio go-librespot[13094]: time="2026-02-11T12:54:41+07:00" level=debug msg="app state loaded" Feb 11 12:54:41 volumio go-librespot[13094]: time="2026-02-11T12:54:41+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 11 12:54:41 volumio volumio[12747]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 11 12:54:41 volumio volumio[12747]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 11 12:54:41 volumio volumio[12747]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 11 12:54:41 volumio volumio[12747]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 11 12:54:41 volumio volumio[12747]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 11 12:54:41 volumio volumio[12747]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 11 12:54:41 volumio volumio[12747]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 11 12:54:41 volumio volumio[12747]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 11 12:54:41 volumio volumio[12747]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 11 12:54:41 volumio volumio[12747]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 11 12:54:41 volumio volumio[12747]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 11 12:54:41 volumio volumio[12747]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 11 12:54:41 volumio volumio[12747]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 11 12:54:41 volumio volumio[12747]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 11 12:54:41 volumio volumio[12747]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 11 12:54:41 volumio volumio[12747]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 11 12:54:41 volumio volumio[12747]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 11 12:54:41 volumio volumio[12747]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 11 12:54:41 volumio volumio[12747]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 11 12:54:41 volumio volumio[12747]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 11 12:54:41 volumio volumio[12747]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 11 12:54:41 volumio volumio[12747]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 11 12:54:41 volumio volumio[12747]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 11 12:54:41 volumio volumio[12747]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 11 12:54:41 volumio volumio[12747]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 11 12:54:41 volumio volumio[12747]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 11 12:54:41 volumio volumio[12747]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 11 12:54:41 volumio volumio[12747]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 11 12:54:41 volumio volumio[12747]: info: Adding plugin bluetooth to MyMusic Plugins Feb 11 12:54:41 volumio volumio[12747]: info: Adding plugin multiroom to MyMusic Plugins Feb 11 12:54:41 volumio volumio[12747]: info: Adding plugin metavolumio to MyMusic Plugins Feb 11 12:54:41 volumio volumio[12747]: info: Adding plugin cd_controller to MyMusic Plugins Feb 11 12:54:41 volumio volumio[12747]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 11 12:54:41 volumio volumio[12747]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 11 12:54:41 volumio volumio[12747]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 11 12:54:41 volumio volumio[12747]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 11 12:54:41 volumio go-librespot[13094]: time="2026-02-11T12:54:41+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 11 12:54:41 volumio go-librespot[13094]: time="2026-02-11T12:54:41+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 11 12:54:41 volumio go-librespot[13094]: time="2026-02-11T12:54:41+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 11 12:54:41 volumio go-librespot[13094]: time="2026-02-11T12:54:41+07:00" level=info msg="zeroconf server listening on port 43061" Feb 11 12:54:42 volumio go-librespot[13094]: time="2026-02-11T12:54:42+07:00" level=debug msg="obtained new client token: AAAY64HnRz36B0JE5dk5D7fAcNazomWMG+Mv43PEdO5MOAcHe+adwva+QF2mX4JHlfhrGs6YmmGMLFw/Q3xE7IXsPJhw8t8d4mjBp/B0sLZhg0Y26sDwbTkVZkqcGUkPVuIEGCTjb6mQSf9HmIpM9qEnXdVvNCGvtbue2rEkP2L8XtsCTY80sPmcDVR8E7OZvsYUSB0aQ1auS5rz4BlKz16rK0zhYb1lGU2F/7BK8lq4X3lQz2wZ9l4=" Feb 11 12:54:42 volumio go-librespot[13094]: time="2026-02-11T12:54:42+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 11 12:54:42 volumio go-librespot[13094]: time="2026-02-11T12:54:42+07:00" level=debug msg="completed keyexchange" Feb 11 12:54:42 volumio go-librespot[13094]: time="2026-02-11T12:54:42+07:00" level=debug msg="completed challenge" Feb 11 12:54:42 volumio go-librespot[13094]: time="2026-02-11T12:54: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 11 12:54:42 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 11 12:54:42 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 11 12:54:43 volumio volumio[12747]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 11 12:54:43 volumio volumio[12747]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 11 12:54:43 volumio volumio[12747]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 12:54:43 volumio volumio[12747]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 12:54:43 volumio volumio[12747]: info: Starting MyVolumio Remote Streaming Endpoints Feb 11 12:54:43 volumio volumio[12747]: info: MyVolumio login type: Token Feb 11 12:54:43 volumio volumio[12747]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 11 12:54:43 volumio volumio[12747]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 11 12:54:45 volumio volumio[12747]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 11 12:54:45 volumio volumio[12747]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 11 12:54:45 volumio volumio[12747]: info: Streaming services startup Feb 11 12:54:45 volumio volumio[12747]: info: Starting Streaming Daemon Feb 11 12:54:45 volumio sudo[13118]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 11 12:54:45 volumio sudo[13118]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 12:54:45 volumio volumio[12747]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 11 12:54:45 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Feb 11 12:54:45 volumio sudo[13118]: pam_unix(sudo:session): session closed for user root Feb 11 12:54:45 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 11 12:54:45 volumio volumio[12747]: info: Initializing connection to go-librespot Websocket Feb 11 12:54:45 volumio volumio[12747]: error: Cannot start Volumio Streaming Daemon Feb 11 12:54:45 volumio volumio[12747]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 11 12:54:45 volumio volumio[12747]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 11 12:54:45 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 11 12:54:45 volumio go-librespot[13124]: go-librespot daemon starting... Feb 11 12:54:45 volumio volumio[12747]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 11 12:54:45 volumio go-librespot[13126]: time="2026-02-11T12:54:45+07:00" level=info msg="running go-librespot 0.4.0" Feb 11 12:54:45 volumio go-librespot[13126]: time="2026-02-11T12:54:45+07:00" level=debug msg="app state loaded" Feb 11 12:54:45 volumio go-librespot[13126]: time="2026-02-11T12:54:45+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 11 12:54:46 volumio go-librespot[13126]: time="2026-02-11T12:54: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 11 12:54:46 volumio go-librespot[13126]: time="2026-02-11T12:54:46+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 11 12:54:46 volumio go-librespot[13126]: time="2026-02-11T12:54:46+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 11 12:54:46 volumio go-librespot[13126]: time="2026-02-11T12:54:46+07:00" level=info msg="zeroconf server listening on port 39955" Feb 11 12:54:46 volumio go-librespot[13126]: time="2026-02-11T12:54:46+07:00" level=debug msg="obtained new client token: AADwugs0+GkDwoyRsq4hT1QMdn5OA52F4qArY9ZKLDw0R86HnDeMxz+1RN72l8IbkWoQecYNTPybdMmAGCAnT0DRblLXZv9TYFALro9nJO5cMVI0RLOQWvijpJohETUgGJLRSPVDaCOq4tZx6fzOBmPKlOVrlkDWdFUwiDjZ7WtNQ3PqIn59SYU/8FPxG3cmP6jCgDFDtqgnO1apdTg3ElyZkzDEvTlWiDSXxwKgSSKtxRm+bFGEyhCFgg==" Feb 11 12:54:46 volumio go-librespot[13126]: time="2026-02-11T12:54:46+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 11 12:54:46 volumio go-librespot[13126]: time="2026-02-11T12:54:46+07:00" level=debug msg="completed keyexchange" Feb 11 12:54:46 volumio go-librespot[13126]: time="2026-02-11T12:54:46+07:00" level=debug msg="completed challenge" Feb 11 12:54:46 volumio go-librespot[13126]: time="2026-02-11T12:54: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 11 12:54:46 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 11 12:54:46 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 11 12:54:46 volumio volumio[12747]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Feb 11 12:54:47 volumio volumio[12747]: info: MyVolumio token set successfully Feb 11 12:54:47 volumio volumio[12747]: info: MYVOLUMIO: Adding device Feb 11 12:54:47 volumio volumio[12747]: info: MYVOLUMIO: Evaluating Server Feb 11 12:54:48 volumio volumio[12747]: info: MyVolumio status changed Feb 11 12:54:48 volumio volumio[12747]: info: Streaming services startup Feb 11 12:54:48 volumio volumio[12747]: info: Starting Streaming Daemon Feb 11 12:54:48 volumio volumio[12747]: info: Removing browser output: myVolumio user plan is not superstar Feb 11 12:54:48 volumio volumio[12747]: info: Removing audio output: Feb 11 12:54:48 volumio volumio[12747]: info: Stoppping Tunnel 1 Feb 11 12:54:48 volumio sudo[13154]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 11 12:54:48 volumio sudo[13154]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 12:54:48 volumio sudo[13154]: pam_unix(sudo:session): session closed for user root Feb 11 12:54:48 volumio sudo[13157]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Feb 11 12:54:48 volumio sudo[13157]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 12:54:48 volumio volumio[12747]: error: Cannot start Volumio Streaming Daemon Feb 11 12:54:48 volumio volumio[12747]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 11 12:54:48 volumio volumio[12747]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 11 12:54:48 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 11 12:54:48 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 11 12:54:48 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 11 12:54:48 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 11 12:54:48 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 11 12:54:48 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 11 12:54:48 volumio volumio[12747]: info: Setting Geolocation for MyVolumio to as1 Feb 11 12:54:48 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 11 12:54:48 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 11 12:54:48 volumio volumio[12747]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 12:54:48 volumio volumio[12747]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 12:54:48 volumio sudo[13157]: pam_unix(sudo:session): session closed for user root Feb 11 12:54:48 volumio volumio[12747]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 12:54:48 volumio volumio[12747]: info: Remote SSH Stopped Feb 11 12:54:48 volumio volumio[12747]: info: Initializing connection to go-librespot Websocket Feb 11 12:54:48 volumio volumio[12747]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 11 12:54:48 volumio volumio[12747]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Feb 11 12:54:49 volumio volumio[12747]: info: Updating MyVolumio device info Feb 11 12:54:49 volumio volumio[12747]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 12:54:49 volumio volumio[12747]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 12:54:49 volumio volumio[12747]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 12:54:49 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Feb 11 12:54:49 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 11 12:54:49 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 11 12:54:49 volumio go-librespot[13159]: go-librespot daemon starting... Feb 11 12:54:49 volumio go-librespot[13160]: time="2026-02-11T12:54:49+07:00" level=info msg="running go-librespot 0.4.0" Feb 11 12:54:49 volumio go-librespot[13160]: time="2026-02-11T12:54:49+07:00" level=debug msg="app state loaded" Feb 11 12:54:49 volumio go-librespot[13160]: time="2026-02-11T12:54:49+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 11 12:54:49 volumio volumio[12747]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Feb 11 12:54:50 volumio go-librespot[13160]: time="2026-02-11T12:54: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 11 12:54:50 volumio go-librespot[13160]: time="2026-02-11T12:54:50+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 11 12:54:50 volumio go-librespot[13160]: time="2026-02-11T12:54:50+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 11 12:54:50 volumio go-librespot[13160]: time="2026-02-11T12:54:50+07:00" level=info msg="zeroconf server listening on port 34317" Feb 11 12:54:50 volumio go-librespot[13160]: time="2026-02-11T12:54:50+07:00" level=debug msg="obtained new client token: AAC6UkYW0/hM3vWl1+IM/BeBze6wlYyxdTfHkEFvL4qQ2c4ByJ2NsJJgYoHIoQc+gLLvF5qA8JzTytHDn/WXJAHsFDto+MPIUbU9iYw1yIV1kUpBr+ZWFFC8ukK82YcpxCf/LVKQTFfqXCrRtW8y+YsvNkwYY+NdhHX2MiP6Uw0iwfsQuNxq5gdyIQ//6g57FZV+XdQH2eEjccXVY4Vz+eZ+gpiT9v4OJO1LrnwcU6wcfMBYdetZ1mIL4g==" Feb 11 12:54:50 volumio go-librespot[13160]: time="2026-02-11T12:54:50+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 11 12:54:50 volumio go-librespot[13160]: time="2026-02-11T12:54:50+07:00" level=debug msg="completed keyexchange" Feb 11 12:54:50 volumio go-librespot[13160]: time="2026-02-11T12:54:50+07:00" level=debug msg="completed challenge" Feb 11 12:54:50 volumio go-librespot[13160]: time="2026-02-11T12:54:50+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 11 12:54:50 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 11 12:54:50 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 11 12:54:51 volumio volumio[12747]: info: Initializing connection to go-librespot Websocket Feb 11 12:54:51 volumio volumio[12747]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 11 12:54:52 volumio volumio[12747]: info: MYVOLUMIO: Adding device Feb 11 12:54:52 volumio volumio[12747]: info: MYVOLUMIO: Evaluating Server Feb 11 12:54:53 volumio volumio[12747]: info: Setting Geolocation for MyVolumio to as1 Feb 11 12:54:53 volumio volumio[12747]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 12:54:53 volumio volumio[12747]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 12:54:53 volumio volumio[12747]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 12:54:54 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Feb 11 12:54:54 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 11 12:54:54 volumio volumio[12747]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Feb 11 12:54:54 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 11 12:54:54 volumio go-librespot[13199]: go-librespot daemon starting... Feb 11 12:54:54 volumio go-librespot[13200]: time="2026-02-11T12:54:54+07:00" level=info msg="running go-librespot 0.4.0" Feb 11 12:54:54 volumio go-librespot[13200]: time="2026-02-11T12:54:54+07:00" level=debug msg="app state loaded" Feb 11 12:54:54 volumio go-librespot[13200]: time="2026-02-11T12:54:54+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 11 12:54:54 volumio go-librespot[13200]: time="2026-02-11T12:54: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-gew1.spotify.com:80]" Feb 11 12:54:54 volumio go-librespot[13200]: time="2026-02-11T12:54:54+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 11 12:54:54 volumio go-librespot[13200]: time="2026-02-11T12:54:54+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 11 12:54:54 volumio go-librespot[13200]: time="2026-02-11T12:54:54+07:00" level=info msg="zeroconf server listening on port 43397" Feb 11 12:54:54 volumio volumio[12747]: info: Initializing connection to go-librespot Websocket Feb 11 12:54:54 volumio go-librespot[13200]: time="2026-02-11T12:54:54+07:00" level=debug msg="new websocket client" Feb 11 12:54:54 volumio volumio[12747]: info: Connection to go-librespot Websocket established Feb 11 12:54:54 volumio volumio[12747]: info: Updating MyVolumio device info Feb 11 12:54:54 volumio volumio[12747]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 12:54:54 volumio volumio[12747]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 12:54:54 volumio volumio[12747]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 12:54:54 volumio go-librespot[13200]: time="2026-02-11T12:54:54+07:00" level=debug msg="obtained new client token: AACQCqse+yiNLx7KDf/048E27w0FBzTvp/eZ94CS9+ItgUK4pGhsFe0T+Q2fqtKthjTlLCti3IbEGgOU6Vlt6LM0PFhi2kB6oLowa3vZMYq0Gj0cDItKQvF00dkxK2UBbNRsqKqqP86inpFuBwXoVvp8Xrw80Sn6QIfSZ6v1GrHgehmGQ3FzdJ/3b2asqT/yIqYPejB6P9z1e5stxrG0z97IGodS2bK06vX3CTsKncN8qW6meTDAZEEcRw==" Feb 11 12:54:55 volumio go-librespot[13200]: time="2026-02-11T12:54:55+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 11 12:54:55 volumio go-librespot[13200]: time="2026-02-11T12:54:55+07:00" level=debug msg="completed keyexchange" Feb 11 12:54:55 volumio go-librespot[13200]: time="2026-02-11T12:54:55+07:00" level=debug msg="completed challenge" Feb 11 12:54:55 volumio volumio[12747]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Feb 11 12:54:55 volumio go-librespot[13200]: time="2026-02-11T12:54:55+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 11 12:54:55 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 11 12:54:55 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 11 12:54:55 volumio volumio[12747]: info: Connection to go-librespot Websocket closed Feb 11 12:54:56 volumio volumio[12747]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 11 12:54:56 volumio volumio[12747]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 12:54:56 volumio volumio[12747]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 11 12:54:57 volumio volumio-remote-updater[643]: Test mode disabled Feb 11 12:54:57 volumio volumio-remote-updater[643]: Alpha mode disabled Feb 11 12:54:57 volumio volumio-remote-updater[643]: Alpha legacy test mode disabled Feb 11 12:54:57 volumio volumio[12747]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Feb 11 12:54:57 volumio volumio[12747]: info: Getting Spotify volume Feb 11 12:54:57 volumio volumio[12747]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 11 12:54:57 volumio volumio[12747]: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 11 12:54:57 volumio volumio[12747]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Feb 11 12:54:57 volumio volumio[12747]: errno: -111, Feb 11 12:54:57 volumio volumio[12747]: code: 'ECONNREFUSED', Feb 11 12:54:57 volumio volumio[12747]: syscall: 'connect', Feb 11 12:54:57 volumio volumio[12747]: address: '127.0.0.1', Feb 11 12:54:57 volumio volumio[12747]: port: 9879, Feb 11 12:54:57 volumio volumio[12747]: response: undefined Feb 11 12:54:57 volumio volumio[12747]: } Feb 11 12:54:57 volumio volumio[12747]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 11 12:54:58 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Feb 11 12:54:58 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 11 12:54:58 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 11 12:54:58 volumio go-librespot[13225]: go-librespot daemon starting... Feb 11 12:54:58 volumio go-librespot[13226]: time="2026-02-11T12:54:58+07:00" level=info msg="running go-librespot 0.4.0" Feb 11 12:54:58 volumio go-librespot[13226]: time="2026-02-11T12:54:58+07:00" level=debug msg="app state loaded" Feb 11 12:54:58 volumio go-librespot[13226]: time="2026-02-11T12:54:58+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 11 12:54:58 volumio go-librespot[13226]: time="2026-02-11T12:54: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 11 12:54:58 volumio go-librespot[13226]: time="2026-02-11T12:54:58+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 11 12:54:58 volumio go-librespot[13226]: time="2026-02-11T12:54:58+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 11 12:54:59 volumio go-librespot[13226]: time="2026-02-11T12:54:59+07:00" level=info msg="zeroconf server listening on port 43895" Feb 11 12:54:59 volumio go-librespot[13226]: time="2026-02-11T12:54:59+07:00" level=debug msg="obtained new client token: AAB4qZ2+NtWdLdNJ4BiRl2rb2TpDzQtvwqgovcki2iyrffaxtFE98Vqxj4bwGuZZTXTfzMOqjQY3YDacBKWPoPjVNRmsw8a1h4zLixj7K1q/dwVzhICRZeHqUm9JbT8ajE0XsQsKXwBzBe7Gcud98Fu4PM1NfITU+x2yh3TNTnH416Ak+O70C6g66ClaSybsBF/cLJkrLLNrrvKIif/t4a5xSSUYpS2+WXNRZy6Vh5rr018tnJGWdII=" Feb 11 12:54:59 volumio sudo[13235]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-11 12:53' Feb 11 12:54:59 volumio sudo[13235]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 12:54:59 volumio go-librespot[13226]: time="2026-02-11T12:54:59+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 11 12:54:59 volumio go-librespot[13226]: time="2026-02-11T12:54:59+07:00" level=debug msg="completed keyexchange" Feb 11 12:54:59 volumio go-librespot[13226]: time="2026-02-11T12:54:59+07:00" level=debug msg="completed challenge" Feb 11 12:54:59 volumio go-librespot[13226]: time="2026-02-11T12:54:59+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 11 12:54:59 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 11 12:54:59 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 11 12:54:59 volumio sudo[13235]: pam_unix(sudo:session): session closed for user root Feb 11 12:54:59 volumio volumio-remote-updater[643]: [2026-02-11 12:54:59] [error] handle_read_frame error: asio.system:104 (Connection reset by peer) Feb 11 12:54:59 volumio volumio-remote-updater[643]: [2026-02-11 12:54:59] [disconnect] Disconnect close local:[1006,Connection reset by peer] remote:[1006] Feb 11 12:54:59 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 11 12:54:59 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 11 12:54:59 volumio systemd[1]: volumio.service: Consumed 58.476s CPU time. Feb 11 12:54:59 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 11 12:54:59 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 11 12:54:59 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 11765. Feb 11 12:54:59 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 11 12:54:59 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 11 12:54:59 volumio systemd[1]: volumio.service: Consumed 58.476s CPU time. Feb 11 12:54:59 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Feb 11 12:55:00 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 11 12:55:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Feb 11 12:55:02 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 11 12:55:02 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 11 12:55:02 volumio go-librespot[13264]: go-librespot daemon starting... Feb 11 12:55:02 volumio go-librespot[13265]: time="2026-02-11T12:55:02+07:00" level=info msg="running go-librespot 0.4.0" Feb 11 12:55:02 volumio go-librespot[13265]: time="2026-02-11T12:55:02+07:00" level=debug msg="app state loaded" Feb 11 12:55:02 volumio go-librespot[13265]: time="2026-02-11T12:55:02+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 11 12:55:03 volumio go-librespot[13265]: time="2026-02-11T12:55:03+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 11 12:55:03 volumio go-librespot[13265]: time="2026-02-11T12:55:03+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 11 12:55:03 volumio go-librespot[13265]: time="2026-02-11T12:55:03+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 11 12:55:03 volumio go-librespot[13265]: time="2026-02-11T12:55:03+07:00" level=info msg="zeroconf server listening on port 35859" Feb 11 12:55:03 volumio volumio[13249]: info: ------------------------------------------- Feb 11 12:55:03 volumio volumio[13249]: info: ----- Volumio3 ---- Feb 11 12:55:03 volumio volumio[13249]: info: ------------------------------------------- Feb 11 12:55:03 volumio volumio[13249]: info: ----- System startup ---- Feb 11 12:55:03 volumio volumio[13249]: info: ------------------------------------------- Feb 11 12:55:03 volumio go-librespot[13265]: time="2026-02-11T12:55:03+07:00" level=debug msg="obtained new client token: AABpyaFoi59L8BtZ6+SiV2+37RqdMGNd5RuFLvQKhurYFZOj3PyGEfPySqMl93NDJHrBBxf87BIDtwgnP+xSNPljMm+B/Glvikl+xoi4hrRZrJXEC20O99abc6kYEWCmv8jDh9evvDuqZvGBEAfTjAO/qQvxToO8lX0CYK93YNXlqeKGXbR9skGLvXF56foOQnrrMId3aJ8Z+DIXPLSauADBYVcd2PYTkRuCmKN8oxOdB5Wq2XyKkRNpZg==" Feb 11 12:55:03 volumio go-librespot[13265]: time="2026-02-11T12:55:03+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 11 12:55:03 volumio go-librespot[13265]: time="2026-02-11T12:55:03+07:00" level=debug msg="completed keyexchange" Feb 11 12:55:03 volumio go-librespot[13265]: time="2026-02-11T12:55:03+07:00" level=debug msg="completed challenge" Feb 11 12:55:04 volumio go-librespot[13265]: time="2026-02-11T12:55:04+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 11 12:55:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 11 12:55:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 11 12:55:04 volumio volumio-remote-updater[643]: [2026-02-11 12:55:04] [connect] Successful connection Feb 11 12:55:04 volumio volumio[13249]: info: MYVOLUMIO Environment detected Feb 11 12:55:05 volumio volumio[13249]: info: Plugin folders cleanup Feb 11 12:55:05 volumio volumio[13249]: info: Scanning into folder /volumio/app/plugins/ Feb 11 12:55:05 volumio volumio[13249]: info: Scanning category audio_interface Feb 11 12:55:05 volumio volumio[13249]: info: Scanning category miscellanea Feb 11 12:55:05 volumio volumio[13249]: info: Scanning category music_service Feb 11 12:55:05 volumio volumio[13249]: info: Scanning category plugins.json Feb 11 12:55:05 volumio volumio[13249]: info: Scanning category system_controller Feb 11 12:55:05 volumio volumio[13249]: info: Scanning category user_interface Feb 11 12:55:05 volumio volumio[13249]: info: Scanning into folder /data/plugins/ Feb 11 12:55:05 volumio volumio[13249]: info: Scanning category music_service Feb 11 12:55:05 volumio volumio[13249]: info: Plugin folders cleanup completed Feb 11 12:55:05 volumio volumio[13249]: info: ------------------------------------------- Feb 11 12:55:05 volumio volumio[13249]: info: ----- Core plugins startup ---- Feb 11 12:55:05 volumio volumio[13249]: info: ------------------------------------------- Feb 11 12:55:05 volumio volumio[13249]: info: Loading plugins from folder /volumio/app/plugins/ Feb 11 12:55:05 volumio volumio[13249]: info: Adding plugin upnp to MyMusic Plugins Feb 11 12:55:05 volumio volumio[13249]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 11 12:55:05 volumio volumio[13249]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 11 12:55:05 volumio volumio[13249]: info: Loading plugins from folder /data/plugins/ Feb 11 12:55:05 volumio volumio[13249]: info: Loading plugin "system"... Feb 11 12:55:05 volumio volumio[13249]: info: Loading plugin "appearance"... Feb 11 12:55:07 volumio volumio[13249]: info: Loading plugin "network"... Feb 11 12:55:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Feb 11 12:55:07 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 11 12:55:07 volumio volumio[13249]: info: Refreshing Cached IP Addresses Feb 11 12:55:07 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 11 12:55:07 volumio go-librespot[13285]: go-librespot daemon starting... Feb 11 12:55:07 volumio sudo[13287]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 11 12:55:07 volumio sudo[13287]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 12:55:07 volumio sudo[13287]: pam_unix(sudo:session): session closed for user root Feb 11 12:55:07 volumio sudo[13290]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 11 12:55:07 volumio go-librespot[13289]: time="2026-02-11T12:55:07+07:00" level=info msg="running go-librespot 0.4.0" Feb 11 12:55:07 volumio go-librespot[13289]: time="2026-02-11T12:55:07+07:00" level=debug msg="app state loaded" Feb 11 12:55:07 volumio volumio[13249]: info: Loading plugin "services"... Feb 11 12:55:07 volumio go-librespot[13289]: time="2026-02-11T12:55:07+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 11 12:55:07 volumio sudo[13290]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 12:55:07 volumio sudo[13290]: pam_unix(sudo:session): session closed for user root Feb 11 12:55:07 volumio volumio[13249]: info: Loading plugin "alsa_controller"... Feb 11 12:55:07 volumio sudo[13303]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 11 12:55:07 volumio sudo[13303]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 12:55:07 volumio volumio[13249]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 11 12:55:07 volumio volumio[13249]: info: Loading plugin "wizard"... Feb 11 12:55:07 volumio volumio[13249]: info: Loading plugin "networkfs"... Feb 11 12:55:07 volumio volumio[13249]: info: Starting Udev Watcher for removable devices Feb 11 12:55:07 volumio volumio[13249]: info: Ignoring mount for partition: boot Feb 11 12:55:07 volumio volumio[13249]: info: Ignoring mount for partition: volumio Feb 11 12:55:07 volumio volumio[13249]: info: Ignoring mount for partition: volumio_data Feb 11 12:55:07 volumio volumio[13249]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 11 12:55:07 volumio volumio[13249]: info: Loading plugin "volumio_command_line_client"... Feb 11 12:55:07 volumio volumio[13249]: info: Loading plugin "upnp"... Feb 11 12:55:07 volumio volumio[13249]: info: [1770789307522] Starting Upmpd Daemon Feb 11 12:55:07 volumio volumio[13249]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 11 12:55:07 volumio volumio[13249]: info: Loading plugin "my_music"... Feb 11 12:55:07 volumio volumio[13249]: info: Loading plugin "mpd"... Feb 11 12:55:07 volumio go-librespot[13289]: time="2026-02-11T12:55:07+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 11 12:55:07 volumio go-librespot[13289]: time="2026-02-11T12:55:07+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 11 12:55:07 volumio go-librespot[13289]: time="2026-02-11T12:55:07+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 11 12:55:07 volumio go-librespot[13289]: time="2026-02-11T12:55:07+07:00" level=info msg="zeroconf server listening on port 36057" Feb 11 12:55:07 volumio go-librespot[13289]: time="2026-02-11T12:55:07+07:00" level=debug msg="obtained new client token: AABtRn39pyGuX7j6cnUx2BYx65fHa8LfQfFY/RBjgjsWAzvxft2CSIZCQxieDGc2sz8/x28ZhwGURgFfvdtf5fFApyBja/+vZyW2EaJTL48HTCfHfN/NWuG9Y178MKLDd6QmKpiEBlqLVGfdCzoK536KT8dBVbknJwDWKIDELE9BBD+iUDu72rSIm7mcjVEODUdfY4ZiamzcbBIvKD+++jlj+BixVbVlWl5h8QcEolypEPGfa/EeliDbpQ==" Feb 11 12:55:08 volumio go-librespot[13289]: time="2026-02-11T12:55:08+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 11 12:55:08 volumio go-librespot[13289]: time="2026-02-11T12:55:08+07:00" level=debug msg="completed keyexchange" Feb 11 12:55:08 volumio go-librespot[13289]: time="2026-02-11T12:55:08+07:00" level=debug msg="completed challenge" Feb 11 12:55:08 volumio volumio[13249]: info: Loading plugin "upnp_browser"... Feb 11 12:55:08 volumio go-librespot[13289]: time="2026-02-11T12:55:08+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 11 12:55:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 11 12:55:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 11 12:55:10 volumio sudo[13303]: pam_unix(sudo:session): session closed for user root Feb 11 12:55:11 volumio volumio[13249]: info: Starting UPNP Browser Feb 11 12:55:11 volumio volumio[13249]: info: Loading plugin "alarm-clock"... Feb 11 12:55:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Feb 11 12:55:11 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 11 12:55:11 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 11 12:55:11 volumio go-librespot[13327]: go-librespot daemon starting... Feb 11 12:55:11 volumio go-librespot[13328]: time="2026-02-11T12:55:11+07:00" level=info msg="running go-librespot 0.4.0" Feb 11 12:55:11 volumio go-librespot[13328]: time="2026-02-11T12:55:11+07:00" level=debug msg="app state loaded" Feb 11 12:55:11 volumio go-librespot[13328]: time="2026-02-11T12:55:11+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 11 12:55:11 volumio volumio[13249]: info: Loading plugin "airplay_emulation"... Feb 11 12:55:11 volumio volumio[13249]: info: Starting Shairport Sync Feb 11 12:55:11 volumio volumio[13249]: info: Loading plugin "last_100"... Feb 11 12:55:11 volumio volumio[13249]: info: Loading plugin "webradio"... Feb 11 12:55:11 volumio volumio[13249]: info: Loading plugin "i2s_dacs"... Feb 11 12:55:11 volumio volumio[13249]: info: Loading plugin "volumiodiscovery"... Feb 11 12:55:11 volumio volumio[13249]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 11 12:55:11 volumio volumio[13249]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 11 12:55:11 volumio volumio[13249]: *** WARNING *** For more information see Feb 11 12:55:11 volumio volumio[13249]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 11 12:55:11 volumio volumio[13249]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 11 12:55:11 volumio volumio[13249]: *** WARNING *** For more information see Feb 11 12:55:11 volumio node[13249]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 11 12:55:11 volumio node[13249]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 11 12:55:11 volumio node[13249]: *** WARNING *** For more information see Feb 11 12:55:11 volumio node[13249]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 11 12:55:11 volumio node[13249]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 11 12:55:11 volumio node[13249]: *** WARNING *** For more information see Feb 11 12:55:11 volumio volumio[13249]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 11 12:55:11 volumio volumio[13249]: info: Discovery: Started advertising with name: Volumio Feb 11 12:55:11 volumio volumio[13249]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 11 12:55:11 volumio volumio[13249]: info: Loading plugin "spop"... Feb 11 12:55:11 volumio go-librespot[13328]: time="2026-02-11T12:55: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-gew1.spotify.com:80]" Feb 11 12:55:11 volumio go-librespot[13328]: time="2026-02-11T12:55:11+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 11 12:55:11 volumio go-librespot[13328]: time="2026-02-11T12:55:11+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 11 12:55:12 volumio go-librespot[13328]: time="2026-02-11T12:55:12+07:00" level=info msg="zeroconf server listening on port 33521" Feb 11 12:55:12 volumio go-librespot[13328]: time="2026-02-11T12:55:12+07:00" level=debug msg="obtained new client token: AAAOmLfzvXaKvis8T37saT0RjNpRXW8aKwZvO/Lo+PCpSluqo19Ef0R+KCNMb07rzM4TrV6pu5hbjdnrkQczSVPlQto/XLlG5VH70d5vIzj6SVqG3KUV9cLSk1LQOMocHNdgqDMofDHPMWCJcowBGJPllBIVkrNcohWyc3cOnuSRJGFQjEVDTIiyrPZp/patkm7J95A8KbaeZmV7uggxDGeHBBWjFkmYAs2ukAOJe/pRGvdBUwQoKD0=" Feb 11 12:55:12 volumio go-librespot[13328]: time="2026-02-11T12:55:12+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 11 12:55:12 volumio go-librespot[13328]: time="2026-02-11T12:55:12+07:00" level=debug msg="completed keyexchange" Feb 11 12:55:12 volumio go-librespot[13328]: time="2026-02-11T12:55:12+07:00" level=debug msg="completed challenge" Feb 11 12:55:12 volumio go-librespot[13328]: time="2026-02-11T12:55:12+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 11 12:55:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 11 12:55:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 11 12:55:13 volumio volumio[13249]: info: Loading plugin "ytcr"... Feb 11 12:55:15 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11. Feb 11 12:55:15 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 11 12:55:15 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 11 12:55:15 volumio go-librespot[13339]: go-librespot daemon starting... Feb 11 12:55:15 volumio go-librespot[13340]: time="2026-02-11T12:55:15+07:00" level=info msg="running go-librespot 0.4.0" Feb 11 12:55:15 volumio go-librespot[13340]: time="2026-02-11T12:55:15+07:00" level=debug msg="app state loaded" Feb 11 12:55:15 volumio go-librespot[13340]: time="2026-02-11T12:55:15+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 11 12:55:16 volumio go-librespot[13340]: time="2026-02-11T12:55:16+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 11 12:55:16 volumio go-librespot[13340]: time="2026-02-11T12:55:16+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 11 12:55:16 volumio go-librespot[13340]: time="2026-02-11T12:55:16+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 11 12:55:16 volumio volumio[13249]: info: Loading plugin "ytmusic"... Feb 11 12:55:16 volumio go-librespot[13340]: time="2026-02-11T12:55:16+07:00" level=info msg="zeroconf server listening on port 44743" Feb 11 12:55:16 volumio go-librespot[13340]: time="2026-02-11T12:55:16+07:00" level=debug msg="obtained new client token: AACL/wtrEF/ktNlO4/gasxs/Nr+H3QKcXQ4A/50VRp9pQCf4uxq9wmwhCiXJgYxmIjga0wifRJfQI/GpIFgVhq4agSatDiL7NdahZYdgsVKcklKy0UJ0Em3w1d/VuWM1LIHP+4J2CB6g/rKDnjVGLlx4SpwVPSvuzH3K/Ezn01QqLCMTyJU9LAKtNaL9TN7X4k8Jk2bFezqw/HoLJUvQ8rHq3Vi4GdVwkA8HZJqrSjkWKbSzHivF8PetBQ==" Feb 11 12:55:16 volumio go-librespot[13340]: time="2026-02-11T12:55:16+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 11 12:55:16 volumio go-librespot[13340]: time="2026-02-11T12:55:16+07:00" level=debug msg="completed keyexchange" Feb 11 12:55:16 volumio go-librespot[13340]: time="2026-02-11T12:55:16+07:00" level=debug msg="completed challenge" Feb 11 12:55:17 volumio go-librespot[13340]: time="2026-02-11T12:55:17+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 11 12:55:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 11 12:55:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 11 12:55:17 volumio volumio-remote-updater[643]: [2026-02-11 12:55:17] [connect] Successful connection Feb 11 12:55:17 volumio volumio[13249]: info: Loading plugin "outputs"... Feb 11 12:55:17 volumio volumio[13249]: info: Loading plugin "albumart"... Feb 11 12:55:17 volumio volumio[13249]: info: Plugin example_plugin is not enabled Feb 11 12:55:17 volumio volumio[13249]: info: Loading plugin "inputs"... Feb 11 12:55:17 volumio volumio[13249]: info: Loading plugin "updater_comm"... Feb 11 12:55:17 volumio volumio[13249]: info: Plugin mpdemulation is not enabled Feb 11 12:55:17 volumio volumio[13249]: info: Loading plugin "rest_api"... Feb 11 12:55:17 volumio volumio[13249]: info: Loading plugin "websocket"... Feb 11 12:55:18 volumio volumio[13249]: info: Starting Socket.io Server version 1.7.4 Feb 11 12:55:18 volumio volumio[13249]: info: Loading plugin "RoonBridge"... Feb 11 12:55:18 volumio volumio[13249]: info: Applying required configuration parameters for plugin RoonBridge Feb 11 12:55:18 volumio volumio[13249]: info: Loading i18n strings for locale en Feb 11 12:55:18 volumio volumio[13249]: Updating browse sources language Feb 11 12:55:18 volumio volumio[13249]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 12:55:18 volumio volumio[13249]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 11 12:55:18 volumio volumio[13249]: info: CoreCommandRouter::initPlayerControls Feb 11 12:55:18 volumio volumio[13249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 12:55:18 volumio volumio[13249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 12:55:18 volumio volumio[13249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 12:55:18 volumio volumio[13249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 12:55:18 volumio volumio[13249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 12:55:18 volumio volumio[13249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 12:55:18 volumio volumio[13249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 12:55:18 volumio volumio[13249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 12:55:18 volumio volumio[13361]: Forking 3 albumart workers Feb 11 12:55:18 volumio volumio[13249]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 11 12:55:18 volumio volumio[13249]: Express server listening on port 3000 Feb 11 12:55:18 volumio volumio[13249]: [Metrics] WebUI: 16s 132.62ms Feb 11 12:55:18 volumio volumio[13249]: info: CoreStateMachine::resetVolumioState Feb 11 12:55:18 volumio volumio[13249]: info: CoreStateMachine::getcurrentVolume Feb 11 12:55:18 volumio volumio[13249]: info: CoreCommandRouter::volumioRetrievevolume Feb 11 12:55:18 volumio sudo[13404]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 11 12:55:18 volumio sudo[13404]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 12:55:18 volumio sudo[13404]: pam_unix(sudo:session): session closed for user root Feb 11 12:55:19 volumio sudo[13406]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 11 12:55:19 volumio sudo[13406]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 12:55:19 volumio sudo[13406]: pam_unix(sudo:session): session closed for user root Feb 11 12:55:19 volumio volumio[13249]: info: Volumio Network Manager: Network status updated: 1 Feb 11 12:55:19 volumio volumio[13249]: info: VolumeController:: Volume=100 Mute =false Feb 11 12:55:19 volumio volumio[13249]: info: CoreStateMachine::pushState Feb 11 12:55:19 volumio volumio[13249]: info: CorePlayQueue::getTrack 0 Feb 11 12:55:19 volumio volumio[13249]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 12:55:19 volumio volumio[13249]: info: CoreCommandRouter::volumioPushState Feb 11 12:55:19 volumio volumio[13249]: info: CoreStateMachine::updateTrackBlock Feb 11 12:55:19 volumio volumio[13249]: info: CorePlayQueue::getTrackBlock Feb 11 12:55:19 volumio volumio[13249]: info: CoreCommandRouter::volumioRetrievevolume Feb 11 12:55:19 volumio volumio-remote-updater[643]: [2026-02-11 12:55:19] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1770789317 101 Feb 11 12:55:19 volumio volumio[13249]: 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 11 12:55:19 volumio volumio[13249]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 12:55:19 volumio volumio[13249]: info: Reloading queue from file Feb 11 12:55:19 volumio volumio[13249]: info: CoreStateMachine::setRepeat null single undefined Feb 11 12:55:19 volumio volumio[13249]: info: CoreStateMachine::pushState Feb 11 12:55:19 volumio volumio[13249]: info: CorePlayQueue::getTrack 0 Feb 11 12:55:19 volumio volumio[13249]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 12:55:19 volumio volumio[13249]: info: CoreCommandRouter::volumioPushState Feb 11 12:55:19 volumio volumio[13249]: info: CoreStateMachine::setRandom null Feb 11 12:55:19 volumio volumio[13249]: info: CoreStateMachine::pushState Feb 11 12:55:19 volumio volumio[13249]: info: CorePlayQueue::getTrack 0 Feb 11 12:55:19 volumio volumio[13249]: info: CoreCommandRouter::volumioPushState Feb 11 12:55:19 volumio volumio[13249]: info: Setting Device type: Raspberry PI Feb 11 12:55:19 volumio volumio[13249]: info: Completed loading Core Plugins Feb 11 12:55:19 volumio volumio[13249]: info: Preparing to generate the ALSA configuration file Feb 11 12:55:19 volumio volumio[13249]: info: VolumeController:: Volume=100 Mute =false Feb 11 12:55:19 volumio volumio[13249]: info: CoreStateMachine::pushState Feb 11 12:55:19 volumio volumio[13249]: info: CorePlayQueue::getTrack 0 Feb 11 12:55:19 volumio volumio[13249]: info: CoreCommandRouter::volumioPushState Feb 11 12:55:19 volumio volumio[13249]: info: Asound.conf file unchanged, so no further update is needed Feb 11 12:55:19 volumio volumio[13249]: info: Output device has changed, restarting MPD Feb 11 12:55:20 volumio sudo[13423]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 11 12:55:20 volumio volumio[13249]: info: Output device has changed, restarting Shairport Sync Feb 11 12:55:20 volumio volumio[13249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 12:55:20 volumio volumio[13249]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 12:55:20 volumio sudo[13423]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 12:55:20 volumio sudo[13427]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 11 12:55:20 volumio sudo[13427]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 12:55:20 volumio sudo[13425]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 11 12:55:20 volumio sudo[13425]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 12:55:20 volumio sudo[13425]: pam_unix(sudo:session): session closed for user root Feb 11 12:55:20 volumio volumio[13249]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 11 12:55:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12. Feb 11 12:55:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 11 12:55:20 volumio volumio[13249]: info: ___________ START PLUGINS ___________ Feb 11 12:55:20 volumio volumio[13249]: info: ControllerMpd::onStart: Initializing MPD Feb 11 12:55:20 volumio volumio[13249]: info: Creating MPD Configuration file Feb 11 12:55:20 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 11 12:55:20 volumio go-librespot[13435]: go-librespot daemon starting... Feb 11 12:55:20 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 11 12:55:20 volumio go-librespot[13438]: time="2026-02-11T12:55:20+07:00" level=info msg="running go-librespot 0.4.0" Feb 11 12:55:20 volumio go-librespot[13438]: time="2026-02-11T12:55:20+07:00" level=debug msg="app state loaded" Feb 11 12:55:20 volumio go-librespot[13438]: time="2026-02-11T12:55:20+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 11 12:55:20 volumio sudo[13423]: pam_unix(sudo:session): session closed for user root Feb 11 12:55:20 volumio volumio[13249]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 11 12:55:20 volumio volumio[13249]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 11 12:55:20 volumio volumio[13249]: info: [1770789320408] CoreMusicLibrary::Adding element Media Servers Feb 11 12:55:20 volumio volumio[13249]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 12:55:20 volumio sudo[13445]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 11 12:55:20 volumio sudo[13445]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 12:55:20 volumio sudo[13437]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 11 12:55:20 volumio sudo[13437]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 12:55:20 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 11 12:55:20 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 11 12:55:20 volumio systemd[1]: mpd.service: Consumed 7.133s CPU time. Feb 11 12:55:20 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 11 12:55:20 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 11 12:55:20 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 11 12:55:20 volumio sudo[13437]: pam_unix(sudo:session): session closed for user root Feb 11 12:55:20 volumio volumio[13249]: info: UPNP Browser: Client initialized successfully Feb 11 12:55:20 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 11 12:55:20 volumio volumio[13249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 12:55:20 volumio volumio[13249]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 12:55:20 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 11 12:55:20 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 11 12:55:20 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 11 12:55:20 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 11 12:55:20 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 11 12:55:20 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 11 12:55:20 volumio volumio[13249]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 11 12:55:20 volumio volumio[13249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 12:55:20 volumio volumio[13249]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 12:55:20 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 11 12:55:20 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 11 12:55:20 volumio volumio[13249]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 11 12:55:20 volumio volumio[13249]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 11 12:55:20 volumio volumio[13249]: info: [1770789320895] CoreMusicLibrary::Adding element Last_100 Feb 11 12:55:20 volumio volumio[13249]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 12:55:20 volumio volumio[13249]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 11 12:55:20 volumio volumio[13249]: info: [1770789320923] CoreMusicLibrary::Adding element Webradio Feb 11 12:55:20 volumio volumio[13249]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 12:55:20 volumio volumio[13249]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 11 12:55:20 volumio volumio[13249]: info: Initializing BBC Radios Feb 11 12:55:20 volumio go-librespot[13438]: time="2026-02-11T12:55: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-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 11 12:55:20 volumio go-librespot[13438]: time="2026-02-11T12:55:20+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 11 12:55:20 volumio go-librespot[13438]: time="2026-02-11T12:55:20+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 11 12:55:21 volumio go-librespot[13438]: time="2026-02-11T12:55:21+07:00" level=info msg="zeroconf server listening on port 39577" Feb 11 12:55:21 volumio volumio[13249]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 11 12:55:21 volumio volumio[13249]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 12:55:21 volumio go-librespot[13438]: time="2026-02-11T12:55:21+07:00" level=debug msg="obtained new client token: AACRfT+Pa5FqkwuhWb6aGDFGDbe/mrF6ODe2BcSAvzVTc4XXpiK7fRq6eGCElQOg5DxsszAU9TWRxu3zMVam+cGu4vfQpLoCAIb/KSFk1u16jDgm4aBcs4+zY9BgCed6tZnyhzWl1uXz6lWtWrx/lSObtQn2kgk4W69p2ZxLrBPEH14GdflbOnM+ydnoZ7w7TAgsQ9oyVIqMWsqQb6kj2jHEEvxKz9TCoAJoD5Ibll9eWRDvo1Oo3mU=" Feb 11 12:55:21 volumio volumio[13249]: info: Creating Spotify config file Feb 11 12:55:21 volumio volumio[13249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 12:55:21 volumio sudo[13460]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 11 12:55:21 volumio sudo[13460]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 11 12:55:21 volumio sudo[13460]: pam_unix(sudo:session): session closed for user root Feb 11 12:55:21 volumio go-librespot[13438]: time="2026-02-11T12:55:21+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 11 12:55:21 volumio go-librespot[13438]: time="2026-02-11T12:55:21+07:00" level=debug msg="completed keyexchange" Feb 11 12:55:21 volumio go-librespot[13438]: time="2026-02-11T12:55:21+07:00" level=debug msg="completed challenge" Feb 11 12:55:21 volumio go-librespot[13438]: time="2026-02-11T12:55:21+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 11 12:55:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 11 12:55:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 11 12:55:22 volumio volumio[13374]: Starting albumart workers Feb 11 12:55:22 volumio volumio[13372]: Starting albumart workers Feb 11 12:55:22 volumio volumio[13249]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 11 12:55:22 volumio volumio[13249]: info: [1770789322317] CoreMusicLibrary::Adding element YouTube Music Feb 11 12:55:22 volumio volumio[13249]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 12:55:22 volumio volumio[13249]: Cannot find translation for source YouTube Music Feb 11 12:55:22 volumio volumio[13249]: info: Volumio Calling Home Feb 11 12:55:22 volumio sudo[13477]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Feb 11 12:55:22 volumio sudo[13477]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 12:55:22 volumio volumio[13375]: Starting albumart workers Feb 11 12:55:22 volumio sudo[13477]: pam_unix(sudo:session): session closed for user root Feb 11 12:55:23 volumio volumio[13249]: info: Discovery: adding 921648ed-0557-4d70-9927-1420682efcc7 Feb 11 12:55:23 volumio volumio[13249]: info: Discovery: Found device Volumio Feb 11 12:55:23 volumio volumio[13249]: info: CoreCommandRouter::volumioGetState Feb 11 12:55:23 volumio volumio[13249]: info: CorePlayQueue::getTrack 0 Feb 11 12:55:23 volumio volumio[13249]: info: MPD Permissions set Feb 11 12:55:23 volumio volumio[13249]: info: MPD Permissions set Feb 11 12:55:23 volumio volumio[13249]: info: Upmpdcli Daemon Started Feb 11 12:55:23 volumio volumio[13249]: info: Spotify config file written Feb 11 12:55:23 volumio sudo[13483]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 11 12:55:23 volumio sudo[13483]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 12:55:23 volumio volumio[13249]: 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 11 12:55:23 volumio volumio[13249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 12:55:23 volumio volumio[13249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 12:55:23 volumio volumio[13249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 12:55:23 volumio volumio[13249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 12:55:23 volumio volumio[13249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 12:55:23 volumio volumio[13249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 12:55:23 volumio volumio[13249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 12:55:23 volumio volumio[13249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 12:55:23 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 11 12:55:23 volumio volumio[13249]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 12:55:23 volumio volumio[13249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 12:55:23 volumio volumio[13249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 12:55:23 volumio volumio[13249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 12:55:23 volumio volumio[13249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 12:55:23 volumio volumio[13249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 12:55:23 volumio volumio[13249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 12:55:23 volumio volumio[13249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 12:55:23 volumio volumio[13249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 12:55:23 volumio volumio[13249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 12:55:23 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 11 12:55:23 volumio go-librespot[13490]: go-librespot daemon starting... Feb 11 12:55:23 volumio sudo[13483]: pam_unix(sudo:session): session closed for user root Feb 11 12:55:23 volumio volumio[13249]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 12:55:23 volumio volumio[13249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 12:55:23 volumio volumio[13249]: info: No need to fix Spotify hosts Feb 11 12:55:23 volumio go-librespot[13493]: time="2026-02-11T12:55:23+07:00" level=info msg="running go-librespot 0.4.0" Feb 11 12:55:23 volumio go-librespot[13493]: time="2026-02-11T12:55:23+07:00" level=debug msg="app state loaded" Feb 11 12:55:23 volumio go-librespot[13493]: time="2026-02-11T12:55:23+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 11 12:55:24 volumio volumio[13249]: info: Discovery: this is already registered, 921648ed-0557-4d70-9927-1420682efcc7 Feb 11 12:55:24 volumio volumio[13249]: info: Discovery: Found device Volumio Feb 11 12:55:24 volumio volumio[13249]: info: CoreCommandRouter::volumioGetState Feb 11 12:55:24 volumio volumio[13249]: info: CorePlayQueue::getTrack 0 Feb 11 12:55:24 volumio go-librespot[13493]: time="2026-02-11T12:55: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 11 12:55:24 volumio go-librespot[13493]: time="2026-02-11T12:55: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 11 12:55:24 volumio go-librespot[13493]: time="2026-02-11T12:55: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 11 12:55:24 volumio go-librespot[13493]: time="2026-02-11T12:55:24+07:00" level=info msg="zeroconf server listening on port 37073" Feb 11 12:55:24 volumio go-librespot[13493]: time="2026-02-11T12:55:24+07:00" level=debug msg="obtained new client token: AADbu0ocA2KQlrtl5wZZGEHeseMwUbM+fNLXJtOegYLHcuoB/ZlAvR8Wp0NZI4+Xb/dwB8UORh36JvNQ/vWXsKVW0V9aDq4Je+waWqqYFbZo2q6B8XsawYO1HL55JBT8J8gBLkks39Rs/fZYIFPUIZ1Y/GM+mFtJAaZDcIwijUE/H6hcoQuc4ZzREODn4ysZ/ymw2PXaKR5PckfMBIW7BiKsF+0TKc7RBvQdvuN8vayZHs2YbxZyv1F3hQ==" Feb 11 12:55:24 volumio volumio[13249]: info: Volumio called home Feb 11 12:55:24 volumio go-librespot[13493]: time="2026-02-11T12:55:24+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 11 12:55:24 volumio go-librespot[13493]: time="2026-02-11T12:55:24+07:00" level=debug msg="completed keyexchange" Feb 11 12:55:24 volumio go-librespot[13493]: time="2026-02-11T12:55:24+07:00" level=debug msg="completed challenge" Feb 11 12:55:24 volumio go-librespot[13493]: time="2026-02-11T12:55:24+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 11 12:55:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 11 12:55:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 11 12:55:24 volumio volumio[13249]: info: Starting Shairport Sync Feb 11 12:55:24 volumio volumio[13249]: info: Starting Shairport Sync Feb 11 12:55:25 volumio volumio[13249]: info: Starting Shairport Sync Feb 11 12:55:25 volumio sudo[13523]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 11 12:55:25 volumio sudo[13523]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 12:55:25 volumio sudo[13525]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 11 12:55:25 volumio sudo[13525]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 12:55:25 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 11 12:55:25 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 11 12:55:25 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 11 12:55:25 volumio systemd[1]: shairport-sync.service: Consumed 2.408s CPU time. Feb 11 12:55:25 volumio sudo[13528]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 11 12:55:25 volumio sudo[13528]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 12:55:25 volumio volumio[13249]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 11 12:55:25 volumio volumio[13249]: SPOTIFY: BQBeHHvnSEp3dQcV3rs0Xs5rsKf2YQuS-OyevElYZPFXkZng4jqvvDoRNc1cZdxA9Tur9XgXozwuxr_I8-nvgurBJQaYuwyTmHRkJ2ZFs7YE7ou0gQ2jccUSO5iByFUm1IUiVydE2vPjeFCjVGA9ehpjVarM4ufYSSNkBBagOZKDhMowl-T_RyHO6pU-XxLmqKF091FIy1rJENeOSYja1mzi8QF21TOoOZJzczuMBknr0mO3woDBgMBcHmDPqnwrk8OatDb_eqlEqA3yM7pXptIa2hULffWu7PIUDZImsljvgYYVGFylIyWL Feb 11 12:55:25 volumio volumio[13249]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 11 12:55:25 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 11 12:55:25 volumio volumio[13249]: info: New Spotify access token = BQBeHHvnSEp3dQcV3rs0Xs5rsKf2YQuS-OyevElYZPFXkZng4jqvvDoRNc1cZdxA9Tur9XgXozwuxr_I8-nvgurBJQaYuwyTmHRkJ2ZFs7YE7ou0gQ2jccUSO5iByFUm1IUiVydE2vPjeFCjVGA9ehpjVarM4ufYSSNkBBagOZKDhMowl-T_RyHO6pU-XxLmqKF091FIy1rJENeOSYja1mzi8QF21TOoOZJzczuMBknr0mO3woDBgMBcHmDPqnwrk8OatDb_eqlEqA3yM7pXptIa2hULffWu7PIUDZImsljvgYYVGFylIyWL Feb 11 12:55:25 volumio volumio[13249]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 11 12:55:25 volumio sudo[13523]: pam_unix(sudo:session): session closed for user root Feb 11 12:55:25 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 11 12:55:25 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 11 12:55:25 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 11 12:55:25 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 11 12:55:25 volumio sudo[13528]: pam_unix(sudo:session): session closed for user root Feb 11 12:55:25 volumio sudo[13525]: pam_unix(sudo:session): session closed for user root Feb 11 12:55:25 volumio volumio[13249]: info: Shairport-Sync Started Feb 11 12:55:25 volumio volumio[13249]: Error adding Membership: Error: addMembership EINVAL Feb 11 12:55:25 volumio volumio[13249]: info: Shairport-Sync Started Feb 11 12:55:25 volumio volumio[13249]: info: Shairport-Sync Started Feb 11 12:55:25 volumio volumio[13249]: info: CoreCommandRouter::volumioGetState Feb 11 12:55:25 volumio volumio[13249]: info: CorePlayQueue::getTrack 0 Feb 11 12:55:25 volumio volumio[13249]: 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 11 12:55:25 volumio volumio[13249]: info: Spotify Successfully logged in Feb 11 12:55:25 volumio volumio[13249]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 11 12:55:25 volumio volumio[13249]: info: [1770789325935] CoreMusicLibrary::Adding element Spotify Feb 11 12:55:25 volumio volumio[13249]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 12:55:25 volumio volumio[13249]: Cannot find translation for source YouTube Music Feb 11 12:55:25 volumio volumio[13249]: Cannot find translation for source Spotify Feb 11 12:55:26 volumio volumio[13249]: info: [yt-cast-receiver] DIAL server listening on port 8098 Feb 11 12:55:26 volumio volumio[13249]: info: CoreCommandRouter::volumioRetrievevolume Feb 11 12:55:26 volumio volumio[13249]: info: VolumeController:: Volume=100 Mute =false Feb 11 12:55:26 volumio volumio[13249]: info: CoreCommandRouter::volumioGetState Feb 11 12:55:26 volumio volumio[13249]: info: CorePlayQueue::getTrack 0 Feb 11 12:55:26 volumio volumio[13249]: info: CoreStateMachine::pushState Feb 11 12:55:26 volumio volumio[13249]: info: CorePlayQueue::getTrack 0 Feb 11 12:55:26 volumio volumio[13249]: info: CoreCommandRouter::volumioPushState Feb 11 12:55:27 volumio volumio[13249]: info: go-librespot daemon successfully initialized Feb 11 12:55:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13. Feb 11 12:55:28 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 11 12:55:28 volumio mpd[13475]: 2026-02-11T12:55:28 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 11 12:55:28 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 11 12:55:28 volumio go-librespot[13564]: go-librespot daemon starting... Feb 11 12:55:28 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 11 12:55:28 volumio sudo[13445]: pam_unix(sudo:session): session closed for user root Feb 11 12:55:28 volumio sudo[13427]: pam_unix(sudo:session): session closed for user root Feb 11 12:55:28 volumio go-librespot[13567]: time="2026-02-11T12:55:28+07:00" level=info msg="running go-librespot 0.4.0" Feb 11 12:55:28 volumio go-librespot[13567]: time="2026-02-11T12:55:28+07:00" level=debug msg="app state loaded" Feb 11 12:55:28 volumio go-librespot[13567]: time="2026-02-11T12:55:28+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 11 12:55:28 volumio volumio[13249]: error: MPD error: The expression evaluated to a falsy value: Feb 11 12:55:28 volumio volumio[13249]: assert.ok(self.idling) Feb 11 12:55:28 volumio volumio[13249]: error: The expression evaluated to a falsy value: Feb 11 12:55:28 volumio volumio[13249]: assert.ok(self.idling) Feb 11 12:55:28 volumio volumio[13249]: info: MPD running with PID13475 Feb 11 12:55:28 volumio volumio[13249]: ,establishing connection Feb 11 12:55:28 volumio volumio[13249]: error: updateQueue error: null Feb 11 12:55:28 volumio volumio[13249]: info: Completed starting Core Plugins Feb 11 12:55:28 volumio volumio[13249]: info: ------------------------------------------- Feb 11 12:55:28 volumio volumio[13249]: info: ----- MyVolumio plugins startup ---- Feb 11 12:55:28 volumio volumio[13249]: info: ------------------------------------------- Feb 11 12:55:28 volumio volumio[13249]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 11 12:55:28 volumio volumio[13249]: error: updateQueue error: null Feb 11 12:55:28 volumio go-librespot[13567]: time="2026-02-11T12:55:28+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 11 12:55:28 volumio go-librespot[13567]: time="2026-02-11T12:55:28+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 11 12:55:28 volumio go-librespot[13567]: time="2026-02-11T12:55:28+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 11 12:55:28 volumio go-librespot[13567]: time="2026-02-11T12:55:28+07:00" level=info msg="zeroconf server listening on port 34005" Feb 11 12:55:28 volumio go-librespot[13567]: time="2026-02-11T12:55:28+07:00" level=debug msg="obtained new client token: AAC7Y2HUTej7GBB3SggMItFyj5PFhTxy0sOEfWTQciXJQqWYy8fd+OQU+cQan7ToMceWpYtAIvRcqPebna8YeUr0g/L+7MMnD6LWlk9wn0zh0/8pHSdJisqRYVqC8NB1XFUxHfrOQKFBKv5RXNqvwE+Fhv/QHg9L596KNoIoHdnP5czBmA3ZqQTXjnxbdq2CIT3FRqME3jTCQyOBIdiJHgzrD/KCMKXS5H9+ob9/xlSPpj6NHcN4IzilAQ==" Feb 11 12:55:29 volumio go-librespot[13567]: time="2026-02-11T12:55:29+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 11 12:55:29 volumio go-librespot[13567]: time="2026-02-11T12:55:29+07:00" level=debug msg="completed keyexchange" Feb 11 12:55:29 volumio go-librespot[13567]: time="2026-02-11T12:55:29+07:00" level=debug msg="completed challenge" Feb 11 12:55:29 volumio go-librespot[13567]: time="2026-02-11T12:55:29+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 11 12:55:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 11 12:55:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 11 12:55:30 volumio volumio[13249]: info: Initializing connection to go-librespot Websocket Feb 11 12:55:30 volumio volumio[13249]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 11 12:55:32 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14. Feb 11 12:55:32 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 11 12:55:32 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 11 12:55:32 volumio go-librespot[13576]: go-librespot daemon starting... Feb 11 12:55:32 volumio go-librespot[13577]: time="2026-02-11T12:55:32+07:00" level=info msg="running go-librespot 0.4.0" Feb 11 12:55:32 volumio go-librespot[13577]: time="2026-02-11T12:55:32+07:00" level=debug msg="app state loaded" Feb 11 12:55:32 volumio go-librespot[13577]: time="2026-02-11T12:55:32+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 11 12:55:32 volumio go-librespot[13577]: time="2026-02-11T12:55:32+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 11 12:55:32 volumio go-librespot[13577]: time="2026-02-11T12:55:32+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 11 12:55:32 volumio go-librespot[13577]: time="2026-02-11T12:55:32+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 11 12:55:32 volumio go-librespot[13577]: time="2026-02-11T12:55:32+07:00" level=info msg="zeroconf server listening on port 38993" Feb 11 12:55:33 volumio go-librespot[13577]: time="2026-02-11T12:55:33+07:00" level=debug msg="obtained new client token: AAA9A0EGf6uwFbQHgjeAqPB3tHbE7TdI0s6SMmqLhPTtvVqO/6nnEypcSiEzhuhx3gdMI/y39KHG/jC5CRCntZcMnX7I9VyVVTE4tYEjKvA5plmS6KjPwuaGkUVC9rOQV6pioJ+gijuJTT6vKjV3DXUpBRC1v82RkfTJ7mArPl6rlZmYfo5kMuc3dw1Nz3xRrxK6myyepAQRQ2E6bM1ybN5syZIxz/ZP/i83qrgcg8bkInTgBnQi2Qo=" Feb 11 12:55:33 volumio go-librespot[13577]: time="2026-02-11T12:55:33+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 11 12:55:33 volumio volumio[13249]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 11 12:55:33 volumio go-librespot[13577]: time="2026-02-11T12:55:33+07:00" level=debug msg="completed keyexchange" Feb 11 12:55:33 volumio go-librespot[13577]: time="2026-02-11T12:55:33+07:00" level=debug msg="completed challenge" Feb 11 12:55:33 volumio go-librespot[13577]: time="2026-02-11T12:55:33+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 11 12:55:33 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 11 12:55:33 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 11 12:55:33 volumio volumio[13249]: info: Initializing connection to go-librespot Websocket Feb 11 12:55:33 volumio volumio[13249]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 11 12:55:36 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15. Feb 11 12:55:36 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 11 12:55:36 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 11 12:55:36 volumio go-librespot[13584]: go-librespot daemon starting... Feb 11 12:55:36 volumio go-librespot[13585]: time="2026-02-11T12:55:36+07:00" level=info msg="running go-librespot 0.4.0" Feb 11 12:55:36 volumio go-librespot[13585]: time="2026-02-11T12:55:36+07:00" level=debug msg="app state loaded" Feb 11 12:55:36 volumio go-librespot[13585]: time="2026-02-11T12:55:36+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 11 12:55:36 volumio volumio[13249]: info: Initializing connection to go-librespot Websocket Feb 11 12:55:36 volumio go-librespot[13585]: time="2026-02-11T12:55:36+07:00" level=debug msg="new websocket client" Feb 11 12:55:36 volumio volumio[13249]: info: Connection to go-librespot Websocket established Feb 11 12:55:37 volumio go-librespot[13585]: time="2026-02-11T12:55:37+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 11 12:55:37 volumio go-librespot[13585]: time="2026-02-11T12:55:37+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 11 12:55:37 volumio go-librespot[13585]: time="2026-02-11T12:55:37+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 11 12:55:37 volumio go-librespot[13585]: time="2026-02-11T12:55:37+07:00" level=info msg="zeroconf server listening on port 36391" Feb 11 12:55:37 volumio volumio[13249]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 11 12:55:37 volumio volumio[13249]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 11 12:55:37 volumio volumio[13249]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 11 12:55:37 volumio volumio[13249]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 11 12:55:37 volumio volumio[13249]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 11 12:55:37 volumio volumio[13249]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 11 12:55:37 volumio volumio[13249]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 11 12:55:37 volumio volumio[13249]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 11 12:55:37 volumio volumio[13249]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 11 12:55:37 volumio volumio[13249]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 11 12:55:37 volumio volumio[13249]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 11 12:55:37 volumio volumio[13249]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 11 12:55:37 volumio volumio[13249]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 11 12:55:37 volumio volumio[13249]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 11 12:55:37 volumio volumio[13249]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 11 12:55:37 volumio volumio[13249]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 11 12:55:37 volumio volumio[13249]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 11 12:55:37 volumio volumio[13249]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 11 12:55:37 volumio volumio[13249]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 11 12:55:37 volumio volumio[13249]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 11 12:55:37 volumio volumio[13249]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 11 12:55:37 volumio volumio[13249]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 11 12:55:37 volumio volumio[13249]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 11 12:55:37 volumio volumio[13249]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 11 12:55:37 volumio volumio[13249]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 11 12:55:37 volumio volumio[13249]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 11 12:55:37 volumio volumio[13249]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 11 12:55:37 volumio volumio[13249]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 11 12:55:37 volumio volumio[13249]: info: Adding plugin bluetooth to MyMusic Plugins Feb 11 12:55:37 volumio volumio[13249]: info: Adding plugin multiroom to MyMusic Plugins Feb 11 12:55:37 volumio volumio[13249]: info: Adding plugin metavolumio to MyMusic Plugins Feb 11 12:55:37 volumio volumio[13249]: info: Adding plugin cd_controller to MyMusic Plugins Feb 11 12:55:37 volumio volumio[13249]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 11 12:55:37 volumio volumio[13249]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 11 12:55:37 volumio volumio[13249]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 11 12:55:37 volumio volumio[13249]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 11 12:55:37 volumio go-librespot[13585]: time="2026-02-11T12:55:37+07:00" level=debug msg="obtained new client token: AACD0wwNjLRikKqzOHpFnmynPW727JdQSr9Pw+cnYT1L3i5pVyy5YIvolTmoBZRD7vJ+h8nBvjqnyfYuRPMf5fJHy9Y4adZcz7lgR58VgwOTPy7f2ft8MuKBTkde5Umohk8IJ0PuaTCRqOpuvMUaYN81mS0lzN0qazYtC5efLARehO+VTPFeLYLi8t3sM0WoFiR+4ttbtDTybyvi8En4Lc6xgBZAla47ZWKPGeaO09wd5LPrn5Vk1CdY1A==" Feb 11 12:55:37 volumio go-librespot[13585]: time="2026-02-11T12:55:37+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 11 12:55:37 volumio go-librespot[13585]: time="2026-02-11T12:55:37+07:00" level=debug msg="completed keyexchange" Feb 11 12:55:37 volumio go-librespot[13585]: time="2026-02-11T12:55:37+07:00" level=debug msg="completed challenge" Feb 11 12:55:37 volumio go-librespot[13585]: time="2026-02-11T12:55:37+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 11 12:55:37 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 11 12:55:37 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 11 12:55:39 volumio volumio[13249]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 11 12:55:39 volumio volumio[13249]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 11 12:55:39 volumio volumio[13249]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 12:55:39 volumio volumio[13249]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 12:55:39 volumio volumio[13249]: info: Starting MyVolumio Remote Streaming Endpoints Feb 11 12:55:39 volumio volumio[13249]: info: MyVolumio login type: Token Feb 11 12:55:39 volumio volumio[13249]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 11 12:55:39 volumio volumio[13249]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 11 12:55:40 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16. Feb 11 12:55:40 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 11 12:55:40 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 11 12:55:40 volumio go-librespot[13608]: go-librespot daemon starting... Feb 11 12:55:40 volumio go-librespot[13609]: time="2026-02-11T12:55:40+07:00" level=info msg="running go-librespot 0.4.0" Feb 11 12:55:40 volumio go-librespot[13609]: time="2026-02-11T12:55:40+07:00" level=debug msg="app state loaded" Feb 11 12:55:40 volumio go-librespot[13609]: time="2026-02-11T12:55:40+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 11 12:55:40 volumio volumio[13249]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 11 12:55:40 volumio volumio[13249]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 11 12:55:40 volumio volumio[13249]: info: Streaming services startup Feb 11 12:55:40 volumio volumio[13249]: info: Starting Streaming Daemon Feb 11 12:55:41 volumio volumio[13249]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 11 12:55:41 volumio sudo[13617]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 11 12:55:41 volumio sudo[13617]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 12:55:41 volumio sudo[13617]: pam_unix(sudo:session): session closed for user root Feb 11 12:55:41 volumio volumio[13249]: info: Getting Spotify volume Feb 11 12:55:41 volumio volumio[13249]: info: Connection to go-librespot Websocket closed Feb 11 12:55:41 volumio volumio[13249]: error: Cannot start Volumio Streaming Daemon Feb 11 12:55:41 volumio volumio[13249]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 11 12:55:41 volumio volumio[13249]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 11 12:55:41 volumio volumio[13249]: 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 11 12:55:41 volumio go-librespot[13609]: time="2026-02-11T12:55:41+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 11 12:55:41 volumio go-librespot[13609]: time="2026-02-11T12:55:41+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 11 12:55:41 volumio go-librespot[13609]: time="2026-02-11T12:55:41+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 11 12:55:41 volumio go-librespot[13609]: time="2026-02-11T12:55:41+07:00" level=info msg="zeroconf server listening on port 42677" Feb 11 12:55:41 volumio volumio[13249]: info: CoreCommandRouter::volumioGetState Feb 11 12:55:41 volumio volumio[13249]: info: CorePlayQueue::getTrack 0 Feb 11 12:55:41 volumio volumio[13249]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 11 12:55:41 volumio volumio[13249]: SPOTIFY: SPOTIFY VOLUME undefined Feb 11 12:55:41 volumio volumio[13249]: SPOTIFY: VOLUMIO VOLUME 100 Feb 11 12:55:41 volumio volumio[13249]: info: Aligning Spotify Volume to Volumio Volume Feb 11 12:55:41 volumio volumio[13249]: info: CoreCommandRouter::volumioGetState Feb 11 12:55:41 volumio volumio[13249]: info: CorePlayQueue::getTrack 0 Feb 11 12:55:41 volumio volumio[13249]: info: Setting Spotify Volume from Volumio: 100 Feb 11 12:55:41 volumio go-librespot[13609]: time="2026-02-11T12:55:41+07:00" level=debug msg="obtained new client token: AAA7yKnqnRWjXQDzJRoYEW9WxTyzNxrwsDtFOcW4Hm1a62WBAvVUg4E+zs2aZjk8NNElOHMsnEm9fpFwxYpPhHFP116lL8Ove9rrqoEvsoEkVXfaqYegEyc8y4S/AddrZeCfd/N3VffKWmWH012sktPk9yV8+7ZsECpCFVHJFEVTYMFxSJOxPAKBKqJU7G97+HRKXY8o8OpwYJcvYHEYBk49ZnAmp1Md9foZ3Pw7XtrVLtbjefs2rVK79Q==" Feb 11 12:55:41 volumio go-librespot[13609]: time="2026-02-11T12:55:41+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 11 12:55:41 volumio go-librespot[13609]: time="2026-02-11T12:55:41+07:00" level=debug msg="completed keyexchange" Feb 11 12:55:41 volumio go-librespot[13609]: time="2026-02-11T12:55:41+07:00" level=debug msg="completed challenge" Feb 11 12:55:41 volumio go-librespot[13609]: time="2026-02-11T12:55: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 11 12:55:41 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 11 12:55:41 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 11 12:55:42 volumio volumio[13249]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 11 12:55:42 volumio volumio[13249]: Error: socket hang up Feb 11 12:55:42 volumio volumio[13249]: at connResetException (node:internal/errors:720:14) Feb 11 12:55:42 volumio volumio[13249]: at Socket.socketOnEnd (node:_http_client:519:23) Feb 11 12:55:42 volumio volumio[13249]: at Socket.emit (node:events:526:35) Feb 11 12:55:42 volumio volumio[13249]: at endReadableNT (node:internal/streams/readable:1376:12) Feb 11 12:55:42 volumio volumio[13249]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Feb 11 12:55:42 volumio volumio[13249]: code: 'ECONNRESET', Feb 11 12:55:42 volumio volumio[13249]: response: undefined Feb 11 12:55:42 volumio volumio[13249]: } Feb 11 12:55:42 volumio volumio[13249]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 11 12:55:43 volumio sudo[13637]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-11 12:54' Feb 11 12:55:43 volumio sudo[13637]: 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"