Aug 30 23:59:26 living-room ntpd[1168]: CLOCK: time stepped by 19290432.012542 Aug 30 23:59:26 living-room ntpd[1168]: CLOCK: time changed from 2026-01-19 to 2026-08-30 Aug 30 23:59:26 living-room ntpd[1168]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Aug 30 23:59:26 living-room systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1. Aug 30 23:59:27 living-room systemd[1]: Starting dpkg-db-backup.service - Daily dpkg database backup service... Aug 30 23:59:27 living-room systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats. Aug 30 23:59:27 living-room systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 30 23:59:27 living-room systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 30 23:59:27 living-room sudo[1799]: pam_unix(sudo:session): session closed for user root Aug 30 23:59:27 living-room volumio[1244]: info: Upmpdcli Daemon Started Aug 30 23:59:27 living-room systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully. Aug 30 23:59:27 living-room systemd[1]: dpkg-db-backup.service: Deactivated successfully. Aug 30 23:59:27 living-room systemd[1]: Finished dpkg-db-backup.service - Daily dpkg database backup service. Aug 30 23:59:27 living-room systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Aug 30 23:59:27 living-room systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 30 23:59:27 living-room go-librespot[1831]: go-librespot daemon starting... Aug 30 23:59:27 living-room systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 30 23:59:27 living-room go-librespot[1832]: time="2026-08-30T23:59:27+02:00" level=info msg="running go-librespot 0.6.2" Aug 30 23:59:27 living-room go-librespot[1832]: time="2026-08-30T23:59:27+02:00" level=debug msg="app state loaded" Aug 30 23:59:27 living-room go-librespot[1832]: time="2026-08-30T23:59:27+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 30 23:59:27 living-room go-librespot[1832]: time="2026-08-30T23:59:27+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]" Aug 30 23:59:27 living-room go-librespot[1832]: time="2026-08-30T23:59:27+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]" Aug 30 23:59:27 living-room go-librespot[1832]: time="2026-08-30T23:59:27+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]" Aug 30 23:59:27 living-room go-librespot[1832]: time="2026-08-30T23:59:27+02:00" level=info msg="zeroconf server listening on port 37881" Aug 30 23:59:27 living-room volumio[1244]: info: Discovery: A device disappeared from network Aug 30 23:59:27 living-room volumio[1244]: info: Discovery: A device disappeared from network Aug 30 23:59:27 living-room volumio[1244]: info: Discovery: Device bedroom disappeared from network Aug 30 23:59:27 living-room volumio[1244]: info: Discovery: Disconnected from remote: 192.168.0.225 Aug 30 23:59:27 living-room volumio[1244]: info: Discovery: A device disappeared from network Aug 30 23:59:27 living-room go-librespot[1832]: time="2026-08-30T23:59:27+02:00" level=debug msg="obtained new client token: AAHKqpNXGyTKl1fuQf5BRo+1yhX89GETPaLeNNenS4DTUSyso7wtvkQFJVyjCVcurLQvLz5FXTysAdUcWoFdjQUXlTN1pVTmgV6IoVarP7O3vTvpac6z9u6kHwa8hEvSBjYWzLo9eQ2rUcEnr2rsVkBGAONe1+79b7CIGHcNcnoxsCbmuX6vTzFOCxJ9ymd9nMvfPFeggfY9VVU67SD5UIO7lJNwsJYaHy6zavZ9sVVyS7+Ne/E84fA=" Aug 30 23:59:27 living-room volumio[1244]: info: Discovery: adding c02912b3-b468-4a83-923c-8594683cb7b1 Aug 30 23:59:27 living-room volumio[1244]: info: Discovery: Found device Living Room Aug 30 23:59:27 living-room volumio[1244]: info: CoreCommandRouter::volumioGetState Aug 30 23:59:27 living-room volumio[1244]: info: CorePlayQueue::getTrack 0 Aug 30 23:59:27 living-room volumio[1244]: info: Discovery: this is already registered, c02912b3-b468-4a83-923c-8594683cb7b1 Aug 30 23:59:27 living-room volumio[1244]: info: Discovery: Found device Living Room Aug 30 23:59:27 living-room volumio[1244]: info: CoreCommandRouter::volumioGetState Aug 30 23:59:27 living-room volumio[1244]: info: CorePlayQueue::getTrack 0 Aug 30 23:59:27 living-room go-librespot[1832]: time="2026-08-30T23:59:27+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 30 23:59:27 living-room go-librespot[1832]: time="2026-08-30T23:59:27+02:00" level=debug msg="completed keyexchange" Aug 30 23:59:27 living-room go-librespot[1832]: time="2026-08-30T23:59:27+02:00" level=debug msg="completed challenge" Aug 30 23:59:27 living-room volumio[1244]: info: Discovery: adding d85b745d-bd92-490a-ab30-c55655ac1b3a Aug 30 23:59:27 living-room volumio[1244]: info: Discovery: Found device Bedroom Aug 30 23:59:27 living-room volumio[1244]: info: Discovery: Connecting to remote: 192.168.0.225 Aug 30 23:59:27 living-room volumio[1244]: info: Discovery: Connected to remote: 192.168.0.225 Aug 30 23:59:27 living-room go-librespot[1832]: time="2026-08-30T23:59:27+02:00" level=info msg="authenticated AP" username="ik*******ev" Aug 30 23:59:28 living-room go-librespot[1832]: time="2026-08-30T23:59:28+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 30 23:59:28 living-room systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 23:59:28 living-room systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 23:59:29 living-room volumio[1244]: info: Initializing connection to go-librespot Websocket Aug 30 23:59:29 living-room volumio[1244]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 23:59:30 living-room volumio[1244]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Aug 30 23:59:30 living-room volumio[1244]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Aug 30 23:59:30 living-room volumio[1244]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Aug 30 23:59:30 living-room volumio[1244]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Aug 30 23:59:30 living-room volumio[1244]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Aug 30 23:59:30 living-room volumio[1244]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Aug 30 23:59:30 living-room volumio[1244]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Aug 30 23:59:30 living-room volumio[1244]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Aug 30 23:59:30 living-room volumio[1244]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Aug 30 23:59:30 living-room volumio[1244]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Aug 30 23:59:30 living-room volumio[1244]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Aug 30 23:59:30 living-room volumio[1244]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Aug 30 23:59:30 living-room volumio[1244]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Aug 30 23:59:30 living-room volumio[1244]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Aug 30 23:59:30 living-room volumio[1244]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Aug 30 23:59:30 living-room volumio[1244]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Aug 30 23:59:30 living-room volumio[1244]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Aug 30 23:59:30 living-room volumio[1244]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Aug 30 23:59:30 living-room volumio[1244]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Aug 30 23:59:30 living-room volumio[1244]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Aug 30 23:59:30 living-room volumio[1244]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Aug 30 23:59:30 living-room volumio[1244]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Aug 30 23:59:30 living-room volumio[1244]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Aug 30 23:59:30 living-room volumio[1244]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Aug 30 23:59:30 living-room volumio[1244]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Aug 30 23:59:30 living-room volumio[1244]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Aug 30 23:59:30 living-room volumio[1244]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Aug 30 23:59:30 living-room volumio[1244]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Aug 30 23:59:30 living-room volumio[1244]: info: Adding plugin bluetooth to MyMusic Plugins Aug 30 23:59:30 living-room volumio[1244]: info: Adding plugin multiroom to MyMusic Plugins Aug 30 23:59:30 living-room volumio[1244]: info: Adding plugin metavolumio to MyMusic Plugins Aug 30 23:59:30 living-room volumio[1244]: info: Adding plugin cd_controller to MyMusic Plugins Aug 30 23:59:30 living-room volumio[1244]: info: Adding plugin qobuzconnect to MyMusic Plugins Aug 30 23:59:30 living-room volumio[1244]: info: Adding plugin smart_inputs to MyMusic Plugins Aug 30 23:59:30 living-room volumio[1244]: info: Adding plugin tidalconnect to MyMusic Plugins Aug 30 23:59:30 living-room volumio[1244]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Aug 30 23:59:30 living-room volumio[1244]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Aug 30 23:59:30 living-room volumio[1244]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Aug 30 23:59:30 living-room volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 23:59:30 living-room volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 23:59:30 living-room volumio[1244]: info: Starting MyVolumio Remote Streaming Endpoints Aug 30 23:59:30 living-room volumio[1244]: info: MyVolumio login type: Token Aug 30 23:59:30 living-room volumio[1244]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Aug 30 23:59:30 living-room volumio[1244]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Aug 30 23:59:31 living-room systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Aug 30 23:59:31 living-room systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 30 23:59:31 living-room systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 30 23:59:31 living-room go-librespot[1844]: go-librespot daemon starting... Aug 30 23:59:31 living-room go-librespot[1845]: time="2026-08-30T23:59:31+02:00" level=info msg="running go-librespot 0.6.2" Aug 30 23:59:31 living-room go-librespot[1845]: time="2026-08-30T23:59:31+02:00" level=debug msg="app state loaded" Aug 30 23:59:31 living-room go-librespot[1845]: time="2026-08-30T23:59:31+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 30 23:59:31 living-room volumio[1244]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Aug 30 23:59:31 living-room volumio[1244]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Aug 30 23:59:31 living-room volumio[1244]: info: Streaming services startup Aug 30 23:59:31 living-room volumio[1244]: info: Starting Streaming Daemon Aug 30 23:59:31 living-room volumio[1244]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Aug 30 23:59:31 living-room go-librespot[1845]: time="2026-08-30T23:59:31+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]" Aug 30 23:59:31 living-room go-librespot[1845]: time="2026-08-30T23:59:31+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]" Aug 30 23:59:31 living-room go-librespot[1845]: time="2026-08-30T23:59:31+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]" Aug 30 23:59:31 living-room go-librespot[1845]: time="2026-08-30T23:59:31+02:00" level=info msg="zeroconf server listening on port 33261" Aug 30 23:59:31 living-room sudo[1851]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 30 23:59:31 living-room sudo[1851]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 23:59:31 living-room sudo[1851]: pam_unix(sudo:session): session closed for user root Aug 30 23:59:31 living-room volumio[1244]: error: Cannot start Volumio Streaming Daemon Aug 30 23:59:31 living-room volumio[1244]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 30 23:59:31 living-room volumio[1244]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 30 23:59:31 living-room go-librespot[1845]: time="2026-08-30T23:59:31+02:00" level=debug msg="obtained new client token: AAEI8JU5rNABi7SQ0rSH3QyWKuyRRT45HSYYGKtWccBi5a0dQUd4kldqPRWjXM/WOSolEQ29OepEG1Paf8VE3puZoHse4piJNUDmpKWuwSLm1YVS1qErGdX5jN0XyGa7ok3I0OtXQLriobnRaYLVdjG2JzDkKHq2dMtoO7qXUIzVDwaiojNY/ggpkookbaU5JFvmFBRkqmguzXMFC5u14/fkVGyt2ebQMBMErfhGxTOcONQyKhaPDF0=" Aug 30 23:59:31 living-room go-librespot[1845]: time="2026-08-30T23:59:31+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" Aug 30 23:59:31 living-room go-librespot[1845]: time="2026-08-30T23:59:31+02:00" level=debug msg="connected to ap-gew4.spotify.com:443" Aug 30 23:59:31 living-room go-librespot[1845]: time="2026-08-30T23:59:31+02:00" level=debug msg="completed keyexchange" Aug 30 23:59:31 living-room go-librespot[1845]: time="2026-08-30T23:59:31+02:00" level=debug msg="completed challenge" Aug 30 23:59:32 living-room go-librespot[1845]: time="2026-08-30T23:59:32+02:00" level=info msg="authenticated AP" username="ik*******ev" Aug 30 23:59:32 living-room volumio[1244]: error: MyVolumio Custom Token format not valid, refreshing it Aug 30 23:59:32 living-room go-librespot[1845]: time="2026-08-30T23:59:32+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 30 23:59:32 living-room systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 23:59:32 living-room systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 23:59:32 living-room volumio[1244]: info: Initializing connection to go-librespot Websocket Aug 30 23:59:32 living-room volumio[1244]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 23:59:32 living-room volumio[1244]: info: MyVolumio login type: Token Aug 30 23:59:33 living-room volumio[1244]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Aug 30 23:59:33 living-room volumio[1244]: info: MyVolumio token set successfully Aug 30 23:59:33 living-room volumio[1244]: info: MYVOLUMIO: Adding device Aug 30 23:59:33 living-room volumio[1244]: info: MYVOLUMIO: Evaluating Server Aug 30 23:59:34 living-room volumio[1244]: info: MyVolumio Plan changed: premium Aug 30 23:59:34 living-room volumio[1244]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Aug 30 23:59:34 living-room volumio[1244]: info: Removing browser output: myVolumio user plan is not superstar Aug 30 23:59:34 living-room volumio[1244]: info: Removing audio output: Aug 30 23:59:34 living-room volumio[1244]: info: MYVOLUMIO: Adding device Aug 30 23:59:34 living-room volumio[1244]: info: MYVOLUMIO: Evaluating Server Aug 30 23:59:34 living-room volumio[1244]: info: Remote config written successfully Aug 30 23:59:34 living-room volumio[1244]: info: Starting Tunnel 1 Aug 30 23:59:34 living-room volumio[1244]: info: Starting Tunnel Connection Checker Aug 30 23:59:34 living-room volumio[1244]: info: Completed starting MyVolumio Plugin Aug 30 23:59:34 living-room volumio[1244]: info: MYVolumio Device enabled Aug 30 23:59:34 living-room volumio[1244]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Aug 30 23:59:34 living-room volumio[1244]: info: MyVolumio status changed Aug 30 23:59:34 living-room volumio[1244]: info: Streaming services startup Aug 30 23:59:34 living-room volumio[1244]: info: Starting Streaming Daemon Aug 30 23:59:34 living-room sudo[1898]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 30 23:59:34 living-room sudo[1898]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 23:59:34 living-room volumio[1244]: info: Setting Geolocation for MyVolumio to eu5 Aug 30 23:59:34 living-room volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 23:59:34 living-room volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 23:59:34 living-room volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 23:59:34 living-room volumio[1244]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Aug 30 23:59:34 living-room volumio[1244]: error: [MyVolumio PluginManager] Cache data is invalid! Aug 30 23:59:34 living-room sudo[1898]: pam_unix(sudo:session): session closed for user root Aug 30 23:59:34 living-room volumio[1244]: error: Cannot start Volumio Streaming Daemon Aug 30 23:59:34 living-room volumio[1244]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 30 23:59:34 living-room volumio[1244]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 30 23:59:34 living-room volumio[1244]: info: Setting Geolocation for MyVolumio to eu5 Aug 30 23:59:34 living-room volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 23:59:34 living-room volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 23:59:34 living-room volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 23:59:34 living-room volumio[1244]: info: Successfully Added MyVolumio device Aug 30 23:59:35 living-room volumio[1244]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Aug 30 23:59:35 living-room nmbd[1210]: [2026/08/30 23:59:35.092133, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Aug 30 23:59:35 living-room nmbd[1210]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.0.42 for name WORKGROUP<1d>. Aug 30 23:59:35 living-room nmbd[1210]: This response was from IP 192.168.0.69, reporting an IP address of 192.168.0.69. Aug 30 23:59:35 living-room volumio[1244]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin bluetooth Aug 30 23:59:35 living-room volumio[1244]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin bluetooth Aug 30 23:59:35 living-room volumio[1244]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart Aug 30 23:59:35 living-room volumio[1244]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Aug 30 23:59:35 living-room systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Aug 30 23:59:35 living-room systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 30 23:59:35 living-room systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 30 23:59:35 living-room go-librespot[1901]: go-librespot daemon starting... Aug 30 23:59:35 living-room go-librespot[1906]: time="2026-08-30T23:59:35+02:00" level=info msg="running go-librespot 0.6.2" Aug 30 23:59:35 living-room go-librespot[1906]: time="2026-08-30T23:59:35+02:00" level=debug msg="app state loaded" Aug 30 23:59:35 living-room go-librespot[1906]: time="2026-08-30T23:59:35+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 30 23:59:35 living-room go-librespot[1906]: time="2026-08-30T23:59:35+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]" Aug 30 23:59:35 living-room go-librespot[1906]: time="2026-08-30T23:59:35+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]" Aug 30 23:59:35 living-room go-librespot[1906]: time="2026-08-30T23:59:35+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]" Aug 30 23:59:35 living-room go-librespot[1906]: time="2026-08-30T23:59:35+02:00" level=info msg="zeroconf server listening on port 46753" Aug 30 23:59:35 living-room go-librespot[1906]: time="2026-08-30T23:59:35+02:00" level=debug msg="obtained new client token: AAH854Xp4vf+KDSarq5zZHVpTJ2099YnZksKq7ReirU/zonrJxUEmlIA/nPJVwmD7apgz7bhwezwQkjRz9P4SYf/uzwktXnmyx4KU8/VwD2WhnagtnRa6P4QlWvn+olMdNAlAJ+6zOWOnlBEzhZ8lZjP1i8yk/MR9Xxkxz+g+c2IpC9LJSjexmXuIFBtI8QFLJueq+0cY3V8aSXmgO9pMsJSp3tOIXEcu/fjMMP4IvfNJW2ejQ36gu4=" Aug 30 23:59:35 living-room go-librespot[1906]: time="2026-08-30T23:59:35+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 30 23:59:35 living-room go-librespot[1906]: time="2026-08-30T23:59:35+02:00" level=debug msg="completed keyexchange" Aug 30 23:59:35 living-room go-librespot[1906]: time="2026-08-30T23:59:35+02:00" level=debug msg="completed challenge" Aug 30 23:59:36 living-room go-librespot[1906]: time="2026-08-30T23:59:36+02:00" level=info msg="authenticated AP" username="ik*******ev" Aug 30 23:59:36 living-room volumio[1244]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Aug 30 23:59:36 living-room sudo[1925]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Aug 30 23:59:36 living-room sudo[1925]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 23:59:36 living-room sudo[1925]: pam_unix(sudo:session): session closed for user root Aug 30 23:59:36 living-room volumio[1244]: info: MRS: MultiRoom plugin initialized Aug 30 23:59:36 living-room volumio[1244]: info: MRS: STOPPING SNAPCLIENT Aug 30 23:59:36 living-room volumio[1244]: info: MRS: Snap server stop Aug 30 23:59:36 living-room volumio[1244]: info: MRS: STOPPING volumioStreaming Aug 30 23:59:36 living-room sudo[1942]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Aug 30 23:59:36 living-room sudo[1942]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 23:59:36 living-room go-librespot[1906]: time="2026-08-30T23:59:36+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 30 23:59:36 living-room systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 23:59:36 living-room systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 23:59:36 living-room volumio[1244]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Aug 30 23:59:36 living-room sudo[1946]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Aug 30 23:59:36 living-room sudo[1944]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Aug 30 23:59:36 living-room sudo[1944]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 23:59:36 living-room sudo[1946]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 23:59:36 living-room sudo[1950]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Aug 30 23:59:36 living-room sudo[1950]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 23:59:36 living-room sudo[1950]: pam_unix(sudo:session): session closed for user root Aug 30 23:59:36 living-room volumio[1244]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Aug 30 23:59:36 living-room volumio[1244]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Aug 30 23:59:36 living-room volumio[1244]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Aug 30 23:59:36 living-room volumio[1244]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Aug 30 23:59:36 living-room sudo[1942]: pam_unix(sudo:session): session closed for user root Aug 30 23:59:36 living-room sudo[1944]: pam_unix(sudo:session): session closed for user root Aug 30 23:59:36 living-room sudo[1946]: pam_unix(sudo:session): session closed for user root Aug 30 23:59:36 living-room volumio[1244]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Aug 30 23:59:36 living-room volumio[1244]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Aug 30 23:59:36 living-room volumio[1244]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Aug 30 23:59:36 living-room volumio[1244]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Aug 30 23:59:36 living-room volumio[1244]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Aug 30 23:59:36 living-room volumio[1244]: info: Preparing to generate the ALSA configuration file Aug 30 23:59:36 living-room volumio[1244]: info: Updating MyVolumio device info Aug 30 23:59:36 living-room volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 23:59:36 living-room volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 23:59:36 living-room volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 23:59:36 living-room volumio[1244]: info: Updating MyVolumio device info Aug 30 23:59:36 living-room volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 23:59:36 living-room volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 23:59:36 living-room volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 23:59:36 living-room volumio[1244]: info: Initializing connection to go-librespot Websocket Aug 30 23:59:36 living-room volumio[1244]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Aug 30 23:59:36 living-room volumio[1244]: info: Successfully Added MyVolumio device Aug 30 23:59:36 living-room volumio[1244]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Aug 30 23:59:36 living-room volumio[1244]: info: Reading ALSA contributions from plugins. Aug 30 23:59:36 living-room volumio[1244]: info: MRS: Removed streaming files Aug 30 23:59:36 living-room volumio[1244]: info: MRS: volumioStreaming STOPPED Aug 30 23:59:36 living-room volumio[1244]: info: MRS: SNAPSERVER STOPPED Aug 30 23:59:36 living-room volumio[1244]: info: MRS: SNAPCLIENT STOPPED Aug 30 23:59:36 living-room volumio[1244]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 23:59:36 living-room volumio[1244]: info: Asound.conf file written Aug 30 23:59:36 living-room sudo[1956]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Aug 30 23:59:36 living-room sudo[1956]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 23:59:36 living-room sudo[1956]: pam_unix(sudo:session): session closed for user root Aug 30 23:59:36 living-room volumio[1244]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Aug 30 23:59:36 living-room volumio[1244]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Aug 30 23:59:36 living-room volumio[1244]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Aug 30 23:59:36 living-room volumio[1244]: info: Output device has changed, restarting MPD Aug 30 23:59:36 living-room volumio[1244]: info: Output device has changed, restarting Shairport Sync Aug 30 23:59:36 living-room volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 23:59:36 living-room volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 23:59:36 living-room sudo[1962]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 30 23:59:36 living-room sudo[1964]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 30 23:59:36 living-room sudo[1962]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 23:59:36 living-room sudo[1964]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 23:59:36 living-room sudo[1962]: pam_unix(sudo:session): session closed for user root Aug 30 23:59:36 living-room volumio[1244]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 30 23:59:36 living-room volumio[1244]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 30 23:59:36 living-room volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 23:59:36 living-room systemd[1]: musicservicesshield.service: Deactivated successfully. Aug 30 23:59:36 living-room systemd[1]: Stopped musicservicesshield.service - Shield Volumio Music Services in the User CPU Set. Aug 30 23:59:36 living-room systemd[1]: Stopping musicservicesshield.service - Shield Volumio Music Services in the User CPU Set... Aug 30 23:59:36 living-room systemd[1]: Stopping mpd.service - Music Player Daemon... Aug 30 23:59:36 living-room volumio[1244]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Aug 30 23:59:36 living-room volumio[1244]: ------------------------------------ BT MESSAGE: [FUNC] onStart Aug 30 23:59:36 living-room volumio[1244]: ------------------------------------ BT MESSAGE: Starting Volumio Bluetooth Service Aug 30 23:59:36 living-room volumio[1244]: ------------------------------------ BT MESSAGE: Boot config /etc/bluetooth/volumio.conf: cache mode = tmp Aug 30 23:59:36 living-room volumio[1244]: ------------------------------------ BT MESSAGE: [metaCache] Created directory: /tmp/bluetooth-cache/ Aug 30 23:59:36 living-room volumio[1244]: ------------------------------------ BT MESSAGE: [metaCache] Directory exists and is ready. Aug 30 23:59:36 living-room volumio[1244]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Aug 30 23:59:36 living-room volumio[1244]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Aug 30 23:59:36 living-room volumio[1244]: info: MRS: Pushing multiroomSync output for this device Aug 30 23:59:36 living-room volumio[1244]: info: MRS: Pushing multiroomSync output Aug 30 23:59:36 living-room volumio[1244]: info: Adding audio output: Aug 30 23:59:36 living-room volumio[1244]: info: Adding audio output: Aug 30 23:59:36 living-room volumio[1244]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 30 23:59:36 living-room volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 23:59:36 living-room volumio[1244]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Aug 30 23:59:36 living-room volumio[1244]: info: Adding METAVOLUMIO REST API Endpoints Aug 30 23:59:36 living-room volumio[1244]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Aug 30 23:59:36 living-room volumio[1244]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Aug 30 23:59:36 living-room volumio[1244]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Aug 30 23:59:36 living-room volumio[1244]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Aug 30 23:59:36 living-room volumio[1244]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Aug 30 23:59:36 living-room volumio[1244]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Aug 30 23:59:36 living-room volumio[1244]: info: [MyVolumio PluginManager] Plugin cd_controller disabled by user. Not starting Aug 30 23:59:36 living-room volumio[1244]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Aug 30 23:59:36 living-room volumio[1244]: info: [MyVolumio PluginManager] Plugin smart_inputs disabled by user. Not starting Aug 30 23:59:36 living-room volumio[1244]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Aug 30 23:59:36 living-room bluetoothd[1005]: Path / reserved for Adv Monitor app :1.24 Aug 30 23:59:36 living-room bluetoothd[1005]: Adv Monitor app :1.24 disconnected from D-Bus Aug 30 23:59:36 living-room volumio[1244]: error: Hi Res Audio Failed Login: Missing Login Data Aug 30 23:59:36 living-room volumio[1244]: info: Adding HIGHRESAUDIO REST API Endpoints Aug 30 23:59:36 living-room volumio[1244]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Aug 30 23:59:36 living-room volumio[1244]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Aug 30 23:59:36 living-room volumio[1244]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Aug 30 23:59:36 living-room volumio[1244]: info: Refreshing QOBUZ token Aug 30 23:59:36 living-room volumio[1244]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Aug 30 23:59:36 living-room volumio[1244]: info: [MyVolumio PluginManager] Plugin tidalconnect disabled by user. Not starting Aug 30 23:59:36 living-room volumio[1244]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect Aug 30 23:59:36 living-room volumio[1244]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Aug 30 23:59:36 living-room systemd[1]: mpd.service: Deactivated successfully. Aug 30 23:59:36 living-room systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 30 23:59:36 living-room systemd[1]: mpd.service: Consumed 1.259s CPU time. Aug 30 23:59:36 living-room systemd[1]: mpd.socket: Deactivated successfully. Aug 30 23:59:36 living-room systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 30 23:59:36 living-room systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 30 23:59:36 living-room volumio[1244]: info: QobuzConnect: Starting Qobuz Connect socket and service Aug 30 23:59:36 living-room volumio[1244]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Aug 30 23:59:36 living-room volumio[1244]: info: Adding TIDAL REST API Endpoints Aug 30 23:59:36 living-room sudo[1984]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Aug 30 23:59:36 living-room systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 30 23:59:36 living-room systemd[1]: Starting mpd.service - Music Player Daemon... Aug 30 23:59:36 living-room sudo[1984]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 23:59:36 living-room sudo[1984]: pam_unix(sudo:session): session closed for user root Aug 30 23:59:36 living-room volumio[1244]: info: MRS: Adding multiroomSync output Aug 30 23:59:36 living-room sudo[1987]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Aug 30 23:59:36 living-room sudo[1987]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 23:59:36 living-room volumio[1244]: info: Adding audio output: Aug 30 23:59:36 living-room volumio[1244]: info: MRS: Pushing multiroomSync output Aug 30 23:59:36 living-room volumio[1244]: info: Successfully Updated MyVolumio device Aug 30 23:59:36 living-room volumio[1244]: ------------------------------------ BT MESSAGE: Bluetooth adapter powered on Aug 30 23:59:36 living-room sudo[1985]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 30 23:59:36 living-room sudo[1985]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 30 23:59:36 living-room volumio[1244]: info: MPD Permissions set Aug 30 23:59:36 living-room sudo[1985]: pam_unix(sudo:session): session closed for user root Aug 30 23:59:36 living-room sudo[1990]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Aug 30 23:59:36 living-room sudo[1990]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 23:59:36 living-room systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Aug 30 23:59:36 living-room sudo[1987]: pam_unix(sudo:session): session closed for user root Aug 30 23:59:36 living-room volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 23:59:36 living-room volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 23:59:36 living-room volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 23:59:36 living-room volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 23:59:36 living-room volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 23:59:36 living-room volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 23:59:36 living-room volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 23:59:36 living-room volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 23:59:37 living-room volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 23:59:37 living-room volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 23:59:37 living-room systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Aug 30 23:59:37 living-room sudo[1990]: pam_unix(sudo:session): session closed for user root Aug 30 23:59:37 living-room volumio[1244]: info: Starting Shairport Sync Aug 30 23:59:37 living-room volumiobt[2001]: INFO [BTSTART] Ensuring Bluetooth directory exists... Aug 30 23:59:37 living-room sudo[2003]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth Aug 30 23:59:37 living-room sudo[2003]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 23:59:37 living-room sudo[2003]: pam_unix(sudo:session): session closed for user root Aug 30 23:59:37 living-room sudo[2006]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth Aug 30 23:59:37 living-room sudo[2006]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 23:59:37 living-room sudo[2005]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 30 23:59:37 living-room sudo[2005]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 23:59:37 living-room sudo[2006]: pam_unix(sudo:session): session closed for user root Aug 30 23:59:37 living-room volumiobt[2009]: INFO [BTSTART] Powering on Bluetooth if needed... Aug 30 23:59:37 living-room systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 30 23:59:37 living-room systemd[1]: shairport-sync.service: Deactivated successfully. Aug 30 23:59:37 living-room systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 30 23:59:37 living-room bluetoothd[1005]: Adv Monitor app :1.25 disconnected from D-Bus Aug 30 23:59:37 living-room volumiobt[2013]: INFO [BTSTART] Making Bluetooth discoverable and pairable... Aug 30 23:59:37 living-room systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 30 23:59:37 living-room sudo[2005]: pam_unix(sudo:session): session closed for user root Aug 30 23:59:37 living-room volumio[1244]: info: Executing endpoint qc_getconfig Aug 30 23:59:37 living-room volumio[1244]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Aug 30 23:59:37 living-room volumiobt[2014]: [176B blob data] Aug 30 23:59:37 living-room volumiobt[2014]: [157B blob data] Aug 30 23:59:37 living-room volumiobt[2014]: [157B blob data] Aug 30 23:59:37 living-room volumiobt[2014]: [157B blob data] Aug 30 23:59:37 living-room volumiobt[2014]: [113B blob data] Aug 30 23:59:37 living-room volumiobt[2014]: [bluetoothctl]> discoverable on Aug 30 23:59:37 living-room volumiobt[2014]: Warning: setting discoverable while discoverable-timeout not set(0) is not recommended Aug 30 23:59:37 living-room volumiobt[2014]: [bluetoothctl]> pairable on Aug 30 23:59:37 living-room bluetoothd[1005]: Adv Monitor app :1.26 disconnected from D-Bus Aug 30 23:59:37 living-room volumiobt[2014]: [bluetoothctl]> Aug 30 23:59:37 living-room volumiobt[2030]: INFO [BTSTART] Registering Bluetooth agent... Aug 30 23:59:37 living-room qobuz-connect[1991]: 20260830 23:59:37.372 [1991.1991] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Aug 30 23:59:37 living-room volumio[1244]: ------------------------------------ BT MESSAGE: volumiobt.service started successfully Aug 30 23:59:37 living-room volumio[1244]: ------------------------------------ BT MESSAGE: [FUNC] dbusStart Aug 30 23:59:37 living-room volumiobt[2031]: [NEW] Media /org/bluez/hci0 Aug 30 23:59:37 living-room volumiobt[2031]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Aug 30 23:59:37 living-room volumiobt[2031]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Aug 30 23:59:37 living-room volumiobt[2031]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb Aug 30 23:59:37 living-room volumio[1244]: info: Access Token successfully retrieved Aug 30 23:59:37 living-room volumio[1244]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 30 23:59:37 living-room volumio[1244]: info: [1788127177440] CoreMusicLibrary::Adding element QOBUZ Aug 30 23:59:37 living-room volumio[1244]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 23:59:37 living-room volumio[1244]: Cannot find translation for source QOBUZ Aug 30 23:59:37 living-room volumio[1244]: info: Stopping AccessToken refresher cron for QOBUZ Aug 30 23:59:37 living-room bluetoothd[1005]: Adv Monitor app :1.28 disconnected from D-Bus Aug 30 23:59:37 living-room volumiobt[2033]: No agent is registered Aug 30 23:59:37 living-room volumiobt[2033]: [NEW] Media /org/bluez/hci0 Aug 30 23:59:37 living-room volumiobt[2033]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Aug 30 23:59:37 living-room volumiobt[2033]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Aug 30 23:59:37 living-room volumiobt[2033]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb Aug 30 23:59:37 living-room qobuz-connect[1991]: 20260830 23:59:37.537 [1991.1991] INFO VolumeManager: [0x16c0f68]: Setting new playback volume: 75 Aug 30 23:59:37 living-room qobuz-connect[1991]: 20260830 23:59:37.537 [1991.1991] INFO VolumeManager: [0x16c0f68]: Setting new mute state: 0 Aug 30 23:59:37 living-room qobuz-connect[1991]: 20260830 23:59:37.537 [1991.1991] INFO AudioStreamManager: [0x16c0cc0]: Setting new audio download buffer size: 1048576 Aug 30 23:59:37 living-room qobuz-connect[1991]: 20260830 23:59:37.537 [1991.1991] INFO QobuzConnect: [0x16c1830]: Client initialized! Aug 30 23:59:37 living-room qobuz-connect[1991]: 20260830 23:59:37.537 [1991.1991] INFO SampleApp: Starting Avahi advertising, name: Living Room, service name: _qobuz-connect._tcp Aug 30 23:59:37 living-room volumio[1244]: info: AccessToken refresher cron started for QOBUZ Aug 30 23:59:37 living-room volumio[1244]: info: Adding QOBUZ REST API Endpoints Aug 30 23:59:37 living-room volumio[1244]: [Metrics] CommandRouter: 23s 164.01ms Aug 30 23:59:37 living-room volumio[1244]: info: CoreCommandRouter::volumiosetStartupVolume Aug 30 23:59:37 living-room volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 23:59:37 living-room volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 23:59:37 living-room bluetoothd[1005]: Adv Monitor app :1.30 disconnected from D-Bus Aug 30 23:59:37 living-room volumiobt[2034]: INFO [BTSTART] Agent registered successfully. Aug 30 23:59:37 living-room volumiobt[2037]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... Aug 30 23:59:37 living-room qobuz-connect[1991]: 20260830 23:59:37.571 [1991.1991] INFO LocalConfigManager: [0x16c09e8]: Starting Local Configuration server Aug 30 23:59:37 living-room qobuz-connect[1991]: 20260830 23:59:37.571 [1991.1991] INFO SampleApp: Starting Local configuration server Aug 30 23:59:37 living-room qobuz-connect[1991]: 20260830 23:59:37.571 [1991.1991] INFO SampleApp: Connected to UNIX socket client 0x16ab818 Aug 30 23:59:37 living-room qobuz-connect[1991]: 20260830 23:59:37.571 [1991.1991] INFO SampleApp: Playback volume changed: 75 Aug 30 23:59:37 living-room volumio[1244]: info: CoreCommandRouter::Close All Modals sent Aug 30 23:59:37 living-room volumio[1244]: info: CoreCommandRouter::Close All Modals sent Aug 30 23:59:37 living-room volumio[1244]: info: Successfully Updated MyVolumio device Aug 30 23:59:37 living-room volumio[1244]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Aug 30 23:59:37 living-room volumio[1244]: info: QobuzConnect: QOBUZ Connect daemon connected Aug 30 23:59:37 living-room volumio[1244]: info: Shairport-Sync Started Aug 30 23:59:37 living-room volumio[1244]: info: CoreCommandRouter::volumioGetState Aug 30 23:59:37 living-room volumio[1244]: info: CorePlayQueue::getTrack 0 Aug 30 23:59:38 living-room volumiobt[2040]: 2026-08-30 23:59:38 a2dp-agent [INFO] Connecting to system D-Bus Aug 30 23:59:38 living-room volumiobt[2040]: 2026-08-30 23:59:38 a2dp-agent [INFO] Connected to system D-Bus Aug 30 23:59:38 living-room volumiobt[2040]: 2026-08-30 23:59:38 bluezutils [INFO] Found adapter at: /org/bluez/hci0 Aug 30 23:59:38 living-room volumiobt[2040]: 2026-08-30 23:59:38 a2dp-agent [INFO] Found Bluetooth adapter: /org/bluez/hci0 Aug 30 23:59:38 living-room volumiobt[2040]: 2026-08-30 23:59:38 a2dp-agent [INFO] Set DiscoverableTimeout to infinite Aug 30 23:59:38 living-room volumiobt[2040]: 2026-08-30 23:59:38 a2dp-agent [INFO] Enabled Discoverable mode Aug 30 23:59:38 living-room volumiobt[2040]: 2026-08-30 23:59:38 a2dp-agent [INFO] Agent registered at /local/a2dpagent Aug 30 23:59:38 living-room volumiobt[2040]: 2026-08-30 23:59:38 a2dp-agent [INFO] Agent set as default Aug 30 23:59:38 living-room volumiobt[2040]: 2026-08-30 23:59:38 a2dp-agent [INFO] A2DP agent running, waiting for connections... Aug 30 23:59:38 living-room volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Aug 30 23:59:38 living-room volumio[1244]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 30 23:59:38 living-room volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Aug 30 23:59:38 living-room mpd[1994]: 2026-08-30T23:59:38 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 30 23:59:38 living-room systemd[1]: Started mpd.service - Music Player Daemon. Aug 30 23:59:38 living-room sudo[1964]: pam_unix(sudo:session): session closed for user root Aug 30 23:59:38 living-room volumio[1244]: error: updateQueue error: null Aug 30 23:59:38 living-room systemd[1]: Starting musicservicesshield.service - Shield Volumio Music Services in the User CPU Set... Aug 30 23:59:39 living-room sudo[2062]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Aug 30 23:59:39 living-room sudo[2062]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 23:59:39 living-room systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Aug 30 23:59:39 living-room 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. Aug 30 23:59:39 living-room 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. Aug 30 23:59:39 living-room systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 30 23:59:39 living-room systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 30 23:59:39 living-room systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Aug 30 23:59:39 living-room go-librespot[2064]: go-librespot daemon starting... Aug 30 23:59:39 living-room sudo[2062]: pam_unix(sudo:session): session closed for user root Aug 30 23:59:39 living-room volumio[1244]: info: Remote SSH Started Aug 30 23:59:39 living-room autossh[2068]: port set to 0, monitoring disabled Aug 30 23:59:39 living-room autossh[2068]: starting ssh (count 1) Aug 30 23:59:39 living-room autossh[2068]: ssh child pid is 2074 Aug 30 23:59:39 living-room go-librespot[2066]: time="2026-08-30T23:59:39+02:00" level=info msg="running go-librespot 0.6.2" Aug 30 23:59:39 living-room go-librespot[2066]: time="2026-08-30T23:59:39+02:00" level=debug msg="app state loaded" Aug 30 23:59:39 living-room go-librespot[2066]: time="2026-08-30T23:59:39+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 30 23:59:39 living-room systemd[1]: systemd-fsckd.service: Deactivated successfully. Aug 30 23:59:39 living-room volumio[1244]: info: Initializing connection to go-librespot Websocket Aug 30 23:59:39 living-room volumiossh-tunnel[2074]: Warning: Permanently added '[eu5.myvolumio.org]:2222' (RSA) to the list of known hosts. Aug 30 23:59:39 living-room go-librespot[2066]: time="2026-08-30T23:59:39+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]" Aug 30 23:59:39 living-room go-librespot[2066]: time="2026-08-30T23:59:39+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]" Aug 30 23:59:39 living-room go-librespot[2066]: time="2026-08-30T23:59:39+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]" Aug 30 23:59:39 living-room go-librespot[2066]: time="2026-08-30T23:59:39+02:00" level=info msg="zeroconf server listening on port 44881" Aug 30 23:59:39 living-room volumio[1244]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Aug 30 23:59:39 living-room go-librespot[2066]: time="2026-08-30T23:59:39+02:00" level=debug msg="new websocket client" Aug 30 23:59:39 living-room bash[2077]: cset: --> shielding system active with Aug 30 23:59:39 living-room bash[2077]: cset: "system" cpuset of CPUSPEC(0) with 175 tasks running Aug 30 23:59:39 living-room bash[2077]: cset: "user" cpuset of CPUSPEC(1-3) with 3 tasks running Aug 30 23:59:39 living-room systemd[1]: Finished musicservicesshield.service - Shield Volumio Music Services in the User CPU Set. Aug 30 23:59:39 living-room volumio[1244]: info: Connection to go-librespot Websocket established Aug 30 23:59:39 living-room go-librespot[2066]: time="2026-08-30T23:59:39+02:00" level=debug msg="obtained new client token: AAE9dv00OKztj6CgnRy2RHQFgjGug71AWqt82VPmqNL8eq35PxkRF4D+1B3yZ1tlbFvPZ8tYQLpW2oSpiM8M79ir7QVqEEGyHMaSkWlV/uQAnOdQKr+lqGrRaXzNFvCP32W1TeACRligvIXKfuouXL/XVeLeUdnzME/Gt7jThOdYMFrosjwhOFhHEwHfTJYOlIMOg3+TmYcb/pIBoTnokjQSn/SExPla/OfEvXQzMuQ4lMTME1F5iJQ=" Aug 30 23:59:39 living-room go-librespot[2066]: time="2026-08-30T23:59:39+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 30 23:59:39 living-room go-librespot[2066]: time="2026-08-30T23:59:39+02:00" level=debug msg="completed keyexchange" Aug 30 23:59:39 living-room go-librespot[2066]: time="2026-08-30T23:59:39+02:00" level=debug msg="completed challenge" Aug 30 23:59:39 living-room volumio[1244]: info: CoreCommandRouter::volumioGetState Aug 30 23:59:39 living-room go-librespot[2066]: time="2026-08-30T23:59:39+02:00" level=info msg="authenticated AP" username="ik*******ev" Aug 30 23:59:39 living-room volumio[1244]: info: CorePlayQueue::getTrack 0 Aug 30 23:59:40 living-room go-librespot[2066]: time="2026-08-30T23:59:40+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 30 23:59:40 living-room volumio[1244]: info: Connection to go-librespot Websocket closed Aug 30 23:59:40 living-room systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 23:59:40 living-room systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 23:59:41 living-room upmpdcli[2080]: writing RSA key Aug 30 23:59:41 living-room systemd[1]: systemd-hostnamed.service: Deactivated successfully. Aug 30 23:59:41 living-room volumio[1244]: info: MRS: Getting audio outputs on start Aug 30 23:59:41 living-room volumio[1244]: info: MRS: Requesting all other devices output Aug 30 23:59:42 living-room volumio[1244]: info: Getting Spotify volume Aug 30 23:59:42 living-room volumio[1244]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 23:59:42 living-room volumio[1244]: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 23:59:42 living-room volumio[1244]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Aug 30 23:59:42 living-room volumio[1244]: errno: -111, Aug 30 23:59:42 living-room volumio[1244]: code: 'ECONNREFUSED', Aug 30 23:59:42 living-room volumio[1244]: syscall: 'connect', Aug 30 23:59:42 living-room volumio[1244]: address: '127.0.0.1', Aug 30 23:59:42 living-room volumio[1244]: port: 9879, Aug 30 23:59:42 living-room volumio[1244]: response: undefined Aug 30 23:59:42 living-room volumio[1244]: } Aug 30 23:59:42 living-room volumio[1244]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 23:59:42 living-room sudo[2102]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-30 23:58' Aug 30 23:59:42 living-room sudo[2102]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="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"