Dec 06 11:46:00 donutvolumio volumio[1173]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 06 11:46:00 donutvolumio volumio[1173]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 06 11:46:00 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 11:46:00 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 11:46:00 donutvolumio volumio[1173]: info: Starting MyVolumio Remote Streaming Endpoints Dec 06 11:46:00 donutvolumio volumio[1173]: info: MyVolumio login type: Token Dec 06 11:46:00 donutvolumio volumio[1173]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 06 11:46:00 donutvolumio volumio[1173]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Dec 06 11:46:01 donutvolumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Dec 06 11:46:01 donutvolumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 06 11:46:01 donutvolumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 06 11:46:01 donutvolumio go-librespot[2036]: go-librespot daemon starting... Dec 06 11:46:01 donutvolumio go-librespot[2037]: time="2025-12-06T11:46:01+08:00" level=info msg="running go-librespot 0.4.0" Dec 06 11:46:01 donutvolumio go-librespot[2037]: time="2025-12-06T11:46:01+08:00" level=debug msg="app state loaded" Dec 06 11:46:01 donutvolumio go-librespot[2037]: time="2025-12-06T11:46:01+08:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 06 11:46:02 donutvolumio go-librespot[2037]: time="2025-12-06T11:46:02+08: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]" Dec 06 11:46:02 donutvolumio go-librespot[2037]: time="2025-12-06T11:46:02+08: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]" Dec 06 11:46:02 donutvolumio go-librespot[2037]: time="2025-12-06T11:46:02+08: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]" Dec 06 11:46:02 donutvolumio go-librespot[2037]: time="2025-12-06T11:46:02+08:00" level=info msg="zeroconf server listening on port 43401" Dec 06 11:46:02 donutvolumio go-librespot[2037]: time="2025-12-06T11:46:02+08:00" level=debug msg="obtained new client token: AAB2uVeKc7ekzMouMiwua7o4z2LFQaDLs7iQvN+yEz/sx7r02W1u+ABKfldJF60ofz0LASoxi094qOzmtB/crRjTsg+zj2P/XYmhvNCbvZreiyIn9sAgBKgUPYuUPGEBpa/PwgwoMgAo86x/V7YnXUWZVeU4H/LePVAWLeBzKv4m6+eeXv1vm16NsyTowInjSdPVIzNV7m6JI69YadvJ7pdKr+lIqVy7GUMduWz2hv2793N6fZJk4jL9" Dec 06 11:46:02 donutvolumio go-librespot[2037]: time="2025-12-06T11:46:02+08:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Dec 06 11:46:02 donutvolumio go-librespot[2037]: time="2025-12-06T11:46:02+08:00" level=debug msg="completed keyexchange" Dec 06 11:46:02 donutvolumio go-librespot[2037]: time="2025-12-06T11:46:02+08:00" level=debug msg="completed challenge" Dec 06 11:46:02 donutvolumio go-librespot[2037]: time="2025-12-06T11:46:02+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 06 11:46:02 donutvolumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 06 11:46:02 donutvolumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 06 11:46:05 donutvolumio volumio[1173]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Dec 06 11:46:05 donutvolumio volumio[1173]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Dec 06 11:46:05 donutvolumio volumio[1173]: info: Streaming services startup Dec 06 11:46:05 donutvolumio volumio[1173]: info: Starting Streaming Daemon Dec 06 11:46:05 donutvolumio volumio[1173]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Dec 06 11:46:05 donutvolumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Dec 06 11:46:05 donutvolumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 06 11:46:05 donutvolumio sudo[2049]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 06 11:46:05 donutvolumio sudo[2049]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 11:46:05 donutvolumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 06 11:46:05 donutvolumio go-librespot[2054]: go-librespot daemon starting... Dec 06 11:46:05 donutvolumio go-librespot[2056]: time="2025-12-06T11:46:05+08:00" level=info msg="running go-librespot 0.4.0" Dec 06 11:46:05 donutvolumio go-librespot[2056]: time="2025-12-06T11:46:05+08:00" level=debug msg="app state loaded" Dec 06 11:46:05 donutvolumio go-librespot[2056]: time="2025-12-06T11:46:05+08:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 06 11:46:05 donutvolumio sudo[2049]: pam_unix(sudo:session): session closed for user root Dec 06 11:46:05 donutvolumio upmpdcli[2064]: writing RSA key Dec 06 11:46:05 donutvolumio go-librespot[2056]: time="2025-12-06T11:46:05+08: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]" Dec 06 11:46:05 donutvolumio go-librespot[2056]: time="2025-12-06T11:46:05+08: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]" Dec 06 11:46:05 donutvolumio go-librespot[2056]: time="2025-12-06T11:46:05+08: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]" Dec 06 11:46:05 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 06 11:46:05 donutvolumio go-librespot[2056]: time="2025-12-06T11:46:05+08:00" level=info msg="zeroconf server listening on port 36547" Dec 06 11:46:05 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 11:46:05 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 06 11:46:05 donutvolumio volumio[1173]: info: Initializing connection to go-librespot Websocket Dec 06 11:46:05 donutvolumio go-librespot[2056]: time="2025-12-06T11:46:05+08:00" level=debug msg="obtained new client token: AACMd2O/Myvb1CKCrnDhd5wJJ9emoStak0u8ke4hUrnb3xiaaHolPGJ6E5Wg2FCw9i2YAzhHuUReoP3xCFh9Ubd16DefOhmTUBOKIptJUfjKZoR02EQNukBoNEKpuC5K47bFMw3Czg8jQuwqm01Lrf+wPXbTitzj8ROClnCM2Fdvomg8cJuHopZdyXbS1iso0yGGfaEQD9b4hlAxCLvyAVNmxjUF0cNvAzwP6Ro4DHV/hsaX9EQ+CH47J/s=" Dec 06 11:46:06 donutvolumio go-librespot[2056]: time="2025-12-06T11:46:06+08:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Dec 06 11:46:06 donutvolumio go-librespot[2056]: time="2025-12-06T11:46:06+08:00" level=debug msg="completed keyexchange" Dec 06 11:46:06 donutvolumio go-librespot[2056]: time="2025-12-06T11:46:06+08:00" level=debug msg="completed challenge" Dec 06 11:46:06 donutvolumio volumio[1173]: error: Cannot start Volumio Streaming Daemon Dec 06 11:46:06 donutvolumio volumio[1173]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 06 11:46:06 donutvolumio volumio[1173]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 06 11:46:06 donutvolumio go-librespot[2056]: time="2025-12-06T11:46:06+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 06 11:46:06 donutvolumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 06 11:46:06 donutvolumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 06 11:46:06 donutvolumio volumio[1173]: info: Error connecting to go-librespot Websocket: Error: socket hang up Dec 06 11:46:06 donutvolumio volumio[1173]: error: MyVolumio Custom Token format not valid, refreshing it Dec 06 11:46:07 donutvolumio volumio-remote-updater[732]: Test mode disabled Dec 06 11:46:07 donutvolumio volumio-remote-updater[732]: Alpha mode disabled Dec 06 11:46:07 donutvolumio volumio-remote-updater[732]: Alpha legacy test mode disabled Dec 06 11:46:07 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Dec 06 11:46:08 donutvolumio volumio[1173]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Dec 06 11:46:08 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 06 11:46:08 donutvolumio volumio[1173]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/142.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Dec 06 11:46:08 donutvolumio volumio[1173]: info: MyVolumio login type: Token Dec 06 11:46:09 donutvolumio volumio[1173]: info: Initializing connection to go-librespot Websocket Dec 06 11:46:09 donutvolumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Dec 06 11:46:09 donutvolumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 06 11:46:09 donutvolumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 06 11:46:09 donutvolumio go-librespot[2073]: go-librespot daemon starting... Dec 06 11:46:09 donutvolumio go-librespot[2074]: time="2025-12-06T11:46:09+08:00" level=info msg="running go-librespot 0.4.0" Dec 06 11:46:09 donutvolumio go-librespot[2074]: time="2025-12-06T11:46:09+08:00" level=debug msg="app state loaded" Dec 06 11:46:09 donutvolumio go-librespot[2074]: time="2025-12-06T11:46:09+08:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 06 11:46:09 donutvolumio volumio[1173]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Dec 06 11:46:09 donutvolumio go-librespot[2074]: time="2025-12-06T11:46:09+08: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]" Dec 06 11:46:09 donutvolumio go-librespot[2074]: time="2025-12-06T11:46:09+08: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]" Dec 06 11:46:09 donutvolumio go-librespot[2074]: time="2025-12-06T11:46:09+08: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]" Dec 06 11:46:09 donutvolumio go-librespot[2074]: time="2025-12-06T11:46:09+08:00" level=info msg="zeroconf server listening on port 46513" Dec 06 11:46:09 donutvolumio volumio[1173]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 06 11:46:09 donutvolumio go-librespot[2074]: time="2025-12-06T11:46:09+08:00" level=debug msg="obtained new client token: AADi/YF7WHOEJOiBcK7TILwpihg2W1S1QvUlszxJxixhcO+ZYtASMR0VkwsHLjKdEE7LbpT9VcQqXhfbqSFdor6drJ6cw7DEC3QzWLwOm36UxLUBZXqN/5s+bQW06sGuElTZpurmK+ztPZlW+ZZzOFi742Z409JglwVsHUwHrDSneeWEkNcvkxUXIy8laAHaJf44ljMjtL8Omb5SCFTb88T34yquHsc+BuES9r6O6xnOPgj7QJvP2I7PHR8=" Dec 06 11:46:09 donutvolumio go-librespot[2074]: time="2025-12-06T11:46:09+08:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Dec 06 11:46:09 donutvolumio go-librespot[2074]: time="2025-12-06T11:46:09+08:00" level=debug msg="completed keyexchange" Dec 06 11:46:09 donutvolumio go-librespot[2074]: time="2025-12-06T11:46:09+08:00" level=debug msg="completed challenge" Dec 06 11:46:09 donutvolumio go-librespot[2074]: time="2025-12-06T11:46:09+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 06 11:46:09 donutvolumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 06 11:46:09 donutvolumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 06 11:46:10 donutvolumio volumio[1173]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/142.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Dec 06 11:46:10 donutvolumio volumio[1173]: info: MyVolumio token set successfully Dec 06 11:46:10 donutvolumio volumio[1173]: info: MYVOLUMIO: Adding device Dec 06 11:46:10 donutvolumio volumio[1173]: info: MYVOLUMIO: Evaluating Server Dec 06 11:46:11 donutvolumio volumio[1173]: info: MyVolumio Plan changed: premium Dec 06 11:46:11 donutvolumio volumio[1173]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Dec 06 11:46:11 donutvolumio volumio[1173]: info: Removing browser output: myVolumio user plan is not superstar Dec 06 11:46:11 donutvolumio volumio[1173]: info: Removing audio output: Dec 06 11:46:11 donutvolumio volumio[1173]: info: MYVOLUMIO: Adding device Dec 06 11:46:11 donutvolumio volumio[1173]: info: MYVOLUMIO: Evaluating Server Dec 06 11:46:12 donutvolumio volumio[1173]: info: Remote config written successfully Dec 06 11:46:12 donutvolumio volumio[1173]: info: Starting Tunnel 1 Dec 06 11:46:12 donutvolumio volumio[1173]: info: Starting Tunnel Connection Checker Dec 06 11:46:12 donutvolumio volumio[1173]: info: Completed starting MyVolumio Plugin Dec 06 11:46:12 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 06 11:46:12 donutvolumio volumio[1173]: info: Initializing connection to go-librespot Websocket Dec 06 11:46:12 donutvolumio volumio[1173]: info: MYVolumio Device enabled Dec 06 11:46:12 donutvolumio volumio[1173]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Dec 06 11:46:12 donutvolumio volumio[1173]: info: MyVolumio status changed Dec 06 11:46:12 donutvolumio volumio[1173]: info: Streaming services startup Dec 06 11:46:12 donutvolumio volumio[1173]: info: Starting Streaming Daemon Dec 06 11:46:12 donutvolumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Dec 06 11:46:12 donutvolumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 06 11:46:13 donutvolumio volumio[1173]: info: Setting Geolocation for MyVolumio to as1 Dec 06 11:46:13 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 11:46:13 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 11:46:13 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 11:46:13 donutvolumio sudo[2134]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 06 11:46:13 donutvolumio sudo[2134]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 11:46:13 donutvolumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 06 11:46:13 donutvolumio go-librespot[2135]: go-librespot daemon starting... Dec 06 11:46:13 donutvolumio go-librespot[2137]: time="2025-12-06T11:46:13+08:00" level=info msg="running go-librespot 0.4.0" Dec 06 11:46:13 donutvolumio go-librespot[2137]: time="2025-12-06T11:46:13+08:00" level=debug msg="app state loaded" Dec 06 11:46:13 donutvolumio go-librespot[2137]: time="2025-12-06T11:46:13+08:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 06 11:46:13 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 06 11:46:13 donutvolumio sudo[2134]: pam_unix(sudo:session): session closed for user root Dec 06 11:46:13 donutvolumio volumio[1173]: info: Setting Geolocation for MyVolumio to as1 Dec 06 11:46:13 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 11:46:13 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 11:46:13 donutvolumio go-librespot[2137]: time="2025-12-06T11:46:13+08: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]" Dec 06 11:46:13 donutvolumio go-librespot[2137]: time="2025-12-06T11:46:13+08: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]" Dec 06 11:46:13 donutvolumio go-librespot[2137]: time="2025-12-06T11:46:13+08: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]" Dec 06 11:46:13 donutvolumio go-librespot[2137]: time="2025-12-06T11:46:13+08:00" level=info msg="zeroconf server listening on port 46137" Dec 06 11:46:13 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 11:46:13 donutvolumio go-librespot[2137]: time="2025-12-06T11:46:13+08:00" level=debug msg="obtained new client token: AACvrbFEwUHyV88a5w1KPfM0eGI+otEmpi5Dmma8R2GyaIGiog6A50N0P40CDj6Ilu9tGTPnKclmbvL/Xbk2SOFZHaEawSZteILkwVM6Bw53e+Nqg6VLgTFofIzKLboJ9UrNBMUIZ+zI4ciEP0ae+y75mKhA1FgzFHr99RZuJXrOCmi1pAZJLcdYAJsvClfun1C4d+sW836e39n2k5PkRtAVx2rmltAnmiGntx1RfDvzyYuhJ9IIJSxBkEQ=" Dec 06 11:46:13 donutvolumio go-librespot[2137]: time="2025-12-06T11:46:13+08:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Dec 06 11:46:13 donutvolumio volumio[1173]: info: CoreCommandRouter::volumioGetVisibleSources Dec 06 11:46:13 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 06 11:46:13 donutvolumio volumio[1173]: info: CoreCommandRouter::volumioGetState Dec 06 11:46:13 donutvolumio volumio[1173]: info: CorePlayQueue::getTrack 0 Dec 06 11:46:13 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 06 11:46:13 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 06 11:46:13 donutvolumio volumio[1173]: info: Received Get System Info Dec 06 11:46:13 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 06 11:46:13 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 06 11:46:13 donutvolumio go-librespot[2137]: time="2025-12-06T11:46:13+08:00" level=debug msg="completed keyexchange" Dec 06 11:46:13 donutvolumio go-librespot[2137]: time="2025-12-06T11:46:13+08:00" level=debug msg="completed challenge" Dec 06 11:46:13 donutvolumio volumio[1173]: info: Discovery: Getting this device information Dec 06 11:46:13 donutvolumio volumio[1173]: info: CoreCommandRouter::volumioGetState Dec 06 11:46:13 donutvolumio volumio[1173]: info: CorePlayQueue::getTrack 0 Dec 06 11:46:13 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 06 11:46:13 donutvolumio volumio[1173]: info: CoreCommandRouter::volumioGetState Dec 06 11:46:13 donutvolumio volumio[1173]: info: CorePlayQueue::getTrack 0 Dec 06 11:46:13 donutvolumio go-librespot[2137]: time="2025-12-06T11:46:13+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 06 11:46:13 donutvolumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 06 11:46:13 donutvolumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 06 11:46:13 donutvolumio volumio[1173]: info: Listing playlists Dec 06 11:46:13 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 06 11:46:13 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 06 11:46:13 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 06 11:46:13 donutvolumio volumio[1173]: info: Received Get System Info Dec 06 11:46:13 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 06 11:46:13 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 06 11:46:13 donutvolumio volumio[1173]: info: Discovery: Getting this device information Dec 06 11:46:13 donutvolumio volumio[1173]: info: CoreCommandRouter::volumioGetState Dec 06 11:46:13 donutvolumio volumio[1173]: info: CorePlayQueue::getTrack 0 Dec 06 11:46:13 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 06 11:46:14 donutvolumio volumio[1173]: info: Successfully Added MyVolumio device Dec 06 11:46:14 donutvolumio volumio[1173]: error: Cannot start Volumio Streaming Daemon Dec 06 11:46:14 donutvolumio volumio[1173]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 06 11:46:14 donutvolumio volumio[1173]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 06 11:46:14 donutvolumio volumio[1173]: info: Updating MyVolumio device info Dec 06 11:46:14 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 11:46:14 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 11:46:14 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 11:46:14 donutvolumio volumio[1173]: info: Error connecting to go-librespot Websocket: Error: socket hang up Dec 06 11:46:14 donutvolumio volumio[1173]: info: Successfully Added MyVolumio device Dec 06 11:46:14 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Dec 06 11:46:14 donutvolumio volumio[1173]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Dec 06 11:46:15 donutvolumio volumio[1173]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart Dec 06 11:46:15 donutvolumio volumio[1173]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Dec 06 11:46:16 donutvolumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Dec 06 11:46:16 donutvolumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 06 11:46:16 donutvolumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 06 11:46:16 donutvolumio go-librespot[2147]: go-librespot daemon starting... Dec 06 11:46:16 donutvolumio go-librespot[2148]: time="2025-12-06T11:46:16+08:00" level=info msg="running go-librespot 0.4.0" Dec 06 11:46:16 donutvolumio go-librespot[2148]: time="2025-12-06T11:46:16+08:00" level=debug msg="app state loaded" Dec 06 11:46:16 donutvolumio go-librespot[2148]: time="2025-12-06T11:46:16+08:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 06 11:46:17 donutvolumio go-librespot[2148]: time="2025-12-06T11:46:17+08: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]" Dec 06 11:46:17 donutvolumio go-librespot[2148]: time="2025-12-06T11:46:17+08: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]" Dec 06 11:46:17 donutvolumio go-librespot[2148]: time="2025-12-06T11:46:17+08: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]" Dec 06 11:46:17 donutvolumio go-librespot[2148]: time="2025-12-06T11:46:17+08:00" level=info msg="zeroconf server listening on port 45971" Dec 06 11:46:17 donutvolumio go-librespot[2148]: time="2025-12-06T11:46:17+08:00" level=debug msg="obtained new client token: AACV27pO9+fELbObGDXH64iEOVc4sUhiBDUx67+deLTjxScc8yzVmxZEAUtoN+kMNgsZK89GmTgOMK9w51oP+TXcONfjeLhlWg2IlF2L0qZ9HVe1CiOfhJP49ieeihJA0XEAZOviGmY/f1P8OCZ+WjmeQKvN40tt+B6X6xDwZF1j33Xwn+tmM7FH9ggOHecDGr6S6qiDPOFiwmVxhiH1eC0U0QQ+UfOcdn4ZjYOLfELRanpFOEKpHpNI" Dec 06 11:46:17 donutvolumio go-librespot[2148]: time="2025-12-06T11:46:17+08:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Dec 06 11:46:17 donutvolumio go-librespot[2148]: time="2025-12-06T11:46:17+08:00" level=debug msg="completed keyexchange" Dec 06 11:46:17 donutvolumio go-librespot[2148]: time="2025-12-06T11:46:17+08:00" level=debug msg="completed challenge" Dec 06 11:46:17 donutvolumio go-librespot[2148]: time="2025-12-06T11:46:17+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 06 11:46:17 donutvolumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 06 11:46:17 donutvolumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 06 11:46:19 donutvolumio volumio[1173]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Dec 06 11:46:19 donutvolumio sudo[2157]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Dec 06 11:46:19 donutvolumio sudo[2157]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 11:46:19 donutvolumio sudo[2157]: pam_unix(sudo:session): session closed for user root Dec 06 11:46:19 donutvolumio volumio[1173]: info: MRS: MultiRoom plugin initialized Dec 06 11:46:19 donutvolumio volumio[1173]: info: MRS: STOPPING SNAPCLIENT Dec 06 11:46:19 donutvolumio volumio[1173]: info: MRS: Snap server stop Dec 06 11:46:19 donutvolumio volumio[1173]: info: MRS: STOPPING volumioStreaming Dec 06 11:46:19 donutvolumio sudo[2174]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Dec 06 11:46:19 donutvolumio sudo[2174]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 11:46:19 donutvolumio sudo[2176]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Dec 06 11:46:19 donutvolumio sudo[2178]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Dec 06 11:46:19 donutvolumio sudo[2178]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 11:46:19 donutvolumio sudo[2176]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 11:46:19 donutvolumio volumio[1173]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Dec 06 11:46:19 donutvolumio sudo[2174]: pam_unix(sudo:session): session closed for user root Dec 06 11:46:19 donutvolumio sudo[2176]: pam_unix(sudo:session): session closed for user root Dec 06 11:46:19 donutvolumio sudo[2178]: pam_unix(sudo:session): session closed for user root Dec 06 11:46:19 donutvolumio volumio[1173]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Dec 06 11:46:19 donutvolumio sudo[2183]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Dec 06 11:46:20 donutvolumio sudo[2183]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 11:46:20 donutvolumio volumio[1173]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Dec 06 11:46:20 donutvolumio sudo[2183]: pam_unix(sudo:session): session closed for user root Dec 06 11:46:20 donutvolumio volumio[1173]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Dec 06 11:46:20 donutvolumio volumio[1173]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Dec 06 11:46:20 donutvolumio volumio[1173]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Dec 06 11:46:20 donutvolumio volumio[1173]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Dec 06 11:46:20 donutvolumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11. Dec 06 11:46:20 donutvolumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 06 11:46:20 donutvolumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 06 11:46:20 donutvolumio go-librespot[2199]: go-librespot daemon starting... Dec 06 11:46:20 donutvolumio volumio[1173]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Dec 06 11:46:20 donutvolumio go-librespot[2200]: time="2025-12-06T11:46:20+08:00" level=info msg="running go-librespot 0.4.0" Dec 06 11:46:20 donutvolumio go-librespot[2200]: time="2025-12-06T11:46:20+08:00" level=debug msg="app state loaded" Dec 06 11:46:20 donutvolumio go-librespot[2200]: time="2025-12-06T11:46:20+08:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 06 11:46:20 donutvolumio volumio[1173]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Dec 06 11:46:20 donutvolumio volumio[1173]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Dec 06 11:46:20 donutvolumio volumio[1173]: info: Preparing to generate the ALSA configuration file Dec 06 11:46:20 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 06 11:46:20 donutvolumio volumio[1173]: info: Received Get System Info Dec 06 11:46:20 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 06 11:46:20 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 06 11:46:20 donutvolumio volumio[1173]: info: Discovery: Getting this device information Dec 06 11:46:20 donutvolumio volumio[1173]: info: CoreCommandRouter::volumioGetState Dec 06 11:46:20 donutvolumio volumio[1173]: info: CorePlayQueue::getTrack 0 Dec 06 11:46:20 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 06 11:46:20 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 06 11:46:20 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 06 11:46:20 donutvolumio volumio[1173]: info: Updating MyVolumio device info Dec 06 11:46:20 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 11:46:20 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 11:46:20 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 11:46:20 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 06 11:46:20 donutvolumio volumio[1173]: info: Initializing connection to go-librespot Websocket Dec 06 11:46:20 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 11:46:20 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 11:46:20 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 06 11:46:20 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 06 11:46:20 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 06 11:46:20 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 06 11:46:20 donutvolumio volumio[1173]: info: CoreCommandRouter::volumioGetBrowseSources Dec 06 11:46:20 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 06 11:46:20 donutvolumio go-librespot[2200]: time="2025-12-06T11:46:20+08: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]" Dec 06 11:46:20 donutvolumio go-librespot[2200]: time="2025-12-06T11:46:20+08: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]" Dec 06 11:46:20 donutvolumio go-librespot[2200]: time="2025-12-06T11:46:20+08: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]" Dec 06 11:46:20 donutvolumio go-librespot[2200]: time="2025-12-06T11:46:20+08:00" level=info msg="zeroconf server listening on port 41797" Dec 06 11:46:20 donutvolumio volumio[1173]: info: Successfully Updated MyVolumio device Dec 06 11:46:20 donutvolumio volumio[1173]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 06 11:46:20 donutvolumio go-librespot[2200]: time="2025-12-06T11:46:20+08:00" level=debug msg="obtained new client token: AAD1DqIATnrf+JTk2LpTgfQZHVpozlwJOVz2xN3mf393OWH7GbXYbuOzf91+Aqwbk25mX7EltLsEBPwJHSnDtX0lH6ckVkjPvn5T0GlZxixCHOYLqG18wWUP/grHexBLMUK9Ua6iaIMJq8znWdJ3NYuuljjmYxlCGnwIkH5wLtN6oGo0esZQ07dUpkc64rmo/TkV6A1wsIrlouf0FgpLRibHCRq45psl9EiLlxCH6X4zqCkALLCQAJREgeA=" Dec 06 11:46:20 donutvolumio volumio[1173]: info: Reading ALSA contributions from plugins. Dec 06 11:46:20 donutvolumio go-librespot[2200]: time="2025-12-06T11:46:20+08:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Dec 06 11:46:21 donutvolumio go-librespot[2200]: time="2025-12-06T11:46:21+08:00" level=debug msg="completed keyexchange" Dec 06 11:46:21 donutvolumio go-librespot[2200]: time="2025-12-06T11:46:21+08:00" level=debug msg="completed challenge" Dec 06 11:46:21 donutvolumio go-librespot[2200]: time="2025-12-06T11:46:21+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 06 11:46:21 donutvolumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 06 11:46:21 donutvolumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 06 11:46:21 donutvolumio volumio[1173]: info: MRS: Removed streaming files Dec 06 11:46:21 donutvolumio volumio[1173]: info: MRS: volumioStreaming STOPPED Dec 06 11:46:21 donutvolumio volumio[1173]: info: MRS: SNAPSERVER STOPPED Dec 06 11:46:21 donutvolumio volumio[1173]: info: MRS: SNAPCLIENT STOPPED Dec 06 11:46:21 donutvolumio volumio[1173]: info: Error connecting to go-librespot Websocket: Error: socket hang up Dec 06 11:46:21 donutvolumio volumio[1173]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Dec 06 11:46:22 donutvolumio sudo[2222]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Dec 06 11:46:22 donutvolumio volumio[1173]: info: Successfully Updated MyVolumio device Dec 06 11:46:22 donutvolumio sudo[2222]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 11:46:22 donutvolumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 06 11:46:22 donutvolumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 06 11:46:22 donutvolumio systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Dec 06 11:46:22 donutvolumio sudo[2222]: pam_unix(sudo:session): session closed for user root Dec 06 11:46:22 donutvolumio volumio[1173]: info: CoreCommandRouter::volumioGetState Dec 06 11:46:22 donutvolumio volumio[1173]: info: CorePlayQueue::getTrack 0 Dec 06 11:46:22 donutvolumio autossh[2225]: port set to 0, monitoring disabled Dec 06 11:46:22 donutvolumio autossh[2225]: starting ssh (count 1) Dec 06 11:46:22 donutvolumio autossh[2225]: ssh child pid is 2228 Dec 06 11:46:22 donutvolumio volumio[1173]: info: Remote SSH Started Dec 06 11:46:22 donutvolumio volumiossh-tunnel[2228]: Warning: Permanently added '[as1.myvolumio.org]:2222' (RSA) to the list of known hosts. Dec 06 11:46:23 donutvolumio volumio[1173]: info: Asound.conf file written Dec 06 11:46:23 donutvolumio sudo[2231]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 06 11:46:23 donutvolumio sudo[2231]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 11:46:23 donutvolumio sudo[2231]: pam_unix(sudo:session): session closed for user root Dec 06 11:46:23 donutvolumio volumio[1173]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Dec 06 11:46:23 donutvolumio volumio[1173]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Dec 06 11:46:23 donutvolumio volumio[1173]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Dec 06 11:46:23 donutvolumio volumio[1173]: info: Output device has changed, restarting MPD Dec 06 11:46:23 donutvolumio volumio[1173]: info: Output device has changed, restarting Shairport Sync Dec 06 11:46:23 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 11:46:23 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 11:46:23 donutvolumio sudo[2237]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 06 11:46:23 donutvolumio sudo[2237]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 11:46:23 donutvolumio sudo[2237]: pam_unix(sudo:session): session closed for user root Dec 06 11:46:23 donutvolumio sudo[2239]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 06 11:46:23 donutvolumio sudo[2239]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 11:46:23 donutvolumio volumio[1173]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 06 11:46:23 donutvolumio volumio[1173]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 06 11:46:23 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 11:46:23 donutvolumio systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 06 11:46:23 donutvolumio volumio[1173]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Dec 06 11:46:23 donutvolumio volumio[1173]: ------------------------------------ BT MESSAGE: [FUNC] onStart Dec 06 11:46:23 donutvolumio volumio[1173]: ------------------------------------ BT MESSAGE: Starting Volumio Bluetooth Service Dec 06 11:46:23 donutvolumio volumio[1173]: ------------------------------------ BT MESSAGE: Boot config /etc/bluetooth/volumio.conf: cache mode = tmp Dec 06 11:46:23 donutvolumio volumio[1173]: ------------------------------------ BT MESSAGE: [metaCache] Created directory: /tmp/bluetooth-cache/ Dec 06 11:46:23 donutvolumio volumio[1173]: ------------------------------------ BT MESSAGE: [metaCache] Directory exists and is ready. Dec 06 11:46:23 donutvolumio systemd[1]: mpd.service: Deactivated successfully. Dec 06 11:46:23 donutvolumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 06 11:46:23 donutvolumio systemd[1]: mpd.service: Consumed 3.678s CPU time. Dec 06 11:46:23 donutvolumio systemd[1]: mpd.socket: Deactivated successfully. Dec 06 11:46:23 donutvolumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 06 11:46:23 donutvolumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 06 11:46:23 donutvolumio volumio[1173]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Dec 06 11:46:23 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Dec 06 11:46:23 donutvolumio volumio[1173]: info: MRS: Pushing multiroomSync output for this device Dec 06 11:46:23 donutvolumio volumio[1173]: info: MRS: Pushing multiroomSync output Dec 06 11:46:23 donutvolumio bluetoothd[790]: Path / reserved for Adv Monitor app :1.24 Dec 06 11:46:23 donutvolumio bluetoothd[790]: Adv Monitor app :1.24 disconnected from D-Bus Dec 06 11:46:23 donutvolumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 06 11:46:23 donutvolumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 06 11:46:23 donutvolumio volumio[1173]: info: Adding audio output: Dec 06 11:46:24 donutvolumio volumio[1173]: info: Adding audio output: Dec 06 11:46:24 donutvolumio sudo[2251]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 06 11:46:24 donutvolumio sudo[2251]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 06 11:46:24 donutvolumio sudo[2251]: pam_unix(sudo:session): session closed for user root Dec 06 11:46:24 donutvolumio volumio[1173]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 06 11:46:24 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 11:46:24 donutvolumio volumio[1173]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Dec 06 11:46:24 donutvolumio volumio[1173]: info: Adding METAVOLUMIO REST API Endpoints Dec 06 11:46:24 donutvolumio volumio[1173]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Dec 06 11:46:24 donutvolumio volumio[1173]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Dec 06 11:46:24 donutvolumio volumio[1173]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Dec 06 11:46:24 donutvolumio volumio[1173]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Dec 06 11:46:24 donutvolumio volumio[1173]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Dec 06 11:46:24 donutvolumio volumio[1173]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Dec 06 11:46:24 donutvolumio volumio[1173]: info: Preparing CD Folders Dec 06 11:46:24 donutvolumio volumio[1173]: info: Adding CD REST API Endpoints Dec 06 11:46:24 donutvolumio volumio[1173]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Dec 06 11:46:24 donutvolumio volumio[1173]: info: Starting UDEV Watcher for CD Dec 06 11:46:24 donutvolumio volumio[1173]: info: Detecting CD presence with UDEV Dec 06 11:46:24 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Dec 06 11:46:24 donutvolumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12. Dec 06 11:46:24 donutvolumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 06 11:46:24 donutvolumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 06 11:46:24 donutvolumio go-librespot[2257]: go-librespot daemon starting... Dec 06 11:46:24 donutvolumio go-librespot[2258]: time="2025-12-06T11:46:24+08:00" level=info msg="running go-librespot 0.4.0" Dec 06 11:46:24 donutvolumio go-librespot[2258]: time="2025-12-06T11:46:24+08:00" level=debug msg="app state loaded" Dec 06 11:46:24 donutvolumio go-librespot[2258]: time="2025-12-06T11:46:24+08:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 06 11:46:24 donutvolumio go-librespot[2258]: time="2025-12-06T11:46:24+08: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]" Dec 06 11:46:24 donutvolumio go-librespot[2258]: time="2025-12-06T11:46:24+08: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]" Dec 06 11:46:24 donutvolumio go-librespot[2258]: time="2025-12-06T11:46:24+08: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]" Dec 06 11:46:24 donutvolumio go-librespot[2258]: time="2025-12-06T11:46:24+08:00" level=info msg="zeroconf server listening on port 43229" Dec 06 11:46:24 donutvolumio go-librespot[2258]: time="2025-12-06T11:46:24+08:00" level=debug msg="obtained new client token: AABw3oJtPTlMz4eQCt7NtjDkbS+OSFIlcaQYtZpwBuQIdGYKdKChDORzb45u2msxyWaTbGIN4v3iuRvsZG3iJ/31YCnv6Yd6xKQoK4e7JB8FxgomYfFLFoPRG24qX+TD6Nji01UFhYuLYZ/4eo23e4DFzSh2yH5Ss7wFvoqSzpLINl9U+L4bWnJbxMr2Eg/BKK4QCtZwaXW1q5L1PN9EdxlB2a1aHyWKMTed0IpN5tN9AzlJOFbeM1npMkc=" Dec 06 11:46:24 donutvolumio go-librespot[2258]: time="2025-12-06T11:46:24+08:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Dec 06 11:46:24 donutvolumio go-librespot[2258]: time="2025-12-06T11:46:24+08:00" level=debug msg="completed keyexchange" Dec 06 11:46:24 donutvolumio go-librespot[2258]: time="2025-12-06T11:46:24+08:00" level=debug msg="completed challenge" Dec 06 11:46:24 donutvolumio go-librespot[2258]: time="2025-12-06T11:46:24+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 06 11:46:24 donutvolumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 06 11:46:24 donutvolumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 06 11:46:25 donutvolumio mpd[2254]: 2025-12-06T11:46:25 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 06 11:46:25 donutvolumio systemd[1]: Started mpd.service - Music Player Daemon. Dec 06 11:46:25 donutvolumio sudo[2239]: pam_unix(sudo:session): session closed for user root Dec 06 11:46:27 donutvolumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13. Dec 06 11:46:27 donutvolumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 06 11:46:28 donutvolumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 06 11:46:28 donutvolumio go-librespot[2270]: go-librespot daemon starting... Dec 06 11:46:28 donutvolumio go-librespot[2271]: time="2025-12-06T11:46:28+08:00" level=info msg="running go-librespot 0.4.0" Dec 06 11:46:28 donutvolumio go-librespot[2271]: time="2025-12-06T11:46:28+08:00" level=debug msg="app state loaded" Dec 06 11:46:28 donutvolumio go-librespot[2271]: time="2025-12-06T11:46:28+08:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 06 11:46:28 donutvolumio go-librespot[2271]: time="2025-12-06T11:46:28+08: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]" Dec 06 11:46:28 donutvolumio go-librespot[2271]: time="2025-12-06T11:46:28+08: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]" Dec 06 11:46:28 donutvolumio go-librespot[2271]: time="2025-12-06T11:46:28+08: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]" Dec 06 11:46:28 donutvolumio go-librespot[2271]: time="2025-12-06T11:46:28+08:00" level=info msg="zeroconf server listening on port 37471" Dec 06 11:46:28 donutvolumio go-librespot[2271]: time="2025-12-06T11:46:28+08:00" level=debug msg="obtained new client token: AADIkYNTLERehoEBB8X1I8i6LOxPtB9oXUmLtJVFV1TrcBTyaRV2GQgtFVEMilSdQiPLytVJflLKY6NcS4QfxZP6vZRRs09aanyLUe+NFvIvTG5U1gGLZYbJxoFCkGQNeixhHsPzf3LexY5z96wFi9OT3sCGspZrePwWuzPZep5HzXk7as9bII9aEgXzMvvVSgNTIyYtbkcdeFKYDVHVQi8nk4tQ3zxgBVJS+VguTzxCWjLt9uMch7AwdM0=" Dec 06 11:46:28 donutvolumio go-librespot[2271]: time="2025-12-06T11:46:28+08:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Dec 06 11:46:28 donutvolumio go-librespot[2271]: time="2025-12-06T11:46:28+08:00" level=debug msg="completed keyexchange" Dec 06 11:46:28 donutvolumio go-librespot[2271]: time="2025-12-06T11:46:28+08:00" level=debug msg="completed challenge" Dec 06 11:46:28 donutvolumio go-librespot[2271]: time="2025-12-06T11:46:28+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 06 11:46:28 donutvolumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 06 11:46:28 donutvolumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 06 11:46:29 donutvolumio volumio[1173]: warn: [cd-plugin] cdspeedctl: device or media not ready Dec 06 11:46:29 donutvolumio volumio[1173]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Dec 06 11:46:29 donutvolumio volumio[1173]: info: Adding inputs REST Endpoints Dec 06 11:46:29 donutvolumio volumio[1173]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Dec 06 11:46:29 donutvolumio volumio[1173]: info: Scanning Audio Inputs Dec 06 11:46:29 donutvolumio volumio[1173]: info: Checking against Known Cards name Dec 06 11:46:29 donutvolumio volumio[1173]: info: Checking against Known Cards name Dec 06 11:46:29 donutvolumio volumio[1173]: info: Adding Server instance for streaming Dec 06 11:46:29 donutvolumio volumio[1173]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Dec 06 11:46:29 donutvolumio volumio[1173]: error: Hi Res Audio Failed Login: Missing Login Data Dec 06 11:46:29 donutvolumio volumio[1173]: info: Adding HIGHRESAUDIO REST API Endpoints Dec 06 11:46:29 donutvolumio volumio[1173]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Dec 06 11:46:29 donutvolumio volumio[1173]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Dec 06 11:46:29 donutvolumio volumio[1173]: info: Refreshing TIDAL token Dec 06 11:46:29 donutvolumio volumio[1173]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Dec 06 11:46:29 donutvolumio volumio[1173]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Dec 06 11:46:29 donutvolumio volumio[1173]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect Dec 06 11:46:29 donutvolumio volumio[1173]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Dec 06 11:46:29 donutvolumio sudo[2282]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 06 11:46:29 donutvolumio sudo[2282]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 11:46:29 donutvolumio volumio[1173]: info: QobuzConnect: Starting Qobuz Connect socket and service Dec 06 11:46:29 donutvolumio sudo[2282]: pam_unix(sudo:session): session closed for user root Dec 06 11:46:29 donutvolumio volumio[1173]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Dec 06 11:46:29 donutvolumio volumio[1173]: info: Stopping AccessToken refresher cron for QOBUZ Dec 06 11:46:29 donutvolumio sudo[2290]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 06 11:46:29 donutvolumio sudo[2290]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 11:46:29 donutvolumio sudo[2290]: pam_unix(sudo:session): session closed for user root Dec 06 11:46:30 donutvolumio volumio[1173]: info: AccessToken refresher cron started for QOBUZ Dec 06 11:46:30 donutvolumio volumio[1173]: info: Adding QOBUZ REST API Endpoints Dec 06 11:46:30 donutvolumio sudo[2292]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 06 11:46:30 donutvolumio sudo[2292]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 11:46:30 donutvolumio volumio[1173]: info: Initializing connection to go-librespot Websocket Dec 06 11:46:30 donutvolumio volumio[1173]: info: MRS: Getting audio outputs on start Dec 06 11:46:30 donutvolumio volumio[1173]: info: MRS: Requesting all other devices output Dec 06 11:46:30 donutvolumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 06 11:46:30 donutvolumio sudo[2292]: pam_unix(sudo:session): session closed for user root Dec 06 11:46:30 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 06 11:46:30 donutvolumio volumio[1173]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 06 11:46:30 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 06 11:46:30 donutvolumio volumio[1173]: info: Received Get System Version Dec 06 11:46:30 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 06 11:46:30 donutvolumio volumio[1173]: info: Received Get System Info Dec 06 11:46:30 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 06 11:46:30 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 06 11:46:30 donutvolumio volumio[1173]: info: Discovery: Getting this device information Dec 06 11:46:30 donutvolumio volumio[1173]: info: CoreCommandRouter::volumioGetState Dec 06 11:46:30 donutvolumio volumio[1173]: info: CorePlayQueue::getTrack 0 Dec 06 11:46:30 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 06 11:46:30 donutvolumio volumio[1173]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Dec 06 11:46:30 donutvolumio volumio[1173]: ------------------------------------ BT MESSAGE: Bluetooth adapter powered on Dec 06 11:46:30 donutvolumio volumio[1173]: info: MPD Permissions set Dec 06 11:46:30 donutvolumio sudo[2310]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Dec 06 11:46:30 donutvolumio sudo[2310]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 11:46:30 donutvolumio systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Dec 06 11:46:30 donutvolumio sudo[2310]: pam_unix(sudo:session): session closed for user root Dec 06 11:46:30 donutvolumio volumiobt[2314]: INFO [BTSTART] Ensuring Bluetooth directory exists... Dec 06 11:46:30 donutvolumio sudo[2315]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth Dec 06 11:46:30 donutvolumio sudo[2315]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 11:46:30 donutvolumio sudo[2315]: pam_unix(sudo:session): session closed for user root Dec 06 11:46:31 donutvolumio sudo[2317]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth Dec 06 11:46:31 donutvolumio sudo[2317]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 11:46:31 donutvolumio sudo[2317]: pam_unix(sudo:session): session closed for user root Dec 06 11:46:31 donutvolumio volumiobt[2319]: INFO [BTSTART] Powering on Bluetooth if needed... Dec 06 11:46:31 donutvolumio volumio[1173]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 06 11:46:31 donutvolumio bluetoothd[790]: Path / reserved for Adv Monitor app :1.26 Dec 06 11:46:31 donutvolumio bluetoothd[790]: Adv Monitor app :1.26 disconnected from D-Bus Dec 06 11:46:31 donutvolumio volumiobt[2322]: INFO [BTSTART] Making Bluetooth discoverable and pairable... Dec 06 11:46:31 donutvolumio volumiobt[2323]: [176B blob data] Dec 06 11:46:31 donutvolumio volumiobt[2323]: [157B blob data] Dec 06 11:46:31 donutvolumio volumiobt[2323]: [157B blob data] Dec 06 11:46:31 donutvolumio volumiobt[2323]: [157B blob data] Dec 06 11:46:31 donutvolumio volumiobt[2323]: [113B blob data] Dec 06 11:46:31 donutvolumio volumiobt[2323]: [bluetoothctl]> discoverable on Dec 06 11:46:31 donutvolumio volumiobt[2323]: Warning: setting discoverable while discoverable-timeout not set(0) is not recommended Dec 06 11:46:31 donutvolumio volumiobt[2323]: [bluetoothctl]> pairable on Dec 06 11:46:31 donutvolumio bluetoothd[790]: Path / reserved for Adv Monitor app :1.27 Dec 06 11:46:31 donutvolumio bluetoothd[790]: Adv Monitor app :1.27 disconnected from D-Bus Dec 06 11:46:31 donutvolumio volumiobt[2323]: [bluetoothctl]> Dec 06 11:46:31 donutvolumio volumiobt[2324]: INFO [BTSTART] Registering Bluetooth agent... Dec 06 11:46:31 donutvolumio volumiobt[2325]: [NEW] Media /org/bluez/hci0 Dec 06 11:46:31 donutvolumio volumiobt[2325]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Dec 06 11:46:31 donutvolumio volumiobt[2325]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Dec 06 11:46:31 donutvolumio volumiobt[2325]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb Dec 06 11:46:31 donutvolumio bluetoothd[790]: Adv Monitor app :1.28 disconnected from D-Bus Dec 06 11:46:31 donutvolumio volumiobt[2326]: No agent is registered Dec 06 11:46:31 donutvolumio volumiobt[2326]: [NEW] Media /org/bluez/hci0 Dec 06 11:46:31 donutvolumio volumiobt[2326]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Dec 06 11:46:31 donutvolumio volumiobt[2326]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Dec 06 11:46:31 donutvolumio volumiobt[2326]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb Dec 06 11:46:31 donutvolumio bluetoothd[790]: Adv Monitor app :1.29 disconnected from D-Bus Dec 06 11:46:31 donutvolumio volumiobt[2327]: INFO [BTSTART] Agent registered successfully. Dec 06 11:46:31 donutvolumio volumiobt[2328]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... Dec 06 11:46:31 donutvolumio volumio[1173]: info: Executing endpoint qc_getconfig Dec 06 11:46:31 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 06 11:46:31 donutvolumio qobuz-connect[2294]: 20251206 11:46:31.624 [2294.2294] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 06 11:46:31 donutvolumio volumio[1173]: ------------------------------------ BT MESSAGE: volumiobt.service started successfully Dec 06 11:46:31 donutvolumio volumio[1173]: info: CoreCommandRouter::volumioStop Dec 06 11:46:31 donutvolumio volumio[1173]: info: CoreStateMachine::stop Dec 06 11:46:31 donutvolumio volumio[1173]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 06 11:46:31 donutvolumio volumio[1173]: ------------------------------------ BT MESSAGE: [FUNC] dbusStart Dec 06 11:46:31 donutvolumio volumio[1173]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 06 11:46:31 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 11:46:31 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 11:46:31 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 11:46:31 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 11:46:31 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 11:46:31 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 11:46:31 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 11:46:31 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 11:46:31 donutvolumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14. Dec 06 11:46:31 donutvolumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 06 11:46:31 donutvolumio qobuz-connect[2294]: 20251206 11:46:31.741 [2294.2294] INFO VolumeManager: [0x149db8]: Setting new playback volume: 75 Dec 06 11:46:31 donutvolumio qobuz-connect[2294]: 20251206 11:46:31.741 [2294.2294] INFO VolumeManager: [0x149db8]: Setting new mute state: 0 Dec 06 11:46:31 donutvolumio qobuz-connect[2294]: 20251206 11:46:31.741 [2294.2294] INFO QobuzConnect: [0x14a788]: Client initialized! Dec 06 11:46:31 donutvolumio qobuz-connect[2294]: 20251206 11:46:31.742 [2294.2294] INFO SampleApp: Starting Avahi advertising, name: Donut_Volumio, service name: _qobuz-connect._tcp Dec 06 11:46:31 donutvolumio qobuz-connect[2294]: 20251206 11:46:31.772 [2294.2294] INFO LocalConfigManager: [0x149698]: Starting Local Configuration server Dec 06 11:46:31 donutvolumio qobuz-connect[2294]: 20251206 11:46:31.772 [2294.2294] INFO SampleApp: Starting Local configuration server Dec 06 11:46:31 donutvolumio qobuz-connect[2294]: 20251206 11:46:31.773 [2294.2294] INFO SampleApp: Connected to UNIX socket client 0x134818 Dec 06 11:46:31 donutvolumio qobuz-connect[2294]: 20251206 11:46:31.774 [2294.2294] INFO SampleApp: Playback volume changed: 75 Dec 06 11:46:31 donutvolumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 06 11:46:31 donutvolumio go-librespot[2334]: go-librespot daemon starting... Dec 06 11:46:31 donutvolumio go-librespot[2337]: time="2025-12-06T11:46:31+08:00" level=info msg="running go-librespot 0.4.0" Dec 06 11:46:31 donutvolumio go-librespot[2337]: time="2025-12-06T11:46:31+08:00" level=debug msg="app state loaded" Dec 06 11:46:31 donutvolumio go-librespot[2337]: time="2025-12-06T11:46:31+08:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 06 11:46:31 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 11:46:31 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 11:46:32 donutvolumio volumio[1173]: info: Access Token successfully retrieved Dec 06 11:46:32 donutvolumio volumio[1173]: error: updateQueue error: null Dec 06 11:46:32 donutvolumio volumiobt[2329]: INFO:a2dp-agent:Connecting to system bus. Dec 06 11:46:32 donutvolumio volumiobt[2329]: INFO:a2dp-agent:Found Bluetooth adapter at: /org/bluez/hci0 Dec 06 11:46:32 donutvolumio volumiobt[2329]: INFO:a2dp-agent:Enabling infinite discovery on adapter. Dec 06 11:46:32 donutvolumio go-librespot[2337]: time="2025-12-06T11:46:32+08: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]" Dec 06 11:46:32 donutvolumio go-librespot[2337]: time="2025-12-06T11:46:32+08: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]" Dec 06 11:46:32 donutvolumio go-librespot[2337]: time="2025-12-06T11:46:32+08: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]" Dec 06 11:46:32 donutvolumio go-librespot[2337]: time="2025-12-06T11:46:32+08:00" level=info msg="zeroconf server listening on port 46617" Dec 06 11:46:32 donutvolumio volumiobt[2329]: INFO:a2dp-agent:Registering agent. Dec 06 11:46:32 donutvolumio volumiobt[2329]: INFO:a2dp-agent:Searching for MediaPlayer1 interface... Dec 06 11:46:32 donutvolumio volumiobt[2329]: WARNING:a2dp-agent:No active MediaPlayer1 found. Metadata updates won't work. Dec 06 11:46:32 donutvolumio go-librespot[2337]: time="2025-12-06T11:46:32+08:00" level=debug msg="obtained new client token: AADippUEesf2DVAGIzEpeFDrhiAufQ3SoTz01xXO5IjVk3xEBSqVlelSHxICI/GJDa767zFZoB4/nuZw5/VAjN40+L2PzVEWjBfJa29FGipYvB836CEofSp7AVMzwHDYTycaZChYZJxukduLEnGlyCqfPs1+Gs3i3RmrxQQQ6SRas5OEKH4zw1tRc9opptNf+GvgzKyqqoJL6Ix66ohaFptLTJlLAotv+fEwqKC5qd9NEaGl8zYv6XdE" Dec 06 11:46:32 donutvolumio go-librespot[2337]: time="2025-12-06T11:46:32+08:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Dec 06 11:46:32 donutvolumio go-librespot[2337]: time="2025-12-06T11:46:32+08:00" level=debug msg="completed keyexchange" Dec 06 11:46:32 donutvolumio go-librespot[2337]: time="2025-12-06T11:46:32+08:00" level=debug msg="completed challenge" Dec 06 11:46:32 donutvolumio go-librespot[2337]: time="2025-12-06T11:46:32+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 06 11:46:32 donutvolumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 06 11:46:32 donutvolumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 06 11:46:32 donutvolumio volumio[1173]: info: Adding audio output: Dec 06 11:46:32 donutvolumio volumio[1173]: info: Adding audio output: Dec 06 11:46:32 donutvolumio volumio[1173]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 06 11:46:32 donutvolumio volumio[1173]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 06 11:46:32 donutvolumio volumio[1173]: info: CoreCommandRouter::volumioGetState Dec 06 11:46:32 donutvolumio volumio[1173]: info: TidalConnect service stoped! Dec 06 11:46:32 donutvolumio volumio[1173]: info: Starting Shairport Sync Dec 06 11:46:32 donutvolumio sudo[2354]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 06 11:46:32 donutvolumio sudo[2354]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 11:46:33 donutvolumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 06 11:46:33 donutvolumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 06 11:46:33 donutvolumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 06 11:46:33 donutvolumio systemd[1]: shairport-sync.service: Consumed 1.290s CPU time. Dec 06 11:46:33 donutvolumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 06 11:46:33 donutvolumio sudo[2354]: pam_unix(sudo:session): session closed for user root Dec 06 11:46:33 donutvolumio volumio[1173]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 06 11:46:33 donutvolumio volumio[1173]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 06 11:46:33 donutvolumio volumio[1173]: info: CoreCommandRouter::volumioGetState Dec 06 11:46:33 donutvolumio volumio[1173]: info: Shairport-Sync Started Dec 06 11:46:33 donutvolumio sudo[2376]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 06 11:46:33 donutvolumio sudo[2376]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 11:46:33 donutvolumio volumio[1173]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.Kk1vDwa8e2Uehxm7loTVRE6Kq9F2.12936f8cbdf64d326593a5acc844d65d.state.status' Dec 06 11:46:33 donutvolumio volumio[1173]: info: Successfully retrieved User Session From TIDAL Dec 06 11:46:33 donutvolumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Dec 06 11:46:33 donutvolumio sudo[2376]: pam_unix(sudo:session): session closed for user root Dec 06 11:46:33 donutvolumio sudo[2382]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 06 11:46:33 donutvolumio sudo[2382]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 11:46:33 donutvolumio volumio[1173]: info: Executing endpoint tc_getconfig Dec 06 11:46:33 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 06 11:46:33 donutvolumio vtcs[2379]: STARTING TidalConnect services, version: 1.5.2.56 Dec 06 11:46:33 donutvolumio vtcs[2379]: STARTED TidalConnect services. Dec 06 11:46:34 donutvolumio volumio[1173]: info: Executing endpoint tc_connect Dec 06 11:46:34 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Dec 06 11:46:34 donutvolumio volumio[1173]: info: Connecting to TidalConnect Dec 06 11:46:34 donutvolumio volumio[1173]: info: Initializing connection to go-librespot Websocket Dec 06 11:46:34 donutvolumio volumio[1173]: info: Successfully retrieved User Subscription From TIDAL Dec 06 11:46:34 donutvolumio volumio[1173]: info: Adding TIDAL to Browse Sources Dec 06 11:46:34 donutvolumio volumio[1173]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 06 11:46:34 donutvolumio volumio[1173]: info: [1764992794075] CoreMusicLibrary::Adding element TIDAL Dec 06 11:46:34 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 06 11:46:34 donutvolumio volumio[1173]: Cannot find translation for source TIDAL Dec 06 11:46:34 donutvolumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 06 11:46:34 donutvolumio systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 06 11:46:34 donutvolumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 06 11:46:34 donutvolumio volumio[1173]: info: Adding TIDAL REST API Endpoints Dec 06 11:46:34 donutvolumio volumio[1173]: info: CoreCommandRouter::servicePushState Dec 06 11:46:34 donutvolumio volumio[1173]: info: CoreStateMachine::pushState Dec 06 11:46:34 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 06 11:46:34 donutvolumio volumio[1173]: info: CoreCommandRouter::volumioPushState Dec 06 11:46:34 donutvolumio volumio[1173]: info: MRS: Pushing multiroomSync output update for this device Dec 06 11:46:34 donutvolumio volumio[1173]: info: MRS: Pushing multiroomSync output Dec 06 11:46:34 donutvolumio volumio[1173]: info: CoreCommandRouter::volumioGetState Dec 06 11:46:34 donutvolumio volumio[1173]: info: CorePlayQueue::getTrack 0 Dec 06 11:46:34 donutvolumio volumio[1173]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect Dec 06 11:46:34 donutvolumio volumio[1173]: info: CoreCommandRouter::servicePushState Dec 06 11:46:34 donutvolumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 06 11:46:34 donutvolumio mpd_monitor.sh[2390]: MPD Monitor Service: Starting MPD Monitor Service Dec 06 11:46:34 donutvolumio sudo[2382]: pam_unix(sudo:session): session closed for user root Dec 06 11:46:34 donutvolumio volumio[1173]: info: CoreStateMachine::pushState Dec 06 11:46:34 donutvolumio volumio[1173]: info: CoreCommandRouter::volumioPushState Dec 06 11:46:34 donutvolumio volumio[1173]: info: MRS: Pushing multiroomSync output update for this device Dec 06 11:46:34 donutvolumio volumio[1173]: info: MRS: Pushing multiroomSync output Dec 06 11:46:34 donutvolumio volumio[1173]: info: CoreCommandRouter::volumioGetState Dec 06 11:46:34 donutvolumio volumio[1173]: info: CorePlayQueue::getTrack 0 Dec 06 11:46:34 donutvolumio volumio[1173]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect Dec 06 11:46:34 donutvolumio volumio[1173]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.Kk1vDwa8e2Uehxm7loTVRE6Kq9F2.12936f8cbdf64d326593a5acc844d65d.state.status' Dec 06 11:46:34 donutvolumio volumio[1173]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 06 11:46:34 donutvolumio volumio[1173]: info: Successfully started MPD Monitor Dec 06 11:46:35 donutvolumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15. Dec 06 11:46:35 donutvolumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 06 11:46:35 donutvolumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 06 11:46:35 donutvolumio go-librespot[2402]: go-librespot daemon starting... Dec 06 11:46:35 donutvolumio go-librespot[2403]: time="2025-12-06T11:46:35+08:00" level=info msg="running go-librespot 0.4.0" Dec 06 11:46:35 donutvolumio go-librespot[2403]: time="2025-12-06T11:46:35+08:00" level=debug msg="app state loaded" Dec 06 11:46:35 donutvolumio go-librespot[2403]: time="2025-12-06T11:46:35+08:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 06 11:46:35 donutvolumio go-librespot[2403]: time="2025-12-06T11:46:35+08: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]" Dec 06 11:46:35 donutvolumio go-librespot[2403]: time="2025-12-06T11:46:35+08: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]" Dec 06 11:46:35 donutvolumio go-librespot[2403]: time="2025-12-06T11:46:35+08: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]" Dec 06 11:46:35 donutvolumio go-librespot[2403]: time="2025-12-06T11:46:35+08:00" level=info msg="zeroconf server listening on port 46433" Dec 06 11:46:35 donutvolumio go-librespot[2403]: time="2025-12-06T11:46:35+08:00" level=debug msg="obtained new client token: AABqh6CqsBQ7DDV6xxSL/nd+6/vRXIpknB+N6T+xXQ3vfAkxbQPY+Jh80kxsJygR3XLBwwdIuFux9ada1fxbdB887bU6zwaMKx/1ZoV3RcBaJlCajq5jM8qm3e3EDL4XUTKiqUFelJlN1/+9GzUn07JyYlLA3/DQn19QrxDLGpYZRqRphqFKyo/0a0sfxk/Ap3eC5R0ulBjmcYY72dm8q9bWA+iUmEo6uJwiiMGEha3bVjOq+6+eR4v3LvU=" Dec 06 11:46:35 donutvolumio go-librespot[2403]: time="2025-12-06T11:46:35+08:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Dec 06 11:46:36 donutvolumio go-librespot[2403]: time="2025-12-06T11:46:36+08:00" level=debug msg="completed keyexchange" Dec 06 11:46:36 donutvolumio go-librespot[2403]: time="2025-12-06T11:46:36+08:00" level=debug msg="completed challenge" Dec 06 11:46:36 donutvolumio go-librespot[2403]: time="2025-12-06T11:46:36+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 06 11:46:36 donutvolumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 06 11:46:36 donutvolumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 06 11:46:36 donutvolumio volumio[1173]: info: TidalConnect service started! Dec 06 11:46:36 donutvolumio volumio[1173]: [Metrics] CommandRouter: 80s 784.18ms Dec 06 11:46:36 donutvolumio volumio[1173]: info: CoreCommandRouter::volumiosetStartupVolume Dec 06 11:46:36 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 11:46:36 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 11:46:36 donutvolumio volumio[1173]: info: CoreCommandRouter::Close All Modals sent Dec 06 11:46:36 donutvolumio volumio[1173]: info: CoreCommandRouter::Close All Modals sent Dec 06 11:46:37 donutvolumio volumio[1173]: info: Initializing connection to go-librespot Websocket Dec 06 11:46:37 donutvolumio volumio[1173]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 06 11:46:37 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 06 11:46:37 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 06 11:46:37 donutvolumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 06 11:46:39 donutvolumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16. Dec 06 11:46:39 donutvolumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 06 11:46:39 donutvolumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 06 11:46:39 donutvolumio go-librespot[2415]: go-librespot daemon starting... Dec 06 11:46:39 donutvolumio go-librespot[2416]: time="2025-12-06T11:46:39+08:00" level=info msg="running go-librespot 0.4.0" Dec 06 11:46:39 donutvolumio go-librespot[2416]: time="2025-12-06T11:46:39+08:00" level=debug msg="app state loaded" Dec 06 11:46:39 donutvolumio go-librespot[2416]: time="2025-12-06T11:46:39+08:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 06 11:46:39 donutvolumio go-librespot[2416]: time="2025-12-06T11:46:39+08: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]" Dec 06 11:46:39 donutvolumio go-librespot[2416]: time="2025-12-06T11:46:39+08: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]" Dec 06 11:46:39 donutvolumio go-librespot[2416]: time="2025-12-06T11:46:39+08: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]" Dec 06 11:46:39 donutvolumio go-librespot[2416]: time="2025-12-06T11:46:39+08:00" level=info msg="zeroconf server listening on port 36165" Dec 06 11:46:39 donutvolumio go-librespot[2416]: time="2025-12-06T11:46:39+08:00" level=debug msg="obtained new client token: AACtYMwPB41IO7W+Czx/bbda1EOmF0aZE8c+1RSyAL+ZPM//gvhEGyfZUQwh6XCOqKRq6+vxCKDgTVW0m6QaWMPgliVKTLS8p2xl/yMqfXiD2+252YLFGQj4jAFZFwOmzzhXQYJoE+5XpuPJrKr5HELPIvDOTnFCN5XvokAqD0FV6w8NhlCIcdrv0E6ZkqZEoEPt9Wxj7JMO6OG6o7lg79k7He6wMQkNRvWVx0nH9hRSr1aqZQCTyFWRGcE=" Dec 06 11:46:39 donutvolumio go-librespot[2416]: time="2025-12-06T11:46:39+08:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Dec 06 11:46:39 donutvolumio go-librespot[2416]: time="2025-12-06T11:46:39+08:00" level=debug msg="completed keyexchange" Dec 06 11:46:39 donutvolumio go-librespot[2416]: time="2025-12-06T11:46:39+08:00" level=debug msg="completed challenge" Dec 06 11:46:39 donutvolumio go-librespot[2416]: time="2025-12-06T11:46:39+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 06 11:46:39 donutvolumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 06 11:46:39 donutvolumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 06 11:46:40 donutvolumio volumio[1173]: info: Initializing connection to go-librespot Websocket Dec 06 11:46:40 donutvolumio volumio[1173]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 06 11:46:42 donutvolumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17. Dec 06 11:46:42 donutvolumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 06 11:46:43 donutvolumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 06 11:46:43 donutvolumio go-librespot[2437]: go-librespot daemon starting... Dec 06 11:46:43 donutvolumio go-librespot[2438]: time="2025-12-06T11:46:43+08:00" level=info msg="running go-librespot 0.4.0" Dec 06 11:46:43 donutvolumio go-librespot[2438]: time="2025-12-06T11:46:43+08:00" level=debug msg="app state loaded" Dec 06 11:46:43 donutvolumio go-librespot[2438]: time="2025-12-06T11:46:43+08:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 06 11:46:43 donutvolumio go-librespot[2438]: time="2025-12-06T11:46:43+08: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]" Dec 06 11:46:43 donutvolumio go-librespot[2438]: time="2025-12-06T11:46:43+08: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]" Dec 06 11:46:43 donutvolumio go-librespot[2438]: time="2025-12-06T11:46:43+08: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]" Dec 06 11:46:43 donutvolumio go-librespot[2438]: time="2025-12-06T11:46:43+08:00" level=info msg="zeroconf server listening on port 40257" Dec 06 11:46:43 donutvolumio go-librespot[2438]: time="2025-12-06T11:46:43+08:00" level=debug msg="obtained new client token: AAArZChH6/MtFpyRZ+Q0k5tMVM6in2k1cptd+JWY7ujyHZkJwsHWh36KpARK4lIQ64Uq3zSECL3mJBaI8EEVgVr46VSuGL/e0KN1GBY1iTW1Flf1aeCLGS8bgRn08MGfi3httXZASPmyfiUXQ+HupfW0WHYRIaPTGbLFsioAttrADqh6xEWbc8EWmxl22GnnTDEEVjPelRANB1fhEnOpDkFdy9xJVCIn0qlTzVaIdQAk1+8XH50aLqxh5pE=" Dec 06 11:46:43 donutvolumio go-librespot[2438]: time="2025-12-06T11:46:43+08:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Dec 06 11:46:43 donutvolumio go-librespot[2438]: time="2025-12-06T11:46:43+08:00" level=debug msg="completed keyexchange" Dec 06 11:46:43 donutvolumio go-librespot[2438]: time="2025-12-06T11:46:43+08:00" level=debug msg="completed challenge" Dec 06 11:46:43 donutvolumio go-librespot[2438]: time="2025-12-06T11:46:43+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 06 11:46:43 donutvolumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 06 11:46:43 donutvolumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 06 11:46:43 donutvolumio volumio[1173]: info: Initializing connection to go-librespot Websocket Dec 06 11:46:43 donutvolumio volumio[1173]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 06 11:46:43 donutvolumio volumio[1173]: info: BOOT COMPLETED Dec 06 11:46:46 donutvolumio volumio[1173]: info: Initializing connection to go-librespot Websocket Dec 06 11:46:46 donutvolumio volumio[1173]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 06 11:46:46 donutvolumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 18. Dec 06 11:46:46 donutvolumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 06 11:46:46 donutvolumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 06 11:46:46 donutvolumio go-librespot[2446]: go-librespot daemon starting... Dec 06 11:46:46 donutvolumio go-librespot[2447]: time="2025-12-06T11:46:46+08:00" level=info msg="running go-librespot 0.4.0" Dec 06 11:46:46 donutvolumio go-librespot[2447]: time="2025-12-06T11:46:46+08:00" level=debug msg="app state loaded" Dec 06 11:46:46 donutvolumio go-librespot[2447]: time="2025-12-06T11:46:46+08:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 06 11:46:47 donutvolumio go-librespot[2447]: time="2025-12-06T11:46:47+08: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]" Dec 06 11:46:47 donutvolumio go-librespot[2447]: time="2025-12-06T11:46:47+08: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]" Dec 06 11:46:47 donutvolumio go-librespot[2447]: time="2025-12-06T11:46:47+08: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]" Dec 06 11:46:47 donutvolumio go-librespot[2447]: time="2025-12-06T11:46:47+08:00" level=info msg="zeroconf server listening on port 46419" Dec 06 11:46:47 donutvolumio go-librespot[2447]: time="2025-12-06T11:46:47+08:00" level=debug msg="obtained new client token: AAB6k+ak9m04d1vrDVRsYjyd3Bz2hxoTvKcOAuAXv9MbPFk+yC97g/FXWYAd479yGJW4GH7w1RZLKSSYqXaEkWEmOQ3nS1EEG3U/7U0eDLCeqnhCXFZq8uPZuwXUt7XP7CTWRBF67ag6Afk70PSuQqsrCSRcoaqSZzQRg1ZfIbWrEr7+ixOrTzPqqefo6Lr+E6WsrE6+/uf4iPnGfvMhrOt2pMGd14UtAnZbdKcFHMVrDdjBjkQ/n1/x" Dec 06 11:46:47 donutvolumio go-librespot[2447]: time="2025-12-06T11:46:47+08:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Dec 06 11:46:47 donutvolumio go-librespot[2447]: time="2025-12-06T11:46:47+08:00" level=debug msg="completed keyexchange" Dec 06 11:46:47 donutvolumio go-librespot[2447]: time="2025-12-06T11:46:47+08:00" level=debug msg="completed challenge" Dec 06 11:46:47 donutvolumio go-librespot[2447]: time="2025-12-06T11:46:47+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 06 11:46:47 donutvolumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 06 11:46:47 donutvolumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 06 11:46:49 donutvolumio volumio[1173]: info: Initializing connection to go-librespot Websocket Dec 06 11:46:49 donutvolumio volumio[1173]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 06 11:46:50 donutvolumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 19. Dec 06 11:46:50 donutvolumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 06 11:46:50 donutvolumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 06 11:46:50 donutvolumio go-librespot[2455]: go-librespot daemon starting... Dec 06 11:46:50 donutvolumio go-librespot[2456]: time="2025-12-06T11:46:50+08:00" level=info msg="running go-librespot 0.4.0" Dec 06 11:46:50 donutvolumio go-librespot[2456]: time="2025-12-06T11:46:50+08:00" level=debug msg="app state loaded" Dec 06 11:46:50 donutvolumio go-librespot[2456]: time="2025-12-06T11:46:50+08:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 06 11:46:50 donutvolumio go-librespot[2456]: time="2025-12-06T11:46:50+08: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]" Dec 06 11:46:50 donutvolumio go-librespot[2456]: time="2025-12-06T11:46:50+08: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]" Dec 06 11:46:50 donutvolumio go-librespot[2456]: time="2025-12-06T11:46:50+08: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]" Dec 06 11:46:50 donutvolumio go-librespot[2456]: time="2025-12-06T11:46:50+08:00" level=info msg="zeroconf server listening on port 34705" Dec 06 11:46:50 donutvolumio go-librespot[2456]: time="2025-12-06T11:46:50+08:00" level=debug msg="obtained new client token: AAAzX90ePz8w9M4u/ilAF7lvJaDI4KCiLeOi6YdEHXoJrHpPBce3Je2tZDl4N+LFz47S96sJPrewpD/t7eeYmdrgNO9z/34CQtIoCm+UQTtnNhcVMMAqOFn1IQNPwFfaD9BmVfIx4y4vREwGox1i698MLGBmUOIJlN+0IAQrKxLMb5YT9d41fHoLtWRZcjlVkXZZJHe3YQmSx7Mr+tUjyDyna38VmxTllEgkY46b9Xu8O34pyc+1EOFZGXA=" Dec 06 11:46:50 donutvolumio go-librespot[2456]: time="2025-12-06T11:46:50+08: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" Dec 06 11:46:51 donutvolumio go-librespot[2456]: time="2025-12-06T11:46:51+08:00" level=debug msg="connected to ap-gae2.spotify.com:443" Dec 06 11:46:51 donutvolumio go-librespot[2456]: time="2025-12-06T11:46:51+08:00" level=debug msg="completed keyexchange" Dec 06 11:46:51 donutvolumio go-librespot[2456]: time="2025-12-06T11:46:51+08:00" level=debug msg="completed challenge" Dec 06 11:46:51 donutvolumio go-librespot[2456]: time="2025-12-06T11:46:51+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 06 11:46:51 donutvolumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 06 11:46:51 donutvolumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 06 11:46:52 donutvolumio volumio[1173]: info: Initializing connection to go-librespot Websocket Dec 06 11:46:52 donutvolumio volumio[1173]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 06 11:46:54 donutvolumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 20. Dec 06 11:46:54 donutvolumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 06 11:46:54 donutvolumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 06 11:46:54 donutvolumio go-librespot[2480]: go-librespot daemon starting... Dec 06 11:46:54 donutvolumio go-librespot[2483]: time="2025-12-06T11:46:54+08:00" level=info msg="running go-librespot 0.4.0" Dec 06 11:46:54 donutvolumio go-librespot[2483]: time="2025-12-06T11:46:54+08:00" level=debug msg="app state loaded" Dec 06 11:46:54 donutvolumio go-librespot[2483]: time="2025-12-06T11:46:54+08:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 06 11:46:54 donutvolumio go-librespot[2483]: time="2025-12-06T11:46:54+08: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]" Dec 06 11:46:54 donutvolumio go-librespot[2483]: time="2025-12-06T11:46:54+08: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]" Dec 06 11:46:54 donutvolumio go-librespot[2483]: time="2025-12-06T11:46:54+08: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]" Dec 06 11:46:54 donutvolumio go-librespot[2483]: time="2025-12-06T11:46:54+08:00" level=info msg="zeroconf server listening on port 38531" Dec 06 11:46:54 donutvolumio go-librespot[2483]: time="2025-12-06T11:46:54+08:00" level=debug msg="obtained new client token: AAAnbtbTvSI04OGrfU7aGDLVvFc8ooEmRilT3wJLw0tVJqeT+1rarFu3Yl7HljJatmGQk3gf3p2vHYQ4Zgioq78y5D0xbeFFZmyODdFjcX4ohvJcdrK/yKlI8RpjTWP9bu/3cIoROPQQ3ZvlXXjuY02LXOhNyBkGh3W0FRTm/Vj686J+p+exc5+fD+NXRr/KznAPUG3ah5zRSgKZJJ+8TCaocFie/iCHmeNBe0rSwVmIiP1rWwPXGD7dr9o=" Dec 06 11:46:54 donutvolumio go-librespot[2483]: time="2025-12-06T11:46:54+08:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Dec 06 11:46:54 donutvolumio go-librespot[2483]: time="2025-12-06T11:46:54+08:00" level=debug msg="completed keyexchange" Dec 06 11:46:54 donutvolumio go-librespot[2483]: time="2025-12-06T11:46:54+08:00" level=debug msg="completed challenge" Dec 06 11:46:54 donutvolumio go-librespot[2483]: time="2025-12-06T11:46:54+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 06 11:46:54 donutvolumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 06 11:46:54 donutvolumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 06 11:46:55 donutvolumio volumio[1173]: info: Initializing connection to go-librespot Websocket Dec 06 11:46:55 donutvolumio volumio[1173]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 06 11:46:57 donutvolumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 21. Dec 06 11:46:57 donutvolumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 06 11:46:58 donutvolumio go-librespot[2491]: go-librespot daemon starting... Dec 06 11:46:58 donutvolumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 06 11:46:58 donutvolumio go-librespot[2492]: time="2025-12-06T11:46:58+08:00" level=info msg="running go-librespot 0.4.0" Dec 06 11:46:58 donutvolumio go-librespot[2492]: time="2025-12-06T11:46:58+08:00" level=debug msg="app state loaded" Dec 06 11:46:58 donutvolumio go-librespot[2492]: time="2025-12-06T11:46:58+08:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 06 11:46:58 donutvolumio go-librespot[2492]: time="2025-12-06T11:46:58+08: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]" Dec 06 11:46:58 donutvolumio go-librespot[2492]: time="2025-12-06T11:46:58+08: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]" Dec 06 11:46:58 donutvolumio go-librespot[2492]: time="2025-12-06T11:46:58+08: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]" Dec 06 11:46:58 donutvolumio go-librespot[2492]: time="2025-12-06T11:46:58+08:00" level=info msg="zeroconf server listening on port 41293" Dec 06 11:46:58 donutvolumio go-librespot[2492]: time="2025-12-06T11:46:58+08:00" level=debug msg="obtained new client token: AACv0AOQj9o7110EWzH6ee0ApKC8A8paKttMR8bRJQqicWONDcgL4b2Ac9mZY1eNm2GYWPdHLu3uUsS9r8HN0f/rLl3OO+TPA9zg31rP7mEjpX/odmBLU2Wx9nyhl42hsEsRGbgxbOTKpM1dNH2Y3624HQgtHdj9f9x7bSMtYBlfYNbR3M8nFFzvwoxI0FVnfdEmsCVjrg6Jwxno7qHIbMFNV99bvsSF9HQ3ClfHhOCJUYMh183YRLz0qCM=" Dec 06 11:46:58 donutvolumio go-librespot[2492]: time="2025-12-06T11:46:58+08:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Dec 06 11:46:58 donutvolumio go-librespot[2492]: time="2025-12-06T11:46:58+08:00" level=debug msg="completed keyexchange" Dec 06 11:46:58 donutvolumio go-librespot[2492]: time="2025-12-06T11:46:58+08:00" level=debug msg="completed challenge" Dec 06 11:46:58 donutvolumio go-librespot[2492]: time="2025-12-06T11:46:58+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 06 11:46:58 donutvolumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 06 11:46:58 donutvolumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 06 11:46:58 donutvolumio volumio[1173]: info: Initializing connection to go-librespot Websocket Dec 06 11:46:58 donutvolumio volumio[1173]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 06 11:47:01 donutvolumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22. Dec 06 11:47:01 donutvolumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 06 11:47:01 donutvolumio volumio[1173]: info: Initializing connection to go-librespot Websocket Dec 06 11:47:01 donutvolumio volumio[1173]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 06 11:47:01 donutvolumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 06 11:47:01 donutvolumio go-librespot[2514]: go-librespot daemon starting... Dec 06 11:47:01 donutvolumio go-librespot[2515]: time="2025-12-06T11:47:01+08:00" level=info msg="running go-librespot 0.4.0" Dec 06 11:47:01 donutvolumio go-librespot[2515]: time="2025-12-06T11:47:01+08:00" level=debug msg="app state loaded" Dec 06 11:47:01 donutvolumio go-librespot[2515]: time="2025-12-06T11:47:01+08:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 06 11:47:02 donutvolumio go-librespot[2515]: time="2025-12-06T11:47:02+08: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]" Dec 06 11:47:02 donutvolumio go-librespot[2515]: time="2025-12-06T11:47:02+08: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]" Dec 06 11:47:02 donutvolumio go-librespot[2515]: time="2025-12-06T11:47:02+08: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]" Dec 06 11:47:02 donutvolumio go-librespot[2515]: time="2025-12-06T11:47:02+08:00" level=info msg="zeroconf server listening on port 34969" Dec 06 11:47:02 donutvolumio go-librespot[2515]: time="2025-12-06T11:47:02+08:00" level=debug msg="obtained new client token: AACF1U1nJx0fuRGsFb9++uNkSTqoy3fghI81cYPevDsILU1WMdCBWFHAA9liAxu8qBvtqBngzZ2rAb91jSXN+RMwmfPtFNQ7aYStIEzhjq4gRWb06+6QKwf+O8WZU3DjER6jvQFl8hfMrym8PqY2hTP/ZfAOzMYW9q+AF3EIumd+VP3PA6lsBghbw3oRfdIz0UBzBCp7rUTntXHm56TOMbWOHynCgt8uUuBkXc9mLuS5K2s9jGrjo+Je" Dec 06 11:47:02 donutvolumio go-librespot[2515]: time="2025-12-06T11:47:02+08:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Dec 06 11:47:02 donutvolumio go-librespot[2515]: time="2025-12-06T11:47:02+08:00" level=debug msg="completed keyexchange" Dec 06 11:47:02 donutvolumio go-librespot[2515]: time="2025-12-06T11:47:02+08:00" level=debug msg="completed challenge" Dec 06 11:47:02 donutvolumio go-librespot[2515]: time="2025-12-06T11:47:02+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 06 11:47:02 donutvolumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 06 11:47:02 donutvolumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 06 11:47:04 donutvolumio volumio[1173]: info: Initializing connection to go-librespot Websocket Dec 06 11:47:04 donutvolumio volumio[1173]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 06 11:47:05 donutvolumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 23. Dec 06 11:47:05 donutvolumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 06 11:47:05 donutvolumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 06 11:47:05 donutvolumio go-librespot[2523]: go-librespot daemon starting... Dec 06 11:47:05 donutvolumio go-librespot[2524]: time="2025-12-06T11:47:05+08:00" level=info msg="running go-librespot 0.4.0" Dec 06 11:47:05 donutvolumio go-librespot[2524]: time="2025-12-06T11:47:05+08:00" level=debug msg="app state loaded" Dec 06 11:47:05 donutvolumio go-librespot[2524]: time="2025-12-06T11:47:05+08:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 06 11:47:05 donutvolumio go-librespot[2524]: time="2025-12-06T11:47:05+08: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]" Dec 06 11:47:05 donutvolumio go-librespot[2524]: time="2025-12-06T11:47:05+08: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]" Dec 06 11:47:05 donutvolumio go-librespot[2524]: time="2025-12-06T11:47:05+08: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]" Dec 06 11:47:05 donutvolumio go-librespot[2524]: time="2025-12-06T11:47:05+08:00" level=info msg="zeroconf server listening on port 33575" Dec 06 11:47:05 donutvolumio go-librespot[2524]: time="2025-12-06T11:47:05+08:00" level=debug msg="obtained new client token: AAAOYG57UDF/lCgkPGPBhWYM2uUjucz5gujtvgQZnL1bHhu6ohXIO6BegBuqCDC/NDxtjgp4HHJv+MzirpaCZc9LfK+4XYnbVvO9bFWJhEZuSBDMr9mUNSBe4n2ghj/TP3s6nLiluu20lQ6vJvGSS3F/MofF4SLtnPSls3fQaps7YEB/bFGEzTxKkMBlOagEsB9avhxIGi5/Zuy86jUuX0v0zzhICNOwMS5glQ5g2QXXEdpTnm29IAZGRFU=" Dec 06 11:47:05 donutvolumio go-librespot[2524]: time="2025-12-06T11:47:05+08:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Dec 06 11:47:05 donutvolumio go-librespot[2524]: time="2025-12-06T11:47:05+08:00" level=debug msg="completed keyexchange" Dec 06 11:47:06 donutvolumio go-librespot[2524]: time="2025-12-06T11:47:06+08:00" level=debug msg="completed challenge" Dec 06 11:47:06 donutvolumio go-librespot[2524]: time="2025-12-06T11:47:06+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 06 11:47:06 donutvolumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 06 11:47:06 donutvolumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 06 11:47:07 donutvolumio volumio[1173]: info: Initializing connection to go-librespot Websocket Dec 06 11:47:07 donutvolumio volumio[1173]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 06 11:47:09 donutvolumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 24. Dec 06 11:47:09 donutvolumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 06 11:47:09 donutvolumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 06 11:47:09 donutvolumio go-librespot[2531]: go-librespot daemon starting... Dec 06 11:47:09 donutvolumio go-librespot[2532]: time="2025-12-06T11:47:09+08:00" level=info msg="running go-librespot 0.4.0" Dec 06 11:47:09 donutvolumio go-librespot[2532]: time="2025-12-06T11:47:09+08:00" level=debug msg="app state loaded" Dec 06 11:47:09 donutvolumio go-librespot[2532]: time="2025-12-06T11:47:09+08:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 06 11:47:09 donutvolumio go-librespot[2532]: time="2025-12-06T11:47:09+08: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]" Dec 06 11:47:09 donutvolumio go-librespot[2532]: time="2025-12-06T11:47:09+08: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]" Dec 06 11:47:09 donutvolumio go-librespot[2532]: time="2025-12-06T11:47:09+08: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]" Dec 06 11:47:09 donutvolumio go-librespot[2532]: time="2025-12-06T11:47:09+08:00" level=info msg="zeroconf server listening on port 33689" Dec 06 11:47:09 donutvolumio go-librespot[2532]: time="2025-12-06T11:47:09+08:00" level=debug msg="obtained new client token: AAAyqU5FifAeD+Vll4rOcICpA+kFKiIgvnOa0R2cpxGr0HSVW6izPXEGuoyI4UaT5JxXJPGaLzwIdZh2I4HPLvP1jF9AsVMNi/n2gSeSijFL328cot7l331DIDzPJ5wZEfFmRiei738wbwKBWaVbumEDcUeCg6H5aQhCBuVOJ5WhzAZXjmJgp2MJKjYBQ5MdOall/oqoKl+WZ3SOxyzQcx6+nUsHnVUgn/vFwitmX4ANI5Caw5d+CixX10E=" Dec 06 11:47:09 donutvolumio go-librespot[2532]: time="2025-12-06T11:47:09+08:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Dec 06 11:47:09 donutvolumio go-librespot[2532]: time="2025-12-06T11:47:09+08:00" level=debug msg="completed keyexchange" Dec 06 11:47:09 donutvolumio go-librespot[2532]: time="2025-12-06T11:47:09+08:00" level=debug msg="completed challenge" Dec 06 11:47:09 donutvolumio go-librespot[2532]: time="2025-12-06T11:47:09+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 06 11:47:09 donutvolumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 06 11:47:09 donutvolumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 06 11:47:10 donutvolumio volumio[1173]: info: Initializing connection to go-librespot Websocket Dec 06 11:47:10 donutvolumio volumio[1173]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 06 11:47:12 donutvolumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 25. Dec 06 11:47:12 donutvolumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 06 11:47:13 donutvolumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 06 11:47:13 donutvolumio go-librespot[2553]: go-librespot daemon starting... Dec 06 11:47:13 donutvolumio go-librespot[2554]: time="2025-12-06T11:47:13+08:00" level=info msg="running go-librespot 0.4.0" Dec 06 11:47:13 donutvolumio go-librespot[2554]: time="2025-12-06T11:47:13+08:00" level=debug msg="app state loaded" Dec 06 11:47:13 donutvolumio go-librespot[2554]: time="2025-12-06T11:47:13+08:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 06 11:47:13 donutvolumio go-librespot[2554]: time="2025-12-06T11:47:13+08: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]" Dec 06 11:47:13 donutvolumio go-librespot[2554]: time="2025-12-06T11:47:13+08: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]" Dec 06 11:47:13 donutvolumio go-librespot[2554]: time="2025-12-06T11:47:13+08: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]" Dec 06 11:47:13 donutvolumio go-librespot[2554]: time="2025-12-06T11:47:13+08:00" level=info msg="zeroconf server listening on port 43827" Dec 06 11:47:13 donutvolumio go-librespot[2554]: time="2025-12-06T11:47:13+08:00" level=debug msg="obtained new client token: AACI3/2wVzi57vTqjRluUK7VxB1KCNYH2652l2rBGTwAtZdnMtuSeU7PSWJ+AReHFkuu40G8ULbYt6ZWH8kjLL5Kx4A69MJAVBbgCp4FM5aLiZlAWUqXINKvliIzPEJB2vB5cu/mpI5w+Byh7kEdbxJc64OGYdix7ahRV9D7yg+lEeLL47UWKDD5CAMK5aSjhQptlL1E0qj/9CDYGy4qzUXU0U/mDbdCHYZQbmFV9TanfBBHskrPtqMAnMg=" Dec 06 11:47:13 donutvolumio go-librespot[2554]: time="2025-12-06T11:47:13+08:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Dec 06 11:47:13 donutvolumio go-librespot[2554]: time="2025-12-06T11:47:13+08:00" level=debug msg="completed keyexchange" Dec 06 11:47:13 donutvolumio go-librespot[2554]: time="2025-12-06T11:47:13+08:00" level=debug msg="completed challenge" Dec 06 11:47:13 donutvolumio go-librespot[2554]: time="2025-12-06T11:47:13+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 06 11:47:13 donutvolumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 06 11:47:13 donutvolumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 06 11:47:13 donutvolumio volumio[1173]: info: Initializing connection to go-librespot Websocket Dec 06 11:47:13 donutvolumio volumio[1173]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 06 11:47:16 donutvolumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 26. Dec 06 11:47:16 donutvolumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 06 11:47:16 donutvolumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 06 11:47:16 donutvolumio go-librespot[2564]: go-librespot daemon starting... Dec 06 11:47:16 donutvolumio go-librespot[2565]: time="2025-12-06T11:47:16+08:00" level=info msg="running go-librespot 0.4.0" Dec 06 11:47:16 donutvolumio go-librespot[2565]: time="2025-12-06T11:47:16+08:00" level=debug msg="app state loaded" Dec 06 11:47:16 donutvolumio go-librespot[2565]: time="2025-12-06T11:47:16+08:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 06 11:47:16 donutvolumio volumio[1173]: info: Initializing connection to go-librespot Websocket Dec 06 11:47:16 donutvolumio go-librespot[2565]: time="2025-12-06T11:47:16+08:00" level=debug msg="new websocket client" Dec 06 11:47:16 donutvolumio volumio[1173]: info: Connection to go-librespot Websocket established Dec 06 11:47:17 donutvolumio go-librespot[2565]: time="2025-12-06T11:47:17+08: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]" Dec 06 11:47:17 donutvolumio go-librespot[2565]: time="2025-12-06T11:47:17+08: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]" Dec 06 11:47:17 donutvolumio go-librespot[2565]: time="2025-12-06T11:47:17+08: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]" Dec 06 11:47:17 donutvolumio go-librespot[2565]: time="2025-12-06T11:47:17+08:00" level=info msg="zeroconf server listening on port 44009" Dec 06 11:47:17 donutvolumio go-librespot[2565]: time="2025-12-06T11:47:17+08:00" level=debug msg="obtained new client token: AAAzsxFlrzxweFlhSs/VV5RsfIEuA+OGoDs+4ulOYr6Qbuh5hMY4bxYJg2TaNcKOH6ILxkKLMpgd5lVwvD4WSkZ1SSNwkuqXWrx95EsPBbypj1Gxa85y1RGia0s3QT8yX+prYv8h8rjVsHydXfIhwSq7JuU7Ngyw/1m7cM+XIIr5sPVfDqttJNpWLCplPB93woxEqj1rJzoFVCuDT2+aXqqXDqmA4HXDa0KrK/qkis/YrrYD4yQQGFFX" Dec 06 11:47:17 donutvolumio go-librespot[2565]: time="2025-12-06T11:47:17+08:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Dec 06 11:47:17 donutvolumio go-librespot[2565]: time="2025-12-06T11:47:17+08:00" level=debug msg="completed keyexchange" Dec 06 11:47:17 donutvolumio go-librespot[2565]: time="2025-12-06T11:47:17+08:00" level=debug msg="completed challenge" Dec 06 11:47:17 donutvolumio go-librespot[2565]: time="2025-12-06T11:47:17+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 06 11:47:17 donutvolumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 06 11:47:17 donutvolumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 06 11:47:17 donutvolumio volumio[1173]: info: Connection to go-librespot Websocket closed Dec 06 11:47:19 donutvolumio volumio[1173]: info: Getting Spotify volume Dec 06 11:47:19 donutvolumio volumio[1173]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 06 11:47:20 donutvolumio volumio[1173]: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 06 11:47:20 donutvolumio volumio[1173]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Dec 06 11:47:20 donutvolumio volumio[1173]: errno: -111, Dec 06 11:47:20 donutvolumio volumio[1173]: code: 'ECONNREFUSED', Dec 06 11:47:20 donutvolumio volumio[1173]: syscall: 'connect', Dec 06 11:47:20 donutvolumio volumio[1173]: address: '127.0.0.1', Dec 06 11:47:20 donutvolumio volumio[1173]: port: 9879, Dec 06 11:47:20 donutvolumio volumio[1173]: response: undefined Dec 06 11:47:20 donutvolumio volumio[1173]: } Dec 06 11:47:20 donutvolumio volumio[1173]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 06 11:47:20 donutvolumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 27. Dec 06 11:47:20 donutvolumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 06 11:47:20 donutvolumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 06 11:47:20 donutvolumio go-librespot[2585]: go-librespot daemon starting... Dec 06 11:47:20 donutvolumio go-librespot[2586]: time="2025-12-06T11:47:20+08:00" level=info msg="running go-librespot 0.4.0" Dec 06 11:47:20 donutvolumio go-librespot[2586]: time="2025-12-06T11:47:20+08:00" level=debug msg="app state loaded" Dec 06 11:47:20 donutvolumio go-librespot[2586]: time="2025-12-06T11:47:20+08:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 06 11:47:20 donutvolumio go-librespot[2586]: time="2025-12-06T11:47:20+08: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]" Dec 06 11:47:20 donutvolumio go-librespot[2586]: time="2025-12-06T11:47:20+08: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]" Dec 06 11:47:20 donutvolumio go-librespot[2586]: time="2025-12-06T11:47:20+08: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]" Dec 06 11:47:20 donutvolumio go-librespot[2586]: time="2025-12-06T11:47:20+08:00" level=info msg="zeroconf server listening on port 43773" Dec 06 11:47:20 donutvolumio go-librespot[2586]: time="2025-12-06T11:47:20+08:00" level=debug msg="obtained new client token: AAARRumkf720xE5Lm6npAsczXqUuDxAvj1pddsbz2JgN6YwSoii6Qc92RrwsMUnHOc3BfN2p74c95vZRAoQzAg4+M6bQiOgsRs6/ZP7mBDU0xYiV5UhPPxNsd4hHorf6lRMJNGS8ouNuArseUC1vv3cB5GAPdyJJatzpWqrx9oqdZrLHZCJY30B5zyLobJ6sLqx8XYiWlLGZrYGtlEYM7ZvxWryr9eLSDfdFhmuS7XCjp9V29z+vlWpYeFo=" Dec 06 11:47:20 donutvolumio go-librespot[2586]: time="2025-12-06T11:47:20+08:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Dec 06 11:47:20 donutvolumio go-librespot[2586]: time="2025-12-06T11:47:20+08:00" level=debug msg="completed keyexchange" Dec 06 11:47:20 donutvolumio go-librespot[2586]: time="2025-12-06T11:47:20+08:00" level=debug msg="completed challenge" Dec 06 11:47:21 donutvolumio go-librespot[2586]: time="2025-12-06T11:47:21+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 06 11:47:21 donutvolumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 06 11:47:21 donutvolumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 06 11:47:23 donutvolumio sudo[2611]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-06 11:46' Dec 06 11:47:23 donutvolumio sudo[2611]: 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="3a3cfe435f8bb2bc94a920662e91a4ef35bc8e7c" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="8bcc10c3dbcbcb349e9887dc0527d54876b32688" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Dec 2 11:51:19 UTC 2025" VOLUMIO_VERSION="4.072" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e5001581b220b096097cf8f46a54d4f1"