Oct 11 09:15:00 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:00 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:00 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:00 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:01 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:01 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:01 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:01 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:02 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:02 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:02 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:02 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Oct 11 09:15:03 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 11 09:15:03 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 11 09:15:03 volumio go-librespot[2017]: go-librespot daemon starting... Oct 11 09:15:03 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:03 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:03 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:03 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:03 volumio go-librespot[2018]: time="2026-10-11T09:15:03+02:00" level=info msg="running go-librespot 0.7.1" Oct 11 09:15:03 volumio go-librespot[2018]: time="2026-10-11T09:15:03+02:00" level=debug msg="app state loaded" Oct 11 09:15:03 volumio go-librespot[2018]: time="2026-10-11T09:15:03+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 11 09:15:03 volumio go-librespot[2018]: time="2026-10-11T09:15:03+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 11 09:15:03 volumio go-librespot[2018]: time="2026-10-11T09:15:03+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 11 09:15:03 volumio go-librespot[2018]: time="2026-10-11T09:15:03+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 11 09:15:03 volumio go-librespot[2018]: time="2026-10-11T09:15:03+02:00" level=info msg="zeroconf server listening on port 39987" Oct 11 09:15:03 volumio go-librespot[2018]: time="2026-10-11T09:15:03+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 11 09:15:03 volumio volumio5-onboarding[1693]: failed to bootstrap state: failed to check for software update: could not check for updates: context deadline exceeded Oct 11 09:15:03 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Oct 11 09:15:03 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Oct 11 09:15:03 volumio go-librespot[2018]: time="2026-10-11T09:15:03+02:00" level=debug msg="obtained new client token: AAFOkXwgjGGNx7KBj9yefJ/470ZvVG9uLRTBNeogf+jDUFHa8dEU5MVNgaQ06T7HGLCZSRzA8n+vS6xWJKQOHFTXUiRfyp+kPUBe+5xdgogjL5s70J3VH0ayB6MVuI/jG4Q4BsJkJbz0pnNiz/4IkY0g5Mn6uDmsXnIOg8S02reUJj7q99m2fU6n08pkE8fU3BAw6yIAsFBXQkZmz0pFjYxgjUKmcQCxtrtajD7NNK2gCbArOfIa6UQa" Oct 11 09:15:04 volumio go-librespot[2018]: time="2026-10-11T09:15:04+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 11 09:15:04 volumio go-librespot[2018]: time="2026-10-11T09:15:04+02:00" level=debug msg="completed keyexchange" Oct 11 09:15:04 volumio go-librespot[2018]: time="2026-10-11T09:15:04+02:00" level=debug msg="completed challenge" Oct 11 09:15:04 volumio go-librespot[2018]: time="2026-10-11T09:15:04+02:00" level=info msg="authenticated AP" username="ql*********************ho" Oct 11 09:15:04 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 2. Oct 11 09:15:04 volumio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 11 09:15:04 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 11 09:15:04 volumio go-librespot[2018]: time="2026-10-11T09:15:04+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 11 09:15:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 11 09:15:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 11 09:15:04 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:04 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:04 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:04 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:04 volumio volumio5-onboarding[2027]: time=2026-10-11T09:15:04.292+02:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Oct 11 09:15:05 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:05 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:05 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:05 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:06 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:06 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:06 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:06 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:07 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:07 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:07 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:07 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Oct 11 09:15:07 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 11 09:15:07 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 11 09:15:07 volumio go-librespot[2035]: go-librespot daemon starting... Oct 11 09:15:07 volumio go-librespot[2036]: time="2026-10-11T09:15:07+02:00" level=info msg="running go-librespot 0.7.1" Oct 11 09:15:07 volumio go-librespot[2036]: time="2026-10-11T09:15:07+02:00" level=debug msg="app state loaded" Oct 11 09:15:07 volumio go-librespot[2036]: time="2026-10-11T09:15:07+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 11 09:15:08 volumio go-librespot[2036]: time="2026-10-11T09:15:08+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 11 09:15:08 volumio go-librespot[2036]: time="2026-10-11T09:15:08+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 11 09:15:08 volumio go-librespot[2036]: time="2026-10-11T09:15:08+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 11 09:15:08 volumio go-librespot[2036]: time="2026-10-11T09:15:08+02:00" level=info msg="zeroconf server listening on port 44991" Oct 11 09:15:08 volumio go-librespot[2036]: time="2026-10-11T09:15:08+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 11 09:15:08 volumio go-librespot[2036]: time="2026-10-11T09:15:08+02:00" level=debug msg="obtained new client token: AAFkHI0iBSHCYjkEd4BXogEH2YXdeTkcAHzCsguZKb1HWOHvRTxf94YW9NcW8FdgeC6g8oPmzeTEWZ6XMtOGRyk5ErwnTSH+5/8v3gGJGJilvaB1L1KDnIyFhxVn2IUGezzR1x6uDbaqcNaP9bUmxyG9UrChRpCOgaDm5iienrCN7XpaGnoDsh7Soxx9kXneCny4a4ybhbxcMXkA7D90I1kZvXfbNOOi90fICy56W7eGkob+ko50vQ==" Oct 11 09:15:08 volumio go-librespot[2036]: time="2026-10-11T09:15:08+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 11 09:15:08 volumio go-librespot[2036]: time="2026-10-11T09:15:08+02:00" level=debug msg="completed keyexchange" Oct 11 09:15:08 volumio go-librespot[2036]: time="2026-10-11T09:15:08+02:00" level=debug msg="completed challenge" Oct 11 09:15:08 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:08 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:08 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:08 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:08 volumio go-librespot[2036]: time="2026-10-11T09:15:08+02:00" level=info msg="authenticated AP" username="ql*********************ho" Oct 11 09:15:08 volumio go-librespot[2036]: time="2026-10-11T09:15:08+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 11 09:15:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 11 09:15:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 11 09:15:08 volumio volumio[1291]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 11 09:15:08 volumio volumio[1291]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 11 09:15:08 volumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 09:15:08 volumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 09:15:08 volumio volumio[1291]: info: Starting MyVolumio Remote Streaming Endpoints Oct 11 09:15:08 volumio volumio[1291]: info: MyVolumio login type: Token Oct 11 09:15:09 volumio volumio[1291]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 11 09:15:09 volumio volumio[1291]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Oct 11 09:15:09 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:09 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:09 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:09 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:10 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:10 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:10 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:10 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:11 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:11 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:11 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:11 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Oct 11 09:15:11 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 11 09:15:11 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 11 09:15:11 volumio go-librespot[2045]: go-librespot daemon starting... Oct 11 09:15:11 volumio go-librespot[2046]: time="2026-10-11T09:15:11+02:00" level=info msg="running go-librespot 0.7.1" Oct 11 09:15:11 volumio go-librespot[2046]: time="2026-10-11T09:15:11+02:00" level=debug msg="app state loaded" Oct 11 09:15:11 volumio go-librespot[2046]: time="2026-10-11T09:15:11+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 11 09:15:12 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:12 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:12 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:12 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:12 volumio go-librespot[2046]: time="2026-10-11T09:15:12+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 11 09:15:12 volumio go-librespot[2046]: time="2026-10-11T09:15:12+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 11 09:15:12 volumio go-librespot[2046]: time="2026-10-11T09:15:12+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 11 09:15:12 volumio go-librespot[2046]: time="2026-10-11T09:15:12+02:00" level=info msg="zeroconf server listening on port 33791" Oct 11 09:15:12 volumio go-librespot[2046]: time="2026-10-11T09:15:12+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 11 09:15:12 volumio go-librespot[2046]: time="2026-10-11T09:15:12+02:00" level=debug msg="obtained new client token: AAEldECi5dBa8cPvssWmCZ31jp+R3sfshjbKmhQRaYgDHrcyhBL35QnD6f7tbUDcYOf/OFV7L97Iv5YHuTqfUAZw9E6iftdtMMbkph/bFfC8Gm6Jhh+YO8ZYLdjpjQlcyn72IRXN1pLhsRqKTsiug8FNLcJjgIijPRLTWUNdQbxvodXQ6m20tjdOkGbOaZiQq5KIhQMhx75MtBq4BE79mdIWdHXR3G0P8Xb/18cr/LvpSWp91ZywZ/Bi" Oct 11 09:15:12 volumio go-librespot[2046]: time="2026-10-11T09:15:12+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 11 09:15:12 volumio go-librespot[2046]: time="2026-10-11T09:15:12+02:00" level=debug msg="completed keyexchange" Oct 11 09:15:12 volumio go-librespot[2046]: time="2026-10-11T09:15:12+02:00" level=debug msg="completed challenge" Oct 11 09:15:12 volumio go-librespot[2046]: time="2026-10-11T09:15:12+02:00" level=info msg="authenticated AP" username="ql*********************ho" Oct 11 09:15:12 volumio go-librespot[2046]: time="2026-10-11T09:15:12+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 11 09:15:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 11 09:15:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 11 09:15:13 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:13 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:13 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:13 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:14 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:14 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:14 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:14 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:14 volumio volumio5-onboarding[2027]: failed to create app: failed to initialize host: failed to create socket connection: could not connect to socket.io server: read tcp 127.0.0.1:41512->127.0.0.1:3000: i/o timeout Oct 11 09:15:14 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Oct 11 09:15:14 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Oct 11 09:15:14 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 3. Oct 11 09:15:14 volumio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 11 09:15:14 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 11 09:15:14 volumio volumio5-onboarding[2069]: time=2026-10-11T09:15:14.807+02:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Oct 11 09:15:15 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:15 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:15 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:15 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:15 volumio volumio[1291]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 11 09:15:15 volumio volumio[1291]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 11 09:15:15 volumio volumio[1291]: info: Streaming services startup Oct 11 09:15:15 volumio volumio[1291]: info: Starting Streaming Daemon Oct 11 09:15:15 volumio volumio[1291]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Oct 11 09:15:15 volumio sudo[2078]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 11 09:15:15 volumio sudo[2078]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 09:15:15 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Oct 11 09:15:15 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 11 09:15:15 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 11 09:15:15 volumio go-librespot[2084]: go-librespot daemon starting... Oct 11 09:15:16 volumio go-librespot[2085]: time="2026-10-11T09:15:16+02:00" level=info msg="running go-librespot 0.7.1" Oct 11 09:15:16 volumio go-librespot[2085]: time="2026-10-11T09:15:16+02:00" level=debug msg="app state loaded" Oct 11 09:15:16 volumio volumio[1291]: info: Initializing connection to go-librespot Websocket Oct 11 09:15:16 volumio go-librespot[2085]: time="2026-10-11T09:15:16+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 11 09:15:16 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:16 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:16 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:16 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:16 volumio sudo[2078]: pam_unix(sudo:session): session closed for user root Oct 11 09:15:16 volumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 11 09:15:16 volumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 09:15:16 volumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 11 09:15:16 volumio go-librespot[2085]: time="2026-10-11T09:15:16+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 11 09:15:16 volumio go-librespot[2085]: time="2026-10-11T09:15:16+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 11 09:15:16 volumio go-librespot[2085]: time="2026-10-11T09:15:16+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 11 09:15:16 volumio go-librespot[2085]: time="2026-10-11T09:15:16+02:00" level=info msg="zeroconf server listening on port 45955" Oct 11 09:15:16 volumio go-librespot[2085]: time="2026-10-11T09:15:16+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 11 09:15:16 volumio go-librespot[2085]: time="2026-10-11T09:15:16+02:00" level=debug msg="obtained new client token: AAEkzmeJmORWnwndWie4pzTupmiuokQHLKBYeCRgcD2Y6h3NbEWe/8GsCYa7WkJPfaZjOviBniOe5YNovYXLDvia+QvFpEj/1Dz/dRbeBWz7vAukS2eIBP6Xh6+c+uao5X7IH2OW6ch173dCTYOO9ARHqEjuRrgVfgF4dpUrBMHIcgSgU8HxhkVDaQUgfhmE05XP60uWvsAQV9EFFB6keAhhdfV1l1sv8SWUPJ6dBdk+zFG1iGavR7F2" Oct 11 09:15:16 volumio go-librespot[2085]: time="2026-10-11T09:15:16+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 11 09:15:16 volumio go-librespot[2085]: time="2026-10-11T09:15:16+02:00" level=debug msg="completed keyexchange" Oct 11 09:15:16 volumio go-librespot[2085]: time="2026-10-11T09:15:16+02:00" level=debug msg="completed challenge" Oct 11 09:15:16 volumio go-librespot[2085]: time="2026-10-11T09:15:16+02:00" level=info msg="authenticated AP" username="ql*********************ho" Oct 11 09:15:16 volumio volumio[1291]: error: Cannot start Volumio Streaming Daemon Oct 11 09:15:16 volumio volumio[1291]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 11 09:15:16 volumio volumio[1291]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 11 09:15:16 volumio go-librespot[2085]: time="2026-10-11T09:15:16+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 11 09:15:16 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 11 09:15:16 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 11 09:15:17 volumio volumio[1291]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Oct 11 09:15:17 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:17 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:17 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:17 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:17 volumio volumio[1291]: info: Error connecting to go-librespot Websocket: Error: socket hang up Oct 11 09:15:18 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:18 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:18 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:18 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:18 volumio volumio[1291]: error: MyVolumio Custom Token format not valid, refreshing it Oct 11 09:15:18 volumio volumio[1291]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Oct 11 09:15:19 volumio volumio[1291]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 6 Oct 11 09:15:19 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:19 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:19 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:19 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:19 volumio volumio[1291]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 6 Oct 11 09:15:19 volumio volumio[1291]: info: Received Get System Info Oct 11 09:15:19 volumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 09:15:19 volumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 09:15:19 volumio volumio[1291]: info: Discovery: Getting this device information Oct 11 09:15:19 volumio volumio[1291]: info: CoreCommandRouter::volumioGetState Oct 11 09:15:19 volumio volumio[1291]: info: CorePlayQueue::getTrack 0 Oct 11 09:15:19 volumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 09:15:19 volumio volumio5-onboarding[2069]: time=2026-10-11T09:15:19.778+02:00 level=INFO msg="system info for 2a3cf7b69565641c3eb4d4ea4d1040fb" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=4.119 Oct 11 09:15:19 volumio volumio5-onboarding[2069]: time=2026-10-11T09:15:19.803+02:00 level=INFO msg="bootstrapping state" hasInternet=true Oct 11 09:15:19 volumio volumio[1291]: info: Received Get System Info Oct 11 09:15:19 volumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 09:15:19 volumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 09:15:19 volumio volumio[1291]: info: Discovery: Getting this device information Oct 11 09:15:19 volumio volumio[1291]: info: CoreCommandRouter::volumioGetState Oct 11 09:15:19 volumio volumio[1291]: info: CorePlayQueue::getTrack 0 Oct 11 09:15:19 volumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 09:15:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11. Oct 11 09:15:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 11 09:15:20 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 11 09:15:20 volumio go-librespot[2098]: go-librespot daemon starting... Oct 11 09:15:20 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:20 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:20 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:20 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:20 volumio go-librespot[2099]: time="2026-10-11T09:15:20+02:00" level=info msg="running go-librespot 0.7.1" Oct 11 09:15:20 volumio go-librespot[2099]: time="2026-10-11T09:15:20+02:00" level=debug msg="app state loaded" Oct 11 09:15:20 volumio go-librespot[2099]: time="2026-10-11T09:15:20+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 11 09:15:20 volumio volumio-remote-updater[735]: Test mode disabled Oct 11 09:15:20 volumio volumio-remote-updater[735]: Alpha mode disabled Oct 11 09:15:20 volumio volumio-remote-updater[735]: Alpha legacy test mode disabled Oct 11 09:15:20 volumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Oct 11 09:15:20 volumio volumio-remote-updater[735]: Test mode disabled Oct 11 09:15:20 volumio volumio-remote-updater[735]: Alpha mode disabled Oct 11 09:15:20 volumio volumio-remote-updater[735]: Alpha legacy test mode disabled Oct 11 09:15:20 volumio volumio[1291]: info: CoreCommandRouter::volumioGetState Oct 11 09:15:20 volumio volumio[1291]: info: CorePlayQueue::getTrack 0 Oct 11 09:15:20 volumio go-librespot[2099]: time="2026-10-11T09:15:20+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 11 09:15:20 volumio go-librespot[2099]: time="2026-10-11T09:15:20+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 11 09:15:20 volumio go-librespot[2099]: time="2026-10-11T09:15:20+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 11 09:15:20 volumio go-librespot[2099]: time="2026-10-11T09:15:20+02:00" level=info msg="zeroconf server listening on port 34877" Oct 11 09:15:20 volumio go-librespot[2099]: time="2026-10-11T09:15:20+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 11 09:15:20 volumio volumio[1291]: info: CoreCommandRouter::volumioGetQueue Oct 11 09:15:20 volumio volumio[1291]: info: CoreStateMachine::getQueue Oct 11 09:15:20 volumio volumio[1291]: info: CorePlayQueue::getQueue Oct 11 09:15:20 volumio go-librespot[2099]: time="2026-10-11T09:15:20+02:00" level=debug msg="obtained new client token: AAFL2MY+fVGLYSvcxPbJ36xO04RyXIQ6hS0W6QLNnSriig0kmGkw8mJXbDP/EYIV3QvMMOdzUplQHTGaRUc7FIyjO8sovswpxekFAxfsXDRbaWE9T/sTPC5buNkt932fR/AO+uHZZ6BED557YSSnw4J52njluqQH02N3+vydusdb30Lj0DXV0obVrtZXSb2OwDcGFbsgPUTYdGd+tOAdGmDLZzREf9TnvTUu4uUigXcWXAsUcXuCWii4" Oct 11 09:15:21 volumio go-librespot[2099]: time="2026-10-11T09:15:21+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 11 09:15:21 volumio go-librespot[2099]: time="2026-10-11T09:15:21+02:00" level=debug msg="completed keyexchange" Oct 11 09:15:21 volumio go-librespot[2099]: time="2026-10-11T09:15:21+02:00" level=debug msg="completed challenge" Oct 11 09:15:21 volumio go-librespot[2099]: time="2026-10-11T09:15:21+02:00" level=info msg="authenticated AP" username="ql*********************ho" Oct 11 09:15:21 volumio go-librespot[2099]: time="2026-10-11T09:15:21+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 11 09:15:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 11 09:15:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 11 09:15:21 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:21 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:21 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:21 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:21 volumio volumio[1291]: info: Initializing connection to go-librespot Websocket Oct 11 09:15:21 volumio volumio[1291]: info: CoreCommandRouter::volumioGetState Oct 11 09:15:21 volumio volumio[1291]: info: CorePlayQueue::getTrack 0 Oct 11 09:15:21 volumio volumio[1291]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 11 09:15:21 volumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 11 09:15:21 volumio volumio[1291]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 11 09:15:21 volumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 11 09:15:22 volumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 11 09:15:22 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:22 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:22 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:22 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:22 volumio volumio[1291]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 11 09:15:22 volumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 11 09:15:22 volumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 11 09:15:22 volumio volumio[1291]: info: Received Get System Info Oct 11 09:15:22 volumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 09:15:22 volumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 09:15:22 volumio volumio[1291]: info: Discovery: Getting this device information Oct 11 09:15:22 volumio volumio[1291]: info: CoreCommandRouter::volumioGetState Oct 11 09:15:22 volumio volumio[1291]: info: CorePlayQueue::getTrack 0 Oct 11 09:15:22 volumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 09:15:22 volumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 11 09:15:22 volumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 11 09:15:23 volumio volumio5-onboarding[2069]: time=2026-10-11T09:15:23.001+02:00 level=INFO msg="enabling local network discovery" Oct 11 09:15:23 volumio volumio5-onboarding[2069]: time=2026-10-11T09:15:23.045+02:00 level=INFO msg="enabling BLE discovery" Oct 11 09:15:23 volumio volumio[1291]: info: MyVolumio login type: Token Oct 11 09:15:23 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:23 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:23 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:23 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:24 volumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 11 09:15:24 volumio volumio[1291]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Oct 11 09:15:24 volumio volumio5-onboarding[2069]: time=2026-10-11T09:15:24.310+02:00 level=INFO msg="service successfully established" component=discovery/localnet Oct 11 09:15:24 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:24 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:24 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:24 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12. Oct 11 09:15:24 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 11 09:15:24 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 11 09:15:24 volumio go-librespot[2127]: go-librespot daemon starting... Oct 11 09:15:24 volumio go-librespot[2128]: time="2026-10-11T09:15:24+02:00" level=info msg="running go-librespot 0.7.1" Oct 11 09:15:24 volumio go-librespot[2128]: time="2026-10-11T09:15:24+02:00" level=debug msg="app state loaded" Oct 11 09:15:24 volumio go-librespot[2128]: time="2026-10-11T09:15:24+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 11 09:15:25 volumio go-librespot[2128]: time="2026-10-11T09:15:25+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 11 09:15:25 volumio go-librespot[2128]: time="2026-10-11T09:15:25+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 11 09:15:25 volumio go-librespot[2128]: time="2026-10-11T09:15:25+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 11 09:15:25 volumio go-librespot[2128]: time="2026-10-11T09:15:25+02:00" level=info msg="zeroconf server listening on port 34633" Oct 11 09:15:25 volumio go-librespot[2128]: time="2026-10-11T09:15:25+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 11 09:15:25 volumio volumio[1291]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Oct 11 09:15:25 volumio go-librespot[2128]: time="2026-10-11T09:15:25+02:00" level=debug msg="obtained new client token: AAEiscMHFA8fwvjn6Zz2TeSKLad8ITTvteeRVxsWK+aTEQZC8jz8YQQ9Wh+OW7wmia/23EkTAx4nCIEgQUMzaeDh80PZw1OzcxXaFlvg2XfeUHXWR/yd6XwLDlm8R3MdyQoGJtjq3P509aBYkzGetincKIcWl5fLb8EtXSYVzOtMG5UgMjWVc6/OQ9w/I7qSxs9LLiu2xldWWJVGeRhRSsM5iOTLTRIWVvk65NMqn+OdEaqMEYi35A==" Oct 11 09:15:25 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:25 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:25 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:25 volumio go-librespot[2128]: time="2026-10-11T09:15:25+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 11 09:15:25 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:25 volumio go-librespot[2128]: time="2026-10-11T09:15:25+02:00" level=debug msg="completed keyexchange" Oct 11 09:15:25 volumio go-librespot[2128]: time="2026-10-11T09:15:25+02:00" level=debug msg="completed challenge" Oct 11 09:15:25 volumio volumio[1291]: info: Initializing connection to go-librespot Websocket Oct 11 09:15:25 volumio go-librespot[2128]: time="2026-10-11T09:15:25+02:00" level=info msg="authenticated AP" username="ql*********************ho" Oct 11 09:15:25 volumio go-librespot[2128]: time="2026-10-11T09:15:25+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 11 09:15:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 11 09:15:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 11 09:15:26 volumio volumio[1291]: info: Error connecting to go-librespot Websocket: Error: socket hang up Oct 11 09:15:26 volumio volumio[1291]: info: CoreCommandRouter::volumioGetState Oct 11 09:15:26 volumio volumio[1291]: info: CorePlayQueue::getTrack 0 Oct 11 09:15:26 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:26 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:26 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:26 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:26 volumio volumio[1291]: info: MyVolumio token set successfully Oct 11 09:15:26 volumio volumio[1291]: info: MYVOLUMIO: Adding device Oct 11 09:15:26 volumio volumio[1291]: info: MYVOLUMIO: Evaluating Server Oct 11 09:15:27 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:27 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:27 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:27 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:28 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:28 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:28 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:28 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13. Oct 11 09:15:28 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 11 09:15:28 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 11 09:15:28 volumio go-librespot[2157]: go-librespot daemon starting... Oct 11 09:15:28 volumio volumio[1291]: info: MyVolumio Plan changed: premium Oct 11 09:15:28 volumio volumio[1291]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Oct 11 09:15:28 volumio volumio[1291]: info: Removing browser output: myVolumio user plan is not superstar Oct 11 09:15:28 volumio volumio[1291]: info: Removing audio output: Oct 11 09:15:28 volumio volumio[1291]: info: MYVOLUMIO: Adding device Oct 11 09:15:28 volumio volumio[1291]: info: MYVOLUMIO: Evaluating Server Oct 11 09:15:28 volumio go-librespot[2158]: time="2026-10-11T09:15:28+02:00" level=info msg="running go-librespot 0.7.1" Oct 11 09:15:28 volumio go-librespot[2158]: time="2026-10-11T09:15:28+02:00" level=debug msg="app state loaded" Oct 11 09:15:28 volumio go-librespot[2158]: time="2026-10-11T09:15:28+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 11 09:15:29 volumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 09:15:29 volumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 09:15:29 volumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 11 09:15:29 volumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 11 09:15:29 volumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 11 09:15:29 volumio volumio[1291]: info: CoreCommandRouter::volumioGetBrowseSources Oct 11 09:15:29 volumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 11 09:15:29 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:29 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:29 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:29 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:29 volumio go-librespot[2158]: time="2026-10-11T09:15:29+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 11 09:15:29 volumio go-librespot[2158]: time="2026-10-11T09:15:29+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 11 09:15:29 volumio go-librespot[2158]: time="2026-10-11T09:15:29+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 11 09:15:29 volumio go-librespot[2158]: time="2026-10-11T09:15:29+02:00" level=info msg="zeroconf server listening on port 36197" Oct 11 09:15:29 volumio go-librespot[2158]: time="2026-10-11T09:15:29+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 11 09:15:29 volumio volumio[1291]: info: Initializing connection to go-librespot Websocket Oct 11 09:15:29 volumio go-librespot[2158]: time="2026-10-11T09:15:29+02:00" level=debug msg="obtained new client token: AAG3SNZAlc5+bGXcAHy8G/7/a2ZX6UIMMTAc/toLSWoU/BHt1EwvYIuuoLLrkXidiB3wHFw79Uh2LHDinrsvGuXina4kZy9Ob8EFYwuJj0awMrpx+or0gJ/tP8ttkUMlcM3qaeGIg2SgCbzrldjm65LHHy8sGqPoAC5sFTmQrIW6+0IQA7lL7OVUr/VzF5tkNHGtqYkIaZDu2l/Qr+Svy62OVp+oW1chI4QVBqOkO8EnWbOvUlxksztn" Oct 11 09:15:29 volumio go-librespot[2158]: time="2026-10-11T09:15:29+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 11 09:15:29 volumio go-librespot[2158]: time="2026-10-11T09:15:29+02:00" level=debug msg="completed keyexchange" Oct 11 09:15:29 volumio go-librespot[2158]: time="2026-10-11T09:15:29+02:00" level=debug msg="completed challenge" Oct 11 09:15:29 volumio go-librespot[2158]: time="2026-10-11T09:15:29+02:00" level=info msg="authenticated AP" username="ql*********************ho" Oct 11 09:15:29 volumio go-librespot[2158]: time="2026-10-11T09:15:29+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 11 09:15:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 11 09:15:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 11 09:15:30 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:30 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:30 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:30 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:31 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:31 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:31 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:31 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:31 volumio volumio[1291]: info: Setting Geolocation for MyVolumio to eu6 Oct 11 09:15:31 volumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 09:15:31 volumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 09:15:31 volumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 09:15:31 volumio volumio[1291]: info: Setting Geolocation for MyVolumio to eu6 Oct 11 09:15:31 volumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 09:15:31 volumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 09:15:31 volumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 09:15:31 volumio volumio[1291]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 11 09:15:31 volumio volumio[1291]: info: Successfully Added MyVolumio device Oct 11 09:15:31 volumio volumio[1291]: info: Remote config written successfully Oct 11 09:15:31 volumio volumio[1291]: info: Starting Tunnel 1 Oct 11 09:15:31 volumio volumio[1291]: info: Starting Tunnel Connection Checker Oct 11 09:15:32 volumio volumio[1291]: info: Completed starting MyVolumio Plugin Oct 11 09:15:32 volumio volumio[1291]: info: MYVolumio Device enabled Oct 11 09:15:32 volumio volumio[1291]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Oct 11 09:15:32 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:32 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:32 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:32 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:32 volumio volumio[1291]: info: MyVolumio status changed Oct 11 09:15:32 volumio volumio[1291]: info: Streaming services startup Oct 11 09:15:32 volumio volumio[1291]: info: Starting Streaming Daemon Oct 11 09:15:32 volumio volumio[1291]: info: Updating MyVolumio device info Oct 11 09:15:32 volumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 09:15:32 volumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 09:15:32 volumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 09:15:32 volumio sudo[2186]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 11 09:15:32 volumio sudo[2186]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 09:15:32 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14. Oct 11 09:15:32 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 11 09:15:32 volumio volumio[1291]: info: Updating MyVolumio device info Oct 11 09:15:32 volumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 09:15:32 volumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 09:15:32 volumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 09:15:33 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 11 09:15:33 volumio go-librespot[2188]: go-librespot daemon starting... Oct 11 09:15:33 volumio go-librespot[2189]: time="2026-10-11T09:15:33+02:00" level=info msg="running go-librespot 0.7.1" Oct 11 09:15:33 volumio go-librespot[2189]: time="2026-10-11T09:15:33+02:00" level=debug msg="app state loaded" Oct 11 09:15:33 volumio sudo[2186]: pam_unix(sudo:session): session closed for user root Oct 11 09:15:33 volumio go-librespot[2189]: time="2026-10-11T09:15:33+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 11 09:15:33 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:33 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:33 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:33 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:33 volumio go-librespot[2189]: time="2026-10-11T09:15:33+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Oct 11 09:15:33 volumio go-librespot[2189]: time="2026-10-11T09:15:33+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Oct 11 09:15:33 volumio go-librespot[2189]: time="2026-10-11T09:15:33+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Oct 11 09:15:33 volumio go-librespot[2189]: time="2026-10-11T09:15:33+02:00" level=info msg="zeroconf server listening on port 39689" Oct 11 09:15:33 volumio go-librespot[2189]: time="2026-10-11T09:15:33+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 11 09:15:33 volumio go-librespot[2189]: time="2026-10-11T09:15:33+02:00" level=debug msg="obtained new client token: AAFqg84QXHirRI0QGSZo01pDsKXoR97icjkvbFxvgSFFdneZSlIfJdwnvNmIzqzk2pt3d2ctPTuoNh+eRbDEUgKIqy59ae1XAvest4vllevjvQHlg6a/aoskj15c/3v0lmIJ3juXd5wSzU3l6O1PC66VQURGRjvcy7IClxov1o6KeDOh728LcEwxsF8qBP23M0jDnncykYKxKYyUOVPKihqghvaZ7XPzKwCuHg8lSUeG3AlE4cshS7V3" Oct 11 09:15:33 volumio go-librespot[2189]: time="2026-10-11T09:15:33+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 11 09:15:33 volumio go-librespot[2189]: time="2026-10-11T09:15:33+02:00" level=debug msg="completed keyexchange" Oct 11 09:15:33 volumio go-librespot[2189]: time="2026-10-11T09:15:33+02:00" level=debug msg="completed challenge" Oct 11 09:15:34 volumio go-librespot[2189]: time="2026-10-11T09:15:34+02:00" level=info msg="authenticated AP" username="ql*********************ho" Oct 11 09:15:34 volumio go-librespot[2189]: time="2026-10-11T09:15:34+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 11 09:15:34 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 11 09:15:34 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 11 09:15:34 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:34 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:34 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:34 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:34 volumio volumio[1291]: info: Successfully Added MyVolumio device Oct 11 09:15:34 volumio volumio[1291]: info: Successfully Updated MyVolumio device Oct 11 09:15:34 volumio volumio[1291]: error: Cannot start Volumio Streaming Daemon Oct 11 09:15:34 volumio volumio[1291]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 11 09:15:34 volumio volumio[1291]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 11 09:15:34 volumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Oct 11 09:15:34 volumio volumio[1291]: error: [MyVolumio PluginManager] Cache data is invalid! Oct 11 09:15:34 volumio volumio[1291]: info: Initializing connection to go-librespot Websocket Oct 11 09:15:34 volumio volumio[1291]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 11 09:15:35 volumio volumio[1291]: info: Successfully Updated MyVolumio device Oct 11 09:15:35 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:35 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:35 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:35 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:35 volumio volumio[1291]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Oct 11 09:15:36 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:36 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:36 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:36 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:36 volumio volumio[1291]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin bluetooth Oct 11 09:15:36 volumio volumio[1291]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin bluetooth Oct 11 09:15:36 volumio volumio[1291]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart Oct 11 09:15:36 volumio volumio[1291]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Oct 11 09:15:37 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:37 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:37 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:37 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:37 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15. Oct 11 09:15:37 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 11 09:15:37 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 11 09:15:37 volumio go-librespot[2213]: go-librespot daemon starting... Oct 11 09:15:37 volumio go-librespot[2214]: time="2026-10-11T09:15:37+02:00" level=info msg="running go-librespot 0.7.1" Oct 11 09:15:37 volumio go-librespot[2214]: time="2026-10-11T09:15:37+02:00" level=debug msg="app state loaded" Oct 11 09:15:37 volumio go-librespot[2214]: time="2026-10-11T09:15:37+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 11 09:15:38 volumio go-librespot[2214]: time="2026-10-11T09:15:38+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 11 09:15:38 volumio go-librespot[2214]: time="2026-10-11T09:15:38+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 11 09:15:38 volumio go-librespot[2214]: time="2026-10-11T09:15:38+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 11 09:15:38 volumio go-librespot[2214]: time="2026-10-11T09:15:38+02:00" level=info msg="zeroconf server listening on port 43625" Oct 11 09:15:38 volumio go-librespot[2214]: time="2026-10-11T09:15:38+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 11 09:15:38 volumio go-librespot[2214]: time="2026-10-11T09:15:38+02:00" level=debug msg="obtained new client token: AAE6g88eQQtKTRDM8re0zMnMM8b37ArOG31RSsGA9xrHwX6d/WacnWPAXw+f7st5nG0W3obg9PWb4lp5dKUV8zc3IvtKQe+sN0vQljXHgJC//MiQbnGxERLAyQ2/fOPOausUKFJ+zC8AZp0XbFLsfA52QuQbBD/wC1sUSRBhhI5IXahk7OVdUAce87PEkmo9pHAPQNvbZeEDUxVe2gqtq4VjWUmRhcy5pqL3N/DlgANiRhp3Rj1XWw==" Oct 11 09:15:38 volumio go-librespot[2214]: time="2026-10-11T09:15:38+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 11 09:15:38 volumio go-librespot[2214]: time="2026-10-11T09:15:38+02:00" level=debug msg="completed keyexchange" Oct 11 09:15:38 volumio go-librespot[2214]: time="2026-10-11T09:15:38+02:00" level=debug msg="completed challenge" Oct 11 09:15:38 volumio go-librespot[2214]: time="2026-10-11T09:15:38+02:00" level=info msg="authenticated AP" username="ql*********************ho" Oct 11 09:15:38 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:38 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:38 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:38 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:38 volumio go-librespot[2214]: time="2026-10-11T09:15:38+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 11 09:15:38 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 11 09:15:38 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 11 09:15:39 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:39 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:39 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:39 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:40 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:40 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:40 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:40 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:41 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:41 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:41 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:41 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:41 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16. Oct 11 09:15:41 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 11 09:15:41 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 11 09:15:41 volumio go-librespot[2223]: go-librespot daemon starting... Oct 11 09:15:41 volumio go-librespot[2224]: time="2026-10-11T09:15:41+02:00" level=info msg="running go-librespot 0.7.1" Oct 11 09:15:41 volumio go-librespot[2224]: time="2026-10-11T09:15:41+02:00" level=debug msg="app state loaded" Oct 11 09:15:41 volumio go-librespot[2224]: time="2026-10-11T09:15:41+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 11 09:15:42 volumio go-librespot[2224]: time="2026-10-11T09:15:42+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 11 09:15:42 volumio go-librespot[2224]: time="2026-10-11T09:15:42+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 11 09:15:42 volumio go-librespot[2224]: time="2026-10-11T09:15:42+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 11 09:15:42 volumio go-librespot[2224]: time="2026-10-11T09:15:42+02:00" level=info msg="zeroconf server listening on port 35391" Oct 11 09:15:42 volumio go-librespot[2224]: time="2026-10-11T09:15:42+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 11 09:15:42 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:42 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:42 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:42 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:42 volumio go-librespot[2224]: time="2026-10-11T09:15:42+02:00" level=debug msg="obtained new client token: AAGa/QGP6qt2yW5RpKVYYZei5TgnaNguoJwyoyPA4CiLWSRagX4jWfvrEejsVQB880kMoJdxAGrvuPmNWRwXDlj5yhyYspdsivtWHrTxTkIvm5yPmKBTSew0LpEtGpACnXLhP836JxgF7TZ223FGxiVXdi/yzDEH47EKoYwhXU3PNq8gNepy/zAebWpVoESow57DGW8QKGJ6OIZBucjSip5Vi5nIgvQ6Adx+rTwMvHNryTrcVTqT7B8q" Oct 11 09:15:42 volumio go-librespot[2224]: time="2026-10-11T09:15:42+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 11 09:15:42 volumio go-librespot[2224]: time="2026-10-11T09:15:42+02:00" level=debug msg="completed keyexchange" Oct 11 09:15:42 volumio go-librespot[2224]: time="2026-10-11T09:15:42+02:00" level=debug msg="completed challenge" Oct 11 09:15:42 volumio go-librespot[2224]: time="2026-10-11T09:15:42+02:00" level=info msg="authenticated AP" username="ql*********************ho" Oct 11 09:15:42 volumio go-librespot[2224]: time="2026-10-11T09:15:42+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 11 09:15:42 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 11 09:15:42 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 11 09:15:43 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:43 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:43 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:43 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:44 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:44 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:44 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:44 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:45 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:45 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:45 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:45 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:45 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17. Oct 11 09:15:45 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 11 09:15:45 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 11 09:15:45 volumio go-librespot[2247]: go-librespot daemon starting... Oct 11 09:15:46 volumio go-librespot[2248]: time="2026-10-11T09:15:46+02:00" level=info msg="running go-librespot 0.7.1" Oct 11 09:15:46 volumio go-librespot[2248]: time="2026-10-11T09:15:46+02:00" level=debug msg="app state loaded" Oct 11 09:15:46 volumio go-librespot[2248]: time="2026-10-11T09:15:46+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 11 09:15:46 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:46 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:46 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:46 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:46 volumio go-librespot[2248]: time="2026-10-11T09:15:46+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 11 09:15:46 volumio go-librespot[2248]: time="2026-10-11T09:15:46+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 11 09:15:46 volumio go-librespot[2248]: time="2026-10-11T09:15:46+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 11 09:15:46 volumio go-librespot[2248]: time="2026-10-11T09:15:46+02:00" level=info msg="zeroconf server listening on port 46751" Oct 11 09:15:46 volumio go-librespot[2248]: time="2026-10-11T09:15:46+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 11 09:15:46 volumio go-librespot[2248]: time="2026-10-11T09:15:46+02:00" level=debug msg="obtained new client token: AAErIlZpwQWVH8VjYu+zauAFKDoffB4wROigYa9u1yV+w++YLIdP3pL8xlfNyv3nFNGLJOUy4SINnKbnAvIhFFKNuChEsufD9oCTd++Cv4kS28iBnT6IcPvfUSluRWgvoi9+CPktZIOOtHwIiFRAI6DHlgVoa4B/k4BYVdF8NcGL6l8e1taowKsWrg6LnhWtRv9wrgxpTxK9yBaVTQXWLwp+2v9EDaWKYY+W9ydlrSWwldBynaBxwxWh" Oct 11 09:15:46 volumio go-librespot[2248]: time="2026-10-11T09:15:46+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 11 09:15:46 volumio go-librespot[2248]: time="2026-10-11T09:15:46+02:00" level=debug msg="completed keyexchange" Oct 11 09:15:46 volumio go-librespot[2248]: time="2026-10-11T09:15:46+02:00" level=debug msg="completed challenge" Oct 11 09:15:46 volumio go-librespot[2248]: time="2026-10-11T09:15:46+02:00" level=info msg="authenticated AP" username="ql*********************ho" Oct 11 09:15:47 volumio go-librespot[2248]: time="2026-10-11T09:15:47+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 11 09:15:47 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 11 09:15:47 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 11 09:15:47 volumio volumio[1291]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Oct 11 09:15:47 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:47 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:47 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:47 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:47 volumio sudo[2260]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Oct 11 09:15:47 volumio sudo[2260]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 09:15:47 volumio sudo[2260]: pam_unix(sudo:session): session closed for user root Oct 11 09:15:48 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:48 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:48 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:48 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:48 volumio volumio[1291]: info: MRS: MultiRoom plugin initialized Oct 11 09:15:48 volumio volumio[1291]: info: MRS: STOPPING SNAPCLIENT Oct 11 09:15:48 volumio volumio[1291]: info: MRS: Snap server stop Oct 11 09:15:48 volumio volumio[1291]: info: MRS: STOPPING volumioStreaming Oct 11 09:15:48 volumio volumio[1291]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Oct 11 09:15:49 volumio sudo[2278]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Oct 11 09:15:49 volumio sudo[2278]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 09:15:49 volumio sudo[2280]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Oct 11 09:15:49 volumio sudo[2280]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 09:15:49 volumio volumio[1291]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Oct 11 09:15:49 volumio sudo[2284]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Oct 11 09:15:49 volumio sudo[2284]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 09:15:49 volumio sudo[2283]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Oct 11 09:15:49 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:49 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:49 volumio sudo[2283]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 09:15:49 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:49 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:49 volumio sudo[2283]: pam_unix(sudo:session): session closed for user root Oct 11 09:15:49 volumio volumio[1291]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Oct 11 09:15:49 volumio sudo[2278]: pam_unix(sudo:session): session closed for user root Oct 11 09:15:49 volumio sudo[2280]: pam_unix(sudo:session): session closed for user root Oct 11 09:15:49 volumio volumio[1291]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Oct 11 09:15:49 volumio sudo[2284]: pam_unix(sudo:session): session closed for user root Oct 11 09:15:50 volumio volumio[1291]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Oct 11 09:15:50 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 18. Oct 11 09:15:50 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 11 09:15:50 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 11 09:15:50 volumio go-librespot[2289]: go-librespot daemon starting... Oct 11 09:15:50 volumio go-librespot[2290]: time="2026-10-11T09:15:50+02:00" level=info msg="running go-librespot 0.7.1" Oct 11 09:15:50 volumio go-librespot[2290]: time="2026-10-11T09:15:50+02:00" level=debug msg="app state loaded" Oct 11 09:15:50 volumio go-librespot[2290]: time="2026-10-11T09:15:50+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 11 09:15:50 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:50 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:50 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:50 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:50 volumio volumio[1291]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Oct 11 09:15:50 volumio volumio[1291]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Oct 11 09:15:50 volumio go-librespot[2290]: time="2026-10-11T09:15:50+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 11 09:15:50 volumio go-librespot[2290]: time="2026-10-11T09:15:50+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 11 09:15:50 volumio go-librespot[2290]: time="2026-10-11T09:15:50+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 11 09:15:50 volumio go-librespot[2290]: time="2026-10-11T09:15:50+02:00" level=info msg="zeroconf server listening on port 37195" Oct 11 09:15:50 volumio go-librespot[2290]: time="2026-10-11T09:15:50+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 11 09:15:50 volumio go-librespot[2290]: time="2026-10-11T09:15:50+02:00" level=debug msg="obtained new client token: AAH0KMyZe95R1hkAG1ZEGN/eWmS8qukPKsIUR6A3uGMoWjveMni4l0TQ4pRAcVu/GtWbCddTvz9A0L4z9XYo9JU0bbDvGSClw5Ckf5+HgDn4aYfEI2P0stwwF8WSfM7H4EBx5CM9jIw8bN8EfJmxjm/zkX5WzDQZrByzrdzWvtSE1LTzcdBa/J4htsIg9iQWCWgPsWfFLjQT8v3z7z3AKf+4WP9f2kysXCjnJw1GcY2IcVYb6ITYGwnN" Oct 11 09:15:50 volumio volumio[1291]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Oct 11 09:15:50 volumio go-librespot[2290]: time="2026-10-11T09:15:50+02:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.1.133:4070: connect: connection refused" Oct 11 09:15:51 volumio go-librespot[2290]: time="2026-10-11T09:15:51+02:00" level=debug msg="connected to ap-gew4.spotify.com:443" Oct 11 09:15:51 volumio go-librespot[2290]: time="2026-10-11T09:15:51+02:00" level=debug msg="completed keyexchange" Oct 11 09:15:51 volumio go-librespot[2290]: time="2026-10-11T09:15:51+02:00" level=debug msg="completed challenge" Oct 11 09:15:51 volumio go-librespot[2290]: time="2026-10-11T09:15:51+02:00" level=info msg="authenticated AP" username="ql*********************ho" Oct 11 09:15:51 volumio go-librespot[2290]: time="2026-10-11T09:15:51+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 11 09:15:51 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 11 09:15:51 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 11 09:15:51 volumio volumio[1291]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Oct 11 09:15:51 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:51 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:51 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:51 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:51 volumio volumio[1291]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Oct 11 09:15:52 volumio volumio[1291]: info: Preparing to generate the ALSA configuration file Oct 11 09:15:52 volumio volumio[1291]: info: Initializing connection to go-librespot Websocket Oct 11 09:15:52 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:52 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:52 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:52 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:52 volumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Oct 11 09:15:52 volumio volumio[1291]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 11 09:15:52 volumio volumio[1291]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 11 09:15:52 volumio volumio[1291]: info: Reading ALSA contributions from plugins. Oct 11 09:15:52 volumio volumio[1291]: info: MRS: Removed streaming files Oct 11 09:15:52 volumio volumio[1291]: info: MRS: volumioStreaming STOPPED Oct 11 09:15:52 volumio volumio[1291]: info: MRS: SNAPSERVER STOPPED Oct 11 09:15:52 volumio volumio[1291]: info: MRS: SNAPCLIENT STOPPED Oct 11 09:15:53 volumio volumio[1291]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 11 09:15:53 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:53 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:53 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:53 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:54 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 19. Oct 11 09:15:54 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 11 09:15:54 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 11 09:15:54 volumio go-librespot[2307]: go-librespot daemon starting... Oct 11 09:15:54 volumio sudo[2306]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Oct 11 09:15:54 volumio sudo[2306]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 09:15:54 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:54 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:54 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:54 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:54 volumio go-librespot[2308]: time="2026-10-11T09:15:54+02:00" level=info msg="running go-librespot 0.7.1" Oct 11 09:15:54 volumio go-librespot[2308]: time="2026-10-11T09:15:54+02:00" level=debug msg="app state loaded" Oct 11 09:15:54 volumio go-librespot[2308]: time="2026-10-11T09:15:54+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 11 09:15:54 volumio volumio[1291]: info: Asound.conf file written Oct 11 09:15:54 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. Oct 11 09:15:54 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. Oct 11 09:15:54 volumio systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Oct 11 09:15:54 volumio go-librespot[2308]: time="2026-10-11T09:15:54+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 11 09:15:54 volumio go-librespot[2308]: time="2026-10-11T09:15:54+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 11 09:15:54 volumio go-librespot[2308]: time="2026-10-11T09:15:54+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 11 09:15:54 volumio go-librespot[2308]: time="2026-10-11T09:15:54+02:00" level=info msg="zeroconf server listening on port 45577" Oct 11 09:15:54 volumio go-librespot[2308]: time="2026-10-11T09:15:54+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 11 09:15:54 volumio sudo[2306]: pam_unix(sudo:session): session closed for user root Oct 11 09:15:54 volumio autossh[2323]: port set to 0, monitoring disabled Oct 11 09:15:54 volumio autossh[2323]: starting ssh (count 1) Oct 11 09:15:54 volumio autossh[2323]: ssh child pid is 2329 Oct 11 09:15:55 volumio go-librespot[2308]: time="2026-10-11T09:15:55+02:00" level=debug msg="obtained new client token: AAH6AVuIbPEDC8E2wtoPcHvNTKhRLEdRgGi5ez/iVQCR1Lg+Lj/3ciONV+j//LmJJGj+p2rCpTmbK/cFbxVXHK8Q0mNGbOzj5lHuih+fQZ5ko9xOVQYS4bH+77R6cxcra+tIXACU66gShcocDnXTS1tS7HN09zXqKpM5NpVs2FlEAo4PhyegYD23gAgNM/QiFx3MxPHTywbHAnkFTOYXwBV/iU04xYBXPGW1iNkVN73PN/NgLGzDrg==" Oct 11 09:15:55 volumio go-librespot[2308]: time="2026-10-11T09:15:55+02:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.1.133:4070: connect: connection refused" Oct 11 09:15:55 volumio go-librespot[2308]: time="2026-10-11T09:15:55+02:00" level=debug msg="connected to ap-gew4.spotify.com:443" Oct 11 09:15:55 volumio go-librespot[2308]: time="2026-10-11T09:15:55+02:00" level=debug msg="completed keyexchange" Oct 11 09:15:55 volumio go-librespot[2308]: time="2026-10-11T09:15:55+02:00" level=debug msg="completed challenge" Oct 11 09:15:55 volumio go-librespot[2308]: time="2026-10-11T09:15:55+02:00" level=info msg="authenticated AP" username="ql*********************ho" Oct 11 09:15:55 volumio sudo[2320]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 11 09:15:55 volumio sudo[2320]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 09:15:55 volumio volumiossh-tunnel[2329]: Warning: Permanently added '[eu6.myvolumio.org]:2222' (RSA) to the list of known hosts. Oct 11 09:15:55 volumio sudo[2320]: pam_unix(sudo:session): session closed for user root Oct 11 09:15:55 volumio go-librespot[2308]: time="2026-10-11T09:15:55+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 11 09:15:55 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 11 09:15:55 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 11 09:15:55 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:55 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:55 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:55 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:55 volumio volumio[1291]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 11 09:15:55 volumio volumio[1291]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Oct 11 09:15:55 volumio volumio[1291]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Oct 11 09:15:55 volumio volumio[1291]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 Oct 11 09:15:55 volumio volumio[1291]: info: Output device has changed, restarting MPD Oct 11 09:15:55 volumio sudo[2345]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 11 09:15:55 volumio sudo[2345]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 09:15:55 volumio volumio[1291]: info: Output device has changed, restarting Shairport Sync Oct 11 09:15:55 volumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 09:15:55 volumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 09:15:55 volumio sudo[2345]: pam_unix(sudo:session): session closed for user root Oct 11 09:15:55 volumio sudo[2347]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 11 09:15:55 volumio sudo[2347]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 09:15:56 volumio volumio[1291]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 11 09:15:56 volumio volumio[1291]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 11 09:15:56 volumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 09:15:56 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Oct 11 09:15:56 volumio volumio[1291]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Oct 11 09:15:56 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:56 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:56 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:56 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:56 volumio volumio[1291]: ------------------------------------ BT MESSAGE: [FUNC] onStart Oct 11 09:15:56 volumio volumio[1291]: ------------------------------------ BT MESSAGE: Starting Volumio Bluetooth Service Oct 11 09:15:56 volumio systemd[1]: mpd.service: Deactivated successfully. Oct 11 09:15:56 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 11 09:15:56 volumio systemd[1]: mpd.service: Consumed 3.384s CPU time. Oct 11 09:15:56 volumio systemd[1]: mpd.socket: Deactivated successfully. Oct 11 09:15:56 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 11 09:15:56 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 11 09:15:56 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 11 09:15:56 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 11 09:15:56 volumio volumio[1291]: ------------------------------------ BT MESSAGE: Boot config /etc/bluetooth/volumio.conf: cache mode = tmp Oct 11 09:15:56 volumio volumio[1291]: ------------------------------------ BT MESSAGE: [metaCache] Created directory: /tmp/bluetooth-cache/ Oct 11 09:15:56 volumio volumio[1291]: ------------------------------------ BT MESSAGE: [metaCache] Directory exists and is ready. Oct 11 09:15:56 volumio volumio[1291]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Oct 11 09:15:56 volumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Oct 11 09:15:56 volumio volumio[1291]: info: MRS: Pushing multiroomSync output for this device Oct 11 09:15:56 volumio sudo[2357]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 11 09:15:56 volumio sudo[2357]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 11 09:15:56 volumio sudo[2357]: pam_unix(sudo:session): session closed for user root Oct 11 09:15:56 volumio volumio[1291]: info: MRS: Pushing multiroomSync output Oct 11 09:15:57 volumio volumio[1291]: info: Adding audio output: Oct 11 09:15:57 volumio volumio[1291]: info: Adding audio output: Oct 11 09:15:57 volumio bluetoothd[966]: Path / reserved for Adv Monitor app :1.45 Oct 11 09:15:57 volumio volumio[1291]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 11 09:15:57 volumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 09:15:57 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:57 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:57 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:57 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:57 volumio volumio[1291]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Oct 11 09:15:57 volumio volumio[1291]: info: Adding METAVOLUMIO REST API Endpoints Oct 11 09:15:57 volumio volumio[1291]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Oct 11 09:15:57 volumio volumio[1291]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Oct 11 09:15:57 volumio volumio[1291]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Oct 11 09:15:57 volumio volumio[1291]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Oct 11 09:15:57 volumio volumio[1291]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Oct 11 09:15:57 volumio bluetoothd[966]: Adv Monitor app :1.45 disconnected from D-Bus Oct 11 09:15:57 volumio volumio[1291]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Oct 11 09:15:57 volumio volumio[1291]: info: Preparing CD Folders Oct 11 09:15:57 volumio volumio[1291]: info: Adding CD REST API Endpoints Oct 11 09:15:57 volumio volumio[1291]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Oct 11 09:15:57 volumio volumio[1291]: info: Starting UDEV Watcher for CD Oct 11 09:15:57 volumio volumio[1291]: info: Detecting CD presence with UDEV Oct 11 09:15:57 volumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Oct 11 09:15:58 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:58 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:58 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:58 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:58 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 20. Oct 11 09:15:58 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 11 09:15:58 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 11 09:15:58 volumio go-librespot[2366]: go-librespot daemon starting... Oct 11 09:15:58 volumio go-librespot[2368]: time="2026-10-11T09:15:58+02:00" level=info msg="running go-librespot 0.7.1" Oct 11 09:15:58 volumio go-librespot[2368]: time="2026-10-11T09:15:58+02:00" level=debug msg="app state loaded" Oct 11 09:15:58 volumio go-librespot[2368]: time="2026-10-11T09:15:58+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 11 09:15:59 volumio go-librespot[2368]: time="2026-10-11T09:15:59+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Oct 11 09:15:59 volumio go-librespot[2368]: time="2026-10-11T09:15:59+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Oct 11 09:15:59 volumio go-librespot[2368]: time="2026-10-11T09:15:59+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Oct 11 09:15:59 volumio go-librespot[2368]: time="2026-10-11T09:15:59+02:00" level=info msg="zeroconf server listening on port 40963" Oct 11 09:15:59 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:59 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:59 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:15:59 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:15:59 volumio go-librespot[2368]: time="2026-10-11T09:15:59+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 11 09:15:59 volumio go-librespot[2368]: time="2026-10-11T09:15:59+02:00" level=debug msg="obtained new client token: AAGD4t7Kk8YebZ/Ag+2HLXAw5r+em605X2PlvXpgl00rDDuorBjcvs3NSIC3CjqH8Kg6pmgSB9ouSGe7l7DtUd+6NMojSA9mX8wZ6SSI2WwkTgAcf7pl3UTM254mptAvHijroYX+5aBPupph/kBzadBB+N4cEDUTvy/aCFDqWMjli3CvD+t8pCIw/YJlDpAwITy4TJslCpmX4ODf/0kBbAVYU3S8Y2rdpSP7dJ5Qe+xPjUqvzb76035L" Oct 11 09:15:59 volumio go-librespot[2368]: time="2026-10-11T09:15:59+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 11 09:15:59 volumio go-librespot[2368]: time="2026-10-11T09:15:59+02:00" level=debug msg="completed keyexchange" Oct 11 09:15:59 volumio go-librespot[2368]: time="2026-10-11T09:15:59+02:00" level=debug msg="completed challenge" Oct 11 09:15:59 volumio go-librespot[2368]: time="2026-10-11T09:15:59+02:00" level=info msg="authenticated AP" username="ql*********************ho" Oct 11 09:15:59 volumio go-librespot[2368]: time="2026-10-11T09:15:59+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 11 09:15:59 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 11 09:15:59 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 11 09:16:00 volumio mpd[2361]: 2026-10-11T09:16:00 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 11 09:16:00 volumio systemd[1]: Started mpd.service - Music Player Daemon. Oct 11 09:16:00 volumio sudo[2347]: pam_unix(sudo:session): session closed for user root Oct 11 09:16:00 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:16:00 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:16:00 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:16:00 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:16:01 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:16:01 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:16:01 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:16:01 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:16:02 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:16:02 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:16:02 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:16:02 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:16:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 21. Oct 11 09:16:03 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 11 09:16:03 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 11 09:16:03 volumio go-librespot[2381]: go-librespot daemon starting... Oct 11 09:16:03 volumio go-librespot[2382]: time="2026-10-11T09:16:03+02:00" level=info msg="running go-librespot 0.7.1" Oct 11 09:16:03 volumio go-librespot[2382]: time="2026-10-11T09:16:03+02:00" level=debug msg="app state loaded" Oct 11 09:16:03 volumio go-librespot[2382]: time="2026-10-11T09:16:03+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 11 09:16:03 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:16:03 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:16:03 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:16:03 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:16:03 volumio go-librespot[2382]: time="2026-10-11T09:16:03+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 11 09:16:03 volumio go-librespot[2382]: time="2026-10-11T09:16:03+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 11 09:16:03 volumio go-librespot[2382]: time="2026-10-11T09:16:03+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 11 09:16:03 volumio go-librespot[2382]: time="2026-10-11T09:16:03+02:00" level=info msg="zeroconf server listening on port 42021" Oct 11 09:16:03 volumio go-librespot[2382]: time="2026-10-11T09:16:03+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 11 09:16:03 volumio go-librespot[2382]: time="2026-10-11T09:16:03+02:00" level=debug msg="obtained new client token: AAHmhbQEcNmg0LEr0Jb8UbAeslpBTc7r+3QxAPqndienSMYRZjj1jI5zEtBiNw7cLrizzLfRLOrhNqcyMp3Bh+8ilQeE5/2ylleLR1V5iJg/FbfWetTGvOfpxsWT7MHEt5t710as4YZeenLhLJE71LIMTcq9ZNjxo/KBT4vKyVxUhFYpQ7FG7bV5PBJuAy86KD28gyLwWYlNQKJ2OIIePBI2PV5pjIWcTwWA0UVHge8GKoIxggZH6qJY" Oct 11 09:16:04 volumio go-librespot[2382]: time="2026-10-11T09:16:04+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 11 09:16:04 volumio volumio[1291]: warn: [cd-plugin] cdspeedctl: device or media not ready Oct 11 09:16:04 volumio volumio[1291]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Oct 11 09:16:04 volumio volumio[1291]: info: Adding inputs REST Endpoints Oct 11 09:16:04 volumio volumio[1291]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Oct 11 09:16:04 volumio volumio[1291]: info: Scanning Audio Inputs Oct 11 09:16:04 volumio go-librespot[2382]: time="2026-10-11T09:16:04+02:00" level=debug msg="completed keyexchange" Oct 11 09:16:04 volumio go-librespot[2382]: time="2026-10-11T09:16:04+02:00" level=debug msg="completed challenge" Oct 11 09:16:04 volumio go-librespot[2382]: time="2026-10-11T09:16:04+02:00" level=info msg="authenticated AP" username="ql*********************ho" Oct 11 09:16:04 volumio go-librespot[2382]: time="2026-10-11T09:16:04+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 11 09:16:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 11 09:16:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 11 09:16:04 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:16:04 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:16:04 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:16:04 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:16:04 volumio volumio[1291]: info: Checking against Known Cards name Oct 11 09:16:04 volumio volumio[1291]: info: Adding Server instance for streaming Oct 11 09:16:04 volumio volumio[1291]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Oct 11 09:16:04 volumio volumio[1291]: error: Hi Res Audio Failed Login: Missing Login Data Oct 11 09:16:04 volumio volumio[1291]: info: Adding HIGHRESAUDIO REST API Endpoints Oct 11 09:16:04 volumio volumio[1291]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Oct 11 09:16:04 volumio volumio[1291]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Oct 11 09:16:04 volumio volumio[1291]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Oct 11 09:16:04 volumio volumio[1291]: info: Refreshing QOBUZ token Oct 11 09:16:04 volumio volumio[1291]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Oct 11 09:16:04 volumio volumio[1291]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect Oct 11 09:16:04 volumio volumio[1291]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Oct 11 09:16:05 volumio volumio[1291]: info: QobuzConnect: Starting Qobuz Connect socket and service Oct 11 09:16:05 volumio sudo[2395]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 11 09:16:05 volumio sudo[2395]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 09:16:05 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:16:05 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:16:05 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:16:05 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:16:05 volumio volumio[1291]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Oct 11 09:16:05 volumio volumio[1291]: info: Adding TIDAL REST API Endpoints Oct 11 09:16:05 volumio sudo[2395]: pam_unix(sudo:session): session closed for user root Oct 11 09:16:05 volumio volumio[1291]: info: Initializing connection to go-librespot Websocket Oct 11 09:16:05 volumio volumio[1291]: error: MyVolumio Plugins failed to start in a timely fashion Oct 11 09:16:05 volumio volumio[1291]: error: Plugin audio_interface bluetooth failed to complete 'onStart' in a timely fashion Oct 11 09:16:05 volumio volumio[1291]: error: Plugin audio_interface multiroom failed to complete 'onStart' in a timely fashion Oct 11 09:16:05 volumio volumio[1291]: error: Plugin music_service qobuz failed to complete 'onStart' in a timely fashion Oct 11 09:16:05 volumio volumio[1291]: error: Plugin music_service tidalconnect failed to complete 'onStart' in a timely fashion Oct 11 09:16:05 volumio volumio[1291]: error: Plugin music_service qobuzconnect failed to complete 'onStart' in a timely fashion Oct 11 09:16:05 volumio volumio[1291]: [Metrics] CommandRouter: 153s 725.92ms Oct 11 09:16:05 volumio volumio[1291]: info: CoreCommandRouter::volumiosetStartupVolume Oct 11 09:16:05 volumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 09:16:05 volumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 09:16:05 volumio sudo[2403]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 11 09:16:05 volumio sudo[2403]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 09:16:05 volumio volumio[1291]: info: CoreCommandRouter::Close All Modals sent Oct 11 09:16:06 volumio volumio[1291]: info: CoreCommandRouter::Close All Modals sent Oct 11 09:16:06 volumio volumio[1291]: info: MRS: Getting audio outputs on start Oct 11 09:16:06 volumio volumio[1291]: info: MRS: Requesting all other devices output Oct 11 09:16:06 volumio sudo[2403]: pam_unix(sudo:session): session closed for user root Oct 11 09:16:06 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:16:06 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:16:06 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:16:06 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:16:06 volumio sudo[2421]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 11 09:16:06 volumio sudo[2421]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 09:16:07 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 11 09:16:07 volumio sudo[2421]: pam_unix(sudo:session): session closed for user root Oct 11 09:16:07 volumio volumio[1291]: ------------------------------------ BT MESSAGE: Bluetooth adapter powered on Oct 11 09:16:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22. Oct 11 09:16:07 volumio volumio[1291]: info: MPD Permissions set Oct 11 09:16:07 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 11 09:16:07 volumio volumio[1291]: info: Remote SSH Started Oct 11 09:16:07 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:16:07 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:16:07 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:16:07 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:16:07 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 11 09:16:07 volumio go-librespot[2432]: go-librespot daemon starting... Oct 11 09:16:07 volumio go-librespot[2433]: time="2026-10-11T09:16:07+02:00" level=info msg="running go-librespot 0.7.1" Oct 11 09:16:07 volumio go-librespot[2433]: time="2026-10-11T09:16:07+02:00" level=debug msg="app state loaded" Oct 11 09:16:07 volumio sudo[2431]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Oct 11 09:16:07 volumio go-librespot[2433]: time="2026-10-11T09:16:07+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 11 09:16:07 volumio sudo[2431]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 09:16:08 volumio go-librespot[2433]: time="2026-10-11T09:16:08+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 11 09:16:08 volumio go-librespot[2433]: time="2026-10-11T09:16:08+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 11 09:16:08 volumio go-librespot[2433]: time="2026-10-11T09:16:08+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 11 09:16:08 volumio go-librespot[2433]: time="2026-10-11T09:16:08+02:00" level=info msg="zeroconf server listening on port 33855" Oct 11 09:16:08 volumio go-librespot[2433]: time="2026-10-11T09:16:08+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 11 09:16:08 volumio systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Oct 11 09:16:08 volumio go-librespot[2433]: time="2026-10-11T09:16:08+02:00" level=debug msg="obtained new client token: AAELPTPlX1CrdA7S26VnmDdS//ou2kWrFbob71DpBfAGvNCG5RxbQGaPoM5sXW4WhHekvakfJW6oY1o2M96ErFGv86y7Wnhrl2VqxssWPrRSX6IDEaWpHcyQIp7Twu21a2MhMAXeVvlsRRVXuUEYFO9D/m4aWiIBie2IhqOEiHY6pZUSNrE4Ile/CDj8zkC/LUrMpMyK8mxCJdUMO3HeD5le7C3hGwcXg5gUCzlkoU2VQeJ6ekFjJA==" Oct 11 09:16:08 volumio volumiobt[2444]: INFO [BTSTART] Ensuring Bluetooth directory exists... Oct 11 09:16:08 volumio sudo[2446]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth Oct 11 09:16:08 volumio sudo[2446]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 09:16:08 volumio go-librespot[2433]: time="2026-10-11T09:16:08+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 11 09:16:08 volumio sudo[2446]: pam_unix(sudo:session): session closed for user root Oct 11 09:16:08 volumio sudo[2431]: pam_unix(sudo:session): session closed for user root Oct 11 09:16:08 volumio go-librespot[2433]: time="2026-10-11T09:16:08+02:00" level=debug msg="completed keyexchange" Oct 11 09:16:08 volumio go-librespot[2433]: time="2026-10-11T09:16:08+02:00" level=debug msg="completed challenge" Oct 11 09:16:08 volumio sudo[2448]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth Oct 11 09:16:08 volumio sudo[2448]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 09:16:08 volumio sudo[2448]: pam_unix(sudo:session): session closed for user root Oct 11 09:16:08 volumio go-librespot[2433]: time="2026-10-11T09:16:08+02:00" level=info msg="authenticated AP" username="ql*********************ho" Oct 11 09:16:08 volumio volumiobt[2450]: INFO [BTSTART] Powering on Bluetooth if needed... Oct 11 09:16:08 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:16:08 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:16:08 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:16:08 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:16:08 volumio go-librespot[2433]: time="2026-10-11T09:16:08+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 11 09:16:08 volumio bluetoothd[966]: Path / reserved for Adv Monitor app :1.50 Oct 11 09:16:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 11 09:16:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 11 09:16:08 volumio bluetoothd[966]: Adv Monitor app :1.50 disconnected from D-Bus Oct 11 09:16:08 volumio volumiobt[2453]: INFO [BTSTART] Making Bluetooth discoverable and pairable... Oct 11 09:16:08 volumio volumiobt[2454]: [176B blob data] Oct 11 09:16:08 volumio volumiobt[2454]: [157B blob data] Oct 11 09:16:08 volumio volumiobt[2454]: [157B blob data] Oct 11 09:16:08 volumio volumiobt[2454]: [157B blob data] Oct 11 09:16:08 volumio volumiobt[2454]: [113B blob data] Oct 11 09:16:08 volumio bluetoothd[966]: Path / reserved for Adv Monitor app :1.51 Oct 11 09:16:08 volumio volumiobt[2454]: [bluetoothctl]> discoverable on Oct 11 09:16:08 volumio volumiobt[2454]: Warning: setting discoverable while discoverable-timeout not set(0) is not recommended Oct 11 09:16:08 volumio volumiobt[2454]: [bluetoothctl]> pairable on Oct 11 09:16:08 volumio volumiobt[2454]: [133B blob data] Oct 11 09:16:08 volumio bluetoothd[966]: Adv Monitor app :1.51 disconnected from D-Bus Oct 11 09:16:08 volumio volumiobt[2454]: [bluetoothctl]> Oct 11 09:16:08 volumio volumiobt[2455]: INFO [BTSTART] Registering Bluetooth agent... Oct 11 09:16:08 volumio volumiobt[2456]: [NEW] Media /org/bluez/hci0 Oct 11 09:16:08 volumio volumiobt[2456]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Oct 11 09:16:08 volumio volumiobt[2456]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Oct 11 09:16:08 volumio volumiobt[2456]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb Oct 11 09:16:08 volumio bluetoothd[966]: Path / reserved for Adv Monitor app :1.52 Oct 11 09:16:08 volumio bluetoothd[966]: Adv Monitor app :1.52 disconnected from D-Bus Oct 11 09:16:08 volumio volumiobt[2457]: No agent is registered Oct 11 09:16:08 volumio volumiobt[2457]: [NEW] Media /org/bluez/hci0 Oct 11 09:16:08 volumio volumiobt[2457]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Oct 11 09:16:08 volumio volumiobt[2457]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Oct 11 09:16:08 volumio volumiobt[2457]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb Oct 11 09:16:08 volumio bluetoothd[966]: Path / reserved for Adv Monitor app :1.53 Oct 11 09:16:08 volumio bluetoothd[966]: Adv Monitor app :1.53 disconnected from D-Bus Oct 11 09:16:09 volumio volumiobt[2458]: INFO [BTSTART] Agent registered successfully. Oct 11 09:16:09 volumio volumiobt[2459]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... Oct 11 09:16:09 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:16:09 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:16:09 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:16:09 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:16:09 volumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 11 09:16:09 volumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 11 09:16:10 volumio volumiobt[2460]: 2026-10-11 09:16:10 a2dp-agent [INFO] Connecting to system D-Bus Oct 11 09:16:10 volumio volumiobt[2460]: 2026-10-11 09:16:10 a2dp-agent [INFO] Connected to system D-Bus Oct 11 09:16:10 volumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 11 09:16:10 volumio volumiobt[2460]: 2026-10-11 09:16:10 bluezutils [INFO] Found adapter at: /org/bluez/hci0 Oct 11 09:16:10 volumio volumiobt[2460]: 2026-10-11 09:16:10 a2dp-agent [INFO] Found Bluetooth adapter: /org/bluez/hci0 Oct 11 09:16:10 volumio volumiobt[2460]: 2026-10-11 09:16:10 a2dp-agent [INFO] Set DiscoverableTimeout to infinite Oct 11 09:16:10 volumio volumiobt[2460]: 2026-10-11 09:16:10 a2dp-agent [INFO] Enabled Discoverable mode Oct 11 09:16:10 volumio volumiobt[2460]: 2026-10-11 09:16:10 a2dp-agent [INFO] Agent registered at /local/a2dpagent Oct 11 09:16:10 volumio volumiobt[2460]: 2026-10-11 09:16:10 a2dp-agent [INFO] Agent set as default Oct 11 09:16:10 volumio volumiobt[2460]: 2026-10-11 09:16:10 a2dp-agent [INFO] A2DP agent running, waiting for connections... Oct 11 09:16:10 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:16:10 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:16:10 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:16:10 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:16:10 volumio volumio[1291]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 11 09:16:10 volumio volumio[1291]: ------------------------------------ BT MESSAGE: volumiobt.service started successfully Oct 11 09:16:10 volumio volumio[1291]: ------------------------------------ BT MESSAGE: [FUNC] dbusStart Oct 11 09:16:10 volumio volumio[1291]: info: TidalConnect service stoped! Oct 11 09:16:10 volumio volumio[1291]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Oct 11 09:16:10 volumio volumio[1291]: info: FusionDsp - ---- read samplerate from file: 44100 Oct 11 09:16:10 volumio volumio[1291]: info: camilladsp stopping service pid 1692... Oct 11 09:16:11 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:16:11 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:16:11 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:16:11 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:16:11 volumio kernel: xhci_hcd 0000:01:00.0: ERROR Transfer event for disabled endpoint slot 4 ep 1 Oct 11 09:16:11 volumio kernel: xhci_hcd 0000:01:00.0: @000000040cd06a10 00000000 00000000 0e000000 04028001 Oct 11 09:16:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 23. Oct 11 09:16:11 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 11 09:16:11 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 11 09:16:11 volumio go-librespot[2478]: go-librespot daemon starting... Oct 11 09:16:11 volumio volumio[1291]: grep: /proc/1692/cmdline: binary file matches Oct 11 09:16:11 volumio volumio[1291]: grep: /proc/1692/cmdline: binary file matches Oct 11 09:16:11 volumio volumio[1291]: grep: /proc/1692/cmdline: binary file matches Oct 11 09:16:11 volumio volumio[1291]: info: camilladsp service terminated, instance 1 Oct 11 09:16:11 volumio volumio[1291]: info: FusionDsp - If filter freq >samplerate/2 then disable it Oct 11 09:16:11 volumio volumio[1291]: info: camilladsp service started and running in background, instance 1 Oct 11 09:16:11 volumio go-librespot[2480]: time="2026-10-11T09:16:11+02:00" level=info msg="running go-librespot 0.7.1" Oct 11 09:16:11 volumio go-librespot[2480]: time="2026-10-11T09:16:11+02:00" level=debug msg="app state loaded" Oct 11 09:16:11 volumio go-librespot[2480]: time="2026-10-11T09:16:11+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 11 09:16:12 volumio go-librespot[2480]: time="2026-10-11T09:16:12+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 11 09:16:12 volumio go-librespot[2480]: time="2026-10-11T09:16:12+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 11 09:16:12 volumio go-librespot[2480]: time="2026-10-11T09:16:12+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 11 09:16:12 volumio go-librespot[2480]: time="2026-10-11T09:16:12+02:00" level=info msg="zeroconf server listening on port 40593" Oct 11 09:16:12 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:16:12 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:16:12 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:16:12 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:16:12 volumio go-librespot[2480]: time="2026-10-11T09:16:12+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 11 09:16:12 volumio go-librespot[2480]: time="2026-10-11T09:16:12+02:00" level=debug msg="obtained new client token: AAG8+uLw7WYCWretVO7HPqR5BhhcdKKtOqNdU859fjASsdcdjE/36phoH5tgYa7+MJXU2UAnbYGeFU72ALUUn9DjsLundY+70M6K2L40knwvhECGslTGlGNihLfD329C35gK7ZA6B055QAsetKGE91hwJnsEdDFm/e+/rMB58Ie3WjBH1PhjGtUSMopViiHvNEazB3upmIddRbJhSfPWw8xqKYInrX4YFlCwDvm7tANIvrSvA+rMCM8A" Oct 11 09:16:12 volumio go-librespot[2480]: time="2026-10-11T09:16:12+02:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.1.133:4070: connect: connection refused" Oct 11 09:16:12 volumio go-librespot[2480]: time="2026-10-11T09:16:12+02:00" level=debug msg="connected to ap-gew4.spotify.com:443" Oct 11 09:16:12 volumio go-librespot[2480]: time="2026-10-11T09:16:12+02:00" level=debug msg="completed keyexchange" Oct 11 09:16:12 volumio go-librespot[2480]: time="2026-10-11T09:16:12+02:00" level=debug msg="completed challenge" Oct 11 09:16:12 volumio go-librespot[2480]: time="2026-10-11T09:16:12+02:00" level=info msg="authenticated AP" username="ql*********************ho" Oct 11 09:16:12 volumio go-librespot[2480]: time="2026-10-11T09:16:12+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 11 09:16:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 11 09:16:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 11 09:16:13 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:16:13 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:16:13 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:16:13 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:16:14 volumio volumio[1291]: info: Executing endpoint qc_getconfig Oct 11 09:16:14 volumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 11 09:16:14 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:16:14 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:16:14 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:16:14 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:16:14 volumio qobuz-connect[2429]: 20261011 09:16:14.483 [2429.2429] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 11 09:16:14 volumio volumio[1291]: info: camilladsp respawn in 100 ms (attempt 1/10) Oct 11 09:16:14 volumio volumio[1291]: info: BOOT COMPLETED Oct 11 09:16:14 volumio volumio[1291]: info: Initializing connection to go-librespot Websocket Oct 11 09:16:14 volumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 09:16:14 volumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 09:16:14 volumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 09:16:14 volumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 09:16:14 volumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 09:16:14 volumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 09:16:14 volumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 09:16:14 volumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 09:16:14 volumio qobuz-connect[2429]: 20261011 09:16:14.738 [2429.2429] INFO VolumeManager: [0x1faff68]: Setting new playback volume: 75 Oct 11 09:16:14 volumio qobuz-connect[2429]: 20261011 09:16:14.745 [2429.2429] INFO VolumeManager: [0x1faff68]: Setting new mute state: 0 Oct 11 09:16:14 volumio qobuz-connect[2429]: 20261011 09:16:14.745 [2429.2429] INFO AudioStreamManager: [0x1fafcc0]: Setting new audio download buffer size: 1048576 Oct 11 09:16:14 volumio qobuz-connect[2429]: 20261011 09:16:14.745 [2429.2429] INFO QobuzConnect: [0x1fb0830]: Client initialized! Oct 11 09:16:14 volumio qobuz-connect[2429]: 20261011 09:16:14.745 [2429.2429] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Oct 11 09:16:14 volumio qobuz-connect[2429]: 20261011 09:16:14.772 [2429.2429] INFO LocalConfigManager: [0x1faf9e8]: Starting Local Configuration server Oct 11 09:16:14 volumio qobuz-connect[2429]: 20261011 09:16:14.772 [2429.2429] INFO SampleApp: Starting Local configuration server Oct 11 09:16:14 volumio qobuz-connect[2429]: 20261011 09:16:14.773 [2429.2429] INFO SampleApp: Connected to UNIX socket client 0x1f9a818 Oct 11 09:16:14 volumio qobuz-connect[2429]: 20261011 09:16:14.774 [2429.2429] INFO SampleApp: Playback volume changed: 75 Oct 11 09:16:14 volumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 09:16:14 volumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 09:16:14 volumio volumio[1291]: info: Access Token successfully retrieved Oct 11 09:16:14 volumio volumio[1291]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 11 09:16:14 volumio volumio[1291]: info: [1791702974921] CoreMusicLibrary::Adding element QOBUZ Oct 11 09:16:14 volumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 09:16:14 volumio volumio[1291]: Cannot find translation for source QOBUZ Oct 11 09:16:14 volumio volumio[1291]: info: Stopping AccessToken refresher cron for QOBUZ Oct 11 09:16:15 volumio volumio[1291]: info: AccessToken refresher cron started for QOBUZ Oct 11 09:16:15 volumio volumio[1291]: info: Adding QOBUZ REST API Endpoints Oct 11 09:16:15 volumio volumio[1291]: error: updateQueue error: null Oct 11 09:16:15 volumio volumio[1291]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Oct 11 09:16:15 volumio volumio[1291]: info: QobuzConnect: QOBUZ Connect daemon connected Oct 11 09:16:15 volumio volumio[1291]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Oct 11 09:16:15 volumio volumio[1291]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 11 09:16:15 volumio volumio[1291]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 11 09:16:15 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:16:15 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:16:15 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:16:15 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:16:15 volumio volumio[1291]: info: MRS: Found cast device: MagentaTV-One-(2.-Ge-f4cce37676ff4f8c11940976c4cd7187 Oct 11 09:16:15 volumio volumio[1291]: info: Adding audio output: Oct 11 09:16:15 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 24. Oct 11 09:16:15 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 11 09:16:15 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 11 09:16:15 volumio go-librespot[2511]: go-librespot daemon starting... Oct 11 09:16:16 volumio volumio[1291]: info: CoreCommandRouter::volumioGetState Oct 11 09:16:16 volumio volumio[1291]: info: CorePlayQueue::getTrack 0 Oct 11 09:16:16 volumio go-librespot[2512]: time="2026-10-11T09:16:16+02:00" level=info msg="running go-librespot 0.7.1" Oct 11 09:16:16 volumio go-librespot[2512]: time="2026-10-11T09:16:16+02:00" level=debug msg="app state loaded" Oct 11 09:16:16 volumio go-librespot[2512]: time="2026-10-11T09:16:16+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 11 09:16:16 volumio volumio[1291]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 11 09:16:16 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:16:16 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:16:16 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:16:16 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:16:16 volumio kernel: xhci_hcd 0000:01:00.0: ERROR Transfer event for disabled endpoint slot 4 ep 1 Oct 11 09:16:16 volumio kernel: xhci_hcd 0000:01:00.0: @000000040cd08dd0 00000000 00000000 0e000000 04028001 Oct 11 09:16:16 volumio volumio[1291]: info: Starting Shairport Sync Oct 11 09:16:16 volumio go-librespot[2512]: time="2026-10-11T09:16:16+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 11 09:16:16 volumio go-librespot[2512]: time="2026-10-11T09:16:16+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 11 09:16:16 volumio go-librespot[2512]: time="2026-10-11T09:16:16+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 11 09:16:16 volumio go-librespot[2512]: time="2026-10-11T09:16:16+02:00" level=info msg="zeroconf server listening on port 45991" Oct 11 09:16:16 volumio go-librespot[2512]: time="2026-10-11T09:16:16+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 11 09:16:16 volumio go-librespot[2512]: time="2026-10-11T09:16:16+02:00" level=debug msg="obtained new client token: AAGywUSTWweXXOdviUjiZPowaymV59Ij0n++bD/SwUlAYK9F7CN9FheZttCHacW7f4KguwKZFbaFN/e5cjJrKYOsE0LVe+3lV3YE4PHKhBBaRTZFjl4+vfzv8ye1ldtIWdLb9OvXggcltnu8l0AUXKc9WCiA/FpOCZGv4IVCHxnedcIbUrPYnV9KjfQbZK5uyQn24QXif5X9wqD0lkESk1oNBVfpREWNhRwl+8vpmEZWw2gU9W/rS2r+" Oct 11 09:16:16 volumio go-librespot[2512]: time="2026-10-11T09:16:16+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 11 09:16:16 volumio go-librespot[2512]: time="2026-10-11T09:16:16+02:00" level=debug msg="completed keyexchange" Oct 11 09:16:16 volumio go-librespot[2512]: time="2026-10-11T09:16:16+02:00" level=debug msg="completed challenge" Oct 11 09:16:16 volumio volumio[1291]: info: camilladsp respawn in 200 ms (attempt 2/10) Oct 11 09:16:16 volumio go-librespot[2512]: time="2026-10-11T09:16:16+02:00" level=info msg="authenticated AP" username="ql*********************ho" Oct 11 09:16:17 volumio go-librespot[2512]: time="2026-10-11T09:16:17+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 11 09:16:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 11 09:16:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 11 09:16:17 volumio sudo[2531]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 11 09:16:17 volumio sudo[2531]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 09:16:17 volumio sudo[2537]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 11 09:16:17 volumio sudo[2537]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 09:16:17 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:16:17 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:16:17 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:16:17 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:16:17 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 11 09:16:17 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Oct 11 09:16:17 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 11 09:16:17 volumio systemd[1]: shairport-sync.service: Consumed 1.820s CPU time. Oct 11 09:16:17 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 11 09:16:17 volumio sudo[2531]: pam_unix(sudo:session): session closed for user root Oct 11 09:16:17 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Oct 11 09:16:17 volumio sudo[2537]: pam_unix(sudo:session): session closed for user root Oct 11 09:16:18 volumio volumio[1291]: info: Shairport-Sync Started Oct 11 09:16:18 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:16:18 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:16:18 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:16:18 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:16:18 volumio volumio[1291]: info: CoreCommandRouter::volumioGetState Oct 11 09:16:18 volumio volumio[1291]: info: CorePlayQueue::getTrack 0 Oct 11 09:16:19 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:16:19 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:16:19 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:16:19 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:16:19 volumio volumio[1291]: info: Initializing connection to go-librespot Websocket Oct 11 09:16:19 volumio volumio[1291]: info: Executing endpoint tc_getconfig Oct 11 09:16:19 volumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Oct 11 09:16:19 volumio vtcs[2559]: STARTING TidalConnect services, version: 1.6.1 Oct 11 09:16:19 volumio vtcs[2559]: STARTED TidalConnect services. Oct 11 09:16:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 25. Oct 11 09:16:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 11 09:16:20 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 11 09:16:20 volumio go-librespot[2568]: go-librespot daemon starting... Oct 11 09:16:20 volumio go-librespot[2569]: time="2026-10-11T09:16:20+02:00" level=info msg="running go-librespot 0.7.1" Oct 11 09:16:20 volumio go-librespot[2569]: time="2026-10-11T09:16:20+02:00" level=debug msg="app state loaded" Oct 11 09:16:20 volumio go-librespot[2569]: time="2026-10-11T09:16:20+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 11 09:16:20 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:16:20 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:16:20 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:16:20 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:16:20 volumio go-librespot[2569]: time="2026-10-11T09:16:20+02:00" level=debug msg="new websocket client" Oct 11 09:16:20 volumio volumio[1291]: info: Executing endpoint tc_connect Oct 11 09:16:20 volumio volumio[1291]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Oct 11 09:16:20 volumio volumio[1291]: info: Connecting to TidalConnect Oct 11 09:16:20 volumio go-librespot[2569]: time="2026-10-11T09:16:20+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 11 09:16:20 volumio go-librespot[2569]: time="2026-10-11T09:16:20+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 11 09:16:20 volumio go-librespot[2569]: time="2026-10-11T09:16:20+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 11 09:16:20 volumio go-librespot[2569]: time="2026-10-11T09:16:20+02:00" level=info msg="zeroconf server listening on port 37587" Oct 11 09:16:20 volumio go-librespot[2569]: time="2026-10-11T09:16:20+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 11 09:16:20 volumio volumio[1291]: info: Connection to go-librespot Websocket established Oct 11 09:16:21 volumio volumio[1291]: info: CoreCommandRouter::servicePushState Oct 11 09:16:21 volumio volumio[1291]: info: CoreStateMachine::pushState Oct 11 09:16:21 volumio volumio[1291]: info: CorePlayQueue::getTrack 0 Oct 11 09:16:21 volumio volumio[1291]: info: CoreCommandRouter::volumioPushState Oct 11 09:16:21 volumio go-librespot[2569]: time="2026-10-11T09:16:21+02:00" level=debug msg="obtained new client token: AAGTxYu20w4W3Sh6mywjtwDBRo81iPa0+i7hMC5Y54VKErQn4h/MJ061dTFZukHmdHyLuHvMJ8T0JP4BdL2kXp/159co8I1FlUl7a7/rVJq4fmVQH7d0cYGEKJPqOxQEUzx0sBEBvKw/+vBX5kYl5sIpLyGqhT3EenEbcIlLTivgvqqEC9RG3D1qh3vuSdQ3yVoksxzsqjfcXqekdWnha//PiJ6e+9I7LSIcsLX6AfECk5pXj0jbzQ==" Oct 11 09:16:21 volumio go-librespot[2569]: time="2026-10-11T09:16:21+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 11 09:16:21 volumio go-librespot[2569]: time="2026-10-11T09:16:21+02:00" level=debug msg="completed keyexchange" Oct 11 09:16:21 volumio go-librespot[2569]: time="2026-10-11T09:16:21+02:00" level=debug msg="completed challenge" Oct 11 09:16:21 volumio volumio[1291]: info: MRS: Pushing multiroomSync output update for this device Oct 11 09:16:21 volumio volumio[1291]: info: MRS: Pushing multiroomSync output Oct 11 09:16:21 volumio go-librespot[2569]: time="2026-10-11T09:16:21+02:00" level=info msg="authenticated AP" username="ql*********************ho" Oct 11 09:16:21 volumio volumio[1291]: info: CoreCommandRouter::volumioGetState Oct 11 09:16:21 volumio volumio[1291]: info: CorePlayQueue::getTrack 0 Oct 11 09:16:21 volumio volumio[1291]: info: CorePlayQueue::getTrack 0 Oct 11 09:16:21 volumio volumio[1291]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received tidalconnect Oct 11 09:16:21 volumio volumio[1291]: info: CoreCommandRouter::servicePushState Oct 11 09:16:21 volumio volumio[1291]: info: CoreStateMachine::pushState Oct 11 09:16:21 volumio volumio[1291]: info: CorePlayQueue::getTrack 0 Oct 11 09:16:21 volumio volumio[1291]: info: CoreCommandRouter::volumioPushState Oct 11 09:16:21 volumio volumio[1291]: info: MRS: Pushing multiroomSync output update for this device Oct 11 09:16:21 volumio volumio[1291]: info: MRS: Pushing multiroomSync output Oct 11 09:16:21 volumio volumio[1291]: info: CoreCommandRouter::volumioGetState Oct 11 09:16:21 volumio volumio[1291]: info: CorePlayQueue::getTrack 0 Oct 11 09:16:21 volumio go-librespot[2569]: time="2026-10-11T09:16:21+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 11 09:16:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 11 09:16:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 11 09:16:21 volumio volumio[1291]: info: CorePlayQueue::getTrack 0 Oct 11 09:16:21 volumio volumio[1291]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received tidalconnect Oct 11 09:16:21 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:16:21 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:16:21 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:16:21 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:16:22 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:16:22 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:16:22 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:16:22 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:16:22 volumio volumio[1291]: info: Connection to go-librespot Websocket closed Oct 11 09:16:22 volumio volumio[1291]: info: TidalConnect service started! Oct 11 09:16:23 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:16:23 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:16:23 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:16:23 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:16:24 volumio volumio[1291]: info: Getting Spotify volume Oct 11 09:16:24 volumio volumio[1291]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 11 09:16:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 26. Oct 11 09:16:24 volumio volumio[1291]: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 11 09:16:24 volumio volumio[1291]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Oct 11 09:16:24 volumio volumio[1291]: errno: -111, Oct 11 09:16:24 volumio volumio[1291]: code: 'ECONNREFUSED', Oct 11 09:16:24 volumio volumio[1291]: syscall: 'connect', Oct 11 09:16:24 volumio volumio[1291]: address: '127.0.0.1', Oct 11 09:16:24 volumio volumio[1291]: port: 9879, Oct 11 09:16:24 volumio volumio[1291]: response: undefined Oct 11 09:16:24 volumio volumio[1291]: } Oct 11 09:16:24 volumio volumio[1291]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 11 09:16:24 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 11 09:16:24 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 11 09:16:24 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:16:24 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:16:24 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:16:24 volumio go-librespot[2587]: go-librespot daemon starting... Oct 11 09:16:24 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:16:24 volumio go-librespot[2590]: time="2026-10-11T09:16:24+02:00" level=info msg="running go-librespot 0.7.1" Oct 11 09:16:24 volumio go-librespot[2590]: time="2026-10-11T09:16:24+02:00" level=debug msg="app state loaded" Oct 11 09:16:24 volumio go-librespot[2590]: time="2026-10-11T09:16:24+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 11 09:16:25 volumio go-librespot[2590]: time="2026-10-11T09:16:25+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 11 09:16:25 volumio go-librespot[2590]: time="2026-10-11T09:16:25+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 11 09:16:25 volumio go-librespot[2590]: time="2026-10-11T09:16:25+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 11 09:16:25 volumio go-librespot[2590]: time="2026-10-11T09:16:25+02:00" level=info msg="zeroconf server listening on port 38107" Oct 11 09:16:25 volumio go-librespot[2590]: time="2026-10-11T09:16:25+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 11 09:16:25 volumio go-librespot[2590]: time="2026-10-11T09:16:25+02:00" level=debug msg="obtained new client token: AAEnPL2d4S6vNy9L/HY5JxHAhlT3DL4DcV0nwCN12ms4wIJFi0sZB8tfrGJIpi2OZ0Ditd1xijKE/r52R7p5GWbcQF2ZpqBKm8KfBALLn1vLKQtEclr/iXS7F/cwQXr/lL0tpUS+WWDzYhH3m3YBssyZXLyDwiazC8H9De8ACjll8jM4KVcoSvALu/lpF9Wr74zc0VZk/ihpeIPkKyUfdOxgIz4zE9cDKChMEpDa1+b8EEfu2hIOrw==" Oct 11 09:16:25 volumio go-librespot[2590]: time="2026-10-11T09:16:25+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 11 09:16:25 volumio go-librespot[2590]: time="2026-10-11T09:16:25+02:00" level=debug msg="completed keyexchange" Oct 11 09:16:25 volumio go-librespot[2590]: time="2026-10-11T09:16:25+02:00" level=debug msg="completed challenge" Oct 11 09:16:25 volumio go-librespot[2590]: time="2026-10-11T09:16:25+02:00" level=info msg="authenticated AP" username="ql*********************ho" Oct 11 09:16:25 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:16:25 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:16:25 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:16:25 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:16:25 volumio go-librespot[2590]: time="2026-10-11T09:16:25+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 11 09:16:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 11 09:16:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 11 09:16:26 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:16:26 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:16:26 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:16:26 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:16:27 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:16:27 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:16:27 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:16:27 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:16:28 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:16:28 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:16:28 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:16:28 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:16:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 27. Oct 11 09:16:28 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 11 09:16:28 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 11 09:16:28 volumio go-librespot[2622]: go-librespot daemon starting... Oct 11 09:16:28 volumio go-librespot[2623]: time="2026-10-11T09:16:28+02:00" level=info msg="running go-librespot 0.7.1" Oct 11 09:16:28 volumio go-librespot[2623]: time="2026-10-11T09:16:28+02:00" level=debug msg="app state loaded" Oct 11 09:16:28 volumio go-librespot[2623]: time="2026-10-11T09:16:28+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 11 09:16:29 volumio go-librespot[2623]: time="2026-10-11T09:16:29+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 11 09:16:29 volumio go-librespot[2623]: time="2026-10-11T09:16:29+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 11 09:16:29 volumio go-librespot[2623]: time="2026-10-11T09:16:29+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 11 09:16:29 volumio go-librespot[2623]: time="2026-10-11T09:16:29+02:00" level=info msg="zeroconf server listening on port 36449" Oct 11 09:16:29 volumio go-librespot[2623]: time="2026-10-11T09:16:29+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 11 09:16:29 volumio go-librespot[2623]: time="2026-10-11T09:16:29+02:00" level=debug msg="obtained new client token: AAHR5RcW5rr/ALFfqFA9zB+qLcoTsv35JtqNyaPu7g/vVa/PJDosV1MK0eM7dmpWRETk3OrG/Rr9MqSbrEZIuJQbxx9nI9Vo4pRHVOilGg83q4kWOZ+6IyszdR+8aT2FKH3IvtxpkrpByPxCUhGb1vCAMhAry5hE2KlJW5/rjRpS5+Bw4N4I00XcIH6W2Y+SBAaPwpgkIyW+ElcXIF7DXGsL0lsVaNwZYBQ7+Uhos+pN75i+qwnQSZkC" Oct 11 09:16:29 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:16:29 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:16:29 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:16:29 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:16:29 volumio go-librespot[2623]: time="2026-10-11T09:16:29+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 11 09:16:29 volumio go-librespot[2623]: time="2026-10-11T09:16:29+02:00" level=debug msg="completed keyexchange" Oct 11 09:16:29 volumio go-librespot[2623]: time="2026-10-11T09:16:29+02:00" level=debug msg="completed challenge" Oct 11 09:16:29 volumio go-librespot[2623]: time="2026-10-11T09:16:29+02:00" level=info msg="authenticated AP" username="ql*********************ho" Oct 11 09:16:29 volumio go-librespot[2623]: time="2026-10-11T09:16:29+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 11 09:16:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 11 09:16:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 11 09:16:30 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:16:30 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:16:30 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:16:30 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:16:31 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:16:31 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:16:31 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:16:31 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:16:32 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:16:32 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:16:32 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:16:32 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:16:32 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 28. Oct 11 09:16:32 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 11 09:16:32 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 11 09:16:33 volumio go-librespot[2633]: go-librespot daemon starting... Oct 11 09:16:33 volumio go-librespot[2634]: time="2026-10-11T09:16:33+02:00" level=info msg="running go-librespot 0.7.1" Oct 11 09:16:33 volumio go-librespot[2634]: time="2026-10-11T09:16:33+02:00" level=debug msg="app state loaded" Oct 11 09:16:33 volumio go-librespot[2634]: time="2026-10-11T09:16:33+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 11 09:16:33 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:16:33 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:16:33 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:16:33 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:16:33 volumio go-librespot[2634]: time="2026-10-11T09:16:33+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Oct 11 09:16:33 volumio go-librespot[2634]: time="2026-10-11T09:16:33+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Oct 11 09:16:33 volumio go-librespot[2634]: time="2026-10-11T09:16:33+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Oct 11 09:16:33 volumio go-librespot[2634]: time="2026-10-11T09:16:33+02:00" level=info msg="zeroconf server listening on port 46473" Oct 11 09:16:33 volumio go-librespot[2634]: time="2026-10-11T09:16:33+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 11 09:16:33 volumio go-librespot[2634]: time="2026-10-11T09:16:33+02:00" level=debug msg="obtained new client token: AAFr6kPpy/DmTXBz7sQWX4NLoqBtbxVvW4k8ZSNXpzwmlAvL2/lnOFUqTBecBHFZKgwjYc0NEYW354aepX3QQqiR3zxIlqKplWQ+FIFSDiNCNDYaxKUm3V5wwCdwphXs8+cdyF84Kc0OIUglNzGepkWt5/8/qPAwYFz/VoPX4W1456z7HTctkW+gXGFMvn9bPJIjzXpqNGFXep3fezcVNwdb4uGpTDwTLJRXlH4o1goVed6lUY7e1dNg" Oct 11 09:16:33 volumio go-librespot[2634]: time="2026-10-11T09:16:33+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 11 09:16:33 volumio go-librespot[2634]: time="2026-10-11T09:16:33+02:00" level=debug msg="completed keyexchange" Oct 11 09:16:33 volumio go-librespot[2634]: time="2026-10-11T09:16:33+02:00" level=debug msg="completed challenge" Oct 11 09:16:33 volumio go-librespot[2634]: time="2026-10-11T09:16:33+02:00" level=info msg="authenticated AP" username="ql*********************ho" Oct 11 09:16:34 volumio go-librespot[2634]: time="2026-10-11T09:16:34+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 11 09:16:34 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 11 09:16:34 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 11 09:16:34 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:16:34 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:16:34 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:16:34 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:16:35 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:16:35 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:16:35 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:16:35 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:16:36 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:16:36 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:16:36 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:16:36 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:16:36 volumio sudo[2645]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-11 09:15' Oct 11 09:16:36 volumio sudo[2645]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 09:16:37 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 29. Oct 11 09:16:37 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 11 09:16:37 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 11 09:16:37 volumio go-librespot[2647]: go-librespot daemon starting... Oct 11 09:16:37 volumio go-librespot[2648]: time="2026-10-11T09:16:37+02:00" level=info msg="running go-librespot 0.7.1" Oct 11 09:16:37 volumio go-librespot[2648]: time="2026-10-11T09:16:37+02:00" level=debug msg="app state loaded" Oct 11 09:16:37 volumio go-librespot[2648]: time="2026-10-11T09:16:37+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 11 09:16:37 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:16:37 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:16:37 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:16:37 volumio lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:16:37 volumio go-librespot[2648]: time="2026-10-11T09:16:37+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 11 09:16:37 volumio go-librespot[2648]: time="2026-10-11T09:16:37+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 11 09:16:37 volumio go-librespot[2648]: time="2026-10-11T09:16:37+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 11 09:16:37 volumio go-librespot[2648]: time="2026-10-11T09:16:37+02:00" level=info msg="zeroconf server listening on port 42663" Oct 11 09:16:37 volumio go-librespot[2648]: time="2026-10-11T09:16:37+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 11 09:16:38 volumio go-librespot[2648]: time="2026-10-11T09:16:38+02:00" level=debug msg="obtained new client token: AAEjn05eCuI4D4c1Jj6CqUjvqfMZlmoFObzzTprPVMv8zJEcQPS6NM8VZVzFsBarzOKIPGQ2TjSprCupnzIZf49nA1KBJgJR3H6gztKuTvWfw0u1Wr5epDNGW/WeiQ7MDfoc349QRoGHUkn8HYf9Iw3zVsPT0MGNqqXQiMyOaG+vkZLLGvVwUOlYQqfW3CGBzlaKfUqcZ67RmJ+IaNKVUuFYjk3Y6tAfmrChakqD0IiCm2FtEH7tmA==" Oct 11 09:16:38 volumio go-librespot[2648]: time="2026-10-11T09:16:38+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 11 09:16:38 volumio go-librespot[2648]: time="2026-10-11T09:16:38+02:00" level=debug msg="completed keyexchange" Oct 11 09:16:38 volumio go-librespot[2648]: time="2026-10-11T09:16:38+02:00" level=debug msg="completed challenge" Oct 11 09:16:38 volumio go-librespot[2648]: time="2026-10-11T09:16:38+02:00" level=info msg="authenticated AP" username="ql*********************ho" Oct 11 09:16:38 volumio go-librespot[2648]: time="2026-10-11T09:16:38+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 11 09:16:38 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 11 09:16:38 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 11 09:16:38 volumio lircd[1838]: lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:16:38 volumio lircd[1838]: lircd-0.10.1[1838]: default_init(): No such file or directory Oct 11 09:16:38 volumio lircd-0.10.1[1838]: Error: could not get file information for /dev/lirc0 Oct 11 09:16:38 volumio lircd-0.10.1[1838]: default_init(): No such file or directory 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"