Apr 14 20:13:12 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:12 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:12 sony volumio[2438]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 20:13:12 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:12 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:12 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:12 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:12 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:12 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:12 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:12 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:12 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:12 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:12 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:12 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:12 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:12 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:12 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:12 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:12 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:12 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:12 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:12 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:12 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:12 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:12 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:12.630+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=66000 volume=83 Apr 14 20:13:12 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:12.631+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="Comfortably Numb" Apr 14 20:13:12 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:12.634+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=66000 volume=83 Apr 14 20:13:12 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:12.635+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="Comfortably Numb" Apr 14 20:13:12 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:12.638+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=66000 volume=83 Apr 14 20:13:12 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:12.638+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=66000 volume=83 Apr 14 20:13:12 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:12.641+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="Comfortably Numb" Apr 14 20:13:12 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:12.643+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="Comfortably Numb" Apr 14 20:13:12 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:12 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:12 sony volumio[2438]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 20:13:12 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:12 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:12 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:12 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:12 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:12 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:12 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:12 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:12 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:12 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:12 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:12 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:12 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:12 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:12 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:12 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:12 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:12 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:12 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:12 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:12 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:12 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:12 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:12.756+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:12 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:12.758+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="Comfortably Numb" Apr 14 20:13:12 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:12.760+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:12 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:12.762+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="Comfortably Numb" Apr 14 20:13:12 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:12.766+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:12 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:12.763+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:12 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:12.770+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="Comfortably Numb" Apr 14 20:13:12 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:12.772+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="Comfortably Numb" Apr 14 20:13:12 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:12 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:12 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:12 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:12 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:12 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:12 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:12 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:12 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:12 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:12 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:12 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:12 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:12 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:12 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:12 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:12 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:12 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:12 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:12 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:12 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:12 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:12 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:12 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:12 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:12.902+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:12 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:12.903+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="Comfortably Numb" Apr 14 20:13:12 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:12.908+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:12 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:12.910+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="Comfortably Numb" Apr 14 20:13:12 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:12.912+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:12 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:12.914+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="Comfortably Numb" Apr 14 20:13:12 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:12.914+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:12 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:12.922+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="Comfortably Numb" Apr 14 20:13:13 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:13 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:13 sony volumio[2438]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 20:13:13 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:13 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:13 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:13 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:13 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:13 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:13 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:13 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:13 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:13 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:13 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:13 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:13 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:13 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:13 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:13 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:13 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:13 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:13 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:13 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:13 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:13 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:13 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:13.443+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=1000 volume=83 Apr 14 20:13:13 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:13.445+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="Comfortably Numb" Apr 14 20:13:13 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:13.447+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=1000 volume=83 Apr 14 20:13:13 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:13.448+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="Comfortably Numb" Apr 14 20:13:13 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:13.457+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=1000 volume=83 Apr 14 20:13:13 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:13.458+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="Comfortably Numb" Apr 14 20:13:13 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:13.470+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=1000 volume=83 Apr 14 20:13:13 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:13.471+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="Comfortably Numb" Apr 14 20:13:13 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:13 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:13 sony volumio[2438]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 20:13:13 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:13 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:13 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:13 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:13 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:13 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:13 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:13 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:13 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:13 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:13 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:13 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:13 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:13 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:13 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:13 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:13 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:13 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:13 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:13 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:13 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:13 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:13 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:13.594+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:13 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:13.598+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="Comfortably Numb" Apr 14 20:13:13 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:13.603+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:13 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:13.605+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="Comfortably Numb" Apr 14 20:13:13 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:13.607+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:13 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:13.609+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:13 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:13.609+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="Comfortably Numb" Apr 14 20:13:13 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:13.619+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="Comfortably Numb" Apr 14 20:13:13 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:13 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:13 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:13 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:13 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:13 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:13 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:13 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:13 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:13 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:13 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:13 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:13 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:13 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:13 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:13 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:13 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:13 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:13 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:13 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:13 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:13 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:13 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:13 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:13 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:13.738+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:13 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:13.743+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:13 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:13.741+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:13 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:13.741+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:13 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:13.746+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="Comfortably Numb" Apr 14 20:13:13 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:13.755+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="Comfortably Numb" Apr 14 20:13:13 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:13.757+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="Comfortably Numb" Apr 14 20:13:13 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:13.757+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="Comfortably Numb" Apr 14 20:13:16 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:16 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:16 sony volumio[2438]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 20:13:16 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:16 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:16 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:16 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:16 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:16 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:16 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:16 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:16 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:16 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:16 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:16 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:16 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:17 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:17 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:17 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:17 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:17 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:17 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:17 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:17 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:17 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:17 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:17.034+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=3000 volume=83 Apr 14 20:13:17 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:17.040+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="You Oughta Know - 2015 Remaster" Apr 14 20:13:17 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:17.043+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=3000 volume=83 Apr 14 20:13:17 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:17.045+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="You Oughta Know - 2015 Remaster" Apr 14 20:13:17 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:17.048+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=3000 volume=83 Apr 14 20:13:17 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:17.050+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="You Oughta Know - 2015 Remaster" Apr 14 20:13:17 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:17.054+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=3000 volume=83 Apr 14 20:13:17 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:17.057+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="You Oughta Know - 2015 Remaster" Apr 14 20:13:17 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:17 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:17 sony volumio[2438]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 20:13:17 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:17 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:17 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:17 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:17 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:17 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:17 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:17 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:17 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:17 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:17 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:17 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:17 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:17 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:17 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:17 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:17 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:17 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:17 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:17 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:17 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:17 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:17 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:17.167+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:17 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:17.173+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="You Oughta Know - 2015 Remaster" Apr 14 20:13:17 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:17.174+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:17 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:17.176+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="You Oughta Know - 2015 Remaster" Apr 14 20:13:17 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:17.178+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:17 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:17.181+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:17 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:17.183+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="You Oughta Know - 2015 Remaster" Apr 14 20:13:17 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:17.184+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="You Oughta Know - 2015 Remaster" Apr 14 20:13:17 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:17 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:17 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:17 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:17 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:17 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:17 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:17 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:17 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:17 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:17 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:17 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:17 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:17 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:17 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:17 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:17 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:17 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:17 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:17 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:17 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:17 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:17 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:17 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:17 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:17.284+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:17 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:17.286+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="You Oughta Know - 2015 Remaster" Apr 14 20:13:17 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:17.298+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:17 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:17.299+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="You Oughta Know - 2015 Remaster" Apr 14 20:13:17 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:17.305+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:17 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:17.312+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="You Oughta Know - 2015 Remaster" Apr 14 20:13:17 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:17.322+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:17 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:17.323+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="You Oughta Know - 2015 Remaster" Apr 14 20:13:17 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:17 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:17 sony volumio[2438]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 20:13:17 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:17 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:17 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:17 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:17 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:17 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:17 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:17 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:17 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:17 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:17 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:17 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:17 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:17 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:17 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:17 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:17 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:17 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:17 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:17 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:17 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:17 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:17 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:17.649+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=1000 volume=83 Apr 14 20:13:17 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:17.652+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=1000 volume=83 Apr 14 20:13:17 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:17.657+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=1000 volume=83 Apr 14 20:13:17 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:17.660+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="You Oughta Know - 2015 Remaster" Apr 14 20:13:17 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:17.665+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=1000 volume=83 Apr 14 20:13:17 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:17.667+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="You Oughta Know - 2015 Remaster" Apr 14 20:13:17 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:17.669+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="You Oughta Know - 2015 Remaster" Apr 14 20:13:17 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:17.673+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="You Oughta Know - 2015 Remaster" Apr 14 20:13:17 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:17 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:17 sony volumio[2438]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 20:13:17 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:17 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:17 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:17 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:17 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:17 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:17 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:17 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:17 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:17 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:17 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:17 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:17 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:17 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:17 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:17 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:17 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:17 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:17 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:17 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:17 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:17 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:17 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:17.776+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:17 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:17.778+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="You Oughta Know - 2015 Remaster" Apr 14 20:13:17 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:17.789+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:17 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:17.796+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:17 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:17.797+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="You Oughta Know - 2015 Remaster" Apr 14 20:13:17 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:17.798+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="You Oughta Know - 2015 Remaster" Apr 14 20:13:17 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:17.803+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:17 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:17.806+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="You Oughta Know - 2015 Remaster" Apr 14 20:13:17 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:17 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:17 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:17 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:17 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:17 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:17 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:17 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:17 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:17 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:17 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:17 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:17 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:17 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:17 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:17 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:17 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:17 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:17 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:17 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:17 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:17 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:17 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:17 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:17 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:17.888+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:17 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:17.890+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="You Oughta Know - 2015 Remaster" Apr 14 20:13:17 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:17.889+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:17 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:17.892+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="You Oughta Know - 2015 Remaster" Apr 14 20:13:17 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:17.893+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:17 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:17.893+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:17 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:17.894+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="You Oughta Know - 2015 Remaster" Apr 14 20:13:17 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:17.895+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="You Oughta Know - 2015 Remaster" Apr 14 20:13:19 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:19 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:19 sony volumio[2438]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 20:13:19 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:19 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:19 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:19 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:19 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:19 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:19 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:19 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:19 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:19 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:20 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:20 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:20 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:20 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:20 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:20 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:20 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:20 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:20 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:20 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:20 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:20 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:20 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:20.054+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=2000 volume=83 Apr 14 20:13:20 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:20.054+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=2000 volume=83 Apr 14 20:13:20 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:20.058+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="I'm Gonna Be (500 Miles)" Apr 14 20:13:20 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:20.067+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="I'm Gonna Be (500 Miles)" Apr 14 20:13:20 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:20.071+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=2000 volume=83 Apr 14 20:13:20 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:20.072+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=2000 volume=83 Apr 14 20:13:20 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:20.072+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="I'm Gonna Be (500 Miles)" Apr 14 20:13:20 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:20.081+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="I'm Gonna Be (500 Miles)" Apr 14 20:13:20 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:20 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:20 sony volumio[2438]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 20:13:20 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:20 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:20 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:20 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:20 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:20 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:20 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:20 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:20 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:20 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:20 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:20 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:20 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:20 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:20 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:20 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:20 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:20 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:20 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:20 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:20 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:20 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:20 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:20.249+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:20 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:20.253+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="I'm Gonna Be (500 Miles)" Apr 14 20:13:20 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:20.259+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:20 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:20.260+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:20 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:20.261+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="I'm Gonna Be (500 Miles)" Apr 14 20:13:20 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:20.262+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="I'm Gonna Be (500 Miles)" Apr 14 20:13:20 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:20.275+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:20 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:20.277+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="I'm Gonna Be (500 Miles)" Apr 14 20:13:20 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:20 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:20 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:20 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:20 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:20 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:20 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:20 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:20 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:20 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:20 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:20 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:20 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:20 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:20 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:20 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:20 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:20 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:20 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:20 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:20 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:20 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:20 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:20 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:20 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:20.445+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:20 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:20.446+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="I'm Gonna Be (500 Miles)" Apr 14 20:13:20 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:20.448+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:20 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:20.450+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:20 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:20.451+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="I'm Gonna Be (500 Miles)" Apr 14 20:13:20 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:20.451+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="I'm Gonna Be (500 Miles)" Apr 14 20:13:20 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:20.452+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:20 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:20.454+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="I'm Gonna Be (500 Miles)" Apr 14 20:13:20 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:20 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:20 sony volumio[2438]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 20:13:20 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:20 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:20 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:20 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:20 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:20 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:20 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:20 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:20 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:20 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:20 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:20 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:20 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:20 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:20 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:20 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:20 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:20 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:20 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:20 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:20 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:20 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:20 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:20.891+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=1000 volume=83 Apr 14 20:13:20 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:20.893+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="I'm Gonna Be (500 Miles)" Apr 14 20:13:20 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:20.915+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=1000 volume=83 Apr 14 20:13:20 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:20.916+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="I'm Gonna Be (500 Miles)" Apr 14 20:13:20 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:20.917+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=1000 volume=83 Apr 14 20:13:20 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:20.918+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="I'm Gonna Be (500 Miles)" Apr 14 20:13:20 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:20.922+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=1000 volume=83 Apr 14 20:13:20 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:20.931+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="I'm Gonna Be (500 Miles)" Apr 14 20:13:21 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:21 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:21 sony volumio[2438]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 20:13:21 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:21 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:21 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:21 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:21 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:21 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:21 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:21 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:21 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:21 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:21 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:21 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:21 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:21 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:21 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:21 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:21 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:21 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:21 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:21 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:21 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:21 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:21 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:21.113+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:21 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:21.114+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="I'm Gonna Be (500 Miles)" Apr 14 20:13:21 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:21.114+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:21 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:21.120+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:21 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:21.121+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="I'm Gonna Be (500 Miles)" Apr 14 20:13:21 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:21.123+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="I'm Gonna Be (500 Miles)" Apr 14 20:13:21 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:21.126+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:21 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:21.128+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="I'm Gonna Be (500 Miles)" Apr 14 20:13:21 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:21 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:21 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:21 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:21 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:21 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:21 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:21 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:21 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:21 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:21 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:21 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:21 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:21 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:21 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:21 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:21 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:21 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:21 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:21 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:21 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:21 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:21 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:21 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:21 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:21.243+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:21 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:21.244+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="I'm Gonna Be (500 Miles)" Apr 14 20:13:21 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:21.253+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:21 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:21.254+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="I'm Gonna Be (500 Miles)" Apr 14 20:13:21 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:21.256+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:21 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:21.260+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="I'm Gonna Be (500 Miles)" Apr 14 20:13:21 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:21.261+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:21 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:21.263+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="I'm Gonna Be (500 Miles)" Apr 14 20:13:22 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:22 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:22 sony volumio[2438]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 20:13:22 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:22 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:22 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:22 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:22 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:22 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:22 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:22 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:22 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:22 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:22 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:22 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:22 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:22 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:22 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:22 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:22 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:22 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:22 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:22 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:22 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:22 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:22 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:22.201+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PAUSED positionMs=1000 volume=83 Apr 14 20:13:22 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:22.204+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="Prayer in C - Robin Schulz Radio Edit" Apr 14 20:13:22 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:22.207+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PAUSED positionMs=1000 volume=83 Apr 14 20:13:22 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:22.214+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PAUSED positionMs=1000 volume=83 Apr 14 20:13:22 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:22.217+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="Prayer in C - Robin Schulz Radio Edit" Apr 14 20:13:22 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:22.219+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PAUSED positionMs=1000 volume=83 Apr 14 20:13:22 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:22.222+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="Prayer in C - Robin Schulz Radio Edit" Apr 14 20:13:22 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:22.226+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="Prayer in C - Robin Schulz Radio Edit" Apr 14 20:13:22 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:22 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:22 sony volumio[2438]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 20:13:22 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:22 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:22 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:22 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:22 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:22 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:22 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:22 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:22 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:22 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:22 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:22 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:22 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:22 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:22 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:22 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:22 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:22 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:22 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:22 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:22 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:22 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:22 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:22.338+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=1000 volume=83 Apr 14 20:13:22 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:22.351+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="Prayer in C - Robin Schulz Radio Edit" Apr 14 20:13:22 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:22.352+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=1000 volume=83 Apr 14 20:13:22 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:22.356+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=1000 volume=83 Apr 14 20:13:22 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:22.369+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="Prayer in C - Robin Schulz Radio Edit" Apr 14 20:13:22 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:22.372+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="Prayer in C - Robin Schulz Radio Edit" Apr 14 20:13:22 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:22.406+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=1000 volume=83 Apr 14 20:13:22 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:22.411+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="Prayer in C - Robin Schulz Radio Edit" Apr 14 20:13:22 sony volumio[2438]: info: touch_display: Setting screensaver timeout to 20 seconds. Apr 14 20:13:22 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:22 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:22 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:22 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:22 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:22 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:22 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:22 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:22 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:22 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:22 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:22 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:22 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:22 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:22 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:22 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:22 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:22 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:22 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:22 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:22 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:22 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:22 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:22 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:22 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:22.834+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=1000 volume=83 Apr 14 20:13:22 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:22.840+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=1000 volume=83 Apr 14 20:13:22 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:22.841+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="Prayer in C - Robin Schulz Radio Edit" Apr 14 20:13:22 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:22.841+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="Prayer in C - Robin Schulz Radio Edit" Apr 14 20:13:22 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:22.838+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=1000 volume=83 Apr 14 20:13:22 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:22.843+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="Prayer in C - Robin Schulz Radio Edit" Apr 14 20:13:22 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:22.839+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=1000 volume=83 Apr 14 20:13:22 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:22.845+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="Prayer in C - Robin Schulz Radio Edit" Apr 14 20:13:22 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:22 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:22 sony volumio[2438]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 20:13:22 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:22 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:22 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:22 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:22 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:22 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:22 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:22 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:22 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:22 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:22 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:22 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:22 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:22 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:22 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:22 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:22 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:22 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:22 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:22 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:22 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:22 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:22 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:22.933+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:22 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:22.934+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="Prayer in C - Robin Schulz Radio Edit" Apr 14 20:13:22 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:22.943+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:22 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:22.948+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="Prayer in C - Robin Schulz Radio Edit" Apr 14 20:13:22 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:22.954+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:22 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:22.955+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="Prayer in C - Robin Schulz Radio Edit" Apr 14 20:13:22 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:22.958+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:22 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:22.960+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="Prayer in C - Robin Schulz Radio Edit" Apr 14 20:13:22 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:22 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:22 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:22 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:22 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:22 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:23 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:23 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:23 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:23 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:23 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:23 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:23 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:23 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:23 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:23 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:23 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:23 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:23 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:23 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:23 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:23 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:23 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:23 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:23 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:23.040+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:23 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:23.042+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="Prayer in C - Robin Schulz Radio Edit" Apr 14 20:13:23 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:23.048+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:23 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:23.049+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:23 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:23.055+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:23 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:23.058+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="Prayer in C - Robin Schulz Radio Edit" Apr 14 20:13:23 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:23.060+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="Prayer in C - Robin Schulz Radio Edit" Apr 14 20:13:23 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:23.101+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="Prayer in C - Robin Schulz Radio Edit" Apr 14 20:13:24 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:24 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:24 sony volumio[2438]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 20:13:24 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:24 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:24 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:24 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:24 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:24 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:24 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:24 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:24 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:24 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:24 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:24 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:24 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:24 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:24 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:24 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:24 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:24 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:24 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:24 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:24 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:24 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:24 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:24.211+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=1000 volume=83 Apr 14 20:13:24 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:24.223+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=1000 volume=83 Apr 14 20:13:24 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:24.223+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=1000 volume=83 Apr 14 20:13:24 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:24.231+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="The Wild Boys - Live at Oakland Coliseum, Oakland, CA, 14/04/1984; 2004 Remaster" Apr 14 20:13:24 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:24.231+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="The Wild Boys - Live at Oakland Coliseum, Oakland, CA, 14/04/1984; 2004 Remaster" Apr 14 20:13:24 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:24.234+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=1000 volume=83 Apr 14 20:13:24 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:24.235+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="The Wild Boys - Live at Oakland Coliseum, Oakland, CA, 14/04/1984; 2004 Remaster" Apr 14 20:13:24 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:24.235+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="The Wild Boys - Live at Oakland Coliseum, Oakland, CA, 14/04/1984; 2004 Remaster" Apr 14 20:13:24 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:24 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:24 sony volumio[2438]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 20:13:24 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:24 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:24 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:24 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:24 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:24 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:24 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:24 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:24 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:24 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:24 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:24 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:24 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:24 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:24 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:24 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:24 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:24 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:24 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:24 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:24 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:24 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:24 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:24.403+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:24 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:24.407+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:24 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:24.414+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:24 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:24.418+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="The Wild Boys - Live at Oakland Coliseum, Oakland, CA, 14/04/1984; 2004 Remaster" Apr 14 20:13:24 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:24.418+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="The Wild Boys - Live at Oakland Coliseum, Oakland, CA, 14/04/1984; 2004 Remaster" Apr 14 20:13:24 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:24.414+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:24 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:24.419+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="The Wild Boys - Live at Oakland Coliseum, Oakland, CA, 14/04/1984; 2004 Remaster" Apr 14 20:13:24 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:24.419+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="The Wild Boys - Live at Oakland Coliseum, Oakland, CA, 14/04/1984; 2004 Remaster" Apr 14 20:13:24 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:24 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:24 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:24 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:24 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:24 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:24 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:24 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:24 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:24 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:24 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:24 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:24 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:24 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:24 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:24 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:24 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:24 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:24 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:24 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:24 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:24 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:24 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:24 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:24 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:24.573+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:24 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:24.577+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="The Wild Boys - Live at Oakland Coliseum, Oakland, CA, 14/04/1984; 2004 Remaster" Apr 14 20:13:24 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:24.583+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:24 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:24.584+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:24 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:24.591+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="The Wild Boys - Live at Oakland Coliseum, Oakland, CA, 14/04/1984; 2004 Remaster" Apr 14 20:13:24 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:24.586+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:24 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:24.593+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="The Wild Boys - Live at Oakland Coliseum, Oakland, CA, 14/04/1984; 2004 Remaster" Apr 14 20:13:24 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:24.596+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="The Wild Boys - Live at Oakland Coliseum, Oakland, CA, 14/04/1984; 2004 Remaster" Apr 14 20:13:24 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:24 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:24 sony volumio[2438]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 20:13:24 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:24 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:24 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:24 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:24 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:24 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:24 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:24 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:24 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:24 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:24 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:24 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:24 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:24 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:24 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:24 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:24 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:24 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:24 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:24 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:24 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:24 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:24 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:24.904+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=1000 volume=83 Apr 14 20:13:24 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:24.905+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="The Wild Boys - Live at Oakland Coliseum, Oakland, CA, 14/04/1984; 2004 Remaster" Apr 14 20:13:24 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:24.909+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=1000 volume=83 Apr 14 20:13:24 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:24.913+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=1000 volume=83 Apr 14 20:13:24 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:24.914+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=1000 volume=83 Apr 14 20:13:24 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:24.914+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="The Wild Boys - Live at Oakland Coliseum, Oakland, CA, 14/04/1984; 2004 Remaster" Apr 14 20:13:24 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:24.915+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="The Wild Boys - Live at Oakland Coliseum, Oakland, CA, 14/04/1984; 2004 Remaster" Apr 14 20:13:24 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:24.916+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="The Wild Boys - Live at Oakland Coliseum, Oakland, CA, 14/04/1984; 2004 Remaster" Apr 14 20:13:24 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:24 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:24 sony volumio[2438]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 20:13:24 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:24 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:24 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:24 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:24 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:24 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:24 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:24 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:24 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:24 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:24 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:24 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:24 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:24 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:24 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:24 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:24 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:24 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:24 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:24 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:24 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:24 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:25 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:25.007+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:25 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:25.009+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="The Wild Boys - Live at Oakland Coliseum, Oakland, CA, 14/04/1984; 2004 Remaster" Apr 14 20:13:25 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:25.010+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:25 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:25.011+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="The Wild Boys - Live at Oakland Coliseum, Oakland, CA, 14/04/1984; 2004 Remaster" Apr 14 20:13:25 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:25.013+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:25 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:25.014+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:25 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:25.014+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="The Wild Boys - Live at Oakland Coliseum, Oakland, CA, 14/04/1984; 2004 Remaster" Apr 14 20:13:25 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:25.019+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="The Wild Boys - Live at Oakland Coliseum, Oakland, CA, 14/04/1984; 2004 Remaster" Apr 14 20:13:25 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:25 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:25 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:25 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:25 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:25 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:25 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:25 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:25 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:25 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:25 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:25 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:25 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:25 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:25 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:25 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:25 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:25 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:25 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:25 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:25 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:25 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:25 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:25 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:25 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:25.114+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:25 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:25.118+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="The Wild Boys - Live at Oakland Coliseum, Oakland, CA, 14/04/1984; 2004 Remaster" Apr 14 20:13:25 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:25.123+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:25 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:25.124+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="The Wild Boys - Live at Oakland Coliseum, Oakland, CA, 14/04/1984; 2004 Remaster" Apr 14 20:13:25 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:25.130+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:25 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:25.135+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="The Wild Boys - Live at Oakland Coliseum, Oakland, CA, 14/04/1984; 2004 Remaster" Apr 14 20:13:25 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:25.146+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:25 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:25.147+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="The Wild Boys - Live at Oakland Coliseum, Oakland, CA, 14/04/1984; 2004 Remaster" Apr 14 20:13:26 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:26 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:26 sony volumio[2438]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 20:13:26 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:26 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:26 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:26 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:26 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:26 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:26 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:26 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:26 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:26 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:26 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:26 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:26 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:26 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:26 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:26 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:26 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:26 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:26 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:26 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:26 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:26 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:26 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:26.374+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=1000 volume=83 Apr 14 20:13:26 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:26.378+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="Cream - Without Rap Monologue" Apr 14 20:13:26 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:26.385+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=1000 volume=83 Apr 14 20:13:26 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:26.391+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=1000 volume=83 Apr 14 20:13:26 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:26.391+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=1000 volume=83 Apr 14 20:13:26 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:26.396+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="Cream - Without Rap Monologue" Apr 14 20:13:26 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:26.398+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="Cream - Without Rap Monologue" Apr 14 20:13:26 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:26.401+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="Cream - Without Rap Monologue" Apr 14 20:13:26 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:26 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:26 sony volumio[2438]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 20:13:26 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:26 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:26 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:26 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:26 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:26 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:26 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:26 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:26 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:26 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:26 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:26 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:26 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:26 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:26 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:26 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:26 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:26 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:26 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:26 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:26 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:26 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:26 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:26.499+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:26 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:26.506+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:26 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:26.509+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="Cream - Without Rap Monologue" Apr 14 20:13:26 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:26.507+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="Cream - Without Rap Monologue" Apr 14 20:13:26 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:26.517+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:26 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:26.518+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="Cream - Without Rap Monologue" Apr 14 20:13:26 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:26.525+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:26 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:26.530+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="Cream - Without Rap Monologue" Apr 14 20:13:26 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:26 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:26 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:26 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:26 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:26 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:26 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:26 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:26 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:26 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:26 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:26 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:26 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:26 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:26 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:26 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:26 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:26 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:26 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:26 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:26 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:26 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:26 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:26 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:26 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:26.637+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:26 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:26.637+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:26 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:26.638+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="Cream - Without Rap Monologue" Apr 14 20:13:26 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:26.639+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="Cream - Without Rap Monologue" Apr 14 20:13:26 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:26.653+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:26 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:26.657+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="Cream - Without Rap Monologue" Apr 14 20:13:26 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:26.662+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:26 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:26.664+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="Cream - Without Rap Monologue" Apr 14 20:13:26 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:26 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:26 sony volumio[2438]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 20:13:26 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:27 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:27 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:27 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:27 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:27 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:27 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:27 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:27 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:27 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:27 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:27 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:27 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:27 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:27 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:27 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:27 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:27 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:27 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:27 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:27 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:27 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:27 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:27.069+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=1000 volume=83 Apr 14 20:13:27 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:27.072+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=1000 volume=83 Apr 14 20:13:27 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:27.077+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="Cream - Without Rap Monologue" Apr 14 20:13:27 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:27.087+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=1000 volume=83 Apr 14 20:13:27 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:27.092+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=1000 volume=83 Apr 14 20:13:27 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:27.092+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="Cream - Without Rap Monologue" Apr 14 20:13:27 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:27.095+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="Cream - Without Rap Monologue" Apr 14 20:13:27 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:27.104+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="Cream - Without Rap Monologue" Apr 14 20:13:27 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:27 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:27 sony volumio[2438]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 20:13:27 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:27 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:27 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:27 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:27 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:27 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:27 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:27 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:27 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:27 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:27 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:27 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:27 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:27 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:27 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:27 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:27 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:27 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:27 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:27 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:27 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:27 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:27 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:27.249+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:27 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:27.252+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="Cream - Without Rap Monologue" Apr 14 20:13:27 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:27.260+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:27 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:27.261+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:27 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:27.263+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="Cream - Without Rap Monologue" Apr 14 20:13:27 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:27.257+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:27 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:27.265+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="Cream - Without Rap Monologue" Apr 14 20:13:27 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:27.267+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="Cream - Without Rap Monologue" Apr 14 20:13:27 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:27 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:27 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:27 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:27 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:27 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:27 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:27 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:27 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:27 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:27 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:27 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:27 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:27 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:27 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:27 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:27 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:27 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:27 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:27 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:27 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:27 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:27 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:27 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:27 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:27.386+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:27 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:27.394+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="Cream - Without Rap Monologue" Apr 14 20:13:27 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:27.394+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:27 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:27.396+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="Cream - Without Rap Monologue" Apr 14 20:13:27 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:27.405+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:27 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:27.412+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:27 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:27.414+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="Cream - Without Rap Monologue" Apr 14 20:13:27 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:27.420+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title="Cream - Without Rap Monologue" Apr 14 20:13:28 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:28 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:28 sony volumio[2438]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 20:13:28 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:28 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:28 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:28 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:28 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:28 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:28 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:28 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:28 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:28 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:28 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:28 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:28 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:28 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:28 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:28 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:28 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:28 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:28 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:28 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:28 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:28 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:28 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:28.116+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=1000 volume=83 Apr 14 20:13:28 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:28.117+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title=Loverboy Apr 14 20:13:28 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:28.123+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=1000 volume=83 Apr 14 20:13:28 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:28.124+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title=Loverboy Apr 14 20:13:28 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:28.128+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=1000 volume=83 Apr 14 20:13:28 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:28.131+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=1000 volume=83 Apr 14 20:13:28 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:28.133+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title=Loverboy Apr 14 20:13:28 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:28.134+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title=Loverboy Apr 14 20:13:28 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:28 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:28 sony volumio[2438]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 20:13:28 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:28 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:28 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:28 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:28 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:28 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:28 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:28 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:28 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:28 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:28 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:28 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:28 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:28 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:28 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:28 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:28 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:28 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:28 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:28 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:28 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:28 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:28 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:28.237+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:28 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:28.241+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title=Loverboy Apr 14 20:13:28 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:28.251+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:28 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:28.253+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:28 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:28.258+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title=Loverboy Apr 14 20:13:28 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:28.258+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title=Loverboy Apr 14 20:13:28 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:28.259+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:28 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:28.263+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title=Loverboy Apr 14 20:13:28 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:28 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:28 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:28 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:28 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:28 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:28 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:28 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:28 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:28 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:28 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:28 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:28 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:28 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:28 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:28 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:28 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:28 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:28 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:28 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:28 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:28 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:28 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:28 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:28 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:28.382+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:28 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:28.382+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:28 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:28.384+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:28 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:28.385+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:28 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:28.390+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title=Loverboy Apr 14 20:13:28 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:28.391+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title=Loverboy Apr 14 20:13:28 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:28.391+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title=Loverboy Apr 14 20:13:28 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:28.400+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title=Loverboy Apr 14 20:13:28 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:28 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:28 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:28 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:28 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:28 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:28 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:28 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:28 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:28 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:28 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:28 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:28 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:28 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:28 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:28 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:28 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:28 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:28 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:28 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:28 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:28 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:28 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:28 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:28 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:28.773+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:28 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:28.778+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:28 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:28.781+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title=Loverboy Apr 14 20:13:28 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:28.783+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title=Loverboy Apr 14 20:13:28 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:28.788+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:28 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:28.790+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title=Loverboy Apr 14 20:13:28 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:28.794+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:28 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:28.798+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title=Loverboy Apr 14 20:13:28 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:28 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:28 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:28 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:28 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:28 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:28 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:28 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:28 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:28 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:28 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:28 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:28 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:28 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:28 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:28 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:28 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:28 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:28 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:28 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:28 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:28 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:28 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:28 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:28 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:28.915+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:28 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:28.918+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:28 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:28.920+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title=Loverboy Apr 14 20:13:28 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:28.921+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title=Loverboy Apr 14 20:13:28 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:28.929+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:28 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:28.932+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:28 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:28.933+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title=Loverboy Apr 14 20:13:28 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:28.933+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title=Loverboy Apr 14 20:13:28 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:28 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:28 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:28 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:28 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:28 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:28 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:28 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:28 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:28 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:28 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:28 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:28 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:28 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:28 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:29 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:29 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:29 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:29 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:29 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:29 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:29 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:29 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:29 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:29 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:29.033+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:29 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:29.039+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:29 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:29.043+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:29 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:29.046+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title=Loverboy Apr 14 20:13:29 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:29.044+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:29 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:29.047+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title=Loverboy Apr 14 20:13:29 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:29.052+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title=Loverboy Apr 14 20:13:29 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:29.062+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title=Loverboy Apr 14 20:13:42 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:42 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:42 sony volumio[2438]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 20:13:42 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:42 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:42 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:42 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:42 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:42 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:42 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:42 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:42 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:42 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:42 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:42 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:42 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:42 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:42 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:42 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:42 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:42 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:42 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:42 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:42 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:42 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:42 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:42.138+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PAUSED positionMs=13000 volume=83 Apr 14 20:13:42 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:42.140+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title=Loverboy Apr 14 20:13:42 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:42.144+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PAUSED positionMs=13000 volume=83 Apr 14 20:13:42 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:42.144+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PAUSED positionMs=13000 volume=83 Apr 14 20:13:42 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:42.145+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title=Loverboy Apr 14 20:13:42 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:42.146+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title=Loverboy Apr 14 20:13:42 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:42.146+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PAUSED positionMs=13000 volume=83 Apr 14 20:13:42 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:42.147+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title=Loverboy Apr 14 20:13:42 sony volumio[2438]: info: touch_display: Setting screensaver timeout to 20 seconds. Apr 14 20:13:44 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:44 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:44 sony volumio[2438]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 20:13:44 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:44 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:44 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:44 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:44 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:44 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:44 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:44 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:44 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:44 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:44 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:44 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:44 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:44 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:44 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:44 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:44 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:44 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:44 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:44 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:44 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:44 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:44 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:44.489+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PAUSED positionMs=0 volume=83 Apr 14 20:13:44 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:44.491+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title= Apr 14 20:13:44 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:44.491+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PAUSED positionMs=0 volume=83 Apr 14 20:13:44 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:44.495+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PAUSED positionMs=0 volume=83 Apr 14 20:13:44 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:44.497+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PAUSED positionMs=0 volume=83 Apr 14 20:13:44 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:44.499+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title= Apr 14 20:13:44 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:44.500+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title= Apr 14 20:13:44 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:44.501+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title= Apr 14 20:13:44 sony shairport-sync[7835]: {"time":1776190307197,"response":"airplayActive Success"} Apr 14 20:13:44 sony shairport-sync[8121]: % Total % Received % Xferd Average Speed Time Time Time Current Apr 14 20:13:44 sony shairport-sync[8121]: Dload Upload Total Spent Left Speed Apr 14 20:13:44 sony volumio[2438]: info: Apr 14 20:13:44 sony volumio[2438]: ---------------------------- Client requests Stop Airplay Playback Apr 14 20:13:44 sony volumio[2438]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , stopAirplayPlayback Apr 14 20:13:44 sony volumio[2438]: info: Airplay playback stop Apr 14 20:13:44 sony volumio[2438]: info: Airplay Playback Stopped, clearing state Apr 14 20:13:44 sony volumio[2438]: info: CoreStateMachine::resetVolumioState Apr 14 20:13:44 sony volumio[2438]: info: CoreStateMachine::getcurrentVolume Apr 14 20:13:44 sony volumio[2438]: info: CoreCommandRouter::volumioRetrievevolume Apr 14 20:13:44 sony shairport-sync[8121]: [158B blob data] Apr 14 20:13:44 sony volumio[2438]: info: VolumeController:: Volume=83 Mute =false Apr 14 20:13:44 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:44 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:44 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:44 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:44 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:44 sony volumio[2438]: info: CoreStateMachine::updateTrackBlock Apr 14 20:13:44 sony volumio[2438]: info: CorePlayQueue::getTrackBlock Apr 14 20:13:44 sony volumio[2438]: info: CoreCommandRouter::volumioRetrievevolume Apr 14 20:13:45 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:45.026+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PAUSED positionMs=0 volume=83 Apr 14 20:13:45 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:45.033+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title= Apr 14 20:13:45 sony volumio[2438]: info: VolumeController:: Volume=83 Mute =false Apr 14 20:13:45 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:45 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:45 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:45 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:45 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:45 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:45.203+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PAUSED positionMs=0 volume=83 Apr 14 20:13:45 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:45.210+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title= Apr 14 20:13:47 sony volumio[2438]: info: Airplay started streaming, receiving metadatas Apr 14 20:13:47 sony volumio[2438]: info: Airplay started streaming, receiving metadatas Apr 14 20:13:47 sony volumio[2438]: info: Airplay started streaming, receiving metadatas Apr 14 20:13:47 sony volumio[2438]: info: Airplay started streaming, receiving metadatas Apr 14 20:13:47 sony shairport-sync[8121]: {"time":1776190424688,"response":"stopAirplayPlayback Success"} Apr 14 20:13:47 sony shairport-sync[8131]: % Total % Received % Xferd Average Speed Time Time Time Current Apr 14 20:13:47 sony shairport-sync[8131]: Dload Upload Total Spent Left Speed Apr 14 20:13:47 sony volumio[2438]: info: Apr 14 20:13:47 sony volumio[2438]: ---------------------------- Client requests Start Airplay PlaybackRoutine Apr 14 20:13:47 sony volumio[2438]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback Apr 14 20:13:47 sony volumio[2438]: info: Airplay playback start Apr 14 20:13:47 sony shairport-sync[8131]: [158B blob data] Apr 14 20:13:47 sony volumio[2438]: verbose: New Socket.io Connection to 192.168.1.203 from 192.168.1.197 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 9 Apr 14 20:13:48 sony volumio[2438]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 14 20:13:48 sony volumio[2438]: info: CoreCommandRouter::volumioGetVisibleSources Apr 14 20:13:48 sony volumio[2438]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 14 20:13:48 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:48 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:48 sony volumio[2438]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 14 20:13:48 sony volumio[2438]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 14 20:13:48 sony volumio[2438]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Apr 14 20:13:48 sony volumio[2438]: info: Received Get System Info Apr 14 20:13:48 sony volumio[2438]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 20:13:48 sony volumio[2438]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 20:13:48 sony volumio[2438]: info: Discovery: Getting this device information Apr 14 20:13:48 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:48 sony volumio[2438]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 20:13:48 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:48 sony volumio[2438]: info: Listing playlists Apr 14 20:13:48 sony volumio[2438]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 14 20:13:48 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:48 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:48 sony volumio[2438]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 20:13:48 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:48 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:48 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:48 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:48 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:48.543+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:48 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:48.546+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title= Apr 14 20:13:48 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:48 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:48 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:48 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:48 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:48 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:48 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:48.613+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:48 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:48.623+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title= Apr 14 20:13:48 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:48 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:48 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:48 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:48 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:48 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:48 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:48.670+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:48 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:48.676+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title= Apr 14 20:13:48 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:48 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:48 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:48 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:48 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:48 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:48 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:48.714+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:48 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:48.716+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title= Apr 14 20:13:48 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:48 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:48 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:48 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:48 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:48 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:48 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:48.810+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:13:48 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:48.812+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title= Apr 14 20:13:49 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:49 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:49 sony volumio[2438]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 20:13:49 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:49 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:49 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:49 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:49 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:49 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:49 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:49 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:49 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:49 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:49 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:49 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:49 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:49 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:49 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:49 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:49 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:49 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:49 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:49 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:49 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:49 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:49 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:49.387+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs= volume=83 Apr 14 20:13:49 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:49.388+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs= volume=83 Apr 14 20:13:49 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:49.389+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title= Apr 14 20:13:49 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:49.388+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title= Apr 14 20:13:49 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:49.391+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs= volume=83 Apr 14 20:13:49 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:49.393+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title= Apr 14 20:13:49 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:49.393+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs= volume=83 Apr 14 20:13:49 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:49.395+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title= Apr 14 20:13:49 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:49 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:49 sony volumio[2438]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 20:13:49 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:49 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:49 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:49 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:49 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:49 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:49 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:49 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:49 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:49 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:49 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:49 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:49 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:49 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:49 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:49 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:49 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:49 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:49 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:49 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:49 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:49 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:49 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:49.514+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs= volume=83 Apr 14 20:13:49 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:49.519+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title= Apr 14 20:13:49 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:49.528+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs= volume=83 Apr 14 20:13:49 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:49.538+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title= Apr 14 20:13:49 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:49.543+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs= volume=83 Apr 14 20:13:49 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:49.544+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title= Apr 14 20:13:49 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:49.549+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs= volume=83 Apr 14 20:13:49 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:49.553+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title= Apr 14 20:13:49 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:49 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:49 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:49 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:49 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:49 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:49 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:49 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:49 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:49 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:49 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:49 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:49 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:49 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:49 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:49 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:50 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:50 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:50 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:50 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:50 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:50 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:50 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:50 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:50 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:50.021+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs= volume=83 Apr 14 20:13:50 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:50.023+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title= Apr 14 20:13:50 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:50.026+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs= volume=83 Apr 14 20:13:50 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:50.031+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs= volume=83 Apr 14 20:13:50 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:50.032+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title= Apr 14 20:13:50 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:50.035+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs= volume=83 Apr 14 20:13:50 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:50.032+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title= Apr 14 20:13:50 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:50.038+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title= Apr 14 20:13:50 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:50 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:50 sony volumio[2438]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 20:13:50 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:50 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:50 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:50 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:50 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:50 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:50 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:50 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:50 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:50 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:50 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:50 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:50 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:50 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:50 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:50 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:50 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:50 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:50 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:50 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:50 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:50 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:50 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:50.136+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=1000 volume=83 Apr 14 20:13:50 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:50.143+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title= Apr 14 20:13:50 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:50.148+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=1000 volume=83 Apr 14 20:13:50 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:50.151+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title= Apr 14 20:13:50 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:50.153+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=1000 volume=83 Apr 14 20:13:50 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:50.153+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=1000 volume=83 Apr 14 20:13:50 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:50.157+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title= Apr 14 20:13:50 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:50.158+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title= Apr 14 20:13:50 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:50 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:50 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:50 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:50 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:50 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:50 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:50 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:50 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:50 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:50 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:50 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:50 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:50 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:50 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:50 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:50 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:50 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:50 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:50 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:50 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:50 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:50 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:50 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:50 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:50.541+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=1000 volume=83 Apr 14 20:13:50 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:50.542+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title= Apr 14 20:13:50 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:50.544+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=1000 volume=83 Apr 14 20:13:50 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:50.549+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title= Apr 14 20:13:50 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:50.549+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=1000 volume=83 Apr 14 20:13:50 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:50.554+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title= Apr 14 20:13:50 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:50.545+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=1000 volume=83 Apr 14 20:13:50 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:50.556+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title= Apr 14 20:13:50 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:50 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:50 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:50 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:50 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:50 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:50 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:50 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:50 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:50 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:50 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:50 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:50 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:50 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:50 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:50 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:50 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:50 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:50 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:50 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:50 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:50 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:50 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:50 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:50 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:50.659+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=1000 volume=83 Apr 14 20:13:50 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:50.662+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title= Apr 14 20:13:50 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:50.668+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=1000 volume=83 Apr 14 20:13:50 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:50.674+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=1000 volume=83 Apr 14 20:13:50 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:50.676+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title= Apr 14 20:13:50 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:50.680+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title= Apr 14 20:13:50 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:50.685+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=1000 volume=83 Apr 14 20:13:50 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:50.687+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title= Apr 14 20:13:50 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:51 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:51 sony volumio[2438]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 20:13:51 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:51 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:51 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:51 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:51 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:51 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:51 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:51 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:51 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:51 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:51 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:51 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:51 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:51 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:51 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:51 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:51 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:51 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:51 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:51 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:51 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:51 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:51 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:51.067+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=2000 volume=83 Apr 14 20:13:51 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:51.068+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title= Apr 14 20:13:51 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:51.072+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=2000 volume=83 Apr 14 20:13:51 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:51.074+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title= Apr 14 20:13:51 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:51.076+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=2000 volume=83 Apr 14 20:13:51 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:51.080+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=2000 volume=83 Apr 14 20:13:51 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:51.083+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title= Apr 14 20:13:51 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:51.085+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title= Apr 14 20:13:51 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:51 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:51 sony volumio[2438]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 20:13:51 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:51 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:51 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:51 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:51 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:51 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:51 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:51 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:51 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:51 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:51 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:51 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:51 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:51 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:51 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:51 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:51 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:51 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:51 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:51 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:51 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:51 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:51 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:51.176+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=1000 volume=83 Apr 14 20:13:51 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:51.180+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title= Apr 14 20:13:51 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:51.183+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=1000 volume=83 Apr 14 20:13:51 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:51.188+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title= Apr 14 20:13:51 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:51.193+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=1000 volume=83 Apr 14 20:13:51 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:51.196+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=1000 volume=83 Apr 14 20:13:51 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:51.197+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title= Apr 14 20:13:51 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:51.200+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title= Apr 14 20:13:51 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:51 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:51 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:51 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:51 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:51 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:51 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:51 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:51 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:51 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:51 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:51 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:51 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:51 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:51 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:51 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:51 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:51 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:51 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:51 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:51 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:51 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:51 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:51 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:51 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:51.271+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=1000 volume=83 Apr 14 20:13:51 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:51.277+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title= Apr 14 20:13:51 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:51.282+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=1000 volume=83 Apr 14 20:13:51 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:51.283+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title= Apr 14 20:13:51 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:51.283+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=1000 volume=83 Apr 14 20:13:51 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:51.284+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title= Apr 14 20:13:51 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:51.288+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=1000 volume=83 Apr 14 20:13:51 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:51.289+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title= Apr 14 20:13:51 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:51 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:51 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:51 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:51 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:51 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:51 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:51 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:51 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:51 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:51 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:51 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:51 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:51 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:51 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:51 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:51 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:51 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:51 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:51 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:51 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:51 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:51 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:51 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:51 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:51.648+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=1000 volume=83 Apr 14 20:13:51 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:51.650+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title= Apr 14 20:13:51 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:51.660+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=1000 volume=83 Apr 14 20:13:51 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:51.661+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=1000 volume=83 Apr 14 20:13:51 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:51.662+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title= Apr 14 20:13:51 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:51.664+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title= Apr 14 20:13:51 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:51.667+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=1000 volume=83 Apr 14 20:13:51 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:51.670+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title= Apr 14 20:13:51 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:51 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:51 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:51 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:51 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:51 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:51 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:51 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:51 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:51 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:51 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:51 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:51 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:51 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:51 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:51 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:51 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:51 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:51 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:51 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:51 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:51 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:51 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:51 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:51 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:51.749+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=1000 volume=83 Apr 14 20:13:51 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:51.753+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=1000 volume=83 Apr 14 20:13:51 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:51.754+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title= Apr 14 20:13:51 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:51.758+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title= Apr 14 20:13:51 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:51.759+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=1000 volume=83 Apr 14 20:13:51 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:51.759+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=1000 volume=83 Apr 14 20:13:51 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:51.765+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title= Apr 14 20:13:51 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:51.767+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title= Apr 14 20:13:51 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:51 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:51 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:51 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:51 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:51 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:51 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:51 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:51 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:51 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:51 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:51 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:51 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:51 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:51 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:51 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:51 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:51 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:51 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:13:51 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:13:51 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:13:51 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:13:51 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:13:51 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:13:51 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:51.850+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=1000 volume=83 Apr 14 20:13:51 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:51.852+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=1000 volume=83 Apr 14 20:13:51 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:51.852+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=1000 volume=83 Apr 14 20:13:51 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:51.852+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=1000 volume=83 Apr 14 20:13:51 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:51.854+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title= Apr 14 20:13:51 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:51.855+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title= Apr 14 20:13:51 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:51.857+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title= Apr 14 20:13:51 sony volumio5-onboarding[2794]: time=2026-04-14T20:13:51.864+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title= Apr 14 20:13:59 sony volumio[2438]: info: CoreCommandRouter::volumioGetQueue Apr 14 20:13:59 sony volumio[2438]: info: CoreStateMachine::getQueue Apr 14 20:13:59 sony volumio[2438]: info: CorePlayQueue::getQueue Apr 14 20:14:00 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:14:00 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:14:00 sony volumio[2438]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 20:14:00 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:14:01 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:14:01 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:14:01 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:14:01 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:14:01 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:14:01 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:14:01 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:14:01 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:14:01 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:14:01 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:14:01 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:14:01 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:14:01 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:14:01 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:14:01 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:14:01 sony volumio[2438]: info: CoreCommandRouter::servicePushState Apr 14 20:14:01 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:14:01 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:14:01 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:14:01 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:14:01 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:14:01 sony volumio5-onboarding[2794]: time=2026-04-14T20:14:01.059+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:14:01 sony volumio5-onboarding[2794]: time=2026-04-14T20:14:01.060+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title= Apr 14 20:14:01 sony volumio5-onboarding[2794]: time=2026-04-14T20:14:01.066+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:14:01 sony volumio5-onboarding[2794]: time=2026-04-14T20:14:01.068+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:14:01 sony volumio5-onboarding[2794]: time=2026-04-14T20:14:01.068+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title= Apr 14 20:14:01 sony volumio5-onboarding[2794]: time=2026-04-14T20:14:01.071+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:14:01 sony volumio5-onboarding[2794]: time=2026-04-14T20:14:01.072+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title= Apr 14 20:14:01 sony volumio5-onboarding[2794]: time=2026-04-14T20:14:01.076+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title= Apr 14 20:14:01 sony shairport-sync[8131]: {"time":1776190427598,"response":"startAirplayPlayback Success"} Apr 14 20:14:01 sony shairport-sync[8162]: % Total % Received % Xferd Average Speed Time Time Time Current Apr 14 20:14:01 sony shairport-sync[8162]: Dload Upload Total Spent Left Speed Apr 14 20:14:01 sony volumio[2438]: info: Apr 14 20:14:01 sony volumio[2438]: ---------------------------- Client requests Stop Airplay Playback Apr 14 20:14:01 sony volumio[2438]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , stopAirplayPlayback Apr 14 20:14:01 sony volumio[2438]: info: Airplay playback stop Apr 14 20:14:01 sony volumio[2438]: info: Airplay Playback Stopped, clearing state Apr 14 20:14:01 sony volumio[2438]: info: CoreStateMachine::resetVolumioState Apr 14 20:14:01 sony volumio[2438]: info: CoreStateMachine::getcurrentVolume Apr 14 20:14:01 sony volumio[2438]: info: CoreCommandRouter::volumioRetrievevolume Apr 14 20:14:01 sony shairport-sync[8162]: [237B blob data] Apr 14 20:14:01 sony volumio[2438]: info: VolumeController:: Volume=83 Mute =false Apr 14 20:14:01 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:14:01 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:14:01 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:14:01 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:14:01 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:14:01 sony volumio[2438]: info: CoreStateMachine::updateTrackBlock Apr 14 20:14:01 sony volumio[2438]: info: CorePlayQueue::getTrackBlock Apr 14 20:14:01 sony volumio[2438]: info: CoreCommandRouter::volumioRetrievevolume Apr 14 20:14:01 sony volumio5-onboarding[2794]: time=2026-04-14T20:14:01.556+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.1.197:53493 @ 0x1524450" latency=14.246339ms timeout=3s volume=113 Apr 14 20:14:01 sony volumio5-onboarding[2794]: time=2026-04-14T20:14:01.556+02:00 level=ERROR msg="failed to set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.1.197:53493 @ 0x1524450" latency=14.246339ms timeout=3s volume=113 error="could not set volume to 113: volume must be between 0 and 100, got 113" Apr 14 20:14:01 sony volumio5-onboarding[2794]: time=2026-04-14T20:14:01.587+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:14:01 sony volumio5-onboarding[2794]: time=2026-04-14T20:14:01.589+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title= Apr 14 20:14:01 sony volumio[2438]: info: VolumeController:: Volume=83 Mute =false Apr 14 20:14:01 sony volumio[2438]: info: CoreStateMachine::pushState Apr 14 20:14:01 sony volumio[2438]: info: CoreCommandRouter::volumioPushState Apr 14 20:14:01 sony volumio[2438]: info: MRS: Pushing multiroomSync output update for this device Apr 14 20:14:01 sony volumio[2438]: info: MRS: Pushing multiroomSync output Apr 14 20:14:01 sony volumio[2438]: info: CoreCommandRouter::volumioGetState Apr 14 20:14:01 sony volumio5-onboarding[2794]: time=2026-04-14T20:14:01.745+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" state=STATUS_PLAYING positionMs=0 volume=83 Apr 14 20:14:01 sony volumio5-onboarding[2794]: time=2026-04-14T20:14:01.746+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:53493 @ 0x1524450" id= title= Apr 14 20:14:11 sony shairport-sync[8162]: {"time":1776190441241,"response":"stopAirplayPlayback Success"} Apr 14 20:14:11 sony shairport-sync[8183]: % Total % Received % Xferd Average Speed Time Time Time Current Apr 14 20:14:11 sony shairport-sync[8183]: Dload Upload Total Spent Left Speed Apr 14 20:14:11 sony volumio[2438]: info: Apr 14 20:14:11 sony volumio[2438]: ---------------------------- Client requests AirplayInactive Apr 14 20:14:11 sony volumio[2438]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , setAirplayInctive Apr 14 20:14:11 sony shairport-sync[8183]: [158B blob data] Apr 14 20:14:12 sony volumio[2438]: info: Preload queue cleared Apr 14 20:14:12 sony volumio[2438]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 14 20:14:12 sony volumio[2438]: info: CoreStateMachine::ClearQueue Apr 14 20:14:12 sony volumio[2438]: info: CoreStateMachine::stop Apr 14 20:14:12 sony volumio[2438]: info: CoreStateMachine::serviceStop Apr 14 20:14:12 sony volumio[2438]: info: CoreCommandRouter::serviceStop Apr 14 20:14:12 sony volumio[2438]: info: Airplay Stop Apr 14 20:14:12 sony volumio[2438]: info: Stopping Airplay Playback and sending pause command to client via USR2 Apr 14 20:14:12 sony volumio[2438]: info: CorePlayQueue::clearPlayQueue Apr 14 20:14:12 sony volumio[2438]: info: CorePlayQueue::saveQueue Apr 14 20:14:12 sony volumio[2438]: info: CoreCommandRouter::volumioPushQueue Apr 14 20:14:12 sony volumio[2438]: info: CoreStateMachine::addQueueItems Apr 14 20:14:12 sony volumio[2438]: info: CorePlayQueue::addQueueItems Apr 14 20:14:12 sony volumio[2438]: info: Preload queue cleared Apr 14 20:14:12 sony volumio[2438]: info: Adding Item to queue: music-library/USB/ssd/Metallica - Discography 1983-2023 (FLAC) 88 Apr 14 20:14:12 sony volumio[2438]: info: Exploding uri music-library/USB/ssd/Metallica - Discography 1983-2023 (FLAC) 88 in service mpd Apr 14 20:14:12 sony sudo[8186]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 3186 Apr 14 20:14:12 sony sudo[8186]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 14 20:14:12 sony sudo[8186]: pam_unix(sudo:session): session closed for user root Apr 14 20:14:12 sony shairport-sync[8183]: {"time":1776190451122,"response":"airplayInactive Success"} Apr 14 20:14:12 sony systemd[1]: shairport-sync.service: Main process exited, code=killed, status=12/USR2 Apr 14 20:14:12 sony systemd[1]: shairport-sync.service: Failed with result 'signal'. Apr 14 20:14:12 sony systemd[1]: shairport-sync.service: Consumed 53.635s CPU time. Apr 14 20:14:14 sony volumio[2438]: info: ALBUMART /albumart?cacheid=917&web=Metallica/Kill%20%E2%80%99Em%20All/extralarge&path=%2Fmnt%2FUSB%2Fssd%2FMetallica%20-%20Discography%201983-2023%20(FLAC)%2088%2F1983%20-%20Kill%20%E2%80%99Em%20All%2F01.%20Hit%20The%20Lights.flac&metadata=false Apr 14 20:14:14 sony volumio[2438]: info: URI /mnt/USB/ssd/Metallica - Discography 1983-2023 (FLAC) 88/1983 - Kill ’Em All/01. Hit The Lights.flac Apr 14 20:14:14 sony volumio[2438]: info: Shairport-Sync paused with USR2 Apr 14 20:14:14 sony volumio[2438]: info: ALBUMART /albumart?cacheid=917&web=Metallica/Kill%20%E2%80%99Em%20All/extralarge&path=%2Fmnt%2FUSB%2Fssd%2FMetallica%20-%20Discography%201983-2023%20(FLAC)%2088%2F1983%20-%20Kill%20%E2%80%99Em%20All%2F02.%20The%20Four%20Horsemen.flac&metadata=false Apr 14 20:14:14 sony volumio[2438]: info: URI /mnt/USB/ssd/Metallica - Discography 1983-2023 (FLAC) 88/1983 - Kill ’Em All/02. The Four Horsemen.flac Apr 14 20:14:14 sony volumio[2438]: info: ALBUMART /albumart?cacheid=917&web=Metallica/Kill%20%E2%80%99Em%20All/extralarge&path=%2Fmnt%2FUSB%2Fssd%2FMetallica%20-%20Discography%201983-2023%20(FLAC)%2088%2F1983%20-%20Kill%20%E2%80%99Em%20All%2F03.%20Motorbreath.flac&metadata=false Apr 14 20:14:14 sony volumio[2438]: info: URI /mnt/USB/ssd/Metallica - Discography 1983-2023 (FLAC) 88/1983 - Kill ’Em All/03. Motorbreath.flac Apr 14 20:14:14 sony volumio[2438]: info: ALBUMART /albumart?cacheid=917&web=Metallica/Kill%20%E2%80%99Em%20All/extralarge&path=%2Fmnt%2FUSB%2Fssd%2FMetallica%20-%20Discography%201983-2023%20(FLAC)%2088%2F1983%20-%20Kill%20%E2%80%99Em%20All%2F04.%20Jump%20In%20The%20Fire.flac&metadata=false Apr 14 20:14:14 sony volumio[2438]: info: URI /mnt/USB/ssd/Metallica - Discography 1983-2023 (FLAC) 88/1983 - Kill ’Em All/04. Jump In The Fire.flac Apr 14 20:14:14 sony volumio[2438]: info: ALBUMART /albumart?cacheid=917&web=Metallica/Kill%20%E2%80%99Em%20All/extralarge&path=%2Fmnt%2FUSB%2Fssd%2FMetallica%20-%20Discography%201983-2023%20(FLAC)%2088%2F1983%20-%20Kill%20%E2%80%99Em%20All%2F05.%20(Anesthesia)%20%E2%80%93%20Pulling%20Teeth.flac&metadata=false Apr 14 20:14:14 sony volumio[2438]: info: URI /mnt/USB/ssd/Metallica - Discography 1983-2023 (FLAC) 88/1983 - Kill ’Em All/05. (Anesthesia) – Pulling Teeth.flac Apr 14 20:14:14 sony volumio[2438]: info: ALBUMART /albumart?cacheid=917&web=Metallica/Kill%20%E2%80%99Em%20All/extralarge&path=%2Fmnt%2FUSB%2Fssd%2FMetallica%20-%20Discography%201983-2023%20(FLAC)%2088%2F1983%20-%20Kill%20%E2%80%99Em%20All%2F06.%20Whiplash.flac&metadata=false Apr 14 20:14:14 sony volumio[2438]: info: URI /mnt/USB/ssd/Metallica - Discography 1983-2023 (FLAC) 88/1983 - Kill ’Em All/06. Whiplash.flac Apr 14 20:14:14 sony volumio[2438]: info: ALBUMART /albumart?cacheid=917&web=Metallica/Kill%20%E2%80%99Em%20All/extralarge&path=%2Fmnt%2FUSB%2Fssd%2FMetallica%20-%20Discography%201983-2023%20(FLAC)%2088%2F1983%20-%20Kill%20%E2%80%99Em%20All%2F07.%20Phantom%20Lord.flac&metadata=false Apr 14 20:14:14 sony volumio[2438]: info: URI /mnt/USB/ssd/Metallica - Discography 1983-2023 (FLAC) 88/1983 - Kill ’Em All/07. Phantom Lord.flac Apr 14 20:14:14 sony volumio[2438]: info: ALBUMART /albumart?cacheid=917&web=Metallica/Kill%20%E2%80%99Em%20All/extralarge&path=%2Fmnt%2FUSB%2Fssd%2FMetallica%20-%20Discography%201983-2023%20(FLAC)%2088%2F1983%20-%20Kill%20%E2%80%99Em%20All%2F08.%20No%20Remorse.flac&metadata=false Apr 14 20:14:14 sony volumio[2438]: info: URI /mnt/USB/ssd/Metallica - Discography 1983-2023 (FLAC) 88/1983 - Kill ’Em All/08. No Remorse.flac Apr 14 20:14:14 sony volumio[2438]: info: ALBUMART /albumart?cacheid=917&web=Metallica/Kill%20%E2%80%99Em%20All/extralarge&path=%2Fmnt%2FUSB%2Fssd%2FMetallica%20-%20Discography%201983-2023%20(FLAC)%2088%2F1983%20-%20Kill%20%E2%80%99Em%20All%2F09.%20Seek%20%26%20Destroy.flac&metadata=false Apr 14 20:14:14 sony volumio[2438]: info: URI /mnt/USB/ssd/Metallica - Discography 1983-2023 (FLAC) 88/1983 - Kill ’Em All/09. Seek & Destroy.flac Apr 14 20:14:14 sony volumio[2438]: info: ALBUMART /albumart?cacheid=917&web=Metallica/Kill%20%E2%80%99Em%20All/extralarge&path=%2Fmnt%2FUSB%2Fssd%2FMetallica%20-%20Discography%201983-2023%20(FLAC)%2088%2F1983%20-%20Kill%20%E2%80%99Em%20All%2F10.%20Metal%20Militia.flac&metadata=false Apr 14 20:14:14 sony volumio[2438]: info: URI /mnt/USB/ssd/Metallica - Discography 1983-2023 (FLAC) 88/1983 - Kill ’Em All/10. Metal Militia.flac Apr 14 20:14:14 sony volumio[2438]: info: ALBUMART /albumart?cacheid=917&web=Metallica/Kill%20%E2%80%99Em%20All/extralarge&path=%2Fmnt%2FUSB%2Fssd%2FMetallica%20-%20Discography%201983-2023%20(FLAC)%2088%2F1983%20-%20Kill%20%E2%80%99Em%20All%2F11.%20Am%20I%20Evil_.flac&metadata=false Apr 14 20:14:14 sony volumio[2438]: info: URI /mnt/USB/ssd/Metallica - Discography 1983-2023 (FLAC) 88/1983 - Kill ’Em All/11. Am I Evil_.flac Apr 14 20:14:14 sony volumio[2438]: info: ALBUMART /albumart?cacheid=917&web=Metallica/Kill%20%E2%80%99Em%20All/extralarge&path=%2Fmnt%2FUSB%2Fssd%2FMetallica%20-%20Discography%201983-2023%20(FLAC)%2088%2F1983%20-%20Kill%20%E2%80%99Em%20All%2F12.%20Blitzkrieg.flac&metadata=false Apr 14 20:14:14 sony volumio[2438]: info: URI /mnt/USB/ssd/Metallica - Discography 1983-2023 (FLAC) 88/1983 - Kill ’Em All/12. Blitzkrieg.flac Apr 14 20:14:14 sony volumio[2438]: info: ALBUMART /albumart?cacheid=917&web=Metallica/Kill%20%3FEm%20All/extralarge&path=%2Fmnt%2FUSB%2Fssd%2FMetallica%20-%20Discography%201983-2023%20(FLAC)%2088%2F1983%20-%20Kill%20%E2%80%99Em%20All%2FMetallica%20-%20Kill%20%E2%80%99Em%20All.cue&metadata=false Apr 14 20:14:14 sony volumio[2438]: info: URI /mnt/USB/ssd/Metallica - Discography 1983-2023 (FLAC) 88/1983 - Kill ’Em All/Metallica - Kill ’Em All.cue Apr 14 20:14:14 sony volumio[2438]: info: ALBUMART /albumart?cacheid=917&web=Metallica/Kill%20%3FEm%20All/extralarge&path=%2Fmnt%2FUSB%2Fssd%2FMetallica%20-%20Discography%201983-2023%20(FLAC)%2088%2F1983%20-%20Kill%20%E2%80%99Em%20All%2FMetallica%20-%20Kill%20%E2%80%99Em%20All.cue&metadata=false Apr 14 20:14:14 sony volumio[2438]: info: URI /mnt/USB/ssd/Metallica - Discography 1983-2023 (FLAC) 88/1983 - Kill ’Em All/Metallica - Kill ’Em All.cue Apr 14 20:14:14 sony volumio[2438]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 14 20:14:14 sony volumio[2438]: Error: Unable to resolve or reject the same promise twice Apr 14 20:14:14 sony volumio[2438]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Apr 14 20:14:14 sony volumio[2438]: at /volumio/app/plugins/music_service/mpd/index.js:2582:21 Apr 14 20:14:14 sony volumio[2438]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Apr 14 20:14:14 sony volumio[2438]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Apr 14 20:14:14 sony volumio[2438]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Apr 14 20:14:14 sony volumio[2438]: at Socket.emit (node:events:514:28) Apr 14 20:14:14 sony volumio[2438]: at addChunk (node:internal/streams/readable:343:12) Apr 14 20:14:14 sony volumio[2438]: at readableAddChunk (node:internal/streams/readable:312:11) Apr 14 20:14:14 sony volumio[2438]: at Readable.push (node:internal/streams/readable:253:10) Apr 14 20:14:14 sony volumio[2438]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Apr 14 20:14:14 sony volumio[2438]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 14 20:14:19 sony sudo[8228]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-04-14 20:13' Apr 14 20:14:19 sony sudo[8228]: 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="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:07:07 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"