Mar 29 21:16:00 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:16:00 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:16:00 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 21:16:00 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:16:00 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:16:00 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:16:00 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:16:00 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:16:00 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:16:00 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:16:00 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:16:00 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:16:00 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:16:00 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:16:00 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:16:00 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:16:00 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:16:00 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:16:00 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:16:00 volumio volumio5-onboarding[1756]: time=2026-03-29T13:16:00.423Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=92000 volume=100 Mar 29 21:16:00 volumio volumio5-onboarding[1756]: time=2026-03-29T13:16:00.424Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title="Future Soul" Mar 29 21:16:00 volumio volumio5-onboarding[1756]: time=2026-03-29T13:16:00.427Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=92000 volume=100 Mar 29 21:16:00 volumio volumio5-onboarding[1756]: time=2026-03-29T13:16:00.427Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title="Future Soul" Mar 29 21:16:00 volumio volumio5-onboarding[1756]: time=2026-03-29T13:16:00.430Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=92000 volume=100 Mar 29 21:16:00 volumio volumio5-onboarding[1756]: time=2026-03-29T13:16:00.431Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title="Future Soul" Mar 29 21:16:00 volumio volumio5-onboarding[1756]: time=2026-03-29T13:16:00.432Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=92000 volume=100 Mar 29 21:16:00 volumio volumio5-onboarding[1756]: time=2026-03-29T13:16:00.432Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title="Future Soul" Mar 29 21:16:00 volumio volumio5-onboarding[1756]: time=2026-03-29T13:16:00.435Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=92000 volume=100 Mar 29 21:16:00 volumio volumio5-onboarding[1756]: time=2026-03-29T13:16:00.435Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title="Future Soul" Mar 29 21:16:00 volumio volumio5-onboarding[1756]: time=2026-03-29T13:16:00.437Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=92000 volume=100 Mar 29 21:16:00 volumio volumio5-onboarding[1756]: time=2026-03-29T13:16:00.438Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title="Future Soul" Mar 29 21:16:00 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:16:00 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:16:00 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 21:16:00 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:16:00 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:16:00 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:16:00 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:16:00 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:16:00 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:16:00 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:16:00 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:16:00 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:16:00 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:16:00 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:16:00 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:16:00 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:16:00 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:16:00 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:16:00 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:16:00 volumio volumio5-onboarding[1756]: time=2026-03-29T13:16:00.483Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PAUSED positionMs=92000 volume=100 Mar 29 21:16:00 volumio volumio5-onboarding[1756]: time=2026-03-29T13:16:00.483Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title="Future Soul" Mar 29 21:16:00 volumio volumio5-onboarding[1756]: time=2026-03-29T13:16:00.485Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PAUSED positionMs=92000 volume=100 Mar 29 21:16:00 volumio volumio5-onboarding[1756]: time=2026-03-29T13:16:00.486Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title="Future Soul" Mar 29 21:16:00 volumio volumio5-onboarding[1756]: time=2026-03-29T13:16:00.491Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PAUSED positionMs=92000 volume=100 Mar 29 21:16:00 volumio volumio5-onboarding[1756]: time=2026-03-29T13:16:00.491Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title="Future Soul" Mar 29 21:16:00 volumio volumio5-onboarding[1756]: time=2026-03-29T13:16:00.493Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PAUSED positionMs=92000 volume=100 Mar 29 21:16:00 volumio volumio5-onboarding[1756]: time=2026-03-29T13:16:00.493Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title="Future Soul" Mar 29 21:16:00 volumio volumio5-onboarding[1756]: time=2026-03-29T13:16:00.495Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PAUSED positionMs=92000 volume=100 Mar 29 21:16:00 volumio volumio5-onboarding[1756]: time=2026-03-29T13:16:00.496Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title="Future Soul" Mar 29 21:16:00 volumio volumio5-onboarding[1756]: time=2026-03-29T13:16:00.501Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PAUSED positionMs=92000 volume=100 Mar 29 21:16:00 volumio volumio5-onboarding[1756]: time=2026-03-29T13:16:00.501Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title="Future Soul" Mar 29 21:16:00 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:16:00 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:16:00 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:16:00 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:16:00 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:16:00 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:16:00 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:16:00 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:16:00 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:16:00 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:16:00 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:16:00 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:16:00 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:16:00 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:16:00 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:16:00 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:16:00 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:16:00 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:16:00 volumio volumio5-onboarding[1756]: time=2026-03-29T13:16:00.580Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PAUSED positionMs=92000 volume=100 Mar 29 21:16:00 volumio volumio5-onboarding[1756]: time=2026-03-29T13:16:00.581Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title="Future Soul" Mar 29 21:16:00 volumio volumio5-onboarding[1756]: time=2026-03-29T13:16:00.584Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PAUSED positionMs=92000 volume=100 Mar 29 21:16:00 volumio volumio5-onboarding[1756]: time=2026-03-29T13:16:00.585Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title="Future Soul" Mar 29 21:16:00 volumio volumio5-onboarding[1756]: time=2026-03-29T13:16:00.589Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PAUSED positionMs=92000 volume=100 Mar 29 21:16:00 volumio volumio5-onboarding[1756]: time=2026-03-29T13:16:00.590Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title="Future Soul" Mar 29 21:16:00 volumio volumio5-onboarding[1756]: time=2026-03-29T13:16:00.591Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PAUSED positionMs=92000 volume=100 Mar 29 21:16:00 volumio volumio5-onboarding[1756]: time=2026-03-29T13:16:00.592Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title="Future Soul" Mar 29 21:16:00 volumio volumio5-onboarding[1756]: time=2026-03-29T13:16:00.595Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PAUSED positionMs=92000 volume=100 Mar 29 21:16:00 volumio volumio5-onboarding[1756]: time=2026-03-29T13:16:00.595Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title="Future Soul" Mar 29 21:16:00 volumio volumio5-onboarding[1756]: time=2026-03-29T13:16:00.599Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PAUSED positionMs=92000 volume=100 Mar 29 21:16:00 volumio volumio5-onboarding[1756]: time=2026-03-29T13:16:00.599Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title="Future Soul" Mar 29 21:16:01 volumio kernel: hwmon hwmon1: Undervoltage detected! Mar 29 21:16:01 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:16:01 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:16:01 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 21:16:01 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:16:01 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:16:01 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:16:01 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:16:01 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:16:01 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:16:01 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:16:01 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:16:01 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:16:01 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:16:01 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:16:01 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:16:01 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:16:01 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:16:01 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:16:01 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:16:01 volumio volumio5-onboarding[1756]: time=2026-03-29T13:16:01.720Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PAUSED positionMs=0 volume=100 Mar 29 21:16:01 volumio volumio5-onboarding[1756]: time=2026-03-29T13:16:01.721Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title= Mar 29 21:16:01 volumio volumio5-onboarding[1756]: time=2026-03-29T13:16:01.724Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PAUSED positionMs=0 volume=100 Mar 29 21:16:01 volumio volumio5-onboarding[1756]: time=2026-03-29T13:16:01.725Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title= Mar 29 21:16:01 volumio volumio5-onboarding[1756]: time=2026-03-29T13:16:01.729Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PAUSED positionMs=0 volume=100 Mar 29 21:16:01 volumio volumio5-onboarding[1756]: time=2026-03-29T13:16:01.730Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title= Mar 29 21:16:01 volumio volumio5-onboarding[1756]: time=2026-03-29T13:16:01.733Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PAUSED positionMs=0 volume=100 Mar 29 21:16:01 volumio volumio5-onboarding[1756]: time=2026-03-29T13:16:01.735Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title= Mar 29 21:16:01 volumio volumio5-onboarding[1756]: time=2026-03-29T13:16:01.737Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PAUSED positionMs=0 volume=100 Mar 29 21:16:01 volumio volumio5-onboarding[1756]: time=2026-03-29T13:16:01.738Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title= Mar 29 21:16:01 volumio volumio5-onboarding[1756]: time=2026-03-29T13:16:01.741Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PAUSED positionMs=0 volume=100 Mar 29 21:16:01 volumio volumio5-onboarding[1756]: time=2026-03-29T13:16:01.743Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title= Mar 29 21:16:01 volumio shairport-sync[7350]: {"time":1774790074115,"response":"startAirplayPlayback Success"} Mar 29 21:16:01 volumio shairport-sync[7484]: % Total % Received % Xferd Average Speed Time Time Time Current Mar 29 21:16:01 volumio shairport-sync[7484]: Dload Upload Total Spent Left Speed Mar 29 21:16:01 volumio volumio[1126]: info: Mar 29 21:16:01 volumio volumio[1126]: ---------------------------- Client requests Stop Airplay Playback Mar 29 21:16:01 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , stopAirplayPlayback Mar 29 21:16:01 volumio volumio[1126]: info: Airplay playback stop Mar 29 21:16:01 volumio volumio[1126]: info: Airplay Playback Stopped, clearing state Mar 29 21:16:01 volumio volumio[1126]: info: CoreStateMachine::resetVolumioState Mar 29 21:16:01 volumio volumio[1126]: info: CoreStateMachine::getcurrentVolume Mar 29 21:16:01 volumio volumio[1126]: info: CoreCommandRouter::volumioRetrievevolume Mar 29 21:16:01 volumio shairport-sync[7484]: [158B blob data] Mar 29 21:16:01 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:16:01 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:16:01 volumio volumio5-onboarding[1756]: time=2026-03-29T13:16:01.857Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PAUSED positionMs=0 volume=100 Mar 29 21:16:01 volumio volumio5-onboarding[1756]: time=2026-03-29T13:16:01.858Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title= Mar 29 21:16:07 volumio kernel: hwmon hwmon1: Voltage normalised Mar 29 21:16:11 volumio shairport-sync[7484]: {"time":1774790161832,"response":"stopAirplayPlayback Success"} Mar 29 21:16:11 volumio shairport-sync[7501]: % Total % Received % Xferd Average Speed Time Time Time Current Mar 29 21:16:11 volumio shairport-sync[7501]: Dload Upload Total Spent Left Speed Mar 29 21:16:11 volumio volumio[1126]: info: Mar 29 21:16:11 volumio volumio[1126]: ---------------------------- Client requests AirplayInactive Mar 29 21:16:11 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , setAirplayInctive Mar 29 21:16:11 volumio shairport-sync[7501]: [158B blob data] Mar 29 21:16:13 volumio kernel: hwmon hwmon1: Undervoltage detected! Mar 29 21:16:17 volumio kernel: hwmon hwmon1: Voltage normalised Mar 29 21:16:24 volumio kernel: hwmon hwmon1: Undervoltage detected! Mar 29 21:16:30 volumio kernel: hwmon hwmon1: Voltage normalised Mar 29 21:16:34 volumio kernel: hwmon hwmon1: Undervoltage detected! Mar 29 21:16:38 volumio kernel: hwmon hwmon1: Voltage normalised Mar 29 21:16:44 volumio kernel: hwmon hwmon1: Undervoltage detected! Mar 29 21:16:49 volumio kernel: hwmon hwmon1: Voltage normalised Mar 29 21:16:55 volumio kernel: hwmon hwmon1: Undervoltage detected! Mar 29 21:16:59 volumio kernel: hwmon hwmon1: Voltage normalised Mar 29 21:17:00 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 21:17:00 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 21:17:00 volumio volumio[1126]: info: Discovery: Getting this device information Mar 29 21:17:00 volumio volumio[1126]: info: CoreCommandRouter::volumioGetState Mar 29 21:17:00 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 21:17:00 volumio volumio[1126]: verbose: New Socket.io Connection to 192.168.0.139:3000 from 192.168.0.137 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Mar 29 21:17:00 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 29 21:17:00 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 29 21:17:00 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:00.315Z level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.0.137:59891 Mar 29 21:17:03 volumio kernel: hwmon hwmon1: Undervoltage detected! Mar 29 21:17:04 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:04.169Z level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.0.137:59891 @ 0x18ac240" latency=177.493147ms platform=PLATFORM_IOS version=5.260209.0 Mar 29 21:17:04 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:04.170Z level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.0.137:59891 @ 0x18ac240" latency=176.272019ms timeout=10s Mar 29 21:17:04 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:04.170Z level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" Mar 29 21:17:04 volumio volumio[1126]: info: Received Get System Info Mar 29 21:17:04 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 21:17:04 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 21:17:04 volumio volumio[1126]: info: Discovery: Getting this device information Mar 29 21:17:04 volumio volumio[1126]: info: CoreCommandRouter::volumioGetState Mar 29 21:17:04 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 21:17:04 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:04.180Z level=INFO msg="emitting device name changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" name=Volumio Mar 29 21:17:04 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:04.182Z level=INFO msg="emitting device language changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" language=zh Mar 29 21:17:04 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Mar 29 21:17:04 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:04.189Z level=INFO msg="emitting device timezone changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" timezone=Asia/Shanghai Mar 29 21:17:04 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:04.192Z level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" available=true connected=false macAddress= ip4Address= ip6Address= Mar 29 21:17:04 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:04.198Z level=INFO msg="emitting wifi info changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" available=true connected=true macAddress=b8:27:eb:a2:71:d3 ip4Address=192.168.0.139/24 ip6Address= ssid=OpenFi_2G Mar 29 21:17:04 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:04.199Z level=INFO msg="emitting device setup status changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" setupComplete=true Mar 29 21:17:04 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Mar 29 21:17:04 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 29 21:17:04 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 21:17:04 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 29 21:17:04 volumio volumio[1126]: amixer -c 0 info | grep "bcm2835 ALSA" Mar 29 21:17:04 volumio volumio[1126]: amixer -c 1 info | grep "bcm2835 Headphones" Mar 29 21:17:04 volumio volumio[1126]: Card sysdefault:1 'Headphones'/'bcm2835 Headphones' Mar 29 21:17:04 volumio volumio[1126]: amixer -c 3 info | grep "snd_rpi_hifiberry_dac" Mar 29 21:17:04 volumio volumio[1126]: Card sysdefault:3 'sndrpihifiberry'/'snd_rpi_hifiberry_dac' Mar 29 21:17:04 volumio volumio[1126]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 2 Mar 29 21:17:04 volumio volumio[1126]: /bin/sh: 1: /usr/local/bin/alsacap: not found Mar 29 21:17:04 volumio volumio[1126]: {"cmd":"/usr/local/bin/alsacap -C 2","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 2\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Mar 29 21:17:04 volumio volumio[1126]: amixer -c 2 info | grep "vc4-hdmi" Mar 29 21:17:04 volumio volumio[1126]: Card sysdefault:2 'vc4hdmi'/'vc4-hdmi' Mar 29 21:17:04 volumio volumio[1126]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 3 Mar 29 21:17:04 volumio volumio[1126]: /bin/sh: 1: /usr/local/bin/alsacap: not found Mar 29 21:17:04 volumio volumio[1126]: {"cmd":"/usr/local/bin/alsacap -C 3","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 3\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Mar 29 21:17:04 volumio volumio[1126]: amixer -c 3 info | grep "HiFiBerry DAC" Mar 29 21:17:04 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:04.592Z level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" selectedOutputId=3 Mar 29 21:17:04 volumio volumio[1126]: info: Received Get System Info Mar 29 21:17:04 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 21:17:04 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 21:17:04 volumio volumio[1126]: info: Discovery: Getting this device information Mar 29 21:17:04 volumio volumio[1126]: info: CoreCommandRouter::volumioGetState Mar 29 21:17:04 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 21:17:04 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:04.647Z level=INFO msg="emitting software info changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" currentVersion=4.103 latestVersion=4.103 Mar 29 21:17:04 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:04.648Z level=INFO msg="emitting software update progress event" component=server peer="192.168.0.137:59891 @ 0x18ac240" status=UPDATE_STATUS_NONE progress=0 Mar 29 21:17:04 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 29 21:17:04 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:04.656Z level=INFO msg="emitting user changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" userId= Mar 29 21:17:04 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 29 21:17:04 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:04.663Z level=WARN msg="could not read OAuth data for music provider" component=volumio provider=tidal error="could not open plugin config file for \"tidal\": open /data/configuration/music_service/tidal/config.json: no such file or directory" Mar 29 21:17:04 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:04.665Z level=WARN msg="could not read OAuth data for music provider" component=volumio provider=qobuz error="could not open plugin config file for \"qobuz\": open /data/configuration/music_service/qobuz/config.json: no such file or directory" Mar 29 21:17:04 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:04.665Z level=WARN msg="could not read username/password data for music provider" component=volumio provider=hi_res_audio error="could not open plugin config file for \"hi_res_audio\": open /data/configuration/music_service/hi_res_audio/config.json: no such file or directory" Mar 29 21:17:04 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:04.665Z level=INFO msg="emitting music providers changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" providers=3 Mar 29 21:17:04 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 29 21:17:04 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:04.672Z level=INFO msg="emitting plugins changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" plugins=0 Mar 29 21:17:04 volumio volumio[1126]: info: CoreCommandRouter::volumioGetState Mar 29 21:17:04 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:04.682Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" state=STATUS_PAUSED positionMs=0 volume=100 Mar 29 21:17:04 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:04.682Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" id= title= Mar 29 21:17:04 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:04.732Z level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.0.137:59891 @ 0x18ac240" latency=171.826122ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Mar 29 21:17:05 volumio volumio[1126]: info: Airplay started streaming, receiving metadatas Mar 29 21:17:05 volumio volumio[1126]: info: Airplay started streaming, receiving metadatas Mar 29 21:17:05 volumio volumio[1126]: info: Airplay started streaming, receiving metadatas Mar 29 21:17:05 volumio volumio[1126]: info: Airplay started streaming, receiving metadatas Mar 29 21:17:05 volumio volumio[1126]: info: Airplay started streaming, receiving metadatas Mar 29 21:17:05 volumio volumio[1126]: info: Airplay started streaming, receiving metadatas Mar 29 21:17:05 volumio shairport-sync[7501]: {"time":1774790171740,"response":"airplayInactive Success"} Mar 29 21:17:05 volumio shairport-sync[7639]: % Total % Received % Xferd Average Speed Time Time Time Current Mar 29 21:17:05 volumio shairport-sync[7639]: Dload Upload Total Spent Left Speed Mar 29 21:17:05 volumio shairport-sync[7639]: [79B blob data] Mar 29 21:17:05 volumio shairport-sync[7638]: % Total % Received % Xferd Average Speed Time Time Time Current Mar 29 21:17:05 volumio shairport-sync[7638]: Dload Upload Total Spent Left Speed Mar 29 21:17:05 volumio volumio[1126]: info: Mar 29 21:17:05 volumio volumio[1126]: ---------------------------- Client requests Start Airplay PlaybackRoutine Mar 29 21:17:05 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback Mar 29 21:17:05 volumio volumio[1126]: info: Airplay playback start Mar 29 21:17:05 volumio shairport-sync[7638]: [79B blob data] Mar 29 21:17:05 volumio shairport-sync[7639]: [79B blob data] Mar 29 21:17:05 volumio volumio[1126]: info: Mar 29 21:17:05 volumio volumio[1126]: ---------------------------- Client requests AirplayActive Mar 29 21:17:05 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , setAirplayActive Mar 29 21:17:05 volumio shairport-sync[7639]: {"time":1774790225322,"response":"startAirplayPlayback Success"} Mar 29 21:17:05 volumio shairport-sync[7638]: [79B blob data] Mar 29 21:17:06 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:06 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:06 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 21:17:06 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:06 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:06.238Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 21:17:06 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:06.238Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 21:17:06 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:06.239Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title= Mar 29 21:17:06 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:06.239Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" id= title= Mar 29 21:17:06 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:06 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:06 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:06 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:06.271Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 21:17:06 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:06.272Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 21:17:06 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:06.273Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title= Mar 29 21:17:06 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:06.274Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" id= title= Mar 29 21:17:06 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:06 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:06 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:06 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:06.293Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 21:17:06 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:06.294Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 21:17:06 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:06.294Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title= Mar 29 21:17:06 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:06.295Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" id= title= Mar 29 21:17:06 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:06 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:06 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:06 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:06.314Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 21:17:06 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:06.314Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 21:17:06 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:06.315Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title= Mar 29 21:17:06 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:06.316Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" id= title= Mar 29 21:17:06 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:06 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:06 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:06 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:06.344Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 21:17:06 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:06.344Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 21:17:06 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:06.345Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title= Mar 29 21:17:06 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:06.345Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" id= title= Mar 29 21:17:06 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:06 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:06 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:06 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:06.365Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 21:17:06 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:06.366Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 21:17:06 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:06.367Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title= Mar 29 21:17:06 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:06.367Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" id= title= Mar 29 21:17:06 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:06 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:06 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:06 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:06.416Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 21:17:06 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:06.418Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 21:17:06 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:06.419Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title= Mar 29 21:17:06 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:06.420Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" id= title= Mar 29 21:17:06 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:06 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:06 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 21:17:06 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:06 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:06 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:06 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:06 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:06 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:06 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:06 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:06 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:06 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:06 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:06 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:06 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:06 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:06 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:06 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:06 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:06.817Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs= volume=100 Mar 29 21:17:06 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:06.817Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" state=STATUS_PLAYING positionMs= volume=100 Mar 29 21:17:06 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:06.818Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title= Mar 29 21:17:06 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:06.818Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" id= title= Mar 29 21:17:06 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:06.820Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs= volume=100 Mar 29 21:17:06 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:06.820Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" state=STATUS_PLAYING positionMs= volume=100 Mar 29 21:17:06 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:06.821Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title= Mar 29 21:17:06 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:06.821Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" id= title= Mar 29 21:17:06 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:06.822Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs= volume=100 Mar 29 21:17:06 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:06.823Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" state=STATUS_PLAYING positionMs= volume=100 Mar 29 21:17:06 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:06.824Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title= Mar 29 21:17:06 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:06.825Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" id= title= Mar 29 21:17:06 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:06.825Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs= volume=100 Mar 29 21:17:06 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:06.826Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" state=STATUS_PLAYING positionMs= volume=100 Mar 29 21:17:06 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:06.826Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title= Mar 29 21:17:06 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:06.826Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" id= title= Mar 29 21:17:06 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:06.828Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs= volume=100 Mar 29 21:17:06 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:06.828Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" state=STATUS_PLAYING positionMs= volume=100 Mar 29 21:17:06 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:06.830Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title= Mar 29 21:17:06 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:06.831Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" id= title= Mar 29 21:17:06 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:06.831Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs= volume=100 Mar 29 21:17:06 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:06.832Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" state=STATUS_PLAYING positionMs= volume=100 Mar 29 21:17:06 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:06.832Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title= Mar 29 21:17:06 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:06.833Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" id= title= Mar 29 21:17:06 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:06 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:06 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 21:17:06 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:06 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:06 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:06 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:06 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:06 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:06 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:06 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:06 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:06 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:06 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:06 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:06 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:06 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:06 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:06 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:06 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:06.922Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs= volume=100 Mar 29 21:17:06 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:06.922Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" state=STATUS_PLAYING positionMs= volume=100 Mar 29 21:17:06 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:06.927Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title= Mar 29 21:17:06 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:06.927Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs= volume=100 Mar 29 21:17:06 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:06.929Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" id= title= Mar 29 21:17:06 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:06.930Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" state=STATUS_PLAYING positionMs= volume=100 Mar 29 21:17:06 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:06.932Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs= volume=100 Mar 29 21:17:06 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:06.935Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title= Mar 29 21:17:06 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:06.937Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" id= title= Mar 29 21:17:06 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:06.937Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs= volume=100 Mar 29 21:17:06 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:06.936Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" state=STATUS_PLAYING positionMs= volume=100 Mar 29 21:17:06 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:06.938Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" state=STATUS_PLAYING positionMs= volume=100 Mar 29 21:17:06 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:06.939Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title= Mar 29 21:17:06 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:06.939Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title= Mar 29 21:17:06 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:06.939Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" id= title= Mar 29 21:17:06 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:06.939Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" id= title= Mar 29 21:17:06 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:06.943Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs= volume=100 Mar 29 21:17:06 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:06.943Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" state=STATUS_PLAYING positionMs= volume=100 Mar 29 21:17:06 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:06.944Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title= Mar 29 21:17:06 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:06.944Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" id= title= Mar 29 21:17:06 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:06.947Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs= volume=100 Mar 29 21:17:06 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:06.948Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" state=STATUS_PLAYING positionMs= volume=100 Mar 29 21:17:06 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:06.952Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title= Mar 29 21:17:06 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:06.952Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" id= title= Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.039Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs= volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.039Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" state=STATUS_PLAYING positionMs= volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.040Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title= Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.042Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" id= title= Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.043Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs= volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.044Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" state=STATUS_PLAYING positionMs= volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.044Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title= Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.045Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" id= title= Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.048Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs= volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.048Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" state=STATUS_PLAYING positionMs= volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.049Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title= Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.049Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" id= title= Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.054Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs= volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.055Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" state=STATUS_PLAYING positionMs= volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.055Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title= Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.056Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs= volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.057Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" state=STATUS_PLAYING positionMs= volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.058Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title= Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.058Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" id= title= Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.063Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs= volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.064Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" state=STATUS_PLAYING positionMs= volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.065Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title= Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.065Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" id= title= Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.065Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" id= title= Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.160Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.160Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.161Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title= Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.162Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" id= title= Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.164Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.165Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.166Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title= Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.166Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" id= title= Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.169Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.169Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.171Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title= Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.171Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" id= title= Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.173Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.174Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.175Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title= Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.175Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" id= title= Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.181Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.181Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.182Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title= Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.182Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" id= title= Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.189Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.189Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.190Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title= Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.190Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" id= title= Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.284Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=1000 volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.285Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" state=STATUS_PLAYING positionMs=1000 volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.288Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title= Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.288Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=1000 volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.288Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" state=STATUS_PLAYING positionMs=1000 volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.288Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" id= title= Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.290Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title= Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.291Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" id= title= Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.292Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=1000 volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.293Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" state=STATUS_PLAYING positionMs=1000 volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.295Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title= Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.295Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" id= title= Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.297Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=1000 volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.297Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" state=STATUS_PLAYING positionMs=1000 volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.298Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title= Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.299Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" id= title= Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.301Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=1000 volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.301Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" state=STATUS_PLAYING positionMs=1000 volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.302Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title= Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.303Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" id= title= Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.305Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=1000 volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.305Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" state=STATUS_PLAYING positionMs=1000 volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.306Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title= Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.307Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" id= title= Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.560Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=1000 volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.560Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" state=STATUS_PLAYING positionMs=1000 volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.562Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title= Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.563Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" id= title= Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.564Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=1000 volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.566Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" state=STATUS_PLAYING positionMs=1000 volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.568Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=1000 volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.568Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" state=STATUS_PLAYING positionMs=1000 volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.569Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title= Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.569Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" id= title= Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.571Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title= Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.571Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" id= title= Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.572Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=1000 volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.572Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" state=STATUS_PLAYING positionMs=1000 volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.573Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title= Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.573Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" id= title= Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.576Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=1000 volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.576Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" state=STATUS_PLAYING positionMs=1000 volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.577Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title= Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.578Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" id= title= Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.581Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=1000 volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.581Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" state=STATUS_PLAYING positionMs=1000 volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.583Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title= Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.583Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" id= title= Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.608Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=1000 volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.608Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" state=STATUS_PLAYING positionMs=1000 volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.612Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=1000 volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.613Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=1000 volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.614Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title= Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.614Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" id= title= Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.614Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=1000 volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.613Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=1000 volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.615Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" state=STATUS_PLAYING positionMs=1000 volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.614Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=1000 volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.616Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" state=STATUS_PLAYING positionMs=1000 volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.616Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title= Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.616Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title= Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.615Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" state=STATUS_PLAYING positionMs=1000 volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.617Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" id= title= Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.616Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" id= title= Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.617Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title= Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.615Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" state=STATUS_PLAYING positionMs=1000 volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.617Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" id= title= Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.618Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title= Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.618Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" state=STATUS_PLAYING positionMs=1000 volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.618Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" id= title= Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.619Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title= Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.619Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" id= title= Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.917Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=1000 volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.917Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" state=STATUS_PLAYING positionMs=1000 volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.920Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=1000 volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.921Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" state=STATUS_PLAYING positionMs=1000 volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.923Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=1000 volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.923Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" state=STATUS_PLAYING positionMs=1000 volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.923Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=1000 volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.924Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" state=STATUS_PLAYING positionMs=1000 volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.924Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=1000 volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.925Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=1000 volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.925Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" state=STATUS_PLAYING positionMs=1000 volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.927Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title= Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.927Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title= Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.927Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" id= title= Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.928Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" id= title= Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.927Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title= Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.929Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title= Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.928Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" id= title= Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.929Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" id= title= Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.931Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title= Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.932Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" id= title= Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.935Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" state=STATUS_PLAYING positionMs=1000 volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.936Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title= Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.937Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" id= title= Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:07 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.968Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=1000 volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.968Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=1000 volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.968Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" state=STATUS_PLAYING positionMs=1000 volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.968Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" state=STATUS_PLAYING positionMs=1000 volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.970Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=1000 volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.971Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" state=STATUS_PLAYING positionMs=1000 volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.972Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=1000 volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.973Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" state=STATUS_PLAYING positionMs=1000 volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.974Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title= Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.974Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" id= title= Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.976Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=1000 volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.977Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=1000 volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.977Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" state=STATUS_PLAYING positionMs=1000 volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.977Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" state=STATUS_PLAYING positionMs=1000 volume=100 Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.978Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title= Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.978Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" id= title= Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.980Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title= Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.980Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title= Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.981Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" id= title= Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.981Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" id= title= Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.981Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title= Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.981Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" id= title= Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.982Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title= Mar 29 21:17:07 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:07.983Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" id= title= Mar 29 21:17:08 volumio sudo[7641]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 29 21:17:08 volumio sudo[7641]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 21:17:08 volumio sudo[7641]: pam_unix(sudo:session): session closed for user root Mar 29 21:17:08 volumio sudo[7643]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 29 21:17:08 volumio sudo[7643]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 21:17:08 volumio sudo[7643]: pam_unix(sudo:session): session closed for user root Mar 29 21:17:08 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:08 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:08 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 21:17:08 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:08 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:08 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:08 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:08 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:08 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:08 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:08 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:08 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:08 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:08 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:08 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:08 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:08 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:08 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:08 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:08 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:08.789Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=2000 volume=100 Mar 29 21:17:08 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:08.789Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" state=STATUS_PLAYING positionMs=2000 volume=100 Mar 29 21:17:08 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:08.790Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=2000 volume=100 Mar 29 21:17:08 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:08.790Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title= Mar 29 21:17:08 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:08.790Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59891 @ 0x18ac240" state=STATUS_PLAYING positionMs=2000 volume=100 Mar 29 21:17:08 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:08.790Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=2000 volume=100 Mar 29 21:17:08 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:08.791Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" id= title= Mar 29 21:17:08 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:08.791Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=2000 volume=100 Mar 29 21:17:08 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:08.793Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=2000 volume=100 Mar 29 21:17:08 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:08.793Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=2000 volume=100 Mar 29 21:17:08 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:08 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:08 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 21:17:08 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:08 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:08 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:08 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:08 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:08 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:08 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:08 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:08 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:08 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:08 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:08 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:08 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:08 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:08 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:08 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:08 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:08.833Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=1000 volume=100 Mar 29 21:17:08 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:08.834Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=1000 volume=100 Mar 29 21:17:08 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:08.835Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=1000 volume=100 Mar 29 21:17:08 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:08.835Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=1000 volume=100 Mar 29 21:17:08 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:08.837Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=1000 volume=100 Mar 29 21:17:08 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:08.839Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=1000 volume=100 Mar 29 21:17:08 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:08 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:08 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:08 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:08 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:08 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:08 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:08 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:08 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:08 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:08 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:08 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:08 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:08 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:08 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:08 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:08 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:08 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:08 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:08.877Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=1000 volume=100 Mar 29 21:17:08 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:08.878Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=1000 volume=100 Mar 29 21:17:08 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:08.878Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=1000 volume=100 Mar 29 21:17:08 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:08.880Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=1000 volume=100 Mar 29 21:17:08 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:08.881Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=1000 volume=100 Mar 29 21:17:08 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:08.882Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=1000 volume=100 Mar 29 21:17:08 volumio volumio[1126]: verbose: New Socket.io Connection to 192.168.0.139 from 192.168.0.137 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 5 Mar 29 21:17:09 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 29 21:17:09 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:09 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:09 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 21:17:09 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:09 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:09 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:09 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:09 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:09 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:09 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:09 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:09 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:09 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:09 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:09 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:09 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:09 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:09 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:09 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:09 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:09.450Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=2000 volume=100 Mar 29 21:17:09 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:09.451Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=2000 volume=100 Mar 29 21:17:09 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:09.454Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=2000 volume=100 Mar 29 21:17:09 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:09.455Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=2000 volume=100 Mar 29 21:17:09 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:09.456Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=2000 volume=100 Mar 29 21:17:09 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:09.457Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=2000 volume=100 Mar 29 21:17:09 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:09 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:09 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:09 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:09 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:09 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:09 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:09 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:09 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:09 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:09 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:09 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:09 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:09 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:09 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:09 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:09 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:09 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:09 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:09.489Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=2000 volume=100 Mar 29 21:17:09 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:09.491Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=2000 volume=100 Mar 29 21:17:09 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:09.491Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=2000 volume=100 Mar 29 21:17:09 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:09.491Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=2000 volume=100 Mar 29 21:17:09 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:09.492Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=2000 volume=100 Mar 29 21:17:09 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:09.492Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=2000 volume=100 Mar 29 21:17:09 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:09 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:09 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:09 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:09 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:09 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:09 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:09 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:09 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:09 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:09 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:09 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:09 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:09 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:09 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:09 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:09 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:09 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:09 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:09.524Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=2000 volume=100 Mar 29 21:17:09 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:09.524Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=2000 volume=100 Mar 29 21:17:09 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:09.525Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=2000 volume=100 Mar 29 21:17:09 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:09.528Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=2000 volume=100 Mar 29 21:17:09 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:09.528Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=2000 volume=100 Mar 29 21:17:09 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:09.529Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=2000 volume=100 Mar 29 21:17:09 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 29 21:17:09 volumio sudo[7647]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 29 21:17:09 volumio sudo[7647]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 21:17:09 volumio sudo[7649]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 29 21:17:09 volumio sudo[7649]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 21:17:09 volumio sudo[7647]: pam_unix(sudo:session): session closed for user root Mar 29 21:17:09 volumio sudo[7649]: pam_unix(sudo:session): session closed for user root Mar 29 21:17:09 volumio volumio[1126]: verbose: New Socket.io Connection to 192.168.0.139 from 192.168.0.137 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6 Mar 29 21:17:09 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 29 21:17:09 volumio volumio[1126]: info: CoreCommandRouter::volumioGetVisibleSources Mar 29 21:17:09 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 29 21:17:09 volumio volumio[1126]: info: CoreCommandRouter::volumioGetState Mar 29 21:17:09 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 29 21:17:09 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 29 21:17:09 volumio volumio[1126]: info: Received Get System Info Mar 29 21:17:09 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 21:17:09 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 21:17:09 volumio volumio[1126]: info: Discovery: Getting this device information Mar 29 21:17:09 volumio volumio[1126]: info: CoreCommandRouter::volumioGetState Mar 29 21:17:09 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 21:17:09 volumio volumio[1126]: info: CoreCommandRouter::volumioGetState Mar 29 21:17:10 volumio volumio[1126]: info: Listing playlists Mar 29 21:17:10 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 29 21:17:10 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 29 21:17:10 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:10 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:10 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:10 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:10 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:10 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:10 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:10 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:10 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:10 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:10 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:10 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:10 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:10 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:10 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:10 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:10 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:10 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:10 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:10.047Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=2000 volume=100 Mar 29 21:17:10 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:10.048Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=2000 volume=100 Mar 29 21:17:10 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:10.048Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=2000 volume=100 Mar 29 21:17:10 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:10.050Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=2000 volume=100 Mar 29 21:17:10 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:10.051Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=2000 volume=100 Mar 29 21:17:10 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:10.054Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=2000 volume=100 Mar 29 21:17:10 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:10 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:10 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:10 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:10 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:10 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:10 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:10 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:10 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:10 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:10 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:10 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:10 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:10 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:10 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:10 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:10 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:10 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:10 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:10.093Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=2000 volume=100 Mar 29 21:17:10 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:10.096Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=2000 volume=100 Mar 29 21:17:10 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:10.096Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=2000 volume=100 Mar 29 21:17:10 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:10.097Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=2000 volume=100 Mar 29 21:17:10 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:10.097Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=2000 volume=100 Mar 29 21:17:10 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:10.099Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=2000 volume=100 Mar 29 21:17:10 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:10 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:10 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:10 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:10 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:10 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:10 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:10 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:10 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:10 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:10 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:10 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:10 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:10 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:10 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:10 volumio volumio[1126]: info: CoreCommandRouter::servicePushState Mar 29 21:17:10 volumio volumio[1126]: info: CoreStateMachine::pushState Mar 29 21:17:10 volumio volumio[1126]: info: CoreCommandRouter::volumioPushState Mar 29 21:17:10 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:10.130Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=2000 volume=100 Mar 29 21:17:10 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:10.131Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=2000 volume=100 Mar 29 21:17:10 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:10.132Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=2000 volume=100 Mar 29 21:17:10 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:10.135Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=2000 volume=100 Mar 29 21:17:10 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:10.135Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=2000 volume=100 Mar 29 21:17:10 volumio volumio5-onboarding[1756]: time=2026-03-29T13:17:10.136Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.137:59560 @ 0x192c270" state=STATUS_PLAYING positionMs=2000 volume=100 Mar 29 21:17:10 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 29 21:17:11 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 29 21:17:11 volumio volumio[1126]: info: Received Get System Info Mar 29 21:17:11 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 21:17:11 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 21:17:11 volumio volumio[1126]: info: Discovery: Getting this device information Mar 29 21:17:11 volumio volumio[1126]: info: CoreCommandRouter::volumioGetState Mar 29 21:17:11 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 21:17:11 volumio volumio[1126]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 29 21:17:14 volumio volumio[1126]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 29 21:17:14 volumio volumio[1126]: Error: connect ETIMEDOUT 45.114.11.25:443 Mar 29 21:17:14 volumio volumio[1126]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Mar 29 21:17:14 volumio volumio[1126]: errno: -110, Mar 29 21:17:14 volumio volumio[1126]: code: 'ETIMEDOUT', Mar 29 21:17:14 volumio volumio[1126]: syscall: 'connect', Mar 29 21:17:14 volumio volumio[1126]: address: '45.114.11.25', Mar 29 21:17:14 volumio volumio[1126]: port: 443 Mar 29 21:17:14 volumio volumio[1126]: } Mar 29 21:17:14 volumio volumio[1126]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 29 21:17:15 volumio sudo[7681]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-29 21:16' Mar 29 21:17:15 volumio sudo[7681]: 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"