-- Logs begin at Thu 2026-03-19 15:42:38 CET, end at Fri 2026-03-27 11:39:36 CET. -- Mar 27 11:38:16 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Mar 27 11:38:16 volumio volumio[846]: info: CorePlayQueue::getTrack 0 Mar 27 11:38:16 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Mar 27 11:38:16 volumio volumio[846]: info: CorePlayQueue::getTrack 0 Mar 27 11:38:16 volumio volumio[846]: info: Currently active: webradio Mar 27 11:38:16 volumio volumio[846]: info: Stopping currently active service Mar 27 11:38:16 volumio volumio[846]: info: CoreCommandRouter::volumioStop Mar 27 11:38:16 volumio volumio[846]: info: CoreStateMachine::stop Mar 27 11:38:16 volumio volumio[846]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 27 11:38:16 volumio volumio[846]: info: TidalConnect Active Mar 27 11:38:16 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Mar 27 11:38:16 volumio volumio[846]: info: CorePlayQueue::getTrack 0 Mar 27 11:38:16 volumio volumio[846]: info: Currently active: webradio Mar 27 11:38:16 volumio volumio[846]: info: Setting Volatile state to tidalconnect Mar 27 11:38:16 volumio volumio[846]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 27 11:38:16 volumio volumio[846]: info: CoreCommandRouter::servicePushState Mar 27 11:38:16 volumio volumio[846]: info: CoreStateMachine::pushState Mar 27 11:38:16 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 11:38:16 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Mar 27 11:38:16 volumio volumio[846]: info: MRS: Pushing multiroomSync output update for this device Mar 27 11:38:16 volumio volumio[846]: info: MRS: Pushing multiroomSync output Mar 27 11:38:16 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Mar 27 11:38:16 volumio volumio[846]: info: CoreCommandRouter::servicePushState Mar 27 11:38:16 volumio volumio[846]: info: CoreStateMachine::pushState Mar 27 11:38:16 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Mar 27 11:38:16 volumio volumio[846]: info: MRS: Pushing multiroomSync output update for this device Mar 27 11:38:16 volumio volumio[846]: info: MRS: Pushing multiroomSync output Mar 27 11:38:16 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Mar 27 11:38:16 volumio volumio5-onboarding[1125]: time=2026-03-27T11:38:16.774+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.195:58077 @ 0x1f010e0" state=STATUS_PAUSED positionMs=0 volume=100 Mar 27 11:38:16 volumio volumio5-onboarding[1125]: time=2026-03-27T11:38:16.774+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%1f,192.168.0.195:58077 @ 0x1cb7e90" state=STATUS_PAUSED positionMs=0 volume=100 Mar 27 11:38:16 volumio volumio5-onboarding[1125]: time=2026-03-27T11:38:16.775+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.195:58077 @ 0x1f010e0" id= title="set me free" Mar 27 11:38:16 volumio volumio5-onboarding[1125]: time=2026-03-27T11:38:16.775+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.195:58077 @ 0x1f010e0" state=STATUS_PAUSED positionMs=0 volume=100 Mar 27 11:38:16 volumio volumio5-onboarding[1125]: time=2026-03-27T11:38:16.775+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%1f,192.168.0.195:58077 @ 0x1cb7e90" id= title="set me free" Mar 27 11:38:16 volumio volumio5-onboarding[1125]: time=2026-03-27T11:38:16.775+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%1f,192.168.0.195:58077 @ 0x1cb7e90" state=STATUS_PAUSED positionMs=0 volume=100 Mar 27 11:38:16 volumio volumio5-onboarding[1125]: time=2026-03-27T11:38:16.775+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.195:58077 @ 0x1f010e0" id= title="set me free" Mar 27 11:38:16 volumio volumio5-onboarding[1125]: time=2026-03-27T11:38:16.775+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%1f,192.168.0.195:58077 @ 0x1cb7e90" id= title="set me free" Mar 27 11:38:17 volumio vtcs[2106]: [2026-03-27 11:38:17.846] [tisoc] [warning] [PlaybackControllerImpl.cpp:498] Illegal state:1 Mar 27 11:38:18 volumio vtcs[2106]: [2026-03-27 11:38:18.276] [tisoc] [warning] [PlaybackControllerImpl.cpp:213] Ignore play request because playback state is not STARTED. state_=2, playstate_=1, player_state_=0 Mar 27 11:38:18 volumio volumio[846]: info: Pushing metadata Mar 27 11:38:18 volumio volumio[846]: info: CoreCommandRouter::servicePushState Mar 27 11:38:18 volumio volumio[846]: info: CoreStateMachine::pushState Mar 27 11:38:18 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 11:38:18 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Mar 27 11:38:18 volumio volumio[846]: info: MRS: Pushing multiroomSync output update for this device Mar 27 11:38:18 volumio volumio[846]: info: MRS: Pushing multiroomSync output Mar 27 11:38:18 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Mar 27 11:38:18 volumio volumio5-onboarding[1125]: time=2026-03-27T11:38:18.282+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.195:58077 @ 0x1f010e0" state=STATUS_PAUSED positionMs=0 volume=100 Mar 27 11:38:18 volumio volumio5-onboarding[1125]: time=2026-03-27T11:38:18.282+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%1f,192.168.0.195:58077 @ 0x1cb7e90" state=STATUS_PAUSED positionMs=0 volume=100 Mar 27 11:38:18 volumio volumio5-onboarding[1125]: time=2026-03-27T11:38:18.283+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.195:58077 @ 0x1f010e0" id= title=Big Mar 27 11:38:18 volumio volumio5-onboarding[1125]: time=2026-03-27T11:38:18.283+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%1f,192.168.0.195:58077 @ 0x1cb7e90" id= title=Big Mar 27 11:38:18 volumio volumio[846]: info: CoreCommandRouter::servicePushState Mar 27 11:38:18 volumio volumio[846]: info: CoreStateMachine::pushState Mar 27 11:38:18 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 11:38:18 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Mar 27 11:38:18 volumio volumio[846]: info: MRS: Pushing multiroomSync output update for this device Mar 27 11:38:18 volumio volumio[846]: info: MRS: Pushing multiroomSync output Mar 27 11:38:18 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Mar 27 11:38:18 volumio volumio[846]: info: CoreCommandRouter::servicePushState Mar 27 11:38:18 volumio volumio[846]: info: CoreStateMachine::pushState Mar 27 11:38:18 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Mar 27 11:38:18 volumio volumio[846]: info: MRS: Pushing multiroomSync output update for this device Mar 27 11:38:18 volumio volumio[846]: info: MRS: Pushing multiroomSync output Mar 27 11:38:18 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Mar 27 11:38:18 volumio volumio[846]: info: CoreCommandRouter::servicePushState Mar 27 11:38:18 volumio volumio[846]: info: CoreStateMachine::pushState Mar 27 11:38:18 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 11:38:18 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Mar 27 11:38:18 volumio volumio[846]: info: MRS: Pushing multiroomSync output update for this device Mar 27 11:38:18 volumio volumio[846]: info: MRS: Pushing multiroomSync output Mar 27 11:38:18 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Mar 27 11:38:18 volumio volumio5-onboarding[1125]: time=2026-03-27T11:38:18.302+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.195:58077 @ 0x1f010e0" state=STATUS_PAUSED positionMs=9700 volume=100 Mar 27 11:38:18 volumio volumio5-onboarding[1125]: time=2026-03-27T11:38:18.303+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%1f,192.168.0.195:58077 @ 0x1cb7e90" state=STATUS_PAUSED positionMs=9700 volume=100 Mar 27 11:38:18 volumio volumio5-onboarding[1125]: time=2026-03-27T11:38:18.304+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.195:58077 @ 0x1f010e0" id= title=Big Mar 27 11:38:18 volumio volumio5-onboarding[1125]: time=2026-03-27T11:38:18.304+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%1f,192.168.0.195:58077 @ 0x1cb7e90" id= title=Big Mar 27 11:38:18 volumio volumio5-onboarding[1125]: time=2026-03-27T11:38:18.307+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.195:58077 @ 0x1f010e0" state=STATUS_PAUSED positionMs=9700 volume=100 Mar 27 11:38:18 volumio volumio5-onboarding[1125]: time=2026-03-27T11:38:18.308+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%1f,192.168.0.195:58077 @ 0x1cb7e90" state=STATUS_PAUSED positionMs=9700 volume=100 Mar 27 11:38:18 volumio volumio5-onboarding[1125]: time=2026-03-27T11:38:18.309+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.195:58077 @ 0x1f010e0" id= title=Big Mar 27 11:38:18 volumio volumio5-onboarding[1125]: time=2026-03-27T11:38:18.309+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.195:58077 @ 0x1f010e0" state=STATUS_PLAYING positionMs=9700 volume=100 Mar 27 11:38:18 volumio volumio5-onboarding[1125]: time=2026-03-27T11:38:18.309+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%1f,192.168.0.195:58077 @ 0x1cb7e90" state=STATUS_PLAYING positionMs=9700 volume=100 Mar 27 11:38:18 volumio volumio5-onboarding[1125]: time=2026-03-27T11:38:18.310+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.195:58077 @ 0x1f010e0" id= title=Big Mar 27 11:38:18 volumio volumio5-onboarding[1125]: time=2026-03-27T11:38:18.310+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%1f,192.168.0.195:58077 @ 0x1cb7e90" id= title=Big Mar 27 11:38:18 volumio volumio5-onboarding[1125]: time=2026-03-27T11:38:18.309+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%1f,192.168.0.195:58077 @ 0x1cb7e90" id= title=Big Mar 27 11:38:18 volumio volumio[846]: info: touch_display: Setting screensaver timeout to 0 seconds. Mar 27 11:38:18 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Mar 27 11:38:18 volumio volumio[846]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Mar 27 11:38:18 volumio volumio[846]: info: touch_display: Setting screensaver timeout to 0 seconds. Mar 27 11:38:18 volumio vtcs[2106]: [close:85] Entering Mar 27 11:38:18 volumio vtcs[2106]: [close:100] Exiting Mar 27 11:38:18 volumio volumio[846]: info: Pushing metadata Mar 27 11:38:18 volumio volumio[846]: info: CoreCommandRouter::servicePushState Mar 27 11:38:18 volumio volumio[846]: info: CoreStateMachine::pushState Mar 27 11:38:18 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Mar 27 11:38:18 volumio volumio[846]: info: MRS: Pushing multiroomSync output update for this device Mar 27 11:38:18 volumio volumio[846]: info: MRS: Pushing multiroomSync output Mar 27 11:38:18 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Mar 27 11:38:18 volumio volumio[846]: info: CoreCommandRouter::servicePushState Mar 27 11:38:18 volumio volumio[846]: info: CoreStateMachine::pushState Mar 27 11:38:18 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Mar 27 11:38:18 volumio volumio[846]: info: MRS: Pushing multiroomSync output update for this device Mar 27 11:38:18 volumio volumio[846]: info: MRS: Pushing multiroomSync output Mar 27 11:38:18 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Mar 27 11:38:18 volumio volumio[846]: info: CoreCommandRouter::servicePushState Mar 27 11:38:18 volumio volumio[846]: info: CoreStateMachine::pushState Mar 27 11:38:18 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 11:38:18 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Mar 27 11:38:18 volumio volumio[846]: info: MRS: Pushing multiroomSync output update for this device Mar 27 11:38:18 volumio volumio[846]: info: MRS: Pushing multiroomSync output Mar 27 11:38:18 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Mar 27 11:38:18 volumio volumio[846]: info: CoreCommandRouter::servicePushState Mar 27 11:38:18 volumio volumio[846]: info: CoreStateMachine::pushState Mar 27 11:38:18 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 11:38:18 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Mar 27 11:38:18 volumio volumio[846]: info: MRS: Pushing multiroomSync output update for this device Mar 27 11:38:18 volumio volumio[846]: info: MRS: Pushing multiroomSync output Mar 27 11:38:18 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Mar 27 11:38:18 volumio volumio[846]: info: CoreCommandRouter::servicePushState Mar 27 11:38:18 volumio volumio[846]: info: CoreStateMachine::pushState Mar 27 11:38:18 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Mar 27 11:38:18 volumio volumio[846]: info: MRS: Pushing multiroomSync output update for this device Mar 27 11:38:18 volumio volumio[846]: info: MRS: Pushing multiroomSync output Mar 27 11:38:18 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Mar 27 11:38:18 volumio volumio5-onboarding[1125]: time=2026-03-27T11:38:18.712+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.195:58077 @ 0x1f010e0" state=STATUS_PLAYING positionMs=9700 volume=100 Mar 27 11:38:18 volumio volumio5-onboarding[1125]: time=2026-03-27T11:38:18.716+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%1f,192.168.0.195:58077 @ 0x1cb7e90" state=STATUS_PLAYING positionMs=9700 volume=100 Mar 27 11:38:18 volumio volumio5-onboarding[1125]: time=2026-03-27T11:38:18.716+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.195:58077 @ 0x1f010e0" id= title=Big Mar 27 11:38:18 volumio volumio5-onboarding[1125]: time=2026-03-27T11:38:18.716+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%1f,192.168.0.195:58077 @ 0x1cb7e90" id= title=Big Mar 27 11:38:18 volumio volumio5-onboarding[1125]: time=2026-03-27T11:38:18.716+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.195:58077 @ 0x1f010e0" state=STATUS_PAUSED positionMs=9700 volume=100 Mar 27 11:38:18 volumio volumio5-onboarding[1125]: time=2026-03-27T11:38:18.717+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%1f,192.168.0.195:58077 @ 0x1cb7e90" state=STATUS_PAUSED positionMs=9700 volume=100 Mar 27 11:38:18 volumio volumio5-onboarding[1125]: time=2026-03-27T11:38:18.717+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.195:58077 @ 0x1f010e0" state=STATUS_PLAYING positionMs=9700 volume=100 Mar 27 11:38:18 volumio volumio5-onboarding[1125]: time=2026-03-27T11:38:18.717+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.195:58077 @ 0x1f010e0" id= title=Big Mar 27 11:38:18 volumio volumio5-onboarding[1125]: time=2026-03-27T11:38:18.717+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%1f,192.168.0.195:58077 @ 0x1cb7e90" state=STATUS_PLAYING positionMs=9700 volume=100 Mar 27 11:38:18 volumio volumio5-onboarding[1125]: time=2026-03-27T11:38:18.717+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%1f,192.168.0.195:58077 @ 0x1cb7e90" id= title=Big Mar 27 11:38:18 volumio volumio5-onboarding[1125]: time=2026-03-27T11:38:18.717+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.195:58077 @ 0x1f010e0" id= title=Big Mar 27 11:38:18 volumio volumio5-onboarding[1125]: time=2026-03-27T11:38:18.717+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%1f,192.168.0.195:58077 @ 0x1cb7e90" id= title=Big Mar 27 11:38:18 volumio volumio5-onboarding[1125]: time=2026-03-27T11:38:18.717+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.195:58077 @ 0x1f010e0" state=STATUS_PLAYING positionMs=9700 volume=100 Mar 27 11:38:18 volumio volumio5-onboarding[1125]: time=2026-03-27T11:38:18.718+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%1f,192.168.0.195:58077 @ 0x1cb7e90" state=STATUS_PLAYING positionMs=9700 volume=100 Mar 27 11:38:18 volumio volumio5-onboarding[1125]: time=2026-03-27T11:38:18.718+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.195:58077 @ 0x1f010e0" id= title=Big Mar 27 11:38:18 volumio volumio5-onboarding[1125]: time=2026-03-27T11:38:18.718+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.195:58077 @ 0x1f010e0" state=STATUS_PLAYING positionMs=9700 volume=100 Mar 27 11:38:18 volumio volumio5-onboarding[1125]: time=2026-03-27T11:38:18.718+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%1f,192.168.0.195:58077 @ 0x1cb7e90" id= title=Big Mar 27 11:38:18 volumio volumio5-onboarding[1125]: time=2026-03-27T11:38:18.718+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%1f,192.168.0.195:58077 @ 0x1cb7e90" state=STATUS_PLAYING positionMs=9700 volume=100 Mar 27 11:38:18 volumio volumio5-onboarding[1125]: time=2026-03-27T11:38:18.718+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.195:58077 @ 0x1f010e0" id= title=Big Mar 27 11:38:18 volumio volumio5-onboarding[1125]: time=2026-03-27T11:38:18.718+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%1f,192.168.0.195:58077 @ 0x1cb7e90" id= title=Big Mar 27 11:38:18 volumio volumio[846]: info: touch_display: Setting screensaver timeout to 120 seconds. Mar 27 11:38:18 volumio volumio[846]: info: touch_display: Setting screensaver timeout to 0 seconds. Mar 27 11:38:19 volumio vtcs[2106]: [close:85] Entering Mar 27 11:38:19 volumio vtcs[2106]: [close:100] Exiting Mar 27 11:38:19 volumio vtcs[2106]: [open:50] Opening with sample rate: 96000, type: 3 Mar 27 11:38:19 volumio vtcs[2106]: [start:106] Entering Mar 27 11:38:19 volumio vtcs[2106]: [start:113] Reopening PCM device... Mar 27 11:38:19 volumio vtcs[2106]: [start:115] PCM opened successfully. Mar 27 11:38:19 volumio vtcs[2106]: [start:120] Exiting Mar 27 11:38:19 volumio vtcs[2106]: [feedThread:223] Entering Mar 27 11:38:19 volumio volumio[846]: info: CoreCommandRouter::servicePushState Mar 27 11:38:19 volumio volumio[846]: info: CoreStateMachine::pushState Mar 27 11:38:19 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 11:38:19 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Mar 27 11:38:19 volumio volumio[846]: info: MRS: Pushing multiroomSync output update for this device Mar 27 11:38:19 volumio volumio[846]: info: MRS: Pushing multiroomSync output Mar 27 11:38:19 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Mar 27 11:38:19 volumio volumio5-onboarding[1125]: time=2026-03-27T11:38:19.204+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.195:58077 @ 0x1f010e0" state=STATUS_PLAYING positionMs=9700 volume=100 Mar 27 11:38:19 volumio volumio5-onboarding[1125]: time=2026-03-27T11:38:19.204+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%1f,192.168.0.195:58077 @ 0x1cb7e90" state=STATUS_PLAYING positionMs=9700 volume=100 Mar 27 11:38:19 volumio volumio5-onboarding[1125]: time=2026-03-27T11:38:19.204+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.195:58077 @ 0x1f010e0" id= title=Big Mar 27 11:38:19 volumio volumio5-onboarding[1125]: time=2026-03-27T11:38:19.207+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%1f,192.168.0.195:58077 @ 0x1cb7e90" id= title=Big Mar 27 11:38:19 volumio vtcs[2106]: [2026-03-27 11:38:19.482] [tisoc] [warning] [flac_decoder.cpp:302] FLACDecoder::error_callback FLAC__STREAM_DECODER_ERROR_STATUS_LOST_SYNC Mar 27 11:38:19 volumio volumio[846]: info: CoreCommandRouter::servicePushState Mar 27 11:38:19 volumio volumio[846]: info: CoreStateMachine::pushState Mar 27 11:38:19 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Mar 27 11:38:19 volumio volumio[846]: info: MRS: Pushing multiroomSync output update for this device Mar 27 11:38:19 volumio volumio[846]: info: MRS: Pushing multiroomSync output Mar 27 11:38:19 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Mar 27 11:38:19 volumio volumio5-onboarding[1125]: time=2026-03-27T11:38:19.606+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.195:58077 @ 0x1f010e0" state=STATUS_PLAYING positionMs=9700 volume=100 Mar 27 11:38:19 volumio volumio5-onboarding[1125]: time=2026-03-27T11:38:19.606+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%1f,192.168.0.195:58077 @ 0x1cb7e90" state=STATUS_PLAYING positionMs=9700 volume=100 Mar 27 11:38:19 volumio volumio5-onboarding[1125]: time=2026-03-27T11:38:19.607+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.195:58077 @ 0x1f010e0" id= title=Big Mar 27 11:38:19 volumio volumio5-onboarding[1125]: time=2026-03-27T11:38:19.607+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%1f,192.168.0.195:58077 @ 0x1cb7e90" id= title=Big Mar 27 11:38:19 volumio volumio[846]: info: CoreCommandRouter::servicePushState Mar 27 11:38:19 volumio volumio[846]: info: CoreStateMachine::pushState Mar 27 11:38:19 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 11:38:19 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Mar 27 11:38:19 volumio volumio[846]: info: MRS: Pushing multiroomSync output update for this device Mar 27 11:38:19 volumio volumio[846]: info: MRS: Pushing multiroomSync output Mar 27 11:38:19 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Mar 27 11:38:19 volumio volumio5-onboarding[1125]: time=2026-03-27T11:38:19.938+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.195:58077 @ 0x1f010e0" state=STATUS_PLAYING positionMs=10203 volume=100 Mar 27 11:38:19 volumio volumio5-onboarding[1125]: time=2026-03-27T11:38:19.938+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%1f,192.168.0.195:58077 @ 0x1cb7e90" state=STATUS_PLAYING positionMs=10203 volume=100 Mar 27 11:38:19 volumio volumio5-onboarding[1125]: time=2026-03-27T11:38:19.938+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.195:58077 @ 0x1f010e0" id= title=Big Mar 27 11:38:19 volumio volumio5-onboarding[1125]: time=2026-03-27T11:38:19.938+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%1f,192.168.0.195:58077 @ 0x1cb7e90" id= title=Big Mar 27 11:38:21 volumio volumio[846]: info: Executing endpoint metavolumio Mar 27 11:38:21 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 27 11:38:21 volumio volumio[846]: info: Executing endpoint metavolumio Mar 27 11:38:21 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 27 11:38:21 volumio volumio[846]: info: Executing endpoint metavolumio Mar 27 11:38:21 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 27 11:38:42 volumio vtcs[2106]: [2026-03-27 11:38:42] [info] asio async_shutdown error: asio.misc:2 (End of file) Mar 27 11:38:45 volumio volumio[846]: verbose: New Socket.io Connection to 192.168.0.242 from 192.168.0.195 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 9 Mar 27 11:38:45 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 27 11:38:45 volumio volumio[846]: info: CoreCommandRouter::volumioGetVisibleSources Mar 27 11:38:45 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 27 11:38:45 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Mar 27 11:38:45 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 27 11:38:45 volumio volumio[846]: info: CoreCommandRouter::volumioGetQueue Mar 27 11:38:45 volumio volumio[846]: info: CoreStateMachine::getQueue Mar 27 11:38:45 volumio volumio[846]: info: CorePlayQueue::getQueue Mar 27 11:38:45 volumio volumio[846]: info: Listing playlists Mar 27 11:38:45 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 27 11:38:45 volumio volumio[846]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Mar 27 11:38:45 volumio volumio[846]: info: Received Get System Info Mar 27 11:38:45 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 27 11:38:45 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 27 11:38:45 volumio volumio[846]: info: Discovery: Getting this device information Mar 27 11:38:45 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Mar 27 11:38:45 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 27 11:38:45 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Mar 27 11:38:46 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 27 11:38:52 volumio volumio5-onboarding[1125]: time=2026-03-27T11:38:52.628+01:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="00:00:00:00:00:00%1f,192.168.0.195:58077 @ 0x1cb7e90" latency=49.329594ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Mar 27 11:38:52 volumio sudo[3195]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 27 11:38:52 volumio sudo[3195]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 27 11:38:52 volumio sudo[3195]: pam_unix(sudo:session): session closed for user root Mar 27 11:38:52 volumio sudo[3198]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 27 11:38:52 volumio sudo[3198]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 27 11:38:52 volumio sudo[3198]: pam_unix(sudo:session): session closed for user root Mar 27 11:38:53 volumio volumio[846]: verbose: New Socket.io Connection to 192.168.0.242 from 192.168.0.195 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 10 Mar 27 11:38:53 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 27 11:38:53 volumio sudo[3201]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 27 11:38:53 volumio sudo[3201]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 27 11:38:53 volumio sudo[3201]: pam_unix(sudo:session): session closed for user root Mar 27 11:38:53 volumio sudo[3204]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 27 11:38:53 volumio sudo[3204]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 27 11:38:53 volumio sudo[3204]: pam_unix(sudo:session): session closed for user root Mar 27 11:38:53 volumio volumio[846]: verbose: New Socket.io Connection to 192.168.0.242 from 192.168.0.195 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 11 Mar 27 11:38:53 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 27 11:38:53 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 27 11:38:53 volumio volumio[846]: info: CoreCommandRouter::volumioGetVisibleSources Mar 27 11:38:53 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 27 11:38:53 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Mar 27 11:38:53 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 27 11:38:53 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 27 11:38:53 volumio volumio[846]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Mar 27 11:38:53 volumio volumio[846]: info: Received Get System Info Mar 27 11:38:53 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 27 11:38:53 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 27 11:38:53 volumio volumio[846]: info: Discovery: Getting this device information Mar 27 11:38:53 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Mar 27 11:38:53 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 27 11:38:53 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Mar 27 11:38:53 volumio volumio[846]: info: Listing playlists Mar 27 11:38:53 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 27 11:38:53 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 27 11:38:53 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 27 11:38:54 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 27 11:38:55 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 27 11:38:55 volumio volumio[846]: info: Received Get System Info Mar 27 11:38:55 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 27 11:38:55 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 27 11:38:55 volumio volumio[846]: info: Discovery: Getting this device information Mar 27 11:38:55 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Mar 27 11:38:55 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 27 11:38:55 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 27 11:38:57 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: multiroom , enableAudioOutput Mar 27 11:38:57 volumio volumio[846]: info: MRS: Starting browser stream Mar 27 11:38:57 volumio volumio[846]: info: MRS: Setting this device as Streaming Server Mar 27 11:38:57 volumio volumio[846]: info: Mar 27 11:38:57 volumio volumio[846]: [1774607937310] ---------------------------- MRS: Setting Streaming Server Mar 27 11:38:57 volumio volumio[846]: info: Enabled audio output: browserPlayback Mar 27 11:38:57 volumio volumio[846]: info: MRS: enable multiroom server output Mar 27 11:38:57 volumio volumio[846]: info: MRS: Set multiroom target PCM to volumioMultiRoom Mar 27 11:38:57 volumio volumio[846]: info: Changed audio target for /tmp/multiroom/server/switch.target to volumioMultiRoom Mar 27 11:38:57 volumio volumio[846]: info: MRS: Set multiroom target PCM to volumioLocalPlayback Mar 27 11:38:57 volumio volumio[846]: info: Changed audio target for /tmp/multiroom/client/switch.target to volumioLocalPlayback Mar 27 11:38:57 volumio volumio[846]: info: MRS: STARTING volumioStreaming Mar 27 11:38:57 volumio sudo[3224]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Mar 27 11:38:57 volumio sudo[3224]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 27 11:38:57 volumio sudo[3224]: pam_unix(sudo:session): session closed for user root Mar 27 11:38:57 volumio sudo[3227]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumioStreaming Mar 27 11:38:57 volumio sudo[3227]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 27 11:38:57 volumio systemd[1]: Started VolumioStreamingService. Mar 27 11:38:57 volumio sudo[3227]: pam_unix(sudo:session): session closed for user root Mar 27 11:38:57 volumio volumio[846]: info: MRS: volumioStreaming STARTED Mar 27 11:38:57 volumio sudo[3230]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Mar 27 11:38:57 volumio sudo[3230]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 27 11:38:57 volumio sudo[3230]: pam_unix(sudo:session): session closed for user root Mar 27 11:38:57 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 27 11:38:57 volumio volumio[846]: info: Received Get System Info Mar 27 11:38:57 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 27 11:38:57 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 27 11:38:57 volumio volumio[846]: info: Discovery: Getting this device information Mar 27 11:38:57 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Mar 27 11:38:57 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 27 11:38:58 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 27 11:38:58 volumio volumio[846]: info: Received Get System Info Mar 27 11:38:58 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 27 11:38:58 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 27 11:38:58 volumio volumio[846]: info: Discovery: Getting this device information Mar 27 11:38:58 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Mar 27 11:38:58 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 27 11:38:58 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 27 11:38:58 volumio volumio[846]: info: Received Get System Info Mar 27 11:38:58 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 27 11:38:58 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 27 11:38:58 volumio volumio[846]: info: Discovery: Getting this device information Mar 27 11:38:58 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Mar 27 11:38:58 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 27 11:39:03 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 27 11:39:11 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 27 11:39:11 volumio volumio[846]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 27 11:39:11 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 27 11:39:11 volumio volumio[846]: info: Received Get System Version Mar 27 11:39:11 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 27 11:39:11 volumio volumio[846]: info: Received Get System Info Mar 27 11:39:11 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 27 11:39:11 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 27 11:39:11 volumio volumio[846]: info: Discovery: Getting this device information Mar 27 11:39:11 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Mar 27 11:39:11 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 27 11:39:13 volumio volumio[846]: info: CoreCommandRouter::volumioPause Mar 27 11:39:13 volumio volumio[846]: info: CoreStateMachine::pause Mar 27 11:39:13 volumio volumio[846]: info: CoreStateMachine::stPlaybackTimer Mar 27 11:39:13 volumio volumio[846]: info: CoreStateMachine::servicePause Mar 27 11:39:13 volumio volumio[846]: info: CoreCommandRouter::servicePause Mar 27 11:39:13 volumio volumio[846]: info: Received pause Mar 27 11:39:13 volumio vtcs[2106]: [pause:147] Entering Mar 27 11:39:13 volumio vtcs[2106]: [feedThread:276] Exiting Mar 27 11:39:13 volumio vtcs[2106]: [pause:161] Exiting Mar 27 11:39:13 volumio volumio[846]: info: CoreCommandRouter::servicePushState Mar 27 11:39:13 volumio volumio[846]: info: CoreStateMachine::pushState Mar 27 11:39:13 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 11:39:13 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Mar 27 11:39:13 volumio volumio[846]: info: MRS: Pushing multiroomSync output update for this device Mar 27 11:39:13 volumio volumio[846]: info: MRS: Pushing multiroomSync output Mar 27 11:39:13 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Mar 27 11:39:13 volumio volumio5-onboarding[1125]: time=2026-03-27T11:39:13.260+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.195:58077 @ 0x1f010e0" state=STATUS_PAUSED positionMs=62828 volume=100 Mar 27 11:39:13 volumio volumio5-onboarding[1125]: time=2026-03-27T11:39:13.260+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%1f,192.168.0.195:58077 @ 0x1cb7e90" state=STATUS_PAUSED positionMs=62828 volume=100 Mar 27 11:39:13 volumio volumio5-onboarding[1125]: time=2026-03-27T11:39:13.261+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.195:58077 @ 0x1f010e0" id= title=Big Mar 27 11:39:13 volumio volumio5-onboarding[1125]: time=2026-03-27T11:39:13.261+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%1f,192.168.0.195:58077 @ 0x1cb7e90" id= title=Big Mar 27 11:39:13 volumio volumio[846]: info: touch_display: Setting screensaver timeout to 120 seconds. Mar 27 11:39:14 volumio volumio[846]: info: CoreCommandRouter::volumioVolatilePlay Mar 27 11:39:14 volumio volumio[846]: info: CoreStateMachine::volatilePlay Mar 27 11:39:14 volumio volumio[846]: info: Received play: Mar 27 11:39:14 volumio vtcs[2106]: [start:106] Entering Mar 27 11:39:14 volumio vtcs[2106]: [start:113] Reopening PCM device... Mar 27 11:39:14 volumio volumio[846]: info: CoreCommandRouter::servicePushState Mar 27 11:39:14 volumio volumio[846]: info: CoreStateMachine::pushState Mar 27 11:39:14 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 11:39:14 volumio vtcs[2106]: [start:115] PCM opened successfully. Mar 27 11:39:14 volumio vtcs[2106]: [start:120] Exiting Mar 27 11:39:14 volumio vtcs[2106]: [feedThread:223] Entering Mar 27 11:39:14 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Mar 27 11:39:14 volumio volumio[846]: info: MRS: Pushing multiroomSync output update for this device Mar 27 11:39:14 volumio volumio[846]: info: MRS: Pushing multiroomSync output Mar 27 11:39:14 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Mar 27 11:39:14 volumio volumio5-onboarding[1125]: time=2026-03-27T11:39:14.239+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.195:58077 @ 0x1f010e0" state=STATUS_PLAYING positionMs=62828 volume=100 Mar 27 11:39:14 volumio volumio5-onboarding[1125]: time=2026-03-27T11:39:14.239+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%1f,192.168.0.195:58077 @ 0x1cb7e90" state=STATUS_PLAYING positionMs=62828 volume=100 Mar 27 11:39:14 volumio volumio5-onboarding[1125]: time=2026-03-27T11:39:14.239+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.195:58077 @ 0x1f010e0" id= title=Big Mar 27 11:39:14 volumio volumio5-onboarding[1125]: time=2026-03-27T11:39:14.239+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%1f,192.168.0.195:58077 @ 0x1cb7e90" id= title=Big Mar 27 11:39:14 volumio volumio[846]: info: touch_display: Setting screensaver timeout to 0 seconds. Mar 27 11:39:14 volumio volumio[846]: info: CoreCommandRouter::servicePushState Mar 27 11:39:14 volumio volumio[846]: info: CoreStateMachine::pushState Mar 27 11:39:14 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 11:39:14 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Mar 27 11:39:14 volumio volumio[846]: info: MRS: Pushing multiroomSync output update for this device Mar 27 11:39:14 volumio volumio[846]: info: MRS: Pushing multiroomSync output Mar 27 11:39:14 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Mar 27 11:39:14 volumio volumio5-onboarding[1125]: time=2026-03-27T11:39:14.442+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.195:58077 @ 0x1f010e0" state=STATUS_PLAYING positionMs=63428 volume=100 Mar 27 11:39:14 volumio volumio5-onboarding[1125]: time=2026-03-27T11:39:14.442+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%1f,192.168.0.195:58077 @ 0x1cb7e90" state=STATUS_PLAYING positionMs=63428 volume=100 Mar 27 11:39:14 volumio volumio5-onboarding[1125]: time=2026-03-27T11:39:14.442+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.195:58077 @ 0x1f010e0" id= title=Big Mar 27 11:39:14 volumio volumio5-onboarding[1125]: time=2026-03-27T11:39:14.442+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%1f,192.168.0.195:58077 @ 0x1cb7e90" id= title=Big Mar 27 11:39:17 volumio volumio[846]: info: Pushing metadata Mar 27 11:39:17 volumio volumio[846]: info: CoreCommandRouter::servicePushState Mar 27 11:39:17 volumio volumio[846]: info: CoreStateMachine::pushState Mar 27 11:39:17 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 11:39:17 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Mar 27 11:39:17 volumio volumio[846]: info: MRS: Pushing multiroomSync output update for this device Mar 27 11:39:17 volumio volumio[846]: info: MRS: Pushing multiroomSync output Mar 27 11:39:17 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Mar 27 11:39:17 volumio volumio[846]: info: CoreCommandRouter::servicePushState Mar 27 11:39:17 volumio volumio[846]: info: CoreStateMachine::pushState Mar 27 11:39:17 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Mar 27 11:39:17 volumio volumio[846]: info: MRS: Pushing multiroomSync output update for this device Mar 27 11:39:17 volumio volumio[846]: info: MRS: Pushing multiroomSync output Mar 27 11:39:17 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Mar 27 11:39:17 volumio volumio5-onboarding[1125]: time=2026-03-27T11:39:17.185+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.195:58077 @ 0x1f010e0" state=STATUS_PLAYING positionMs=66703 volume=100 Mar 27 11:39:17 volumio volumio5-onboarding[1125]: time=2026-03-27T11:39:17.186+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%1f,192.168.0.195:58077 @ 0x1cb7e90" state=STATUS_PLAYING positionMs=66703 volume=100 Mar 27 11:39:17 volumio volumio5-onboarding[1125]: time=2026-03-27T11:39:17.186+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.195:58077 @ 0x1f010e0" state=STATUS_PLAYING positionMs=66703 volume=100 Mar 27 11:39:17 volumio volumio5-onboarding[1125]: time=2026-03-27T11:39:17.186+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.195:58077 @ 0x1f010e0" id= title=Big Mar 27 11:39:17 volumio volumio5-onboarding[1125]: time=2026-03-27T11:39:17.186+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%1f,192.168.0.195:58077 @ 0x1cb7e90" id= title=Big Mar 27 11:39:17 volumio volumio5-onboarding[1125]: time=2026-03-27T11:39:17.186+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%1f,192.168.0.195:58077 @ 0x1cb7e90" state=STATUS_PLAYING positionMs=66703 volume=100 Mar 27 11:39:17 volumio volumio5-onboarding[1125]: time=2026-03-27T11:39:17.187+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.195:58077 @ 0x1f010e0" id= title=Big Mar 27 11:39:17 volumio volumio5-onboarding[1125]: time=2026-03-27T11:39:17.187+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%1f,192.168.0.195:58077 @ 0x1cb7e90" id= title=Big Mar 27 11:39:18 volumio vtcs[2106]: [2026-03-27 11:39:18.199] [tisoc] [warning] [PlaybackControllerImpl.cpp:220] Ignore play request because player state is already PLAYING. state_=3, playstate_=1, player_state_=1 Mar 27 11:39:20 volumio vtcs[2106]: [2026-03-27 11:39:20] [info] asio async_shutdown error: asio.misc:2 (End of file) Mar 27 11:39:21 volumio volumio[846]: info: CoreCommandRouter::volumioPause Mar 27 11:39:21 volumio volumio[846]: info: CoreStateMachine::pause Mar 27 11:39:21 volumio volumio[846]: info: CoreStateMachine::stPlaybackTimer Mar 27 11:39:21 volumio volumio[846]: info: CoreStateMachine::servicePause Mar 27 11:39:21 volumio volumio[846]: info: CoreCommandRouter::servicePause Mar 27 11:39:21 volumio volumio[846]: info: Received pause Mar 27 11:39:21 volumio vtcs[2106]: [pause:147] Entering Mar 27 11:39:21 volumio volumio[846]: info: CoreCommandRouter::servicePushState Mar 27 11:39:21 volumio volumio[846]: info: CoreStateMachine::pushState Mar 27 11:39:21 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 11:39:21 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Mar 27 11:39:21 volumio volumio[846]: info: MRS: Pushing multiroomSync output update for this device Mar 27 11:39:21 volumio volumio[846]: info: MRS: Pushing multiroomSync output Mar 27 11:39:21 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Mar 27 11:39:21 volumio volumio5-onboarding[1125]: time=2026-03-27T11:39:21.801+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.195:58077 @ 0x1f010e0" state=STATUS_PAUSED positionMs=71553 volume=100 Mar 27 11:39:21 volumio volumio5-onboarding[1125]: time=2026-03-27T11:39:21.801+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%1f,192.168.0.195:58077 @ 0x1cb7e90" state=STATUS_PAUSED positionMs=71553 volume=100 Mar 27 11:39:21 volumio volumio5-onboarding[1125]: time=2026-03-27T11:39:21.801+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.195:58077 @ 0x1f010e0" id= title=Big Mar 27 11:39:21 volumio volumio5-onboarding[1125]: time=2026-03-27T11:39:21.801+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%1f,192.168.0.195:58077 @ 0x1cb7e90" id= title=Big Mar 27 11:39:21 volumio vtcs[2106]: [feedThread:276] Exiting Mar 27 11:39:21 volumio vtcs[2106]: [pause:161] Exiting Mar 27 11:39:21 volumio volumio[846]: info: touch_display: Setting screensaver timeout to 120 seconds. Mar 27 11:39:21 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Mar 27 11:39:21 volumio volumio[846]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Mar 27 11:39:21 volumio volumio[846]: info: touch_display: Setting screensaver timeout to 120 seconds. Mar 27 11:39:24 volumio volumio[846]: info: MRS: STARTING volumioStreaming Mar 27 11:39:24 volumio sudo[3281]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/stream.m3u8 /tmp/hls/stream19.ts /tmp/hls/stream20.ts /tmp/hls/stream21.ts /tmp/hls/stream22.ts /tmp/hls/stream23.ts Mar 27 11:39:24 volumio sudo[3281]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 27 11:39:24 volumio sudo[3281]: pam_unix(sudo:session): session closed for user root Mar 27 11:39:24 volumio sudo[3284]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumioStreaming Mar 27 11:39:24 volumio sudo[3284]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 27 11:39:24 volumio systemd[1]: Stopping VolumioStreamingService... Mar 27 11:39:24 volumio systemd[1]: volumioStreaming.service: Main process exited, code=killed, status=15/TERM Mar 27 11:39:24 volumio volumio[846]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 27 11:39:24 volumio systemd[1]: volumioStreaming.service: Succeeded. Mar 27 11:39:24 volumio systemd[1]: Stopped VolumioStreamingService. Mar 27 11:39:24 volumio volumio[846]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 27 11:39:24 volumio systemd[1]: Started VolumioStreamingService. Mar 27 11:39:24 volumio sudo[3284]: pam_unix(sudo:session): session closed for user root Mar 27 11:39:24 volumio volumio[846]: info: MRS: volumioStreaming STARTED Mar 27 11:39:24 volumio sudo[3288]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Mar 27 11:39:24 volumio sudo[3288]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 27 11:39:24 volumio sudo[3288]: pam_unix(sudo:session): session closed for user root Mar 27 11:39:25 volumio volumio5-onboarding[1125]: time=2026-03-27T11:39:25.082+01:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="00:00:00:00:00:00%1f,192.168.0.195:58077 @ 0x1cb7e90" latency=67.641826ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Mar 27 11:39:25 volumio sudo[3292]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 27 11:39:25 volumio sudo[3292]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 27 11:39:25 volumio sudo[3292]: pam_unix(sudo:session): session closed for user root Mar 27 11:39:25 volumio sudo[3295]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 27 11:39:25 volumio sudo[3295]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 27 11:39:25 volumio sudo[3295]: pam_unix(sudo:session): session closed for user root Mar 27 11:39:25 volumio volumio[846]: verbose: New Socket.io Connection to 192.168.0.242 from 192.168.0.195 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 10 Mar 27 11:39:25 volumio sudo[3298]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 27 11:39:25 volumio sudo[3298]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 27 11:39:25 volumio sudo[3298]: pam_unix(sudo:session): session closed for user root Mar 27 11:39:25 volumio sudo[3301]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 27 11:39:25 volumio sudo[3301]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 27 11:39:25 volumio sudo[3301]: pam_unix(sudo:session): session closed for user root Mar 27 11:39:25 volumio volumio[846]: verbose: New Socket.io Connection to 192.168.0.242 from 192.168.0.195 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 11 Mar 27 11:39:25 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 27 11:39:25 volumio volumio[846]: info: CoreCommandRouter::volumioGetVisibleSources Mar 27 11:39:25 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 27 11:39:25 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Mar 27 11:39:25 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 27 11:39:25 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 27 11:39:25 volumio volumio[846]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Mar 27 11:39:25 volumio volumio[846]: info: Received Get System Info Mar 27 11:39:25 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 27 11:39:25 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 27 11:39:25 volumio volumio[846]: info: Discovery: Getting this device information Mar 27 11:39:25 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Mar 27 11:39:25 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 27 11:39:25 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Mar 27 11:39:25 volumio volumio[846]: info: Listing playlists Mar 27 11:39:25 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 27 11:39:25 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 27 11:39:25 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 27 11:39:27 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 27 11:39:27 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: multiroom , disableAudioOutput Mar 27 11:39:27 volumio volumio[846]: info: MRS: Starting browser stream Mar 27 11:39:27 volumio volumio[846]: info: MRS: Setting this device as single Mar 27 11:39:27 volumio volumio[846]: info: Mar 27 11:39:27 volumio volumio[846]: [1774607967573] ---------------------------- Setting Multiroom Single Mar 27 11:39:27 volumio volumio[846]: info: MRS: disable multiroom output Mar 27 11:39:27 volumio volumio[846]: info: MRS: Set multiroom target PCM to volumioDiscard Mar 27 11:39:27 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 27 11:39:27 volumio volumio[846]: info: Received Get System Info Mar 27 11:39:27 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 27 11:39:27 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 27 11:39:27 volumio volumio[846]: info: Discovery: Getting this device information Mar 27 11:39:27 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Mar 27 11:39:27 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 27 11:39:27 volumio volumio[846]: info: Changed audio target for /tmp/multiroom/client/switch.target to volumioDiscard Mar 27 11:39:28 volumio volumio[846]: info: MRS: Set multiroom target PCM to volumioLocalPlayback Mar 27 11:39:28 volumio volumio[846]: info: Changed audio target for /tmp/multiroom/server/switch.target to volumioLocalPlayback Mar 27 11:39:28 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 27 11:39:28 volumio volumio[846]: info: Received Get System Info Mar 27 11:39:28 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 27 11:39:28 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 27 11:39:28 volumio volumio[846]: info: Discovery: Getting this device information Mar 27 11:39:28 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Mar 27 11:39:28 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 27 11:39:29 volumio volumio[846]: info: MRS: STOPPING volumioStreaming Mar 27 11:39:29 volumio sudo[3321]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Mar 27 11:39:29 volumio sudo[3321]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 27 11:39:29 volumio systemd[1]: Stopping VolumioStreamingService... Mar 27 11:39:29 volumio systemd[1]: volumioStreaming.service: Main process exited, code=killed, status=15/TERM Mar 27 11:39:29 volumio volumio[846]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 27 11:39:29 volumio sudo[3324]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Mar 27 11:39:29 volumio sudo[3324]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 27 11:39:29 volumio systemd[1]: volumioStreaming.service: Succeeded. Mar 27 11:39:29 volumio systemd[1]: Stopped VolumioStreamingService. Mar 27 11:39:29 volumio sudo[3324]: pam_unix(sudo:session): session closed for user root Mar 27 11:39:29 volumio volumio[846]: info: MRS: Removed streaming files Mar 27 11:39:29 volumio sudo[3321]: pam_unix(sudo:session): session closed for user root Mar 27 11:39:29 volumio volumio[846]: info: MRS: volumioStreaming STOPPED Mar 27 11:39:29 volumio volumio[846]: info: CoreCommandRouter::volumioVolatilePlay Mar 27 11:39:29 volumio volumio[846]: info: CoreStateMachine::volatilePlay Mar 27 11:39:29 volumio volumio[846]: info: Received play: Mar 27 11:39:29 volumio vtcs[2106]: [start:106] Entering Mar 27 11:39:29 volumio vtcs[2106]: [start:113] Reopening PCM device... Mar 27 11:39:29 volumio vtcs[2106]: [start:115] PCM opened successfully. Mar 27 11:39:29 volumio vtcs[2106]: [start:120] Exiting Mar 27 11:39:29 volumio vtcs[2106]: [feedThread:223] Entering Mar 27 11:39:29 volumio volumio[846]: info: CoreCommandRouter::servicePushState Mar 27 11:39:29 volumio volumio[846]: info: CoreStateMachine::pushState Mar 27 11:39:29 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 11:39:29 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Mar 27 11:39:29 volumio volumio[846]: info: MRS: Pushing multiroomSync output update for this device Mar 27 11:39:29 volumio volumio[846]: info: MRS: Pushing multiroomSync output Mar 27 11:39:29 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Mar 27 11:39:29 volumio volumio5-onboarding[1125]: time=2026-03-27T11:39:29.515+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.195:58077 @ 0x1f010e0" state=STATUS_PLAYING positionMs=71553 volume=100 Mar 27 11:39:29 volumio volumio5-onboarding[1125]: time=2026-03-27T11:39:29.516+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%1f,192.168.0.195:58077 @ 0x1cb7e90" state=STATUS_PLAYING positionMs=71553 volume=100 Mar 27 11:39:29 volumio volumio5-onboarding[1125]: time=2026-03-27T11:39:29.517+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.195:58077 @ 0x1f010e0" id= title=Big Mar 27 11:39:29 volumio volumio5-onboarding[1125]: time=2026-03-27T11:39:29.517+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%1f,192.168.0.195:58077 @ 0x1cb7e90" id= title=Big Mar 27 11:39:29 volumio volumio[846]: info: touch_display: Setting screensaver timeout to 0 seconds. Mar 27 11:39:29 volumio volumio[846]: info: CoreCommandRouter::servicePushState Mar 27 11:39:29 volumio volumio[846]: info: CoreStateMachine::pushState Mar 27 11:39:29 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 11:39:29 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Mar 27 11:39:29 volumio volumio[846]: info: MRS: Pushing multiroomSync output update for this device Mar 27 11:39:29 volumio volumio[846]: info: MRS: Pushing multiroomSync output Mar 27 11:39:29 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Mar 27 11:39:29 volumio volumio5-onboarding[1125]: time=2026-03-27T11:39:29.818+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.195:58077 @ 0x1f010e0" state=STATUS_PLAYING positionMs=72053 volume=100 Mar 27 11:39:29 volumio volumio5-onboarding[1125]: time=2026-03-27T11:39:29.819+01:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%1f,192.168.0.195:58077 @ 0x1cb7e90" state=STATUS_PLAYING positionMs=72053 volume=100 Mar 27 11:39:29 volumio volumio5-onboarding[1125]: time=2026-03-27T11:39:29.819+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.195:58077 @ 0x1f010e0" id= title=Big Mar 27 11:39:29 volumio volumio5-onboarding[1125]: time=2026-03-27T11:39:29.819+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%1f,192.168.0.195:58077 @ 0x1cb7e90" id= title=Big Mar 27 11:39:34 volumio volumio[846]: info: CoreCommandRouter::volumioGetQueue Mar 27 11:39:34 volumio volumio[846]: info: CoreStateMachine::getQueue Mar 27 11:39:34 volumio volumio[846]: info: CorePlayQueue::getQueue Mar 27 11:39:35 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 27 11:39:36 volumio volumio[846]: info: CoreCommandRouter::volumioRemoveQueueItem Mar 27 11:39:36 volumio volumio[846]: info: CoreStateMachine::removeQueueItem Mar 27 11:39:36 volumio volumio[846]: info: CoreStateMachine::stop Mar 27 11:39:36 volumio volumio[846]: info: CoreStateMachine::serviceStop Mar 27 11:39:36 volumio volumio[846]: info: CoreCommandRouter::serviceStop Mar 27 11:39:36 volumio vtcs[2106]: [close:85] Entering Mar 27 11:39:36 volumio volumio[846]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 27 11:39:36 volumio volumio[846]: TypeError: Cannot read property 'then' of undefined Mar 27 11:39:36 volumio volumio[846]: at CoreStateMachine.removeQueueItem (/volumio/app/statemachine.js:1372:7) Mar 27 11:39:36 volumio volumio[846]: at CoreCommandRouter.volumioRemoveQueueItem (/volumio/app/index.js:121:28) Mar 27 11:39:36 volumio volumio[846]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:173:33) Mar 27 11:39:36 volumio volumio[846]: at Socket.emit (events.js:315:20) Mar 27 11:39:36 volumio volumio[846]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Mar 27 11:39:36 volumio volumio[846]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Mar 27 11:39:36 volumio volumio[846]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 27 11:39:36 volumio vtcs[2106]: [feedThread:276] Exiting Mar 27 11:39:36 volumio vtcs[2106]: [close:100] Exiting Mar 27 11:39:36 volumio vtcs[2106]: [2026-03-27 11:39:36.864] [tisoc] [warning] [PlaybackControllerImpl.cpp:498] Illegal state:0 Mar 27 11:39:36 volumio sudo[3341]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-03-27 11:38 Mar 27 11:39:36 volumio sudo[3341]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 10:59:40 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="37c6ab864cb114e1344d540995c69f86"