Feb 13 11:34:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 67. Feb 13 11:34:00 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 11:34:00 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 11:34:00 volumio go-librespot[22500]: go-librespot daemon starting... Feb 13 11:34:00 volumio go-librespot[22501]: time="2026-02-13T11:34:00+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 11:34:00 volumio go-librespot[22501]: time="2026-02-13T11:34:00+07:00" level=debug msg="app state loaded" Feb 13 11:34:00 volumio go-librespot[22501]: time="2026-02-13T11:34:00+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 11:34:01 volumio go-librespot[22501]: time="2026-02-13T11:34:01+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 13 11:34:01 volumio go-librespot[22501]: time="2026-02-13T11:34:01+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 13 11:34:01 volumio go-librespot[22501]: time="2026-02-13T11:34:01+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 13 11:34:01 volumio go-librespot[22501]: time="2026-02-13T11:34:01+07:00" level=info msg="zeroconf server listening on port 38865" Feb 13 11:34:01 volumio go-librespot[22501]: time="2026-02-13T11:34:01+07:00" level=debug msg="obtained new client token: AACioGExvAuggsR4mo5Idn4HMg/2nnFok7IBaNrwavmitIMPPx3TAUPK9RdIHK5yW+/SyUt9KNn0YJXgl4gdlU2YrKXbgBR2UynKlXDkGDDHWkazzO6XdzRl5K9dYNq4me/bQN+OCU4cTfFEBngHkMlnVqYCOafgGxhKBpfDIl3OtUVJVkS/+4elTtk8TgqIbHnqzdLQTN3vqJgeAVWyZMuhP78BGhu0RxuDhePklMuhoaOjV/cy38pJhw==" Feb 13 11:34:01 volumio go-librespot[22501]: time="2026-02-13T11:34:01+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 11:34:01 volumio go-librespot[22501]: time="2026-02-13T11:34:01+07:00" level=debug msg="completed keyexchange" Feb 13 11:34:01 volumio go-librespot[22501]: time="2026-02-13T11:34:01+07:00" level=debug msg="completed challenge" Feb 13 11:34:01 volumio go-librespot[22501]: time="2026-02-13T11:34:01+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 13 11:34:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 11:34:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 11:34:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 68. Feb 13 11:34:05 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 11:34:05 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 11:34:05 volumio go-librespot[22509]: go-librespot daemon starting... Feb 13 11:34:05 volumio go-librespot[22510]: time="2026-02-13T11:34:05+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 11:34:05 volumio go-librespot[22510]: time="2026-02-13T11:34:05+07:00" level=debug msg="app state loaded" Feb 13 11:34:05 volumio go-librespot[22510]: time="2026-02-13T11:34:05+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 11:34:05 volumio go-librespot[22510]: time="2026-02-13T11:34:05+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 13 11:34:05 volumio go-librespot[22510]: time="2026-02-13T11:34:05+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 13 11:34:05 volumio go-librespot[22510]: time="2026-02-13T11:34:05+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 13 11:34:05 volumio go-librespot[22510]: time="2026-02-13T11:34:05+07:00" level=info msg="zeroconf server listening on port 36271" Feb 13 11:34:06 volumio go-librespot[22510]: time="2026-02-13T11:34:06+07:00" level=debug msg="obtained new client token: AAAm0s4DxZF0cuj3IaTRyVxt7e5AB5ObVeh5p4938jKDlVpDPlVU+3ruRCdKDpwNeBNFfjgtSstUVVDk/yQu5J1OOd1lRjUk4wnY+QhRYifM0Jp8A6tAfHG8EcqmoRmLc/EPSbsJxTNSpp2D593sZlnlQks93siVeUbseDa7VOo/hfcjuvRglyI0keMl8KcR+XXuZ8Ou6VyanbwnT+tp//A3Bb8LzWhGEWtYHWuwiZw2yWLHWzewGks=" Feb 13 11:34:06 volumio go-librespot[22510]: time="2026-02-13T11:34:06+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 11:34:06 volumio go-librespot[22510]: time="2026-02-13T11:34:06+07:00" level=debug msg="completed keyexchange" Feb 13 11:34:06 volumio go-librespot[22510]: time="2026-02-13T11:34:06+07:00" level=debug msg="completed challenge" Feb 13 11:34:06 volumio go-librespot[22510]: time="2026-02-13T11:34: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 13 11:34:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 11:34:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 11:34:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 69. Feb 13 11:34:09 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 11:34:09 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 11:34:09 volumio go-librespot[22533]: go-librespot daemon starting... Feb 13 11:34:09 volumio go-librespot[22534]: time="2026-02-13T11:34:09+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 11:34:09 volumio go-librespot[22534]: time="2026-02-13T11:34:09+07:00" level=debug msg="app state loaded" Feb 13 11:34:09 volumio go-librespot[22534]: time="2026-02-13T11:34:09+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 11:34:10 volumio go-librespot[22534]: time="2026-02-13T11:34:10+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 13 11:34:10 volumio go-librespot[22534]: time="2026-02-13T11:34:10+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 13 11:34:10 volumio go-librespot[22534]: time="2026-02-13T11:34:10+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 13 11:34:10 volumio go-librespot[22534]: time="2026-02-13T11:34:10+07:00" level=info msg="zeroconf server listening on port 37567" Feb 13 11:34:10 volumio go-librespot[22534]: time="2026-02-13T11:34:10+07:00" level=debug msg="obtained new client token: AADFnv4/2e4Ghvg5I+Htxi9C6KyMYtj86VHFazBp2MrtsWXaNzH7Nu/2CrhkieTofKMDrtFCDodeObCarNCFnPiEYJ10aovA1YXTkA5o2riUuq1OqWFgTF08bBdMrGdaI+ijaE5Z8lm9LnrWJiJEQBHicQsTRdUJ+NkR+iWOBTdQRK3DoVyNuHFdm2VKehPWHw/RSeT7xVyD8dzfOc7B04u8ffohq6UgwnDK2G2i5J3n95dsEpI/VJrf8w==" Feb 13 11:34:10 volumio go-librespot[22534]: time="2026-02-13T11:34:10+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 11:34:10 volumio go-librespot[22534]: time="2026-02-13T11:34:10+07:00" level=debug msg="completed keyexchange" Feb 13 11:34:10 volumio go-librespot[22534]: time="2026-02-13T11:34:10+07:00" level=debug msg="completed challenge" Feb 13 11:34:10 volumio go-librespot[22534]: time="2026-02-13T11:34: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 13 11:34:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 11:34:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 11:34:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 70. Feb 13 11:34:13 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 11:34:13 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 11:34:13 volumio go-librespot[22542]: go-librespot daemon starting... Feb 13 11:34:13 volumio go-librespot[22543]: time="2026-02-13T11:34:13+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 11:34:13 volumio go-librespot[22543]: time="2026-02-13T11:34:13+07:00" level=debug msg="app state loaded" Feb 13 11:34:13 volumio go-librespot[22543]: time="2026-02-13T11:34:13+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 11:34:14 volumio go-librespot[22543]: time="2026-02-13T11:34: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 13 11:34:14 volumio go-librespot[22543]: time="2026-02-13T11:34:14+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 13 11:34:14 volumio go-librespot[22543]: time="2026-02-13T11:34:14+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 13 11:34:14 volumio go-librespot[22543]: time="2026-02-13T11:34:14+07:00" level=info msg="zeroconf server listening on port 43493" Feb 13 11:34:14 volumio go-librespot[22543]: time="2026-02-13T11:34:14+07:00" level=debug msg="obtained new client token: AAC7OUDGIRNIvJKwceZdRuX1PkmDKRdDe4Jkm3Fohzae35QLoaBRcBPqc/lYoBdnIFcCENp5TrZ5wi0cxwRG0g1UoM6VsZtY2xloG2hr4Qdxp1nCLDTI2wy8EvoiGm6ouWD0wN00mFNU+F/mqpPdQqmcTGyW6OwgDJgPlddmRArjYpQYWoRfQrWcK5nrFnNdgvGgF8gm713juBvW4RyP0fyUzJGPS0MOvTf9SsUAq2Ylo+8MJvQaLHs4Jg==" Feb 13 11:34:14 volumio go-librespot[22543]: time="2026-02-13T11:34:14+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 11:34:14 volumio volumio-remote-updater[643]: [2026-02-13 11:34:14] [error] handle_read_frame error: asio.system:104 (Connection reset by peer) Feb 13 11:34:14 volumio volumio-remote-updater[643]: [2026-02-13 11:34:14] [disconnect] Disconnect close local:[1006,Connection reset by peer] remote:[1006] Feb 13 11:34:14 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 13 11:34:14 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 13 11:34:14 volumio systemd[1]: volumio.service: Consumed 57.844s CPU time. Feb 13 11:34:14 volumio go-librespot[22543]: time="2026-02-13T11:34:14+07:00" level=debug msg="completed keyexchange" Feb 13 11:34:14 volumio go-librespot[22543]: time="2026-02-13T11:34:14+07:00" level=debug msg="completed challenge" Feb 13 11:34:14 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 13 11:34:15 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 13 11:34:15 volumio go-librespot[22543]: time="2026-02-13T11:34: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 13 11:34:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 11:34:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 11:34:15 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 14632. Feb 13 11:34:15 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 13 11:34:15 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 13 11:34:15 volumio systemd[1]: volumio.service: Consumed 57.844s CPU time. Feb 13 11:34:15 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Feb 13 11:34:15 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 13 11:34:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 71. Feb 13 11:34:18 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 11:34:18 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 11:34:18 volumio go-librespot[22574]: go-librespot daemon starting... Feb 13 11:34:18 volumio go-librespot[22575]: time="2026-02-13T11:34:18+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 11:34:18 volumio go-librespot[22575]: time="2026-02-13T11:34:18+07:00" level=debug msg="app state loaded" Feb 13 11:34:18 volumio go-librespot[22575]: time="2026-02-13T11:34:18+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 11:34:18 volumio volumio[22559]: info: ------------------------------------------- Feb 13 11:34:18 volumio volumio[22559]: info: ----- Volumio3 ---- Feb 13 11:34:18 volumio volumio[22559]: info: ------------------------------------------- Feb 13 11:34:18 volumio volumio[22559]: info: ----- System startup ---- Feb 13 11:34:18 volumio volumio[22559]: info: ------------------------------------------- Feb 13 11:34:18 volumio go-librespot[22575]: time="2026-02-13T11:34:18+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 13 11:34:18 volumio go-librespot[22575]: time="2026-02-13T11:34:18+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 13 11:34:18 volumio go-librespot[22575]: time="2026-02-13T11:34:18+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 13 11:34:18 volumio go-librespot[22575]: time="2026-02-13T11:34:18+07:00" level=info msg="zeroconf server listening on port 42675" Feb 13 11:34:19 volumio go-librespot[22575]: time="2026-02-13T11:34:19+07:00" level=debug msg="obtained new client token: AABFiR5o5pLf3MK1hpEsEukem6w6FLl/BgGe37dxXCaXThsadt8cwmpBHVSzaIPyiRF6JwlEdG3SmXjgkV40+9BodKkSKzCy+VZmyI+DXeGN5y/iVUDy3Gv0s89500y2jLXwe7ZPjboyf+hL9NSm4Che25HwAXwN7GuYeTS3ePj0q5vw9CZgAODzhZe7rXNZyXoyGLzSqhsj0jYmDrxqOONnJwSQbL3O/wDp3qcK79KOBoCJJgLG36E=" Feb 13 11:34:19 volumio go-librespot[22575]: time="2026-02-13T11:34:19+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 11:34:19 volumio go-librespot[22575]: time="2026-02-13T11:34:19+07:00" level=debug msg="completed keyexchange" Feb 13 11:34:19 volumio go-librespot[22575]: time="2026-02-13T11:34:19+07:00" level=debug msg="completed challenge" Feb 13 11:34:19 volumio go-librespot[22575]: time="2026-02-13T11:34: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 13 11:34:19 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 11:34:19 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 11:34:19 volumio volumio-remote-updater[643]: [2026-02-13 11:34:19] [connect] Successful connection Feb 13 11:34:20 volumio volumio[22559]: info: MYVOLUMIO Environment detected Feb 13 11:34:20 volumio volumio[22559]: info: Plugin folders cleanup Feb 13 11:34:20 volumio volumio[22559]: info: Scanning into folder /volumio/app/plugins/ Feb 13 11:34:20 volumio volumio[22559]: info: Scanning category audio_interface Feb 13 11:34:20 volumio volumio[22559]: info: Scanning category miscellanea Feb 13 11:34:20 volumio volumio[22559]: info: Scanning category music_service Feb 13 11:34:20 volumio volumio[22559]: info: Scanning category plugins.json Feb 13 11:34:20 volumio volumio[22559]: info: Scanning category system_controller Feb 13 11:34:20 volumio volumio[22559]: info: Scanning category user_interface Feb 13 11:34:20 volumio volumio[22559]: info: Scanning into folder /data/plugins/ Feb 13 11:34:20 volumio volumio[22559]: info: Scanning category music_service Feb 13 11:34:20 volumio volumio[22559]: info: Plugin folders cleanup completed Feb 13 11:34:20 volumio volumio[22559]: info: ------------------------------------------- Feb 13 11:34:20 volumio volumio[22559]: info: ----- Core plugins startup ---- Feb 13 11:34:20 volumio volumio[22559]: info: ------------------------------------------- Feb 13 11:34:20 volumio volumio[22559]: info: Loading plugins from folder /volumio/app/plugins/ Feb 13 11:34:20 volumio volumio[22559]: info: Adding plugin upnp to MyMusic Plugins Feb 13 11:34:20 volumio volumio[22559]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 13 11:34:20 volumio volumio[22559]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 13 11:34:20 volumio volumio[22559]: info: Loading plugins from folder /data/plugins/ Feb 13 11:34:20 volumio volumio[22559]: info: Loading plugin "system"... Feb 13 11:34:20 volumio volumio[22559]: info: Loading plugin "appearance"... Feb 13 11:34:22 volumio volumio[22559]: info: Loading plugin "network"... Feb 13 11:34:22 volumio volumio[22559]: info: Refreshing Cached IP Addresses Feb 13 11:34:22 volumio sudo[22596]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 13 11:34:22 volumio sudo[22596]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 11:34:22 volumio sudo[22596]: pam_unix(sudo:session): session closed for user root Feb 13 11:34:22 volumio sudo[22598]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 13 11:34:22 volumio sudo[22598]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 11:34:22 volumio volumio[22559]: info: Loading plugin "services"... Feb 13 11:34:22 volumio volumio[22559]: info: Loading plugin "alsa_controller"... Feb 13 11:34:22 volumio sudo[22598]: pam_unix(sudo:session): session closed for user root Feb 13 11:34:22 volumio sudo[22608]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 13 11:34:22 volumio sudo[22608]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 11:34:22 volumio volumio[22559]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 13 11:34:22 volumio volumio[22559]: info: Loading plugin "wizard"... Feb 13 11:34:22 volumio volumio[22559]: info: Loading plugin "networkfs"... Feb 13 11:34:22 volumio volumio[22559]: info: Starting Udev Watcher for removable devices Feb 13 11:34:22 volumio volumio[22559]: info: Ignoring mount for partition: boot Feb 13 11:34:22 volumio volumio[22559]: info: Ignoring mount for partition: volumio Feb 13 11:34:22 volumio volumio[22559]: info: Ignoring mount for partition: volumio_data Feb 13 11:34:22 volumio volumio[22559]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 13 11:34:22 volumio volumio[22559]: info: Loading plugin "volumio_command_line_client"... Feb 13 11:34:22 volumio volumio[22559]: info: Loading plugin "upnp"... Feb 13 11:34:22 volumio volumio[22559]: info: [1770957262627] Starting Upmpd Daemon Feb 13 11:34:22 volumio volumio[22559]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 13 11:34:22 volumio volumio[22559]: info: Loading plugin "my_music"... Feb 13 11:34:22 volumio volumio[22559]: info: Loading plugin "mpd"... Feb 13 11:34:22 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 72. Feb 13 11:34:22 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 11:34:22 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 11:34:22 volumio go-librespot[22629]: go-librespot daemon starting... Feb 13 11:34:22 volumio go-librespot[22630]: time="2026-02-13T11:34:22+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 11:34:22 volumio go-librespot[22630]: time="2026-02-13T11:34:22+07:00" level=debug msg="app state loaded" Feb 13 11:34:22 volumio go-librespot[22630]: time="2026-02-13T11:34:22+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 11:34:23 volumio volumio[22559]: info: Loading plugin "upnp_browser"... Feb 13 11:34:23 volumio go-librespot[22630]: time="2026-02-13T11:34: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-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 13 11:34:23 volumio go-librespot[22630]: time="2026-02-13T11:34:23+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 13 11:34:23 volumio go-librespot[22630]: time="2026-02-13T11:34:23+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 13 11:34:23 volumio go-librespot[22630]: time="2026-02-13T11:34:23+07:00" level=info msg="zeroconf server listening on port 42159" Feb 13 11:34:23 volumio go-librespot[22630]: time="2026-02-13T11:34:23+07:00" level=debug msg="obtained new client token: AADBJnbnUCo+hcOdUyo+y+qjg5SHBvEDOMJBljyCT6cXunAJNzUj2p0yWrHogAIuE12uMmPGSnRBMGeG3Unp9KLW1XcIH9i1Q7i79X/wtvvErlItaeQ6jyUDZxhHpO0xWNJDPylIqh9M0eLuSREKbPD+AeFVU/ZQ5IXqGvTDHvOkh08hsSRLgCwa/UHxcnGj5W7WqmUT5pjm/EDTucHGbBdvV4AItpRUgLMyMkJntl8M+N0Z+YG0o3Isug==" Feb 13 11:34:23 volumio go-librespot[22630]: time="2026-02-13T11:34:23+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 11:34:23 volumio go-librespot[22630]: time="2026-02-13T11:34:23+07:00" level=debug msg="completed keyexchange" Feb 13 11:34:23 volumio go-librespot[22630]: time="2026-02-13T11:34:23+07:00" level=debug msg="completed challenge" Feb 13 11:34:23 volumio go-librespot[22630]: time="2026-02-13T11:34:23+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 13 11:34:23 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 11:34:23 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 11:34:25 volumio sudo[22608]: pam_unix(sudo:session): session closed for user root Feb 13 11:34:26 volumio volumio[22559]: info: Starting UPNP Browser Feb 13 11:34:26 volumio volumio[22559]: info: Loading plugin "alarm-clock"... Feb 13 11:34:26 volumio volumio[22559]: info: Loading plugin "airplay_emulation"... Feb 13 11:34:26 volumio volumio[22559]: info: Starting Shairport Sync Feb 13 11:34:26 volumio volumio[22559]: info: Loading plugin "last_100"... Feb 13 11:34:26 volumio volumio[22559]: info: Loading plugin "webradio"... Feb 13 11:34:26 volumio volumio[22559]: info: Loading plugin "i2s_dacs"... Feb 13 11:34:26 volumio volumio[22559]: info: Loading plugin "volumiodiscovery"... Feb 13 11:34:26 volumio volumio[22559]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 13 11:34:26 volumio node[22559]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 13 11:34:26 volumio volumio[22559]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 13 11:34:26 volumio volumio[22559]: *** WARNING *** For more information see Feb 13 11:34:26 volumio volumio[22559]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 13 11:34:26 volumio volumio[22559]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 13 11:34:26 volumio volumio[22559]: *** WARNING *** For more information see Feb 13 11:34:26 volumio node[22559]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 13 11:34:26 volumio node[22559]: *** WARNING *** For more information see Feb 13 11:34:26 volumio node[22559]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 13 11:34:26 volumio node[22559]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 13 11:34:26 volumio node[22559]: *** WARNING *** For more information see Feb 13 11:34:26 volumio volumio[22559]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 13 11:34:26 volumio volumio[22559]: info: Discovery: Started advertising with name: Volumio Feb 13 11:34:26 volumio volumio[22559]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 13 11:34:26 volumio volumio[22559]: info: Loading plugin "spop"... Feb 13 11:34:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 73. Feb 13 11:34:27 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 11:34:27 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 11:34:27 volumio go-librespot[22640]: go-librespot daemon starting... Feb 13 11:34:27 volumio go-librespot[22641]: time="2026-02-13T11:34:27+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 11:34:27 volumio go-librespot[22641]: time="2026-02-13T11:34:27+07:00" level=debug msg="app state loaded" Feb 13 11:34:27 volumio go-librespot[22641]: time="2026-02-13T11:34:27+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 11:34:27 volumio go-librespot[22641]: time="2026-02-13T11:34:27+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 13 11:34:27 volumio go-librespot[22641]: time="2026-02-13T11:34:27+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 13 11:34:27 volumio go-librespot[22641]: time="2026-02-13T11:34:27+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 13 11:34:27 volumio go-librespot[22641]: time="2026-02-13T11:34:27+07:00" level=info msg="zeroconf server listening on port 46609" Feb 13 11:34:27 volumio go-librespot[22641]: time="2026-02-13T11:34:27+07:00" level=debug msg="obtained new client token: AABTB499bYrXbZ8Lgs9SK8aixIKoAUB6Cf3OUg+P40U/vCMs0WHORnofxqYU/ICyXnNSmC64crst5a5Lpz6siosaZstzKlfAe733PpJlPmMRyxZOciiRFu5bWdTBm/Q8cmz1aexjS4EZyZ3DQF9+4p4QO2+qEfxtntLXRrIyxzzhTIHX/JxoTAloq9L922yz2ae7/4mvwQLzXuUiKhTW2omE2uUiC0W0zkm0oPkEirRyqq1fsl/Y3j8ABg==" Feb 13 11:34:28 volumio go-librespot[22641]: time="2026-02-13T11:34:28+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 11:34:28 volumio go-librespot[22641]: time="2026-02-13T11:34:28+07:00" level=debug msg="completed keyexchange" Feb 13 11:34:28 volumio go-librespot[22641]: time="2026-02-13T11:34:28+07:00" level=debug msg="completed challenge" Feb 13 11:34:28 volumio go-librespot[22641]: time="2026-02-13T11:34:28+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 13 11:34:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 11:34:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 11:34:28 volumio volumio[22559]: info: Loading plugin "ytcr"... Feb 13 11:34:31 volumio volumio[22559]: info: Loading plugin "ytmusic"... Feb 13 11:34:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 74. Feb 13 11:34:31 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 11:34:31 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 11:34:31 volumio go-librespot[22648]: go-librespot daemon starting... Feb 13 11:34:31 volumio go-librespot[22649]: time="2026-02-13T11:34:31+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 11:34:31 volumio go-librespot[22649]: time="2026-02-13T11:34:31+07:00" level=debug msg="app state loaded" Feb 13 11:34:31 volumio go-librespot[22649]: time="2026-02-13T11:34:31+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 11:34:32 volumio volumio-remote-updater[643]: [2026-02-13 11:34:32] [connect] Successful connection Feb 13 11:34:32 volumio go-librespot[22649]: time="2026-02-13T11:34: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-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 13 11:34:32 volumio go-librespot[22649]: time="2026-02-13T11:34:32+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 13 11:34:32 volumio go-librespot[22649]: time="2026-02-13T11:34:32+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 13 11:34:32 volumio go-librespot[22649]: time="2026-02-13T11:34:32+07:00" level=info msg="zeroconf server listening on port 41025" Feb 13 11:34:32 volumio volumio[22559]: info: Loading plugin "outputs"... Feb 13 11:34:32 volumio volumio[22559]: info: Loading plugin "albumart"... Feb 13 11:34:32 volumio volumio[22559]: info: Plugin example_plugin is not enabled Feb 13 11:34:32 volumio volumio[22559]: info: Loading plugin "inputs"... Feb 13 11:34:32 volumio volumio[22559]: info: Loading plugin "updater_comm"... Feb 13 11:34:32 volumio go-librespot[22649]: time="2026-02-13T11:34:32+07:00" level=debug msg="obtained new client token: AAB8EqsNjRRTyRlWTDwqOmk8oilQHxv4Xkoul8Bml02Hcc2TNJuqtNwCTQPS1QjTkW0gJNBcg6ii8rvUWN9BNvd489yoG0+jXgQ4wch3qj1qtPNpzo/qxv8U6hNx+XrVBl/Evlew/rzk3HqsFTe+Ey7DQ0Dcy1DwqDrGfvs23RF+wz7MxeeG0jg1kkK0CuBZ2yAUG/nc57tMfewj5+6SCVnKWN5v0a5y8nzjwSBxwjxcGSftDDstsXZcmQ==" Feb 13 11:34:32 volumio go-librespot[22649]: time="2026-02-13T11:34:32+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 11:34:32 volumio go-librespot[22649]: time="2026-02-13T11:34:32+07:00" level=debug msg="completed keyexchange" Feb 13 11:34:32 volumio go-librespot[22649]: time="2026-02-13T11:34:32+07:00" level=debug msg="completed challenge" Feb 13 11:34:32 volumio volumio[22559]: info: Plugin mpdemulation is not enabled Feb 13 11:34:32 volumio volumio[22559]: info: Loading plugin "rest_api"... Feb 13 11:34:33 volumio volumio[22559]: info: Loading plugin "websocket"... Feb 13 11:34:33 volumio volumio[22559]: info: Starting Socket.io Server version 1.7.4 Feb 13 11:34:33 volumio volumio[22559]: info: Loading plugin "RoonBridge"... Feb 13 11:34:33 volumio go-librespot[22649]: time="2026-02-13T11:34: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 13 11:34:33 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 11:34:33 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 11:34:33 volumio volumio[22559]: info: Applying required configuration parameters for plugin RoonBridge Feb 13 11:34:33 volumio volumio[22559]: info: Loading i18n strings for locale en Feb 13 11:34:33 volumio volumio[22559]: Updating browse sources language Feb 13 11:34:33 volumio volumio[22559]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 13 11:34:33 volumio volumio[22670]: Forking 3 albumart workers Feb 13 11:34:33 volumio volumio[22559]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 13 11:34:33 volumio volumio[22559]: info: CoreCommandRouter::initPlayerControls Feb 13 11:34:33 volumio volumio[22559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 11:34:33 volumio volumio[22559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 11:34:33 volumio volumio[22559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 11:34:33 volumio volumio[22559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 11:34:33 volumio volumio[22559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 11:34:33 volumio volumio[22559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 11:34:33 volumio volumio[22559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 11:34:33 volumio volumio[22559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 11:34:33 volumio volumio[22559]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 13 11:34:33 volumio volumio[22559]: Express server listening on port 3000 Feb 13 11:34:33 volumio volumio[22559]: [Metrics] WebUI: 16s 58.39ms Feb 13 11:34:33 volumio volumio[22559]: info: CoreStateMachine::resetVolumioState Feb 13 11:34:33 volumio volumio[22559]: info: CoreStateMachine::getcurrentVolume Feb 13 11:34:33 volumio volumio[22559]: info: CoreCommandRouter::volumioRetrievevolume Feb 13 11:34:33 volumio sudo[22715]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 13 11:34:33 volumio sudo[22715]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 11:34:34 volumio sudo[22715]: pam_unix(sudo:session): session closed for user root Feb 13 11:34:34 volumio sudo[22717]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 13 11:34:34 volumio sudo[22717]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 11:34:34 volumio sudo[22717]: pam_unix(sudo:session): session closed for user root Feb 13 11:34:34 volumio volumio[22559]: info: Volumio Network Manager: Network status updated: 1 Feb 13 11:34:34 volumio volumio[22559]: info: VolumeController:: Volume=100 Mute =false Feb 13 11:34:34 volumio volumio[22559]: info: CoreStateMachine::pushState Feb 13 11:34:34 volumio volumio[22559]: info: CorePlayQueue::getTrack 0 Feb 13 11:34:34 volumio volumio[22559]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 13 11:34:34 volumio volumio[22559]: info: CoreCommandRouter::volumioPushState Feb 13 11:34:34 volumio volumio[22559]: info: CoreStateMachine::updateTrackBlock Feb 13 11:34:34 volumio volumio[22559]: info: CorePlayQueue::getTrackBlock Feb 13 11:34:34 volumio volumio[22559]: info: CoreCommandRouter::volumioRetrievevolume Feb 13 11:34:34 volumio volumio-remote-updater[643]: [2026-02-13 11:34:34] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1770957272 101 Feb 13 11:34:34 volumio volumio[22559]: 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 13 11:34:34 volumio volumio[22559]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 11:34:34 volumio volumio[22559]: info: Reloading queue from file Feb 13 11:34:34 volumio volumio[22559]: info: CoreStateMachine::setRepeat null single undefined Feb 13 11:34:34 volumio volumio[22559]: info: CoreStateMachine::pushState Feb 13 11:34:34 volumio volumio[22559]: info: CorePlayQueue::getTrack 0 Feb 13 11:34:34 volumio volumio[22559]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 13 11:34:34 volumio volumio[22559]: info: CoreCommandRouter::volumioPushState Feb 13 11:34:34 volumio volumio[22559]: info: CoreStateMachine::setRandom null Feb 13 11:34:34 volumio volumio[22559]: info: CoreStateMachine::pushState Feb 13 11:34:34 volumio volumio[22559]: info: CorePlayQueue::getTrack 0 Feb 13 11:34:34 volumio volumio[22559]: info: CoreCommandRouter::volumioPushState Feb 13 11:34:34 volumio volumio[22559]: info: Setting Device type: Raspberry PI Feb 13 11:34:34 volumio volumio[22559]: info: Completed loading Core Plugins Feb 13 11:34:34 volumio volumio[22559]: info: Preparing to generate the ALSA configuration file Feb 13 11:34:34 volumio volumio[22559]: info: VolumeController:: Volume=100 Mute =false Feb 13 11:34:34 volumio volumio[22559]: info: CoreStateMachine::pushState Feb 13 11:34:34 volumio volumio[22559]: info: CorePlayQueue::getTrack 0 Feb 13 11:34:34 volumio volumio[22559]: info: CoreCommandRouter::volumioPushState Feb 13 11:34:34 volumio volumio[22559]: info: Asound.conf file unchanged, so no further update is needed Feb 13 11:34:34 volumio volumio[22559]: info: Output device has changed, restarting MPD Feb 13 11:34:34 volumio volumio[22559]: info: Output device has changed, restarting Shairport Sync Feb 13 11:34:34 volumio volumio[22559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 11:34:34 volumio volumio[22559]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 11:34:34 volumio sudo[22734]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 13 11:34:35 volumio sudo[22734]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 11:34:35 volumio sudo[22736]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 13 11:34:35 volumio sudo[22736]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 11:34:35 volumio volumio[22559]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 13 11:34:35 volumio sudo[22736]: pam_unix(sudo:session): session closed for user root Feb 13 11:34:35 volumio volumio[22559]: info: ___________ START PLUGINS ___________ Feb 13 11:34:35 volumio volumio[22559]: info: ControllerMpd::onStart: Initializing MPD Feb 13 11:34:35 volumio volumio[22559]: info: Creating MPD Configuration file Feb 13 11:34:35 volumio sudo[22738]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 13 11:34:35 volumio sudo[22738]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 11:34:35 volumio sudo[22734]: pam_unix(sudo:session): session closed for user root Feb 13 11:34:35 volumio volumio[22559]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 13 11:34:35 volumio volumio[22559]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 13 11:34:35 volumio volumio[22559]: info: [1770957275162] CoreMusicLibrary::Adding element Media Servers Feb 13 11:34:35 volumio volumio[22559]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 13 11:34:35 volumio volumio[22559]: info: UPNP Browser: Client initialized successfully Feb 13 11:34:35 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 13 11:34:35 volumio volumio[22559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 11:34:35 volumio volumio[22559]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 11:34:35 volumio sudo[22747]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 13 11:34:35 volumio sudo[22747]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 11:34:35 volumio sudo[22749]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 13 11:34:35 volumio volumio[22559]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 13 11:34:35 volumio volumio[22559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 11:34:35 volumio volumio[22559]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 11:34:35 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 13 11:34:35 volumio sudo[22749]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 11:34:35 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 13 11:34:35 volumio systemd[1]: mpd.service: Consumed 7.090s CPU time. Feb 13 11:34:35 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 13 11:34:35 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 13 11:34:35 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 13 11:34:35 volumio sudo[22747]: pam_unix(sudo:session): session closed for user root Feb 13 11:34:35 volumio volumio[22559]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 13 11:34:35 volumio volumio[22559]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 13 11:34:35 volumio volumio[22559]: info: [1770957275431] CoreMusicLibrary::Adding element Last_100 Feb 13 11:34:35 volumio volumio[22559]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 13 11:34:35 volumio volumio[22559]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 13 11:34:35 volumio volumio[22559]: info: [1770957275441] CoreMusicLibrary::Adding element Webradio Feb 13 11:34:35 volumio volumio[22559]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 13 11:34:35 volumio volumio[22559]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 13 11:34:35 volumio volumio[22559]: info: Initializing BBC Radios Feb 13 11:34:35 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 13 11:34:35 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 13 11:34:35 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 13 11:34:35 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 13 11:34:35 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 13 11:34:35 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 13 11:34:35 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 13 11:34:35 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 13 11:34:35 volumio volumio[22559]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 13 11:34:35 volumio volumio[22559]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 11:34:35 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 13 11:34:35 volumio volumio[22559]: info: Creating Spotify config file Feb 13 11:34:35 volumio volumio[22559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 11:34:36 volumio sudo[22776]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 13 11:34:36 volumio sudo[22776]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 13 11:34:36 volumio sudo[22776]: pam_unix(sudo:session): session closed for user root Feb 13 11:34:36 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 75. Feb 13 11:34:36 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 11:34:36 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 11:34:36 volumio go-librespot[22779]: go-librespot daemon starting... Feb 13 11:34:36 volumio go-librespot[22780]: time="2026-02-13T11:34:36+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 11:34:36 volumio volumio[22684]: Starting albumart workers Feb 13 11:34:37 volumio volumio[22683]: Starting albumart workers Feb 13 11:34:37 volumio go-librespot[22780]: time="2026-02-13T11:34:37+07:00" level=info msg="zeroconf server listening on port 40501" Feb 13 11:34:37 volumio volumio[22559]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 13 11:34:37 volumio volumio[22559]: info: [1770957277166] CoreMusicLibrary::Adding element YouTube Music Feb 13 11:34:37 volumio volumio[22559]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 13 11:34:37 volumio volumio[22559]: Cannot find translation for source YouTube Music Feb 13 11:34:37 volumio volumio[22559]: info: Volumio Calling Home Feb 13 11:34:37 volumio sudo[22788]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Feb 13 11:34:37 volumio sudo[22788]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 11:34:37 volumio sudo[22788]: pam_unix(sudo:session): session closed for user root Feb 13 11:34:37 volumio volumio[22685]: Starting albumart workers Feb 13 11:34:37 volumio volumio[22559]: info: Discovery: adding 921648ed-0557-4d70-9927-1420682efcc7 Feb 13 11:34:37 volumio volumio[22559]: info: Discovery: Found device Volumio Feb 13 11:34:37 volumio volumio[22559]: info: CoreCommandRouter::volumioGetState Feb 13 11:34:37 volumio volumio[22559]: info: CorePlayQueue::getTrack 0 Feb 13 11:34:37 volumio volumio[22559]: info: MPD Permissions set Feb 13 11:34:37 volumio volumio[22559]: info: MPD Permissions set Feb 13 11:34:37 volumio volumio[22559]: info: Upmpdcli Daemon Started Feb 13 11:34:38 volumio volumio[22559]: info: Discovery: this is already registered, 921648ed-0557-4d70-9927-1420682efcc7 Feb 13 11:34:38 volumio volumio[22559]: info: Discovery: Found device Volumio Feb 13 11:34:38 volumio volumio[22559]: info: CoreCommandRouter::volumioGetState Feb 13 11:34:38 volumio volumio[22559]: info: CorePlayQueue::getTrack 0 Feb 13 11:34:38 volumio volumio[22559]: info: Spotify config file written Feb 13 11:34:38 volumio sudo[22794]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 13 11:34:38 volumio sudo[22794]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 11:34:38 volumio volumio[22559]: 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 13 11:34:38 volumio volumio[22559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 11:34:38 volumio volumio[22559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 11:34:38 volumio volumio[22559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 11:34:38 volumio volumio[22559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 11:34:38 volumio volumio[22559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 11:34:38 volumio volumio[22559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 11:34:38 volumio volumio[22559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 11:34:38 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Feb 13 11:34:38 volumio volumio[22559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 11:34:38 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Feb 13 11:34:38 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 11:34:38 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 11:34:38 volumio go-librespot[22798]: go-librespot daemon starting... Feb 13 11:34:38 volumio volumio[22559]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 11:34:38 volumio sudo[22794]: pam_unix(sudo:session): session closed for user root Feb 13 11:34:38 volumio go-librespot[22802]: time="2026-02-13T11:34:38+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 11:34:38 volumio go-librespot[22802]: time="2026-02-13T11:34:38+07:00" level=debug msg="app state loaded" Feb 13 11:34:38 volumio go-librespot[22802]: time="2026-02-13T11:34:38+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 11:34:38 volumio volumio[22559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 11:34:38 volumio volumio[22559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 11:34:38 volumio volumio[22559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 11:34:38 volumio volumio[22559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 11:34:38 volumio volumio[22559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 11:34:38 volumio volumio[22559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 11:34:38 volumio volumio[22559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 11:34:38 volumio volumio[22559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 11:34:38 volumio volumio[22559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 11:34:38 volumio volumio[22559]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 11:34:38 volumio volumio[22559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 11:34:38 volumio volumio[22559]: info: No need to fix Spotify hosts Feb 13 11:34:39 volumio go-librespot[22802]: time="2026-02-13T11:34:39+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 13 11:34:39 volumio go-librespot[22802]: time="2026-02-13T11:34:39+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 13 11:34:39 volumio go-librespot[22802]: time="2026-02-13T11:34:39+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 13 11:34:39 volumio go-librespot[22802]: time="2026-02-13T11:34:39+07:00" level=info msg="zeroconf server listening on port 38139" Feb 13 11:34:39 volumio go-librespot[22802]: time="2026-02-13T11:34:39+07:00" level=debug msg="obtained new client token: AADQVXJf7koC9DEhggtxYB7CgD80cEftHhU3WT0DLDkqC1k9WgIaMDxGTYs3+W9tBgjQE5nUskYv3Pe3gN0u0k1pTz0NtlN/BD5Yp9zvrIqJOL9BUj+RjPLd3KuKlnzlY82pq99zMpUVDRK+oX9GvjCyPVObJ2spMXr/dzGhCUVJ2+FJDl+S3o9oJJcrtVMClIIOuSul8DLz14D/V2ME06P1+O25XyxHPp48YoYfirS6xxPcpkYsWZrI7g==" Feb 13 11:34:39 volumio volumio[22559]: info: Volumio called home Feb 13 11:34:39 volumio go-librespot[22802]: time="2026-02-13T11:34:39+07:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.241.202:4070: connect: connection refused" Feb 13 11:34:39 volumio go-librespot[22802]: time="2026-02-13T11:34:39+07:00" level=debug msg="connected to ap-gae2.spotify.com:443" Feb 13 11:34:39 volumio go-librespot[22802]: time="2026-02-13T11:34:39+07:00" level=debug msg="completed keyexchange" Feb 13 11:34:39 volumio go-librespot[22802]: time="2026-02-13T11:34:39+07:00" level=debug msg="completed challenge" Feb 13 11:34:39 volumio go-librespot[22802]: time="2026-02-13T11:34:39+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 13 11:34:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 11:34:39 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 11:34:39 volumio volumio[22559]: info: Starting Shairport Sync Feb 13 11:34:39 volumio volumio[22559]: info: Starting Shairport Sync Feb 13 11:34:40 volumio volumio[22559]: info: Starting Shairport Sync Feb 13 11:34:40 volumio sudo[22834]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 13 11:34:40 volumio sudo[22834]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 11:34:40 volumio sudo[22836]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 13 11:34:40 volumio sudo[22836]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 11:34:40 volumio sudo[22839]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 13 11:34:40 volumio sudo[22839]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 11:34:40 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 13 11:34:40 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 13 11:34:40 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 13 11:34:40 volumio systemd[1]: shairport-sync.service: Consumed 2.582s CPU time. Feb 13 11:34:40 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 13 11:34:40 volumio volumio[22559]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 13 11:34:40 volumio volumio[22559]: SPOTIFY: BQDfXPtBB5Y17xAwnxlfUN-JwC7fGEvPwjByYY7s1Brq4KC07ZmVEAJcZLr-6Pqas9jfVmt61vHCYza8IuQ2yuVB6Cd54ngre8Ft88lrAX7LBFObT5gvjbjkIeNnKkQNgj-oDPaqaEq98xq0uFyYXPsu8u2nKR2f98WoTXB9dvMMXnWlx7Ib7_fXw2tEcrCIZkc5GS2oKkb8-JMi0yJ2DCmHWvSZIZP6SArH5CwL4fsSIrNDnPNfoww69Eeu7FfuekcNWkeaIrwYDVTe_6sJMy9TAvYHjMEGRpltxMvCxNl__iJZBzAJk0nV Feb 13 11:34:40 volumio volumio[22559]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 13 11:34:40 volumio volumio[22559]: info: New Spotify access token = BQDfXPtBB5Y17xAwnxlfUN-JwC7fGEvPwjByYY7s1Brq4KC07ZmVEAJcZLr-6Pqas9jfVmt61vHCYza8IuQ2yuVB6Cd54ngre8Ft88lrAX7LBFObT5gvjbjkIeNnKkQNgj-oDPaqaEq98xq0uFyYXPsu8u2nKR2f98WoTXB9dvMMXnWlx7Ib7_fXw2tEcrCIZkc5GS2oKkb8-JMi0yJ2DCmHWvSZIZP6SArH5CwL4fsSIrNDnPNfoww69Eeu7FfuekcNWkeaIrwYDVTe_6sJMy9TAvYHjMEGRpltxMvCxNl__iJZBzAJk0nV Feb 13 11:34:40 volumio volumio[22559]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 13 11:34:40 volumio sudo[22834]: pam_unix(sudo:session): session closed for user root Feb 13 11:34:40 volumio sudo[22836]: pam_unix(sudo:session): session closed for user root Feb 13 11:34:40 volumio sudo[22839]: pam_unix(sudo:session): session closed for user root Feb 13 11:34:40 volumio volumio[22559]: info: Shairport-Sync Started Feb 13 11:34:40 volumio volumio[22559]: Error adding Membership: Error: addMembership EINVAL Feb 13 11:34:40 volumio volumio[22559]: info: Shairport-Sync Started Feb 13 11:34:40 volumio volumio[22559]: info: Shairport-Sync Started Feb 13 11:34:40 volumio volumio[22559]: info: CoreCommandRouter::volumioGetState Feb 13 11:34:40 volumio volumio[22559]: info: CorePlayQueue::getTrack 0 Feb 13 11:34:40 volumio volumio[22559]: 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 13 11:34:40 volumio volumio[22559]: info: Spotify Successfully logged in Feb 13 11:34:41 volumio volumio[22559]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 13 11:34:41 volumio volumio[22559]: info: [1770957281008] CoreMusicLibrary::Adding element Spotify Feb 13 11:34:41 volumio volumio[22559]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 13 11:34:41 volumio volumio[22559]: Cannot find translation for source YouTube Music Feb 13 11:34:41 volumio volumio[22559]: Cannot find translation for source Spotify Feb 13 11:34:41 volumio volumio[22559]: info: [yt-cast-receiver] DIAL server listening on port 8098 Feb 13 11:34:41 volumio volumio[22559]: info: CoreCommandRouter::volumioRetrievevolume Feb 13 11:34:41 volumio volumio[22559]: info: VolumeController:: Volume=100 Mute =false Feb 13 11:34:41 volumio volumio[22559]: info: CoreCommandRouter::volumioGetState Feb 13 11:34:41 volumio volumio[22559]: info: CorePlayQueue::getTrack 0 Feb 13 11:34:41 volumio volumio[22559]: info: CoreStateMachine::pushState Feb 13 11:34:41 volumio volumio[22559]: info: CorePlayQueue::getTrack 0 Feb 13 11:34:41 volumio volumio[22559]: info: CoreCommandRouter::volumioPushState Feb 13 11:34:42 volumio volumio[22559]: info: go-librespot daemon successfully initialized Feb 13 11:34:43 volumio mpd[22778]: 2026-02-13T11:34:43 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 13 11:34:43 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Feb 13 11:34:43 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 13 11:34:43 volumio sudo[22738]: pam_unix(sudo:session): session closed for user root Feb 13 11:34:43 volumio sudo[22749]: pam_unix(sudo:session): session closed for user root Feb 13 11:34:43 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 11:34:43 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 11:34:43 volumio go-librespot[22876]: go-librespot daemon starting... Feb 13 11:34:43 volumio go-librespot[22879]: time="2026-02-13T11:34:43+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 11:34:43 volumio go-librespot[22879]: time="2026-02-13T11:34:43+07:00" level=debug msg="app state loaded" Feb 13 11:34:43 volumio go-librespot[22879]: time="2026-02-13T11:34:43+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 11:34:43 volumio volumio[22559]: error: MPD error: The expression evaluated to a falsy value: Feb 13 11:34:43 volumio volumio[22559]: assert.ok(self.idling) Feb 13 11:34:43 volumio volumio[22559]: error: The expression evaluated to a falsy value: Feb 13 11:34:43 volumio volumio[22559]: assert.ok(self.idling) Feb 13 11:34:43 volumio volumio[22559]: error: updateQueue error: null Feb 13 11:34:43 volumio volumio[22559]: info: MPD running with PID22778 Feb 13 11:34:43 volumio volumio[22559]: ,establishing connection Feb 13 11:34:43 volumio volumio[22559]: info: Completed starting Core Plugins Feb 13 11:34:43 volumio volumio[22559]: info: ------------------------------------------- Feb 13 11:34:43 volumio volumio[22559]: info: ----- MyVolumio plugins startup ---- Feb 13 11:34:43 volumio volumio[22559]: info: ------------------------------------------- Feb 13 11:34:43 volumio volumio[22559]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 13 11:34:43 volumio volumio[22559]: error: updateQueue error: null Feb 13 11:34:43 volumio go-librespot[22879]: time="2026-02-13T11:34:43+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 13 11:34:43 volumio go-librespot[22879]: time="2026-02-13T11:34:43+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 13 11:34:43 volumio go-librespot[22879]: time="2026-02-13T11:34:43+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 13 11:34:43 volumio go-librespot[22879]: time="2026-02-13T11:34:43+07:00" level=info msg="zeroconf server listening on port 35565" Feb 13 11:34:43 volumio go-librespot[22879]: time="2026-02-13T11:34:43+07:00" level=debug msg="obtained new client token: AABhJ7q0DL/Kv0HQWvO64xp4oX+RLkC74Vn3UocCLYvrSqCAUg7xyYdbRi7SzAWLMcVXSpBXWFwnRB2cCsXNMJTLhWDHFvmcXyZnKPuSdkpKMszCim3zA2IWH4xxKpksysAj4zUSmpztXbsmjaCjvH0OZk1ifDvWsyn9RdPIc3fDYW6dEgISOKKPRJF3L6au4NAmJ0FSy+pucrlW61z6bynQE8fF7RwTbiyx7+u/bloxxdpcS+L8djRzlg==" Feb 13 11:34:44 volumio go-librespot[22879]: time="2026-02-13T11:34:44+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 11:34:44 volumio go-librespot[22879]: time="2026-02-13T11:34:44+07:00" level=debug msg="completed keyexchange" Feb 13 11:34:44 volumio go-librespot[22879]: time="2026-02-13T11:34:44+07:00" level=debug msg="completed challenge" Feb 13 11:34:44 volumio go-librespot[22879]: time="2026-02-13T11:34:44+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 13 11:34:44 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 11:34:44 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 11:34:45 volumio volumio[22559]: info: Initializing connection to go-librespot Websocket Feb 13 11:34:45 volumio volumio[22559]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 13 11:34:47 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Feb 13 11:34:47 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 11:34:47 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 11:34:47 volumio go-librespot[22887]: go-librespot daemon starting... Feb 13 11:34:47 volumio go-librespot[22888]: time="2026-02-13T11:34:47+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 11:34:47 volumio go-librespot[22888]: time="2026-02-13T11:34:47+07:00" level=debug msg="app state loaded" Feb 13 11:34:47 volumio go-librespot[22888]: time="2026-02-13T11:34:47+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 11:34:47 volumio go-librespot[22888]: time="2026-02-13T11:34:47+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 13 11:34:47 volumio go-librespot[22888]: time="2026-02-13T11:34:47+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 13 11:34:47 volumio go-librespot[22888]: time="2026-02-13T11:34:47+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 13 11:34:47 volumio go-librespot[22888]: time="2026-02-13T11:34:47+07:00" level=info msg="zeroconf server listening on port 40649" Feb 13 11:34:48 volumio go-librespot[22888]: time="2026-02-13T11:34:48+07:00" level=debug msg="obtained new client token: AADbUufUtLH0L4KFOQIcya2PoMG+pIqKjqYY013q+ohmtQHEUhzjeiRDECyjiJ2DYsLQULpOUQCr6m1BxUgFiE2oOaY1kMpj6aaLxYrAjJjDcvkW0JABkT+A/YDUSUpoTfCujhuQzDDA6qV/jhEQmMK7FYiC3IvKUeqEm5186TyFCZp/RgDJJ1+4UK3fzlAKOymcrd4nHkDzzfFNIwHJ6vRIJBjn4mQ5UEetnjXrxFVlVhEJ7UJQUjE=" Feb 13 11:34:48 volumio volumio[22559]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 13 11:34:48 volumio go-librespot[22888]: time="2026-02-13T11:34:48+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 11:34:48 volumio go-librespot[22888]: time="2026-02-13T11:34:48+07:00" level=debug msg="completed keyexchange" Feb 13 11:34:48 volumio go-librespot[22888]: time="2026-02-13T11:34:48+07:00" level=debug msg="completed challenge" Feb 13 11:34:48 volumio go-librespot[22888]: time="2026-02-13T11:34:48+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 13 11:34:48 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 11:34:48 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 11:34:48 volumio volumio[22559]: info: Initializing connection to go-librespot Websocket Feb 13 11:34:48 volumio volumio[22559]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 13 11:34:51 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Feb 13 11:34:51 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 11:34:51 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 11:34:51 volumio go-librespot[22895]: go-librespot daemon starting... Feb 13 11:34:51 volumio go-librespot[22896]: time="2026-02-13T11:34:51+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 11:34:51 volumio go-librespot[22896]: time="2026-02-13T11:34:51+07:00" level=debug msg="app state loaded" Feb 13 11:34:51 volumio go-librespot[22896]: time="2026-02-13T11:34:51+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 11:34:51 volumio volumio[22559]: info: Initializing connection to go-librespot Websocket Feb 13 11:34:51 volumio go-librespot[22896]: time="2026-02-13T11:34:51+07:00" level=debug msg="new websocket client" Feb 13 11:34:51 volumio volumio[22559]: info: Connection to go-librespot Websocket established Feb 13 11:34:52 volumio go-librespot[22896]: time="2026-02-13T11:34:52+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 13 11:34:52 volumio go-librespot[22896]: time="2026-02-13T11:34:52+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 13 11:34:52 volumio go-librespot[22896]: time="2026-02-13T11:34:52+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 13 11:34:52 volumio go-librespot[22896]: time="2026-02-13T11:34:52+07:00" level=info msg="zeroconf server listening on port 33027" Feb 13 11:34:52 volumio volumio[22559]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 13 11:34:52 volumio volumio[22559]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 13 11:34:52 volumio volumio[22559]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 13 11:34:52 volumio volumio[22559]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 13 11:34:52 volumio volumio[22559]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 13 11:34:52 volumio volumio[22559]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 13 11:34:52 volumio volumio[22559]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 13 11:34:52 volumio volumio[22559]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 13 11:34:52 volumio volumio[22559]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 13 11:34:52 volumio volumio[22559]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 13 11:34:52 volumio volumio[22559]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 13 11:34:52 volumio volumio[22559]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 13 11:34:52 volumio volumio[22559]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 13 11:34:52 volumio volumio[22559]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 13 11:34:52 volumio volumio[22559]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 13 11:34:52 volumio volumio[22559]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 13 11:34:52 volumio volumio[22559]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 13 11:34:52 volumio volumio[22559]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 13 11:34:52 volumio volumio[22559]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 13 11:34:52 volumio volumio[22559]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 13 11:34:52 volumio volumio[22559]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 13 11:34:52 volumio volumio[22559]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 13 11:34:52 volumio volumio[22559]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 13 11:34:52 volumio volumio[22559]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 13 11:34:52 volumio volumio[22559]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 13 11:34:52 volumio volumio[22559]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 13 11:34:52 volumio volumio[22559]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 13 11:34:52 volumio volumio[22559]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 13 11:34:52 volumio volumio[22559]: info: Adding plugin bluetooth to MyMusic Plugins Feb 13 11:34:52 volumio volumio[22559]: info: Adding plugin multiroom to MyMusic Plugins Feb 13 11:34:52 volumio volumio[22559]: info: Adding plugin metavolumio to MyMusic Plugins Feb 13 11:34:52 volumio volumio[22559]: info: Adding plugin cd_controller to MyMusic Plugins Feb 13 11:34:52 volumio volumio[22559]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 13 11:34:52 volumio volumio[22559]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 13 11:34:52 volumio volumio[22559]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 13 11:34:52 volumio volumio[22559]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 13 11:34:52 volumio go-librespot[22896]: time="2026-02-13T11:34:52+07:00" level=debug msg="obtained new client token: AADkBkN7S9uukq/li/P3MU1tl7idjPGvFOlD/jKSXqkym63M2LC9kINpS03NrPnxJNqt9X1nN2GpF8C4Wm013JhuUgi3+MwWvl7AvWsR3/JADft7aUnHzxa+kVxdA6n35kmY7sLBzw2t7bXkqB0ImgEOOH6gXu+vjhSRXPd7sCeMVhe+/wjgyUPBRTo+KkWmLmRpQMVeMHWWLI/choKuWfdyvrh+2a0UGvmUmCr7/qEHbvD5/ttElg8uBA==" Feb 13 11:34:52 volumio go-librespot[22896]: time="2026-02-13T11:34:52+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 11:34:52 volumio go-librespot[22896]: time="2026-02-13T11:34:52+07:00" level=debug msg="completed keyexchange" Feb 13 11:34:52 volumio go-librespot[22896]: time="2026-02-13T11:34:52+07:00" level=debug msg="completed challenge" Feb 13 11:34:52 volumio go-librespot[22896]: time="2026-02-13T11:34:52+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 13 11:34:52 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 11:34:52 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 11:34:54 volumio volumio[22559]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 13 11:34:54 volumio volumio[22559]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 13 11:34:54 volumio volumio[22559]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 11:34:54 volumio volumio[22559]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 11:34:54 volumio volumio[22559]: info: Starting MyVolumio Remote Streaming Endpoints Feb 13 11:34:54 volumio volumio[22559]: info: MyVolumio login type: Token Feb 13 11:34:54 volumio volumio[22559]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 13 11:34:54 volumio volumio[22559]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 13 11:34:55 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Feb 13 11:34:55 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 11:34:55 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 11:34:55 volumio go-librespot[22917]: go-librespot daemon starting... Feb 13 11:34:55 volumio volumio[22559]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 13 11:34:55 volumio volumio[22559]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 13 11:34:55 volumio volumio[22559]: info: Streaming services startup Feb 13 11:34:55 volumio volumio[22559]: info: Starting Streaming Daemon Feb 13 11:34:55 volumio go-librespot[22918]: time="2026-02-13T11:34:55+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 11:34:55 volumio go-librespot[22918]: time="2026-02-13T11:34:55+07:00" level=debug msg="app state loaded" Feb 13 11:34:55 volumio go-librespot[22918]: time="2026-02-13T11:34:55+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 11:34:55 volumio sudo[22925]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 13 11:34:55 volumio sudo[22925]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 11:34:55 volumio volumio[22559]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 13 11:34:56 volumio sudo[22925]: pam_unix(sudo:session): session closed for user root Feb 13 11:34:56 volumio volumio[22559]: info: Getting Spotify volume Feb 13 11:34:56 volumio volumio[22559]: info: Connection to go-librespot Websocket closed Feb 13 11:34:56 volumio volumio[22559]: error: Cannot start Volumio Streaming Daemon Feb 13 11:34:56 volumio volumio[22559]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 13 11:34:56 volumio volumio[22559]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 13 11:34:56 volumio volumio[22559]: 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 13 11:34:56 volumio volumio[22559]: info: CoreCommandRouter::volumioGetState Feb 13 11:34:56 volumio volumio[22559]: info: CorePlayQueue::getTrack 0 Feb 13 11:34:56 volumio volumio[22559]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 13 11:34:56 volumio go-librespot[22918]: time="2026-02-13T11:34:56+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 13 11:34:56 volumio go-librespot[22918]: time="2026-02-13T11:34:56+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 13 11:34:56 volumio go-librespot[22918]: time="2026-02-13T11:34:56+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 13 11:34:56 volumio volumio[22559]: SPOTIFY: SPOTIFY VOLUME undefined Feb 13 11:34:56 volumio volumio[22559]: SPOTIFY: VOLUMIO VOLUME 100 Feb 13 11:34:56 volumio volumio[22559]: info: Aligning Spotify Volume to Volumio Volume Feb 13 11:34:56 volumio volumio[22559]: info: CoreCommandRouter::volumioGetState Feb 13 11:34:56 volumio volumio[22559]: info: CorePlayQueue::getTrack 0 Feb 13 11:34:56 volumio volumio[22559]: info: Setting Spotify Volume from Volumio: 100 Feb 13 11:34:56 volumio go-librespot[22918]: time="2026-02-13T11:34:56+07:00" level=info msg="zeroconf server listening on port 33347" Feb 13 11:34:56 volumio go-librespot[22918]: time="2026-02-13T11:34:56+07:00" level=debug msg="obtained new client token: AABXfGalHZggYL1E4zdwZLXRCeu+bqL4WpelOkRzhS3ut3hf6aCxSEzMsXKuq4I/N5reeRpi1IhrPmdvYyHMUSVFrhFoEx5c4aeZgYgsbiPBrrsI68f/+GZB5/kaNYIp47+EE2/KSXp7Es5h6bVRvLwWdhOihwA7xWHQIf0s9XuWJPMGm8wURftCdCkMf/6Pwj2a/E4ZOfasQA9S0FOATlzU1f6SJ64bDfMSKHeEMf7ej+mr3tluIq30Wg==" Feb 13 11:34:56 volumio go-librespot[22918]: time="2026-02-13T11:34:56+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 11:34:56 volumio go-librespot[22918]: time="2026-02-13T11:34:56+07:00" level=debug msg="completed keyexchange" Feb 13 11:34:56 volumio go-librespot[22918]: time="2026-02-13T11:34:56+07:00" level=debug msg="completed challenge" Feb 13 11:34:56 volumio go-librespot[22918]: time="2026-02-13T11:34:56+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 13 11:34:56 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 11:34:56 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 11:34:56 volumio volumio[22559]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 13 11:34:57 volumio volumio[22559]: Error: socket hang up Feb 13 11:34:57 volumio volumio[22559]: at connResetException (node:internal/errors:720:14) Feb 13 11:34:57 volumio volumio[22559]: at Socket.socketOnEnd (node:_http_client:519:23) Feb 13 11:34:57 volumio volumio[22559]: at Socket.emit (node:events:526:35) Feb 13 11:34:57 volumio volumio[22559]: at endReadableNT (node:internal/streams/readable:1376:12) Feb 13 11:34:57 volumio volumio[22559]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Feb 13 11:34:57 volumio volumio[22559]: code: 'ECONNRESET', Feb 13 11:34:57 volumio volumio[22559]: response: undefined Feb 13 11:34:57 volumio volumio[22559]: } Feb 13 11:34:57 volumio volumio[22559]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 13 11:34:58 volumio sudo[22947]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-13 11:33' Feb 13 11:34:58 volumio sudo[22947]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 11:34:58 volumio sudo[22947]: pam_unix(sudo:session): session closed for user root Feb 13 11:34:58 volumio volumio-remote-updater[643]: [2026-02-13 11:34:58] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 13 11:34:58 volumio volumio-remote-updater[643]: [2026-02-13 11:34:58] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 13 11:34:58 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 13 11:34:58 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 13 11:34:58 volumio systemd[1]: volumio.service: Consumed 55.669s CPU time. Feb 13 11:34:59 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 13 11:34:59 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 13 11:34:59 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 14633. Feb 13 11:34:59 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 13 11:34:59 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 13 11:34:59 volumio systemd[1]: volumio.service: Consumed 55.669s CPU time. Feb 13 11:34:59 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Feb 13 11:34:59 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 13 11:35:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Feb 13 11:35:00 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 11:35:00 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 11:35:00 volumio go-librespot[22981]: go-librespot daemon starting... Feb 13 11:35:00 volumio go-librespot[22982]: time="2026-02-13T11:35:00+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 11:35:00 volumio go-librespot[22982]: time="2026-02-13T11:35:00+07:00" level=debug msg="app state loaded" Feb 13 11:35:00 volumio go-librespot[22982]: time="2026-02-13T11:35:00+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 11:35:00 volumio go-librespot[22982]: time="2026-02-13T11:35:00+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 13 11:35:00 volumio go-librespot[22982]: time="2026-02-13T11:35:00+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 13 11:35:00 volumio go-librespot[22982]: time="2026-02-13T11:35:00+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 13 11:35:00 volumio go-librespot[22982]: time="2026-02-13T11:35:00+07:00" level=info msg="zeroconf server listening on port 38743" Feb 13 11:35:00 volumio go-librespot[22982]: time="2026-02-13T11:35:00+07:00" level=debug msg="obtained new client token: AADey4vdklS1t8YlGg2d63YOQqxwRVetI8fztUbel2qBtitCioicKmb9F93RzD38UoIXCkFncSe23NyzLokw5w49+eImP6EfF+WHkthCH6J2SX4wIhfqA0Jj1iRl65Q2bykfSZ2acd/jxJEdiu1U9gKxmD5XEUqXXH7UNFfF0/awAeVWkbH31kMt67OamoNW0zL111HAcc8L+aKjHfgIoqNCpnSDungtv9YUohWeB48MMxuznn/5S/pMmQ==" Feb 13 11:35:01 volumio go-librespot[22982]: time="2026-02-13T11:35:01+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 11:35:01 volumio go-librespot[22982]: time="2026-02-13T11:35:01+07:00" level=debug msg="completed keyexchange" Feb 13 11:35:01 volumio go-librespot[22982]: time="2026-02-13T11:35:01+07:00" level=debug msg="completed challenge" Feb 13 11:35:01 volumio go-librespot[22982]: time="2026-02-13T11:35:01+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 13 11:35:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 11:35:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 11:35:02 volumio volumio[22966]: info: ------------------------------------------- Feb 13 11:35:02 volumio volumio[22966]: info: ----- Volumio3 ---- Feb 13 11:35:02 volumio volumio[22966]: info: ------------------------------------------- Feb 13 11:35:02 volumio volumio[22966]: info: ----- System startup ---- Feb 13 11:35:02 volumio volumio[22966]: info: ------------------------------------------- Feb 13 11:35:03 volumio volumio-remote-updater[643]: [2026-02-13 11:35:03] [connect] Successful connection Feb 13 11:35:04 volumio volumio[22966]: info: MYVOLUMIO Environment detected Feb 13 11:35:04 volumio volumio[22966]: info: Plugin folders cleanup Feb 13 11:35:04 volumio volumio[22966]: info: Scanning into folder /volumio/app/plugins/ Feb 13 11:35:04 volumio volumio[22966]: info: Scanning category audio_interface Feb 13 11:35:04 volumio volumio[22966]: info: Scanning category miscellanea Feb 13 11:35:04 volumio volumio[22966]: info: Scanning category music_service Feb 13 11:35:04 volumio volumio[22966]: info: Scanning category plugins.json Feb 13 11:35:04 volumio volumio[22966]: info: Scanning category system_controller Feb 13 11:35:04 volumio volumio[22966]: info: Scanning category user_interface Feb 13 11:35:04 volumio volumio[22966]: info: Scanning into folder /data/plugins/ Feb 13 11:35:04 volumio volumio[22966]: info: Scanning category music_service Feb 13 11:35:04 volumio volumio[22966]: info: Plugin folders cleanup completed Feb 13 11:35:04 volumio volumio[22966]: info: ------------------------------------------- Feb 13 11:35:04 volumio volumio[22966]: info: ----- Core plugins startup ---- Feb 13 11:35:04 volumio volumio[22966]: info: ------------------------------------------- Feb 13 11:35:04 volumio volumio[22966]: info: Loading plugins from folder /volumio/app/plugins/ Feb 13 11:35:04 volumio volumio[22966]: info: Adding plugin upnp to MyMusic Plugins Feb 13 11:35:04 volumio volumio[22966]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 13 11:35:04 volumio volumio[22966]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 13 11:35:04 volumio volumio[22966]: info: Loading plugins from folder /data/plugins/ Feb 13 11:35:04 volumio volumio[22966]: info: Loading plugin "system"... Feb 13 11:35:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Feb 13 11:35:04 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 11:35:04 volumio volumio[22966]: info: Loading plugin "appearance"... Feb 13 11:35:04 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 11:35:04 volumio go-librespot[23001]: go-librespot daemon starting... Feb 13 11:35:04 volumio go-librespot[23002]: time="2026-02-13T11:35:04+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 11:35:04 volumio go-librespot[23002]: time="2026-02-13T11:35:04+07:00" level=debug msg="app state loaded" Feb 13 11:35:04 volumio go-librespot[23002]: time="2026-02-13T11:35:04+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 11:35:04 volumio go-librespot[23002]: time="2026-02-13T11:35:04+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 13 11:35:04 volumio go-librespot[23002]: time="2026-02-13T11:35:04+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 13 11:35:04 volumio go-librespot[23002]: time="2026-02-13T11:35:04+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 13 11:35:04 volumio go-librespot[23002]: time="2026-02-13T11:35:04+07:00" level=info msg="zeroconf server listening on port 35875" Feb 13 11:35:05 volumio go-librespot[23002]: time="2026-02-13T11:35:05+07:00" level=debug msg="obtained new client token: AADSB7I46yOfoczGukb+RW746OiKG2c4bxBm9pTglDwQYXGWDX9MFNoc9byvw8m084OiUW8vrhXqbmLn/Kw4gdqkfVa/I2VYXIHNCESt4vzq3Thoxyw3rnAeRo2vAeBOPIQDsXDPfPE9Adcqw7f2/sMmzZ3vGxDu9n5HgZWdJP4JhsjAwvxldKStfCRkdeL8e9USxhrrByDz4aWyCH8SBi1ah7P2gc4wN9qM/PidUlz2W8Y/Go+4dJw=" Feb 13 11:35:05 volumio go-librespot[23002]: time="2026-02-13T11:35:05+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 11:35:05 volumio go-librespot[23002]: time="2026-02-13T11:35:05+07:00" level=debug msg="completed keyexchange" Feb 13 11:35:05 volumio go-librespot[23002]: time="2026-02-13T11:35:05+07:00" level=debug msg="completed challenge" Feb 13 11:35:05 volumio go-librespot[23002]: time="2026-02-13T11:35:05+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 13 11:35:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 11:35:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 11:35:06 volumio volumio[22966]: info: Loading plugin "network"... Feb 13 11:35:06 volumio volumio[22966]: info: Refreshing Cached IP Addresses Feb 13 11:35:06 volumio sudo[23010]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 13 11:35:06 volumio sudo[23010]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 11:35:06 volumio sudo[23012]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 13 11:35:06 volumio sudo[23012]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 11:35:06 volumio volumio[22966]: info: Loading plugin "services"... Feb 13 11:35:06 volumio sudo[23010]: pam_unix(sudo:session): session closed for user root Feb 13 11:35:06 volumio volumio[22966]: info: Loading plugin "alsa_controller"... Feb 13 11:35:06 volumio sudo[23012]: pam_unix(sudo:session): session closed for user root Feb 13 11:35:06 volumio sudo[23020]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 13 11:35:06 volumio sudo[23020]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 11:35:06 volumio volumio[22966]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 13 11:35:06 volumio volumio[22966]: info: Loading plugin "wizard"... Feb 13 11:35:06 volumio volumio[22966]: info: Loading plugin "networkfs"... Feb 13 11:35:06 volumio volumio[22966]: info: Starting Udev Watcher for removable devices Feb 13 11:35:06 volumio volumio[22966]: info: Ignoring mount for partition: boot Feb 13 11:35:06 volumio volumio[22966]: info: Ignoring mount for partition: volumio Feb 13 11:35:06 volumio volumio[22966]: info: Ignoring mount for partition: volumio_data Feb 13 11:35:06 volumio volumio[22966]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 13 11:35:06 volumio volumio[22966]: info: Loading plugin "volumio_command_line_client"... Feb 13 11:35:06 volumio volumio[22966]: info: Loading plugin "upnp"... Feb 13 11:35:06 volumio volumio[22966]: info: [1770957306675] Starting Upmpd Daemon Feb 13 11:35:06 volumio volumio[22966]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 13 11:35:06 volumio volumio[22966]: info: Loading plugin "my_music"... Feb 13 11:35:06 volumio volumio[22966]: info: Loading plugin "mpd"... Feb 13 11:35:07 volumio volumio[22966]: info: Loading plugin "upnp_browser"... Feb 13 11:35:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Feb 13 11:35:08 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 11:35:08 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 11:35:08 volumio go-librespot[23043]: go-librespot daemon starting... Feb 13 11:35:08 volumio go-librespot[23044]: time="2026-02-13T11:35:08+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 11:35:08 volumio go-librespot[23044]: time="2026-02-13T11:35:08+07:00" level=debug msg="app state loaded" Feb 13 11:35:08 volumio go-librespot[23044]: time="2026-02-13T11:35:08+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 11:35:09 volumio sudo[23020]: pam_unix(sudo:session): session closed for user root Feb 13 11:35:09 volumio go-librespot[23044]: time="2026-02-13T11:35:09+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 13 11:35:09 volumio go-librespot[23044]: time="2026-02-13T11:35:09+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 13 11:35:09 volumio go-librespot[23044]: time="2026-02-13T11:35:09+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 13 11:35:09 volumio go-librespot[23044]: time="2026-02-13T11:35:09+07:00" level=info msg="zeroconf server listening on port 33351" Feb 13 11:35:09 volumio go-librespot[23044]: time="2026-02-13T11:35:09+07:00" level=debug msg="obtained new client token: AAAZ+/TSMKAd86eoXIt0jJuVjzepUweHtUct5SHtzMExaaP024oV5u0n6xSXT/VgclPw4OOOwgT5UoJS3esn+fG33uKF8k/zh6Tltbcz37DTlvX2dbu9/Tb1Ak/xbzSbe2kmdbLNDoVCX7Sx28uV3Mrw+s8WderV9p92fcxeqwJbqe/D8Jp30LfOs0SbPVYTC8aUBlPFDjnAv80yxUbiYcjTKWn/fmGNwG/V0mPWY1pUTTIpOoKnYPYh3Q==" Feb 13 11:35:09 volumio go-librespot[23044]: time="2026-02-13T11:35:09+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 11:35:09 volumio go-librespot[23044]: time="2026-02-13T11:35:09+07:00" level=debug msg="completed keyexchange" Feb 13 11:35:09 volumio go-librespot[23044]: time="2026-02-13T11:35:09+07:00" level=debug msg="completed challenge" Feb 13 11:35:09 volumio go-librespot[23044]: time="2026-02-13T11:35:09+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 13 11:35:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 11:35:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 11:35:10 volumio volumio[22966]: info: Starting UPNP Browser Feb 13 11:35:10 volumio volumio[22966]: info: Loading plugin "alarm-clock"... Feb 13 11:35:10 volumio volumio[22966]: info: Loading plugin "airplay_emulation"... Feb 13 11:35:10 volumio volumio[22966]: info: Starting Shairport Sync Feb 13 11:35:10 volumio volumio[22966]: info: Loading plugin "last_100"... Feb 13 11:35:10 volumio volumio[22966]: info: Loading plugin "webradio"... Feb 13 11:35:10 volumio volumio[22966]: info: Loading plugin "i2s_dacs"... Feb 13 11:35:10 volumio volumio[22966]: info: Loading plugin "volumiodiscovery"... Feb 13 11:35:10 volumio volumio[22966]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 13 11:35:10 volumio volumio[22966]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 13 11:35:10 volumio node[22966]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 13 11:35:10 volumio volumio[22966]: *** WARNING *** For more information see Feb 13 11:35:10 volumio volumio[22966]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 13 11:35:10 volumio volumio[22966]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 13 11:35:10 volumio volumio[22966]: *** WARNING *** For more information see Feb 13 11:35:10 volumio node[22966]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 13 11:35:10 volumio node[22966]: *** WARNING *** For more information see Feb 13 11:35:10 volumio node[22966]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 13 11:35:10 volumio node[22966]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 13 11:35:10 volumio node[22966]: *** WARNING *** For more information see Feb 13 11:35:10 volumio volumio[22966]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 13 11:35:10 volumio volumio[22966]: info: Discovery: Started advertising with name: Volumio Feb 13 11:35:10 volumio volumio[22966]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 13 11:35:10 volumio volumio[22966]: info: Loading plugin "spop"... Feb 13 11:35:12 volumio volumio[22966]: info: Loading plugin "ytcr"... Feb 13 11:35:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Feb 13 11:35:13 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 11:35:13 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 11:35:13 volumio go-librespot[23056]: go-librespot daemon starting... Feb 13 11:35:13 volumio go-librespot[23057]: time="2026-02-13T11:35:13+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 11:35:13 volumio go-librespot[23057]: time="2026-02-13T11:35:13+07:00" level=debug msg="app state loaded" Feb 13 11:35:13 volumio go-librespot[23057]: time="2026-02-13T11:35:13+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 11:35:13 volumio go-librespot[23057]: time="2026-02-13T11:35:13+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 13 11:35:13 volumio go-librespot[23057]: time="2026-02-13T11:35:13+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 13 11:35:13 volumio go-librespot[23057]: time="2026-02-13T11:35:13+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 13 11:35:13 volumio go-librespot[23057]: time="2026-02-13T11:35:13+07:00" level=info msg="zeroconf server listening on port 33557" Feb 13 11:35:13 volumio go-librespot[23057]: time="2026-02-13T11:35:13+07:00" level=debug msg="obtained new client token: AADXjoQWrO3X/OBhvRf6UGnFojIDUJd/e0h6+bp1CS48vVneRKywrtX0Vcb1WgxE5OKtAcyMGwAcdnRgtanxyxiqxPVs//T/GHN28Ejrd3QAibjnsvMwx3Z7p1IogJDk32UGegsJiWzXKUFBUMgSlObGLYFqDmsBmSDkR2hfYEhPAMOPCv/75MSmHd8qKZiiEhROvjVGKb7bY+MX8KZDq+njfYOR3BuUIRas/FJBJ4DF0mgk8Cc9+vd4yw==" Feb 13 11:35:14 volumio go-librespot[23057]: time="2026-02-13T11:35:14+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 11:35:14 volumio go-librespot[23057]: time="2026-02-13T11:35:14+07:00" level=debug msg="completed keyexchange" Feb 13 11:35:14 volumio go-librespot[23057]: time="2026-02-13T11:35:14+07:00" level=debug msg="completed challenge" Feb 13 11:35:14 volumio go-librespot[23057]: time="2026-02-13T11:35:14+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 13 11:35:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 11:35:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 11:35:15 volumio volumio[22966]: info: Loading plugin "ytmusic"... Feb 13 11:35:16 volumio volumio-remote-updater[643]: [2026-02-13 11:35:16] [connect] Successful connection Feb 13 11:35:16 volumio volumio[22966]: info: Loading plugin "outputs"... Feb 13 11:35:16 volumio volumio[22966]: info: Loading plugin "albumart"... Feb 13 11:35:16 volumio volumio[22966]: info: Plugin example_plugin is not enabled Feb 13 11:35:16 volumio volumio[22966]: info: Loading plugin "inputs"... Feb 13 11:35:16 volumio volumio[22966]: info: Loading plugin "updater_comm"... Feb 13 11:35:16 volumio volumio[22966]: info: Plugin mpdemulation is not enabled Feb 13 11:35:16 volumio volumio[22966]: info: Loading plugin "rest_api"... Feb 13 11:35:16 volumio volumio[22966]: info: Loading plugin "websocket"... Feb 13 11:35:16 volumio volumio[22966]: info: Starting Socket.io Server version 1.7.4 Feb 13 11:35:16 volumio volumio[22966]: info: Loading plugin "RoonBridge"... Feb 13 11:35:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Feb 13 11:35:17 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 11:35:17 volumio volumio[22966]: info: Applying required configuration parameters for plugin RoonBridge Feb 13 11:35:17 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 11:35:17 volumio go-librespot[23089]: go-librespot daemon starting... Feb 13 11:35:17 volumio volumio[22966]: info: Loading i18n strings for locale en Feb 13 11:35:17 volumio volumio[22966]: Updating browse sources language Feb 13 11:35:17 volumio volumio[22966]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 13 11:35:17 volumio go-librespot[23090]: time="2026-02-13T11:35:17+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 11:35:17 volumio go-librespot[23090]: time="2026-02-13T11:35:17+07:00" level=debug msg="app state loaded" Feb 13 11:35:17 volumio go-librespot[23090]: time="2026-02-13T11:35:17+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 11:35:17 volumio volumio[23079]: Forking 3 albumart workers Feb 13 11:35:17 volumio volumio[22966]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 13 11:35:17 volumio volumio[22966]: info: CoreCommandRouter::initPlayerControls Feb 13 11:35:17 volumio volumio[22966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 11:35:17 volumio volumio[22966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 11:35:17 volumio volumio[22966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 11:35:17 volumio volumio[22966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 11:35:17 volumio volumio[22966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 11:35:17 volumio volumio[22966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 11:35:17 volumio volumio[22966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 11:35:17 volumio volumio[22966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 11:35:17 volumio volumio[22966]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 13 11:35:17 volumio volumio[22966]: Express server listening on port 3000 Feb 13 11:35:17 volumio volumio[22966]: [Metrics] WebUI: 15s 987.72ms Feb 13 11:35:17 volumio volumio[22966]: info: CoreStateMachine::resetVolumioState Feb 13 11:35:17 volumio volumio[22966]: info: CoreStateMachine::getcurrentVolume Feb 13 11:35:17 volumio volumio[22966]: info: CoreCommandRouter::volumioRetrievevolume Feb 13 11:35:18 volumio go-librespot[23090]: time="2026-02-13T11:35:18+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 13 11:35:18 volumio go-librespot[23090]: time="2026-02-13T11:35:18+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 13 11:35:18 volumio go-librespot[23090]: time="2026-02-13T11:35:18+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 13 11:35:18 volumio go-librespot[23090]: time="2026-02-13T11:35:18+07:00" level=info msg="zeroconf server listening on port 37245" Feb 13 11:35:18 volumio sudo[23131]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 13 11:35:18 volumio volumio[22966]: info: Volumio Network Manager: Network status updated: 1 Feb 13 11:35:18 volumio sudo[23131]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 11:35:18 volumio sudo[23133]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 13 11:35:18 volumio sudo[23133]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 11:35:18 volumio sudo[23131]: pam_unix(sudo:session): session closed for user root Feb 13 11:35:18 volumio sudo[23133]: pam_unix(sudo:session): session closed for user root Feb 13 11:35:18 volumio go-librespot[23090]: time="2026-02-13T11:35:18+07:00" level=debug msg="obtained new client token: AADk21/yRXynJu8egBPjKqEgfR4YBjmbp1vzR0liT1jXHYqQn39EUR72Ip0qoMgDjUpDIMIcXDgKrPzUWZayuXo1D9TdW5m9NhA7zrcLNS00H7cV3/c221OOdwqYcbyI4n018C2wNRwLMTyXRK62bpuvF/tonuqBHBZ7/CQZU6b8XXgpyLWJvAXPyEk3S/rV4/qODerhK8KpDG7qwy+axYv19yte7XC+5UUss1eco/JNOeM0jTYsc/U=" Feb 13 11:35:18 volumio go-librespot[23090]: time="2026-02-13T11:35:18+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 11:35:18 volumio volumio[22966]: info: VolumeController:: Volume=100 Mute =false Feb 13 11:35:18 volumio volumio[22966]: info: CoreStateMachine::pushState Feb 13 11:35:18 volumio volumio[22966]: info: CorePlayQueue::getTrack 0 Feb 13 11:35:18 volumio volumio[22966]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 13 11:35:18 volumio volumio[22966]: info: CoreCommandRouter::volumioPushState Feb 13 11:35:18 volumio volumio[22966]: info: CoreStateMachine::updateTrackBlock Feb 13 11:35:18 volumio volumio[22966]: info: CorePlayQueue::getTrackBlock Feb 13 11:35:18 volumio volumio[22966]: info: CoreCommandRouter::volumioRetrievevolume Feb 13 11:35:18 volumio volumio-remote-updater[643]: [2026-02-13 11:35:18] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1770957316 101 Feb 13 11:35:18 volumio volumio[22966]: 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 13 11:35:18 volumio volumio[22966]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 11:35:18 volumio volumio[22966]: info: Reloading queue from file Feb 13 11:35:18 volumio volumio[22966]: info: CoreStateMachine::setRepeat null single undefined Feb 13 11:35:18 volumio volumio[22966]: info: CoreStateMachine::pushState Feb 13 11:35:18 volumio volumio[22966]: info: CorePlayQueue::getTrack 0 Feb 13 11:35:18 volumio volumio[22966]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 13 11:35:18 volumio volumio[22966]: info: CoreCommandRouter::volumioPushState Feb 13 11:35:18 volumio volumio[22966]: info: CoreStateMachine::setRandom null Feb 13 11:35:18 volumio volumio[22966]: info: CoreStateMachine::pushState Feb 13 11:35:18 volumio volumio[22966]: info: CorePlayQueue::getTrack 0 Feb 13 11:35:18 volumio volumio[22966]: info: CoreCommandRouter::volumioPushState Feb 13 11:35:18 volumio volumio[22966]: info: Setting Device type: Raspberry PI Feb 13 11:35:18 volumio volumio[22966]: info: Completed loading Core Plugins Feb 13 11:35:18 volumio volumio[22966]: info: Preparing to generate the ALSA configuration file Feb 13 11:35:18 volumio volumio[22966]: info: VolumeController:: Volume=100 Mute =false Feb 13 11:35:18 volumio volumio[22966]: info: CoreStateMachine::pushState Feb 13 11:35:18 volumio volumio[22966]: info: CorePlayQueue::getTrack 0 Feb 13 11:35:18 volumio volumio[22966]: info: CoreCommandRouter::volumioPushState Feb 13 11:35:18 volumio volumio[22966]: info: Asound.conf file unchanged, so no further update is needed Feb 13 11:35:18 volumio volumio[22966]: info: Output device has changed, restarting MPD Feb 13 11:35:19 volumio sudo[23147]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 13 11:35:19 volumio volumio[22966]: info: Output device has changed, restarting Shairport Sync Feb 13 11:35:19 volumio volumio[22966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 11:35:19 volumio volumio[22966]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 11:35:19 volumio sudo[23147]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 11:35:19 volumio sudo[23149]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 13 11:35:19 volumio volumio[22966]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 13 11:35:19 volumio sudo[23149]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 11:35:19 volumio volumio[22966]: info: ___________ START PLUGINS ___________ Feb 13 11:35:19 volumio sudo[23149]: pam_unix(sudo:session): session closed for user root Feb 13 11:35:19 volumio sudo[23151]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 13 11:35:19 volumio sudo[23151]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 11:35:19 volumio volumio[22966]: info: ControllerMpd::onStart: Initializing MPD Feb 13 11:35:19 volumio volumio[22966]: info: Creating MPD Configuration file Feb 13 11:35:19 volumio sudo[23147]: pam_unix(sudo:session): session closed for user root Feb 13 11:35:19 volumio volumio[22966]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 13 11:35:19 volumio volumio[22966]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 13 11:35:19 volumio volumio[22966]: info: [1770957319246] CoreMusicLibrary::Adding element Media Servers Feb 13 11:35:19 volumio go-librespot[23090]: time="2026-02-13T11:35:19+07:00" level=debug msg="completed keyexchange" Feb 13 11:35:19 volumio go-librespot[23090]: time="2026-02-13T11:35:19+07:00" level=debug msg="completed challenge" Feb 13 11:35:19 volumio volumio[22966]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 13 11:35:19 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 13 11:35:19 volumio go-librespot[23090]: time="2026-02-13T11:35: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 13 11:35:19 volumio volumio[22966]: info: UPNP Browser: Client initialized successfully Feb 13 11:35:19 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 11:35:19 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 11:35:19 volumio sudo[23160]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 13 11:35:19 volumio sudo[23160]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 11:35:19 volumio sudo[23162]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 13 11:35:19 volumio sudo[23160]: pam_unix(sudo:session): session closed for user root Feb 13 11:35:19 volumio sudo[23162]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 11:35:19 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 13 11:35:19 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 13 11:35:19 volumio systemd[1]: mpd.service: Consumed 7.126s CPU time. Feb 13 11:35:19 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 13 11:35:19 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 13 11:35:19 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 13 11:35:19 volumio volumio[22966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 11:35:19 volumio volumio[22966]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 11:35:19 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 13 11:35:19 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 13 11:35:19 volumio volumio[22966]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 13 11:35:19 volumio volumio[22966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 11:35:19 volumio volumio[22966]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 11:35:19 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 13 11:35:19 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 13 11:35:19 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 13 11:35:19 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 13 11:35:19 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 13 11:35:19 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 13 11:35:19 volumio volumio[22966]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 13 11:35:19 volumio volumio[22966]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 13 11:35:19 volumio volumio[22966]: info: [1770957319722] CoreMusicLibrary::Adding element Last_100 Feb 13 11:35:19 volumio volumio[22966]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 13 11:35:19 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 13 11:35:19 volumio volumio[22966]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 13 11:35:19 volumio volumio[22966]: info: [1770957319748] CoreMusicLibrary::Adding element Webradio Feb 13 11:35:19 volumio volumio[22966]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 13 11:35:19 volumio volumio[22966]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 13 11:35:19 volumio volumio[22966]: info: Initializing BBC Radios Feb 13 11:35:19 volumio volumio[22966]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 13 11:35:19 volumio volumio[22966]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 11:35:20 volumio volumio[22966]: info: Creating Spotify config file Feb 13 11:35:20 volumio volumio[22966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 11:35:20 volumio sudo[23176]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 13 11:35:20 volumio sudo[23176]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 13 11:35:20 volumio sudo[23176]: pam_unix(sudo:session): session closed for user root Feb 13 11:35:20 volumio volumio[23098]: Starting albumart workers Feb 13 11:35:20 volumio volumio[23097]: Starting albumart workers Feb 13 11:35:21 volumio volumio[22966]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 13 11:35:21 volumio volumio[22966]: info: [1770957321089] CoreMusicLibrary::Adding element YouTube Music Feb 13 11:35:21 volumio volumio[22966]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 13 11:35:21 volumio volumio[22966]: Cannot find translation for source YouTube Music Feb 13 11:35:21 volumio volumio[22966]: info: Volumio Calling Home Feb 13 11:35:21 volumio sudo[23193]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Feb 13 11:35:21 volumio sudo[23193]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 11:35:21 volumio sudo[23193]: pam_unix(sudo:session): session closed for user root Feb 13 11:35:21 volumio volumio[23099]: Starting albumart workers Feb 13 11:35:21 volumio volumio[22966]: info: Discovery: adding 921648ed-0557-4d70-9927-1420682efcc7 Feb 13 11:35:21 volumio volumio[22966]: info: Discovery: Found device Volumio Feb 13 11:35:21 volumio volumio[22966]: info: CoreCommandRouter::volumioGetState Feb 13 11:35:21 volumio volumio[22966]: info: CorePlayQueue::getTrack 0 Feb 13 11:35:21 volumio volumio[22966]: info: Discovery: this is already registered, 921648ed-0557-4d70-9927-1420682efcc7 Feb 13 11:35:22 volumio volumio[22966]: info: Discovery: Found device Volumio Feb 13 11:35:22 volumio volumio[22966]: info: CoreCommandRouter::volumioGetState Feb 13 11:35:22 volumio volumio[22966]: info: CorePlayQueue::getTrack 0 Feb 13 11:35:22 volumio volumio[22966]: info: MPD Permissions set Feb 13 11:35:22 volumio volumio[22966]: info: MPD Permissions set Feb 13 11:35:22 volumio volumio[22966]: info: Upmpdcli Daemon Started Feb 13 11:35:22 volumio volumio[22966]: info: Volumio called home Feb 13 11:35:22 volumio volumio[22966]: info: Spotify config file written Feb 13 11:35:22 volumio volumio[22966]: 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 13 11:35:22 volumio volumio[22966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 11:35:22 volumio volumio[22966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 11:35:22 volumio volumio[22966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 11:35:22 volumio volumio[22966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 11:35:22 volumio volumio[22966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 11:35:22 volumio volumio[22966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 11:35:22 volumio volumio[22966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 11:35:22 volumio volumio[22966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 11:35:22 volumio sudo[23199]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 13 11:35:22 volumio sudo[23199]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 11:35:22 volumio volumio[22966]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 11:35:22 volumio volumio[22966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 11:35:22 volumio volumio[22966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 11:35:22 volumio volumio[22966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 11:35:22 volumio volumio[22966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 11:35:22 volumio volumio[22966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 11:35:22 volumio volumio[22966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 11:35:22 volumio volumio[22966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 11:35:22 volumio volumio[22966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 11:35:22 volumio volumio[22966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 11:35:22 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 11:35:22 volumio volumio[22966]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 13 11:35:22 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 11:35:22 volumio go-librespot[23209]: go-librespot daemon starting... Feb 13 11:35:22 volumio volumio[22966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 13 11:35:22 volumio volumio[22966]: info: No need to fix Spotify hosts Feb 13 11:35:22 volumio sudo[23199]: pam_unix(sudo:session): session closed for user root Feb 13 11:35:22 volumio go-librespot[23212]: time="2026-02-13T11:35:22+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 11:35:22 volumio go-librespot[23212]: time="2026-02-13T11:35:22+07:00" level=debug msg="app state loaded" Feb 13 11:35:22 volumio go-librespot[23212]: time="2026-02-13T11:35:22+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 11:35:23 volumio go-librespot[23212]: time="2026-02-13T11:35: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 13 11:35:23 volumio go-librespot[23212]: time="2026-02-13T11:35: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 13 11:35:23 volumio go-librespot[23212]: time="2026-02-13T11:35: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 13 11:35:23 volumio go-librespot[23212]: time="2026-02-13T11:35:23+07:00" level=info msg="zeroconf server listening on port 41215" Feb 13 11:35:23 volumio go-librespot[23212]: time="2026-02-13T11:35:23+07:00" level=debug msg="obtained new client token: AADlMFRl/FcRUcNdTEl1iRgMUX3K/MpIlR2BuEF1FCrLGd0xyrtXLFNOsZizrkgMx7jTF+eB7KxWJsV2HoJeKcdNSZ2/6H++XcbMHEvbVLyIkrUIvxkCG50ZYmi7isGEfWI0ve4kjENVmDOu/VJV/8kgPKrZTs/1cSm89AAjQ7iMuVEZeuncVTtwwNOtpAPZRHvlpPjPxVrmMNjAhILqpNOdJlwuVJIjHPRt3vL94t6iVJl36t4cxt8ohA==" Feb 13 11:35:23 volumio go-librespot[23212]: time="2026-02-13T11:35:23+07:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.241.202:4070: connect: connection refused" Feb 13 11:35:23 volumio volumio[22966]: info: Starting Shairport Sync Feb 13 11:35:23 volumio volumio[22966]: info: Starting Shairport Sync Feb 13 11:35:23 volumio go-librespot[23212]: time="2026-02-13T11:35:23+07:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:443, retrying with a different AP" error="dial tcp 104.199.241.202:443: connect: connection refused" Feb 13 11:35:23 volumio volumio[22966]: info: Starting Shairport Sync Feb 13 11:35:23 volumio sudo[23238]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 13 11:35:23 volumio sudo[23238]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 11:35:23 volumio go-librespot[23212]: time="2026-02-13T11:35:23+07:00" level=debug msg="connected to ap-gae2.spotify.com:80" Feb 13 11:35:23 volumio sudo[23243]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 13 11:35:23 volumio sudo[23243]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 11:35:23 volumio sudo[23240]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 13 11:35:23 volumio sudo[23240]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 13 11:35:23 volumio go-librespot[23212]: time="2026-02-13T11:35:23+07:00" level=debug msg="completed keyexchange" Feb 13 11:35:23 volumio go-librespot[23212]: time="2026-02-13T11:35:23+07:00" level=debug msg="completed challenge" Feb 13 11:35:23 volumio volumio[22966]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 13 11:35:23 volumio volumio[22966]: SPOTIFY: BQDyJZOSFyYIqE0eTUGwB0zChTX1nmQq6mcR2fXw27X_I5GRQpb51snMql4ifa65Jr830EnUnXyrGdokbtx6PZ2K2uu9x5Q0otga1V_EWPguGGn660NKGiVA4VVpP0tJmFR_kcrLBZ6wFuPa-yneVMUZlKaS2qZyLrmJ5ZxzMcTJh4j6CDqtOAKt2y9SZV4veTy0nJjXey0q-GcURAIkoHES45kkpQu7ZOW6l16VnBFQVNsteiR8-772e2uEv_UstBdEb8Y2kvsseKL8SYFgAguItBTvPSDqf3FmSYygucgorK7DmumYSBax Feb 13 11:35:23 volumio volumio[22966]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 13 11:35:23 volumio volumio[22966]: info: New Spotify access token = BQDyJZOSFyYIqE0eTUGwB0zChTX1nmQq6mcR2fXw27X_I5GRQpb51snMql4ifa65Jr830EnUnXyrGdokbtx6PZ2K2uu9x5Q0otga1V_EWPguGGn660NKGiVA4VVpP0tJmFR_kcrLBZ6wFuPa-yneVMUZlKaS2qZyLrmJ5ZxzMcTJh4j6CDqtOAKt2y9SZV4veTy0nJjXey0q-GcURAIkoHES45kkpQu7ZOW6l16VnBFQVNsteiR8-772e2uEv_UstBdEb8Y2kvsseKL8SYFgAguItBTvPSDqf3FmSYygucgorK7DmumYSBax Feb 13 11:35:23 volumio volumio[22966]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 13 11:35:24 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 13 11:35:24 volumio go-librespot[23212]: time="2026-02-13T11:35: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 13 11:35:24 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 13 11:35:24 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 13 11:35:24 volumio systemd[1]: shairport-sync.service: Consumed 2.325s CPU time. Feb 13 11:35:24 volumio volumio[22966]: info: CoreCommandRouter::volumioGetState Feb 13 11:35:24 volumio volumio[22966]: info: CorePlayQueue::getTrack 0 Feb 13 11:35:24 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 13 11:35:24 volumio sudo[23243]: pam_unix(sudo:session): session closed for user root Feb 13 11:35:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 11:35:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 11:35:24 volumio sudo[23240]: pam_unix(sudo:session): session closed for user root Feb 13 11:35:24 volumio sudo[23238]: pam_unix(sudo:session): session closed for user root Feb 13 11:35:24 volumio volumio[22966]: info: Shairport-Sync Started Feb 13 11:35:24 volumio volumio[22966]: Error adding Membership: Error: addMembership EINVAL Feb 13 11:35:24 volumio volumio[22966]: info: Shairport-Sync Started Feb 13 11:35:24 volumio volumio[22966]: info: Shairport-Sync Started Feb 13 11:35:24 volumio volumio[22966]: 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 13 11:35:24 volumio volumio[22966]: info: Spotify Successfully logged in Feb 13 11:35:24 volumio volumio[22966]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 13 11:35:24 volumio volumio[22966]: info: [1770957324505] CoreMusicLibrary::Adding element Spotify Feb 13 11:35:24 volumio volumio[22966]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 13 11:35:24 volumio volumio[22966]: Cannot find translation for source YouTube Music Feb 13 11:35:24 volumio volumio[22966]: Cannot find translation for source Spotify Feb 13 11:35:25 volumio volumio[22966]: info: [yt-cast-receiver] DIAL server listening on port 8098 Feb 13 11:35:25 volumio volumio[22966]: info: CoreCommandRouter::volumioRetrievevolume Feb 13 11:35:25 volumio volumio[22966]: info: VolumeController:: Volume=100 Mute =false Feb 13 11:35:25 volumio volumio[22966]: info: CoreCommandRouter::volumioGetState Feb 13 11:35:25 volumio volumio[22966]: info: CorePlayQueue::getTrack 0 Feb 13 11:35:25 volumio volumio[22966]: info: CoreStateMachine::pushState Feb 13 11:35:25 volumio volumio[22966]: info: CorePlayQueue::getTrack 0 Feb 13 11:35:25 volumio volumio[22966]: info: CoreCommandRouter::volumioPushState Feb 13 11:35:26 volumio volumio[22966]: info: go-librespot daemon successfully initialized Feb 13 11:35:27 volumio mpd[23191]: 2026-02-13T11:35:27 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 13 11:35:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Feb 13 11:35:27 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 13 11:35:27 volumio sudo[23151]: pam_unix(sudo:session): session closed for user root Feb 13 11:35:27 volumio sudo[23162]: pam_unix(sudo:session): session closed for user root Feb 13 11:35:27 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 11:35:27 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 11:35:27 volumio go-librespot[23280]: go-librespot daemon starting... Feb 13 11:35:27 volumio go-librespot[23283]: time="2026-02-13T11:35:27+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 11:35:27 volumio go-librespot[23283]: time="2026-02-13T11:35:27+07:00" level=debug msg="app state loaded" Feb 13 11:35:27 volumio go-librespot[23283]: time="2026-02-13T11:35:27+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 11:35:27 volumio volumio[22966]: error: MPD error: The expression evaluated to a falsy value: Feb 13 11:35:27 volumio volumio[22966]: assert.ok(self.idling) Feb 13 11:35:27 volumio volumio[22966]: error: The expression evaluated to a falsy value: Feb 13 11:35:27 volumio volumio[22966]: assert.ok(self.idling) Feb 13 11:35:27 volumio volumio[22966]: error: updateQueue error: null Feb 13 11:35:27 volumio volumio[22966]: info: MPD running with PID23191 Feb 13 11:35:27 volumio volumio[22966]: ,establishing connection Feb 13 11:35:27 volumio volumio[22966]: info: Completed starting Core Plugins Feb 13 11:35:27 volumio volumio[22966]: info: ------------------------------------------- Feb 13 11:35:27 volumio volumio[22966]: info: ----- MyVolumio plugins startup ---- Feb 13 11:35:27 volumio volumio[22966]: info: ------------------------------------------- Feb 13 11:35:27 volumio volumio[22966]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 13 11:35:27 volumio volumio[22966]: error: updateQueue error: null Feb 13 11:35:27 volumio go-librespot[23283]: time="2026-02-13T11:35:27+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 13 11:35:27 volumio go-librespot[23283]: time="2026-02-13T11:35:27+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 13 11:35:27 volumio go-librespot[23283]: time="2026-02-13T11:35:27+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 13 11:35:27 volumio go-librespot[23283]: time="2026-02-13T11:35:27+07:00" level=info msg="zeroconf server listening on port 44669" Feb 13 11:35:28 volumio go-librespot[23283]: time="2026-02-13T11:35:28+07:00" level=debug msg="obtained new client token: AABCmaBbb3byeWAf9xE0Mfy1Xuh+3ekSMyNhbKHgpoybYqcQYyfVdG7XlFGnSmhgzXEhZdjvPBfKo6GfrZB3m1Xft4/D42v91J8gYyNQRXtjgpg+cPvlaAlUmHrW/6N3GtjPPuCZ6zj31hc8ayMAowFtMYFUlsEziaNIq18hnCQQSKWhWMgdRk5xAB4PtRHfQ6Hv9qG5bSPUwOya2WxUtaCQqFXD1NmN3DnLXKrd+7IhVlR96z3Q5a8=" Feb 13 11:35:28 volumio go-librespot[23283]: time="2026-02-13T11:35:28+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 11:35:28 volumio go-librespot[23283]: time="2026-02-13T11:35:28+07:00" level=debug msg="completed keyexchange" Feb 13 11:35:28 volumio go-librespot[23283]: time="2026-02-13T11:35:28+07:00" level=debug msg="completed challenge" Feb 13 11:35:28 volumio go-librespot[23283]: time="2026-02-13T11:35:28+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 13 11:35:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 11:35:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 11:35:29 volumio volumio[22966]: info: Initializing connection to go-librespot Websocket Feb 13 11:35:29 volumio volumio[22966]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 13 11:35:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11. Feb 13 11:35:31 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 11:35:31 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 11:35:31 volumio go-librespot[23291]: go-librespot daemon starting... Feb 13 11:35:31 volumio go-librespot[23292]: time="2026-02-13T11:35:31+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 11:35:31 volumio go-librespot[23292]: time="2026-02-13T11:35:31+07:00" level=debug msg="app state loaded" Feb 13 11:35:31 volumio go-librespot[23292]: time="2026-02-13T11:35:31+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 11:35:32 volumio go-librespot[23292]: time="2026-02-13T11:35: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 13 11:35:32 volumio go-librespot[23292]: time="2026-02-13T11:35: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 13 11:35:32 volumio go-librespot[23292]: time="2026-02-13T11:35: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 13 11:35:32 volumio go-librespot[23292]: time="2026-02-13T11:35:32+07:00" level=info msg="zeroconf server listening on port 42963" Feb 13 11:35:32 volumio volumio[22966]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 13 11:35:32 volumio go-librespot[23292]: time="2026-02-13T11:35:32+07:00" level=debug msg="obtained new client token: AABcIp6WrRRbsthq4AQzUXMW0ogiHLpgIghVFxskEo3nm926S3kCpqGnVRaD/lirKhPwJMmWfI1xELMOkt1tFAQyB3RaCeFG2+C3m/o9qgyxjN0iTiny7fEXSiZxxVbVvzXIafYRMEsglvOwnQqonGRp4W1uFTRgtE5PmL0pOJZzeyNZe5CIu3KzdDgTbvxFYc/gqY606M1Npu9BNtpwqP7hZkZePg2uoUy8hM6yTBoa66sSWVwRu7zgig==" Feb 13 11:35:32 volumio go-librespot[23292]: time="2026-02-13T11:35:32+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 11:35:32 volumio go-librespot[23292]: time="2026-02-13T11:35:32+07:00" level=debug msg="completed keyexchange" Feb 13 11:35:32 volumio go-librespot[23292]: time="2026-02-13T11:35:32+07:00" level=debug msg="completed challenge" Feb 13 11:35:32 volumio volumio[22966]: info: Initializing connection to go-librespot Websocket Feb 13 11:35:32 volumio go-librespot[23292]: time="2026-02-13T11:35:32+07:00" level=debug msg="new websocket client" Feb 13 11:35:32 volumio volumio[22966]: info: Connection to go-librespot Websocket established Feb 13 11:35:32 volumio go-librespot[23292]: time="2026-02-13T11:35:32+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 13 11:35:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 11:35:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 11:35:32 volumio volumio[22966]: info: Connection to go-librespot Websocket closed Feb 13 11:35:35 volumio volumio[22966]: info: Getting Spotify volume Feb 13 11:35:35 volumio volumio[22966]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 13 11:35:35 volumio volumio[22966]: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 13 11:35:35 volumio volumio[22966]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Feb 13 11:35:35 volumio volumio[22966]: errno: -111, Feb 13 11:35:35 volumio volumio[22966]: code: 'ECONNREFUSED', Feb 13 11:35:35 volumio volumio[22966]: syscall: 'connect', Feb 13 11:35:35 volumio volumio[22966]: address: '127.0.0.1', Feb 13 11:35:35 volumio volumio[22966]: port: 9879, Feb 13 11:35:35 volumio volumio[22966]: response: undefined Feb 13 11:35:35 volumio volumio[22966]: } Feb 13 11:35:35 volumio volumio[22966]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 13 11:35:35 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12. Feb 13 11:35:35 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 13 11:35:35 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 13 11:35:35 volumio go-librespot[23311]: go-librespot daemon starting... Feb 13 11:35:35 volumio go-librespot[23312]: time="2026-02-13T11:35:35+07:00" level=info msg="running go-librespot 0.4.0" Feb 13 11:35:35 volumio go-librespot[23312]: time="2026-02-13T11:35:35+07:00" level=debug msg="app state loaded" Feb 13 11:35:35 volumio go-librespot[23312]: time="2026-02-13T11:35:35+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 13 11:35:36 volumio go-librespot[23312]: time="2026-02-13T11:35:36+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 13 11:35:36 volumio go-librespot[23312]: time="2026-02-13T11:35:36+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 13 11:35:36 volumio go-librespot[23312]: time="2026-02-13T11:35:36+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 13 11:35:36 volumio go-librespot[23312]: time="2026-02-13T11:35:36+07:00" level=info msg="zeroconf server listening on port 46735" Feb 13 11:35:36 volumio go-librespot[23312]: time="2026-02-13T11:35:36+07:00" level=debug msg="obtained new client token: AAC9PPFPBBjs95sIq7681XUJXseesik7qs3dBheszwS1ytKNhtct0NWrdUp/Bv0ZcBDjwSN6b3EPwDdDOIUzs2qwih7JcHZPXUJnKHqMEkbrIzMdzp1IosNdAKGuS3swxXnASHNuO8Skpwgr0Zx/fkobl2YOE2r2ftrW9SIW+2RqiGg75Z8mo6Y50EIYdrLu9jIHZ4OgG4rRAYlz83wRTMs4k98zZDQ9+GSilehe1/MBD9cJoCrCqysbbw==" Feb 13 11:35:36 volumio go-librespot[23312]: time="2026-02-13T11:35:36+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 13 11:35:37 volumio go-librespot[23312]: time="2026-02-13T11:35:37+07:00" level=debug msg="completed keyexchange" Feb 13 11:35:37 volumio go-librespot[23312]: time="2026-02-13T11:35:37+07:00" level=debug msg="completed challenge" Feb 13 11:35:37 volumio go-librespot[23312]: time="2026-02-13T11:35: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 13 11:35:37 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 13 11:35:37 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 13 11:35:37 volumio sudo[23336]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-13 11:34' Feb 13 11:35:37 volumio sudo[23336]: 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"