Feb 22 13:45:00 kitchen volumio-remote-updater[724]: [2026-02-22 13:45:00] [connect] Successful connection Feb 22 13:45:09 kitchen volumio[1251]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 22 13:45:09 kitchen volumio[1251]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 22 13:45:09 kitchen volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 13:45:09 kitchen volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 13:45:09 kitchen volumio[1251]: info: Starting MyVolumio Remote Streaming Endpoints Feb 22 13:45:09 kitchen volumio[1251]: info: MyVolumio login type: Token Feb 22 13:45:10 kitchen volumio[1251]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 22 13:45:10 kitchen volumio[1251]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 22 13:45:15 kitchen volumio-remote-updater[724]: [2026-02-22 13:45:15] [connect] Successful connection Feb 22 13:45:20 kitchen volumio[1251]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 22 13:45:20 kitchen volumio[1251]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 22 13:45:20 kitchen volumio[1251]: info: Streaming services startup Feb 22 13:45:20 kitchen volumio[1251]: info: Starting Streaming Daemon Feb 22 13:45:20 kitchen volumio[1251]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 22 13:45:20 kitchen sudo[1779]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 22 13:45:20 kitchen sudo[1779]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 13:45:21 kitchen sudo[1779]: pam_unix(sudo:session): session closed for user root Feb 22 13:45:21 kitchen volumio[1251]: verbose: New Socket.io Connection to 192.168.178.35:3000 from 192.168.178.41 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Feb 22 13:45:21 kitchen volumio[1251]: info: Initializing connection to go-librespot Websocket Feb 22 13:45:21 kitchen volumio[1251]: 0+0 records in Feb 22 13:45:21 kitchen volumio[1251]: 0+0 records out Feb 22 13:45:21 kitchen volumio[1251]: 0 bytes copied, 0.000282761 s, 0.0 kB/s Feb 22 13:45:21 kitchen volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 22 13:45:21 kitchen volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 13:45:21 kitchen volumio[1251]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 22 13:45:22 kitchen volumio[1251]: error: Cannot start Volumio Streaming Daemon Feb 22 13:45:22 kitchen volumio[1251]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 22 13:45:22 kitchen volumio[1251]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 22 13:45:22 kitchen volumio[1251]: info: Shairport-Sync Started Feb 22 13:45:22 kitchen volumio[1251]: info: Discovery: Disconnected from remote: 192.168.178.91 Feb 22 13:45:22 kitchen go-librespot[1619]: time="2026-02-22T13:45:22+01:00" level=debug msg="new websocket client" Feb 22 13:45:22 kitchen volumio[1251]: info: Connection to go-librespot Websocket established Feb 22 13:45:22 kitchen volumio[1251]: verbose: New Socket.io Connection to 192.168.178.35:3000 from 192.168.178.41 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Feb 22 13:45:23 kitchen volumio[1251]: 0+0 records in Feb 22 13:45:23 kitchen volumio[1251]: 0+0 records out Feb 22 13:45:23 kitchen volumio[1251]: 0 bytes copied, 0.000256615 s, 0.0 kB/s Feb 22 13:45:23 kitchen volumio[1251]: error: updateQueue error: null Feb 22 13:45:23 kitchen volumio[1251]: verbose: New Socket.io Connection to 192.168.178.35:3000 from 192.168.178.41 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Feb 22 13:45:24 kitchen volumio[1251]: info: CoreCommandRouter::volumioGetState Feb 22 13:45:24 kitchen volumio[1251]: info: CorePlayQueue::getTrack 0 Feb 22 13:45:25 kitchen volumio5-onboarding[1636]: failed to bootstrap state: failed to check for software update: could not check for updates: context deadline exceeded Feb 22 13:45:25 kitchen systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Feb 22 13:45:25 kitchen systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Feb 22 13:45:25 kitchen volumio[1251]: info: Discovery: Connected to remote: 192.168.178.91 Feb 22 13:45:25 kitchen volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 22 13:45:25 kitchen systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 3. Feb 22 13:45:25 kitchen systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Feb 22 13:45:25 kitchen systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Feb 22 13:45:25 kitchen volumio[1251]: 0+0 records in Feb 22 13:45:25 kitchen volumio[1251]: 0+0 records out Feb 22 13:45:25 kitchen volumio[1251]: 0 bytes copied, 0.000296824 s, 0.0 kB/s Feb 22 13:45:25 kitchen volumio5-onboarding[1814]: time=2026-02-22T13:45:25.823+01:00 level=INFO msg="running volumio5-device-gateway" version=30d1950e+CHANGES buildDate=2026-01-29T13:10:08Z Feb 22 13:45:26 kitchen volumio[1251]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Feb 22 13:45:26 kitchen volumio[1251]: info: Getting Spotify volume Feb 22 13:45:26 kitchen volumio[1251]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Feb 22 13:45:27 kitchen volumio[1251]: verbose: New Socket.io Connection to 192.168.178.35:3000 from 192.168.178.41 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 15 Feb 22 13:45:27 kitchen volumio5-onboarding[1814]: time=2026-02-22T13:45:27.501+01:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci0 error="bluetooth: adapter /org/bluez/hci0 does not exist" Feb 22 13:45:27 kitchen volumio5-onboarding[1814]: time=2026-02-22T13:45:27.507+01:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci1 error="bluetooth: adapter /org/bluez/hci1 does not exist" Feb 22 13:45:27 kitchen volumio5-onboarding[1814]: time=2026-02-22T13:45:27.514+01:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci2 error="bluetooth: adapter /org/bluez/hci2 does not exist" Feb 22 13:45:27 kitchen volumio5-onboarding[1814]: time=2026-02-22T13:45:27.519+01:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci3 error="bluetooth: adapter /org/bluez/hci3 does not exist" Feb 22 13:45:27 kitchen volumio5-onboarding[1814]: time=2026-02-22T13:45:27.523+01:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci4 error="bluetooth: adapter /org/bluez/hci4 does not exist" Feb 22 13:45:27 kitchen volumio5-onboarding[1814]: time=2026-02-22T13:45:27.523+01:00 level=WARN msg="no Bluetooth adapter found, BLE discovery disabled" Feb 22 13:45:27 kitchen volumio[1251]: 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: 16 Feb 22 13:45:27 kitchen volumio[1251]: 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: 16 Feb 22 13:45:27 kitchen volumio[1251]: info: Received Get System Info Feb 22 13:45:27 kitchen volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 13:45:27 kitchen volumio[1251]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 13:45:27 kitchen volumio[1251]: info: Discovery: Getting this device information Feb 22 13:45:27 kitchen volumio[1251]: info: CoreCommandRouter::volumioGetState Feb 22 13:45:27 kitchen volumio[1251]: info: CorePlayQueue::getTrack 0 Feb 22 13:45:27 kitchen volumio[1251]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 13:45:27 kitchen volumio5-onboarding[1814]: time=2026-02-22T13:45:27.900+01:00 level=INFO msg="system info for 5ce74563ddbec851932948f8619b1c52" deviceName=Kitchen deviceVariant=volumio deviceModel= softwareVersion=4.096 Feb 22 13:45:27 kitchen volumio[1251]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 17 Feb 22 13:45:28 kitchen volumio[1251]: info: Received Get System Info Feb 22 13:45:28 kitchen volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 13:45:28 kitchen volumio[1251]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 13:45:28 kitchen volumio[1251]: info: Discovery: Getting this device information Feb 22 13:45:28 kitchen volumio[1251]: info: CoreCommandRouter::volumioGetState Feb 22 13:45:28 kitchen volumio[1251]: info: CorePlayQueue::getTrack 0 Feb 22 13:45:28 kitchen volumio[1251]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 13:45:28 kitchen volumio[1251]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 18 Feb 22 13:45:28 kitchen volumio[1251]: 0+0 records in Feb 22 13:45:28 kitchen volumio[1251]: 0+0 records out Feb 22 13:45:28 kitchen volumio[1251]: 0 bytes copied, 0.000271458 s, 0.0 kB/s Feb 22 13:45:28 kitchen volumio[1251]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 22 13:45:28 kitchen volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 22 13:45:28 kitchen volumio5-onboarding[1814]: time=2026-02-22T13:45:28.503+01:00 level=INFO msg="enabling local network discovery" Feb 22 13:45:28 kitchen volumio5-onboarding[1814]: time=2026-02-22T13:45:28.563+01:00 level=INFO msg="bootstrapping state" hasInternet=true Feb 22 13:45:29 kitchen volumio[1251]: info: Received Get System Info Feb 22 13:45:29 kitchen volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 13:45:29 kitchen volumio[1251]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 13:45:29 kitchen volumio[1251]: info: Discovery: Getting this device information Feb 22 13:45:29 kitchen volumio[1251]: info: CoreCommandRouter::volumioGetState Feb 22 13:45:29 kitchen volumio[1251]: info: CorePlayQueue::getTrack 0 Feb 22 13:45:29 kitchen volumio[1251]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 13:45:29 kitchen volumio[1251]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Feb 22 13:45:29 kitchen volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 13:45:29 kitchen volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 13:45:29 kitchen volumio[1251]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Feb 22 13:45:29 kitchen volumio[1251]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Feb 22 13:45:29 kitchen volumio[1251]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 22 13:45:29 kitchen volumio[1251]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 22 13:45:29 kitchen volumio[1251]: info: CoreCommandRouter::volumioGetBrowseSources Feb 22 13:45:29 kitchen volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 22 13:45:30 kitchen volumio-remote-updater[724]: [2026-02-22 13:45:30] [connect] Successful connection Feb 22 13:45:30 kitchen volumio[1251]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Feb 22 13:45:30 kitchen volumio[1251]: info: CoreCommandRouter::volumioGetState Feb 22 13:45:30 kitchen volumio[1251]: info: CorePlayQueue::getTrack 0 Feb 22 13:45:31 kitchen volumio[1251]: 0+0 records in Feb 22 13:45:31 kitchen volumio[1251]: 0+0 records out Feb 22 13:45:31 kitchen volumio[1251]: 0 bytes copied, 0.000299115 s, 0.0 kB/s Feb 22 13:45:33 kitchen volumio-remote-updater[724]: [2026-02-22 13:45:33] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1771764330 101 Feb 22 13:45:33 kitchen volumio[1251]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 19 Feb 22 13:45:33 kitchen volumio[1251]: info: MyVolumio token set successfully Feb 22 13:45:33 kitchen volumio[1251]: info: MYVOLUMIO: Adding device Feb 22 13:45:33 kitchen volumio[1251]: info: MYVOLUMIO: Evaluating Server Feb 22 13:45:34 kitchen volumio[1251]: info: CoreCommandRouter::volumioGetState Feb 22 13:45:34 kitchen volumio[1251]: info: CorePlayQueue::getTrack 0 Feb 22 13:45:34 kitchen volumio[1251]: 0+0 records in Feb 22 13:45:34 kitchen volumio[1251]: 0+0 records out Feb 22 13:45:34 kitchen volumio[1251]: 0 bytes copied, 0.0167068 s, 0.0 kB/s Feb 22 13:45:35 kitchen volumio[1251]: info: MyVolumio Plan changed: premium Feb 22 13:45:35 kitchen volumio[1251]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Feb 22 13:45:35 kitchen volumio[1251]: info: Removing browser output: myVolumio user plan is not superstar Feb 22 13:45:35 kitchen volumio[1251]: info: Removing audio output: Feb 22 13:45:35 kitchen volumio[1251]: info: MYVOLUMIO: Adding device Feb 22 13:45:35 kitchen volumio[1251]: info: MYVOLUMIO: Evaluating Server Feb 22 13:45:36 kitchen volumio[1251]: info: CoreCommandRouter::volumioGetState Feb 22 13:45:36 kitchen volumio[1251]: info: CorePlayQueue::getTrack 0 Feb 22 13:45:37 kitchen volumio[1251]: 0+0 records in Feb 22 13:45:37 kitchen volumio[1251]: 0+0 records out Feb 22 13:45:37 kitchen volumio[1251]: 0 bytes copied, 0.000265156 s, 0.0 kB/s Feb 22 13:45:37 kitchen volumio[1251]: info: Remote config written successfully Feb 22 13:45:37 kitchen volumio[1251]: info: Starting Tunnel 1 Feb 22 13:45:37 kitchen volumio[1251]: info: Starting Tunnel Connection Checker Feb 22 13:45:37 kitchen volumio[1251]: info: Completed starting MyVolumio Plugin Feb 22 13:45:37 kitchen volumio[1251]: info: Setting Geolocation for MyVolumio to eu6 Feb 22 13:45:37 kitchen volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 13:45:37 kitchen volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 13:45:37 kitchen volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 13:45:38 kitchen volumio[1251]: info: MYVolumio Device enabled Feb 22 13:45:38 kitchen volumio[1251]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Feb 22 13:45:38 kitchen volumio[1251]: info: MyVolumio status changed Feb 22 13:45:38 kitchen volumio[1251]: info: Streaming services startup Feb 22 13:45:38 kitchen volumio[1251]: info: Starting Streaming Daemon Feb 22 13:45:38 kitchen volumio[1251]: info: Setting Geolocation for MyVolumio to eu10 Feb 22 13:45:38 kitchen volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 13:45:38 kitchen volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 13:45:38 kitchen volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 13:45:38 kitchen sudo[1909]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 22 13:45:38 kitchen sudo[1909]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 13:45:38 kitchen sudo[1909]: pam_unix(sudo:session): session closed for user root Feb 22 13:45:38 kitchen volumio[1251]: info: Successfully Added MyVolumio device Feb 22 13:45:38 kitchen volumio[1251]: info: Updating MyVolumio device info Feb 22 13:45:38 kitchen volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 13:45:38 kitchen volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 13:45:38 kitchen volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 13:45:39 kitchen volumio[1251]: info: Successfully Added MyVolumio device Feb 22 13:45:39 kitchen volumio[1251]: error: Cannot start Volumio Streaming Daemon Feb 22 13:45:39 kitchen volumio[1251]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 22 13:45:39 kitchen volumio[1251]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 22 13:45:39 kitchen volumio[1251]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Feb 22 13:45:39 kitchen volumio[1251]: error: [MyVolumio PluginManager] Cache data is invalid! Feb 22 13:45:39 kitchen volumio[1251]: info: Successfully Updated MyVolumio device Feb 22 13:45:39 kitchen volumio[1251]: info: Updating MyVolumio device info Feb 22 13:45:39 kitchen volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 13:45:39 kitchen volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 13:45:39 kitchen volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 13:45:39 kitchen volumio[1251]: 0+0 records in Feb 22 13:45:39 kitchen volumio[1251]: 0+0 records out Feb 22 13:45:39 kitchen volumio[1251]: 0 bytes copied, 0.000247344 s, 0.0 kB/s Feb 22 13:45:39 kitchen volumio[1251]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Feb 22 13:45:42 kitchen volumio[1251]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart Feb 22 13:45:42 kitchen volumio[1251]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Feb 22 13:45:55 kitchen volumio[1251]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Feb 22 13:45:56 kitchen sudo[1948]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Feb 22 13:45:56 kitchen sudo[1948]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 13:45:56 kitchen sudo[1948]: pam_unix(sudo:session): session closed for user root Feb 22 13:45:56 kitchen volumio[1251]: info: MRS: MultiRoom plugin initialized Feb 22 13:45:56 kitchen volumio[1251]: info: MRS: STOPPING SNAPCLIENT Feb 22 13:45:57 kitchen volumio[1251]: info: MRS: Snap server stop Feb 22 13:45:57 kitchen volumio[1251]: info: MRS: STOPPING volumioStreaming Feb 22 13:45:57 kitchen volumio[1251]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Feb 22 13:45:57 kitchen sudo[1965]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Feb 22 13:45:57 kitchen sudo[1965]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 13:45:57 kitchen sudo[1967]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Feb 22 13:45:57 kitchen sudo[1967]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 13:45:57 kitchen sudo[1969]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Feb 22 13:45:57 kitchen sudo[1969]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 13:45:57 kitchen sudo[1971]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Feb 22 13:45:57 kitchen sudo[1971]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 13:45:57 kitchen sudo[1971]: pam_unix(sudo:session): session closed for user root Feb 22 13:45:57 kitchen sudo[1965]: pam_unix(sudo:session): session closed for user root Feb 22 13:45:57 kitchen volumio[1251]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Feb 22 13:45:57 kitchen sudo[1969]: pam_unix(sudo:session): session closed for user root Feb 22 13:45:57 kitchen sudo[1967]: pam_unix(sudo:session): session closed for user root Feb 22 13:45:57 kitchen volumio[1251]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Feb 22 13:45:58 kitchen volumio[1251]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Feb 22 13:45:58 kitchen volumio[1251]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Feb 22 13:45:58 kitchen volumio5-onboarding[1814]: failed to bootstrap state: failed to check for software update: could not check for updates: context deadline exceeded Feb 22 13:45:58 kitchen systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Feb 22 13:45:58 kitchen systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Feb 22 13:45:58 kitchen volumio[1251]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Feb 22 13:45:58 kitchen volumio[1251]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Feb 22 13:45:58 kitchen volumio[1251]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Feb 22 13:45:58 kitchen systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 4. Feb 22 13:45:58 kitchen systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Feb 22 13:45:59 kitchen systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Feb 22 13:45:59 kitchen volumio[1251]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Feb 22 13:45:59 kitchen volumio5-onboarding[1976]: time=2026-02-22T13:45:59.195+01:00 level=INFO msg="running volumio5-device-gateway" version=30d1950e+CHANGES buildDate=2026-01-29T13:10:08Z Feb 22 13:45:59 kitchen volumio[1251]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Feb 22 13:45:59 kitchen volumio[1251]: info: Preparing to generate the ALSA configuration file Feb 22 13:46:00 kitchen volumio[1251]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Feb 22 13:46:00 kitchen volumio[1251]: info: Successfully Updated MyVolumio device Feb 22 13:46:00 kitchen volumio[1251]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Feb 22 13:46:00 kitchen volumio[1251]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Feb 22 13:46:00 kitchen volumio[1251]: info: Reading ALSA contributions from plugins. Feb 22 13:46:00 kitchen volumio[1251]: info: MRS: Removed streaming files Feb 22 13:46:00 kitchen volumio[1251]: info: MRS: volumioStreaming STOPPED Feb 22 13:46:00 kitchen volumio[1251]: info: MRS: SNAPSERVER STOPPED Feb 22 13:46:00 kitchen volumio[1251]: info: MRS: SNAPCLIENT STOPPED Feb 22 13:46:00 kitchen volumio[1251]: info: Discovery: Disconnected from remote: 192.168.178.91 Feb 22 13:46:00 kitchen volumio[1251]: info: MRS: Pushing multiroomSync output for this device Feb 22 13:46:00 kitchen volumio[1251]: info: MRS: Pushing multiroomSync output Feb 22 13:46:00 kitchen volumio[1251]: info: Adding audio output: Feb 22 13:46:00 kitchen volumio[1251]: info: Adding audio output: Feb 22 13:46:00 kitchen volumio[1251]: info: Discovery: Disconnected from remote: 192.168.178.91 Feb 22 13:46:01 kitchen volumio5-onboarding[1976]: time=2026-02-22T13:46:01.252+01:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci0 error="bluetooth: adapter /org/bluez/hci0 does not exist" Feb 22 13:46:01 kitchen volumio5-onboarding[1976]: time=2026-02-22T13:46:01.262+01:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci1 error="bluetooth: adapter /org/bluez/hci1 does not exist" Feb 22 13:46:01 kitchen volumio5-onboarding[1976]: time=2026-02-22T13:46:01.268+01:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci2 error="bluetooth: adapter /org/bluez/hci2 does not exist" Feb 22 13:46:01 kitchen volumio5-onboarding[1976]: time=2026-02-22T13:46:01.272+01:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci3 error="bluetooth: adapter /org/bluez/hci3 does not exist" Feb 22 13:46:01 kitchen volumio5-onboarding[1976]: time=2026-02-22T13:46:01.277+01:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci4 error="bluetooth: adapter /org/bluez/hci4 does not exist" Feb 22 13:46:01 kitchen volumio5-onboarding[1976]: time=2026-02-22T13:46:01.278+01:00 level=WARN msg="no Bluetooth adapter found, BLE discovery disabled" Feb 22 13:46:01 kitchen volumio[1251]: 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: 15 Feb 22 13:46:01 kitchen volumio[1251]: 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: 15 Feb 22 13:46:01 kitchen volumio[1251]: info: Received Get System Info Feb 22 13:46:01 kitchen volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 13:46:01 kitchen volumio[1251]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 13:46:01 kitchen volumio[1251]: info: Discovery: Getting this device information Feb 22 13:46:01 kitchen volumio[1251]: info: CoreCommandRouter::volumioGetState Feb 22 13:46:01 kitchen volumio[1251]: info: CorePlayQueue::getTrack 0 Feb 22 13:46:01 kitchen volumio[1251]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 13:46:01 kitchen volumio5-onboarding[1976]: time=2026-02-22T13:46:01.538+01:00 level=INFO msg="system info for 5ce74563ddbec851932948f8619b1c52" deviceName=Kitchen deviceVariant=volumio deviceModel= softwareVersion=4.096 Feb 22 13:46:01 kitchen volumio[1251]: 0+0 records in Feb 22 13:46:01 kitchen volumio[1251]: 0+0 records out Feb 22 13:46:01 kitchen volumio[1251]: 0 bytes copied, 0.000266146 s, 0.0 kB/s Feb 22 13:46:02 kitchen volumio[1251]: info: Received Get System Info Feb 22 13:46:02 kitchen volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 13:46:02 kitchen volumio[1251]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 13:46:02 kitchen volumio[1251]: info: Discovery: Getting this device information Feb 22 13:46:02 kitchen volumio[1251]: info: CoreCommandRouter::volumioGetState Feb 22 13:46:02 kitchen volumio[1251]: info: CorePlayQueue::getTrack 0 Feb 22 13:46:02 kitchen volumio[1251]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 13:46:02 kitchen volumio[1251]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 22 13:46:02 kitchen volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 22 13:46:02 kitchen volumio5-onboarding[1976]: time=2026-02-22T13:46:02.505+01:00 level=INFO msg="enabling local network discovery" Feb 22 13:46:02 kitchen volumio5-onboarding[1976]: time=2026-02-22T13:46:02.567+01:00 level=INFO msg="bootstrapping state" hasInternet=true Feb 22 13:46:02 kitchen volumio[1251]: info: Received Get System Info Feb 22 13:46:02 kitchen volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 13:46:02 kitchen volumio[1251]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 13:46:02 kitchen volumio[1251]: info: Discovery: Getting this device information Feb 22 13:46:02 kitchen volumio[1251]: info: CoreCommandRouter::volumioGetState Feb 22 13:46:02 kitchen volumio[1251]: info: CorePlayQueue::getTrack 0 Feb 22 13:46:02 kitchen volumio[1251]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 13:46:02 kitchen volumio[1251]: info: MRS: Pushing audio output on request Feb 22 13:46:02 kitchen volumio[1251]: info: MRS: Pushing multiroomSync output Feb 22 13:46:02 kitchen volumio[1251]: info: Discovery: Connected to remote: 192.168.178.91 Feb 22 13:46:02 kitchen sudo[2004]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Feb 22 13:46:02 kitchen sudo[2004]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 13:46:02 kitchen volumio-remote-updater[724]: Test mode disabled Feb 22 13:46:02 kitchen volumio-remote-updater[724]: Alpha mode disabled Feb 22 13:46:02 kitchen volumio-remote-updater[724]: Alpha legacy test mode disabled Feb 22 13:46:03 kitchen systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 22 13:46:03 kitchen systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 22 13:46:03 kitchen volumio[1251]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Feb 22 13:46:03 kitchen volumio[1251]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 22 13:46:03 kitchen systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Feb 22 13:46:03 kitchen sudo[2004]: pam_unix(sudo:session): session closed for user root Feb 22 13:46:03 kitchen autossh[2012]: port set to 0, monitoring disabled Feb 22 13:46:03 kitchen autossh[2012]: starting ssh (count 1) Feb 22 13:46:03 kitchen autossh[2012]: ssh child pid is 2015 Feb 22 13:46:03 kitchen volumio[1251]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 22 13:46:03 kitchen volumio[1251]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 22 13:46:03 kitchen volumio[1251]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 22 13:46:03 kitchen volumiossh-tunnel[2015]: Warning: Permanently added '[eu10.myvolumio.org]:2222' (RSA) to the list of known hosts. Feb 22 13:46:03 kitchen volumio[1251]: info: Remote SSH Started Feb 22 13:46:06 kitchen volumio[1251]: 0+0 records in Feb 22 13:46:06 kitchen volumio[1251]: 0+0 records out Feb 22 13:46:06 kitchen volumio[1251]: 0 bytes copied, 0.000226511 s, 0.0 kB/s Feb 22 13:46:06 kitchen volumio[1251]: info: Asound.conf file written Feb 22 13:46:07 kitchen sudo[2034]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Feb 22 13:46:07 kitchen sudo[2034]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 13:46:07 kitchen sudo[2034]: pam_unix(sudo:session): session closed for user root Feb 22 13:46:07 kitchen volumio[1251]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Feb 22 13:46:07 kitchen volumio[1251]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Feb 22 13:46:07 kitchen volumio[1251]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Feb 22 13:46:07 kitchen volumio[1251]: No state is present for card vc4hdmi Feb 22 13:46:07 kitchen volumio[1251]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Feb 22 13:46:07 kitchen volumio[1251]: Found hardware: "vc4-hdmi" "" "" "" "" Feb 22 13:46:07 kitchen volumio[1251]: Hardware is initialized using a generic method Feb 22 13:46:07 kitchen volumio[1251]: No state is present for card vc4hdmi Feb 22 13:46:07 kitchen volumio[1251]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 Feb 22 13:46:07 kitchen volumio[1251]: info: Output device has changed, restarting MPD Feb 22 13:46:07 kitchen volumio[1251]: info: Output device has changed, restarting Shairport Sync Feb 22 13:46:07 kitchen volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 13:46:07 kitchen volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 13:46:07 kitchen sudo[2040]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 22 13:46:07 kitchen sudo[2040]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 13:46:07 kitchen sudo[2040]: pam_unix(sudo:session): session closed for user root Feb 22 13:46:07 kitchen sudo[2042]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 22 13:46:07 kitchen volumio[1251]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 22 13:46:07 kitchen volumio[1251]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 22 13:46:07 kitchen sudo[2042]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 13:46:07 kitchen volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 13:46:08 kitchen volumio[1251]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Feb 22 13:46:08 kitchen volumio[1251]: ------------------------------------ BT MESSAGE: [FUNC] onStart Feb 22 13:46:08 kitchen volumio[1251]: ------------------------------------ BT MESSAGE: Starting Volumio Bluetooth Service Feb 22 13:46:08 kitchen systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 22 13:46:08 kitchen volumio[1251]: ------------------------------------ BT MESSAGE: Boot config /etc/bluetooth/volumio.conf: cache mode = tmp Feb 22 13:46:08 kitchen volumio[1251]: ------------------------------------ BT MESSAGE: [metaCache] Created directory: /tmp/bluetooth-cache/ Feb 22 13:46:08 kitchen volumio[1251]: ------------------------------------ BT MESSAGE: [metaCache] Directory exists and is ready. Feb 22 13:46:08 kitchen volumio[1251]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Feb 22 13:46:08 kitchen volumio[1251]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Feb 22 13:46:08 kitchen systemd[1]: mpd.service: Deactivated successfully. Feb 22 13:46:08 kitchen systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 22 13:46:08 kitchen systemd[1]: mpd.service: Consumed 5.491s CPU time. Feb 22 13:46:08 kitchen systemd[1]: mpd.socket: Deactivated successfully. Feb 22 13:46:08 kitchen systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 22 13:46:08 kitchen systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 22 13:46:08 kitchen systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 22 13:46:08 kitchen systemd[1]: Starting mpd.service - Music Player Daemon... Feb 22 13:46:08 kitchen volumio[1251]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 22 13:46:08 kitchen volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 13:46:08 kitchen volumio[1251]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Feb 22 13:46:08 kitchen volumio[1251]: info: Adding METAVOLUMIO REST API Endpoints Feb 22 13:46:08 kitchen volumio[1251]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Feb 22 13:46:08 kitchen volumio[1251]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Feb 22 13:46:08 kitchen volumio[1251]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Feb 22 13:46:08 kitchen volumio[1251]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Feb 22 13:46:08 kitchen volumio[1251]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Feb 22 13:46:08 kitchen volumio[1251]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Feb 22 13:46:08 kitchen volumio[1251]: info: Preparing CD Folders Feb 22 13:46:08 kitchen volumio[1251]: info: Adding CD REST API Endpoints Feb 22 13:46:08 kitchen volumio[1251]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Feb 22 13:46:08 kitchen volumio[1251]: info: Starting UDEV Watcher for CD Feb 22 13:46:08 kitchen volumio[1251]: info: Detecting CD presence with UDEV Feb 22 13:46:08 kitchen volumio[1251]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Feb 22 13:46:08 kitchen sudo[2055]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 22 13:46:08 kitchen sudo[2055]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 22 13:46:08 kitchen sudo[2055]: pam_unix(sudo:session): session closed for user root Feb 22 13:46:14 kitchen mpd[2060]: 2026-02-22T13:46:14 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 22 13:46:14 kitchen volumio[1251]: warn: [cd-plugin] cdspeedctl: device or media not ready Feb 22 13:46:14 kitchen volumio[1251]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Feb 22 13:46:14 kitchen systemd[1]: Started mpd.service - Music Player Daemon. Feb 22 13:46:14 kitchen sudo[2042]: pam_unix(sudo:session): session closed for user root Feb 22 13:46:14 kitchen volumio[1251]: info: Adding inputs REST Endpoints Feb 22 13:46:14 kitchen volumio[1251]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Feb 22 13:46:14 kitchen volumio[1251]: info: Scanning Audio Inputs Feb 22 13:46:14 kitchen volumio[1251]: info: Checking against Known Cards name Feb 22 13:46:14 kitchen volumio[1251]: info: Adding Server instance for streaming Feb 22 13:46:14 kitchen volumio[1251]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Feb 22 13:46:14 kitchen volumio[1251]: error: Hi Res Audio Failed Login: Missing Login Data Feb 22 13:46:14 kitchen volumio[1251]: info: Adding HIGHRESAUDIO REST API Endpoints Feb 22 13:46:14 kitchen volumio[1251]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Feb 22 13:46:14 kitchen volumio[1251]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Feb 22 13:46:14 kitchen volumio[1251]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Feb 22 13:46:14 kitchen volumio[1251]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Feb 22 13:46:14 kitchen volumio[1251]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect Feb 22 13:46:14 kitchen volumio[1251]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Feb 22 13:46:15 kitchen volumio[1251]: info: QobuzConnect: Starting Qobuz Connect socket and service Feb 22 13:46:15 kitchen sudo[2084]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 22 13:46:15 kitchen sudo[2084]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 13:46:15 kitchen volumio[1251]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Feb 22 13:46:15 kitchen volumio[1251]: info: Adding TIDAL REST API Endpoints Feb 22 13:46:15 kitchen volumio[1251]: info: Stopping AccessToken refresher cron for QOBUZ Feb 22 13:46:15 kitchen sudo[2091]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 22 13:46:15 kitchen sudo[2091]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 13:46:15 kitchen sudo[2084]: pam_unix(sudo:session): session closed for user root Feb 22 13:46:15 kitchen sudo[2091]: pam_unix(sudo:session): session closed for user root Feb 22 13:46:15 kitchen sudo[2094]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 22 13:46:15 kitchen sudo[2094]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 13:46:15 kitchen volumio[1251]: info: AccessToken refresher cron started for QOBUZ Feb 22 13:46:15 kitchen volumio[1251]: info: Adding QOBUZ REST API Endpoints Feb 22 13:46:16 kitchen volumio[1251]: error: MyVolumio Plugins failed to start in a timely fashion Feb 22 13:46:16 kitchen volumio[1251]: error: Plugin audio_interface bluetooth failed to complete 'onStart' in a timely fashion Feb 22 13:46:16 kitchen volumio[1251]: error: Plugin audio_interface multiroom failed to complete 'onStart' in a timely fashion Feb 22 13:46:16 kitchen volumio[1251]: error: Plugin music_service tidalconnect failed to complete 'onStart' in a timely fashion Feb 22 13:46:16 kitchen volumio[1251]: error: Plugin music_service qobuzconnect failed to complete 'onStart' in a timely fashion Feb 22 13:46:16 kitchen volumio[1251]: [Metrics] CommandRouter: 143s 112.63ms Feb 22 13:46:16 kitchen systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Feb 22 13:46:16 kitchen volumio[1251]: info: CoreCommandRouter::volumiosetStartupVolume Feb 22 13:46:16 kitchen volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 13:46:16 kitchen volumio[1251]: info: VolumeController:: Setting startup Volume 50 Feb 22 13:46:16 kitchen volumio[1251]: info: VolumeController::SetAlsaVolume50 Feb 22 13:46:16 kitchen sudo[2094]: pam_unix(sudo:session): session closed for user root Feb 22 13:46:16 kitchen volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 13:46:16 kitchen volumio[1251]: info: CoreCommandRouter::Close All Modals sent Feb 22 13:46:16 kitchen volumio[1251]: info: CoreCommandRouter::Close All Modals sent Feb 22 13:46:16 kitchen volumio[1251]: info: CoreStateMachine::pushState Feb 22 13:46:16 kitchen volumio[1251]: info: CorePlayQueue::getTrack 0 Feb 22 13:46:16 kitchen volumio[1251]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 13:46:16 kitchen volumio[1251]: info: CoreCommandRouter::volumioPushState Feb 22 13:46:16 kitchen volumio[1251]: info: MRS: Pushing multiroomSync output update for this device Feb 22 13:46:16 kitchen volumio[1251]: info: MRS: Pushing multiroomSync output Feb 22 13:46:16 kitchen volumio[1251]: info: CoreCommandRouter::volumioGetState Feb 22 13:46:16 kitchen volumio[1251]: info: CorePlayQueue::getTrack 0 Feb 22 13:46:16 kitchen volumio[1251]: info: MRS: Getting audio outputs on start Feb 22 13:46:16 kitchen volumio[1251]: info: MRS: Requesting all other devices output Feb 22 13:46:17 kitchen volumio[1251]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Feb 22 13:46:17 kitchen volumio[1251]: SPOTIFY: SPOTIFY VOLUME undefined Feb 22 13:46:17 kitchen volumio[1251]: SPOTIFY: VOLUMIO VOLUME 50 Feb 22 13:46:17 kitchen volumio[1251]: info: Aligning Spotify Volume to Volumio Volume Feb 22 13:46:17 kitchen volumio[1251]: info: CoreCommandRouter::volumioGetState Feb 22 13:46:17 kitchen volumio[1251]: info: CorePlayQueue::getTrack 0 Feb 22 13:46:17 kitchen volumio[1251]: info: Setting Spotify Volume from Volumio: 50 Feb 22 13:46:17 kitchen volumio[1251]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Feb 22 13:46:17 kitchen volumio[1251]: info: FusionDsp - ---- read samplerate from file: 44100 Feb 22 13:46:17 kitchen volumio[1251]: info: camilladsp service terminated, instance 1 Feb 22 13:46:17 kitchen volumio[1251]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 22 13:46:17 kitchen volumio[1251]: info: camilladsp service started and running in background, instance 1 Feb 22 13:46:17 kitchen volumio[1251]: info: MRS: Adding multiroomSync output Feb 22 13:46:17 kitchen volumio[1251]: info: Adding audio output: Feb 22 13:46:18 kitchen volumio[1251]: info: MRS: Pushing multiroomSync output Feb 22 13:46:18 kitchen volumio[1251]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Feb 22 13:46:18 kitchen volumio[1251]: ------------------------------------ BT MESSAGE: Failed to power on adapter: Feb 22 13:46:18 kitchen volumio[1251]: info: MPD Permissions set Feb 22 13:46:18 kitchen sudo[2105]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Feb 22 13:46:18 kitchen sudo[2105]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 13:46:18 kitchen volumio[1251]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Feb 22 13:46:18 kitchen volumio[1251]: 2+0 records in Feb 22 13:46:18 kitchen volumio[1251]: 2+0 records out Feb 22 13:46:18 kitchen volumio[1251]: 65536 bytes (66 kB, 64 KiB) copied, 0.00060573 s, 108 MB/s Feb 22 13:46:18 kitchen volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Feb 22 13:46:18 kitchen volumio[1251]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 22 13:46:18 kitchen volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Feb 22 13:46:18 kitchen volumio[1251]: info: Version has changed, forcing UI Reload Feb 22 13:46:18 kitchen volumio[1251]: info: CoreCommandRouter::Reload Ui Feb 22 13:46:18 kitchen volumio[1251]: error: Cannot download Available plugins list: Error: ETIMEDOUT Feb 22 13:46:18 kitchen volumio[1251]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 22 13:46:18 kitchen systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Feb 22 13:46:18 kitchen sudo[2105]: pam_unix(sudo:session): session closed for user root Feb 22 13:46:18 kitchen volumiobt[2111]: INFO [BTSTART] Ensuring Bluetooth directory exists... Feb 22 13:46:19 kitchen sudo[2112]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth Feb 22 13:46:19 kitchen sudo[2112]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 13:46:19 kitchen sudo[2112]: pam_unix(sudo:session): session closed for user root Feb 22 13:46:19 kitchen sudo[2114]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth Feb 22 13:46:19 kitchen sudo[2114]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 13:46:19 kitchen sudo[2114]: pam_unix(sudo:session): session closed for user root Feb 22 13:46:19 kitchen volumiobt[2116]: INFO [BTSTART] Powering on Bluetooth if needed... Feb 22 13:46:19 kitchen volumio[1251]: Error [ERR_INTERNAL_ASSERTION]: This is caused by either a bug in Node.js or incorrect usage of Node.js internals. Feb 22 13:46:19 kitchen volumio[1251]: Please open an issue with this stack trace at https://github.com/nodejs/node/issues Feb 22 13:46:19 kitchen volumio[1251]: at new NodeError (node:internal/errors:405:5) Feb 22 13:46:19 kitchen volumio[1251]: at assert (node:internal/assert:14:11) Feb 22 13:46:19 kitchen volumio[1251]: at internalConnectMultiple (node:net:1118:3) Feb 22 13:46:19 kitchen volumio[1251]: at Timeout.internalConnectMultipleTimeout (node:net:1687:3) Feb 22 13:46:19 kitchen volumio[1251]: at listOnTimeout (node:internal/timers:575:11) Feb 22 13:46:19 kitchen volumio[1251]: at process.processTimers (node:internal/timers:514:7) { Feb 22 13:46:19 kitchen volumio[1251]: code: 'ERR_INTERNAL_ASSERTION' Feb 22 13:46:19 kitchen volumio[1251]: } Feb 22 13:46:19 kitchen volumio[1251]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 22 13:46:19 kitchen volumiobt[2120]: No default controller available Feb 22 13:46:20 kitchen volumiobt[2132]: INFO [BTSTART] Making Bluetooth discoverable and pairable... Feb 22 13:46:20 kitchen volumiobt[2133]: [83B blob data] Feb 22 13:46:20 kitchen volumiobt[2133]: No default controller available Feb 22 13:46:20 kitchen volumiobt[2133]: [bluetoothctl]> pairable on Feb 22 13:46:20 kitchen volumiobt[2133]: No default controller available Feb 22 13:46:20 kitchen volumiobt[2133]: [113B blob data] Feb 22 13:46:20 kitchen volumiobt[2133]: [bluetoothctl]> Feb 22 13:46:20 kitchen volumiobt[2134]: INFO [BTSTART] Registering Bluetooth agent... Feb 22 13:46:20 kitchen volumiobt[2136]: No agent is registered Feb 22 13:46:20 kitchen volumiobt[2137]: INFO [BTSTART] Agent registered successfully. Feb 22 13:46:20 kitchen volumiobt[2138]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... Feb 22 13:46:22 kitchen volumiobt[2139]: INFO:a2dp-agent:Connecting to system bus. Feb 22 13:46:22 kitchen volumiobt[2139]: ERROR:a2dp-agent:No Bluetooth adapter found: Bluetooth adapter not found Feb 22 13:46:22 kitchen volumiobt[2143]: INFO [BTSTART] Disconnecting all Bluetooth devices... Feb 22 13:46:22 kitchen volumiobt[2148]: INFO [BTSTART] Unregistering Bluetooth agent... Feb 22 13:46:22 kitchen volumiobt[2149]: No agent is registered Feb 22 13:46:22 kitchen volumiobt[2150]: INFO [BTSTART] Stopping bluealsa-aplay (if any)... Feb 22 13:46:22 kitchen volumiobt[2152]: INFO [BTSTART] Stopping A2DP agent (a2dp-agent)... Feb 22 13:46:23 kitchen systemd[1]: volumiobt.service: Deactivated successfully. Feb 22 13:46:23 kitchen systemd[1]: volumiobt.service: Consumed 2.267s CPU time. Feb 22 13:46:31 kitchen sudo[2170]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-22 13:45' Feb 22 13:46:31 kitchen sudo[2170]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 13:46:32 kitchen volumio5-onboarding[1976]: failed to bootstrap state: failed to get music providers: could not get available plugins: could not get available plugins: context deadline exceeded Feb 22 13:46:32 kitchen systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Feb 22 13:46:32 kitchen systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. 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="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:07:07 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"