Mar 24 19:25:00 volumio volumio[1169]: info: CoreCommandRouter::volumioGetState Mar 24 19:25:07 volumio volumio[1169]: info: CoreCommandRouter::volumioPause Mar 24 19:25:07 volumio volumio[1169]: info: CoreStateMachine::pause Mar 24 19:25:07 volumio volumio[1169]: info: CoreStateMachine::stPlaybackTimer Mar 24 19:25:07 volumio volumio[1169]: info: CoreStateMachine::servicePause Mar 24 19:25:07 volumio volumio[1169]: info: CorePlayQueue::getTrack 2 Mar 24 19:25:07 volumio volumio[1169]: info: CoreCommandRouter::servicePause Mar 24 19:25:07 volumio volumio[1169]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 24 19:25:07 volumio volumio[1169]: info: ControllerMpd::pause Mar 24 19:25:07 volumio volumio[1169]: verbose: ControllerMpd::sendMpdCommand pause Mar 24 19:25:07 volumio volumio[1169]: info: sendMpdCommand pause took 1 milliseconds Mar 24 19:25:07 volumio volumio[1169]: info: Mar 24 19:25:07 volumio volumio[1169]: ---------------------------- MPD announces state update: player Mar 24 19:25:07 volumio volumio[1169]: info: ControllerMpd::getState Mar 24 19:25:07 volumio volumio[1169]: verbose: ControllerMpd::sendMpdCommand status Mar 24 19:25:07 volumio volumio[1169]: info: Mar 24 19:25:07 volumio volumio[1169]: ---------------------------- MPD announces state update: player Mar 24 19:25:07 volumio volumio[1169]: info: ControllerMpd::getState Mar 24 19:25:07 volumio volumio[1169]: verbose: ControllerMpd::sendMpdCommand status Mar 24 19:25:07 volumio volumio[1169]: info: Mar 24 19:25:07 volumio volumio[1169]: ---------------------------- MPD announces state update: player Mar 24 19:25:07 volumio volumio[1169]: info: ControllerMpd::getState Mar 24 19:25:07 volumio volumio[1169]: verbose: ControllerMpd::sendMpdCommand status Mar 24 19:25:07 volumio volumio[1169]: info: sendMpdCommand status took 5 milliseconds Mar 24 19:25:07 volumio volumio[1169]: info: sendMpdCommand status took 4 milliseconds Mar 24 19:25:07 volumio volumio[1169]: info: sendMpdCommand status took 3 milliseconds Mar 24 19:25:07 volumio volumio[1169]: verbose: ControllerMpd::parseState Mar 24 19:25:07 volumio volumio[1169]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 19:25:07 volumio volumio[1169]: verbose: ControllerMpd::parseState Mar 24 19:25:07 volumio volumio[1169]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 19:25:07 volumio volumio[1169]: verbose: ControllerMpd::parseState Mar 24 19:25:07 volumio volumio[1169]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 19:25:07 volumio volumio[1169]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 24 19:25:07 volumio volumio[1169]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 24 19:25:07 volumio volumio[1169]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 24 19:25:07 volumio volumio[1169]: verbose: ControllerMpd::parseTrackInfo Mar 24 19:25:07 volumio volumio[1169]: verbose: ControllerMpd::parseTrackInfo Mar 24 19:25:07 volumio volumio[1169]: verbose: ControllerMpd::parseTrackInfo Mar 24 19:25:07 volumio volumio[1169]: info: ControllerMpd::pushState Mar 24 19:25:07 volumio volumio[1169]: info: CoreCommandRouter::servicePushState Mar 24 19:25:07 volumio volumio[1169]: info: CorePlayQueue::getTrack 2 Mar 24 19:25:07 volumio volumio[1169]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":68408,"duration":206,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1108 Kbps","isStreaming":false,"title":"Sixteen Tons Of Hardware","artist":"BWO","album":"Prototype","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-149.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D604568459f88ebf9a854399c9d311e671febb528fe478807f1c718bbf17136fe%2Clid%3D217%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69d0379b%2F0%2F14618%2F8d9487fc.196343752.5.358635%2Fflac-mp4&ext=.flac","trackType":"flac"} Mar 24 19:25:07 volumio volumio[1169]: verbose: CURRENT POSITION 2 Mar 24 19:25:07 volumio volumio[1169]: info: CoreStateMachine::syncState stateService pause Mar 24 19:25:07 volumio volumio[1169]: info: CoreStateMachine::syncState currentStatus pause Mar 24 19:25:07 volumio volumio[1169]: info: CoreStateMachine::pushState Mar 24 19:25:07 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 19:25:07 volumio volumio[1169]: info: CoreCommandRouter::volumioPushState Mar 24 19:25:07 volumio volumio[1169]: info: CoreStateMachine::stPlaybackTimer Mar 24 19:25:07 volumio volumio[1169]: info: ControllerMpd::pushState Mar 24 19:25:07 volumio volumio[1169]: info: CoreCommandRouter::servicePushState Mar 24 19:25:07 volumio volumio[1169]: info: CorePlayQueue::getTrack 2 Mar 24 19:25:07 volumio volumio[1169]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":68408,"duration":206,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1108 Kbps","isStreaming":false,"title":"Sixteen Tons Of Hardware","artist":"BWO","album":"Prototype","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-149.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D604568459f88ebf9a854399c9d311e671febb528fe478807f1c718bbf17136fe%2Clid%3D217%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69d0379b%2F0%2F14618%2F8d9487fc.196343752.5.358635%2Fflac-mp4&ext=.flac","trackType":"flac"} Mar 24 19:25:07 volumio volumio[1169]: verbose: CURRENT POSITION 2 Mar 24 19:25:07 volumio volumio[1169]: info: CoreStateMachine::syncState stateService pause Mar 24 19:25:07 volumio volumio[1169]: info: CoreStateMachine::syncState currentStatus pause Mar 24 19:25:07 volumio volumio[1169]: info: CoreStateMachine::pushState Mar 24 19:25:07 volumio volumio[1169]: info: CoreCommandRouter::volumioPushState Mar 24 19:25:07 volumio volumio[1169]: info: CoreStateMachine::stPlaybackTimer Mar 24 19:25:07 volumio volumio[1169]: info: ControllerMpd::pushState Mar 24 19:25:07 volumio volumio[1169]: info: CoreCommandRouter::servicePushState Mar 24 19:25:07 volumio volumio[1169]: info: CorePlayQueue::getTrack 2 Mar 24 19:25:07 volumio volumio[1169]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":68408,"duration":206,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1108 Kbps","isStreaming":false,"title":"Sixteen Tons Of Hardware","artist":"BWO","album":"Prototype","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-149.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D604568459f88ebf9a854399c9d311e671febb528fe478807f1c718bbf17136fe%2Clid%3D217%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69d0379b%2F0%2F14618%2F8d9487fc.196343752.5.358635%2Fflac-mp4&ext=.flac","trackType":"flac"} Mar 24 19:25:07 volumio volumio[1169]: verbose: CURRENT POSITION 2 Mar 24 19:25:07 volumio volumio[1169]: info: CoreStateMachine::syncState stateService pause Mar 24 19:25:07 volumio volumio[1169]: info: CoreStateMachine::syncState currentStatus pause Mar 24 19:25:07 volumio volumio[1169]: info: CoreStateMachine::pushState Mar 24 19:25:07 volumio volumio[1169]: info: CoreCommandRouter::volumioPushState Mar 24 19:25:07 volumio volumio[1169]: info: CoreStateMachine::stPlaybackTimer Mar 24 19:25:07 volumio volumio5-onboarding[1797]: time=2026-03-24T19:25:07.529+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.107:49533,00:00:00:00:00:00%13 @ 0x23f9050" state=STATUS_PAUSED positionMs=115623 volume=100 Mar 24 19:25:07 volumio volumio5-onboarding[1797]: time=2026-03-24T19:25:07.529+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.107:49533,00:00:00:00:00:00%13 @ 0x23f9050" id="http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-149.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D604568459f88ebf9a854399c9d311e671febb528fe478807f1c718bbf17136fe%2Clid%3D217%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69d0379b%2F0%2F14618%2F8d9487fc.196343752.5.358635%2Fflac-mp4&ext=.flac" title="Sixteen Tons Of Hardware" Mar 24 19:25:07 volumio volumio5-onboarding[1797]: time=2026-03-24T19:25:07.532+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.107:49533,00:00:00:00:00:00%13 @ 0x23f9050" state=STATUS_PAUSED positionMs=115623 volume=100 Mar 24 19:25:07 volumio volumio5-onboarding[1797]: time=2026-03-24T19:25:07.533+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.107:49533,00:00:00:00:00:00%13 @ 0x23f9050" id="http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-149.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D604568459f88ebf9a854399c9d311e671febb528fe478807f1c718bbf17136fe%2Clid%3D217%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69d0379b%2F0%2F14618%2F8d9487fc.196343752.5.358635%2Fflac-mp4&ext=.flac" title="Sixteen Tons Of Hardware" Mar 24 19:25:07 volumio volumio5-onboarding[1797]: time=2026-03-24T19:25:07.534+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.107:49533,00:00:00:00:00:00%13 @ 0x23f9050" state=STATUS_PAUSED positionMs=115623 volume=100 Mar 24 19:25:07 volumio volumio5-onboarding[1797]: time=2026-03-24T19:25:07.535+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.107:49533,00:00:00:00:00:00%13 @ 0x23f9050" id="http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-149.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D604568459f88ebf9a854399c9d311e671febb528fe478807f1c718bbf17136fe%2Clid%3D217%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69d0379b%2F0%2F14618%2F8d9487fc.196343752.5.358635%2Fflac-mp4&ext=.flac" title="Sixteen Tons Of Hardware" Mar 24 19:25:07 volumio volumio[1169]: info: ------------------------------ 52ms Mar 24 19:25:07 volumio volumio[1169]: info: ------------------------------ 51ms Mar 24 19:25:07 volumio volumio[1169]: info: ------------------------------ 50ms Mar 24 19:25:07 volumio volumio[1169]: info: CoreCommandRouter::volumioGetQueue Mar 24 19:25:07 volumio volumio[1169]: info: CoreStateMachine::getQueue Mar 24 19:25:07 volumio volumio[1169]: info: CorePlayQueue::getQueue Mar 24 19:25:07 volumio volumio[1169]: info: CoreCommandRouter::volumioGetQueue Mar 24 19:25:07 volumio volumio[1169]: info: CoreStateMachine::getQueue Mar 24 19:25:07 volumio volumio[1169]: info: CorePlayQueue::getQueue Mar 24 19:25:07 volumio volumio[1169]: info: CoreCommandRouter::volumioGetQueue Mar 24 19:25:07 volumio volumio[1169]: info: CoreStateMachine::getQueue Mar 24 19:25:07 volumio volumio[1169]: info: CorePlayQueue::getQueue Mar 24 19:25:09 volumio volumio[1169]: info: CoreCommandRouter::volumioPlay Mar 24 19:25:09 volumio volumio[1169]: info: CoreStateMachine::play index undefined Mar 24 19:25:09 volumio volumio[1169]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 19:25:09 volumio volumio[1169]: info: CorePlayQueue::getTrack 2 Mar 24 19:25:09 volumio volumio[1169]: info: CoreStateMachine::startPlaybackTimer Mar 24 19:25:09 volumio volumio[1169]: info: CorePlayQueue::getTrack 2 Mar 24 19:25:09 volumio volumio[1169]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 24 19:25:09 volumio volumio[1169]: info: ControllerMpd::resume Mar 24 19:25:09 volumio volumio[1169]: verbose: ControllerMpd::sendMpdCommand play Mar 24 19:25:09 volumio volumio[1169]: info: sendMpdCommand play took 5 milliseconds Mar 24 19:25:09 volumio volumio[1169]: info: Mar 24 19:25:09 volumio volumio[1169]: ---------------------------- MPD announces state update: player Mar 24 19:25:09 volumio volumio[1169]: info: ControllerMpd::getState Mar 24 19:25:09 volumio volumio[1169]: verbose: ControllerMpd::sendMpdCommand status Mar 24 19:25:09 volumio volumio[1169]: info: Mar 24 19:25:09 volumio volumio[1169]: ---------------------------- MPD announces state update: player Mar 24 19:25:09 volumio volumio[1169]: info: ControllerMpd::getState Mar 24 19:25:09 volumio volumio[1169]: verbose: ControllerMpd::sendMpdCommand status Mar 24 19:25:09 volumio volumio[1169]: info: Mar 24 19:25:09 volumio volumio[1169]: ---------------------------- MPD announces state update: player Mar 24 19:25:09 volumio volumio[1169]: info: ControllerMpd::getState Mar 24 19:25:09 volumio volumio[1169]: verbose: ControllerMpd::sendMpdCommand status Mar 24 19:25:09 volumio volumio[1169]: info: sendMpdCommand status took 4 milliseconds Mar 24 19:25:09 volumio volumio[1169]: info: sendMpdCommand status took 2 milliseconds Mar 24 19:25:09 volumio volumio[1169]: verbose: ControllerMpd::parseState Mar 24 19:25:09 volumio volumio[1169]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 19:25:09 volumio volumio[1169]: verbose: ControllerMpd::parseState Mar 24 19:25:09 volumio volumio[1169]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 19:25:09 volumio volumio[1169]: info: sendMpdCommand status took 3 milliseconds Mar 24 19:25:09 volumio volumio[1169]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 24 19:25:09 volumio volumio[1169]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 24 19:25:09 volumio volumio[1169]: verbose: ControllerMpd::parseState Mar 24 19:25:09 volumio volumio[1169]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 19:25:09 volumio volumio[1169]: verbose: ControllerMpd::parseTrackInfo Mar 24 19:25:09 volumio volumio[1169]: verbose: ControllerMpd::parseTrackInfo Mar 24 19:25:09 volumio volumio[1169]: info: ControllerMpd::pushState Mar 24 19:25:09 volumio volumio[1169]: info: CoreCommandRouter::servicePushState Mar 24 19:25:09 volumio volumio[1169]: info: CorePlayQueue::getTrack 2 Mar 24 19:25:09 volumio volumio[1169]: verbose: STATE SERVICE {"status":"play","position":0,"seek":68820,"duration":206,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1108 Kbps","isStreaming":false,"title":"Sixteen Tons Of Hardware","artist":"BWO","album":"Prototype","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-149.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D604568459f88ebf9a854399c9d311e671febb528fe478807f1c718bbf17136fe%2Clid%3D217%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69d0379b%2F0%2F14618%2F8d9487fc.196343752.5.358635%2Fflac-mp4&ext=.flac","trackType":"flac"} Mar 24 19:25:09 volumio volumio[1169]: verbose: CURRENT POSITION 2 Mar 24 19:25:09 volumio volumio[1169]: info: CoreStateMachine::syncState stateService play Mar 24 19:25:09 volumio volumio[1169]: info: CoreStateMachine::syncState currentStatus pause Mar 24 19:25:09 volumio volumio[1169]: info: CoreStateMachine::pushState Mar 24 19:25:09 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 19:25:09 volumio volumio[1169]: info: CoreCommandRouter::volumioPushState Mar 24 19:25:09 volumio volumio[1169]: info: ControllerMpd::pushState Mar 24 19:25:09 volumio volumio[1169]: info: CoreCommandRouter::servicePushState Mar 24 19:25:09 volumio volumio[1169]: info: CorePlayQueue::getTrack 2 Mar 24 19:25:09 volumio volumio[1169]: verbose: STATE SERVICE {"status":"play","position":0,"seek":68820,"duration":206,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1108 Kbps","isStreaming":false,"title":"Sixteen Tons Of Hardware","artist":"BWO","album":"Prototype","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-149.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D604568459f88ebf9a854399c9d311e671febb528fe478807f1c718bbf17136fe%2Clid%3D217%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69d0379b%2F0%2F14618%2F8d9487fc.196343752.5.358635%2Fflac-mp4&ext=.flac","trackType":"flac"} Mar 24 19:25:09 volumio volumio[1169]: verbose: CURRENT POSITION 2 Mar 24 19:25:09 volumio volumio[1169]: info: CoreStateMachine::syncState stateService play Mar 24 19:25:09 volumio volumio[1169]: info: CoreStateMachine::syncState currentStatus play Mar 24 19:25:09 volumio volumio[1169]: info: Received an update from plugin. extracting info from payload Mar 24 19:25:09 volumio volumio[1169]: info: CoreStateMachine::pushState Mar 24 19:25:09 volumio volumio[1169]: info: CoreCommandRouter::volumioPushState Mar 24 19:25:09 volumio volumio[1169]: info: CoreStateMachine::pushState Mar 24 19:25:09 volumio volumio[1169]: info: CoreCommandRouter::volumioPushState Mar 24 19:25:09 volumio volumio5-onboarding[1797]: time=2026-03-24T19:25:09.614+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.107:49533,00:00:00:00:00:00%13 @ 0x23f9050" state=STATUS_PLAYING positionMs=115874 volume=100 Mar 24 19:25:09 volumio volumio5-onboarding[1797]: time=2026-03-24T19:25:09.615+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.107:49533,00:00:00:00:00:00%13 @ 0x23f9050" id="http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-149.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D604568459f88ebf9a854399c9d311e671febb528fe478807f1c718bbf17136fe%2Clid%3D217%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69d0379b%2F0%2F14618%2F8d9487fc.196343752.5.358635%2Fflac-mp4&ext=.flac" title="Sixteen Tons Of Hardware" Mar 24 19:25:09 volumio volumio5-onboarding[1797]: time=2026-03-24T19:25:09.618+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.107:49533,00:00:00:00:00:00%13 @ 0x23f9050" state=STATUS_PLAYING positionMs=115874 volume=100 Mar 24 19:25:09 volumio volumio5-onboarding[1797]: time=2026-03-24T19:25:09.619+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.107:49533,00:00:00:00:00:00%13 @ 0x23f9050" id="http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-149.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D604568459f88ebf9a854399c9d311e671febb528fe478807f1c718bbf17136fe%2Clid%3D217%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69d0379b%2F0%2F14618%2F8d9487fc.196343752.5.358635%2Fflac-mp4&ext=.flac" title="Sixteen Tons Of Hardware" Mar 24 19:25:09 volumio volumio5-onboarding[1797]: time=2026-03-24T19:25:09.621+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.107:49533,00:00:00:00:00:00%13 @ 0x23f9050" state=STATUS_PLAYING positionMs=115874 volume=100 Mar 24 19:25:09 volumio volumio5-onboarding[1797]: time=2026-03-24T19:25:09.621+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.107:49533,00:00:00:00:00:00%13 @ 0x23f9050" id="http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-149.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D604568459f88ebf9a854399c9d311e671febb528fe478807f1c718bbf17136fe%2Clid%3D217%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69d0379b%2F0%2F14618%2F8d9487fc.196343752.5.358635%2Fflac-mp4&ext=.flac" title="Sixteen Tons Of Hardware" Mar 24 19:25:09 volumio volumio[1169]: info: ------------------------------ 50ms Mar 24 19:25:09 volumio volumio[1169]: info: ------------------------------ 49ms Mar 24 19:25:09 volumio volumio[1169]: info: sendMpdCommand playlistinfo took 43 milliseconds Mar 24 19:25:09 volumio volumio[1169]: verbose: ControllerMpd::parseTrackInfo Mar 24 19:25:09 volumio volumio[1169]: info: ControllerMpd::pushState Mar 24 19:25:09 volumio volumio[1169]: info: CoreCommandRouter::servicePushState Mar 24 19:25:09 volumio volumio[1169]: info: CorePlayQueue::getTrack 2 Mar 24 19:25:09 volumio volumio[1169]: verbose: STATE SERVICE {"status":"play","position":0,"seek":68820,"duration":206,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1108 Kbps","isStreaming":false,"title":"Sixteen Tons Of Hardware","artist":"BWO","album":"Prototype","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-149.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D604568459f88ebf9a854399c9d311e671febb528fe478807f1c718bbf17136fe%2Clid%3D217%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69d0379b%2F0%2F14618%2F8d9487fc.196343752.5.358635%2Fflac-mp4&ext=.flac","trackType":"flac"} Mar 24 19:25:09 volumio volumio[1169]: verbose: CURRENT POSITION 2 Mar 24 19:25:09 volumio volumio[1169]: info: CoreStateMachine::syncState stateService play Mar 24 19:25:09 volumio volumio[1169]: info: CoreStateMachine::syncState currentStatus play Mar 24 19:25:09 volumio volumio[1169]: info: Received an update from plugin. extracting info from payload Mar 24 19:25:09 volumio volumio[1169]: info: CoreStateMachine::pushState Mar 24 19:25:09 volumio volumio[1169]: info: CoreCommandRouter::volumioPushState Mar 24 19:25:09 volumio volumio[1169]: info: CoreStateMachine::pushState Mar 24 19:25:09 volumio volumio[1169]: info: CoreCommandRouter::volumioPushState Mar 24 19:25:09 volumio volumio5-onboarding[1797]: time=2026-03-24T19:25:09.651+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.107:49533,00:00:00:00:00:00%13 @ 0x23f9050" state=STATUS_PLAYING positionMs=115874 volume=100 Mar 24 19:25:09 volumio volumio5-onboarding[1797]: time=2026-03-24T19:25:09.652+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.107:49533,00:00:00:00:00:00%13 @ 0x23f9050" id="http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-149.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D604568459f88ebf9a854399c9d311e671febb528fe478807f1c718bbf17136fe%2Clid%3D217%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69d0379b%2F0%2F14618%2F8d9487fc.196343752.5.358635%2Fflac-mp4&ext=.flac" title="Sixteen Tons Of Hardware" Mar 24 19:25:09 volumio volumio5-onboarding[1797]: time=2026-03-24T19:25:09.654+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.107:49533,00:00:00:00:00:00%13 @ 0x23f9050" state=STATUS_PLAYING positionMs=115874 volume=100 Mar 24 19:25:09 volumio volumio5-onboarding[1797]: time=2026-03-24T19:25:09.655+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.107:49533,00:00:00:00:00:00%13 @ 0x23f9050" id="http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-149.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D604568459f88ebf9a854399c9d311e671febb528fe478807f1c718bbf17136fe%2Clid%3D217%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69d0379b%2F0%2F14618%2F8d9487fc.196343752.5.358635%2Fflac-mp4&ext=.flac" title="Sixteen Tons Of Hardware" Mar 24 19:25:09 volumio volumio[1169]: info: ------------------------------ 79ms Mar 24 19:25:09 volumio volumio[1169]: info: CoreCommandRouter::volumioGetQueue Mar 24 19:25:09 volumio volumio[1169]: info: CoreStateMachine::getQueue Mar 24 19:25:09 volumio volumio[1169]: info: CorePlayQueue::getQueue Mar 24 19:25:09 volumio volumio[1169]: info: CoreCommandRouter::volumioGetQueue Mar 24 19:25:09 volumio volumio[1169]: info: CoreStateMachine::getQueue Mar 24 19:25:09 volumio volumio[1169]: info: CorePlayQueue::getQueue Mar 24 19:25:09 volumio volumio[1169]: info: CoreCommandRouter::volumioGetQueue Mar 24 19:25:09 volumio volumio[1169]: info: CoreStateMachine::getQueue Mar 24 19:25:09 volumio volumio[1169]: info: CorePlayQueue::getQueue Mar 24 19:25:09 volumio volumio[1169]: info: CoreCommandRouter::volumioGetQueue Mar 24 19:25:09 volumio volumio[1169]: info: CoreStateMachine::getQueue Mar 24 19:25:09 volumio volumio[1169]: info: CorePlayQueue::getQueue Mar 24 19:25:09 volumio volumio[1169]: info: CoreCommandRouter::volumioGetQueue Mar 24 19:25:09 volumio volumio[1169]: info: CoreStateMachine::getQueue Mar 24 19:25:09 volumio volumio[1169]: info: CorePlayQueue::getQueue Mar 24 19:25:10 volumio volumio[1169]: info: CoreCommandRouter::volumioGetState Mar 24 19:25:10 volumio volumio[1169]: info: Listing playlists Mar 24 19:25:10 volumio volumio[1169]: info: Listing playlists Mar 24 19:25:14 volumio volumio[1169]: info: CoreCommandRouter::volumioPause Mar 24 19:25:14 volumio volumio[1169]: info: CoreStateMachine::pause Mar 24 19:25:14 volumio volumio[1169]: info: CoreStateMachine::stPlaybackTimer Mar 24 19:25:14 volumio volumio[1169]: info: CoreStateMachine::servicePause Mar 24 19:25:14 volumio volumio[1169]: info: CorePlayQueue::getTrack 2 Mar 24 19:25:14 volumio volumio[1169]: info: CoreCommandRouter::servicePause Mar 24 19:25:14 volumio volumio[1169]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 24 19:25:14 volumio volumio[1169]: info: ControllerMpd::pause Mar 24 19:25:14 volumio volumio[1169]: verbose: ControllerMpd::sendMpdCommand pause Mar 24 19:25:14 volumio volumio[1169]: info: Mar 24 19:25:14 volumio volumio[1169]: ---------------------------- MPD announces state update: player Mar 24 19:25:14 volumio volumio[1169]: info: sendMpdCommand pause took 3 milliseconds Mar 24 19:25:14 volumio volumio[1169]: info: ControllerMpd::getState Mar 24 19:25:14 volumio volumio[1169]: verbose: ControllerMpd::sendMpdCommand status Mar 24 19:25:14 volumio volumio[1169]: info: Mar 24 19:25:14 volumio volumio[1169]: ---------------------------- MPD announces state update: player Mar 24 19:25:14 volumio volumio[1169]: info: ControllerMpd::getState Mar 24 19:25:14 volumio volumio[1169]: verbose: ControllerMpd::sendMpdCommand status Mar 24 19:25:14 volumio volumio[1169]: info: Mar 24 19:25:14 volumio volumio[1169]: ---------------------------- MPD announces state update: player Mar 24 19:25:14 volumio volumio[1169]: info: ControllerMpd::getState Mar 24 19:25:14 volumio volumio[1169]: verbose: ControllerMpd::sendMpdCommand status Mar 24 19:25:14 volumio volumio[1169]: info: sendMpdCommand status took 7 milliseconds Mar 24 19:25:14 volumio volumio[1169]: info: sendMpdCommand status took 5 milliseconds Mar 24 19:25:14 volumio volumio[1169]: info: sendMpdCommand status took 2 milliseconds Mar 24 19:25:14 volumio volumio[1169]: verbose: ControllerMpd::parseState Mar 24 19:25:14 volumio volumio[1169]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 19:25:14 volumio volumio[1169]: verbose: ControllerMpd::parseState Mar 24 19:25:14 volumio volumio[1169]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 19:25:14 volumio volumio[1169]: verbose: ControllerMpd::parseState Mar 24 19:25:14 volumio volumio[1169]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 19:25:14 volumio volumio[1169]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 24 19:25:14 volumio volumio[1169]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 24 19:25:14 volumio volumio[1169]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 24 19:25:14 volumio volumio[1169]: verbose: ControllerMpd::parseTrackInfo Mar 24 19:25:14 volumio volumio[1169]: verbose: ControllerMpd::parseTrackInfo Mar 24 19:25:14 volumio volumio[1169]: verbose: ControllerMpd::parseTrackInfo Mar 24 19:25:14 volumio volumio[1169]: info: ControllerMpd::pushState Mar 24 19:25:14 volumio volumio[1169]: info: CoreCommandRouter::servicePushState Mar 24 19:25:14 volumio volumio[1169]: info: CorePlayQueue::getTrack 2 Mar 24 19:25:14 volumio volumio[1169]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":72168,"duration":206,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1108 Kbps","isStreaming":false,"title":"Sixteen Tons Of Hardware","artist":"BWO","album":"Prototype","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-149.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D604568459f88ebf9a854399c9d311e671febb528fe478807f1c718bbf17136fe%2Clid%3D217%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69d0379b%2F0%2F14618%2F8d9487fc.196343752.5.358635%2Fflac-mp4&ext=.flac","trackType":"flac"} Mar 24 19:25:14 volumio volumio[1169]: verbose: CURRENT POSITION 2 Mar 24 19:25:14 volumio volumio[1169]: info: CoreStateMachine::syncState stateService pause Mar 24 19:25:14 volumio volumio[1169]: info: CoreStateMachine::syncState currentStatus pause Mar 24 19:25:14 volumio volumio[1169]: info: CoreStateMachine::pushState Mar 24 19:25:14 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 19:25:14 volumio volumio[1169]: info: CoreCommandRouter::volumioPushState Mar 24 19:25:14 volumio volumio[1169]: info: CoreStateMachine::stPlaybackTimer Mar 24 19:25:14 volumio volumio[1169]: info: ControllerMpd::pushState Mar 24 19:25:14 volumio volumio[1169]: info: CoreCommandRouter::servicePushState Mar 24 19:25:14 volumio volumio[1169]: info: CorePlayQueue::getTrack 2 Mar 24 19:25:14 volumio volumio[1169]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":72168,"duration":206,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1108 Kbps","isStreaming":false,"title":"Sixteen Tons Of Hardware","artist":"BWO","album":"Prototype","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-149.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D604568459f88ebf9a854399c9d311e671febb528fe478807f1c718bbf17136fe%2Clid%3D217%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69d0379b%2F0%2F14618%2F8d9487fc.196343752.5.358635%2Fflac-mp4&ext=.flac","trackType":"flac"} Mar 24 19:25:14 volumio volumio[1169]: verbose: CURRENT POSITION 2 Mar 24 19:25:14 volumio volumio[1169]: info: CoreStateMachine::syncState stateService pause Mar 24 19:25:14 volumio volumio[1169]: info: CoreStateMachine::syncState currentStatus pause Mar 24 19:25:14 volumio volumio[1169]: info: CoreStateMachine::pushState Mar 24 19:25:14 volumio volumio[1169]: info: CoreCommandRouter::volumioPushState Mar 24 19:25:14 volumio volumio[1169]: info: CoreStateMachine::stPlaybackTimer Mar 24 19:25:14 volumio volumio[1169]: info: ControllerMpd::pushState Mar 24 19:25:14 volumio volumio[1169]: info: CoreCommandRouter::servicePushState Mar 24 19:25:14 volumio volumio[1169]: info: CorePlayQueue::getTrack 2 Mar 24 19:25:14 volumio volumio[1169]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":72168,"duration":206,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1108 Kbps","isStreaming":false,"title":"Sixteen Tons Of Hardware","artist":"BWO","album":"Prototype","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-149.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D604568459f88ebf9a854399c9d311e671febb528fe478807f1c718bbf17136fe%2Clid%3D217%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69d0379b%2F0%2F14618%2F8d9487fc.196343752.5.358635%2Fflac-mp4&ext=.flac","trackType":"flac"} Mar 24 19:25:14 volumio volumio[1169]: verbose: CURRENT POSITION 2 Mar 24 19:25:14 volumio volumio[1169]: info: CoreStateMachine::syncState stateService pause Mar 24 19:25:14 volumio volumio[1169]: info: CoreStateMachine::syncState currentStatus pause Mar 24 19:25:14 volumio volumio[1169]: info: CoreStateMachine::pushState Mar 24 19:25:14 volumio volumio[1169]: info: CoreCommandRouter::volumioPushState Mar 24 19:25:14 volumio volumio[1169]: info: CoreStateMachine::stPlaybackTimer Mar 24 19:25:14 volumio volumio5-onboarding[1797]: time=2026-03-24T19:25:14.359+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.107:49533,00:00:00:00:00:00%13 @ 0x23f9050" state=STATUS_PAUSED positionMs=120631 volume=100 Mar 24 19:25:14 volumio volumio5-onboarding[1797]: time=2026-03-24T19:25:14.360+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.107:49533,00:00:00:00:00:00%13 @ 0x23f9050" id="http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-149.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D604568459f88ebf9a854399c9d311e671febb528fe478807f1c718bbf17136fe%2Clid%3D217%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69d0379b%2F0%2F14618%2F8d9487fc.196343752.5.358635%2Fflac-mp4&ext=.flac" title="Sixteen Tons Of Hardware" Mar 24 19:25:14 volumio volumio5-onboarding[1797]: time=2026-03-24T19:25:14.363+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.107:49533,00:00:00:00:00:00%13 @ 0x23f9050" state=STATUS_PAUSED positionMs=120631 volume=100 Mar 24 19:25:14 volumio volumio5-onboarding[1797]: time=2026-03-24T19:25:14.364+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.107:49533,00:00:00:00:00:00%13 @ 0x23f9050" id="http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-149.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D604568459f88ebf9a854399c9d311e671febb528fe478807f1c718bbf17136fe%2Clid%3D217%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69d0379b%2F0%2F14618%2F8d9487fc.196343752.5.358635%2Fflac-mp4&ext=.flac" title="Sixteen Tons Of Hardware" Mar 24 19:25:14 volumio volumio5-onboarding[1797]: time=2026-03-24T19:25:14.366+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.107:49533,00:00:00:00:00:00%13 @ 0x23f9050" state=STATUS_PAUSED positionMs=120631 volume=100 Mar 24 19:25:14 volumio volumio5-onboarding[1797]: time=2026-03-24T19:25:14.367+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.107:49533,00:00:00:00:00:00%13 @ 0x23f9050" id="http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-149.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D604568459f88ebf9a854399c9d311e671febb528fe478807f1c718bbf17136fe%2Clid%3D217%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69d0379b%2F0%2F14618%2F8d9487fc.196343752.5.358635%2Fflac-mp4&ext=.flac" title="Sixteen Tons Of Hardware" Mar 24 19:25:14 volumio volumio[1169]: info: ------------------------------ 53ms Mar 24 19:25:14 volumio volumio[1169]: info: ------------------------------ 52ms Mar 24 19:25:14 volumio volumio[1169]: info: ------------------------------ 50ms Mar 24 19:25:14 volumio volumio[1169]: info: CoreCommandRouter::volumioGetQueue Mar 24 19:25:14 volumio volumio[1169]: info: CoreStateMachine::getQueue Mar 24 19:25:14 volumio volumio[1169]: info: CorePlayQueue::getQueue Mar 24 19:25:14 volumio volumio[1169]: info: CoreCommandRouter::volumioGetQueue Mar 24 19:25:14 volumio volumio[1169]: info: CoreStateMachine::getQueue Mar 24 19:25:14 volumio volumio[1169]: info: CorePlayQueue::getQueue Mar 24 19:25:14 volumio volumio[1169]: info: CoreCommandRouter::volumioGetQueue Mar 24 19:25:14 volumio volumio[1169]: info: CoreStateMachine::getQueue Mar 24 19:25:14 volumio volumio[1169]: info: CorePlayQueue::getQueue Mar 24 19:25:15 volumio volumio[1169]: info: CoreCommandRouter::volumioNext Mar 24 19:25:15 volumio volumio[1169]: info: CoreStateMachine::next Mar 24 19:25:15 volumio volumio[1169]: info: ControllerMpd::next Mar 24 19:25:15 volumio volumio[1169]: verbose: ControllerMpd::sendMpdCommand next Mar 24 19:25:15 volumio volumio[1169]: info: sendMpdCommand next took 6 milliseconds Mar 24 19:25:15 volumio volumio[1169]: info: Mar 24 19:25:15 volumio volumio[1169]: ---------------------------- MPD announces system playlist update Mar 24 19:25:15 volumio volumio[1169]: info: Ignoring MPD Status Update Mar 24 19:25:15 volumio volumio[1169]: info: Mar 24 19:25:15 volumio volumio[1169]: ---------------------------- MPD announces state update: player Mar 24 19:25:15 volumio volumio[1169]: info: ControllerMpd::getState Mar 24 19:25:15 volumio volumio[1169]: verbose: ControllerMpd::sendMpdCommand status Mar 24 19:25:15 volumio volumio[1169]: info: Mar 24 19:25:15 volumio volumio[1169]: ---------------------------- MPD announces system playlist update Mar 24 19:25:15 volumio volumio[1169]: info: Ignoring MPD Status Update Mar 24 19:25:15 volumio volumio[1169]: info: Mar 24 19:25:15 volumio volumio[1169]: ---------------------------- MPD announces state update: player Mar 24 19:25:15 volumio volumio[1169]: info: ControllerMpd::getState Mar 24 19:25:15 volumio volumio[1169]: verbose: ControllerMpd::sendMpdCommand status Mar 24 19:25:15 volumio volumio[1169]: info: Mar 24 19:25:15 volumio volumio[1169]: ---------------------------- MPD announces system playlist update Mar 24 19:25:15 volumio volumio[1169]: info: Ignoring MPD Status Update Mar 24 19:25:15 volumio volumio[1169]: info: Mar 24 19:25:15 volumio volumio[1169]: ---------------------------- MPD announces state update: player Mar 24 19:25:15 volumio volumio[1169]: info: ControllerMpd::getState Mar 24 19:25:15 volumio volumio[1169]: verbose: ControllerMpd::sendMpdCommand status Mar 24 19:25:15 volumio volumio[1169]: error: updateQueue error: null Mar 24 19:25:15 volumio volumio[1169]: error: updateQueue error: null Mar 24 19:25:15 volumio volumio[1169]: error: updateQueue error: null Mar 24 19:25:15 volumio volumio[1169]: info: ------------------------------ 7ms Mar 24 19:25:15 volumio volumio[1169]: info: sendMpdCommand status took 6 milliseconds Mar 24 19:25:15 volumio volumio[1169]: info: ------------------------------ 6ms Mar 24 19:25:15 volumio volumio[1169]: info: sendMpdCommand status took 5 milliseconds Mar 24 19:25:15 volumio volumio[1169]: info: ------------------------------ 4ms Mar 24 19:25:15 volumio volumio[1169]: info: sendMpdCommand status took 4 milliseconds Mar 24 19:25:15 volumio volumio[1169]: verbose: ControllerMpd::parseState Mar 24 19:25:15 volumio volumio[1169]: verbose: ControllerMpd::parseState Mar 24 19:25:15 volumio volumio[1169]: verbose: ControllerMpd::parseState Mar 24 19:25:15 volumio volumio[1169]: info: ControllerMpd::pushState Mar 24 19:25:15 volumio volumio[1169]: info: CoreCommandRouter::servicePushState Mar 24 19:25:15 volumio volumio[1169]: info: CoreStateMachine::pushState Mar 24 19:25:15 volumio volumio[1169]: info: CoreCommandRouter::volumioPushState Mar 24 19:25:15 volumio volumio[1169]: info: CorePlayQueue::getTrack 2 Mar 24 19:25:15 volumio volumio[1169]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 24 19:25:15 volumio volumio[1169]: verbose: CURRENT POSITION 2 Mar 24 19:25:15 volumio volumio[1169]: info: CoreStateMachine::syncState stateService stop Mar 24 19:25:15 volumio volumio[1169]: info: CoreStateMachine::syncState currentStatus pause Mar 24 19:25:15 volumio volumio[1169]: info: CoreStateMachine::pushState Mar 24 19:25:15 volumio volumio[1169]: info: CoreCommandRouter::volumioPushState Mar 24 19:25:15 volumio volumio[1169]: info: ControllerMpd::pushState Mar 24 19:25:15 volumio volumio[1169]: info: CoreCommandRouter::servicePushState Mar 24 19:25:15 volumio volumio[1169]: info: CoreStateMachine::pushState Mar 24 19:25:15 volumio volumio[1169]: info: CoreCommandRouter::volumioPushState Mar 24 19:25:15 volumio volumio[1169]: info: CorePlayQueue::getTrack 2 Mar 24 19:25:15 volumio volumio[1169]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 24 19:25:15 volumio volumio[1169]: verbose: CURRENT POSITION 2 Mar 24 19:25:15 volumio volumio[1169]: info: CoreStateMachine::syncState stateService stop Mar 24 19:25:15 volumio volumio[1169]: info: CoreStateMachine::syncState currentStatus pause Mar 24 19:25:15 volumio volumio[1169]: info: CoreStateMachine::pushState Mar 24 19:25:15 volumio volumio[1169]: info: CoreCommandRouter::volumioPushState Mar 24 19:25:15 volumio volumio[1169]: info: ControllerMpd::pushState Mar 24 19:25:15 volumio volumio[1169]: info: CoreCommandRouter::servicePushState Mar 24 19:25:15 volumio volumio[1169]: info: CoreStateMachine::pushState Mar 24 19:25:15 volumio volumio[1169]: info: CoreCommandRouter::volumioPushState Mar 24 19:25:15 volumio volumio[1169]: info: CorePlayQueue::getTrack 2 Mar 24 19:25:15 volumio volumio[1169]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 24 19:25:15 volumio volumio[1169]: verbose: CURRENT POSITION 2 Mar 24 19:25:15 volumio volumio[1169]: info: CoreStateMachine::syncState stateService stop Mar 24 19:25:15 volumio volumio[1169]: info: CoreStateMachine::syncState currentStatus pause Mar 24 19:25:15 volumio volumio[1169]: info: CoreStateMachine::pushState Mar 24 19:25:15 volumio volumio[1169]: info: CoreCommandRouter::volumioPushState Mar 24 19:25:15 volumio volumio5-onboarding[1797]: time=2026-03-24T19:25:15.506+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.107:49533,00:00:00:00:00:00%13 @ 0x23f9050" state=STATUS_PAUSED positionMs=120631 volume=100 Mar 24 19:25:15 volumio volumio5-onboarding[1797]: time=2026-03-24T19:25:15.506+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.107:49533,00:00:00:00:00:00%13 @ 0x23f9050" id="http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-149.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D604568459f88ebf9a854399c9d311e671febb528fe478807f1c718bbf17136fe%2Clid%3D217%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69d0379b%2F0%2F14618%2F8d9487fc.196343752.5.358635%2Fflac-mp4&ext=.flac" title="Sixteen Tons Of Hardware" Mar 24 19:25:15 volumio volumio5-onboarding[1797]: time=2026-03-24T19:25:15.509+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.107:49533,00:00:00:00:00:00%13 @ 0x23f9050" state=STATUS_PAUSED positionMs=120631 volume=100 Mar 24 19:25:15 volumio volumio5-onboarding[1797]: time=2026-03-24T19:25:15.510+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.107:49533,00:00:00:00:00:00%13 @ 0x23f9050" id="http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-149.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D604568459f88ebf9a854399c9d311e671febb528fe478807f1c718bbf17136fe%2Clid%3D217%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69d0379b%2F0%2F14618%2F8d9487fc.196343752.5.358635%2Fflac-mp4&ext=.flac" title="Sixteen Tons Of Hardware" Mar 24 19:25:15 volumio volumio5-onboarding[1797]: time=2026-03-24T19:25:15.512+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.107:49533,00:00:00:00:00:00%13 @ 0x23f9050" state=STATUS_PAUSED positionMs=120631 volume=100 Mar 24 19:25:15 volumio volumio5-onboarding[1797]: time=2026-03-24T19:25:15.512+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.107:49533,00:00:00:00:00:00%13 @ 0x23f9050" id="http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-149.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D604568459f88ebf9a854399c9d311e671febb528fe478807f1c718bbf17136fe%2Clid%3D217%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69d0379b%2F0%2F14618%2F8d9487fc.196343752.5.358635%2Fflac-mp4&ext=.flac" title="Sixteen Tons Of Hardware" Mar 24 19:25:15 volumio volumio5-onboarding[1797]: time=2026-03-24T19:25:15.514+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.107:49533,00:00:00:00:00:00%13 @ 0x23f9050" state=STATUS_PAUSED positionMs=120631 volume=100 Mar 24 19:25:15 volumio volumio5-onboarding[1797]: time=2026-03-24T19:25:15.515+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.107:49533,00:00:00:00:00:00%13 @ 0x23f9050" id="http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-149.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D604568459f88ebf9a854399c9d311e671febb528fe478807f1c718bbf17136fe%2Clid%3D217%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69d0379b%2F0%2F14618%2F8d9487fc.196343752.5.358635%2Fflac-mp4&ext=.flac" title="Sixteen Tons Of Hardware" Mar 24 19:25:15 volumio volumio5-onboarding[1797]: time=2026-03-24T19:25:15.517+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.107:49533,00:00:00:00:00:00%13 @ 0x23f9050" state=STATUS_PAUSED positionMs=120631 volume=100 Mar 24 19:25:15 volumio volumio5-onboarding[1797]: time=2026-03-24T19:25:15.517+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.107:49533,00:00:00:00:00:00%13 @ 0x23f9050" id="http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-149.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D604568459f88ebf9a854399c9d311e671febb528fe478807f1c718bbf17136fe%2Clid%3D217%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69d0379b%2F0%2F14618%2F8d9487fc.196343752.5.358635%2Fflac-mp4&ext=.flac" title="Sixteen Tons Of Hardware" Mar 24 19:25:15 volumio volumio5-onboarding[1797]: time=2026-03-24T19:25:15.519+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.107:49533,00:00:00:00:00:00%13 @ 0x23f9050" state=STATUS_PAUSED positionMs=120631 volume=100 Mar 24 19:25:15 volumio volumio5-onboarding[1797]: time=2026-03-24T19:25:15.520+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.107:49533,00:00:00:00:00:00%13 @ 0x23f9050" id="http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-149.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D604568459f88ebf9a854399c9d311e671febb528fe478807f1c718bbf17136fe%2Clid%3D217%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69d0379b%2F0%2F14618%2F8d9487fc.196343752.5.358635%2Fflac-mp4&ext=.flac" title="Sixteen Tons Of Hardware" Mar 24 19:25:15 volumio volumio[1169]: info: ------------------------------ 82ms Mar 24 19:25:15 volumio volumio[1169]: info: ------------------------------ 80ms Mar 24 19:25:15 volumio volumio[1169]: info: ------------------------------ 79ms Mar 24 19:25:15 volumio volumio[1169]: info: CoreCommandRouter::volumioGetQueue Mar 24 19:25:15 volumio volumio[1169]: info: CoreStateMachine::getQueue Mar 24 19:25:15 volumio volumio[1169]: info: CorePlayQueue::getQueue Mar 24 19:25:15 volumio volumio[1169]: info: CoreCommandRouter::volumioGetQueue Mar 24 19:25:15 volumio volumio[1169]: info: CoreStateMachine::getQueue Mar 24 19:25:15 volumio volumio[1169]: info: CorePlayQueue::getQueue Mar 24 19:25:15 volumio volumio[1169]: info: CoreCommandRouter::volumioGetQueue Mar 24 19:25:15 volumio volumio[1169]: info: CoreStateMachine::getQueue Mar 24 19:25:15 volumio volumio[1169]: info: CorePlayQueue::getQueue Mar 24 19:25:15 volumio volumio[1169]: info: CoreCommandRouter::volumioGetQueue Mar 24 19:25:15 volumio volumio[1169]: info: CoreStateMachine::getQueue Mar 24 19:25:15 volumio volumio[1169]: info: CorePlayQueue::getQueue Mar 24 19:25:15 volumio volumio[1169]: info: CoreCommandRouter::volumioGetQueue Mar 24 19:25:15 volumio volumio[1169]: info: CoreStateMachine::getQueue Mar 24 19:25:15 volumio volumio[1169]: info: CorePlayQueue::getQueue Mar 24 19:25:15 volumio volumio[1169]: info: CoreCommandRouter::volumioGetQueue Mar 24 19:25:15 volumio volumio[1169]: info: CoreStateMachine::getQueue Mar 24 19:25:15 volumio volumio[1169]: info: CorePlayQueue::getQueue Mar 24 19:25:16 volumio volumio[1169]: info: CoreCommandRouter::volumioNext Mar 24 19:25:16 volumio volumio[1169]: info: CoreStateMachine::next Mar 24 19:25:16 volumio volumio[1169]: info: ControllerMpd::next Mar 24 19:25:16 volumio volumio[1169]: verbose: ControllerMpd::sendMpdCommand next Mar 24 19:25:17 volumio volumio[1169]: info: CoreCommandRouter::volumioNext Mar 24 19:25:17 volumio volumio[1169]: info: CoreStateMachine::next Mar 24 19:25:17 volumio volumio[1169]: info: ControllerMpd::next Mar 24 19:25:17 volumio volumio[1169]: verbose: ControllerMpd::sendMpdCommand next Mar 24 19:25:19 volumio volumio[1169]: info: CoreCommandRouter::volumioGetState Mar 24 19:25:20 volumio volumio[1169]: info: CoreCommandRouter::volumioNext Mar 24 19:25:20 volumio volumio[1169]: info: CoreStateMachine::next Mar 24 19:25:20 volumio volumio[1169]: info: ControllerMpd::next Mar 24 19:25:20 volumio volumio[1169]: verbose: ControllerMpd::sendMpdCommand next Mar 24 19:25:23 volumio volumio[1169]: info: CoreCommandRouter::volumioGetQueue Mar 24 19:25:23 volumio volumio[1169]: info: CoreStateMachine::getQueue Mar 24 19:25:23 volumio volumio[1169]: info: CorePlayQueue::getQueue Mar 24 19:25:27 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: yandex_music , handleBrowseUri Mar 24 19:25:28 volumio volumio[1169]: info: Preload queue cleared Mar 24 19:25:28 volumio volumio[1169]: info: Preloading song: yandex_music/track/53185874:37192024@user:onyourwave Mar 24 19:25:28 volumio volumio[1169]: info: Preloading song: yandex_music/track/17231151:1898081@user:onyourwave Mar 24 19:25:28 volumio volumio[1169]: info: Preloading song: yandex_music/track/52322669:7360824@user:onyourwave Mar 24 19:25:28 volumio volumio[1169]: info: Preloading song: yandex_music/track/7113807:3765965@user:onyourwave Mar 24 19:25:28 volumio volumio[1169]: info: Preloading song: yandex_music/track/123164968:29962442@user:onyourwave Mar 24 19:25:28 volumio volumio[1169]: info: Exploding uri yandex_music/track/53185874:37192024@user:onyourwave in service yandex_music Mar 24 19:25:28 volumio volumio[1169]: info: Exploding uri yandex_music/track/17231151:1898081@user:onyourwave in service yandex_music Mar 24 19:25:28 volumio volumio[1169]: info: Exploding uri yandex_music/track/52322669:7360824@user:onyourwave in service yandex_music Mar 24 19:25:28 volumio volumio[1169]: info: Exploding uri yandex_music/track/7113807:3765965@user:onyourwave in service yandex_music Mar 24 19:25:28 volumio volumio[1169]: info: Exploding uri yandex_music/track/123164968:29962442@user:onyourwave in service yandex_music Mar 24 19:25:29 volumio volumio[1169]: info: CoreCommandRouter::volumioGetState Mar 24 19:25:29 volumio volumio[1169]: info: Listing playlists Mar 24 19:25:29 volumio volumio[1169]: info: Listing playlists Mar 24 19:25:36 volumio volumio[1169]: info: Preload queue cleared Mar 24 19:25:36 volumio volumio[1169]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 24 19:25:36 volumio volumio[1169]: info: CoreStateMachine::ClearQueue Mar 24 19:25:36 volumio volumio[1169]: info: CoreStateMachine::stop Mar 24 19:25:36 volumio volumio[1169]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 19:25:36 volumio volumio[1169]: info: CoreStateMachine::updateTrackBlock Mar 24 19:25:36 volumio volumio[1169]: info: CorePlayQueue::getTrackBlock Mar 24 19:25:36 volumio volumio[1169]: info: CoreStateMachine::stPlaybackTimer Mar 24 19:25:36 volumio volumio[1169]: info: CoreStateMachine::pushState Mar 24 19:25:36 volumio volumio[1169]: info: CorePlayQueue::getTrack 2 Mar 24 19:25:36 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 19:25:36 volumio volumio[1169]: info: CoreCommandRouter::volumioPushState Mar 24 19:25:36 volumio volumio[1169]: info: CoreStateMachine::serviceStop Mar 24 19:25:36 volumio volumio[1169]: info: CorePlayQueue::getTrack 2 Mar 24 19:25:36 volumio volumio[1169]: info: CoreCommandRouter::serviceStop Mar 24 19:25:36 volumio volumio[1169]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 24 19:25:36 volumio volumio[1169]: info: ControllerMpd::stop Mar 24 19:25:36 volumio volumio[1169]: verbose: ControllerMpd::sendMpdCommand stop Mar 24 19:25:36 volumio volumio[1169]: info: CorePlayQueue::clearPlayQueue Mar 24 19:25:36 volumio volumio[1169]: info: CorePlayQueue::saveQueue Mar 24 19:25:36 volumio volumio[1169]: info: CoreCommandRouter::volumioPushQueue Mar 24 19:25:36 volumio volumio[1169]: info: CoreStateMachine::addQueueItems Mar 24 19:25:36 volumio volumio[1169]: info: CorePlayQueue::addQueueItems Mar 24 19:25:36 volumio volumio[1169]: info: Preload queue cleared Mar 24 19:25:36 volumio volumio[1169]: info: Adding Item to queue: yandex_music/track/53185874:37192024@user:onyourwave Mar 24 19:25:36 volumio volumio[1169]: info: Using cached record of: yandex_music/track/53185874:37192024@user:onyourwave Mar 24 19:25:36 volumio volumio[1169]: info: Adding Item to queue: yandex_music/track/17231151:1898081@user:onyourwave Mar 24 19:25:36 volumio volumio[1169]: info: Using cached record of: yandex_music/track/17231151:1898081@user:onyourwave Mar 24 19:25:36 volumio volumio[1169]: info: Adding Item to queue: yandex_music/track/52322669:7360824@user:onyourwave Mar 24 19:25:36 volumio volumio[1169]: info: Using cached record of: yandex_music/track/52322669:7360824@user:onyourwave Mar 24 19:25:36 volumio volumio5-onboarding[1797]: time=2026-03-24T19:25:36.402+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.107:49533,00:00:00:00:00:00%13 @ 0x23f9050" state=STATUS_STOPPED positionMs=0 volume=100 Mar 24 19:25:36 volumio volumio5-onboarding[1797]: time=2026-03-24T19:25:36.403+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.107:49533,00:00:00:00:00:00%13 @ 0x23f9050" id=yandex_music/track/358635:13620942 title="Sixteen Tons Of Hardware" Mar 24 19:25:36 volumio volumio[1169]: info: CoreCommandRouter::volumioPushQueue Mar 24 19:25:36 volumio volumio[1169]: info: CorePlayQueue::saveQueue Mar 24 19:25:36 volumio volumio[1169]: info: CoreStateMachine::updateTrackBlock Mar 24 19:25:36 volumio volumio[1169]: info: CorePlayQueue::getTrackBlock Mar 24 19:25:36 volumio volumio[1169]: info: CoreCommandRouter::volumioPlay Mar 24 19:25:36 volumio volumio[1169]: info: CoreStateMachine::play index 2 Mar 24 19:25:36 volumio volumio[1169]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 19:25:36 volumio volumio[1169]: info: CoreStateMachine::addQueueItems Mar 24 19:25:36 volumio volumio[1169]: info: CorePlayQueue::addQueueItems Mar 24 19:25:36 volumio volumio[1169]: info: Preload queue cleared Mar 24 19:25:36 volumio volumio[1169]: info: Adding Item to queue: yandex_music/track/7113807:3765965@user:onyourwave Mar 24 19:25:36 volumio volumio[1169]: info: Using cached record of: yandex_music/track/7113807:3765965@user:onyourwave Mar 24 19:25:36 volumio volumio[1169]: info: Adding Item to queue: yandex_music/track/123164968:29962442@user:onyourwave Mar 24 19:25:36 volumio volumio[1169]: info: Using cached record of: yandex_music/track/123164968:29962442@user:onyourwave Mar 24 19:25:36 volumio volumio[1169]: info: CoreStateMachine::stop Mar 24 19:25:36 volumio volumio[1169]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 19:25:36 volumio volumio[1169]: info: CoreCommandRouter::volumioPushQueue Mar 24 19:25:36 volumio volumio[1169]: info: CorePlayQueue::saveQueue Mar 24 19:25:36 volumio volumio[1169]: info: CoreStateMachine::play index undefined Mar 24 19:25:36 volumio volumio[1169]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 19:25:36 volumio volumio[1169]: info: CoreStateMachine::updateTrackBlock Mar 24 19:25:36 volumio volumio[1169]: info: CorePlayQueue::getTrackBlock Mar 24 19:25:36 volumio volumio[1169]: info: CorePlayQueue::getTrack 2 Mar 24 19:25:36 volumio volumio[1169]: info: CoreStateMachine::startPlaybackTimer Mar 24 19:25:36 volumio volumio[1169]: info: CorePlayQueue::getTrack 2 Mar 24 19:25:36 volumio volumio[1169]: verbose: ControllerMpd::sendMpdCommand stop Mar 24 19:25:36 volumio volumio[1169]: info: sendMpdCommand stop took 65 milliseconds Mar 24 19:25:36 volumio volumio[1169]: info: sendMpdCommand stop took 11 milliseconds Mar 24 19:25:36 volumio volumio[1169]: verbose: ControllerMpd::sendMpdCommand clear Mar 24 19:25:36 volumio volumio[1169]: info: sendMpdCommand clear took 1 milliseconds Mar 24 19:25:36 volumio volumio[1169]: info: Mar 24 19:25:36 volumio volumio[1169]: ---------------------------- MPD announces system playlist update Mar 24 19:25:36 volumio volumio[1169]: info: Ignoring MPD Status Update Mar 24 19:25:36 volumio volumio[1169]: info: Mar 24 19:25:36 volumio volumio[1169]: ---------------------------- MPD announces system playlist update Mar 24 19:25:36 volumio volumio[1169]: info: Ignoring MPD Status Update Mar 24 19:25:36 volumio volumio[1169]: info: Mar 24 19:25:36 volumio volumio[1169]: ---------------------------- MPD announces system playlist update Mar 24 19:25:36 volumio volumio[1169]: info: Ignoring MPD Status Update Mar 24 19:25:36 volumio volumio[1169]: error: updateQueue error: null Mar 24 19:25:36 volumio volumio[1169]: error: updateQueue error: null Mar 24 19:25:36 volumio volumio[1169]: info: ------------------------------ 4ms Mar 24 19:25:36 volumio volumio[1169]: info: ------------------------------ 3ms Mar 24 19:25:36 volumio volumio[1169]: error: updateQueue error: null Mar 24 19:25:36 volumio volumio[1169]: info: ------------------------------ 3ms Mar 24 19:25:36 volumio volumio[1169]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-206.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D0dda847921976ef28c1afbdd666a4746dd70f14ff0d4928916e59b935b55213e%2Clid%3D177%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69d0ad7d%2F0%2F104981%2F6f8c8c16.195721335.16.52322669%2Fflac-mp4&ext=.flac" Mar 24 19:25:36 volumio volumio[1169]: info: Mar 24 19:25:36 volumio volumio[1169]: ---------------------------- MPD announces system playlist update Mar 24 19:25:36 volumio volumio[1169]: info: Ignoring MPD Status Update Mar 24 19:25:36 volumio volumio[1169]: info: sendMpdCommand addid "http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-206.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D0dda847921976ef28c1afbdd666a4746dd70f14ff0d4928916e59b935b55213e%2Clid%3D177%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69d0ad7d%2F0%2F104981%2F6f8c8c16.195721335.16.52322669%2Fflac-mp4&ext=.flac" took 2 milliseconds Mar 24 19:25:36 volumio volumio[1169]: verbose: MPD COMMAND [object Object] Mar 24 19:25:36 volumio volumio[1169]: verbose: MPD COMMAND [object Object] Mar 24 19:25:36 volumio volumio[1169]: verbose: MPD COMMAND [object Object] Mar 24 19:25:36 volumio volumio[1169]: info: Mar 24 19:25:36 volumio volumio[1169]: ---------------------------- MPD announces system playlist update Mar 24 19:25:36 volumio volumio[1169]: info: Ignoring MPD Status Update Mar 24 19:25:36 volumio volumio[1169]: info: Mar 24 19:25:36 volumio volumio[1169]: ---------------------------- MPD announces system playlist update Mar 24 19:25:36 volumio volumio[1169]: info: Ignoring MPD Status Update Mar 24 19:25:36 volumio volumio[1169]: info: Mar 24 19:25:36 volumio volumio[1169]: ---------------------------- MPD announces system playlist update Mar 24 19:25:36 volumio volumio[1169]: info: Ignoring MPD Status Update Mar 24 19:25:36 volumio volumio[1169]: info: ------------------------------ 6ms Mar 24 19:25:36 volumio volumio[1169]: info: ------------------------------ 3ms Mar 24 19:25:36 volumio volumio[1169]: info: ------------------------------ 2ms Mar 24 19:25:36 volumio volumio[1169]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 24 19:25:36 volumio volumio[1169]: verbose: ControllerMpd::sendMpdCommand play Mar 24 19:25:36 volumio volumio[1169]: info: Mar 24 19:25:36 volumio volumio[1169]: ---------------------------- MPD announces system playlist update Mar 24 19:25:36 volumio volumio[1169]: info: Ignoring MPD Status Update Mar 24 19:25:36 volumio volumio[1169]: info: Mar 24 19:25:36 volumio volumio[1169]: ---------------------------- MPD announces system playlist update Mar 24 19:25:36 volumio volumio[1169]: info: Ignoring MPD Status Update Mar 24 19:25:36 volumio volumio[1169]: info: ------------------------------ 6ms Mar 24 19:25:36 volumio volumio[1169]: info: sendMpdCommand play took 4 milliseconds Mar 24 19:25:36 volumio volumio[1169]: info: ------------------------------ 3ms Mar 24 19:25:36 volumio volumio[1169]: info: ------------------------------ 2ms Mar 24 19:25:37 volumio volumio[1169]: info: Mar 24 19:25:37 volumio volumio[1169]: ---------------------------- MPD announces state update: player Mar 24 19:25:37 volumio volumio[1169]: info: ControllerMpd::getState Mar 24 19:25:37 volumio volumio[1169]: verbose: ControllerMpd::sendMpdCommand status Mar 24 19:25:37 volumio volumio[1169]: info: Mar 24 19:25:37 volumio volumio[1169]: ---------------------------- MPD announces state update: player Mar 24 19:25:37 volumio volumio[1169]: info: ControllerMpd::getState Mar 24 19:25:37 volumio volumio[1169]: verbose: ControllerMpd::sendMpdCommand status Mar 24 19:25:37 volumio volumio[1169]: info: Mar 24 19:25:37 volumio volumio[1169]: ---------------------------- MPD announces state update: player Mar 24 19:25:37 volumio volumio[1169]: info: Mar 24 19:25:37 volumio volumio[1169]: ---------------------------- MPD announces state update: player Mar 24 19:25:37 volumio volumio[1169]: info: ControllerMpd::getState Mar 24 19:25:37 volumio volumio[1169]: verbose: ControllerMpd::sendMpdCommand status Mar 24 19:25:37 volumio volumio[1169]: info: sendMpdCommand status took 3 milliseconds Mar 24 19:25:37 volumio volumio[1169]: info: ControllerMpd::getState Mar 24 19:25:37 volumio volumio[1169]: verbose: ControllerMpd::sendMpdCommand status Mar 24 19:25:37 volumio volumio[1169]: verbose: ControllerMpd::parseState Mar 24 19:25:37 volumio kernel: bcm2835-i2s 3f203000.i2s: I2S SYNC error! Mar 24 19:25:37 volumio volumio[1169]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 19:25:37 volumio volumio[1169]: info: Mar 24 19:25:37 volumio volumio[1169]: ---------------------------- MPD announces state update: player Mar 24 19:25:37 volumio volumio[1169]: info: ControllerMpd::getState Mar 24 19:25:37 volumio volumio[1169]: verbose: ControllerMpd::sendMpdCommand status Mar 24 19:25:37 volumio volumio[1169]: info: Mar 24 19:25:37 volumio volumio[1169]: ---------------------------- MPD announces state update: player Mar 24 19:25:37 volumio volumio[1169]: info: ControllerMpd::getState Mar 24 19:25:37 volumio volumio[1169]: verbose: ControllerMpd::sendMpdCommand status Mar 24 19:25:37 volumio volumio[1169]: info: sendMpdCommand status took 7 milliseconds Mar 24 19:25:37 volumio volumio[1169]: info: sendMpdCommand status took 6 milliseconds Mar 24 19:25:37 volumio volumio[1169]: info: sendMpdCommand status took 6 milliseconds Mar 24 19:25:37 volumio volumio[1169]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 24 19:25:37 volumio volumio[1169]: info: sendMpdCommand status took 4 milliseconds Mar 24 19:25:37 volumio volumio[1169]: verbose: ControllerMpd::parseState Mar 24 19:25:37 volumio volumio[1169]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 19:25:37 volumio volumio[1169]: verbose: ControllerMpd::parseState Mar 24 19:25:37 volumio volumio[1169]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 19:25:37 volumio volumio[1169]: verbose: ControllerMpd::parseState Mar 24 19:25:37 volumio volumio[1169]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 19:25:37 volumio volumio[1169]: verbose: ControllerMpd::parseTrackInfo Mar 24 19:25:37 volumio volumio[1169]: verbose: ControllerMpd::parseState Mar 24 19:25:37 volumio volumio[1169]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 19:25:37 volumio volumio[1169]: info: ControllerMpd::pushState Mar 24 19:25:37 volumio volumio[1169]: info: CoreCommandRouter::servicePushState Mar 24 19:25:37 volumio volumio[1169]: info: CorePlayQueue::getTrack 2 Mar 24 19:25:37 volumio volumio[1169]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":203,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Can't Help Me Now","artist":"Rob Thomas","album":"Chip Tooth Smile","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-206.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D0dda847921976ef28c1afbdd666a4746dd70f14ff0d4928916e59b935b55213e%2Clid%3D177%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69d0ad7d%2F0%2F104981%2F6f8c8c16.195721335.16.52322669%2Fflac-mp4&ext=.flac","trackType":"flac"} Mar 24 19:25:37 volumio volumio[1169]: verbose: CURRENT POSITION 2 Mar 24 19:25:37 volumio volumio[1169]: info: CoreStateMachine::syncState stateService play Mar 24 19:25:37 volumio volumio[1169]: info: CoreStateMachine::syncState currentStatus stop Mar 24 19:25:37 volumio volumio[1169]: info: ------------------------------ 15ms Mar 24 19:25:37 volumio volumio[1169]: info: sendMpdCommand status took 8 milliseconds Mar 24 19:25:37 volumio volumio[1169]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 24 19:25:37 volumio volumio[1169]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 24 19:25:37 volumio volumio[1169]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 24 19:25:37 volumio volumio[1169]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 24 19:25:37 volumio volumio[1169]: verbose: ControllerMpd::parseState Mar 24 19:25:37 volumio volumio[1169]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 19:25:37 volumio volumio[1169]: verbose: ControllerMpd::parseTrackInfo Mar 24 19:25:37 volumio volumio[1169]: verbose: ControllerMpd::parseTrackInfo Mar 24 19:25:37 volumio volumio[1169]: verbose: ControllerMpd::parseTrackInfo Mar 24 19:25:37 volumio volumio[1169]: verbose: ControllerMpd::parseTrackInfo Mar 24 19:25:37 volumio volumio[1169]: info: ControllerMpd::pushState Mar 24 19:25:37 volumio volumio[1169]: info: CoreCommandRouter::servicePushState Mar 24 19:25:37 volumio volumio[1169]: info: CorePlayQueue::getTrack 2 Mar 24 19:25:37 volumio volumio[1169]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":203,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Can't Help Me Now","artist":"Rob Thomas","album":"Chip Tooth Smile","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-206.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D0dda847921976ef28c1afbdd666a4746dd70f14ff0d4928916e59b935b55213e%2Clid%3D177%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69d0ad7d%2F0%2F104981%2F6f8c8c16.195721335.16.52322669%2Fflac-mp4&ext=.flac","trackType":"flac"} Mar 24 19:25:37 volumio volumio[1169]: verbose: CURRENT POSITION 2 Mar 24 19:25:37 volumio volumio[1169]: info: CoreStateMachine::syncState stateService play Mar 24 19:25:37 volumio volumio[1169]: info: CoreStateMachine::syncState currentStatus play Mar 24 19:25:37 volumio volumio[1169]: info: Received an update from plugin. extracting info from payload Mar 24 19:25:37 volumio volumio[1169]: info: CoreStateMachine::pushState Mar 24 19:25:37 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 19:25:37 volumio volumio[1169]: info: CoreCommandRouter::volumioPushState Mar 24 19:25:37 volumio volumio[1169]: info: CoreStateMachine::pushState Mar 24 19:25:37 volumio volumio[1169]: info: CoreCommandRouter::volumioPushState Mar 24 19:25:37 volumio volumio[1169]: info: ControllerMpd::pushState Mar 24 19:25:37 volumio volumio[1169]: info: CoreCommandRouter::servicePushState Mar 24 19:25:37 volumio volumio[1169]: info: CorePlayQueue::getTrack 2 Mar 24 19:25:37 volumio volumio[1169]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":203,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Can't Help Me Now","artist":"Rob Thomas","album":"Chip Tooth Smile","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-206.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D0dda847921976ef28c1afbdd666a4746dd70f14ff0d4928916e59b935b55213e%2Clid%3D177%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69d0ad7d%2F0%2F104981%2F6f8c8c16.195721335.16.52322669%2Fflac-mp4&ext=.flac","trackType":"flac"} Mar 24 19:25:37 volumio volumio[1169]: verbose: CURRENT POSITION 2 Mar 24 19:25:37 volumio volumio[1169]: info: CoreStateMachine::syncState stateService play Mar 24 19:25:37 volumio volumio[1169]: info: CoreStateMachine::syncState currentStatus play Mar 24 19:25:37 volumio volumio[1169]: info: Received an update from plugin. extracting info from payload Mar 24 19:25:37 volumio volumio[1169]: info: CoreStateMachine::pushState Mar 24 19:25:37 volumio volumio[1169]: info: CoreCommandRouter::volumioPushState Mar 24 19:25:37 volumio volumio[1169]: info: CoreStateMachine::pushState Mar 24 19:25:37 volumio volumio[1169]: info: CoreCommandRouter::volumioPushState Mar 24 19:25:37 volumio volumio[1169]: info: ControllerMpd::pushState Mar 24 19:25:37 volumio volumio[1169]: info: CoreCommandRouter::servicePushState Mar 24 19:25:37 volumio volumio[1169]: info: CorePlayQueue::getTrack 2 Mar 24 19:25:37 volumio volumio[1169]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":203,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Can't Help Me Now","artist":"Rob Thomas","album":"Chip Tooth Smile","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-206.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D0dda847921976ef28c1afbdd666a4746dd70f14ff0d4928916e59b935b55213e%2Clid%3D177%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69d0ad7d%2F0%2F104981%2F6f8c8c16.195721335.16.52322669%2Fflac-mp4&ext=.flac","trackType":"flac"} Mar 24 19:25:37 volumio volumio[1169]: verbose: CURRENT POSITION 2 Mar 24 19:25:37 volumio volumio[1169]: info: CoreStateMachine::syncState stateService play Mar 24 19:25:37 volumio volumio[1169]: info: CoreStateMachine::syncState currentStatus play Mar 24 19:25:37 volumio volumio[1169]: info: Received an update from plugin. extracting info from payload Mar 24 19:25:37 volumio volumio[1169]: info: CoreStateMachine::pushState Mar 24 19:25:37 volumio volumio[1169]: info: CoreCommandRouter::volumioPushState Mar 24 19:25:37 volumio volumio[1169]: info: CoreStateMachine::pushState Mar 24 19:25:37 volumio volumio[1169]: info: CoreCommandRouter::volumioPushState Mar 24 19:25:37 volumio volumio[1169]: info: ControllerMpd::pushState Mar 24 19:25:37 volumio volumio[1169]: info: CoreCommandRouter::servicePushState Mar 24 19:25:37 volumio volumio[1169]: info: CorePlayQueue::getTrack 2 Mar 24 19:25:37 volumio volumio[1169]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":203,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Can't Help Me Now","artist":"Rob Thomas","album":"Chip Tooth Smile","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-206.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D0dda847921976ef28c1afbdd666a4746dd70f14ff0d4928916e59b935b55213e%2Clid%3D177%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69d0ad7d%2F0%2F104981%2F6f8c8c16.195721335.16.52322669%2Fflac-mp4&ext=.flac","trackType":"flac"} Mar 24 19:25:37 volumio volumio[1169]: verbose: CURRENT POSITION 2 Mar 24 19:25:37 volumio volumio[1169]: info: CoreStateMachine::syncState stateService play Mar 24 19:25:37 volumio volumio[1169]: info: CoreStateMachine::syncState currentStatus play Mar 24 19:25:37 volumio volumio[1169]: info: Received an update from plugin. extracting info from payload Mar 24 19:25:37 volumio volumio[1169]: info: CoreStateMachine::pushState Mar 24 19:25:37 volumio volumio[1169]: info: CoreCommandRouter::volumioPushState Mar 24 19:25:37 volumio volumio[1169]: info: CoreStateMachine::pushState Mar 24 19:25:37 volumio volumio[1169]: info: CoreCommandRouter::volumioPushState Mar 24 19:25:37 volumio volumio5-onboarding[1797]: time=2026-03-24T19:25:37.491+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.107:49533,00:00:00:00:00:00%13 @ 0x23f9050" state=STATUS_PLAYING positionMs=0 volume=100 Mar 24 19:25:37 volumio volumio5-onboarding[1797]: time=2026-03-24T19:25:37.492+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.107:49533,00:00:00:00:00:00%13 @ 0x23f9050" id="http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-206.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D0dda847921976ef28c1afbdd666a4746dd70f14ff0d4928916e59b935b55213e%2Clid%3D177%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69d0ad7d%2F0%2F104981%2F6f8c8c16.195721335.16.52322669%2Fflac-mp4&ext=.flac" title="Can't Help Me Now" Mar 24 19:25:37 volumio volumio5-onboarding[1797]: time=2026-03-24T19:25:37.494+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.107:49533,00:00:00:00:00:00%13 @ 0x23f9050" state=STATUS_PLAYING positionMs=0 volume=100 Mar 24 19:25:37 volumio volumio5-onboarding[1797]: time=2026-03-24T19:25:37.495+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.107:49533,00:00:00:00:00:00%13 @ 0x23f9050" id="http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-206.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D0dda847921976ef28c1afbdd666a4746dd70f14ff0d4928916e59b935b55213e%2Clid%3D177%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69d0ad7d%2F0%2F104981%2F6f8c8c16.195721335.16.52322669%2Fflac-mp4&ext=.flac" title="Can't Help Me Now" Mar 24 19:25:37 volumio volumio5-onboarding[1797]: time=2026-03-24T19:25:37.496+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.107:49533,00:00:00:00:00:00%13 @ 0x23f9050" state=STATUS_PLAYING positionMs=0 volume=100 Mar 24 19:25:37 volumio volumio5-onboarding[1797]: time=2026-03-24T19:25:37.497+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.107:49533,00:00:00:00:00:00%13 @ 0x23f9050" id="http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-206.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D0dda847921976ef28c1afbdd666a4746dd70f14ff0d4928916e59b935b55213e%2Clid%3D177%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69d0ad7d%2F0%2F104981%2F6f8c8c16.195721335.16.52322669%2Fflac-mp4&ext=.flac" title="Can't Help Me Now" Mar 24 19:25:37 volumio volumio5-onboarding[1797]: time=2026-03-24T19:25:37.499+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.107:49533,00:00:00:00:00:00%13 @ 0x23f9050" state=STATUS_PLAYING positionMs=0 volume=100 Mar 24 19:25:37 volumio volumio5-onboarding[1797]: time=2026-03-24T19:25:37.499+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.107:49533,00:00:00:00:00:00%13 @ 0x23f9050" id="http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-206.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D0dda847921976ef28c1afbdd666a4746dd70f14ff0d4928916e59b935b55213e%2Clid%3D177%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69d0ad7d%2F0%2F104981%2F6f8c8c16.195721335.16.52322669%2Fflac-mp4&ext=.flac" title="Can't Help Me Now" Mar 24 19:25:37 volumio volumio5-onboarding[1797]: time=2026-03-24T19:25:37.501+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.107:49533,00:00:00:00:00:00%13 @ 0x23f9050" state=STATUS_PLAYING positionMs=0 volume=100 Mar 24 19:25:37 volumio volumio5-onboarding[1797]: time=2026-03-24T19:25:37.502+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.107:49533,00:00:00:00:00:00%13 @ 0x23f9050" id="http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-206.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D0dda847921976ef28c1afbdd666a4746dd70f14ff0d4928916e59b935b55213e%2Clid%3D177%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69d0ad7d%2F0%2F104981%2F6f8c8c16.195721335.16.52322669%2Fflac-mp4&ext=.flac" title="Can't Help Me Now" Mar 24 19:25:37 volumio volumio5-onboarding[1797]: time=2026-03-24T19:25:37.503+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.107:49533,00:00:00:00:00:00%13 @ 0x23f9050" state=STATUS_PLAYING positionMs=0 volume=100 Mar 24 19:25:37 volumio volumio5-onboarding[1797]: time=2026-03-24T19:25:37.504+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.107:49533,00:00:00:00:00:00%13 @ 0x23f9050" id="http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-206.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D0dda847921976ef28c1afbdd666a4746dd70f14ff0d4928916e59b935b55213e%2Clid%3D177%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69d0ad7d%2F0%2F104981%2F6f8c8c16.195721335.16.52322669%2Fflac-mp4&ext=.flac" title="Can't Help Me Now" Mar 24 19:25:37 volumio volumio5-onboarding[1797]: time=2026-03-24T19:25:37.506+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.107:49533,00:00:00:00:00:00%13 @ 0x23f9050" state=STATUS_PLAYING positionMs=0 volume=100 Mar 24 19:25:37 volumio volumio5-onboarding[1797]: time=2026-03-24T19:25:37.506+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.107:49533,00:00:00:00:00:00%13 @ 0x23f9050" id="http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-206.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D0dda847921976ef28c1afbdd666a4746dd70f14ff0d4928916e59b935b55213e%2Clid%3D177%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69d0ad7d%2F0%2F104981%2F6f8c8c16.195721335.16.52322669%2Fflac-mp4&ext=.flac" title="Can't Help Me Now" Mar 24 19:25:37 volumio volumio5-onboarding[1797]: time=2026-03-24T19:25:37.508+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.107:49533,00:00:00:00:00:00%13 @ 0x23f9050" state=STATUS_PLAYING positionMs=0 volume=100 Mar 24 19:25:37 volumio volumio5-onboarding[1797]: time=2026-03-24T19:25:37.509+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.107:49533,00:00:00:00:00:00%13 @ 0x23f9050" id="http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-206.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D0dda847921976ef28c1afbdd666a4746dd70f14ff0d4928916e59b935b55213e%2Clid%3D177%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69d0ad7d%2F0%2F104981%2F6f8c8c16.195721335.16.52322669%2Fflac-mp4&ext=.flac" title="Can't Help Me Now" Mar 24 19:25:37 volumio volumio[1169]: info: ------------------------------ 109ms Mar 24 19:25:37 volumio volumio[1169]: info: ------------------------------ 108ms Mar 24 19:25:37 volumio volumio[1169]: info: ------------------------------ 109ms Mar 24 19:25:37 volumio volumio[1169]: info: ------------------------------ 107ms Mar 24 19:25:37 volumio volumio[1169]: info: sendMpdCommand playlistinfo took 99 milliseconds Mar 24 19:25:37 volumio volumio[1169]: verbose: ControllerMpd::parseTrackInfo Mar 24 19:25:37 volumio volumio[1169]: info: ControllerMpd::pushState Mar 24 19:25:37 volumio volumio[1169]: info: CoreCommandRouter::servicePushState Mar 24 19:25:37 volumio volumio[1169]: info: CorePlayQueue::getTrack 2 Mar 24 19:25:37 volumio volumio[1169]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":203,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Can't Help Me Now","artist":"Rob Thomas","album":"Chip Tooth Smile","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-206.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D0dda847921976ef28c1afbdd666a4746dd70f14ff0d4928916e59b935b55213e%2Clid%3D177%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69d0ad7d%2F0%2F104981%2F6f8c8c16.195721335.16.52322669%2Fflac-mp4&ext=.flac","trackType":"flac"} Mar 24 19:25:37 volumio volumio[1169]: verbose: CURRENT POSITION 2 Mar 24 19:25:37 volumio volumio[1169]: info: CoreStateMachine::syncState stateService play Mar 24 19:25:37 volumio volumio[1169]: info: CoreStateMachine::syncState currentStatus play Mar 24 19:25:37 volumio volumio[1169]: info: Received an update from plugin. extracting info from payload Mar 24 19:25:37 volumio volumio[1169]: info: CoreStateMachine::pushState Mar 24 19:25:37 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 19:25:37 volumio volumio[1169]: info: CoreCommandRouter::volumioPushState Mar 24 19:25:37 volumio volumio[1169]: info: CoreStateMachine::pushState Mar 24 19:25:37 volumio volumio[1169]: info: CoreCommandRouter::volumioPushState Mar 24 19:25:37 volumio volumio5-onboarding[1797]: time=2026-03-24T19:25:37.554+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.107:49533,00:00:00:00:00:00%13 @ 0x23f9050" state=STATUS_PLAYING positionMs=344 volume=100 Mar 24 19:25:37 volumio volumio5-onboarding[1797]: time=2026-03-24T19:25:37.554+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.107:49533,00:00:00:00:00:00%13 @ 0x23f9050" id="http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-206.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D0dda847921976ef28c1afbdd666a4746dd70f14ff0d4928916e59b935b55213e%2Clid%3D177%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69d0ad7d%2F0%2F104981%2F6f8c8c16.195721335.16.52322669%2Fflac-mp4&ext=.flac" title="Can't Help Me Now" Mar 24 19:25:37 volumio volumio5-onboarding[1797]: time=2026-03-24T19:25:37.558+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.107:49533,00:00:00:00:00:00%13 @ 0x23f9050" state=STATUS_PLAYING positionMs=344 volume=100 Mar 24 19:25:37 volumio volumio5-onboarding[1797]: time=2026-03-24T19:25:37.558+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.107:49533,00:00:00:00:00:00%13 @ 0x23f9050" id="http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-206.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D0dda847921976ef28c1afbdd666a4746dd70f14ff0d4928916e59b935b55213e%2Clid%3D177%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69d0ad7d%2F0%2F104981%2F6f8c8c16.195721335.16.52322669%2Fflac-mp4&ext=.flac" title="Can't Help Me Now" Mar 24 19:25:37 volumio volumio[1169]: info: ------------------------------ 134ms Mar 24 19:25:37 volumio volumio[1169]: info: CoreCommandRouter::volumioGetQueue Mar 24 19:25:37 volumio volumio[1169]: info: CoreStateMachine::getQueue Mar 24 19:25:37 volumio volumio[1169]: info: CorePlayQueue::getQueue Mar 24 19:25:37 volumio volumio[1169]: info: CoreCommandRouter::volumioGetQueue Mar 24 19:25:37 volumio volumio[1169]: info: CoreStateMachine::getQueue Mar 24 19:25:37 volumio volumio[1169]: info: CorePlayQueue::getQueue Mar 24 19:25:37 volumio volumio[1169]: info: CoreCommandRouter::volumioGetQueue Mar 24 19:25:37 volumio volumio[1169]: info: CoreStateMachine::getQueue Mar 24 19:25:37 volumio volumio[1169]: info: CorePlayQueue::getQueue Mar 24 19:25:37 volumio volumio[1169]: info: CoreCommandRouter::volumioGetQueue Mar 24 19:25:37 volumio volumio[1169]: info: CoreStateMachine::getQueue Mar 24 19:25:37 volumio volumio[1169]: info: CorePlayQueue::getQueue Mar 24 19:25:37 volumio volumio[1169]: info: CoreCommandRouter::volumioGetQueue Mar 24 19:25:37 volumio volumio[1169]: info: CoreStateMachine::getQueue Mar 24 19:25:37 volumio volumio[1169]: info: CorePlayQueue::getQueue Mar 24 19:25:37 volumio volumio[1169]: info: CoreCommandRouter::volumioGetQueue Mar 24 19:25:37 volumio volumio[1169]: info: CoreStateMachine::getQueue Mar 24 19:25:37 volumio volumio[1169]: info: CorePlayQueue::getQueue Mar 24 19:25:37 volumio volumio[1169]: info: CoreCommandRouter::volumioGetQueue Mar 24 19:25:37 volumio volumio[1169]: info: CoreStateMachine::getQueue Mar 24 19:25:37 volumio volumio[1169]: info: CorePlayQueue::getQueue Mar 24 19:25:37 volumio volumio[1169]: info: CoreCommandRouter::volumioGetQueue Mar 24 19:25:37 volumio volumio[1169]: info: CoreStateMachine::getQueue Mar 24 19:25:37 volumio volumio[1169]: info: CorePlayQueue::getQueue Mar 24 19:25:37 volumio volumio[1169]: info: CoreCommandRouter::volumioGetQueue Mar 24 19:25:37 volumio volumio[1169]: info: CoreStateMachine::getQueue Mar 24 19:25:37 volumio volumio[1169]: info: CorePlayQueue::getQueue Mar 24 19:25:37 volumio volumio[1169]: info: CoreCommandRouter::volumioGetQueue Mar 24 19:25:37 volumio volumio[1169]: info: CoreStateMachine::getQueue Mar 24 19:25:37 volumio volumio[1169]: info: CorePlayQueue::getQueue Mar 24 19:25:40 volumio volumio[1169]: info: CoreCommandRouter::volumioGetState Mar 24 19:25:57 volumio volumio[1169]: info: CoreCommandRouter::volumioGetState Mar 24 19:25:59 volumio volumio[1169]: info: Listing playlists Mar 24 19:25:59 volumio volumio[1169]: info: Listing playlists Mar 24 19:26:01 volumio volumio[1169]: info: CoreCommandRouter::volumioGetState Mar 24 19:26:10 volumio volumio[1169]: info: CoreCommandRouter::volumioGetState Mar 24 19:26:11 volumio volumio[1169]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 24 19:26:11 volumio volumio[1169]: Error: read ECONNRESET Mar 24 19:26:11 volumio volumio[1169]: at TLSWrap.onStreamRead (node:internal/stream_base_commons:217:20) { Mar 24 19:26:11 volumio volumio[1169]: errno: -104, Mar 24 19:26:11 volumio volumio[1169]: code: 'ECONNRESET', Mar 24 19:26:11 volumio volumio[1169]: syscall: 'read' Mar 24 19:26:11 volumio volumio[1169]: } Mar 24 19:26:11 volumio volumio[1169]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 24 19:26:12 volumio sudo[4943]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-24 19:25' Mar 24 19:26:12 volumio sudo[4943]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="dc61260dec5515dafd2b634881860b4c46c919ff" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Mar 6 16:46:58 UTC 2026" VOLUMIO_VERSION="4.103" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="aa741395b8bfc648ff5d04e312f53d2c"