Apr 03 16:56:00 volumio volumio[1265]: info: CoreCommandRouter::volumioGetState Apr 03 16:56:00 volumio volumio[1265]: info: CorePlayQueue::getTrack 0 Apr 03 16:56:00 volumio volumio[1265]: info: MyVolumio login type: Token Apr 03 16:56:00 volumio volumio[1265]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 49 Apr 03 16:56:00 volumio volumio[1265]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 49 Apr 03 16:56:02 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 11. Apr 03 16:56:02 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Apr 03 16:56:02 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Apr 03 16:56:02 volumio upmpdcli[2265]: Could not open config: /tmp/upmpdcli.conf Apr 03 16:56:02 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Apr 03 16:56:02 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Apr 03 16:56:03 volumio volumio[1265]: verbose: New Socket.io Connection to 192.168.2.9:3000 from 192.168.2.55 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 43 Apr 03 16:56:04 volumio volumio[1265]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Apr 03 16:56:05 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Apr 03 16:56:05 volumio volumio[1265]: info: CoreCommandRouter::volumioGetState Apr 03 16:56:05 volumio volumio[1265]: info: CorePlayQueue::getTrack 0 Apr 03 16:56:05 volumio volumio[1265]: info: CoreCommandRouter::volumioGetState Apr 03 16:56:05 volumio volumio[1265]: info: CorePlayQueue::getTrack 0 Apr 03 16:56:06 volumio volumio[1265]: info: CoreCommandRouter::volumioGetState Apr 03 16:56:06 volumio volumio[1265]: info: CorePlayQueue::getTrack 0 Apr 03 16:56:06 volumio volumio[1265]: info: MyVolumio token set successfully Apr 03 16:56:06 volumio volumio[1265]: info: MYVOLUMIO: Adding device Apr 03 16:56:06 volumio volumio[1265]: info: MYVOLUMIO: Evaluating Server Apr 03 16:56:08 volumio volumio[1265]: info: MyVolumio Plan changed: premium Apr 03 16:56:08 volumio volumio[1265]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Apr 03 16:56:08 volumio volumio[1265]: info: Removing browser output: myVolumio user plan is not superstar Apr 03 16:56:08 volumio volumio[1265]: info: Removing audio output: Apr 03 16:56:08 volumio volumio[1265]: info: MYVOLUMIO: Adding device Apr 03 16:56:08 volumio volumio[1265]: info: MYVOLUMIO: Evaluating Server Apr 03 16:56:09 volumio volumio[1265]: info: CoreCommandRouter::volumioPlay Apr 03 16:56:09 volumio volumio[1265]: info: CoreStateMachine::play index undefined Apr 03 16:56:09 volumio volumio[1265]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 03 16:56:09 volumio volumio[1265]: info: CorePlayQueue::getTrack 0 Apr 03 16:56:09 volumio volumio[1265]: info: CoreStateMachine::startPlaybackTimer Apr 03 16:56:09 volumio volumio[1265]: info: CorePlayQueue::getTrack 0 Apr 03 16:56:09 volumio volumio[1265]: info: [1775228169305] ControllerWebradio::clearAddPlayTrack Apr 03 16:56:09 volumio volumio[1265]: verbose: ControllerMpd::sendMpdCommand stop Apr 03 16:56:11 volumio volumio[1265]: info: Setting Geolocation for MyVolumio to eu8 Apr 03 16:56:11 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 03 16:56:11 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 03 16:56:11 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 03 16:56:11 volumio volumio[1265]: info: sendMpdCommand stop took 1929 milliseconds Apr 03 16:56:11 volumio volumio[1265]: verbose: ControllerMpd::sendMpdCommand clear Apr 03 16:56:11 volumio volumio[1265]: info: Apr 03 16:56:11 volumio volumio[1265]: ---------------------------- MPD announces system playlist update Apr 03 16:56:11 volumio volumio[1265]: info: Ignoring MPD Status Update Apr 03 16:56:11 volumio volumio[1265]: info: sendMpdCommand clear took 227 milliseconds Apr 03 16:56:11 volumio volumio[1265]: verbose: ControllerMpd::sendMpdCommand load "https://playerservices.streamtheworld.com/api/livestream-redirect/RADIO10.mp3?dist=tunein&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc3Mzc1MjI1MCwiaXNzIjoidGlzcnYifQ.MHrboAuPQyVzzDUbvme13bycY1xBwuk3IlnkhB8P7zM" Apr 03 16:56:12 volumio volumio[1265]: info: Setting Geolocation for MyVolumio to eu12 Apr 03 16:56:12 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 03 16:56:12 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 03 16:56:12 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 03 16:56:12 volumio volumio[1265]: info: Updating MyVolumio device info Apr 03 16:56:12 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 03 16:56:12 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 03 16:56:12 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 03 16:56:12 volumio volumio[1265]: info: Remote config written successfully Apr 03 16:56:12 volumio volumio[1265]: info: Starting Tunnel 1 Apr 03 16:56:12 volumio volumio[1265]: info: Starting Tunnel Connection Checker Apr 03 16:56:12 volumio volumio[1265]: info: Completed starting MyVolumio Plugin Apr 03 16:56:13 volumio volumio[1265]: error: updateQueue error: null Apr 03 16:56:13 volumio volumio[1265]: info: ------------------------------ 1681ms Apr 03 16:56:13 volumio volumio[1265]: info: Successfully Added MyVolumio device Apr 03 16:56:13 volumio volumio[1265]: info: Updating MyVolumio device info Apr 03 16:56:13 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 03 16:56:13 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 03 16:56:13 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 03 16:56:13 volumio volumio[1265]: info: MYVolumio Device enabled Apr 03 16:56:13 volumio volumio[1265]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Apr 03 16:56:13 volumio volumio[1265]: info: MyVolumio status changed Apr 03 16:56:13 volumio volumio[1265]: info: Streaming services startup Apr 03 16:56:13 volumio volumio[1265]: info: Starting Streaming Daemon Apr 03 16:56:14 volumio volumio[1265]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 27 Apr 03 16:56:14 volumio sudo[2324]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 03 16:56:14 volumio sudo[2324]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 03 16:56:14 volumio sudo[2324]: pam_unix(sudo:session): session closed for user root Apr 03 16:56:15 volumio volumio[1265]: info: Successfully Updated MyVolumio device Apr 03 16:56:16 volumio volumio[1265]: info: Successfully Added MyVolumio device Apr 03 16:56:16 volumio volumio[1265]: error: Cannot start Volumio Streaming Daemon Apr 03 16:56:16 volumio volumio[1265]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 03 16:56:16 volumio volumio[1265]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 03 16:56:16 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Apr 03 16:56:16 volumio volumio[1265]: error: [MyVolumio PluginManager] Cache data is invalid! Apr 03 16:56:17 volumio volumio[1265]: verbose: ControllerMpd::sendMpdCommand add "https://playerservices.streamtheworld.com/api/livestream-redirect/RADIO10.mp3?dist=tunein&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc3Mzc1MjI1MCwiaXNzIjoidGlzcnYifQ.MHrboAuPQyVzzDUbvme13bycY1xBwuk3IlnkhB8P7zM" Apr 03 16:56:17 volumio volumio[1265]: info: Successfully Updated MyVolumio device Apr 03 16:56:17 volumio volumio[1265]: info: Apr 03 16:56:17 volumio volumio[1265]: ---------------------------- MPD announces system playlist update Apr 03 16:56:17 volumio volumio[1265]: info: Ignoring MPD Status Update Apr 03 16:56:17 volumio volumio[1265]: info: sendMpdCommand add "https://playerservices.streamtheworld.com/api/livestream-redirect/RADIO10.mp3?dist=tunein&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc3Mzc1MjI1MCwiaXNzIjoidGlzcnYifQ.MHrboAuPQyVzzDUbvme13bycY1xBwuk3IlnkhB8P7zM" took 661 milliseconds Apr 03 16:56:17 volumio volumio[1265]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 03 16:56:17 volumio volumio[1265]: verbose: ControllerMpd::sendMpdCommand play Apr 03 16:56:18 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 12. Apr 03 16:56:18 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Apr 03 16:56:18 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Apr 03 16:56:18 volumio upmpdcli[2330]: Could not open config: /tmp/upmpdcli.conf Apr 03 16:56:18 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Apr 03 16:56:18 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Apr 03 16:56:19 volumio volumio[1265]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 28 Apr 03 16:56:20 volumio volumio[1265]: info: ------------------------------ 2757ms Apr 03 16:56:20 volumio volumio[1265]: info: sendMpdCommand play took 2699 milliseconds Apr 03 16:56:21 volumio volumio[1265]: info: Apr 03 16:56:21 volumio volumio[1265]: ---------------------------- MPD announces state update: player Apr 03 16:56:21 volumio volumio[1265]: info: ControllerMpd::getState Apr 03 16:56:21 volumio volumio[1265]: verbose: ControllerMpd::sendMpdCommand status Apr 03 16:56:22 volumio volumio[1265]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Apr 03 16:56:24 volumio volumio5-onboarding[2033]: failed to bootstrap state: failed to check for software update: could not check for updates: context deadline exceeded Apr 03 16:56:24 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Apr 03 16:56:24 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Apr 03 16:56:25 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 3. Apr 03 16:56:25 volumio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Apr 03 16:56:25 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Apr 03 16:56:25 volumio volumio5-onboarding[2353]: time=2026-04-03T16:56:25.707+02:00 level=INFO msg="running volumio5-device-gateway" version=10ca7e93+CHANGES buildDate=2026-03-06T14:30:20Z Apr 03 16:56:26 volumio volumio[1265]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin bluetooth Apr 03 16:56:26 volumio volumio[1265]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin bluetooth Apr 03 16:56:26 volumio volumio[1265]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart Apr 03 16:56:26 volumio volumio[1265]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Apr 03 16:56:33 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 13. Apr 03 16:56:33 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Apr 03 16:56:33 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Apr 03 16:56:33 volumio upmpdcli[2377]: Could not open config: /tmp/upmpdcli.conf Apr 03 16:56:33 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Apr 03 16:56:33 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Apr 03 16:56:35 volumio volumio5-onboarding[2353]: 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:52790->127.0.0.1:3000: i/o timeout Apr 03 16:56:35 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Apr 03 16:56:35 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Apr 03 16:56:36 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 4. Apr 03 16:56:36 volumio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Apr 03 16:56:36 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Apr 03 16:56:37 volumio volumio5-onboarding[2385]: time=2026-04-03T16:56:37.763+02:00 level=INFO msg="running volumio5-device-gateway" version=10ca7e93+CHANGES buildDate=2026-03-06T14:30:20Z Apr 03 16:56:47 volumio volumio5-onboarding[2385]: 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:33624->127.0.0.1:3000: i/o timeout Apr 03 16:56:47 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Apr 03 16:56:47 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Apr 03 16:56:48 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 5. Apr 03 16:56:48 volumio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Apr 03 16:56:48 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Apr 03 16:56:48 volumio volumio5-onboarding[2415]: time=2026-04-03T16:56:48.353+02:00 level=INFO msg="running volumio5-device-gateway" version=10ca7e93+CHANGES buildDate=2026-03-06T14:30:20Z Apr 03 16:56:49 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 14. Apr 03 16:56:49 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Apr 03 16:56:49 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Apr 03 16:56:49 volumio upmpdcli[2423]: Could not open config: /tmp/upmpdcli.conf Apr 03 16:56:49 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Apr 03 16:56:49 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Apr 03 16:56:56 volumio dbus-daemon[779]: [system] Connection has not authenticated soon enough, closing it (auth_timeout=30000ms, elapsed: 30000ms) Apr 03 16:56:58 volumio volumio5-onboarding[2415]: 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:37440->127.0.0.1:3000: i/o timeout Apr 03 16:56:58 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Apr 03 16:56:58 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Apr 03 16:56:58 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 6. Apr 03 16:56:58 volumio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Apr 03 16:56:58 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Apr 03 16:56:58 volumio volumio5-onboarding[2439]: time=2026-04-03T16:56:58.781+02:00 level=INFO msg="running volumio5-device-gateway" version=10ca7e93+CHANGES buildDate=2026-03-06T14:30:20Z Apr 03 16:57:03 volumio volumio[1265]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Apr 03 16:57:04 volumio sudo[2464]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Apr 03 16:57:04 volumio sudo[2464]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 03 16:57:04 volumio sudo[2464]: pam_unix(sudo:session): session closed for user root Apr 03 16:57:04 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 15. Apr 03 16:57:04 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Apr 03 16:57:04 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Apr 03 16:57:04 volumio upmpdcli[2469]: Could not open config: /tmp/upmpdcli.conf Apr 03 16:57:04 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Apr 03 16:57:04 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Apr 03 16:57:05 volumio volumio[1265]: info: MRS: MultiRoom plugin initialized Apr 03 16:57:05 volumio volumio[1265]: info: MRS: STOPPING SNAPCLIENT Apr 03 16:57:05 volumio volumio[1265]: info: MRS: Snap server stop Apr 03 16:57:05 volumio sudo[2482]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Apr 03 16:57:05 volumio sudo[2482]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 03 16:57:05 volumio volumio[1265]: info: MRS: STOPPING volumioStreaming Apr 03 16:57:05 volumio sudo[2482]: pam_unix(sudo:session): session closed for user root Apr 03 16:57:05 volumio sudo[2487]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Apr 03 16:57:05 volumio sudo[2487]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 03 16:57:05 volumio sudo[2485]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Apr 03 16:57:06 volumio sudo[2485]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 03 16:57:06 volumio volumio[1265]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Apr 03 16:57:06 volumio sudo[2487]: pam_unix(sudo:session): session closed for user root Apr 03 16:57:06 volumio sudo[2490]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Apr 03 16:57:06 volumio sudo[2490]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 03 16:57:06 volumio sudo[2485]: pam_unix(sudo:session): session closed for user root Apr 03 16:57:06 volumio sudo[2490]: pam_unix(sudo:session): session closed for user root Apr 03 16:57:08 volumio volumio[1265]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Apr 03 16:57:08 volumio volumio[1265]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Apr 03 16:57:08 volumio volumio5-onboarding[2439]: 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:59748->127.0.0.1:3000: i/o timeout Apr 03 16:57:08 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Apr 03 16:57:08 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Apr 03 16:57:09 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 7. Apr 03 16:57:09 volumio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Apr 03 16:57:09 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Apr 03 16:57:09 volumio volumio5-onboarding[2493]: time=2026-04-03T16:57:09.238+02:00 level=INFO msg="running volumio5-device-gateway" version=10ca7e93+CHANGES buildDate=2026-03-06T14:30:20Z Apr 03 16:57:09 volumio volumio[1265]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Apr 03 16:57:09 volumio volumio[1265]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Apr 03 16:57:10 volumio volumio[1265]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Apr 03 16:57:10 volumio volumio[1265]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Apr 03 16:57:10 volumio volumio[1265]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Apr 03 16:57:10 volumio volumio[1265]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Apr 03 16:57:11 volumio volumio[1265]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Apr 03 16:57:11 volumio volumio[1265]: info: Preparing to generate the ALSA configuration file Apr 03 16:57:13 volumio volumio[1265]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Apr 03 16:57:13 volumio volumio[1265]: info: FusionDsp - ---- read samplerate from file: 44100 Apr 03 16:57:13 volumio volumio[1265]: info: camilladsp stopping service pid 1885... Apr 03 16:57:14 volumio volumio[1265]: grep: /proc/1885/cmdline: binary file matches Apr 03 16:57:14 volumio volumio[1265]: grep: /proc/1885/cmdline: binary file matches Apr 03 16:57:14 volumio volumio[1265]: grep: /proc/1885/cmdline: binary file matches Apr 03 16:57:14 volumio volumio[1265]: info: camilladsp service terminated, instance 1 Apr 03 16:57:14 volumio volumio[1265]: info: FusionDsp - If filter freq >samplerate/2 then disable it Apr 03 16:57:14 volumio volumio[1265]: info: camilladsp service started and running in background, instance 1 Apr 03 16:57:14 volumio volumio[1265]: error: MyVolumio Plugins failed to start in a timely fashion Apr 03 16:57:14 volumio volumio[1265]: error: Plugin audio_interface bluetooth failed to complete 'onStart' in a timely fashion Apr 03 16:57:14 volumio volumio[1265]: error: Plugin miscellanea metavolumio failed to complete 'onStart' in a timely fashion Apr 03 16:57:14 volumio volumio[1265]: error: Plugin music_service cd_controller failed to complete 'onStart' in a timely fashion Apr 03 16:57:14 volumio volumio[1265]: error: Plugin music_service tidalconnect failed to complete 'onStart' in a timely fashion Apr 03 16:57:14 volumio volumio[1265]: error: Plugin music_service qobuzconnect failed to complete 'onStart' in a timely fashion Apr 03 16:57:14 volumio volumio[1265]: [Metrics] CommandRouter: 201s 215.38ms Apr 03 16:57:14 volumio volumio[1265]: info: CoreCommandRouter::volumiosetStartupVolume Apr 03 16:57:14 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 16:57:14 volumio volumio[1265]: info: VolumeController:: Setting startup Volume 25 Apr 03 16:57:14 volumio volumio[1265]: info: VolumeController::SetAlsaVolume25 Apr 03 16:57:14 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 03 16:57:14 volumio volumio[1265]: info: CoreCommandRouter::Close All Modals sent Apr 03 16:57:14 volumio volumio[1265]: info: CoreCommandRouter::Close All Modals sent Apr 03 16:57:14 volumio volumio[1265]: info: CoreStateMachine::pushState Apr 03 16:57:14 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 16:57:14 volumio volumio[1265]: info: CoreCommandRouter::volumioPushState Apr 03 16:57:15 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Apr 03 16:57:15 volumio volumio[1265]: info: Apr 03 16:57:15 volumio volumio[1265]: ---------------------------- MPD announces state update: player Apr 03 16:57:15 volumio volumio[1265]: info: sendMpdCommand status took 54239 milliseconds Apr 03 16:57:15 volumio volumio[1265]: info: ControllerMpd::getState Apr 03 16:57:15 volumio volumio[1265]: verbose: ControllerMpd::sendMpdCommand status Apr 03 16:57:15 volumio volumio[1265]: verbose: ControllerMpd::parseState Apr 03 16:57:15 volumio volumio[1265]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 03 16:57:15 volumio volumio[1265]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Apr 03 16:57:15 volumio volumio[1265]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Apr 03 16:57:15 volumio volumio[1265]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 03 16:57:15 volumio volumio[1265]: info: Reading ALSA contributions from plugins. Apr 03 16:57:16 volumio volumio[1265]: info: VolumeController::SetAlsaVolume35 Apr 03 16:57:16 volumio volumio[1265]: info: VolumeController::SetAlsaVolume97 Apr 03 16:57:16 volumio volumio[1265]: info: CoreCommandRouter::volumioPlay Apr 03 16:57:16 volumio volumio[1265]: info: CoreStateMachine::play index undefined Apr 03 16:57:16 volumio volumio[1265]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 03 16:57:16 volumio volumio[1265]: info: VolumeController::SetAlsaVolume46 Apr 03 16:57:16 volumio volumio[1265]: info: CoreStateMachine::pushState Apr 03 16:57:17 volumio volumio[1265]: info: CorePlayQueue::getTrack 0 Apr 03 16:57:17 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 16:57:17 volumio volumio[1265]: info: CoreCommandRouter::volumioPushState Apr 03 16:57:17 volumio volumio[1265]: info: CoreStateMachine::pushState Apr 03 16:57:17 volumio volumio[1265]: info: CorePlayQueue::getTrack 0 Apr 03 16:57:17 volumio volumio[1265]: info: CoreCommandRouter::volumioPushState Apr 03 16:57:17 volumio volumio[1265]: info: CorePlayQueue::getTrack 0 Apr 03 16:57:17 volumio volumio[1265]: info: CoreStateMachine::startPlaybackTimer Apr 03 16:57:17 volumio volumio[1265]: info: CorePlayQueue::getTrack 0 Apr 03 16:57:17 volumio volumio[1265]: info: CoreCommandRouter::volumioGetVisibleSources Apr 03 16:57:17 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 03 16:57:17 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 03 16:57:17 volumio volumio[1265]: info: [1775228237659] ControllerWebradio::clearAddPlayTrack Apr 03 16:57:17 volumio volumio[1265]: verbose: ControllerMpd::sendMpdCommand stop Apr 03 16:57:17 volumio volumio[1265]: info: CoreStateMachine::pushState Apr 03 16:57:17 volumio volumio[1265]: info: CorePlayQueue::getTrack 0 Apr 03 16:57:17 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 16:57:17 volumio volumio[1265]: info: CoreCommandRouter::volumioPushState Apr 03 16:57:19 volumio volumio[1265]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 03 16:57:19 volumio volumio5-onboarding[2493]: 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:32930->127.0.0.1:3000: i/o timeout Apr 03 16:57:19 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Apr 03 16:57:19 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Apr 03 16:57:19 volumio volumio[1265]: Error: write EPIPE Apr 03 16:57:19 volumio volumio[1265]: at afterWriteDispatched (node:internal/stream_base_commons:160:15) Apr 03 16:57:19 volumio volumio[1265]: at writeGeneric (node:internal/stream_base_commons:151:3) Apr 03 16:57:19 volumio volumio[1265]: at Socket._writeGeneric (node:net:952:11) Apr 03 16:57:19 volumio volumio[1265]: at Socket._write (node:net:964:8) Apr 03 16:57:19 volumio volumio[1265]: at writeOrBuffer (node:internal/streams/writable:399:12) Apr 03 16:57:19 volumio volumio[1265]: at _write (node:internal/streams/writable:340:10) Apr 03 16:57:19 volumio volumio[1265]: at Writable.write (node:internal/streams/writable:344:10) Apr 03 16:57:19 volumio volumio[1265]: at auth (/myvolumio/plugins/audio_interface/bluetooth/node_modules/dbus-next/lib/handshake.js:67:10) Apr 03 16:57:19 volumio volumio[1265]: at Socket. (/myvolumio/plugins/audio_interface/bluetooth/node_modules/dbus-next/lib/connection.js:137:32) Apr 03 16:57:19 volumio volumio[1265]: at Object.onceWrapper (node:events:628:28) { Apr 03 16:57:19 volumio volumio[1265]: errno: -32, Apr 03 16:57:19 volumio volumio[1265]: code: 'EPIPE', Apr 03 16:57:19 volumio volumio[1265]: syscall: 'write' Apr 03 16:57:19 volumio volumio[1265]: } Apr 03 16:57:19 volumio volumio[1265]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 03 16:57:19 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 8. Apr 03 16:57:19 volumio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Apr 03 16:57:19 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Apr 03 16:57:19 volumio volumio5-onboarding[2540]: time=2026-04-03T16:57:19.742+02:00 level=INFO msg="running volumio5-device-gateway" version=10ca7e93+CHANGES buildDate=2026-03-06T14:30:20Z Apr 03 16:57:19 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 16. Apr 03 16:57:19 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Apr 03 16:57:19 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Apr 03 16:57:20 volumio upmpdcli[2550]: Could not open config: /tmp/upmpdcli.conf Apr 03 16:57:20 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Apr 03 16:57:20 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Apr 03 16:57:29 volumio volumio5-onboarding[2540]: 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:54078->127.0.0.1:3000: i/o timeout Apr 03 16:57:29 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Apr 03 16:57:29 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Apr 03 16:57:30 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 9. Apr 03 16:57:30 volumio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Apr 03 16:57:30 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Apr 03 16:57:30 volumio volumio5-onboarding[2575]: time=2026-04-03T16:57:30.255+02:00 level=INFO msg="running volumio5-device-gateway" version=10ca7e93+CHANGES buildDate=2026-03-06T14:30:20Z Apr 03 16:57:35 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 17. Apr 03 16:57:35 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Apr 03 16:57:35 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Apr 03 16:57:35 volumio upmpdcli[2597]: Could not open config: /tmp/upmpdcli.conf Apr 03 16:57:35 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Apr 03 16:57:35 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Apr 03 16:57:38 volumio sudo[2600]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-04-03 16:56' Apr 03 16:57:38 volumio sudo[2600]: 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="dc61260dec5515dafd2b634881860b4c46c919ff" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Mar 6 16:46:58 UTC 2026" VOLUMIO_VERSION="4.103" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="aa741395b8bfc648ff5d04e312f53d2c"