Aug 25 17:01:01 motivo volumio[6716]: info: CoreCommandRouter::executeOnPlugin: musicserver , handleBrowseUri Aug 25 17:01:01 motivo volumio[6716]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 25 17:01:01 motivo volumio[6716]: info: Preload queue cleared Aug 25 17:01:01 motivo volumio[6716]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Aug 25 17:01:01 motivo volumio[6716]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Aug 25 17:01:01 motivo volumio[6716]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Aug 25 17:01:01 motivo volumio[6716]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Aug 25 17:01:02 motivo volumio[6716]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Aug 25 17:01:02 motivo volumio[6716]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Aug 25 17:01:02 motivo volumio[6716]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Aug 25 17:01:02 motivo volumio[6716]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Aug 25 17:01:02 motivo volumio[6716]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Aug 25 17:01:02 motivo volumio[6716]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Aug 25 17:01:02 motivo volumio[6716]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Aug 25 17:01:02 motivo volumio[6716]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Aug 25 17:01:02 motivo volumio[6716]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Aug 25 17:01:02 motivo volumio[6716]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Aug 25 17:01:02 motivo volumio[6716]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Aug 25 17:01:02 motivo volumio[6716]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Aug 25 17:01:02 motivo volumio[6716]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Aug 25 17:01:02 motivo volumio[6716]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Aug 25 17:01:02 motivo volumio[6716]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Aug 25 17:01:02 motivo volumio[6716]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Aug 25 17:01:02 motivo volumio[6716]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Aug 25 17:01:02 motivo volumio[6716]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Aug 25 17:01:02 motivo volumio[6716]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Aug 25 17:01:02 motivo volumio[6716]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Aug 25 17:01:02 motivo volumio[6716]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Aug 25 17:01:02 motivo volumio[6716]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Aug 25 17:01:02 motivo volumio[6716]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Aug 25 17:01:02 motivo volumio[6716]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Aug 25 17:01:02 motivo volumio[6716]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Aug 25 17:01:02 motivo volumio[6716]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Aug 25 17:01:02 motivo volumio[6716]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Aug 25 17:01:02 motivo volumio[6716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 25 17:01:02 motivo volumio[6716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 25 17:01:02 motivo volumio[6716]: info: Starting MyVolumio Remote Streaming Endpoints Aug 25 17:01:02 motivo volumio[6716]: info: MyVolumio login type: Token Aug 25 17:01:02 motivo volumio[6716]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Aug 25 17:01:02 motivo volumio[6716]: error: [MyVolumio PluginManager] Could not read package.json file: Error: /myvolumio/plugins/music_service/streaming_services//package.json: ENOENT: no such file or directory, open '/myvolumio/plugins/music_service/streaming_services//package.json' Aug 25 17:01:02 motivo volumio[6716]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Aug 25 17:01:02 motivo volumio[6716]: info: MyVolumio login type: Token Aug 25 17:01:02 motivo volumio[6716]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 25 17:01:02 motivo volumio5-onboarding[7278]: time=2026-08-25T17:01:02.813+02:00 level=INFO msg="emitting user changed event" component=server peer="10.0.1.243:52491,00:00:00:00:00:00%01 @ 0x258f2f0" userId= Aug 25 17:01:03 motivo volumio[6716]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Aug 25 17:01:03 motivo volumio[6716]: info: CoreCommandRouter::executeOnPlugin: musicserver , handleBrowseUri Aug 25 17:01:03 motivo volumio[6716]: info: Preload queue cleared Aug 25 17:01:03 motivo volumio[6716]: info: MyVolumio token set successfully Aug 25 17:01:03 motivo volumio[6716]: info: MYVOLUMIO: Adding device Aug 25 17:01:03 motivo volumio[6716]: info: MYVOLUMIO: Evaluating Server Aug 25 17:01:04 motivo volumio[6716]: info: MyVolumio Plan changed: superstar Aug 25 17:01:04 motivo volumio[6716]: info: [MyVolumio PluginManager] Subscribed plan changed to superstar Aug 25 17:01:04 motivo volumio[6716]: info: Adding audio output: Aug 25 17:01:04 motivo volumio[6716]: info: MYVOLUMIO: Adding device Aug 25 17:01:04 motivo volumio[6716]: info: MYVOLUMIO: Evaluating Server Aug 25 17:01:04 motivo volumio[6716]: info: Remote config written successfully Aug 25 17:01:04 motivo volumio[6716]: info: Starting Tunnel 1 Aug 25 17:01:04 motivo volumio[6716]: info: Starting Tunnel Connection Checker Aug 25 17:01:04 motivo volumio[6716]: info: MYVolumio Device enabled Aug 25 17:01:04 motivo volumio[6716]: info: MyVolumio status changed Aug 25 17:01:04 motivo volumio[6716]: info: Streaming services startup Aug 25 17:01:04 motivo volumio[6716]: info: Starting Streaming Daemon Aug 25 17:01:04 motivo volumio[6716]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Aug 25 17:01:04 motivo sudo[7464]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 25 17:01:04 motivo sudo[7464]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 25 17:01:04 motivo volumio[6716]: info: Setting Geolocation for MyVolumio to eu7 Aug 25 17:01:04 motivo volumio[6716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 25 17:01:04 motivo volumio[6716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 25 17:01:04 motivo volumio[6716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 25 17:01:04 motivo volumio[6716]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Aug 25 17:01:04 motivo sudo[7464]: pam_unix(sudo:session): session closed for user root Aug 25 17:01:04 motivo volumio[6716]: info: [MyVolumio PluginManager] Plugin audio_interface/bluetooth is enabled for this plan, but could not be found on the local filesystem! Aug 25 17:01:04 motivo volumio[6716]: info: [MyVolumio PluginManager] Plugin audio_interface/multiroom is enabled for this plan, but could not be found on the local filesystem! Aug 25 17:01:04 motivo volumio[6716]: info: [MyVolumio PluginManager] Plugin miscellanea/metavolumio is enabled for this plan, but could not be found on the local filesystem! Aug 25 17:01:04 motivo volumio[6716]: info: [MyVolumio PluginManager] Plugin miscellanea/manifestui is enabled for this plan, but could not be found on the local filesystem! Aug 25 17:01:04 motivo volumio[6716]: info: [MyVolumio PluginManager] Plugin music_service/cd_controller is enabled for this plan, but could not be found on the local filesystem! Aug 25 17:01:04 motivo volumio[6716]: info: [MyVolumio PluginManager] Plugin music_service/smart_inputs is enabled for this plan, but could not be found on the local filesystem! Aug 25 17:01:04 motivo volumio[6716]: info: [MyVolumio PluginManager] Plugin music_service/hi_res_audio is enabled for this plan, but could not be found on the local filesystem! Aug 25 17:01:04 motivo volumio[6716]: info: [MyVolumio PluginManager] Plugin music_service/tidal is enabled for this plan, but could not be found on the local filesystem! Aug 25 17:01:04 motivo volumio[6716]: info: [MyVolumio PluginManager] Plugin music_service/qobuz is enabled for this plan, but could not be found on the local filesystem! Aug 25 17:01:04 motivo volumio[6716]: info: [MyVolumio PluginManager] Plugin music_service/tidalconnect is enabled for this plan, but could not be found on the local filesystem! Aug 25 17:01:04 motivo volumio[6716]: info: [MyVolumio PluginManager] Plugin music_service/qobuzconnect is enabled for this plan, but could not be found on the local filesystem! Aug 25 17:01:04 motivo volumio[6716]: error: Cannot start Volumio Streaming Daemon Aug 25 17:01:04 motivo volumio[6716]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 25 17:01:04 motivo volumio[6716]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 25 17:01:04 motivo volumio[6716]: info: Setting Geolocation for MyVolumio to eu6 Aug 25 17:01:04 motivo volumio[6716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 25 17:01:04 motivo volumio[6716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 25 17:01:04 motivo volumio[6716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 25 17:01:05 motivo volumio[6716]: info: Successfully Added MyVolumio device Aug 25 17:01:05 motivo volumio[6716]: info: Updating MyVolumio device info Aug 25 17:01:05 motivo volumio[6716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 25 17:01:05 motivo volumio[6716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 25 17:01:05 motivo volumio[6716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 25 17:01:05 motivo volumio[6716]: info: Successfully Added MyVolumio device Aug 25 17:01:05 motivo volumio[6716]: info: Updating MyVolumio device info Aug 25 17:01:05 motivo volumio[6716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 25 17:01:05 motivo volumio[6716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 25 17:01:05 motivo volumio[6716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 25 17:01:06 motivo volumio[6716]: info: Successfully Updated MyVolumio device Aug 25 17:01:06 motivo volumio[6716]: info: Successfully Updated MyVolumio device Aug 25 17:01:07 motivo volumio[6716]: info: CoreCommandRouter::executeOnPlugin: musicserver , handleBrowseUri Aug 25 17:01:07 motivo volumio[6716]: info: Preload queue cleared Aug 25 17:01:08 motivo volumio[6716]: info: CoreCommandRouter::executeOnPlugin: musicserver , handleBrowseUri Aug 25 17:01:09 motivo volumio[6716]: info: Preload queue cleared Aug 25 17:01:09 motivo volumio[6716]: info: Preloading song: musicserver/filesystem-music-source://artists/ALL/93ecfc47-18d8-477b-8529-21bd2473fbac/08ef272b-de97-481f-bcfb-0b222f2bccfd/94e971ff-6988-42e2-bafb-eaecf9d2d7a1 Aug 25 17:01:09 motivo volumio[6716]: info: Preloading song: musicserver/filesystem-music-source://artists/ALL/93ecfc47-18d8-477b-8529-21bd2473fbac/08ef272b-de97-481f-bcfb-0b222f2bccfd/e185ec19-1269-45ef-8443-9818dcd4eaf9 Aug 25 17:01:09 motivo volumio[6716]: info: Preloading song: musicserver/filesystem-music-source://artists/ALL/93ecfc47-18d8-477b-8529-21bd2473fbac/08ef272b-de97-481f-bcfb-0b222f2bccfd/5a988c43-08b2-4b6a-8d21-b988953e1965 Aug 25 17:01:09 motivo volumio[6716]: info: Preloading song: musicserver/filesystem-music-source://artists/ALL/93ecfc47-18d8-477b-8529-21bd2473fbac/08ef272b-de97-481f-bcfb-0b222f2bccfd/8d68d10b-9666-4337-adbe-bfa0affc90c5 Aug 25 17:01:09 motivo volumio[6716]: info: Preloading song: musicserver/filesystem-music-source://artists/ALL/93ecfc47-18d8-477b-8529-21bd2473fbac/08ef272b-de97-481f-bcfb-0b222f2bccfd/e78fd033-eba3-4965-9ce6-97c7d34f7b9f Aug 25 17:01:09 motivo volumio[6716]: info: Preloading song: musicserver/filesystem-music-source://artists/ALL/93ecfc47-18d8-477b-8529-21bd2473fbac/08ef272b-de97-481f-bcfb-0b222f2bccfd/c4206080-a6af-4bf1-b086-f5c95c82b4df Aug 25 17:01:09 motivo volumio[6716]: info: Preloading song: musicserver/filesystem-music-source://artists/ALL/93ecfc47-18d8-477b-8529-21bd2473fbac/08ef272b-de97-481f-bcfb-0b222f2bccfd/fe428c92-5a53-422e-ac49-7f5ce49743db Aug 25 17:01:09 motivo volumio[6716]: info: Preloading song: musicserver/filesystem-music-source://artists/ALL/93ecfc47-18d8-477b-8529-21bd2473fbac/08ef272b-de97-481f-bcfb-0b222f2bccfd/aa047992-7a6f-4214-a980-084ad433e281 Aug 25 17:01:09 motivo volumio[6716]: info: Preloading song: musicserver/filesystem-music-source://artists/ALL/93ecfc47-18d8-477b-8529-21bd2473fbac/08ef272b-de97-481f-bcfb-0b222f2bccfd/612ec11e-dcad-453f-b1c0-22853cdde0aa Aug 25 17:01:09 motivo volumio[6716]: info: Preloading song: musicserver/filesystem-music-source://artists/ALL/93ecfc47-18d8-477b-8529-21bd2473fbac/08ef272b-de97-481f-bcfb-0b222f2bccfd/cca1b5b7-4d1f-4c79-992f-9d9df7f925e1 Aug 25 17:01:09 motivo volumio[6716]: info: Preloading song: musicserver/filesystem-music-source://artists/ALL/93ecfc47-18d8-477b-8529-21bd2473fbac/08ef272b-de97-481f-bcfb-0b222f2bccfd/05d871a5-30c4-4f10-b7cd-8688dee0e9ba Aug 25 17:01:09 motivo volumio[6716]: info: Exploding uri musicserver/filesystem-music-source://artists/ALL/93ecfc47-18d8-477b-8529-21bd2473fbac/08ef272b-de97-481f-bcfb-0b222f2bccfd/94e971ff-6988-42e2-bafb-eaecf9d2d7a1 in service musicserver Aug 25 17:01:09 motivo volumio[6716]: info: Exploding uri musicserver/filesystem-music-source://artists/ALL/93ecfc47-18d8-477b-8529-21bd2473fbac/08ef272b-de97-481f-bcfb-0b222f2bccfd/e185ec19-1269-45ef-8443-9818dcd4eaf9 in service musicserver Aug 25 17:01:09 motivo volumio[6716]: info: Exploding uri musicserver/filesystem-music-source://artists/ALL/93ecfc47-18d8-477b-8529-21bd2473fbac/08ef272b-de97-481f-bcfb-0b222f2bccfd/5a988c43-08b2-4b6a-8d21-b988953e1965 in service musicserver Aug 25 17:01:09 motivo volumio[6716]: info: Exploding uri musicserver/filesystem-music-source://artists/ALL/93ecfc47-18d8-477b-8529-21bd2473fbac/08ef272b-de97-481f-bcfb-0b222f2bccfd/8d68d10b-9666-4337-adbe-bfa0affc90c5 in service musicserver Aug 25 17:01:09 motivo volumio[6716]: info: Exploding uri musicserver/filesystem-music-source://artists/ALL/93ecfc47-18d8-477b-8529-21bd2473fbac/08ef272b-de97-481f-bcfb-0b222f2bccfd/e78fd033-eba3-4965-9ce6-97c7d34f7b9f in service musicserver Aug 25 17:01:09 motivo volumio[6716]: info: Exploding uri musicserver/filesystem-music-source://artists/ALL/93ecfc47-18d8-477b-8529-21bd2473fbac/08ef272b-de97-481f-bcfb-0b222f2bccfd/c4206080-a6af-4bf1-b086-f5c95c82b4df in service musicserver Aug 25 17:01:09 motivo volumio[6716]: info: Exploding uri musicserver/filesystem-music-source://artists/ALL/93ecfc47-18d8-477b-8529-21bd2473fbac/08ef272b-de97-481f-bcfb-0b222f2bccfd/fe428c92-5a53-422e-ac49-7f5ce49743db in service musicserver Aug 25 17:01:09 motivo volumio[6716]: info: Exploding uri musicserver/filesystem-music-source://artists/ALL/93ecfc47-18d8-477b-8529-21bd2473fbac/08ef272b-de97-481f-bcfb-0b222f2bccfd/aa047992-7a6f-4214-a980-084ad433e281 in service musicserver Aug 25 17:01:09 motivo sudo[7486]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Aug 25 17:01:09 motivo sudo[7486]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 25 17:01:09 motivo systemd[1]: Stopping sshtunnel.service - MyVolumio SSH Tunnel... Aug 25 17:01:09 motivo autossh[4910]: received signal to exit (15) Aug 25 17:01:09 motivo systemd[1]: sshtunnel.service: Deactivated successfully. Aug 25 17:01:09 motivo systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Aug 25 17:01:09 motivo volumio[6716]: info: Exploding uri musicserver/filesystem-music-source://artists/ALL/93ecfc47-18d8-477b-8529-21bd2473fbac/08ef272b-de97-481f-bcfb-0b222f2bccfd/612ec11e-dcad-453f-b1c0-22853cdde0aa in service musicserver Aug 25 17:01:09 motivo systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Aug 25 17:01:09 motivo sudo[7486]: pam_unix(sudo:session): session closed for user root Aug 25 17:01:09 motivo volumio[6716]: info: Remote SSH Started Aug 25 17:01:09 motivo autossh[7489]: port set to 0, monitoring disabled Aug 25 17:01:09 motivo autossh[7489]: starting ssh (count 1) Aug 25 17:01:09 motivo autossh[7489]: ssh child pid is 7492 Aug 25 17:01:09 motivo volumio[6716]: info: Exploding uri musicserver/filesystem-music-source://artists/ALL/93ecfc47-18d8-477b-8529-21bd2473fbac/08ef272b-de97-481f-bcfb-0b222f2bccfd/cca1b5b7-4d1f-4c79-992f-9d9df7f925e1 in service musicserver Aug 25 17:01:09 motivo volumio[6716]: info: Exploding uri musicserver/filesystem-music-source://artists/ALL/93ecfc47-18d8-477b-8529-21bd2473fbac/08ef272b-de97-481f-bcfb-0b222f2bccfd/05d871a5-30c4-4f10-b7cd-8688dee0e9ba in service musicserver Aug 25 17:01:09 motivo volumio[6716]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Aug 25 17:01:09 motivo volumio[6716]: info: CoreCommandRouter::volumioGetState Aug 25 17:01:09 motivo volumio[6716]: info: CorePlayQueue::getTrack 0 Aug 25 17:01:09 motivo volumiossh-tunnel[7492]: Warning: Permanently added '[eu6.myvolumio.org]:2222' (RSA) to the list of known hosts. Aug 25 17:01:11 motivo volumio[6716]: info: Preload queue cleared Aug 25 17:01:11 motivo volumio[6716]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 25 17:01:11 motivo volumio[6716]: info: CoreStateMachine::ClearQueue Aug 25 17:01:11 motivo volumio[6716]: info: CoreStateMachine::stop Aug 25 17:01:11 motivo volumio[6716]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 25 17:01:11 motivo volumio[6716]: info: CorePlayQueue::clearPlayQueue Aug 25 17:01:11 motivo volumio[6716]: info: CorePlayQueue::saveQueue Aug 25 17:01:11 motivo volumio[6716]: info: CoreCommandRouter::volumioPushQueue Aug 25 17:01:11 motivo volumio[6716]: info: CoreStateMachine::addQueueItems Aug 25 17:01:11 motivo volumio[6716]: info: CorePlayQueue::addQueueItems Aug 25 17:01:11 motivo volumio[6716]: info: Preload queue cleared Aug 25 17:01:11 motivo volumio[6716]: info: Adding Item to queue: musicserver/filesystem-music-source://artists/ALL/93ecfc47-18d8-477b-8529-21bd2473fbac/08ef272b-de97-481f-bcfb-0b222f2bccfd/94e971ff-6988-42e2-bafb-eaecf9d2d7a1 Aug 25 17:01:11 motivo volumio[6716]: info: Using cached record of: musicserver/filesystem-music-source://artists/ALL/93ecfc47-18d8-477b-8529-21bd2473fbac/08ef272b-de97-481f-bcfb-0b222f2bccfd/94e971ff-6988-42e2-bafb-eaecf9d2d7a1 Aug 25 17:01:11 motivo volumio[6716]: info: Adding Item to queue: musicserver/filesystem-music-source://artists/ALL/93ecfc47-18d8-477b-8529-21bd2473fbac/08ef272b-de97-481f-bcfb-0b222f2bccfd/e185ec19-1269-45ef-8443-9818dcd4eaf9 Aug 25 17:01:11 motivo volumio[6716]: info: Using cached record of: musicserver/filesystem-music-source://artists/ALL/93ecfc47-18d8-477b-8529-21bd2473fbac/08ef272b-de97-481f-bcfb-0b222f2bccfd/e185ec19-1269-45ef-8443-9818dcd4eaf9 Aug 25 17:01:11 motivo volumio[6716]: info: CoreCommandRouter::volumioPushQueue Aug 25 17:01:11 motivo volumio[6716]: info: CorePlayQueue::saveQueue Aug 25 17:01:11 motivo volumio[6716]: info: CoreStateMachine::updateTrackBlock Aug 25 17:01:11 motivo volumio[6716]: info: CorePlayQueue::getTrackBlock Aug 25 17:01:11 motivo volumio[6716]: info: CoreCommandRouter::volumioGetState Aug 25 17:01:11 motivo volumio[6716]: info: CorePlayQueue::getTrack 0 Aug 25 17:01:11 motivo volumio[6716]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Aug 25 17:01:11 motivo volumio[6716]: info: CoreCommandRouter::volumioPlay Aug 25 17:01:11 motivo volumio[6716]: info: CoreStateMachine::play index 1 Aug 25 17:01:11 motivo volumio[6716]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 25 17:01:11 motivo volumio[6716]: info: CoreStateMachine::addQueueItems Aug 25 17:01:11 motivo volumio[6716]: info: CorePlayQueue::addQueueItems Aug 25 17:01:11 motivo volumio[6716]: info: Preload queue cleared Aug 25 17:01:11 motivo volumio[6716]: info: Adding Item to queue: musicserver/filesystem-music-source://artists/ALL/93ecfc47-18d8-477b-8529-21bd2473fbac/08ef272b-de97-481f-bcfb-0b222f2bccfd/5a988c43-08b2-4b6a-8d21-b988953e1965 Aug 25 17:01:11 motivo volumio[6716]: info: Using cached record of: musicserver/filesystem-music-source://artists/ALL/93ecfc47-18d8-477b-8529-21bd2473fbac/08ef272b-de97-481f-bcfb-0b222f2bccfd/5a988c43-08b2-4b6a-8d21-b988953e1965 Aug 25 17:01:11 motivo volumio[6716]: info: Adding Item to queue: musicserver/filesystem-music-source://artists/ALL/93ecfc47-18d8-477b-8529-21bd2473fbac/08ef272b-de97-481f-bcfb-0b222f2bccfd/8d68d10b-9666-4337-adbe-bfa0affc90c5 Aug 25 17:01:11 motivo volumio[6716]: info: Using cached record of: musicserver/filesystem-music-source://artists/ALL/93ecfc47-18d8-477b-8529-21bd2473fbac/08ef272b-de97-481f-bcfb-0b222f2bccfd/8d68d10b-9666-4337-adbe-bfa0affc90c5 Aug 25 17:01:11 motivo volumio[6716]: info: Adding Item to queue: musicserver/filesystem-music-source://artists/ALL/93ecfc47-18d8-477b-8529-21bd2473fbac/08ef272b-de97-481f-bcfb-0b222f2bccfd/e78fd033-eba3-4965-9ce6-97c7d34f7b9f Aug 25 17:01:11 motivo volumio[6716]: info: Using cached record of: musicserver/filesystem-music-source://artists/ALL/93ecfc47-18d8-477b-8529-21bd2473fbac/08ef272b-de97-481f-bcfb-0b222f2bccfd/e78fd033-eba3-4965-9ce6-97c7d34f7b9f Aug 25 17:01:11 motivo volumio[6716]: info: Adding Item to queue: musicserver/filesystem-music-source://artists/ALL/93ecfc47-18d8-477b-8529-21bd2473fbac/08ef272b-de97-481f-bcfb-0b222f2bccfd/c4206080-a6af-4bf1-b086-f5c95c82b4df Aug 25 17:01:11 motivo volumio[6716]: info: Using cached record of: musicserver/filesystem-music-source://artists/ALL/93ecfc47-18d8-477b-8529-21bd2473fbac/08ef272b-de97-481f-bcfb-0b222f2bccfd/c4206080-a6af-4bf1-b086-f5c95c82b4df Aug 25 17:01:11 motivo volumio[6716]: info: Adding Item to queue: musicserver/filesystem-music-source://artists/ALL/93ecfc47-18d8-477b-8529-21bd2473fbac/08ef272b-de97-481f-bcfb-0b222f2bccfd/fe428c92-5a53-422e-ac49-7f5ce49743db Aug 25 17:01:11 motivo volumio[6716]: info: Using cached record of: musicserver/filesystem-music-source://artists/ALL/93ecfc47-18d8-477b-8529-21bd2473fbac/08ef272b-de97-481f-bcfb-0b222f2bccfd/fe428c92-5a53-422e-ac49-7f5ce49743db Aug 25 17:01:11 motivo volumio[6716]: info: Adding Item to queue: musicserver/filesystem-music-source://artists/ALL/93ecfc47-18d8-477b-8529-21bd2473fbac/08ef272b-de97-481f-bcfb-0b222f2bccfd/aa047992-7a6f-4214-a980-084ad433e281 Aug 25 17:01:11 motivo volumio[6716]: info: Using cached record of: musicserver/filesystem-music-source://artists/ALL/93ecfc47-18d8-477b-8529-21bd2473fbac/08ef272b-de97-481f-bcfb-0b222f2bccfd/aa047992-7a6f-4214-a980-084ad433e281 Aug 25 17:01:11 motivo volumio[6716]: info: Adding Item to queue: musicserver/filesystem-music-source://artists/ALL/93ecfc47-18d8-477b-8529-21bd2473fbac/08ef272b-de97-481f-bcfb-0b222f2bccfd/612ec11e-dcad-453f-b1c0-22853cdde0aa Aug 25 17:01:11 motivo volumio[6716]: info: Using cached record of: musicserver/filesystem-music-source://artists/ALL/93ecfc47-18d8-477b-8529-21bd2473fbac/08ef272b-de97-481f-bcfb-0b222f2bccfd/612ec11e-dcad-453f-b1c0-22853cdde0aa Aug 25 17:01:11 motivo volumio[6716]: info: Adding Item to queue: musicserver/filesystem-music-source://artists/ALL/93ecfc47-18d8-477b-8529-21bd2473fbac/08ef272b-de97-481f-bcfb-0b222f2bccfd/cca1b5b7-4d1f-4c79-992f-9d9df7f925e1 Aug 25 17:01:11 motivo volumio[6716]: info: Using cached record of: musicserver/filesystem-music-source://artists/ALL/93ecfc47-18d8-477b-8529-21bd2473fbac/08ef272b-de97-481f-bcfb-0b222f2bccfd/cca1b5b7-4d1f-4c79-992f-9d9df7f925e1 Aug 25 17:01:11 motivo volumio[6716]: info: Adding Item to queue: musicserver/filesystem-music-source://artists/ALL/93ecfc47-18d8-477b-8529-21bd2473fbac/08ef272b-de97-481f-bcfb-0b222f2bccfd/05d871a5-30c4-4f10-b7cd-8688dee0e9ba Aug 25 17:01:11 motivo volumio[6716]: info: Using cached record of: musicserver/filesystem-music-source://artists/ALL/93ecfc47-18d8-477b-8529-21bd2473fbac/08ef272b-de97-481f-bcfb-0b222f2bccfd/05d871a5-30c4-4f10-b7cd-8688dee0e9ba Aug 25 17:01:11 motivo volumio[6716]: info: CoreStateMachine::stop Aug 25 17:01:11 motivo volumio[6716]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 25 17:01:11 motivo volumio[6716]: info: CoreCommandRouter::volumioPushQueue Aug 25 17:01:11 motivo volumio[6716]: info: CorePlayQueue::saveQueue Aug 25 17:01:11 motivo volumio[6716]: info: CoreStateMachine::play index undefined Aug 25 17:01:11 motivo volumio[6716]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 25 17:01:11 motivo volumio[6716]: info: CoreStateMachine::updateTrackBlock Aug 25 17:01:11 motivo volumio[6716]: info: CorePlayQueue::getTrackBlock Aug 25 17:01:11 motivo volumio[6716]: info: CorePlayQueue::getTrack 1 Aug 25 17:01:11 motivo volumio[6716]: info: CoreStateMachine::startPlaybackTimer Aug 25 17:01:11 motivo volumio[6716]: info: CorePlayQueue::getTrack 1 Aug 25 17:01:11 motivo volumio[6716]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 25 17:01:11 motivo volumio[6716]: info: Using uri: http://localhost:10000/music/stream?id=filesystem-music-source://artists/ALL/93ecfc47-18d8-477b-8529-21bd2473fbac/08ef272b-de97-481f-bcfb-0b222f2bccfd/e185ec19-1269-45ef-8443-9818dcd4eaf9 Aug 25 17:01:11 motivo volumio[6716]: verbose: MPD COMMAND [object Object] Aug 25 17:01:11 motivo volumio[6716]: verbose: MPD COMMAND [object Object] Aug 25 17:01:11 motivo volumio[6716]: verbose: MPD COMMAND [object Object] Aug 25 17:01:11 motivo volumio[6716]: info: Aug 25 17:01:11 motivo volumio[6716]: ---------------------------- MPD announces system playlist update Aug 25 17:01:11 motivo volumio[6716]: info: Ignoring MPD Status Update Aug 25 17:01:11 motivo volumio[6716]: info: Aug 25 17:01:11 motivo volumio[6716]: ---------------------------- MPD announces system playlist update Aug 25 17:01:11 motivo volumio[6716]: info: Ignoring MPD Status Update Aug 25 17:01:11 motivo volumio[6716]: info: ------------------------------ 6ms Aug 25 17:01:11 motivo volumio[6716]: info: ------------------------------ 5ms Aug 25 17:01:11 motivo volumio[6716]: info: Aug 25 17:01:11 motivo volumio[6716]: ---------------------------- MPD announces state update: player Aug 25 17:01:11 motivo volumio[6716]: info: ControllerMpd::getState Aug 25 17:01:11 motivo volumio[6716]: verbose: ControllerMpd::sendMpdCommand status Aug 25 17:01:11 motivo volumio[6716]: info: Aug 25 17:01:11 motivo volumio[6716]: ---------------------------- MPD announces state update: player Aug 25 17:01:11 motivo volumio[6716]: info: ControllerMpd::getState Aug 25 17:01:11 motivo volumio[6716]: verbose: ControllerMpd::sendMpdCommand status Aug 25 17:01:11 motivo volumio[6716]: info: Aug 25 17:01:11 motivo volumio[6716]: ---------------------------- MPD announces state update: player Aug 25 17:01:11 motivo volumio[6716]: info: sendMpdCommand status took 25 milliseconds Aug 25 17:01:11 motivo volumio[6716]: info: ControllerMpd::getState Aug 25 17:01:11 motivo volumio[6716]: verbose: ControllerMpd::sendMpdCommand status Aug 25 17:01:11 motivo volumio[6716]: info: sendMpdCommand status took 24 milliseconds Aug 25 17:01:11 motivo volumio[6716]: verbose: ControllerMpd::parseState Aug 25 17:01:11 motivo volumio[6716]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 25 17:01:11 motivo volumio[6716]: verbose: ControllerMpd::parseState Aug 25 17:01:11 motivo volumio[6716]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 25 17:01:11 motivo volumio[6716]: info: Aug 25 17:01:11 motivo volumio[6716]: ---------------------------- MPD announces state update: player Aug 25 17:01:11 motivo volumio[6716]: info: ControllerMpd::getState Aug 25 17:01:11 motivo volumio[6716]: verbose: ControllerMpd::sendMpdCommand status Aug 25 17:01:11 motivo volumio[6716]: info: sendMpdCommand status took 9 milliseconds Aug 25 17:01:11 motivo volumio[6716]: info: sendMpdCommand playlistinfo took 8 milliseconds Aug 25 17:01:11 motivo volumio[6716]: info: sendMpdCommand playlistinfo took 8 milliseconds Aug 25 17:01:11 motivo volumio[6716]: info: sendMpdCommand status took 6 milliseconds Aug 25 17:01:11 motivo volumio[6716]: verbose: ControllerMpd::parseState Aug 25 17:01:11 motivo volumio[6716]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 25 17:01:11 motivo volumio[6716]: verbose: ControllerMpd::parseTrackInfo Aug 25 17:01:11 motivo volumio[6716]: verbose: ControllerMpd::parseTrackInfo Aug 25 17:01:11 motivo volumio[6716]: verbose: ControllerMpd::parseState Aug 25 17:01:11 motivo volumio[6716]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 25 17:01:11 motivo volumio[6716]: info: ControllerMpd::pushState Aug 25 17:01:11 motivo volumio[6716]: info: CoreCommandRouter::servicePushState Aug 25 17:01:11 motivo volumio[6716]: info: CorePlayQueue::getTrack 1 Aug 25 17:01:11 motivo volumio[6716]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":323,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"e185ec19-1269-45ef-8443-9818dcd4eaf9","artist":null,"album":null,"uri":"http://localhost:10000/music/stream?id=filesystem-music-source://artists/ALL/93ecfc47-18d8-477b-8529-21bd2473fbac/08ef272b-de97-481f-bcfb-0b222f2bccfd/e185ec19-1269-45ef-8443-9818dcd4eaf9","trackType":""} Aug 25 17:01:11 motivo volumio[6716]: verbose: CURRENT POSITION 1 Aug 25 17:01:11 motivo volumio[6716]: info: CoreStateMachine::syncState stateService play Aug 25 17:01:11 motivo volumio[6716]: info: CoreStateMachine::syncState currentStatus stop Aug 25 17:01:11 motivo volumio[6716]: info: ControllerMpd::pushState Aug 25 17:01:11 motivo volumio[6716]: info: CoreCommandRouter::servicePushState Aug 25 17:01:11 motivo volumio[6716]: info: CorePlayQueue::getTrack 1 Aug 25 17:01:11 motivo volumio[6716]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1214,"duration":323,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"e185ec19-1269-45ef-8443-9818dcd4eaf9","artist":null,"album":null,"uri":"http://localhost:10000/music/stream?id=filesystem-music-source://artists/ALL/93ecfc47-18d8-477b-8529-21bd2473fbac/08ef272b-de97-481f-bcfb-0b222f2bccfd/e185ec19-1269-45ef-8443-9818dcd4eaf9","trackType":""} Aug 25 17:01:11 motivo volumio[6716]: verbose: CURRENT POSITION 1 Aug 25 17:01:11 motivo volumio[6716]: info: CoreStateMachine::syncState stateService play Aug 25 17:01:11 motivo volumio[6716]: info: CoreStateMachine::syncState currentStatus play Aug 25 17:01:11 motivo volumio[6716]: info: Received an update from plugin. extracting info from payload Aug 25 17:01:11 motivo volumio[6716]: info: CoreStateMachine::pushState Aug 25 17:01:11 motivo volumio[6716]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 17:01:11 motivo volumio[6716]: info: CoreCommandRouter::volumioPushState Aug 25 17:01:11 motivo volumio[6716]: info: CoreCommandRouter::volumioGetState Aug 25 17:01:11 motivo volumio[6716]: info: MRS: Pushing multiroomSync output update for this device Aug 25 17:01:11 motivo volumio[6716]: info: MRS: Pushing multiroomSync output Aug 25 17:01:11 motivo volumio[6716]: info: CoreStateMachine::pushState Aug 25 17:01:11 motivo volumio[6716]: info: CoreCommandRouter::volumioPushState Aug 25 17:01:11 motivo volumio[6716]: info: CoreCommandRouter::volumioGetState Aug 25 17:01:11 motivo volumio[6716]: info: MRS: Pushing multiroomSync output update for this device Aug 25 17:01:11 motivo volumio[6716]: info: MRS: Pushing multiroomSync output Aug 25 17:01:11 motivo volumio[6716]: info: ------------------------------ 55ms Aug 25 17:01:11 motivo volumio5-onboarding[7278]: time=2026-08-25T17:01:11.467+02:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.1.243:52491,00:00:00:00:00:00%01 @ 0x258f2f0" state=STATUS_PLAYING positionMs=0 volume=29 Aug 25 17:01:11 motivo volumio5-onboarding[7278]: time=2026-08-25T17:01:11.468+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.1.243:52491,00:00:00:00:00:00%01 @ 0x258f2f0" id="http://localhost:10000/music/stream?id=filesystem-music-source://artists/ALL/93ecfc47-18d8-477b-8529-21bd2473fbac/08ef272b-de97-481f-bcfb-0b222f2bccfd/e185ec19-1269-45ef-8443-9818dcd4eaf9" title=Run Aug 25 17:01:11 motivo volumio5-onboarding[7278]: time=2026-08-25T17:01:11.470+02:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.1.243:52491,00:00:00:00:00:00%01 @ 0x258f2f0" state=STATUS_PLAYING positionMs=0 volume=29 Aug 25 17:01:11 motivo volumio5-onboarding[7278]: time=2026-08-25T17:01:11.471+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.1.243:52491,00:00:00:00:00:00%01 @ 0x258f2f0" id="http://localhost:10000/music/stream?id=filesystem-music-source://artists/ALL/93ecfc47-18d8-477b-8529-21bd2473fbac/08ef272b-de97-481f-bcfb-0b222f2bccfd/e185ec19-1269-45ef-8443-9818dcd4eaf9" title=Run Aug 25 17:01:11 motivo volumio[6716]: info: ------------------------------ 72ms Aug 25 17:01:11 motivo volumio[6716]: info: sendMpdCommand playlistinfo took 192 milliseconds Aug 25 17:01:11 motivo volumio[6716]: info: sendMpdCommand playlistinfo took 191 milliseconds Aug 25 17:01:11 motivo volumio[6716]: verbose: ControllerMpd::parseTrackInfo Aug 25 17:01:11 motivo volumio[6716]: verbose: ControllerMpd::parseTrackInfo Aug 25 17:01:11 motivo volumio[6716]: info: ControllerMpd::pushState Aug 25 17:01:11 motivo volumio[6716]: info: CoreCommandRouter::servicePushState Aug 25 17:01:11 motivo volumio[6716]: info: CorePlayQueue::getTrack 1 Aug 25 17:01:11 motivo volumio[6716]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1214,"duration":323,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"e185ec19-1269-45ef-8443-9818dcd4eaf9","artist":null,"album":null,"uri":"http://localhost:10000/music/stream?id=filesystem-music-source://artists/ALL/93ecfc47-18d8-477b-8529-21bd2473fbac/08ef272b-de97-481f-bcfb-0b222f2bccfd/e185ec19-1269-45ef-8443-9818dcd4eaf9","trackType":""} Aug 25 17:01:11 motivo volumio[6716]: verbose: CURRENT POSITION 1 Aug 25 17:01:11 motivo volumio[6716]: info: CoreStateMachine::syncState stateService play Aug 25 17:01:11 motivo volumio[6716]: info: CoreStateMachine::syncState currentStatus play Aug 25 17:01:11 motivo volumio[6716]: info: Received an update from plugin. extracting info from payload Aug 25 17:01:11 motivo volumio[6716]: info: CoreStateMachine::pushState Aug 25 17:01:11 motivo volumio[6716]: info: CoreCommandRouter::volumioPushState Aug 25 17:01:11 motivo volumio[6716]: info: CoreCommandRouter::volumioGetState Aug 25 17:01:11 motivo volumio[6716]: info: MRS: Pushing multiroomSync output update for this device Aug 25 17:01:11 motivo volumio[6716]: info: MRS: Pushing multiroomSync output Aug 25 17:01:11 motivo volumio[6716]: info: CoreStateMachine::pushState Aug 25 17:01:11 motivo volumio[6716]: info: CoreCommandRouter::volumioPushState Aug 25 17:01:11 motivo volumio[6716]: info: CoreCommandRouter::volumioGetState Aug 25 17:01:11 motivo volumio[6716]: info: MRS: Pushing multiroomSync output update for this device Aug 25 17:01:11 motivo volumio[6716]: info: MRS: Pushing multiroomSync output Aug 25 17:01:11 motivo volumio[6716]: info: ControllerMpd::pushState Aug 25 17:01:11 motivo volumio[6716]: info: CoreCommandRouter::servicePushState Aug 25 17:01:11 motivo volumio[6716]: info: CorePlayQueue::getTrack 1 Aug 25 17:01:11 motivo volumio[6716]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1214,"duration":323,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"e185ec19-1269-45ef-8443-9818dcd4eaf9","artist":null,"album":null,"uri":"http://localhost:10000/music/stream?id=filesystem-music-source://artists/ALL/93ecfc47-18d8-477b-8529-21bd2473fbac/08ef272b-de97-481f-bcfb-0b222f2bccfd/e185ec19-1269-45ef-8443-9818dcd4eaf9","trackType":""} Aug 25 17:01:11 motivo volumio[6716]: verbose: CURRENT POSITION 1 Aug 25 17:01:11 motivo volumio[6716]: info: CoreStateMachine::syncState stateService play Aug 25 17:01:11 motivo volumio[6716]: info: CoreStateMachine::syncState currentStatus play Aug 25 17:01:11 motivo volumio[6716]: info: Received an update from plugin. extracting info from payload Aug 25 17:01:11 motivo volumio[6716]: info: CoreStateMachine::pushState Aug 25 17:01:11 motivo volumio[6716]: info: CoreCommandRouter::volumioPushState Aug 25 17:01:11 motivo volumio[6716]: info: CoreCommandRouter::volumioGetState Aug 25 17:01:11 motivo volumio[6716]: info: MRS: Pushing multiroomSync output update for this device Aug 25 17:01:11 motivo volumio[6716]: info: MRS: Pushing multiroomSync output Aug 25 17:01:11 motivo volumio[6716]: info: CoreStateMachine::pushState Aug 25 17:01:11 motivo volumio[6716]: info: CoreCommandRouter::volumioPushState Aug 25 17:01:11 motivo volumio[6716]: info: CoreCommandRouter::volumioGetState Aug 25 17:01:11 motivo volumio[6716]: info: MRS: Pushing multiroomSync output update for this device Aug 25 17:01:11 motivo volumio[6716]: info: MRS: Pushing multiroomSync output Aug 25 17:01:11 motivo volumio5-onboarding[7278]: time=2026-08-25T17:01:11.695+02:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.1.243:52491,00:00:00:00:00:00%01 @ 0x258f2f0" state=STATUS_PLAYING positionMs=0 volume=29 Aug 25 17:01:11 motivo volumio5-onboarding[7278]: time=2026-08-25T17:01:11.695+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.1.243:52491,00:00:00:00:00:00%01 @ 0x258f2f0" id="http://localhost:10000/music/stream?id=filesystem-music-source://artists/ALL/93ecfc47-18d8-477b-8529-21bd2473fbac/08ef272b-de97-481f-bcfb-0b222f2bccfd/e185ec19-1269-45ef-8443-9818dcd4eaf9" title=Run Aug 25 17:01:11 motivo volumio5-onboarding[7278]: time=2026-08-25T17:01:11.700+02:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.1.243:52491,00:00:00:00:00:00%01 @ 0x258f2f0" state=STATUS_PLAYING positionMs=0 volume=29 Aug 25 17:01:11 motivo volumio5-onboarding[7278]: time=2026-08-25T17:01:11.701+02:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.1.243:52491,00:00:00:00:00:00%01 @ 0x258f2f0" state=STATUS_PLAYING positionMs=0 volume=29 Aug 25 17:01:11 motivo volumio5-onboarding[7278]: time=2026-08-25T17:01:11.702+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.1.243:52491,00:00:00:00:00:00%01 @ 0x258f2f0" id="http://localhost:10000/music/stream?id=filesystem-music-source://artists/ALL/93ecfc47-18d8-477b-8529-21bd2473fbac/08ef272b-de97-481f-bcfb-0b222f2bccfd/e185ec19-1269-45ef-8443-9818dcd4eaf9" title=Run Aug 25 17:01:11 motivo volumio5-onboarding[7278]: time=2026-08-25T17:01:11.703+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.1.243:52491,00:00:00:00:00:00%01 @ 0x258f2f0" id="http://localhost:10000/music/stream?id=filesystem-music-source://artists/ALL/93ecfc47-18d8-477b-8529-21bd2473fbac/08ef272b-de97-481f-bcfb-0b222f2bccfd/e185ec19-1269-45ef-8443-9818dcd4eaf9" title=Run Aug 25 17:01:11 motivo volumio5-onboarding[7278]: time=2026-08-25T17:01:11.704+02:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.1.243:52491,00:00:00:00:00:00%01 @ 0x258f2f0" state=STATUS_PLAYING positionMs=0 volume=29 Aug 25 17:01:11 motivo volumio5-onboarding[7278]: time=2026-08-25T17:01:11.704+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.1.243:52491,00:00:00:00:00:00%01 @ 0x258f2f0" id="http://localhost:10000/music/stream?id=filesystem-music-source://artists/ALL/93ecfc47-18d8-477b-8529-21bd2473fbac/08ef272b-de97-481f-bcfb-0b222f2bccfd/e185ec19-1269-45ef-8443-9818dcd4eaf9" title=Run Aug 25 17:01:11 motivo volumio[6716]: info: ------------------------------ 283ms Aug 25 17:01:11 motivo volumio[6716]: info: ------------------------------ 279ms Aug 25 17:01:11 motivo volumio[6716]: info: CoreCommandRouter::volumioGetQueue Aug 25 17:01:11 motivo volumio[6716]: info: CoreStateMachine::getQueue Aug 25 17:01:11 motivo volumio[6716]: info: CorePlayQueue::getQueue Aug 25 17:01:11 motivo volumio[6716]: info: Signalling Playback active due to playback status change Aug 25 17:01:11 motivo volumio[6716]: info: Signalling Playback active due to playback status change Aug 25 17:01:11 motivo volumio[6716]: info: Signalling Playback active due to playback status change Aug 25 17:01:11 motivo volumio[6716]: info: Signalling Playback active due to playback status change Aug 25 17:01:11 motivo volumio[6716]: info: Signalling Playback active due to playback status change Aug 25 17:01:11 motivo volumio[6716]: info: Signalling Playback active due to playback status change Aug 25 17:01:11 motivo volumio[6716]: info: Updating RAAT Signal Path Aug 25 17:01:11 motivo volumio[6716]: info: Updating RAAT Signal Path Aug 25 17:01:11 motivo volumio[6716]: info: Updating RAAT Signal Path Aug 25 17:01:11 motivo volumio[6716]: info: Updating RAAT Signal Path Aug 25 17:01:11 motivo volumio[6716]: info: Updating RAAT Signal Path Aug 25 17:01:11 motivo volumio[6716]: info: Updating RAAT Signal Path Aug 25 17:01:12 motivo volumio[6716]: info: MCU Signalled Playback Active Aug 25 17:01:12 motivo volumio[6716]: info: Executing endpoint metavolumio Aug 25 17:01:12 motivo volumio[6716]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 25 17:01:12 motivo volumio[6716]: info: Executing endpoint metavolumio Aug 25 17:01:12 motivo volumio[6716]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 25 17:01:12 motivo volumio[6716]: info: Executing endpoint metavolumio Aug 25 17:01:12 motivo volumio[6716]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 25 17:01:12 motivo volumio[6716]: info: Executing endpoint metavolumio Aug 25 17:01:12 motivo volumio[6716]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 25 17:01:13 motivo volumio[6716]: error: MyVolumio Plugin failed to start in a timely fashion Aug 25 17:01:13 motivo volumio[6716]: [Metrics] CommandRouter: 48s 969.77ms Aug 25 17:01:13 motivo volumio[6716]: info: CoreCommandRouter::volumiosetStartupVolume Aug 25 17:01:13 motivo volumio[6716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 17:01:13 motivo volumio[6716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 25 17:01:13 motivo volumio[6716]: info: CoreCommandRouter::Close All Modals sent Aug 25 17:01:13 motivo volumio[6716]: info: CoreCommandRouter::Close All Modals sent Aug 25 17:01:13 motivo volumio[6716]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Aug 25 17:01:13 motivo volumio[6716]: ALSA lib ./src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Aug 25 17:01:13 motivo volumio[6716]: aplay: main:831: audio open error: Device or resource busy Aug 25 17:01:14 motivo volumio[6716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Aug 25 17:01:14 motivo volumio[6716]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 25 17:01:14 motivo volumio[6716]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Aug 25 17:01:15 motivo volumio[6716]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 25 17:01:15 motivo volumio[6716]: info: Executing endpoint metavolumio Aug 25 17:01:15 motivo volumio[6716]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 25 17:01:15 motivo volumio[6716]: info: Executing endpoint metavolumio Aug 25 17:01:15 motivo volumio[6716]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 25 17:01:15 motivo volumio[6716]: info: Executing endpoint metavolumio Aug 25 17:01:15 motivo volumio[6716]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 25 17:01:15 motivo volumio[6716]: info: Executing endpoint metavolumio Aug 25 17:01:15 motivo volumio[6716]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 25 17:01:17 motivo volumio[6716]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 25 17:01:17 motivo volumio[6716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 25 17:01:17 motivo volumio[6716]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 25 17:01:17 motivo volumio-remote-updater[820]: Test mode disabled Aug 25 17:01:17 motivo volumio-remote-updater[820]: Alpha mode enabled Aug 25 17:01:17 motivo volumio-remote-updater[820]: Alpha legacy test mode disabled Aug 25 17:01:17 motivo volumio[6716]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Aug 25 17:01:17 motivo volumio[6716]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Aug 25 17:01:17 motivo volumio[6716]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Aug 25 17:01:17 motivo volumio[6716]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 25 17:01:17 motivo volumio[6716]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Aug 25 17:01:17 motivo volumio[6716]: info: CoreCommandRouter::volumioGetState Aug 25 17:01:19 motivo volumio[6716]: info: CoreCommandRouter::volumioGetState Aug 25 17:01:20 motivo volumio[6716]: info: BOOT COMPLETED Aug 25 17:01:21 motivo volumio[6716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 17:01:21 motivo volumio[6716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 17:01:21 motivo volumio[6716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 17:01:21 motivo volumio[6716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 17:01:21 motivo volumio[6716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 17:01:21 motivo volumio[6716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 17:01:21 motivo volumio[6716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 17:01:21 motivo volumio[6716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 17:01:21 motivo volumio[6716]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 25 17:01:21 motivo volumio[6716]: info: Not Reporting Auto name since its the default one Aug 25 17:01:21 motivo volumio[6716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Aug 25 17:01:21 motivo sudo[7567]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Aug 25 17:01:21 motivo sudo[7567]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 25 17:01:21 motivo sudo[7567]: pam_unix(sudo:session): session closed for user root Aug 25 17:01:21 motivo sudo[7569]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Aug 25 17:01:21 motivo sudo[7569]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 25 17:01:21 motivo systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Aug 25 17:01:21 motivo systemd[1]: raat-daemon.service: Deactivated successfully. Aug 25 17:01:21 motivo systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Aug 25 17:01:21 motivo systemd[1]: Started raat-daemon.service - RAAT DAEMON. Aug 25 17:01:21 motivo sudo[7569]: pam_unix(sudo:session): session closed for user root Aug 25 17:01:21 motivo volumio[6716]: info: Raat Daemon started successfully Aug 25 17:01:21 motivo volumio[6716]: info: Executing endpoint restartRAATSocket Aug 25 17:01:21 motivo volumio[6716]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Aug 25 17:01:21 motivo volumio[6716]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Aug 25 17:01:22 motivo volumio[6716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 17:01:22 motivo volumio[6716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 17:01:22 motivo volumio[6716]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Aug 25 17:01:22 motivo volumio[6716]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Aug 25 17:01:22 motivo volumio[6716]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Aug 25 17:01:22 motivo volumio[6716]: info: CoreCommandRouter::volumioGetBrowseSources Aug 25 17:01:22 motivo volumio[6716]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 25 17:01:23 motivo volumio[6716]: info: CoreCommandRouter::volumioGetState Aug 25 17:01:24 motivo volumio[6716]: info: RAAT: Requesting Headphone Status Aug 25 17:01:24 motivo volumio[6716]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Aug 25 17:01:24 motivo volumio[6716]: info: MCU Signalled Headphone Mode Disabled Aug 25 17:01:24 motivo volumio[6716]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Aug 25 17:01:24 motivo volumio[6716]: info: Reporting Headphone State: false Aug 25 17:01:24 motivo volumio[6716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 17:01:24 motivo volumio[6716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 17:01:24 motivo volumio[6716]: info: Updating RAAT Signal Path Aug 25 17:01:27 motivo volumio[6716]: info: CoreCommandRouter::volumioGetState Aug 25 17:01:31 motivo volumio[6716]: info: CoreCommandRouter::volumioGetState Aug 25 17:01:35 motivo volumio[6716]: info: CoreCommandRouter::volumioGetState Aug 25 17:01:39 motivo volumio[6716]: info: CoreCommandRouter::volumioGetState Aug 25 17:01:43 motivo volumio5-onboarding[7278]: time=2026-08-25T17:01:43.060+02:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="10.0.1.243:52491,00:00:00:00:00:00%01 @ 0x258f2f0" latency=94.612151ms timeout=10s Aug 25 17:01:43 motivo volumio5-onboarding[7278]: time=2026-08-25T17:01:43.098+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="10.0.1.243:52491,00:00:00:00:00:00%01 @ 0x258f2f0" latency=94.612151ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=37.107648ms Aug 25 17:01:43 motivo volumio5-onboarding[7278]: time=2026-08-25T17:01:43.104+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="10.0.1.243:52491,00:00:00:00:00:00%01 @ 0x258f2f0" latency=94.612151ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=41.12081ms Aug 25 17:01:43 motivo volumio5-onboarding[7278]: time=2026-08-25T17:01:43.111+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="10.0.1.243:52491,00:00:00:00:00:00%01 @ 0x258f2f0" latency=94.612151ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=50.334601ms Aug 25 17:01:43 motivo volumio5-onboarding[7278]: time=2026-08-25T17:01:43.113+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="10.0.1.243:52491,00:00:00:00:00:00%01 @ 0x258f2f0" latency=94.612151ms timeout=10s endpoint=http://pushupdates.volumio.org duration=50.019457ms Aug 25 17:01:43 motivo volumio5-onboarding[7278]: time=2026-08-25T17:01:43.194+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="10.0.1.243:52491,00:00:00:00:00:00%01 @ 0x258f2f0" latency=94.612151ms timeout=10s endpoint=https://www.googleapis.com duration=131.967931ms Aug 25 17:01:43 motivo volumio5-onboarding[7278]: time=2026-08-25T17:01:43.213+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="10.0.1.243:52491,00:00:00:00:00:00%01 @ 0x258f2f0" latency=94.612151ms timeout=10s endpoint=https://securetoken.googleapis.com duration=150.816345ms Aug 25 17:01:43 motivo volumio5-onboarding[7278]: time=2026-08-25T17:01:43.220+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="10.0.1.243:52491,00:00:00:00:00:00%01 @ 0x258f2f0" latency=94.612151ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=157.168611ms Aug 25 17:01:43 motivo volumio5-onboarding[7278]: time=2026-08-25T17:01:43.323+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="10.0.1.243:52491,00:00:00:00:00:00%01 @ 0x258f2f0" latency=94.612151ms timeout=10s endpoint=https://database.volumio.cloud duration=259.313319ms Aug 25 17:01:43 motivo volumio5-onboarding[7278]: time=2026-08-25T17:01:43.325+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="10.0.1.243:52491,00:00:00:00:00:00%01 @ 0x258f2f0" latency=94.612151ms timeout=10s endpoint=https://functions.volumio.cloud duration=263.615848ms Aug 25 17:01:43 motivo volumio5-onboarding[7278]: time=2026-08-25T17:01:43.334+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="10.0.1.243:52491,00:00:00:00:00:00%01 @ 0x258f2f0" latency=94.612151ms timeout=10s endpoint=https://functions.volumio.cloud duration=270.890235ms Aug 25 17:01:43 motivo volumio5-onboarding[7278]: time=2026-08-25T17:01:43.446+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="10.0.1.243:52491,00:00:00:00:00:00%01 @ 0x258f2f0" latency=94.612151ms timeout=10s endpoint=https://google.com duration=384.34923ms Aug 25 17:01:43 motivo volumio5-onboarding[7278]: time=2026-08-25T17:01:43.512+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="10.0.1.243:52491,00:00:00:00:00:00%01 @ 0x258f2f0" latency=94.612151ms timeout=10s endpoint=http://cddb.volumio.org duration=448.827924ms Aug 25 17:01:43 motivo volumio[6716]: info: CoreCommandRouter::volumioGetState Aug 25 17:01:43 motivo volumio5-onboarding[7278]: time=2026-08-25T17:01:43.802+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="10.0.1.243:52491,00:00:00:00:00:00%01 @ 0x258f2f0" latency=94.612151ms timeout=10s endpoint=http://plugins.volumio.org duration=739.453285ms Aug 25 17:01:45 motivo volumio[6716]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 25 17:01:45 motivo volumio[6716]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 25 17:01:45 motivo volumio[6716]: info: Discovery: Getting this device information Aug 25 17:01:45 motivo volumio[6716]: info: CoreCommandRouter::volumioGetState Aug 25 17:01:45 motivo volumio[6716]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 25 17:01:45 motivo volumio[6716]: verbose: New Socket.io Connection to 10.0.1.13:3000 from 10.0.1.243 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 12 Aug 25 17:01:45 motivo volumio[6716]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 25 17:01:45 motivo volumio[6716]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 25 17:01:51 motivo volumio[6716]: info: CoreCommandRouter::volumioGetState Aug 25 17:01:59 motivo volumio[6716]: info: CoreCommandRouter::volumioGetState Aug 25 17:02:02 motivo systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Aug 25 17:02:03 motivo systemd[1]: setdatetime-helper.service: Deactivated successfully. Aug 25 17:02:03 motivo systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Aug 25 17:02:07 motivo volumio[6716]: info: CoreCommandRouter::volumioGetState Aug 25 17:02:15 motivo volumio[6716]: info: CoreCommandRouter::volumioGetState Aug 25 17:02:23 motivo volumio[6716]: info: CoreCommandRouter::volumioGetState Aug 25 17:02:28 motivo volumio[6716]: info: CoreCommandRouter::volumioPause Aug 25 17:02:28 motivo volumio[6716]: info: CoreStateMachine::pause Aug 25 17:02:28 motivo volumio[6716]: info: CoreStateMachine::stPlaybackTimer Aug 25 17:02:28 motivo volumio[6716]: info: CoreStateMachine::servicePause Aug 25 17:02:28 motivo volumio[6716]: info: CorePlayQueue::getTrack 1 Aug 25 17:02:28 motivo volumio[6716]: info: CoreCommandRouter::servicePause Aug 25 17:02:28 motivo volumio[6716]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 25 17:02:28 motivo volumio[6716]: TypeError: this[#playback].pause is not a function Aug 25 17:02:28 motivo volumio[6716]: at MusicServerPlugin.pause (/data/plugins/music_service/musicserver/index.js:183:27) Aug 25 17:02:28 motivo volumio[6716]: at CoreCommandRouter.servicePause (/volumio/app/index.js:387:23) Aug 25 17:02:28 motivo volumio[6716]: at CoreStateMachine.servicePause (/volumio/app/statemachine.js:1222:33) Aug 25 17:02:28 motivo volumio[6716]: at CoreStateMachine.pause (/volumio/app/statemachine.js:1201:17) Aug 25 17:02:28 motivo volumio[6716]: at CoreCommandRouter.volumioPause (/volumio/app/index.js:85:28) Aug 25 17:02:28 motivo volumio[6716]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:254:33) Aug 25 17:02:28 motivo volumio[6716]: at Socket.emit (node:events:514:28) Aug 25 17:02:28 motivo volumio[6716]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Aug 25 17:02:28 motivo volumio[6716]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Aug 25 17:02:28 motivo volumio[6716]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 25 17:02:28 motivo sudo[7868]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-25 17:01' Aug 25 17:02:28 motivo sudo[7868]: 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="ad2b65e62ee66106fc9799a5d56d019303babfad" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="9938d7179e3b7c4e41f3e2d60c255985cff08fee" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="motivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Aug 25 09:07:59 UTC 2026" VOLUMIO_VERSION="4.194" VOLUMIO_HARDWARE="cm4" VOLUMIO_DEVICENAME="CM4" VOLUMIO_VENDOR_MODEL="Volumio Motivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Motivo" VOLUMIO_HASH="9d3d545f2de2f9b52cbca802b20b81c2"