-- Logs begin at Thu 2019-02-14 15:11:58 +05, end at Sun 2026-04-12 12:15:36 +05. -- Apr 12 12:14:02 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Apr 12 12:14:02 volumio volumio[1006]: info: Preload queue cleared Apr 12 12:14:03 volumio volumio[1006]: info: Preload queue cleared Apr 12 12:14:03 volumio volumio[1006]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 12 12:14:03 volumio volumio[1006]: info: CoreStateMachine::ClearQueue Apr 12 12:14:03 volumio volumio[1006]: info: CoreStateMachine::stop Apr 12 12:14:03 volumio volumio[1006]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 12:14:03 volumio volumio[1006]: info: CorePlayQueue::clearPlayQueue Apr 12 12:14:03 volumio volumio[1006]: info: CorePlayQueue::saveQueue Apr 12 12:14:03 volumio volumio[1006]: info: CoreCommandRouter::volumioPushQueue Apr 12 12:14:03 volumio volumio[1006]: info: CoreStateMachine::addQueueItems Apr 12 12:14:03 volumio volumio[1006]: info: CorePlayQueue::addQueueItems Apr 12 12:14:03 volumio volumio[1006]: info: Preload queue cleared Apr 12 12:14:03 volumio volumio[1006]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s338222 Apr 12 12:14:03 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 12 12:14:03 volumio volumio[1006]: info: CoreCommandRouter::volumioPushQueue Apr 12 12:14:03 volumio volumio[1006]: info: CorePlayQueue::saveQueue Apr 12 12:14:03 volumio volumio[1006]: info: CoreStateMachine::updateTrackBlock Apr 12 12:14:03 volumio volumio[1006]: info: CorePlayQueue::getTrackBlock Apr 12 12:14:03 volumio volumio[1006]: info: CoreCommandRouter::volumioPlay Apr 12 12:14:03 volumio volumio[1006]: info: CoreStateMachine::play index 0 Apr 12 12:14:03 volumio volumio[1006]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 12:14:03 volumio volumio[1006]: info: CoreStateMachine::stop Apr 12 12:14:03 volumio volumio[1006]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 12:14:03 volumio volumio[1006]: info: CoreStateMachine::play index undefined Apr 12 12:14:03 volumio volumio[1006]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 12:14:03 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:14:03 volumio volumio[1006]: info: CoreStateMachine::startPlaybackTimer Apr 12 12:14:03 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:14:03 volumio volumio[1006]: info: [1775978043889] ControllerWebradio::clearAddPlayTrack Apr 12 12:14:03 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand stop Apr 12 12:14:03 volumio volumio[1006]: info: sendMpdCommand stop took 3 milliseconds Apr 12 12:14:03 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand clear Apr 12 12:14:03 volumio volumio[1006]: info: Apr 12 12:14:03 volumio volumio[1006]: ---------------------------- MPD announces system playlist update Apr 12 12:14:03 volumio volumio[1006]: info: Ignoring MPD Status Update Apr 12 12:14:03 volumio volumio[1006]: info: sendMpdCommand clear took 2 milliseconds Apr 12 12:14:03 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand load "https://air.unmixed.ru/lradio256chelyabinsk" Apr 12 12:14:03 volumio volumio[1006]: info: Apr 12 12:14:03 volumio volumio[1006]: ---------------------------- MPD announces system playlist update Apr 12 12:14:03 volumio volumio[1006]: info: Ignoring MPD Status Update Apr 12 12:14:03 volumio volumio[1006]: info: Apr 12 12:14:03 volumio volumio[1006]: ---------------------------- MPD announces system playlist update Apr 12 12:14:03 volumio volumio[1006]: info: Ignoring MPD Status Update Apr 12 12:14:03 volumio volumio[1006]: error: updateQueue error: null Apr 12 12:14:03 volumio volumio[1006]: info: ------------------------------ 7ms Apr 12 12:14:03 volumio volumio[1006]: error: Upnp client error: Error: This socket has been ended by the other party Apr 12 12:14:04 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand add "https://air.unmixed.ru/lradio256chelyabinsk" Apr 12 12:14:04 volumio volumio[1006]: error: updateQueue error: null Apr 12 12:14:04 volumio volumio[1006]: error: updateQueue error: null Apr 12 12:14:04 volumio volumio[1006]: info: ------------------------------ 880ms Apr 12 12:14:04 volumio volumio[1006]: info: ------------------------------ 879ms Apr 12 12:14:04 volumio volumio[1006]: info: Apr 12 12:14:04 volumio volumio[1006]: ---------------------------- MPD announces system playlist update Apr 12 12:14:04 volumio volumio[1006]: info: Ignoring MPD Status Update Apr 12 12:14:04 volumio volumio[1006]: info: sendMpdCommand add "https://air.unmixed.ru/lradio256chelyabinsk" took 2 milliseconds Apr 12 12:14:04 volumio volumio[1006]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 12 12:14:04 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand play Apr 12 12:14:04 volumio volumio[1006]: info: Apr 12 12:14:04 volumio volumio[1006]: ---------------------------- MPD announces system playlist update Apr 12 12:14:04 volumio volumio[1006]: info: Ignoring MPD Status Update Apr 12 12:14:04 volumio volumio[1006]: info: Apr 12 12:14:04 volumio volumio[1006]: ---------------------------- MPD announces system playlist update Apr 12 12:14:04 volumio volumio[1006]: info: Ignoring MPD Status Update Apr 12 12:14:04 volumio volumio[1006]: info: ------------------------------ 4ms Apr 12 12:14:04 volumio volumio[1006]: info: sendMpdCommand play took 4 milliseconds Apr 12 12:14:04 volumio volumio[1006]: info: ------------------------------ 3ms Apr 12 12:14:04 volumio volumio[1006]: info: ------------------------------ 2ms Apr 12 12:14:04 volumio volumio[1006]: info: Apr 12 12:14:04 volumio volumio[1006]: ---------------------------- MPD announces state update: player Apr 12 12:14:04 volumio volumio[1006]: info: ControllerMpd::getState Apr 12 12:14:04 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:14:04 volumio volumio[1006]: info: Apr 12 12:14:04 volumio volumio[1006]: ---------------------------- MPD announces state update: player Apr 12 12:14:04 volumio volumio[1006]: info: ControllerMpd::getState Apr 12 12:14:04 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:14:04 volumio volumio[1006]: info: Apr 12 12:14:04 volumio volumio[1006]: ---------------------------- MPD announces state update: player Apr 12 12:14:04 volumio volumio[1006]: info: ControllerMpd::getState Apr 12 12:14:04 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:14:04 volumio volumio[1006]: error: MPD returned error for command status: Failed to decode https://air.unmixed.ru/lradio256chelyabinsk; got HTTP status 429 Apr 12 12:14:04 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 12 12:14:04 volumio volumio[1006]: info: sendMpdCommand status took 9 milliseconds Apr 12 12:14:04 volumio volumio[1006]: error: MPD returned error for command status: Failed to decode https://air.unmixed.ru/lradio256chelyabinsk; got HTTP status 429 Apr 12 12:14:04 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 12 12:14:04 volumio volumio[1006]: info: sendMpdCommand status took 9 milliseconds Apr 12 12:14:04 volumio volumio[1006]: error: MPD returned error for command status: Failed to decode https://air.unmixed.ru/lradio256chelyabinsk; got HTTP status 429 Apr 12 12:14:04 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 12 12:14:04 volumio volumio[1006]: info: sendMpdCommand status took 9 milliseconds Apr 12 12:14:04 volumio volumio[1006]: verbose: ControllerMpd::parseState Apr 12 12:14:04 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:14:04 volumio volumio[1006]: verbose: ControllerMpd::parseState Apr 12 12:14:04 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:14:04 volumio volumio[1006]: verbose: ControllerMpd::parseState Apr 12 12:14:04 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:14:04 volumio volumio[1006]: info: sendMpdCommand clearerror took 15 milliseconds Apr 12 12:14:04 volumio volumio[1006]: info: sendMpdCommand clearerror took 14 milliseconds Apr 12 12:14:04 volumio volumio[1006]: info: sendMpdCommand clearerror took 13 milliseconds Apr 12 12:14:04 volumio volumio[1006]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 12 12:14:04 volumio volumio[1006]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 12 12:14:04 volumio volumio[1006]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 12 12:14:04 volumio volumio[1006]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:14:04 volumio volumio[1006]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:14:04 volumio volumio[1006]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:14:04 volumio volumio[1006]: info: ControllerMpd::pushState Apr 12 12:14:04 volumio volumio[1006]: info: CoreCommandRouter::servicePushState Apr 12 12:14:04 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:14:04 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 12:14:04 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:14:04 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:14:04 volumio volumio[1006]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"lradio256chelyabinsk","artist":null,"album":null,"uri":"https://air.unmixed.ru/lradio256chelyabinsk","trackType":""} Apr 12 12:14:04 volumio volumio[1006]: verbose: CURRENT POSITION 0 Apr 12 12:14:04 volumio volumio[1006]: info: CoreStateMachine::syncState stateService stop Apr 12 12:14:04 volumio volumio[1006]: info: CoreStateMachine::syncState currentStatus stop Apr 12 12:14:04 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:14:04 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:14:04 volumio volumio[1006]: info: No code Apr 12 12:14:04 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:14:04 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:14:04 volumio volumio[1006]: info: ControllerMpd::pushState Apr 12 12:14:04 volumio volumio[1006]: info: CoreCommandRouter::servicePushState Apr 12 12:14:04 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:14:04 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:14:04 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:14:04 volumio volumio[1006]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"lradio256chelyabinsk","artist":null,"album":null,"uri":"https://air.unmixed.ru/lradio256chelyabinsk","trackType":""} Apr 12 12:14:04 volumio volumio[1006]: verbose: CURRENT POSITION 0 Apr 12 12:14:04 volumio volumio[1006]: info: CoreStateMachine::syncState stateService stop Apr 12 12:14:04 volumio volumio[1006]: info: CoreStateMachine::syncState currentStatus stop Apr 12 12:14:04 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:14:04 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:14:04 volumio volumio[1006]: info: No code Apr 12 12:14:04 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:14:04 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:14:04 volumio volumio[1006]: info: ControllerMpd::pushState Apr 12 12:14:04 volumio volumio[1006]: info: CoreCommandRouter::servicePushState Apr 12 12:14:04 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:14:04 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:14:04 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:14:04 volumio volumio[1006]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"lradio256chelyabinsk","artist":null,"album":null,"uri":"https://air.unmixed.ru/lradio256chelyabinsk","trackType":""} Apr 12 12:14:04 volumio volumio[1006]: verbose: CURRENT POSITION 0 Apr 12 12:14:04 volumio volumio[1006]: info: CoreStateMachine::syncState stateService stop Apr 12 12:14:04 volumio volumio[1006]: info: CoreStateMachine::syncState currentStatus stop Apr 12 12:14:04 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:14:04 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:14:04 volumio volumio[1006]: info: No code Apr 12 12:14:04 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:14:04 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:14:04 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:04.903+05:00 level=WARN msg="received unknown player status" component=volumio status="" Apr 12 12:14:04 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:04.903+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_STOPPED positionMs=752 volume=100 Apr 12 12:14:04 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:04.904+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id= title= Apr 12 12:14:04 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:04.906+05:00 level=WARN msg="received unknown player status" component=volumio status="" Apr 12 12:14:04 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:04.906+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_STOPPED positionMs=752 volume=100 Apr 12 12:14:04 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:04.906+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id= title= Apr 12 12:14:04 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:04.907+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id= title= Apr 12 12:14:04 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:04.908+05:00 level=WARN msg="received unknown player status" component=volumio status="" Apr 12 12:14:04 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:04.908+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_STOPPED positionMs=752 volume=100 Apr 12 12:14:04 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:04.908+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id= title= Apr 12 12:14:04 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:04.910+05:00 level=WARN msg="received unknown player status" component=volumio status="" Apr 12 12:14:04 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:04.910+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_STOPPED positionMs=752 volume=100 Apr 12 12:14:04 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:04.911+05:00 level=WARN msg="received unknown player status" component=volumio status="" Apr 12 12:14:04 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:04.911+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id= title= Apr 12 12:14:04 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:04.911+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_STOPPED positionMs=752 volume=100 Apr 12 12:14:04 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:04.913+05:00 level=WARN msg="received unknown player status" component=volumio status="" Apr 12 12:14:04 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:04.913+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id= title= Apr 12 12:14:04 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:04.913+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_STOPPED positionMs=752 volume=100 Apr 12 12:14:04 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:04.913+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id= title= Apr 12 12:14:04 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:04.914+05:00 level=WARN msg="received unknown player status" component=volumio status="" Apr 12 12:14:04 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:04.914+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_STOPPED positionMs=752 volume=100 Apr 12 12:14:04 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:04.915+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id= title= Apr 12 12:14:04 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:04.916+05:00 level=WARN msg="received unknown player status" component=volumio status="" Apr 12 12:14:04 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:04.916+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_STOPPED positionMs=752 volume=100 Apr 12 12:14:04 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:04.916+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id= title= Apr 12 12:14:04 volumio volumio[1006]: info: ------------------------------ 127ms Apr 12 12:14:04 volumio volumio[1006]: info: ------------------------------ 127ms Apr 12 12:14:04 volumio volumio[1006]: info: ------------------------------ 126ms Apr 12 12:14:11 volumio volumio[1006]: info: Preload queue cleared Apr 12 12:14:11 volumio volumio[1006]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 12 12:14:11 volumio volumio[1006]: info: CoreStateMachine::ClearQueue Apr 12 12:14:11 volumio volumio[1006]: info: CoreStateMachine::stop Apr 12 12:14:11 volumio volumio[1006]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 12:14:11 volumio volumio[1006]: info: CorePlayQueue::clearPlayQueue Apr 12 12:14:11 volumio volumio[1006]: info: CorePlayQueue::saveQueue Apr 12 12:14:11 volumio volumio[1006]: info: CoreCommandRouter::volumioPushQueue Apr 12 12:14:11 volumio volumio[1006]: info: CoreStateMachine::addQueueItems Apr 12 12:14:11 volumio volumio[1006]: info: CorePlayQueue::addQueueItems Apr 12 12:14:11 volumio volumio[1006]: info: Preload queue cleared Apr 12 12:14:11 volumio volumio[1006]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s338222&filter=s:~L:country Apr 12 12:14:11 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 12 12:14:11 volumio volumio[1006]: info: CoreCommandRouter::volumioPushQueue Apr 12 12:14:11 volumio volumio[1006]: info: CorePlayQueue::saveQueue Apr 12 12:14:11 volumio volumio[1006]: info: CoreStateMachine::updateTrackBlock Apr 12 12:14:11 volumio volumio[1006]: info: CorePlayQueue::getTrackBlock Apr 12 12:14:11 volumio volumio[1006]: info: CoreCommandRouter::volumioPlay Apr 12 12:14:11 volumio volumio[1006]: info: CoreStateMachine::play index 0 Apr 12 12:14:11 volumio volumio[1006]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 12:14:11 volumio volumio[1006]: info: CoreStateMachine::stop Apr 12 12:14:11 volumio volumio[1006]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 12:14:11 volumio volumio[1006]: info: CoreStateMachine::play index undefined Apr 12 12:14:11 volumio volumio[1006]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 12:14:11 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:14:11 volumio volumio[1006]: info: CoreStateMachine::startPlaybackTimer Apr 12 12:14:11 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:14:11 volumio volumio[1006]: info: [1775978051431] ControllerWebradio::clearAddPlayTrack Apr 12 12:14:11 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand stop Apr 12 12:14:11 volumio volumio[1006]: info: sendMpdCommand stop took 3 milliseconds Apr 12 12:14:11 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand clear Apr 12 12:14:11 volumio volumio[1006]: info: Apr 12 12:14:11 volumio volumio[1006]: ---------------------------- MPD announces system playlist update Apr 12 12:14:11 volumio volumio[1006]: info: Ignoring MPD Status Update Apr 12 12:14:11 volumio volumio[1006]: info: sendMpdCommand clear took 2 milliseconds Apr 12 12:14:11 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand load "https://air.unmixed.ru/lradio256chelyabinsk" Apr 12 12:14:11 volumio volumio[1006]: info: Apr 12 12:14:11 volumio volumio[1006]: ---------------------------- MPD announces system playlist update Apr 12 12:14:11 volumio volumio[1006]: info: Ignoring MPD Status Update Apr 12 12:14:11 volumio volumio[1006]: info: Apr 12 12:14:11 volumio volumio[1006]: ---------------------------- MPD announces system playlist update Apr 12 12:14:11 volumio volumio[1006]: info: Ignoring MPD Status Update Apr 12 12:14:11 volumio volumio[1006]: error: updateQueue error: null Apr 12 12:14:11 volumio volumio[1006]: info: ------------------------------ 5ms Apr 12 12:14:11 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand add "https://air.unmixed.ru/lradio256chelyabinsk" Apr 12 12:14:11 volumio volumio[1006]: error: updateQueue error: null Apr 12 12:14:11 volumio volumio[1006]: error: updateQueue error: null Apr 12 12:14:11 volumio volumio[1006]: info: Apr 12 12:14:11 volumio volumio[1006]: ---------------------------- MPD announces system playlist update Apr 12 12:14:11 volumio volumio[1006]: info: Ignoring MPD Status Update Apr 12 12:14:11 volumio volumio[1006]: info: ------------------------------ 46ms Apr 12 12:14:11 volumio volumio[1006]: info: ------------------------------ 45ms Apr 12 12:14:11 volumio volumio[1006]: info: sendMpdCommand add "https://air.unmixed.ru/lradio256chelyabinsk" took 2 milliseconds Apr 12 12:14:11 volumio volumio[1006]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 12 12:14:11 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand play Apr 12 12:14:11 volumio volumio[1006]: info: Apr 12 12:14:11 volumio volumio[1006]: ---------------------------- MPD announces system playlist update Apr 12 12:14:11 volumio volumio[1006]: info: Ignoring MPD Status Update Apr 12 12:14:11 volumio volumio[1006]: info: Apr 12 12:14:11 volumio volumio[1006]: ---------------------------- MPD announces system playlist update Apr 12 12:14:11 volumio volumio[1006]: info: Ignoring MPD Status Update Apr 12 12:14:11 volumio volumio[1006]: info: ------------------------------ 5ms Apr 12 12:14:11 volumio volumio[1006]: info: sendMpdCommand play took 3 milliseconds Apr 12 12:14:11 volumio volumio[1006]: info: ------------------------------ 4ms Apr 12 12:14:11 volumio volumio[1006]: info: ------------------------------ 3ms Apr 12 12:14:11 volumio volumio[1006]: info: Apr 12 12:14:11 volumio volumio[1006]: ---------------------------- MPD announces state update: player Apr 12 12:14:11 volumio volumio[1006]: info: ControllerMpd::getState Apr 12 12:14:11 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:14:11 volumio volumio[1006]: info: Apr 12 12:14:11 volumio volumio[1006]: ---------------------------- MPD announces state update: player Apr 12 12:14:11 volumio volumio[1006]: info: ControllerMpd::getState Apr 12 12:14:11 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:14:11 volumio volumio[1006]: error: MPD returned error for command status: Failed to decode https://air.unmixed.ru/lradio256chelyabinsk; got HTTP status 429 Apr 12 12:14:11 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 12 12:14:11 volumio volumio[1006]: info: sendMpdCommand status took 7 milliseconds Apr 12 12:14:11 volumio volumio[1006]: error: MPD returned error for command status: Failed to decode https://air.unmixed.ru/lradio256chelyabinsk; got HTTP status 429 Apr 12 12:14:11 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 12 12:14:11 volumio volumio[1006]: info: sendMpdCommand status took 7 milliseconds Apr 12 12:14:11 volumio volumio[1006]: verbose: ControllerMpd::parseState Apr 12 12:14:11 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:14:11 volumio volumio[1006]: verbose: ControllerMpd::parseState Apr 12 12:14:11 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:14:11 volumio volumio[1006]: info: Apr 12 12:14:11 volumio volumio[1006]: ---------------------------- MPD announces state update: player Apr 12 12:14:11 volumio volumio[1006]: info: ControllerMpd::getState Apr 12 12:14:11 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:14:11 volumio volumio[1006]: info: sendMpdCommand clearerror took 12 milliseconds Apr 12 12:14:11 volumio volumio[1006]: info: sendMpdCommand clearerror took 12 milliseconds Apr 12 12:14:11 volumio volumio[1006]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 12 12:14:11 volumio volumio[1006]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 12 12:14:11 volumio volumio[1006]: info: sendMpdCommand status took 3 milliseconds Apr 12 12:14:11 volumio volumio[1006]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:14:11 volumio volumio[1006]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:14:11 volumio volumio[1006]: verbose: ControllerMpd::parseState Apr 12 12:14:11 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:14:11 volumio volumio[1006]: info: ControllerMpd::pushState Apr 12 12:14:11 volumio volumio[1006]: info: CoreCommandRouter::servicePushState Apr 12 12:14:11 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:14:11 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 12:14:11 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:14:11 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:14:11 volumio volumio[1006]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"lradio256chelyabinsk","artist":null,"album":null,"uri":"https://air.unmixed.ru/lradio256chelyabinsk","trackType":""} Apr 12 12:14:11 volumio volumio[1006]: verbose: CURRENT POSITION 0 Apr 12 12:14:11 volumio volumio[1006]: info: CoreStateMachine::syncState stateService stop Apr 12 12:14:11 volumio volumio[1006]: info: CoreStateMachine::syncState currentStatus stop Apr 12 12:14:11 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:14:11 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:14:11 volumio volumio[1006]: info: No code Apr 12 12:14:11 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:14:11 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:14:11 volumio volumio[1006]: info: ControllerMpd::pushState Apr 12 12:14:11 volumio volumio[1006]: info: CoreCommandRouter::servicePushState Apr 12 12:14:11 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:14:11 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:14:11 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:14:11 volumio volumio[1006]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"lradio256chelyabinsk","artist":null,"album":null,"uri":"https://air.unmixed.ru/lradio256chelyabinsk","trackType":""} Apr 12 12:14:11 volumio volumio[1006]: verbose: CURRENT POSITION 0 Apr 12 12:14:11 volumio volumio[1006]: info: CoreStateMachine::syncState stateService stop Apr 12 12:14:11 volumio volumio[1006]: info: CoreStateMachine::syncState currentStatus stop Apr 12 12:14:11 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:14:11 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:14:11 volumio volumio[1006]: info: No code Apr 12 12:14:11 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:14:11 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:14:11 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:11.596+05:00 level=WARN msg="received unknown player status" component=volumio status="" Apr 12 12:14:11 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:11.596+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_STOPPED positionMs=50 volume=100 Apr 12 12:14:11 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:11.597+05:00 level=WARN msg="received unknown player status" component=volumio status="" Apr 12 12:14:11 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:11.597+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_STOPPED positionMs=50 volume=100 Apr 12 12:14:11 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:11.598+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id= title= Apr 12 12:14:11 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:11.599+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id= title= Apr 12 12:14:11 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:11.601+05:00 level=WARN msg="received unknown player status" component=volumio status="" Apr 12 12:14:11 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:11.601+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_STOPPED positionMs=50 volume=100 Apr 12 12:14:11 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:11.601+05:00 level=WARN msg="received unknown player status" component=volumio status="" Apr 12 12:14:11 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:11.602+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_STOPPED positionMs=50 volume=100 Apr 12 12:14:11 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:11.602+05:00 level=WARN msg="received unknown player status" component=volumio status="" Apr 12 12:14:11 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:11.602+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_STOPPED positionMs=50 volume=100 Apr 12 12:14:11 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:11.603+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id= title= Apr 12 12:14:11 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:11.604+05:00 level=WARN msg="received unknown player status" component=volumio status="" Apr 12 12:14:11 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:11.604+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_STOPPED positionMs=50 volume=100 Apr 12 12:14:11 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:11.604+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id= title= Apr 12 12:14:11 volumio volumio[1006]: info: ------------------------------ 101ms Apr 12 12:14:11 volumio volumio[1006]: info: ------------------------------ 100ms Apr 12 12:14:11 volumio volumio[1006]: info: sendMpdCommand playlistinfo took 88 milliseconds Apr 12 12:14:11 volumio volumio[1006]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:14:11 volumio volumio[1006]: info: ControllerMpd::pushState Apr 12 12:14:11 volumio volumio[1006]: info: CoreCommandRouter::servicePushState Apr 12 12:14:11 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:14:11 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:14:11 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:14:11 volumio volumio[1006]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"lradio256chelyabinsk","artist":null,"album":null,"uri":"https://air.unmixed.ru/lradio256chelyabinsk","trackType":""} Apr 12 12:14:11 volumio volumio[1006]: verbose: CURRENT POSITION 0 Apr 12 12:14:11 volumio volumio[1006]: info: CoreStateMachine::syncState stateService stop Apr 12 12:14:11 volumio volumio[1006]: info: CoreStateMachine::syncState currentStatus stop Apr 12 12:14:11 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:14:11 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:14:11 volumio volumio[1006]: info: No code Apr 12 12:14:11 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:14:11 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:14:11 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:11.656+05:00 level=WARN msg="received unknown player status" component=volumio status="" Apr 12 12:14:11 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:11.656+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_STOPPED positionMs=50 volume=100 Apr 12 12:14:11 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:11.657+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id= title= Apr 12 12:14:11 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:11.657+05:00 level=WARN msg="received unknown player status" component=volumio status="" Apr 12 12:14:11 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:11.657+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_STOPPED positionMs=50 volume=100 Apr 12 12:14:11 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:11.657+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id= title= Apr 12 12:14:11 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:11.658+05:00 level=WARN msg="received unknown player status" component=volumio status="" Apr 12 12:14:11 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:11.658+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_STOPPED positionMs=50 volume=100 Apr 12 12:14:11 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:11.659+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id= title= Apr 12 12:14:11 volumio volumio[1006]: info: ------------------------------ 123ms Apr 12 12:14:34 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Apr 12 12:14:34 volumio volumio[1006]: info: TuneIn handleBrowseUri: tunein/local Apr 12 12:14:34 volumio volumio[1006]: info: Preload queue cleared Apr 12 12:14:41 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Apr 12 12:14:41 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Apr 12 12:14:42 volumio volumio[1006]: info: Preload queue cleared Apr 12 12:14:42 volumio volumio[1006]: info: Preload queue cleared Apr 12 12:14:46 volumio volumio[1006]: info: Preload queue cleared Apr 12 12:14:46 volumio volumio[1006]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 12 12:14:46 volumio volumio[1006]: info: CoreStateMachine::ClearQueue Apr 12 12:14:46 volumio volumio[1006]: info: CoreStateMachine::stop Apr 12 12:14:46 volumio volumio[1006]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 12:14:46 volumio volumio[1006]: info: CorePlayQueue::clearPlayQueue Apr 12 12:14:46 volumio volumio[1006]: info: CorePlayQueue::saveQueue Apr 12 12:14:46 volumio volumio[1006]: info: CoreCommandRouter::volumioPushQueue Apr 12 12:14:46 volumio volumio[1006]: info: CoreStateMachine::addQueueItems Apr 12 12:14:46 volumio volumio[1006]: info: CorePlayQueue::addQueueItems Apr 12 12:14:46 volumio volumio[1006]: info: Preload queue cleared Apr 12 12:14:46 volumio volumio[1006]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996 Apr 12 12:14:46 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 12 12:14:46 volumio volumio[1006]: info: CoreCommandRouter::volumioPushQueue Apr 12 12:14:46 volumio volumio[1006]: info: CorePlayQueue::saveQueue Apr 12 12:14:46 volumio volumio[1006]: info: CoreStateMachine::updateTrackBlock Apr 12 12:14:46 volumio volumio[1006]: info: CorePlayQueue::getTrackBlock Apr 12 12:14:46 volumio volumio[1006]: info: CoreCommandRouter::volumioPlay Apr 12 12:14:46 volumio volumio[1006]: info: CoreStateMachine::play index 0 Apr 12 12:14:46 volumio volumio[1006]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 12:14:46 volumio volumio[1006]: info: CoreStateMachine::stop Apr 12 12:14:46 volumio volumio[1006]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 12:14:46 volumio volumio[1006]: info: CoreStateMachine::play index undefined Apr 12 12:14:46 volumio volumio[1006]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 12:14:46 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:14:46 volumio volumio[1006]: info: CoreStateMachine::startPlaybackTimer Apr 12 12:14:46 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:14:46 volumio volumio[1006]: info: [1775978086367] ControllerWebradio::clearAddPlayTrack Apr 12 12:14:46 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand stop Apr 12 12:14:46 volumio volumio[1006]: info: sendMpdCommand stop took 2 milliseconds Apr 12 12:14:46 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand clear Apr 12 12:14:46 volumio volumio[1006]: info: Apr 12 12:14:46 volumio volumio[1006]: ---------------------------- MPD announces system playlist update Apr 12 12:14:46 volumio volumio[1006]: info: Ignoring MPD Status Update Apr 12 12:14:46 volumio volumio[1006]: info: sendMpdCommand clear took 1 milliseconds Apr 12 12:14:46 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996" Apr 12 12:14:46 volumio volumio[1006]: info: Apr 12 12:14:46 volumio volumio[1006]: ---------------------------- MPD announces system playlist update Apr 12 12:14:46 volumio volumio[1006]: info: Ignoring MPD Status Update Apr 12 12:14:46 volumio volumio[1006]: info: Apr 12 12:14:46 volumio volumio[1006]: ---------------------------- MPD announces system playlist update Apr 12 12:14:46 volumio volumio[1006]: info: Ignoring MPD Status Update Apr 12 12:14:46 volumio volumio[1006]: error: updateQueue error: null Apr 12 12:14:46 volumio volumio[1006]: info: ------------------------------ 3ms Apr 12 12:14:47 volumio volumio[1006]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996" took 1249 milliseconds Apr 12 12:14:47 volumio volumio[1006]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 12 12:14:47 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand play Apr 12 12:14:47 volumio volumio[1006]: info: Apr 12 12:14:47 volumio volumio[1006]: ---------------------------- MPD announces system playlist update Apr 12 12:14:47 volumio volumio[1006]: info: Ignoring MPD Status Update Apr 12 12:14:47 volumio volumio[1006]: info: ------------------------------ 1251ms Apr 12 12:14:47 volumio volumio[1006]: info: ------------------------------ 1250ms Apr 12 12:14:47 volumio volumio[1006]: info: Apr 12 12:14:47 volumio volumio[1006]: ---------------------------- MPD announces system playlist update Apr 12 12:14:47 volumio volumio[1006]: info: Ignoring MPD Status Update Apr 12 12:14:47 volumio volumio[1006]: info: Apr 12 12:14:47 volumio volumio[1006]: ---------------------------- MPD announces system playlist update Apr 12 12:14:47 volumio volumio[1006]: info: Ignoring MPD Status Update Apr 12 12:14:47 volumio volumio[1006]: info: sendMpdCommand play took 4 milliseconds Apr 12 12:14:47 volumio volumio[1006]: info: ------------------------------ 5ms Apr 12 12:14:47 volumio volumio[1006]: info: ------------------------------ 3ms Apr 12 12:14:47 volumio volumio[1006]: info: ------------------------------ 2ms Apr 12 12:14:49 volumio volumio[1006]: info: Apr 12 12:14:49 volumio volumio[1006]: ---------------------------- MPD announces state update: player Apr 12 12:14:49 volumio volumio[1006]: info: ControllerMpd::getState Apr 12 12:14:49 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:14:49 volumio volumio[1006]: info: Apr 12 12:14:49 volumio volumio[1006]: ---------------------------- MPD announces state update: player Apr 12 12:14:49 volumio volumio[1006]: info: ControllerMpd::getState Apr 12 12:14:49 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:14:49 volumio volumio[1006]: info: Apr 12 12:14:49 volumio volumio[1006]: ---------------------------- MPD announces state update: player Apr 12 12:14:49 volumio volumio[1006]: info: ControllerMpd::getState Apr 12 12:14:49 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:14:49 volumio volumio[1006]: info: Apr 12 12:14:49 volumio volumio[1006]: ---------------------------- MPD announces state update: player Apr 12 12:14:49 volumio volumio[1006]: info: ControllerMpd::getState Apr 12 12:14:49 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:14:49 volumio volumio[1006]: info: sendMpdCommand status took 4 milliseconds Apr 12 12:14:49 volumio volumio[1006]: info: sendMpdCommand status took 3 milliseconds Apr 12 12:14:49 volumio volumio[1006]: info: sendMpdCommand status took 2 milliseconds Apr 12 12:14:49 volumio volumio[1006]: verbose: ControllerMpd::parseState Apr 12 12:14:49 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:14:49 volumio volumio[1006]: verbose: ControllerMpd::parseState Apr 12 12:14:49 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:14:49 volumio volumio[1006]: verbose: ControllerMpd::parseState Apr 12 12:14:49 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:14:49 volumio volumio[1006]: info: Apr 12 12:14:49 volumio volumio[1006]: ---------------------------- MPD announces state update: player Apr 12 12:14:49 volumio volumio[1006]: info: ControllerMpd::getState Apr 12 12:14:49 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:14:49 volumio volumio[1006]: info: Apr 12 12:14:49 volumio volumio[1006]: ---------------------------- MPD announces state update: player Apr 12 12:14:49 volumio volumio[1006]: info: ControllerMpd::getState Apr 12 12:14:49 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:14:49 volumio volumio[1006]: info: sendMpdCommand status took 5 milliseconds Apr 12 12:14:49 volumio volumio[1006]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 12 12:14:49 volumio volumio[1006]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 12 12:14:49 volumio volumio[1006]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 12 12:14:49 volumio volumio[1006]: info: sendMpdCommand status took 3 milliseconds Apr 12 12:14:49 volumio volumio[1006]: info: sendMpdCommand status took 2 milliseconds Apr 12 12:14:49 volumio volumio[1006]: verbose: ControllerMpd::parseState Apr 12 12:14:49 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:14:49 volumio volumio[1006]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:14:49 volumio volumio[1006]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:14:49 volumio volumio[1006]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:14:49 volumio volumio[1006]: verbose: ControllerMpd::parseState Apr 12 12:14:49 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:14:49 volumio volumio[1006]: verbose: ControllerMpd::parseState Apr 12 12:14:49 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:14:49 volumio volumio[1006]: info: ControllerMpd::pushState Apr 12 12:14:49 volumio volumio[1006]: info: CoreCommandRouter::servicePushState Apr 12 12:14:49 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:14:49 volumio volumio[1006]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"antenne","artist":"(#1 - 4908/500000) ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Apr 12 12:14:49 volumio volumio[1006]: verbose: CURRENT POSITION 0 Apr 12 12:14:49 volumio volumio[1006]: info: CoreStateMachine::syncState stateService play Apr 12 12:14:49 volumio volumio[1006]: info: CoreStateMachine::syncState currentStatus stop Apr 12 12:14:49 volumio volumio[1006]: info: ControllerMpd::pushState Apr 12 12:14:49 volumio volumio[1006]: info: CoreCommandRouter::servicePushState Apr 12 12:14:49 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:14:49 volumio volumio[1006]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"antenne","artist":"(#1 - 4908/500000) ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Apr 12 12:14:49 volumio volumio[1006]: verbose: CURRENT POSITION 0 Apr 12 12:14:49 volumio volumio[1006]: info: CoreStateMachine::syncState stateService play Apr 12 12:14:49 volumio volumio[1006]: info: CoreStateMachine::syncState currentStatus play Apr 12 12:14:49 volumio volumio[1006]: info: Received an update from plugin. extracting info from payload Apr 12 12:14:49 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 12 12:14:49 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:14:49 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:14:49 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 12:14:49 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:14:49 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:14:49 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:14:49 volumio volumio[1006]: info: ControllerMpd::pushState Apr 12 12:14:49 volumio volumio[1006]: info: CoreCommandRouter::servicePushState Apr 12 12:14:49 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:14:49 volumio volumio[1006]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"antenne","artist":"(#1 - 4908/500000) ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Apr 12 12:14:49 volumio volumio[1006]: verbose: CURRENT POSITION 0 Apr 12 12:14:49 volumio volumio[1006]: info: CoreStateMachine::syncState stateService play Apr 12 12:14:49 volumio volumio[1006]: info: CoreStateMachine::syncState currentStatus play Apr 12 12:14:49 volumio volumio[1006]: info: Received an update from plugin. extracting info from payload Apr 12 12:14:49 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 12 12:14:49 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:14:49 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:14:49 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:14:49 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:14:49 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:14:49 volumio volumio[1006]: info: ------------------------------ 28ms Apr 12 12:14:49 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:49.990+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_PLAYING positionMs=0 volume=100 Apr 12 12:14:49 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:49.991+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_PLAYING positionMs=0 volume=100 Apr 12 12:14:49 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:49.991+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id=http://stream.antenne.de:80/antenne title="ANTENNE BAYERN" Apr 12 12:14:49 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:49.992+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_PLAYING positionMs=0 volume=100 Apr 12 12:14:49 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:49.992+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id=http://stream.antenne.de:80/antenne title="ANTENNE BAYERN" Apr 12 12:14:49 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:49.993+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_PLAYING positionMs=0 volume=100 Apr 12 12:14:49 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:49.993+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id=http://stream.antenne.de:80/antenne title="ANTENNE BAYERN" Apr 12 12:14:50 volumio volumio[1006]: info: ------------------------------ 46ms Apr 12 12:14:50 volumio volumio[1006]: info: ------------------------------ 45ms Apr 12 12:14:50 volumio volumio[1006]: info: sendMpdCommand playlistinfo took 46 milliseconds Apr 12 12:14:50 volumio volumio[1006]: info: sendMpdCommand playlistinfo took 45 milliseconds Apr 12 12:14:50 volumio volumio[1006]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:14:50 volumio volumio[1006]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:14:50 volumio volumio[1006]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:14:50 volumio volumio[1006]: info: ControllerMpd::pushState Apr 12 12:14:50 volumio volumio[1006]: info: CoreCommandRouter::servicePushState Apr 12 12:14:50 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:14:50 volumio volumio[1006]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"antenne","artist":"(#1 - 4908/500000) ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Apr 12 12:14:50 volumio volumio[1006]: verbose: CURRENT POSITION 0 Apr 12 12:14:50 volumio volumio[1006]: info: CoreStateMachine::syncState stateService play Apr 12 12:14:50 volumio volumio[1006]: info: CoreStateMachine::syncState currentStatus play Apr 12 12:14:50 volumio volumio[1006]: info: Received an update from plugin. extracting info from payload Apr 12 12:14:50 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 12 12:14:50 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:14:50 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:14:50 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:14:50 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:14:50 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:14:50 volumio volumio[1006]: info: ControllerMpd::pushState Apr 12 12:14:50 volumio volumio[1006]: info: CoreCommandRouter::servicePushState Apr 12 12:14:50 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:14:50 volumio volumio[1006]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"antenne","artist":"(#1 - 4908/500000) ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Apr 12 12:14:50 volumio volumio[1006]: verbose: CURRENT POSITION 0 Apr 12 12:14:50 volumio volumio[1006]: info: CoreStateMachine::syncState stateService play Apr 12 12:14:50 volumio volumio[1006]: info: CoreStateMachine::syncState currentStatus play Apr 12 12:14:50 volumio volumio[1006]: info: Received an update from plugin. extracting info from payload Apr 12 12:14:50 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 12 12:14:50 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:14:50 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:14:50 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:14:50 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:14:50 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:14:50 volumio volumio[1006]: info: ControllerMpd::pushState Apr 12 12:14:50 volumio volumio[1006]: info: CoreCommandRouter::servicePushState Apr 12 12:14:50 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:14:50 volumio volumio[1006]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"antenne","artist":"(#1 - 4908/500000) ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Apr 12 12:14:50 volumio volumio[1006]: verbose: CURRENT POSITION 0 Apr 12 12:14:50 volumio volumio[1006]: info: CoreStateMachine::syncState stateService play Apr 12 12:14:50 volumio volumio[1006]: info: CoreStateMachine::syncState currentStatus play Apr 12 12:14:50 volumio volumio[1006]: info: Received an update from plugin. extracting info from payload Apr 12 12:14:50 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 12 12:14:50 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:14:50 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:14:50 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:14:50 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:14:50 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:14:50 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:50.065+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_PLAYING positionMs=0 volume=100 Apr 12 12:14:50 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:50.067+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_PLAYING positionMs=0 volume=100 Apr 12 12:14:50 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:50.068+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id=http://stream.antenne.de:80/antenne title="ANTENNE BAYERN" Apr 12 12:14:50 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:50.069+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_PLAYING positionMs=0 volume=100 Apr 12 12:14:50 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:50.069+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id=http://stream.antenne.de:80/antenne title="ANTENNE BAYERN" Apr 12 12:14:50 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:50.071+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_PLAYING positionMs=0 volume=100 Apr 12 12:14:50 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:50.072+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id=http://stream.antenne.de:80/antenne title="ANTENNE BAYERN" Apr 12 12:14:50 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:50.074+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_PLAYING positionMs=0 volume=100 Apr 12 12:14:50 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:50.075+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_PLAYING positionMs=0 volume=100 Apr 12 12:14:50 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:50.076+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id=http://stream.antenne.de:80/antenne title="ANTENNE BAYERN" Apr 12 12:14:50 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:50.076+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id=http://stream.antenne.de:80/antenne title="ANTENNE BAYERN" Apr 12 12:14:50 volumio volumio[1006]: info: ------------------------------ 145ms Apr 12 12:14:50 volumio volumio[1006]: info: ------------------------------ 143ms Apr 12 12:14:50 volumio volumio[1006]: info: ------------------------------ 143ms Apr 12 12:14:50 volumio volumio[1006]: info: Apr 12 12:14:50 volumio volumio[1006]: ---------------------------- MPD announces system playlist update Apr 12 12:14:50 volumio volumio[1006]: info: Ignoring MPD Status Update Apr 12 12:14:50 volumio volumio[1006]: info: Apr 12 12:14:50 volumio volumio[1006]: ---------------------------- MPD announces state update: player Apr 12 12:14:50 volumio volumio[1006]: info: ControllerMpd::getState Apr 12 12:14:50 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:14:50 volumio volumio[1006]: info: Apr 12 12:14:50 volumio volumio[1006]: ---------------------------- MPD announces system playlist update Apr 12 12:14:50 volumio volumio[1006]: info: Ignoring MPD Status Update Apr 12 12:14:50 volumio volumio[1006]: info: Apr 12 12:14:50 volumio volumio[1006]: ---------------------------- MPD announces state update: player Apr 12 12:14:50 volumio volumio[1006]: info: ControllerMpd::getState Apr 12 12:14:50 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:14:50 volumio volumio[1006]: info: Apr 12 12:14:50 volumio volumio[1006]: ---------------------------- MPD announces system playlist update Apr 12 12:14:50 volumio volumio[1006]: info: Ignoring MPD Status Update Apr 12 12:14:50 volumio volumio[1006]: info: Apr 12 12:14:50 volumio volumio[1006]: ---------------------------- MPD announces state update: player Apr 12 12:14:50 volumio volumio[1006]: info: ControllerMpd::getState Apr 12 12:14:50 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:14:50 volumio volumio[1006]: info: Apr 12 12:14:50 volumio volumio[1006]: ---------------------------- MPD announces system playlist update Apr 12 12:14:50 volumio volumio[1006]: info: Ignoring MPD Status Update Apr 12 12:14:50 volumio volumio[1006]: info: Apr 12 12:14:50 volumio volumio[1006]: ---------------------------- MPD announces state update: player Apr 12 12:14:50 volumio volumio[1006]: info: ControllerMpd::getState Apr 12 12:14:50 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:14:50 volumio volumio[1006]: info: ------------------------------ 11ms Apr 12 12:14:50 volumio volumio[1006]: info: sendMpdCommand status took 9 milliseconds Apr 12 12:14:50 volumio volumio[1006]: info: ------------------------------ 6ms Apr 12 12:14:50 volumio volumio[1006]: info: sendMpdCommand status took 5 milliseconds Apr 12 12:14:50 volumio volumio[1006]: info: ------------------------------ 5ms Apr 12 12:14:50 volumio volumio[1006]: info: sendMpdCommand status took 3 milliseconds Apr 12 12:14:50 volumio volumio[1006]: verbose: ControllerMpd::parseState Apr 12 12:14:50 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:14:50 volumio volumio[1006]: verbose: ControllerMpd::parseState Apr 12 12:14:50 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:14:50 volumio volumio[1006]: verbose: ControllerMpd::parseState Apr 12 12:14:50 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:14:50 volumio volumio[1006]: info: Apr 12 12:14:50 volumio volumio[1006]: ---------------------------- MPD announces system playlist update Apr 12 12:14:50 volumio volumio[1006]: info: Ignoring MPD Status Update Apr 12 12:14:50 volumio volumio[1006]: info: Apr 12 12:14:50 volumio volumio[1006]: ---------------------------- MPD announces state update: player Apr 12 12:14:50 volumio volumio[1006]: info: ControllerMpd::getState Apr 12 12:14:50 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:14:50 volumio volumio[1006]: info: Apr 12 12:14:50 volumio volumio[1006]: ---------------------------- MPD announces system playlist update Apr 12 12:14:50 volumio volumio[1006]: info: Ignoring MPD Status Update Apr 12 12:14:50 volumio volumio[1006]: info: Apr 12 12:14:50 volumio volumio[1006]: ---------------------------- MPD announces state update: player Apr 12 12:14:50 volumio volumio[1006]: info: ControllerMpd::getState Apr 12 12:14:50 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:14:50 volumio volumio[1006]: info: ------------------------------ 15ms Apr 12 12:14:50 volumio volumio[1006]: info: sendMpdCommand status took 13 milliseconds Apr 12 12:14:50 volumio volumio[1006]: info: sendMpdCommand playlistinfo took 12 milliseconds Apr 12 12:14:50 volumio volumio[1006]: info: sendMpdCommand playlistinfo took 12 milliseconds Apr 12 12:14:50 volumio volumio[1006]: info: sendMpdCommand playlistinfo took 12 milliseconds Apr 12 12:14:50 volumio volumio[1006]: info: ------------------------------ 12ms Apr 12 12:14:50 volumio volumio[1006]: info: sendMpdCommand status took 11 milliseconds Apr 12 12:14:50 volumio volumio[1006]: info: ------------------------------ 10ms Apr 12 12:14:50 volumio volumio[1006]: info: sendMpdCommand status took 9 milliseconds Apr 12 12:14:50 volumio volumio[1006]: verbose: ControllerMpd::parseState Apr 12 12:14:50 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:14:50 volumio volumio[1006]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:14:50 volumio volumio[1006]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:14:50 volumio volumio[1006]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:14:50 volumio volumio[1006]: verbose: ControllerMpd::parseState Apr 12 12:14:50 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:14:50 volumio volumio[1006]: verbose: ControllerMpd::parseState Apr 12 12:14:50 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:14:50 volumio volumio[1006]: info: ControllerMpd::pushState Apr 12 12:14:50 volumio volumio[1006]: info: CoreCommandRouter::servicePushState Apr 12 12:14:50 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:14:50 volumio volumio[1006]: verbose: STATE SERVICE {"status":"play","position":0,"seek":852,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Red Hot Chili Peppers - Otherside","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Apr 12 12:14:50 volumio volumio[1006]: verbose: CURRENT POSITION 0 Apr 12 12:14:50 volumio volumio[1006]: info: CoreStateMachine::syncState stateService play Apr 12 12:14:50 volumio volumio[1006]: info: CoreStateMachine::syncState currentStatus play Apr 12 12:14:50 volumio volumio[1006]: info: Received an update from plugin. extracting info from payload Apr 12 12:14:50 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 12 12:14:50 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:14:50 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 12:14:50 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:14:50 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:14:50 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:14:50 volumio volumio[1006]: info: ControllerMpd::pushState Apr 12 12:14:50 volumio volumio[1006]: info: CoreCommandRouter::servicePushState Apr 12 12:14:50 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:14:50 volumio volumio[1006]: verbose: STATE SERVICE {"status":"play","position":0,"seek":852,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Red Hot Chili Peppers - Otherside","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Apr 12 12:14:50 volumio volumio[1006]: verbose: CURRENT POSITION 0 Apr 12 12:14:50 volumio volumio[1006]: info: CoreStateMachine::syncState stateService play Apr 12 12:14:50 volumio volumio[1006]: info: CoreStateMachine::syncState currentStatus play Apr 12 12:14:50 volumio volumio[1006]: info: Received an update from plugin. extracting info from payload Apr 12 12:14:50 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 12 12:14:50 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:14:50 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:14:50 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:14:50 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:14:50 volumio volumio[1006]: info: ControllerMpd::pushState Apr 12 12:14:50 volumio volumio[1006]: info: CoreCommandRouter::servicePushState Apr 12 12:14:50 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:14:50 volumio volumio[1006]: verbose: STATE SERVICE {"status":"play","position":0,"seek":852,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Red Hot Chili Peppers - Otherside","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Apr 12 12:14:50 volumio volumio[1006]: verbose: CURRENT POSITION 0 Apr 12 12:14:50 volumio volumio[1006]: info: CoreStateMachine::syncState stateService play Apr 12 12:14:50 volumio volumio[1006]: info: CoreStateMachine::syncState currentStatus play Apr 12 12:14:50 volumio volumio[1006]: info: Received an update from plugin. extracting info from payload Apr 12 12:14:50 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 12 12:14:50 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:14:50 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:14:50 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:14:50 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:14:50 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:50.188+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_PLAYING positionMs=207 volume=100 Apr 12 12:14:50 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:50.188+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id=http://stream.antenne.de:80/antenne title="Red Hot Chili Peppers - Otherside" Apr 12 12:14:50 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:50.190+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_PLAYING positionMs=207 volume=100 Apr 12 12:14:50 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:50.190+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id=http://stream.antenne.de:80/antenne title="Red Hot Chili Peppers - Otherside" Apr 12 12:14:50 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:50.190+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_PLAYING positionMs=207 volume=100 Apr 12 12:14:50 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:50.190+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id=http://stream.antenne.de:80/antenne title="Red Hot Chili Peppers - Otherside" Apr 12 12:14:50 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:50.191+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_PLAYING positionMs=207 volume=100 Apr 12 12:14:50 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:50.191+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id=http://stream.antenne.de:80/antenne title="Red Hot Chili Peppers - Otherside" Apr 12 12:14:50 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:50.192+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_PLAYING positionMs=207 volume=100 Apr 12 12:14:50 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:50.192+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id=http://stream.antenne.de:80/antenne title="Red Hot Chili Peppers - Otherside" Apr 12 12:14:50 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:50.193+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_PLAYING positionMs=207 volume=100 Apr 12 12:14:50 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:50.193+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id=http://stream.antenne.de:80/antenne title="Red Hot Chili Peppers - Otherside" Apr 12 12:14:50 volumio volumio[1006]: info: ------------------------------ 88ms Apr 12 12:14:50 volumio volumio[1006]: info: ------------------------------ 84ms Apr 12 12:14:50 volumio volumio[1006]: info: ------------------------------ 82ms Apr 12 12:14:50 volumio volumio[1006]: info: sendMpdCommand playlistinfo took 84 milliseconds Apr 12 12:14:50 volumio volumio[1006]: info: sendMpdCommand playlistinfo took 83 milliseconds Apr 12 12:14:50 volumio volumio[1006]: info: sendMpdCommand playlistinfo took 83 milliseconds Apr 12 12:14:50 volumio volumio[1006]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:14:50 volumio volumio[1006]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:14:50 volumio volumio[1006]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:14:50 volumio volumio[1006]: info: ControllerMpd::pushState Apr 12 12:14:50 volumio volumio[1006]: info: CoreCommandRouter::servicePushState Apr 12 12:14:50 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:14:50 volumio volumio[1006]: verbose: STATE SERVICE {"status":"play","position":0,"seek":852,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Red Hot Chili Peppers - Otherside","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Apr 12 12:14:50 volumio volumio[1006]: verbose: CURRENT POSITION 0 Apr 12 12:14:50 volumio volumio[1006]: info: CoreStateMachine::syncState stateService play Apr 12 12:14:50 volumio volumio[1006]: info: CoreStateMachine::syncState currentStatus play Apr 12 12:14:50 volumio volumio[1006]: info: Received an update from plugin. extracting info from payload Apr 12 12:14:50 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 12 12:14:50 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:14:50 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 12:14:50 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:14:50 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:14:50 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:14:50 volumio volumio[1006]: info: ControllerMpd::pushState Apr 12 12:14:50 volumio volumio[1006]: info: CoreCommandRouter::servicePushState Apr 12 12:14:50 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:14:50 volumio volumio[1006]: verbose: STATE SERVICE {"status":"play","position":0,"seek":852,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Red Hot Chili Peppers - Otherside","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Apr 12 12:14:50 volumio volumio[1006]: verbose: CURRENT POSITION 0 Apr 12 12:14:50 volumio volumio[1006]: info: CoreStateMachine::syncState stateService play Apr 12 12:14:50 volumio volumio[1006]: info: CoreStateMachine::syncState currentStatus play Apr 12 12:14:50 volumio volumio[1006]: info: Received an update from plugin. extracting info from payload Apr 12 12:14:50 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 12 12:14:50 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:14:50 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:14:50 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:14:50 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:14:50 volumio volumio[1006]: info: ControllerMpd::pushState Apr 12 12:14:50 volumio volumio[1006]: info: CoreCommandRouter::servicePushState Apr 12 12:14:50 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:14:50 volumio volumio[1006]: verbose: STATE SERVICE {"status":"play","position":0,"seek":852,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Red Hot Chili Peppers - Otherside","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Apr 12 12:14:50 volumio volumio[1006]: verbose: CURRENT POSITION 0 Apr 12 12:14:50 volumio volumio[1006]: info: CoreStateMachine::syncState stateService play Apr 12 12:14:50 volumio volumio[1006]: info: CoreStateMachine::syncState currentStatus play Apr 12 12:14:50 volumio volumio[1006]: info: Received an update from plugin. extracting info from payload Apr 12 12:14:50 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 12 12:14:50 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:14:50 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:14:50 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:14:50 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:14:50 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:50.267+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_PLAYING positionMs=317 volume=100 Apr 12 12:14:50 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:50.267+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id=http://stream.antenne.de:80/antenne title="Red Hot Chili Peppers - Otherside" Apr 12 12:14:50 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:50.268+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_PLAYING positionMs=317 volume=100 Apr 12 12:14:50 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:50.268+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id=http://stream.antenne.de:80/antenne title="Red Hot Chili Peppers - Otherside" Apr 12 12:14:50 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:50.269+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_PLAYING positionMs=317 volume=100 Apr 12 12:14:50 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:50.269+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id=http://stream.antenne.de:80/antenne title="Red Hot Chili Peppers - Otherside" Apr 12 12:14:50 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:50.270+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_PLAYING positionMs=317 volume=100 Apr 12 12:14:50 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:50.270+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id=http://stream.antenne.de:80/antenne title="Red Hot Chili Peppers - Otherside" Apr 12 12:14:50 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:50.271+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_PLAYING positionMs=317 volume=100 Apr 12 12:14:50 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:50.271+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id=http://stream.antenne.de:80/antenne title="Red Hot Chili Peppers - Otherside" Apr 12 12:14:50 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:50.272+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_PLAYING positionMs=317 volume=100 Apr 12 12:14:50 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:50.272+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id=http://stream.antenne.de:80/antenne title="Red Hot Chili Peppers - Otherside" Apr 12 12:14:50 volumio volumio[1006]: info: ------------------------------ 156ms Apr 12 12:14:50 volumio volumio[1006]: info: ------------------------------ 153ms Apr 12 12:14:50 volumio volumio[1006]: info: ------------------------------ 151ms Apr 12 12:14:51 volumio volumio[1006]: info: Apr 12 12:14:51 volumio volumio[1006]: ---------------------------- MPD announces system playlist update Apr 12 12:14:51 volumio volumio[1006]: info: Ignoring MPD Status Update Apr 12 12:14:51 volumio volumio[1006]: info: Apr 12 12:14:51 volumio volumio[1006]: ---------------------------- MPD announces state update: player Apr 12 12:14:51 volumio volumio[1006]: info: ControllerMpd::getState Apr 12 12:14:51 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:14:51 volumio volumio[1006]: info: Apr 12 12:14:51 volumio volumio[1006]: ---------------------------- MPD announces system playlist update Apr 12 12:14:51 volumio volumio[1006]: info: Ignoring MPD Status Update Apr 12 12:14:51 volumio volumio[1006]: info: Apr 12 12:14:51 volumio volumio[1006]: ---------------------------- MPD announces state update: player Apr 12 12:14:51 volumio volumio[1006]: info: ControllerMpd::getState Apr 12 12:14:51 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:14:51 volumio volumio[1006]: info: Apr 12 12:14:51 volumio volumio[1006]: ---------------------------- MPD announces system playlist update Apr 12 12:14:51 volumio volumio[1006]: info: Ignoring MPD Status Update Apr 12 12:14:51 volumio volumio[1006]: info: Apr 12 12:14:51 volumio volumio[1006]: ---------------------------- MPD announces state update: player Apr 12 12:14:51 volumio volumio[1006]: info: ControllerMpd::getState Apr 12 12:14:51 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:14:51 volumio volumio[1006]: info: ------------------------------ 7ms Apr 12 12:14:51 volumio volumio[1006]: info: sendMpdCommand status took 7 milliseconds Apr 12 12:14:51 volumio volumio[1006]: info: ------------------------------ 6ms Apr 12 12:14:51 volumio volumio[1006]: verbose: ControllerMpd::parseState Apr 12 12:14:51 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:14:51 volumio volumio[1006]: info: sendMpdCommand status took 5 milliseconds Apr 12 12:14:51 volumio volumio[1006]: verbose: ControllerMpd::parseState Apr 12 12:14:51 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:14:51 volumio volumio[1006]: info: ------------------------------ 6ms Apr 12 12:14:51 volumio volumio[1006]: info: sendMpdCommand status took 5 milliseconds Apr 12 12:14:51 volumio volumio[1006]: verbose: ControllerMpd::parseState Apr 12 12:14:51 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:14:51 volumio volumio[1006]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 12 12:14:51 volumio volumio[1006]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 12 12:14:51 volumio volumio[1006]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 12 12:14:51 volumio volumio[1006]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:14:51 volumio volumio[1006]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:14:51 volumio volumio[1006]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:14:51 volumio volumio[1006]: info: ControllerMpd::pushState Apr 12 12:14:51 volumio volumio[1006]: info: CoreCommandRouter::servicePushState Apr 12 12:14:51 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:14:51 volumio volumio[1006]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2109,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Red Hot Chili Peppers - Otherside","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Apr 12 12:14:51 volumio volumio[1006]: verbose: CURRENT POSITION 0 Apr 12 12:14:51 volumio volumio[1006]: info: CoreStateMachine::syncState stateService play Apr 12 12:14:51 volumio volumio[1006]: info: CoreStateMachine::syncState currentStatus play Apr 12 12:14:51 volumio volumio[1006]: info: Received an update from plugin. extracting info from payload Apr 12 12:14:51 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 12 12:14:51 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:14:51 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 12:14:51 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:14:51 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:14:51 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:14:51 volumio volumio[1006]: info: ControllerMpd::pushState Apr 12 12:14:51 volumio volumio[1006]: info: CoreCommandRouter::servicePushState Apr 12 12:14:51 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:14:51 volumio volumio[1006]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2109,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Red Hot Chili Peppers - Otherside","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Apr 12 12:14:51 volumio volumio[1006]: verbose: CURRENT POSITION 0 Apr 12 12:14:51 volumio volumio[1006]: info: CoreStateMachine::syncState stateService play Apr 12 12:14:51 volumio volumio[1006]: info: CoreStateMachine::syncState currentStatus play Apr 12 12:14:51 volumio volumio[1006]: info: Received an update from plugin. extracting info from payload Apr 12 12:14:51 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 12 12:14:51 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:14:51 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:14:51 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:14:51 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:14:51 volumio volumio[1006]: info: ControllerMpd::pushState Apr 12 12:14:51 volumio volumio[1006]: info: CoreCommandRouter::servicePushState Apr 12 12:14:51 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:14:51 volumio volumio[1006]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2109,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Red Hot Chili Peppers - Otherside","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Apr 12 12:14:51 volumio volumio[1006]: verbose: CURRENT POSITION 0 Apr 12 12:14:51 volumio volumio[1006]: info: CoreStateMachine::syncState stateService play Apr 12 12:14:51 volumio volumio[1006]: info: CoreStateMachine::syncState currentStatus play Apr 12 12:14:51 volumio volumio[1006]: info: Received an update from plugin. extracting info from payload Apr 12 12:14:51 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 12 12:14:51 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:14:51 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:14:51 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:14:51 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:14:51 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:51.342+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_PLAYING positionMs=1321 volume=100 Apr 12 12:14:51 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:51.342+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id=http://stream.antenne.de:80/antenne title="Red Hot Chili Peppers - Otherside" Apr 12 12:14:51 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:51.343+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_PLAYING positionMs=1321 volume=100 Apr 12 12:14:51 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:51.343+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_PLAYING positionMs=1321 volume=100 Apr 12 12:14:51 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:51.344+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id=http://stream.antenne.de:80/antenne title="Red Hot Chili Peppers - Otherside" Apr 12 12:14:51 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:51.344+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id=http://stream.antenne.de:80/antenne title="Red Hot Chili Peppers - Otherside" Apr 12 12:14:51 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:51.345+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_PLAYING positionMs=1321 volume=100 Apr 12 12:14:51 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:51.345+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id=http://stream.antenne.de:80/antenne title="Red Hot Chili Peppers - Otherside" Apr 12 12:14:51 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:51.346+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_PLAYING positionMs=1321 volume=100 Apr 12 12:14:51 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:51.346+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id=http://stream.antenne.de:80/antenne title="Red Hot Chili Peppers - Otherside" Apr 12 12:14:51 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:51.347+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_PLAYING positionMs=1321 volume=100 Apr 12 12:14:51 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:51.347+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id=http://stream.antenne.de:80/antenne title="Red Hot Chili Peppers - Otherside" Apr 12 12:14:51 volumio volumio[1006]: info: ------------------------------ 60ms Apr 12 12:14:51 volumio volumio[1006]: info: ------------------------------ 60ms Apr 12 12:14:51 volumio volumio[1006]: info: ------------------------------ 57ms Apr 12 12:14:52 volumio volumio[1006]: info: Apr 12 12:14:52 volumio volumio[1006]: ---------------------------- MPD announces system playlist update Apr 12 12:14:52 volumio volumio[1006]: info: Ignoring MPD Status Update Apr 12 12:14:52 volumio volumio[1006]: info: Apr 12 12:14:52 volumio volumio[1006]: ---------------------------- MPD announces state update: player Apr 12 12:14:52 volumio volumio[1006]: info: ControllerMpd::getState Apr 12 12:14:52 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:14:52 volumio volumio[1006]: info: Apr 12 12:14:52 volumio volumio[1006]: ---------------------------- MPD announces system playlist update Apr 12 12:14:52 volumio volumio[1006]: info: Ignoring MPD Status Update Apr 12 12:14:52 volumio volumio[1006]: info: Apr 12 12:14:52 volumio volumio[1006]: ---------------------------- MPD announces state update: player Apr 12 12:14:52 volumio volumio[1006]: info: ControllerMpd::getState Apr 12 12:14:52 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:14:52 volumio volumio[1006]: info: Apr 12 12:14:52 volumio volumio[1006]: ---------------------------- MPD announces system playlist update Apr 12 12:14:52 volumio volumio[1006]: info: Ignoring MPD Status Update Apr 12 12:14:52 volumio volumio[1006]: info: Apr 12 12:14:52 volumio volumio[1006]: ---------------------------- MPD announces state update: player Apr 12 12:14:52 volumio volumio[1006]: info: ControllerMpd::getState Apr 12 12:14:52 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:14:52 volumio volumio[1006]: info: ------------------------------ 6ms Apr 12 12:14:52 volumio volumio[1006]: info: sendMpdCommand status took 5 milliseconds Apr 12 12:14:52 volumio volumio[1006]: info: ------------------------------ 4ms Apr 12 12:14:52 volumio volumio[1006]: info: sendMpdCommand status took 4 milliseconds Apr 12 12:14:52 volumio volumio[1006]: info: ------------------------------ 3ms Apr 12 12:14:52 volumio volumio[1006]: info: sendMpdCommand status took 2 milliseconds Apr 12 12:14:52 volumio volumio[1006]: verbose: ControllerMpd::parseState Apr 12 12:14:52 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:14:52 volumio volumio[1006]: verbose: ControllerMpd::parseState Apr 12 12:14:52 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:14:52 volumio volumio[1006]: verbose: ControllerMpd::parseState Apr 12 12:14:52 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:14:52 volumio volumio[1006]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 12 12:14:52 volumio volumio[1006]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 12 12:14:52 volumio volumio[1006]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 12 12:14:52 volumio volumio[1006]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:14:52 volumio volumio[1006]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:14:52 volumio volumio[1006]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:14:52 volumio volumio[1006]: info: ControllerMpd::pushState Apr 12 12:14:52 volumio volumio[1006]: info: CoreCommandRouter::servicePushState Apr 12 12:14:52 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:14:52 volumio volumio[1006]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3477,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Red Hot Chili Peppers - Otherside","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Apr 12 12:14:52 volumio volumio[1006]: verbose: CURRENT POSITION 0 Apr 12 12:14:52 volumio volumio[1006]: info: CoreStateMachine::syncState stateService play Apr 12 12:14:52 volumio volumio[1006]: info: CoreStateMachine::syncState currentStatus play Apr 12 12:14:52 volumio volumio[1006]: info: Received an update from plugin. extracting info from payload Apr 12 12:14:52 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 12 12:14:52 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:14:52 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 12:14:52 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:14:52 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:14:52 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:14:52 volumio volumio[1006]: info: ControllerMpd::pushState Apr 12 12:14:52 volumio volumio[1006]: info: CoreCommandRouter::servicePushState Apr 12 12:14:52 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:14:52 volumio volumio[1006]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3477,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Red Hot Chili Peppers - Otherside","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Apr 12 12:14:52 volumio volumio[1006]: verbose: CURRENT POSITION 0 Apr 12 12:14:52 volumio volumio[1006]: info: CoreStateMachine::syncState stateService play Apr 12 12:14:52 volumio volumio[1006]: info: CoreStateMachine::syncState currentStatus play Apr 12 12:14:52 volumio volumio[1006]: info: Received an update from plugin. extracting info from payload Apr 12 12:14:52 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 12 12:14:52 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:14:52 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:14:52 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:14:52 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:14:52 volumio volumio[1006]: info: ControllerMpd::pushState Apr 12 12:14:52 volumio volumio[1006]: info: CoreCommandRouter::servicePushState Apr 12 12:14:52 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:14:52 volumio volumio[1006]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3477,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Red Hot Chili Peppers - Otherside","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Apr 12 12:14:52 volumio volumio[1006]: verbose: CURRENT POSITION 0 Apr 12 12:14:52 volumio volumio[1006]: info: CoreStateMachine::syncState stateService play Apr 12 12:14:52 volumio volumio[1006]: info: CoreStateMachine::syncState currentStatus play Apr 12 12:14:52 volumio volumio[1006]: info: Received an update from plugin. extracting info from payload Apr 12 12:14:52 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 12 12:14:52 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:14:52 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:14:52 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:14:52 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:14:52 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:52.725+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_PLAYING positionMs=2710 volume=100 Apr 12 12:14:52 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:52.725+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id=http://stream.antenne.de:80/antenne title="Red Hot Chili Peppers - Otherside" Apr 12 12:14:52 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:52.726+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_PLAYING positionMs=2710 volume=100 Apr 12 12:14:52 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:52.726+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id=http://stream.antenne.de:80/antenne title="Red Hot Chili Peppers - Otherside" Apr 12 12:14:52 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:52.727+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_PLAYING positionMs=2710 volume=100 Apr 12 12:14:52 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:52.727+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id=http://stream.antenne.de:80/antenne title="Red Hot Chili Peppers - Otherside" Apr 12 12:14:52 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:52.728+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_PLAYING positionMs=2710 volume=100 Apr 12 12:14:52 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:52.729+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id=http://stream.antenne.de:80/antenne title="Red Hot Chili Peppers - Otherside" Apr 12 12:14:52 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:52.729+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_PLAYING positionMs=2710 volume=100 Apr 12 12:14:52 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:52.729+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id=http://stream.antenne.de:80/antenne title="Red Hot Chili Peppers - Otherside" Apr 12 12:14:52 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:52.730+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_PLAYING positionMs=2710 volume=100 Apr 12 12:14:52 volumio volumio5-onboarding[1280]: time=2026-04-12T12:14:52.731+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id=http://stream.antenne.de:80/antenne title="Red Hot Chili Peppers - Otherside" Apr 12 12:14:52 volumio volumio[1006]: info: ------------------------------ 58ms Apr 12 12:14:52 volumio volumio[1006]: info: ------------------------------ 58ms Apr 12 12:14:52 volumio volumio[1006]: info: ------------------------------ 56ms Apr 12 12:15:18 volumio volumio[1006]: info: Preload queue cleared Apr 12 12:15:18 volumio volumio[1006]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 12 12:15:18 volumio volumio[1006]: info: CoreStateMachine::ClearQueue Apr 12 12:15:18 volumio volumio[1006]: info: CoreStateMachine::stop Apr 12 12:15:18 volumio volumio[1006]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 12:15:18 volumio volumio[1006]: info: CoreStateMachine::stPlaybackTimer Apr 12 12:15:18 volumio volumio[1006]: info: CoreStateMachine::updateTrackBlock Apr 12 12:15:18 volumio volumio[1006]: info: CorePlayQueue::getTrackBlock Apr 12 12:15:18 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:15:18 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:15:18 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 12:15:18 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:15:18 volumio volumio[1006]: info: CoreStateMachine::serviceStop Apr 12 12:15:18 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:15:18 volumio volumio[1006]: info: CoreCommandRouter::serviceStop Apr 12 12:15:18 volumio volumio[1006]: info: [1775978118816] ControllerWebradio::stop Apr 12 12:15:18 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand stop Apr 12 12:15:18 volumio volumio[1006]: info: CorePlayQueue::clearPlayQueue Apr 12 12:15:18 volumio volumio[1006]: info: CorePlayQueue::saveQueue Apr 12 12:15:18 volumio volumio[1006]: info: CoreCommandRouter::volumioPushQueue Apr 12 12:15:18 volumio volumio[1006]: info: CoreStateMachine::addQueueItems Apr 12 12:15:18 volumio volumio[1006]: info: CorePlayQueue::addQueueItems Apr 12 12:15:18 volumio volumio[1006]: info: Preload queue cleared Apr 12 12:15:18 volumio volumio[1006]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99623166 Apr 12 12:15:18 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 12 12:15:18 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:18.821+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_STOPPED positionMs=0 volume=100 Apr 12 12:15:18 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:18.822+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996" title="ANTENNE BAYERN" Apr 12 12:15:18 volumio volumio[1006]: info: CoreCommandRouter::volumioPushQueue Apr 12 12:15:18 volumio volumio[1006]: info: CorePlayQueue::saveQueue Apr 12 12:15:18 volumio volumio[1006]: info: CoreStateMachine::updateTrackBlock Apr 12 12:15:18 volumio volumio[1006]: info: CorePlayQueue::getTrackBlock Apr 12 12:15:18 volumio volumio[1006]: info: CoreCommandRouter::volumioPlay Apr 12 12:15:18 volumio volumio[1006]: info: CoreStateMachine::play index 0 Apr 12 12:15:18 volumio volumio[1006]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 12:15:18 volumio volumio[1006]: info: CoreStateMachine::stop Apr 12 12:15:18 volumio volumio[1006]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 12:15:18 volumio volumio[1006]: info: CoreStateMachine::play index undefined Apr 12 12:15:18 volumio volumio[1006]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 12:15:18 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:15:18 volumio volumio[1006]: info: CoreStateMachine::startPlaybackTimer Apr 12 12:15:18 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:15:18 volumio volumio[1006]: info: [1775978118840] ControllerWebradio::clearAddPlayTrack Apr 12 12:15:18 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand stop Apr 12 12:15:18 volumio volumio[1006]: info: Apr 12 12:15:18 volumio volumio[1006]: ---------------------------- MPD announces state update: player Apr 12 12:15:18 volumio volumio[1006]: info: sendMpdCommand stop took 51 milliseconds Apr 12 12:15:18 volumio volumio[1006]: info: ControllerMpd::getState Apr 12 12:15:18 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:15:18 volumio volumio[1006]: info: sendMpdCommand stop took 27 milliseconds Apr 12 12:15:18 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand clear Apr 12 12:15:18 volumio volumio[1006]: info: Apr 12 12:15:18 volumio volumio[1006]: ---------------------------- MPD announces state update: player Apr 12 12:15:18 volumio volumio[1006]: info: ControllerMpd::getState Apr 12 12:15:18 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:15:18 volumio volumio[1006]: info: Apr 12 12:15:18 volumio volumio[1006]: ---------------------------- MPD announces state update: player Apr 12 12:15:18 volumio volumio[1006]: info: ControllerMpd::getState Apr 12 12:15:18 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:15:18 volumio volumio[1006]: info: Apr 12 12:15:18 volumio volumio[1006]: ---------------------------- MPD announces system playlist update Apr 12 12:15:18 volumio volumio[1006]: info: Ignoring MPD Status Update Apr 12 12:15:18 volumio volumio[1006]: info: sendMpdCommand status took 9 milliseconds Apr 12 12:15:18 volumio volumio[1006]: info: sendMpdCommand clear took 8 milliseconds Apr 12 12:15:18 volumio volumio[1006]: info: sendMpdCommand status took 7 milliseconds Apr 12 12:15:18 volumio volumio[1006]: info: sendMpdCommand status took 7 milliseconds Apr 12 12:15:18 volumio volumio[1006]: verbose: ControllerMpd::parseState Apr 12 12:15:18 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:15:18 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99623166" Apr 12 12:15:18 volumio volumio[1006]: verbose: ControllerMpd::parseState Apr 12 12:15:18 volumio volumio[1006]: verbose: ControllerMpd::parseState Apr 12 12:15:18 volumio volumio[1006]: info: ControllerMpd::pushState Apr 12 12:15:18 volumio volumio[1006]: info: CoreCommandRouter::servicePushState Apr 12 12:15:18 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:15:18 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:15:18 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 12:15:18 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:15:18 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:15:18 volumio volumio[1006]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 12 12:15:18 volumio volumio[1006]: info: ControllerMpd::pushState Apr 12 12:15:18 volumio volumio[1006]: info: CoreCommandRouter::servicePushState Apr 12 12:15:18 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:15:18 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:15:18 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:15:18 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:15:18 volumio volumio[1006]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 12 12:15:18 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:18.895+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_STOPPED positionMs=0 volume=100 Apr 12 12:15:18 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:18.896+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99623166" title="[EN] HUBU.FM | MUSIC RADIO STATION | AD FREE" Apr 12 12:15:18 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:18.897+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_STOPPED positionMs=0 volume=100 Apr 12 12:15:18 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:18.897+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99623166" title="[EN] HUBU.FM | MUSIC RADIO STATION | AD FREE" Apr 12 12:15:18 volumio volumio[1006]: info: ------------------------------ 32ms Apr 12 12:15:18 volumio volumio[1006]: info: Apr 12 12:15:18 volumio volumio[1006]: ---------------------------- MPD announces system playlist update Apr 12 12:15:18 volumio volumio[1006]: info: Ignoring MPD Status Update Apr 12 12:15:18 volumio volumio[1006]: info: Apr 12 12:15:18 volumio volumio[1006]: ---------------------------- MPD announces system playlist update Apr 12 12:15:18 volumio volumio[1006]: info: Ignoring MPD Status Update Apr 12 12:15:18 volumio volumio[1006]: info: touch_display: Setting screensaver timeout to 3600 seconds. Apr 12 12:15:18 volumio volumio[1006]: error: updateQueue error: null Apr 12 12:15:18 volumio volumio[1006]: info: ------------------------------ 39ms Apr 12 12:15:18 volumio volumio[1006]: info: sendMpdCommand playlistinfo took 35 milliseconds Apr 12 12:15:18 volumio volumio[1006]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:15:18 volumio volumio[1006]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 12 12:15:18 volumio volumio[1006]: info: ------------------------------ 50ms Apr 12 12:15:19 volumio volumio[1006]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99623166" took 149 milliseconds Apr 12 12:15:19 volumio volumio[1006]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 12 12:15:19 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand play Apr 12 12:15:19 volumio volumio[1006]: info: Apr 12 12:15:19 volumio volumio[1006]: ---------------------------- MPD announces system playlist update Apr 12 12:15:19 volumio volumio[1006]: info: Ignoring MPD Status Update Apr 12 12:15:19 volumio volumio[1006]: info: ------------------------------ 127ms Apr 12 12:15:19 volumio volumio[1006]: info: ------------------------------ 125ms Apr 12 12:15:19 volumio volumio[1006]: info: Apr 12 12:15:19 volumio volumio[1006]: ---------------------------- MPD announces system playlist update Apr 12 12:15:19 volumio volumio[1006]: info: Ignoring MPD Status Update Apr 12 12:15:19 volumio volumio[1006]: info: Apr 12 12:15:19 volumio volumio[1006]: ---------------------------- MPD announces system playlist update Apr 12 12:15:19 volumio volumio[1006]: info: Ignoring MPD Status Update Apr 12 12:15:19 volumio volumio[1006]: info: sendMpdCommand play took 4 milliseconds Apr 12 12:15:19 volumio volumio[1006]: info: ------------------------------ 4ms Apr 12 12:15:19 volumio volumio[1006]: info: ------------------------------ 2ms Apr 12 12:15:19 volumio volumio[1006]: info: ------------------------------ 1ms Apr 12 12:15:19 volumio volumio[1006]: info: Apr 12 12:15:19 volumio volumio[1006]: ---------------------------- MPD announces state update: player Apr 12 12:15:19 volumio volumio[1006]: info: ControllerMpd::getState Apr 12 12:15:19 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:15:19 volumio volumio[1006]: info: Apr 12 12:15:19 volumio volumio[1006]: ---------------------------- MPD announces state update: player Apr 12 12:15:19 volumio volumio[1006]: info: ControllerMpd::getState Apr 12 12:15:19 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:15:19 volumio volumio[1006]: info: Apr 12 12:15:19 volumio volumio[1006]: ---------------------------- MPD announces state update: player Apr 12 12:15:19 volumio volumio[1006]: info: ControllerMpd::getState Apr 12 12:15:19 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:15:19 volumio volumio[1006]: info: Apr 12 12:15:19 volumio volumio[1006]: ---------------------------- MPD announces state update: player Apr 12 12:15:19 volumio volumio[1006]: info: sendMpdCommand status took 4 milliseconds Apr 12 12:15:19 volumio volumio[1006]: info: ControllerMpd::getState Apr 12 12:15:19 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:15:19 volumio volumio[1006]: info: sendMpdCommand status took 3 milliseconds Apr 12 12:15:19 volumio volumio[1006]: info: sendMpdCommand status took 2 milliseconds Apr 12 12:15:19 volumio volumio[1006]: verbose: ControllerMpd::parseState Apr 12 12:15:19 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:15:19 volumio volumio[1006]: verbose: ControllerMpd::parseState Apr 12 12:15:19 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:15:19 volumio volumio[1006]: verbose: ControllerMpd::parseState Apr 12 12:15:19 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:15:19 volumio volumio[1006]: info: Apr 12 12:15:19 volumio volumio[1006]: ---------------------------- MPD announces state update: player Apr 12 12:15:19 volumio volumio[1006]: info: ControllerMpd::getState Apr 12 12:15:19 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:15:19 volumio volumio[1006]: info: sendMpdCommand status took 3 milliseconds Apr 12 12:15:19 volumio volumio[1006]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 12 12:15:19 volumio volumio[1006]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 12 12:15:19 volumio volumio[1006]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 12 12:15:19 volumio volumio[1006]: verbose: ControllerMpd::parseState Apr 12 12:15:19 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:15:19 volumio volumio[1006]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:15:19 volumio volumio[1006]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:15:19 volumio volumio[1006]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:15:19 volumio volumio[1006]: info: ControllerMpd::pushState Apr 12 12:15:19 volumio volumio[1006]: info: CoreCommandRouter::servicePushState Apr 12 12:15:19 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:15:19 volumio volumio[1006]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":"(#1 - 17566/24576) [EN] HUBU.FM | MUSIC RADIO STATION | AD FREE","album":null,"uri":"https://live.hubu.fm:8000/","trackType":"fm:8000/"} Apr 12 12:15:19 volumio volumio[1006]: verbose: CURRENT POSITION 0 Apr 12 12:15:19 volumio volumio[1006]: info: CoreStateMachine::syncState stateService play Apr 12 12:15:19 volumio volumio[1006]: info: CoreStateMachine::syncState currentStatus stop Apr 12 12:15:19 volumio volumio[1006]: info: ControllerMpd::pushState Apr 12 12:15:19 volumio volumio[1006]: info: CoreCommandRouter::servicePushState Apr 12 12:15:19 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:15:19 volumio volumio[1006]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":"(#1 - 17566/24576) [EN] HUBU.FM | MUSIC RADIO STATION | AD FREE","album":null,"uri":"https://live.hubu.fm:8000/","trackType":"fm:8000/"} Apr 12 12:15:19 volumio volumio[1006]: verbose: CURRENT POSITION 0 Apr 12 12:15:19 volumio volumio[1006]: info: CoreStateMachine::syncState stateService play Apr 12 12:15:19 volumio volumio[1006]: info: CoreStateMachine::syncState currentStatus play Apr 12 12:15:19 volumio volumio[1006]: info: Received an update from plugin. extracting info from payload Apr 12 12:15:19 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 12 12:15:19 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:15:19 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 12:15:19 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:15:19 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:15:19 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:15:19 volumio volumio[1006]: info: ControllerMpd::pushState Apr 12 12:15:19 volumio volumio[1006]: info: CoreCommandRouter::servicePushState Apr 12 12:15:19 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:15:19 volumio volumio[1006]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":"(#1 - 17566/24576) [EN] HUBU.FM | MUSIC RADIO STATION | AD FREE","album":null,"uri":"https://live.hubu.fm:8000/","trackType":"fm:8000/"} Apr 12 12:15:19 volumio volumio[1006]: verbose: CURRENT POSITION 0 Apr 12 12:15:19 volumio volumio[1006]: info: CoreStateMachine::syncState stateService play Apr 12 12:15:19 volumio volumio[1006]: info: CoreStateMachine::syncState currentStatus play Apr 12 12:15:19 volumio volumio[1006]: info: Received an update from plugin. extracting info from payload Apr 12 12:15:19 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 12 12:15:19 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:15:19 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:15:19 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:15:19 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:15:19 volumio volumio[1006]: info: ------------------------------ 24ms Apr 12 12:15:19 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:19.836+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_PLAYING positionMs=0 volume=100 Apr 12 12:15:19 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:19.836+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id=https://live.hubu.fm:8000/ title= Apr 12 12:15:19 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:19.837+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_PLAYING positionMs=0 volume=100 Apr 12 12:15:19 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:19.837+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id=https://live.hubu.fm:8000/ title= Apr 12 12:15:19 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:19.838+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_PLAYING positionMs=0 volume=100 Apr 12 12:15:19 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:19.838+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id=https://live.hubu.fm:8000/ title= Apr 12 12:15:19 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:19.839+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_PLAYING positionMs=0 volume=100 Apr 12 12:15:19 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:19.839+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id=https://live.hubu.fm:8000/ title= Apr 12 12:15:19 volumio volumio[1006]: info: ------------------------------ 45ms Apr 12 12:15:19 volumio volumio[1006]: info: ------------------------------ 45ms Apr 12 12:15:19 volumio volumio[1006]: info: Apr 12 12:15:19 volumio volumio[1006]: ---------------------------- MPD announces state update: player Apr 12 12:15:19 volumio volumio[1006]: info: ControllerMpd::getState Apr 12 12:15:19 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:15:19 volumio volumio[1006]: info: sendMpdCommand status took 44 milliseconds Apr 12 12:15:19 volumio volumio[1006]: info: sendMpdCommand playlistinfo took 44 milliseconds Apr 12 12:15:19 volumio volumio[1006]: verbose: ControllerMpd::parseState Apr 12 12:15:19 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:15:19 volumio volumio[1006]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:15:19 volumio volumio[1006]: info: ControllerMpd::pushState Apr 12 12:15:19 volumio volumio[1006]: info: CoreCommandRouter::servicePushState Apr 12 12:15:19 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:15:19 volumio volumio[1006]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":"(#1 - 17566/24576) [EN] HUBU.FM | MUSIC RADIO STATION | AD FREE","album":null,"uri":"https://live.hubu.fm:8000/","trackType":"fm:8000/"} Apr 12 12:15:19 volumio volumio[1006]: verbose: CURRENT POSITION 0 Apr 12 12:15:19 volumio volumio[1006]: info: CoreStateMachine::syncState stateService play Apr 12 12:15:19 volumio volumio[1006]: info: CoreStateMachine::syncState currentStatus play Apr 12 12:15:19 volumio volumio[1006]: info: Received an update from plugin. extracting info from payload Apr 12 12:15:19 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 12 12:15:19 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:15:19 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 12:15:19 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:15:19 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:15:19 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:15:19 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:19.893+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_PLAYING positionMs=71 volume=100 Apr 12 12:15:19 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:19.893+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id=https://live.hubu.fm:8000/ title= Apr 12 12:15:19 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:19.894+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_PLAYING positionMs=71 volume=100 Apr 12 12:15:19 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:19.894+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id=https://live.hubu.fm:8000/ title= Apr 12 12:15:19 volumio volumio[1006]: info: ------------------------------ 91ms Apr 12 12:15:19 volumio volumio[1006]: info: sendMpdCommand status took 52 milliseconds Apr 12 12:15:19 volumio volumio[1006]: info: sendMpdCommand playlistinfo took 48 milliseconds Apr 12 12:15:19 volumio volumio[1006]: verbose: ControllerMpd::parseState Apr 12 12:15:19 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:15:19 volumio volumio[1006]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:15:19 volumio volumio[1006]: info: ControllerMpd::pushState Apr 12 12:15:19 volumio volumio[1006]: info: CoreCommandRouter::servicePushState Apr 12 12:15:19 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:15:19 volumio volumio[1006]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":"(#1 - 17566/24576) [EN] HUBU.FM | MUSIC RADIO STATION | AD FREE","album":null,"uri":"https://live.hubu.fm:8000/","trackType":"fm:8000/"} Apr 12 12:15:19 volumio volumio[1006]: verbose: CURRENT POSITION 0 Apr 12 12:15:19 volumio volumio[1006]: info: CoreStateMachine::syncState stateService play Apr 12 12:15:19 volumio volumio[1006]: info: CoreStateMachine::syncState currentStatus play Apr 12 12:15:19 volumio volumio[1006]: info: Received an update from plugin. extracting info from payload Apr 12 12:15:19 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 12 12:15:19 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:15:19 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:15:19 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:15:19 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:15:19 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:19.923+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_PLAYING positionMs=71 volume=100 Apr 12 12:15:19 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:19.923+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id=https://live.hubu.fm:8000/ title= Apr 12 12:15:19 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:19.924+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_PLAYING positionMs=71 volume=100 Apr 12 12:15:19 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:19.925+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id=https://live.hubu.fm:8000/ title= Apr 12 12:15:19 volumio volumio[1006]: info: ------------------------------ 119ms Apr 12 12:15:19 volumio volumio[1006]: info: Apr 12 12:15:19 volumio volumio[1006]: ---------------------------- MPD announces system playlist update Apr 12 12:15:19 volumio volumio[1006]: info: Ignoring MPD Status Update Apr 12 12:15:19 volumio volumio[1006]: info: Apr 12 12:15:19 volumio volumio[1006]: ---------------------------- MPD announces state update: player Apr 12 12:15:19 volumio volumio[1006]: info: sendMpdCommand playlistinfo took 28 milliseconds Apr 12 12:15:19 volumio volumio[1006]: info: ControllerMpd::getState Apr 12 12:15:19 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:15:19 volumio volumio[1006]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:15:19 volumio volumio[1006]: info: ControllerMpd::pushState Apr 12 12:15:19 volumio volumio[1006]: info: CoreCommandRouter::servicePushState Apr 12 12:15:19 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:15:19 volumio volumio[1006]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":"(#1 - 17566/24576) [EN] HUBU.FM | MUSIC RADIO STATION | AD FREE","album":null,"uri":"https://live.hubu.fm:8000/","trackType":"fm:8000/"} Apr 12 12:15:19 volumio volumio[1006]: verbose: CURRENT POSITION 0 Apr 12 12:15:19 volumio volumio[1006]: info: CoreStateMachine::syncState stateService play Apr 12 12:15:19 volumio volumio[1006]: info: CoreStateMachine::syncState currentStatus play Apr 12 12:15:19 volumio volumio[1006]: info: Received an update from plugin. extracting info from payload Apr 12 12:15:19 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 12 12:15:19 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:15:19 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 12:15:19 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:15:19 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:15:19 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:15:19 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:19.947+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_PLAYING positionMs=149 volume=100 Apr 12 12:15:19 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:19.947+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id=https://live.hubu.fm:8000/ title= Apr 12 12:15:19 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:19.948+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_PLAYING positionMs=149 volume=100 Apr 12 12:15:19 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:19.948+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id=https://live.hubu.fm:8000/ title= Apr 12 12:15:19 volumio volumio[1006]: info: ------------------------------ 100ms Apr 12 12:15:19 volumio volumio[1006]: info: Apr 12 12:15:19 volumio volumio[1006]: ---------------------------- MPD announces system playlist update Apr 12 12:15:19 volumio volumio[1006]: info: Ignoring MPD Status Update Apr 12 12:15:19 volumio volumio[1006]: info: Apr 12 12:15:19 volumio volumio[1006]: ---------------------------- MPD announces state update: player Apr 12 12:15:19 volumio volumio[1006]: info: ControllerMpd::getState Apr 12 12:15:19 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:15:19 volumio volumio[1006]: info: Apr 12 12:15:19 volumio volumio[1006]: ---------------------------- MPD announces system playlist update Apr 12 12:15:19 volumio volumio[1006]: info: Ignoring MPD Status Update Apr 12 12:15:19 volumio volumio[1006]: info: Apr 12 12:15:19 volumio volumio[1006]: ---------------------------- MPD announces state update: player Apr 12 12:15:19 volumio volumio[1006]: info: ControllerMpd::getState Apr 12 12:15:19 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:15:19 volumio volumio[1006]: info: Apr 12 12:15:19 volumio volumio[1006]: ---------------------------- MPD announces system playlist update Apr 12 12:15:19 volumio volumio[1006]: info: Ignoring MPD Status Update Apr 12 12:15:19 volumio volumio[1006]: info: Apr 12 12:15:19 volumio volumio[1006]: ---------------------------- MPD announces state update: player Apr 12 12:15:19 volumio volumio[1006]: info: ControllerMpd::getState Apr 12 12:15:19 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:15:19 volumio volumio[1006]: info: ------------------------------ 43ms Apr 12 12:15:19 volumio volumio[1006]: info: sendMpdCommand status took 42 milliseconds Apr 12 12:15:19 volumio volumio[1006]: info: ------------------------------ 23ms Apr 12 12:15:19 volumio volumio[1006]: info: sendMpdCommand status took 22 milliseconds Apr 12 12:15:19 volumio volumio[1006]: info: ------------------------------ 22ms Apr 12 12:15:19 volumio volumio[1006]: info: sendMpdCommand status took 20 milliseconds Apr 12 12:15:19 volumio volumio[1006]: verbose: ControllerMpd::parseState Apr 12 12:15:19 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:15:19 volumio volumio[1006]: verbose: ControllerMpd::parseState Apr 12 12:15:19 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:15:19 volumio volumio[1006]: verbose: ControllerMpd::parseState Apr 12 12:15:19 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:15:19 volumio volumio[1006]: info: Apr 12 12:15:19 volumio volumio[1006]: ---------------------------- MPD announces system playlist update Apr 12 12:15:19 volumio volumio[1006]: info: Ignoring MPD Status Update Apr 12 12:15:19 volumio volumio[1006]: info: Apr 12 12:15:19 volumio volumio[1006]: ---------------------------- MPD announces state update: player Apr 12 12:15:19 volumio volumio[1006]: info: ControllerMpd::getState Apr 12 12:15:19 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:15:19 volumio volumio[1006]: info: Apr 12 12:15:19 volumio volumio[1006]: ---------------------------- MPD announces system playlist update Apr 12 12:15:19 volumio volumio[1006]: info: Ignoring MPD Status Update Apr 12 12:15:19 volumio volumio[1006]: info: Apr 12 12:15:19 volumio volumio[1006]: ---------------------------- MPD announces state update: player Apr 12 12:15:19 volumio volumio[1006]: info: ControllerMpd::getState Apr 12 12:15:19 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:15:19 volumio volumio[1006]: info: ------------------------------ 13ms Apr 12 12:15:19 volumio volumio[1006]: info: sendMpdCommand status took 12 milliseconds Apr 12 12:15:19 volumio volumio[1006]: info: sendMpdCommand playlistinfo took 12 milliseconds Apr 12 12:15:19 volumio volumio[1006]: info: sendMpdCommand playlistinfo took 12 milliseconds Apr 12 12:15:19 volumio volumio[1006]: info: sendMpdCommand playlistinfo took 11 milliseconds Apr 12 12:15:19 volumio volumio[1006]: info: ------------------------------ 12ms Apr 12 12:15:19 volumio volumio[1006]: info: sendMpdCommand status took 11 milliseconds Apr 12 12:15:19 volumio volumio[1006]: info: ------------------------------ 10ms Apr 12 12:15:19 volumio volumio[1006]: info: sendMpdCommand status took 10 milliseconds Apr 12 12:15:19 volumio volumio[1006]: verbose: ControllerMpd::parseState Apr 12 12:15:19 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:15:19 volumio volumio[1006]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:15:19 volumio volumio[1006]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:15:19 volumio volumio[1006]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:15:19 volumio volumio[1006]: verbose: ControllerMpd::parseState Apr 12 12:15:19 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:15:19 volumio volumio[1006]: verbose: ControllerMpd::parseState Apr 12 12:15:19 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:15:19 volumio volumio[1006]: info: ControllerMpd::pushState Apr 12 12:15:19 volumio volumio[1006]: info: CoreCommandRouter::servicePushState Apr 12 12:15:19 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:15:19 volumio volumio[1006]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"09:14 - Hubu.FM Live","artist":"[EN] HUBU.FM | MUSIC RADIO STATION | AD FREE","album":null,"uri":"https://live.hubu.fm:8000/","trackType":"fm:8000/"} Apr 12 12:15:19 volumio volumio[1006]: verbose: CURRENT POSITION 0 Apr 12 12:15:19 volumio volumio[1006]: info: CoreStateMachine::syncState stateService play Apr 12 12:15:19 volumio volumio[1006]: info: CoreStateMachine::syncState currentStatus play Apr 12 12:15:19 volumio volumio[1006]: info: Received an update from plugin. extracting info from payload Apr 12 12:15:19 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 12 12:15:19 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:15:19 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 12:15:19 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:15:20 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:15:20 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:15:20 volumio volumio[1006]: info: ControllerMpd::pushState Apr 12 12:15:20 volumio volumio[1006]: info: CoreCommandRouter::servicePushState Apr 12 12:15:20 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:15:20 volumio volumio[1006]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"09:14 - Hubu.FM Live","artist":"[EN] HUBU.FM | MUSIC RADIO STATION | AD FREE","album":null,"uri":"https://live.hubu.fm:8000/","trackType":"fm:8000/"} Apr 12 12:15:20 volumio volumio[1006]: verbose: CURRENT POSITION 0 Apr 12 12:15:20 volumio volumio[1006]: info: CoreStateMachine::syncState stateService play Apr 12 12:15:20 volumio volumio[1006]: info: CoreStateMachine::syncState currentStatus play Apr 12 12:15:20 volumio volumio[1006]: info: Received an update from plugin. extracting info from payload Apr 12 12:15:20 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 12 12:15:20 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:15:20 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:15:20 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:15:20 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:15:20 volumio volumio[1006]: info: ControllerMpd::pushState Apr 12 12:15:20 volumio volumio[1006]: info: CoreCommandRouter::servicePushState Apr 12 12:15:20 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:15:20 volumio volumio[1006]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"09:14 - Hubu.FM Live","artist":"[EN] HUBU.FM | MUSIC RADIO STATION | AD FREE","album":null,"uri":"https://live.hubu.fm:8000/","trackType":"fm:8000/"} Apr 12 12:15:20 volumio volumio[1006]: verbose: CURRENT POSITION 0 Apr 12 12:15:20 volumio volumio[1006]: info: CoreStateMachine::syncState stateService play Apr 12 12:15:20 volumio volumio[1006]: info: CoreStateMachine::syncState currentStatus play Apr 12 12:15:20 volumio volumio[1006]: info: Received an update from plugin. extracting info from payload Apr 12 12:15:20 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 12 12:15:20 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:15:20 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:15:20 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:15:20 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:15:20 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:20.027+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_PLAYING positionMs=149 volume=100 Apr 12 12:15:20 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:20.027+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id=https://live.hubu.fm:8000/ title="09:14 - Hubu.FM Live" Apr 12 12:15:20 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:20.028+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_PLAYING positionMs=149 volume=100 Apr 12 12:15:20 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:20.028+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id=https://live.hubu.fm:8000/ title="09:14 - Hubu.FM Live" Apr 12 12:15:20 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:20.029+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_PLAYING positionMs=149 volume=100 Apr 12 12:15:20 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:20.030+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id=https://live.hubu.fm:8000/ title="09:14 - Hubu.FM Live" Apr 12 12:15:20 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:20.030+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_PLAYING positionMs=149 volume=100 Apr 12 12:15:20 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:20.031+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id=https://live.hubu.fm:8000/ title="09:14 - Hubu.FM Live" Apr 12 12:15:20 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:20.031+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_PLAYING positionMs=149 volume=100 Apr 12 12:15:20 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:20.032+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id=https://live.hubu.fm:8000/ title="09:14 - Hubu.FM Live" Apr 12 12:15:20 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:20.033+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_PLAYING positionMs=149 volume=100 Apr 12 12:15:20 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:20.033+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id=https://live.hubu.fm:8000/ title="09:14 - Hubu.FM Live" Apr 12 12:15:20 volumio volumio[1006]: info: ------------------------------ 122ms Apr 12 12:15:20 volumio volumio[1006]: info: ------------------------------ 103ms Apr 12 12:15:20 volumio volumio[1006]: info: ------------------------------ 101ms Apr 12 12:15:20 volumio volumio[1006]: info: sendMpdCommand playlistinfo took 67 milliseconds Apr 12 12:15:20 volumio volumio[1006]: info: sendMpdCommand playlistinfo took 67 milliseconds Apr 12 12:15:20 volumio volumio[1006]: info: sendMpdCommand playlistinfo took 67 milliseconds Apr 12 12:15:20 volumio volumio[1006]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:15:20 volumio volumio[1006]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:15:20 volumio volumio[1006]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:15:20 volumio volumio[1006]: info: ControllerMpd::pushState Apr 12 12:15:20 volumio volumio[1006]: info: CoreCommandRouter::servicePushState Apr 12 12:15:20 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:15:20 volumio volumio[1006]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"09:14 - Hubu.FM Live","artist":"[EN] HUBU.FM | MUSIC RADIO STATION | AD FREE","album":null,"uri":"https://live.hubu.fm:8000/","trackType":"fm:8000/"} Apr 12 12:15:20 volumio volumio[1006]: verbose: CURRENT POSITION 0 Apr 12 12:15:20 volumio volumio[1006]: info: CoreStateMachine::syncState stateService play Apr 12 12:15:20 volumio volumio[1006]: info: CoreStateMachine::syncState currentStatus play Apr 12 12:15:20 volumio volumio[1006]: info: Received an update from plugin. extracting info from payload Apr 12 12:15:20 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 12 12:15:20 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:15:20 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 12:15:20 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:15:20 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:15:20 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:15:20 volumio volumio[1006]: info: ControllerMpd::pushState Apr 12 12:15:20 volumio volumio[1006]: info: CoreCommandRouter::servicePushState Apr 12 12:15:20 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:15:20 volumio volumio[1006]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"09:14 - Hubu.FM Live","artist":"[EN] HUBU.FM | MUSIC RADIO STATION | AD FREE","album":null,"uri":"https://live.hubu.fm:8000/","trackType":"fm:8000/"} Apr 12 12:15:20 volumio volumio[1006]: verbose: CURRENT POSITION 0 Apr 12 12:15:20 volumio volumio[1006]: info: CoreStateMachine::syncState stateService play Apr 12 12:15:20 volumio volumio[1006]: info: CoreStateMachine::syncState currentStatus play Apr 12 12:15:20 volumio volumio[1006]: info: Received an update from plugin. extracting info from payload Apr 12 12:15:20 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 12 12:15:20 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:15:20 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:15:20 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:15:20 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:15:20 volumio volumio[1006]: info: ControllerMpd::pushState Apr 12 12:15:20 volumio volumio[1006]: info: CoreCommandRouter::servicePushState Apr 12 12:15:20 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:15:20 volumio volumio[1006]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"09:14 - Hubu.FM Live","artist":"[EN] HUBU.FM | MUSIC RADIO STATION | AD FREE","album":null,"uri":"https://live.hubu.fm:8000/","trackType":"fm:8000/"} Apr 12 12:15:20 volumio volumio[1006]: verbose: CURRENT POSITION 0 Apr 12 12:15:20 volumio volumio[1006]: info: CoreStateMachine::syncState stateService play Apr 12 12:15:20 volumio volumio[1006]: info: CoreStateMachine::syncState currentStatus play Apr 12 12:15:20 volumio volumio[1006]: info: Received an update from plugin. extracting info from payload Apr 12 12:15:20 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 12 12:15:20 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:15:20 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:15:20 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:15:20 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:15:20 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:20.102+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_PLAYING positionMs=274 volume=100 Apr 12 12:15:20 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:20.102+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id=https://live.hubu.fm:8000/ title="09:14 - Hubu.FM Live" Apr 12 12:15:20 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:20.103+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_PLAYING positionMs=274 volume=100 Apr 12 12:15:20 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:20.103+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id=https://live.hubu.fm:8000/ title="09:14 - Hubu.FM Live" Apr 12 12:15:20 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:20.104+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_PLAYING positionMs=274 volume=100 Apr 12 12:15:20 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:20.105+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id=https://live.hubu.fm:8000/ title="09:14 - Hubu.FM Live" Apr 12 12:15:20 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:20.106+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_PLAYING positionMs=274 volume=100 Apr 12 12:15:20 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:20.106+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id=https://live.hubu.fm:8000/ title="09:14 - Hubu.FM Live" Apr 12 12:15:20 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:20.107+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_PLAYING positionMs=274 volume=100 Apr 12 12:15:20 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:20.108+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id=https://live.hubu.fm:8000/ title="09:14 - Hubu.FM Live" Apr 12 12:15:20 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:20.109+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_PLAYING positionMs=274 volume=100 Apr 12 12:15:20 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:20.109+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id=https://live.hubu.fm:8000/ title="09:14 - Hubu.FM Live" Apr 12 12:15:20 volumio volumio[1006]: info: ------------------------------ 158ms Apr 12 12:15:20 volumio volumio[1006]: info: ------------------------------ 154ms Apr 12 12:15:20 volumio volumio[1006]: info: ------------------------------ 154ms Apr 12 12:15:33 volumio volumio[1006]: info: Preload queue cleared Apr 12 12:15:33 volumio volumio[1006]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 12 12:15:33 volumio volumio[1006]: info: CoreStateMachine::ClearQueue Apr 12 12:15:33 volumio volumio[1006]: info: CoreStateMachine::stop Apr 12 12:15:33 volumio volumio[1006]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 12:15:33 volumio volumio[1006]: info: CoreStateMachine::stPlaybackTimer Apr 12 12:15:33 volumio volumio[1006]: info: CoreStateMachine::updateTrackBlock Apr 12 12:15:33 volumio volumio[1006]: info: CorePlayQueue::getTrackBlock Apr 12 12:15:33 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:15:33 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:15:33 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 12:15:33 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:15:33 volumio volumio[1006]: info: CoreStateMachine::serviceStop Apr 12 12:15:33 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:15:33 volumio volumio[1006]: info: CoreCommandRouter::serviceStop Apr 12 12:15:33 volumio volumio[1006]: info: [1775978133360] ControllerWebradio::stop Apr 12 12:15:33 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand stop Apr 12 12:15:33 volumio volumio[1006]: info: CorePlayQueue::clearPlayQueue Apr 12 12:15:33 volumio volumio[1006]: info: CorePlayQueue::saveQueue Apr 12 12:15:33 volumio volumio[1006]: info: CoreCommandRouter::volumioPushQueue Apr 12 12:15:33 volumio volumio[1006]: info: CoreStateMachine::addQueueItems Apr 12 12:15:33 volumio volumio[1006]: info: CorePlayQueue::addQueueItems Apr 12 12:15:33 volumio volumio[1006]: info: Preload queue cleared Apr 12 12:15:33 volumio volumio[1006]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320 Apr 12 12:15:33 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 12 12:15:33 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:33.363+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_STOPPED positionMs=0 volume=100 Apr 12 12:15:33 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:33.364+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99623166" title="[EN] HUBU.FM | MUSIC RADIO STATION | AD FREE" Apr 12 12:15:33 volumio volumio[1006]: info: CoreCommandRouter::volumioPushQueue Apr 12 12:15:33 volumio volumio[1006]: info: CorePlayQueue::saveQueue Apr 12 12:15:33 volumio volumio[1006]: info: CoreStateMachine::updateTrackBlock Apr 12 12:15:33 volumio volumio[1006]: info: CorePlayQueue::getTrackBlock Apr 12 12:15:33 volumio volumio[1006]: info: CoreCommandRouter::volumioPlay Apr 12 12:15:33 volumio volumio[1006]: info: CoreStateMachine::play index 0 Apr 12 12:15:33 volumio volumio[1006]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 12:15:33 volumio volumio[1006]: info: CoreStateMachine::stop Apr 12 12:15:33 volumio volumio[1006]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 12:15:33 volumio volumio[1006]: info: CoreStateMachine::play index undefined Apr 12 12:15:33 volumio volumio[1006]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 12:15:33 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:15:33 volumio volumio[1006]: info: CoreStateMachine::startPlaybackTimer Apr 12 12:15:33 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:15:33 volumio volumio[1006]: info: [1775978133381] ControllerWebradio::clearAddPlayTrack Apr 12 12:15:33 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand stop Apr 12 12:15:33 volumio volumio[1006]: info: Apr 12 12:15:33 volumio volumio[1006]: ---------------------------- MPD announces state update: player Apr 12 12:15:33 volumio volumio[1006]: info: sendMpdCommand stop took 43 milliseconds Apr 12 12:15:33 volumio volumio[1006]: info: ControllerMpd::getState Apr 12 12:15:33 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:15:33 volumio volumio[1006]: info: sendMpdCommand stop took 23 milliseconds Apr 12 12:15:33 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand clear Apr 12 12:15:33 volumio volumio[1006]: info: Apr 12 12:15:33 volumio volumio[1006]: ---------------------------- MPD announces state update: player Apr 12 12:15:33 volumio volumio[1006]: info: ControllerMpd::getState Apr 12 12:15:33 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:15:33 volumio volumio[1006]: info: Apr 12 12:15:33 volumio volumio[1006]: ---------------------------- MPD announces state update: player Apr 12 12:15:33 volumio volumio[1006]: info: ControllerMpd::getState Apr 12 12:15:33 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:15:33 volumio volumio[1006]: info: Apr 12 12:15:33 volumio volumio[1006]: ---------------------------- MPD announces system playlist update Apr 12 12:15:33 volumio volumio[1006]: info: Ignoring MPD Status Update Apr 12 12:15:33 volumio volumio[1006]: info: sendMpdCommand status took 10 milliseconds Apr 12 12:15:33 volumio volumio[1006]: info: sendMpdCommand clear took 9 milliseconds Apr 12 12:15:33 volumio volumio[1006]: info: sendMpdCommand status took 8 milliseconds Apr 12 12:15:33 volumio volumio[1006]: info: sendMpdCommand status took 6 milliseconds Apr 12 12:15:33 volumio volumio[1006]: verbose: ControllerMpd::parseState Apr 12 12:15:33 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:15:33 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320" Apr 12 12:15:33 volumio volumio[1006]: verbose: ControllerMpd::parseState Apr 12 12:15:33 volumio volumio[1006]: verbose: ControllerMpd::parseState Apr 12 12:15:33 volumio volumio[1006]: info: ControllerMpd::pushState Apr 12 12:15:33 volumio volumio[1006]: info: CoreCommandRouter::servicePushState Apr 12 12:15:33 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:15:33 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:15:33 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 12:15:33 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:15:33 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:15:33 volumio volumio[1006]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 12 12:15:33 volumio volumio[1006]: info: ControllerMpd::pushState Apr 12 12:15:33 volumio volumio[1006]: info: CoreCommandRouter::servicePushState Apr 12 12:15:33 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:15:33 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:15:33 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:15:33 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:15:33 volumio volumio[1006]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 12 12:15:33 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:33.426+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_STOPPED positionMs=32 volume=100 Apr 12 12:15:33 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:33.426+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320" title="Dance Wave!" Apr 12 12:15:33 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:33.427+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_STOPPED positionMs=32 volume=100 Apr 12 12:15:33 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:33.427+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320" title="Dance Wave!" Apr 12 12:15:33 volumio volumio[1006]: info: ------------------------------ 31ms Apr 12 12:15:33 volumio volumio[1006]: info: ------------------------------ 29ms Apr 12 12:15:33 volumio volumio[1006]: info: Apr 12 12:15:33 volumio volumio[1006]: ---------------------------- MPD announces system playlist update Apr 12 12:15:33 volumio volumio[1006]: info: Ignoring MPD Status Update Apr 12 12:15:33 volumio volumio[1006]: info: Apr 12 12:15:33 volumio volumio[1006]: ---------------------------- MPD announces system playlist update Apr 12 12:15:33 volumio volumio[1006]: info: Ignoring MPD Status Update Apr 12 12:15:33 volumio volumio[1006]: info: touch_display: Setting screensaver timeout to 3600 seconds. Apr 12 12:15:33 volumio volumio[1006]: error: updateQueue error: null Apr 12 12:15:33 volumio volumio[1006]: info: ------------------------------ 32ms Apr 12 12:15:33 volumio volumio[1006]: info: sendMpdCommand playlistinfo took 31 milliseconds Apr 12 12:15:33 volumio volumio[1006]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:15:33 volumio volumio[1006]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 12 12:15:33 volumio volumio[1006]: info: ------------------------------ 44ms Apr 12 12:15:33 volumio volumio[1006]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320" took 147 milliseconds Apr 12 12:15:33 volumio volumio[1006]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 12 12:15:33 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand play Apr 12 12:15:33 volumio volumio[1006]: info: Apr 12 12:15:33 volumio volumio[1006]: ---------------------------- MPD announces system playlist update Apr 12 12:15:33 volumio volumio[1006]: info: Ignoring MPD Status Update Apr 12 12:15:33 volumio volumio[1006]: info: ------------------------------ 125ms Apr 12 12:15:33 volumio volumio[1006]: info: ------------------------------ 124ms Apr 12 12:15:33 volumio volumio[1006]: info: sendMpdCommand play took 2 milliseconds Apr 12 12:15:33 volumio volumio[1006]: info: Apr 12 12:15:33 volumio volumio[1006]: ---------------------------- MPD announces system playlist update Apr 12 12:15:33 volumio volumio[1006]: info: Ignoring MPD Status Update Apr 12 12:15:33 volumio volumio[1006]: info: Apr 12 12:15:33 volumio volumio[1006]: ---------------------------- MPD announces system playlist update Apr 12 12:15:33 volumio volumio[1006]: info: Ignoring MPD Status Update Apr 12 12:15:33 volumio volumio[1006]: info: ------------------------------ 3ms Apr 12 12:15:33 volumio volumio[1006]: info: ------------------------------ 2ms Apr 12 12:15:33 volumio volumio[1006]: info: ------------------------------ 1ms Apr 12 12:15:35 volumio volumio[1006]: info: Apr 12 12:15:35 volumio volumio[1006]: ---------------------------- MPD announces state update: player Apr 12 12:15:35 volumio volumio[1006]: info: ControllerMpd::getState Apr 12 12:15:35 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:15:35 volumio volumio[1006]: info: Apr 12 12:15:35 volumio volumio[1006]: ---------------------------- MPD announces state update: player Apr 12 12:15:35 volumio volumio[1006]: info: ControllerMpd::getState Apr 12 12:15:35 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:15:35 volumio volumio[1006]: info: Apr 12 12:15:35 volumio volumio[1006]: ---------------------------- MPD announces state update: player Apr 12 12:15:35 volumio volumio[1006]: info: ControllerMpd::getState Apr 12 12:15:35 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:15:35 volumio volumio[1006]: info: Apr 12 12:15:35 volumio volumio[1006]: ---------------------------- MPD announces state update: player Apr 12 12:15:35 volumio volumio[1006]: info: ControllerMpd::getState Apr 12 12:15:35 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:15:35 volumio volumio[1006]: info: Apr 12 12:15:35 volumio volumio[1006]: ---------------------------- MPD announces state update: player Apr 12 12:15:35 volumio volumio[1006]: info: sendMpdCommand status took 4 milliseconds Apr 12 12:15:35 volumio volumio[1006]: info: ControllerMpd::getState Apr 12 12:15:35 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:15:35 volumio volumio[1006]: info: sendMpdCommand status took 3 milliseconds Apr 12 12:15:35 volumio volumio[1006]: info: sendMpdCommand status took 3 milliseconds Apr 12 12:15:35 volumio volumio[1006]: info: sendMpdCommand status took 2 milliseconds Apr 12 12:15:35 volumio volumio[1006]: verbose: ControllerMpd::parseState Apr 12 12:15:35 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:15:35 volumio volumio[1006]: verbose: ControllerMpd::parseState Apr 12 12:15:35 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:15:35 volumio volumio[1006]: verbose: ControllerMpd::parseState Apr 12 12:15:35 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:15:35 volumio volumio[1006]: verbose: ControllerMpd::parseState Apr 12 12:15:35 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:15:35 volumio volumio[1006]: info: Apr 12 12:15:35 volumio volumio[1006]: ---------------------------- MPD announces state update: player Apr 12 12:15:35 volumio volumio[1006]: info: ControllerMpd::getState Apr 12 12:15:35 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:15:35 volumio volumio[1006]: info: sendMpdCommand status took 4 milliseconds Apr 12 12:15:35 volumio volumio[1006]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 12 12:15:35 volumio volumio[1006]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 12 12:15:35 volumio volumio[1006]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 12 12:15:35 volumio volumio[1006]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 12 12:15:35 volumio volumio[1006]: info: sendMpdCommand status took 2 milliseconds Apr 12 12:15:35 volumio volumio[1006]: verbose: ControllerMpd::parseState Apr 12 12:15:35 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:15:35 volumio volumio[1006]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:15:35 volumio volumio[1006]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:15:35 volumio volumio[1006]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:15:35 volumio volumio[1006]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:15:35 volumio volumio[1006]: verbose: ControllerMpd::parseState Apr 12 12:15:35 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:15:35 volumio volumio[1006]: info: ControllerMpd::pushState Apr 12 12:15:35 volumio volumio[1006]: info: CoreCommandRouter::servicePushState Apr 12 12:15:35 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:15:35 volumio volumio[1006]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"dance.mp3","artist":"(#1 - 5518/32000) Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Apr 12 12:15:35 volumio volumio[1006]: verbose: CURRENT POSITION 0 Apr 12 12:15:35 volumio volumio[1006]: info: CoreStateMachine::syncState stateService play Apr 12 12:15:35 volumio volumio[1006]: info: CoreStateMachine::syncState currentStatus stop Apr 12 12:15:35 volumio volumio[1006]: info: ControllerMpd::pushState Apr 12 12:15:35 volumio volumio[1006]: info: CoreCommandRouter::servicePushState Apr 12 12:15:35 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:15:35 volumio volumio[1006]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"dance.mp3","artist":"(#1 - 5518/32000) Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Apr 12 12:15:35 volumio volumio[1006]: verbose: CURRENT POSITION 0 Apr 12 12:15:35 volumio volumio[1006]: info: CoreStateMachine::syncState stateService play Apr 12 12:15:35 volumio volumio[1006]: info: CoreStateMachine::syncState currentStatus play Apr 12 12:15:35 volumio volumio[1006]: info: Received an update from plugin. extracting info from payload Apr 12 12:15:35 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 12 12:15:35 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:15:35 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:15:35 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 12:15:35 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:15:35 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:15:35 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:15:35 volumio volumio[1006]: info: ControllerMpd::pushState Apr 12 12:15:35 volumio volumio[1006]: info: CoreCommandRouter::servicePushState Apr 12 12:15:35 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:15:35 volumio volumio[1006]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"dance.mp3","artist":"(#1 - 5518/32000) Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Apr 12 12:15:35 volumio volumio[1006]: verbose: CURRENT POSITION 0 Apr 12 12:15:35 volumio volumio[1006]: info: CoreStateMachine::syncState stateService play Apr 12 12:15:35 volumio volumio[1006]: info: CoreStateMachine::syncState currentStatus play Apr 12 12:15:35 volumio volumio[1006]: info: Received an update from plugin. extracting info from payload Apr 12 12:15:35 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 12 12:15:35 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:15:35 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:15:35 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:15:35 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:15:35 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:15:35 volumio volumio[1006]: info: ControllerMpd::pushState Apr 12 12:15:35 volumio volumio[1006]: info: CoreCommandRouter::servicePushState Apr 12 12:15:35 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:15:35 volumio volumio[1006]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"dance.mp3","artist":"(#1 - 5518/32000) Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Apr 12 12:15:35 volumio volumio[1006]: verbose: CURRENT POSITION 0 Apr 12 12:15:35 volumio volumio[1006]: info: CoreStateMachine::syncState stateService play Apr 12 12:15:35 volumio volumio[1006]: info: CoreStateMachine::syncState currentStatus play Apr 12 12:15:35 volumio volumio[1006]: info: Received an update from plugin. extracting info from payload Apr 12 12:15:35 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 12 12:15:35 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:15:35 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:15:35 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:15:35 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:15:35 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:15:35 volumio volumio[1006]: info: ------------------------------ 23ms Apr 12 12:15:35 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:35.529+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_PLAYING positionMs=0 volume=100 Apr 12 12:15:35 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:35.529+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 12 12:15:35 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:35.530+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_PLAYING positionMs=0 volume=100 Apr 12 12:15:35 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:35.531+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 12 12:15:35 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:35.532+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_PLAYING positionMs=0 volume=100 Apr 12 12:15:35 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:35.532+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 12 12:15:35 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:35.533+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_PLAYING positionMs=0 volume=100 Apr 12 12:15:35 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:35.533+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 12 12:15:35 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:35.534+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_PLAYING positionMs=0 volume=100 Apr 12 12:15:35 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:35.534+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 12 12:15:35 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:35.535+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_PLAYING positionMs=0 volume=100 Apr 12 12:15:35 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:35.536+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 12 12:15:35 volumio volumio[1006]: info: ------------------------------ 64ms Apr 12 12:15:35 volumio volumio[1006]: info: ------------------------------ 64ms Apr 12 12:15:35 volumio volumio[1006]: info: sendMpdCommand playlistinfo took 59 milliseconds Apr 12 12:15:35 volumio volumio[1006]: info: sendMpdCommand playlistinfo took 58 milliseconds Apr 12 12:15:35 volumio volumio[1006]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:15:35 volumio volumio[1006]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:15:35 volumio volumio[1006]: info: ControllerMpd::pushState Apr 12 12:15:35 volumio volumio[1006]: info: CoreCommandRouter::servicePushState Apr 12 12:15:35 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:15:35 volumio volumio[1006]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"dance.mp3","artist":"(#1 - 5518/32000) Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Apr 12 12:15:35 volumio volumio[1006]: verbose: CURRENT POSITION 0 Apr 12 12:15:35 volumio volumio[1006]: info: CoreStateMachine::syncState stateService play Apr 12 12:15:35 volumio volumio[1006]: info: CoreStateMachine::syncState currentStatus play Apr 12 12:15:35 volumio volumio[1006]: info: Received an update from plugin. extracting info from payload Apr 12 12:15:35 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 12 12:15:35 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:15:35 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:15:35 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:15:35 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:15:35 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:15:35 volumio volumio[1006]: info: ControllerMpd::pushState Apr 12 12:15:35 volumio volumio[1006]: info: CoreCommandRouter::servicePushState Apr 12 12:15:35 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:15:35 volumio volumio[1006]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"dance.mp3","artist":"(#1 - 5518/32000) Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Apr 12 12:15:35 volumio volumio[1006]: verbose: CURRENT POSITION 0 Apr 12 12:15:35 volumio volumio[1006]: info: CoreStateMachine::syncState stateService play Apr 12 12:15:35 volumio volumio[1006]: info: CoreStateMachine::syncState currentStatus play Apr 12 12:15:35 volumio volumio[1006]: info: Received an update from plugin. extracting info from payload Apr 12 12:15:35 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 12 12:15:35 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:15:35 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:15:35 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:15:35 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:15:35 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:15:35 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:35.592+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_PLAYING positionMs=0 volume=100 Apr 12 12:15:35 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:35.592+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 12 12:15:35 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:35.593+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_PLAYING positionMs=0 volume=100 Apr 12 12:15:35 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:35.594+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 12 12:15:35 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:35.598+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_PLAYING positionMs=0 volume=100 Apr 12 12:15:35 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:35.598+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 12 12:15:35 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:35.598+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_PLAYING positionMs=0 volume=100 Apr 12 12:15:35 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:35.599+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 12 12:15:35 volumio volumio[1006]: info: ------------------------------ 114ms Apr 12 12:15:35 volumio volumio[1006]: info: ------------------------------ 112ms Apr 12 12:15:35 volumio volumio[1006]: info: Apr 12 12:15:35 volumio volumio[1006]: ---------------------------- MPD announces system playlist update Apr 12 12:15:35 volumio volumio[1006]: info: Ignoring MPD Status Update Apr 12 12:15:35 volumio volumio[1006]: info: Apr 12 12:15:35 volumio volumio[1006]: ---------------------------- MPD announces state update: player Apr 12 12:15:35 volumio volumio[1006]: info: ControllerMpd::getState Apr 12 12:15:35 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:15:35 volumio volumio[1006]: info: Apr 12 12:15:35 volumio volumio[1006]: ---------------------------- MPD announces system playlist update Apr 12 12:15:35 volumio volumio[1006]: info: Ignoring MPD Status Update Apr 12 12:15:35 volumio volumio[1006]: info: Apr 12 12:15:35 volumio volumio[1006]: ---------------------------- MPD announces state update: player Apr 12 12:15:35 volumio volumio[1006]: info: ControllerMpd::getState Apr 12 12:15:35 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:15:35 volumio volumio[1006]: info: Apr 12 12:15:35 volumio volumio[1006]: ---------------------------- MPD announces system playlist update Apr 12 12:15:35 volumio volumio[1006]: info: Ignoring MPD Status Update Apr 12 12:15:35 volumio volumio[1006]: info: Apr 12 12:15:35 volumio volumio[1006]: ---------------------------- MPD announces state update: player Apr 12 12:15:35 volumio volumio[1006]: info: ControllerMpd::getState Apr 12 12:15:35 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:15:35 volumio volumio[1006]: info: Apr 12 12:15:35 volumio volumio[1006]: ---------------------------- MPD announces system playlist update Apr 12 12:15:35 volumio volumio[1006]: info: Ignoring MPD Status Update Apr 12 12:15:35 volumio volumio[1006]: info: Apr 12 12:15:35 volumio volumio[1006]: ---------------------------- MPD announces state update: player Apr 12 12:15:35 volumio volumio[1006]: info: ControllerMpd::getState Apr 12 12:15:35 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:15:35 volumio volumio[1006]: info: ------------------------------ 6ms Apr 12 12:15:35 volumio volumio[1006]: info: sendMpdCommand status took 6 milliseconds Apr 12 12:15:35 volumio volumio[1006]: info: ------------------------------ 6ms Apr 12 12:15:35 volumio volumio[1006]: info: sendMpdCommand status took 5 milliseconds Apr 12 12:15:35 volumio volumio[1006]: info: ------------------------------ 5ms Apr 12 12:15:35 volumio volumio[1006]: info: sendMpdCommand status took 4 milliseconds Apr 12 12:15:35 volumio volumio[1006]: verbose: ControllerMpd::parseState Apr 12 12:15:35 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:15:35 volumio volumio[1006]: verbose: ControllerMpd::parseState Apr 12 12:15:35 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:15:35 volumio volumio[1006]: verbose: ControllerMpd::parseState Apr 12 12:15:35 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:15:35 volumio volumio[1006]: info: Apr 12 12:15:35 volumio volumio[1006]: ---------------------------- MPD announces system playlist update Apr 12 12:15:35 volumio volumio[1006]: info: Ignoring MPD Status Update Apr 12 12:15:35 volumio volumio[1006]: info: Apr 12 12:15:35 volumio volumio[1006]: ---------------------------- MPD announces state update: player Apr 12 12:15:35 volumio volumio[1006]: info: ControllerMpd::getState Apr 12 12:15:35 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:15:35 volumio volumio[1006]: info: Apr 12 12:15:35 volumio volumio[1006]: ---------------------------- MPD announces system playlist update Apr 12 12:15:35 volumio volumio[1006]: info: Ignoring MPD Status Update Apr 12 12:15:35 volumio volumio[1006]: info: Apr 12 12:15:35 volumio volumio[1006]: ---------------------------- MPD announces state update: player Apr 12 12:15:35 volumio volumio[1006]: info: ControllerMpd::getState Apr 12 12:15:35 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:15:35 volumio volumio[1006]: info: ------------------------------ 12ms Apr 12 12:15:35 volumio volumio[1006]: info: sendMpdCommand status took 12 milliseconds Apr 12 12:15:35 volumio volumio[1006]: info: sendMpdCommand playlistinfo took 10 milliseconds Apr 12 12:15:35 volumio volumio[1006]: info: sendMpdCommand playlistinfo took 10 milliseconds Apr 12 12:15:35 volumio volumio[1006]: info: ------------------------------ 10ms Apr 12 12:15:35 volumio volumio[1006]: info: sendMpdCommand status took 10 milliseconds Apr 12 12:15:35 volumio volumio[1006]: info: ------------------------------ 10ms Apr 12 12:15:35 volumio volumio[1006]: info: sendMpdCommand status took 9 milliseconds Apr 12 12:15:35 volumio volumio[1006]: verbose: ControllerMpd::parseState Apr 12 12:15:35 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:15:35 volumio volumio[1006]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:15:35 volumio volumio[1006]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:15:35 volumio volumio[1006]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:15:35 volumio volumio[1006]: verbose: ControllerMpd::parseState Apr 12 12:15:35 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:15:35 volumio volumio[1006]: verbose: ControllerMpd::parseState Apr 12 12:15:35 volumio volumio[1006]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:15:35 volumio volumio[1006]: info: ControllerMpd::pushState Apr 12 12:15:35 volumio volumio[1006]: info: CoreCommandRouter::servicePushState Apr 12 12:15:35 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:15:35 volumio volumio[1006]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"112 Kbps","isStreaming":false,"title":"Tracklist: https://dancewave.online","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Apr 12 12:15:35 volumio volumio[1006]: verbose: CURRENT POSITION 0 Apr 12 12:15:35 volumio volumio[1006]: info: CoreStateMachine::syncState stateService play Apr 12 12:15:35 volumio volumio[1006]: info: CoreStateMachine::syncState currentStatus play Apr 12 12:15:35 volumio volumio[1006]: info: Received an update from plugin. extracting info from payload Apr 12 12:15:35 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 12 12:15:35 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:15:35 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 12:15:35 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:15:35 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:15:35 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:15:35 volumio volumio[1006]: info: ControllerMpd::pushState Apr 12 12:15:35 volumio volumio[1006]: info: CoreCommandRouter::servicePushState Apr 12 12:15:35 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:15:35 volumio volumio[1006]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"112 Kbps","isStreaming":false,"title":"Tracklist: https://dancewave.online","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Apr 12 12:15:35 volumio volumio[1006]: verbose: CURRENT POSITION 0 Apr 12 12:15:35 volumio volumio[1006]: info: CoreStateMachine::syncState stateService play Apr 12 12:15:35 volumio volumio[1006]: info: CoreStateMachine::syncState currentStatus play Apr 12 12:15:35 volumio volumio[1006]: info: Received an update from plugin. extracting info from payload Apr 12 12:15:35 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 12 12:15:35 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:15:35 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:15:35 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:15:35 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:15:35 volumio volumio[1006]: info: ControllerMpd::pushState Apr 12 12:15:35 volumio volumio[1006]: info: CoreCommandRouter::servicePushState Apr 12 12:15:35 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:15:35 volumio volumio[1006]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"112 Kbps","isStreaming":false,"title":"Tracklist: https://dancewave.online","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Apr 12 12:15:35 volumio volumio[1006]: verbose: CURRENT POSITION 0 Apr 12 12:15:35 volumio volumio[1006]: info: CoreStateMachine::syncState stateService play Apr 12 12:15:35 volumio volumio[1006]: info: CoreStateMachine::syncState currentStatus play Apr 12 12:15:35 volumio volumio[1006]: info: Received an update from plugin. extracting info from payload Apr 12 12:15:35 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 12 12:15:35 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:15:35 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:15:35 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:15:35 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:15:35 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:35.679+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_PLAYING positionMs=196 volume=100 Apr 12 12:15:35 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:35.679+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id=https://dancewave.online:443/dance.mp3 title="Tracklist: https://dancewave.online" Apr 12 12:15:35 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:35.680+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_PLAYING positionMs=196 volume=100 Apr 12 12:15:35 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:35.680+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id=https://dancewave.online:443/dance.mp3 title="Tracklist: https://dancewave.online" Apr 12 12:15:35 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:35.680+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_PLAYING positionMs=196 volume=100 Apr 12 12:15:35 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:35.681+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id=https://dancewave.online:443/dance.mp3 title="Tracklist: https://dancewave.online" Apr 12 12:15:35 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:35.681+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_PLAYING positionMs=196 volume=100 Apr 12 12:15:35 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:35.681+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id=https://dancewave.online:443/dance.mp3 title="Tracklist: https://dancewave.online" Apr 12 12:15:35 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:35.682+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_PLAYING positionMs=196 volume=100 Apr 12 12:15:35 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:35.682+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id=https://dancewave.online:443/dance.mp3 title="Tracklist: https://dancewave.online" Apr 12 12:15:35 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:35.682+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_PLAYING positionMs=196 volume=100 Apr 12 12:15:35 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:35.682+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id=https://dancewave.online:443/dance.mp3 title="Tracklist: https://dancewave.online" Apr 12 12:15:35 volumio volumio[1006]: info: ------------------------------ 79ms Apr 12 12:15:35 volumio volumio[1006]: info: ------------------------------ 79ms Apr 12 12:15:35 volumio volumio[1006]: info: ------------------------------ 79ms Apr 12 12:15:35 volumio volumio[1006]: info: sendMpdCommand playlistinfo took 63 milliseconds Apr 12 12:15:35 volumio volumio[1006]: info: sendMpdCommand playlistinfo took 63 milliseconds Apr 12 12:15:35 volumio volumio[1006]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:15:35 volumio volumio[1006]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:15:35 volumio volumio[1006]: info: ControllerMpd::pushState Apr 12 12:15:35 volumio volumio[1006]: info: CoreCommandRouter::servicePushState Apr 12 12:15:35 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:15:35 volumio volumio[1006]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"112 Kbps","isStreaming":false,"title":"Tracklist: https://dancewave.online","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Apr 12 12:15:35 volumio volumio[1006]: verbose: CURRENT POSITION 0 Apr 12 12:15:35 volumio volumio[1006]: info: CoreStateMachine::syncState stateService play Apr 12 12:15:35 volumio volumio[1006]: info: CoreStateMachine::syncState currentStatus play Apr 12 12:15:35 volumio volumio[1006]: info: Received an update from plugin. extracting info from payload Apr 12 12:15:35 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 12 12:15:35 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:15:35 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 12:15:35 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:15:35 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:15:35 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:15:35 volumio volumio[1006]: info: ControllerMpd::pushState Apr 12 12:15:35 volumio volumio[1006]: info: CoreCommandRouter::servicePushState Apr 12 12:15:35 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:15:35 volumio volumio[1006]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"112 Kbps","isStreaming":false,"title":"Tracklist: https://dancewave.online","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Apr 12 12:15:35 volumio volumio[1006]: verbose: CURRENT POSITION 0 Apr 12 12:15:35 volumio volumio[1006]: info: CoreStateMachine::syncState stateService play Apr 12 12:15:35 volumio volumio[1006]: info: CoreStateMachine::syncState currentStatus play Apr 12 12:15:35 volumio volumio[1006]: info: Received an update from plugin. extracting info from payload Apr 12 12:15:35 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 12 12:15:35 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:15:35 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:15:35 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:15:35 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:15:35 volumio volumio[1006]: info: ControllerMpd::pushState Apr 12 12:15:35 volumio volumio[1006]: info: CoreCommandRouter::servicePushState Apr 12 12:15:35 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Apr 12 12:15:35 volumio volumio[1006]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"112 Kbps","isStreaming":false,"title":"Tracklist: https://dancewave.online","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Apr 12 12:15:35 volumio volumio[1006]: verbose: CURRENT POSITION 0 Apr 12 12:15:35 volumio volumio[1006]: info: CoreStateMachine::syncState stateService play Apr 12 12:15:35 volumio volumio[1006]: info: CoreStateMachine::syncState currentStatus play Apr 12 12:15:35 volumio volumio[1006]: info: Received an update from plugin. extracting info from payload Apr 12 12:15:35 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 12 12:15:35 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:15:35 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:15:35 volumio volumio[1006]: info: CoreStateMachine::pushState Apr 12 12:15:35 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Apr 12 12:15:35 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:35.745+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_PLAYING positionMs=263 volume=100 Apr 12 12:15:35 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:35.747+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id=https://dancewave.online:443/dance.mp3 title="Tracklist: https://dancewave.online" Apr 12 12:15:35 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:35.746+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_PLAYING positionMs=263 volume=100 Apr 12 12:15:35 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:35.748+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id=https://dancewave.online:443/dance.mp3 title="Tracklist: https://dancewave.online" Apr 12 12:15:35 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:35.748+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_PLAYING positionMs=263 volume=100 Apr 12 12:15:35 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:35.748+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id=https://dancewave.online:443/dance.mp3 title="Tracklist: https://dancewave.online" Apr 12 12:15:35 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:35.748+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_PLAYING positionMs=263 volume=100 Apr 12 12:15:35 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:35.749+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id=https://dancewave.online:443/dance.mp3 title="Tracklist: https://dancewave.online" Apr 12 12:15:35 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:35.749+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_PLAYING positionMs=263 volume=100 Apr 12 12:15:35 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:35.750+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id=https://dancewave.online:443/dance.mp3 title="Tracklist: https://dancewave.online" Apr 12 12:15:35 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:35.751+05:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" state=STATUS_PLAYING positionMs=263 volume=100 Apr 12 12:15:35 volumio volumio5-onboarding[1280]: time=2026-04-12T12:15:35.752+05:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.8:41766 @ 0x3096d20" id=https://dancewave.online:443/dance.mp3 title="Tracklist: https://dancewave.online" Apr 12 12:15:35 volumio volumio[1006]: info: ------------------------------ 140ms Apr 12 12:15:35 volumio volumio[1006]: info: ------------------------------ 138ms Apr 12 12:15:35 volumio volumio[1006]: info: ------------------------------ 137ms Apr 12 12:15:36 volumio volumio[1006]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 12 12:15:36 volumio volumio[1006]: TypeError: Cannot read property 'slice' of null Apr 12 12:15:36 volumio volumio[1006]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Apr 12 12:15:36 volumio volumio[1006]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Apr 12 12:15:36 volumio volumio[1006]: at Object.onceWrapper (events.js:422:26) Apr 12 12:15:36 volumio volumio[1006]: at ClientRequest.emit (events.js:315:20) Apr 12 12:15:36 volumio volumio[1006]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:641:27) Apr 12 12:15:36 volumio volumio[1006]: at HTTPParser.parserOnHeadersComplete (_http_common.js:126:17) Apr 12 12:15:36 volumio volumio[1006]: at TLSSocket.socketOnData (_http_client.js:509:22) Apr 12 12:15:36 volumio volumio[1006]: at TLSSocket.emit (events.js:315:20) Apr 12 12:15:36 volumio volumio[1006]: at addChunk (internal/streams/readable.js:309:12) Apr 12 12:15:36 volumio volumio[1006]: at readableAddChunk (internal/streams/readable.js:284:9) Apr 12 12:15:36 volumio volumio[1006]: at TLSSocket.Readable.push (internal/streams/readable.js:223:10) Apr 12 12:15:36 volumio volumio[1006]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:188:23) Apr 12 12:15:36 volumio volumio[1006]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 12 12:15:36 volumio sudo[2452]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-04-12 12:14 Apr 12 12:15:36 volumio sudo[2452]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 10:59:40 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="37c6ab864cb114e1344d540995c69f86"