-- Logs begin at Thu 2019-02-14 13:11:58 MSK, end at Tue 2026-02-24 18:50:06 MSK. -- Feb 24 18:49:00 volumio volumio[1088]: info: CoreCommandRouter::volumioPushQueue Feb 24 18:49:00 volumio volumio[1088]: info: CorePlayQueue::saveQueue Feb 24 18:49:00 volumio volumio[1088]: info: CoreStateMachine::updateTrackBlock Feb 24 18:49:00 volumio volumio[1088]: info: CorePlayQueue::getTrackBlock Feb 24 18:49:00 volumio volumio[1088]: info: CoreCommandRouter::volumioPlay Feb 24 18:49:00 volumio volumio[1088]: info: CoreStateMachine::play index 0 Feb 24 18:49:00 volumio volumio[1088]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 18:49:00 volumio volumio[1088]: info: CoreStateMachine::stop Feb 24 18:49:00 volumio volumio[1088]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 18:49:00 volumio volumio[1088]: info: CoreStateMachine::play index undefined Feb 24 18:49:00 volumio volumio[1088]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 18:49:00 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:00 volumio volumio[1088]: info: CoreStateMachine::startPlaybackTimer Feb 24 18:49:00 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:00 volumio volumio[1088]: info: [1771948140030] ControllerWebradio::clearAddPlayTrack Feb 24 18:49:00 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand stop Feb 24 18:49:00 volumio volumio[1088]: info: sendMpdCommand stop took 2 milliseconds Feb 24 18:49:00 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand clear Feb 24 18:49:00 volumio volumio[1088]: info: Feb 24 18:49:00 volumio volumio[1088]: ---------------------------- MPD announces system playlist update Feb 24 18:49:00 volumio volumio[1088]: info: Ignoring MPD Status Update Feb 24 18:49:00 volumio volumio[1088]: info: sendMpdCommand clear took 1 milliseconds Feb 24 18:49:00 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand load "https://listen.eaplfm.com/top.mp3" Feb 24 18:49:00 volumio volumio[1088]: info: Feb 24 18:49:00 volumio volumio[1088]: ---------------------------- MPD announces system playlist update Feb 24 18:49:00 volumio volumio[1088]: info: Ignoring MPD Status Update Feb 24 18:49:00 volumio volumio[1088]: info: Feb 24 18:49:00 volumio volumio[1088]: ---------------------------- MPD announces system playlist update Feb 24 18:49:00 volumio volumio[1088]: info: Ignoring MPD Status Update Feb 24 18:49:00 volumio volumio[1088]: error: updateQueue error: null Feb 24 18:49:00 volumio volumio[1088]: info: ------------------------------ 2ms Feb 24 18:49:01 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand add "https://listen.eaplfm.com/top.mp3" Feb 24 18:49:01 volumio volumio[1088]: error: updateQueue error: null Feb 24 18:49:01 volumio volumio[1088]: info: Feb 24 18:49:01 volumio volumio[1088]: ---------------------------- MPD announces system playlist update Feb 24 18:49:01 volumio volumio[1088]: info: Ignoring MPD Status Update Feb 24 18:49:01 volumio volumio[1088]: info: ------------------------------ 1068ms Feb 24 18:49:01 volumio volumio[1088]: info: sendMpdCommand add "https://listen.eaplfm.com/top.mp3" took 1 milliseconds Feb 24 18:49:01 volumio volumio[1088]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 24 18:49:01 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand play Feb 24 18:49:01 volumio volumio[1088]: info: Feb 24 18:49:01 volumio volumio[1088]: ---------------------------- MPD announces system playlist update Feb 24 18:49:01 volumio volumio[1088]: info: Ignoring MPD Status Update Feb 24 18:49:01 volumio volumio[1088]: info: Feb 24 18:49:01 volumio volumio[1088]: ---------------------------- MPD announces system playlist update Feb 24 18:49:01 volumio volumio[1088]: info: Ignoring MPD Status Update Feb 24 18:49:01 volumio volumio[1088]: info: ------------------------------ 3ms Feb 24 18:49:01 volumio volumio[1088]: info: sendMpdCommand play took 2 milliseconds Feb 24 18:49:01 volumio volumio[1088]: info: ------------------------------ 1ms Feb 24 18:49:01 volumio volumio[1088]: info: ------------------------------ 2ms Feb 24 18:49:01 volumio volumio[1088]: info: Feb 24 18:49:01 volumio volumio[1088]: ---------------------------- MPD announces state update: player Feb 24 18:49:01 volumio volumio[1088]: info: ControllerMpd::getState Feb 24 18:49:01 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:49:01 volumio volumio[1088]: info: Feb 24 18:49:01 volumio volumio[1088]: ---------------------------- MPD announces state update: player Feb 24 18:49:01 volumio volumio[1088]: info: ControllerMpd::getState Feb 24 18:49:01 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:49:01 volumio volumio[1088]: info: Feb 24 18:49:01 volumio volumio[1088]: ---------------------------- MPD announces state update: player Feb 24 18:49:01 volumio volumio[1088]: info: ControllerMpd::getState Feb 24 18:49:01 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:49:01 volumio volumio[1088]: info: sendMpdCommand status took 2 milliseconds Feb 24 18:49:01 volumio volumio[1088]: info: sendMpdCommand status took 1 milliseconds Feb 24 18:49:01 volumio volumio[1088]: verbose: ControllerMpd::parseState Feb 24 18:49:01 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:49:01 volumio volumio[1088]: verbose: ControllerMpd::parseState Feb 24 18:49:01 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:49:01 volumio volumio[1088]: info: sendMpdCommand status took 7 milliseconds Feb 24 18:49:01 volumio volumio[1088]: verbose: ControllerMpd::parseState Feb 24 18:49:01 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:49:01 volumio volumio[1088]: info: sendMpdCommand playlistinfo took 7 milliseconds Feb 24 18:49:01 volumio volumio[1088]: info: sendMpdCommand playlistinfo took 7 milliseconds Feb 24 18:49:01 volumio volumio[1088]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:49:01 volumio volumio[1088]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:49:01 volumio volumio[1088]: info: ControllerMpd::pushState Feb 24 18:49:01 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Feb 24 18:49:01 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:01 volumio volumio[1088]: 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":"top.mp3","artist":null,"album":null,"uri":"https://listen.eaplfm.com/top.mp3","trackType":"mp3"} Feb 24 18:49:01 volumio volumio[1088]: verbose: CURRENT POSITION 0 Feb 24 18:49:01 volumio volumio[1088]: info: CoreStateMachine::syncState stateService play Feb 24 18:49:01 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Feb 24 18:49:01 volumio volumio[1088]: info: ControllerMpd::pushState Feb 24 18:49:01 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Feb 24 18:49:01 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:01 volumio volumio[1088]: 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":"top.mp3","artist":null,"album":null,"uri":"https://listen.eaplfm.com/top.mp3","trackType":"mp3"} Feb 24 18:49:01 volumio volumio[1088]: verbose: CURRENT POSITION 0 Feb 24 18:49:01 volumio volumio[1088]: info: CoreStateMachine::syncState stateService play Feb 24 18:49:01 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus play Feb 24 18:49:01 volumio volumio[1088]: info: Received an update from plugin. extracting info from payload Feb 24 18:49:01 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:01 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:01 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 18:49:01 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:01 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:01 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:01 volumio volumio[1088]: info: ------------------------------ 15ms Feb 24 18:49:01 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:01.374+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" state=STATUS_PLAYING positionMs=0 volume=100 Feb 24 18:49:01 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:01.374+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" id=https://listen.eaplfm.com/top.mp3 title="Earth plusFM" Feb 24 18:49:01 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:01.375+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" state=STATUS_PLAYING positionMs=0 volume=100 Feb 24 18:49:01 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:01.375+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" id=https://listen.eaplfm.com/top.mp3 title="Earth plusFM" Feb 24 18:49:01 volumio volumio[1088]: info: ------------------------------ 18ms Feb 24 18:49:01 volumio volumio[1088]: info: Feb 24 18:49:01 volumio volumio[1088]: ---------------------------- MPD announces system playlist update Feb 24 18:49:01 volumio volumio[1088]: info: Ignoring MPD Status Update Feb 24 18:49:01 volumio volumio[1088]: info: Feb 24 18:49:01 volumio volumio[1088]: ---------------------------- MPD announces state update: player Feb 24 18:49:01 volumio volumio[1088]: info: sendMpdCommand playlistinfo took 10 milliseconds Feb 24 18:49:01 volumio volumio[1088]: info: ControllerMpd::getState Feb 24 18:49:01 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:49:01 volumio volumio[1088]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:49:01 volumio volumio[1088]: info: ControllerMpd::pushState Feb 24 18:49:01 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Feb 24 18:49:01 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:01 volumio volumio[1088]: 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":"top.mp3","artist":null,"album":null,"uri":"https://listen.eaplfm.com/top.mp3","trackType":"mp3"} Feb 24 18:49:01 volumio volumio[1088]: verbose: CURRENT POSITION 0 Feb 24 18:49:01 volumio volumio[1088]: info: CoreStateMachine::syncState stateService play Feb 24 18:49:01 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus play Feb 24 18:49:01 volumio volumio[1088]: info: Received an update from plugin. extracting info from payload Feb 24 18:49:01 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:01 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:01 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:01 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:01 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:01 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:01.386+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" state=STATUS_PLAYING positionMs=0 volume=100 Feb 24 18:49:01 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:01.386+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" id=https://listen.eaplfm.com/top.mp3 title="Earth plusFM" Feb 24 18:49:01 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:01.387+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" state=STATUS_PLAYING positionMs=0 volume=100 Feb 24 18:49:01 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:01.387+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" id=https://listen.eaplfm.com/top.mp3 title="Earth plusFM" Feb 24 18:49:01 volumio volumio[1088]: info: ------------------------------ 30ms Feb 24 18:49:01 volumio volumio[1088]: info: Feb 24 18:49:01 volumio volumio[1088]: ---------------------------- MPD announces system playlist update Feb 24 18:49:01 volumio volumio[1088]: info: Ignoring MPD Status Update Feb 24 18:49:01 volumio volumio[1088]: info: Feb 24 18:49:01 volumio volumio[1088]: ---------------------------- MPD announces state update: player Feb 24 18:49:01 volumio volumio[1088]: info: ControllerMpd::getState Feb 24 18:49:01 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:49:01 volumio volumio[1088]: info: Feb 24 18:49:01 volumio volumio[1088]: ---------------------------- MPD announces system playlist update Feb 24 18:49:01 volumio volumio[1088]: info: Ignoring MPD Status Update Feb 24 18:49:01 volumio volumio[1088]: info: Feb 24 18:49:01 volumio volumio[1088]: ---------------------------- MPD announces state update: player Feb 24 18:49:01 volumio volumio[1088]: info: ControllerMpd::getState Feb 24 18:49:01 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:49:01 volumio volumio[1088]: info: Feb 24 18:49:01 volumio volumio[1088]: ---------------------------- MPD announces system playlist update Feb 24 18:49:01 volumio volumio[1088]: info: Ignoring MPD Status Update Feb 24 18:49:01 volumio volumio[1088]: info: Feb 24 18:49:01 volumio volumio[1088]: ---------------------------- MPD announces state update: player Feb 24 18:49:01 volumio volumio[1088]: info: ControllerMpd::getState Feb 24 18:49:01 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:49:01 volumio volumio[1088]: info: ------------------------------ 43ms Feb 24 18:49:01 volumio volumio[1088]: info: sendMpdCommand status took 43 milliseconds Feb 24 18:49:01 volumio volumio[1088]: info: ------------------------------ 31ms Feb 24 18:49:01 volumio volumio[1088]: info: sendMpdCommand status took 30 milliseconds Feb 24 18:49:01 volumio volumio[1088]: info: ------------------------------ 30ms Feb 24 18:49:01 volumio volumio[1088]: info: sendMpdCommand status took 29 milliseconds Feb 24 18:49:01 volumio volumio[1088]: verbose: ControllerMpd::parseState Feb 24 18:49:01 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:49:01 volumio volumio[1088]: verbose: ControllerMpd::parseState Feb 24 18:49:01 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:49:01 volumio volumio[1088]: verbose: ControllerMpd::parseState Feb 24 18:49:01 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:49:01 volumio volumio[1088]: info: Feb 24 18:49:01 volumio volumio[1088]: ---------------------------- MPD announces system playlist update Feb 24 18:49:01 volumio volumio[1088]: info: Ignoring MPD Status Update Feb 24 18:49:01 volumio volumio[1088]: info: Feb 24 18:49:01 volumio volumio[1088]: ---------------------------- MPD announces state update: player Feb 24 18:49:01 volumio volumio[1088]: info: ControllerMpd::getState Feb 24 18:49:01 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:49:01 volumio volumio[1088]: info: Feb 24 18:49:01 volumio volumio[1088]: ---------------------------- MPD announces system playlist update Feb 24 18:49:01 volumio volumio[1088]: info: Ignoring MPD Status Update Feb 24 18:49:01 volumio volumio[1088]: info: Feb 24 18:49:01 volumio volumio[1088]: ---------------------------- MPD announces state update: player Feb 24 18:49:01 volumio volumio[1088]: info: ControllerMpd::getState Feb 24 18:49:01 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:49:01 volumio volumio[1088]: info: ------------------------------ 29ms Feb 24 18:49:01 volumio volumio[1088]: info: sendMpdCommand status took 28 milliseconds Feb 24 18:49:01 volumio volumio[1088]: info: sendMpdCommand playlistinfo took 25 milliseconds Feb 24 18:49:01 volumio volumio[1088]: info: sendMpdCommand playlistinfo took 25 milliseconds Feb 24 18:49:01 volumio volumio[1088]: info: sendMpdCommand playlistinfo took 25 milliseconds Feb 24 18:49:01 volumio volumio[1088]: info: ------------------------------ 25ms Feb 24 18:49:01 volumio volumio[1088]: info: sendMpdCommand status took 24 milliseconds Feb 24 18:49:01 volumio volumio[1088]: info: ------------------------------ 24ms Feb 24 18:49:01 volumio volumio[1088]: info: sendMpdCommand status took 23 milliseconds Feb 24 18:49:01 volumio volumio[1088]: verbose: ControllerMpd::parseState Feb 24 18:49:01 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:49:01 volumio volumio[1088]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:49:01 volumio volumio[1088]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:49:01 volumio volumio[1088]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:49:01 volumio volumio[1088]: verbose: ControllerMpd::parseState Feb 24 18:49:01 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:49:01 volumio volumio[1088]: verbose: ControllerMpd::parseState Feb 24 18:49:01 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:49:01 volumio volumio[1088]: info: ControllerMpd::pushState Feb 24 18:49:01 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Feb 24 18:49:01 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:01 volumio volumio[1088]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Zedd - Beautiful Now (Grey Remix) [f","artist":"Earth plus","album":null,"uri":"https://listen.eaplfm.com/top.mp3","trackType":"mp3"} Feb 24 18:49:01 volumio volumio[1088]: verbose: CURRENT POSITION 0 Feb 24 18:49:01 volumio volumio[1088]: info: CoreStateMachine::syncState stateService play Feb 24 18:49:01 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus play Feb 24 18:49:01 volumio volumio[1088]: info: Received an update from plugin. extracting info from payload Feb 24 18:49:01 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:01 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 18:49:01 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:01 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:01 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:01 volumio volumio[1088]: info: ControllerMpd::pushState Feb 24 18:49:01 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Feb 24 18:49:01 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:01 volumio volumio[1088]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Zedd - Beautiful Now (Grey Remix) [f","artist":"Earth plus","album":null,"uri":"https://listen.eaplfm.com/top.mp3","trackType":"mp3"} Feb 24 18:49:01 volumio volumio[1088]: verbose: CURRENT POSITION 0 Feb 24 18:49:01 volumio volumio[1088]: info: CoreStateMachine::syncState stateService play Feb 24 18:49:01 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus play Feb 24 18:49:01 volumio volumio[1088]: info: Received an update from plugin. extracting info from payload Feb 24 18:49:01 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:01 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:01 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:01 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:01 volumio volumio[1088]: info: ControllerMpd::pushState Feb 24 18:49:01 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Feb 24 18:49:01 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:01 volumio volumio[1088]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Zedd - Beautiful Now (Grey Remix) [f","artist":"Earth plus","album":null,"uri":"https://listen.eaplfm.com/top.mp3","trackType":"mp3"} Feb 24 18:49:01 volumio volumio[1088]: verbose: CURRENT POSITION 0 Feb 24 18:49:01 volumio volumio[1088]: info: CoreStateMachine::syncState stateService play Feb 24 18:49:01 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus play Feb 24 18:49:01 volumio volumio[1088]: info: Received an update from plugin. extracting info from payload Feb 24 18:49:01 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:01 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:01 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:01 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:01 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:01.475+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" state=STATUS_PLAYING positionMs=0 volume=100 Feb 24 18:49:01 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:01.475+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" id=https://listen.eaplfm.com/top.mp3 title="Zedd - Beautiful Now (Grey Remix) [f" Feb 24 18:49:01 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:01.477+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" state=STATUS_PLAYING positionMs=0 volume=100 Feb 24 18:49:01 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:01.477+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" id=https://listen.eaplfm.com/top.mp3 title="Zedd - Beautiful Now (Grey Remix) [f" Feb 24 18:49:01 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:01.478+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" state=STATUS_PLAYING positionMs=0 volume=100 Feb 24 18:49:01 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:01.478+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" id=https://listen.eaplfm.com/top.mp3 title="Zedd - Beautiful Now (Grey Remix) [f" Feb 24 18:49:01 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:01.480+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" state=STATUS_PLAYING positionMs=0 volume=100 Feb 24 18:49:01 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:01.480+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" id=https://listen.eaplfm.com/top.mp3 title="Zedd - Beautiful Now (Grey Remix) [f" Feb 24 18:49:01 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:01.481+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" state=STATUS_PLAYING positionMs=0 volume=100 Feb 24 18:49:01 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:01.481+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" id=https://listen.eaplfm.com/top.mp3 title="Zedd - Beautiful Now (Grey Remix) [f" Feb 24 18:49:01 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:01.482+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" state=STATUS_PLAYING positionMs=0 volume=100 Feb 24 18:49:01 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:01.482+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" id=https://listen.eaplfm.com/top.mp3 title="Zedd - Beautiful Now (Grey Remix) [f" Feb 24 18:49:01 volumio volumio[1088]: info: ------------------------------ 112ms Feb 24 18:49:01 volumio volumio[1088]: info: ------------------------------ 100ms Feb 24 18:49:01 volumio volumio[1088]: info: ------------------------------ 99ms Feb 24 18:49:01 volumio volumio[1088]: info: sendMpdCommand playlistinfo took 43 milliseconds Feb 24 18:49:01 volumio volumio[1088]: info: sendMpdCommand playlistinfo took 43 milliseconds Feb 24 18:49:01 volumio volumio[1088]: info: sendMpdCommand playlistinfo took 43 milliseconds Feb 24 18:49:01 volumio volumio[1088]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:49:01 volumio volumio[1088]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:49:01 volumio volumio[1088]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:49:01 volumio volumio[1088]: info: ControllerMpd::pushState Feb 24 18:49:01 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Feb 24 18:49:01 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:01 volumio volumio[1088]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Zedd - Beautiful Now (Grey Remix) [f","artist":"Earth plus","album":null,"uri":"https://listen.eaplfm.com/top.mp3","trackType":"mp3"} Feb 24 18:49:01 volumio volumio[1088]: verbose: CURRENT POSITION 0 Feb 24 18:49:01 volumio volumio[1088]: info: CoreStateMachine::syncState stateService play Feb 24 18:49:01 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus play Feb 24 18:49:01 volumio volumio[1088]: info: Received an update from plugin. extracting info from payload Feb 24 18:49:01 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:01 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:01 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:01 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:01 volumio volumio[1088]: info: ControllerMpd::pushState Feb 24 18:49:01 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Feb 24 18:49:01 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:01 volumio volumio[1088]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Zedd - Beautiful Now (Grey Remix) [f","artist":"Earth plus","album":null,"uri":"https://listen.eaplfm.com/top.mp3","trackType":"mp3"} Feb 24 18:49:01 volumio volumio[1088]: verbose: CURRENT POSITION 0 Feb 24 18:49:01 volumio volumio[1088]: info: CoreStateMachine::syncState stateService play Feb 24 18:49:01 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus play Feb 24 18:49:01 volumio volumio[1088]: info: Received an update from plugin. extracting info from payload Feb 24 18:49:01 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:01 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:01 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:01 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:01 volumio volumio[1088]: info: ControllerMpd::pushState Feb 24 18:49:01 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Feb 24 18:49:01 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:01 volumio volumio[1088]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Zedd - Beautiful Now (Grey Remix) [f","artist":"Earth plus","album":null,"uri":"https://listen.eaplfm.com/top.mp3","trackType":"mp3"} Feb 24 18:49:01 volumio volumio[1088]: verbose: CURRENT POSITION 0 Feb 24 18:49:01 volumio volumio[1088]: info: CoreStateMachine::syncState stateService play Feb 24 18:49:01 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus play Feb 24 18:49:01 volumio volumio[1088]: info: Received an update from plugin. extracting info from payload Feb 24 18:49:01 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:01 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:01 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:01 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:01 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:01.504+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" state=STATUS_PLAYING positionMs=0 volume=100 Feb 24 18:49:01 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:01.504+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" id=https://listen.eaplfm.com/top.mp3 title="Zedd - Beautiful Now (Grey Remix) [f" Feb 24 18:49:01 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:01.505+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" state=STATUS_PLAYING positionMs=0 volume=100 Feb 24 18:49:01 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:01.505+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" id=https://listen.eaplfm.com/top.mp3 title="Zedd - Beautiful Now (Grey Remix) [f" Feb 24 18:49:01 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:01.506+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" state=STATUS_PLAYING positionMs=0 volume=100 Feb 24 18:49:01 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:01.506+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" id=https://listen.eaplfm.com/top.mp3 title="Zedd - Beautiful Now (Grey Remix) [f" Feb 24 18:49:01 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:01.507+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" state=STATUS_PLAYING positionMs=0 volume=100 Feb 24 18:49:01 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:01.507+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" id=https://listen.eaplfm.com/top.mp3 title="Zedd - Beautiful Now (Grey Remix) [f" Feb 24 18:49:01 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:01.507+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" state=STATUS_PLAYING positionMs=0 volume=100 Feb 24 18:49:01 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:01.508+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" id=https://listen.eaplfm.com/top.mp3 title="Zedd - Beautiful Now (Grey Remix) [f" Feb 24 18:49:01 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:01.508+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" state=STATUS_PLAYING positionMs=0 volume=100 Feb 24 18:49:01 volumio volumio[1088]: info: ------------------------------ 92ms Feb 24 18:49:01 volumio volumio[1088]: info: ------------------------------ 86ms Feb 24 18:49:01 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:01.512+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" id=https://listen.eaplfm.com/top.mp3 title="Zedd - Beautiful Now (Grey Remix) [f" Feb 24 18:49:01 volumio volumio[1088]: info: touch_display: Setting screensaver timeout to 0 seconds. Feb 24 18:49:05 volumio volumio[1088]: info: Preload queue cleared Feb 24 18:49:05 volumio volumio[1088]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 24 18:49:05 volumio volumio[1088]: info: CoreStateMachine::ClearQueue Feb 24 18:49:05 volumio volumio[1088]: info: CoreStateMachine::stop Feb 24 18:49:05 volumio volumio[1088]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 18:49:05 volumio volumio[1088]: info: CoreStateMachine::stPlaybackTimer Feb 24 18:49:05 volumio volumio[1088]: info: CoreStateMachine::updateTrackBlock Feb 24 18:49:05 volumio volumio[1088]: info: CorePlayQueue::getTrackBlock Feb 24 18:49:05 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:05 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:05 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 18:49:05 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:05 volumio volumio[1088]: info: CoreStateMachine::serviceStop Feb 24 18:49:05 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:05 volumio volumio[1088]: info: CoreCommandRouter::serviceStop Feb 24 18:49:05 volumio volumio[1088]: info: [1771948145927] ControllerWebradio::stop Feb 24 18:49:05 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand stop Feb 24 18:49:05 volumio volumio[1088]: info: CorePlayQueue::clearPlayQueue Feb 24 18:49:05 volumio volumio[1088]: info: CorePlayQueue::saveQueue Feb 24 18:49:05 volumio volumio[1088]: info: CoreCommandRouter::volumioPushQueue Feb 24 18:49:05 volumio volumio[1088]: info: CoreStateMachine::addQueueItems Feb 24 18:49:05 volumio volumio[1088]: info: CorePlayQueue::addQueueItems Feb 24 18:49:05 volumio volumio[1088]: info: Preload queue cleared Feb 24 18:49:05 volumio volumio[1088]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s254559 Feb 24 18:49:05 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Feb 24 18:49:05 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:05.930+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" state=STATUS_STOPPED positionMs=0 volume=100 Feb 24 18:49:05 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:05.930+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" id=https://listen.eaplfm.com/top.mp3 title="Earth plusFM" Feb 24 18:49:05 volumio volumio[1088]: info: CoreCommandRouter::volumioPushQueue Feb 24 18:49:05 volumio volumio[1088]: info: CorePlayQueue::saveQueue Feb 24 18:49:05 volumio volumio[1088]: info: CoreStateMachine::updateTrackBlock Feb 24 18:49:05 volumio volumio[1088]: info: CorePlayQueue::getTrackBlock Feb 24 18:49:05 volumio volumio[1088]: info: CoreCommandRouter::volumioPlay Feb 24 18:49:05 volumio volumio[1088]: info: CoreStateMachine::play index 0 Feb 24 18:49:05 volumio volumio[1088]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 18:49:05 volumio volumio[1088]: info: CoreStateMachine::stop Feb 24 18:49:05 volumio volumio[1088]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 18:49:05 volumio volumio[1088]: info: CoreStateMachine::play index undefined Feb 24 18:49:05 volumio volumio[1088]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 18:49:05 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:05 volumio volumio[1088]: info: CoreStateMachine::startPlaybackTimer Feb 24 18:49:05 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:05 volumio volumio[1088]: info: [1771948145937] ControllerWebradio::clearAddPlayTrack Feb 24 18:49:05 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand stop Feb 24 18:49:05 volumio volumio[1088]: info: Feb 24 18:49:05 volumio volumio[1088]: ---------------------------- MPD announces state update: player Feb 24 18:49:05 volumio volumio[1088]: info: sendMpdCommand stop took 17 milliseconds Feb 24 18:49:05 volumio volumio[1088]: info: ControllerMpd::getState Feb 24 18:49:05 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:49:05 volumio volumio[1088]: info: sendMpdCommand stop took 10 milliseconds Feb 24 18:49:05 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand clear Feb 24 18:49:05 volumio volumio[1088]: info: Feb 24 18:49:05 volumio volumio[1088]: ---------------------------- MPD announces state update: player Feb 24 18:49:05 volumio volumio[1088]: info: ControllerMpd::getState Feb 24 18:49:05 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:49:05 volumio volumio[1088]: info: Feb 24 18:49:05 volumio volumio[1088]: ---------------------------- MPD announces state update: player Feb 24 18:49:05 volumio volumio[1088]: info: ControllerMpd::getState Feb 24 18:49:05 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:49:05 volumio volumio[1088]: info: Feb 24 18:49:05 volumio volumio[1088]: ---------------------------- MPD announces system playlist update Feb 24 18:49:05 volumio volumio[1088]: info: Ignoring MPD Status Update Feb 24 18:49:05 volumio volumio[1088]: info: sendMpdCommand status took 3 milliseconds Feb 24 18:49:05 volumio volumio[1088]: info: sendMpdCommand clear took 3 milliseconds Feb 24 18:49:05 volumio volumio[1088]: info: sendMpdCommand status took 2 milliseconds Feb 24 18:49:05 volumio volumio[1088]: info: sendMpdCommand status took 2 milliseconds Feb 24 18:49:05 volumio volumio[1088]: verbose: ControllerMpd::parseState Feb 24 18:49:05 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:49:05 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand load "http://178.172.150.248:2530" Feb 24 18:49:05 volumio volumio[1088]: verbose: ControllerMpd::parseState Feb 24 18:49:05 volumio volumio[1088]: verbose: ControllerMpd::parseState Feb 24 18:49:05 volumio volumio[1088]: info: ControllerMpd::pushState Feb 24 18:49:05 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Feb 24 18:49:05 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:05 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:05 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 18:49:05 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:05 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:05 volumio volumio[1088]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 24 18:49:05 volumio volumio[1088]: info: ControllerMpd::pushState Feb 24 18:49:05 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Feb 24 18:49:05 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:05 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:05 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:05 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:05 volumio volumio[1088]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 24 18:49:05 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:05.958+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" state=STATUS_STOPPED positionMs=0 volume=100 Feb 24 18:49:05 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:05.958+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" state=STATUS_STOPPED positionMs=0 volume=100 Feb 24 18:49:05 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:05.959+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" id=http://178.172.150.248:2530 title="Radio Skif" Feb 24 18:49:05 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:05.960+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" id=http://178.172.150.248:2530 title="Radio Skif" Feb 24 18:49:05 volumio volumio[1088]: info: ------------------------------ 11ms Feb 24 18:49:05 volumio volumio[1088]: info: ------------------------------ 11ms Feb 24 18:49:05 volumio volumio[1088]: info: Feb 24 18:49:05 volumio volumio[1088]: ---------------------------- MPD announces system playlist update Feb 24 18:49:05 volumio volumio[1088]: info: Ignoring MPD Status Update Feb 24 18:49:05 volumio volumio[1088]: info: Feb 24 18:49:05 volumio volumio[1088]: ---------------------------- MPD announces system playlist update Feb 24 18:49:05 volumio volumio[1088]: info: Ignoring MPD Status Update Feb 24 18:49:05 volumio volumio[1088]: error: updateQueue error: null Feb 24 18:49:05 volumio volumio[1088]: info: ------------------------------ 32ms Feb 24 18:49:05 volumio volumio[1088]: info: sendMpdCommand playlistinfo took 31 milliseconds Feb 24 18:49:05 volumio volumio[1088]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:49:05 volumio volumio[1088]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 24 18:49:05 volumio volumio[1088]: info: ------------------------------ 42ms Feb 24 18:49:05 volumio volumio[1088]: info: touch_display: Setting screensaver timeout to 10000000000 seconds. Feb 24 18:49:06 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand add "http://178.172.150.248:2530" Feb 24 18:49:06 volumio volumio[1088]: error: updateQueue error: null Feb 24 18:49:06 volumio volumio[1088]: error: updateQueue error: null Feb 24 18:49:06 volumio volumio[1088]: info: Feb 24 18:49:06 volumio volumio[1088]: ---------------------------- MPD announces system playlist update Feb 24 18:49:06 volumio volumio[1088]: info: Ignoring MPD Status Update Feb 24 18:49:06 volumio volumio[1088]: info: ------------------------------ 314ms Feb 24 18:49:06 volumio volumio[1088]: info: ------------------------------ 313ms Feb 24 18:49:06 volumio volumio[1088]: info: sendMpdCommand add "http://178.172.150.248:2530" took 1 milliseconds Feb 24 18:49:06 volumio volumio[1088]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 24 18:49:06 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand play Feb 24 18:49:06 volumio volumio[1088]: info: Feb 24 18:49:06 volumio volumio[1088]: ---------------------------- MPD announces system playlist update Feb 24 18:49:06 volumio volumio[1088]: info: Ignoring MPD Status Update Feb 24 18:49:06 volumio volumio[1088]: info: Feb 24 18:49:06 volumio volumio[1088]: ---------------------------- MPD announces system playlist update Feb 24 18:49:06 volumio volumio[1088]: info: Ignoring MPD Status Update Feb 24 18:49:06 volumio volumio[1088]: info: ------------------------------ 3ms Feb 24 18:49:06 volumio volumio[1088]: info: sendMpdCommand play took 3 milliseconds Feb 24 18:49:06 volumio volumio[1088]: info: ------------------------------ 2ms Feb 24 18:49:06 volumio volumio[1088]: info: ------------------------------ 1ms Feb 24 18:49:06 volumio volumio[1088]: info: Feb 24 18:49:06 volumio volumio[1088]: ---------------------------- MPD announces state update: player Feb 24 18:49:06 volumio volumio[1088]: info: ControllerMpd::getState Feb 24 18:49:06 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:49:06 volumio volumio[1088]: info: Feb 24 18:49:06 volumio volumio[1088]: ---------------------------- MPD announces state update: player Feb 24 18:49:06 volumio volumio[1088]: info: ControllerMpd::getState Feb 24 18:49:06 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:49:06 volumio volumio[1088]: info: Feb 24 18:49:06 volumio volumio[1088]: ---------------------------- MPD announces state update: player Feb 24 18:49:06 volumio volumio[1088]: info: ControllerMpd::getState Feb 24 18:49:06 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:49:06 volumio volumio[1088]: info: sendMpdCommand status took 2 milliseconds Feb 24 18:49:06 volumio volumio[1088]: info: sendMpdCommand status took 1 milliseconds Feb 24 18:49:06 volumio volumio[1088]: info: sendMpdCommand status took 1 milliseconds Feb 24 18:49:06 volumio volumio[1088]: verbose: ControllerMpd::parseState Feb 24 18:49:06 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:49:06 volumio volumio[1088]: verbose: ControllerMpd::parseState Feb 24 18:49:06 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:49:06 volumio volumio[1088]: verbose: ControllerMpd::parseState Feb 24 18:49:06 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:49:06 volumio volumio[1088]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 24 18:49:06 volumio volumio[1088]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 24 18:49:06 volumio volumio[1088]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 24 18:49:06 volumio volumio[1088]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:49:06 volumio volumio[1088]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:49:06 volumio volumio[1088]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:49:06 volumio volumio[1088]: info: ControllerMpd::pushState Feb 24 18:49:06 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Feb 24 18:49:06 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:06 volumio volumio[1088]: 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":"178.172.150.248:2530","artist":null,"album":null,"uri":"http://178.172.150.248:2530","trackType":"248:2530"} Feb 24 18:49:06 volumio volumio[1088]: verbose: CURRENT POSITION 0 Feb 24 18:49:06 volumio volumio[1088]: info: CoreStateMachine::syncState stateService play Feb 24 18:49:06 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Feb 24 18:49:06 volumio volumio[1088]: info: ControllerMpd::pushState Feb 24 18:49:06 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Feb 24 18:49:06 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:06 volumio volumio[1088]: 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":"178.172.150.248:2530","artist":null,"album":null,"uri":"http://178.172.150.248:2530","trackType":"248:2530"} Feb 24 18:49:06 volumio volumio[1088]: verbose: CURRENT POSITION 0 Feb 24 18:49:06 volumio volumio[1088]: info: CoreStateMachine::syncState stateService play Feb 24 18:49:06 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus play Feb 24 18:49:06 volumio volumio[1088]: info: Received an update from plugin. extracting info from payload Feb 24 18:49:06 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:06 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:06 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 18:49:06 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:06 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:06 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:06 volumio volumio[1088]: info: ControllerMpd::pushState Feb 24 18:49:06 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Feb 24 18:49:06 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:06 volumio volumio[1088]: 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":"178.172.150.248:2530","artist":null,"album":null,"uri":"http://178.172.150.248:2530","trackType":"248:2530"} Feb 24 18:49:06 volumio volumio[1088]: verbose: CURRENT POSITION 0 Feb 24 18:49:06 volumio volumio[1088]: info: CoreStateMachine::syncState stateService play Feb 24 18:49:06 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus play Feb 24 18:49:06 volumio volumio[1088]: info: Received an update from plugin. extracting info from payload Feb 24 18:49:06 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:06 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:06 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:06 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:06 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:06 volumio volumio[1088]: info: ------------------------------ 10ms Feb 24 18:49:06 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:06.607+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" state=STATUS_PLAYING positionMs=0 volume=100 Feb 24 18:49:06 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:06.607+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" id=http://178.172.150.248:2530 title="Radio Skif" Feb 24 18:49:06 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:06.608+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" state=STATUS_PLAYING positionMs=0 volume=100 Feb 24 18:49:06 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:06.608+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" id=http://178.172.150.248:2530 title="Radio Skif" Feb 24 18:49:06 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:06.608+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" state=STATUS_PLAYING positionMs=0 volume=100 Feb 24 18:49:06 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:06.609+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" id=http://178.172.150.248:2530 title="Radio Skif" Feb 24 18:49:06 volumio volumio[1088]: info: ------------------------------ 18ms Feb 24 18:49:06 volumio volumio[1088]: info: ------------------------------ 18ms Feb 24 18:49:06 volumio volumio[1088]: info: touch_display: Setting screensaver timeout to 0 seconds. Feb 24 18:49:06 volumio volumio[1088]: info: Feb 24 18:49:06 volumio volumio[1088]: ---------------------------- MPD announces system playlist update Feb 24 18:49:06 volumio volumio[1088]: info: Ignoring MPD Status Update Feb 24 18:49:06 volumio volumio[1088]: info: Feb 24 18:49:06 volumio volumio[1088]: ---------------------------- MPD announces state update: player Feb 24 18:49:06 volumio volumio[1088]: info: ControllerMpd::getState Feb 24 18:49:06 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:49:06 volumio volumio[1088]: info: Feb 24 18:49:06 volumio volumio[1088]: ---------------------------- MPD announces system playlist update Feb 24 18:49:06 volumio volumio[1088]: info: Ignoring MPD Status Update Feb 24 18:49:06 volumio volumio[1088]: info: Feb 24 18:49:06 volumio volumio[1088]: ---------------------------- MPD announces state update: player Feb 24 18:49:06 volumio volumio[1088]: info: ControllerMpd::getState Feb 24 18:49:06 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:49:06 volumio volumio[1088]: info: Feb 24 18:49:06 volumio volumio[1088]: ---------------------------- MPD announces system playlist update Feb 24 18:49:06 volumio volumio[1088]: info: Ignoring MPD Status Update Feb 24 18:49:06 volumio volumio[1088]: info: Feb 24 18:49:06 volumio volumio[1088]: ---------------------------- MPD announces state update: player Feb 24 18:49:06 volumio volumio[1088]: info: ControllerMpd::getState Feb 24 18:49:06 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:49:06 volumio volumio[1088]: info: ------------------------------ 3ms Feb 24 18:49:06 volumio volumio[1088]: info: sendMpdCommand status took 2 milliseconds Feb 24 18:49:06 volumio volumio[1088]: info: ------------------------------ 2ms Feb 24 18:49:06 volumio volumio[1088]: info: sendMpdCommand status took 1 milliseconds Feb 24 18:49:06 volumio volumio[1088]: verbose: ControllerMpd::parseState Feb 24 18:49:06 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:49:06 volumio volumio[1088]: verbose: ControllerMpd::parseState Feb 24 18:49:06 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:49:06 volumio volumio[1088]: info: ------------------------------ 5ms Feb 24 18:49:06 volumio volumio[1088]: info: sendMpdCommand status took 5 milliseconds Feb 24 18:49:06 volumio volumio[1088]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 24 18:49:06 volumio volumio[1088]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 24 18:49:06 volumio volumio[1088]: verbose: ControllerMpd::parseState Feb 24 18:49:06 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:49:06 volumio volumio[1088]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:49:06 volumio volumio[1088]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:49:06 volumio volumio[1088]: info: ControllerMpd::pushState Feb 24 18:49:06 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Feb 24 18:49:06 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:06 volumio volumio[1088]: verbose: STATE SERVICE {"status":"play","position":0,"seek":491,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"178.172.150.248:2530","artist":"SKIF","album":null,"uri":"http://178.172.150.248:2530","trackType":"248:2530"} Feb 24 18:49:06 volumio volumio[1088]: verbose: CURRENT POSITION 0 Feb 24 18:49:06 volumio volumio[1088]: info: CoreStateMachine::syncState stateService play Feb 24 18:49:06 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus play Feb 24 18:49:06 volumio volumio[1088]: info: Received an update from plugin. extracting info from payload Feb 24 18:49:06 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:06 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:06 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 18:49:06 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:06 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:06 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:06 volumio volumio[1088]: info: ControllerMpd::pushState Feb 24 18:49:06 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Feb 24 18:49:06 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:06 volumio volumio[1088]: verbose: STATE SERVICE {"status":"play","position":0,"seek":617,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"178.172.150.248:2530","artist":"SKIF","album":null,"uri":"http://178.172.150.248:2530","trackType":"248:2530"} Feb 24 18:49:06 volumio volumio[1088]: verbose: CURRENT POSITION 0 Feb 24 18:49:06 volumio volumio[1088]: info: CoreStateMachine::syncState stateService play Feb 24 18:49:06 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus play Feb 24 18:49:06 volumio volumio[1088]: info: Received an update from plugin. extracting info from payload Feb 24 18:49:06 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:06 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:06 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:06 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:06 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:06 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:06.769+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" state=STATUS_PLAYING positionMs=152 volume=100 Feb 24 18:49:06 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:06.770+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" state=STATUS_PLAYING positionMs=152 volume=100 Feb 24 18:49:06 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:06.770+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" id=http://178.172.150.248:2530 title="Radio Skif" Feb 24 18:49:06 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:06.770+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" state=STATUS_PLAYING positionMs=152 volume=100 Feb 24 18:49:06 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:06.770+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" id=http://178.172.150.248:2530 title="Radio Skif" Feb 24 18:49:06 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:06.770+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" state=STATUS_PLAYING positionMs=152 volume=100 Feb 24 18:49:06 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:06.770+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" id=http://178.172.150.248:2530 title="Radio Skif" Feb 24 18:49:06 volumio volumio[1088]: info: ------------------------------ 23ms Feb 24 18:49:06 volumio volumio[1088]: info: ------------------------------ 23ms Feb 24 18:49:06 volumio volumio[1088]: info: sendMpdCommand playlistinfo took 16 milliseconds Feb 24 18:49:06 volumio volumio[1088]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:49:06 volumio volumio[1088]: info: ControllerMpd::pushState Feb 24 18:49:06 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Feb 24 18:49:06 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:06 volumio volumio[1088]: verbose: STATE SERVICE {"status":"play","position":0,"seek":685,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"178.172.150.248:2530","artist":"SKIF","album":null,"uri":"http://178.172.150.248:2530","trackType":"248:2530"} Feb 24 18:49:06 volumio volumio[1088]: verbose: CURRENT POSITION 0 Feb 24 18:49:06 volumio volumio[1088]: info: CoreStateMachine::syncState stateService play Feb 24 18:49:06 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus play Feb 24 18:49:06 volumio volumio[1088]: info: Received an update from plugin. extracting info from payload Feb 24 18:49:06 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:06 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:06 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:06 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:06 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:06 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:06.781+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" state=STATUS_PLAYING positionMs=152 volume=100 Feb 24 18:49:06 volumio volumio[1088]: info: ------------------------------ 30ms Feb 24 18:49:06 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:06.783+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" id=http://178.172.150.248:2530 title="Radio Skif" Feb 24 18:49:06 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:06.784+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" state=STATUS_PLAYING positionMs=152 volume=100 Feb 24 18:49:06 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:06.784+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" id=http://178.172.150.248:2530 title="Radio Skif" Feb 24 18:49:14 volumio volumio[1088]: info: Preload queue cleared Feb 24 18:49:14 volumio volumio[1088]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 24 18:49:14 volumio volumio[1088]: info: CoreStateMachine::ClearQueue Feb 24 18:49:14 volumio volumio[1088]: info: CoreStateMachine::stop Feb 24 18:49:14 volumio volumio[1088]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 18:49:14 volumio volumio[1088]: info: CoreStateMachine::stPlaybackTimer Feb 24 18:49:14 volumio volumio[1088]: info: CoreStateMachine::updateTrackBlock Feb 24 18:49:14 volumio volumio[1088]: info: CorePlayQueue::getTrackBlock Feb 24 18:49:14 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:14 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:14 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 18:49:14 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:14 volumio volumio[1088]: info: CoreStateMachine::serviceStop Feb 24 18:49:14 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:14 volumio volumio[1088]: info: CoreCommandRouter::serviceStop Feb 24 18:49:14 volumio volumio[1088]: info: [1771948154591] ControllerWebradio::stop Feb 24 18:49:14 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand stop Feb 24 18:49:14 volumio volumio[1088]: info: CorePlayQueue::clearPlayQueue Feb 24 18:49:14 volumio volumio[1088]: info: CorePlayQueue::saveQueue Feb 24 18:49:14 volumio volumio[1088]: info: CoreCommandRouter::volumioPushQueue Feb 24 18:49:14 volumio volumio[1088]: info: CoreStateMachine::addQueueItems Feb 24 18:49:14 volumio volumio[1088]: info: CorePlayQueue::addQueueItems Feb 24 18:49:14 volumio volumio[1088]: info: Preload queue cleared Feb 24 18:49:14 volumio volumio[1088]: info: Adding Item to queue: http://icecast227.ptspb.ru:8104/ruradio Feb 24 18:49:14 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Feb 24 18:49:14 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:14.594+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" state=STATUS_STOPPED positionMs=0 volume=100 Feb 24 18:49:14 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:14.594+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" id=http://178.172.150.248:2530 title="Radio Skif" Feb 24 18:49:14 volumio volumio[1088]: info: CoreCommandRouter::volumioPushQueue Feb 24 18:49:14 volumio volumio[1088]: info: CorePlayQueue::saveQueue Feb 24 18:49:14 volumio volumio[1088]: info: CoreStateMachine::updateTrackBlock Feb 24 18:49:14 volumio volumio[1088]: info: CorePlayQueue::getTrackBlock Feb 24 18:49:14 volumio volumio[1088]: info: CoreCommandRouter::volumioPlay Feb 24 18:49:14 volumio volumio[1088]: info: CoreStateMachine::play index 0 Feb 24 18:49:14 volumio volumio[1088]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 18:49:14 volumio volumio[1088]: info: CoreStateMachine::stop Feb 24 18:49:14 volumio volumio[1088]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 18:49:14 volumio volumio[1088]: info: CoreStateMachine::play index undefined Feb 24 18:49:14 volumio volumio[1088]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 18:49:14 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:14 volumio volumio[1088]: info: CoreStateMachine::startPlaybackTimer Feb 24 18:49:14 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:14 volumio volumio[1088]: info: [1771948154600] ControllerWebradio::clearAddPlayTrack Feb 24 18:49:14 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand stop Feb 24 18:49:14 volumio volumio[1088]: info: Feb 24 18:49:14 volumio volumio[1088]: ---------------------------- MPD announces state update: player Feb 24 18:49:14 volumio volumio[1088]: info: sendMpdCommand stop took 12 milliseconds Feb 24 18:49:14 volumio volumio[1088]: info: ControllerMpd::getState Feb 24 18:49:14 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:49:14 volumio volumio[1088]: info: sendMpdCommand stop took 4 milliseconds Feb 24 18:49:14 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand clear Feb 24 18:49:14 volumio volumio[1088]: info: Feb 24 18:49:14 volumio volumio[1088]: ---------------------------- MPD announces state update: player Feb 24 18:49:14 volumio volumio[1088]: info: ControllerMpd::getState Feb 24 18:49:14 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:49:14 volumio volumio[1088]: info: Feb 24 18:49:14 volumio volumio[1088]: ---------------------------- MPD announces state update: player Feb 24 18:49:14 volumio volumio[1088]: info: ControllerMpd::getState Feb 24 18:49:14 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:49:14 volumio volumio[1088]: info: Feb 24 18:49:14 volumio volumio[1088]: ---------------------------- MPD announces system playlist update Feb 24 18:49:14 volumio volumio[1088]: info: Ignoring MPD Status Update Feb 24 18:49:14 volumio volumio[1088]: info: sendMpdCommand status took 3 milliseconds Feb 24 18:49:14 volumio volumio[1088]: info: sendMpdCommand clear took 3 milliseconds Feb 24 18:49:14 volumio volumio[1088]: info: sendMpdCommand status took 2 milliseconds Feb 24 18:49:14 volumio volumio[1088]: info: sendMpdCommand status took 2 milliseconds Feb 24 18:49:14 volumio volumio[1088]: verbose: ControllerMpd::parseState Feb 24 18:49:14 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:49:14 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand load "http://icecast227.ptspb.ru:8104/ruradio" Feb 24 18:49:14 volumio volumio[1088]: verbose: ControllerMpd::parseState Feb 24 18:49:14 volumio volumio[1088]: info: ControllerMpd::pushState Feb 24 18:49:14 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Feb 24 18:49:14 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:14 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:14 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 18:49:14 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:14 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:14 volumio volumio[1088]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 24 18:49:14 volumio volumio[1088]: info: ControllerMpd::pushState Feb 24 18:49:14 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Feb 24 18:49:14 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:14 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:14 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:14 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:14 volumio volumio[1088]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 24 18:49:14 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:14.616+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" state=STATUS_STOPPED positionMs=0 volume=100 Feb 24 18:49:14 volumio volumio[1088]: info: ------------------------------ 13ms Feb 24 18:49:14 volumio volumio[1088]: info: ------------------------------ 12ms Feb 24 18:49:14 volumio volumio[1088]: info: Feb 24 18:49:14 volumio volumio[1088]: ---------------------------- MPD announces system playlist update Feb 24 18:49:14 volumio volumio[1088]: info: Ignoring MPD Status Update Feb 24 18:49:14 volumio volumio[1088]: info: Feb 24 18:49:14 volumio volumio[1088]: ---------------------------- MPD announces system playlist update Feb 24 18:49:14 volumio volumio[1088]: info: Ignoring MPD Status Update Feb 24 18:49:14 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:14.621+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" id=http://icecast227.ptspb.ru:8104/ruradio title="Русское Радио" Feb 24 18:49:14 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:14.617+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" state=STATUS_STOPPED positionMs=0 volume=100 Feb 24 18:49:14 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:14.622+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" id=http://icecast227.ptspb.ru:8104/ruradio title="Русское Радио" Feb 24 18:49:14 volumio volumio[1088]: error: updateQueue error: null Feb 24 18:49:14 volumio volumio[1088]: info: ------------------------------ 36ms Feb 24 18:49:14 volumio volumio[1088]: info: sendMpdCommand playlistinfo took 36 milliseconds Feb 24 18:49:14 volumio volumio[1088]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:49:14 volumio volumio[1088]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 24 18:49:14 volumio volumio[1088]: info: ------------------------------ 42ms Feb 24 18:49:14 volumio volumio[1088]: info: touch_display: Setting screensaver timeout to 10000000000 seconds. Feb 24 18:49:14 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand add "http://icecast227.ptspb.ru:8104/ruradio" Feb 24 18:49:14 volumio volumio[1088]: error: updateQueue error: null Feb 24 18:49:14 volumio volumio[1088]: error: updateQueue error: null Feb 24 18:49:14 volumio volumio[1088]: info: Feb 24 18:49:14 volumio volumio[1088]: ---------------------------- MPD announces system playlist update Feb 24 18:49:14 volumio volumio[1088]: info: Ignoring MPD Status Update Feb 24 18:49:14 volumio volumio[1088]: info: ------------------------------ 229ms Feb 24 18:49:14 volumio volumio[1088]: info: ------------------------------ 229ms Feb 24 18:49:14 volumio volumio[1088]: info: sendMpdCommand add "http://icecast227.ptspb.ru:8104/ruradio" took 2 milliseconds Feb 24 18:49:14 volumio volumio[1088]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 24 18:49:14 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand play Feb 24 18:49:14 volumio volumio[1088]: info: Feb 24 18:49:14 volumio volumio[1088]: ---------------------------- MPD announces system playlist update Feb 24 18:49:14 volumio volumio[1088]: info: Ignoring MPD Status Update Feb 24 18:49:14 volumio volumio[1088]: info: Feb 24 18:49:14 volumio volumio[1088]: ---------------------------- MPD announces system playlist update Feb 24 18:49:14 volumio volumio[1088]: info: Ignoring MPD Status Update Feb 24 18:49:14 volumio volumio[1088]: info: ------------------------------ 6ms Feb 24 18:49:14 volumio volumio[1088]: info: sendMpdCommand play took 4 milliseconds Feb 24 18:49:14 volumio volumio[1088]: info: ------------------------------ 3ms Feb 24 18:49:14 volumio volumio[1088]: info: ------------------------------ 2ms Feb 24 18:49:14 volumio volumio[1088]: info: Feb 24 18:49:14 volumio volumio[1088]: ---------------------------- MPD announces state update: player Feb 24 18:49:14 volumio volumio[1088]: info: ControllerMpd::getState Feb 24 18:49:14 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:49:14 volumio volumio[1088]: info: Feb 24 18:49:14 volumio volumio[1088]: ---------------------------- MPD announces state update: player Feb 24 18:49:14 volumio volumio[1088]: info: ControllerMpd::getState Feb 24 18:49:14 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:49:14 volumio volumio[1088]: info: Feb 24 18:49:14 volumio volumio[1088]: ---------------------------- MPD announces state update: player Feb 24 18:49:14 volumio volumio[1088]: info: ControllerMpd::getState Feb 24 18:49:14 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:49:14 volumio volumio[1088]: error: MPD returned error for command status: Failed to decode http://icecast227.ptspb.ru:8104/ruradio; CURL failed: Failed to connect to icecast227.ptspb.ru port 8104: Connection refused Feb 24 18:49:14 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand clearerror Feb 24 18:49:14 volumio volumio[1088]: info: sendMpdCommand status took 5 milliseconds Feb 24 18:49:14 volumio volumio[1088]: error: MPD returned error for command status: Failed to decode http://icecast227.ptspb.ru:8104/ruradio; CURL failed: Failed to connect to icecast227.ptspb.ru port 8104: Connection refused Feb 24 18:49:14 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand clearerror Feb 24 18:49:14 volumio volumio[1088]: info: sendMpdCommand status took 5 milliseconds Feb 24 18:49:14 volumio volumio[1088]: verbose: ControllerMpd::parseState Feb 24 18:49:14 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:49:14 volumio volumio[1088]: verbose: ControllerMpd::parseState Feb 24 18:49:14 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:49:14 volumio volumio[1088]: error: MPD returned error for command status: Failed to decode http://icecast227.ptspb.ru:8104/ruradio; CURL failed: Failed to connect to icecast227.ptspb.ru port 8104: Connection refused Feb 24 18:49:14 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand clearerror Feb 24 18:49:14 volumio volumio[1088]: info: sendMpdCommand status took 13 milliseconds Feb 24 18:49:14 volumio volumio[1088]: info: sendMpdCommand clearerror took 10 milliseconds Feb 24 18:49:14 volumio volumio[1088]: info: sendMpdCommand clearerror took 9 milliseconds Feb 24 18:49:14 volumio volumio[1088]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 24 18:49:14 volumio volumio[1088]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 24 18:49:14 volumio volumio[1088]: verbose: ControllerMpd::parseState Feb 24 18:49:14 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:49:14 volumio volumio[1088]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:49:14 volumio volumio[1088]: info: ControllerMpd::pushState Feb 24 18:49:14 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Feb 24 18:49:14 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:14 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 18:49:14 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:14 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:14 volumio volumio[1088]: 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":"ruradio","artist":null,"album":null,"uri":"http://icecast227.ptspb.ru:8104/ruradio","trackType":""} Feb 24 18:49:14 volumio volumio[1088]: verbose: CURRENT POSITION 0 Feb 24 18:49:14 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Feb 24 18:49:14 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Feb 24 18:49:14 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:14 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:14 volumio volumio[1088]: info: No code Feb 24 18:49:14 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:14 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:14 volumio volumio[1088]: info: ControllerMpd::pushState Feb 24 18:49:14 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Feb 24 18:49:14 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:14 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:14 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:14 volumio volumio[1088]: 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":"ruradio","artist":null,"album":null,"uri":"http://icecast227.ptspb.ru:8104/ruradio","trackType":""} Feb 24 18:49:14 volumio volumio[1088]: verbose: CURRENT POSITION 0 Feb 24 18:49:14 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Feb 24 18:49:14 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Feb 24 18:49:14 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:14 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:14 volumio volumio[1088]: info: No code Feb 24 18:49:14 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:14 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:14 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:14.896+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" state=STATUS_PLAYING positionMs=253 volume=100 Feb 24 18:49:14 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:14.896+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" id=http://178.172.150.248:2530 title="Radio Skif" Feb 24 18:49:14 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:14.897+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" state=STATUS_PLAYING positionMs=253 volume=100 Feb 24 18:49:14 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:14.897+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" id=http://178.172.150.248:2530 title="Radio Skif" Feb 24 18:49:14 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:14.897+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" state=STATUS_PLAYING positionMs=253 volume=100 Feb 24 18:49:14 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:14.898+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" id=http://178.172.150.248:2530 title="Radio Skif" Feb 24 18:49:14 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:14.898+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" state=STATUS_PLAYING positionMs=253 volume=100 Feb 24 18:49:14 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:14.898+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" id=http://178.172.150.248:2530 title="Radio Skif" Feb 24 18:49:14 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:14.898+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" state=STATUS_PLAYING positionMs=253 volume=100 Feb 24 18:49:14 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:14.898+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" id=http://178.172.150.248:2530 title="Radio Skif" Feb 24 18:49:14 volumio volumio[1088]: info: ------------------------------ 40ms Feb 24 18:49:14 volumio volumio[1088]: info: ------------------------------ 40ms Feb 24 18:49:14 volumio volumio[1088]: info: sendMpdCommand clearerror took 58 milliseconds Feb 24 18:49:14 volumio volumio[1088]: info: sendMpdCommand playlistinfo took 58 milliseconds Feb 24 18:49:14 volumio volumio[1088]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:49:14 volumio volumio[1088]: info: ControllerMpd::pushState Feb 24 18:49:14 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Feb 24 18:49:14 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:14 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:14 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:14 volumio volumio[1088]: 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":"ruradio","artist":null,"album":null,"uri":"http://icecast227.ptspb.ru:8104/ruradio","trackType":""} Feb 24 18:49:14 volumio volumio[1088]: verbose: CURRENT POSITION 0 Feb 24 18:49:14 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Feb 24 18:49:14 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Feb 24 18:49:14 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:14 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:14 volumio volumio[1088]: info: No code Feb 24 18:49:14 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:14 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:14 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:14.949+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" state=STATUS_PLAYING positionMs=253 volume=100 Feb 24 18:49:14 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:14.949+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" id=http://178.172.150.248:2530 title="Radio Skif" Feb 24 18:49:14 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:14.952+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" state=STATUS_PLAYING positionMs=253 volume=100 Feb 24 18:49:14 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:14.953+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" id=http://178.172.150.248:2530 title="Radio Skif" Feb 24 18:49:14 volumio volumio[1088]: info: ------------------------------ 91ms Feb 24 18:49:14 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:14.955+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" state=STATUS_PLAYING positionMs=253 volume=100 Feb 24 18:49:14 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:14.955+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" id=http://178.172.150.248:2530 title="Radio Skif" Feb 24 18:49:14 volumio volumio[1088]: info: touch_display: Setting screensaver timeout to 0 seconds. Feb 24 18:49:16 volumio volumio[1088]: info: Preload queue cleared Feb 24 18:49:16 volumio volumio[1088]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 24 18:49:16 volumio volumio[1088]: info: CoreStateMachine::ClearQueue Feb 24 18:49:16 volumio volumio[1088]: info: CoreStateMachine::stop Feb 24 18:49:16 volumio volumio[1088]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 18:49:16 volumio volumio[1088]: info: CorePlayQueue::clearPlayQueue Feb 24 18:49:16 volumio volumio[1088]: info: CorePlayQueue::saveQueue Feb 24 18:49:16 volumio volumio[1088]: info: CoreCommandRouter::volumioPushQueue Feb 24 18:49:16 volumio volumio[1088]: info: CoreStateMachine::addQueueItems Feb 24 18:49:16 volumio volumio[1088]: info: CorePlayQueue::addQueueItems Feb 24 18:49:16 volumio volumio[1088]: info: Preload queue cleared Feb 24 18:49:16 volumio volumio[1088]: info: Adding Item to queue: http://icecast227.ptspb.ru:8104/ruradio Feb 24 18:49:16 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Feb 24 18:49:16 volumio volumio[1088]: info: CoreCommandRouter::volumioPushQueue Feb 24 18:49:16 volumio volumio[1088]: info: CorePlayQueue::saveQueue Feb 24 18:49:16 volumio volumio[1088]: info: CoreStateMachine::updateTrackBlock Feb 24 18:49:16 volumio volumio[1088]: info: CorePlayQueue::getTrackBlock Feb 24 18:49:16 volumio volumio[1088]: info: CoreCommandRouter::volumioPlay Feb 24 18:49:16 volumio volumio[1088]: info: CoreStateMachine::play index 0 Feb 24 18:49:16 volumio volumio[1088]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 18:49:16 volumio volumio[1088]: info: CoreStateMachine::stop Feb 24 18:49:16 volumio volumio[1088]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 18:49:16 volumio volumio[1088]: info: CoreStateMachine::play index undefined Feb 24 18:49:16 volumio volumio[1088]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 18:49:16 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:16 volumio volumio[1088]: info: CoreStateMachine::startPlaybackTimer Feb 24 18:49:16 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:16 volumio volumio[1088]: info: [1771948156217] ControllerWebradio::clearAddPlayTrack Feb 24 18:49:16 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand stop Feb 24 18:49:16 volumio volumio[1088]: info: sendMpdCommand stop took 2 milliseconds Feb 24 18:49:16 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand clear Feb 24 18:49:16 volumio volumio[1088]: info: Feb 24 18:49:16 volumio volumio[1088]: ---------------------------- MPD announces system playlist update Feb 24 18:49:16 volumio volumio[1088]: info: Ignoring MPD Status Update Feb 24 18:49:16 volumio volumio[1088]: info: sendMpdCommand clear took 1 milliseconds Feb 24 18:49:16 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand load "http://icecast227.ptspb.ru:8104/ruradio" Feb 24 18:49:16 volumio volumio[1088]: info: Feb 24 18:49:16 volumio volumio[1088]: ---------------------------- MPD announces system playlist update Feb 24 18:49:16 volumio volumio[1088]: info: Ignoring MPD Status Update Feb 24 18:49:16 volumio volumio[1088]: info: Feb 24 18:49:16 volumio volumio[1088]: ---------------------------- MPD announces system playlist update Feb 24 18:49:16 volumio volumio[1088]: info: Ignoring MPD Status Update Feb 24 18:49:16 volumio volumio[1088]: error: updateQueue error: null Feb 24 18:49:16 volumio volumio[1088]: info: ------------------------------ 2ms Feb 24 18:49:16 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand add "http://icecast227.ptspb.ru:8104/ruradio" Feb 24 18:49:16 volumio volumio[1088]: error: updateQueue error: null Feb 24 18:49:16 volumio volumio[1088]: error: updateQueue error: null Feb 24 18:49:16 volumio volumio[1088]: info: Feb 24 18:49:16 volumio volumio[1088]: ---------------------------- MPD announces system playlist update Feb 24 18:49:16 volumio volumio[1088]: info: Ignoring MPD Status Update Feb 24 18:49:16 volumio volumio[1088]: info: ------------------------------ 13ms Feb 24 18:49:16 volumio volumio[1088]: info: ------------------------------ 13ms Feb 24 18:49:16 volumio volumio[1088]: info: sendMpdCommand add "http://icecast227.ptspb.ru:8104/ruradio" took 1 milliseconds Feb 24 18:49:16 volumio volumio[1088]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 24 18:49:16 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand play Feb 24 18:49:16 volumio volumio[1088]: info: Feb 24 18:49:16 volumio volumio[1088]: ---------------------------- MPD announces system playlist update Feb 24 18:49:16 volumio volumio[1088]: info: Ignoring MPD Status Update Feb 24 18:49:16 volumio volumio[1088]: info: Feb 24 18:49:16 volumio volumio[1088]: ---------------------------- MPD announces system playlist update Feb 24 18:49:16 volumio volumio[1088]: info: Ignoring MPD Status Update Feb 24 18:49:16 volumio volumio[1088]: info: ------------------------------ 2ms Feb 24 18:49:16 volumio volumio[1088]: info: sendMpdCommand play took 2 milliseconds Feb 24 18:49:16 volumio volumio[1088]: info: ------------------------------ 1ms Feb 24 18:49:16 volumio volumio[1088]: info: ------------------------------ 1ms Feb 24 18:49:16 volumio volumio[1088]: info: Feb 24 18:49:16 volumio volumio[1088]: ---------------------------- MPD announces state update: player Feb 24 18:49:16 volumio volumio[1088]: info: ControllerMpd::getState Feb 24 18:49:16 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:49:16 volumio volumio[1088]: info: Feb 24 18:49:16 volumio volumio[1088]: ---------------------------- MPD announces state update: player Feb 24 18:49:16 volumio volumio[1088]: info: ControllerMpd::getState Feb 24 18:49:16 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:49:16 volumio volumio[1088]: info: Feb 24 18:49:16 volumio volumio[1088]: ---------------------------- MPD announces state update: player Feb 24 18:49:16 volumio volumio[1088]: info: ControllerMpd::getState Feb 24 18:49:16 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:49:16 volumio volumio[1088]: error: MPD returned error for command status: Failed to decode http://icecast227.ptspb.ru:8104/ruradio; CURL failed: Failed to connect to icecast227.ptspb.ru port 8104: Connection refused Feb 24 18:49:16 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand clearerror Feb 24 18:49:16 volumio volumio[1088]: info: sendMpdCommand status took 4 milliseconds Feb 24 18:49:16 volumio volumio[1088]: error: MPD returned error for command status: Failed to decode http://icecast227.ptspb.ru:8104/ruradio; CURL failed: Failed to connect to icecast227.ptspb.ru port 8104: Connection refused Feb 24 18:49:16 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand clearerror Feb 24 18:49:16 volumio volumio[1088]: info: sendMpdCommand status took 4 milliseconds Feb 24 18:49:16 volumio volumio[1088]: verbose: ControllerMpd::parseState Feb 24 18:49:16 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:49:16 volumio volumio[1088]: verbose: ControllerMpd::parseState Feb 24 18:49:16 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:49:16 volumio volumio[1088]: error: MPD returned error for command status: Failed to decode http://icecast227.ptspb.ru:8104/ruradio; CURL failed: Failed to connect to icecast227.ptspb.ru port 8104: Connection refused Feb 24 18:49:16 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand clearerror Feb 24 18:49:16 volumio volumio[1088]: info: sendMpdCommand status took 8 milliseconds Feb 24 18:49:16 volumio volumio[1088]: info: sendMpdCommand clearerror took 6 milliseconds Feb 24 18:49:16 volumio volumio[1088]: info: sendMpdCommand clearerror took 6 milliseconds Feb 24 18:49:16 volumio volumio[1088]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 24 18:49:16 volumio volumio[1088]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 24 18:49:16 volumio volumio[1088]: verbose: ControllerMpd::parseState Feb 24 18:49:16 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:49:16 volumio volumio[1088]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:49:16 volumio volumio[1088]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:49:16 volumio volumio[1088]: info: ControllerMpd::pushState Feb 24 18:49:16 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Feb 24 18:49:16 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:16 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 18:49:16 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:16 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:16 volumio volumio[1088]: 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":"ruradio","artist":null,"album":null,"uri":"http://icecast227.ptspb.ru:8104/ruradio","trackType":""} Feb 24 18:49:16 volumio volumio[1088]: verbose: CURRENT POSITION 0 Feb 24 18:49:16 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Feb 24 18:49:16 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Feb 24 18:49:16 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:16 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:16 volumio volumio[1088]: info: No code Feb 24 18:49:16 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:16 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:16 volumio volumio[1088]: info: ControllerMpd::pushState Feb 24 18:49:16 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Feb 24 18:49:16 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:16 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:16 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:16 volumio volumio[1088]: 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":"ruradio","artist":null,"album":null,"uri":"http://icecast227.ptspb.ru:8104/ruradio","trackType":""} Feb 24 18:49:16 volumio volumio[1088]: verbose: CURRENT POSITION 0 Feb 24 18:49:16 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Feb 24 18:49:16 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Feb 24 18:49:16 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:16 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:16 volumio volumio[1088]: info: No code Feb 24 18:49:16 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:16 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:16 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:16.275+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" state=STATUS_PLAYING positionMs=16 volume=100 Feb 24 18:49:16 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:16.276+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" id=http://178.172.150.248:2530 title="Radio Skif" Feb 24 18:49:16 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:16.276+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" state=STATUS_PLAYING positionMs=16 volume=100 Feb 24 18:49:16 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:16.276+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" id=http://178.172.150.248:2530 title="Radio Skif" Feb 24 18:49:16 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:16.276+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" state=STATUS_PLAYING positionMs=16 volume=100 Feb 24 18:49:16 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:16.277+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" id=http://178.172.150.248:2530 title="Radio Skif" Feb 24 18:49:16 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:16.277+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" state=STATUS_PLAYING positionMs=16 volume=100 Feb 24 18:49:16 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:16.277+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" id=http://178.172.150.248:2530 title="Radio Skif" Feb 24 18:49:16 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:16.279+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" state=STATUS_PLAYING positionMs=16 volume=100 Feb 24 18:49:16 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:16.279+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" state=STATUS_PLAYING positionMs=16 volume=100 Feb 24 18:49:16 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:16.280+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" id=http://178.172.150.248:2530 title="Radio Skif" Feb 24 18:49:16 volumio volumio[1088]: info: ------------------------------ 32ms Feb 24 18:49:16 volumio volumio[1088]: info: sendMpdCommand clearerror took 31 milliseconds Feb 24 18:49:16 volumio volumio[1088]: info: sendMpdCommand playlistinfo took 29 milliseconds Feb 24 18:49:16 volumio volumio[1088]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:49:16 volumio volumio[1088]: info: ControllerMpd::pushState Feb 24 18:49:16 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Feb 24 18:49:16 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:16 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:16 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:16 volumio volumio[1088]: 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":"ruradio","artist":null,"album":null,"uri":"http://icecast227.ptspb.ru:8104/ruradio","trackType":""} Feb 24 18:49:16 volumio volumio[1088]: verbose: CURRENT POSITION 0 Feb 24 18:49:16 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Feb 24 18:49:16 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Feb 24 18:49:16 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:16 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:16 volumio volumio[1088]: info: No code Feb 24 18:49:16 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:16 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:16 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:16.298+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" state=STATUS_PLAYING positionMs=16 volume=100 Feb 24 18:49:16 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:16.298+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" id=http://178.172.150.248:2530 title="Radio Skif" Feb 24 18:49:16 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:16.299+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" state=STATUS_PLAYING positionMs=16 volume=100 Feb 24 18:49:16 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:16.299+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" id=http://178.172.150.248:2530 title="Radio Skif" Feb 24 18:49:16 volumio volumio[1088]: info: ------------------------------ 51ms Feb 24 18:49:18 volumio volumio[1088]: info: Preload queue cleared Feb 24 18:49:18 volumio volumio[1088]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 24 18:49:18 volumio volumio[1088]: info: CoreStateMachine::ClearQueue Feb 24 18:49:18 volumio volumio[1088]: info: CoreStateMachine::stop Feb 24 18:49:18 volumio volumio[1088]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 18:49:18 volumio volumio[1088]: info: CorePlayQueue::clearPlayQueue Feb 24 18:49:18 volumio volumio[1088]: info: CorePlayQueue::saveQueue Feb 24 18:49:18 volumio volumio[1088]: info: CoreCommandRouter::volumioPushQueue Feb 24 18:49:18 volumio volumio[1088]: info: CoreStateMachine::addQueueItems Feb 24 18:49:18 volumio volumio[1088]: info: CorePlayQueue::addQueueItems Feb 24 18:49:18 volumio volumio[1088]: info: Preload queue cleared Feb 24 18:49:18 volumio volumio[1088]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s254559 Feb 24 18:49:18 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Feb 24 18:49:18 volumio volumio[1088]: info: CoreCommandRouter::volumioPushQueue Feb 24 18:49:18 volumio volumio[1088]: info: CorePlayQueue::saveQueue Feb 24 18:49:18 volumio volumio[1088]: info: CoreStateMachine::updateTrackBlock Feb 24 18:49:18 volumio volumio[1088]: info: CorePlayQueue::getTrackBlock Feb 24 18:49:18 volumio volumio[1088]: info: CoreCommandRouter::volumioPlay Feb 24 18:49:18 volumio volumio[1088]: info: CoreStateMachine::play index 0 Feb 24 18:49:18 volumio volumio[1088]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 18:49:18 volumio volumio[1088]: info: CoreStateMachine::stop Feb 24 18:49:18 volumio volumio[1088]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 18:49:18 volumio volumio[1088]: info: CoreStateMachine::play index undefined Feb 24 18:49:18 volumio volumio[1088]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 18:49:18 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:18 volumio volumio[1088]: info: CoreStateMachine::startPlaybackTimer Feb 24 18:49:18 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:18 volumio volumio[1088]: info: [1771948158309] ControllerWebradio::clearAddPlayTrack Feb 24 18:49:18 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand stop Feb 24 18:49:18 volumio volumio[1088]: info: sendMpdCommand stop took 1 milliseconds Feb 24 18:49:18 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand clear Feb 24 18:49:18 volumio volumio[1088]: info: sendMpdCommand clear took 0 milliseconds Feb 24 18:49:18 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand load "http://178.172.150.248:2530" Feb 24 18:49:18 volumio volumio[1088]: info: Feb 24 18:49:18 volumio volumio[1088]: ---------------------------- MPD announces system playlist update Feb 24 18:49:18 volumio volumio[1088]: info: Ignoring MPD Status Update Feb 24 18:49:18 volumio volumio[1088]: info: Feb 24 18:49:18 volumio volumio[1088]: ---------------------------- MPD announces system playlist update Feb 24 18:49:18 volumio volumio[1088]: info: Ignoring MPD Status Update Feb 24 18:49:18 volumio volumio[1088]: info: Feb 24 18:49:18 volumio volumio[1088]: ---------------------------- MPD announces system playlist update Feb 24 18:49:18 volumio volumio[1088]: info: Ignoring MPD Status Update Feb 24 18:49:18 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand add "http://178.172.150.248:2530" Feb 24 18:49:18 volumio volumio[1088]: error: updateQueue error: null Feb 24 18:49:18 volumio volumio[1088]: error: updateQueue error: null Feb 24 18:49:18 volumio volumio[1088]: error: updateQueue error: null Feb 24 18:49:18 volumio volumio[1088]: info: Feb 24 18:49:18 volumio volumio[1088]: ---------------------------- MPD announces system playlist update Feb 24 18:49:18 volumio volumio[1088]: info: Ignoring MPD Status Update Feb 24 18:49:18 volumio volumio[1088]: info: ------------------------------ 339ms Feb 24 18:49:18 volumio volumio[1088]: info: ------------------------------ 338ms Feb 24 18:49:18 volumio volumio[1088]: info: ------------------------------ 338ms Feb 24 18:49:18 volumio volumio[1088]: info: sendMpdCommand add "http://178.172.150.248:2530" took 1 milliseconds Feb 24 18:49:18 volumio volumio[1088]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 24 18:49:18 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand play Feb 24 18:49:18 volumio volumio[1088]: info: Feb 24 18:49:18 volumio volumio[1088]: ---------------------------- MPD announces system playlist update Feb 24 18:49:18 volumio volumio[1088]: info: Ignoring MPD Status Update Feb 24 18:49:18 volumio volumio[1088]: info: Feb 24 18:49:18 volumio volumio[1088]: ---------------------------- MPD announces system playlist update Feb 24 18:49:18 volumio volumio[1088]: info: Ignoring MPD Status Update Feb 24 18:49:18 volumio volumio[1088]: info: ------------------------------ 4ms Feb 24 18:49:18 volumio volumio[1088]: info: sendMpdCommand play took 4 milliseconds Feb 24 18:49:18 volumio volumio[1088]: info: ------------------------------ 2ms Feb 24 18:49:18 volumio volumio[1088]: info: ------------------------------ 1ms Feb 24 18:49:19 volumio volumio[1088]: info: Feb 24 18:49:19 volumio volumio[1088]: ---------------------------- MPD announces state update: player Feb 24 18:49:19 volumio volumio[1088]: info: ControllerMpd::getState Feb 24 18:49:19 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:49:19 volumio volumio[1088]: info: Feb 24 18:49:19 volumio volumio[1088]: ---------------------------- MPD announces state update: player Feb 24 18:49:19 volumio volumio[1088]: info: ControllerMpd::getState Feb 24 18:49:19 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:49:19 volumio volumio[1088]: info: Feb 24 18:49:19 volumio volumio[1088]: ---------------------------- MPD announces state update: player Feb 24 18:49:19 volumio volumio[1088]: info: ControllerMpd::getState Feb 24 18:49:19 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:49:19 volumio volumio[1088]: info: sendMpdCommand status took 2 milliseconds Feb 24 18:49:19 volumio volumio[1088]: info: sendMpdCommand status took 1 milliseconds Feb 24 18:49:19 volumio volumio[1088]: info: sendMpdCommand status took 1 milliseconds Feb 24 18:49:19 volumio volumio[1088]: verbose: ControllerMpd::parseState Feb 24 18:49:19 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:49:19 volumio volumio[1088]: verbose: ControllerMpd::parseState Feb 24 18:49:19 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:49:19 volumio volumio[1088]: verbose: ControllerMpd::parseState Feb 24 18:49:19 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:49:19 volumio volumio[1088]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 24 18:49:19 volumio volumio[1088]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 24 18:49:19 volumio volumio[1088]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 24 18:49:19 volumio volumio[1088]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:49:19 volumio volumio[1088]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:49:19 volumio volumio[1088]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:49:19 volumio volumio[1088]: info: ControllerMpd::pushState Feb 24 18:49:19 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Feb 24 18:49:19 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:19 volumio volumio[1088]: 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":"178.172.150.248:2530","artist":null,"album":null,"uri":"http://178.172.150.248:2530","trackType":"248:2530"} Feb 24 18:49:19 volumio volumio[1088]: verbose: CURRENT POSITION 0 Feb 24 18:49:19 volumio volumio[1088]: info: CoreStateMachine::syncState stateService play Feb 24 18:49:19 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Feb 24 18:49:19 volumio volumio[1088]: info: ControllerMpd::pushState Feb 24 18:49:19 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Feb 24 18:49:19 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:19 volumio volumio[1088]: 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":"178.172.150.248:2530","artist":null,"album":null,"uri":"http://178.172.150.248:2530","trackType":"248:2530"} Feb 24 18:49:19 volumio volumio[1088]: verbose: CURRENT POSITION 0 Feb 24 18:49:19 volumio volumio[1088]: info: CoreStateMachine::syncState stateService play Feb 24 18:49:19 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus play Feb 24 18:49:19 volumio volumio[1088]: info: Received an update from plugin. extracting info from payload Feb 24 18:49:19 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:19 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:19 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 18:49:19 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:19 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:19 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:19 volumio volumio[1088]: info: ControllerMpd::pushState Feb 24 18:49:19 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Feb 24 18:49:19 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:19 volumio volumio[1088]: 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":"178.172.150.248:2530","artist":null,"album":null,"uri":"http://178.172.150.248:2530","trackType":"248:2530"} Feb 24 18:49:19 volumio volumio[1088]: verbose: CURRENT POSITION 0 Feb 24 18:49:19 volumio volumio[1088]: info: CoreStateMachine::syncState stateService play Feb 24 18:49:19 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus play Feb 24 18:49:19 volumio volumio[1088]: info: Received an update from plugin. extracting info from payload Feb 24 18:49:19 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:19 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:19 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:19 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:19 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:19 volumio volumio[1088]: info: ------------------------------ 10ms Feb 24 18:49:19 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:19.030+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" state=STATUS_PLAYING positionMs=0 volume=100 Feb 24 18:49:19 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:19.031+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" id=http://178.172.150.248:2530 title="Radio Skif" Feb 24 18:49:19 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:19.031+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" state=STATUS_PLAYING positionMs=0 volume=100 Feb 24 18:49:19 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:19.031+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" id=http://178.172.150.248:2530 title="Radio Skif" Feb 24 18:49:19 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:19.032+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" state=STATUS_PLAYING positionMs=0 volume=100 Feb 24 18:49:19 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:19.032+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" id=http://178.172.150.248:2530 title="Radio Skif" Feb 24 18:49:19 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:19.032+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" state=STATUS_PLAYING positionMs=0 volume=100 Feb 24 18:49:19 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:19.032+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" id=http://178.172.150.248:2530 title="Radio Skif" Feb 24 18:49:19 volumio volumio[1088]: info: ------------------------------ 14ms Feb 24 18:49:19 volumio volumio[1088]: info: Feb 24 18:49:19 volumio volumio[1088]: ---------------------------- MPD announces system playlist update Feb 24 18:49:19 volumio volumio[1088]: info: Ignoring MPD Status Update Feb 24 18:49:19 volumio volumio[1088]: info: Feb 24 18:49:19 volumio volumio[1088]: ---------------------------- MPD announces state update: player Feb 24 18:49:19 volumio volumio[1088]: info: ControllerMpd::getState Feb 24 18:49:19 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:49:19 volumio volumio[1088]: info: Feb 24 18:49:19 volumio volumio[1088]: ---------------------------- MPD announces system playlist update Feb 24 18:49:19 volumio volumio[1088]: info: Ignoring MPD Status Update Feb 24 18:49:19 volumio volumio[1088]: info: Feb 24 18:49:19 volumio volumio[1088]: ---------------------------- MPD announces state update: player Feb 24 18:49:19 volumio volumio[1088]: info: ControllerMpd::getState Feb 24 18:49:19 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:49:19 volumio volumio[1088]: info: Feb 24 18:49:19 volumio volumio[1088]: ---------------------------- MPD announces system playlist update Feb 24 18:49:19 volumio volumio[1088]: info: Ignoring MPD Status Update Feb 24 18:49:19 volumio volumio[1088]: info: Feb 24 18:49:19 volumio volumio[1088]: ---------------------------- MPD announces state update: player Feb 24 18:49:19 volumio volumio[1088]: info: ControllerMpd::getState Feb 24 18:49:19 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:49:19 volumio volumio[1088]: info: ------------------------------ 4ms Feb 24 18:49:19 volumio volumio[1088]: info: sendMpdCommand status took 3 milliseconds Feb 24 18:49:19 volumio volumio[1088]: info: ------------------------------ 3ms Feb 24 18:49:19 volumio volumio[1088]: info: sendMpdCommand status took 2 milliseconds Feb 24 18:49:19 volumio volumio[1088]: verbose: ControllerMpd::parseState Feb 24 18:49:19 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:49:19 volumio volumio[1088]: verbose: ControllerMpd::parseState Feb 24 18:49:19 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:49:19 volumio volumio[1088]: info: ------------------------------ 4ms Feb 24 18:49:19 volumio volumio[1088]: info: sendMpdCommand status took 4 milliseconds Feb 24 18:49:19 volumio volumio[1088]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 24 18:49:19 volumio volumio[1088]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 24 18:49:19 volumio volumio[1088]: verbose: ControllerMpd::parseState Feb 24 18:49:19 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:49:19 volumio volumio[1088]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:49:19 volumio volumio[1088]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:49:19 volumio volumio[1088]: info: ControllerMpd::pushState Feb 24 18:49:19 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Feb 24 18:49:19 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:19 volumio volumio[1088]: verbose: STATE SERVICE {"status":"play","position":0,"seek":342,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"178.172.150.248:2530","artist":"SKIF","album":null,"uri":"http://178.172.150.248:2530","trackType":"248:2530"} Feb 24 18:49:19 volumio volumio[1088]: verbose: CURRENT POSITION 0 Feb 24 18:49:19 volumio volumio[1088]: info: CoreStateMachine::syncState stateService play Feb 24 18:49:19 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus play Feb 24 18:49:19 volumio volumio[1088]: info: Received an update from plugin. extracting info from payload Feb 24 18:49:19 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:19 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:19 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 18:49:19 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:19 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:19 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:19 volumio volumio[1088]: info: ControllerMpd::pushState Feb 24 18:49:19 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Feb 24 18:49:19 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:19 volumio volumio[1088]: verbose: STATE SERVICE {"status":"play","position":0,"seek":525,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"178.172.150.248:2530","artist":"SKIF","album":null,"uri":"http://178.172.150.248:2530","trackType":"248:2530"} Feb 24 18:49:19 volumio volumio[1088]: verbose: CURRENT POSITION 0 Feb 24 18:49:19 volumio volumio[1088]: info: CoreStateMachine::syncState stateService play Feb 24 18:49:19 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus play Feb 24 18:49:19 volumio volumio[1088]: info: Received an update from plugin. extracting info from payload Feb 24 18:49:19 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:19 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:19 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:19 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:19 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:19 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:19.152+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" state=STATUS_PLAYING positionMs=120 volume=100 Feb 24 18:49:19 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:19.154+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" id=http://178.172.150.248:2530 title="Radio Skif" Feb 24 18:49:19 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:19.153+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" state=STATUS_PLAYING positionMs=120 volume=100 Feb 24 18:49:19 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:19.155+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" id=http://178.172.150.248:2530 title="Radio Skif" Feb 24 18:49:19 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:19.153+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" state=STATUS_PLAYING positionMs=120 volume=100 Feb 24 18:49:19 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:19.155+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" id=http://178.172.150.248:2530 title="Radio Skif" Feb 24 18:49:19 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:19.153+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" state=STATUS_PLAYING positionMs=120 volume=100 Feb 24 18:49:19 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:19.156+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" id=http://178.172.150.248:2530 title="Radio Skif" Feb 24 18:49:19 volumio volumio[1088]: info: ------------------------------ 19ms Feb 24 18:49:19 volumio volumio[1088]: info: ------------------------------ 18ms Feb 24 18:49:19 volumio volumio[1088]: info: sendMpdCommand playlistinfo took 13 milliseconds Feb 24 18:49:19 volumio volumio[1088]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:49:19 volumio volumio[1088]: info: ControllerMpd::pushState Feb 24 18:49:19 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Feb 24 18:49:19 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:19 volumio volumio[1088]: verbose: STATE SERVICE {"status":"play","position":0,"seek":697,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"178.172.150.248:2530","artist":"SKIF","album":null,"uri":"http://178.172.150.248:2530","trackType":"248:2530"} Feb 24 18:49:19 volumio volumio[1088]: verbose: CURRENT POSITION 0 Feb 24 18:49:19 volumio volumio[1088]: info: CoreStateMachine::syncState stateService play Feb 24 18:49:19 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus play Feb 24 18:49:19 volumio volumio[1088]: info: Received an update from plugin. extracting info from payload Feb 24 18:49:19 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:19 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:19 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:19 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:19 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:19 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:19.163+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" state=STATUS_PLAYING positionMs=120 volume=100 Feb 24 18:49:19 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:19.163+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" id=http://178.172.150.248:2530 title="Radio Skif" Feb 24 18:49:19 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:19.163+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" state=STATUS_PLAYING positionMs=120 volume=100 Feb 24 18:49:19 volumio volumio[1088]: info: ------------------------------ 26ms Feb 24 18:49:19 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:19.165+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" id=http://178.172.150.248:2530 title="Radio Skif" Feb 24 18:49:20 volumio volumio[1088]: info: Preload queue cleared Feb 24 18:49:20 volumio volumio[1088]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 24 18:49:20 volumio volumio[1088]: info: CoreStateMachine::ClearQueue Feb 24 18:49:20 volumio volumio[1088]: info: CoreStateMachine::stop Feb 24 18:49:20 volumio volumio[1088]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 18:49:20 volumio volumio[1088]: info: CoreStateMachine::stPlaybackTimer Feb 24 18:49:20 volumio volumio[1088]: info: CoreStateMachine::updateTrackBlock Feb 24 18:49:20 volumio volumio[1088]: info: CorePlayQueue::getTrackBlock Feb 24 18:49:20 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:20 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:20 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 18:49:20 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:20 volumio volumio[1088]: info: CoreStateMachine::serviceStop Feb 24 18:49:20 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:20 volumio volumio[1088]: info: CoreCommandRouter::serviceStop Feb 24 18:49:20 volumio volumio[1088]: info: [1771948160954] ControllerWebradio::stop Feb 24 18:49:20 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand stop Feb 24 18:49:20 volumio volumio[1088]: info: CorePlayQueue::clearPlayQueue Feb 24 18:49:20 volumio volumio[1088]: info: CorePlayQueue::saveQueue Feb 24 18:49:20 volumio volumio[1088]: info: CoreCommandRouter::volumioPushQueue Feb 24 18:49:20 volumio volumio[1088]: info: CoreStateMachine::addQueueItems Feb 24 18:49:20 volumio volumio[1088]: info: CorePlayQueue::addQueueItems Feb 24 18:49:20 volumio volumio[1088]: info: Preload queue cleared Feb 24 18:49:20 volumio volumio[1088]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s254560 Feb 24 18:49:20 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Feb 24 18:49:20 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:20.957+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" state=STATUS_STOPPED positionMs=0 volume=100 Feb 24 18:49:20 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:20.958+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" id=http://178.172.150.248:2530 title="Radio Skif" Feb 24 18:49:20 volumio volumio[1088]: info: CoreCommandRouter::volumioPushQueue Feb 24 18:49:20 volumio volumio[1088]: info: CorePlayQueue::saveQueue Feb 24 18:49:20 volumio volumio[1088]: info: CoreStateMachine::updateTrackBlock Feb 24 18:49:20 volumio volumio[1088]: info: CorePlayQueue::getTrackBlock Feb 24 18:49:20 volumio volumio[1088]: info: CoreCommandRouter::volumioPlay Feb 24 18:49:20 volumio volumio[1088]: info: CoreStateMachine::play index 0 Feb 24 18:49:20 volumio volumio[1088]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 18:49:20 volumio volumio[1088]: info: CoreStateMachine::stop Feb 24 18:49:20 volumio volumio[1088]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 18:49:20 volumio volumio[1088]: info: CoreStateMachine::play index undefined Feb 24 18:49:20 volumio volumio[1088]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 18:49:20 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:20 volumio volumio[1088]: info: CoreStateMachine::startPlaybackTimer Feb 24 18:49:20 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:20 volumio volumio[1088]: info: [1771948160965] ControllerWebradio::clearAddPlayTrack Feb 24 18:49:20 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand stop Feb 24 18:49:20 volumio volumio[1088]: info: Feb 24 18:49:20 volumio volumio[1088]: ---------------------------- MPD announces state update: player Feb 24 18:49:20 volumio volumio[1088]: info: sendMpdCommand stop took 14 milliseconds Feb 24 18:49:20 volumio volumio[1088]: info: ControllerMpd::getState Feb 24 18:49:20 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:49:20 volumio volumio[1088]: info: sendMpdCommand stop took 4 milliseconds Feb 24 18:49:20 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand clear Feb 24 18:49:20 volumio volumio[1088]: info: Feb 24 18:49:20 volumio volumio[1088]: ---------------------------- MPD announces state update: player Feb 24 18:49:20 volumio volumio[1088]: info: ControllerMpd::getState Feb 24 18:49:20 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:49:20 volumio volumio[1088]: info: Feb 24 18:49:20 volumio volumio[1088]: ---------------------------- MPD announces state update: player Feb 24 18:49:20 volumio volumio[1088]: info: ControllerMpd::getState Feb 24 18:49:20 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:49:20 volumio volumio[1088]: info: Feb 24 18:49:20 volumio volumio[1088]: ---------------------------- MPD announces system playlist update Feb 24 18:49:20 volumio volumio[1088]: info: Ignoring MPD Status Update Feb 24 18:49:20 volumio volumio[1088]: info: sendMpdCommand status took 3 milliseconds Feb 24 18:49:20 volumio volumio[1088]: info: sendMpdCommand clear took 3 milliseconds Feb 24 18:49:20 volumio volumio[1088]: info: sendMpdCommand status took 2 milliseconds Feb 24 18:49:20 volumio volumio[1088]: info: sendMpdCommand status took 2 milliseconds Feb 24 18:49:20 volumio volumio[1088]: verbose: ControllerMpd::parseState Feb 24 18:49:20 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:49:20 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand load "http://live.humorfm.by:8000/veseloe" Feb 24 18:49:20 volumio volumio[1088]: verbose: ControllerMpd::parseState Feb 24 18:49:20 volumio volumio[1088]: info: ControllerMpd::pushState Feb 24 18:49:20 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Feb 24 18:49:20 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:20 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:20 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 18:49:20 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:20 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:20 volumio volumio[1088]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 24 18:49:20 volumio volumio[1088]: info: ControllerMpd::pushState Feb 24 18:49:20 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Feb 24 18:49:20 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:20 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:20 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:20 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:20 volumio volumio[1088]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 24 18:49:20 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:20.979+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" state=STATUS_STOPPED positionMs=7 volume=100 Feb 24 18:49:20 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:20.979+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" id=http://live.humorfm.by:8000/veseloe title="Humor FM" Feb 24 18:49:20 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:20.979+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" state=STATUS_STOPPED positionMs=7 volume=100 Feb 24 18:49:20 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:20.980+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" id=http://live.humorfm.by:8000/veseloe title="Humor FM" Feb 24 18:49:20 volumio volumio[1088]: info: ------------------------------ 10ms Feb 24 18:49:20 volumio volumio[1088]: info: ------------------------------ 9ms Feb 24 18:49:20 volumio volumio[1088]: info: Feb 24 18:49:20 volumio volumio[1088]: ---------------------------- MPD announces system playlist update Feb 24 18:49:20 volumio volumio[1088]: info: Ignoring MPD Status Update Feb 24 18:49:20 volumio volumio[1088]: info: Feb 24 18:49:20 volumio volumio[1088]: ---------------------------- MPD announces system playlist update Feb 24 18:49:20 volumio volumio[1088]: info: Ignoring MPD Status Update Feb 24 18:49:21 volumio volumio[1088]: error: updateQueue error: null Feb 24 18:49:21 volumio volumio[1088]: info: ------------------------------ 31ms Feb 24 18:49:21 volumio volumio[1088]: info: sendMpdCommand playlistinfo took 30 milliseconds Feb 24 18:49:21 volumio volumio[1088]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:49:21 volumio volumio[1088]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 24 18:49:21 volumio volumio[1088]: info: ------------------------------ 36ms Feb 24 18:49:21 volumio volumio[1088]: info: touch_display: Setting screensaver timeout to 10000000000 seconds. Feb 24 18:49:22 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand add "http://live.humorfm.by:8000/veseloe" Feb 24 18:49:22 volumio volumio[1088]: error: updateQueue error: null Feb 24 18:49:22 volumio volumio[1088]: info: Feb 24 18:49:22 volumio volumio[1088]: ---------------------------- MPD announces system playlist update Feb 24 18:49:22 volumio volumio[1088]: info: Ignoring MPD Status Update Feb 24 18:49:22 volumio volumio[1088]: info: ------------------------------ 1509ms Feb 24 18:49:22 volumio volumio[1088]: info: ------------------------------ 1510ms Feb 24 18:49:22 volumio volumio[1088]: info: sendMpdCommand add "http://live.humorfm.by:8000/veseloe" took 2 milliseconds Feb 24 18:49:22 volumio volumio[1088]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 24 18:49:22 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand play Feb 24 18:49:22 volumio volumio[1088]: info: Feb 24 18:49:22 volumio volumio[1088]: ---------------------------- MPD announces system playlist update Feb 24 18:49:22 volumio volumio[1088]: info: Ignoring MPD Status Update Feb 24 18:49:22 volumio volumio[1088]: info: Feb 24 18:49:22 volumio volumio[1088]: ---------------------------- MPD announces system playlist update Feb 24 18:49:22 volumio volumio[1088]: info: Ignoring MPD Status Update Feb 24 18:49:22 volumio volumio[1088]: info: ------------------------------ 3ms Feb 24 18:49:22 volumio volumio[1088]: info: sendMpdCommand play took 2 milliseconds Feb 24 18:49:22 volumio volumio[1088]: info: ------------------------------ 1ms Feb 24 18:49:23 volumio volumio[1088]: info: Feb 24 18:49:23 volumio volumio[1088]: ---------------------------- MPD announces state update: player Feb 24 18:49:23 volumio volumio[1088]: info: ControllerMpd::getState Feb 24 18:49:23 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:49:23 volumio volumio[1088]: info: Feb 24 18:49:23 volumio volumio[1088]: ---------------------------- MPD announces state update: player Feb 24 18:49:23 volumio volumio[1088]: info: ControllerMpd::getState Feb 24 18:49:23 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:49:23 volumio volumio[1088]: info: Feb 24 18:49:23 volumio volumio[1088]: ---------------------------- MPD announces state update: player Feb 24 18:49:23 volumio volumio[1088]: info: ControllerMpd::getState Feb 24 18:49:23 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:49:23 volumio volumio[1088]: info: sendMpdCommand status took 2 milliseconds Feb 24 18:49:23 volumio volumio[1088]: verbose: ControllerMpd::parseState Feb 24 18:49:23 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:49:23 volumio volumio[1088]: info: sendMpdCommand status took 2 milliseconds Feb 24 18:49:23 volumio volumio[1088]: info: sendMpdCommand status took 2 milliseconds Feb 24 18:49:23 volumio volumio[1088]: verbose: ControllerMpd::parseState Feb 24 18:49:23 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:49:23 volumio volumio[1088]: verbose: ControllerMpd::parseState Feb 24 18:49:23 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:49:23 volumio volumio[1088]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 24 18:49:23 volumio volumio[1088]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 24 18:49:23 volumio volumio[1088]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 24 18:49:23 volumio volumio[1088]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:49:23 volumio volumio[1088]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:49:23 volumio volumio[1088]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:49:23 volumio volumio[1088]: info: ControllerMpd::pushState Feb 24 18:49:23 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Feb 24 18:49:23 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:23 volumio volumio[1088]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"veseloe","artist":null,"album":null,"uri":"http://live.humorfm.by:8000/veseloe","trackType":""} Feb 24 18:49:23 volumio volumio[1088]: verbose: CURRENT POSITION 0 Feb 24 18:49:23 volumio volumio[1088]: info: CoreStateMachine::syncState stateService play Feb 24 18:49:23 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Feb 24 18:49:23 volumio volumio[1088]: info: ControllerMpd::pushState Feb 24 18:49:23 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Feb 24 18:49:23 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:23 volumio volumio[1088]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"veseloe","artist":null,"album":null,"uri":"http://live.humorfm.by:8000/veseloe","trackType":""} Feb 24 18:49:23 volumio volumio[1088]: verbose: CURRENT POSITION 0 Feb 24 18:49:23 volumio volumio[1088]: info: CoreStateMachine::syncState stateService play Feb 24 18:49:23 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus play Feb 24 18:49:23 volumio volumio[1088]: info: Received an update from plugin. extracting info from payload Feb 24 18:49:23 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:23 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:23 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 18:49:23 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:23 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:23 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:23 volumio volumio[1088]: info: ControllerMpd::pushState Feb 24 18:49:23 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Feb 24 18:49:23 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:23 volumio volumio[1088]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"veseloe","artist":null,"album":null,"uri":"http://live.humorfm.by:8000/veseloe","trackType":""} Feb 24 18:49:23 volumio volumio[1088]: verbose: CURRENT POSITION 0 Feb 24 18:49:23 volumio volumio[1088]: info: CoreStateMachine::syncState stateService play Feb 24 18:49:23 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus play Feb 24 18:49:23 volumio volumio[1088]: info: Received an update from plugin. extracting info from payload Feb 24 18:49:23 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:23 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:23 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:23 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:23 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:23 volumio volumio[1088]: info: ------------------------------ 12ms Feb 24 18:49:23 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:23.061+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" state=STATUS_PLAYING positionMs=0 volume=100 Feb 24 18:49:23 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:23.061+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" id=http://live.humorfm.by:8000/veseloe title="Humor FM" Feb 24 18:49:23 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:23.061+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" state=STATUS_PLAYING positionMs=0 volume=100 Feb 24 18:49:23 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:23.062+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" state=STATUS_PLAYING positionMs=0 volume=100 Feb 24 18:49:23 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:23.062+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" id=http://live.humorfm.by:8000/veseloe title="Humor FM" Feb 24 18:49:23 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:23.062+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" state=STATUS_PLAYING positionMs=0 volume=100 Feb 24 18:49:23 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:23.062+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" id=http://live.humorfm.by:8000/veseloe title="Humor FM" Feb 24 18:49:23 volumio volumio[1088]: info: ------------------------------ 22ms Feb 24 18:49:23 volumio volumio[1088]: info: ------------------------------ 22ms Feb 24 18:49:23 volumio volumio[1088]: info: Feb 24 18:49:23 volumio volumio[1088]: ---------------------------- MPD announces system playlist update Feb 24 18:49:23 volumio volumio[1088]: info: Ignoring MPD Status Update Feb 24 18:49:23 volumio volumio[1088]: info: Feb 24 18:49:23 volumio volumio[1088]: ---------------------------- MPD announces state update: player Feb 24 18:49:23 volumio volumio[1088]: info: ControllerMpd::getState Feb 24 18:49:23 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:49:23 volumio volumio[1088]: info: Feb 24 18:49:23 volumio volumio[1088]: ---------------------------- MPD announces system playlist update Feb 24 18:49:23 volumio volumio[1088]: info: Ignoring MPD Status Update Feb 24 18:49:23 volumio volumio[1088]: info: Feb 24 18:49:23 volumio volumio[1088]: ---------------------------- MPD announces state update: player Feb 24 18:49:23 volumio volumio[1088]: info: ControllerMpd::getState Feb 24 18:49:23 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:49:23 volumio volumio[1088]: info: Feb 24 18:49:23 volumio volumio[1088]: ---------------------------- MPD announces system playlist update Feb 24 18:49:23 volumio volumio[1088]: info: Ignoring MPD Status Update Feb 24 18:49:23 volumio volumio[1088]: info: Feb 24 18:49:23 volumio volumio[1088]: ---------------------------- MPD announces state update: player Feb 24 18:49:23 volumio volumio[1088]: info: ControllerMpd::getState Feb 24 18:49:23 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:49:23 volumio volumio[1088]: info: Feb 24 18:49:23 volumio volumio[1088]: ---------------------------- MPD announces system playlist update Feb 24 18:49:23 volumio volumio[1088]: info: Ignoring MPD Status Update Feb 24 18:49:23 volumio volumio[1088]: info: Feb 24 18:49:23 volumio volumio[1088]: ---------------------------- MPD announces state update: player Feb 24 18:49:23 volumio volumio[1088]: info: ControllerMpd::getState Feb 24 18:49:23 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:49:23 volumio volumio[1088]: info: ------------------------------ 28ms Feb 24 18:49:23 volumio volumio[1088]: info: sendMpdCommand status took 27 milliseconds Feb 24 18:49:23 volumio volumio[1088]: info: ------------------------------ 5ms Feb 24 18:49:23 volumio volumio[1088]: info: sendMpdCommand status took 4 milliseconds Feb 24 18:49:23 volumio volumio[1088]: info: ------------------------------ 4ms Feb 24 18:49:23 volumio volumio[1088]: info: sendMpdCommand status took 3 milliseconds Feb 24 18:49:23 volumio volumio[1088]: verbose: ControllerMpd::parseState Feb 24 18:49:23 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:49:23 volumio volumio[1088]: verbose: ControllerMpd::parseState Feb 24 18:49:23 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:49:23 volumio volumio[1088]: verbose: ControllerMpd::parseState Feb 24 18:49:23 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:49:23 volumio volumio[1088]: info: Feb 24 18:49:23 volumio volumio[1088]: ---------------------------- MPD announces system playlist update Feb 24 18:49:23 volumio volumio[1088]: info: Ignoring MPD Status Update Feb 24 18:49:23 volumio volumio[1088]: info: Feb 24 18:49:23 volumio volumio[1088]: ---------------------------- MPD announces state update: player Feb 24 18:49:23 volumio volumio[1088]: info: ControllerMpd::getState Feb 24 18:49:23 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:49:23 volumio volumio[1088]: info: Feb 24 18:49:23 volumio volumio[1088]: ---------------------------- MPD announces system playlist update Feb 24 18:49:23 volumio volumio[1088]: info: Ignoring MPD Status Update Feb 24 18:49:23 volumio volumio[1088]: info: Feb 24 18:49:23 volumio volumio[1088]: ---------------------------- MPD announces state update: player Feb 24 18:49:23 volumio volumio[1088]: info: ControllerMpd::getState Feb 24 18:49:23 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:49:23 volumio volumio[1088]: info: ------------------------------ 22ms Feb 24 18:49:23 volumio volumio[1088]: info: sendMpdCommand status took 20 milliseconds Feb 24 18:49:23 volumio volumio[1088]: info: sendMpdCommand playlistinfo took 18 milliseconds Feb 24 18:49:23 volumio volumio[1088]: info: ------------------------------ 18ms Feb 24 18:49:23 volumio volumio[1088]: info: sendMpdCommand status took 18 milliseconds Feb 24 18:49:23 volumio volumio[1088]: info: ------------------------------ 18ms Feb 24 18:49:23 volumio volumio[1088]: info: sendMpdCommand status took 17 milliseconds Feb 24 18:49:23 volumio volumio[1088]: verbose: ControllerMpd::parseState Feb 24 18:49:23 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:49:23 volumio volumio[1088]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:49:23 volumio volumio[1088]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:49:23 volumio volumio[1088]: verbose: ControllerMpd::parseState Feb 24 18:49:23 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:49:23 volumio volumio[1088]: verbose: ControllerMpd::parseState Feb 24 18:49:23 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:49:23 volumio volumio[1088]: info: ControllerMpd::pushState Feb 24 18:49:23 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Feb 24 18:49:23 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:23 volumio volumio[1088]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"veseloe","artist":"YUMOR FM BELARUS","album":null,"uri":"http://live.humorfm.by:8000/veseloe","trackType":""} Feb 24 18:49:23 volumio volumio[1088]: verbose: CURRENT POSITION 0 Feb 24 18:49:23 volumio volumio[1088]: info: CoreStateMachine::syncState stateService play Feb 24 18:49:23 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus play Feb 24 18:49:23 volumio volumio[1088]: info: Received an update from plugin. extracting info from payload Feb 24 18:49:23 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:23 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:23 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 18:49:23 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:23 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:23 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:23 volumio volumio[1088]: info: ControllerMpd::pushState Feb 24 18:49:23 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Feb 24 18:49:23 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:23 volumio volumio[1088]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"veseloe","artist":"YUMOR FM BELARUS","album":null,"uri":"http://live.humorfm.by:8000/veseloe","trackType":""} Feb 24 18:49:23 volumio volumio[1088]: verbose: CURRENT POSITION 0 Feb 24 18:49:23 volumio volumio[1088]: info: CoreStateMachine::syncState stateService play Feb 24 18:49:23 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus play Feb 24 18:49:23 volumio volumio[1088]: info: Received an update from plugin. extracting info from payload Feb 24 18:49:23 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:23 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:23 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:23 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:23 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:23 volumio volumio[1088]: info: ControllerMpd::pushState Feb 24 18:49:23 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Feb 24 18:49:23 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:23 volumio volumio[1088]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"veseloe","artist":"YUMOR FM BELARUS","album":null,"uri":"http://live.humorfm.by:8000/veseloe","trackType":""} Feb 24 18:49:23 volumio volumio[1088]: verbose: CURRENT POSITION 0 Feb 24 18:49:23 volumio volumio[1088]: info: CoreStateMachine::syncState stateService play Feb 24 18:49:23 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus play Feb 24 18:49:23 volumio volumio[1088]: info: Received an update from plugin. extracting info from payload Feb 24 18:49:23 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:23 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:23 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:23 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:23 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:23 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:23.131+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" state=STATUS_PLAYING positionMs=115 volume=100 Feb 24 18:49:23 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:23.132+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" id=http://live.humorfm.by:8000/veseloe title="Humor FM" Feb 24 18:49:23 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:23.132+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" state=STATUS_PLAYING positionMs=115 volume=100 Feb 24 18:49:23 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:23.132+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" id=http://live.humorfm.by:8000/veseloe title="Humor FM" Feb 24 18:49:23 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:23.132+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" state=STATUS_PLAYING positionMs=115 volume=100 Feb 24 18:49:23 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:23.133+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" id=http://live.humorfm.by:8000/veseloe title="Humor FM" Feb 24 18:49:23 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:23.133+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" state=STATUS_PLAYING positionMs=115 volume=100 Feb 24 18:49:23 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:23.132+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" id=http://live.humorfm.by:8000/veseloe title="Humor FM" Feb 24 18:49:23 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:23.133+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" id=http://live.humorfm.by:8000/veseloe title="Humor FM" Feb 24 18:49:23 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:23.133+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" state=STATUS_PLAYING positionMs=115 volume=100 Feb 24 18:49:23 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:23.133+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" id=http://live.humorfm.by:8000/veseloe title="Humor FM" Feb 24 18:49:23 volumio volumio[1088]: info: ------------------------------ 64ms Feb 24 18:49:23 volumio volumio[1088]: info: ------------------------------ 42ms Feb 24 18:49:23 volumio volumio[1088]: info: ------------------------------ 42ms Feb 24 18:49:23 volumio volumio[1088]: info: sendMpdCommand playlistinfo took 20 milliseconds Feb 24 18:49:23 volumio volumio[1088]: info: sendMpdCommand playlistinfo took 19 milliseconds Feb 24 18:49:23 volumio volumio[1088]: info: sendMpdCommand playlistinfo took 19 milliseconds Feb 24 18:49:23 volumio volumio[1088]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:49:23 volumio volumio[1088]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:49:23 volumio volumio[1088]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:49:23 volumio volumio[1088]: info: ControllerMpd::pushState Feb 24 18:49:23 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Feb 24 18:49:23 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:23 volumio volumio[1088]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"veseloe","artist":"YUMOR FM BELARUS","album":null,"uri":"http://live.humorfm.by:8000/veseloe","trackType":""} Feb 24 18:49:23 volumio volumio[1088]: verbose: CURRENT POSITION 0 Feb 24 18:49:23 volumio volumio[1088]: info: CoreStateMachine::syncState stateService play Feb 24 18:49:23 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus play Feb 24 18:49:23 volumio volumio[1088]: info: Received an update from plugin. extracting info from payload Feb 24 18:49:23 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:23 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:23 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:23 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:23 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:23 volumio volumio[1088]: info: ControllerMpd::pushState Feb 24 18:49:23 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Feb 24 18:49:23 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:23 volumio volumio[1088]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"veseloe","artist":"YUMOR FM BELARUS","album":null,"uri":"http://live.humorfm.by:8000/veseloe","trackType":""} Feb 24 18:49:23 volumio volumio[1088]: verbose: CURRENT POSITION 0 Feb 24 18:49:23 volumio volumio[1088]: info: CoreStateMachine::syncState stateService play Feb 24 18:49:23 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus play Feb 24 18:49:23 volumio volumio[1088]: info: Received an update from plugin. extracting info from payload Feb 24 18:49:23 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:23 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:23 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:23 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:23 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:23 volumio volumio[1088]: info: ControllerMpd::pushState Feb 24 18:49:23 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Feb 24 18:49:23 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:23 volumio volumio[1088]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"veseloe","artist":"YUMOR FM BELARUS","album":null,"uri":"http://live.humorfm.by:8000/veseloe","trackType":""} Feb 24 18:49:23 volumio volumio[1088]: verbose: CURRENT POSITION 0 Feb 24 18:49:23 volumio volumio[1088]: info: CoreStateMachine::syncState stateService play Feb 24 18:49:23 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus play Feb 24 18:49:23 volumio volumio[1088]: info: Received an update from plugin. extracting info from payload Feb 24 18:49:23 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:23 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:23 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:23 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:23 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:23 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:23.160+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" state=STATUS_PLAYING positionMs=115 volume=100 Feb 24 18:49:23 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:23.160+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" id=http://live.humorfm.by:8000/veseloe title="Humor FM" Feb 24 18:49:23 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:23.164+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" state=STATUS_PLAYING positionMs=115 volume=100 Feb 24 18:49:23 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:23.165+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" id=http://live.humorfm.by:8000/veseloe title="Humor FM" Feb 24 18:49:23 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:23.165+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" state=STATUS_PLAYING positionMs=115 volume=100 Feb 24 18:49:23 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:23.166+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" id=http://live.humorfm.by:8000/veseloe title="Humor FM" Feb 24 18:49:23 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:23.166+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" state=STATUS_PLAYING positionMs=115 volume=100 Feb 24 18:49:23 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:23.166+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" id=http://live.humorfm.by:8000/veseloe title="Humor FM" Feb 24 18:49:23 volumio volumio[1088]: info: ------------------------------ 71ms Feb 24 18:49:23 volumio volumio[1088]: info: ------------------------------ 67ms Feb 24 18:49:23 volumio volumio[1088]: info: ------------------------------ 67ms Feb 24 18:49:23 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:23.170+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" state=STATUS_PLAYING positionMs=115 volume=100 Feb 24 18:49:23 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:23.171+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" id=http://live.humorfm.by:8000/veseloe title="Humor FM" Feb 24 18:49:23 volumio volumio[1088]: info: touch_display: Setting screensaver timeout to 0 seconds. Feb 24 18:49:23 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState Feb 24 18:49:23 volumio volumio[1088]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Feb 24 18:49:23 volumio volumio[1088]: info: touch_display: Setting screensaver timeout to 0 seconds. Feb 24 18:49:29 volumio volumio[1088]: info: Removing uri http://icecast227.ptspb.ru:8104/ruradio from favourites Feb 24 18:49:29 volumio volumio[1088]: info: Saving local item radio-favourites Feb 24 18:49:37 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Feb 24 18:49:37 volumio volumio[1088]: info: Preload queue cleared Feb 24 18:49:40 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Feb 24 18:49:40 volumio volumio[1088]: info: Getting webradio selection Feb 24 18:49:40 volumio volumio[1088]: info: Preload queue cleared Feb 24 18:49:48 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Feb 24 18:49:48 volumio volumio[1088]: info: Preload queue cleared Feb 24 18:49:53 volumio volumio[1088]: info: Preload queue cleared Feb 24 18:49:53 volumio volumio[1088]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 24 18:49:53 volumio volumio[1088]: info: CoreStateMachine::ClearQueue Feb 24 18:49:53 volumio volumio[1088]: info: CoreStateMachine::stop Feb 24 18:49:53 volumio volumio[1088]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 18:49:53 volumio volumio[1088]: info: CoreStateMachine::stPlaybackTimer Feb 24 18:49:53 volumio volumio[1088]: info: CoreStateMachine::updateTrackBlock Feb 24 18:49:53 volumio volumio[1088]: info: CorePlayQueue::getTrackBlock Feb 24 18:49:53 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:53 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:53 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 18:49:53 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:53 volumio volumio[1088]: info: CoreStateMachine::serviceStop Feb 24 18:49:53 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:53 volumio volumio[1088]: info: CoreCommandRouter::serviceStop Feb 24 18:49:53 volumio volumio[1088]: info: [1771948193151] ControllerWebradio::stop Feb 24 18:49:53 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand stop Feb 24 18:49:53 volumio volumio[1088]: info: CorePlayQueue::clearPlayQueue Feb 24 18:49:53 volumio volumio[1088]: info: CorePlayQueue::saveQueue Feb 24 18:49:53 volumio volumio[1088]: info: CoreCommandRouter::volumioPushQueue Feb 24 18:49:53 volumio volumio[1088]: info: CoreStateMachine::addQueueItems Feb 24 18:49:53 volumio volumio[1088]: info: CorePlayQueue::addQueueItems Feb 24 18:49:53 volumio volumio[1088]: info: Preload queue cleared Feb 24 18:49:53 volumio volumio[1088]: info: Adding Item to queue: http.//emgspb.hostingradio.ru/retrofmspb.128 Feb 24 18:49:53 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Feb 24 18:49:53 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:53.155+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" state=STATUS_STOPPED positionMs=0 volume=100 Feb 24 18:49:53 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:53.155+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" id=http://live.humorfm.by:8000/veseloe title="Humor FM" Feb 24 18:49:53 volumio volumio[1088]: info: CoreCommandRouter::volumioPushQueue Feb 24 18:49:53 volumio volumio[1088]: info: CorePlayQueue::saveQueue Feb 24 18:49:53 volumio volumio[1088]: info: CoreStateMachine::updateTrackBlock Feb 24 18:49:53 volumio volumio[1088]: info: CorePlayQueue::getTrackBlock Feb 24 18:49:53 volumio volumio[1088]: info: CoreCommandRouter::volumioPlay Feb 24 18:49:53 volumio volumio[1088]: info: CoreStateMachine::play index 0 Feb 24 18:49:53 volumio volumio[1088]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 18:49:53 volumio volumio[1088]: info: CoreStateMachine::stop Feb 24 18:49:53 volumio volumio[1088]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 18:49:53 volumio volumio[1088]: info: CoreStateMachine::play index undefined Feb 24 18:49:53 volumio volumio[1088]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 18:49:53 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:53 volumio volumio[1088]: info: CoreStateMachine::startPlaybackTimer Feb 24 18:49:53 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:53 volumio volumio[1088]: info: [1771948193162] ControllerWebradio::clearAddPlayTrack Feb 24 18:49:53 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand stop Feb 24 18:49:53 volumio volumio[1088]: info: Feb 24 18:49:53 volumio volumio[1088]: ---------------------------- MPD announces state update: player Feb 24 18:49:53 volumio volumio[1088]: info: sendMpdCommand stop took 14 milliseconds Feb 24 18:49:53 volumio volumio[1088]: info: ControllerMpd::getState Feb 24 18:49:53 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:49:53 volumio volumio[1088]: info: sendMpdCommand stop took 4 milliseconds Feb 24 18:49:53 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand clear Feb 24 18:49:53 volumio volumio[1088]: info: Feb 24 18:49:53 volumio volumio[1088]: ---------------------------- MPD announces state update: player Feb 24 18:49:53 volumio volumio[1088]: info: ControllerMpd::getState Feb 24 18:49:53 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:49:53 volumio volumio[1088]: info: Feb 24 18:49:53 volumio volumio[1088]: ---------------------------- MPD announces state update: player Feb 24 18:49:53 volumio volumio[1088]: info: ControllerMpd::getState Feb 24 18:49:53 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:49:53 volumio volumio[1088]: info: Feb 24 18:49:53 volumio volumio[1088]: ---------------------------- MPD announces system playlist update Feb 24 18:49:53 volumio volumio[1088]: info: Ignoring MPD Status Update Feb 24 18:49:53 volumio volumio[1088]: info: sendMpdCommand status took 3 milliseconds Feb 24 18:49:53 volumio volumio[1088]: info: sendMpdCommand clear took 4 milliseconds Feb 24 18:49:53 volumio volumio[1088]: info: sendMpdCommand status took 3 milliseconds Feb 24 18:49:53 volumio volumio[1088]: info: sendMpdCommand status took 2 milliseconds Feb 24 18:49:53 volumio volumio[1088]: verbose: ControllerMpd::parseState Feb 24 18:49:53 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:49:53 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand load "http.//emgspb.hostingradio.ru/retrofmspb.128" Feb 24 18:49:53 volumio volumio[1088]: verbose: ControllerMpd::parseState Feb 24 18:49:53 volumio volumio[1088]: verbose: ControllerMpd::parseState Feb 24 18:49:53 volumio volumio[1088]: info: ControllerMpd::pushState Feb 24 18:49:53 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Feb 24 18:49:53 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:53 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:53 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 18:49:53 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:53 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:53 volumio volumio[1088]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 24 18:49:53 volumio volumio[1088]: info: ControllerMpd::pushState Feb 24 18:49:53 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Feb 24 18:49:53 volumio volumio[1088]: info: CoreStateMachine::pushState Feb 24 18:49:53 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:53 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Feb 24 18:49:53 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:53 volumio volumio[1088]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 24 18:49:53 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:53.180+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" state=STATUS_STOPPED positionMs=0 volume=100 Feb 24 18:49:53 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:53.180+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" state=STATUS_STOPPED positionMs=0 volume=100 Feb 24 18:49:53 volumio volumio5-onboarding[1261]: time=2026-02-24T18:49:53.180+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.151:54307 @ 0x2834270" id=http.//emgspb.hostingradio.ru/retrofmspb.128 title="Ретро FM" Feb 24 18:49:53 volumio volumio[1088]: info: ------------------------------ 14ms Feb 24 18:49:53 volumio volumio[1088]: info: ------------------------------ 14ms Feb 24 18:49:53 volumio volumio[1088]: info: Feb 24 18:49:53 volumio volumio[1088]: ---------------------------- MPD announces system playlist update Feb 24 18:49:53 volumio volumio[1088]: info: Ignoring MPD Status Update Feb 24 18:49:53 volumio volumio[1088]: info: Feb 24 18:49:53 volumio volumio[1088]: ---------------------------- MPD announces system playlist update Feb 24 18:49:53 volumio volumio[1088]: info: Ignoring MPD Status Update Feb 24 18:49:53 volumio volumio[1088]: error: updateQueue error: null Feb 24 18:49:53 volumio volumio[1088]: error: updateQueue error: null Feb 24 18:49:53 volumio volumio[1088]: error: updateQueue error: null Feb 24 18:49:53 volumio volumio[1088]: info: ------------------------------ 35ms Feb 24 18:49:53 volumio volumio[1088]: info: sendMpdCommand playlistinfo took 33 milliseconds Feb 24 18:49:53 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand add "http.//emgspb.hostingradio.ru/retrofmspb.128" Feb 24 18:49:53 volumio volumio[1088]: info: ------------------------------ 21ms Feb 24 18:49:53 volumio volumio[1088]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:49:53 volumio volumio[1088]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 24 18:49:53 volumio volumio[1088]: info: ------------------------------ 40ms Feb 24 18:49:53 volumio volumio[1088]: info: touch_display: Setting screensaver timeout to 10000000000 seconds. Feb 24 18:49:56 volumio volumio[1088]: info: Preload queue cleared Feb 24 18:49:56 volumio volumio[1088]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 24 18:49:56 volumio volumio[1088]: info: CoreStateMachine::ClearQueue Feb 24 18:49:56 volumio volumio[1088]: info: CoreStateMachine::stop Feb 24 18:49:56 volumio volumio[1088]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 18:49:56 volumio volumio[1088]: info: CorePlayQueue::clearPlayQueue Feb 24 18:49:56 volumio volumio[1088]: info: CorePlayQueue::saveQueue Feb 24 18:49:56 volumio volumio[1088]: info: CoreCommandRouter::volumioPushQueue Feb 24 18:49:56 volumio volumio[1088]: info: CoreStateMachine::addQueueItems Feb 24 18:49:56 volumio volumio[1088]: info: CorePlayQueue::addQueueItems Feb 24 18:49:56 volumio volumio[1088]: info: Preload queue cleared Feb 24 18:49:56 volumio volumio[1088]: info: Adding Item to queue: http.//emgspb.hostingradio.ru/retrofmspb.128 Feb 24 18:49:56 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Feb 24 18:49:56 volumio volumio[1088]: info: CoreCommandRouter::volumioPushQueue Feb 24 18:49:56 volumio volumio[1088]: info: CorePlayQueue::saveQueue Feb 24 18:49:56 volumio volumio[1088]: info: CoreStateMachine::updateTrackBlock Feb 24 18:49:56 volumio volumio[1088]: info: CorePlayQueue::getTrackBlock Feb 24 18:49:56 volumio volumio[1088]: info: CoreCommandRouter::volumioPlay Feb 24 18:49:56 volumio volumio[1088]: info: CoreStateMachine::play index 0 Feb 24 18:49:56 volumio volumio[1088]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 18:49:56 volumio volumio[1088]: info: CoreStateMachine::stop Feb 24 18:49:56 volumio volumio[1088]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 18:49:56 volumio volumio[1088]: info: CoreStateMachine::play index undefined Feb 24 18:49:56 volumio volumio[1088]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 18:49:56 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:56 volumio volumio[1088]: info: CoreStateMachine::startPlaybackTimer Feb 24 18:49:56 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:56 volumio volumio[1088]: info: [1771948196173] ControllerWebradio::clearAddPlayTrack Feb 24 18:49:56 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand stop Feb 24 18:49:56 volumio volumio[1088]: info: sendMpdCommand stop took 1 milliseconds Feb 24 18:49:56 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand clear Feb 24 18:49:56 volumio volumio[1088]: info: sendMpdCommand clear took 1 milliseconds Feb 24 18:49:56 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand load "http.//emgspb.hostingradio.ru/retrofmspb.128" Feb 24 18:49:56 volumio volumio[1088]: info: Feb 24 18:49:56 volumio volumio[1088]: ---------------------------- MPD announces system playlist update Feb 24 18:49:56 volumio volumio[1088]: info: Ignoring MPD Status Update Feb 24 18:49:56 volumio volumio[1088]: info: Feb 24 18:49:56 volumio volumio[1088]: ---------------------------- MPD announces system playlist update Feb 24 18:49:56 volumio volumio[1088]: info: Ignoring MPD Status Update Feb 24 18:49:56 volumio volumio[1088]: info: Feb 24 18:49:56 volumio volumio[1088]: ---------------------------- MPD announces system playlist update Feb 24 18:49:56 volumio volumio[1088]: info: Ignoring MPD Status Update Feb 24 18:49:56 volumio volumio[1088]: error: updateQueue error: null Feb 24 18:49:56 volumio volumio[1088]: error: updateQueue error: null Feb 24 18:49:56 volumio volumio[1088]: error: updateQueue error: null Feb 24 18:49:56 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand add "http.//emgspb.hostingradio.ru/retrofmspb.128" Feb 24 18:49:56 volumio volumio[1088]: info: ------------------------------ 6ms Feb 24 18:49:56 volumio volumio[1088]: info: ------------------------------ 5ms Feb 24 18:49:56 volumio volumio[1088]: info: ------------------------------ 5ms Feb 24 18:49:59 volumio volumio[1088]: info: Preload queue cleared Feb 24 18:49:59 volumio volumio[1088]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 24 18:49:59 volumio volumio[1088]: info: CoreStateMachine::ClearQueue Feb 24 18:49:59 volumio volumio[1088]: info: CoreStateMachine::stop Feb 24 18:49:59 volumio volumio[1088]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 18:49:59 volumio volumio[1088]: info: CorePlayQueue::clearPlayQueue Feb 24 18:49:59 volumio volumio[1088]: info: CorePlayQueue::saveQueue Feb 24 18:49:59 volumio volumio[1088]: info: CoreCommandRouter::volumioPushQueue Feb 24 18:49:59 volumio volumio[1088]: info: CoreStateMachine::addQueueItems Feb 24 18:49:59 volumio volumio[1088]: info: CorePlayQueue::addQueueItems Feb 24 18:49:59 volumio volumio[1088]: info: Preload queue cleared Feb 24 18:49:59 volumio volumio[1088]: info: Adding Item to queue: http.//emgspb.hostingradio.ru/retrofmspb.128 Feb 24 18:49:59 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Feb 24 18:49:59 volumio volumio[1088]: info: CoreCommandRouter::volumioPushQueue Feb 24 18:49:59 volumio volumio[1088]: info: CorePlayQueue::saveQueue Feb 24 18:49:59 volumio volumio[1088]: info: CoreStateMachine::updateTrackBlock Feb 24 18:49:59 volumio volumio[1088]: info: CorePlayQueue::getTrackBlock Feb 24 18:49:59 volumio volumio[1088]: info: CoreCommandRouter::volumioPlay Feb 24 18:49:59 volumio volumio[1088]: info: CoreStateMachine::play index 0 Feb 24 18:49:59 volumio volumio[1088]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 18:49:59 volumio volumio[1088]: info: CoreStateMachine::stop Feb 24 18:49:59 volumio volumio[1088]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 18:49:59 volumio volumio[1088]: info: CoreStateMachine::play index undefined Feb 24 18:49:59 volumio volumio[1088]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 18:49:59 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:59 volumio volumio[1088]: info: CoreStateMachine::startPlaybackTimer Feb 24 18:49:59 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Feb 24 18:49:59 volumio volumio[1088]: info: [1771948199735] ControllerWebradio::clearAddPlayTrack Feb 24 18:49:59 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand stop Feb 24 18:49:59 volumio volumio[1088]: info: sendMpdCommand stop took 1 milliseconds Feb 24 18:49:59 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand clear Feb 24 18:49:59 volumio volumio[1088]: info: Feb 24 18:49:59 volumio volumio[1088]: ---------------------------- MPD announces system playlist update Feb 24 18:49:59 volumio volumio[1088]: info: Ignoring MPD Status Update Feb 24 18:49:59 volumio volumio[1088]: info: sendMpdCommand clear took 1 milliseconds Feb 24 18:49:59 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand load "http.//emgspb.hostingradio.ru/retrofmspb.128" Feb 24 18:49:59 volumio volumio[1088]: error: updateQueue error: null Feb 24 18:49:59 volumio volumio[1088]: info: ------------------------------ 1ms Feb 24 18:49:59 volumio volumio[1088]: info: Feb 24 18:49:59 volumio volumio[1088]: ---------------------------- MPD announces system playlist update Feb 24 18:49:59 volumio volumio[1088]: info: Ignoring MPD Status Update Feb 24 18:49:59 volumio volumio[1088]: info: Feb 24 18:49:59 volumio volumio[1088]: ---------------------------- MPD announces system playlist update Feb 24 18:49:59 volumio volumio[1088]: info: Ignoring MPD Status Update Feb 24 18:49:59 volumio volumio[1088]: error: updateQueue error: null Feb 24 18:49:59 volumio volumio[1088]: error: updateQueue error: null Feb 24 18:49:59 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand add "http.//emgspb.hostingradio.ru/retrofmspb.128" Feb 24 18:49:59 volumio volumio[1088]: info: ------------------------------ 1ms Feb 24 18:49:59 volumio volumio[1088]: info: ------------------------------ 1ms Feb 24 18:50:03 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: webradio , removeMyWebRadio Feb 24 18:50:03 volumio volumio[1088]: info: {"name":"Ретро FM"} Feb 24 18:50:03 volumio volumio[1088]: info: Saving local item my-web-radio Feb 24 18:50:03 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Feb 24 18:50:06 volumio volumio[1088]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 24 18:50:06 volumio volumio[1088]: Error: connect ETIMEDOUT 23.73.2.88:443 Feb 24 18:50:06 volumio volumio[1088]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Feb 24 18:50:06 volumio volumio[1088]: errno: -110, Feb 24 18:50:06 volumio volumio[1088]: code: 'ETIMEDOUT', Feb 24 18:50:06 volumio volumio[1088]: syscall: 'connect', Feb 24 18:50:06 volumio volumio[1088]: address: '23.73.2.88', Feb 24 18:50:06 volumio volumio[1088]: port: 443 Feb 24 18:50:06 volumio volumio[1088]: } Feb 24 18:50:06 volumio volumio[1088]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 24 18:50:06 volumio sudo[4501]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-24 18:49 Feb 24 18:50:06 volumio sudo[4501]: 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="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 05 Feb 2026 01:46:18 PM CET" VOLUMIO_VERSION="3.908" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3e9dbaf8f44792d26e97456a18f12823"