Feb 07 19:45:00 volumio volumio[20176]: info: Initializing connection to go-librespot Websocket Feb 07 19:45:00 volumio volumio[20176]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 07 19:45:01 volumio volumio[20176]: info: MYVOLUMIO: Adding device Feb 07 19:45:01 volumio volumio[20176]: info: MYVOLUMIO: Evaluating Server Feb 07 19:45:02 volumio volumio[20176]: info: Setting Geolocation for MyVolumio to as1 Feb 07 19:45:02 volumio volumio[20176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 19:45:02 volumio volumio[20176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 19:45:02 volumio volumio[20176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 19:45:02 volumio volumio[20176]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Feb 07 19:45:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Feb 07 19:45:02 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 19:45:02 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 19:45:02 volumio go-librespot[20625]: go-librespot daemon starting... Feb 07 19:45:02 volumio go-librespot[20626]: time="2026-02-07T19:45:02+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 19:45:02 volumio go-librespot[20626]: time="2026-02-07T19:45:02+07:00" level=debug msg="app state loaded" Feb 07 19:45:02 volumio go-librespot[20626]: time="2026-02-07T19:45:02+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 19:45:03 volumio volumio[20176]: info: Updating MyVolumio device info Feb 07 19:45:03 volumio volumio[20176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 19:45:03 volumio volumio[20176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 19:45:03 volumio volumio[20176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 19:45:03 volumio volumio[20176]: info: Initializing connection to go-librespot Websocket Feb 07 19:45:03 volumio go-librespot[20626]: time="2026-02-07T19:45:03+07:00" level=debug msg="new websocket client" Feb 07 19:45:03 volumio volumio[20176]: info: Connection to go-librespot Websocket established Feb 07 19:45:03 volumio go-librespot[20626]: time="2026-02-07T19:45:03+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 07 19:45:03 volumio go-librespot[20626]: time="2026-02-07T19:45:03+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 07 19:45:03 volumio go-librespot[20626]: time="2026-02-07T19:45:03+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 07 19:45:03 volumio go-librespot[20626]: time="2026-02-07T19:45:03+07:00" level=info msg="zeroconf server listening on port 42613" Feb 07 19:45:03 volumio volumio[20176]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Feb 07 19:45:03 volumio go-librespot[20626]: time="2026-02-07T19:45:03+07:00" level=debug msg="obtained new client token: AAA41fsAxohugtD5sB5GmKvOkFr6YveYLrlQeu4VOuCPrXxBux3epm3ikybAEKeYWJvVBu9eFX+VOnSzOlUGO8fR82kELpbExRLGXuVRGiFH8iqjT2FY7StQZIRt2rULgpd6y2DtZ/b+XteH2V4lgKVnKX5EIZsVs353L5w5Yf8Xt79Pq6mSioRyslz9mFQXYHiXNDGwZaE8Cgq24MsG1uCPBwJmjsI4y/GJ6lQjsoGjl4zd+Zz9Gdc9Nw==" Feb 07 19:45:03 volumio go-librespot[20626]: time="2026-02-07T19:45:03+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 07 19:45:03 volumio go-librespot[20626]: time="2026-02-07T19:45:03+07:00" level=debug msg="completed keyexchange" Feb 07 19:45:03 volumio go-librespot[20626]: time="2026-02-07T19:45:03+07:00" level=debug msg="completed challenge" Feb 07 19:45:03 volumio go-librespot[20626]: time="2026-02-07T19:45:03+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 07 19:45:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 19:45:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 19:45:03 volumio volumio[20176]: info: Connection to go-librespot Websocket closed Feb 07 19:45:05 volumio volumio[20176]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 07 19:45:05 volumio volumio[20176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 19:45:05 volumio volumio[20176]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 07 19:45:05 volumio volumio-remote-updater[643]: Test mode disabled Feb 07 19:45:05 volumio volumio-remote-updater[643]: Alpha mode disabled Feb 07 19:45:05 volumio volumio-remote-updater[643]: Alpha legacy test mode disabled Feb 07 19:45:05 volumio volumio[20176]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Feb 07 19:45:05 volumio volumio[20176]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Feb 07 19:45:05 volumio volumio[20176]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 07 19:45:06 volumio volumio[20176]: info: Getting Spotify volume Feb 07 19:45:06 volumio volumio[20176]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 07 19:45:06 volumio volumio[20176]: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 07 19:45:06 volumio volumio[20176]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Feb 07 19:45:06 volumio volumio[20176]: errno: -111, Feb 07 19:45:06 volumio volumio[20176]: code: 'ECONNREFUSED', Feb 07 19:45:06 volumio volumio[20176]: syscall: 'connect', Feb 07 19:45:06 volumio volumio[20176]: address: '127.0.0.1', Feb 07 19:45:06 volumio volumio[20176]: port: 9879, Feb 07 19:45:06 volumio volumio[20176]: response: undefined Feb 07 19:45:06 volumio volumio[20176]: } Feb 07 19:45:06 volumio volumio[20176]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 07 19:45:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Feb 07 19:45:07 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 19:45:07 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 19:45:07 volumio go-librespot[20649]: go-librespot daemon starting... Feb 07 19:45:07 volumio go-librespot[20650]: time="2026-02-07T19:45:07+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 19:45:07 volumio go-librespot[20650]: time="2026-02-07T19:45:07+07:00" level=debug msg="app state loaded" Feb 07 19:45:07 volumio go-librespot[20650]: time="2026-02-07T19:45:07+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 19:45:07 volumio go-librespot[20650]: time="2026-02-07T19:45:07+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 07 19:45:07 volumio go-librespot[20650]: time="2026-02-07T19:45:07+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 07 19:45:07 volumio go-librespot[20650]: time="2026-02-07T19:45:07+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 07 19:45:07 volumio go-librespot[20650]: time="2026-02-07T19:45:07+07:00" level=info msg="zeroconf server listening on port 36333" Feb 07 19:45:07 volumio sudo[20659]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-07 19:44' Feb 07 19:45:07 volumio sudo[20659]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 19:45:07 volumio go-librespot[20650]: time="2026-02-07T19:45:07+07:00" level=debug msg="obtained new client token: AACSQeAXWjHMANCFzpnC8utoffa9fuOFOpQiGKDR1RRB+tP/qRWjJex1Gx64z8I1Dj/nEUTqbkfFASxeuY1MsMMw7FWeLxvs5UN68HNJOYIc3IcoHbjq8ReCW9K/rdoJQ1k74hgPVrAJthIyNv+ldVc4JjuaBLI3BAGU1p8hiQkCC51dGmR79esdZI+M8QF6+RYrWoMieX9xqMc6IibJEGv7WodDlZUdObVoGPNh/E82+A3JWGYZl+us3Q==" Feb 07 19:45:08 volumio sudo[20659]: pam_unix(sudo:session): session closed for user root Feb 07 19:45:08 volumio go-librespot[20650]: time="2026-02-07T19:45:08+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 07 19:45:08 volumio go-librespot[20650]: time="2026-02-07T19:45:08+07:00" level=debug msg="completed keyexchange" Feb 07 19:45:08 volumio go-librespot[20650]: time="2026-02-07T19:45:08+07:00" level=debug msg="completed challenge" Feb 07 19:45:08 volumio go-librespot[20650]: time="2026-02-07T19:45:08+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 07 19:45:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 19:45:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 19:45:09 volumio volumio-remote-updater[643]: [2026-02-07 19:45:09] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 07 19:45:09 volumio volumio-remote-updater[643]: [2026-02-07 19:45:09] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 07 19:45:09 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 07 19:45:09 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 07 19:45:09 volumio systemd[1]: volumio.service: Consumed 58.448s CPU time. Feb 07 19:45:09 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 07 19:45:09 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 07 19:45:09 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 6234. Feb 07 19:45:09 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 07 19:45:09 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 07 19:45:09 volumio systemd[1]: volumio.service: Consumed 58.448s CPU time. Feb 07 19:45:09 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Feb 07 19:45:09 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 07 19:45:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Feb 07 19:45:11 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 19:45:11 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 19:45:11 volumio go-librespot[20691]: go-librespot daemon starting... Feb 07 19:45:11 volumio go-librespot[20692]: time="2026-02-07T19:45:11+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 19:45:11 volumio go-librespot[20692]: time="2026-02-07T19:45:11+07:00" level=debug msg="app state loaded" Feb 07 19:45:11 volumio go-librespot[20692]: time="2026-02-07T19:45:11+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 19:45:11 volumio go-librespot[20692]: time="2026-02-07T19:45:11+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 07 19:45:11 volumio go-librespot[20692]: time="2026-02-07T19:45:11+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 07 19:45:11 volumio go-librespot[20692]: time="2026-02-07T19:45:11+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 07 19:45:11 volumio go-librespot[20692]: time="2026-02-07T19:45:11+07:00" level=info msg="zeroconf server listening on port 41921" Feb 07 19:45:12 volumio go-librespot[20692]: time="2026-02-07T19:45:12+07:00" level=debug msg="obtained new client token: AABTOdaafbGWE2nKskv1d0C32ereMWcAwc29/PVQe184lI9+eL1Frpghy5RWRI+UU5wdxmQRwTEMlA76axv/LR2qDcZsIvCXceJbrgqNpjh5HOyc3dNQHZU80ef6HtJrOeVchIBd1a/KN97f+ibyV8XY6h2oa7wbYJUM5bviAXK4NXJBQ1f5/4nDOMcAnygq9FRP8d+WjeDB7kn/vMmJ5bdry7rwtb6OUsrKzCibCLSwTlvDooPCPs8=" Feb 07 19:45:12 volumio go-librespot[20692]: time="2026-02-07T19:45:12+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 07 19:45:12 volumio go-librespot[20692]: time="2026-02-07T19:45:12+07:00" level=debug msg="completed keyexchange" Feb 07 19:45:12 volumio go-librespot[20692]: time="2026-02-07T19:45:12+07:00" level=debug msg="completed challenge" Feb 07 19:45:12 volumio go-librespot[20692]: time="2026-02-07T19:45:12+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 07 19:45:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 19:45:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 19:45:13 volumio volumio[20676]: info: ------------------------------------------- Feb 07 19:45:13 volumio volumio[20676]: info: ----- Volumio3 ---- Feb 07 19:45:13 volumio volumio[20676]: info: ------------------------------------------- Feb 07 19:45:13 volumio volumio[20676]: info: ----- System startup ---- Feb 07 19:45:13 volumio volumio[20676]: info: ------------------------------------------- Feb 07 19:45:14 volumio volumio-remote-updater[643]: [2026-02-07 19:45:14] [connect] Successful connection Feb 07 19:45:14 volumio volumio[20676]: info: MYVOLUMIO Environment detected Feb 07 19:45:14 volumio volumio[20676]: info: Plugin folders cleanup Feb 07 19:45:14 volumio volumio[20676]: info: Scanning into folder /volumio/app/plugins/ Feb 07 19:45:14 volumio volumio[20676]: info: Scanning category audio_interface Feb 07 19:45:14 volumio volumio[20676]: info: Scanning category miscellanea Feb 07 19:45:14 volumio volumio[20676]: info: Scanning category music_service Feb 07 19:45:14 volumio volumio[20676]: info: Scanning category plugins.json Feb 07 19:45:14 volumio volumio[20676]: info: Scanning category system_controller Feb 07 19:45:14 volumio volumio[20676]: info: Scanning category user_interface Feb 07 19:45:14 volumio volumio[20676]: info: Scanning into folder /data/plugins/ Feb 07 19:45:14 volumio volumio[20676]: info: Scanning category music_service Feb 07 19:45:14 volumio volumio[20676]: info: Plugin folders cleanup completed Feb 07 19:45:15 volumio volumio[20676]: info: ------------------------------------------- Feb 07 19:45:15 volumio volumio[20676]: info: ----- Core plugins startup ---- Feb 07 19:45:15 volumio volumio[20676]: info: ------------------------------------------- Feb 07 19:45:15 volumio volumio[20676]: info: Loading plugins from folder /volumio/app/plugins/ Feb 07 19:45:15 volumio volumio[20676]: info: Adding plugin upnp to MyMusic Plugins Feb 07 19:45:15 volumio volumio[20676]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 07 19:45:15 volumio volumio[20676]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 07 19:45:15 volumio volumio[20676]: info: Loading plugins from folder /data/plugins/ Feb 07 19:45:15 volumio volumio[20676]: info: Loading plugin "system"... Feb 07 19:45:15 volumio volumio[20676]: info: Loading plugin "appearance"... Feb 07 19:45:15 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Feb 07 19:45:15 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 19:45:15 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 19:45:15 volumio go-librespot[20712]: go-librespot daemon starting... Feb 07 19:45:15 volumio go-librespot[20713]: time="2026-02-07T19:45:15+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 19:45:15 volumio go-librespot[20713]: time="2026-02-07T19:45:15+07:00" level=debug msg="app state loaded" Feb 07 19:45:15 volumio go-librespot[20713]: time="2026-02-07T19:45:15+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 19:45:16 volumio go-librespot[20713]: time="2026-02-07T19:45:16+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 07 19:45:16 volumio go-librespot[20713]: time="2026-02-07T19:45:16+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 07 19:45:16 volumio go-librespot[20713]: time="2026-02-07T19:45:16+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 07 19:45:16 volumio go-librespot[20713]: time="2026-02-07T19:45:16+07:00" level=info msg="zeroconf server listening on port 35077" Feb 07 19:45:16 volumio go-librespot[20713]: time="2026-02-07T19:45:16+07:00" level=debug msg="obtained new client token: AABJuWaL+Pn95GA9gM38sHOAwExvNgaGOMmXSfx3MFD0ckDFjOPv55JALwul4/QarXW0Wo+sNeITJMcmcHvKJ6dgBPnkZCCQaFCR5zxuPzf8ZeTVMx9aTIDZrl0TLIyJ/UnRWy+s2AWUy2X7bDxMDFQLjyYd2HT8/rUDCL/6GsTRKg/G1qOKrtbtRLATJ8m0NFDoCCD904MbhBojaiCzBHOutV08S9IWM1Ct5BX6NTZUdNmVBJJNcSmCaw==" Feb 07 19:45:16 volumio go-librespot[20713]: time="2026-02-07T19:45:16+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 07 19:45:16 volumio go-librespot[20713]: time="2026-02-07T19:45:16+07:00" level=debug msg="completed keyexchange" Feb 07 19:45:16 volumio go-librespot[20713]: time="2026-02-07T19:45:16+07:00" level=debug msg="completed challenge" Feb 07 19:45:16 volumio go-librespot[20713]: time="2026-02-07T19:45:16+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 07 19:45:16 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 19:45:16 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 19:45:17 volumio volumio[20676]: info: Loading plugin "network"... Feb 07 19:45:17 volumio volumio[20676]: info: Refreshing Cached IP Addresses Feb 07 19:45:17 volumio sudo[20721]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 07 19:45:17 volumio sudo[20721]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 19:45:17 volumio sudo[20723]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 07 19:45:17 volumio volumio[20676]: info: Loading plugin "services"... Feb 07 19:45:17 volumio sudo[20723]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 19:45:17 volumio sudo[20721]: pam_unix(sudo:session): session closed for user root Feb 07 19:45:17 volumio volumio[20676]: info: Loading plugin "alsa_controller"... Feb 07 19:45:17 volumio sudo[20723]: pam_unix(sudo:session): session closed for user root Feb 07 19:45:17 volumio sudo[20730]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 07 19:45:17 volumio sudo[20730]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 19:45:17 volumio volumio[20676]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 07 19:45:17 volumio volumio[20676]: info: Loading plugin "wizard"... Feb 07 19:45:17 volumio volumio[20676]: info: Loading plugin "networkfs"... Feb 07 19:45:17 volumio volumio[20676]: info: Starting Udev Watcher for removable devices Feb 07 19:45:17 volumio volumio[20676]: info: Ignoring mount for partition: boot Feb 07 19:45:17 volumio volumio[20676]: info: Ignoring mount for partition: volumio Feb 07 19:45:17 volumio volumio[20676]: info: Ignoring mount for partition: volumio_data Feb 07 19:45:17 volumio volumio[20676]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 07 19:45:17 volumio volumio[20676]: info: Loading plugin "volumio_command_line_client"... Feb 07 19:45:17 volumio volumio[20676]: info: Loading plugin "upnp"... Feb 07 19:45:17 volumio volumio[20676]: info: [1770468317423] Starting Upmpd Daemon Feb 07 19:45:17 volumio volumio[20676]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 07 19:45:17 volumio volumio[20676]: info: Loading plugin "my_music"... Feb 07 19:45:17 volumio volumio[20676]: info: Loading plugin "mpd"... Feb 07 19:45:18 volumio volumio[20676]: info: Loading plugin "upnp_browser"... Feb 07 19:45:19 volumio sudo[20730]: pam_unix(sudo:session): session closed for user root Feb 07 19:45:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Feb 07 19:45:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 19:45:20 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 19:45:20 volumio go-librespot[20755]: go-librespot daemon starting... Feb 07 19:45:20 volumio go-librespot[20756]: time="2026-02-07T19:45:20+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 19:45:20 volumio go-librespot[20756]: time="2026-02-07T19:45:20+07:00" level=debug msg="app state loaded" Feb 07 19:45:20 volumio go-librespot[20756]: time="2026-02-07T19:45:20+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 19:45:20 volumio go-librespot[20756]: time="2026-02-07T19:45:20+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 07 19:45:20 volumio go-librespot[20756]: time="2026-02-07T19:45:20+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 07 19:45:20 volumio go-librespot[20756]: time="2026-02-07T19:45:20+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 07 19:45:20 volumio go-librespot[20756]: time="2026-02-07T19:45:20+07:00" level=info msg="zeroconf server listening on port 46603" Feb 07 19:45:20 volumio go-librespot[20756]: time="2026-02-07T19:45:20+07:00" level=debug msg="obtained new client token: AADezY7ieKSqnL1JrbC/zkUqBWKvfBV/tLhAf7cQ1f0wgJulhICeFCr+q2tzPsG9ty4ngIE4Ai1CkX1halu97I9reGBll7dSW16fYCXr5Tfyy2TIYUCQMn4RZh4GogCJ/84WRPQNdPKMz3faD3e/NX0jvsZlwznnDs3LvQHKuTiiFrvdehA5i2YatAbVz/YXKwtbN6efLwPzM+7M2KxiRX2p3Mmf4Nddrb+fdbVGzIekh81ceF844RQkKQ==" Feb 07 19:45:20 volumio go-librespot[20756]: time="2026-02-07T19:45:20+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 07 19:45:21 volumio volumio[20676]: info: Starting UPNP Browser Feb 07 19:45:21 volumio volumio[20676]: info: Loading plugin "alarm-clock"... Feb 07 19:45:21 volumio go-librespot[20756]: time="2026-02-07T19:45:21+07:00" level=debug msg="completed keyexchange" Feb 07 19:45:21 volumio go-librespot[20756]: time="2026-02-07T19:45:21+07:00" level=debug msg="completed challenge" Feb 07 19:45:21 volumio go-librespot[20756]: time="2026-02-07T19:45:21+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 07 19:45:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 19:45:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 19:45:21 volumio volumio[20676]: info: Loading plugin "airplay_emulation"... Feb 07 19:45:21 volumio volumio[20676]: info: Starting Shairport Sync Feb 07 19:45:21 volumio volumio[20676]: info: Loading plugin "last_100"... Feb 07 19:45:21 volumio volumio[20676]: info: Loading plugin "webradio"... Feb 07 19:45:21 volumio volumio[20676]: info: Loading plugin "i2s_dacs"... Feb 07 19:45:21 volumio volumio[20676]: info: Loading plugin "volumiodiscovery"... Feb 07 19:45:21 volumio volumio[20676]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 07 19:45:21 volumio volumio[20676]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 07 19:45:21 volumio volumio[20676]: *** WARNING *** For more information see Feb 07 19:45:21 volumio volumio[20676]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 07 19:45:21 volumio volumio[20676]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 07 19:45:21 volumio volumio[20676]: *** WARNING *** For more information see Feb 07 19:45:21 volumio node[20676]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 07 19:45:21 volumio node[20676]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 07 19:45:21 volumio node[20676]: *** WARNING *** For more information see Feb 07 19:45:21 volumio node[20676]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 07 19:45:21 volumio node[20676]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 07 19:45:21 volumio node[20676]: *** WARNING *** For more information see Feb 07 19:45:21 volumio volumio[20676]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 07 19:45:21 volumio volumio[20676]: info: Discovery: Started advertising with name: Volumio Feb 07 19:45:21 volumio volumio[20676]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 07 19:45:21 volumio volumio[20676]: info: Loading plugin "spop"... Feb 07 19:45:23 volumio volumio[20676]: info: Loading plugin "ytcr"... Feb 07 19:45:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11. Feb 07 19:45:24 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 19:45:24 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 19:45:24 volumio go-librespot[20766]: go-librespot daemon starting... Feb 07 19:45:24 volumio go-librespot[20767]: time="2026-02-07T19:45:24+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 19:45:24 volumio go-librespot[20767]: time="2026-02-07T19:45:24+07:00" level=debug msg="app state loaded" Feb 07 19:45:24 volumio go-librespot[20767]: time="2026-02-07T19:45:24+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 19:45:24 volumio go-librespot[20767]: time="2026-02-07T19:45:24+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 07 19:45:24 volumio go-librespot[20767]: time="2026-02-07T19:45:24+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 07 19:45:24 volumio go-librespot[20767]: time="2026-02-07T19:45:24+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 07 19:45:24 volumio go-librespot[20767]: time="2026-02-07T19:45:24+07:00" level=info msg="zeroconf server listening on port 35597" Feb 07 19:45:25 volumio go-librespot[20767]: time="2026-02-07T19:45:25+07:00" level=debug msg="obtained new client token: AAB5jlMSdiciMKaGBg6CNnmkQXHp3hNf13hsPylSaASq8YGGmgHQuHR/qFbuC6+UBB7BIIXbY5PD4CscK0paUBWZ7pjM1oBainnHUJhu9c/NRT5tIXqtXRf1fLzujSUNe6C1iCfchRLTSiCKrktLcgQgcm57XvUJGaEf8EBpVIvgsU4/e4pFN33YjjI7S4Rds+lEH21EvGsEo0enwQTEZFGv+SIjWXEioPg+aJOH+t7jM+M5+kOe+/k=" Feb 07 19:45:25 volumio go-librespot[20767]: time="2026-02-07T19:45:25+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 07 19:45:25 volumio go-librespot[20767]: time="2026-02-07T19:45:25+07:00" level=debug msg="completed keyexchange" Feb 07 19:45:25 volumio go-librespot[20767]: time="2026-02-07T19:45:25+07:00" level=debug msg="completed challenge" Feb 07 19:45:25 volumio go-librespot[20767]: time="2026-02-07T19:45:25+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 07 19:45:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 19:45:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 19:45:26 volumio volumio[20676]: info: Loading plugin "ytmusic"... Feb 07 19:45:26 volumio volumio-remote-updater[643]: [2026-02-07 19:45:26] [connect] Successful connection Feb 07 19:45:27 volumio volumio[20676]: info: Loading plugin "outputs"... Feb 07 19:45:27 volumio volumio[20676]: info: Loading plugin "albumart"... Feb 07 19:45:27 volumio volumio[20676]: info: Plugin example_plugin is not enabled Feb 07 19:45:27 volumio volumio[20676]: info: Loading plugin "inputs"... Feb 07 19:45:27 volumio volumio[20676]: info: Loading plugin "updater_comm"... Feb 07 19:45:27 volumio volumio[20676]: info: Plugin mpdemulation is not enabled Feb 07 19:45:27 volumio volumio[20676]: info: Loading plugin "rest_api"... Feb 07 19:45:27 volumio volumio[20676]: info: Loading plugin "websocket"... Feb 07 19:45:27 volumio volumio[20676]: info: Starting Socket.io Server version 1.7.4 Feb 07 19:45:27 volumio volumio[20676]: info: Loading plugin "RoonBridge"... Feb 07 19:45:28 volumio volumio[20676]: info: Applying required configuration parameters for plugin RoonBridge Feb 07 19:45:28 volumio volumio[20676]: info: Loading i18n strings for locale en Feb 07 19:45:28 volumio volumio[20676]: Updating browse sources language Feb 07 19:45:28 volumio volumio[20676]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 19:45:28 volumio volumio[20676]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 07 19:45:28 volumio volumio[20676]: info: CoreCommandRouter::initPlayerControls Feb 07 19:45:28 volumio volumio[20676]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 19:45:28 volumio volumio[20676]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 19:45:28 volumio volumio[20676]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 19:45:28 volumio volumio[20676]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 19:45:28 volumio volumio[20676]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 19:45:28 volumio volumio[20676]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 19:45:28 volumio volumio[20676]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 19:45:28 volumio volumio[20676]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 19:45:28 volumio volumio[20676]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 07 19:45:28 volumio volumio[20676]: Express server listening on port 3000 Feb 07 19:45:28 volumio volumio[20676]: [Metrics] WebUI: 15s 875.77ms Feb 07 19:45:28 volumio volumio[20789]: Forking 3 albumart workers Feb 07 19:45:28 volumio volumio[20676]: info: CoreStateMachine::resetVolumioState Feb 07 19:45:28 volumio volumio[20676]: info: CoreStateMachine::getcurrentVolume Feb 07 19:45:28 volumio volumio[20676]: info: CoreCommandRouter::volumioRetrievevolume Feb 07 19:45:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12. Feb 07 19:45:28 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 19:45:28 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 19:45:28 volumio go-librespot[20806]: go-librespot daemon starting... Feb 07 19:45:28 volumio go-librespot[20834]: time="2026-02-07T19:45:28+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 19:45:28 volumio go-librespot[20834]: time="2026-02-07T19:45:28+07:00" level=debug msg="app state loaded" Feb 07 19:45:28 volumio go-librespot[20834]: time="2026-02-07T19:45:28+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 19:45:28 volumio sudo[20824]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 07 19:45:28 volumio sudo[20824]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 19:45:28 volumio sudo[20824]: pam_unix(sudo:session): session closed for user root Feb 07 19:45:28 volumio sudo[20829]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 07 19:45:28 volumio sudo[20829]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 19:45:28 volumio sudo[20829]: pam_unix(sudo:session): session closed for user root Feb 07 19:45:28 volumio volumio[20676]: info: Volumio Network Manager: Network status updated: 1 Feb 07 19:45:29 volumio volumio[20676]: info: VolumeController:: Volume=100 Mute =false Feb 07 19:45:29 volumio volumio[20676]: info: CoreStateMachine::pushState Feb 07 19:45:29 volumio volumio[20676]: info: CorePlayQueue::getTrack 0 Feb 07 19:45:29 volumio volumio[20676]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 07 19:45:29 volumio volumio[20676]: info: CoreCommandRouter::volumioPushState Feb 07 19:45:29 volumio volumio[20676]: info: CoreStateMachine::updateTrackBlock Feb 07 19:45:29 volumio volumio[20676]: info: CorePlayQueue::getTrackBlock Feb 07 19:45:29 volumio volumio[20676]: info: CoreCommandRouter::volumioRetrievevolume Feb 07 19:45:29 volumio volumio-remote-updater[643]: [2026-02-07 19:45:29] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1770468326 101 Feb 07 19:45:29 volumio volumio[20676]: 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 07 19:45:29 volumio go-librespot[20834]: time="2026-02-07T19:45:29+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 07 19:45:29 volumio go-librespot[20834]: time="2026-02-07T19:45:29+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 07 19:45:29 volumio go-librespot[20834]: time="2026-02-07T19:45:29+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 07 19:45:29 volumio go-librespot[20834]: time="2026-02-07T19:45:29+07:00" level=info msg="zeroconf server listening on port 34365" Feb 07 19:45:29 volumio volumio[20676]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 19:45:29 volumio volumio[20676]: info: Reloading queue from file Feb 07 19:45:29 volumio volumio[20676]: info: CoreStateMachine::setRepeat null single undefined Feb 07 19:45:29 volumio volumio[20676]: info: CoreStateMachine::pushState Feb 07 19:45:29 volumio volumio[20676]: info: CorePlayQueue::getTrack 0 Feb 07 19:45:29 volumio volumio[20676]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 07 19:45:29 volumio volumio[20676]: info: CoreCommandRouter::volumioPushState Feb 07 19:45:29 volumio volumio[20676]: info: CoreStateMachine::setRandom null Feb 07 19:45:29 volumio volumio[20676]: info: CoreStateMachine::pushState Feb 07 19:45:29 volumio volumio[20676]: info: CorePlayQueue::getTrack 0 Feb 07 19:45:29 volumio volumio[20676]: info: CoreCommandRouter::volumioPushState Feb 07 19:45:29 volumio volumio[20676]: info: Setting Device type: Raspberry PI Feb 07 19:45:29 volumio volumio[20676]: info: Completed loading Core Plugins Feb 07 19:45:29 volumio volumio[20676]: info: Preparing to generate the ALSA configuration file Feb 07 19:45:29 volumio go-librespot[20834]: time="2026-02-07T19:45:29+07:00" level=debug msg="obtained new client token: AADeOw+45ECWtCsSWybED0dau5pbkuTEZaBXdxD5jhpxE9r2C0Ygm7eHKX63Uu9iHUidWjUq9DwxVvX/3Ts4Nc9PVcSLhi8oEhF3+tWprg+mNKxkXRgaBU1sGvDEAMGxGuUO7Epgx7gftUTyLwebN6BOEwfOCBh9rUMhrmLTBCV41nG4spA/lZPOD6XN63atzu+cLBZpYpwLY8Q92BYBi8ctPqQXPPlawnGyfIxDjTbcLuT8c1QmbX48Yw==" Feb 07 19:45:29 volumio go-librespot[20834]: time="2026-02-07T19:45:29+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 07 19:45:29 volumio volumio[20676]: info: VolumeController:: Volume=100 Mute =false Feb 07 19:45:29 volumio volumio[20676]: info: CoreStateMachine::pushState Feb 07 19:45:29 volumio volumio[20676]: info: CorePlayQueue::getTrack 0 Feb 07 19:45:29 volumio volumio[20676]: info: CoreCommandRouter::volumioPushState Feb 07 19:45:29 volumio volumio[20676]: info: Asound.conf file unchanged, so no further update is needed Feb 07 19:45:29 volumio volumio[20676]: info: Output device has changed, restarting MPD Feb 07 19:45:29 volumio go-librespot[20834]: time="2026-02-07T19:45:29+07:00" level=debug msg="completed keyexchange" Feb 07 19:45:29 volumio go-librespot[20834]: time="2026-02-07T19:45:29+07:00" level=debug msg="completed challenge" Feb 07 19:45:29 volumio volumio[20676]: info: Output device has changed, restarting Shairport Sync Feb 07 19:45:29 volumio volumio[20676]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 19:45:29 volumio sudo[20859]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 07 19:45:29 volumio sudo[20859]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 19:45:29 volumio volumio[20676]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 19:45:29 volumio go-librespot[20834]: time="2026-02-07T19:45:29+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 07 19:45:29 volumio sudo[20861]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 07 19:45:29 volumio sudo[20861]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 19:45:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 19:45:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 19:45:29 volumio sudo[20861]: pam_unix(sudo:session): session closed for user root Feb 07 19:45:30 volumio sudo[20864]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 07 19:45:30 volumio sudo[20864]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 19:45:30 volumio volumio[20676]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 07 19:45:30 volumio volumio[20676]: info: ___________ START PLUGINS ___________ Feb 07 19:45:30 volumio volumio[20676]: info: ControllerMpd::onStart: Initializing MPD Feb 07 19:45:30 volumio sudo[20859]: pam_unix(sudo:session): session closed for user root Feb 07 19:45:30 volumio volumio[20676]: info: Creating MPD Configuration file Feb 07 19:45:30 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 07 19:45:30 volumio volumio[20676]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 07 19:45:30 volumio volumio[20676]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 19:45:30 volumio volumio[20676]: info: [1770468330163] CoreMusicLibrary::Adding element Media Servers Feb 07 19:45:30 volumio volumio[20676]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 19:45:30 volumio volumio[20676]: info: UPNP Browser: Client initialized successfully Feb 07 19:45:30 volumio sudo[20874]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 07 19:45:30 volumio sudo[20874]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 19:45:30 volumio volumio[20676]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 19:45:30 volumio volumio[20676]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 19:45:30 volumio sudo[20872]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 07 19:45:30 volumio sudo[20872]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 19:45:30 volumio sudo[20872]: pam_unix(sudo:session): session closed for user root Feb 07 19:45:30 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 07 19:45:30 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 07 19:45:30 volumio systemd[1]: mpd.service: Consumed 7.141s CPU time. Feb 07 19:45:30 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 07 19:45:30 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 07 19:45:30 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 07 19:45:30 volumio volumio[20676]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 07 19:45:30 volumio volumio[20676]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 19:45:30 volumio volumio[20676]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 19:45:30 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 07 19:45:30 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 07 19:45:30 volumio volumio[20676]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 07 19:45:30 volumio volumio[20676]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 19:45:30 volumio volumio[20676]: info: [1770468330539] CoreMusicLibrary::Adding element Last_100 Feb 07 19:45:30 volumio volumio[20676]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 19:45:30 volumio volumio[20676]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 19:45:30 volumio volumio[20676]: info: [1770468330565] CoreMusicLibrary::Adding element Webradio Feb 07 19:45:30 volumio volumio[20676]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 19:45:30 volumio volumio[20676]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 07 19:45:30 volumio volumio[20676]: info: Initializing BBC Radios Feb 07 19:45:30 volumio volumio[20676]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 07 19:45:30 volumio volumio[20676]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 19:45:30 volumio volumio[20676]: info: Creating Spotify config file Feb 07 19:45:30 volumio volumio[20676]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 19:45:30 volumio sudo[20886]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 07 19:45:30 volumio sudo[20886]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 07 19:45:30 volumio sudo[20886]: pam_unix(sudo:session): session closed for user root Feb 07 19:45:31 volumio volumio[20803]: Starting albumart workers Feb 07 19:45:31 volumio volumio[20804]: Starting albumart workers Feb 07 19:45:31 volumio volumio[20676]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 19:45:31 volumio volumio[20676]: info: [1770468331921] CoreMusicLibrary::Adding element YouTube Music Feb 07 19:45:31 volumio volumio[20676]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 19:45:31 volumio volumio[20676]: Cannot find translation for source YouTube Music Feb 07 19:45:31 volumio volumio[20676]: info: Volumio Calling Home Feb 07 19:45:32 volumio sudo[20903]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Feb 07 19:45:32 volumio sudo[20903]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 19:45:32 volumio sudo[20903]: pam_unix(sudo:session): session closed for user root Feb 07 19:45:32 volumio volumio[20802]: Starting albumart workers Feb 07 19:45:32 volumio volumio[20676]: info: MPD Permissions set Feb 07 19:45:32 volumio volumio[20676]: info: MPD Permissions set Feb 07 19:45:32 volumio volumio[20676]: info: Upmpdcli Daemon Started Feb 07 19:45:32 volumio volumio[20676]: info: Discovery: adding 921648ed-0557-4d70-9927-1420682efcc7 Feb 07 19:45:32 volumio volumio[20676]: info: Discovery: Found device Volumio Feb 07 19:45:32 volumio volumio[20676]: info: CoreCommandRouter::volumioGetState Feb 07 19:45:32 volumio volumio[20676]: info: CorePlayQueue::getTrack 0 Feb 07 19:45:32 volumio volumio[20676]: info: Discovery: this is already registered, 921648ed-0557-4d70-9927-1420682efcc7 Feb 07 19:45:32 volumio volumio[20676]: info: Discovery: Found device Volumio Feb 07 19:45:32 volumio volumio[20676]: info: CoreCommandRouter::volumioGetState Feb 07 19:45:32 volumio volumio[20676]: info: CorePlayQueue::getTrack 0 Feb 07 19:45:33 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13. Feb 07 19:45:33 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 19:45:33 volumio volumio[20676]: info: Volumio called home Feb 07 19:45:33 volumio volumio[20676]: info: Spotify config file written Feb 07 19:45:33 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 19:45:33 volumio go-librespot[20910]: go-librespot daemon starting... Feb 07 19:45:33 volumio sudo[20912]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 07 19:45:33 volumio sudo[20912]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 19:45:33 volumio go-librespot[20913]: time="2026-02-07T19:45:33+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 19:45:33 volumio go-librespot[20913]: time="2026-02-07T19:45:33+07:00" level=debug msg="app state loaded" Feb 07 19:45:33 volumio go-librespot[20913]: time="2026-02-07T19:45:33+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 19:45:33 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Feb 07 19:45:33 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Feb 07 19:45:33 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 19:45:33 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 19:45:33 volumio go-librespot[20920]: go-librespot daemon starting... Feb 07 19:45:33 volumio volumio[20676]: 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 07 19:45:33 volumio sudo[20912]: pam_unix(sudo:session): session closed for user root Feb 07 19:45:33 volumio volumio[20676]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 19:45:33 volumio volumio[20676]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 19:45:33 volumio volumio[20676]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 19:45:33 volumio volumio[20676]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 19:45:33 volumio volumio[20676]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 19:45:33 volumio volumio[20676]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 19:45:33 volumio volumio[20676]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 19:45:33 volumio volumio[20676]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 19:45:33 volumio volumio[20676]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 19:45:33 volumio volumio[20676]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 19:45:33 volumio volumio[20676]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 19:45:33 volumio volumio[20676]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 19:45:33 volumio volumio[20676]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 19:45:33 volumio volumio[20676]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 19:45:33 volumio volumio[20676]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 19:45:33 volumio volumio[20676]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 19:45:33 volumio volumio[20676]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 19:45:33 volumio go-librespot[20921]: time="2026-02-07T19:45:33+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 19:45:33 volumio go-librespot[20921]: time="2026-02-07T19:45:33+07:00" level=debug msg="app state loaded" Feb 07 19:45:33 volumio volumio[20676]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 19:45:33 volumio go-librespot[20921]: time="2026-02-07T19:45:33+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 19:45:33 volumio volumio[20676]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 19:45:33 volumio volumio[20676]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 19:45:33 volumio volumio[20676]: info: No need to fix Spotify hosts Feb 07 19:45:34 volumio go-librespot[20921]: time="2026-02-07T19:45:34+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 07 19:45:34 volumio go-librespot[20921]: time="2026-02-07T19:45:34+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 07 19:45:34 volumio go-librespot[20921]: time="2026-02-07T19:45:34+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 07 19:45:34 volumio go-librespot[20921]: time="2026-02-07T19:45:34+07:00" level=info msg="zeroconf server listening on port 42247" Feb 07 19:45:34 volumio go-librespot[20921]: time="2026-02-07T19:45:34+07:00" level=debug msg="obtained new client token: AADs19znvrJNPj1UdIiqMUEAIK4fd66gKGpCoVPahvwo50/ZqkGRQ7YmbOaoT33g7J8IVOvXM7C/jmIkgUIGM8RXsfW8ZEe+mVs6GVbsbeAQflr9YPuJhVlT5PTLmjp1dG4c6eWoqgcDfSlwKepSe66sJCy+yWrCy6wDg15Xeu+LYb0YjAcLP2Nk4JtRQU6+zmOKVUQk+50DHDqS11dKaDMF5mrbd2FqmNJEnUcYhqKjHj2tMY31MorXow==" Feb 07 19:45:34 volumio go-librespot[20921]: time="2026-02-07T19:45:34+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 07 19:45:34 volumio volumio[20676]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 07 19:45:34 volumio volumio[20676]: SPOTIFY: BQAFCU7W7WLCwn23z7CpmdZLt1ltGZ3gC4UdJpP5scewxfclBL-2Ab21xqDWE9bhk-HD48gGjqoX0StVV8okm2mI2MFCxHqUarTFi-P8v7yoWwvolmNx26uTgmtpZBh40XEvXDr28OGjK5f3M71C4OZzQji_yJSxI7i5_mW7VnBvVmF68RLMrKbl1epGWmsAPvyDVPHTUdofT9YUIVPCnQQ97CrWvPS56yzVoppTZREBX8hGh0bSu5YcMmS_iXbOd6Z9xaRQzP8_eoBUGYjZuWYrF4yJm24jtoSA93dBBHpj23EFvFnycYsz Feb 07 19:45:34 volumio volumio[20676]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 07 19:45:34 volumio volumio[20676]: info: New Spotify access token = BQAFCU7W7WLCwn23z7CpmdZLt1ltGZ3gC4UdJpP5scewxfclBL-2Ab21xqDWE9bhk-HD48gGjqoX0StVV8okm2mI2MFCxHqUarTFi-P8v7yoWwvolmNx26uTgmtpZBh40XEvXDr28OGjK5f3M71C4OZzQji_yJSxI7i5_mW7VnBvVmF68RLMrKbl1epGWmsAPvyDVPHTUdofT9YUIVPCnQQ97CrWvPS56yzVoppTZREBX8hGh0bSu5YcMmS_iXbOd6Z9xaRQzP8_eoBUGYjZuWYrF4yJm24jtoSA93dBBHpj23EFvFnycYsz Feb 07 19:45:34 volumio volumio[20676]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 07 19:45:34 volumio volumio[20676]: info: Starting Shairport Sync Feb 07 19:45:34 volumio go-librespot[20921]: time="2026-02-07T19:45:34+07:00" level=debug msg="connected to ap-gae2.spotify.com:443" Feb 07 19:45:34 volumio volumio[20676]: info: Starting Shairport Sync Feb 07 19:45:34 volumio volumio[20676]: info: Starting Shairport Sync Feb 07 19:45:34 volumio sudo[20957]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 07 19:45:34 volumio sudo[20957]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 19:45:34 volumio sudo[20959]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 07 19:45:34 volumio go-librespot[20921]: time="2026-02-07T19:45:34+07:00" level=debug msg="completed keyexchange" Feb 07 19:45:34 volumio go-librespot[20921]: time="2026-02-07T19:45:34+07:00" level=debug msg="completed challenge" Feb 07 19:45:34 volumio sudo[20959]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 19:45:35 volumio sudo[20961]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 07 19:45:35 volumio sudo[20961]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 19:45:35 volumio go-librespot[20921]: time="2026-02-07T19:45:35+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 07 19:45:35 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 19:45:35 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 19:45:35 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 07 19:45:35 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 07 19:45:35 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 07 19:45:35 volumio systemd[1]: shairport-sync.service: Consumed 2.415s CPU time. Feb 07 19:45:35 volumio volumio[20676]: info: CoreCommandRouter::volumioGetState Feb 07 19:45:35 volumio volumio[20676]: info: CorePlayQueue::getTrack 0 Feb 07 19:45:35 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 07 19:45:35 volumio sudo[20959]: pam_unix(sudo:session): session closed for user root Feb 07 19:45:35 volumio sudo[20957]: pam_unix(sudo:session): session closed for user root Feb 07 19:45:35 volumio sudo[20961]: pam_unix(sudo:session): session closed for user root Feb 07 19:45:35 volumio volumio[20676]: info: Shairport-Sync Started Feb 07 19:45:35 volumio volumio[20676]: Error adding Membership: Error: addMembership EINVAL Feb 07 19:45:35 volumio volumio[20676]: info: Shairport-Sync Started Feb 07 19:45:35 volumio volumio[20676]: info: Shairport-Sync Started Feb 07 19:45:35 volumio volumio[20676]: 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 07 19:45:35 volumio volumio[20676]: info: Spotify Successfully logged in Feb 07 19:45:35 volumio volumio[20676]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 19:45:35 volumio volumio[20676]: info: [1770468335576] CoreMusicLibrary::Adding element Spotify Feb 07 19:45:35 volumio volumio[20676]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 19:45:35 volumio volumio[20676]: Cannot find translation for source YouTube Music Feb 07 19:45:35 volumio volumio[20676]: Cannot find translation for source Spotify Feb 07 19:45:36 volumio volumio[20676]: info: [yt-cast-receiver] DIAL server listening on port 8098 Feb 07 19:45:36 volumio volumio[20676]: info: CoreCommandRouter::volumioRetrievevolume Feb 07 19:45:36 volumio volumio[20676]: info: VolumeController:: Volume=100 Mute =false Feb 07 19:45:36 volumio volumio[20676]: info: CoreCommandRouter::volumioGetState Feb 07 19:45:36 volumio volumio[20676]: info: CorePlayQueue::getTrack 0 Feb 07 19:45:36 volumio volumio[20676]: info: CoreStateMachine::pushState Feb 07 19:45:36 volumio volumio[20676]: info: CorePlayQueue::getTrack 0 Feb 07 19:45:36 volumio volumio[20676]: info: CoreCommandRouter::volumioPushState Feb 07 19:45:37 volumio volumio[20676]: info: go-librespot daemon successfully initialized Feb 07 19:45:37 volumio mpd[20901]: 2026-02-07T19:45:37 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 07 19:45:37 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 07 19:45:37 volumio sudo[20864]: pam_unix(sudo:session): session closed for user root Feb 07 19:45:37 volumio sudo[20874]: pam_unix(sudo:session): session closed for user root Feb 07 19:45:38 volumio volumio[20676]: error: MPD error: The expression evaluated to a falsy value: Feb 07 19:45:38 volumio volumio[20676]: assert.ok(self.idling) Feb 07 19:45:38 volumio volumio[20676]: error: The expression evaluated to a falsy value: Feb 07 19:45:38 volumio volumio[20676]: assert.ok(self.idling) Feb 07 19:45:38 volumio volumio[20676]: error: updateQueue error: null Feb 07 19:45:38 volumio volumio[20676]: info: MPD running with PID20901 Feb 07 19:45:38 volumio volumio[20676]: ,establishing connection Feb 07 19:45:38 volumio volumio[20676]: info: Completed starting Core Plugins Feb 07 19:45:38 volumio volumio[20676]: info: ------------------------------------------- Feb 07 19:45:38 volumio volumio[20676]: info: ----- MyVolumio plugins startup ---- Feb 07 19:45:38 volumio volumio[20676]: info: ------------------------------------------- Feb 07 19:45:38 volumio volumio[20676]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 07 19:45:38 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Feb 07 19:45:38 volumio volumio[20676]: error: updateQueue error: null Feb 07 19:45:38 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 19:45:38 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 19:45:38 volumio go-librespot[21001]: go-librespot daemon starting... Feb 07 19:45:38 volumio go-librespot[21002]: time="2026-02-07T19:45:38+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 19:45:38 volumio go-librespot[21002]: time="2026-02-07T19:45:38+07:00" level=debug msg="app state loaded" Feb 07 19:45:38 volumio go-librespot[21002]: time="2026-02-07T19:45:38+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 19:45:38 volumio go-librespot[21002]: time="2026-02-07T19:45:38+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 07 19:45:38 volumio go-librespot[21002]: time="2026-02-07T19:45:38+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 07 19:45:38 volumio go-librespot[21002]: time="2026-02-07T19:45:38+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 07 19:45:38 volumio go-librespot[21002]: time="2026-02-07T19:45:38+07:00" level=info msg="zeroconf server listening on port 44251" Feb 07 19:45:39 volumio go-librespot[21002]: time="2026-02-07T19:45:39+07:00" level=debug msg="obtained new client token: AADpdrCg7KpRgIh9s0WhplJplExoxQwz9jkKahLj3nldYyfFx1ugcC0TSwOcmqB9XgXns1nuY8XjaTPNn/SvbtUtqDhyKmn6Mjd42jov8GmjKMyK2ANve8IkOfOceiPAb1Ml15OJV9WTnxpckrSkrKB8rsaf9JDMqbsPVHC3I4hUm7ukpNYPTmnGJoatJmBtGzHsfWSXcuMy7E9VuZdUEjWDMFMdkSf0+Rdcz1GiURTkXHVo8+B4AMY=" Feb 07 19:45:39 volumio go-librespot[21002]: time="2026-02-07T19:45:39+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 07 19:45:39 volumio go-librespot[21002]: time="2026-02-07T19:45:39+07:00" level=debug msg="completed keyexchange" Feb 07 19:45:39 volumio go-librespot[21002]: time="2026-02-07T19:45:39+07:00" level=debug msg="completed challenge" Feb 07 19:45:39 volumio go-librespot[21002]: time="2026-02-07T19:45: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 07 19:45:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 19:45:39 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 19:45:40 volumio volumio[20676]: info: Initializing connection to go-librespot Websocket Feb 07 19:45:40 volumio volumio[20676]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 07 19:45:42 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Feb 07 19:45:42 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 19:45:42 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 19:45:42 volumio go-librespot[21010]: go-librespot daemon starting... Feb 07 19:45:42 volumio go-librespot[21011]: time="2026-02-07T19:45:42+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 19:45:42 volumio go-librespot[21011]: time="2026-02-07T19:45:42+07:00" level=debug msg="app state loaded" Feb 07 19:45:42 volumio go-librespot[21011]: time="2026-02-07T19:45:42+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 19:45:43 volumio volumio[20676]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 07 19:45:43 volumio go-librespot[21011]: time="2026-02-07T19:45:43+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 07 19:45:43 volumio go-librespot[21011]: time="2026-02-07T19:45:43+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 07 19:45:43 volumio go-librespot[21011]: time="2026-02-07T19:45:43+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 07 19:45:43 volumio go-librespot[21011]: time="2026-02-07T19:45:43+07:00" level=info msg="zeroconf server listening on port 38325" Feb 07 19:45:43 volumio go-librespot[21011]: time="2026-02-07T19:45:43+07:00" level=debug msg="obtained new client token: AABSwdk3UT66dmJP2LqlNO8lvZDC7VUkNouej2qHg+AVsVs80WVS/ScFJkIHNFU47CUKa1Kk0a4igb4Xmh9+9qgutnhUOgMDzgDF0hK9R3VnHlfpVISCqW1+7AGbqfjX8V3DmjdLjfsmWcYEteHrd6rDyiVJCiSWBoHjmGhzZLDyJ6JVMrCPirFitqpI+09UY2gO0PgbKgItyzvF573GIupD6tieHSHjfZRXu/z1nrOqLzslIPeLTgFBgQ==" Feb 07 19:45:43 volumio go-librespot[21011]: time="2026-02-07T19:45:43+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 07 19:45:43 volumio volumio[20676]: info: Initializing connection to go-librespot Websocket Feb 07 19:45:43 volumio go-librespot[21011]: time="2026-02-07T19:45:43+07:00" level=debug msg="new websocket client" Feb 07 19:45:43 volumio volumio[20676]: info: Connection to go-librespot Websocket established Feb 07 19:45:43 volumio go-librespot[21011]: time="2026-02-07T19:45:43+07:00" level=debug msg="completed keyexchange" Feb 07 19:45:43 volumio go-librespot[21011]: time="2026-02-07T19:45:43+07:00" level=debug msg="completed challenge" Feb 07 19:45:43 volumio go-librespot[21011]: time="2026-02-07T19:45:43+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 07 19:45:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 19:45:43 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 19:45:43 volumio volumio[20676]: info: Connection to go-librespot Websocket closed Feb 07 19:45:46 volumio volumio[20676]: info: Getting Spotify volume Feb 07 19:45:46 volumio volumio[20676]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 07 19:45:46 volumio volumio[20676]: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 07 19:45:46 volumio volumio[20676]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Feb 07 19:45:46 volumio volumio[20676]: errno: -111, Feb 07 19:45:46 volumio volumio[20676]: code: 'ECONNREFUSED', Feb 07 19:45:46 volumio volumio[20676]: syscall: 'connect', Feb 07 19:45:46 volumio volumio[20676]: address: '127.0.0.1', Feb 07 19:45:46 volumio volumio[20676]: port: 9879, Feb 07 19:45:46 volumio volumio[20676]: response: undefined Feb 07 19:45:46 volumio volumio[20676]: } Feb 07 19:45:46 volumio volumio[20676]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 07 19:45:46 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Feb 07 19:45:46 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 19:45:46 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 19:45:46 volumio go-librespot[21029]: go-librespot daemon starting... Feb 07 19:45:46 volumio go-librespot[21031]: time="2026-02-07T19:45:46+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 19:45:46 volumio go-librespot[21031]: time="2026-02-07T19:45:46+07:00" level=debug msg="app state loaded" Feb 07 19:45:46 volumio go-librespot[21031]: time="2026-02-07T19:45:46+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 19:45:47 volumio go-librespot[21031]: time="2026-02-07T19:45:47+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 07 19:45:47 volumio go-librespot[21031]: time="2026-02-07T19:45:47+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 07 19:45:47 volumio go-librespot[21031]: time="2026-02-07T19:45:47+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 07 19:45:47 volumio go-librespot[21031]: time="2026-02-07T19:45:47+07:00" level=info msg="zeroconf server listening on port 41275" Feb 07 19:45:47 volumio go-librespot[21031]: time="2026-02-07T19:45:47+07:00" level=debug msg="obtained new client token: AABDN9RtwRbzvSpNah9neYFdt+fyqNdTP6cmrizpqm9qbN5fC9w7lott6v3K2op2SCg5dk+8356Iy/uikQiu1umUxdr/UvEC6UqwkqZi7W/bU+i2iKeGINDE9R/LRlv+JgHtge31b3GnxwBBO0G5WmnCTeUD4Qt/BblptWhOAA7EgWfF/vhvp32J9kzYPbIsLeYfwtvCY2B2OvwWXQEXLfSNxD8SqrAKEd2HBNEmuCyl/iz/YRTaID0V9g==" Feb 07 19:45:47 volumio go-librespot[21031]: time="2026-02-07T19:45:47+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 07 19:45:47 volumio go-librespot[21031]: time="2026-02-07T19:45:47+07:00" level=debug msg="completed keyexchange" Feb 07 19:45:47 volumio go-librespot[21031]: time="2026-02-07T19:45:47+07:00" level=debug msg="completed challenge" Feb 07 19:45:47 volumio go-librespot[21031]: time="2026-02-07T19:45:47+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 07 19:45:47 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 19:45:47 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 19:45:48 volumio sudo[21054]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-07 19:44' Feb 07 19:45:48 volumio sudo[21054]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 19:45:48 volumio sudo[21054]: pam_unix(sudo:session): session closed for user root Feb 07 19:45:48 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 07 19:45:48 volumio volumio-remote-updater[643]: [2026-02-07 19:45:48] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 07 19:45:48 volumio volumio-remote-updater[643]: [2026-02-07 19:45:48] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 07 19:45:48 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 07 19:45:48 volumio systemd[1]: volumio.service: Consumed 49.290s CPU time. Feb 07 19:45:48 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 07 19:45:48 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 07 19:45:48 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 6235. Feb 07 19:45:48 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 07 19:45:48 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 07 19:45:48 volumio systemd[1]: volumio.service: Consumed 49.290s CPU time. Feb 07 19:45:48 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Feb 07 19:45:48 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 07 19:45:51 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Feb 07 19:45:51 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 19:45:51 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 19:45:51 volumio go-librespot[21081]: go-librespot daemon starting... Feb 07 19:45:51 volumio go-librespot[21082]: time="2026-02-07T19:45:51+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 19:45:51 volumio go-librespot[21082]: time="2026-02-07T19:45:51+07:00" level=debug msg="app state loaded" Feb 07 19:45:51 volumio go-librespot[21082]: time="2026-02-07T19:45:51+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 19:45:51 volumio go-librespot[21082]: time="2026-02-07T19:45:51+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 07 19:45:51 volumio go-librespot[21082]: time="2026-02-07T19:45:51+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 07 19:45:51 volumio go-librespot[21082]: time="2026-02-07T19:45:51+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 07 19:45:51 volumio go-librespot[21082]: time="2026-02-07T19:45:51+07:00" level=info msg="zeroconf server listening on port 40327" Feb 07 19:45:51 volumio go-librespot[21082]: time="2026-02-07T19:45:51+07:00" level=debug msg="obtained new client token: AACkw8SmngnngyjRrAQCsMHMML0kG9mjk5jpbRveT5PQ2yqjnTGVzSR+xjFKYgaCFagCUWxM7C2GCTJ9kFZjwUphrOKfP4C1JyAh8sgQ1Qus6aNRMqgPJrKhm+mFyY0KDXeMhaJU62blEfE4K/wf3F5DF68zZZy0q2xfnv5pkIF7FveSAUqIc4qF+9i8OHNyPSimEQs9Y9djYNo/GZK9eDE5CFesQJronS8f5ivOrEWB5Diky5yQKERYlQ==" Feb 07 19:45:52 volumio go-librespot[21082]: time="2026-02-07T19:45:52+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 07 19:45:52 volumio go-librespot[21082]: time="2026-02-07T19:45:52+07:00" level=debug msg="completed keyexchange" Feb 07 19:45:52 volumio go-librespot[21082]: time="2026-02-07T19:45:52+07:00" level=debug msg="completed challenge" Feb 07 19:45:52 volumio go-librespot[21082]: time="2026-02-07T19:45: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 07 19:45:52 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 19:45:52 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 19:45:52 volumio volumio[21066]: info: ------------------------------------------- Feb 07 19:45:52 volumio volumio[21066]: info: ----- Volumio3 ---- Feb 07 19:45:52 volumio volumio[21066]: info: ------------------------------------------- Feb 07 19:45:52 volumio volumio[21066]: info: ----- System startup ---- Feb 07 19:45:52 volumio volumio[21066]: info: ------------------------------------------- Feb 07 19:45:53 volumio volumio-remote-updater[643]: [2026-02-07 19:45:53] [connect] Successful connection Feb 07 19:45:53 volumio volumio[21066]: info: MYVOLUMIO Environment detected Feb 07 19:45:53 volumio volumio[21066]: info: Plugin folders cleanup Feb 07 19:45:53 volumio volumio[21066]: info: Scanning into folder /volumio/app/plugins/ Feb 07 19:45:53 volumio volumio[21066]: info: Scanning category audio_interface Feb 07 19:45:53 volumio volumio[21066]: info: Scanning category miscellanea Feb 07 19:45:53 volumio volumio[21066]: info: Scanning category music_service Feb 07 19:45:53 volumio volumio[21066]: info: Scanning category plugins.json Feb 07 19:45:53 volumio volumio[21066]: info: Scanning category system_controller Feb 07 19:45:53 volumio volumio[21066]: info: Scanning category user_interface Feb 07 19:45:53 volumio volumio[21066]: info: Scanning into folder /data/plugins/ Feb 07 19:45:53 volumio volumio[21066]: info: Scanning category music_service Feb 07 19:45:53 volumio volumio[21066]: info: Plugin folders cleanup completed Feb 07 19:45:54 volumio volumio[21066]: info: ------------------------------------------- Feb 07 19:45:54 volumio volumio[21066]: info: ----- Core plugins startup ---- Feb 07 19:45:54 volumio volumio[21066]: info: ------------------------------------------- Feb 07 19:45:54 volumio volumio[21066]: info: Loading plugins from folder /volumio/app/plugins/ Feb 07 19:45:54 volumio volumio[21066]: info: Adding plugin upnp to MyMusic Plugins Feb 07 19:45:54 volumio volumio[21066]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 07 19:45:54 volumio volumio[21066]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 07 19:45:54 volumio volumio[21066]: info: Loading plugins from folder /data/plugins/ Feb 07 19:45:54 volumio volumio[21066]: info: Loading plugin "system"... Feb 07 19:45:54 volumio volumio[21066]: info: Loading plugin "appearance"... Feb 07 19:45:55 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Feb 07 19:45:55 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 19:45:55 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 19:45:55 volumio go-librespot[21101]: go-librespot daemon starting... Feb 07 19:45:55 volumio go-librespot[21102]: time="2026-02-07T19:45:55+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 19:45:55 volumio go-librespot[21102]: time="2026-02-07T19:45:55+07:00" level=debug msg="app state loaded" Feb 07 19:45:55 volumio go-librespot[21102]: time="2026-02-07T19:45:55+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 19:45:56 volumio volumio[21066]: info: Loading plugin "network"... Feb 07 19:45:56 volumio volumio[21066]: info: Refreshing Cached IP Addresses Feb 07 19:45:56 volumio sudo[21111]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 07 19:45:56 volumio sudo[21111]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 19:45:56 volumio sudo[21113]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 07 19:45:56 volumio sudo[21113]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 19:45:56 volumio volumio[21066]: info: Loading plugin "services"... Feb 07 19:45:56 volumio sudo[21111]: pam_unix(sudo:session): session closed for user root Feb 07 19:45:56 volumio volumio[21066]: info: Loading plugin "alsa_controller"... Feb 07 19:45:56 volumio sudo[21113]: pam_unix(sudo:session): session closed for user root Feb 07 19:45:56 volumio go-librespot[21102]: time="2026-02-07T19:45: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 07 19:45:56 volumio go-librespot[21102]: time="2026-02-07T19:45: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 07 19:45:56 volumio go-librespot[21102]: time="2026-02-07T19:45: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 07 19:45:56 volumio sudo[21126]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 07 19:45:56 volumio sudo[21126]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 19:45:56 volumio go-librespot[21102]: time="2026-02-07T19:45:56+07:00" level=info msg="zeroconf server listening on port 45867" Feb 07 19:45:56 volumio volumio[21066]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 07 19:45:56 volumio volumio[21066]: info: Loading plugin "wizard"... Feb 07 19:45:56 volumio volumio[21066]: info: Loading plugin "networkfs"... Feb 07 19:45:56 volumio volumio[21066]: info: Starting Udev Watcher for removable devices Feb 07 19:45:56 volumio go-librespot[21102]: time="2026-02-07T19:45:56+07:00" level=debug msg="obtained new client token: AABrLKuQAnLTFQd/sgIfAwkmYVAvq6RZv1MFhbDNyw1NmY18nxoOB0Zo/xeKIhxC62vy9lVDFloupHAFghxFuzs1JBsGsJTlpDT8QtaHmXHW447Vt1x/fkFkYKezG7JLDRIEqHgX3GIuriwhkMf/lB7YBYomuX1TwtaszkQ/prgMMv0+vpypWWMAT64zOcSzJmYpFnYr9kqtNg97ODvjDK3t+VnEWJONKmQYk5nWsXjj9FEUdVlJEY94Sw==" Feb 07 19:45:56 volumio volumio[21066]: info: Ignoring mount for partition: boot Feb 07 19:45:56 volumio volumio[21066]: info: Ignoring mount for partition: volumio Feb 07 19:45:56 volumio volumio[21066]: info: Ignoring mount for partition: volumio_data Feb 07 19:45:56 volumio volumio[21066]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 07 19:45:56 volumio volumio[21066]: info: Loading plugin "volumio_command_line_client"... Feb 07 19:45:56 volumio volumio[21066]: info: Loading plugin "upnp"... Feb 07 19:45:56 volumio volumio[21066]: info: [1770468356450] Starting Upmpd Daemon Feb 07 19:45:56 volumio volumio[21066]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 07 19:45:56 volumio volumio[21066]: info: Loading plugin "my_music"... Feb 07 19:45:56 volumio volumio[21066]: info: Loading plugin "mpd"... Feb 07 19:45:56 volumio go-librespot[21102]: time="2026-02-07T19:45:56+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 07 19:45:56 volumio go-librespot[21102]: time="2026-02-07T19:45:56+07:00" level=debug msg="completed keyexchange" Feb 07 19:45:56 volumio go-librespot[21102]: time="2026-02-07T19:45:56+07:00" level=debug msg="completed challenge" Feb 07 19:45:56 volumio go-librespot[21102]: time="2026-02-07T19:45: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 07 19:45:56 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 19:45:56 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 19:45:57 volumio volumio[21066]: info: Loading plugin "upnp_browser"... Feb 07 19:45:58 volumio sudo[21126]: pam_unix(sudo:session): session closed for user root Feb 07 19:45:59 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Feb 07 19:45:59 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 19:45:59 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 19:45:59 volumio go-librespot[21145]: go-librespot daemon starting... Feb 07 19:45:59 volumio go-librespot[21146]: time="2026-02-07T19:45:59+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 19:45:59 volumio go-librespot[21146]: time="2026-02-07T19:45:59+07:00" level=debug msg="app state loaded" Feb 07 19:45:59 volumio go-librespot[21146]: time="2026-02-07T19:45:59+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 19:46:00 volumio volumio[21066]: info: Starting UPNP Browser Feb 07 19:46:00 volumio volumio[21066]: info: Loading plugin "alarm-clock"... Feb 07 19:46:00 volumio go-librespot[21146]: time="2026-02-07T19:46: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 07 19:46:00 volumio go-librespot[21146]: time="2026-02-07T19:46: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 07 19:46:00 volumio go-librespot[21146]: time="2026-02-07T19:46: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 07 19:46:00 volumio volumio[21066]: info: Loading plugin "airplay_emulation"... Feb 07 19:46:00 volumio volumio[21066]: info: Starting Shairport Sync Feb 07 19:46:00 volumio go-librespot[21146]: time="2026-02-07T19:46:00+07:00" level=info msg="zeroconf server listening on port 40137" Feb 07 19:46:00 volumio volumio[21066]: info: Loading plugin "last_100"... Feb 07 19:46:00 volumio volumio[21066]: info: Loading plugin "webradio"... Feb 07 19:46:00 volumio go-librespot[21146]: time="2026-02-07T19:46:00+07:00" level=debug msg="obtained new client token: AAAapKbrCOjf08YZk5s7mfqd7AQDouo0ddCWRgAe/acUjB5RZRxyM8cyY/rZp0mCfK8w/6U4PDY8JpZn0B3NDsoULhKNbJzwM+CwfCDNBY3P0kOHIoVUBIBdyELrLS9gTgTQzluCO7QyWwv5Z8+eJ26sf25DXKLmbKU9yRaN73M4LmTDFpsQTKr01xirTQXaJ2xCI+JwldV7B0i5C0pOKaiXUxkHDrufyHno9YGOQe9OGiioLaToQ2J7eQ==" Feb 07 19:46:00 volumio volumio[21066]: info: Loading plugin "i2s_dacs"... Feb 07 19:46:00 volumio volumio[21066]: info: Loading plugin "volumiodiscovery"... Feb 07 19:46:00 volumio volumio[21066]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 07 19:46:00 volumio volumio[21066]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 07 19:46:00 volumio volumio[21066]: *** WARNING *** For more information see Feb 07 19:46:00 volumio volumio[21066]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 07 19:46:00 volumio volumio[21066]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 07 19:46:00 volumio volumio[21066]: *** WARNING *** For more information see Feb 07 19:46:00 volumio node[21066]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 07 19:46:00 volumio node[21066]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 07 19:46:00 volumio node[21066]: *** WARNING *** For more information see Feb 07 19:46:00 volumio node[21066]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 07 19:46:00 volumio node[21066]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 07 19:46:00 volumio node[21066]: *** WARNING *** For more information see Feb 07 19:46:00 volumio go-librespot[21146]: time="2026-02-07T19:46:00+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 07 19:46:00 volumio volumio[21066]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 07 19:46:00 volumio volumio[21066]: info: Discovery: Started advertising with name: Volumio Feb 07 19:46:00 volumio volumio[21066]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 07 19:46:00 volumio volumio[21066]: info: Loading plugin "spop"... Feb 07 19:46:00 volumio go-librespot[21146]: time="2026-02-07T19:46:00+07:00" level=debug msg="completed keyexchange" Feb 07 19:46:00 volumio go-librespot[21146]: time="2026-02-07T19:46:00+07:00" level=debug msg="completed challenge" Feb 07 19:46:00 volumio go-librespot[21146]: time="2026-02-07T19:46:00+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 07 19:46:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 19:46:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 19:46:02 volumio volumio[21066]: info: Loading plugin "ytcr"... Feb 07 19:46:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Feb 07 19:46:04 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 19:46:04 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 19:46:04 volumio go-librespot[21157]: go-librespot daemon starting... Feb 07 19:46:04 volumio go-librespot[21158]: time="2026-02-07T19:46:04+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 19:46:04 volumio go-librespot[21158]: time="2026-02-07T19:46:04+07:00" level=debug msg="app state loaded" Feb 07 19:46:04 volumio go-librespot[21158]: time="2026-02-07T19:46:04+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 19:46:04 volumio go-librespot[21158]: time="2026-02-07T19:46:04+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 07 19:46:04 volumio go-librespot[21158]: time="2026-02-07T19:46:04+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 07 19:46:04 volumio go-librespot[21158]: time="2026-02-07T19:46:04+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 07 19:46:04 volumio go-librespot[21158]: time="2026-02-07T19:46:04+07:00" level=info msg="zeroconf server listening on port 40343" Feb 07 19:46:05 volumio go-librespot[21158]: time="2026-02-07T19:46:05+07:00" level=debug msg="obtained new client token: AAB5UQFsb5SCJiWnlEQ0yYjz2rfLp6v0ZPDQdAiPdj8n6FqtStCPYV4+9MT/iAGksrXNAeKjpTnMHeo800Bi6v7ZsGw+6NnoYFpbTCMwv+dXLjvAUMgsM9LiToM8MC5mTCp6SeUoHiet1STHEws1zmDuvHNNh8CjQA22O4cRFmOnp44522VzBJ+Fzy/v+jnq3eziNDlhOJxXNdBLhBREH2ODYEWsuMeXpJkjF7lasyqvk+e6ZmKn7Hc32A==" Feb 07 19:46:05 volumio go-librespot[21158]: time="2026-02-07T19:46:05+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 07 19:46:05 volumio go-librespot[21158]: time="2026-02-07T19:46:05+07:00" level=debug msg="completed keyexchange" Feb 07 19:46:05 volumio go-librespot[21158]: time="2026-02-07T19:46:05+07:00" level=debug msg="completed challenge" Feb 07 19:46:05 volumio go-librespot[21158]: time="2026-02-07T19:46: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 07 19:46:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 19:46:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 19:46:05 volumio volumio[21066]: info: Loading plugin "ytmusic"... Feb 07 19:46:06 volumio volumio-remote-updater[643]: [2026-02-07 19:46:06] [connect] Successful connection Feb 07 19:46:06 volumio volumio[21066]: info: Loading plugin "outputs"... Feb 07 19:46:06 volumio volumio[21066]: info: Loading plugin "albumart"... Feb 07 19:46:06 volumio volumio[21066]: info: Plugin example_plugin is not enabled Feb 07 19:46:06 volumio volumio[21066]: info: Loading plugin "inputs"... Feb 07 19:46:06 volumio volumio[21066]: info: Loading plugin "updater_comm"... Feb 07 19:46:06 volumio volumio[21066]: info: Plugin mpdemulation is not enabled Feb 07 19:46:06 volumio volumio[21066]: info: Loading plugin "rest_api"... Feb 07 19:46:06 volumio volumio[21066]: info: Loading plugin "websocket"... Feb 07 19:46:06 volumio volumio[21066]: info: Starting Socket.io Server version 1.7.4 Feb 07 19:46:06 volumio volumio[21066]: info: Loading plugin "RoonBridge"... Feb 07 19:46:07 volumio volumio[21066]: info: Applying required configuration parameters for plugin RoonBridge Feb 07 19:46:07 volumio volumio[21066]: info: Loading i18n strings for locale en Feb 07 19:46:07 volumio volumio[21066]: Updating browse sources language Feb 07 19:46:07 volumio volumio[21066]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 19:46:07 volumio volumio[21179]: Forking 3 albumart workers Feb 07 19:46:07 volumio volumio[21066]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 07 19:46:07 volumio volumio[21066]: info: CoreCommandRouter::initPlayerControls Feb 07 19:46:07 volumio volumio[21066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 19:46:07 volumio volumio[21066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 19:46:07 volumio volumio[21066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 19:46:07 volumio volumio[21066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 19:46:07 volumio volumio[21066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 19:46:07 volumio volumio[21066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 19:46:07 volumio volumio[21066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 19:46:07 volumio volumio[21066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 19:46:07 volumio volumio[21066]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 07 19:46:07 volumio volumio[21066]: Express server listening on port 3000 Feb 07 19:46:07 volumio volumio[21066]: [Metrics] WebUI: 16s 223.89ms Feb 07 19:46:07 volumio volumio[21066]: info: CoreStateMachine::resetVolumioState Feb 07 19:46:07 volumio volumio[21066]: info: CoreStateMachine::getcurrentVolume Feb 07 19:46:07 volumio volumio[21066]: info: CoreCommandRouter::volumioRetrievevolume Feb 07 19:46:07 volumio sudo[21224]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 07 19:46:07 volumio sudo[21224]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 19:46:08 volumio sudo[21224]: pam_unix(sudo:session): session closed for user root Feb 07 19:46:08 volumio sudo[21226]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 07 19:46:08 volumio sudo[21226]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 19:46:08 volumio volumio[21066]: info: Volumio Network Manager: Network status updated: 1 Feb 07 19:46:08 volumio sudo[21226]: pam_unix(sudo:session): session closed for user root Feb 07 19:46:08 volumio volumio[21066]: info: VolumeController:: Volume=100 Mute =false Feb 07 19:46:08 volumio volumio[21066]: info: CoreStateMachine::pushState Feb 07 19:46:08 volumio volumio[21066]: info: CorePlayQueue::getTrack 0 Feb 07 19:46:08 volumio volumio[21066]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 07 19:46:08 volumio volumio[21066]: info: CoreCommandRouter::volumioPushState Feb 07 19:46:08 volumio volumio[21066]: info: CoreStateMachine::updateTrackBlock Feb 07 19:46:08 volumio volumio[21066]: info: CorePlayQueue::getTrackBlock Feb 07 19:46:08 volumio volumio[21066]: info: CoreCommandRouter::volumioRetrievevolume Feb 07 19:46:08 volumio volumio-remote-updater[643]: [2026-02-07 19:46:08] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1770468366 101 Feb 07 19:46:08 volumio volumio[21066]: 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 07 19:46:08 volumio volumio[21066]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 19:46:08 volumio volumio[21066]: info: Reloading queue from file Feb 07 19:46:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Feb 07 19:46:08 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 19:46:08 volumio volumio[21066]: info: CoreStateMachine::setRepeat null single undefined Feb 07 19:46:08 volumio volumio[21066]: info: CoreStateMachine::pushState Feb 07 19:46:08 volumio volumio[21066]: info: CorePlayQueue::getTrack 0 Feb 07 19:46:08 volumio volumio[21066]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 07 19:46:08 volumio volumio[21066]: info: CoreCommandRouter::volumioPushState Feb 07 19:46:08 volumio volumio[21066]: info: CoreStateMachine::setRandom null Feb 07 19:46:08 volumio volumio[21066]: info: CoreStateMachine::pushState Feb 07 19:46:08 volumio volumio[21066]: info: CorePlayQueue::getTrack 0 Feb 07 19:46:08 volumio volumio[21066]: info: CoreCommandRouter::volumioPushState Feb 07 19:46:08 volumio volumio[21066]: info: Setting Device type: Raspberry PI Feb 07 19:46:08 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 19:46:08 volumio go-librespot[21237]: go-librespot daemon starting... Feb 07 19:46:08 volumio volumio[21066]: info: Completed loading Core Plugins Feb 07 19:46:08 volumio go-librespot[21239]: time="2026-02-07T19:46:08+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 19:46:08 volumio go-librespot[21239]: time="2026-02-07T19:46:08+07:00" level=debug msg="app state loaded" Feb 07 19:46:08 volumio go-librespot[21239]: time="2026-02-07T19:46:08+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 19:46:08 volumio volumio[21066]: info: Preparing to generate the ALSA configuration file Feb 07 19:46:08 volumio volumio[21066]: info: VolumeController:: Volume=100 Mute =false Feb 07 19:46:08 volumio volumio[21066]: info: CoreStateMachine::pushState Feb 07 19:46:08 volumio volumio[21066]: info: CorePlayQueue::getTrack 0 Feb 07 19:46:08 volumio volumio[21066]: info: CoreCommandRouter::volumioPushState Feb 07 19:46:09 volumio volumio[21066]: info: Asound.conf file unchanged, so no further update is needed Feb 07 19:46:09 volumio volumio[21066]: info: Output device has changed, restarting MPD Feb 07 19:46:09 volumio sudo[21249]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 07 19:46:09 volumio sudo[21249]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 19:46:09 volumio sudo[21247]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 07 19:46:09 volumio sudo[21249]: pam_unix(sudo:session): session closed for user root Feb 07 19:46:09 volumio sudo[21247]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 19:46:09 volumio volumio[21066]: info: Output device has changed, restarting Shairport Sync Feb 07 19:46:09 volumio volumio[21066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 19:46:09 volumio volumio[21066]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 19:46:09 volumio sudo[21252]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 07 19:46:09 volumio sudo[21252]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 19:46:09 volumio sudo[21247]: pam_unix(sudo:session): session closed for user root Feb 07 19:46:09 volumio volumio[21066]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 07 19:46:09 volumio volumio[21066]: info: ___________ START PLUGINS ___________ Feb 07 19:46:09 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 07 19:46:09 volumio volumio[21066]: info: ControllerMpd::onStart: Initializing MPD Feb 07 19:46:09 volumio volumio[21066]: info: Creating MPD Configuration file Feb 07 19:46:09 volumio go-librespot[21239]: time="2026-02-07T19:46: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 07 19:46:09 volumio go-librespot[21239]: time="2026-02-07T19:46:09+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 07 19:46:09 volumio go-librespot[21239]: time="2026-02-07T19:46:09+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 07 19:46:09 volumio volumio[21066]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 07 19:46:09 volumio go-librespot[21239]: time="2026-02-07T19:46:09+07:00" level=info msg="zeroconf server listening on port 36881" Feb 07 19:46:09 volumio volumio[21066]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 19:46:09 volumio volumio[21066]: info: [1770468369501] CoreMusicLibrary::Adding element Media Servers Feb 07 19:46:09 volumio volumio[21066]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 19:46:09 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 07 19:46:09 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 07 19:46:09 volumio systemd[1]: mpd.service: Consumed 7.165s CPU time. Feb 07 19:46:09 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 07 19:46:09 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 07 19:46:09 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 07 19:46:09 volumio sudo[21261]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 07 19:46:09 volumio volumio[21066]: info: UPNP Browser: Client initialized successfully Feb 07 19:46:09 volumio sudo[21261]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 19:46:09 volumio sudo[21261]: pam_unix(sudo:session): session closed for user root Feb 07 19:46:09 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 07 19:46:09 volumio sudo[21263]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 07 19:46:09 volumio sudo[21263]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 19:46:09 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 07 19:46:09 volumio volumio[21066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 19:46:09 volumio volumio[21066]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 19:46:09 volumio go-librespot[21239]: time="2026-02-07T19:46:09+07:00" level=debug msg="obtained new client token: AAAbwAcUAoJ0fPwdLqCjiIXYBuidrZPYrEIr0ZCzjZEjT01Q5EGdHxO8zZTRnAiuZJ1heHgLH/VAHFWw2PBUgkH748iADk9BSN+WlmKpfZCtLJ0suf7poLujZFx14N/VBeYmQA39KMrcU3GDApNoRmczBcUbn4hBMMK3526suJ2aLV/CCI3JLkto0Bt92+Sy2Z8OHOp08/jxT0qVmCVWsyB8FggMZL1y8tQRrHd71XWAa+CikJx/DTvDzA==" Feb 07 19:46:09 volumio volumio[21066]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 07 19:46:09 volumio volumio[21066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 19:46:09 volumio volumio[21066]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 19:46:09 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 07 19:46:09 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 07 19:46:09 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 07 19:46:09 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 07 19:46:09 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 07 19:46:09 volumio volumio[21066]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 07 19:46:09 volumio volumio[21066]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 19:46:09 volumio volumio[21066]: info: [1770468369899] CoreMusicLibrary::Adding element Last_100 Feb 07 19:46:09 volumio volumio[21066]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 19:46:09 volumio go-librespot[21239]: time="2026-02-07T19:46:09+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 07 19:46:09 volumio volumio[21066]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 19:46:09 volumio volumio[21066]: info: [1770468369918] CoreMusicLibrary::Adding element Webradio Feb 07 19:46:09 volumio volumio[21066]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 19:46:09 volumio volumio[21066]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 07 19:46:09 volumio volumio[21066]: info: Initializing BBC Radios Feb 07 19:46:09 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 07 19:46:09 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 07 19:46:10 volumio go-librespot[21239]: time="2026-02-07T19:46:10+07:00" level=debug msg="completed keyexchange" Feb 07 19:46:10 volumio go-librespot[21239]: time="2026-02-07T19:46:10+07:00" level=debug msg="completed challenge" Feb 07 19:46:10 volumio volumio[21066]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 07 19:46:10 volumio volumio[21066]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 19:46:10 volumio go-librespot[21239]: time="2026-02-07T19:46: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 07 19:46:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 19:46:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 19:46:10 volumio volumio[21066]: info: Creating Spotify config file Feb 07 19:46:10 volumio volumio[21066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 19:46:10 volumio sudo[21278]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 07 19:46:10 volumio sudo[21278]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 07 19:46:10 volumio sudo[21278]: pam_unix(sudo:session): session closed for user root Feb 07 19:46:10 volumio volumio[21191]: Starting albumart workers Feb 07 19:46:11 volumio volumio[21190]: Starting albumart workers Feb 07 19:46:11 volumio volumio[21066]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 19:46:11 volumio volumio[21066]: info: [1770468371103] CoreMusicLibrary::Adding element YouTube Music Feb 07 19:46:11 volumio volumio[21066]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 19:46:11 volumio volumio[21066]: Cannot find translation for source YouTube Music Feb 07 19:46:11 volumio volumio[21189]: Starting albumart workers Feb 07 19:46:11 volumio volumio[21066]: info: Volumio Calling Home Feb 07 19:46:11 volumio sudo[21294]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Feb 07 19:46:11 volumio sudo[21294]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 19:46:11 volumio sudo[21294]: pam_unix(sudo:session): session closed for user root Feb 07 19:46:12 volumio volumio[21066]: info: Discovery: adding 921648ed-0557-4d70-9927-1420682efcc7 Feb 07 19:46:12 volumio volumio[21066]: info: Discovery: Found device Volumio Feb 07 19:46:12 volumio volumio[21066]: info: CoreCommandRouter::volumioGetState Feb 07 19:46:12 volumio volumio[21066]: info: CorePlayQueue::getTrack 0 Feb 07 19:46:12 volumio volumio[21066]: info: Discovery: this is already registered, 921648ed-0557-4d70-9927-1420682efcc7 Feb 07 19:46:12 volumio volumio[21066]: info: Discovery: Found device Volumio Feb 07 19:46:12 volumio volumio[21066]: info: CoreCommandRouter::volumioGetState Feb 07 19:46:12 volumio volumio[21066]: info: CorePlayQueue::getTrack 0 Feb 07 19:46:12 volumio volumio[21066]: info: MPD Permissions set Feb 07 19:46:12 volumio volumio[21066]: info: MPD Permissions set Feb 07 19:46:12 volumio volumio[21066]: info: Upmpdcli Daemon Started Feb 07 19:46:12 volumio volumio[21066]: info: Volumio called home Feb 07 19:46:12 volumio volumio[21066]: info: Spotify config file written Feb 07 19:46:12 volumio sudo[21300]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 07 19:46:12 volumio sudo[21300]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 19:46:12 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 19:46:12 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 19:46:12 volumio go-librespot[21308]: go-librespot daemon starting... Feb 07 19:46:12 volumio sudo[21300]: pam_unix(sudo:session): session closed for user root Feb 07 19:46:12 volumio go-librespot[21310]: time="2026-02-07T19:46:12+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 19:46:12 volumio go-librespot[21310]: time="2026-02-07T19:46:12+07:00" level=debug msg="app state loaded" Feb 07 19:46:12 volumio go-librespot[21310]: time="2026-02-07T19:46:12+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 19:46:12 volumio volumio[21066]: 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 07 19:46:12 volumio volumio[21066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 19:46:12 volumio volumio[21066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 19:46:12 volumio volumio[21066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 19:46:12 volumio volumio[21066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 19:46:12 volumio volumio[21066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 19:46:12 volumio volumio[21066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 19:46:12 volumio volumio[21066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 19:46:12 volumio volumio[21066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 19:46:13 volumio volumio[21066]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 19:46:13 volumio volumio[21066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 19:46:13 volumio volumio[21066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 19:46:13 volumio volumio[21066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 19:46:13 volumio volumio[21066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 19:46:13 volumio volumio[21066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 19:46:13 volumio volumio[21066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 19:46:13 volumio volumio[21066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 19:46:13 volumio volumio[21066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 19:46:13 volumio volumio[21066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 19:46:13 volumio volumio[21066]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 19:46:13 volumio volumio[21066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 19:46:13 volumio volumio[21066]: info: No need to fix Spotify hosts Feb 07 19:46:13 volumio go-librespot[21310]: time="2026-02-07T19:46: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 07 19:46:13 volumio go-librespot[21310]: time="2026-02-07T19:46:13+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 07 19:46:13 volumio go-librespot[21310]: time="2026-02-07T19:46:13+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 07 19:46:13 volumio go-librespot[21310]: time="2026-02-07T19:46:13+07:00" level=info msg="zeroconf server listening on port 39405" Feb 07 19:46:13 volumio go-librespot[21310]: time="2026-02-07T19:46:13+07:00" level=debug msg="obtained new client token: AAA5QBmn6+qWpOUKIZUjvg59fTe2gVTrEMzy8S/IRrPZwIF91HN1KU2Wr4MuGI5YNQmz1Hl9S0DVgv5NCdG5OnXWOpBz3tB6QqIBPgk5G7+Zf7AaqGG54PaE0jyAlZgsPprjGjhAYuUh0GVztK17KMFHcvZRzj+0+ge1zybYW6Q9SZ/Pc5YDzHsNFjfWzMsPCBLdMwKw6KKAiM3OrSp2n+S4fIQVRQEXZl0ryGV1+YIGIN4exIjSVuJF0w==" Feb 07 19:46:13 volumio go-librespot[21310]: time="2026-02-07T19:46:13+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 07 19:46:13 volumio volumio[21066]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 07 19:46:13 volumio volumio[21066]: SPOTIFY: BQCdjROzBjHcyIcv1MURR96isUY87l-FllviVzyQ0iQjnmGpxW27GiNTmAHRTXJafzYu4PeyULyUdAn5pkOprJoNRYlub8YtloOzkQ5A0VfkSYxA9NpJZnFAftx75e8KMj1MGrxXXaI_bFHB0UFWIRAr1N9wvs1CMGi5RdoJsZayl-EbVgKwN6XYf6d0MCSNoJFqvgPwYbFi9xXHoyaVWrasKWD0bfgraHprszNp3fOTXxYZbeZ9y9SvIjS0QBzzwaU3rK3v87bUE2Di0XQcg1hM7vWfnY-lC54RMsVTnOJMj1IddSTywI7U Feb 07 19:46:13 volumio volumio[21066]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 07 19:46:13 volumio volumio[21066]: info: New Spotify access token = BQCdjROzBjHcyIcv1MURR96isUY87l-FllviVzyQ0iQjnmGpxW27GiNTmAHRTXJafzYu4PeyULyUdAn5pkOprJoNRYlub8YtloOzkQ5A0VfkSYxA9NpJZnFAftx75e8KMj1MGrxXXaI_bFHB0UFWIRAr1N9wvs1CMGi5RdoJsZayl-EbVgKwN6XYf6d0MCSNoJFqvgPwYbFi9xXHoyaVWrasKWD0bfgraHprszNp3fOTXxYZbeZ9y9SvIjS0QBzzwaU3rK3v87bUE2Di0XQcg1hM7vWfnY-lC54RMsVTnOJMj1IddSTywI7U Feb 07 19:46:13 volumio volumio[21066]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 07 19:46:13 volumio volumio[21066]: info: Starting Shairport Sync Feb 07 19:46:13 volumio volumio[21066]: info: Starting Shairport Sync Feb 07 19:46:13 volumio go-librespot[21310]: time="2026-02-07T19:46:13+07:00" level=debug msg="completed keyexchange" Feb 07 19:46:13 volumio go-librespot[21310]: time="2026-02-07T19:46:13+07:00" level=debug msg="completed challenge" Feb 07 19:46:13 volumio volumio[21066]: info: Starting Shairport Sync Feb 07 19:46:13 volumio sudo[21339]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 07 19:46:13 volumio sudo[21339]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 19:46:13 volumio go-librespot[21310]: time="2026-02-07T19:46:13+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 07 19:46:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 19:46:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 19:46:14 volumio sudo[21344]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 07 19:46:14 volumio sudo[21341]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 07 19:46:14 volumio sudo[21344]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 19:46:14 volumio sudo[21341]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 19:46:14 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 07 19:46:14 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 07 19:46:14 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 07 19:46:14 volumio systemd[1]: shairport-sync.service: Consumed 2.216s CPU time. Feb 07 19:46:14 volumio volumio[21066]: info: CoreCommandRouter::volumioGetState Feb 07 19:46:14 volumio volumio[21066]: info: CorePlayQueue::getTrack 0 Feb 07 19:46:14 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 07 19:46:14 volumio sudo[21339]: pam_unix(sudo:session): session closed for user root Feb 07 19:46:14 volumio sudo[21341]: pam_unix(sudo:session): session closed for user root Feb 07 19:46:14 volumio sudo[21344]: pam_unix(sudo:session): session closed for user root Feb 07 19:46:14 volumio volumio[21066]: info: Shairport-Sync Started Feb 07 19:46:14 volumio volumio[21066]: Error adding Membership: Error: addMembership EINVAL Feb 07 19:46:14 volumio volumio[21066]: info: Shairport-Sync Started Feb 07 19:46:14 volumio volumio[21066]: info: Shairport-Sync Started Feb 07 19:46:14 volumio volumio[21066]: 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 07 19:46:14 volumio volumio[21066]: info: Spotify Successfully logged in Feb 07 19:46:14 volumio volumio[21066]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 19:46:14 volumio volumio[21066]: info: [1770468374636] CoreMusicLibrary::Adding element Spotify Feb 07 19:46:14 volumio volumio[21066]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 19:46:14 volumio volumio[21066]: Cannot find translation for source YouTube Music Feb 07 19:46:14 volumio volumio[21066]: Cannot find translation for source Spotify Feb 07 19:46:15 volumio volumio[21066]: info: [yt-cast-receiver] DIAL server listening on port 8098 Feb 07 19:46:15 volumio volumio[21066]: info: CoreCommandRouter::volumioRetrievevolume Feb 07 19:46:15 volumio volumio[21066]: info: VolumeController:: Volume=100 Mute =false Feb 07 19:46:15 volumio volumio[21066]: info: CoreCommandRouter::volumioGetState Feb 07 19:46:15 volumio volumio[21066]: info: CorePlayQueue::getTrack 0 Feb 07 19:46:15 volumio volumio[21066]: info: CoreStateMachine::pushState Feb 07 19:46:15 volumio volumio[21066]: info: CorePlayQueue::getTrack 0 Feb 07 19:46:15 volumio volumio[21066]: info: CoreCommandRouter::volumioPushState Feb 07 19:46:16 volumio volumio[21066]: info: go-librespot daemon successfully initialized Feb 07 19:46:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Feb 07 19:46:17 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 19:46:17 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 19:46:17 volumio go-librespot[21380]: go-librespot daemon starting... Feb 07 19:46:17 volumio go-librespot[21381]: time="2026-02-07T19:46:17+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 19:46:17 volumio go-librespot[21381]: time="2026-02-07T19:46:17+07:00" level=debug msg="app state loaded" Feb 07 19:46:17 volumio go-librespot[21381]: time="2026-02-07T19:46:17+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 19:46:17 volumio mpd[21292]: 2026-02-07T19:46:17 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 07 19:46:17 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 07 19:46:17 volumio sudo[21252]: pam_unix(sudo:session): session closed for user root Feb 07 19:46:17 volumio sudo[21263]: pam_unix(sudo:session): session closed for user root Feb 07 19:46:17 volumio volumio[21066]: error: MPD error: The expression evaluated to a falsy value: Feb 07 19:46:17 volumio volumio[21066]: assert.ok(self.idling) Feb 07 19:46:17 volumio volumio[21066]: error: The expression evaluated to a falsy value: Feb 07 19:46:17 volumio volumio[21066]: assert.ok(self.idling) Feb 07 19:46:17 volumio volumio[21066]: info: MPD running with PID21292 Feb 07 19:46:17 volumio volumio[21066]: ,establishing connection Feb 07 19:46:17 volumio volumio[21066]: error: updateQueue error: null Feb 07 19:46:17 volumio volumio[21066]: info: Completed starting Core Plugins Feb 07 19:46:17 volumio volumio[21066]: info: ------------------------------------------- Feb 07 19:46:17 volumio volumio[21066]: info: ----- MyVolumio plugins startup ---- Feb 07 19:46:17 volumio volumio[21066]: info: ------------------------------------------- Feb 07 19:46:17 volumio volumio[21066]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 07 19:46:17 volumio volumio[21066]: error: updateQueue error: null Feb 07 19:46:17 volumio go-librespot[21381]: time="2026-02-07T19:46:17+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 07 19:46:17 volumio go-librespot[21381]: time="2026-02-07T19:46:17+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 07 19:46:17 volumio go-librespot[21381]: time="2026-02-07T19:46:17+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 07 19:46:17 volumio go-librespot[21381]: time="2026-02-07T19:46:17+07:00" level=info msg="zeroconf server listening on port 43021" Feb 07 19:46:17 volumio go-librespot[21381]: time="2026-02-07T19:46:17+07:00" level=debug msg="obtained new client token: AABiEfeZlO0/ITOhUeQSTA16E65iaOemAWrlT+JD8IEA3wBdcspQY622aYGG49JaPOboN+KyMGmkGLOrAVBxrnDzQqpsl+9b5Kvgt43yHPyTpKmPl/BMecERWoGq+WZXqrBIydAAphZY+Wg2KasdlCHdVbvq1mXmAg7+8okx379jxDWjvTNoF6V0x9E/0vOTjLywoFb3D9E5FzVcz7t+94+0X2CbdIg8yOVlXecNDoOdhUl8ovpdMg1IEQ==" Feb 07 19:46:18 volumio go-librespot[21381]: time="2026-02-07T19:46:18+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 07 19:46:18 volumio go-librespot[21381]: time="2026-02-07T19:46:18+07:00" level=debug msg="completed keyexchange" Feb 07 19:46:18 volumio go-librespot[21381]: time="2026-02-07T19:46:18+07:00" level=debug msg="completed challenge" Feb 07 19:46:18 volumio go-librespot[21381]: time="2026-02-07T19:46:18+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 07 19:46:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 19:46:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 19:46:19 volumio volumio[21066]: info: Initializing connection to go-librespot Websocket Feb 07 19:46:19 volumio volumio[21066]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 07 19:46:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Feb 07 19:46:21 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 19:46:21 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 19:46:21 volumio go-librespot[21393]: go-librespot daemon starting... Feb 07 19:46:21 volumio go-librespot[21394]: time="2026-02-07T19:46:21+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 19:46:21 volumio go-librespot[21394]: time="2026-02-07T19:46:21+07:00" level=debug msg="app state loaded" Feb 07 19:46:21 volumio go-librespot[21394]: time="2026-02-07T19:46:21+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 19:46:21 volumio go-librespot[21394]: time="2026-02-07T19:46:21+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 07 19:46:21 volumio go-librespot[21394]: time="2026-02-07T19:46:21+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 07 19:46:21 volumio go-librespot[21394]: time="2026-02-07T19:46:21+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 07 19:46:21 volumio go-librespot[21394]: time="2026-02-07T19:46:21+07:00" level=info msg="zeroconf server listening on port 34829" Feb 07 19:46:22 volumio go-librespot[21394]: time="2026-02-07T19:46:22+07:00" level=debug msg="obtained new client token: AAD+fHgzR1FmyzLjBllU6DiUV351pMM+14/vVsDjYclGMowb3VQQUxk2cWEnmZ80munihgrLOB1B4pg3xy8M4WZrwQpOiOLfwazcF9Z94IqaljQ0n690XG3iAOl23qAHuW6fD6OTGCwekeWpRqyYRsq1OFYH/vA5sQJIyXTo4yrj09ITEdenEkcBtRlsdOZA924VCX/0P6hWxrLJbwEDLMC7qrZ6g9pyHRivshOJrOzcklZWiHdapNQ=" Feb 07 19:46:22 volumio go-librespot[21394]: time="2026-02-07T19:46:22+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 07 19:46:22 volumio volumio[21066]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 07 19:46:22 volumio go-librespot[21394]: time="2026-02-07T19:46:22+07:00" level=debug msg="completed keyexchange" Feb 07 19:46:22 volumio go-librespot[21394]: time="2026-02-07T19:46:22+07:00" level=debug msg="completed challenge" Feb 07 19:46:22 volumio go-librespot[21394]: time="2026-02-07T19:46:22+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 07 19:46:22 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 19:46:22 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 19:46:22 volumio volumio[21066]: info: Initializing connection to go-librespot Websocket Feb 07 19:46:22 volumio volumio[21066]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 07 19:46:25 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11. Feb 07 19:46:25 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 19:46:25 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 19:46:25 volumio go-librespot[21402]: go-librespot daemon starting... Feb 07 19:46:25 volumio go-librespot[21403]: time="2026-02-07T19:46:25+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 19:46:25 volumio go-librespot[21403]: time="2026-02-07T19:46:25+07:00" level=debug msg="app state loaded" Feb 07 19:46:25 volumio go-librespot[21403]: time="2026-02-07T19:46:25+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 19:46:25 volumio volumio[21066]: info: Initializing connection to go-librespot Websocket Feb 07 19:46:25 volumio go-librespot[21403]: time="2026-02-07T19:46:25+07:00" level=debug msg="new websocket client" Feb 07 19:46:25 volumio volumio[21066]: info: Connection to go-librespot Websocket established Feb 07 19:46:26 volumio go-librespot[21403]: time="2026-02-07T19:46:26+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 07 19:46:26 volumio go-librespot[21403]: time="2026-02-07T19:46:26+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 07 19:46:26 volumio go-librespot[21403]: time="2026-02-07T19:46:26+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 07 19:46:26 volumio go-librespot[21403]: time="2026-02-07T19:46:26+07:00" level=info msg="zeroconf server listening on port 33867" Feb 07 19:46:26 volumio volumio[21066]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 07 19:46:26 volumio volumio[21066]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 07 19:46:26 volumio volumio[21066]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 07 19:46:26 volumio volumio[21066]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 07 19:46:26 volumio volumio[21066]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 07 19:46:26 volumio volumio[21066]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 07 19:46:26 volumio volumio[21066]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 07 19:46:26 volumio volumio[21066]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 07 19:46:26 volumio volumio[21066]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 07 19:46:26 volumio volumio[21066]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 07 19:46:26 volumio volumio[21066]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 07 19:46:26 volumio volumio[21066]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 07 19:46:26 volumio volumio[21066]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 07 19:46:26 volumio volumio[21066]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 07 19:46:26 volumio volumio[21066]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 07 19:46:26 volumio volumio[21066]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 07 19:46:26 volumio volumio[21066]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 07 19:46:26 volumio volumio[21066]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 07 19:46:26 volumio volumio[21066]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 07 19:46:26 volumio volumio[21066]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 07 19:46:26 volumio volumio[21066]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 07 19:46:26 volumio volumio[21066]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 07 19:46:26 volumio volumio[21066]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 07 19:46:26 volumio volumio[21066]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 07 19:46:26 volumio volumio[21066]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 07 19:46:26 volumio volumio[21066]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 07 19:46:26 volumio volumio[21066]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 07 19:46:26 volumio volumio[21066]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 07 19:46:26 volumio volumio[21066]: info: Adding plugin bluetooth to MyMusic Plugins Feb 07 19:46:26 volumio volumio[21066]: info: Adding plugin multiroom to MyMusic Plugins Feb 07 19:46:26 volumio volumio[21066]: info: Adding plugin metavolumio to MyMusic Plugins Feb 07 19:46:26 volumio volumio[21066]: info: Adding plugin cd_controller to MyMusic Plugins Feb 07 19:46:26 volumio volumio[21066]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 07 19:46:26 volumio volumio[21066]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 07 19:46:26 volumio volumio[21066]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 07 19:46:26 volumio volumio[21066]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 07 19:46:26 volumio go-librespot[21403]: time="2026-02-07T19:46:26+07:00" level=debug msg="obtained new client token: AAC4LWoLLhCnh/DRolAOiQ28ZWZePRDvNh9ATMsGBWDDybqXS6K1ICYZHII/A2tM0zqs8+lF0W5wVRnQfDigdVaJsGJJqZX0Gk93aQ/2uFTlgJe+HJ4H1eaaaSsL6ioG8/Q4+ueYACipy5ExGxb7zPhnmnoMZOukBonbY73CMGb2f1ZqVCYkiYAvk1/U1w1Hul4zgJPsRNeYjKuFUHc/fTfgrYSe8g0gidY0CKQyF0SQ3pOmSIg9QDumYA==" Feb 07 19:46:26 volumio go-librespot[21403]: time="2026-02-07T19:46:26+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 07 19:46:26 volumio go-librespot[21403]: time="2026-02-07T19:46:26+07:00" level=debug msg="completed keyexchange" Feb 07 19:46:26 volumio go-librespot[21403]: time="2026-02-07T19:46:26+07:00" level=debug msg="completed challenge" Feb 07 19:46:26 volumio go-librespot[21403]: time="2026-02-07T19:46:26+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 07 19:46:26 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 19:46:26 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 19:46:28 volumio volumio[21066]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 07 19:46:28 volumio volumio[21066]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 07 19:46:28 volumio volumio[21066]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 19:46:28 volumio volumio[21066]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 19:46:28 volumio volumio[21066]: info: Starting MyVolumio Remote Streaming Endpoints Feb 07 19:46:28 volumio volumio[21066]: info: MyVolumio login type: Token Feb 07 19:46:28 volumio volumio[21066]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 07 19:46:28 volumio volumio[21066]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 07 19:46:29 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12. Feb 07 19:46:29 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 19:46:29 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 19:46:29 volumio go-librespot[21424]: go-librespot daemon starting... Feb 07 19:46:29 volumio volumio[21066]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 07 19:46:29 volumio volumio[21066]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 07 19:46:29 volumio volumio[21066]: info: Streaming services startup Feb 07 19:46:29 volumio go-librespot[21425]: time="2026-02-07T19:46:29+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 19:46:29 volumio go-librespot[21425]: time="2026-02-07T19:46:29+07:00" level=debug msg="app state loaded" Feb 07 19:46:29 volumio go-librespot[21425]: time="2026-02-07T19:46:29+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 19:46:29 volumio volumio[21066]: info: Starting Streaming Daemon Feb 07 19:46:29 volumio sudo[21433]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 07 19:46:29 volumio sudo[21433]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 19:46:29 volumio volumio[21066]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 07 19:46:30 volumio sudo[21433]: pam_unix(sudo:session): session closed for user root Feb 07 19:46:30 volumio volumio[21066]: info: Getting Spotify volume Feb 07 19:46:30 volumio volumio[21066]: info: Connection to go-librespot Websocket closed Feb 07 19:46:30 volumio volumio[21066]: error: Cannot start Volumio Streaming Daemon Feb 07 19:46:30 volumio volumio[21066]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 07 19:46:30 volumio volumio[21066]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 07 19:46:30 volumio volumio[21066]: 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 07 19:46:30 volumio volumio[21066]: info: CoreCommandRouter::volumioGetState Feb 07 19:46:30 volumio volumio[21066]: info: CorePlayQueue::getTrack 0 Feb 07 19:46:30 volumio volumio[21066]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 07 19:46:30 volumio volumio[21066]: SPOTIFY: SPOTIFY VOLUME undefined Feb 07 19:46:30 volumio volumio[21066]: SPOTIFY: VOLUMIO VOLUME 100 Feb 07 19:46:30 volumio volumio[21066]: info: Aligning Spotify Volume to Volumio Volume Feb 07 19:46:30 volumio volumio[21066]: info: CoreCommandRouter::volumioGetState Feb 07 19:46:30 volumio volumio[21066]: info: CorePlayQueue::getTrack 0 Feb 07 19:46:30 volumio volumio[21066]: info: Setting Spotify Volume from Volumio: 100 Feb 07 19:46:30 volumio go-librespot[21425]: time="2026-02-07T19:46:30+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 07 19:46:30 volumio go-librespot[21425]: time="2026-02-07T19:46:30+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 07 19:46:30 volumio go-librespot[21425]: time="2026-02-07T19:46:30+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 07 19:46:30 volumio go-librespot[21425]: time="2026-02-07T19:46:30+07:00" level=info msg="zeroconf server listening on port 39623" Feb 07 19:46:30 volumio go-librespot[21425]: time="2026-02-07T19:46:30+07:00" level=debug msg="obtained new client token: AABaAuFBC4bc0/G/paL+IORJLG2e7Fuy65eL8daTEIcRrHM1lzr1eaAK//JmlAishH5kLkn+QL6c/rya1Q4OIYUZZV3pEYsIzKG0/guHpTU6r3RMRIRrMcSxsjd7hs26a0nnOKcbROPlJpAaGP/cR9mBJDDCrF8661tSkNN/phzp/4TogIZoC8vcAyYVvk0DVYjqbVzMdsCYEwT8aW8Utpc+hD/yqk9uWZE18YPm1d5XRXxONEO3Mn+PCA==" Feb 07 19:46:30 volumio go-librespot[21425]: time="2026-02-07T19:46:30+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 07 19:46:30 volumio go-librespot[21425]: time="2026-02-07T19:46:30+07:00" level=debug msg="completed keyexchange" Feb 07 19:46:30 volumio go-librespot[21425]: time="2026-02-07T19:46:30+07:00" level=debug msg="completed challenge" Feb 07 19:46:31 volumio go-librespot[21425]: time="2026-02-07T19:46:31+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 07 19:46:31 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 19:46:31 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 19:46:31 volumio volumio[21066]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 07 19:46:31 volumio volumio[21066]: Error: socket hang up Feb 07 19:46:31 volumio volumio[21066]: at connResetException (node:internal/errors:720:14) Feb 07 19:46:31 volumio volumio[21066]: at Socket.socketOnEnd (node:_http_client:519:23) Feb 07 19:46:31 volumio volumio[21066]: at Socket.emit (node:events:526:35) Feb 07 19:46:31 volumio volumio[21066]: at endReadableNT (node:internal/streams/readable:1376:12) Feb 07 19:46:31 volumio volumio[21066]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Feb 07 19:46:31 volumio volumio[21066]: code: 'ECONNRESET', Feb 07 19:46:31 volumio volumio[21066]: response: undefined Feb 07 19:46:31 volumio volumio[21066]: } Feb 07 19:46:31 volumio volumio[21066]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 07 19:46:32 volumio sudo[21455]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-07 19:45' Feb 07 19:46:32 volumio sudo[21455]: 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"