-- Logs begin at Thu 2019-02-14 12:11:58 EET, end at Fri 2026-04-03 00:44:46 EEST. -- Apr 03 00:43:09 rivoplus volumio[3107]: info: CoreCommandRouter::volumioNext Apr 03 00:43:09 rivoplus volumio[3107]: info: CoreStateMachine::next Apr 03 00:43:09 rivoplus volumio[3107]: info: Received next Apr 03 00:43:09 rivoplus vtcs[4051]: [close:85] Entering Apr 03 00:43:09 rivoplus vtcs[4051]: [feedThread:276] Exiting Apr 03 00:43:09 rivoplus vtcs[4051]: [close:100] Exiting Apr 03 00:43:09 rivoplus volumio[3107]: info: Pushing metadata Apr 03 00:43:09 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:43:09 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:43:09 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 00:43:09 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:43:09 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:43:09 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:43:09 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:43:09 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:43:09 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:43:09 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 00:43:09 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:43:09 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:43:09 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:43:09 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:43:09 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:43:09 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:43:09 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:43:09 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:43:09 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:43:09 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:43:09 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:09.724+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PLAYING positionMs=68718 volume=100 Apr 03 00:43:09 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:09.724+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" id= title="Like a Rock" Apr 03 00:43:09 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:09.725+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PLAYING positionMs=0 volume=100 Apr 03 00:43:09 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:09.725+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" id= title="Like a Rock" Apr 03 00:43:09 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:09.726+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PLAYING positionMs=0 volume=100 Apr 03 00:43:09 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:09.727+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" id= title="Like a Rock" Apr 03 00:43:09 rivoplus volumio[3107]: info: Signalling Playback active due to playback status change Apr 03 00:43:09 rivoplus volumio[3107]: info: Signalling Playback active due to playback status change Apr 03 00:43:09 rivoplus volumio[3107]: info: Signalling Playback active due to playback status change Apr 03 00:43:09 rivoplus volumio[3107]: info: Updating RAAT Signal Path Apr 03 00:43:09 rivoplus volumio[3107]: info: Updating RAAT Signal Path Apr 03 00:43:09 rivoplus volumio[3107]: info: Updating RAAT Signal Path Apr 03 00:43:09 rivoplus vtcs[4051]: [close:85] Entering Apr 03 00:43:09 rivoplus vtcs[4051]: [close:100] Exiting Apr 03 00:43:09 rivoplus volumio[3107]: info: Pushing metadata Apr 03 00:43:09 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:43:09 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:43:09 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:43:09 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:43:09 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:43:09 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:43:09 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:09.997+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PLAYING positionMs=0 volume=100 Apr 03 00:43:09 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:09.998+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" id= title="Like a Rock" Apr 03 00:43:10 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:43:10 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:43:10 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:43:10 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:43:10 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:43:10 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:43:10 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:43:10 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:43:10 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 00:43:10 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:43:10 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:43:10 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:43:10 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:43:10 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:43:10 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:43:10 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 00:43:10 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:43:10 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:43:10 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:43:10 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:43:10 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:43:10 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:43:10 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:43:10 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:43:10 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:43:10 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:43:10 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:10.034+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PLAYING positionMs=0 volume=100 Apr 03 00:43:10 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:10.035+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" id= title="Like a Rock" Apr 03 00:43:10 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:10.036+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PAUSED positionMs=0 volume=100 Apr 03 00:43:10 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:10.037+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" id= title="Like a Rock" Apr 03 00:43:10 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:10.038+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PLAYING positionMs=0 volume=100 Apr 03 00:43:10 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:10.038+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" id= title="Like a Rock" Apr 03 00:43:10 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:10.040+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PLAYING positionMs=0 volume=100 Apr 03 00:43:10 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:10.041+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" id= title="Like a Rock" Apr 03 00:43:10 rivoplus volumio[3107]: info: Signalling Playback active due to playback status change Apr 03 00:43:10 rivoplus volumio[3107]: info: Signalling Playback active due to playback status change Apr 03 00:43:10 rivoplus volumio[3107]: info: Signalling Playback active due to playback status change Apr 03 00:43:10 rivoplus volumio[3107]: info: Signalling Playback active due to playback status change Apr 03 00:43:10 rivoplus volumio[3107]: info: Updating RAAT Signal Path Apr 03 00:43:10 rivoplus volumio[3107]: info: Updating RAAT Signal Path Apr 03 00:43:10 rivoplus volumio[3107]: info: Updating RAAT Signal Path Apr 03 00:43:10 rivoplus volumio[3107]: info: Updating RAAT Signal Path Apr 03 00:43:10 rivoplus volumio[3107]: info: MCU Signalled Playback Inactive Apr 03 00:43:10 rivoplus volumio[3107]: info: MCU Signalled Playback Active Apr 03 00:43:10 rivoplus vtcs[4051]: [close:85] Entering Apr 03 00:43:10 rivoplus vtcs[4051]: [close:100] Exiting Apr 03 00:43:10 rivoplus vtcs[4051]: [open:50] Opening with sample rate: 44100, type: 0 Apr 03 00:43:10 rivoplus vtcs[4051]: [start:106] Entering Apr 03 00:43:10 rivoplus vtcs[4051]: [start:113] Reopening PCM device... Apr 03 00:43:10 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:43:10 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:43:10 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:43:10 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:43:10 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:43:10 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:43:10 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:10.442+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PLAYING positionMs=0 volume=100 Apr 03 00:43:10 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:10.442+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" id= title="Like a Rock" Apr 03 00:43:10 rivoplus volumio[3107]: info: Signalling Playback active due to playback status change Apr 03 00:43:10 rivoplus volumio[3107]: info: Updating RAAT Signal Path Apr 03 00:43:10 rivoplus vtcs[4051]: [start:115] PCM opened successfully. Apr 03 00:43:10 rivoplus vtcs[4051]: [start:120] Exiting Apr 03 00:43:10 rivoplus vtcs[4051]: [feedThread:223] Entering Apr 03 00:43:10 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:43:10 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:43:10 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:43:10 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:43:10 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:43:10 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:43:10 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:10.489+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PLAYING positionMs=0 volume=100 Apr 03 00:43:10 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:10.490+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" id= title="Like a Rock" Apr 03 00:43:10 rivoplus volumio[3107]: info: Signalling Playback active due to playback status change Apr 03 00:43:10 rivoplus volumio[3107]: info: Updating RAAT Signal Path Apr 03 00:43:10 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:43:10 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:43:10 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 00:43:10 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:43:10 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:43:10 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:43:10 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:43:10 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:10.875+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PLAYING positionMs=524 volume=100 Apr 03 00:43:10 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:10.876+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" id= title="Like a Rock" Apr 03 00:43:10 rivoplus volumio[3107]: info: Signalling Playback active due to playback status change Apr 03 00:43:10 rivoplus volumio[3107]: info: Updating RAAT Signal Path Apr 03 00:43:11 rivoplus volumio[3107]: info: Executing endpoint metavolumio Apr 03 00:43:11 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 03 00:43:11 rivoplus volumio[3107]: info: Executing endpoint metavolumio Apr 03 00:43:11 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 03 00:43:11 rivoplus volumio[3107]: info: Executing endpoint metavolumio Apr 03 00:43:11 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 03 00:43:12 rivoplus volumio[3107]: info: Executing endpoint metavolumio Apr 03 00:43:12 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 03 00:43:12 rivoplus volumio[3107]: info: Executing endpoint metavolumio Apr 03 00:43:12 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 03 00:43:12 rivoplus volumio[3107]: info: Executing endpoint metavolumio Apr 03 00:43:12 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 03 00:43:25 rivoplus volumio[3107]: info: CoreCommandRouter::volumioNext Apr 03 00:43:25 rivoplus volumio[3107]: info: CoreStateMachine::next Apr 03 00:43:25 rivoplus volumio[3107]: info: Received next Apr 03 00:43:25 rivoplus vtcs[4051]: [close:85] Entering Apr 03 00:43:25 rivoplus vtcs[4051]: [feedThread:276] Exiting Apr 03 00:43:25 rivoplus vtcs[4051]: [close:100] Exiting Apr 03 00:43:25 rivoplus volumio[3107]: info: Pushing metadata Apr 03 00:43:25 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:43:25 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:43:25 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 00:43:25 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:43:25 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:43:25 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:43:25 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:43:25 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:43:25 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:43:25 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 00:43:25 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:43:25 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:43:25 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:43:25 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:43:25 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:43:25 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:43:25 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:43:25 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:43:25 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:43:25 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:43:25 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:25.875+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PLAYING positionMs=14893 volume=100 Apr 03 00:43:25 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:25.875+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" id= title="Take Me Home, Country Roads" Apr 03 00:43:25 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:25.876+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PLAYING positionMs=0 volume=100 Apr 03 00:43:25 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:25.877+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" id= title="Take Me Home, Country Roads" Apr 03 00:43:25 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:25.878+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PLAYING positionMs=0 volume=100 Apr 03 00:43:25 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:25.878+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" id= title="Take Me Home, Country Roads" Apr 03 00:43:25 rivoplus volumio[3107]: info: Signalling Playback active due to playback status change Apr 03 00:43:25 rivoplus volumio[3107]: info: Signalling Playback active due to playback status change Apr 03 00:43:25 rivoplus volumio[3107]: info: Signalling Playback active due to playback status change Apr 03 00:43:25 rivoplus volumio[3107]: info: Updating RAAT Signal Path Apr 03 00:43:25 rivoplus volumio[3107]: info: Updating RAAT Signal Path Apr 03 00:43:25 rivoplus volumio[3107]: info: Updating RAAT Signal Path Apr 03 00:43:26 rivoplus vtcs[4051]: [close:85] Entering Apr 03 00:43:26 rivoplus vtcs[4051]: [close:100] Exiting Apr 03 00:43:26 rivoplus volumio[3107]: info: Pushing metadata Apr 03 00:43:26 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:43:26 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:43:26 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:43:26 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:43:26 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:43:26 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:43:26 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:26.056+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PLAYING positionMs=0 volume=100 Apr 03 00:43:26 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:26.056+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" id= title="Take Me Home, Country Roads" Apr 03 00:43:26 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:43:26 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:43:26 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:43:26 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:43:26 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:43:26 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:43:26 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:43:26 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:43:26 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 00:43:26 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:43:26 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:43:26 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:43:26 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:43:26 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:43:26 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:43:26 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 00:43:26 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:43:26 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:43:26 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:43:26 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:43:26 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:26.091+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PLAYING positionMs=0 volume=100 Apr 03 00:43:26 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:26.092+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" id= title="Take Me Home, Country Roads" Apr 03 00:43:26 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:26.094+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PAUSED positionMs=0 volume=100 Apr 03 00:43:26 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:26.095+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" id= title="Take Me Home, Country Roads" Apr 03 00:43:26 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:26.096+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PLAYING positionMs=0 volume=100 Apr 03 00:43:26 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:26.096+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" id= title="Take Me Home, Country Roads" Apr 03 00:43:26 rivoplus volumio[3107]: info: Signalling Playback active due to playback status change Apr 03 00:43:26 rivoplus volumio[3107]: info: Signalling Playback active due to playback status change Apr 03 00:43:26 rivoplus volumio[3107]: info: Signalling Playback active due to playback status change Apr 03 00:43:26 rivoplus volumio[3107]: info: Updating RAAT Signal Path Apr 03 00:43:26 rivoplus volumio[3107]: info: Updating RAAT Signal Path Apr 03 00:43:26 rivoplus volumio[3107]: info: Updating RAAT Signal Path Apr 03 00:43:26 rivoplus volumio[3107]: info: Updating RAAT Signal Path Apr 03 00:43:26 rivoplus volumio[3107]: info: MCU Signalled Playback Inactive Apr 03 00:43:26 rivoplus volumio[3107]: info: MCU Signalled Playback Active Apr 03 00:43:26 rivoplus vtcs[4051]: [close:85] Entering Apr 03 00:43:26 rivoplus vtcs[4051]: [close:100] Exiting Apr 03 00:43:26 rivoplus vtcs[4051]: [open:50] Opening with sample rate: 44100, type: 0 Apr 03 00:43:26 rivoplus vtcs[4051]: [start:106] Entering Apr 03 00:43:26 rivoplus vtcs[4051]: [start:113] Reopening PCM device... Apr 03 00:43:26 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:43:26 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:43:26 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:43:26 rivoplus vtcs[4051]: [start:115] PCM opened successfully. Apr 03 00:43:26 rivoplus vtcs[4051]: [start:120] Exiting Apr 03 00:43:26 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:43:26 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:43:26 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:43:26 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:26.502+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PLAYING positionMs=0 volume=100 Apr 03 00:43:26 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:26.503+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" id= title="Take Me Home, Country Roads" Apr 03 00:43:26 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:43:26 rivoplus vtcs[4051]: [feedThread:223] Entering Apr 03 00:43:26 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:43:26 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:43:26 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:43:26 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:43:26 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:43:26 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:26.525+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PLAYING positionMs=0 volume=100 Apr 03 00:43:26 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:26.526+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" id= title="Take Me Home, Country Roads" Apr 03 00:43:26 rivoplus volumio[3107]: info: Signalling Playback active due to playback status change Apr 03 00:43:26 rivoplus volumio[3107]: info: Signalling Playback active due to playback status change Apr 03 00:43:26 rivoplus volumio[3107]: info: Updating RAAT Signal Path Apr 03 00:43:26 rivoplus volumio[3107]: info: Updating RAAT Signal Path Apr 03 00:43:26 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:43:26 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:43:26 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:43:26 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:43:26 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:43:26 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:43:26 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:26.563+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PLAYING positionMs=0 volume=100 Apr 03 00:43:26 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:26.563+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" id= title="Take Me Home, Country Roads" Apr 03 00:43:26 rivoplus volumio[3107]: info: Signalling Playback active due to playback status change Apr 03 00:43:26 rivoplus volumio[3107]: info: Updating RAAT Signal Path Apr 03 00:43:26 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:43:26 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:43:26 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 00:43:26 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:43:26 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:43:26 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:43:26 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:43:26 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:26.927+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PLAYING positionMs=524 volume=100 Apr 03 00:43:26 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:26.928+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" id= title="Take Me Home, Country Roads" Apr 03 00:43:26 rivoplus volumio[3107]: info: Signalling Playback active due to playback status change Apr 03 00:43:26 rivoplus volumio[3107]: info: Updating RAAT Signal Path Apr 03 00:43:27 rivoplus volumio[3107]: info: Executing endpoint metavolumio Apr 03 00:43:27 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 03 00:43:27 rivoplus volumio[3107]: info: Executing endpoint metavolumio Apr 03 00:43:27 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 03 00:43:27 rivoplus volumio[3107]: info: Executing endpoint metavolumio Apr 03 00:43:27 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 03 00:43:28 rivoplus volumio[3107]: info: Executing endpoint metavolumio Apr 03 00:43:28 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 03 00:43:28 rivoplus volumio[3107]: info: Executing endpoint metavolumio Apr 03 00:43:28 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 03 00:43:28 rivoplus volumio[3107]: info: Executing endpoint metavolumio Apr 03 00:43:28 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 03 00:43:28 rivoplus volumio[3107]: info: CoreCommandRouter::volumioNext Apr 03 00:43:28 rivoplus volumio[3107]: info: CoreStateMachine::next Apr 03 00:43:28 rivoplus volumio[3107]: info: Received next Apr 03 00:43:28 rivoplus vtcs[4051]: [close:85] Entering Apr 03 00:43:28 rivoplus vtcs[4051]: [feedThread:276] Exiting Apr 03 00:43:28 rivoplus vtcs[4051]: [close:100] Exiting Apr 03 00:43:28 rivoplus volumio[3107]: info: Pushing metadata Apr 03 00:43:28 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:43:28 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:43:28 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 00:43:28 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:43:28 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:43:28 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:43:28 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:43:28 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:43:28 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:43:28 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 00:43:28 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:43:28 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:43:28 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:43:28 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:43:28 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:43:28 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:43:28 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:43:28 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:43:28 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:43:28 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:43:28 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:28.764+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PLAYING positionMs=2049 volume=100 Apr 03 00:43:28 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:28.765+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" id= title="Smooth Operator" Apr 03 00:43:28 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:28.765+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PLAYING positionMs=0 volume=100 Apr 03 00:43:28 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:28.766+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" id= title="Smooth Operator" Apr 03 00:43:28 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:28.767+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PLAYING positionMs=0 volume=100 Apr 03 00:43:28 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:28.767+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" id= title="Smooth Operator" Apr 03 00:43:28 rivoplus volumio[3107]: info: Signalling Playback active due to playback status change Apr 03 00:43:28 rivoplus volumio[3107]: info: Signalling Playback active due to playback status change Apr 03 00:43:28 rivoplus volumio[3107]: info: Signalling Playback active due to playback status change Apr 03 00:43:28 rivoplus volumio[3107]: info: Updating RAAT Signal Path Apr 03 00:43:28 rivoplus volumio[3107]: info: Updating RAAT Signal Path Apr 03 00:43:28 rivoplus volumio[3107]: info: Updating RAAT Signal Path Apr 03 00:43:28 rivoplus vtcs[4051]: [close:85] Entering Apr 03 00:43:28 rivoplus vtcs[4051]: [close:100] Exiting Apr 03 00:43:28 rivoplus volumio[3107]: info: Pushing metadata Apr 03 00:43:28 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:43:28 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:43:28 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:43:28 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:43:28 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:43:28 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:43:28 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:28.966+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PLAYING positionMs=0 volume=100 Apr 03 00:43:28 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:28.967+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" id= title="Smooth Operator" Apr 03 00:43:28 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:43:28 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:43:28 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:43:28 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:43:28 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:43:28 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:43:28 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:28.986+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PLAYING positionMs=0 volume=100 Apr 03 00:43:28 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:28.987+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" id= title="Smooth Operator" Apr 03 00:43:28 rivoplus volumio[3107]: info: Signalling Playback active due to playback status change Apr 03 00:43:28 rivoplus volumio[3107]: info: Signalling Playback active due to playback status change Apr 03 00:43:29 rivoplus volumio[3107]: info: Updating RAAT Signal Path Apr 03 00:43:29 rivoplus volumio[3107]: info: Updating RAAT Signal Path Apr 03 00:43:29 rivoplus vtcs[4051]: [close:85] Entering Apr 03 00:43:29 rivoplus vtcs[4051]: [close:100] Exiting Apr 03 00:43:29 rivoplus vtcs[4051]: [open:50] Opening with sample rate: 44100, type: 0 Apr 03 00:43:29 rivoplus vtcs[4051]: [start:106] Entering Apr 03 00:43:29 rivoplus vtcs[4051]: [start:113] Reopening PCM device... Apr 03 00:43:29 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:43:29 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:43:29 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:43:29 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:43:29 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:43:29 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:43:29 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:29.367+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PLAYING positionMs=0 volume=100 Apr 03 00:43:29 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:29.367+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" id= title="Smooth Operator" Apr 03 00:43:29 rivoplus vtcs[4051]: [start:115] PCM opened successfully. Apr 03 00:43:29 rivoplus vtcs[4051]: [start:120] Exiting Apr 03 00:43:29 rivoplus volumio[3107]: info: Signalling Playback active due to playback status change Apr 03 00:43:29 rivoplus volumio[3107]: info: Updating RAAT Signal Path Apr 03 00:43:29 rivoplus vtcs[4051]: [feedThread:223] Entering Apr 03 00:43:29 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:43:29 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:43:29 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:43:29 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:43:29 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:43:29 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:43:29 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:29.402+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PLAYING positionMs=0 volume=100 Apr 03 00:43:29 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:29.402+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" id= title="Smooth Operator" Apr 03 00:43:29 rivoplus volumio[3107]: info: Signalling Playback active due to playback status change Apr 03 00:43:29 rivoplus volumio[3107]: info: Updating RAAT Signal Path Apr 03 00:43:29 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:43:29 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:43:29 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 00:43:29 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:43:29 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:43:29 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:43:29 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:43:29 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:29.805+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PLAYING positionMs=524 volume=100 Apr 03 00:43:29 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:29.805+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" id= title="Smooth Operator" Apr 03 00:43:29 rivoplus volumio[3107]: info: Signalling Playback active due to playback status change Apr 03 00:43:29 rivoplus volumio[3107]: info: Updating RAAT Signal Path Apr 03 00:43:30 rivoplus volumio[3107]: info: Executing endpoint metavolumio Apr 03 00:43:30 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 03 00:43:30 rivoplus volumio[3107]: info: Executing endpoint metavolumio Apr 03 00:43:30 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 03 00:43:30 rivoplus volumio[3107]: info: Executing endpoint metavolumio Apr 03 00:43:30 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 03 00:43:31 rivoplus volumio[3107]: info: Executing endpoint metavolumio Apr 03 00:43:31 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 03 00:43:31 rivoplus volumio[3107]: info: Executing endpoint metavolumio Apr 03 00:43:31 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 03 00:43:31 rivoplus volumio[3107]: info: Executing endpoint metavolumio Apr 03 00:43:31 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 03 00:43:31 rivoplus volumio[3107]: info: CoreCommandRouter::volumioNext Apr 03 00:43:31 rivoplus volumio[3107]: info: CoreStateMachine::next Apr 03 00:43:31 rivoplus volumio[3107]: info: Received next Apr 03 00:43:31 rivoplus vtcs[4051]: [close:85] Entering Apr 03 00:43:32 rivoplus vtcs[4051]: [feedThread:276] Exiting Apr 03 00:43:32 rivoplus vtcs[4051]: [close:100] Exiting Apr 03 00:43:32 rivoplus volumio[3107]: info: Pushing metadata Apr 03 00:43:32 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:43:32 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:43:32 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 00:43:32 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:43:32 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:43:32 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:43:32 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:43:32 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:43:32 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:43:32 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 00:43:32 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:43:32 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:43:32 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:43:32 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:43:32 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:43:32 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:43:32 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:43:32 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:43:32 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:43:32 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:43:32 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:32.043+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PLAYING positionMs=2149 volume=100 Apr 03 00:43:32 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:32.044+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" id= title=Temptation Apr 03 00:43:32 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:32.044+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PLAYING positionMs=0 volume=100 Apr 03 00:43:32 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:32.044+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" id= title=Temptation Apr 03 00:43:32 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:32.045+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PLAYING positionMs=0 volume=100 Apr 03 00:43:32 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:32.045+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" id= title=Temptation Apr 03 00:43:32 rivoplus volumio[3107]: info: Signalling Playback active due to playback status change Apr 03 00:43:32 rivoplus volumio[3107]: info: Signalling Playback active due to playback status change Apr 03 00:43:32 rivoplus volumio[3107]: info: Signalling Playback active due to playback status change Apr 03 00:43:32 rivoplus volumio[3107]: info: Updating RAAT Signal Path Apr 03 00:43:32 rivoplus volumio[3107]: info: Updating RAAT Signal Path Apr 03 00:43:32 rivoplus vtcs[4051]: [close:85] Entering Apr 03 00:43:32 rivoplus vtcs[4051]: [close:100] Exiting Apr 03 00:43:32 rivoplus volumio[3107]: info: Pushing metadata Apr 03 00:43:32 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:43:32 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:43:32 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:43:32 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:43:32 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:43:32 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:43:32 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:32.200+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PLAYING positionMs=0 volume=100 Apr 03 00:43:32 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:32.201+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" id= title=Temptation Apr 03 00:43:32 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:43:32 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:43:32 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:43:32 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:43:32 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:43:32 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:43:32 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:32.219+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PLAYING positionMs=0 volume=100 Apr 03 00:43:32 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:32.219+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" id= title=Temptation Apr 03 00:43:32 rivoplus volumio[3107]: info: Signalling Playback active due to playback status change Apr 03 00:43:32 rivoplus volumio[3107]: info: Signalling Playback active due to playback status change Apr 03 00:43:32 rivoplus volumio[3107]: info: Updating RAAT Signal Path Apr 03 00:43:32 rivoplus volumio[3107]: info: Updating RAAT Signal Path Apr 03 00:43:32 rivoplus vtcs[4051]: [close:85] Entering Apr 03 00:43:32 rivoplus vtcs[4051]: [close:100] Exiting Apr 03 00:43:32 rivoplus vtcs[4051]: [open:50] Opening with sample rate: 44100, type: 0 Apr 03 00:43:32 rivoplus vtcs[4051]: [start:106] Entering Apr 03 00:43:32 rivoplus vtcs[4051]: [start:113] Reopening PCM device... Apr 03 00:43:32 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:43:32 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:43:32 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:43:32 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:43:32 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:43:32 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:43:32 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:32.629+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PLAYING positionMs=0 volume=100 Apr 03 00:43:32 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:32.630+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" id= title=Temptation Apr 03 00:43:32 rivoplus volumio[3107]: info: Signalling Playback active due to playback status change Apr 03 00:43:32 rivoplus vtcs[4051]: [start:115] PCM opened successfully. Apr 03 00:43:32 rivoplus vtcs[4051]: [start:120] Exiting Apr 03 00:43:32 rivoplus volumio[3107]: info: Updating RAAT Signal Path Apr 03 00:43:32 rivoplus vtcs[4051]: [feedThread:223] Entering Apr 03 00:43:32 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:43:32 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:43:32 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:43:32 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:43:32 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:43:32 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:43:32 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:32.675+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PLAYING positionMs=0 volume=100 Apr 03 00:43:32 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:32.676+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" id= title=Temptation Apr 03 00:43:32 rivoplus volumio[3107]: info: Signalling Playback active due to playback status change Apr 03 00:43:32 rivoplus volumio[3107]: info: Updating RAAT Signal Path Apr 03 00:43:33 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:43:33 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:43:33 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 00:43:33 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:43:33 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:43:33 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:43:33 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:43:33 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:33.070+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PLAYING positionMs=524 volume=100 Apr 03 00:43:33 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:33.071+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" id= title=Temptation Apr 03 00:43:33 rivoplus volumio[3107]: info: Signalling Playback active due to playback status change Apr 03 00:43:33 rivoplus volumio[3107]: info: Updating RAAT Signal Path Apr 03 00:43:34 rivoplus volumio[3107]: info: Executing endpoint metavolumio Apr 03 00:43:34 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 03 00:43:34 rivoplus volumio[3107]: info: Executing endpoint metavolumio Apr 03 00:43:34 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 03 00:43:34 rivoplus volumio[3107]: info: Executing endpoint metavolumio Apr 03 00:43:34 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 03 00:43:34 rivoplus volumio[3107]: info: Executing endpoint metavolumio Apr 03 00:43:34 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 03 00:43:34 rivoplus volumio[3107]: info: Executing endpoint metavolumio Apr 03 00:43:34 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 03 00:43:34 rivoplus volumio[3107]: info: Executing endpoint metavolumio Apr 03 00:43:34 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 03 00:43:41 rivoplus volumio[3107]: info: CoreCommandRouter::volumioNext Apr 03 00:43:41 rivoplus volumio[3107]: info: CoreStateMachine::next Apr 03 00:43:41 rivoplus volumio[3107]: info: Received next Apr 03 00:43:41 rivoplus vtcs[4051]: [close:85] Entering Apr 03 00:43:41 rivoplus vtcs[4051]: [feedThread:276] Exiting Apr 03 00:43:41 rivoplus vtcs[4051]: [close:100] Exiting Apr 03 00:43:41 rivoplus volumio[3107]: info: Pushing metadata Apr 03 00:43:41 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:43:41 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:43:41 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 00:43:41 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:43:41 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:43:41 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:43:41 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:43:41 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:43:41 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:43:41 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 00:43:41 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:43:41 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:43:41 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:43:41 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:43:41 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:43:41 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:43:41 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:43:41 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:43:41 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:43:41 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:43:41 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:41.459+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PLAYING positionMs=8446 volume=100 Apr 03 00:43:41 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:41.460+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" id= title="I Love You More Than You'll Ever Know" Apr 03 00:43:41 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:41.461+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PLAYING positionMs=0 volume=100 Apr 03 00:43:41 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:41.461+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" id= title="I Love You More Than You'll Ever Know" Apr 03 00:43:41 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:41.462+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PLAYING positionMs=0 volume=100 Apr 03 00:43:41 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:41.463+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" id= title="I Love You More Than You'll Ever Know" Apr 03 00:43:41 rivoplus volumio[3107]: info: Signalling Playback active due to playback status change Apr 03 00:43:41 rivoplus volumio[3107]: info: Signalling Playback active due to playback status change Apr 03 00:43:41 rivoplus volumio[3107]: info: Updating RAAT Signal Path Apr 03 00:43:41 rivoplus volumio[3107]: info: Pushing metadata Apr 03 00:43:41 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:43:41 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:43:41 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:43:41 rivoplus vtcs[4051]: [close:85] Entering Apr 03 00:43:41 rivoplus vtcs[4051]: [close:100] Exiting Apr 03 00:43:41 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:43:41 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:43:41 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:43:41 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:41.640+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PLAYING positionMs=0 volume=100 Apr 03 00:43:41 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:41.641+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" id= title="I Love You More Than You'll Ever Know" Apr 03 00:43:41 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:43:41 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:43:41 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:43:41 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:43:41 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:43:41 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:43:41 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:43:41 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:43:41 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 00:43:41 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:43:41 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:43:41 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:43:41 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:43:41 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:43:41 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:43:41 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 00:43:41 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:43:41 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:43:41 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:43:41 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:43:41 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:43:41 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:43:41 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:43:41 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:43:41 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:43:41 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:43:41 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:41.706+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PLAYING positionMs=0 volume=100 Apr 03 00:43:41 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:41.706+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" id= title="I Love You More Than You'll Ever Know" Apr 03 00:43:41 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:41.709+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PAUSED positionMs=0 volume=100 Apr 03 00:43:41 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:41.710+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PLAYING positionMs=0 volume=100 Apr 03 00:43:41 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:41.711+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PLAYING positionMs=0 volume=100 Apr 03 00:43:41 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:41.714+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" id= title="I Love You More Than You'll Ever Know" Apr 03 00:43:41 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:41.714+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" id= title="I Love You More Than You'll Ever Know" Apr 03 00:43:41 rivoplus volumio[3107]: info: Signalling Playback active due to playback status change Apr 03 00:43:41 rivoplus volumio[3107]: info: Signalling Playback active due to playback status change Apr 03 00:43:41 rivoplus volumio[3107]: info: Signalling Playback active due to playback status change Apr 03 00:43:41 rivoplus volumio[3107]: info: Signalling Playback active due to playback status change Apr 03 00:43:41 rivoplus volumio[3107]: info: Updating RAAT Signal Path Apr 03 00:43:41 rivoplus volumio[3107]: info: Updating RAAT Signal Path Apr 03 00:43:41 rivoplus volumio[3107]: info: Updating RAAT Signal Path Apr 03 00:43:41 rivoplus volumio[3107]: info: Updating RAAT Signal Path Apr 03 00:43:41 rivoplus volumio[3107]: info: Updating RAAT Signal Path Apr 03 00:43:41 rivoplus volumio[3107]: info: MCU Signalled Playback Inactive Apr 03 00:43:41 rivoplus volumio[3107]: info: MCU Signalled Playback Active Apr 03 00:43:42 rivoplus vtcs[4051]: [close:85] Entering Apr 03 00:43:42 rivoplus vtcs[4051]: [close:100] Exiting Apr 03 00:43:42 rivoplus vtcs[4051]: [open:50] Opening with sample rate: 44100, type: 0 Apr 03 00:43:42 rivoplus vtcs[4051]: [start:106] Entering Apr 03 00:43:42 rivoplus vtcs[4051]: [start:113] Reopening PCM device... Apr 03 00:43:42 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:43:42 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:43:42 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:43:42 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:43:42 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:43:42 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:43:42 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:42.060+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PLAYING positionMs=0 volume=100 Apr 03 00:43:42 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:42.061+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" id= title="I Love You More Than You'll Ever Know" Apr 03 00:43:42 rivoplus volumio[3107]: info: Signalling Playback active due to playback status change Apr 03 00:43:42 rivoplus volumio[3107]: info: Updating RAAT Signal Path Apr 03 00:43:42 rivoplus vtcs[4051]: [start:115] PCM opened successfully. Apr 03 00:43:42 rivoplus vtcs[4051]: [start:120] Exiting Apr 03 00:43:42 rivoplus vtcs[4051]: [feedThread:223] Entering Apr 03 00:43:42 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:43:42 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:43:42 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:43:42 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:43:42 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:43:42 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:43:42 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:42.103+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PLAYING positionMs=0 volume=100 Apr 03 00:43:42 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:42.104+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" id= title="I Love You More Than You'll Ever Know" Apr 03 00:43:42 rivoplus volumio[3107]: info: Signalling Playback active due to playback status change Apr 03 00:43:42 rivoplus volumio[3107]: info: Updating RAAT Signal Path Apr 03 00:43:42 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:43:42 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:43:42 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 00:43:42 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:43:42 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:43:42 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:43:42 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:43:42 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:42.490+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PLAYING positionMs=524 volume=100 Apr 03 00:43:42 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:43:42.490+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" id= title="I Love You More Than You'll Ever Know" Apr 03 00:43:42 rivoplus volumio[3107]: info: Signalling Playback active due to playback status change Apr 03 00:43:42 rivoplus volumio[3107]: info: Updating RAAT Signal Path Apr 03 00:43:43 rivoplus volumio[3107]: info: Executing endpoint metavolumio Apr 03 00:43:43 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 03 00:43:43 rivoplus volumio[3107]: info: Executing endpoint metavolumio Apr 03 00:43:43 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 03 00:43:43 rivoplus volumio[3107]: info: Executing endpoint metavolumio Apr 03 00:43:43 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 03 00:43:44 rivoplus volumio[3107]: info: Executing endpoint metavolumio Apr 03 00:43:44 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 03 00:43:44 rivoplus volumio[3107]: info: Executing endpoint metavolumio Apr 03 00:43:44 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 03 00:43:44 rivoplus volumio[3107]: info: Executing endpoint metavolumio Apr 03 00:43:44 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 03 00:44:24 rivoplus volumio[3107]: info: CoreCommandRouter::volumioNext Apr 03 00:44:24 rivoplus volumio[3107]: info: CoreStateMachine::next Apr 03 00:44:24 rivoplus volumio[3107]: info: Received next Apr 03 00:44:25 rivoplus vtcs[4051]: [close:85] Entering Apr 03 00:44:25 rivoplus vtcs[4051]: [feedThread:276] Exiting Apr 03 00:44:25 rivoplus vtcs[4051]: [close:100] Exiting Apr 03 00:44:25 rivoplus volumio[3107]: info: Pushing metadata Apr 03 00:44:25 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:44:25 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:44:25 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 00:44:25 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:44:25 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:44:25 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:44:25 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:44:25 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:44:25 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:44:25 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 00:44:25 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:44:25 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:44:25 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:44:25 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:44:25 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:44:25 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:44:25 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:44:25 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:44:25 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:44:25 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:44:25 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:44:25.058+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PLAYING positionMs=42880 volume=100 Apr 03 00:44:25 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:44:25.059+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" id= title="Black Magic Woman" Apr 03 00:44:25 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:44:25.059+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PLAYING positionMs=0 volume=100 Apr 03 00:44:25 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:44:25.059+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" id= title="Black Magic Woman" Apr 03 00:44:25 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:44:25.060+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PLAYING positionMs=0 volume=100 Apr 03 00:44:25 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:44:25.060+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" id= title="Black Magic Woman" Apr 03 00:44:25 rivoplus volumio[3107]: info: Signalling Playback active due to playback status change Apr 03 00:44:25 rivoplus volumio[3107]: info: Signalling Playback active due to playback status change Apr 03 00:44:25 rivoplus volumio[3107]: info: Signalling Playback active due to playback status change Apr 03 00:44:25 rivoplus volumio[3107]: info: Updating RAAT Signal Path Apr 03 00:44:25 rivoplus vtcs[4051]: [close:85] Entering Apr 03 00:44:25 rivoplus vtcs[4051]: [close:100] Exiting Apr 03 00:44:25 rivoplus volumio[3107]: info: Pushing metadata Apr 03 00:44:25 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:44:25 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:44:25 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:44:25 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:44:25 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:44:25 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:44:25 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:44:25.218+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PLAYING positionMs=0 volume=100 Apr 03 00:44:25 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:44:25.218+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" id= title="Black Magic Woman" Apr 03 00:44:25 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:44:25 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:44:25 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:44:25 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:44:25 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:44:25 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:44:25 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:44:25.237+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PLAYING positionMs=0 volume=100 Apr 03 00:44:25 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:44:25.237+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" id= title="Black Magic Woman" Apr 03 00:44:25 rivoplus volumio[3107]: info: Signalling Playback active due to playback status change Apr 03 00:44:25 rivoplus volumio[3107]: info: Updating RAAT Signal Path Apr 03 00:44:25 rivoplus volumio[3107]: info: Updating RAAT Signal Path Apr 03 00:44:25 rivoplus vtcs[4051]: [close:85] Entering Apr 03 00:44:25 rivoplus vtcs[4051]: [close:100] Exiting Apr 03 00:44:25 rivoplus vtcs[4051]: [open:50] Opening with sample rate: 44100, type: 0 Apr 03 00:44:25 rivoplus vtcs[4051]: [start:106] Entering Apr 03 00:44:25 rivoplus vtcs[4051]: [start:113] Reopening PCM device... Apr 03 00:44:25 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:44:25 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:44:25 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:44:25 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:44:25 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:44:25 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:44:25 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:44:25.717+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PLAYING positionMs=0 volume=100 Apr 03 00:44:25 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:44:25.718+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" id= title="Black Magic Woman" Apr 03 00:44:25 rivoplus vtcs[4051]: [start:115] PCM opened successfully. Apr 03 00:44:25 rivoplus vtcs[4051]: [start:120] Exiting Apr 03 00:44:25 rivoplus volumio[3107]: info: Signalling Playback active due to playback status change Apr 03 00:44:25 rivoplus volumio[3107]: info: Updating RAAT Signal Path Apr 03 00:44:25 rivoplus vtcs[4051]: [feedThread:223] Entering Apr 03 00:44:25 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:44:25 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:44:25 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:44:25 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:44:25 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:44:25 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:44:25 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:44:25.765+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PLAYING positionMs=0 volume=100 Apr 03 00:44:25 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:44:25.765+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" id= title="Black Magic Woman" Apr 03 00:44:25 rivoplus volumio[3107]: info: Signalling Playback active due to playback status change Apr 03 00:44:25 rivoplus volumio[3107]: info: Updating RAAT Signal Path Apr 03 00:44:26 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:44:26 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:44:26 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 00:44:26 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:44:26 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:44:26 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:44:26 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:44:26 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:44:26.149+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PLAYING positionMs=524 volume=100 Apr 03 00:44:26 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:44:26.149+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" id= title="Black Magic Woman" Apr 03 00:44:26 rivoplus volumio[3107]: info: Signalling Playback active due to playback status change Apr 03 00:44:26 rivoplus volumio[3107]: info: Updating RAAT Signal Path Apr 03 00:44:27 rivoplus volumio[3107]: info: Executing endpoint metavolumio Apr 03 00:44:27 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 03 00:44:27 rivoplus volumio[3107]: info: Executing endpoint metavolumio Apr 03 00:44:27 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 03 00:44:27 rivoplus volumio[3107]: info: Executing endpoint metavolumio Apr 03 00:44:27 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 03 00:44:27 rivoplus volumio[3107]: info: Executing endpoint metavolumio Apr 03 00:44:27 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 03 00:44:27 rivoplus volumio[3107]: info: Executing endpoint metavolumio Apr 03 00:44:27 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 03 00:44:27 rivoplus volumio[3107]: info: Executing endpoint metavolumio Apr 03 00:44:27 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 03 00:44:29 rivoplus volumio[3107]: info: CoreCommandRouter::volumioNext Apr 03 00:44:29 rivoplus volumio[3107]: info: CoreStateMachine::next Apr 03 00:44:29 rivoplus volumio[3107]: info: Received next Apr 03 00:44:29 rivoplus vtcs[4051]: [close:85] Entering Apr 03 00:44:29 rivoplus vtcs[4051]: [feedThread:276] Exiting Apr 03 00:44:29 rivoplus vtcs[4051]: [close:100] Exiting Apr 03 00:44:29 rivoplus volumio[3107]: info: Pushing metadata Apr 03 00:44:29 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:44:29 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:44:29 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 00:44:29 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:44:29 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:44:29 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:44:29 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:44:29 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:44:29.684+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PLAYING positionMs=3698 volume=100 Apr 03 00:44:29 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:44:29.685+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" id= title=Tulipani Apr 03 00:44:29 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:44:29 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:44:29 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 00:44:29 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:44:29 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:44:29 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:44:29 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:44:29 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:44:29 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:44:29 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:44:29 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:44:29 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:44:29 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:44:29 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:44:29.712+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PLAYING positionMs=0 volume=100 Apr 03 00:44:29 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:44:29.713+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" id= title=Tulipani Apr 03 00:44:29 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:44:29.715+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PLAYING positionMs=0 volume=100 Apr 03 00:44:29 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:44:29.715+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" id= title=Tulipani Apr 03 00:44:29 rivoplus volumio[3107]: info: Signalling Playback active due to playback status change Apr 03 00:44:29 rivoplus volumio[3107]: info: Signalling Playback active due to playback status change Apr 03 00:44:29 rivoplus volumio[3107]: info: Signalling Playback active due to playback status change Apr 03 00:44:29 rivoplus volumio[3107]: info: Updating RAAT Signal Path Apr 03 00:44:29 rivoplus volumio[3107]: info: Updating RAAT Signal Path Apr 03 00:44:29 rivoplus volumio[3107]: info: Updating RAAT Signal Path Apr 03 00:44:29 rivoplus vtcs[4051]: [close:85] Entering Apr 03 00:44:29 rivoplus vtcs[4051]: [close:100] Exiting Apr 03 00:44:29 rivoplus volumio[3107]: info: Pushing metadata Apr 03 00:44:29 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:44:29 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:44:29 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:44:29 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:44:29 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:44:29 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:44:29 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:44:29.846+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PLAYING positionMs=0 volume=100 Apr 03 00:44:29 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:44:29.847+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" id= title=Tulipani Apr 03 00:44:29 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:44:29 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:44:29 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:44:29 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:44:29 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:44:29 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:44:29 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:44:29 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:44:29 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 00:44:29 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:44:29 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:44:29 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:44:29 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:44:29 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:44:29 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:44:29 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 00:44:29 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:44:29 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:44:29 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:44:29 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:44:29 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:44:29 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:44:29 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:44:29 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:44:29 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:44:29 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:44:29 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:44:29.889+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PLAYING positionMs=0 volume=100 Apr 03 00:44:29 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:44:29.889+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" id= title=Tulipani Apr 03 00:44:29 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:44:29.892+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PAUSED positionMs=0 volume=100 Apr 03 00:44:29 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:44:29.892+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" id= title=Tulipani Apr 03 00:44:29 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:44:29.894+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PLAYING positionMs=0 volume=100 Apr 03 00:44:29 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:44:29.894+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" id= title=Tulipani Apr 03 00:44:29 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:44:29.905+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PLAYING positionMs=0 volume=100 Apr 03 00:44:29 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:44:29.907+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" id= title=Tulipani Apr 03 00:44:29 rivoplus volumio[3107]: info: Signalling Playback active due to playback status change Apr 03 00:44:29 rivoplus volumio[3107]: info: Signalling Playback active due to playback status change Apr 03 00:44:29 rivoplus volumio[3107]: info: Updating RAAT Signal Path Apr 03 00:44:29 rivoplus volumio[3107]: info: Updating RAAT Signal Path Apr 03 00:44:29 rivoplus volumio[3107]: info: Updating RAAT Signal Path Apr 03 00:44:29 rivoplus volumio[3107]: info: Updating RAAT Signal Path Apr 03 00:44:30 rivoplus volumio[3107]: info: MCU Signalled Playback Inactive Apr 03 00:44:30 rivoplus volumio[3107]: info: MCU Signalled Playback Active Apr 03 00:44:30 rivoplus vtcs[4051]: [close:85] Entering Apr 03 00:44:30 rivoplus vtcs[4051]: [close:100] Exiting Apr 03 00:44:30 rivoplus vtcs[4051]: [open:50] Opening with sample rate: 44100, type: 0 Apr 03 00:44:30 rivoplus vtcs[4051]: [start:106] Entering Apr 03 00:44:30 rivoplus vtcs[4051]: [start:113] Reopening PCM device... Apr 03 00:44:30 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:44:30 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:44:30 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:44:30 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:44:30 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:44:30 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:44:30 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:44:30.280+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PLAYING positionMs=0 volume=100 Apr 03 00:44:30 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:44:30.281+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" id= title=Tulipani Apr 03 00:44:30 rivoplus volumio[3107]: info: Signalling Playback active due to playback status change Apr 03 00:44:30 rivoplus volumio[3107]: info: Updating RAAT Signal Path Apr 03 00:44:30 rivoplus vtcs[4051]: [start:115] PCM opened successfully. Apr 03 00:44:30 rivoplus vtcs[4051]: [start:120] Exiting Apr 03 00:44:30 rivoplus vtcs[4051]: [feedThread:223] Entering Apr 03 00:44:30 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:44:30 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:44:30 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:44:30 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:44:30 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:44:30 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:44:30 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:44:30.326+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PLAYING positionMs=0 volume=100 Apr 03 00:44:30 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:44:30.326+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" id= title=Tulipani Apr 03 00:44:30 rivoplus volumio[3107]: info: Signalling Playback active due to playback status change Apr 03 00:44:30 rivoplus volumio[3107]: info: Updating RAAT Signal Path Apr 03 00:44:30 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:44:30 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:44:30 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 00:44:30 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:44:30 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:44:30 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:44:30 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:44:30 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:44:30.716+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PLAYING positionMs=524 volume=100 Apr 03 00:44:30 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:44:30.717+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" id= title=Tulipani Apr 03 00:44:30 rivoplus volumio[3107]: info: Signalling Playback active due to playback status change Apr 03 00:44:30 rivoplus volumio[3107]: info: Updating RAAT Signal Path Apr 03 00:44:31 rivoplus volumio[3107]: info: Executing endpoint metavolumio Apr 03 00:44:31 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 03 00:44:31 rivoplus volumio[3107]: info: Executing endpoint metavolumio Apr 03 00:44:31 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 03 00:44:31 rivoplus volumio[3107]: info: Executing endpoint metavolumio Apr 03 00:44:31 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 03 00:44:32 rivoplus volumio[3107]: info: Executing endpoint metavolumio Apr 03 00:44:32 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 03 00:44:32 rivoplus volumio[3107]: info: Executing endpoint metavolumio Apr 03 00:44:32 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 03 00:44:32 rivoplus volumio[3107]: info: Executing endpoint metavolumio Apr 03 00:44:32 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 03 00:44:36 rivoplus volumio[3107]: info: CoreCommandRouter::volumioNext Apr 03 00:44:36 rivoplus volumio[3107]: info: CoreStateMachine::next Apr 03 00:44:36 rivoplus volumio[3107]: info: Received next Apr 03 00:44:36 rivoplus vtcs[4051]: [close:85] Entering Apr 03 00:44:36 rivoplus vtcs[4051]: [feedThread:276] Exiting Apr 03 00:44:36 rivoplus vtcs[4051]: [close:100] Exiting Apr 03 00:44:36 rivoplus volumio[3107]: info: Pushing metadata Apr 03 00:44:36 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:44:36 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:44:36 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 00:44:36 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:44:36 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:44:36 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:44:36 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:44:36 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:44:36 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:44:36 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 00:44:36 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:44:36 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:44:36 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:44:36 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:44:36 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:44:36 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:44:36 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:44:36 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:44:36 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:44:36 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:44:36 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:44:36.189+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PLAYING positionMs=5472 volume=100 Apr 03 00:44:36 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:44:36.189+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" id= title="On the Sunny Side of the Street" Apr 03 00:44:36 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:44:36.190+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PLAYING positionMs=0 volume=100 Apr 03 00:44:36 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:44:36.191+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" id= title="On the Sunny Side of the Street" Apr 03 00:44:36 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:44:36.192+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PLAYING positionMs=0 volume=100 Apr 03 00:44:36 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:44:36.192+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" id= title="On the Sunny Side of the Street" Apr 03 00:44:36 rivoplus volumio[3107]: info: Signalling Playback active due to playback status change Apr 03 00:44:36 rivoplus volumio[3107]: info: Signalling Playback active due to playback status change Apr 03 00:44:36 rivoplus volumio[3107]: info: Signalling Playback active due to playback status change Apr 03 00:44:36 rivoplus volumio[3107]: info: Updating RAAT Signal Path Apr 03 00:44:36 rivoplus vtcs[4051]: [close:85] Entering Apr 03 00:44:36 rivoplus vtcs[4051]: [close:100] Exiting Apr 03 00:44:36 rivoplus volumio[3107]: info: Pushing metadata Apr 03 00:44:36 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:44:36 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:44:36 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:44:36 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:44:36 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:44:36 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:44:36 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:44:36.339+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PLAYING positionMs=0 volume=100 Apr 03 00:44:36 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:44:36.340+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" id= title="On the Sunny Side of the Street" Apr 03 00:44:36 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:44:36 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:44:36 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:44:36 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:44:36 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:44:36 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:44:36 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:44:36 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:44:36 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 00:44:36 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:44:36 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:44:36 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:44:36 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:44:36 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:44:36 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:44:36 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 00:44:36 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:44:36 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:44:36 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:44:36 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:44:36 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:44:36 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:44:36 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:44:36 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:44:36 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:44:36 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:44:36 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:44:36.379+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PLAYING positionMs=0 volume=100 Apr 03 00:44:36 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:44:36.379+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" id= title="On the Sunny Side of the Street" Apr 03 00:44:36 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:44:36.382+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PAUSED positionMs=0 volume=100 Apr 03 00:44:36 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:44:36.382+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" id= title="On the Sunny Side of the Street" Apr 03 00:44:36 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:44:36.383+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PLAYING positionMs=0 volume=100 Apr 03 00:44:36 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:44:36.384+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" id= title="On the Sunny Side of the Street" Apr 03 00:44:36 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:44:36.387+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PLAYING positionMs=0 volume=100 Apr 03 00:44:36 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:44:36.388+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" id= title="On the Sunny Side of the Street" Apr 03 00:44:36 rivoplus volumio[3107]: info: Signalling Playback active due to playback status change Apr 03 00:44:36 rivoplus volumio[3107]: info: Signalling Playback active due to playback status change Apr 03 00:44:36 rivoplus volumio[3107]: info: Updating RAAT Signal Path Apr 03 00:44:36 rivoplus volumio[3107]: info: Updating RAAT Signal Path Apr 03 00:44:36 rivoplus volumio[3107]: info: Updating RAAT Signal Path Apr 03 00:44:36 rivoplus volumio[3107]: info: Updating RAAT Signal Path Apr 03 00:44:36 rivoplus volumio[3107]: info: Updating RAAT Signal Path Apr 03 00:44:36 rivoplus volumio[3107]: info: MCU Signalled Playback Inactive Apr 03 00:44:36 rivoplus volumio[3107]: info: MCU Signalled Playback Active Apr 03 00:44:36 rivoplus vtcs[4051]: [close:85] Entering Apr 03 00:44:36 rivoplus vtcs[4051]: [close:100] Exiting Apr 03 00:44:36 rivoplus vtcs[4051]: [open:50] Opening with sample rate: 44100, type: 3 Apr 03 00:44:36 rivoplus vtcs[4051]: [start:106] Entering Apr 03 00:44:36 rivoplus vtcs[4051]: [start:113] Reopening PCM device... Apr 03 00:44:36 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:44:36 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:44:36 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 00:44:36 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:44:36 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:44:36 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:44:36 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:44:36 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:44:36.761+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PLAYING positionMs=0 volume=100 Apr 03 00:44:36 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:44:36.761+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" id= title="On the Sunny Side of the Street" Apr 03 00:44:36 rivoplus vtcs[4051]: [start:115] PCM opened successfully. Apr 03 00:44:36 rivoplus vtcs[4051]: [start:120] Exiting Apr 03 00:44:36 rivoplus vtcs[4051]: [feedThread:223] Entering Apr 03 00:44:36 rivoplus volumio[3107]: info: Signalling Playback active due to playback status change Apr 03 00:44:36 rivoplus volumio[3107]: info: Updating RAAT Signal Path Apr 03 00:44:36 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:44:36 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:44:36 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:44:36 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:44:36 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:44:36 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:44:36 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:44:36.801+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PLAYING positionMs=0 volume=100 Apr 03 00:44:36 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:44:36.801+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" id= title="On the Sunny Side of the Street" Apr 03 00:44:36 rivoplus volumio[3107]: info: Signalling Playback active due to playback status change Apr 03 00:44:36 rivoplus volumio[3107]: info: Updating RAAT Signal Path Apr 03 00:44:37 rivoplus volumio[3107]: info: CoreCommandRouter::servicePushState Apr 03 00:44:37 rivoplus volumio[3107]: info: CoreStateMachine::pushState Apr 03 00:44:37 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 00:44:37 rivoplus volumio[3107]: info: CoreCommandRouter::volumioPushState Apr 03 00:44:37 rivoplus volumio[3107]: info: CoreCommandRouter::volumioGetState Apr 03 00:44:37 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Apr 03 00:44:37 rivoplus volumio[3107]: info: MRS: Pushing multiroomSync output Apr 03 00:44:37 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:44:37.188+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" state=STATUS_PLAYING positionMs=524 volume=100 Apr 03 00:44:37 rivoplus volumio5-onboarding[3637]: time=2026-04-03T00:44:37.188+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.78:59566 @ 0x210f050" id= title="On the Sunny Side of the Street" Apr 03 00:44:37 rivoplus volumio[3107]: info: Signalling Playback active due to playback status change Apr 03 00:44:37 rivoplus volumio[3107]: info: Updating RAAT Signal Path Apr 03 00:44:38 rivoplus volumio[3107]: info: Executing endpoint metavolumio Apr 03 00:44:38 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 03 00:44:38 rivoplus volumio[3107]: info: Executing endpoint metavolumio Apr 03 00:44:38 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 03 00:44:38 rivoplus volumio[3107]: info: Executing endpoint metavolumio Apr 03 00:44:38 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 03 00:44:39 rivoplus volumio[3107]: info: Executing endpoint metavolumio Apr 03 00:44:39 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 03 00:44:39 rivoplus volumio[3107]: info: Executing endpoint metavolumio Apr 03 00:44:39 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 03 00:44:39 rivoplus volumio[3107]: info: Executing endpoint metavolumio Apr 03 00:44:39 rivoplus volumio[3107]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 03 00:44:45 rivoplus volumio[3107]: info: CoreCommandRouter::volumioRemoveQueueItem Apr 03 00:44:45 rivoplus volumio[3107]: info: CoreStateMachine::removeQueueItem Apr 03 00:44:45 rivoplus volumio[3107]: info: CoreStateMachine::stop Apr 03 00:44:45 rivoplus volumio[3107]: info: CoreStateMachine::serviceStop Apr 03 00:44:45 rivoplus volumio[3107]: info: CoreCommandRouter::serviceStop Apr 03 00:44:45 rivoplus vtcs[4051]: [close:85] Entering Apr 03 00:44:45 rivoplus volumio[3107]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 03 00:44:45 rivoplus vtcs[4051]: [feedThread:276] Exiting Apr 03 00:44:45 rivoplus vtcs[4051]: [close:100] Exiting Apr 03 00:44:45 rivoplus volumio[3107]: TypeError: Cannot read property 'then' of undefined Apr 03 00:44:45 rivoplus volumio[3107]: at CoreStateMachine.removeQueueItem (/volumio/app/statemachine.js:1372:7) Apr 03 00:44:45 rivoplus volumio[3107]: at CoreCommandRouter.volumioRemoveQueueItem (/volumio/app/index.js:121:28) Apr 03 00:44:45 rivoplus volumio[3107]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:173:33) Apr 03 00:44:45 rivoplus volumio[3107]: at Socket.emit (events.js:400:28) Apr 03 00:44:45 rivoplus volumio[3107]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Apr 03 00:44:45 rivoplus volumio[3107]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Apr 03 00:44:45 rivoplus volumio[3107]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 03 00:44:46 rivoplus sudo[11716]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-04-03 00:43 Apr 03 00:44:46 rivoplus sudo[11716]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 11:19:27 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo Plus" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo Plus" VOLUMIO_HASH="a7285124ffc88c43265bb2db03526f6a"