Feb 12 21:27:13 pi2-kche volumio[1108]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 12 21:27:13 pi2-kche volumio[1108]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 12 21:27:13 pi2-kche volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 21:27:13 pi2-kche volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 21:27:13 pi2-kche volumio[1108]: info: Starting MyVolumio Remote Streaming Endpoints Feb 12 21:27:13 pi2-kche volumio[1108]: info: MyVolumio login type: Token Feb 12 21:27:13 pi2-kche volumio[1108]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 12 21:27:13 pi2-kche volumio[1108]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 12 21:27:22 pi2-kche systemd[1]: systemd-hostnamed.service: Deactivated successfully. Feb 12 21:27:23 pi2-kche volumio5-onboarding[1671]: failed to bootstrap state: failed to get system info: could not get system info: context deadline exceeded Feb 12 21:27:23 pi2-kche systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Feb 12 21:27:23 pi2-kche systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Feb 12 21:27:24 pi2-kche systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 4. Feb 12 21:27:24 pi2-kche systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Feb 12 21:27:24 pi2-kche systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Feb 12 21:27:24 pi2-kche volumio5-onboarding[1802]: time=2026-02-12T21:27:24.426+01:00 level=INFO msg="running volumio5-device-gateway" version=30d1950e+CHANGES buildDate=2026-01-29T13:10:08Z Feb 12 21:27:30 pi2-kche volumio[1108]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 12 21:27:30 pi2-kche volumio[1108]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 12 21:27:30 pi2-kche volumio[1108]: info: Streaming services startup Feb 12 21:27:30 pi2-kche volumio[1108]: info: Starting Streaming Daemon Feb 12 21:27:30 pi2-kche volumio[1108]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 12 21:27:31 pi2-kche sudo[1811]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 12 21:27:31 pi2-kche sudo[1811]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 21:27:31 pi2-kche sudo[1811]: pam_unix(sudo:session): session closed for user root Feb 12 21:27:32 pi2-kche volumio[1108]: info: AutoStart - Check #5/60 - VOLUMIO_SYSTEM_STATUS = starting Feb 12 21:27:32 pi2-kche volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 12 21:27:33 pi2-kche volumio[1108]: info: Received Get System Info Feb 12 21:27:33 pi2-kche volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 12 21:27:33 pi2-kche volumio[1108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 12 21:27:33 pi2-kche volumio[1108]: info: Discovery: Getting this device information Feb 12 21:27:33 pi2-kche volumio[1108]: info: CoreCommandRouter::volumioGetState Feb 12 21:27:33 pi2-kche volumio[1108]: info: CorePlayQueue::getTrack 0 Feb 12 21:27:33 pi2-kche volumio[1108]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 12 21:27:33 pi2-kche volumio[1108]: error: Cannot start Volumio Streaming Daemon Feb 12 21:27:33 pi2-kche volumio[1108]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 12 21:27:33 pi2-kche volumio[1108]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 12 21:27:34 pi2-kche volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 21:27:34 pi2-kche volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 21:27:34 pi2-kche volumio[1108]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Feb 12 21:27:34 pi2-kche volumio[1108]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 12 21:27:34 pi2-kche volumio[1108]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 12 21:27:34 pi2-kche volumio[1108]: info: CoreCommandRouter::volumioGetBrowseSources Feb 12 21:27:34 pi2-kche volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 12 21:27:34 pi2-kche volumio5-onboarding[1802]: failed to create app: failed to initialize host: failed to create socket connection: could not connect to socket.io server: read tcp 127.0.0.1:43016->127.0.0.1:3000: i/o timeout Feb 12 21:27:34 pi2-kche systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Feb 12 21:27:34 pi2-kche systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Feb 12 21:27:34 pi2-kche systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 5. Feb 12 21:27:34 pi2-kche systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Feb 12 21:27:34 pi2-kche systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Feb 12 21:27:34 pi2-kche volumio5-onboarding[1831]: time=2026-02-12T21:27:34.861+01:00 level=INFO msg="running volumio5-device-gateway" version=30d1950e+CHANGES buildDate=2026-01-29T13:10:08Z Feb 12 21:27:36 pi2-kche volumio[1108]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Feb 12 21:27:37 pi2-kche volumio[1108]: info: AutoStart - Check #6/60 - VOLUMIO_SYSTEM_STATUS = starting Feb 12 21:27:37 pi2-kche volumio5-onboarding[1831]: time=2026-02-12T21:27:37.620+01:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci0 error="bluetooth: adapter /org/bluez/hci0 does not exist" Feb 12 21:27:37 pi2-kche volumio5-onboarding[1831]: time=2026-02-12T21:27:37.626+01:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci1 error="bluetooth: adapter /org/bluez/hci1 does not exist" Feb 12 21:27:37 pi2-kche volumio5-onboarding[1831]: time=2026-02-12T21:27:37.635+01:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci2 error="bluetooth: adapter /org/bluez/hci2 does not exist" Feb 12 21:27:37 pi2-kche volumio5-onboarding[1831]: time=2026-02-12T21:27:37.640+01:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci3 error="bluetooth: adapter /org/bluez/hci3 does not exist" Feb 12 21:27:37 pi2-kche volumio5-onboarding[1831]: time=2026-02-12T21:27:37.647+01:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci4 error="bluetooth: adapter /org/bluez/hci4 does not exist" Feb 12 21:27:37 pi2-kche volumio5-onboarding[1831]: time=2026-02-12T21:27:37.647+01:00 level=WARN msg="no Bluetooth adapter found, BLE discovery disabled" Feb 12 21:27:37 pi2-kche volumio[1108]: 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: 5 Feb 12 21:27:38 pi2-kche volumio[1108]: 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: 5 Feb 12 21:27:38 pi2-kche volumio[1108]: info: Received Get System Info Feb 12 21:27:38 pi2-kche volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 12 21:27:38 pi2-kche volumio[1108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 12 21:27:38 pi2-kche volumio[1108]: info: Discovery: Getting this device information Feb 12 21:27:38 pi2-kche volumio[1108]: info: CoreCommandRouter::volumioGetState Feb 12 21:27:38 pi2-kche volumio[1108]: info: CorePlayQueue::getTrack 0 Feb 12 21:27:38 pi2-kche volumio[1108]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 12 21:27:38 pi2-kche volumio5-onboarding[1831]: time=2026-02-12T21:27:38.457+01:00 level=INFO msg="system info for 37520a499024b88eada183a4444d6b8e" deviceName="Pi2 Küche" deviceVariant=volumio deviceModel= softwareVersion=4.096 Feb 12 21:27:38 pi2-kche volumio[1108]: info: CoreCommandRouter::volumioGetState Feb 12 21:27:38 pi2-kche volumio[1108]: info: CorePlayQueue::getTrack 0 Feb 12 21:27:39 pi2-kche volumio[1108]: info: Received Get System Info Feb 12 21:27:39 pi2-kche volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 12 21:27:39 pi2-kche volumio[1108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 12 21:27:39 pi2-kche volumio[1108]: info: Discovery: Getting this device information Feb 12 21:27:39 pi2-kche volumio[1108]: info: CoreCommandRouter::volumioGetState Feb 12 21:27:39 pi2-kche volumio[1108]: info: CorePlayQueue::getTrack 0 Feb 12 21:27:39 pi2-kche volumio[1108]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 12 21:27:39 pi2-kche volumio[1108]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 12 21:27:39 pi2-kche volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 12 21:27:39 pi2-kche volumio5-onboarding[1831]: time=2026-02-12T21:27:39.595+01:00 level=INFO msg="enabling local network discovery" Feb 12 21:27:39 pi2-kche volumio5-onboarding[1831]: time=2026-02-12T21:27:39.693+01:00 level=INFO msg="bootstrapping state" hasInternet=true Feb 12 21:27:40 pi2-kche volumio[1108]: info: Received Get System Info Feb 12 21:27:40 pi2-kche volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 12 21:27:40 pi2-kche volumio[1108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 12 21:27:40 pi2-kche volumio[1108]: info: Discovery: Getting this device information Feb 12 21:27:40 pi2-kche volumio[1108]: info: CoreCommandRouter::volumioGetState Feb 12 21:27:40 pi2-kche volumio[1108]: info: CorePlayQueue::getTrack 0 Feb 12 21:27:40 pi2-kche volumio[1108]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 12 21:27:41 pi2-kche volumio-remote-updater[632]: Test mode disabled Feb 12 21:27:41 pi2-kche volumio-remote-updater[632]: Alpha mode disabled Feb 12 21:27:41 pi2-kche volumio-remote-updater[632]: Alpha legacy test mode disabled Feb 12 21:27:41 pi2-kche volumio[1108]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Feb 12 21:27:42 pi2-kche volumio[1108]: info: AutoStart - Check #7/60 - VOLUMIO_SYSTEM_STATUS = starting Feb 12 21:27:42 pi2-kche volumio[1108]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Feb 12 21:27:42 pi2-kche volumio[1108]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 12 21:27:42 pi2-kche volumio[1108]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 12 21:27:42 pi2-kche volumio[1108]: info: CoreCommandRouter::volumioGetState Feb 12 21:27:42 pi2-kche volumio[1108]: info: CorePlayQueue::getTrack 0 Feb 12 21:27:42 pi2-kche volumio[1108]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 12 21:27:42 pi2-kche volumio[1108]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 12 21:27:45 pi2-kche volumio[1108]: info: MyVolumio token set successfully Feb 12 21:27:45 pi2-kche volumio[1108]: info: MYVOLUMIO: Adding device Feb 12 21:27:45 pi2-kche volumio[1108]: info: MYVOLUMIO: Evaluating Server Feb 12 21:27:47 pi2-kche volumio[1108]: info: MyVolumio Plan changed: premium Feb 12 21:27:47 pi2-kche volumio[1108]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Feb 12 21:27:47 pi2-kche volumio[1108]: info: Removing browser output: myVolumio user plan is not superstar Feb 12 21:27:47 pi2-kche volumio[1108]: info: Removing audio output: Feb 12 21:27:47 pi2-kche volumio[1108]: info: MYVOLUMIO: Adding device Feb 12 21:27:47 pi2-kche volumio[1108]: info: MYVOLUMIO: Evaluating Server Feb 12 21:27:47 pi2-kche volumio[1108]: info: AutoStart - Check #8/60 - VOLUMIO_SYSTEM_STATUS = starting Feb 12 21:27:50 pi2-kche volumio[1108]: info: Setting Geolocation for MyVolumio to eu7 Feb 12 21:27:50 pi2-kche volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 21:27:50 pi2-kche volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 21:27:50 pi2-kche volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 21:27:51 pi2-kche volumio[1108]: info: Setting Geolocation for MyVolumio to eu7 Feb 12 21:27:51 pi2-kche volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 21:27:51 pi2-kche volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 21:27:51 pi2-kche volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 21:27:51 pi2-kche volumio[1108]: error: MyVolumio Plugin failed to authenticate in a timely fashion Feb 12 21:27:51 pi2-kche volumio[1108]: info: Completed starting MyVolumio Plugin Feb 12 21:27:51 pi2-kche volumio[1108]: [Metrics] CommandRouter: 136s 763.77ms Feb 12 21:27:51 pi2-kche volumio[1108]: info: CoreCommandRouter::volumiosetStartupVolume Feb 12 21:27:51 pi2-kche volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 21:27:51 pi2-kche volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 21:27:51 pi2-kche volumio[1108]: info: CoreCommandRouter::Close All Modals sent Feb 12 21:27:51 pi2-kche volumio[1108]: info: CoreCommandRouter::Close All Modals sent Feb 12 21:27:51 pi2-kche volumio[1108]: info: Updating MyVolumio device info Feb 12 21:27:51 pi2-kche volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 21:27:51 pi2-kche volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 21:27:51 pi2-kche volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 21:27:52 pi2-kche volumio[1108]: info: Successfully Added MyVolumio device Feb 12 21:27:52 pi2-kche volumio[1108]: info: Successfully Added MyVolumio device Feb 12 21:27:52 pi2-kche volumio[1108]: info: Updating MyVolumio device info Feb 12 21:27:52 pi2-kche volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 21:27:52 pi2-kche volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 21:27:52 pi2-kche volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 21:27:52 pi2-kche volumio[1108]: info: AutoStart - Check #9/60 - VOLUMIO_SYSTEM_STATUS = starting Feb 12 21:27:52 pi2-kche volumio[1108]: info: Remote config written successfully Feb 12 21:27:52 pi2-kche volumio[1108]: info: Starting Tunnel 1 Feb 12 21:27:52 pi2-kche volumio[1108]: info: Starting Tunnel Connection Checker Feb 12 21:27:53 pi2-kche volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Feb 12 21:27:53 pi2-kche volumio[1108]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 12 21:27:53 pi2-kche volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Feb 12 21:27:53 pi2-kche volumio[1108]: info: Version has changed, forcing UI Reload Feb 12 21:27:53 pi2-kche volumio[1108]: info: CoreCommandRouter::Reload Ui Feb 12 21:27:53 pi2-kche volumio[1108]: info: Successfully Updated MyVolumio device Feb 12 21:27:53 pi2-kche volumio[1108]: info: MYVolumio Device enabled Feb 12 21:27:53 pi2-kche volumio[1108]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Feb 12 21:27:53 pi2-kche volumio[1108]: info: MyVolumio status changed Feb 12 21:27:53 pi2-kche volumio[1108]: info: Streaming services startup Feb 12 21:27:53 pi2-kche volumio[1108]: info: Starting Streaming Daemon Feb 12 21:27:54 pi2-kche volumio[1108]: info: Successfully Updated MyVolumio device Feb 12 21:27:54 pi2-kche sudo[1917]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 12 21:27:54 pi2-kche sudo[1917]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 21:27:54 pi2-kche sudo[1917]: pam_unix(sudo:session): session closed for user root Feb 12 21:27:57 pi2-kche volumio[1108]: error: Cannot start Volumio Streaming Daemon Feb 12 21:27:57 pi2-kche volumio[1108]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 12 21:27:57 pi2-kche volumio[1108]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 12 21:27:57 pi2-kche volumio5-onboarding[1831]: time=2026-02-12T21:27:57.229+01:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=spotify error="could not open plugin config file for \"spop\": open /data/configuration/music_service/spop/config.json: no such file or directory" Feb 12 21:27:57 pi2-kche volumio[1108]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Feb 12 21:27:57 pi2-kche volumio[1108]: error: [MyVolumio PluginManager] Cache data is invalid! Feb 12 21:27:57 pi2-kche volumio[1108]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 12 21:27:57 pi2-kche volumio[1108]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 12 21:27:57 pi2-kche volumio[1108]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 12 21:27:57 pi2-kche volumio[1108]: info: AutoStart - Check #10/60 - VOLUMIO_SYSTEM_STATUS = starting Feb 12 21:27:58 pi2-kche volumio[1108]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Feb 12 21:28:01 pi2-kche volumio[1108]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart Feb 12 21:28:01 pi2-kche volumio[1108]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Feb 12 21:28:09 pi2-kche volumio5-onboarding[1831]: failed to bootstrap state: failed to get available plugins: could not get available plugins: context deadline exceeded Feb 12 21:28:09 pi2-kche systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Feb 12 21:28:09 pi2-kche systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Feb 12 21:28:09 pi2-kche systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 6. Feb 12 21:28:09 pi2-kche systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Feb 12 21:28:10 pi2-kche systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Feb 12 21:28:10 pi2-kche volumio5-onboarding[1939]: time=2026-02-12T21:28:10.340+01:00 level=INFO msg="running volumio5-device-gateway" version=30d1950e+CHANGES buildDate=2026-01-29T13:10:08Z Feb 12 21:28:20 pi2-kche volumio5-onboarding[1939]: failed to create app: failed to initialize host: failed to create socket connection: could not connect to socket.io server: read tcp 127.0.0.1:57114->127.0.0.1:3000: i/o timeout Feb 12 21:28:20 pi2-kche systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Feb 12 21:28:20 pi2-kche systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Feb 12 21:28:20 pi2-kche systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 7. Feb 12 21:28:20 pi2-kche systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Feb 12 21:28:20 pi2-kche systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Feb 12 21:28:20 pi2-kche volumio5-onboarding[1962]: time=2026-02-12T21:28:20.888+01:00 level=INFO msg="running volumio5-device-gateway" version=30d1950e+CHANGES buildDate=2026-01-29T13:10:08Z Feb 12 21:28:28 pi2-kche volumio[1108]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Feb 12 21:28:29 pi2-kche sudo[1987]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Feb 12 21:28:29 pi2-kche sudo[1987]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 21:28:29 pi2-kche sudo[1987]: pam_unix(sudo:session): session closed for user root Feb 12 21:28:30 pi2-kche volumio[1108]: info: MRS: MultiRoom plugin initialized Feb 12 21:28:30 pi2-kche volumio[1108]: info: MRS: STOPPING SNAPCLIENT Feb 12 21:28:30 pi2-kche volumio[1108]: info: MRS: Snap server stop Feb 12 21:28:30 pi2-kche volumio[1108]: info: MRS: STOPPING volumioStreaming Feb 12 21:28:30 pi2-kche sudo[2004]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Feb 12 21:28:30 pi2-kche volumio[1108]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Feb 12 21:28:30 pi2-kche sudo[2004]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 21:28:30 pi2-kche volumio5-onboarding[1962]: failed to create app: failed to initialize host: failed to create socket connection: could not connect to socket.io server: read tcp 127.0.0.1:40362->127.0.0.1:3000: i/o timeout Feb 12 21:28:30 pi2-kche systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Feb 12 21:28:30 pi2-kche systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Feb 12 21:28:30 pi2-kche sudo[2006]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Feb 12 21:28:30 pi2-kche sudo[2006]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 21:28:31 pi2-kche systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 8. Feb 12 21:28:31 pi2-kche systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Feb 12 21:28:31 pi2-kche sudo[2008]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Feb 12 21:28:31 pi2-kche sudo[2008]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 21:28:31 pi2-kche systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Feb 12 21:28:31 pi2-kche sudo[2010]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Feb 12 21:28:31 pi2-kche volumio5-onboarding[2014]: time=2026-02-12T21:28:31.400+01:00 level=INFO msg="running volumio5-device-gateway" version=30d1950e+CHANGES buildDate=2026-01-29T13:10:08Z Feb 12 21:28:31 pi2-kche sudo[2010]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 21:28:31 pi2-kche volumio[1108]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Feb 12 21:28:31 pi2-kche sudo[2010]: pam_unix(sudo:session): session closed for user root Feb 12 21:28:31 pi2-kche sudo[2006]: pam_unix(sudo:session): session closed for user root Feb 12 21:28:31 pi2-kche sudo[2004]: pam_unix(sudo:session): session closed for user root Feb 12 21:28:31 pi2-kche dbus-daemon[600]: [system] Connection has not authenticated soon enough, closing it (auth_timeout=30000ms, elapsed: 30000ms) Feb 12 21:28:31 pi2-kche volumio[1108]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Feb 12 21:28:31 pi2-kche sudo[2008]: pam_unix(sudo:session): session closed for user root Feb 12 21:28:32 pi2-kche volumio[1108]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Feb 12 21:28:32 pi2-kche volumio[1108]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Feb 12 21:28:32 pi2-kche volumio[1108]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Feb 12 21:28:32 pi2-kche volumio[1108]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Feb 12 21:28:33 pi2-kche volumio[1108]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Feb 12 21:28:33 pi2-kche volumio[1108]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Feb 12 21:28:33 pi2-kche volumio[1108]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Feb 12 21:28:33 pi2-kche volumio[1108]: info: Preparing to generate the ALSA configuration file Feb 12 21:28:33 pi2-kche volumio[1108]: info: AutoStart - Check #11/60 - VOLUMIO_SYSTEM_STATUS = starting Feb 12 21:28:33 pi2-kche volumio[1108]: info: BOOT COMPLETED Feb 12 21:28:33 pi2-kche volumio[1108]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Feb 12 21:28:33 pi2-kche volumio[1108]: info: Reading ALSA contributions from plugins. Feb 12 21:28:33 pi2-kche volumio[1108]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Feb 12 21:28:34 pi2-kche volumio[1108]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 12 21:28:34 pi2-kche volumio[1108]: Error: write EPIPE Feb 12 21:28:34 pi2-kche volumio[1108]: at afterWriteDispatched (node:internal/stream_base_commons:160:15) Feb 12 21:28:34 pi2-kche volumio[1108]: at writeGeneric (node:internal/stream_base_commons:151:3) Feb 12 21:28:34 pi2-kche volumio[1108]: at Socket._writeGeneric (node:net:952:11) Feb 12 21:28:34 pi2-kche volumio[1108]: at Socket._write (node:net:964:8) Feb 12 21:28:34 pi2-kche volumio[1108]: at writeOrBuffer (node:internal/streams/writable:399:12) Feb 12 21:28:34 pi2-kche volumio[1108]: at _write (node:internal/streams/writable:340:10) Feb 12 21:28:34 pi2-kche volumio[1108]: at Writable.write (node:internal/streams/writable:344:10) Feb 12 21:28:34 pi2-kche volumio[1108]: at auth (/myvolumio/plugins/audio_interface/bluetooth/node_modules/dbus-next/lib/handshake.js:67:10) Feb 12 21:28:34 pi2-kche volumio[1108]: at Socket. (/myvolumio/plugins/audio_interface/bluetooth/node_modules/dbus-next/lib/connection.js:137:32) Feb 12 21:28:34 pi2-kche volumio[1108]: at Object.onceWrapper (node:events:628:28) Feb 12 21:28:34 pi2-kche volumio[1108]: at Socket.emit (node:events:514:28) Feb 12 21:28:34 pi2-kche volumio[1108]: at PipeConnectWrap.afterConnect [as oncomplete] (node:net:1580:10) { Feb 12 21:28:34 pi2-kche volumio[1108]: errno: -32, Feb 12 21:28:34 pi2-kche volumio[1108]: code: 'EPIPE', Feb 12 21:28:34 pi2-kche volumio[1108]: syscall: 'write' Feb 12 21:28:34 pi2-kche volumio[1108]: } Feb 12 21:28:34 pi2-kche volumio[1108]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 12 21:28:41 pi2-kche volumio5-onboarding[2014]: failed to create app: failed to initialize host: failed to create socket connection: could not connect to socket.io server: read tcp 127.0.0.1:41738->127.0.0.1:3000: i/o timeout Feb 12 21:28:41 pi2-kche systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Feb 12 21:28:41 pi2-kche systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Feb 12 21:28:41 pi2-kche systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 9. Feb 12 21:28:41 pi2-kche systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Feb 12 21:28:41 pi2-kche systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Feb 12 21:28:41 pi2-kche volumio5-onboarding[2049]: time=2026-02-12T21:28:41.934+01:00 level=INFO msg="running volumio5-device-gateway" version=30d1950e+CHANGES buildDate=2026-01-29T13:10:08Z Feb 12 21:28:51 pi2-kche sudo[2075]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-12 21:27' Feb 12 21:28:51 pi2-kche sudo[2075]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 21:28:51 pi2-kche volumio5-onboarding[2049]: failed to create app: failed to initialize host: failed to create socket connection: could not connect to socket.io server: read tcp 127.0.0.1:60670->127.0.0.1:3000: i/o timeout Feb 12 21:28:51 pi2-kche systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Feb 12 21:28:51 pi2-kche systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Feb 12 21:28:52 pi2-kche systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 10. Feb 12 21:28:52 pi2-kche systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Feb 12 21:28:52 pi2-kche systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Feb 12 21:28:52 pi2-kche volumio5-onboarding[2077]: time=2026-02-12T21:28:52.507+01:00 level=INFO msg="running volumio5-device-gateway" version=30d1950e+CHANGES buildDate=2026-01-29T13:10:08Z 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"