-- Logs begin at Thu 2019-02-14 12:11:58 IST, end at Tue 2026-10-13 18:08:44 IDT. -- Oct 13 18:07:01 rivoplus volumio[3321]: info: CoreCommandRouter::servicePushState Oct 13 18:07:01 rivoplus volumio[3321]: info: CoreStateMachine::pushState Oct 13 18:07:01 rivoplus volumio[3321]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 18:07:01 rivoplus volumio[3321]: info: CoreCommandRouter::volumioPushState Oct 13 18:07:01 rivoplus volumio[3321]: info: CoreCommandRouter::volumioGetState Oct 13 18:07:01 rivoplus volumio[3321]: info: MRS: Pushing multiroomSync output update for this device Oct 13 18:07:01 rivoplus volumio[3321]: info: MRS: Pushing multiroomSync output Oct 13 18:07:01 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:07:01.785+03:00 level=INFO msg="emitting player state changed event" component=server peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" state=STATUS_PLAYING positionMs=90000 volume=100 Oct 13 18:07:01 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:07:01.786+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" id= title="St Thomas" Oct 13 18:07:04 rivoplus volumio[3321]: info: CoreCommandRouter::servicePushState Oct 13 18:07:04 rivoplus volumio[3321]: info: CoreStateMachine::pushState Oct 13 18:07:04 rivoplus volumio[3321]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 18:07:04 rivoplus volumio[3321]: info: CoreCommandRouter::volumioPushState Oct 13 18:07:04 rivoplus volumio[3321]: info: CoreCommandRouter::volumioGetState Oct 13 18:07:04 rivoplus volumio[3321]: info: MRS: Pushing multiroomSync output update for this device Oct 13 18:07:04 rivoplus volumio[3321]: info: MRS: Pushing multiroomSync output Oct 13 18:07:04 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:07:04.804+03:00 level=INFO msg="emitting player state changed event" component=server peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" state=STATUS_PLAYING positionMs=93000 volume=100 Oct 13 18:07:04 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:07:04.805+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" id= title="St Thomas" Oct 13 18:07:08 rivoplus volumio[3321]: info: [VRCP] already in control, acknowledged Oct 13 18:07:08 rivoplus volumio[3321]: info: CoreCommandRouter::servicePushState Oct 13 18:07:08 rivoplus volumio[3321]: info: CoreStateMachine::pushState Oct 13 18:07:08 rivoplus volumio[3321]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 18:07:08 rivoplus volumio[3321]: info: CoreCommandRouter::volumioPushState Oct 13 18:07:08 rivoplus volumio[3321]: info: CoreCommandRouter::volumioGetState Oct 13 18:07:08 rivoplus volumio[3321]: info: MRS: Pushing multiroomSync output update for this device Oct 13 18:07:08 rivoplus volumio[3321]: info: MRS: Pushing multiroomSync output Oct 13 18:07:08 rivoplus volumio[3321]: info: CoreCommandRouter::servicePushState Oct 13 18:07:08 rivoplus volumio[3321]: info: CoreStateMachine::pushState Oct 13 18:07:08 rivoplus volumio[3321]: info: CoreCommandRouter::volumioPushState Oct 13 18:07:08 rivoplus volumio[3321]: info: CoreCommandRouter::volumioGetState Oct 13 18:07:08 rivoplus volumio[3321]: info: MRS: Pushing multiroomSync output update for this device Oct 13 18:07:08 rivoplus volumio[3321]: info: MRS: Pushing multiroomSync output Oct 13 18:07:08 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:07:08.364+03:00 level=INFO msg="emitting player state changed event" component=server peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" state=STATUS_PLAYING positionMs=96000 volume=100 Oct 13 18:07:08 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:07:08.364+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" id= title="St Thomas" Oct 13 18:07:08 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:07:08.366+03:00 level=INFO msg="emitting player state changed event" component=server peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" state=STATUS_PLAYING positionMs=96000 volume=100 Oct 13 18:07:08 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:07:08.366+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" id= title="St Thomas" Oct 13 18:07:08 rivoplus volumio[3321]: info: CoreCommandRouter::servicePushState Oct 13 18:07:08 rivoplus volumio[3321]: info: CoreStateMachine::pushState Oct 13 18:07:08 rivoplus volumio[3321]: info: CoreCommandRouter::volumioPushState Oct 13 18:07:08 rivoplus volumio[3321]: info: CoreCommandRouter::volumioGetState Oct 13 18:07:08 rivoplus volumio[3321]: info: MRS: Pushing multiroomSync output update for this device Oct 13 18:07:08 rivoplus volumio[3321]: info: MRS: Pushing multiroomSync output Oct 13 18:07:08 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:07:08.403+03:00 level=INFO msg="emitting player state changed event" component=server peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" state=STATUS_PLAYING positionMs=96000 volume=100 Oct 13 18:07:08 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:07:08.404+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" id= title="St Thomas" Oct 13 18:07:08 rivoplus volumio[3321]: [VRCP] RAAT stopped playing Oct 13 18:07:08 rivoplus volumio[3321]: info: CoreCommandRouter::servicePushState Oct 13 18:07:08 rivoplus volumio[3321]: info: CoreStateMachine::pushState Oct 13 18:07:08 rivoplus volumio[3321]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 18:07:08 rivoplus volumio[3321]: info: CoreCommandRouter::volumioPushState Oct 13 18:07:08 rivoplus volumio[3321]: info: CoreCommandRouter::volumioGetState Oct 13 18:07:08 rivoplus volumio[3321]: info: MRS: Pushing multiroomSync output update for this device Oct 13 18:07:08 rivoplus volumio[3321]: info: MRS: Pushing multiroomSync output Oct 13 18:07:08 rivoplus volumio[3321]: info: CorePlayQueue::getTrack 0 Oct 13 18:07:08 rivoplus volumio[3321]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received raat Oct 13 18:07:08 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:07:08.525+03:00 level=INFO msg="emitting player state changed event" component=server peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" state=STATUS_STOPPED positionMs= volume=100 Oct 13 18:07:08 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:07:08.525+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" id= title="Trade it for the Night (Live in Carré)" Oct 13 18:07:08 rivoplus volumio[3321]: info: MCU Signalled Playback Inactive Oct 13 18:07:08 rivoplus volumio[3321]: info: CoreCommandRouter::servicePushState Oct 13 18:07:08 rivoplus volumio[3321]: info: CoreStateMachine::pushState Oct 13 18:07:08 rivoplus volumio[3321]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 18:07:08 rivoplus volumio[3321]: info: CoreCommandRouter::volumioPushState Oct 13 18:07:08 rivoplus volumio[3321]: info: CoreCommandRouter::volumioGetState Oct 13 18:07:08 rivoplus volumio[3321]: info: MRS: Pushing multiroomSync output update for this device Oct 13 18:07:08 rivoplus volumio[3321]: info: MRS: Pushing multiroomSync output Oct 13 18:07:08 rivoplus volumio[3321]: info: CorePlayQueue::getTrack 0 Oct 13 18:07:08 rivoplus volumio[3321]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received raat Oct 13 18:07:08 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:07:08.781+03:00 level=INFO msg="emitting player state changed event" component=server peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" state=STATUS_STOPPED positionMs= volume=100 Oct 13 18:07:08 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:07:08.782+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" id= title="Trade it for the Night (Live in Carré)" Oct 13 18:07:09 rivoplus volumio[3321]: info: CoreCommandRouter::servicePushState Oct 13 18:07:09 rivoplus volumio[3321]: info: CoreStateMachine::pushState Oct 13 18:07:09 rivoplus volumio[3321]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 18:07:09 rivoplus volumio[3321]: info: CoreCommandRouter::volumioPushState Oct 13 18:07:09 rivoplus volumio[3321]: info: CoreCommandRouter::volumioGetState Oct 13 18:07:09 rivoplus volumio[3321]: info: MRS: Pushing multiroomSync output update for this device Oct 13 18:07:09 rivoplus volumio[3321]: info: MRS: Pushing multiroomSync output Oct 13 18:07:09 rivoplus volumio[3321]: info: CorePlayQueue::getTrack 0 Oct 13 18:07:09 rivoplus volumio[3321]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received raat Oct 13 18:07:09 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:07:09.334+03:00 level=INFO msg="emitting player state changed event" component=server peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" state=STATUS_STOPPED positionMs= volume=100 Oct 13 18:07:09 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:07:09.335+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" id= title="Trade it for the Night (Live in Carré)" Oct 13 18:07:09 rivoplus volumio[3321]: info: CoreCommandRouter::servicePushState Oct 13 18:07:09 rivoplus volumio[3321]: info: CoreStateMachine::pushState Oct 13 18:07:09 rivoplus volumio[3321]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 18:07:09 rivoplus volumio[3321]: info: CoreCommandRouter::volumioPushState Oct 13 18:07:09 rivoplus volumio[3321]: info: CoreCommandRouter::volumioGetState Oct 13 18:07:09 rivoplus volumio[3321]: info: MRS: Pushing multiroomSync output update for this device Oct 13 18:07:09 rivoplus volumio[3321]: info: MRS: Pushing multiroomSync output Oct 13 18:07:09 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:07:09.541+03:00 level=INFO msg="emitting player state changed event" component=server peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" state=STATUS_PLAYING positionMs= volume=100 Oct 13 18:07:09 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:07:09.541+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" id= title="Trade it for the Night (Live in Carré)" Oct 13 18:07:09 rivoplus volumio[3321]: info: Signalling Playback active due to playback status change Oct 13 18:07:09 rivoplus volumio[3321]: info: MCU Signalled Playback Active Oct 13 18:07:10 rivoplus volumio[3321]: info: Executing endpoint metavolumio Oct 13 18:07:10 rivoplus volumio[3321]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 13 18:07:10 rivoplus volumio[3321]: info: Executing endpoint metavolumio Oct 13 18:07:10 rivoplus volumio[3321]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 13 18:07:10 rivoplus volumio[3321]: info: Executing endpoint metavolumio Oct 13 18:07:10 rivoplus volumio[3321]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 13 18:07:13 rivoplus volumio[3321]: info: CoreCommandRouter::servicePushState Oct 13 18:07:13 rivoplus volumio[3321]: info: CoreStateMachine::pushState Oct 13 18:07:13 rivoplus volumio[3321]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 18:07:13 rivoplus volumio[3321]: info: CoreCommandRouter::volumioPushState Oct 13 18:07:13 rivoplus volumio[3321]: info: CoreCommandRouter::volumioGetState Oct 13 18:07:13 rivoplus volumio[3321]: info: MRS: Pushing multiroomSync output update for this device Oct 13 18:07:13 rivoplus volumio[3321]: info: MRS: Pushing multiroomSync output Oct 13 18:07:13 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:07:13.089+03:00 level=INFO msg="emitting player state changed event" component=server peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" state=STATUS_PLAYING positionMs=2000 volume=100 Oct 13 18:07:13 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:07:13.091+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" id= title="Trade it for the Night (Live in Carré)" Oct 13 18:07:17 rivoplus volumio[3321]: info: CoreCommandRouter::servicePushState Oct 13 18:07:17 rivoplus volumio[3321]: info: CoreStateMachine::pushState Oct 13 18:07:17 rivoplus volumio[3321]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 18:07:17 rivoplus volumio[3321]: info: CoreCommandRouter::volumioPushState Oct 13 18:07:17 rivoplus volumio[3321]: info: CoreCommandRouter::volumioGetState Oct 13 18:07:17 rivoplus volumio[3321]: info: MRS: Pushing multiroomSync output update for this device Oct 13 18:07:17 rivoplus volumio[3321]: info: MRS: Pushing multiroomSync output Oct 13 18:07:17 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:07:17.106+03:00 level=INFO msg="emitting player state changed event" component=server peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" state=STATUS_PLAYING positionMs=6000 volume=100 Oct 13 18:07:17 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:07:17.107+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" id= title="Trade it for the Night (Live in Carré)" Oct 13 18:07:21 rivoplus volumio[3321]: info: CoreCommandRouter::servicePushState Oct 13 18:07:21 rivoplus volumio[3321]: info: CoreStateMachine::pushState Oct 13 18:07:21 rivoplus volumio[3321]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 18:07:21 rivoplus volumio[3321]: info: CoreCommandRouter::volumioPushState Oct 13 18:07:21 rivoplus volumio[3321]: info: CoreCommandRouter::volumioGetState Oct 13 18:07:21 rivoplus volumio[3321]: info: MRS: Pushing multiroomSync output update for this device Oct 13 18:07:21 rivoplus volumio[3321]: info: MRS: Pushing multiroomSync output Oct 13 18:07:21 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:07:21.111+03:00 level=INFO msg="emitting player state changed event" component=server peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" state=STATUS_PLAYING positionMs=10000 volume=100 Oct 13 18:07:21 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:07:21.112+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" id= title="Trade it for the Night (Live in Carré)" Oct 13 18:07:24 rivoplus volumio[3321]: info: CoreCommandRouter::servicePushState Oct 13 18:07:24 rivoplus volumio[3321]: info: CoreStateMachine::pushState Oct 13 18:07:24 rivoplus volumio[3321]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 18:07:24 rivoplus volumio[3321]: info: CoreCommandRouter::volumioPushState Oct 13 18:07:24 rivoplus volumio[3321]: info: CoreCommandRouter::volumioGetState Oct 13 18:07:24 rivoplus volumio[3321]: info: MRS: Pushing multiroomSync output update for this device Oct 13 18:07:24 rivoplus volumio[3321]: info: MRS: Pushing multiroomSync output Oct 13 18:07:24 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:07:24.137+03:00 level=INFO msg="emitting player state changed event" component=server peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" state=STATUS_PLAYING positionMs=13000 volume=100 Oct 13 18:07:24 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:07:24.138+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" id= title="Trade it for the Night (Live in Carré)" Oct 13 18:07:27 rivoplus volumio[3321]: info: CoreCommandRouter::servicePushState Oct 13 18:07:27 rivoplus volumio[3321]: info: CoreStateMachine::pushState Oct 13 18:07:27 rivoplus volumio[3321]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 18:07:27 rivoplus volumio[3321]: info: CoreCommandRouter::volumioPushState Oct 13 18:07:27 rivoplus volumio[3321]: info: CoreCommandRouter::volumioGetState Oct 13 18:07:27 rivoplus volumio[3321]: info: MRS: Pushing multiroomSync output update for this device Oct 13 18:07:27 rivoplus volumio[3321]: info: MRS: Pushing multiroomSync output Oct 13 18:07:27 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:07:27.155+03:00 level=INFO msg="emitting player state changed event" component=server peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" state=STATUS_PLAYING positionMs=16000 volume=100 Oct 13 18:07:27 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:07:27.157+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" id= title="Trade it for the Night (Live in Carré)" Oct 13 18:07:30 rivoplus volumio[3321]: info: CoreCommandRouter::servicePushState Oct 13 18:07:30 rivoplus volumio[3321]: info: CoreStateMachine::pushState Oct 13 18:07:30 rivoplus volumio[3321]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 18:07:30 rivoplus volumio[3321]: info: CoreCommandRouter::volumioPushState Oct 13 18:07:30 rivoplus volumio[3321]: info: CoreCommandRouter::volumioGetState Oct 13 18:07:30 rivoplus volumio[3321]: info: MRS: Pushing multiroomSync output update for this device Oct 13 18:07:30 rivoplus volumio[3321]: info: MRS: Pushing multiroomSync output Oct 13 18:07:30 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:07:30.971+03:00 level=INFO msg="emitting player state changed event" component=server peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" state=STATUS_PLAYING positionMs=20000 volume=100 Oct 13 18:07:30 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:07:30.972+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" id= title="Trade it for the Night (Live in Carré)" Oct 13 18:07:34 rivoplus volumio[3321]: info: CoreCommandRouter::servicePushState Oct 13 18:07:34 rivoplus volumio[3321]: info: CoreStateMachine::pushState Oct 13 18:07:34 rivoplus volumio[3321]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 18:07:34 rivoplus volumio[3321]: info: CoreCommandRouter::volumioPushState Oct 13 18:07:34 rivoplus volumio[3321]: info: CoreCommandRouter::volumioGetState Oct 13 18:07:34 rivoplus volumio[3321]: info: MRS: Pushing multiroomSync output update for this device Oct 13 18:07:34 rivoplus volumio[3321]: info: MRS: Pushing multiroomSync output Oct 13 18:07:35 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:07:35.001+03:00 level=INFO msg="emitting player state changed event" component=server peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" state=STATUS_PLAYING positionMs=24000 volume=100 Oct 13 18:07:35 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:07:35.002+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" id= title="Trade it for the Night (Live in Carré)" Oct 13 18:07:37 rivoplus volumio[3321]: info: CoreCommandRouter::servicePushState Oct 13 18:07:37 rivoplus volumio[3321]: info: CoreStateMachine::pushState Oct 13 18:07:37 rivoplus volumio[3321]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 18:07:37 rivoplus volumio[3321]: info: CoreCommandRouter::volumioPushState Oct 13 18:07:38 rivoplus volumio[3321]: info: CoreCommandRouter::volumioGetState Oct 13 18:07:38 rivoplus volumio[3321]: info: MRS: Pushing multiroomSync output update for this device Oct 13 18:07:38 rivoplus volumio[3321]: info: MRS: Pushing multiroomSync output Oct 13 18:07:38 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:07:38.011+03:00 level=INFO msg="emitting player state changed event" component=server peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" state=STATUS_PLAYING positionMs=27000 volume=100 Oct 13 18:07:38 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:07:38.013+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" id= title="Trade it for the Night (Live in Carré)" Oct 13 18:07:41 rivoplus volumio[3321]: info: CoreCommandRouter::servicePushState Oct 13 18:07:41 rivoplus volumio[3321]: info: CoreStateMachine::pushState Oct 13 18:07:41 rivoplus volumio[3321]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 18:07:41 rivoplus volumio[3321]: info: CoreCommandRouter::volumioPushState Oct 13 18:07:41 rivoplus volumio[3321]: info: CoreCommandRouter::volumioGetState Oct 13 18:07:42 rivoplus volumio[3321]: info: MRS: Pushing multiroomSync output update for this device Oct 13 18:07:42 rivoplus volumio[3321]: info: MRS: Pushing multiroomSync output Oct 13 18:07:42 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:07:42.008+03:00 level=INFO msg="emitting player state changed event" component=server peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" state=STATUS_PLAYING positionMs=31000 volume=100 Oct 13 18:07:42 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:07:42.009+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" id= title="Trade it for the Night (Live in Carré)" Oct 13 18:07:46 rivoplus volumio[3321]: info: CoreCommandRouter::servicePushState Oct 13 18:07:46 rivoplus volumio[3321]: info: CoreStateMachine::pushState Oct 13 18:07:46 rivoplus volumio[3321]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 18:07:46 rivoplus volumio[3321]: info: CoreCommandRouter::volumioPushState Oct 13 18:07:46 rivoplus volumio[3321]: info: CoreCommandRouter::volumioGetState Oct 13 18:07:46 rivoplus volumio[3321]: info: MRS: Pushing multiroomSync output update for this device Oct 13 18:07:46 rivoplus volumio[3321]: info: MRS: Pushing multiroomSync output Oct 13 18:07:46 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:07:46.011+03:00 level=INFO msg="emitting player state changed event" component=server peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" state=STATUS_PLAYING positionMs=35000 volume=100 Oct 13 18:07:46 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:07:46.012+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" id= title="Trade it for the Night (Live in Carré)" Oct 13 18:07:49 rivoplus volumio[3321]: info: CoreCommandRouter::servicePushState Oct 13 18:07:49 rivoplus volumio[3321]: info: CoreStateMachine::pushState Oct 13 18:07:49 rivoplus volumio[3321]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 18:07:49 rivoplus volumio[3321]: info: CoreCommandRouter::volumioPushState Oct 13 18:07:49 rivoplus volumio[3321]: info: CoreCommandRouter::volumioGetState Oct 13 18:07:49 rivoplus volumio[3321]: info: MRS: Pushing multiroomSync output update for this device Oct 13 18:07:49 rivoplus volumio[3321]: info: MRS: Pushing multiroomSync output Oct 13 18:07:49 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:07:49.024+03:00 level=INFO msg="emitting player state changed event" component=server peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" state=STATUS_PLAYING positionMs=38000 volume=100 Oct 13 18:07:49 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:07:49.025+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" id= title="Trade it for the Night (Live in Carré)" Oct 13 18:07:52 rivoplus volumio[3321]: info: CoreCommandRouter::servicePushState Oct 13 18:07:52 rivoplus volumio[3321]: info: CoreStateMachine::pushState Oct 13 18:07:52 rivoplus volumio[3321]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 18:07:52 rivoplus volumio[3321]: info: CoreCommandRouter::volumioPushState Oct 13 18:07:52 rivoplus volumio[3321]: info: CoreCommandRouter::volumioGetState Oct 13 18:07:52 rivoplus volumio[3321]: info: MRS: Pushing multiroomSync output update for this device Oct 13 18:07:52 rivoplus volumio[3321]: info: MRS: Pushing multiroomSync output Oct 13 18:07:52 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:07:52.060+03:00 level=INFO msg="emitting player state changed event" component=server peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" state=STATUS_PLAYING positionMs=41000 volume=100 Oct 13 18:07:52 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:07:52.061+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" id= title="Trade it for the Night (Live in Carré)" Oct 13 18:07:56 rivoplus volumio[3321]: info: CoreCommandRouter::servicePushState Oct 13 18:07:56 rivoplus volumio[3321]: info: CoreStateMachine::pushState Oct 13 18:07:56 rivoplus volumio[3321]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 18:07:56 rivoplus volumio[3321]: info: CoreCommandRouter::volumioPushState Oct 13 18:07:56 rivoplus volumio[3321]: info: CoreCommandRouter::volumioGetState Oct 13 18:07:56 rivoplus volumio[3321]: info: MRS: Pushing multiroomSync output update for this device Oct 13 18:07:56 rivoplus volumio[3321]: info: MRS: Pushing multiroomSync output Oct 13 18:07:56 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:07:56.064+03:00 level=INFO msg="emitting player state changed event" component=server peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" state=STATUS_PLAYING positionMs=45000 volume=100 Oct 13 18:07:56 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:07:56.065+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" id= title="Trade it for the Night (Live in Carré)" Oct 13 18:08:00 rivoplus volumio[3321]: info: CoreCommandRouter::servicePushState Oct 13 18:08:00 rivoplus volumio[3321]: info: CoreStateMachine::pushState Oct 13 18:08:00 rivoplus volumio[3321]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 18:08:00 rivoplus volumio[3321]: info: CoreCommandRouter::volumioPushState Oct 13 18:08:00 rivoplus volumio[3321]: info: CoreCommandRouter::volumioGetState Oct 13 18:08:00 rivoplus volumio[3321]: info: MRS: Pushing multiroomSync output update for this device Oct 13 18:08:00 rivoplus volumio[3321]: info: MRS: Pushing multiroomSync output Oct 13 18:08:00 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:08:00.073+03:00 level=INFO msg="emitting player state changed event" component=server peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" state=STATUS_PLAYING positionMs=49000 volume=100 Oct 13 18:08:00 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:08:00.075+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" id= title="Trade it for the Night (Live in Carré)" Oct 13 18:08:03 rivoplus volumio[3321]: info: CoreCommandRouter::servicePushState Oct 13 18:08:03 rivoplus volumio[3321]: info: CoreStateMachine::pushState Oct 13 18:08:03 rivoplus volumio[3321]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 18:08:03 rivoplus volumio[3321]: info: CoreCommandRouter::volumioPushState Oct 13 18:08:03 rivoplus volumio[3321]: info: CoreCommandRouter::volumioGetState Oct 13 18:08:03 rivoplus volumio[3321]: info: MRS: Pushing multiroomSync output update for this device Oct 13 18:08:03 rivoplus volumio[3321]: info: MRS: Pushing multiroomSync output Oct 13 18:08:03 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:08:03.121+03:00 level=INFO msg="emitting player state changed event" component=server peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" state=STATUS_PLAYING positionMs=52000 volume=100 Oct 13 18:08:03 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:08:03.123+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" id= title="Trade it for the Night (Live in Carré)" Oct 13 18:08:06 rivoplus volumio[3321]: info: CoreCommandRouter::servicePushState Oct 13 18:08:06 rivoplus volumio[3321]: info: CoreStateMachine::pushState Oct 13 18:08:06 rivoplus volumio[3321]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 18:08:06 rivoplus volumio[3321]: info: CoreCommandRouter::volumioPushState Oct 13 18:08:06 rivoplus volumio[3321]: info: CoreCommandRouter::volumioGetState Oct 13 18:08:06 rivoplus volumio[3321]: info: MRS: Pushing multiroomSync output update for this device Oct 13 18:08:06 rivoplus volumio[3321]: info: MRS: Pushing multiroomSync output Oct 13 18:08:06 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:08:06.152+03:00 level=INFO msg="emitting player state changed event" component=server peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" state=STATUS_PLAYING positionMs=55000 volume=100 Oct 13 18:08:06 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:08:06.155+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" id= title="Trade it for the Night (Live in Carré)" Oct 13 18:08:09 rivoplus volumio[3321]: info: CoreCommandRouter::servicePushState Oct 13 18:08:09 rivoplus volumio[3321]: info: CoreStateMachine::pushState Oct 13 18:08:09 rivoplus volumio[3321]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 18:08:09 rivoplus volumio[3321]: info: CoreCommandRouter::volumioPushState Oct 13 18:08:09 rivoplus volumio[3321]: info: CoreCommandRouter::volumioGetState Oct 13 18:08:09 rivoplus volumio[3321]: info: MRS: Pushing multiroomSync output update for this device Oct 13 18:08:09 rivoplus volumio[3321]: info: MRS: Pushing multiroomSync output Oct 13 18:08:09 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:08:09.958+03:00 level=INFO msg="emitting player state changed event" component=server peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" state=STATUS_PLAYING positionMs=59000 volume=100 Oct 13 18:08:09 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:08:09.959+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" id= title="Trade it for the Night (Live in Carré)" Oct 13 18:08:12 rivoplus volumio[3321]: info: CoreCommandRouter::servicePushState Oct 13 18:08:12 rivoplus volumio[3321]: info: CoreStateMachine::pushState Oct 13 18:08:12 rivoplus volumio[3321]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 18:08:12 rivoplus volumio[3321]: info: CoreCommandRouter::volumioPushState Oct 13 18:08:12 rivoplus volumio[3321]: info: CoreCommandRouter::volumioGetState Oct 13 18:08:12 rivoplus volumio[3321]: info: MRS: Pushing multiroomSync output update for this device Oct 13 18:08:12 rivoplus volumio[3321]: info: MRS: Pushing multiroomSync output Oct 13 18:08:12 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:08:12.972+03:00 level=INFO msg="emitting player state changed event" component=server peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" state=STATUS_PLAYING positionMs=62000 volume=100 Oct 13 18:08:12 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:08:12.973+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" id= title="Trade it for the Night (Live in Carré)" Oct 13 18:08:14 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:08:14.433+03:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" latency=-36.34498ms timeout=10s Oct 13 18:08:14 rivoplus volumio[3321]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 13 18:08:14 rivoplus volumio[3321]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 13 18:08:14 rivoplus volumio[3321]: info: Discovery: Getting this device information Oct 13 18:08:14 rivoplus volumio[3321]: info: CoreCommandRouter::volumioGetState Oct 13 18:08:14 rivoplus volumio[3321]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 13 18:08:14 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:08:14.649+03:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" latency=-36.34498ms timeout=10s endpoint=http://pushupdates.volumio.org duration=212.753408ms Oct 13 18:08:14 rivoplus volumio[3321]: verbose: New Socket.io Connection to 10.100.102.8:3000 from 10.100.102.14 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Oct 13 18:08:14 rivoplus volumio[3321]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 13 18:08:14 rivoplus volumio[3321]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 13 18:08:14 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:08:14.693+03:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" latency=-36.34498ms timeout=10s endpoint=https://www.googleapis.com duration=256.95408ms Oct 13 18:08:14 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:08:14.697+03:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" latency=-36.34498ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=263.431903ms Oct 13 18:08:14 rivoplus volumio[3321]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 13 18:08:14 rivoplus volumio[3321]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 13 18:08:14 rivoplus volumio[3321]: info: Discovery: Getting this device information Oct 13 18:08:14 rivoplus volumio[3321]: info: CoreCommandRouter::volumioGetState Oct 13 18:08:14 rivoplus volumio[3321]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 13 18:08:14 rivoplus volumio[3321]: verbose: New Socket.io Connection to 10.100.102.26:3000 from 10.100.102.14 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Oct 13 18:08:14 rivoplus volumio[3321]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 13 18:08:14 rivoplus volumio[3321]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 13 18:08:14 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:08:14.779+03:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" latency=-36.34498ms timeout=10s endpoint=https://securetoken.googleapis.com duration=343.815461ms Oct 13 18:08:14 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:08:14.883+03:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" latency=-36.34498ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=448.645802ms Oct 13 18:08:14 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:08:14.978+03:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" latency=-36.34498ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=544.317308ms Oct 13 18:08:14 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:08:14.986+03:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" latency=-36.34498ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=549.072206ms Oct 13 18:08:15 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:08:15.011+03:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" latency=-36.34498ms timeout=10s endpoint=http://cddb.volumio.org duration=576.103546ms error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF" Oct 13 18:08:15 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:08:15.382+03:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" latency=-36.34498ms timeout=10s endpoint=https://google.com duration=947.179555ms Oct 13 18:08:15 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:08:15.397+03:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" latency=-36.34498ms timeout=10s endpoint=http://plugins.volumio.org duration=959.19528ms Oct 13 18:08:15 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:08:15.398+03:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" latency=-36.34498ms timeout=10s endpoint=https://functions.volumio.cloud duration=962.746172ms Oct 13 18:08:15 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:08:15.411+03:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" latency=-36.34498ms timeout=10s endpoint=https://functions.volumio.cloud duration=975.125523ms Oct 13 18:08:15 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:08:15.606+03:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" latency=-36.34498ms timeout=10s endpoint=https://database.volumio.cloud duration=1.168837755s Oct 13 18:08:15 rivoplus volumio[3321]: verbose: New Socket.io Connection to 10.100.102.8 from 10.100.102.14 UA: Mozilla/5.0 (Linux; Android 17; SM-S948B Build/CP2A.260605.016; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/154.0.8037.106 Mobile Safari/537.36 VolumioApp/6.260930.0 Engine version: 3 Transport: polling Total Clients: 8 Oct 13 18:08:15 rivoplus volumio[3321]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 13 18:08:15 rivoplus volumio[3321]: info: CoreCommandRouter::volumioGetVisibleSources Oct 13 18:08:15 rivoplus volumio[3321]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 13 18:08:15 rivoplus volumio[3321]: info: CoreCommandRouter::volumioGetState Oct 13 18:08:15 rivoplus volumio[3321]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 13 18:08:15 rivoplus volumio[3321]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 13 18:08:15 rivoplus volumio[3321]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 13 18:08:15 rivoplus volumio[3321]: info: Received Get System Info Oct 13 18:08:15 rivoplus volumio[3321]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 13 18:08:15 rivoplus volumio[3321]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 13 18:08:15 rivoplus volumio[3321]: info: Discovery: Getting this device information Oct 13 18:08:15 rivoplus volumio[3321]: info: CoreCommandRouter::volumioGetState Oct 13 18:08:15 rivoplus volumio[3321]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 13 18:08:15 rivoplus volumio[3321]: info: CoreCommandRouter::volumioGetState Oct 13 18:08:15 rivoplus volumio[3321]: info: Listing playlists Oct 13 18:08:15 rivoplus volumio[3321]: info: CoreCommandRouter::volumioGetQueue Oct 13 18:08:15 rivoplus volumio[3321]: info: CoreStateMachine::getQueue Oct 13 18:08:15 rivoplus volumio[3321]: info: CorePlayQueue::getQueue Oct 13 18:08:16 rivoplus volumio[3321]: info: CoreCommandRouter::servicePushState Oct 13 18:08:16 rivoplus volumio[3321]: info: CoreStateMachine::pushState Oct 13 18:08:16 rivoplus volumio[3321]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 18:08:16 rivoplus volumio[3321]: info: CoreCommandRouter::volumioPushState Oct 13 18:08:16 rivoplus volumio[3321]: info: CoreCommandRouter::volumioGetState Oct 13 18:08:16 rivoplus volumio[3321]: info: MRS: Pushing multiroomSync output update for this device Oct 13 18:08:16 rivoplus volumio[3321]: info: MRS: Pushing multiroomSync output Oct 13 18:08:16 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:08:16.072+03:00 level=INFO msg="emitting player state changed event" component=server peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" state=STATUS_PLAYING positionMs=65000 volume=100 Oct 13 18:08:16 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:08:16.073+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" id= title="Trade it for the Night (Live in Carré)" Oct 13 18:08:16 rivoplus volumio[3321]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 13 18:08:19 rivoplus volumio[3321]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 13 18:08:19 rivoplus volumio[3321]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 13 18:08:20 rivoplus volumio[3321]: info: CoreCommandRouter::servicePushState Oct 13 18:08:20 rivoplus volumio[3321]: info: CoreStateMachine::pushState Oct 13 18:08:20 rivoplus volumio[3321]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 18:08:20 rivoplus volumio[3321]: info: CoreCommandRouter::volumioPushState Oct 13 18:08:20 rivoplus volumio[3321]: info: CoreCommandRouter::volumioGetState Oct 13 18:08:20 rivoplus volumio[3321]: info: MRS: Pushing multiroomSync output update for this device Oct 13 18:08:20 rivoplus volumio[3321]: info: MRS: Pushing multiroomSync output Oct 13 18:08:20 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:08:20.019+03:00 level=INFO msg="emitting player state changed event" component=server peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" state=STATUS_PLAYING positionMs=69000 volume=100 Oct 13 18:08:20 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:08:20.020+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" id= title="Trade it for the Night (Live in Carré)" Oct 13 18:08:24 rivoplus volumio[3321]: info: CoreCommandRouter::servicePushState Oct 13 18:08:24 rivoplus volumio[3321]: info: CoreStateMachine::pushState Oct 13 18:08:24 rivoplus volumio[3321]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 18:08:24 rivoplus volumio[3321]: info: CoreCommandRouter::volumioPushState Oct 13 18:08:24 rivoplus volumio[3321]: info: CoreCommandRouter::volumioGetState Oct 13 18:08:24 rivoplus volumio[3321]: info: MRS: Pushing multiroomSync output update for this device Oct 13 18:08:24 rivoplus volumio[3321]: info: MRS: Pushing multiroomSync output Oct 13 18:08:24 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:08:24.039+03:00 level=INFO msg="emitting player state changed event" component=server peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" state=STATUS_PLAYING positionMs=73000 volume=100 Oct 13 18:08:24 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:08:24.040+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" id= title="Trade it for the Night (Live in Carré)" Oct 13 18:08:28 rivoplus volumio[3321]: info: CoreCommandRouter::servicePushState Oct 13 18:08:28 rivoplus volumio[3321]: info: CoreStateMachine::pushState Oct 13 18:08:28 rivoplus volumio[3321]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 18:08:28 rivoplus volumio[3321]: info: CoreCommandRouter::volumioPushState Oct 13 18:08:28 rivoplus volumio[3321]: info: CoreCommandRouter::volumioGetState Oct 13 18:08:28 rivoplus volumio[3321]: info: MRS: Pushing multiroomSync output update for this device Oct 13 18:08:28 rivoplus volumio[3321]: info: MRS: Pushing multiroomSync output Oct 13 18:08:28 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:08:28.068+03:00 level=INFO msg="emitting player state changed event" component=server peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" state=STATUS_PLAYING positionMs=77000 volume=100 Oct 13 18:08:28 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:08:28.069+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" id= title="Trade it for the Night (Live in Carré)" Oct 13 18:08:32 rivoplus volumio[3321]: info: CoreCommandRouter::servicePushState Oct 13 18:08:32 rivoplus volumio[3321]: info: CoreStateMachine::pushState Oct 13 18:08:32 rivoplus volumio[3321]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 18:08:32 rivoplus volumio[3321]: info: CoreCommandRouter::volumioPushState Oct 13 18:08:32 rivoplus volumio[3321]: info: CoreCommandRouter::volumioGetState Oct 13 18:08:32 rivoplus volumio[3321]: info: MRS: Pushing multiroomSync output update for this device Oct 13 18:08:32 rivoplus volumio[3321]: info: MRS: Pushing multiroomSync output Oct 13 18:08:32 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:08:32.079+03:00 level=INFO msg="emitting player state changed event" component=server peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" state=STATUS_PLAYING positionMs=81000 volume=100 Oct 13 18:08:32 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:08:32.079+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" id= title="Trade it for the Night (Live in Carré)" Oct 13 18:08:32 rivoplus volumio[3321]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 13 18:08:36 rivoplus volumio[3321]: info: CoreCommandRouter::servicePushState Oct 13 18:08:36 rivoplus volumio[3321]: info: CoreStateMachine::pushState Oct 13 18:08:36 rivoplus volumio[3321]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 18:08:36 rivoplus volumio[3321]: info: CoreCommandRouter::volumioPushState Oct 13 18:08:36 rivoplus volumio[3321]: info: CoreCommandRouter::volumioGetState Oct 13 18:08:36 rivoplus volumio[3321]: info: MRS: Pushing multiroomSync output update for this device Oct 13 18:08:36 rivoplus volumio[3321]: info: MRS: Pushing multiroomSync output Oct 13 18:08:36 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:08:36.099+03:00 level=INFO msg="emitting player state changed event" component=server peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" state=STATUS_PLAYING positionMs=85000 volume=100 Oct 13 18:08:36 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:08:36.100+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" id= title="Trade it for the Night (Live in Carré)" Oct 13 18:08:39 rivoplus volumio[3321]: info: CoreCommandRouter::servicePushState Oct 13 18:08:39 rivoplus volumio[3321]: info: CoreStateMachine::pushState Oct 13 18:08:39 rivoplus volumio[3321]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 18:08:39 rivoplus volumio[3321]: info: CoreCommandRouter::volumioPushState Oct 13 18:08:39 rivoplus volumio[3321]: info: CoreCommandRouter::volumioGetState Oct 13 18:08:39 rivoplus volumio[3321]: info: MRS: Pushing multiroomSync output update for this device Oct 13 18:08:39 rivoplus volumio[3321]: info: MRS: Pushing multiroomSync output Oct 13 18:08:39 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:08:39.308+03:00 level=INFO msg="emitting player state changed event" component=server peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" state=STATUS_PLAYING positionMs=88000 volume=100 Oct 13 18:08:39 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:08:39.310+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" id= title="Trade it for the Night (Live in Carré)" Oct 13 18:08:41 rivoplus volumio[3321]: info: Enabling plugin fusiondsp Oct 13 18:08:41 rivoplus volumio[3321]: info: Loading plugin "fusiondsp"... Oct 13 18:08:42 rivoplus volumio[3321]: info: Applying required configuration parameters for plugin fusiondsp Oct 13 18:08:42 rivoplus volumio[3321]: info: Preparing to generate the ALSA configuration file Oct 13 18:08:42 rivoplus volumio[3321]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 13 18:08:42 rivoplus volumio[3321]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 13 18:08:42 rivoplus volumio[3321]: info: Reading ALSA contributions from plugins. Oct 13 18:08:42 rivoplus volumio[3321]: info: CoreCommandRouter::servicePushState Oct 13 18:08:42 rivoplus volumio[3321]: info: CoreStateMachine::pushState Oct 13 18:08:42 rivoplus volumio[3321]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 18:08:42 rivoplus volumio[3321]: info: CoreCommandRouter::volumioPushState Oct 13 18:08:42 rivoplus volumio[3321]: info: CoreCommandRouter::volumioGetState Oct 13 18:08:42 rivoplus volumio[3321]: info: MRS: Pushing multiroomSync output update for this device Oct 13 18:08:42 rivoplus volumio[3321]: info: MRS: Pushing multiroomSync output Oct 13 18:08:42 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:08:42.402+03:00 level=INFO msg="emitting player state changed event" component=server peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" state=STATUS_PLAYING positionMs=91000 volume=100 Oct 13 18:08:42 rivoplus volumio5-onboarding[4118]: time=2026-10-13T18:08:42.404+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.100.102.14:50042,10.100.102.14:49000,00:00:00:00:00:00%01 @ 0x1d4c810" id= title="Trade it for the Night (Live in Carré)" Oct 13 18:08:42 rivoplus volumio[3321]: info: Asound.conf file written Oct 13 18:08:42 rivoplus sudo[14548]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 13 18:08:42 rivoplus sudo[14548]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 18:08:42 rivoplus sudo[14548]: pam_unix(sudo:session): session closed for user root Oct 13 18:08:42 rivoplus kernel: Fine tdm clk setting range (0~2000000), 12287994 Oct 13 18:08:42 rivoplus kernel: Err: can not set rate to 4278967296! Oct 13 18:08:42 rivoplus kernel: Range[3920000 - 500000000] Oct 13 18:08:42 rivoplus kernel: out of value, fixed it Oct 13 18:08:42 rivoplus kernel: id=0 set inskew=0 Oct 13 18:08:42 rivoplus volumio[3321]: No protocol specified Oct 13 18:08:42 rivoplus volumio[3321]: xcb_connection_has_error() returned true Oct 13 18:08:42 rivoplus volumio[3321]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:eARC_TX CDS:0' : Operation not permitted Oct 13 18:08:42 rivoplus volumio[3321]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:I2SIn CLK:0' : Operation not permitted Oct 13 18:08:42 rivoplus volumio[3321]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted Oct 13 18:08:42 rivoplus volumio[3321]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted Oct 13 18:08:42 rivoplus volumio[3321]: Found hardware: "USB-Audio" "USB Mixer" "USB2fc6:6016" "" "" Oct 13 18:08:42 rivoplus volumio[3321]: Hardware is initialized using a generic method Oct 13 18:08:42 rivoplus volumio[3321]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Clock Source 5 Validity:0' : Operation not permitted Oct 13 18:08:42 rivoplus volumio[3321]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Clock Source 41 Validity:0' : Operation not permitted Oct 13 18:08:42 rivoplus volumio[3321]: info: Output device has changed, restarting MPD Oct 13 18:08:42 rivoplus volumio[3321]: info: Output device has changed, restarting Shairport Sync Oct 13 18:08:42 rivoplus volumio[3321]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 18:08:42 rivoplus volumio[3321]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 18:08:42 rivoplus sudo[14553]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 13 18:08:42 rivoplus sudo[14553]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 18:08:42 rivoplus sudo[14553]: pam_unix(sudo:session): session closed for user root Oct 13 18:08:42 rivoplus sudo[14555]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 13 18:08:42 rivoplus sudo[14555]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 18:08:42 rivoplus systemd[1]: Stopping Music Player Daemon... Oct 13 18:08:42 rivoplus volumio[3321]: No protocol specified Oct 13 18:08:42 rivoplus volumio[3321]: xcb_connection_has_error() returned true Oct 13 18:08:42 rivoplus volumio[3321]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 13 18:08:42 rivoplus volumio[3321]: No protocol specified Oct 13 18:08:42 rivoplus volumio[3321]: xcb_connection_has_error() returned true Oct 13 18:08:42 rivoplus volumio[3321]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 13 18:08:42 rivoplus volumio[3321]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 18:08:42 rivoplus volumio[3321]: info: QobuzConnect: setDeactiveState invoked Oct 13 18:08:42 rivoplus volumio[3321]: info: CoreCommandRouter::volumioGetState Oct 13 18:08:42 rivoplus volumio[3321]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 13 18:08:42 rivoplus volumio[3321]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 18:08:42 rivoplus vtcs[4318]: [2026-10-13 18:08:42.832] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Oct 13 18:08:42 rivoplus vtcs[4318]: [2026-10-13 18:08:42.835] [tisoc] [error] [SpkconServer.cpp:383] recv error. client fd=8 errorno=104 error=Connection reset by peer Oct 13 18:08:42 rivoplus vtcs[4318]: [2026-10-13 18:08:42.835] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Oct 13 18:08:42 rivoplus volumio[3321]: info: PLUGIN START: fusiondsp Oct 13 18:08:42 rivoplus volumio[3321]: info: Loading i18n strings for locale en Oct 13 18:08:42 rivoplus sudo[14564]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 13 18:08:42 rivoplus sudo[14564]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 18:08:42 rivoplus volumio[3321]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Oct 13 18:08:42 rivoplus volumio[3321]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 18:08:42 rivoplus volumio[3321]: info: FusionDsp - mixtype--------------------- Hardware Oct 13 18:08:42 rivoplus volumio[3321]: info: Preparing to generate the ALSA configuration file Oct 13 18:08:42 rivoplus volumio[3321]: info: Done. Oct 13 18:08:42 rivoplus systemd[1]: Stopping Volumio Tidal Connect Service... Oct 13 18:08:42 rivoplus systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Oct 13 18:08:42 rivoplus systemd[1]: vtcs.service: Succeeded. Oct 13 18:08:42 rivoplus systemd[1]: Stopped Volumio Tidal Connect Service. Oct 13 18:08:42 rivoplus systemd[1]: mpd.service: Succeeded. Oct 13 18:08:42 rivoplus systemd[1]: Stopped Music Player Daemon. Oct 13 18:08:42 rivoplus sudo[14564]: pam_unix(sudo:session): session closed for user root Oct 13 18:08:42 rivoplus sudo[14568]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 13 18:08:42 rivoplus sudo[14568]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 18:08:43 rivoplus volumio[3321]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 13 18:08:43 rivoplus volumio[3321]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 13 18:08:43 rivoplus volumio[3321]: info: Reading ALSA contributions from plugins. Oct 13 18:08:43 rivoplus systemd[1]: Starting Music Player Daemon... Oct 13 18:08:43 rivoplus volumio[3321]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 13 18:08:43 rivoplus volumio[3321]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 13 18:08:43 rivoplus volumio[3321]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 13 18:08:43 rivoplus sudo[14568]: pam_unix(sudo:session): session closed for user root Oct 13 18:08:43 rivoplus volumio[3321]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 13 18:08:43 rivoplus sudo[14577]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 13 18:08:43 rivoplus sudo[14577]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 18:08:43 rivoplus volumio[3321]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 13 18:08:43 rivoplus volumio[3321]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 13 18:08:43 rivoplus volumio[3321]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 13 18:08:43 rivoplus volumio[3321]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 13 18:08:43 rivoplus volumio[3321]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 13 18:08:43 rivoplus volumio[3321]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 13 18:08:43 rivoplus volumio[3321]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 13 18:08:43 rivoplus volumio[3321]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 13 18:08:43 rivoplus volumio[3321]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 13 18:08:43 rivoplus volumio[3321]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 13 18:08:43 rivoplus sudo[14577]: pam_unix(sudo:session): session closed for user root Oct 13 18:08:43 rivoplus volumio[3321]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 13 18:08:43 rivoplus volumio[3321]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Oct 13 18:08:43 rivoplus volumio[3321]: info: MPD Permissions set Oct 13 18:08:43 rivoplus volumio[3321]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 13 18:08:43 rivoplus volumio[3321]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 13 18:08:43 rivoplus sudo[14587]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 13 18:08:43 rivoplus sudo[14587]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 18:08:43 rivoplus volumio[3321]: TypeError: Cannot read property 'off' of undefined Oct 13 18:08:43 rivoplus volumio[3321]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:107:15) Oct 13 18:08:43 rivoplus volumio[3321]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Oct 13 18:08:43 rivoplus volumio[3321]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1883:8) Oct 13 18:08:43 rivoplus volumio[3321]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1556:29) Oct 13 18:08:43 rivoplus volumio[3321]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1412:47) Oct 13 18:08:43 rivoplus volumio[3321]: at Socket.emit (events.js:400:28) Oct 13 18:08:43 rivoplus volumio[3321]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Oct 13 18:08:43 rivoplus volumio[3321]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Oct 13 18:08:43 rivoplus volumio[3321]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 13 18:08:43 rivoplus systemd[1]: Stopping Volumio Qobuz Connect Service... Oct 13 18:08:43 rivoplus qobuz-connect[4161]: 20261013 18:08:43.200 [4161.4161] INFO SampleApp: Stopping Local configuration server Oct 13 18:08:43 rivoplus qobuz-connect[4161]: 20261013 18:08:43.211 [4161.4161] INFO SampleApp: shat down connection on UNIX socket Oct 13 18:08:43 rivoplus systemd[1]: qobuz-connect.service: Succeeded. Oct 13 18:08:43 rivoplus systemd[1]: Stopped Volumio Qobuz Connect Service. Oct 13 18:08:43 rivoplus sudo[14578]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 13 18:08:43 rivoplus sudo[14578]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 18:08:43 rivoplus systemd[1]: Started Volumio Qobuz Connect Service. Oct 13 18:08:43 rivoplus sudo[14587]: pam_unix(sudo:session): session closed for user root Oct 13 18:08:43 rivoplus sudo[14578]: pam_unix(sudo:session): session closed for user root Oct 13 18:08:44 rivoplus sudo[14620]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-10-13 18:07 Oct 13 18:08:44 rivoplus sudo[14620]: 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"