Feb 07 17:17:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 21. Feb 07 17:17:01 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 17:17:01 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 17:17:01 volumio go-librespot[13105]: go-librespot daemon starting... Feb 07 17:17:01 volumio go-librespot[13106]: time="2026-02-07T17:17:01+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 17:17:01 volumio go-librespot[13106]: time="2026-02-07T17:17:01+07:00" level=debug msg="app state loaded" Feb 07 17:17:01 volumio go-librespot[13106]: time="2026-02-07T17:17:01+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 17:17:01 volumio go-librespot[13106]: time="2026-02-07T17:17:01+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 07 17:17:01 volumio go-librespot[13106]: time="2026-02-07T17:17:01+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 07 17:17:01 volumio go-librespot[13106]: time="2026-02-07T17:17:01+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 07 17:17:01 volumio volumio[12782]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 07 17:17:01 volumio go-librespot[13106]: time="2026-02-07T17:17:01+07:00" level=info msg="zeroconf server listening on port 37225" Feb 07 17:17:02 volumio go-librespot[13106]: time="2026-02-07T17:17:02+07:00" level=debug msg="obtained new client token: AADouDRRqxB1IWYjiyFt1+8/M3Zj69eYyBC1kuS1FqP/Ljs0AB0OEigkw6wJCBsN8Bh1DINGf0avkCuBvBfehv+UivdOjZXVylp6TYKalBcC2UF5yd6tYJ6XlIMkmnMvTy5rlzFODh+QEGqzMACXA3BL1Lm9hzTjfteNX/pnvZJq/9EtD16c2Z6F+yVPztNi6PSCy9ZwJ2LsqHzw1v7WVUrYjwe9KcTRoeTV5C32AyQeU28s5ZZpi40=" Feb 07 17:17:02 volumio go-librespot[13106]: time="2026-02-07T17:17:02+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 07 17:17:02 volumio go-librespot[13106]: time="2026-02-07T17:17:02+07:00" level=debug msg="completed keyexchange" Feb 07 17:17:02 volumio go-librespot[13106]: time="2026-02-07T17:17:02+07:00" level=debug msg="completed challenge" Feb 07 17:17:02 volumio go-librespot[13106]: time="2026-02-07T17:17:02+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 07 17:17:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 17:17:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 17:17:02 volumio volumio[12782]: info: Initializing connection to go-librespot Websocket Feb 07 17:17:02 volumio volumio[12782]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 07 17:17:05 volumio volumio[12782]: info: Initializing connection to go-librespot Websocket Feb 07 17:17:05 volumio volumio[12782]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 07 17:17:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22. Feb 07 17:17:05 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 17:17:05 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 17:17:05 volumio go-librespot[13114]: go-librespot daemon starting... Feb 07 17:17:05 volumio go-librespot[13115]: time="2026-02-07T17:17:05+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 17:17:05 volumio go-librespot[13115]: time="2026-02-07T17:17:05+07:00" level=debug msg="app state loaded" Feb 07 17:17:05 volumio go-librespot[13115]: time="2026-02-07T17:17:05+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 17:17:05 volumio volumio[12782]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 07 17:17:05 volumio volumio[12782]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 07 17:17:05 volumio volumio[12782]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 07 17:17:05 volumio volumio[12782]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 07 17:17:05 volumio volumio[12782]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 07 17:17:05 volumio volumio[12782]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 07 17:17:05 volumio volumio[12782]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 07 17:17:05 volumio volumio[12782]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 07 17:17:05 volumio volumio[12782]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 07 17:17:05 volumio volumio[12782]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 07 17:17:05 volumio volumio[12782]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 07 17:17:05 volumio volumio[12782]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 07 17:17:05 volumio volumio[12782]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 07 17:17:05 volumio volumio[12782]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 07 17:17:05 volumio volumio[12782]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 07 17:17:05 volumio volumio[12782]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 07 17:17:05 volumio volumio[12782]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 07 17:17:05 volumio volumio[12782]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 07 17:17:05 volumio volumio[12782]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 07 17:17:05 volumio volumio[12782]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 07 17:17:05 volumio volumio[12782]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 07 17:17:05 volumio volumio[12782]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 07 17:17:05 volumio volumio[12782]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 07 17:17:05 volumio volumio[12782]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 07 17:17:05 volumio volumio[12782]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 07 17:17:05 volumio volumio[12782]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 07 17:17:05 volumio volumio[12782]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 07 17:17:05 volumio volumio[12782]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 07 17:17:05 volumio volumio[12782]: info: Adding plugin bluetooth to MyMusic Plugins Feb 07 17:17:05 volumio volumio[12782]: info: Adding plugin multiroom to MyMusic Plugins Feb 07 17:17:05 volumio volumio[12782]: info: Adding plugin metavolumio to MyMusic Plugins Feb 07 17:17:05 volumio volumio[12782]: info: Adding plugin cd_controller to MyMusic Plugins Feb 07 17:17:05 volumio volumio[12782]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 07 17:17:05 volumio volumio[12782]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 07 17:17:05 volumio volumio[12782]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 07 17:17:06 volumio volumio[12782]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 07 17:17:06 volumio go-librespot[13115]: time="2026-02-07T17:17:06+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 07 17:17:06 volumio go-librespot[13115]: time="2026-02-07T17:17:06+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 07 17:17:06 volumio go-librespot[13115]: time="2026-02-07T17:17:06+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 07 17:17:06 volumio go-librespot[13115]: time="2026-02-07T17:17:06+07:00" level=info msg="zeroconf server listening on port 39445" Feb 07 17:17:06 volumio go-librespot[13115]: time="2026-02-07T17:17:06+07:00" level=debug msg="obtained new client token: AADiuG8oVIAmkMo4liAnOHyfn9iIDqQYhevFTi729jZb6ucKNguBMLxDhm6EpYpJrbVrOMpjvqK+Ksn5LiQjnMn7rsxQfLwXwxoOuNQ6fTB+YcDwpBxZQMEZuLB1w72iPCWyty/q1t/FLnsEkhbXh/OM1ANC/33rmxmkkoGsKX8KBr+D6Tw8rsa0qRci0nJmLK5g8crYfEZYbZ9WAjG8h6YeaN3SIt+pgf/ZUESWbZfXwFanT6CUV2qK0Q==" Feb 07 17:17:06 volumio go-librespot[13115]: time="2026-02-07T17:17:06+07:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.241.202:4070: connect: connection refused" Feb 07 17:17:06 volumio go-librespot[13115]: time="2026-02-07T17:17:06+07:00" level=debug msg="connected to ap-gae2.spotify.com:443" Feb 07 17:17:06 volumio go-librespot[13115]: time="2026-02-07T17:17:06+07:00" level=debug msg="completed keyexchange" Feb 07 17:17:06 volumio go-librespot[13115]: time="2026-02-07T17:17:06+07:00" level=debug msg="completed challenge" Feb 07 17:17:06 volumio go-librespot[13115]: time="2026-02-07T17:17:06+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 07 17:17:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 17:17:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 17:17:07 volumio volumio[12782]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 07 17:17:07 volumio volumio[12782]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 07 17:17:07 volumio volumio[12782]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 17:17:07 volumio volumio[12782]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 17:17:07 volumio volumio[12782]: info: Starting MyVolumio Remote Streaming Endpoints Feb 07 17:17:07 volumio volumio[12782]: info: MyVolumio login type: Token Feb 07 17:17:07 volumio volumio[12782]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 07 17:17:07 volumio volumio[12782]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 07 17:17:09 volumio volumio[12782]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 07 17:17:09 volumio volumio[12782]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 07 17:17:09 volumio volumio[12782]: info: Streaming services startup Feb 07 17:17:09 volumio volumio[12782]: info: Starting Streaming Daemon Feb 07 17:17:09 volumio sudo[13138]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 07 17:17:09 volumio sudo[13138]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 17:17:09 volumio volumio[12782]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 07 17:17:09 volumio sudo[13138]: pam_unix(sudo:session): session closed for user root Feb 07 17:17:09 volumio volumio[12782]: info: Initializing connection to go-librespot Websocket Feb 07 17:17:09 volumio volumio[12782]: error: Cannot start Volumio Streaming Daemon Feb 07 17:17:09 volumio volumio[12782]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 07 17:17:09 volumio volumio[12782]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 07 17:17:09 volumio volumio[12782]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 07 17:17:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 23. Feb 07 17:17:10 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 17:17:10 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 17:17:10 volumio go-librespot[13144]: go-librespot daemon starting... Feb 07 17:17:10 volumio go-librespot[13145]: time="2026-02-07T17:17:10+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 17:17:10 volumio go-librespot[13145]: time="2026-02-07T17:17:10+07:00" level=debug msg="app state loaded" Feb 07 17:17:10 volumio go-librespot[13145]: time="2026-02-07T17:17:10+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 17:17:10 volumio go-librespot[13145]: time="2026-02-07T17:17:10+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 07 17:17:10 volumio go-librespot[13145]: time="2026-02-07T17:17:10+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 07 17:17:10 volumio go-librespot[13145]: time="2026-02-07T17:17:10+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 07 17:17:10 volumio go-librespot[13145]: time="2026-02-07T17:17:10+07:00" level=info msg="zeroconf server listening on port 35929" Feb 07 17:17:10 volumio volumio[12782]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Feb 07 17:17:10 volumio go-librespot[13145]: time="2026-02-07T17:17:10+07:00" level=debug msg="obtained new client token: AAA/UA6v2GPAYejBy3Fx5QjA39ydjH6RMYH3s5zw8ue1SjFP7B/enJ44ZMj+E2cn39BJMK1N4p+6l1r/MjiR4mTMMoJib2Gq0345UTas8OW+4BPHBOHEnVgjf8ULPalQFSACrYsefgh02eThfD6BhbEiRQSlCpLohkMgbC0JMXJh0qXz3HajDq1SoEYSpB9mATFpywtO7P+TSdEy+urpOYqOu9F7hsI586tLaMPhYYF3upzA/A7pw4zaww==" Feb 07 17:17:11 volumio go-librespot[13145]: time="2026-02-07T17:17:11+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 07 17:17:11 volumio go-librespot[13145]: time="2026-02-07T17:17:11+07:00" level=debug msg="completed keyexchange" Feb 07 17:17:11 volumio go-librespot[13145]: time="2026-02-07T17:17:11+07:00" level=debug msg="completed challenge" Feb 07 17:17:11 volumio go-librespot[13145]: time="2026-02-07T17:17:11+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 07 17:17:11 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 17:17:11 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 17:17:11 volumio volumio[12782]: info: MyVolumio token set successfully Feb 07 17:17:11 volumio volumio[12782]: info: MYVOLUMIO: Adding device Feb 07 17:17:11 volumio volumio[12782]: info: MYVOLUMIO: Evaluating Server Feb 07 17:17:12 volumio volumio[12782]: info: MyVolumio status changed Feb 07 17:17:12 volumio volumio[12782]: info: Streaming services startup Feb 07 17:17:12 volumio volumio[12782]: info: Starting Streaming Daemon Feb 07 17:17:12 volumio volumio[12782]: info: Removing browser output: myVolumio user plan is not superstar Feb 07 17:17:12 volumio volumio[12782]: info: Removing audio output: Feb 07 17:17:12 volumio volumio[12782]: info: Stoppping Tunnel 1 Feb 07 17:17:12 volumio sudo[13174]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 07 17:17:12 volumio sudo[13174]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 17:17:12 volumio sudo[13177]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Feb 07 17:17:12 volumio sudo[13174]: pam_unix(sudo:session): session closed for user root Feb 07 17:17:12 volumio sudo[13177]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 17:17:12 volumio volumio[12782]: error: Cannot start Volumio Streaming Daemon Feb 07 17:17:12 volumio volumio[12782]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 07 17:17:12 volumio volumio[12782]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 07 17:17:12 volumio volumio[12782]: info: Setting Geolocation for MyVolumio to as1 Feb 07 17:17:12 volumio volumio[12782]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 17:17:12 volumio volumio[12782]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 17:17:12 volumio volumio[12782]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 17:17:12 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 07 17:17:12 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 07 17:17:12 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 07 17:17:12 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 07 17:17:12 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 07 17:17:12 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 07 17:17:12 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 07 17:17:12 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 07 17:17:12 volumio sudo[13177]: pam_unix(sudo:session): session closed for user root Feb 07 17:17:12 volumio volumio[12782]: info: Remote SSH Stopped Feb 07 17:17:12 volumio volumio[12782]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Feb 07 17:17:12 volumio volumio[12782]: info: Initializing connection to go-librespot Websocket Feb 07 17:17:12 volumio volumio[12782]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 07 17:17:13 volumio volumio[12782]: info: Updating MyVolumio device info Feb 07 17:17:13 volumio volumio[12782]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 17:17:13 volumio volumio[12782]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 17:17:13 volumio volumio[12782]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 17:17:13 volumio volumio[12782]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Feb 07 17:17:14 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 24. Feb 07 17:17:14 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 17:17:14 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 17:17:14 volumio go-librespot[13179]: go-librespot daemon starting... Feb 07 17:17:14 volumio go-librespot[13180]: time="2026-02-07T17:17:14+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 17:17:14 volumio go-librespot[13180]: time="2026-02-07T17:17:14+07:00" level=debug msg="app state loaded" Feb 07 17:17:14 volumio go-librespot[13180]: time="2026-02-07T17:17:14+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 17:17:15 volumio go-librespot[13180]: time="2026-02-07T17:17:15+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 07 17:17:15 volumio go-librespot[13180]: time="2026-02-07T17:17:15+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 07 17:17:15 volumio go-librespot[13180]: time="2026-02-07T17:17:15+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 07 17:17:15 volumio go-librespot[13180]: time="2026-02-07T17:17:15+07:00" level=info msg="zeroconf server listening on port 34455" Feb 07 17:17:15 volumio go-librespot[13180]: time="2026-02-07T17:17:15+07:00" level=debug msg="obtained new client token: AABWgwuOpvNrOp4m06RmqZ8G3meolcl0dyeHLFGlsscpVBQXqVOehP2dq6Qb8jj7tphl1z07I5hOajKh8XFT7DK4aHVVWgOp8EwjD43wRHuElUnBjisbj4uTHB4s7ENllZqR4FRORvDko9BKGOs1nwbj1OTx46ORI0mrRh//oVTWfJvGMqykoCVZ/eqTsqFe8jEjoOp9VbeTk4FNYOgHKYrpcJQHIIVhATezPub9mEY5v1XawhpRRqxmgA==" Feb 07 17:17:15 volumio go-librespot[13180]: time="2026-02-07T17:17:15+07:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.241.202:4070: connect: connection refused" Feb 07 17:17:15 volumio go-librespot[13180]: time="2026-02-07T17:17:15+07:00" level=debug msg="connected to ap-gae2.spotify.com:443" Feb 07 17:17:15 volumio go-librespot[13180]: time="2026-02-07T17:17:15+07:00" level=debug msg="completed keyexchange" Feb 07 17:17:15 volumio go-librespot[13180]: time="2026-02-07T17:17:15+07:00" level=debug msg="completed challenge" Feb 07 17:17:15 volumio go-librespot[13180]: time="2026-02-07T17:17:15+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 07 17:17:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 17:17:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 17:17:15 volumio volumio[12782]: info: Initializing connection to go-librespot Websocket Feb 07 17:17:15 volumio volumio[12782]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 07 17:17:16 volumio volumio[12782]: info: MYVOLUMIO: Adding device Feb 07 17:17:16 volumio volumio[12782]: info: MYVOLUMIO: Evaluating Server Feb 07 17:17:17 volumio volumio[12782]: info: Setting Geolocation for MyVolumio to as1 Feb 07 17:17:17 volumio volumio[12782]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 17:17:17 volumio volumio[12782]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 17:17:17 volumio volumio[12782]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 17:17:18 volumio volumio[12782]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Feb 07 17:17:18 volumio volumio[12782]: info: Updating MyVolumio device info Feb 07 17:17:18 volumio volumio[12782]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 17:17:18 volumio volumio[12782]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 17:17:18 volumio volumio[12782]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 17:17:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 25. Feb 07 17:17:18 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 17:17:18 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 17:17:18 volumio go-librespot[13219]: go-librespot daemon starting... Feb 07 17:17:18 volumio go-librespot[13220]: time="2026-02-07T17:17:18+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 17:17:18 volumio go-librespot[13220]: time="2026-02-07T17:17:18+07:00" level=debug msg="app state loaded" Feb 07 17:17:18 volumio go-librespot[13220]: time="2026-02-07T17:17:18+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 17:17:18 volumio volumio[12782]: info: Initializing connection to go-librespot Websocket Feb 07 17:17:18 volumio go-librespot[13220]: time="2026-02-07T17:17:18+07:00" level=debug msg="new websocket client" Feb 07 17:17:18 volumio volumio[12782]: info: Connection to go-librespot Websocket established Feb 07 17:17:19 volumio volumio[12782]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Feb 07 17:17:19 volumio go-librespot[13220]: time="2026-02-07T17:17:19+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 07 17:17:19 volumio go-librespot[13220]: time="2026-02-07T17:17:19+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 07 17:17:19 volumio go-librespot[13220]: time="2026-02-07T17:17:19+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 07 17:17:19 volumio go-librespot[13220]: time="2026-02-07T17:17:19+07:00" level=info msg="zeroconf server listening on port 46623" Feb 07 17:17:19 volumio go-librespot[13220]: time="2026-02-07T17:17:19+07:00" level=debug msg="obtained new client token: AABVIuYXIFQDkXUudr94CgmjPpl/VDXJcvRkFdG8tWwf6l+G+yBJy8Re52+m10OMwMWz0+GQgkpDRbGf5k8/OYbvee64I/sJoGxX+BqxwtIwylA4vcmxP5EK/h1g1dMiP2w0rczVvjXuVGlaWhWxXU5es9SCE2GJaeC9hpKVJVGXeNWnbL9oIH+2HkIM+NatGXLBIDaKg0TPGZVOp/plRG9NVdLq6mN+M8LjFuXXrKn7XJ9XWeU3OJTZqw==" Feb 07 17:17:19 volumio go-librespot[13220]: time="2026-02-07T17:17:19+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 07 17:17:19 volumio go-librespot[13220]: time="2026-02-07T17:17:19+07:00" level=debug msg="completed keyexchange" Feb 07 17:17:19 volumio go-librespot[13220]: time="2026-02-07T17:17:19+07:00" level=debug msg="completed challenge" Feb 07 17:17:19 volumio go-librespot[13220]: time="2026-02-07T17:17:19+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 07 17:17:19 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 17:17:19 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 17:17:19 volumio volumio[12782]: info: Connection to go-librespot Websocket closed Feb 07 17:17:20 volumio volumio[12782]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 07 17:17:20 volumio volumio[12782]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 17:17:20 volumio volumio[12782]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 07 17:17:20 volumio volumio-remote-updater[643]: Test mode disabled Feb 07 17:17:20 volumio volumio-remote-updater[643]: Alpha mode disabled Feb 07 17:17:20 volumio volumio-remote-updater[643]: Alpha legacy test mode disabled Feb 07 17:17:20 volumio volumio[12782]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Feb 07 17:17:21 volumio volumio[12782]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Feb 07 17:17:21 volumio volumio[12782]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 07 17:17:21 volumio volumio[12782]: info: Getting Spotify volume Feb 07 17:17:22 volumio volumio[12782]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 07 17:17:22 volumio volumio[12782]: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 07 17:17:22 volumio volumio[12782]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Feb 07 17:17:22 volumio volumio[12782]: errno: -111, Feb 07 17:17:22 volumio volumio[12782]: code: 'ECONNREFUSED', Feb 07 17:17:22 volumio volumio[12782]: syscall: 'connect', Feb 07 17:17:22 volumio volumio[12782]: address: '127.0.0.1', Feb 07 17:17:22 volumio volumio[12782]: port: 9879, Feb 07 17:17:22 volumio volumio[12782]: response: undefined Feb 07 17:17:22 volumio volumio[12782]: } Feb 07 17:17:22 volumio volumio[12782]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 07 17:17:23 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 26. Feb 07 17:17:23 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 17:17:23 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 17:17:23 volumio go-librespot[13243]: go-librespot daemon starting... Feb 07 17:17:23 volumio go-librespot[13244]: time="2026-02-07T17:17:23+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 17:17:23 volumio go-librespot[13244]: time="2026-02-07T17:17:23+07:00" level=debug msg="app state loaded" Feb 07 17:17:23 volumio go-librespot[13244]: time="2026-02-07T17:17:23+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 17:17:23 volumio sudo[13253]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-07 17:16' Feb 07 17:17:23 volumio sudo[13253]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 17:17:23 volumio go-librespot[13244]: time="2026-02-07T17:17:23+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 07 17:17:23 volumio go-librespot[13244]: time="2026-02-07T17:17:23+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 07 17:17:23 volumio go-librespot[13244]: time="2026-02-07T17:17:23+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 07 17:17:23 volumio go-librespot[13244]: time="2026-02-07T17:17:23+07:00" level=info msg="zeroconf server listening on port 43165" Feb 07 17:17:23 volumio sudo[13253]: pam_unix(sudo:session): session closed for user root Feb 07 17:17:23 volumio volumio-remote-updater[643]: [2026-02-07 17:17:23] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 07 17:17:23 volumio volumio-remote-updater[643]: [2026-02-07 17:17:23] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 07 17:17:23 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 07 17:17:23 volumio go-librespot[13244]: time="2026-02-07T17:17:23+07:00" level=debug msg="obtained new client token: AAC+3Ih18kaj59PDh16B0IP3dWq9Els2HvdS+EfLW2i7SM2w2fpuzgST+L1zXDTQsbbR0LO9H1Tfd6jYeBzqYlPUAZR8KwGj88jPE+IrEZ8h1c5xKQck4czq5qZf38lqszth4vtcd4SYyJFlvxNVIQG12kkT07VhZYe0nR2ZZ5KKtFAoPaKaWtMZdhdBpdlxLy2ZGtKMelzYjSCxSbqOA85Fb9tpKyflbwZh5qyZ7X+DjbIBYC2Dlcpb5g==" Feb 07 17:17:24 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 07 17:17:24 volumio systemd[1]: volumio.service: Consumed 58.552s CPU time. Feb 07 17:17:24 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 07 17:17:24 volumio go-librespot[13244]: time="2026-02-07T17:17:24+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 07 17:17:24 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 07 17:17:24 volumio go-librespot[13244]: time="2026-02-07T17:17:24+07:00" level=debug msg="completed keyexchange" Feb 07 17:17:24 volumio go-librespot[13244]: time="2026-02-07T17:17:24+07:00" level=debug msg="completed challenge" Feb 07 17:17:24 volumio go-librespot[13244]: time="2026-02-07T17:17:24+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 07 17:17:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 17:17:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 17:17:24 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 6074. Feb 07 17:17:24 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 07 17:17:24 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 07 17:17:24 volumio systemd[1]: volumio.service: Consumed 58.552s CPU time. Feb 07 17:17:24 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Feb 07 17:17:24 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 07 17:17:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 27. Feb 07 17:17:27 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 17:17:27 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 17:17:27 volumio go-librespot[13281]: go-librespot daemon starting... Feb 07 17:17:27 volumio go-librespot[13282]: time="2026-02-07T17:17:27+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 17:17:27 volumio go-librespot[13282]: time="2026-02-07T17:17:27+07:00" level=debug msg="app state loaded" Feb 07 17:17:27 volumio go-librespot[13282]: time="2026-02-07T17:17:27+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 17:17:27 volumio volumio[13265]: info: ------------------------------------------- Feb 07 17:17:27 volumio volumio[13265]: info: ----- Volumio3 ---- Feb 07 17:17:27 volumio volumio[13265]: info: ------------------------------------------- Feb 07 17:17:27 volumio volumio[13265]: info: ----- System startup ---- Feb 07 17:17:27 volumio volumio[13265]: info: ------------------------------------------- Feb 07 17:17:28 volumio go-librespot[13282]: time="2026-02-07T17:17:28+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 07 17:17:28 volumio go-librespot[13282]: time="2026-02-07T17:17:28+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 07 17:17:28 volumio go-librespot[13282]: time="2026-02-07T17:17:28+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 07 17:17:28 volumio go-librespot[13282]: time="2026-02-07T17:17:28+07:00" level=info msg="zeroconf server listening on port 46035" Feb 07 17:17:28 volumio go-librespot[13282]: time="2026-02-07T17:17:28+07:00" level=debug msg="obtained new client token: AADwc1vGwRPBaL2ZDZQR9gMB0NABnkeM0zQ2bEuq8wkjlOZAg2W/Q0s5Gg8OnASCsofbbSVS2iHBJPu9c2j8ncsVupEj8adVHbWllaPRzmuR+1pq38KtUX/HyaSNE6yFuDwk1vZgyUS2B9pjlZEXcxUNa//GUWYre0Z+5liGiwrDUJHbyJi2l9PnEifJswNn/FcPhJS4fgQufcxL4tsckOSxo3/rOSHkm4pjpH1H8uTBA/2s9+sCbNA=" Feb 07 17:17:28 volumio go-librespot[13282]: time="2026-02-07T17:17:28+07:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.241.202:4070: connect: connection refused" Feb 07 17:17:28 volumio go-librespot[13282]: time="2026-02-07T17:17:28+07:00" level=debug msg="connected to ap-gae2.spotify.com:443" Feb 07 17:17:28 volumio go-librespot[13282]: time="2026-02-07T17:17:28+07:00" level=debug msg="completed keyexchange" Feb 07 17:17:28 volumio go-librespot[13282]: time="2026-02-07T17:17:28+07:00" level=debug msg="completed challenge" Feb 07 17:17:28 volumio go-librespot[13282]: time="2026-02-07T17:17:28+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 07 17:17:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 17:17:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 17:17:28 volumio volumio-remote-updater[643]: [2026-02-07 17:17:28] [connect] Successful connection Feb 07 17:17:29 volumio volumio[13265]: info: MYVOLUMIO Environment detected Feb 07 17:17:29 volumio volumio[13265]: info: Plugin folders cleanup Feb 07 17:17:29 volumio volumio[13265]: info: Scanning into folder /volumio/app/plugins/ Feb 07 17:17:29 volumio volumio[13265]: info: Scanning category audio_interface Feb 07 17:17:29 volumio volumio[13265]: info: Scanning category miscellanea Feb 07 17:17:29 volumio volumio[13265]: info: Scanning category music_service Feb 07 17:17:29 volumio volumio[13265]: info: Scanning category plugins.json Feb 07 17:17:29 volumio volumio[13265]: info: Scanning category system_controller Feb 07 17:17:29 volumio volumio[13265]: info: Scanning category user_interface Feb 07 17:17:29 volumio volumio[13265]: info: Scanning into folder /data/plugins/ Feb 07 17:17:29 volumio volumio[13265]: info: Scanning category music_service Feb 07 17:17:29 volumio volumio[13265]: info: Plugin folders cleanup completed Feb 07 17:17:29 volumio volumio[13265]: info: ------------------------------------------- Feb 07 17:17:29 volumio volumio[13265]: info: ----- Core plugins startup ---- Feb 07 17:17:29 volumio volumio[13265]: info: ------------------------------------------- Feb 07 17:17:29 volumio volumio[13265]: info: Loading plugins from folder /volumio/app/plugins/ Feb 07 17:17:29 volumio volumio[13265]: info: Adding plugin upnp to MyMusic Plugins Feb 07 17:17:29 volumio volumio[13265]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 07 17:17:29 volumio volumio[13265]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 07 17:17:29 volumio volumio[13265]: info: Loading plugins from folder /data/plugins/ Feb 07 17:17:29 volumio volumio[13265]: info: Loading plugin "system"... Feb 07 17:17:29 volumio volumio[13265]: info: Loading plugin "appearance"... Feb 07 17:17:31 volumio volumio[13265]: info: Loading plugin "network"... Feb 07 17:17:31 volumio volumio[13265]: info: Refreshing Cached IP Addresses Feb 07 17:17:31 volumio sudo[13302]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 07 17:17:31 volumio sudo[13302]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 17:17:31 volumio sudo[13304]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 07 17:17:31 volumio sudo[13304]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 17:17:31 volumio sudo[13302]: pam_unix(sudo:session): session closed for user root Feb 07 17:17:31 volumio volumio[13265]: info: Loading plugin "services"... Feb 07 17:17:31 volumio sudo[13304]: pam_unix(sudo:session): session closed for user root Feb 07 17:17:31 volumio volumio[13265]: info: Loading plugin "alsa_controller"... Feb 07 17:17:31 volumio sudo[13311]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 07 17:17:31 volumio sudo[13311]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 17:17:31 volumio volumio[13265]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 07 17:17:31 volumio volumio[13265]: info: Loading plugin "wizard"... Feb 07 17:17:31 volumio volumio[13265]: info: Loading plugin "networkfs"... Feb 07 17:17:31 volumio volumio[13265]: info: Starting Udev Watcher for removable devices Feb 07 17:17:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 28. Feb 07 17:17:31 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 17:17:31 volumio volumio[13265]: info: Ignoring mount for partition: boot Feb 07 17:17:31 volumio volumio[13265]: info: Ignoring mount for partition: volumio Feb 07 17:17:31 volumio volumio[13265]: info: Ignoring mount for partition: volumio_data Feb 07 17:17:31 volumio volumio[13265]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 07 17:17:31 volumio volumio[13265]: info: Loading plugin "volumio_command_line_client"... Feb 07 17:17:31 volumio volumio[13265]: info: Loading plugin "upnp"... Feb 07 17:17:31 volumio volumio[13265]: info: [1770459451871] Starting Upmpd Daemon Feb 07 17:17:31 volumio volumio[13265]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 07 17:17:31 volumio volumio[13265]: info: Loading plugin "my_music"... Feb 07 17:17:31 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 17:17:31 volumio go-librespot[13335]: go-librespot daemon starting... Feb 07 17:17:31 volumio volumio[13265]: info: Loading plugin "mpd"... Feb 07 17:17:31 volumio go-librespot[13336]: time="2026-02-07T17:17:31+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 17:17:31 volumio go-librespot[13336]: time="2026-02-07T17:17:31+07:00" level=debug msg="app state loaded" Feb 07 17:17:31 volumio go-librespot[13336]: time="2026-02-07T17:17:31+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 17:17:32 volumio go-librespot[13336]: time="2026-02-07T17:17:32+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 07 17:17:32 volumio go-librespot[13336]: time="2026-02-07T17:17:32+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 07 17:17:32 volumio go-librespot[13336]: time="2026-02-07T17:17:32+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 07 17:17:32 volumio go-librespot[13336]: time="2026-02-07T17:17:32+07:00" level=info msg="zeroconf server listening on port 34605" Feb 07 17:17:32 volumio volumio[13265]: info: Loading plugin "upnp_browser"... Feb 07 17:17:32 volumio go-librespot[13336]: time="2026-02-07T17:17:32+07:00" level=debug msg="obtained new client token: AADGow7YjzZWnLc5KW2Cd/tNDi5eJTPcJGk54zAO1QKze/19JP2SgJelAbTSHcXsB9hGTf3FrnGOJFxyqobP3fkwQaxvx+gqEVmH0wYP8kAZNdUXZHRbMW6pvNGTQt1cAdcWpCZWw2W7S0bnr7XR5jLwcudlGfEkicy7ZjtPW/KpRA99JZqTlb9wgpqxw8xUNnqO2r2BZGSq6OW0z+MmW4SjNomekjbd5G4cblv3e4MsQyqhcSTWy7BiDQ==" Feb 07 17:17:32 volumio go-librespot[13336]: time="2026-02-07T17:17:32+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 07 17:17:32 volumio go-librespot[13336]: time="2026-02-07T17:17:32+07:00" level=debug msg="completed keyexchange" Feb 07 17:17:32 volumio go-librespot[13336]: time="2026-02-07T17:17:32+07:00" level=debug msg="completed challenge" Feb 07 17:17:32 volumio go-librespot[13336]: time="2026-02-07T17:17:32+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 07 17:17:33 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 17:17:33 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 17:17:34 volumio sudo[13311]: pam_unix(sudo:session): session closed for user root Feb 07 17:17:35 volumio volumio[13265]: info: Starting UPNP Browser Feb 07 17:17:35 volumio volumio[13265]: info: Loading plugin "alarm-clock"... Feb 07 17:17:35 volumio volumio[13265]: info: Loading plugin "airplay_emulation"... Feb 07 17:17:35 volumio volumio[13265]: info: Starting Shairport Sync Feb 07 17:17:35 volumio volumio[13265]: info: Loading plugin "last_100"... Feb 07 17:17:35 volumio volumio[13265]: info: Loading plugin "webradio"... Feb 07 17:17:36 volumio volumio[13265]: info: Loading plugin "i2s_dacs"... Feb 07 17:17:36 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 29. Feb 07 17:17:36 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 17:17:36 volumio volumio[13265]: info: Loading plugin "volumiodiscovery"... Feb 07 17:17:36 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 17:17:36 volumio go-librespot[13346]: go-librespot daemon starting... Feb 07 17:17:36 volumio volumio[13265]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 07 17:17:36 volumio volumio[13265]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 07 17:17:36 volumio volumio[13265]: *** WARNING *** For more information see Feb 07 17:17:36 volumio volumio[13265]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 07 17:17:36 volumio volumio[13265]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 07 17:17:36 volumio node[13265]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 07 17:17:36 volumio volumio[13265]: *** WARNING *** For more information see Feb 07 17:17:36 volumio node[13265]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 07 17:17:36 volumio node[13265]: *** WARNING *** For more information see Feb 07 17:17:36 volumio node[13265]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 07 17:17:36 volumio node[13265]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 07 17:17:36 volumio node[13265]: *** WARNING *** For more information see Feb 07 17:17:36 volumio volumio[13265]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 07 17:17:36 volumio go-librespot[13347]: time="2026-02-07T17:17:36+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 17:17:36 volumio go-librespot[13347]: time="2026-02-07T17:17:36+07:00" level=debug msg="app state loaded" Feb 07 17:17:36 volumio volumio[13265]: info: Discovery: Started advertising with name: Volumio Feb 07 17:17:36 volumio go-librespot[13347]: time="2026-02-07T17:17:36+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 17:17:36 volumio volumio[13265]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 07 17:17:36 volumio volumio[13265]: info: Loading plugin "spop"... Feb 07 17:17:36 volumio go-librespot[13347]: time="2026-02-07T17:17:36+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 07 17:17:36 volumio go-librespot[13347]: time="2026-02-07T17:17:36+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 07 17:17:36 volumio go-librespot[13347]: time="2026-02-07T17:17:36+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 07 17:17:36 volumio go-librespot[13347]: time="2026-02-07T17:17:36+07:00" level=info msg="zeroconf server listening on port 37405" Feb 07 17:17:36 volumio go-librespot[13347]: time="2026-02-07T17:17:36+07:00" level=debug msg="obtained new client token: AADPYrV8aGH0cTY+4pidoXv7IwnccLTTNVRlKHwe9Rr377SqR3djVEOBTb4rF2oA44xvItvTPwdyxLBqnhiDcGa59ZIIW8czuNvTW3HTGfmejwb8lQUXitfA4pnFe7MF9I1IyayKmCQVBLJM7Liles1z3BLzMTf1qrJTHkuPqcZpIcND/YdAUMlmlwRBPqgy2IRRL6RZTldaVbqSRL8p4pzAWnh2Wuy7LAd+h/cOu7L/vhAlhu4YcnA2Xw==" Feb 07 17:17:37 volumio go-librespot[13347]: time="2026-02-07T17:17:37+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 07 17:17:37 volumio go-librespot[13347]: time="2026-02-07T17:17:37+07:00" level=debug msg="completed keyexchange" Feb 07 17:17:37 volumio go-librespot[13347]: time="2026-02-07T17:17:37+07:00" level=debug msg="completed challenge" Feb 07 17:17:37 volumio go-librespot[13347]: time="2026-02-07T17:17:37+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 07 17:17:37 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 17:17:37 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 17:17:38 volumio volumio[13265]: info: Loading plugin "ytcr"... Feb 07 17:17:40 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 30. Feb 07 17:17:40 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 17:17:40 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 17:17:40 volumio go-librespot[13357]: go-librespot daemon starting... Feb 07 17:17:40 volumio go-librespot[13358]: time="2026-02-07T17:17:40+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 17:17:40 volumio go-librespot[13358]: time="2026-02-07T17:17:40+07:00" level=debug msg="app state loaded" Feb 07 17:17:40 volumio go-librespot[13358]: time="2026-02-07T17:17:40+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 17:17:40 volumio volumio[13265]: info: Loading plugin "ytmusic"... Feb 07 17:17:40 volumio go-librespot[13358]: time="2026-02-07T17:17:40+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 07 17:17:40 volumio go-librespot[13358]: time="2026-02-07T17:17:40+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 07 17:17:40 volumio go-librespot[13358]: time="2026-02-07T17:17:40+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 07 17:17:41 volumio go-librespot[13358]: time="2026-02-07T17:17:41+07:00" level=info msg="zeroconf server listening on port 38829" Feb 07 17:17:41 volumio go-librespot[13358]: time="2026-02-07T17:17:41+07:00" level=debug msg="obtained new client token: AACZ1pxizyX1lrTVkA2E5IQdS2SSeZfciLUHZf6fEuPopXhjDCSZrdVfoIiuvfTsXqiKUzjuSLOO74ernuJLrg3thEhIpFAEl3crmlaabd0T6J5R+r+4smLOQlPvQZWV2BiZTYyKE46rO+M4IMVCsNJFzkmuzXbHtrCy0bg1Pl+XFwb6TIiGSnqkirTDJ1uiYXuJAPt3oLwQEed9IG5gTEJG4sFDP+XfQyVbVZL1ArVps57JPOITFNo=" Feb 07 17:17:41 volumio go-librespot[13358]: time="2026-02-07T17:17:41+07:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.241.202:4070: connect: connection refused" Feb 07 17:17:41 volumio go-librespot[13358]: time="2026-02-07T17:17:41+07:00" level=debug msg="connected to ap-gae2.spotify.com:443" Feb 07 17:17:41 volumio volumio-remote-updater[643]: [2026-02-07 17:17:41] [connect] Successful connection Feb 07 17:17:41 volumio go-librespot[13358]: time="2026-02-07T17:17:41+07:00" level=debug msg="completed keyexchange" Feb 07 17:17:41 volumio go-librespot[13358]: time="2026-02-07T17:17:41+07:00" level=debug msg="completed challenge" Feb 07 17:17:41 volumio go-librespot[13358]: time="2026-02-07T17:17:41+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 07 17:17:41 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 17:17:41 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 17:17:41 volumio volumio[13265]: info: Loading plugin "outputs"... Feb 07 17:17:41 volumio volumio[13265]: info: Loading plugin "albumart"... Feb 07 17:17:41 volumio volumio[13265]: info: Plugin example_plugin is not enabled Feb 07 17:17:41 volumio volumio[13265]: info: Loading plugin "inputs"... Feb 07 17:17:42 volumio volumio[13265]: info: Loading plugin "updater_comm"... Feb 07 17:17:42 volumio volumio[13265]: info: Plugin mpdemulation is not enabled Feb 07 17:17:42 volumio volumio[13265]: info: Loading plugin "rest_api"... Feb 07 17:17:42 volumio volumio[13265]: info: Loading plugin "websocket"... Feb 07 17:17:42 volumio volumio[13265]: info: Starting Socket.io Server version 1.7.4 Feb 07 17:17:42 volumio volumio[13265]: info: Loading plugin "RoonBridge"... Feb 07 17:17:42 volumio volumio[13265]: info: Applying required configuration parameters for plugin RoonBridge Feb 07 17:17:42 volumio volumio[13265]: info: Loading i18n strings for locale en Feb 07 17:17:42 volumio volumio[13265]: Updating browse sources language Feb 07 17:17:42 volumio volumio[13265]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 17:17:43 volumio volumio[13265]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 07 17:17:43 volumio volumio[13265]: info: CoreCommandRouter::initPlayerControls Feb 07 17:17:43 volumio volumio[13265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 17:17:43 volumio volumio[13265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 17:17:43 volumio volumio[13265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 17:17:43 volumio volumio[13265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 17:17:43 volumio volumio[13265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 17:17:43 volumio volumio[13265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 17:17:43 volumio volumio[13265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 17:17:43 volumio volumio[13265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 17:17:43 volumio volumio[13265]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 07 17:17:43 volumio volumio[13265]: Express server listening on port 3000 Feb 07 17:17:43 volumio volumio[13265]: [Metrics] WebUI: 16s 135.10ms Feb 07 17:17:43 volumio volumio[13379]: Forking 3 albumart workers Feb 07 17:17:43 volumio volumio[13265]: info: CoreStateMachine::resetVolumioState Feb 07 17:17:43 volumio volumio[13265]: info: CoreStateMachine::getcurrentVolume Feb 07 17:17:43 volumio volumio[13265]: info: CoreCommandRouter::volumioRetrievevolume Feb 07 17:17:43 volumio sudo[13398]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 07 17:17:43 volumio sudo[13398]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 17:17:43 volumio sudo[13407]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 07 17:17:43 volumio sudo[13407]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 17:17:43 volumio sudo[13398]: pam_unix(sudo:session): session closed for user root Feb 07 17:17:43 volumio volumio[13265]: info: Volumio Network Manager: Network status updated: 1 Feb 07 17:17:43 volumio sudo[13407]: pam_unix(sudo:session): session closed for user root Feb 07 17:17:43 volumio volumio[13265]: info: VolumeController:: Volume=100 Mute =false Feb 07 17:17:43 volumio volumio[13265]: info: CoreStateMachine::pushState Feb 07 17:17:43 volumio volumio[13265]: info: CorePlayQueue::getTrack 0 Feb 07 17:17:43 volumio volumio[13265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 07 17:17:43 volumio volumio[13265]: info: CoreCommandRouter::volumioPushState Feb 07 17:17:43 volumio volumio[13265]: info: CoreStateMachine::updateTrackBlock Feb 07 17:17:43 volumio volumio[13265]: info: CorePlayQueue::getTrackBlock Feb 07 17:17:43 volumio volumio[13265]: info: CoreCommandRouter::volumioRetrievevolume Feb 07 17:17:43 volumio volumio-remote-updater[643]: [2026-02-07 17:17:43] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1770459461 101 Feb 07 17:17:43 volumio volumio[13265]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 1 Feb 07 17:17:43 volumio volumio[13265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 17:17:43 volumio volumio[13265]: info: Reloading queue from file Feb 07 17:17:43 volumio volumio[13265]: info: CoreStateMachine::setRepeat null single undefined Feb 07 17:17:43 volumio volumio[13265]: info: CoreStateMachine::pushState Feb 07 17:17:43 volumio volumio[13265]: info: CorePlayQueue::getTrack 0 Feb 07 17:17:43 volumio volumio[13265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 07 17:17:43 volumio volumio[13265]: info: CoreCommandRouter::volumioPushState Feb 07 17:17:43 volumio volumio[13265]: info: CoreStateMachine::setRandom null Feb 07 17:17:43 volumio volumio[13265]: info: CoreStateMachine::pushState Feb 07 17:17:43 volumio volumio[13265]: info: CorePlayQueue::getTrack 0 Feb 07 17:17:43 volumio volumio[13265]: info: CoreCommandRouter::volumioPushState Feb 07 17:17:43 volumio volumio[13265]: info: Setting Device type: Raspberry PI Feb 07 17:17:43 volumio volumio[13265]: info: Completed loading Core Plugins Feb 07 17:17:43 volumio volumio[13265]: info: Preparing to generate the ALSA configuration file Feb 07 17:17:44 volumio volumio[13265]: info: VolumeController:: Volume=100 Mute =false Feb 07 17:17:44 volumio volumio[13265]: info: CoreStateMachine::pushState Feb 07 17:17:44 volumio volumio[13265]: info: CorePlayQueue::getTrack 0 Feb 07 17:17:44 volumio volumio[13265]: info: CoreCommandRouter::volumioPushState Feb 07 17:17:44 volumio volumio[13265]: info: Asound.conf file unchanged, so no further update is needed Feb 07 17:17:44 volumio volumio[13265]: info: Output device has changed, restarting MPD Feb 07 17:17:44 volumio volumio[13265]: info: Output device has changed, restarting Shairport Sync Feb 07 17:17:44 volumio volumio[13265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 17:17:44 volumio volumio[13265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 17:17:44 volumio sudo[13440]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 07 17:17:44 volumio sudo[13440]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 17:17:44 volumio sudo[13442]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 07 17:17:44 volumio sudo[13442]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 17:17:44 volumio sudo[13442]: pam_unix(sudo:session): session closed for user root Feb 07 17:17:44 volumio sudo[13444]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 07 17:17:44 volumio sudo[13444]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 17:17:44 volumio volumio[13265]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 07 17:17:44 volumio volumio[13265]: info: ___________ START PLUGINS ___________ Feb 07 17:17:44 volumio volumio[13265]: info: ControllerMpd::onStart: Initializing MPD Feb 07 17:17:44 volumio volumio[13265]: info: Creating MPD Configuration file Feb 07 17:17:44 volumio sudo[13440]: pam_unix(sudo:session): session closed for user root Feb 07 17:17:44 volumio volumio[13265]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 07 17:17:44 volumio volumio[13265]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 17:17:44 volumio volumio[13265]: info: [1770459464431] CoreMusicLibrary::Adding element Media Servers Feb 07 17:17:44 volumio volumio[13265]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 17:17:44 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 07 17:17:44 volumio sudo[13453]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 07 17:17:44 volumio sudo[13453]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 17:17:44 volumio sudo[13453]: pam_unix(sudo:session): session closed for user root Feb 07 17:17:44 volumio volumio[13265]: info: UPNP Browser: Client initialized successfully Feb 07 17:17:44 volumio sudo[13455]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 07 17:17:44 volumio sudo[13455]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 17:17:44 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 07 17:17:44 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 07 17:17:44 volumio systemd[1]: mpd.service: Consumed 7.232s CPU time. Feb 07 17:17:44 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 31. Feb 07 17:17:44 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 07 17:17:44 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 07 17:17:44 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 07 17:17:44 volumio volumio[13265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 17:17:44 volumio volumio[13265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 17:17:44 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 07 17:17:44 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 17:17:44 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 17:17:44 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 07 17:17:44 volumio go-librespot[13464]: go-librespot daemon starting... Feb 07 17:17:44 volumio volumio[13265]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 07 17:17:44 volumio volumio[13265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 17:17:44 volumio volumio[13265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 17:17:44 volumio go-librespot[13466]: time="2026-02-07T17:17:44+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 17:17:44 volumio go-librespot[13466]: time="2026-02-07T17:17:44+07:00" level=debug msg="app state loaded" Feb 07 17:17:44 volumio go-librespot[13466]: time="2026-02-07T17:17:44+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 17:17:44 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 07 17:17:44 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 07 17:17:44 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 07 17:17:44 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 07 17:17:44 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 07 17:17:44 volumio volumio[13265]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 07 17:17:44 volumio volumio[13265]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 17:17:44 volumio volumio[13265]: info: [1770459464892] CoreMusicLibrary::Adding element Last_100 Feb 07 17:17:44 volumio volumio[13265]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 17:17:44 volumio volumio[13265]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 17:17:44 volumio volumio[13265]: info: [1770459464907] CoreMusicLibrary::Adding element Webradio Feb 07 17:17:44 volumio volumio[13265]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 17:17:44 volumio volumio[13265]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 07 17:17:44 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 07 17:17:44 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 07 17:17:44 volumio volumio[13265]: info: Initializing BBC Radios Feb 07 17:17:45 volumio volumio[13265]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 07 17:17:45 volumio volumio[13265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 17:17:45 volumio volumio[13265]: info: Creating Spotify config file Feb 07 17:17:45 volumio volumio[13265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 17:17:45 volumio sudo[13477]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 07 17:17:45 volumio sudo[13477]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 07 17:17:45 volumio go-librespot[13466]: time="2026-02-07T17:17:45+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 07 17:17:45 volumio go-librespot[13466]: time="2026-02-07T17:17:45+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 07 17:17:45 volumio go-librespot[13466]: time="2026-02-07T17:17:45+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 07 17:17:45 volumio sudo[13477]: pam_unix(sudo:session): session closed for user root Feb 07 17:17:45 volumio go-librespot[13466]: time="2026-02-07T17:17:45+07:00" level=info msg="zeroconf server listening on port 44455" Feb 07 17:17:45 volumio go-librespot[13466]: time="2026-02-07T17:17:45+07:00" level=debug msg="obtained new client token: AABO+NNiqsSHJIFrBcwkSTBx8OjzlVCfSRavj0rlb79lwI7ugBEZUueNnA32JKBK91OvmjFcP3qBBYFxnNs9Pcalyy5CSrIbBLSZsvuDt3MQWukqcU1TvrmwxB9W6YJvQgboa9csQ9/wcPy3JeOqZhKejQ7Ey/jsxFkV/wzjNWPFm6LUuqc57A8AmHIhbwNzY/3qNb8AE5MeVAx8uWc/QEFUYBklTQxAXxxcuS0xd1rvuX12ZgGnouEd9Q==" Feb 07 17:17:45 volumio go-librespot[13466]: time="2026-02-07T17:17:45+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 07 17:17:46 volumio go-librespot[13466]: time="2026-02-07T17:17:46+07:00" level=debug msg="completed keyexchange" Feb 07 17:17:46 volumio go-librespot[13466]: time="2026-02-07T17:17:46+07:00" level=debug msg="completed challenge" Feb 07 17:17:46 volumio go-librespot[13466]: time="2026-02-07T17:17:46+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 07 17:17:46 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 17:17:46 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 17:17:46 volumio volumio[13265]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 17:17:46 volumio volumio[13265]: info: [1770459466552] CoreMusicLibrary::Adding element YouTube Music Feb 07 17:17:46 volumio volumio[13265]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 17:17:46 volumio volumio[13265]: Cannot find translation for source YouTube Music Feb 07 17:17:46 volumio volumio[13265]: info: Volumio Calling Home Feb 07 17:17:46 volumio volumio[13395]: Starting albumart workers Feb 07 17:17:46 volumio volumio[13393]: Starting albumart workers Feb 07 17:17:46 volumio sudo[13495]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Feb 07 17:17:46 volumio sudo[13495]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 17:17:46 volumio volumio[13396]: Starting albumart workers Feb 07 17:17:46 volumio sudo[13495]: pam_unix(sudo:session): session closed for user root Feb 07 17:17:47 volumio volumio[13265]: info: Discovery: adding 921648ed-0557-4d70-9927-1420682efcc7 Feb 07 17:17:47 volumio volumio[13265]: info: Discovery: Found device Volumio Feb 07 17:17:47 volumio volumio[13265]: info: CoreCommandRouter::volumioGetState Feb 07 17:17:47 volumio volumio[13265]: info: CorePlayQueue::getTrack 0 Feb 07 17:17:47 volumio volumio[13265]: info: MPD Permissions set Feb 07 17:17:47 volumio volumio[13265]: info: MPD Permissions set Feb 07 17:17:47 volumio volumio[13265]: info: Upmpdcli Daemon Started Feb 07 17:17:47 volumio volumio[13265]: info: Discovery: this is already registered, 921648ed-0557-4d70-9927-1420682efcc7 Feb 07 17:17:47 volumio volumio[13265]: info: Discovery: Found device Volumio Feb 07 17:17:47 volumio volumio[13265]: info: CoreCommandRouter::volumioGetState Feb 07 17:17:47 volumio volumio[13265]: info: CorePlayQueue::getTrack 0 Feb 07 17:17:47 volumio volumio[13265]: info: Volumio called home Feb 07 17:17:47 volumio volumio[13265]: info: Spotify config file written Feb 07 17:17:47 volumio sudo[13501]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 07 17:17:47 volumio sudo[13501]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 17:17:47 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 17:17:48 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 17:17:48 volumio go-librespot[13503]: go-librespot daemon starting... Feb 07 17:17:48 volumio sudo[13501]: pam_unix(sudo:session): session closed for user root Feb 07 17:17:48 volumio volumio[13265]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Feb 07 17:17:48 volumio go-librespot[13504]: time="2026-02-07T17:17:48+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 17:17:48 volumio go-librespot[13504]: time="2026-02-07T17:17:48+07:00" level=debug msg="app state loaded" Feb 07 17:17:48 volumio volumio[13265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 17:17:48 volumio go-librespot[13504]: time="2026-02-07T17:17:48+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 17:17:48 volumio volumio[13265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 17:17:48 volumio volumio[13265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 17:17:48 volumio volumio[13265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 17:17:48 volumio volumio[13265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 17:17:48 volumio volumio[13265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 17:17:48 volumio volumio[13265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 17:17:48 volumio volumio[13265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 17:17:48 volumio volumio[13265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 17:17:48 volumio volumio[13265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 17:17:48 volumio volumio[13265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 17:17:48 volumio volumio[13265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 17:17:48 volumio volumio[13265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 17:17:48 volumio volumio[13265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 17:17:48 volumio volumio[13265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 17:17:48 volumio volumio[13265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 17:17:48 volumio volumio[13265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 17:17:48 volumio volumio[13265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 17:17:48 volumio volumio[13265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 17:17:48 volumio volumio[13265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 17:17:48 volumio volumio[13265]: info: No need to fix Spotify hosts Feb 07 17:17:48 volumio go-librespot[13504]: time="2026-02-07T17:17:48+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 07 17:17:48 volumio go-librespot[13504]: time="2026-02-07T17:17:48+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 07 17:17:48 volumio go-librespot[13504]: time="2026-02-07T17:17:48+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 07 17:17:48 volumio go-librespot[13504]: time="2026-02-07T17:17:48+07:00" level=info msg="zeroconf server listening on port 40771" Feb 07 17:17:49 volumio go-librespot[13504]: time="2026-02-07T17:17:49+07:00" level=debug msg="obtained new client token: AAC5kdJQJ80VVAJ2g0Cpmo9kxNZYx8g9h2axGPWZvNe9k/fIF0utwZlC2bWTQK7j3R3I5398qj4v8XefH+AEWpaAKj/7QnxAWBdF1ar6w8ySGxE8uTFtGPSx3PqRFmYuTsnZmhQeEKIlhvTFTfxd+j6wmQ3WUxx+X59id6DF95u09RYqZBDDLUnzI1XQ25VkW+knp5OQ1by7qctBwvd5HHs9jBbYTZJeBEC0Ol/Yjl+QpDRpJzdh5+o=" Feb 07 17:17:49 volumio go-librespot[13504]: time="2026-02-07T17:17:49+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 07 17:17:49 volumio go-librespot[13504]: time="2026-02-07T17:17:49+07:00" level=debug msg="completed keyexchange" Feb 07 17:17:49 volumio go-librespot[13504]: time="2026-02-07T17:17:49+07:00" level=debug msg="completed challenge" Feb 07 17:17:49 volumio volumio[13265]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 07 17:17:49 volumio volumio[13265]: SPOTIFY: BQBLZSl3VT160pNykx9NY0e6Osa-194yz9PAlG4NaK3LVn6yYLeTjNPzlsQ4hog4wKq4jwUhiUzp4iAkf3jFDGn4Nf_yNQeEkPgNAgtZd409BSCCK8GtMHin57DQhCuxkfgodPXDSBnCkuxNuKZPLW4v2eefC7llvG9322IAk7Qpc4l_8Wp9J1Fb-b-rZPEGIbY1QETZ8eXrjFYkHtZ1eL9Zp-67NrNRM-_ScgRvv0Rq2w4TN10lIBKVM50zVeO39bM48khSexzVcA5HhQ2TxahiOeBS57SGhO-MuaZ1cGgzpq6IWQsiz9O1 Feb 07 17:17:49 volumio volumio[13265]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 07 17:17:49 volumio volumio[13265]: info: New Spotify access token = BQBLZSl3VT160pNykx9NY0e6Osa-194yz9PAlG4NaK3LVn6yYLeTjNPzlsQ4hog4wKq4jwUhiUzp4iAkf3jFDGn4Nf_yNQeEkPgNAgtZd409BSCCK8GtMHin57DQhCuxkfgodPXDSBnCkuxNuKZPLW4v2eefC7llvG9322IAk7Qpc4l_8Wp9J1Fb-b-rZPEGIbY1QETZ8eXrjFYkHtZ1eL9Zp-67NrNRM-_ScgRvv0Rq2w4TN10lIBKVM50zVeO39bM48khSexzVcA5HhQ2TxahiOeBS57SGhO-MuaZ1cGgzpq6IWQsiz9O1 Feb 07 17:17:49 volumio volumio[13265]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 07 17:17:49 volumio volumio[13265]: info: Starting Shairport Sync Feb 07 17:17:49 volumio go-librespot[13504]: time="2026-02-07T17:17:49+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 07 17:17:49 volumio volumio[13265]: info: Starting Shairport Sync Feb 07 17:17:49 volumio volumio[13265]: info: Starting Shairport Sync Feb 07 17:17:49 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 17:17:49 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 17:17:49 volumio sudo[13540]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 07 17:17:49 volumio sudo[13540]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 17:17:49 volumio sudo[13542]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 07 17:17:49 volumio sudo[13542]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 17:17:49 volumio sudo[13544]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 07 17:17:49 volumio sudo[13544]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 17:17:49 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 07 17:17:49 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 07 17:17:49 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 07 17:17:49 volumio systemd[1]: shairport-sync.service: Consumed 2.449s CPU time. Feb 07 17:17:49 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 07 17:17:49 volumio sudo[13540]: pam_unix(sudo:session): session closed for user root Feb 07 17:17:49 volumio volumio[13265]: info: CoreCommandRouter::volumioGetState Feb 07 17:17:49 volumio volumio[13265]: info: CorePlayQueue::getTrack 0 Feb 07 17:17:49 volumio volumio[13265]: info: Shairport-Sync Started Feb 07 17:17:49 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 07 17:17:49 volumio volumio[13265]: Error adding Membership: Error: addMembership EINVAL Feb 07 17:17:49 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 07 17:17:49 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 07 17:17:50 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 07 17:17:50 volumio sudo[13544]: pam_unix(sudo:session): session closed for user root Feb 07 17:17:50 volumio sudo[13542]: pam_unix(sudo:session): session closed for user root Feb 07 17:17:50 volumio volumio[13265]: info: Shairport-Sync Started Feb 07 17:17:50 volumio volumio[13265]: info: Shairport-Sync Started Feb 07 17:17:50 volumio volumio[13265]: SPOTIFY: User informations: {"country":"PK","display_name":"215mmgea6kss3jxeqsqdquvji","email":"linh.ngotran@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/215mmgea6kss3jxeqsqdquvji"},"followers":{"href":null,"total":1},"href":"https://api.spotify.com/v1/users/215mmgea6kss3jxeqsqdquvji","id":"215mmgea6kss3jxeqsqdquvji","images":[],"product":"premium","type":"user","uri":"spotify:user:215mmgea6kss3jxeqsqdquvji"} Feb 07 17:17:50 volumio volumio[13265]: info: Spotify Successfully logged in Feb 07 17:17:50 volumio volumio[13265]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 17:17:50 volumio volumio[13265]: info: [1770459470207] CoreMusicLibrary::Adding element Spotify Feb 07 17:17:50 volumio volumio[13265]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 17:17:50 volumio volumio[13265]: Cannot find translation for source YouTube Music Feb 07 17:17:50 volumio volumio[13265]: Cannot find translation for source Spotify Feb 07 17:17:50 volumio volumio[13265]: info: [yt-cast-receiver] DIAL server listening on port 8098 Feb 07 17:17:50 volumio volumio[13265]: info: CoreCommandRouter::volumioRetrievevolume Feb 07 17:17:51 volumio volumio[13265]: info: VolumeController:: Volume=100 Mute =false Feb 07 17:17:51 volumio volumio[13265]: info: CoreCommandRouter::volumioGetState Feb 07 17:17:51 volumio volumio[13265]: info: CorePlayQueue::getTrack 0 Feb 07 17:17:51 volumio volumio[13265]: info: CoreStateMachine::pushState Feb 07 17:17:51 volumio volumio[13265]: info: CorePlayQueue::getTrack 0 Feb 07 17:17:51 volumio volumio[13265]: info: CoreCommandRouter::volumioPushState Feb 07 17:17:52 volumio volumio[13265]: info: go-librespot daemon successfully initialized Feb 07 17:17:52 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 32. Feb 07 17:17:52 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 17:17:52 volumio mpd[13493]: 2026-02-07T17:17:52 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 07 17:17:52 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 17:17:52 volumio go-librespot[13581]: go-librespot daemon starting... Feb 07 17:17:52 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 07 17:17:52 volumio sudo[13455]: pam_unix(sudo:session): session closed for user root Feb 07 17:17:52 volumio sudo[13444]: pam_unix(sudo:session): session closed for user root Feb 07 17:17:52 volumio go-librespot[13584]: time="2026-02-07T17:17:52+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 17:17:52 volumio go-librespot[13584]: time="2026-02-07T17:17:52+07:00" level=debug msg="app state loaded" Feb 07 17:17:52 volumio go-librespot[13584]: time="2026-02-07T17:17:52+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 17:17:52 volumio volumio[13265]: error: MPD error: The expression evaluated to a falsy value: Feb 07 17:17:52 volumio volumio[13265]: assert.ok(self.idling) Feb 07 17:17:52 volumio volumio[13265]: error: The expression evaluated to a falsy value: Feb 07 17:17:52 volumio volumio[13265]: assert.ok(self.idling) Feb 07 17:17:52 volumio volumio[13265]: info: Completed starting Core Plugins Feb 07 17:17:52 volumio volumio[13265]: info: ------------------------------------------- Feb 07 17:17:52 volumio volumio[13265]: info: ----- MyVolumio plugins startup ---- Feb 07 17:17:52 volumio volumio[13265]: info: ------------------------------------------- Feb 07 17:17:53 volumio volumio[13265]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 07 17:17:53 volumio volumio[13265]: info: MPD running with PID13493 Feb 07 17:17:53 volumio volumio[13265]: ,establishing connection Feb 07 17:17:53 volumio volumio[13265]: error: updateQueue error: null Feb 07 17:17:53 volumio volumio[13265]: error: updateQueue error: null Feb 07 17:17:53 volumio go-librespot[13584]: time="2026-02-07T17:17:53+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 07 17:17:53 volumio go-librespot[13584]: time="2026-02-07T17:17:53+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 07 17:17:53 volumio go-librespot[13584]: time="2026-02-07T17:17:53+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 07 17:17:53 volumio go-librespot[13584]: time="2026-02-07T17:17:53+07:00" level=info msg="zeroconf server listening on port 39547" Feb 07 17:17:53 volumio go-librespot[13584]: time="2026-02-07T17:17:53+07:00" level=debug msg="obtained new client token: AACNVvxOfftE0XGyJ7cmVCkToDFOR+Pc34GEe1xLG8oGp2bGve+EJ+6P6Vy/NMvqaBlIAAtdp+sUKN0fVGvEkTGANXYhQgkJKvYk6/dniPO1E92j7gPp7TbsqR/6lSYUXoIZB4d2kfL00iIaX2WxuRI3ItM/mRB0pwlIBKmSpQfFm3hd7/mIJi0dD/Hphuxa2DsKPoxUINM+VZniJ+9atBINsF4VnfJGo9vZnlIjrOnWwF+O7g5aF+7IFQ==" Feb 07 17:17:53 volumio go-librespot[13584]: time="2026-02-07T17:17:53+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 07 17:17:53 volumio go-librespot[13584]: time="2026-02-07T17:17:53+07:00" level=debug msg="completed keyexchange" Feb 07 17:17:53 volumio go-librespot[13584]: time="2026-02-07T17:17:53+07:00" level=debug msg="completed challenge" Feb 07 17:17:53 volumio go-librespot[13584]: time="2026-02-07T17:17:53+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 07 17:17:53 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 17:17:53 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 17:17:55 volumio volumio[13265]: info: Initializing connection to go-librespot Websocket Feb 07 17:17:55 volumio volumio[13265]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 07 17:17:56 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 33. Feb 07 17:17:56 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 17:17:56 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 17:17:56 volumio go-librespot[13593]: go-librespot daemon starting... Feb 07 17:17:56 volumio go-librespot[13594]: time="2026-02-07T17:17:56+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 17:17:56 volumio go-librespot[13594]: time="2026-02-07T17:17:56+07:00" level=debug msg="app state loaded" Feb 07 17:17:56 volumio go-librespot[13594]: time="2026-02-07T17:17:56+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 17:17:57 volumio go-librespot[13594]: time="2026-02-07T17:17:57+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 07 17:17:57 volumio go-librespot[13594]: time="2026-02-07T17:17:57+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 07 17:17:57 volumio go-librespot[13594]: time="2026-02-07T17:17:57+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 07 17:17:57 volumio go-librespot[13594]: time="2026-02-07T17:17:57+07:00" level=info msg="zeroconf server listening on port 39081" Feb 07 17:17:57 volumio go-librespot[13594]: time="2026-02-07T17:17:57+07:00" level=debug msg="obtained new client token: AAB60emWzX6zFLpKG5OeJ0h2iGYQn6vNYt6j3B8lWZ7my6HRVT2S8Qckg3qteHmikYCuyfFFBFv+X/i+j+QqloFMbYRoEDxY9NXHftVvExYbzZ+Of9b3ugl57QkQ3DZwcrYD98qLcvC02KtYbrhuqE/sYRka0B5m/iRjQapyVnYW5nX9Pbw39CUuipDCYGuT/3AS3yqmv3FuZ7jsiG5tW2A7XlN7FSSOk+8k8h1s8wu1dBljBhFtslWMxg==" Feb 07 17:17:57 volumio volumio[13265]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 07 17:17:57 volumio go-librespot[13594]: time="2026-02-07T17:17:57+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 07 17:17:57 volumio go-librespot[13594]: time="2026-02-07T17:17:57+07:00" level=debug msg="completed keyexchange" Feb 07 17:17:57 volumio go-librespot[13594]: time="2026-02-07T17:17:57+07:00" level=debug msg="completed challenge" Feb 07 17:17:57 volumio go-librespot[13594]: time="2026-02-07T17:17:57+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 07 17:17:57 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 17:17:57 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 17:17:58 volumio volumio[13265]: info: Initializing connection to go-librespot Websocket Feb 07 17:17:58 volumio volumio[13265]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 07 17:18:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 34. Feb 07 17:18:01 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 17:18:01 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 17:18:01 volumio go-librespot[13602]: go-librespot daemon starting... Feb 07 17:18:01 volumio go-librespot[13603]: time="2026-02-07T17:18:01+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 17:18:01 volumio go-librespot[13603]: time="2026-02-07T17:18:01+07:00" level=debug msg="app state loaded" Feb 07 17:18:01 volumio go-librespot[13603]: time="2026-02-07T17:18:01+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 17:18:01 volumio volumio[13265]: info: Initializing connection to go-librespot Websocket Feb 07 17:18:01 volumio go-librespot[13603]: time="2026-02-07T17:18:01+07:00" level=debug msg="new websocket client" Feb 07 17:18:01 volumio volumio[13265]: info: Connection to go-librespot Websocket established Feb 07 17:18:01 volumio go-librespot[13603]: time="2026-02-07T17:18:01+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 07 17:18:01 volumio go-librespot[13603]: time="2026-02-07T17:18:01+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 07 17:18:01 volumio go-librespot[13603]: time="2026-02-07T17:18:01+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 07 17:18:01 volumio go-librespot[13603]: time="2026-02-07T17:18:01+07:00" level=info msg="zeroconf server listening on port 38211" Feb 07 17:18:01 volumio volumio[13265]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 07 17:18:01 volumio volumio[13265]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 07 17:18:01 volumio volumio[13265]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 07 17:18:01 volumio volumio[13265]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 07 17:18:01 volumio volumio[13265]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 07 17:18:01 volumio volumio[13265]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 07 17:18:01 volumio volumio[13265]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 07 17:18:01 volumio volumio[13265]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 07 17:18:01 volumio volumio[13265]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 07 17:18:01 volumio volumio[13265]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 07 17:18:01 volumio volumio[13265]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 07 17:18:01 volumio volumio[13265]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 07 17:18:01 volumio volumio[13265]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 07 17:18:01 volumio volumio[13265]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 07 17:18:01 volumio volumio[13265]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 07 17:18:01 volumio volumio[13265]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 07 17:18:01 volumio volumio[13265]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 07 17:18:01 volumio volumio[13265]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 07 17:18:01 volumio volumio[13265]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 07 17:18:01 volumio volumio[13265]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 07 17:18:01 volumio volumio[13265]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 07 17:18:01 volumio volumio[13265]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 07 17:18:01 volumio volumio[13265]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 07 17:18:01 volumio volumio[13265]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 07 17:18:01 volumio volumio[13265]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 07 17:18:01 volumio volumio[13265]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 07 17:18:01 volumio volumio[13265]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 07 17:18:01 volumio volumio[13265]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 07 17:18:01 volumio volumio[13265]: info: Adding plugin bluetooth to MyMusic Plugins Feb 07 17:18:01 volumio volumio[13265]: info: Adding plugin multiroom to MyMusic Plugins Feb 07 17:18:01 volumio volumio[13265]: info: Adding plugin metavolumio to MyMusic Plugins Feb 07 17:18:01 volumio volumio[13265]: info: Adding plugin cd_controller to MyMusic Plugins Feb 07 17:18:01 volumio volumio[13265]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 07 17:18:01 volumio volumio[13265]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 07 17:18:01 volumio volumio[13265]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 07 17:18:01 volumio volumio[13265]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 07 17:18:01 volumio go-librespot[13603]: time="2026-02-07T17:18:01+07:00" level=debug msg="obtained new client token: AAAASoxb+xgTi0p+0wmj5n8ntdZogR2C5W40/hMB2ByfE0fpl8iH/nBQvd1EBk+ECiimM86rw6Fj+OPOt7N3Yzlk5zjCpPvk+iqfdnynR1YrYF0X0W7C9BhqpRCPZLX+dKIgwTKttaFv9tcyW4Z1e2p8CnLA5DkHreMe188g23LCYyrfFUtfAJ86C/jx6Ji44ZcujPv9KaQUQU5BrpLnc6Qrmow4zIg5pHaJdiDrYBUipNsivw/CDfiJyg==" Feb 07 17:18:02 volumio go-librespot[13603]: time="2026-02-07T17:18:02+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 07 17:18:02 volumio go-librespot[13603]: time="2026-02-07T17:18:02+07:00" level=debug msg="completed keyexchange" Feb 07 17:18:02 volumio go-librespot[13603]: time="2026-02-07T17:18:02+07:00" level=debug msg="completed challenge" Feb 07 17:18:02 volumio go-librespot[13603]: time="2026-02-07T17:18:02+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 07 17:18:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 17:18:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 17:18:03 volumio volumio[13265]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 07 17:18:03 volumio volumio[13265]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 07 17:18:03 volumio volumio[13265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 17:18:03 volumio volumio[13265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 17:18:03 volumio volumio[13265]: info: Starting MyVolumio Remote Streaming Endpoints Feb 07 17:18:03 volumio volumio[13265]: info: MyVolumio login type: Token Feb 07 17:18:03 volumio volumio[13265]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 07 17:18:03 volumio volumio[13265]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 07 17:18:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 35. Feb 07 17:18:05 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 17:18:05 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 17:18:05 volumio go-librespot[13625]: go-librespot daemon starting... Feb 07 17:18:05 volumio go-librespot[13626]: time="2026-02-07T17:18:05+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 17:18:05 volumio go-librespot[13626]: time="2026-02-07T17:18:05+07:00" level=debug msg="app state loaded" Feb 07 17:18:05 volumio go-librespot[13626]: time="2026-02-07T17:18:05+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 17:18:05 volumio volumio[13265]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 07 17:18:05 volumio volumio[13265]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 07 17:18:05 volumio volumio[13265]: info: Streaming services startup Feb 07 17:18:05 volumio volumio[13265]: info: Starting Streaming Daemon Feb 07 17:18:05 volumio sudo[13633]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 07 17:18:05 volumio sudo[13633]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 17:18:05 volumio volumio[13265]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 07 17:18:05 volumio sudo[13633]: pam_unix(sudo:session): session closed for user root Feb 07 17:18:05 volumio volumio[13265]: info: Getting Spotify volume Feb 07 17:18:05 volumio volumio[13265]: info: Connection to go-librespot Websocket closed Feb 07 17:18:05 volumio volumio[13265]: error: Cannot start Volumio Streaming Daemon Feb 07 17:18:05 volumio volumio[13265]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 07 17:18:05 volumio volumio[13265]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 07 17:18:05 volumio volumio[13265]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Feb 07 17:18:05 volumio go-librespot[13626]: time="2026-02-07T17:18:05+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 07 17:18:05 volumio go-librespot[13626]: time="2026-02-07T17:18:05+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 07 17:18:05 volumio go-librespot[13626]: time="2026-02-07T17:18:05+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 07 17:18:06 volumio go-librespot[13626]: time="2026-02-07T17:18:06+07:00" level=info msg="zeroconf server listening on port 36111" Feb 07 17:18:06 volumio volumio[13265]: info: CoreCommandRouter::volumioGetState Feb 07 17:18:06 volumio volumio[13265]: info: CorePlayQueue::getTrack 0 Feb 07 17:18:06 volumio volumio[13265]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 07 17:18:06 volumio volumio[13265]: SPOTIFY: SPOTIFY VOLUME undefined Feb 07 17:18:06 volumio volumio[13265]: SPOTIFY: VOLUMIO VOLUME 100 Feb 07 17:18:06 volumio volumio[13265]: info: Aligning Spotify Volume to Volumio Volume Feb 07 17:18:06 volumio volumio[13265]: info: CoreCommandRouter::volumioGetState Feb 07 17:18:06 volumio volumio[13265]: info: CorePlayQueue::getTrack 0 Feb 07 17:18:06 volumio volumio[13265]: info: Setting Spotify Volume from Volumio: 100 Feb 07 17:18:06 volumio go-librespot[13626]: time="2026-02-07T17:18:06+07:00" level=debug msg="obtained new client token: AACpPDkOWwPKJMfcHWALHK4gjy4gak/8xPnRijUm0/UiNLgEstQy4z42mxI5PGEYvHkGmhpZvZDaDQdcorCMmREab8QAOqF3Q3NI4rU821KQMVcISFzjeWhhZBLTnr8mbNWz/Z9Ii3fvZhC9vzZWCRn/CHNU3MjZPOmXb3fKigHgRzKdkSM3bndOFDrs3u0sZnQHjXBlBC/Cyk4WIMeS46tiQWQ5A8L3X7boc+hrj8LoVKlpsoni6Mo=" Feb 07 17:18:06 volumio go-librespot[13626]: time="2026-02-07T17:18:06+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 07 17:18:06 volumio go-librespot[13626]: time="2026-02-07T17:18:06+07:00" level=debug msg="completed keyexchange" Feb 07 17:18:06 volumio go-librespot[13626]: time="2026-02-07T17:18:06+07:00" level=debug msg="completed challenge" Feb 07 17:18:06 volumio go-librespot[13626]: time="2026-02-07T17:18:06+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 07 17:18:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 17:18:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 17:18:06 volumio volumio[13265]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 07 17:18:06 volumio volumio[13265]: Error: socket hang up Feb 07 17:18:06 volumio volumio[13265]: at connResetException (node:internal/errors:720:14) Feb 07 17:18:06 volumio volumio[13265]: at Socket.socketOnEnd (node:_http_client:519:23) Feb 07 17:18:06 volumio volumio[13265]: at Socket.emit (node:events:526:35) Feb 07 17:18:06 volumio volumio[13265]: at endReadableNT (node:internal/streams/readable:1376:12) Feb 07 17:18:06 volumio volumio[13265]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Feb 07 17:18:06 volumio volumio[13265]: code: 'ECONNRESET', Feb 07 17:18:06 volumio volumio[13265]: response: undefined Feb 07 17:18:06 volumio volumio[13265]: } Feb 07 17:18:06 volumio volumio[13265]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 07 17:18:07 volumio sudo[13654]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-07 17:17' Feb 07 17:18:07 volumio sudo[13654]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 17:18:08 volumio sudo[13654]: pam_unix(sudo:session): session closed for user root Feb 07 17:18:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 36. Feb 07 17:18:09 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 17:18:09 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 17:18:09 volumio go-librespot[13662]: go-librespot daemon starting... Feb 07 17:18:09 volumio go-librespot[13663]: time="2026-02-07T17:18:09+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 17:18:09 volumio go-librespot[13663]: time="2026-02-07T17:18:09+07:00" level=debug msg="app state loaded" Feb 07 17:18:09 volumio go-librespot[13663]: time="2026-02-07T17:18:09+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 17:18:09 volumio volumio-remote-updater[643]: [2026-02-07 17:18:09] [error] handle_read_frame error: asio.system:104 (Connection reset by peer) Feb 07 17:18:09 volumio volumio-remote-updater[643]: [2026-02-07 17:18:09] [disconnect] Disconnect close local:[1006,Connection reset by peer] remote:[1006] Feb 07 17:18:09 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 07 17:18:09 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 07 17:18:09 volumio systemd[1]: volumio.service: Consumed 55.747s CPU time. Feb 07 17:18:09 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 07 17:18:09 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 07 17:18:09 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 6075. Feb 07 17:18:09 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 07 17:18:09 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 07 17:18:09 volumio systemd[1]: volumio.service: Consumed 55.747s CPU time. Feb 07 17:18:09 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Feb 07 17:18:09 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 07 17:18:10 volumio go-librespot[13663]: time="2026-02-07T17:18:10+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 07 17:18:10 volumio go-librespot[13663]: time="2026-02-07T17:18:10+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 07 17:18:10 volumio go-librespot[13663]: time="2026-02-07T17:18:10+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 07 17:18:10 volumio go-librespot[13663]: time="2026-02-07T17:18:10+07:00" level=info msg="zeroconf server listening on port 43613" Feb 07 17:18:10 volumio go-librespot[13663]: time="2026-02-07T17:18:10+07:00" level=debug msg="obtained new client token: AAA94v1+Iey9nSxZmiLUM3dOzzMkK4f1nRI1RsydmIsRaxlRC6n+zatWut9bvEKohliiU9Yco2EPMQIcwvKOUpaj0yz6TTCytGLex8kPs1XZ4SZTq/+ruBhUbmj9W1IWH9GpcNnA37XUv3IipJGEkssiqtyRzR0XtMs5hJvluWdt+a5C49JJKbBQASbSrdfQzlFQTIS6yl9TDK6K4rIYsD18+P6T3kP96HrOujsL1KnHkqj3DqywxZx8pQ==" Feb 07 17:18:10 volumio go-librespot[13663]: time="2026-02-07T17:18:10+07:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.241.202:4070: connect: connection refused" Feb 07 17:18:10 volumio go-librespot[13663]: time="2026-02-07T17:18:10+07:00" level=debug msg="connected to ap-gae2.spotify.com:443" Feb 07 17:18:10 volumio go-librespot[13663]: time="2026-02-07T17:18:10+07:00" level=debug msg="completed keyexchange" Feb 07 17:18:10 volumio go-librespot[13663]: time="2026-02-07T17:18:10+07:00" level=debug msg="completed challenge" Feb 07 17:18:10 volumio go-librespot[13663]: time="2026-02-07T17:18:10+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 07 17:18:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 17:18:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 17:18:13 volumio volumio[13685]: info: ------------------------------------------- Feb 07 17:18:13 volumio volumio[13685]: info: ----- Volumio3 ---- Feb 07 17:18:13 volumio volumio[13685]: info: ------------------------------------------- Feb 07 17:18:13 volumio volumio[13685]: info: ----- System startup ---- Feb 07 17:18:13 volumio volumio[13685]: info: ------------------------------------------- Feb 07 17:18:14 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 37. Feb 07 17:18:14 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 17:18:14 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 17:18:14 volumio go-librespot[13706]: go-librespot daemon starting... Feb 07 17:18:14 volumio go-librespot[13707]: time="2026-02-07T17:18:14+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 17:18:14 volumio go-librespot[13707]: time="2026-02-07T17:18:14+07:00" level=debug msg="app state loaded" Feb 07 17:18:14 volumio go-librespot[13707]: time="2026-02-07T17:18:14+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 17:18:14 volumio go-librespot[13707]: time="2026-02-07T17:18:14+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 07 17:18:14 volumio go-librespot[13707]: time="2026-02-07T17:18:14+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 07 17:18:14 volumio go-librespot[13707]: time="2026-02-07T17:18:14+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 07 17:18:14 volumio go-librespot[13707]: time="2026-02-07T17:18:14+07:00" level=info msg="zeroconf server listening on port 41679" Feb 07 17:18:14 volumio volumio-remote-updater[643]: [2026-02-07 17:18:14] [connect] Successful connection Feb 07 17:18:14 volumio volumio[13685]: info: MYVOLUMIO Environment detected Feb 07 17:18:14 volumio go-librespot[13707]: time="2026-02-07T17:18:14+07:00" level=debug msg="obtained new client token: AACbvRE6cTkO/taLVY2jpAjjTuvB6cgJnnwID254Oc8eMHZ+BlrnoCpv4kCR9eVIlsqb+hXbPo2RSo4Kr9wFVXzON7xiCZzFSZ2JKsSE7gjxvnRTImj99uPno6nwBthI2sw5qTljoFzI8M2//Qp4fyJrDWtV+jdQZR1MeQKwjUI5x3J0NgHQkmZiMzS8M/e6pk9RttkcWOvIT+nvFnb8ciTcozViGlPoEZPAercCr46OndrGj6wh1L4+fg==" Feb 07 17:18:15 volumio volumio[13685]: info: Plugin folders cleanup Feb 07 17:18:15 volumio volumio[13685]: info: Scanning into folder /volumio/app/plugins/ Feb 07 17:18:15 volumio volumio[13685]: info: Scanning category audio_interface Feb 07 17:18:15 volumio volumio[13685]: info: Scanning category miscellanea Feb 07 17:18:15 volumio volumio[13685]: info: Scanning category music_service Feb 07 17:18:15 volumio volumio[13685]: info: Scanning category plugins.json Feb 07 17:18:15 volumio volumio[13685]: info: Scanning category system_controller Feb 07 17:18:15 volumio go-librespot[13707]: time="2026-02-07T17:18:15+07:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.241.202:4070: connect: connection refused" Feb 07 17:18:15 volumio volumio[13685]: info: Scanning category user_interface Feb 07 17:18:15 volumio volumio[13685]: info: Scanning into folder /data/plugins/ Feb 07 17:18:15 volumio volumio[13685]: info: Scanning category music_service Feb 07 17:18:15 volumio volumio[13685]: info: Plugin folders cleanup completed Feb 07 17:18:15 volumio volumio[13685]: info: ------------------------------------------- Feb 07 17:18:15 volumio volumio[13685]: info: ----- Core plugins startup ---- Feb 07 17:18:15 volumio volumio[13685]: info: ------------------------------------------- Feb 07 17:18:15 volumio volumio[13685]: info: Loading plugins from folder /volumio/app/plugins/ Feb 07 17:18:15 volumio volumio[13685]: info: Adding plugin upnp to MyMusic Plugins Feb 07 17:18:15 volumio volumio[13685]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 07 17:18:15 volumio volumio[13685]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 07 17:18:15 volumio volumio[13685]: info: Loading plugins from folder /data/plugins/ Feb 07 17:18:15 volumio volumio[13685]: info: Loading plugin "system"... Feb 07 17:18:15 volumio go-librespot[13707]: time="2026-02-07T17:18:15+07:00" level=debug msg="connected to ap-gae2.spotify.com:443" Feb 07 17:18:15 volumio volumio[13685]: info: Loading plugin "appearance"... Feb 07 17:18:15 volumio go-librespot[13707]: time="2026-02-07T17:18:15+07:00" level=debug msg="completed keyexchange" Feb 07 17:18:15 volumio go-librespot[13707]: time="2026-02-07T17:18:15+07:00" level=debug msg="completed challenge" Feb 07 17:18:15 volumio go-librespot[13707]: time="2026-02-07T17:18:15+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 07 17:18:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 17:18:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 17:18:17 volumio volumio[13685]: info: Loading plugin "network"... Feb 07 17:18:17 volumio volumio[13685]: info: Refreshing Cached IP Addresses Feb 07 17:18:17 volumio sudo[13721]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 07 17:18:17 volumio sudo[13721]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 17:18:17 volumio sudo[13723]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 07 17:18:17 volumio sudo[13723]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 17:18:17 volumio sudo[13721]: pam_unix(sudo:session): session closed for user root Feb 07 17:18:17 volumio volumio[13685]: info: Loading plugin "services"... Feb 07 17:18:17 volumio volumio[13685]: info: Loading plugin "alsa_controller"... Feb 07 17:18:17 volumio sudo[13723]: pam_unix(sudo:session): session closed for user root Feb 07 17:18:17 volumio sudo[13730]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 07 17:18:17 volumio sudo[13730]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 17:18:17 volumio volumio[13685]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 07 17:18:17 volumio volumio[13685]: info: Loading plugin "wizard"... Feb 07 17:18:17 volumio volumio[13685]: info: Loading plugin "networkfs"... Feb 07 17:18:17 volumio volumio[13685]: info: Starting Udev Watcher for removable devices Feb 07 17:18:17 volumio volumio[13685]: info: Ignoring mount for partition: boot Feb 07 17:18:17 volumio volumio[13685]: info: Ignoring mount for partition: volumio Feb 07 17:18:17 volumio volumio[13685]: info: Ignoring mount for partition: volumio_data Feb 07 17:18:17 volumio volumio[13685]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 07 17:18:17 volumio volumio[13685]: info: Loading plugin "volumio_command_line_client"... Feb 07 17:18:17 volumio volumio[13685]: info: Loading plugin "upnp"... Feb 07 17:18:17 volumio volumio[13685]: info: [1770459497485] Starting Upmpd Daemon Feb 07 17:18:17 volumio volumio[13685]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 07 17:18:17 volumio volumio[13685]: info: Loading plugin "my_music"... Feb 07 17:18:17 volumio volumio[13685]: info: Loading plugin "mpd"... Feb 07 17:18:18 volumio volumio[13685]: info: Loading plugin "upnp_browser"... Feb 07 17:18:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 38. Feb 07 17:18:18 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 17:18:18 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 17:18:18 volumio go-librespot[13754]: go-librespot daemon starting... Feb 07 17:18:18 volumio go-librespot[13755]: time="2026-02-07T17:18:18+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 17:18:18 volumio go-librespot[13755]: time="2026-02-07T17:18:18+07:00" level=debug msg="app state loaded" Feb 07 17:18:18 volumio go-librespot[13755]: time="2026-02-07T17:18:18+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 17:18:19 volumio go-librespot[13755]: time="2026-02-07T17:18:19+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 07 17:18:19 volumio go-librespot[13755]: time="2026-02-07T17:18:19+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 07 17:18:19 volumio go-librespot[13755]: time="2026-02-07T17:18:19+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 07 17:18:19 volumio go-librespot[13755]: time="2026-02-07T17:18:19+07:00" level=info msg="zeroconf server listening on port 40735" Feb 07 17:18:19 volumio go-librespot[13755]: time="2026-02-07T17:18:19+07:00" level=debug msg="obtained new client token: AADWPtwdG2Jmj+p0OfrQOnkg064EL0fg8FKyu1PsoxudASidj7F4Q5yjc1FU/MO1q/mCIlKV7B3PZ2frDNZrktXvvtR5hXWs5tslhk1k+R5K2rEyM1h1JNiAkvGaBGPXvMCGl688+tEJqdG0cQgX93hd6qjycuWonMLaddf7UzgEZ+NoLxmIlgJaG99dg1gjQ5oS8cMojpnDY7/s1fsAXMAQUZzQHCjIgXYZIaVDsT7HXw3ZMCMp9FlVXQ==" Feb 07 17:18:19 volumio go-librespot[13755]: time="2026-02-07T17:18:19+07:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.241.202:4070: connect: connection refused" Feb 07 17:18:19 volumio go-librespot[13755]: time="2026-02-07T17:18:19+07:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:443, retrying with a different AP" error="dial tcp 104.199.241.202:443: connect: connection refused" Feb 07 17:18:19 volumio go-librespot[13755]: time="2026-02-07T17:18:19+07:00" level=debug msg="connected to ap-gae2.spotify.com:80" Feb 07 17:18:19 volumio go-librespot[13755]: time="2026-02-07T17:18:19+07:00" level=debug msg="completed keyexchange" Feb 07 17:18:19 volumio go-librespot[13755]: time="2026-02-07T17:18:19+07:00" level=debug msg="completed challenge" Feb 07 17:18:19 volumio go-librespot[13755]: time="2026-02-07T17:18:19+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 07 17:18:19 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 17:18:19 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 17:18:19 volumio sudo[13730]: pam_unix(sudo:session): session closed for user root Feb 07 17:18:21 volumio volumio[13685]: info: Starting UPNP Browser Feb 07 17:18:21 volumio volumio[13685]: info: Loading plugin "alarm-clock"... Feb 07 17:18:21 volumio volumio[13685]: info: Loading plugin "airplay_emulation"... Feb 07 17:18:21 volumio volumio[13685]: info: Starting Shairport Sync Feb 07 17:18:21 volumio volumio[13685]: info: Loading plugin "last_100"... Feb 07 17:18:21 volumio volumio[13685]: info: Loading plugin "webradio"... Feb 07 17:18:21 volumio volumio[13685]: info: Loading plugin "i2s_dacs"... Feb 07 17:18:21 volumio volumio[13685]: info: Loading plugin "volumiodiscovery"... Feb 07 17:18:21 volumio volumio[13685]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 07 17:18:21 volumio volumio[13685]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 07 17:18:21 volumio node[13685]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 07 17:18:21 volumio volumio[13685]: *** WARNING *** For more information see Feb 07 17:18:21 volumio volumio[13685]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 07 17:18:21 volumio volumio[13685]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 07 17:18:21 volumio volumio[13685]: *** WARNING *** For more information see Feb 07 17:18:21 volumio node[13685]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 07 17:18:21 volumio node[13685]: *** WARNING *** For more information see Feb 07 17:18:21 volumio node[13685]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 07 17:18:21 volumio node[13685]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 07 17:18:21 volumio node[13685]: *** WARNING *** For more information see Feb 07 17:18:21 volumio volumio[13685]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 07 17:18:21 volumio volumio[13685]: info: Discovery: Started advertising with name: Volumio Feb 07 17:18:21 volumio volumio[13685]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 07 17:18:21 volumio volumio[13685]: info: Loading plugin "spop"... Feb 07 17:18:22 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 39. Feb 07 17:18:22 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 17:18:22 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 17:18:22 volumio go-librespot[13764]: go-librespot daemon starting... Feb 07 17:18:23 volumio go-librespot[13765]: time="2026-02-07T17:18:23+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 17:18:23 volumio go-librespot[13765]: time="2026-02-07T17:18:23+07:00" level=debug msg="app state loaded" Feb 07 17:18:23 volumio go-librespot[13765]: time="2026-02-07T17:18:23+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 17:18:23 volumio go-librespot[13765]: time="2026-02-07T17:18:23+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 07 17:18:23 volumio go-librespot[13765]: time="2026-02-07T17:18:23+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 07 17:18:23 volumio go-librespot[13765]: time="2026-02-07T17:18:23+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 07 17:18:23 volumio go-librespot[13765]: time="2026-02-07T17:18:23+07:00" level=info msg="zeroconf server listening on port 40979" Feb 07 17:18:23 volumio volumio[13685]: info: Loading plugin "ytcr"... Feb 07 17:18:23 volumio go-librespot[13765]: time="2026-02-07T17:18:23+07:00" level=debug msg="obtained new client token: AACYWuJjRwmMmJezfwYsdkjoZfy5di3aUyqiEhtgNyBh+yVQe2YUl1UT4mJnvH/Ks3sy/xqIdEk1b1tQigyNNh39jVm9vuJoh1FfDh3aCiOVhwAdc1/51ddZBQGk9T1wmaAeJBrnZqBwkAYbf6zXoN+tInH04LYS/bA45+yLHOyLNM0p9GQgGo6WBMaKIz6+r9xHUPPGNLGmDU3nEAOxqkJwL362k0baGBK2SXjS4seIsM8pl5HN1WzMmg==" Feb 07 17:18:23 volumio go-librespot[13765]: time="2026-02-07T17:18:23+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 07 17:18:24 volumio go-librespot[13765]: time="2026-02-07T17:18:24+07:00" level=debug msg="completed keyexchange" Feb 07 17:18:24 volumio go-librespot[13765]: time="2026-02-07T17:18:24+07:00" level=debug msg="completed challenge" Feb 07 17:18:24 volumio go-librespot[13765]: time="2026-02-07T17:18:24+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 07 17:18:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 17:18:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 17:18:26 volumio volumio[13685]: info: Loading plugin "ytmusic"... Feb 07 17:18:27 volumio volumio-remote-updater[643]: [2026-02-07 17:18:27] [connect] Successful connection Feb 07 17:18:27 volumio volumio[13685]: info: Loading plugin "outputs"... Feb 07 17:18:27 volumio volumio[13685]: info: Loading plugin "albumart"... Feb 07 17:18:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 40. Feb 07 17:18:27 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 17:18:27 volumio volumio[13685]: info: Plugin example_plugin is not enabled Feb 07 17:18:27 volumio volumio[13685]: info: Loading plugin "inputs"... Feb 07 17:18:27 volumio volumio[13685]: info: Loading plugin "updater_comm"... Feb 07 17:18:27 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 17:18:27 volumio go-librespot[13788]: go-librespot daemon starting... Feb 07 17:18:27 volumio go-librespot[13798]: time="2026-02-07T17:18:27+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 17:18:27 volumio go-librespot[13798]: time="2026-02-07T17:18:27+07:00" level=debug msg="app state loaded" Feb 07 17:18:27 volumio go-librespot[13798]: time="2026-02-07T17:18:27+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 17:18:27 volumio volumio[13685]: info: Plugin mpdemulation is not enabled Feb 07 17:18:27 volumio volumio[13685]: info: Loading plugin "rest_api"... Feb 07 17:18:27 volumio volumio[13685]: info: Loading plugin "websocket"... Feb 07 17:18:27 volumio volumio[13685]: info: Starting Socket.io Server version 1.7.4 Feb 07 17:18:27 volumio volumio[13685]: info: Loading plugin "RoonBridge"... Feb 07 17:18:28 volumio go-librespot[13798]: time="2026-02-07T17:18:28+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 07 17:18:28 volumio go-librespot[13798]: time="2026-02-07T17:18:28+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 07 17:18:28 volumio go-librespot[13798]: time="2026-02-07T17:18:28+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 07 17:18:28 volumio go-librespot[13798]: time="2026-02-07T17:18:28+07:00" level=info msg="zeroconf server listening on port 40195" Feb 07 17:18:28 volumio volumio[13685]: info: Applying required configuration parameters for plugin RoonBridge Feb 07 17:18:28 volumio volumio[13685]: info: Loading i18n strings for locale en Feb 07 17:18:28 volumio volumio[13685]: Updating browse sources language Feb 07 17:18:28 volumio volumio[13685]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 17:18:28 volumio go-librespot[13798]: time="2026-02-07T17:18:28+07:00" level=debug msg="obtained new client token: AABNZ2zBsJCpfT9EUeuo2sqsVk8S4aTQhURBkja0VN7+xqERdDgHS1MTNcd2h8ksL100kl9rt1zrN4uwVJldxDXIzwNTFXdAirLbztGkw4o2Mx4lk9GE78RmOUqJkqmOh8ZRzt1+3wB29Wprhsc1wXtbUgMZ2QusmCpc9hbK7RIHEq7l0WPL8t4T3fVY4RtPCfjyZyUJKyMPzVIzhcJE1y1mHK9wyfJQkfcGCtqtRciAGFVWULujcksgmA==" Feb 07 17:18:28 volumio go-librespot[13798]: time="2026-02-07T17:18:28+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 07 17:18:28 volumio go-librespot[13798]: time="2026-02-07T17:18:28+07:00" level=debug msg="completed keyexchange" Feb 07 17:18:28 volumio go-librespot[13798]: time="2026-02-07T17:18:28+07:00" level=debug msg="completed challenge" Feb 07 17:18:28 volumio volumio[13685]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 07 17:18:28 volumio volumio[13685]: info: CoreCommandRouter::initPlayerControls Feb 07 17:18:28 volumio volumio[13685]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 17:18:28 volumio volumio[13685]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 17:18:28 volumio volumio[13685]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 17:18:28 volumio volumio[13685]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 17:18:28 volumio volumio[13685]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 17:18:28 volumio volumio[13685]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 17:18:28 volumio volumio[13685]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 17:18:28 volumio volumio[13685]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 17:18:28 volumio volumio[13789]: Forking 3 albumart workers Feb 07 17:18:28 volumio volumio[13685]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 07 17:18:28 volumio volumio[13685]: Express server listening on port 3000 Feb 07 17:18:28 volumio volumio[13685]: [Metrics] WebUI: 16s 51.22ms Feb 07 17:18:28 volumio go-librespot[13798]: time="2026-02-07T17:18:28+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 07 17:18:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 17:18:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 17:18:28 volumio volumio[13685]: info: CoreStateMachine::resetVolumioState Feb 07 17:18:28 volumio volumio[13685]: info: CoreStateMachine::getcurrentVolume Feb 07 17:18:28 volumio volumio[13685]: info: CoreCommandRouter::volumioRetrievevolume Feb 07 17:18:28 volumio sudo[13839]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 07 17:18:28 volumio sudo[13839]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 17:18:28 volumio sudo[13841]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 07 17:18:28 volumio sudo[13839]: pam_unix(sudo:session): session closed for user root Feb 07 17:18:28 volumio sudo[13841]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 17:18:28 volumio volumio[13685]: info: Volumio Network Manager: Network status updated: 1 Feb 07 17:18:28 volumio sudo[13841]: pam_unix(sudo:session): session closed for user root Feb 07 17:18:29 volumio volumio[13685]: info: VolumeController:: Volume=100 Mute =false Feb 07 17:18:29 volumio volumio[13685]: info: CoreStateMachine::pushState Feb 07 17:18:29 volumio volumio[13685]: info: CorePlayQueue::getTrack 0 Feb 07 17:18:29 volumio volumio[13685]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 07 17:18:29 volumio volumio[13685]: info: CoreCommandRouter::volumioPushState Feb 07 17:18:29 volumio volumio[13685]: info: CoreStateMachine::updateTrackBlock Feb 07 17:18:29 volumio volumio[13685]: info: CorePlayQueue::getTrackBlock Feb 07 17:18:29 volumio volumio[13685]: info: CoreCommandRouter::volumioRetrievevolume Feb 07 17:18:29 volumio volumio-remote-updater[643]: [2026-02-07 17:18:29] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1770459507 101 Feb 07 17:18:29 volumio volumio[13685]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 1 Feb 07 17:18:29 volumio volumio[13685]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 17:18:29 volumio volumio[13685]: info: Reloading queue from file Feb 07 17:18:29 volumio volumio[13685]: info: CoreStateMachine::setRepeat null single undefined Feb 07 17:18:29 volumio volumio[13685]: info: CoreStateMachine::pushState Feb 07 17:18:29 volumio volumio[13685]: info: CorePlayQueue::getTrack 0 Feb 07 17:18:29 volumio volumio[13685]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 07 17:18:29 volumio volumio[13685]: info: CoreCommandRouter::volumioPushState Feb 07 17:18:29 volumio volumio[13685]: info: CoreStateMachine::setRandom null Feb 07 17:18:29 volumio volumio[13685]: info: CoreStateMachine::pushState Feb 07 17:18:29 volumio volumio[13685]: info: CorePlayQueue::getTrack 0 Feb 07 17:18:29 volumio volumio[13685]: info: CoreCommandRouter::volumioPushState Feb 07 17:18:29 volumio volumio[13685]: info: Setting Device type: Raspberry PI Feb 07 17:18:29 volumio volumio[13685]: info: Completed loading Core Plugins Feb 07 17:18:29 volumio volumio[13685]: info: Preparing to generate the ALSA configuration file Feb 07 17:18:29 volumio volumio[13685]: info: VolumeController:: Volume=100 Mute =false Feb 07 17:18:29 volumio volumio[13685]: info: CoreStateMachine::pushState Feb 07 17:18:29 volumio volumio[13685]: info: CorePlayQueue::getTrack 0 Feb 07 17:18:29 volumio volumio[13685]: info: CoreCommandRouter::volumioPushState Feb 07 17:18:29 volumio volumio[13685]: info: Asound.conf file unchanged, so no further update is needed Feb 07 17:18:29 volumio volumio[13685]: info: Output device has changed, restarting MPD Feb 07 17:18:29 volumio volumio[13685]: info: Output device has changed, restarting Shairport Sync Feb 07 17:18:29 volumio sudo[13858]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 07 17:18:29 volumio volumio[13685]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 17:18:29 volumio sudo[13858]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 17:18:29 volumio volumio[13685]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 17:18:29 volumio sudo[13860]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 07 17:18:29 volumio sudo[13860]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 17:18:29 volumio sudo[13863]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 07 17:18:29 volumio sudo[13863]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 17:18:29 volumio sudo[13860]: pam_unix(sudo:session): session closed for user root Feb 07 17:18:29 volumio volumio[13685]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 07 17:18:29 volumio volumio[13685]: info: ___________ START PLUGINS ___________ Feb 07 17:18:29 volumio sudo[13858]: pam_unix(sudo:session): session closed for user root Feb 07 17:18:29 volumio volumio[13685]: info: ControllerMpd::onStart: Initializing MPD Feb 07 17:18:29 volumio volumio[13685]: info: Creating MPD Configuration file Feb 07 17:18:30 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 07 17:18:30 volumio volumio[13685]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 07 17:18:30 volumio volumio[13685]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 17:18:30 volumio volumio[13685]: info: [1770459510055] CoreMusicLibrary::Adding element Media Servers Feb 07 17:18:30 volumio volumio[13685]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 17:18:30 volumio sudo[13873]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 07 17:18:30 volumio sudo[13873]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 17:18:30 volumio volumio[13685]: info: UPNP Browser: Client initialized successfully Feb 07 17:18:30 volumio sudo[13871]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 07 17:18:30 volumio sudo[13871]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 17:18:30 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 07 17:18:30 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 07 17:18:30 volumio systemd[1]: mpd.service: Consumed 7.173s CPU time. Feb 07 17:18:30 volumio sudo[13871]: pam_unix(sudo:session): session closed for user root Feb 07 17:18:30 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 07 17:18:30 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 07 17:18:30 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 07 17:18:30 volumio volumio[13685]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 17:18:30 volumio volumio[13685]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 17:18:30 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 07 17:18:30 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 07 17:18:30 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 07 17:18:30 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 07 17:18:30 volumio volumio[13685]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 07 17:18:30 volumio volumio[13685]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 17:18:30 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 07 17:18:30 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 07 17:18:30 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 07 17:18:30 volumio volumio[13685]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 17:18:30 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 07 17:18:30 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 07 17:18:30 volumio volumio[13685]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 07 17:18:30 volumio volumio[13685]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 17:18:30 volumio volumio[13685]: info: [1770459510494] CoreMusicLibrary::Adding element Last_100 Feb 07 17:18:30 volumio volumio[13685]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 17:18:30 volumio volumio[13685]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 17:18:30 volumio volumio[13685]: info: [1770459510517] CoreMusicLibrary::Adding element Webradio Feb 07 17:18:30 volumio volumio[13685]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 17:18:30 volumio volumio[13685]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 07 17:18:30 volumio volumio[13685]: info: Initializing BBC Radios Feb 07 17:18:30 volumio volumio[13685]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 07 17:18:30 volumio volumio[13685]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 17:18:30 volumio volumio[13685]: info: Creating Spotify config file Feb 07 17:18:30 volumio volumio[13685]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 17:18:30 volumio sudo[13887]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 07 17:18:30 volumio sudo[13887]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 07 17:18:30 volumio sudo[13887]: pam_unix(sudo:session): session closed for user root Feb 07 17:18:31 volumio volumio[13810]: Starting albumart workers Feb 07 17:18:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 41. Feb 07 17:18:31 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 17:18:31 volumio volumio[13809]: Starting albumart workers Feb 07 17:18:31 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 17:18:31 volumio go-librespot[13903]: go-librespot daemon starting... Feb 07 17:18:32 volumio go-librespot[13904]: time="2026-02-07T17:18:32+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 17:18:32 volumio volumio[13685]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 17:18:32 volumio volumio[13685]: info: [1770459512050] CoreMusicLibrary::Adding element YouTube Music Feb 07 17:18:32 volumio volumio[13685]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 17:18:32 volumio volumio[13685]: Cannot find translation for source YouTube Music Feb 07 17:18:32 volumio volumio[13685]: info: Volumio Calling Home Feb 07 17:18:32 volumio sudo[13914]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Feb 07 17:18:32 volumio sudo[13914]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 17:18:32 volumio volumio[13807]: Starting albumart workers Feb 07 17:18:32 volumio sudo[13914]: pam_unix(sudo:session): session closed for user root Feb 07 17:18:32 volumio go-librespot[13904]: time="2026-02-07T17:18:32+07:00" level=info msg="zeroconf server listening on port 40927" Feb 07 17:18:33 volumio volumio[13685]: info: Discovery: adding 921648ed-0557-4d70-9927-1420682efcc7 Feb 07 17:18:33 volumio volumio[13685]: info: Discovery: Found device Volumio Feb 07 17:18:33 volumio volumio[13685]: info: CoreCommandRouter::volumioGetState Feb 07 17:18:33 volumio volumio[13685]: info: CorePlayQueue::getTrack 0 Feb 07 17:18:33 volumio volumio[13685]: info: Discovery: this is already registered, 921648ed-0557-4d70-9927-1420682efcc7 Feb 07 17:18:33 volumio volumio[13685]: info: Discovery: Found device Volumio Feb 07 17:18:33 volumio volumio[13685]: info: CoreCommandRouter::volumioGetState Feb 07 17:18:33 volumio volumio[13685]: info: CorePlayQueue::getTrack 0 Feb 07 17:18:33 volumio volumio[13685]: info: MPD Permissions set Feb 07 17:18:33 volumio volumio[13685]: info: MPD Permissions set Feb 07 17:18:33 volumio volumio[13685]: info: Upmpdcli Daemon Started Feb 07 17:18:33 volumio volumio[13685]: info: Volumio called home Feb 07 17:18:33 volumio volumio[13685]: info: Spotify config file written Feb 07 17:18:33 volumio volumio[13685]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Feb 07 17:18:33 volumio volumio[13685]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 17:18:33 volumio volumio[13685]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 17:18:33 volumio sudo[13928]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 07 17:18:33 volumio sudo[13928]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 17:18:33 volumio volumio[13685]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 17:18:33 volumio volumio[13685]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 17:18:33 volumio volumio[13685]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 17:18:33 volumio volumio[13685]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 17:18:33 volumio volumio[13685]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 17:18:33 volumio volumio[13685]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 17:18:33 volumio volumio[13685]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 17:18:33 volumio volumio[13685]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 17:18:33 volumio volumio[13685]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 17:18:33 volumio volumio[13685]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 17:18:33 volumio volumio[13685]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 17:18:33 volumio volumio[13685]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 17:18:33 volumio volumio[13685]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 17:18:33 volumio volumio[13685]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 17:18:33 volumio volumio[13685]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 17:18:33 volumio volumio[13685]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 17:18:33 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Feb 07 17:18:33 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Feb 07 17:18:33 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 17:18:33 volumio volumio[13685]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 17:18:33 volumio volumio[13685]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 17:18:33 volumio volumio[13685]: info: No need to fix Spotify hosts Feb 07 17:18:33 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 17:18:33 volumio go-librespot[13940]: go-librespot daemon starting... Feb 07 17:18:33 volumio sudo[13928]: pam_unix(sudo:session): session closed for user root Feb 07 17:18:33 volumio go-librespot[13941]: time="2026-02-07T17:18:33+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 17:18:33 volumio go-librespot[13941]: time="2026-02-07T17:18:33+07:00" level=debug msg="app state loaded" Feb 07 17:18:33 volumio go-librespot[13941]: time="2026-02-07T17:18:33+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 17:18:34 volumio go-librespot[13941]: time="2026-02-07T17:18:34+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 07 17:18:34 volumio go-librespot[13941]: time="2026-02-07T17:18:34+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 07 17:18:34 volumio go-librespot[13941]: time="2026-02-07T17:18:34+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 07 17:18:34 volumio go-librespot[13941]: time="2026-02-07T17:18:34+07:00" level=info msg="zeroconf server listening on port 40473" Feb 07 17:18:34 volumio volumio[13685]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 07 17:18:34 volumio volumio[13685]: SPOTIFY: BQAnZeftsfsv4lHlQQ0tlcd_9d-chfZ1zKvB3YiR8cy2zTHLqTSd2tiXkaYpl_5mvGkDtyOo871oJ7nPU4Xri8PNNmZ4UutObaym0fS946Z10kha-nQpcEsRioRveqGjco4XXUKWAPHisMd7o76U0C7bIyqKIKB35-6ArjWyJ8HU7I8B297YfR0UTAWrI4pOrVa_cra9YNrQgP7ur1bZJFFZJ5A560mA151vNN9uI24iVlOlaHBhoShmkeFHVIrQMJMw0NKO9VA_GFdT-6fS16fcd_c4un4ZlgY-eNGV_GvPkI_TFmp22e7J Feb 07 17:18:34 volumio volumio[13685]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 07 17:18:34 volumio volumio[13685]: info: New Spotify access token = BQAnZeftsfsv4lHlQQ0tlcd_9d-chfZ1zKvB3YiR8cy2zTHLqTSd2tiXkaYpl_5mvGkDtyOo871oJ7nPU4Xri8PNNmZ4UutObaym0fS946Z10kha-nQpcEsRioRveqGjco4XXUKWAPHisMd7o76U0C7bIyqKIKB35-6ArjWyJ8HU7I8B297YfR0UTAWrI4pOrVa_cra9YNrQgP7ur1bZJFFZJ5A560mA151vNN9uI24iVlOlaHBhoShmkeFHVIrQMJMw0NKO9VA_GFdT-6fS16fcd_c4un4ZlgY-eNGV_GvPkI_TFmp22e7J Feb 07 17:18:34 volumio volumio[13685]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 07 17:18:34 volumio volumio[13685]: info: Starting Shairport Sync Feb 07 17:18:34 volumio volumio[13685]: info: Starting Shairport Sync Feb 07 17:18:34 volumio volumio[13685]: info: Starting Shairport Sync Feb 07 17:18:34 volumio sudo[13961]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 07 17:18:34 volumio sudo[13961]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 17:18:34 volumio go-librespot[13941]: time="2026-02-07T17:18:34+07:00" level=debug msg="obtained new client token: AABYquZqHMl/AJ8miNRMo1JLz0UBFyXqxM+yo2jdAQTxkwT7CWnAYnJ/2duvXhrztkiet345YjKjEiVjAQX3f8TXiKssmIQ76wFC0aWCdJt10Sw9BBP+OuHoynPX89dgFBLsX68svMD7K/XXUNuRyYVhDtgEG2UnyJV9pGOKunxG5uP4rYj5kLai7hJXrlPVm/RyFGii+qRLWoW17+kzxwHIL4q3h8bU48IUFLlf8PloK+hpURVWiN8QYQ==" Feb 07 17:18:34 volumio sudo[13963]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 07 17:18:34 volumio sudo[13963]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 17:18:35 volumio sudo[13965]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 07 17:18:35 volumio sudo[13965]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 17:18:35 volumio go-librespot[13941]: time="2026-02-07T17:18:35+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 07 17:18:35 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 07 17:18:35 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 07 17:18:35 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 07 17:18:35 volumio systemd[1]: shairport-sync.service: Consumed 2.341s CPU time. Feb 07 17:18:35 volumio go-librespot[13941]: time="2026-02-07T17:18:35+07:00" level=debug msg="completed keyexchange" Feb 07 17:18:35 volumio go-librespot[13941]: time="2026-02-07T17:18:35+07:00" level=debug msg="completed challenge" Feb 07 17:18:35 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 07 17:18:35 volumio go-librespot[13941]: time="2026-02-07T17:18:35+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 07 17:18:35 volumio sudo[13961]: pam_unix(sudo:session): session closed for user root Feb 07 17:18:35 volumio volumio[13685]: info: CoreCommandRouter::volumioGetState Feb 07 17:18:35 volumio volumio[13685]: info: CorePlayQueue::getTrack 0 Feb 07 17:18:35 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 17:18:35 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 17:18:35 volumio sudo[13965]: pam_unix(sudo:session): session closed for user root Feb 07 17:18:35 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 07 17:18:35 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 07 17:18:35 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 07 17:18:35 volumio volumio[13685]: info: Shairport-Sync Started Feb 07 17:18:35 volumio volumio[13685]: Error adding Membership: Error: addMembership EINVAL Feb 07 17:18:35 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 07 17:18:35 volumio sudo[13963]: pam_unix(sudo:session): session closed for user root Feb 07 17:18:35 volumio volumio[13685]: info: Shairport-Sync Started Feb 07 17:18:35 volumio volumio[13685]: info: Shairport-Sync Started Feb 07 17:18:35 volumio volumio[13685]: SPOTIFY: User informations: {"country":"PK","display_name":"215mmgea6kss3jxeqsqdquvji","email":"linh.ngotran@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/215mmgea6kss3jxeqsqdquvji"},"followers":{"href":null,"total":1},"href":"https://api.spotify.com/v1/users/215mmgea6kss3jxeqsqdquvji","id":"215mmgea6kss3jxeqsqdquvji","images":[],"product":"premium","type":"user","uri":"spotify:user:215mmgea6kss3jxeqsqdquvji"} Feb 07 17:18:35 volumio volumio[13685]: info: Spotify Successfully logged in Feb 07 17:18:35 volumio volumio[13685]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 07 17:18:35 volumio volumio[13685]: info: [1770459515708] CoreMusicLibrary::Adding element Spotify Feb 07 17:18:35 volumio volumio[13685]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 17:18:35 volumio volumio[13685]: Cannot find translation for source YouTube Music Feb 07 17:18:35 volumio volumio[13685]: Cannot find translation for source Spotify Feb 07 17:18:36 volumio volumio[13685]: info: [yt-cast-receiver] DIAL server listening on port 8098 Feb 07 17:18:36 volumio volumio[13685]: info: CoreCommandRouter::volumioRetrievevolume Feb 07 17:18:36 volumio volumio[13685]: info: VolumeController:: Volume=100 Mute =false Feb 07 17:18:36 volumio volumio[13685]: info: CoreCommandRouter::volumioGetState Feb 07 17:18:36 volumio volumio[13685]: info: CorePlayQueue::getTrack 0 Feb 07 17:18:36 volumio volumio[13685]: info: CoreStateMachine::pushState Feb 07 17:18:36 volumio volumio[13685]: info: CorePlayQueue::getTrack 0 Feb 07 17:18:36 volumio volumio[13685]: info: CoreCommandRouter::volumioPushState Feb 07 17:18:37 volumio volumio[13685]: info: go-librespot daemon successfully initialized Feb 07 17:18:37 volumio mpd[13902]: 2026-02-07T17:18:37 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 07 17:18:37 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 07 17:18:37 volumio sudo[13863]: pam_unix(sudo:session): session closed for user root Feb 07 17:18:37 volumio sudo[13873]: pam_unix(sudo:session): session closed for user root Feb 07 17:18:38 volumio volumio[13685]: error: MPD error: The expression evaluated to a falsy value: Feb 07 17:18:38 volumio volumio[13685]: assert.ok(self.idling) Feb 07 17:18:38 volumio volumio[13685]: error: The expression evaluated to a falsy value: Feb 07 17:18:38 volumio volumio[13685]: assert.ok(self.idling) Feb 07 17:18:38 volumio volumio[13685]: info: MPD running with PID13902 Feb 07 17:18:38 volumio volumio[13685]: ,establishing connection Feb 07 17:18:38 volumio volumio[13685]: error: updateQueue error: null Feb 07 17:18:38 volumio volumio[13685]: info: Completed starting Core Plugins Feb 07 17:18:38 volumio volumio[13685]: info: ------------------------------------------- Feb 07 17:18:38 volumio volumio[13685]: info: ----- MyVolumio plugins startup ---- Feb 07 17:18:38 volumio volumio[13685]: info: ------------------------------------------- Feb 07 17:18:38 volumio volumio[13685]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 07 17:18:38 volumio volumio[13685]: error: updateQueue error: null Feb 07 17:18:38 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Feb 07 17:18:38 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 17:18:38 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 17:18:38 volumio go-librespot[14006]: go-librespot daemon starting... Feb 07 17:18:38 volumio go-librespot[14007]: time="2026-02-07T17:18:38+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 17:18:38 volumio go-librespot[14007]: time="2026-02-07T17:18:38+07:00" level=debug msg="app state loaded" Feb 07 17:18:38 volumio go-librespot[14007]: time="2026-02-07T17:18:38+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 17:18:39 volumio go-librespot[14007]: time="2026-02-07T17:18:39+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 07 17:18:39 volumio go-librespot[14007]: time="2026-02-07T17:18:39+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 07 17:18:39 volumio go-librespot[14007]: time="2026-02-07T17:18:39+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 07 17:18:39 volumio go-librespot[14007]: time="2026-02-07T17:18:39+07:00" level=info msg="zeroconf server listening on port 35637" Feb 07 17:18:39 volumio go-librespot[14007]: time="2026-02-07T17:18:39+07:00" level=debug msg="obtained new client token: AADlBLLijQfSCdSs93fwJCHEE/qPAJhYVisxYp7eUecHBesvpbnsLuRCSc3vekiXIkOBZC+jBlYD6jPZV0Y1W/Z9v5JWZDsLix9AysV3Nt9ZjNr6nmWSxd7jaVcgfZesI9ea3C1GmnPNHJnhgF9k66erwF3XE69fwn71jpr+li+Wn5naeST3NsMe3MqGT0sK1xP7T0Rp4/yjQLSjXdCa+6izOSgK/HQ654obY24hRQBg3Q2/5DtathL2mg==" Feb 07 17:18:39 volumio go-librespot[14007]: time="2026-02-07T17:18:39+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 07 17:18:39 volumio go-librespot[14007]: time="2026-02-07T17:18:39+07:00" level=debug msg="completed keyexchange" Feb 07 17:18:39 volumio go-librespot[14007]: time="2026-02-07T17:18:39+07:00" level=debug msg="completed challenge" Feb 07 17:18:39 volumio go-librespot[14007]: time="2026-02-07T17:18:39+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 07 17:18:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 17:18:39 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 17:18:40 volumio volumio[13685]: info: Initializing connection to go-librespot Websocket Feb 07 17:18:40 volumio volumio[13685]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 07 17:18:42 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Feb 07 17:18:42 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 17:18:42 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 17:18:42 volumio go-librespot[14015]: go-librespot daemon starting... Feb 07 17:18:42 volumio go-librespot[14016]: time="2026-02-07T17:18:42+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 17:18:42 volumio go-librespot[14016]: time="2026-02-07T17:18:42+07:00" level=debug msg="app state loaded" Feb 07 17:18:42 volumio go-librespot[14016]: time="2026-02-07T17:18:42+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 17:18:43 volumio volumio[13685]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 07 17:18:43 volumio go-librespot[14016]: time="2026-02-07T17:18:43+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 07 17:18:43 volumio go-librespot[14016]: time="2026-02-07T17:18:43+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 07 17:18:43 volumio go-librespot[14016]: time="2026-02-07T17:18:43+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 07 17:18:43 volumio go-librespot[14016]: time="2026-02-07T17:18:43+07:00" level=info msg="zeroconf server listening on port 38141" Feb 07 17:18:43 volumio go-librespot[14016]: time="2026-02-07T17:18:43+07:00" level=debug msg="obtained new client token: AACNuwMACGo11WVa59Gndt1GcK/bMLYDQoiOWm4WAF2bRy+brDnGI7Pj12IUgeF8vdtvMzXek+OqnUKosbq7TRcA+tdNA7662wECqJRlJqySqnRs5VMSVmB8IVtUouujU/R1e0i8XElbwjiY6H3V8Gl86e73Zw5TxlNzJsGGvZVHD9Jver/i2XjFV6WZgg5wa40x20nWMAAHTItc0Ue6THpRoj55X/j32/1dzW8BHdhh9f0w/KF+1lj5Bw==" Feb 07 17:18:43 volumio go-librespot[14016]: time="2026-02-07T17:18:43+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 07 17:18:43 volumio go-librespot[14016]: time="2026-02-07T17:18:43+07:00" level=debug msg="completed keyexchange" Feb 07 17:18:43 volumio go-librespot[14016]: time="2026-02-07T17:18:43+07:00" level=debug msg="completed challenge" Feb 07 17:18:43 volumio volumio[13685]: info: Initializing connection to go-librespot Websocket Feb 07 17:18:43 volumio go-librespot[14016]: time="2026-02-07T17:18:43+07:00" level=debug msg="new websocket client" Feb 07 17:18:43 volumio volumio[13685]: info: Connection to go-librespot Websocket established Feb 07 17:18:43 volumio go-librespot[14016]: time="2026-02-07T17:18:43+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 07 17:18:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 17:18:43 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 17:18:43 volumio volumio[13685]: info: Connection to go-librespot Websocket closed Feb 07 17:18:46 volumio volumio[13685]: info: Getting Spotify volume Feb 07 17:18:46 volumio volumio[13685]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 07 17:18:46 volumio volumio[13685]: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 07 17:18:46 volumio volumio[13685]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Feb 07 17:18:46 volumio volumio[13685]: errno: -111, Feb 07 17:18:46 volumio volumio[13685]: code: 'ECONNREFUSED', Feb 07 17:18:46 volumio volumio[13685]: syscall: 'connect', Feb 07 17:18:46 volumio volumio[13685]: address: '127.0.0.1', Feb 07 17:18:46 volumio volumio[13685]: port: 9879, Feb 07 17:18:46 volumio volumio[13685]: response: undefined Feb 07 17:18:46 volumio volumio[13685]: } Feb 07 17:18:46 volumio volumio[13685]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 07 17:18:47 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Feb 07 17:18:47 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 17:18:47 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 17:18:47 volumio go-librespot[14035]: go-librespot daemon starting... Feb 07 17:18:47 volumio go-librespot[14037]: time="2026-02-07T17:18:47+07:00" level=info msg="running go-librespot 0.4.0" Feb 07 17:18:47 volumio go-librespot[14037]: time="2026-02-07T17:18:47+07:00" level=debug msg="app state loaded" Feb 07 17:18:47 volumio go-librespot[14037]: time="2026-02-07T17:18:47+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 17:18:47 volumio go-librespot[14037]: time="2026-02-07T17:18:47+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 07 17:18:47 volumio go-librespot[14037]: time="2026-02-07T17:18:47+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 07 17:18:47 volumio go-librespot[14037]: time="2026-02-07T17:18:47+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 07 17:18:47 volumio go-librespot[14037]: time="2026-02-07T17:18:47+07:00" level=info msg="zeroconf server listening on port 35183" Feb 07 17:18:47 volumio go-librespot[14037]: time="2026-02-07T17:18:47+07:00" level=debug msg="obtained new client token: AAAWRguCcuTWdLflV0h0DdZUsHvePX1uhDBPDI43mTFHvWFdHvLiBbF9Jsb6uEMiFLdo6ujwzDhEWmfruQa9Vwq/gTpYKUlZ2E6Sm6oyb4WEkqxldgmigWfts8PrkTljdLKdkcKtdtGiiX06du8JL2TWpX4kgECoKKVpym0P2NnH5RDbTVStgYeZ7vNubbkuzUOwDR/9N953fJ2WpfNnAgHRmgr7JKkyPvrjCCMrattCNUX4nrGyxkS4Kw==" Feb 07 17:18:48 volumio go-librespot[14037]: time="2026-02-07T17:18:48+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 07 17:18:48 volumio go-librespot[14037]: time="2026-02-07T17:18:48+07:00" level=debug msg="completed keyexchange" Feb 07 17:18:48 volumio go-librespot[14037]: time="2026-02-07T17:18:48+07:00" level=debug msg="completed challenge" Feb 07 17:18:48 volumio go-librespot[14037]: time="2026-02-07T17:18:48+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 07 17:18:48 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 07 17:18:48 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 17:18:48 volumio sudo[14060]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-07 17:17' Feb 07 17:18:48 volumio sudo[14060]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Dec 25 09:13:19 UTC 2025" VOLUMIO_VERSION="4.084" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="792f09af7488531aecd1aadcaa9b175e"