Mar 29 17:40:04 volumio4 volumio[1320]: info: Preload queue cleared Mar 29 17:40:04 volumio4 volumio[1320]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 29 17:40:04 volumio4 volumio[1320]: info: CoreStateMachine::ClearQueue Mar 29 17:40:04 volumio4 volumio[1320]: info: CoreStateMachine::stop Mar 29 17:40:04 volumio4 volumio[1320]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 17:40:04 volumio4 volumio[1320]: info: CoreStateMachine::stPlaybackTimer Mar 29 17:40:04 volumio4 volumio[1320]: info: CoreStateMachine::updateTrackBlock Mar 29 17:40:04 volumio4 volumio[1320]: info: CorePlayQueue::getTrackBlock Mar 29 17:40:04 volumio4 volumio[1320]: info: CoreStateMachine::pushState Mar 29 17:40:04 volumio4 volumio[1320]: info: CorePlayQueue::getTrack 0 Mar 29 17:40:04 volumio4 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 17:40:04 volumio4 volumio[1320]: info: CoreCommandRouter::volumioPushState Mar 29 17:40:04 volumio4 volumio[1320]: info: CoreStateMachine::serviceStop Mar 29 17:40:04 volumio4 volumio[1320]: info: CorePlayQueue::getTrack 0 Mar 29 17:40:04 volumio4 volumio[1320]: info: CoreCommandRouter::serviceStop Mar 29 17:40:04 volumio4 volumio[1320]: info: [1774788004570] ControllerWebradio::stop Mar 29 17:40:04 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 17:40:04 volumio4 volumio[1320]: info: CorePlayQueue::clearPlayQueue Mar 29 17:40:04 volumio4 volumio[1320]: info: CorePlayQueue::saveQueue Mar 29 17:40:04 volumio4 volumio[1320]: info: CoreCommandRouter::volumioPushQueue Mar 29 17:40:04 volumio4 volumio[1320]: info: CoreStateMachine::addQueueItems Mar 29 17:40:04 volumio4 volumio[1320]: info: CorePlayQueue::addQueueItems Mar 29 17:40:04 volumio4 volumio[1320]: info: Preload queue cleared Mar 29 17:40:04 volumio4 volumio[1320]: info: Adding Item to queue: http://mscp3.live-streams.nl:8360/flac.flac Mar 29 17:40:04 volumio4 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Mar 29 17:40:04 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:04.571+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" state=STATUS_STOPPED positionMs=0 volume= Mar 29 17:40:04 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:04.571+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" id=http://stream-dc1.radioparadise.com/rp_192m.ogg title="Radio Paradise" Mar 29 17:40:04 volumio4 volumio[1320]: info: CoreCommandRouter::volumioPushQueue Mar 29 17:40:04 volumio4 volumio[1320]: info: CorePlayQueue::saveQueue Mar 29 17:40:04 volumio4 volumio[1320]: info: CoreStateMachine::updateTrackBlock Mar 29 17:40:04 volumio4 volumio[1320]: info: CorePlayQueue::getTrackBlock Mar 29 17:40:04 volumio4 volumio[1320]: info: CoreCommandRouter::volumioPlay Mar 29 17:40:04 volumio4 volumio[1320]: info: CoreStateMachine::play index 0 Mar 29 17:40:04 volumio4 volumio[1320]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 17:40:04 volumio4 volumio[1320]: info: CoreStateMachine::stop Mar 29 17:40:04 volumio4 volumio[1320]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 17:40:04 volumio4 volumio[1320]: info: CoreStateMachine::play index undefined Mar 29 17:40:04 volumio4 volumio[1320]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 17:40:04 volumio4 volumio[1320]: info: CorePlayQueue::getTrack 0 Mar 29 17:40:04 volumio4 volumio[1320]: info: CoreStateMachine::startPlaybackTimer Mar 29 17:40:04 volumio4 volumio[1320]: info: CorePlayQueue::getTrack 0 Mar 29 17:40:04 volumio4 volumio[1320]: info: [1774788004573] ControllerWebradio::clearAddPlayTrack Mar 29 17:40:04 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 17:40:04 volumio4 volumio[1320]: info: Mar 29 17:40:04 volumio4 volumio[1320]: ---------------------------- MPD announces state update: player Mar 29 17:40:04 volumio4 volumio[1320]: info: sendMpdCommand stop took 5 milliseconds Mar 29 17:40:04 volumio4 volumio[1320]: info: ControllerMpd::getState Mar 29 17:40:04 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand status Mar 29 17:40:04 volumio4 volumio[1320]: info: sendMpdCommand stop took 2 milliseconds Mar 29 17:40:04 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand clear Mar 29 17:40:04 volumio4 volumio[1320]: info: Mar 29 17:40:04 volumio4 volumio[1320]: ---------------------------- MPD announces state update: player Mar 29 17:40:04 volumio4 volumio[1320]: info: ControllerMpd::getState Mar 29 17:40:04 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand status Mar 29 17:40:04 volumio4 volumio[1320]: info: Mar 29 17:40:04 volumio4 volumio[1320]: ---------------------------- MPD announces state update: player Mar 29 17:40:04 volumio4 volumio[1320]: info: ControllerMpd::getState Mar 29 17:40:04 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand status Mar 29 17:40:04 volumio4 volumio[1320]: info: Mar 29 17:40:04 volumio4 volumio[1320]: ---------------------------- MPD announces system playlist update Mar 29 17:40:04 volumio4 volumio[1320]: info: Ignoring MPD Status Update Mar 29 17:40:04 volumio4 volumio[1320]: info: sendMpdCommand status took 1 milliseconds Mar 29 17:40:04 volumio4 volumio[1320]: info: sendMpdCommand clear took 0 milliseconds Mar 29 17:40:04 volumio4 volumio[1320]: info: sendMpdCommand status took 0 milliseconds Mar 29 17:40:04 volumio4 volumio[1320]: info: sendMpdCommand status took 1 milliseconds Mar 29 17:40:04 volumio4 volumio[1320]: verbose: ControllerMpd::parseState Mar 29 17:40:04 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 17:40:04 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand load "http://mscp3.live-streams.nl:8360/flac.flac" Mar 29 17:40:04 volumio4 volumio[1320]: verbose: ControllerMpd::parseState Mar 29 17:40:04 volumio4 volumio[1320]: verbose: ControllerMpd::parseState Mar 29 17:40:04 volumio4 volumio[1320]: info: ControllerMpd::pushState Mar 29 17:40:04 volumio4 volumio[1320]: info: CoreCommandRouter::servicePushState Mar 29 17:40:04 volumio4 volumio[1320]: info: CoreStateMachine::pushState Mar 29 17:40:04 volumio4 volumio[1320]: info: CorePlayQueue::getTrack 0 Mar 29 17:40:04 volumio4 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 17:40:04 volumio4 volumio[1320]: info: CoreCommandRouter::volumioPushState Mar 29 17:40:04 volumio4 volumio[1320]: info: CorePlayQueue::getTrack 0 Mar 29 17:40:04 volumio4 volumio[1320]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Mar 29 17:40:04 volumio4 volumio[1320]: info: ControllerMpd::pushState Mar 29 17:40:04 volumio4 volumio[1320]: info: CoreCommandRouter::servicePushState Mar 29 17:40:04 volumio4 volumio[1320]: info: CoreStateMachine::pushState Mar 29 17:40:04 volumio4 volumio[1320]: info: CorePlayQueue::getTrack 0 Mar 29 17:40:04 volumio4 volumio[1320]: info: CoreCommandRouter::volumioPushState Mar 29 17:40:04 volumio4 volumio[1320]: info: CorePlayQueue::getTrack 0 Mar 29 17:40:04 volumio4 volumio[1320]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Mar 29 17:40:04 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:04.579+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" state=STATUS_STOPPED positionMs=0 volume= Mar 29 17:40:04 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:04.579+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Mar 29 17:40:04 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:04.579+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" state=STATUS_STOPPED positionMs=0 volume= Mar 29 17:40:04 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:04.579+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Mar 29 17:40:04 volumio4 volumio[1320]: info: ------------------------------ 4ms Mar 29 17:40:04 volumio4 volumio[1320]: info: ------------------------------ 4ms Mar 29 17:40:04 volumio4 volumio[1320]: info: Mar 29 17:40:04 volumio4 volumio[1320]: ---------------------------- MPD announces system playlist update Mar 29 17:40:04 volumio4 volumio[1320]: info: Ignoring MPD Status Update Mar 29 17:40:04 volumio4 volumio[1320]: info: Mar 29 17:40:04 volumio4 volumio[1320]: ---------------------------- MPD announces system playlist update Mar 29 17:40:04 volumio4 volumio[1320]: info: Ignoring MPD Status Update Mar 29 17:40:05 volumio4 volumio[1320]: error: updateQueue error: null Mar 29 17:40:05 volumio4 volumio[1320]: info: ------------------------------ 723ms Mar 29 17:40:05 volumio4 volumio[1320]: info: sendMpdCommand playlistinfo took 722 milliseconds Mar 29 17:40:05 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand add "http://mscp3.live-streams.nl:8360/flac.flac" Mar 29 17:40:05 volumio4 volumio[1320]: verbose: ControllerMpd::parseTrackInfo Mar 29 17:40:05 volumio4 volumio[1320]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Mar 29 17:40:05 volumio4 volumio[1320]: info: ------------------------------ 724ms Mar 29 17:40:05 volumio4 volumio[1320]: error: updateQueue error: null Mar 29 17:40:05 volumio4 volumio[1320]: error: updateQueue error: null Mar 29 17:40:05 volumio4 volumio[1320]: info: Mar 29 17:40:05 volumio4 volumio[1320]: ---------------------------- MPD announces system playlist update Mar 29 17:40:05 volumio4 volumio[1320]: info: Ignoring MPD Status Update Mar 29 17:40:05 volumio4 volumio[1320]: info: ------------------------------ 718ms Mar 29 17:40:05 volumio4 volumio[1320]: info: ------------------------------ 718ms Mar 29 17:40:05 volumio4 volumio[1320]: info: sendMpdCommand add "http://mscp3.live-streams.nl:8360/flac.flac" took 0 milliseconds Mar 29 17:40:05 volumio4 volumio[1320]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 29 17:40:05 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand play Mar 29 17:40:05 volumio4 volumio[1320]: info: Mar 29 17:40:05 volumio4 volumio[1320]: ---------------------------- MPD announces system playlist update Mar 29 17:40:05 volumio4 volumio[1320]: info: Ignoring MPD Status Update Mar 29 17:40:05 volumio4 volumio[1320]: info: Mar 29 17:40:05 volumio4 volumio[1320]: ---------------------------- MPD announces system playlist update Mar 29 17:40:05 volumio4 volumio[1320]: info: Ignoring MPD Status Update Mar 29 17:40:05 volumio4 volumio[1320]: info: ------------------------------ 1ms Mar 29 17:40:05 volumio4 volumio[1320]: info: sendMpdCommand play took 0 milliseconds Mar 29 17:40:05 volumio4 volumio[1320]: info: ------------------------------ 0ms Mar 29 17:40:05 volumio4 volumio[1320]: info: ------------------------------ 0ms Mar 29 17:40:05 volumio4 volumio[1320]: info: Mar 29 17:40:05 volumio4 volumio[1320]: ---------------------------- MPD announces state update: player Mar 29 17:40:05 volumio4 volumio[1320]: info: ControllerMpd::getState Mar 29 17:40:05 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand status Mar 29 17:40:05 volumio4 volumio[1320]: info: Mar 29 17:40:05 volumio4 volumio[1320]: ---------------------------- MPD announces state update: player Mar 29 17:40:05 volumio4 volumio[1320]: info: ControllerMpd::getState Mar 29 17:40:05 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand status Mar 29 17:40:05 volumio4 volumio[1320]: info: Mar 29 17:40:05 volumio4 volumio[1320]: ---------------------------- MPD announces state update: player Mar 29 17:40:05 volumio4 volumio[1320]: info: ControllerMpd::getState Mar 29 17:40:05 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand status Mar 29 17:40:05 volumio4 volumio[1320]: info: Mar 29 17:40:05 volumio4 volumio[1320]: ---------------------------- MPD announces state update: player Mar 29 17:40:05 volumio4 volumio[1320]: info: sendMpdCommand status took 2 milliseconds Mar 29 17:40:05 volumio4 volumio[1320]: info: ControllerMpd::getState Mar 29 17:40:05 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand status Mar 29 17:40:05 volumio4 volumio[1320]: verbose: ControllerMpd::parseState Mar 29 17:40:05 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 17:40:05 volumio4 volumio[1320]: info: sendMpdCommand status took 2 milliseconds Mar 29 17:40:05 volumio4 volumio[1320]: info: sendMpdCommand status took 2 milliseconds Mar 29 17:40:05 volumio4 volumio[1320]: verbose: ControllerMpd::parseState Mar 29 17:40:05 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 17:40:05 volumio4 volumio[1320]: verbose: ControllerMpd::parseState Mar 29 17:40:05 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 17:40:05 volumio4 volumio[1320]: info: Mar 29 17:40:05 volumio4 volumio[1320]: ---------------------------- MPD announces state update: player Mar 29 17:40:05 volumio4 volumio[1320]: info: ControllerMpd::getState Mar 29 17:40:05 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand status Mar 29 17:40:05 volumio4 volumio[1320]: info: Mar 29 17:40:05 volumio4 volumio[1320]: ---------------------------- MPD announces state update: player Mar 29 17:40:05 volumio4 volumio[1320]: info: ControllerMpd::getState Mar 29 17:40:05 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand status Mar 29 17:40:05 volumio4 volumio[1320]: info: sendMpdCommand status took 0 milliseconds Mar 29 17:40:05 volumio4 volumio[1320]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 29 17:40:05 volumio4 volumio[1320]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 29 17:40:05 volumio4 volumio[1320]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 29 17:40:05 volumio4 volumio[1320]: info: sendMpdCommand status took 0 milliseconds Mar 29 17:40:05 volumio4 volumio[1320]: verbose: ControllerMpd::parseState Mar 29 17:40:05 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 17:40:05 volumio4 volumio[1320]: verbose: ControllerMpd::parseTrackInfo Mar 29 17:40:05 volumio4 volumio[1320]: verbose: ControllerMpd::parseTrackInfo Mar 29 17:40:05 volumio4 volumio[1320]: verbose: ControllerMpd::parseTrackInfo Mar 29 17:40:05 volumio4 volumio[1320]: verbose: ControllerMpd::parseState Mar 29 17:40:05 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 17:40:05 volumio4 volumio[1320]: info: ControllerMpd::pushState Mar 29 17:40:05 volumio4 volumio[1320]: info: CoreCommandRouter::servicePushState Mar 29 17:40:05 volumio4 volumio[1320]: info: CorePlayQueue::getTrack 0 Mar 29 17:40:05 volumio4 volumio[1320]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flac.flac","artist":null,"album":null,"uri":"http://mscp3.live-streams.nl:8360/flac.flac","trackType":"flac"} Mar 29 17:40:05 volumio4 volumio[1320]: verbose: CURRENT POSITION 0 Mar 29 17:40:05 volumio4 volumio[1320]: info: CoreStateMachine::syncState stateService play Mar 29 17:40:05 volumio4 volumio[1320]: info: CoreStateMachine::syncState currentStatus stop Mar 29 17:40:05 volumio4 volumio[1320]: info: ControllerMpd::pushState Mar 29 17:40:05 volumio4 volumio[1320]: info: CoreCommandRouter::servicePushState Mar 29 17:40:05 volumio4 volumio[1320]: info: CorePlayQueue::getTrack 0 Mar 29 17:40:05 volumio4 volumio[1320]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flac.flac","artist":null,"album":null,"uri":"http://mscp3.live-streams.nl:8360/flac.flac","trackType":"flac"} Mar 29 17:40:05 volumio4 volumio[1320]: verbose: CURRENT POSITION 0 Mar 29 17:40:05 volumio4 volumio[1320]: info: CoreStateMachine::syncState stateService play Mar 29 17:40:05 volumio4 volumio[1320]: info: CoreStateMachine::syncState currentStatus play Mar 29 17:40:05 volumio4 volumio[1320]: info: Received an update from plugin. extracting info from payload Mar 29 17:40:05 volumio4 volumio[1320]: info: CoreStateMachine::pushState Mar 29 17:40:05 volumio4 volumio[1320]: info: CorePlayQueue::getTrack 0 Mar 29 17:40:05 volumio4 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 17:40:05 volumio4 volumio[1320]: info: CoreCommandRouter::volumioPushState Mar 29 17:40:05 volumio4 volumio[1320]: info: CoreStateMachine::pushState Mar 29 17:40:05 volumio4 volumio[1320]: info: CoreCommandRouter::volumioPushState Mar 29 17:40:05 volumio4 volumio[1320]: info: ControllerMpd::pushState Mar 29 17:40:05 volumio4 volumio[1320]: info: CoreCommandRouter::servicePushState Mar 29 17:40:05 volumio4 volumio[1320]: info: CorePlayQueue::getTrack 0 Mar 29 17:40:05 volumio4 volumio[1320]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flac.flac","artist":null,"album":null,"uri":"http://mscp3.live-streams.nl:8360/flac.flac","trackType":"flac"} Mar 29 17:40:05 volumio4 volumio[1320]: verbose: CURRENT POSITION 0 Mar 29 17:40:05 volumio4 volumio[1320]: info: CoreStateMachine::syncState stateService play Mar 29 17:40:05 volumio4 volumio[1320]: info: CoreStateMachine::syncState currentStatus play Mar 29 17:40:05 volumio4 volumio[1320]: info: Received an update from plugin. extracting info from payload Mar 29 17:40:05 volumio4 volumio[1320]: info: CoreStateMachine::pushState Mar 29 17:40:05 volumio4 volumio[1320]: info: CorePlayQueue::getTrack 0 Mar 29 17:40:05 volumio4 volumio[1320]: info: CoreCommandRouter::volumioPushState Mar 29 17:40:05 volumio4 volumio[1320]: info: CoreStateMachine::pushState Mar 29 17:40:05 volumio4 volumio[1320]: info: CoreCommandRouter::volumioPushState Mar 29 17:40:05 volumio4 volumio[1320]: info: ------------------------------ 4ms Mar 29 17:40:05 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:05.957+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" state=STATUS_PLAYING positionMs=0 volume= Mar 29 17:40:05 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:05.957+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" state=STATUS_PLAYING positionMs=0 volume= Mar 29 17:40:05 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:05.957+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Mar 29 17:40:05 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:05.957+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Mar 29 17:40:05 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:05.957+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" state=STATUS_PLAYING positionMs=0 volume= Mar 29 17:40:05 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:05.957+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Mar 29 17:40:05 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:05.957+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" state=STATUS_PLAYING positionMs=0 volume= Mar 29 17:40:05 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:05.957+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Mar 29 17:40:05 volumio4 volumio[1320]: info: ------------------------------ 8ms Mar 29 17:40:05 volumio4 volumio[1320]: info: ------------------------------ 8ms Mar 29 17:40:05 volumio4 volumio[1320]: info: sendMpdCommand status took 6 milliseconds Mar 29 17:40:05 volumio4 volumio[1320]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 29 17:40:05 volumio4 volumio[1320]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 29 17:40:05 volumio4 volumio[1320]: verbose: ControllerMpd::parseState Mar 29 17:40:05 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 17:40:05 volumio4 volumio[1320]: verbose: ControllerMpd::parseTrackInfo Mar 29 17:40:05 volumio4 volumio[1320]: verbose: ControllerMpd::parseTrackInfo Mar 29 17:40:05 volumio4 volumio[1320]: info: ControllerMpd::pushState Mar 29 17:40:05 volumio4 volumio[1320]: info: CoreCommandRouter::servicePushState Mar 29 17:40:05 volumio4 volumio[1320]: info: CorePlayQueue::getTrack 0 Mar 29 17:40:05 volumio4 volumio[1320]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flac.flac","artist":null,"album":null,"uri":"http://mscp3.live-streams.nl:8360/flac.flac","trackType":"flac"} Mar 29 17:40:05 volumio4 volumio[1320]: verbose: CURRENT POSITION 0 Mar 29 17:40:05 volumio4 volumio[1320]: info: CoreStateMachine::syncState stateService play Mar 29 17:40:05 volumio4 volumio[1320]: info: CoreStateMachine::syncState currentStatus play Mar 29 17:40:05 volumio4 volumio[1320]: info: Received an update from plugin. extracting info from payload Mar 29 17:40:05 volumio4 volumio[1320]: info: CoreStateMachine::pushState Mar 29 17:40:05 volumio4 volumio[1320]: info: CorePlayQueue::getTrack 0 Mar 29 17:40:05 volumio4 volumio[1320]: info: CoreCommandRouter::volumioPushState Mar 29 17:40:05 volumio4 volumio[1320]: info: CoreStateMachine::pushState Mar 29 17:40:05 volumio4 volumio[1320]: info: CoreCommandRouter::volumioPushState Mar 29 17:40:05 volumio4 volumio[1320]: info: ControllerMpd::pushState Mar 29 17:40:05 volumio4 volumio[1320]: info: CoreCommandRouter::servicePushState Mar 29 17:40:05 volumio4 volumio[1320]: info: CorePlayQueue::getTrack 0 Mar 29 17:40:05 volumio4 volumio[1320]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flac.flac","artist":null,"album":null,"uri":"http://mscp3.live-streams.nl:8360/flac.flac","trackType":"flac"} Mar 29 17:40:05 volumio4 volumio[1320]: verbose: CURRENT POSITION 0 Mar 29 17:40:05 volumio4 volumio[1320]: info: CoreStateMachine::syncState stateService play Mar 29 17:40:05 volumio4 volumio[1320]: info: CoreStateMachine::syncState currentStatus play Mar 29 17:40:05 volumio4 volumio[1320]: info: Received an update from plugin. extracting info from payload Mar 29 17:40:05 volumio4 volumio[1320]: info: CoreStateMachine::pushState Mar 29 17:40:05 volumio4 volumio[1320]: info: CorePlayQueue::getTrack 0 Mar 29 17:40:05 volumio4 volumio[1320]: info: CoreCommandRouter::volumioPushState Mar 29 17:40:05 volumio4 volumio[1320]: info: CoreStateMachine::pushState Mar 29 17:40:05 volumio4 volumio[1320]: info: CoreCommandRouter::volumioPushState Mar 29 17:40:05 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:05.964+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" state=STATUS_PLAYING positionMs=0 volume= Mar 29 17:40:05 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:05.964+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Mar 29 17:40:05 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:05.964+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" state=STATUS_PLAYING positionMs=0 volume= Mar 29 17:40:05 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:05.964+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" state=STATUS_PLAYING positionMs=0 volume= Mar 29 17:40:05 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:05.964+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Mar 29 17:40:05 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:05.964+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" state=STATUS_PLAYING positionMs=0 volume= Mar 29 17:40:05 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:05.964+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Mar 29 17:40:05 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:05.964+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Mar 29 17:40:05 volumio4 volumio[1320]: info: ------------------------------ 13ms Mar 29 17:40:05 volumio4 volumio[1320]: info: ------------------------------ 13ms Mar 29 17:40:05 volumio4 volumio[1320]: info: sendMpdCommand playlistinfo took 10 milliseconds Mar 29 17:40:05 volumio4 volumio[1320]: verbose: ControllerMpd::parseTrackInfo Mar 29 17:40:05 volumio4 volumio[1320]: info: ControllerMpd::pushState Mar 29 17:40:05 volumio4 volumio[1320]: info: CoreCommandRouter::servicePushState Mar 29 17:40:05 volumio4 volumio[1320]: info: CorePlayQueue::getTrack 0 Mar 29 17:40:05 volumio4 volumio[1320]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flac.flac","artist":null,"album":null,"uri":"http://mscp3.live-streams.nl:8360/flac.flac","trackType":"flac"} Mar 29 17:40:05 volumio4 volumio[1320]: verbose: CURRENT POSITION 0 Mar 29 17:40:05 volumio4 volumio[1320]: info: CoreStateMachine::syncState stateService play Mar 29 17:40:05 volumio4 volumio[1320]: info: CoreStateMachine::syncState currentStatus play Mar 29 17:40:05 volumio4 volumio[1320]: info: Received an update from plugin. extracting info from payload Mar 29 17:40:05 volumio4 volumio[1320]: info: CoreStateMachine::pushState Mar 29 17:40:05 volumio4 volumio[1320]: info: CorePlayQueue::getTrack 0 Mar 29 17:40:05 volumio4 volumio[1320]: info: CoreCommandRouter::volumioPushState Mar 29 17:40:05 volumio4 volumio[1320]: info: CoreStateMachine::pushState Mar 29 17:40:05 volumio4 volumio[1320]: info: CoreCommandRouter::volumioPushState Mar 29 17:40:05 volumio4 volumio[1320]: info: ------------------------------ 19ms Mar 29 17:40:05 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:05.972+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" state=STATUS_PLAYING positionMs=0 volume= Mar 29 17:40:05 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:05.972+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Mar 29 17:40:05 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:05.972+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" state=STATUS_PLAYING positionMs=0 volume= Mar 29 17:40:05 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:05.972+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Mar 29 17:40:06 volumio4 volumio[1320]: info: Mar 29 17:40:06 volumio4 volumio[1320]: ---------------------------- MPD announces system playlist update Mar 29 17:40:06 volumio4 volumio[1320]: info: Ignoring MPD Status Update Mar 29 17:40:06 volumio4 volumio[1320]: info: Mar 29 17:40:06 volumio4 volumio[1320]: ---------------------------- MPD announces state update: player Mar 29 17:40:06 volumio4 volumio[1320]: info: ControllerMpd::getState Mar 29 17:40:06 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand status Mar 29 17:40:06 volumio4 volumio[1320]: info: Mar 29 17:40:06 volumio4 volumio[1320]: ---------------------------- MPD announces system playlist update Mar 29 17:40:06 volumio4 volumio[1320]: info: Ignoring MPD Status Update Mar 29 17:40:06 volumio4 volumio[1320]: info: Mar 29 17:40:06 volumio4 volumio[1320]: ---------------------------- MPD announces state update: player Mar 29 17:40:06 volumio4 volumio[1320]: info: ControllerMpd::getState Mar 29 17:40:06 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand status Mar 29 17:40:06 volumio4 volumio[1320]: info: Mar 29 17:40:06 volumio4 volumio[1320]: ---------------------------- MPD announces system playlist update Mar 29 17:40:06 volumio4 volumio[1320]: info: Ignoring MPD Status Update Mar 29 17:40:06 volumio4 volumio[1320]: info: Mar 29 17:40:06 volumio4 volumio[1320]: ---------------------------- MPD announces state update: player Mar 29 17:40:06 volumio4 volumio[1320]: info: ControllerMpd::getState Mar 29 17:40:06 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand status Mar 29 17:40:06 volumio4 volumio[1320]: info: ------------------------------ 0ms Mar 29 17:40:06 volumio4 volumio[1320]: info: sendMpdCommand status took 0 milliseconds Mar 29 17:40:06 volumio4 volumio[1320]: info: ------------------------------ 0ms Mar 29 17:40:06 volumio4 volumio[1320]: verbose: ControllerMpd::parseState Mar 29 17:40:06 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 17:40:06 volumio4 volumio[1320]: info: sendMpdCommand status took 1 milliseconds Mar 29 17:40:06 volumio4 volumio[1320]: info: ------------------------------ 1ms Mar 29 17:40:06 volumio4 volumio[1320]: info: sendMpdCommand status took 1 milliseconds Mar 29 17:40:06 volumio4 volumio[1320]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 29 17:40:06 volumio4 volumio[1320]: verbose: ControllerMpd::parseState Mar 29 17:40:06 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 17:40:06 volumio4 volumio[1320]: verbose: ControllerMpd::parseState Mar 29 17:40:06 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 17:40:06 volumio4 volumio[1320]: verbose: ControllerMpd::parseTrackInfo Mar 29 17:40:06 volumio4 volumio[1320]: info: ControllerMpd::pushState Mar 29 17:40:06 volumio4 volumio[1320]: info: CoreCommandRouter::servicePushState Mar 29 17:40:06 volumio4 volumio[1320]: info: CorePlayQueue::getTrack 0 Mar 29 17:40:06 volumio4 volumio[1320]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flac.flac","artist":"Naim Radio","album":null,"uri":"http://mscp3.live-streams.nl:8360/flac.flac","trackType":"flac"} Mar 29 17:40:06 volumio4 volumio[1320]: verbose: CURRENT POSITION 0 Mar 29 17:40:06 volumio4 volumio[1320]: info: CoreStateMachine::syncState stateService play Mar 29 17:40:06 volumio4 volumio[1320]: info: CoreStateMachine::syncState currentStatus play Mar 29 17:40:06 volumio4 volumio[1320]: info: Received an update from plugin. extracting info from payload Mar 29 17:40:06 volumio4 volumio[1320]: info: CoreStateMachine::pushState Mar 29 17:40:06 volumio4 volumio[1320]: info: CorePlayQueue::getTrack 0 Mar 29 17:40:06 volumio4 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 17:40:06 volumio4 volumio[1320]: info: CoreCommandRouter::volumioPushState Mar 29 17:40:06 volumio4 volumio[1320]: info: CoreStateMachine::pushState Mar 29 17:40:06 volumio4 volumio[1320]: info: CoreCommandRouter::volumioPushState Mar 29 17:40:06 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:06.164+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" state=STATUS_PLAYING positionMs=251 volume= Mar 29 17:40:06 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:06.164+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Mar 29 17:40:06 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:06.164+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" state=STATUS_PLAYING positionMs=251 volume= Mar 29 17:40:06 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:06.164+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Mar 29 17:40:06 volumio4 volumio[1320]: info: ------------------------------ 4ms Mar 29 17:40:06 volumio4 volumio[1320]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 29 17:40:06 volumio4 volumio[1320]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 29 17:40:06 volumio4 volumio[1320]: verbose: ControllerMpd::parseTrackInfo Mar 29 17:40:06 volumio4 volumio[1320]: verbose: ControllerMpd::parseTrackInfo Mar 29 17:40:06 volumio4 volumio[1320]: info: ControllerMpd::pushState Mar 29 17:40:06 volumio4 volumio[1320]: info: CoreCommandRouter::servicePushState Mar 29 17:40:06 volumio4 volumio[1320]: info: CorePlayQueue::getTrack 0 Mar 29 17:40:06 volumio4 volumio[1320]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flac.flac","artist":"Naim Radio","album":null,"uri":"http://mscp3.live-streams.nl:8360/flac.flac","trackType":"flac"} Mar 29 17:40:06 volumio4 volumio[1320]: verbose: CURRENT POSITION 0 Mar 29 17:40:06 volumio4 volumio[1320]: info: CoreStateMachine::syncState stateService play Mar 29 17:40:06 volumio4 volumio[1320]: info: CoreStateMachine::syncState currentStatus play Mar 29 17:40:06 volumio4 volumio[1320]: info: Received an update from plugin. extracting info from payload Mar 29 17:40:06 volumio4 volumio[1320]: info: CoreStateMachine::pushState Mar 29 17:40:06 volumio4 volumio[1320]: info: CorePlayQueue::getTrack 0 Mar 29 17:40:06 volumio4 volumio[1320]: info: CoreCommandRouter::volumioPushState Mar 29 17:40:06 volumio4 volumio[1320]: info: CoreStateMachine::pushState Mar 29 17:40:06 volumio4 volumio[1320]: info: CoreCommandRouter::volumioPushState Mar 29 17:40:06 volumio4 volumio[1320]: info: ControllerMpd::pushState Mar 29 17:40:06 volumio4 volumio[1320]: info: CoreCommandRouter::servicePushState Mar 29 17:40:06 volumio4 volumio[1320]: info: CorePlayQueue::getTrack 0 Mar 29 17:40:06 volumio4 volumio[1320]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flac.flac","artist":"Naim Radio","album":null,"uri":"http://mscp3.live-streams.nl:8360/flac.flac","trackType":"flac"} Mar 29 17:40:06 volumio4 volumio[1320]: verbose: CURRENT POSITION 0 Mar 29 17:40:06 volumio4 volumio[1320]: info: CoreStateMachine::syncState stateService play Mar 29 17:40:06 volumio4 volumio[1320]: info: CoreStateMachine::syncState currentStatus play Mar 29 17:40:06 volumio4 volumio[1320]: info: Received an update from plugin. extracting info from payload Mar 29 17:40:06 volumio4 volumio[1320]: info: CoreStateMachine::pushState Mar 29 17:40:06 volumio4 volumio[1320]: info: CorePlayQueue::getTrack 0 Mar 29 17:40:06 volumio4 volumio[1320]: info: CoreCommandRouter::volumioPushState Mar 29 17:40:06 volumio4 volumio[1320]: info: CoreStateMachine::pushState Mar 29 17:40:06 volumio4 volumio[1320]: info: CoreCommandRouter::volumioPushState Mar 29 17:40:06 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:06.170+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" state=STATUS_PLAYING positionMs=251 volume= Mar 29 17:40:06 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:06.170+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Mar 29 17:40:06 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:06.174+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" state=STATUS_PLAYING positionMs=251 volume= Mar 29 17:40:06 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:06.174+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" state=STATUS_PLAYING positionMs=251 volume= Mar 29 17:40:06 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:06.174+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Mar 29 17:40:06 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:06.174+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Mar 29 17:40:06 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:06.175+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" state=STATUS_PLAYING positionMs=251 volume= Mar 29 17:40:06 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:06.175+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Mar 29 17:40:06 volumio4 volumio[1320]: info: ------------------------------ 16ms Mar 29 17:40:06 volumio4 volumio[1320]: info: ------------------------------ 16ms Mar 29 17:40:13 volumio4 volumio[1320]: info: Preload queue cleared Mar 29 17:40:13 volumio4 volumio[1320]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 29 17:40:13 volumio4 volumio[1320]: info: CoreStateMachine::ClearQueue Mar 29 17:40:13 volumio4 volumio[1320]: info: CoreStateMachine::stop Mar 29 17:40:13 volumio4 volumio[1320]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 17:40:13 volumio4 volumio[1320]: info: CoreStateMachine::stPlaybackTimer Mar 29 17:40:13 volumio4 volumio[1320]: info: CoreStateMachine::updateTrackBlock Mar 29 17:40:13 volumio4 volumio[1320]: info: CorePlayQueue::getTrackBlock Mar 29 17:40:13 volumio4 volumio[1320]: info: CoreStateMachine::pushState Mar 29 17:40:13 volumio4 volumio[1320]: info: CorePlayQueue::getTrack 0 Mar 29 17:40:13 volumio4 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 17:40:13 volumio4 volumio[1320]: info: CoreCommandRouter::volumioPushState Mar 29 17:40:13 volumio4 volumio[1320]: info: CoreStateMachine::serviceStop Mar 29 17:40:13 volumio4 volumio[1320]: info: CorePlayQueue::getTrack 0 Mar 29 17:40:13 volumio4 volumio[1320]: info: CoreCommandRouter::serviceStop Mar 29 17:40:13 volumio4 volumio[1320]: info: [1774788013750] ControllerWebradio::stop Mar 29 17:40:13 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 17:40:13 volumio4 volumio[1320]: info: CorePlayQueue::clearPlayQueue Mar 29 17:40:13 volumio4 volumio[1320]: info: CorePlayQueue::saveQueue Mar 29 17:40:13 volumio4 volumio[1320]: info: CoreCommandRouter::volumioPushQueue Mar 29 17:40:13 volumio4 volumio[1320]: info: CoreStateMachine::addQueueItems Mar 29 17:40:13 volumio4 volumio[1320]: info: CorePlayQueue::addQueueItems Mar 29 17:40:13 volumio4 volumio[1320]: info: Preload queue cleared Mar 29 17:40:13 volumio4 volumio[1320]: info: Adding Item to queue: http://mscp3.live-streams.nl:8250/class-flac.flac Mar 29 17:40:13 volumio4 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Mar 29 17:40:13 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:13.751+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" state=STATUS_STOPPED positionMs=0 volume= Mar 29 17:40:13 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:13.751+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Mar 29 17:40:13 volumio4 volumio[1320]: info: CoreCommandRouter::volumioPushQueue Mar 29 17:40:13 volumio4 volumio[1320]: info: CorePlayQueue::saveQueue Mar 29 17:40:13 volumio4 volumio[1320]: info: CoreStateMachine::updateTrackBlock Mar 29 17:40:13 volumio4 volumio[1320]: info: CorePlayQueue::getTrackBlock Mar 29 17:40:13 volumio4 volumio[1320]: info: CoreCommandRouter::volumioPlay Mar 29 17:40:13 volumio4 volumio[1320]: info: CoreStateMachine::play index 0 Mar 29 17:40:13 volumio4 volumio[1320]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 17:40:13 volumio4 volumio[1320]: info: CoreStateMachine::stop Mar 29 17:40:13 volumio4 volumio[1320]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 17:40:13 volumio4 volumio[1320]: info: CoreStateMachine::play index undefined Mar 29 17:40:13 volumio4 volumio[1320]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 17:40:13 volumio4 volumio[1320]: info: CorePlayQueue::getTrack 0 Mar 29 17:40:13 volumio4 volumio[1320]: info: CoreStateMachine::startPlaybackTimer Mar 29 17:40:13 volumio4 volumio[1320]: info: CorePlayQueue::getTrack 0 Mar 29 17:40:13 volumio4 volumio[1320]: info: [1774788013753] ControllerWebradio::clearAddPlayTrack Mar 29 17:40:13 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 17:40:13 volumio4 volumio[1320]: info: Mar 29 17:40:13 volumio4 volumio[1320]: ---------------------------- MPD announces state update: player Mar 29 17:40:13 volumio4 volumio[1320]: info: sendMpdCommand stop took 5 milliseconds Mar 29 17:40:13 volumio4 volumio[1320]: info: ControllerMpd::getState Mar 29 17:40:13 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand status Mar 29 17:40:13 volumio4 volumio[1320]: info: sendMpdCommand stop took 2 milliseconds Mar 29 17:40:13 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand clear Mar 29 17:40:13 volumio4 volumio[1320]: info: Mar 29 17:40:13 volumio4 volumio[1320]: ---------------------------- MPD announces state update: player Mar 29 17:40:13 volumio4 volumio[1320]: info: ControllerMpd::getState Mar 29 17:40:13 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand status Mar 29 17:40:13 volumio4 volumio[1320]: info: Mar 29 17:40:13 volumio4 volumio[1320]: ---------------------------- MPD announces state update: player Mar 29 17:40:13 volumio4 volumio[1320]: info: ControllerMpd::getState Mar 29 17:40:13 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand status Mar 29 17:40:13 volumio4 volumio[1320]: info: Mar 29 17:40:13 volumio4 volumio[1320]: ---------------------------- MPD announces system playlist update Mar 29 17:40:13 volumio4 volumio[1320]: info: Ignoring MPD Status Update Mar 29 17:40:13 volumio4 volumio[1320]: info: sendMpdCommand status took 1 milliseconds Mar 29 17:40:13 volumio4 volumio[1320]: info: sendMpdCommand clear took 0 milliseconds Mar 29 17:40:13 volumio4 volumio[1320]: info: sendMpdCommand status took 0 milliseconds Mar 29 17:40:13 volumio4 volumio[1320]: info: sendMpdCommand status took 0 milliseconds Mar 29 17:40:13 volumio4 volumio[1320]: verbose: ControllerMpd::parseState Mar 29 17:40:13 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 17:40:13 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand load "http://mscp3.live-streams.nl:8250/class-flac.flac" Mar 29 17:40:13 volumio4 volumio[1320]: verbose: ControllerMpd::parseState Mar 29 17:40:13 volumio4 volumio[1320]: verbose: ControllerMpd::parseState Mar 29 17:40:13 volumio4 volumio[1320]: info: ControllerMpd::pushState Mar 29 17:40:13 volumio4 volumio[1320]: info: CoreCommandRouter::servicePushState Mar 29 17:40:13 volumio4 volumio[1320]: info: CoreStateMachine::pushState Mar 29 17:40:13 volumio4 volumio[1320]: info: CorePlayQueue::getTrack 0 Mar 29 17:40:13 volumio4 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 17:40:13 volumio4 volumio[1320]: info: CoreCommandRouter::volumioPushState Mar 29 17:40:13 volumio4 volumio[1320]: info: CorePlayQueue::getTrack 0 Mar 29 17:40:13 volumio4 volumio[1320]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Mar 29 17:40:13 volumio4 volumio[1320]: info: ControllerMpd::pushState Mar 29 17:40:13 volumio4 volumio[1320]: info: CoreCommandRouter::servicePushState Mar 29 17:40:13 volumio4 volumio[1320]: info: CoreStateMachine::pushState Mar 29 17:40:13 volumio4 volumio[1320]: info: CorePlayQueue::getTrack 0 Mar 29 17:40:13 volumio4 volumio[1320]: info: CoreCommandRouter::volumioPushState Mar 29 17:40:13 volumio4 volumio[1320]: info: CorePlayQueue::getTrack 0 Mar 29 17:40:13 volumio4 volumio[1320]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Mar 29 17:40:13 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:13.759+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" state=STATUS_STOPPED positionMs=0 volume= Mar 29 17:40:13 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:13.759+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" id=http://mscp3.live-streams.nl:8250/class-flac.flac title="Naim Classical (FLAC)" Mar 29 17:40:13 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:13.759+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" state=STATUS_STOPPED positionMs=0 volume= Mar 29 17:40:13 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:13.759+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" id=http://mscp3.live-streams.nl:8250/class-flac.flac title="Naim Classical (FLAC)" Mar 29 17:40:13 volumio4 volumio[1320]: info: ------------------------------ 4ms Mar 29 17:40:13 volumio4 volumio[1320]: info: ------------------------------ 4ms Mar 29 17:40:13 volumio4 volumio[1320]: info: Mar 29 17:40:13 volumio4 volumio[1320]: ---------------------------- MPD announces system playlist update Mar 29 17:40:13 volumio4 volumio[1320]: info: Ignoring MPD Status Update Mar 29 17:40:13 volumio4 volumio[1320]: info: Mar 29 17:40:13 volumio4 volumio[1320]: ---------------------------- MPD announces system playlist update Mar 29 17:40:13 volumio4 volumio[1320]: info: Ignoring MPD Status Update Mar 29 17:40:13 volumio4 volumio[1320]: error: updateQueue error: null Mar 29 17:40:13 volumio4 volumio[1320]: info: ------------------------------ 5ms Mar 29 17:40:13 volumio4 volumio[1320]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 29 17:40:13 volumio4 volumio[1320]: verbose: ControllerMpd::parseTrackInfo Mar 29 17:40:13 volumio4 volumio[1320]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Mar 29 17:40:13 volumio4 volumio[1320]: info: ------------------------------ 6ms Mar 29 17:40:14 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand add "http://mscp3.live-streams.nl:8250/class-flac.flac" Mar 29 17:40:14 volumio4 volumio[1320]: error: updateQueue error: null Mar 29 17:40:14 volumio4 volumio[1320]: error: updateQueue error: null Mar 29 17:40:14 volumio4 volumio[1320]: info: ------------------------------ 655ms Mar 29 17:40:14 volumio4 volumio[1320]: info: ------------------------------ 654ms Mar 29 17:40:14 volumio4 volumio[1320]: info: Mar 29 17:40:14 volumio4 volumio[1320]: ---------------------------- MPD announces system playlist update Mar 29 17:40:14 volumio4 volumio[1320]: info: Ignoring MPD Status Update Mar 29 17:40:14 volumio4 volumio[1320]: info: sendMpdCommand add "http://mscp3.live-streams.nl:8250/class-flac.flac" took 1 milliseconds Mar 29 17:40:14 volumio4 volumio[1320]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 29 17:40:14 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand play Mar 29 17:40:14 volumio4 volumio[1320]: info: Mar 29 17:40:14 volumio4 volumio[1320]: ---------------------------- MPD announces system playlist update Mar 29 17:40:14 volumio4 volumio[1320]: info: Ignoring MPD Status Update Mar 29 17:40:14 volumio4 volumio[1320]: info: Mar 29 17:40:14 volumio4 volumio[1320]: ---------------------------- MPD announces system playlist update Mar 29 17:40:14 volumio4 volumio[1320]: info: Ignoring MPD Status Update Mar 29 17:40:14 volumio4 volumio[1320]: info: ------------------------------ 1ms Mar 29 17:40:14 volumio4 volumio[1320]: info: sendMpdCommand play took 0 milliseconds Mar 29 17:40:14 volumio4 volumio[1320]: info: ------------------------------ 0ms Mar 29 17:40:14 volumio4 volumio[1320]: info: ------------------------------ 0ms Mar 29 17:40:15 volumio4 volumio[1320]: info: Mar 29 17:40:15 volumio4 volumio[1320]: ---------------------------- MPD announces state update: player Mar 29 17:40:15 volumio4 volumio[1320]: info: ControllerMpd::getState Mar 29 17:40:15 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand status Mar 29 17:40:15 volumio4 volumio[1320]: info: Mar 29 17:40:15 volumio4 volumio[1320]: ---------------------------- MPD announces state update: player Mar 29 17:40:15 volumio4 volumio[1320]: info: ControllerMpd::getState Mar 29 17:40:15 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand status Mar 29 17:40:15 volumio4 volumio[1320]: info: Mar 29 17:40:15 volumio4 volumio[1320]: ---------------------------- MPD announces state update: player Mar 29 17:40:15 volumio4 volumio[1320]: info: ControllerMpd::getState Mar 29 17:40:15 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand status Mar 29 17:40:15 volumio4 volumio[1320]: info: Mar 29 17:40:15 volumio4 volumio[1320]: ---------------------------- MPD announces state update: player Mar 29 17:40:15 volumio4 volumio[1320]: info: sendMpdCommand status took 1 milliseconds Mar 29 17:40:15 volumio4 volumio[1320]: info: ControllerMpd::getState Mar 29 17:40:15 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand status Mar 29 17:40:15 volumio4 volumio[1320]: verbose: ControllerMpd::parseState Mar 29 17:40:15 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 17:40:15 volumio4 volumio[1320]: info: Mar 29 17:40:15 volumio4 volumio[1320]: ---------------------------- MPD announces state update: player Mar 29 17:40:15 volumio4 volumio[1320]: info: ControllerMpd::getState Mar 29 17:40:15 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand status Mar 29 17:40:15 volumio4 volumio[1320]: info: sendMpdCommand status took 1 milliseconds Mar 29 17:40:15 volumio4 volumio[1320]: info: sendMpdCommand status took 2 milliseconds Mar 29 17:40:15 volumio4 volumio[1320]: info: sendMpdCommand status took 1 milliseconds Mar 29 17:40:15 volumio4 volumio[1320]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 29 17:40:15 volumio4 volumio[1320]: verbose: ControllerMpd::parseState Mar 29 17:40:15 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 17:40:15 volumio4 volumio[1320]: verbose: ControllerMpd::parseState Mar 29 17:40:15 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 17:40:15 volumio4 volumio[1320]: verbose: ControllerMpd::parseState Mar 29 17:40:15 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 17:40:15 volumio4 volumio[1320]: verbose: ControllerMpd::parseTrackInfo Mar 29 17:40:15 volumio4 volumio[1320]: info: ControllerMpd::pushState Mar 29 17:40:15 volumio4 volumio[1320]: info: CoreCommandRouter::servicePushState Mar 29 17:40:15 volumio4 volumio[1320]: info: CorePlayQueue::getTrack 0 Mar 29 17:40:15 volumio4 volumio[1320]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"class-flac.flac","artist":null,"album":null,"uri":"http://mscp3.live-streams.nl:8250/class-flac.flac","trackType":"flac"} Mar 29 17:40:15 volumio4 volumio[1320]: verbose: CURRENT POSITION 0 Mar 29 17:40:15 volumio4 volumio[1320]: info: CoreStateMachine::syncState stateService play Mar 29 17:40:15 volumio4 volumio[1320]: info: CoreStateMachine::syncState currentStatus stop Mar 29 17:40:15 volumio4 volumio[1320]: info: ------------------------------ 2ms Mar 29 17:40:15 volumio4 volumio[1320]: info: Mar 29 17:40:15 volumio4 volumio[1320]: ---------------------------- MPD announces state update: player Mar 29 17:40:15 volumio4 volumio[1320]: info: ControllerMpd::getState Mar 29 17:40:15 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand status Mar 29 17:40:15 volumio4 volumio[1320]: info: sendMpdCommand status took 1 milliseconds Mar 29 17:40:15 volumio4 volumio[1320]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 29 17:40:15 volumio4 volumio[1320]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 29 17:40:15 volumio4 volumio[1320]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 29 17:40:15 volumio4 volumio[1320]: verbose: ControllerMpd::parseState Mar 29 17:40:15 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 17:40:15 volumio4 volumio[1320]: verbose: ControllerMpd::parseTrackInfo Mar 29 17:40:15 volumio4 volumio[1320]: verbose: ControllerMpd::parseTrackInfo Mar 29 17:40:15 volumio4 volumio[1320]: verbose: ControllerMpd::parseTrackInfo Mar 29 17:40:15 volumio4 volumio[1320]: info: ControllerMpd::pushState Mar 29 17:40:15 volumio4 volumio[1320]: info: CoreCommandRouter::servicePushState Mar 29 17:40:15 volumio4 volumio[1320]: info: CorePlayQueue::getTrack 0 Mar 29 17:40:15 volumio4 volumio[1320]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"class-flac.flac","artist":null,"album":null,"uri":"http://mscp3.live-streams.nl:8250/class-flac.flac","trackType":"flac"} Mar 29 17:40:15 volumio4 volumio[1320]: verbose: CURRENT POSITION 0 Mar 29 17:40:15 volumio4 volumio[1320]: info: CoreStateMachine::syncState stateService play Mar 29 17:40:15 volumio4 volumio[1320]: info: CoreStateMachine::syncState currentStatus play Mar 29 17:40:15 volumio4 volumio[1320]: info: Received an update from plugin. extracting info from payload Mar 29 17:40:15 volumio4 volumio[1320]: info: CoreStateMachine::pushState Mar 29 17:40:15 volumio4 volumio[1320]: info: CorePlayQueue::getTrack 0 Mar 29 17:40:15 volumio4 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 17:40:15 volumio4 volumio[1320]: info: CoreCommandRouter::volumioPushState Mar 29 17:40:15 volumio4 volumio[1320]: info: CoreStateMachine::pushState Mar 29 17:40:15 volumio4 volumio[1320]: info: CoreCommandRouter::volumioPushState Mar 29 17:40:15 volumio4 volumio[1320]: info: ControllerMpd::pushState Mar 29 17:40:15 volumio4 volumio[1320]: info: CoreCommandRouter::servicePushState Mar 29 17:40:15 volumio4 volumio[1320]: info: CorePlayQueue::getTrack 0 Mar 29 17:40:15 volumio4 volumio[1320]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"class-flac.flac","artist":null,"album":null,"uri":"http://mscp3.live-streams.nl:8250/class-flac.flac","trackType":"flac"} Mar 29 17:40:15 volumio4 volumio[1320]: verbose: CURRENT POSITION 0 Mar 29 17:40:15 volumio4 volumio[1320]: info: CoreStateMachine::syncState stateService play Mar 29 17:40:15 volumio4 volumio[1320]: info: CoreStateMachine::syncState currentStatus play Mar 29 17:40:15 volumio4 volumio[1320]: info: Received an update from plugin. extracting info from payload Mar 29 17:40:15 volumio4 volumio[1320]: info: CoreStateMachine::pushState Mar 29 17:40:15 volumio4 volumio[1320]: info: CorePlayQueue::getTrack 0 Mar 29 17:40:15 volumio4 volumio[1320]: info: CoreCommandRouter::volumioPushState Mar 29 17:40:15 volumio4 volumio[1320]: info: CoreStateMachine::pushState Mar 29 17:40:15 volumio4 volumio[1320]: info: CoreCommandRouter::volumioPushState Mar 29 17:40:15 volumio4 volumio[1320]: info: ControllerMpd::pushState Mar 29 17:40:15 volumio4 volumio[1320]: info: CoreCommandRouter::servicePushState Mar 29 17:40:15 volumio4 volumio[1320]: info: CorePlayQueue::getTrack 0 Mar 29 17:40:15 volumio4 volumio[1320]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"class-flac.flac","artist":null,"album":null,"uri":"http://mscp3.live-streams.nl:8250/class-flac.flac","trackType":"flac"} Mar 29 17:40:15 volumio4 volumio[1320]: verbose: CURRENT POSITION 0 Mar 29 17:40:15 volumio4 volumio[1320]: info: CoreStateMachine::syncState stateService play Mar 29 17:40:15 volumio4 volumio[1320]: info: CoreStateMachine::syncState currentStatus play Mar 29 17:40:15 volumio4 volumio[1320]: info: Received an update from plugin. extracting info from payload Mar 29 17:40:15 volumio4 volumio[1320]: info: CoreStateMachine::pushState Mar 29 17:40:15 volumio4 volumio[1320]: info: CorePlayQueue::getTrack 0 Mar 29 17:40:15 volumio4 volumio[1320]: info: CoreCommandRouter::volumioPushState Mar 29 17:40:15 volumio4 volumio[1320]: info: CoreStateMachine::pushState Mar 29 17:40:15 volumio4 volumio[1320]: info: CoreCommandRouter::volumioPushState Mar 29 17:40:15 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:15.077+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" state=STATUS_PLAYING positionMs=0 volume= Mar 29 17:40:15 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:15.078+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" state=STATUS_PLAYING positionMs=0 volume= Mar 29 17:40:15 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:15.078+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" id=http://mscp3.live-streams.nl:8250/class-flac.flac title="Naim Classical (FLAC)" Mar 29 17:40:15 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:15.078+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" id=http://mscp3.live-streams.nl:8250/class-flac.flac title="Naim Classical (FLAC)" Mar 29 17:40:15 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:15.078+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" state=STATUS_PLAYING positionMs=0 volume= Mar 29 17:40:15 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:15.078+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" id=http://mscp3.live-streams.nl:8250/class-flac.flac title="Naim Classical (FLAC)" Mar 29 17:40:15 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:15.078+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" state=STATUS_PLAYING positionMs=0 volume= Mar 29 17:40:15 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:15.078+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" id=http://mscp3.live-streams.nl:8250/class-flac.flac title="Naim Classical (FLAC)" Mar 29 17:40:15 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:15.078+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" state=STATUS_PLAYING positionMs=0 volume= Mar 29 17:40:15 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:15.078+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" id=http://mscp3.live-streams.nl:8250/class-flac.flac title="Naim Classical (FLAC)" Mar 29 17:40:15 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:15.078+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" state=STATUS_PLAYING positionMs=0 volume= Mar 29 17:40:15 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:15.078+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" id=http://mscp3.live-streams.nl:8250/class-flac.flac title="Naim Classical (FLAC)" Mar 29 17:40:15 volumio4 volumio[1320]: info: ------------------------------ 11ms Mar 29 17:40:15 volumio4 volumio[1320]: info: ------------------------------ 11ms Mar 29 17:40:15 volumio4 volumio[1320]: info: ------------------------------ 10ms Mar 29 17:40:15 volumio4 volumio[1320]: info: sendMpdCommand status took 10 milliseconds Mar 29 17:40:15 volumio4 volumio[1320]: info: sendMpdCommand playlistinfo took 10 milliseconds Mar 29 17:40:15 volumio4 volumio[1320]: verbose: ControllerMpd::parseState Mar 29 17:40:15 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 17:40:15 volumio4 volumio[1320]: verbose: ControllerMpd::parseTrackInfo Mar 29 17:40:15 volumio4 volumio[1320]: info: ControllerMpd::pushState Mar 29 17:40:15 volumio4 volumio[1320]: info: CoreCommandRouter::servicePushState Mar 29 17:40:15 volumio4 volumio[1320]: info: CorePlayQueue::getTrack 0 Mar 29 17:40:15 volumio4 volumio[1320]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"class-flac.flac","artist":null,"album":null,"uri":"http://mscp3.live-streams.nl:8250/class-flac.flac","trackType":"flac"} Mar 29 17:40:15 volumio4 volumio[1320]: verbose: CURRENT POSITION 0 Mar 29 17:40:15 volumio4 volumio[1320]: info: CoreStateMachine::syncState stateService play Mar 29 17:40:15 volumio4 volumio[1320]: info: CoreStateMachine::syncState currentStatus play Mar 29 17:40:15 volumio4 volumio[1320]: info: Received an update from plugin. extracting info from payload Mar 29 17:40:15 volumio4 volumio[1320]: info: CoreStateMachine::pushState Mar 29 17:40:15 volumio4 volumio[1320]: info: CorePlayQueue::getTrack 0 Mar 29 17:40:15 volumio4 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 17:40:15 volumio4 volumio[1320]: info: CoreCommandRouter::volumioPushState Mar 29 17:40:15 volumio4 volumio[1320]: info: CoreStateMachine::pushState Mar 29 17:40:15 volumio4 volumio[1320]: info: CoreCommandRouter::volumioPushState Mar 29 17:40:15 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:15.086+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" state=STATUS_PLAYING positionMs=14 volume= Mar 29 17:40:15 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:15.086+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" state=STATUS_PLAYING positionMs=14 volume= Mar 29 17:40:15 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:15.087+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" id=http://mscp3.live-streams.nl:8250/class-flac.flac title="Naim Classical (FLAC)" Mar 29 17:40:15 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:15.087+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" id=http://mscp3.live-streams.nl:8250/class-flac.flac title="Naim Classical (FLAC)" Mar 29 17:40:15 volumio4 volumio[1320]: info: ------------------------------ 14ms Mar 29 17:40:15 volumio4 volumio[1320]: info: sendMpdCommand playlistinfo took 7 milliseconds Mar 29 17:40:15 volumio4 volumio[1320]: verbose: ControllerMpd::parseTrackInfo Mar 29 17:40:15 volumio4 volumio[1320]: info: ControllerMpd::pushState Mar 29 17:40:15 volumio4 volumio[1320]: info: CoreCommandRouter::servicePushState Mar 29 17:40:15 volumio4 volumio[1320]: info: CorePlayQueue::getTrack 0 Mar 29 17:40:15 volumio4 volumio[1320]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"class-flac.flac","artist":null,"album":null,"uri":"http://mscp3.live-streams.nl:8250/class-flac.flac","trackType":"flac"} Mar 29 17:40:15 volumio4 volumio[1320]: verbose: CURRENT POSITION 0 Mar 29 17:40:15 volumio4 volumio[1320]: info: CoreStateMachine::syncState stateService play Mar 29 17:40:15 volumio4 volumio[1320]: info: CoreStateMachine::syncState currentStatus play Mar 29 17:40:15 volumio4 volumio[1320]: info: Received an update from plugin. extracting info from payload Mar 29 17:40:15 volumio4 volumio[1320]: info: CoreStateMachine::pushState Mar 29 17:40:15 volumio4 volumio[1320]: info: CorePlayQueue::getTrack 0 Mar 29 17:40:15 volumio4 volumio[1320]: info: CoreCommandRouter::volumioPushState Mar 29 17:40:15 volumio4 volumio[1320]: info: CoreStateMachine::pushState Mar 29 17:40:15 volumio4 volumio[1320]: info: CoreCommandRouter::volumioPushState Mar 29 17:40:15 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:15.092+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" state=STATUS_PLAYING positionMs=14 volume= Mar 29 17:40:15 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:15.092+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" id=http://mscp3.live-streams.nl:8250/class-flac.flac title="Naim Classical (FLAC)" Mar 29 17:40:15 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:15.092+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" state=STATUS_PLAYING positionMs=14 volume= Mar 29 17:40:15 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:15.093+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" id=http://mscp3.live-streams.nl:8250/class-flac.flac title="Naim Classical (FLAC)" Mar 29 17:40:15 volumio4 volumio[1320]: info: ------------------------------ 19ms Mar 29 17:40:15 volumio4 volumio[1320]: info: Mar 29 17:40:15 volumio4 volumio[1320]: ---------------------------- MPD announces system playlist update Mar 29 17:40:15 volumio4 volumio[1320]: info: Ignoring MPD Status Update Mar 29 17:40:15 volumio4 volumio[1320]: info: Mar 29 17:40:15 volumio4 volumio[1320]: ---------------------------- MPD announces state update: player Mar 29 17:40:15 volumio4 volumio[1320]: info: ControllerMpd::getState Mar 29 17:40:15 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand status Mar 29 17:40:15 volumio4 volumio[1320]: info: Mar 29 17:40:15 volumio4 volumio[1320]: ---------------------------- MPD announces system playlist update Mar 29 17:40:15 volumio4 volumio[1320]: info: Ignoring MPD Status Update Mar 29 17:40:15 volumio4 volumio[1320]: info: Mar 29 17:40:15 volumio4 volumio[1320]: ---------------------------- MPD announces state update: player Mar 29 17:40:15 volumio4 volumio[1320]: info: ControllerMpd::getState Mar 29 17:40:15 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand status Mar 29 17:40:15 volumio4 volumio[1320]: info: Mar 29 17:40:15 volumio4 volumio[1320]: ---------------------------- MPD announces system playlist update Mar 29 17:40:15 volumio4 volumio[1320]: info: Ignoring MPD Status Update Mar 29 17:40:15 volumio4 volumio[1320]: info: Mar 29 17:40:15 volumio4 volumio[1320]: ---------------------------- MPD announces state update: player Mar 29 17:40:15 volumio4 volumio[1320]: info: ControllerMpd::getState Mar 29 17:40:15 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand status Mar 29 17:40:15 volumio4 volumio[1320]: info: ------------------------------ 1ms Mar 29 17:40:15 volumio4 volumio[1320]: info: sendMpdCommand status took 1 milliseconds Mar 29 17:40:15 volumio4 volumio[1320]: info: ------------------------------ 1ms Mar 29 17:40:15 volumio4 volumio[1320]: verbose: ControllerMpd::parseState Mar 29 17:40:15 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 17:40:15 volumio4 volumio[1320]: info: sendMpdCommand status took 0 milliseconds Mar 29 17:40:15 volumio4 volumio[1320]: info: ------------------------------ 0ms Mar 29 17:40:15 volumio4 volumio[1320]: info: sendMpdCommand status took 0 milliseconds Mar 29 17:40:15 volumio4 volumio[1320]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 29 17:40:15 volumio4 volumio[1320]: verbose: ControllerMpd::parseState Mar 29 17:40:15 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 17:40:15 volumio4 volumio[1320]: verbose: ControllerMpd::parseState Mar 29 17:40:15 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 17:40:15 volumio4 volumio[1320]: verbose: ControllerMpd::parseTrackInfo Mar 29 17:40:15 volumio4 volumio[1320]: info: ControllerMpd::pushState Mar 29 17:40:15 volumio4 volumio[1320]: info: CoreCommandRouter::servicePushState Mar 29 17:40:15 volumio4 volumio[1320]: info: CorePlayQueue::getTrack 0 Mar 29 17:40:15 volumio4 volumio[1320]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"class-flac.flac","artist":"Naim Classical","album":null,"uri":"http://mscp3.live-streams.nl:8250/class-flac.flac","trackType":"flac"} Mar 29 17:40:15 volumio4 volumio[1320]: verbose: CURRENT POSITION 0 Mar 29 17:40:15 volumio4 volumio[1320]: info: CoreStateMachine::syncState stateService play Mar 29 17:40:15 volumio4 volumio[1320]: info: CoreStateMachine::syncState currentStatus play Mar 29 17:40:15 volumio4 volumio[1320]: info: Received an update from plugin. extracting info from payload Mar 29 17:40:15 volumio4 volumio[1320]: info: CoreStateMachine::pushState Mar 29 17:40:15 volumio4 volumio[1320]: info: CorePlayQueue::getTrack 0 Mar 29 17:40:15 volumio4 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 17:40:15 volumio4 volumio[1320]: info: CoreCommandRouter::volumioPushState Mar 29 17:40:15 volumio4 volumio[1320]: info: CoreStateMachine::pushState Mar 29 17:40:15 volumio4 volumio[1320]: info: CoreCommandRouter::volumioPushState Mar 29 17:40:15 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:15.210+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" state=STATUS_PLAYING positionMs=14 volume= Mar 29 17:40:15 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:15.210+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" id=http://mscp3.live-streams.nl:8250/class-flac.flac title="Naim Classical (FLAC)" Mar 29 17:40:15 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:15.210+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" state=STATUS_PLAYING positionMs=14 volume= Mar 29 17:40:15 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:15.210+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" id=http://mscp3.live-streams.nl:8250/class-flac.flac title="Naim Classical (FLAC)" Mar 29 17:40:15 volumio4 volumio[1320]: info: ------------------------------ 5ms Mar 29 17:40:15 volumio4 volumio[1320]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 29 17:40:15 volumio4 volumio[1320]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 29 17:40:15 volumio4 volumio[1320]: verbose: ControllerMpd::parseTrackInfo Mar 29 17:40:15 volumio4 volumio[1320]: verbose: ControllerMpd::parseTrackInfo Mar 29 17:40:15 volumio4 volumio[1320]: info: ControllerMpd::pushState Mar 29 17:40:15 volumio4 volumio[1320]: info: CoreCommandRouter::servicePushState Mar 29 17:40:15 volumio4 volumio[1320]: info: CorePlayQueue::getTrack 0 Mar 29 17:40:15 volumio4 volumio[1320]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"class-flac.flac","artist":"Naim Classical","album":null,"uri":"http://mscp3.live-streams.nl:8250/class-flac.flac","trackType":"flac"} Mar 29 17:40:15 volumio4 volumio[1320]: verbose: CURRENT POSITION 0 Mar 29 17:40:15 volumio4 volumio[1320]: info: CoreStateMachine::syncState stateService play Mar 29 17:40:15 volumio4 volumio[1320]: info: CoreStateMachine::syncState currentStatus play Mar 29 17:40:15 volumio4 volumio[1320]: info: Received an update from plugin. extracting info from payload Mar 29 17:40:15 volumio4 volumio[1320]: info: CoreStateMachine::pushState Mar 29 17:40:15 volumio4 volumio[1320]: info: CorePlayQueue::getTrack 0 Mar 29 17:40:15 volumio4 volumio[1320]: info: CoreCommandRouter::volumioPushState Mar 29 17:40:15 volumio4 volumio[1320]: info: CoreStateMachine::pushState Mar 29 17:40:15 volumio4 volumio[1320]: info: CoreCommandRouter::volumioPushState Mar 29 17:40:15 volumio4 volumio[1320]: info: ControllerMpd::pushState Mar 29 17:40:15 volumio4 volumio[1320]: info: CoreCommandRouter::servicePushState Mar 29 17:40:15 volumio4 volumio[1320]: info: CorePlayQueue::getTrack 0 Mar 29 17:40:15 volumio4 volumio[1320]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"class-flac.flac","artist":"Naim Classical","album":null,"uri":"http://mscp3.live-streams.nl:8250/class-flac.flac","trackType":"flac"} Mar 29 17:40:15 volumio4 volumio[1320]: verbose: CURRENT POSITION 0 Mar 29 17:40:15 volumio4 volumio[1320]: info: CoreStateMachine::syncState stateService play Mar 29 17:40:15 volumio4 volumio[1320]: info: CoreStateMachine::syncState currentStatus play Mar 29 17:40:15 volumio4 volumio[1320]: info: Received an update from plugin. extracting info from payload Mar 29 17:40:15 volumio4 volumio[1320]: info: CoreStateMachine::pushState Mar 29 17:40:15 volumio4 volumio[1320]: info: CorePlayQueue::getTrack 0 Mar 29 17:40:15 volumio4 volumio[1320]: info: CoreCommandRouter::volumioPushState Mar 29 17:40:15 volumio4 volumio[1320]: info: CoreStateMachine::pushState Mar 29 17:40:15 volumio4 volumio[1320]: info: CoreCommandRouter::volumioPushState Mar 29 17:40:15 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:15.215+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" state=STATUS_PLAYING positionMs=14 volume= Mar 29 17:40:15 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:15.215+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" id=http://mscp3.live-streams.nl:8250/class-flac.flac title="Naim Classical (FLAC)" Mar 29 17:40:15 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:15.215+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" state=STATUS_PLAYING positionMs=14 volume= Mar 29 17:40:15 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:15.215+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" state=STATUS_PLAYING positionMs=14 volume= Mar 29 17:40:15 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:15.216+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" id=http://mscp3.live-streams.nl:8250/class-flac.flac title="Naim Classical (FLAC)" Mar 29 17:40:15 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:15.216+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" state=STATUS_PLAYING positionMs=14 volume= Mar 29 17:40:15 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:15.216+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" id=http://mscp3.live-streams.nl:8250/class-flac.flac title="Naim Classical (FLAC)" Mar 29 17:40:15 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:15.216+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" id=http://mscp3.live-streams.nl:8250/class-flac.flac title="Naim Classical (FLAC)" Mar 29 17:40:15 volumio4 volumio[1320]: info: ------------------------------ 11ms Mar 29 17:40:15 volumio4 volumio[1320]: info: ------------------------------ 10ms Mar 29 17:40:40 volumio4 volumio[1320]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 17:40:40 volumio4 volumio[1320]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 17:40:40 volumio4 volumio[1320]: info: Retrieving Cloud Streaming UI Mar 29 17:40:40 volumio4 volumio[1320]: info: Getting Tidal Cloud Configuration Mar 29 17:40:40 volumio4 volumio[1320]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 17:40:40 volumio4 volumio[1320]: info: Getting Qobuz Cloud Configuration Mar 29 17:40:40 volumio4 volumio[1320]: info: Asking plugin for UI Config Mar 29 17:40:40 volumio4 volumio[1320]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 17:40:40 volumio4 volumio[1320]: info: Getting Spotify Cloud Configuration Mar 29 17:40:40 volumio4 volumio[1320]: info: Asking plugin for UI Config Mar 29 17:40:40 volumio4 volumio[1320]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 17:40:40 volumio4 volumio[1320]: info: Saving Spotify Acccount Mar 29 17:40:40 volumio4 volumio[1320]: info: Got Tidal Cloud Configuration Mar 29 17:40:40 volumio4 volumio[1320]: info: Got it Mar 29 17:40:40 volumio4 volumio[1320]: info: Got it Mar 29 17:40:40 volumio4 volumio[1320]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Mar 29 17:40:40 volumio4 volumio[1320]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 17:40:40 volumio4 volumio[1320]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Mar 29 17:40:40 volumio4 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 29 17:40:40 volumio4 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 29 17:40:40 volumio4 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 29 17:40:40 volumio4 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 29 17:40:40 volumio4 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 17:40:40 volumio4 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 17:40:40 volumio4 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 17:40:40 volumio4 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 17:40:40 volumio4 volumio[1320]: info: CoreCommandRouter::volumioGetBrowseSources Mar 29 17:40:40 volumio4 volumio[1320]: info: CoreCommandRouter::volumioGetBrowseSources Mar 29 17:40:40 volumio4 volumio[1320]: info: CoreCommandRouter::volumioGetBrowseSources Mar 29 17:40:41 volumio4 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 29 17:40:41 volumio4 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Mar 29 17:40:44 volumio4 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Mar 29 17:40:44 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand update Mar 29 17:40:44 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand status Mar 29 17:40:44 volumio4 volumio[1320]: info: Mar 29 17:40:44 volumio4 volumio[1320]: ---------------------------- MPD announces state update: update Mar 29 17:40:44 volumio4 volumio[1320]: info: sendMpdCommand update took 1 milliseconds Mar 29 17:40:44 volumio4 volumio[1320]: info: ControllerMpd::getState Mar 29 17:40:44 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand status Mar 29 17:40:44 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand status Mar 29 17:40:44 volumio4 volumio[1320]: info: Mar 29 17:40:44 volumio4 volumio[1320]: ---------------------------- MPD announces state update: update Mar 29 17:40:44 volumio4 volumio[1320]: info: ControllerMpd::getState Mar 29 17:40:44 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand status Mar 29 17:40:44 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand status Mar 29 17:40:44 volumio4 volumio[1320]: info: Mar 29 17:40:44 volumio4 volumio[1320]: ---------------------------- MPD announces state update: update Mar 29 17:40:44 volumio4 volumio[1320]: info: ControllerMpd::getState Mar 29 17:40:44 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand status Mar 29 17:40:44 volumio4 volumio[1320]: info: sendMpdCommand status took 1 milliseconds Mar 29 17:40:44 volumio4 volumio[1320]: info: sendMpdCommand status took 1 milliseconds Mar 29 17:40:44 volumio4 volumio[1320]: verbose: ControllerMpd::parseState Mar 29 17:40:44 volumio4 volumio[1320]: info: Command Router : Notfying DB Updatetrue Mar 29 17:40:44 volumio4 volumio[1320]: verbose: ControllerMpd::parseState Mar 29 17:40:44 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 17:40:44 volumio4 volumio[1320]: info: sendMpdCommand status took 24 milliseconds Mar 29 17:40:44 volumio4 volumio[1320]: info: sendMpdCommand status took 24 milliseconds Mar 29 17:40:44 volumio4 volumio[1320]: info: sendMpdCommand status took 24 milliseconds Mar 29 17:40:44 volumio4 volumio[1320]: info: sendMpdCommand status took 24 milliseconds Mar 29 17:40:44 volumio4 volumio[1320]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 29 17:40:44 volumio4 volumio[1320]: verbose: ControllerMpd::parseState Mar 29 17:40:44 volumio4 volumio[1320]: info: Command Router : Notfying DB Updatetrue Mar 29 17:40:44 volumio4 volumio[1320]: info: CoreCommandRouter::Close All Modals sent Mar 29 17:40:44 volumio4 volumio[1320]: verbose: ControllerMpd::parseState Mar 29 17:40:44 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 17:40:44 volumio4 volumio[1320]: verbose: ControllerMpd::parseState Mar 29 17:40:44 volumio4 volumio[1320]: info: Command Router : Notfying DB Updatetrue Mar 29 17:40:44 volumio4 volumio[1320]: info: CoreCommandRouter::Close All Modals sent Mar 29 17:40:44 volumio4 volumio[1320]: verbose: ControllerMpd::parseState Mar 29 17:40:44 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 17:40:44 volumio4 volumio[1320]: verbose: ControllerMpd::parseTrackInfo Mar 29 17:40:44 volumio4 volumio[1320]: info: ControllerMpd::pushState Mar 29 17:40:44 volumio4 volumio[1320]: info: CoreCommandRouter::servicePushState Mar 29 17:40:44 volumio4 volumio[1320]: info: CorePlayQueue::getTrack 0 Mar 29 17:40:44 volumio4 volumio[1320]: verbose: STATE SERVICE {"status":"play","position":0,"seek":30476,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"class-flac.flac","artist":"Naim Classical","album":null,"uri":"http://mscp3.live-streams.nl:8250/class-flac.flac","trackType":"flac"} Mar 29 17:40:44 volumio4 volumio[1320]: verbose: CURRENT POSITION 0 Mar 29 17:40:44 volumio4 volumio[1320]: info: CoreStateMachine::syncState stateService play Mar 29 17:40:44 volumio4 volumio[1320]: info: CoreStateMachine::syncState currentStatus play Mar 29 17:40:44 volumio4 volumio[1320]: info: Received an update from plugin. extracting info from payload Mar 29 17:40:44 volumio4 volumio[1320]: info: CoreStateMachine::pushState Mar 29 17:40:44 volumio4 volumio[1320]: info: CorePlayQueue::getTrack 0 Mar 29 17:40:44 volumio4 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 17:40:44 volumio4 volumio[1320]: info: CoreCommandRouter::volumioPushState Mar 29 17:40:44 volumio4 volumio[1320]: info: CoreStateMachine::pushState Mar 29 17:40:44 volumio4 volumio[1320]: info: CoreCommandRouter::volumioPushState Mar 29 17:40:44 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:44.868+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" state=STATUS_PLAYING positionMs=29775 volume= Mar 29 17:40:44 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:44.868+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" id=http://mscp3.live-streams.nl:8250/class-flac.flac title="Naim Classical (FLAC)" Mar 29 17:40:44 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:44.869+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" state=STATUS_PLAYING positionMs=29775 volume= Mar 29 17:40:44 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:44.869+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" id=http://mscp3.live-streams.nl:8250/class-flac.flac title="Naim Classical (FLAC)" Mar 29 17:40:44 volumio4 volumio[1320]: info: ------------------------------ 50ms Mar 29 17:40:44 volumio4 volumio[1320]: info: sendMpdCommand playlistinfo took 19 milliseconds Mar 29 17:40:44 volumio4 volumio[1320]: info: sendMpdCommand playlistinfo took 14 milliseconds Mar 29 17:40:44 volumio4 volumio[1320]: verbose: ControllerMpd::parseTrackInfo Mar 29 17:40:44 volumio4 volumio[1320]: verbose: ControllerMpd::parseTrackInfo Mar 29 17:40:44 volumio4 volumio[1320]: info: ControllerMpd::pushState Mar 29 17:40:44 volumio4 volumio[1320]: info: CoreCommandRouter::servicePushState Mar 29 17:40:44 volumio4 volumio[1320]: info: CorePlayQueue::getTrack 0 Mar 29 17:40:44 volumio4 volumio[1320]: verbose: STATE SERVICE {"status":"play","position":0,"seek":30476,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"class-flac.flac","artist":"Naim Classical","album":null,"uri":"http://mscp3.live-streams.nl:8250/class-flac.flac","trackType":"flac"} Mar 29 17:40:44 volumio4 volumio[1320]: verbose: CURRENT POSITION 0 Mar 29 17:40:44 volumio4 volumio[1320]: info: CoreStateMachine::syncState stateService play Mar 29 17:40:44 volumio4 volumio[1320]: info: CoreStateMachine::syncState currentStatus play Mar 29 17:40:44 volumio4 volumio[1320]: info: Received an update from plugin. extracting info from payload Mar 29 17:40:44 volumio4 volumio[1320]: info: CoreStateMachine::pushState Mar 29 17:40:44 volumio4 volumio[1320]: info: CorePlayQueue::getTrack 0 Mar 29 17:40:44 volumio4 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 17:40:44 volumio4 volumio[1320]: info: CoreCommandRouter::volumioPushState Mar 29 17:40:44 volumio4 volumio[1320]: info: CoreStateMachine::pushState Mar 29 17:40:44 volumio4 volumio[1320]: info: CoreCommandRouter::volumioPushState Mar 29 17:40:44 volumio4 volumio[1320]: info: ControllerMpd::pushState Mar 29 17:40:44 volumio4 volumio[1320]: info: CoreCommandRouter::servicePushState Mar 29 17:40:44 volumio4 volumio[1320]: info: CorePlayQueue::getTrack 0 Mar 29 17:40:44 volumio4 volumio[1320]: verbose: STATE SERVICE {"status":"play","position":0,"seek":30476,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"class-flac.flac","artist":"Naim Classical","album":null,"uri":"http://mscp3.live-streams.nl:8250/class-flac.flac","trackType":"flac"} Mar 29 17:40:44 volumio4 volumio[1320]: verbose: CURRENT POSITION 0 Mar 29 17:40:44 volumio4 volumio[1320]: info: CoreStateMachine::syncState stateService play Mar 29 17:40:44 volumio4 volumio[1320]: info: CoreStateMachine::syncState currentStatus play Mar 29 17:40:44 volumio4 volumio[1320]: info: Received an update from plugin. extracting info from payload Mar 29 17:40:44 volumio4 volumio[1320]: info: CoreStateMachine::pushState Mar 29 17:40:44 volumio4 volumio[1320]: info: CorePlayQueue::getTrack 0 Mar 29 17:40:44 volumio4 volumio[1320]: info: CoreCommandRouter::volumioPushState Mar 29 17:40:44 volumio4 volumio[1320]: info: CoreStateMachine::pushState Mar 29 17:40:44 volumio4 volumio[1320]: info: CoreCommandRouter::volumioPushState Mar 29 17:40:44 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:44.878+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" state=STATUS_PLAYING positionMs=29804 volume= Mar 29 17:40:44 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:44.878+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" id=http://mscp3.live-streams.nl:8250/class-flac.flac title="Naim Classical (FLAC)" Mar 29 17:40:44 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:44.878+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" state=STATUS_PLAYING positionMs=29804 volume= Mar 29 17:40:44 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:44.878+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" id=http://mscp3.live-streams.nl:8250/class-flac.flac title="Naim Classical (FLAC)" Mar 29 17:40:44 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:44.878+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" state=STATUS_PLAYING positionMs=29804 volume= Mar 29 17:40:44 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:44.879+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" id=http://mscp3.live-streams.nl:8250/class-flac.flac title="Naim Classical (FLAC)" Mar 29 17:40:44 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:44.879+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" state=STATUS_PLAYING positionMs=29804 volume= Mar 29 17:40:44 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:44.879+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" id=http://mscp3.live-streams.nl:8250/class-flac.flac title="Naim Classical (FLAC)" Mar 29 17:40:44 volumio4 volumio[1320]: info: ------------------------------ 62ms Mar 29 17:40:44 volumio4 volumio[1320]: info: ------------------------------ 62ms Mar 29 17:40:45 volumio4 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 29 17:40:47 volumio4 volumio[1320]: info: MPD Database updated - AlbumList cache refreshed Mar 29 17:40:47 volumio4 volumio[1320]: info: Mar 29 17:40:47 volumio4 volumio[1320]: ---------------------------- MPD announces state update: database Mar 29 17:40:47 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand status Mar 29 17:40:47 volumio4 volumio[1320]: info: Mar 29 17:40:47 volumio4 volumio[1320]: ---------------------------- MPD announces state update: update Mar 29 17:40:47 volumio4 volumio[1320]: info: ControllerMpd::getState Mar 29 17:40:47 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand status Mar 29 17:40:47 volumio4 volumio[1320]: info: ControllerMpd::getState Mar 29 17:40:47 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand status Mar 29 17:40:47 volumio4 volumio[1320]: info: MPD Database updated - AlbumList cache refreshed Mar 29 17:40:47 volumio4 volumio[1320]: info: Mar 29 17:40:47 volumio4 volumio[1320]: ---------------------------- MPD announces state update: database Mar 29 17:40:47 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand status Mar 29 17:40:47 volumio4 volumio[1320]: info: Mar 29 17:40:47 volumio4 volumio[1320]: ---------------------------- MPD announces state update: update Mar 29 17:40:47 volumio4 volumio[1320]: info: ControllerMpd::getState Mar 29 17:40:47 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand status Mar 29 17:40:47 volumio4 volumio[1320]: info: ControllerMpd::getState Mar 29 17:40:47 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand status Mar 29 17:40:47 volumio4 volumio[1320]: info: MPD Database updated - AlbumList cache refreshed Mar 29 17:40:47 volumio4 volumio[1320]: info: Mar 29 17:40:47 volumio4 volumio[1320]: ---------------------------- MPD announces state update: database Mar 29 17:40:47 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand status Mar 29 17:40:47 volumio4 volumio[1320]: info: Mar 29 17:40:47 volumio4 volumio[1320]: ---------------------------- MPD announces state update: update Mar 29 17:40:47 volumio4 volumio[1320]: info: ControllerMpd::getState Mar 29 17:40:47 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand status Mar 29 17:40:47 volumio4 volumio[1320]: info: ControllerMpd::getState Mar 29 17:40:47 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand status Mar 29 17:40:47 volumio4 volumio[1320]: info: sendMpdCommand status took 467 milliseconds Mar 29 17:40:47 volumio4 volumio[1320]: info: sendMpdCommand status took 467 milliseconds Mar 29 17:40:47 volumio4 volumio[1320]: info: sendMpdCommand status took 467 milliseconds Mar 29 17:40:47 volumio4 volumio[1320]: verbose: ControllerMpd::parseState Mar 29 17:40:47 volumio4 volumio[1320]: info: Command Router : Notfying DB Updatefalse Mar 29 17:40:47 volumio4 volumio[1320]: info: CoreCommandRouter::Close All Modals sent Mar 29 17:40:47 volumio4 volumio[1320]: verbose: ControllerMpd::parseState Mar 29 17:40:47 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 17:40:47 volumio4 volumio[1320]: verbose: ControllerMpd::parseState Mar 29 17:40:47 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 17:40:47 volumio4 volumio[1320]: info: sendMpdCommand status took 798 milliseconds Mar 29 17:40:47 volumio4 volumio[1320]: info: sendMpdCommand status took 799 milliseconds Mar 29 17:40:47 volumio4 volumio[1320]: info: sendMpdCommand status took 799 milliseconds Mar 29 17:40:47 volumio4 volumio[1320]: verbose: ControllerMpd::parseState Mar 29 17:40:47 volumio4 volumio[1320]: info: Command Router : Notfying DB Updatefalse Mar 29 17:40:47 volumio4 volumio[1320]: info: CoreCommandRouter::Close All Modals sent Mar 29 17:40:47 volumio4 volumio[1320]: verbose: ControllerMpd::parseState Mar 29 17:40:47 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 17:40:47 volumio4 volumio[1320]: verbose: ControllerMpd::parseState Mar 29 17:40:47 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 17:40:48 volumio4 volumio[1320]: info: sendMpdCommand status took 1121 milliseconds Mar 29 17:40:48 volumio4 volumio[1320]: info: sendMpdCommand status took 1121 milliseconds Mar 29 17:40:48 volumio4 volumio[1320]: info: sendMpdCommand status took 1121 milliseconds Mar 29 17:40:48 volumio4 volumio[1320]: verbose: ControllerMpd::parseState Mar 29 17:40:48 volumio4 volumio[1320]: info: Command Router : Notfying DB Updatefalse Mar 29 17:40:48 volumio4 volumio[1320]: info: CoreCommandRouter::Close All Modals sent Mar 29 17:40:48 volumio4 volumio[1320]: verbose: ControllerMpd::parseState Mar 29 17:40:48 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 17:40:48 volumio4 volumio[1320]: verbose: ControllerMpd::parseState Mar 29 17:40:48 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 17:40:48 volumio4 volumio[1320]: info: sendMpdCommand playlistinfo took 655 milliseconds Mar 29 17:40:48 volumio4 volumio[1320]: info: sendMpdCommand playlistinfo took 655 milliseconds Mar 29 17:40:48 volumio4 volumio[1320]: verbose: ControllerMpd::parseTrackInfo Mar 29 17:40:48 volumio4 volumio[1320]: verbose: ControllerMpd::parseTrackInfo Mar 29 17:40:48 volumio4 volumio[1320]: info: ControllerMpd::pushState Mar 29 17:40:48 volumio4 volumio[1320]: info: CoreCommandRouter::servicePushState Mar 29 17:40:48 volumio4 volumio[1320]: info: CorePlayQueue::getTrack 0 Mar 29 17:40:48 volumio4 volumio[1320]: verbose: STATE SERVICE {"status":"play","position":0,"seek":32848,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"class-flac.flac","artist":"Naim Classical","album":null,"uri":"http://mscp3.live-streams.nl:8250/class-flac.flac","trackType":"flac"} Mar 29 17:40:48 volumio4 volumio[1320]: verbose: CURRENT POSITION 0 Mar 29 17:40:48 volumio4 volumio[1320]: info: CoreStateMachine::syncState stateService play Mar 29 17:40:48 volumio4 volumio[1320]: info: CoreStateMachine::syncState currentStatus play Mar 29 17:40:48 volumio4 volumio[1320]: info: Received an update from plugin. extracting info from payload Mar 29 17:40:48 volumio4 volumio[1320]: info: CoreStateMachine::pushState Mar 29 17:40:48 volumio4 volumio[1320]: info: CorePlayQueue::getTrack 0 Mar 29 17:40:48 volumio4 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 17:40:48 volumio4 volumio[1320]: info: CoreCommandRouter::volumioPushState Mar 29 17:40:48 volumio4 volumio[1320]: info: CoreStateMachine::pushState Mar 29 17:40:48 volumio4 volumio[1320]: info: CoreCommandRouter::volumioPushState Mar 29 17:40:48 volumio4 volumio[1320]: info: ControllerMpd::pushState Mar 29 17:40:48 volumio4 volumio[1320]: info: CoreCommandRouter::servicePushState Mar 29 17:40:48 volumio4 volumio[1320]: info: CorePlayQueue::getTrack 0 Mar 29 17:40:48 volumio4 volumio[1320]: verbose: STATE SERVICE {"status":"play","position":0,"seek":32848,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"class-flac.flac","artist":"Naim Classical","album":null,"uri":"http://mscp3.live-streams.nl:8250/class-flac.flac","trackType":"flac"} Mar 29 17:40:48 volumio4 volumio[1320]: verbose: CURRENT POSITION 0 Mar 29 17:40:48 volumio4 volumio[1320]: info: CoreStateMachine::syncState stateService play Mar 29 17:40:48 volumio4 volumio[1320]: info: CoreStateMachine::syncState currentStatus play Mar 29 17:40:48 volumio4 volumio[1320]: info: Received an update from plugin. extracting info from payload Mar 29 17:40:48 volumio4 volumio[1320]: info: CoreStateMachine::pushState Mar 29 17:40:48 volumio4 volumio[1320]: info: CorePlayQueue::getTrack 0 Mar 29 17:40:48 volumio4 volumio[1320]: info: CoreCommandRouter::volumioPushState Mar 29 17:40:48 volumio4 volumio[1320]: info: CoreStateMachine::pushState Mar 29 17:40:48 volumio4 volumio[1320]: info: CoreCommandRouter::volumioPushState Mar 29 17:40:48 volumio4 volumio[1320]: info: ------------------------------ 1136ms Mar 29 17:40:48 volumio4 volumio[1320]: info: ------------------------------ 1136ms Mar 29 17:40:48 volumio4 volumio[1320]: info: sendMpdCommand playlistinfo took 332 milliseconds Mar 29 17:40:48 volumio4 volumio[1320]: info: sendMpdCommand playlistinfo took 332 milliseconds Mar 29 17:40:48 volumio4 volumio[1320]: verbose: ControllerMpd::parseTrackInfo Mar 29 17:40:48 volumio4 volumio[1320]: verbose: ControllerMpd::parseTrackInfo Mar 29 17:40:48 volumio4 volumio[1320]: info: ControllerMpd::pushState Mar 29 17:40:48 volumio4 volumio[1320]: info: CoreCommandRouter::servicePushState Mar 29 17:40:48 volumio4 volumio[1320]: info: CorePlayQueue::getTrack 0 Mar 29 17:40:48 volumio4 volumio[1320]: verbose: STATE SERVICE {"status":"play","position":0,"seek":32963,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"class-flac.flac","artist":"Naim Classical","album":null,"uri":"http://mscp3.live-streams.nl:8250/class-flac.flac","trackType":"flac"} Mar 29 17:40:48 volumio4 volumio[1320]: verbose: CURRENT POSITION 0 Mar 29 17:40:48 volumio4 volumio[1320]: info: CoreStateMachine::syncState stateService play Mar 29 17:40:48 volumio4 volumio[1320]: info: CoreStateMachine::syncState currentStatus play Mar 29 17:40:48 volumio4 volumio[1320]: info: Received an update from plugin. extracting info from payload Mar 29 17:40:48 volumio4 volumio[1320]: info: CoreStateMachine::pushState Mar 29 17:40:48 volumio4 volumio[1320]: info: CorePlayQueue::getTrack 0 Mar 29 17:40:48 volumio4 volumio[1320]: info: CoreCommandRouter::volumioPushState Mar 29 17:40:48 volumio4 volumio[1320]: info: CoreStateMachine::pushState Mar 29 17:40:48 volumio4 volumio[1320]: info: CoreCommandRouter::volumioPushState Mar 29 17:40:48 volumio4 volumio[1320]: info: ControllerMpd::pushState Mar 29 17:40:48 volumio4 volumio[1320]: info: CoreCommandRouter::servicePushState Mar 29 17:40:48 volumio4 volumio[1320]: info: CorePlayQueue::getTrack 0 Mar 29 17:40:48 volumio4 volumio[1320]: verbose: STATE SERVICE {"status":"play","position":0,"seek":32963,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"class-flac.flac","artist":"Naim Classical","album":null,"uri":"http://mscp3.live-streams.nl:8250/class-flac.flac","trackType":"flac"} Mar 29 17:40:48 volumio4 volumio[1320]: verbose: CURRENT POSITION 0 Mar 29 17:40:48 volumio4 volumio[1320]: info: CoreStateMachine::syncState stateService play Mar 29 17:40:48 volumio4 volumio[1320]: info: CoreStateMachine::syncState currentStatus play Mar 29 17:40:48 volumio4 volumio[1320]: info: Received an update from plugin. extracting info from payload Mar 29 17:40:48 volumio4 volumio[1320]: info: CoreStateMachine::pushState Mar 29 17:40:48 volumio4 volumio[1320]: info: CorePlayQueue::getTrack 0 Mar 29 17:40:48 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:48.254+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" state=STATUS_PLAYING positionMs=33074 volume= Mar 29 17:40:48 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:48.254+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" state=STATUS_PLAYING positionMs=33074 volume= Mar 29 17:40:48 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:48.255+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" state=STATUS_PLAYING positionMs=33074 volume= Mar 29 17:40:48 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:48.255+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" state=STATUS_PLAYING positionMs=33074 volume= Mar 29 17:40:48 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:48.255+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" id=http://mscp3.live-streams.nl:8250/class-flac.flac title="Naim Classical (FLAC)" Mar 29 17:40:48 volumio4 volumio[1320]: info: CoreCommandRouter::volumioPushState Mar 29 17:40:48 volumio4 volumio[1320]: info: CoreStateMachine::pushState Mar 29 17:40:48 volumio4 volumio[1320]: info: CoreCommandRouter::volumioPushState Mar 29 17:40:48 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:48.255+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" id=http://mscp3.live-streams.nl:8250/class-flac.flac title="Naim Classical (FLAC)" Mar 29 17:40:48 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:48.255+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" id=http://mscp3.live-streams.nl:8250/class-flac.flac title="Naim Classical (FLAC)" Mar 29 17:40:48 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:48.255+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" id=http://mscp3.live-streams.nl:8250/class-flac.flac title="Naim Classical (FLAC)" Mar 29 17:40:48 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:48.256+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" state=STATUS_PLAYING positionMs=33074 volume= Mar 29 17:40:48 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:48.256+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" state=STATUS_PLAYING positionMs=33074 volume= Mar 29 17:40:48 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:48.256+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" id=http://mscp3.live-streams.nl:8250/class-flac.flac title="Naim Classical (FLAC)" Mar 29 17:40:48 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:48.256+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" id=http://mscp3.live-streams.nl:8250/class-flac.flac title="Naim Classical (FLAC)" Mar 29 17:40:48 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:48.256+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" state=STATUS_PLAYING positionMs=33074 volume= Mar 29 17:40:48 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:48.256+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" id=http://mscp3.live-streams.nl:8250/class-flac.flac title="Naim Classical (FLAC)" Mar 29 17:40:48 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:48.256+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" state=STATUS_PLAYING positionMs=33074 volume= Mar 29 17:40:48 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:48.256+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" id=http://mscp3.live-streams.nl:8250/class-flac.flac title="Naim Classical (FLAC)" Mar 29 17:40:48 volumio4 volumio[1320]: info: ------------------------------ 1143ms Mar 29 17:40:48 volumio4 volumio[1320]: info: ------------------------------ 1143ms Mar 29 17:40:48 volumio4 volumio[1320]: info: sendMpdCommand playlistinfo took 16 milliseconds Mar 29 17:40:48 volumio4 volumio[1320]: info: sendMpdCommand playlistinfo took 16 milliseconds Mar 29 17:40:48 volumio4 volumio[1320]: verbose: ControllerMpd::parseTrackInfo Mar 29 17:40:48 volumio4 volumio[1320]: verbose: ControllerMpd::parseTrackInfo Mar 29 17:40:48 volumio4 volumio[1320]: info: ControllerMpd::pushState Mar 29 17:40:48 volumio4 volumio[1320]: info: CoreCommandRouter::servicePushState Mar 29 17:40:48 volumio4 volumio[1320]: info: CorePlayQueue::getTrack 0 Mar 29 17:40:48 volumio4 volumio[1320]: verbose: STATE SERVICE {"status":"play","position":0,"seek":32963,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"class-flac.flac","artist":"Naim Classical","album":null,"uri":"http://mscp3.live-streams.nl:8250/class-flac.flac","trackType":"flac"} Mar 29 17:40:48 volumio4 volumio[1320]: verbose: CURRENT POSITION 0 Mar 29 17:40:48 volumio4 volumio[1320]: info: CoreStateMachine::syncState stateService play Mar 29 17:40:48 volumio4 volumio[1320]: info: CoreStateMachine::syncState currentStatus play Mar 29 17:40:48 volumio4 volumio[1320]: info: Received an update from plugin. extracting info from payload Mar 29 17:40:48 volumio4 volumio[1320]: info: CoreStateMachine::pushState Mar 29 17:40:48 volumio4 volumio[1320]: info: CorePlayQueue::getTrack 0 Mar 29 17:40:48 volumio4 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 17:40:48 volumio4 volumio[1320]: info: CoreCommandRouter::volumioPushState Mar 29 17:40:48 volumio4 volumio[1320]: info: CoreStateMachine::pushState Mar 29 17:40:48 volumio4 volumio[1320]: info: CoreCommandRouter::volumioPushState Mar 29 17:40:48 volumio4 volumio[1320]: info: ControllerMpd::pushState Mar 29 17:40:48 volumio4 volumio[1320]: info: CoreCommandRouter::servicePushState Mar 29 17:40:48 volumio4 volumio[1320]: info: CorePlayQueue::getTrack 0 Mar 29 17:40:48 volumio4 volumio[1320]: verbose: STATE SERVICE {"status":"play","position":0,"seek":32963,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"class-flac.flac","artist":"Naim Classical","album":null,"uri":"http://mscp3.live-streams.nl:8250/class-flac.flac","trackType":"flac"} Mar 29 17:40:48 volumio4 volumio[1320]: verbose: CURRENT POSITION 0 Mar 29 17:40:48 volumio4 volumio[1320]: info: CoreStateMachine::syncState stateService play Mar 29 17:40:48 volumio4 volumio[1320]: info: CoreStateMachine::syncState currentStatus play Mar 29 17:40:48 volumio4 volumio[1320]: info: Received an update from plugin. extracting info from payload Mar 29 17:40:48 volumio4 volumio[1320]: info: CoreStateMachine::pushState Mar 29 17:40:48 volumio4 volumio[1320]: info: CorePlayQueue::getTrack 0 Mar 29 17:40:48 volumio4 volumio[1320]: info: CoreCommandRouter::volumioPushState Mar 29 17:40:48 volumio4 volumio[1320]: info: CoreStateMachine::pushState Mar 29 17:40:48 volumio4 volumio[1320]: info: CoreCommandRouter::volumioPushState Mar 29 17:40:48 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:48.263+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" state=STATUS_PLAYING positionMs=33189 volume= Mar 29 17:40:48 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:48.263+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" id=http://mscp3.live-streams.nl:8250/class-flac.flac title="Naim Classical (FLAC)" Mar 29 17:40:48 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:48.263+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" state=STATUS_PLAYING positionMs=33189 volume= Mar 29 17:40:48 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:48.263+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" state=STATUS_PLAYING positionMs=33189 volume= Mar 29 17:40:48 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:48.263+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" id=http://mscp3.live-streams.nl:8250/class-flac.flac title="Naim Classical (FLAC)" Mar 29 17:40:48 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:48.263+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" id=http://mscp3.live-streams.nl:8250/class-flac.flac title="Naim Classical (FLAC)" Mar 29 17:40:48 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:48.263+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" state=STATUS_PLAYING positionMs=33189 volume= Mar 29 17:40:48 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:40:48.263+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" id=http://mscp3.live-streams.nl:8250/class-flac.flac title="Naim Classical (FLAC)" Mar 29 17:40:48 volumio4 volumio[1320]: info: ------------------------------ 1149ms Mar 29 17:40:48 volumio4 volumio[1320]: info: ------------------------------ 1149ms Mar 29 17:40:49 volumio4 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 29 17:40:53 volumio4 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 29 17:41:02 volumio4 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 29 17:41:02 volumio4 volumio[1320]: info: CURURI: music-library Mar 29 17:41:02 volumio4 volumio[1320]: info: Preload queue cleared Mar 29 17:41:03 volumio4 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 29 17:41:03 volumio4 volumio[1320]: info: CURURI: music-library/USB Mar 29 17:41:03 volumio4 volumio[1320]: info: Preload queue cleared Mar 29 17:41:04 volumio4 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 29 17:41:04 volumio4 volumio[1320]: info: CURURI: music-library/USB/Новый_том Mar 29 17:41:04 volumio4 volumio[1320]: info: Preload queue cleared Mar 29 17:41:06 volumio4 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 29 17:41:06 volumio4 volumio[1320]: info: CURURI: music-library/USB/Новый_том/Зарубежный ROCK Mar 29 17:41:06 volumio4 volumio[1320]: info: Preload queue cleared Mar 29 17:41:14 volumio4 volumio[1320]: info: Preload queue cleared Mar 29 17:41:14 volumio4 volumio[1320]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 29 17:41:14 volumio4 volumio[1320]: info: CoreStateMachine::ClearQueue Mar 29 17:41:14 volumio4 volumio[1320]: info: CoreStateMachine::stop Mar 29 17:41:14 volumio4 volumio[1320]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 17:41:14 volumio4 volumio[1320]: info: CoreStateMachine::stPlaybackTimer Mar 29 17:41:14 volumio4 volumio[1320]: info: CoreStateMachine::updateTrackBlock Mar 29 17:41:14 volumio4 volumio[1320]: info: CorePlayQueue::getTrackBlock Mar 29 17:41:14 volumio4 volumio[1320]: info: CoreStateMachine::pushState Mar 29 17:41:14 volumio4 volumio[1320]: info: CorePlayQueue::getTrack 0 Mar 29 17:41:14 volumio4 volumio[1320]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 17:41:14 volumio4 volumio[1320]: info: CoreCommandRouter::volumioPushState Mar 29 17:41:14 volumio4 volumio[1320]: info: CoreStateMachine::serviceStop Mar 29 17:41:14 volumio4 volumio[1320]: info: CorePlayQueue::getTrack 0 Mar 29 17:41:14 volumio4 volumio[1320]: info: CoreCommandRouter::serviceStop Mar 29 17:41:14 volumio4 volumio[1320]: info: [1774788074336] ControllerWebradio::stop Mar 29 17:41:14 volumio4 volumio[1320]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 17:41:14 volumio4 volumio[1320]: info: CorePlayQueue::clearPlayQueue Mar 29 17:41:14 volumio4 volumio[1320]: info: CorePlayQueue::saveQueue Mar 29 17:41:14 volumio4 volumio[1320]: info: CoreCommandRouter::volumioPushQueue Mar 29 17:41:14 volumio4 volumio[1320]: info: CoreStateMachine::addQueueItems Mar 29 17:41:14 volumio4 volumio[1320]: info: CorePlayQueue::addQueueItems Mar 29 17:41:14 volumio4 volumio[1320]: info: Preload queue cleared Mar 29 17:41:14 volumio4 volumio[1320]: info: Adding Item to queue: music-library/USB/Новый_том/Зарубежный ROCK/Def Leppard – Pyromania [LP] 1983 Mar 29 17:41:14 volumio4 volumio[1320]: info: Exploding uri music-library/USB/Новый_том/Зарубежный ROCK/Def Leppard – Pyromania [LP] 1983 in service mpd Mar 29 17:41:14 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:41:14.338+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" state=STATUS_STOPPED positionMs=0 volume= Mar 29 17:41:14 volumio4 volumio5-onboarding[1624]: time=2026-03-29T17:41:14.338+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.81:47544,00:00:00:00:00:00%01 @ 0xc0002003f0" id=http://mscp3.live-streams.nl:8250/class-flac.flac title="Naim Classical (FLAC)" Mar 29 17:41:14 volumio4 volumio[1320]: info: Mar 29 17:41:14 volumio4 volumio[1320]: ---------------------------- MPD announces state update: player Mar 29 17:41:14 volumio4 volumio[1320]: info: ALBUMART /albumart?cacheid=656&web=/Pyromania/extralarge&path=%2Fmnt%2FUSB%2F%D0%9D%D0%BE%D0%B2%D1%8B%D0%B9_%D1%82%D0%BE%D0%BC%2F%D0%97%D0%B0%D1%80%D1%83%D0%B1%D0%B5%D0%B6%D0%BD%D1%8B%D0%B9%20ROCK%2FDef%20Leppard%20%E2%80%93%20Pyromania%20%5BLP%5D%201983%2FDef%20Leppard%20%E2%80%93%20Pyromania%20%5BLP%5D%201983.cue&metadata=false Mar 29 17:41:14 volumio4 volumio[1320]: info: URI /mnt/USB/Новый_том/Зарубежный ROCK/Def Leppard – Pyromania [LP] 1983/Def Leppard – Pyromania [LP] 1983.cue Mar 29 17:41:14 volumio4 volumio[1320]: info: ALBUMART /albumart?cacheid=656&web=/Pyromania/extralarge&path=%2Fmnt%2FUSB%2F%D0%9D%D0%BE%D0%B2%D1%8B%D0%B9_%D1%82%D0%BE%D0%BC%2F%D0%97%D0%B0%D1%80%D1%83%D0%B1%D0%B5%D0%B6%D0%BD%D1%8B%D0%B9%20ROCK%2FDef%20Leppard%20%E2%80%93%20Pyromania%20%5BLP%5D%201983%2FDef%20Leppard%20%E2%80%93%20Pyromania%20%5BLP%5D%201983.cue&metadata=false Mar 29 17:41:14 volumio4 volumio[1320]: info: URI /mnt/USB/Новый_том/Зарубежный ROCK/Def Leppard – Pyromania [LP] 1983/Def Leppard – Pyromania [LP] 1983.cue Mar 29 17:41:14 volumio4 volumio[1320]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 29 17:41:14 volumio4 volumio[1320]: Error: Unable to resolve or reject the same promise twice Mar 29 17:41:14 volumio4 volumio[1320]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Mar 29 17:41:14 volumio4 volumio[1320]: at /volumio/app/plugins/music_service/mpd/index.js:2582:21 Mar 29 17:41:14 volumio4 volumio[1320]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Mar 29 17:41:14 volumio4 volumio[1320]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Mar 29 17:41:14 volumio4 volumio[1320]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Mar 29 17:41:14 volumio4 volumio[1320]: at Socket.emit (node:events:514:28) Mar 29 17:41:14 volumio4 volumio[1320]: at addChunk (node:internal/streams/readable:343:12) Mar 29 17:41:14 volumio4 volumio[1320]: at readableAddChunk (node:internal/streams/readable:312:11) Mar 29 17:41:14 volumio4 volumio[1320]: at Readable.push (node:internal/streams/readable:253:10) Mar 29 17:41:14 volumio4 volumio[1320]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Mar 29 17:41:14 volumio4 volumio[1320]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 29 17:41:14 volumio4 sudo[3097]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-29 17:40' Mar 29 17:41:14 volumio4 sudo[3097]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:45:45 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6bf7cd61fe53483b72878254df87f1c0"