-- Logs begin at Sat 2026-10-03 17:20:59 +11, end at Sat 2026-10-03 17:52:27 +11. -- Oct 03 17:51:31 localhost volumio[1313]: info: CoreCommandRouter::volumioNext Oct 03 17:51:31 localhost volumio[1313]: info: CoreStateMachine::next Oct 03 17:51:31 localhost volumio[1313]: info: ControllerMpd::next Oct 03 17:51:31 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand next Oct 03 17:51:31 localhost volumio[1313]: info: sendMpdCommand next took 2 milliseconds Oct 03 17:51:31 localhost volumio[1313]: info: Oct 03 17:51:31 localhost volumio[1313]: ---------------------------- MPD announces system playlist update Oct 03 17:51:31 localhost volumio[1313]: info: Ignoring MPD Status Update Oct 03 17:51:31 localhost volumio[1313]: info: Oct 03 17:51:31 localhost volumio[1313]: ---------------------------- MPD announces state update: player Oct 03 17:51:31 localhost volumio[1313]: info: ControllerMpd::getState Oct 03 17:51:31 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:51:31 localhost volumio[1313]: info: Oct 03 17:51:31 localhost volumio[1313]: ---------------------------- MPD announces system playlist update Oct 03 17:51:31 localhost volumio[1313]: info: Ignoring MPD Status Update Oct 03 17:51:31 localhost volumio[1313]: info: Oct 03 17:51:31 localhost volumio[1313]: ---------------------------- MPD announces state update: player Oct 03 17:51:31 localhost volumio[1313]: info: ControllerMpd::getState Oct 03 17:51:31 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:51:31 localhost volumio[1313]: info: Oct 03 17:51:31 localhost volumio[1313]: ---------------------------- MPD announces system playlist update Oct 03 17:51:31 localhost volumio[1313]: info: Ignoring MPD Status Update Oct 03 17:51:31 localhost volumio[1313]: info: Oct 03 17:51:31 localhost volumio[1313]: ---------------------------- MPD announces state update: player Oct 03 17:51:31 localhost volumio[1313]: info: ControllerMpd::getState Oct 03 17:51:31 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:51:31 localhost volumio[1313]: error: updateQueue error: null Oct 03 17:51:31 localhost volumio[1313]: error: updateQueue error: null Oct 03 17:51:31 localhost volumio[1313]: error: updateQueue error: null Oct 03 17:51:31 localhost volumio[1313]: info: ------------------------------ 3ms Oct 03 17:51:31 localhost volumio[1313]: info: sendMpdCommand status took 3 milliseconds Oct 03 17:51:31 localhost volumio[1313]: info: ------------------------------ 2ms Oct 03 17:51:31 localhost volumio[1313]: info: sendMpdCommand status took 2 milliseconds Oct 03 17:51:31 localhost volumio[1313]: info: ------------------------------ 1ms Oct 03 17:51:31 localhost volumio[1313]: info: sendMpdCommand status took 1 milliseconds Oct 03 17:51:31 localhost volumio[1313]: verbose: ControllerMpd::parseState Oct 03 17:51:31 localhost volumio[1313]: verbose: ControllerMpd::parseState Oct 03 17:51:31 localhost volumio[1313]: verbose: ControllerMpd::parseState Oct 03 17:51:31 localhost volumio[1313]: info: ControllerMpd::pushState Oct 03 17:51:31 localhost volumio[1313]: info: CoreCommandRouter::servicePushState Oct 03 17:51:31 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:51:31 localhost volumio[1313]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 17:51:31 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:51:31 localhost volumio[1313]: info: CorePlayQueue::getTrack 8 Oct 03 17:51:31 localhost volumio[1313]: 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} Oct 03 17:51:31 localhost volumio[1313]: verbose: CURRENT POSITION 8 Oct 03 17:51:31 localhost volumio[1313]: info: CoreStateMachine::syncState stateService stop Oct 03 17:51:31 localhost volumio[1313]: info: CoreStateMachine::syncState currentStatus play Oct 03 17:51:31 localhost volumio[1313]: info: CoreStateMachine::play index undefined Oct 03 17:51:31 localhost volumio[1313]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 03 17:51:31 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:51:31 localhost volumio[1313]: info: CorePlayQueue::getTrack 9 Oct 03 17:51:31 localhost volumio[1313]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 17:51:31 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:51:31 localhost volumio[1313]: info: ControllerMpd::pushState Oct 03 17:51:31 localhost volumio[1313]: info: CoreCommandRouter::servicePushState Oct 03 17:51:31 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:51:31 localhost volumio[1313]: info: CorePlayQueue::getTrack 9 Oct 03 17:51:31 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:51:31 localhost volumio[1313]: info: CorePlayQueue::getTrack 9 Oct 03 17:51:31 localhost volumio[1313]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd Oct 03 17:51:31 localhost volumio[1313]: info: ControllerMpd::pushState Oct 03 17:51:31 localhost volumio[1313]: info: CoreCommandRouter::servicePushState Oct 03 17:51:31 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:51:31 localhost volumio[1313]: info: CorePlayQueue::getTrack 9 Oct 03 17:51:31 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:51:31 localhost volumio[1313]: info: CorePlayQueue::getTrack 9 Oct 03 17:51:31 localhost volumio[1313]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd Oct 03 17:51:31 localhost volumio[1313]: info: CorePlayQueue::getTrack 9 Oct 03 17:51:31 localhost volumio[1313]: info: CoreStateMachine::startPlaybackTimer Oct 03 17:51:31 localhost volumio[1313]: info: CorePlayQueue::getTrack 9 Oct 03 17:51:31 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand stop Oct 03 17:51:31 localhost volumio[1313]: info: ------------------------------ 10ms Oct 03 17:51:31 localhost volumio[1313]: info: ------------------------------ 9ms Oct 03 17:51:31 localhost volumio[1313]: info: ------------------------------ 8ms Oct 03 17:51:31 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:51:31 localhost volumio[1313]: info: CorePlayQueue::getTrack 9 Oct 03 17:51:31 localhost volumio[1313]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 17:51:31 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:51:31 localhost volumio[1313]: info: sendMpdCommand stop took 4 milliseconds Oct 03 17:51:31 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand clear Oct 03 17:51:31 localhost volumio[1313]: info: CoreCommandRouter::volumioGetQueue Oct 03 17:51:31 localhost volumio[1313]: info: CoreStateMachine::getQueue Oct 03 17:51:31 localhost volumio[1313]: info: CorePlayQueue::getQueue Oct 03 17:51:31 localhost volumio[1313]: info: Oct 03 17:51:31 localhost volumio[1313]: ---------------------------- MPD announces system playlist update Oct 03 17:51:31 localhost volumio[1313]: info: Ignoring MPD Status Update Oct 03 17:51:31 localhost volumio[1313]: info: sendMpdCommand clear took 4 milliseconds Oct 03 17:51:31 localhost volumio[1313]: info: Oct 03 17:51:31 localhost volumio[1313]: ---------------------------- MPD announces system playlist update Oct 03 17:51:31 localhost volumio[1313]: info: Ignoring MPD Status Update Oct 03 17:51:31 localhost volumio[1313]: info: Oct 03 17:51:31 localhost volumio[1313]: ---------------------------- MPD announces system playlist update Oct 03 17:51:31 localhost volumio[1313]: info: Ignoring MPD Status Update Oct 03 17:51:31 localhost volumio[1313]: error: updateQueue error: null Oct 03 17:51:31 localhost volumio[1313]: info: ------------------------------ 8ms Oct 03 17:51:31 localhost volumio[1313]: info: ------------------------------ 2ms Oct 03 17:51:32 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-226.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Df2dc225cb17a1a2b2a85b307457995f0353b86ac949e6ceedd61e3ca43f2d1c9%2Clid%3D225%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6ace6d63%2F27671%2F23136%2F32d97e3d.80962531.12.25545523%2F320.mp3&ext=.mp3" Oct 03 17:51:32 localhost volumio[1313]: info: Oct 03 17:51:32 localhost volumio[1313]: ---------------------------- MPD announces system playlist update Oct 03 17:51:32 localhost volumio[1313]: info: Ignoring MPD Status Update Oct 03 17:51:32 localhost volumio[1313]: info: sendMpdCommand addid "http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-226.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Df2dc225cb17a1a2b2a85b307457995f0353b86ac949e6ceedd61e3ca43f2d1c9%2Clid%3D225%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6ace6d63%2F27671%2F23136%2F32d97e3d.80962531.12.25545523%2F320.mp3&ext=.mp3" took 0 milliseconds Oct 03 17:51:32 localhost volumio[1313]: verbose: MPD COMMAND [object Object] Oct 03 17:51:32 localhost volumio[1313]: verbose: MPD COMMAND [object Object] Oct 03 17:51:32 localhost volumio[1313]: info: Oct 03 17:51:32 localhost volumio[1313]: ---------------------------- MPD announces system playlist update Oct 03 17:51:32 localhost volumio[1313]: info: Ignoring MPD Status Update Oct 03 17:51:32 localhost volumio[1313]: info: Oct 03 17:51:32 localhost volumio[1313]: ---------------------------- MPD announces system playlist update Oct 03 17:51:32 localhost volumio[1313]: info: Ignoring MPD Status Update Oct 03 17:51:32 localhost volumio[1313]: info: Oct 03 17:51:32 localhost volumio[1313]: ---------------------------- MPD announces system playlist update Oct 03 17:51:32 localhost volumio[1313]: info: Ignoring MPD Status Update Oct 03 17:51:32 localhost volumio[1313]: info: ------------------------------ 1ms Oct 03 17:51:32 localhost volumio[1313]: info: ------------------------------ 0ms Oct 03 17:51:32 localhost volumio[1313]: info: ------------------------------ 0ms Oct 03 17:51:32 localhost volumio[1313]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 03 17:51:32 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand play Oct 03 17:51:32 localhost volumio[1313]: info: Oct 03 17:51:32 localhost volumio[1313]: ---------------------------- MPD announces system playlist update Oct 03 17:51:32 localhost volumio[1313]: info: Ignoring MPD Status Update Oct 03 17:51:32 localhost volumio[1313]: info: Oct 03 17:51:32 localhost volumio[1313]: ---------------------------- MPD announces system playlist update Oct 03 17:51:32 localhost volumio[1313]: info: Ignoring MPD Status Update Oct 03 17:51:32 localhost volumio[1313]: info: ------------------------------ 3ms Oct 03 17:51:32 localhost volumio[1313]: info: sendMpdCommand play took 3 milliseconds Oct 03 17:51:32 localhost volumio[1313]: info: ------------------------------ 3ms Oct 03 17:51:32 localhost volumio[1313]: info: ------------------------------ 3ms Oct 03 17:51:37 localhost volumio[1313]: info: Oct 03 17:51:37 localhost volumio[1313]: ---------------------------- MPD announces state update: player Oct 03 17:51:37 localhost volumio[1313]: info: ControllerMpd::getState Oct 03 17:51:37 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:51:37 localhost volumio[1313]: info: Oct 03 17:51:37 localhost volumio[1313]: ---------------------------- MPD announces state update: player Oct 03 17:51:37 localhost volumio[1313]: info: ControllerMpd::getState Oct 03 17:51:37 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:51:37 localhost volumio[1313]: info: Oct 03 17:51:37 localhost volumio[1313]: ---------------------------- MPD announces state update: player Oct 03 17:51:37 localhost volumio[1313]: info: ControllerMpd::getState Oct 03 17:51:37 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:51:37 localhost volumio[1313]: info: sendMpdCommand status took 5 milliseconds Oct 03 17:51:37 localhost volumio[1313]: verbose: ControllerMpd::parseState Oct 03 17:51:37 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 17:51:37 localhost volumio[1313]: info: Oct 03 17:51:37 localhost volumio[1313]: ---------------------------- MPD announces state update: player Oct 03 17:51:37 localhost volumio[1313]: info: ControllerMpd::getState Oct 03 17:51:37 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:51:37 localhost volumio[1313]: info: sendMpdCommand status took 7 milliseconds Oct 03 17:51:37 localhost volumio[1313]: info: sendMpdCommand status took 6 milliseconds Oct 03 17:51:37 localhost volumio[1313]: verbose: ControllerMpd::parseState Oct 03 17:51:37 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 17:51:37 localhost volumio[1313]: verbose: ControllerMpd::parseState Oct 03 17:51:37 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 17:51:37 localhost volumio[1313]: info: Oct 03 17:51:37 localhost volumio[1313]: ---------------------------- MPD announces state update: player Oct 03 17:51:37 localhost volumio[1313]: info: ControllerMpd::getState Oct 03 17:51:37 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:51:37 localhost volumio[1313]: info: Oct 03 17:51:37 localhost volumio[1313]: ---------------------------- MPD announces state update: player Oct 03 17:51:37 localhost volumio[1313]: info: ControllerMpd::getState Oct 03 17:51:37 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:51:37 localhost volumio[1313]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 03 17:51:37 localhost volumio[1313]: info: sendMpdCommand status took 3 milliseconds Oct 03 17:51:37 localhost volumio[1313]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 03 17:51:37 localhost volumio[1313]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 03 17:51:37 localhost volumio[1313]: info: sendMpdCommand status took 1 milliseconds Oct 03 17:51:37 localhost volumio[1313]: verbose: ControllerMpd::parseTrackInfo Oct 03 17:51:37 localhost volumio[1313]: verbose: ControllerMpd::parseState Oct 03 17:51:37 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 17:51:37 localhost volumio[1313]: verbose: ControllerMpd::parseTrackInfo Oct 03 17:51:37 localhost volumio[1313]: verbose: ControllerMpd::parseTrackInfo Oct 03 17:51:37 localhost volumio[1313]: verbose: ControllerMpd::parseState Oct 03 17:51:37 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 17:51:37 localhost volumio[1313]: info: ControllerMpd::pushState Oct 03 17:51:37 localhost volumio[1313]: info: CoreCommandRouter::servicePushState Oct 03 17:51:37 localhost volumio[1313]: info: CorePlayQueue::getTrack 9 Oct 03 17:51:37 localhost volumio[1313]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":176,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Si No Te Tengo A Ti","artist":"Alvaro Soler","album":"Eterno Agosto","uri":"http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-226.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Df2dc225cb17a1a2b2a85b307457995f0353b86ac949e6ceedd61e3ca43f2d1c9%2Clid%3D225%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6ace6d63%2F27671%2F23136%2F32d97e3d.80962531.12.25545523%2F320.mp3&ext=.mp3","trackType":"mp3"} Oct 03 17:51:37 localhost volumio[1313]: verbose: CURRENT POSITION 9 Oct 03 17:51:37 localhost volumio[1313]: info: CoreStateMachine::syncState stateService play Oct 03 17:51:37 localhost volumio[1313]: info: CoreStateMachine::syncState currentStatus stop Oct 03 17:51:37 localhost volumio[1313]: info: ControllerMpd::pushState Oct 03 17:51:37 localhost volumio[1313]: info: CoreCommandRouter::servicePushState Oct 03 17:51:37 localhost volumio[1313]: info: CorePlayQueue::getTrack 9 Oct 03 17:51:37 localhost volumio[1313]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":176,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Si No Te Tengo A Ti","artist":"Alvaro Soler","album":"Eterno Agosto","uri":"http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-226.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Df2dc225cb17a1a2b2a85b307457995f0353b86ac949e6ceedd61e3ca43f2d1c9%2Clid%3D225%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6ace6d63%2F27671%2F23136%2F32d97e3d.80962531.12.25545523%2F320.mp3&ext=.mp3","trackType":"mp3"} Oct 03 17:51:37 localhost volumio[1313]: verbose: CURRENT POSITION 9 Oct 03 17:51:37 localhost volumio[1313]: info: CoreStateMachine::syncState stateService play Oct 03 17:51:37 localhost volumio[1313]: info: CoreStateMachine::syncState currentStatus play Oct 03 17:51:37 localhost volumio[1313]: info: Received an update from plugin. extracting info from payload Oct 03 17:51:37 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:51:37 localhost volumio[1313]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 17:51:37 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:51:37 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:51:37 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:51:37 localhost volumio[1313]: info: ControllerMpd::pushState Oct 03 17:51:37 localhost volumio[1313]: info: CoreCommandRouter::servicePushState Oct 03 17:51:37 localhost volumio[1313]: info: CorePlayQueue::getTrack 9 Oct 03 17:51:37 localhost volumio[1313]: verbose: STATE SERVICE {"status":"play","position":0,"seek":67,"duration":176,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Si No Te Tengo A Ti","artist":"Alvaro Soler","album":"Eterno Agosto","uri":"http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-226.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Df2dc225cb17a1a2b2a85b307457995f0353b86ac949e6ceedd61e3ca43f2d1c9%2Clid%3D225%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6ace6d63%2F27671%2F23136%2F32d97e3d.80962531.12.25545523%2F320.mp3&ext=.mp3","trackType":"mp3"} Oct 03 17:51:37 localhost volumio[1313]: verbose: CURRENT POSITION 9 Oct 03 17:51:37 localhost volumio[1313]: info: CoreStateMachine::syncState stateService play Oct 03 17:51:37 localhost volumio[1313]: info: CoreStateMachine::syncState currentStatus play Oct 03 17:51:37 localhost volumio[1313]: info: Received an update from plugin. extracting info from payload Oct 03 17:51:37 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:51:37 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:51:37 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:51:37 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:51:37 localhost volumio[1313]: info: ------------------------------ 16ms Oct 03 17:51:37 localhost volumio[1313]: info: ------------------------------ 18ms Oct 03 17:51:37 localhost volumio[1313]: info: ------------------------------ 18ms Oct 03 17:51:37 localhost volumio[1313]: info: sendMpdCommand status took 11 milliseconds Oct 03 17:51:37 localhost volumio[1313]: info: sendMpdCommand playlistinfo took 10 milliseconds Oct 03 17:51:37 localhost volumio[1313]: info: sendMpdCommand playlistinfo took 10 milliseconds Oct 03 17:51:37 localhost volumio[1313]: verbose: ControllerMpd::parseState Oct 03 17:51:37 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 17:51:37 localhost volumio[1313]: verbose: ControllerMpd::parseTrackInfo Oct 03 17:51:37 localhost volumio[1313]: verbose: ControllerMpd::parseTrackInfo Oct 03 17:51:37 localhost volumio[1313]: info: ControllerMpd::pushState Oct 03 17:51:37 localhost volumio[1313]: info: CoreCommandRouter::servicePushState Oct 03 17:51:37 localhost volumio[1313]: info: CorePlayQueue::getTrack 9 Oct 03 17:51:37 localhost volumio[1313]: verbose: STATE SERVICE {"status":"play","position":0,"seek":546,"duration":176,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Si No Te Tengo A Ti","artist":"Alvaro Soler","album":"Eterno Agosto","uri":"http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-226.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Df2dc225cb17a1a2b2a85b307457995f0353b86ac949e6ceedd61e3ca43f2d1c9%2Clid%3D225%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6ace6d63%2F27671%2F23136%2F32d97e3d.80962531.12.25545523%2F320.mp3&ext=.mp3","trackType":"mp3"} Oct 03 17:51:37 localhost volumio[1313]: verbose: CURRENT POSITION 9 Oct 03 17:51:37 localhost volumio[1313]: info: CoreStateMachine::syncState stateService play Oct 03 17:51:37 localhost volumio[1313]: info: CoreStateMachine::syncState currentStatus play Oct 03 17:51:37 localhost volumio[1313]: info: Received an update from plugin. extracting info from payload Oct 03 17:51:37 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:51:37 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:51:37 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:51:37 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:51:37 localhost volumio[1313]: info: ControllerMpd::pushState Oct 03 17:51:37 localhost volumio[1313]: info: CoreCommandRouter::servicePushState Oct 03 17:51:37 localhost volumio[1313]: info: CorePlayQueue::getTrack 9 Oct 03 17:51:37 localhost volumio[1313]: verbose: STATE SERVICE {"status":"play","position":0,"seek":762,"duration":176,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Si No Te Tengo A Ti","artist":"Alvaro Soler","album":"Eterno Agosto","uri":"http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-226.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Df2dc225cb17a1a2b2a85b307457995f0353b86ac949e6ceedd61e3ca43f2d1c9%2Clid%3D225%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6ace6d63%2F27671%2F23136%2F32d97e3d.80962531.12.25545523%2F320.mp3&ext=.mp3","trackType":"mp3"} Oct 03 17:51:37 localhost volumio[1313]: verbose: CURRENT POSITION 9 Oct 03 17:51:37 localhost volumio[1313]: info: CoreStateMachine::syncState stateService play Oct 03 17:51:37 localhost volumio[1313]: info: CoreStateMachine::syncState currentStatus play Oct 03 17:51:37 localhost volumio[1313]: info: Received an update from plugin. extracting info from payload Oct 03 17:51:37 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:51:37 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:51:37 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:51:37 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:51:37 localhost volumio[1313]: info: ------------------------------ 21ms Oct 03 17:51:37 localhost volumio[1313]: info: ------------------------------ 20ms Oct 03 17:51:37 localhost volumio[1313]: info: CoreCommandRouter::volumioGetQueue Oct 03 17:51:37 localhost volumio[1313]: info: CoreStateMachine::getQueue Oct 03 17:51:37 localhost volumio[1313]: info: CorePlayQueue::getQueue Oct 03 17:51:37 localhost volumio[1313]: info: CoreCommandRouter::volumioGetQueue Oct 03 17:51:37 localhost volumio[1313]: info: CoreStateMachine::getQueue Oct 03 17:51:37 localhost volumio[1313]: info: CorePlayQueue::getQueue Oct 03 17:51:37 localhost volumio[1313]: info: CoreCommandRouter::volumioGetQueue Oct 03 17:51:37 localhost volumio[1313]: info: CoreStateMachine::getQueue Oct 03 17:51:37 localhost volumio[1313]: info: CorePlayQueue::getQueue Oct 03 17:51:37 localhost volumio[1313]: info: CoreCommandRouter::volumioGetQueue Oct 03 17:51:37 localhost volumio[1313]: info: CoreStateMachine::getQueue Oct 03 17:51:37 localhost volumio[1313]: info: CorePlayQueue::getQueue Oct 03 17:51:37 localhost volumio[1313]: info: CoreCommandRouter::volumioGetQueue Oct 03 17:51:37 localhost volumio[1313]: info: CoreStateMachine::getQueue Oct 03 17:51:37 localhost volumio[1313]: info: CorePlayQueue::getQueue Oct 03 17:51:37 localhost volumio[1313]: info: CoreCommandRouter::volumioGetQueue Oct 03 17:51:37 localhost volumio[1313]: info: CoreStateMachine::getQueue Oct 03 17:51:37 localhost volumio[1313]: info: CorePlayQueue::getQueue Oct 03 17:51:37 localhost volumio[1313]: info: CoreCommandRouter::volumioGetQueue Oct 03 17:51:37 localhost volumio[1313]: info: CoreStateMachine::getQueue Oct 03 17:51:37 localhost volumio[1313]: info: CorePlayQueue::getQueue Oct 03 17:51:37 localhost volumio[1313]: info: CoreCommandRouter::volumioGetQueue Oct 03 17:51:37 localhost volumio[1313]: info: CoreStateMachine::getQueue Oct 03 17:51:37 localhost volumio[1313]: info: CorePlayQueue::getQueue Oct 03 17:51:37 localhost volumio[1313]: info: sendMpdCommand playlistinfo took 32 milliseconds Oct 03 17:51:37 localhost volumio[1313]: verbose: ControllerMpd::parseTrackInfo Oct 03 17:51:37 localhost volumio[1313]: info: ControllerMpd::pushState Oct 03 17:51:37 localhost volumio[1313]: info: CoreCommandRouter::servicePushState Oct 03 17:51:37 localhost volumio[1313]: info: CorePlayQueue::getTrack 9 Oct 03 17:51:37 localhost volumio[1313]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":176,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Si No Te Tengo A Ti","artist":"Alvaro Soler","album":"Eterno Agosto","uri":"http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-226.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Df2dc225cb17a1a2b2a85b307457995f0353b86ac949e6ceedd61e3ca43f2d1c9%2Clid%3D225%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6ace6d63%2F27671%2F23136%2F32d97e3d.80962531.12.25545523%2F320.mp3&ext=.mp3","trackType":"mp3"} Oct 03 17:51:37 localhost volumio[1313]: verbose: CURRENT POSITION 9 Oct 03 17:51:37 localhost volumio[1313]: info: CoreStateMachine::syncState stateService play Oct 03 17:51:37 localhost volumio[1313]: info: CoreStateMachine::syncState currentStatus play Oct 03 17:51:37 localhost volumio[1313]: info: Received an update from plugin. extracting info from payload Oct 03 17:51:37 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:51:37 localhost volumio[1313]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 17:51:37 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:51:37 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:51:37 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:51:37 localhost volumio[1313]: info: ------------------------------ 48ms Oct 03 17:51:37 localhost volumio[1313]: info: CoreCommandRouter::volumioGetQueue Oct 03 17:51:37 localhost volumio[1313]: info: CoreStateMachine::getQueue Oct 03 17:51:37 localhost volumio[1313]: info: CorePlayQueue::getQueue Oct 03 17:51:37 localhost volumio[1313]: info: CoreCommandRouter::volumioGetQueue Oct 03 17:51:37 localhost volumio[1313]: info: CoreStateMachine::getQueue Oct 03 17:51:37 localhost volumio[1313]: info: CorePlayQueue::getQueue Oct 03 17:51:39 localhost volumio[1313]: info: CoreCommandRouter::volumioNext Oct 03 17:51:39 localhost volumio[1313]: info: CoreStateMachine::next Oct 03 17:51:39 localhost volumio[1313]: info: ControllerMpd::next Oct 03 17:51:39 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand next Oct 03 17:51:39 localhost volumio[1313]: info: Oct 03 17:51:39 localhost volumio[1313]: ---------------------------- MPD announces system playlist update Oct 03 17:51:39 localhost volumio[1313]: info: Ignoring MPD Status Update Oct 03 17:51:39 localhost volumio[1313]: info: Oct 03 17:51:39 localhost volumio[1313]: ---------------------------- MPD announces state update: player Oct 03 17:51:39 localhost volumio[1313]: info: sendMpdCommand next took 2 milliseconds Oct 03 17:51:39 localhost volumio[1313]: info: ControllerMpd::getState Oct 03 17:51:39 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:51:39 localhost volumio[1313]: error: updateQueue error: null Oct 03 17:51:39 localhost volumio[1313]: info: ------------------------------ 1ms Oct 03 17:51:39 localhost volumio[1313]: info: Oct 03 17:51:39 localhost volumio[1313]: ---------------------------- MPD announces system playlist update Oct 03 17:51:39 localhost volumio[1313]: info: Ignoring MPD Status Update Oct 03 17:51:39 localhost volumio[1313]: info: Oct 03 17:51:39 localhost volumio[1313]: ---------------------------- MPD announces state update: player Oct 03 17:51:39 localhost volumio[1313]: info: ControllerMpd::getState Oct 03 17:51:39 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:51:39 localhost volumio[1313]: info: Oct 03 17:51:39 localhost volumio[1313]: ---------------------------- MPD announces system playlist update Oct 03 17:51:39 localhost volumio[1313]: info: Ignoring MPD Status Update Oct 03 17:51:39 localhost volumio[1313]: info: Oct 03 17:51:39 localhost volumio[1313]: ---------------------------- MPD announces state update: player Oct 03 17:51:39 localhost volumio[1313]: info: ControllerMpd::getState Oct 03 17:51:39 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:51:39 localhost volumio[1313]: error: updateQueue error: null Oct 03 17:51:39 localhost volumio[1313]: error: updateQueue error: null Oct 03 17:51:39 localhost volumio[1313]: info: sendMpdCommand status took 3 milliseconds Oct 03 17:51:39 localhost volumio[1313]: info: ------------------------------ 2ms Oct 03 17:51:39 localhost volumio[1313]: info: sendMpdCommand status took 2 milliseconds Oct 03 17:51:39 localhost volumio[1313]: info: ------------------------------ 2ms Oct 03 17:51:39 localhost volumio[1313]: info: sendMpdCommand status took 1 milliseconds Oct 03 17:51:39 localhost volumio[1313]: verbose: ControllerMpd::parseState Oct 03 17:51:39 localhost volumio[1313]: verbose: ControllerMpd::parseState Oct 03 17:51:39 localhost volumio[1313]: verbose: ControllerMpd::parseState Oct 03 17:51:39 localhost volumio[1313]: info: ControllerMpd::pushState Oct 03 17:51:39 localhost volumio[1313]: info: CoreCommandRouter::servicePushState Oct 03 17:51:39 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:51:39 localhost volumio[1313]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 17:51:39 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:51:39 localhost volumio[1313]: info: CorePlayQueue::getTrack 9 Oct 03 17:51:39 localhost volumio[1313]: 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} Oct 03 17:51:39 localhost volumio[1313]: verbose: CURRENT POSITION 9 Oct 03 17:51:39 localhost volumio[1313]: info: CoreStateMachine::syncState stateService stop Oct 03 17:51:39 localhost volumio[1313]: info: CoreStateMachine::syncState currentStatus play Oct 03 17:51:39 localhost volumio[1313]: info: CoreStateMachine::play index undefined Oct 03 17:51:39 localhost volumio[1313]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 03 17:51:39 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:51:39 localhost volumio[1313]: info: CorePlayQueue::getTrack 10 Oct 03 17:51:39 localhost volumio[1313]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 17:51:39 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:51:39 localhost volumio[1313]: info: ControllerMpd::pushState Oct 03 17:51:39 localhost volumio[1313]: info: CoreCommandRouter::servicePushState Oct 03 17:51:39 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:51:39 localhost volumio[1313]: info: CorePlayQueue::getTrack 10 Oct 03 17:51:39 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:51:39 localhost volumio[1313]: info: CorePlayQueue::getTrack 10 Oct 03 17:51:39 localhost volumio[1313]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd Oct 03 17:51:39 localhost volumio[1313]: info: ControllerMpd::pushState Oct 03 17:51:39 localhost volumio[1313]: info: CoreCommandRouter::servicePushState Oct 03 17:51:39 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:51:39 localhost volumio[1313]: info: CorePlayQueue::getTrack 10 Oct 03 17:51:39 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:51:39 localhost volumio[1313]: info: CorePlayQueue::getTrack 10 Oct 03 17:51:39 localhost volumio[1313]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd Oct 03 17:51:39 localhost volumio[1313]: info: CorePlayQueue::getTrack 10 Oct 03 17:51:39 localhost volumio[1313]: info: CoreStateMachine::startPlaybackTimer Oct 03 17:51:39 localhost volumio[1313]: info: CorePlayQueue::getTrack 10 Oct 03 17:51:39 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand stop Oct 03 17:51:39 localhost volumio[1313]: info: ------------------------------ 9ms Oct 03 17:51:39 localhost volumio[1313]: info: ------------------------------ 9ms Oct 03 17:51:39 localhost volumio[1313]: info: ------------------------------ 8ms Oct 03 17:51:39 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:51:39 localhost volumio[1313]: info: CorePlayQueue::getTrack 10 Oct 03 17:51:39 localhost volumio[1313]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 17:51:39 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:51:39 localhost volumio[1313]: info: sendMpdCommand stop took 3 milliseconds Oct 03 17:51:39 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand clear Oct 03 17:51:39 localhost volumio[1313]: info: CoreCommandRouter::volumioGetQueue Oct 03 17:51:39 localhost volumio[1313]: info: CoreStateMachine::getQueue Oct 03 17:51:39 localhost volumio[1313]: info: CorePlayQueue::getQueue Oct 03 17:51:39 localhost volumio[1313]: info: Oct 03 17:51:39 localhost volumio[1313]: ---------------------------- MPD announces system playlist update Oct 03 17:51:39 localhost volumio[1313]: info: Ignoring MPD Status Update Oct 03 17:51:39 localhost volumio[1313]: info: sendMpdCommand clear took 4 milliseconds Oct 03 17:51:39 localhost volumio[1313]: info: Oct 03 17:51:39 localhost volumio[1313]: ---------------------------- MPD announces system playlist update Oct 03 17:51:39 localhost volumio[1313]: info: Ignoring MPD Status Update Oct 03 17:51:39 localhost volumio[1313]: info: Oct 03 17:51:39 localhost volumio[1313]: ---------------------------- MPD announces system playlist update Oct 03 17:51:39 localhost volumio[1313]: info: Ignoring MPD Status Update Oct 03 17:51:39 localhost volumio[1313]: error: updateQueue error: null Oct 03 17:51:39 localhost volumio[1313]: info: ------------------------------ 8ms Oct 03 17:51:39 localhost volumio[1313]: info: ------------------------------ 2ms Oct 03 17:51:39 localhost volumio[1313]: info: ------------------------------ 1ms Oct 03 17:51:41 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fext-strm-runvs04mts-01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D6eae0dcda1fdcba4b8fb8c6029230d68433e1d2e06b4e5e83eac8bf249a89a34%2Clid%3D1640%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6acdd9a8%2F59047%2F12900%2Fbcac0a04.48995498.10.32974845%2F320.mp3&ext=.mp3" Oct 03 17:51:41 localhost volumio[1313]: info: Oct 03 17:51:41 localhost volumio[1313]: ---------------------------- MPD announces system playlist update Oct 03 17:51:41 localhost volumio[1313]: info: Ignoring MPD Status Update Oct 03 17:51:41 localhost volumio[1313]: info: sendMpdCommand addid "http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fext-strm-runvs04mts-01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D6eae0dcda1fdcba4b8fb8c6029230d68433e1d2e06b4e5e83eac8bf249a89a34%2Clid%3D1640%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6acdd9a8%2F59047%2F12900%2Fbcac0a04.48995498.10.32974845%2F320.mp3&ext=.mp3" took 1 milliseconds Oct 03 17:51:41 localhost volumio[1313]: verbose: MPD COMMAND [object Object] Oct 03 17:51:41 localhost volumio[1313]: verbose: MPD COMMAND [object Object] Oct 03 17:51:41 localhost volumio[1313]: verbose: MPD COMMAND [object Object] Oct 03 17:51:41 localhost volumio[1313]: info: Oct 03 17:51:41 localhost volumio[1313]: ---------------------------- MPD announces system playlist update Oct 03 17:51:41 localhost volumio[1313]: info: Ignoring MPD Status Update Oct 03 17:51:41 localhost volumio[1313]: info: Oct 03 17:51:41 localhost volumio[1313]: ---------------------------- MPD announces system playlist update Oct 03 17:51:41 localhost volumio[1313]: info: Ignoring MPD Status Update Oct 03 17:51:41 localhost volumio[1313]: info: Oct 03 17:51:41 localhost volumio[1313]: ---------------------------- MPD announces system playlist update Oct 03 17:51:41 localhost volumio[1313]: info: Ignoring MPD Status Update Oct 03 17:51:41 localhost volumio[1313]: info: ------------------------------ 1ms Oct 03 17:51:41 localhost volumio[1313]: info: ------------------------------ 1ms Oct 03 17:51:41 localhost volumio[1313]: info: ------------------------------ 0ms Oct 03 17:51:41 localhost volumio[1313]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 03 17:51:41 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand play Oct 03 17:51:41 localhost volumio[1313]: info: Oct 03 17:51:41 localhost volumio[1313]: ---------------------------- MPD announces system playlist update Oct 03 17:51:41 localhost volumio[1313]: info: Ignoring MPD Status Update Oct 03 17:51:41 localhost volumio[1313]: info: Oct 03 17:51:41 localhost volumio[1313]: ---------------------------- MPD announces system playlist update Oct 03 17:51:41 localhost volumio[1313]: info: Ignoring MPD Status Update Oct 03 17:51:41 localhost volumio[1313]: info: ------------------------------ 1ms Oct 03 17:51:41 localhost volumio[1313]: info: sendMpdCommand play took 1 milliseconds Oct 03 17:51:41 localhost volumio[1313]: info: ------------------------------ 1ms Oct 03 17:51:41 localhost volumio[1313]: info: ------------------------------ 1ms Oct 03 17:51:42 localhost volumio[1313]: info: Oct 03 17:51:42 localhost volumio[1313]: ---------------------------- MPD announces state update: player Oct 03 17:51:42 localhost volumio[1313]: info: ControllerMpd::getState Oct 03 17:51:42 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:51:42 localhost volumio[1313]: info: Oct 03 17:51:42 localhost volumio[1313]: ---------------------------- MPD announces state update: player Oct 03 17:51:42 localhost volumio[1313]: info: ControllerMpd::getState Oct 03 17:51:42 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:51:42 localhost volumio[1313]: info: Oct 03 17:51:42 localhost volumio[1313]: ---------------------------- MPD announces state update: player Oct 03 17:51:42 localhost volumio[1313]: info: ControllerMpd::getState Oct 03 17:51:42 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:51:42 localhost volumio[1313]: info: sendMpdCommand status took 6 milliseconds Oct 03 17:51:42 localhost volumio[1313]: verbose: ControllerMpd::parseState Oct 03 17:51:42 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 17:51:42 localhost volumio[1313]: info: Oct 03 17:51:42 localhost volumio[1313]: ---------------------------- MPD announces state update: player Oct 03 17:51:42 localhost volumio[1313]: info: ControllerMpd::getState Oct 03 17:51:42 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:51:42 localhost volumio[1313]: info: sendMpdCommand status took 7 milliseconds Oct 03 17:51:42 localhost volumio[1313]: info: sendMpdCommand status took 7 milliseconds Oct 03 17:51:42 localhost volumio[1313]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 03 17:51:42 localhost volumio[1313]: verbose: ControllerMpd::parseState Oct 03 17:51:42 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 17:51:42 localhost volumio[1313]: verbose: ControllerMpd::parseState Oct 03 17:51:42 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 17:51:42 localhost volumio[1313]: verbose: ControllerMpd::parseTrackInfo Oct 03 17:51:42 localhost volumio[1313]: info: ControllerMpd::pushState Oct 03 17:51:42 localhost volumio[1313]: info: CoreCommandRouter::servicePushState Oct 03 17:51:42 localhost volumio[1313]: info: CorePlayQueue::getTrack 10 Oct 03 17:51:42 localhost volumio[1313]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":380,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Wasted Time","artist":"Memphis Train","album":"Beatin' Around the Blues","uri":"http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fext-strm-runvs04mts-01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D6eae0dcda1fdcba4b8fb8c6029230d68433e1d2e06b4e5e83eac8bf249a89a34%2Clid%3D1640%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6acdd9a8%2F59047%2F12900%2Fbcac0a04.48995498.10.32974845%2F320.mp3&ext=.mp3","trackType":"mp3"} Oct 03 17:51:42 localhost volumio[1313]: verbose: CURRENT POSITION 10 Oct 03 17:51:42 localhost volumio[1313]: info: CoreStateMachine::syncState stateService play Oct 03 17:51:42 localhost volumio[1313]: info: CoreStateMachine::syncState currentStatus stop Oct 03 17:51:42 localhost volumio[1313]: info: ------------------------------ 9ms Oct 03 17:51:42 localhost volumio[1313]: info: Oct 03 17:51:42 localhost volumio[1313]: ---------------------------- MPD announces state update: player Oct 03 17:51:42 localhost volumio[1313]: info: ControllerMpd::getState Oct 03 17:51:42 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:51:42 localhost volumio[1313]: info: Oct 03 17:51:42 localhost volumio[1313]: ---------------------------- MPD announces state update: player Oct 03 17:51:42 localhost volumio[1313]: info: ControllerMpd::getState Oct 03 17:51:42 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:51:42 localhost volumio[1313]: info: sendMpdCommand status took 3 milliseconds Oct 03 17:51:42 localhost volumio[1313]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 03 17:51:42 localhost volumio[1313]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 03 17:51:42 localhost volumio[1313]: info: sendMpdCommand status took 1 milliseconds Oct 03 17:51:42 localhost volumio[1313]: info: sendMpdCommand status took 1 milliseconds Oct 03 17:51:42 localhost volumio[1313]: verbose: ControllerMpd::parseState Oct 03 17:51:42 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 17:51:42 localhost volumio[1313]: verbose: ControllerMpd::parseTrackInfo Oct 03 17:51:42 localhost volumio[1313]: verbose: ControllerMpd::parseTrackInfo Oct 03 17:51:42 localhost volumio[1313]: verbose: ControllerMpd::parseState Oct 03 17:51:42 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 17:51:42 localhost volumio[1313]: verbose: ControllerMpd::parseState Oct 03 17:51:42 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 17:51:42 localhost volumio[1313]: info: ControllerMpd::pushState Oct 03 17:51:42 localhost volumio[1313]: info: CoreCommandRouter::servicePushState Oct 03 17:51:42 localhost volumio[1313]: info: CorePlayQueue::getTrack 10 Oct 03 17:51:42 localhost volumio[1313]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":380,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Wasted Time","artist":"Memphis Train","album":"Beatin' Around the Blues","uri":"http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fext-strm-runvs04mts-01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D6eae0dcda1fdcba4b8fb8c6029230d68433e1d2e06b4e5e83eac8bf249a89a34%2Clid%3D1640%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6acdd9a8%2F59047%2F12900%2Fbcac0a04.48995498.10.32974845%2F320.mp3&ext=.mp3","trackType":"mp3"} Oct 03 17:51:42 localhost volumio[1313]: verbose: CURRENT POSITION 10 Oct 03 17:51:42 localhost volumio[1313]: info: CoreStateMachine::syncState stateService play Oct 03 17:51:42 localhost volumio[1313]: info: CoreStateMachine::syncState currentStatus play Oct 03 17:51:42 localhost volumio[1313]: info: Received an update from plugin. extracting info from payload Oct 03 17:51:42 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:51:42 localhost volumio[1313]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 17:51:42 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:51:42 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:51:42 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:51:42 localhost volumio[1313]: info: ControllerMpd::pushState Oct 03 17:51:42 localhost volumio[1313]: info: CoreCommandRouter::servicePushState Oct 03 17:51:42 localhost volumio[1313]: info: CorePlayQueue::getTrack 10 Oct 03 17:51:42 localhost volumio[1313]: verbose: STATE SERVICE {"status":"play","position":0,"seek":11,"duration":380,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Wasted Time","artist":"Memphis Train","album":"Beatin' Around the Blues","uri":"http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fext-strm-runvs04mts-01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D6eae0dcda1fdcba4b8fb8c6029230d68433e1d2e06b4e5e83eac8bf249a89a34%2Clid%3D1640%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6acdd9a8%2F59047%2F12900%2Fbcac0a04.48995498.10.32974845%2F320.mp3&ext=.mp3","trackType":"mp3"} Oct 03 17:51:42 localhost volumio[1313]: verbose: CURRENT POSITION 10 Oct 03 17:51:42 localhost volumio[1313]: info: CoreStateMachine::syncState stateService play Oct 03 17:51:42 localhost volumio[1313]: info: CoreStateMachine::syncState currentStatus play Oct 03 17:51:42 localhost volumio[1313]: info: Received an update from plugin. extracting info from payload Oct 03 17:51:42 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:51:42 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:51:42 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:51:42 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:51:42 localhost volumio[1313]: info: ------------------------------ 18ms Oct 03 17:51:42 localhost volumio[1313]: info: ------------------------------ 18ms Oct 03 17:51:42 localhost volumio[1313]: info: sendMpdCommand playlistinfo took 9 milliseconds Oct 03 17:51:42 localhost volumio[1313]: info: sendMpdCommand playlistinfo took 8 milliseconds Oct 03 17:51:42 localhost volumio[1313]: info: sendMpdCommand playlistinfo took 8 milliseconds Oct 03 17:51:42 localhost volumio[1313]: verbose: ControllerMpd::parseTrackInfo Oct 03 17:51:42 localhost volumio[1313]: verbose: ControllerMpd::parseTrackInfo Oct 03 17:51:42 localhost volumio[1313]: verbose: ControllerMpd::parseTrackInfo Oct 03 17:51:42 localhost volumio[1313]: info: ControllerMpd::pushState Oct 03 17:51:42 localhost volumio[1313]: info: CoreCommandRouter::servicePushState Oct 03 17:51:42 localhost volumio[1313]: info: CorePlayQueue::getTrack 10 Oct 03 17:51:42 localhost volumio[1313]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":380,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Wasted Time","artist":"Memphis Train","album":"Beatin' Around the Blues","uri":"http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fext-strm-runvs04mts-01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D6eae0dcda1fdcba4b8fb8c6029230d68433e1d2e06b4e5e83eac8bf249a89a34%2Clid%3D1640%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6acdd9a8%2F59047%2F12900%2Fbcac0a04.48995498.10.32974845%2F320.mp3&ext=.mp3","trackType":"mp3"} Oct 03 17:51:42 localhost volumio[1313]: verbose: CURRENT POSITION 10 Oct 03 17:51:42 localhost volumio[1313]: info: CoreStateMachine::syncState stateService play Oct 03 17:51:42 localhost volumio[1313]: info: CoreStateMachine::syncState currentStatus play Oct 03 17:51:42 localhost volumio[1313]: info: Received an update from plugin. extracting info from payload Oct 03 17:51:42 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:51:42 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:51:42 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:51:42 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:51:42 localhost volumio[1313]: info: ControllerMpd::pushState Oct 03 17:51:42 localhost volumio[1313]: info: CoreCommandRouter::servicePushState Oct 03 17:51:42 localhost volumio[1313]: info: CorePlayQueue::getTrack 10 Oct 03 17:51:42 localhost volumio[1313]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":380,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Wasted Time","artist":"Memphis Train","album":"Beatin' Around the Blues","uri":"http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fext-strm-runvs04mts-01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D6eae0dcda1fdcba4b8fb8c6029230d68433e1d2e06b4e5e83eac8bf249a89a34%2Clid%3D1640%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6acdd9a8%2F59047%2F12900%2Fbcac0a04.48995498.10.32974845%2F320.mp3&ext=.mp3","trackType":"mp3"} Oct 03 17:51:42 localhost volumio[1313]: verbose: CURRENT POSITION 10 Oct 03 17:51:42 localhost volumio[1313]: info: CoreStateMachine::syncState stateService play Oct 03 17:51:42 localhost volumio[1313]: info: CoreStateMachine::syncState currentStatus play Oct 03 17:51:42 localhost volumio[1313]: info: Received an update from plugin. extracting info from payload Oct 03 17:51:42 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:51:42 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:51:42 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:51:42 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:51:42 localhost volumio[1313]: info: ControllerMpd::pushState Oct 03 17:51:42 localhost volumio[1313]: info: CoreCommandRouter::servicePushState Oct 03 17:51:42 localhost volumio[1313]: info: CorePlayQueue::getTrack 10 Oct 03 17:51:42 localhost volumio[1313]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":380,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Wasted Time","artist":"Memphis Train","album":"Beatin' Around the Blues","uri":"http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fext-strm-runvs04mts-01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D6eae0dcda1fdcba4b8fb8c6029230d68433e1d2e06b4e5e83eac8bf249a89a34%2Clid%3D1640%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6acdd9a8%2F59047%2F12900%2Fbcac0a04.48995498.10.32974845%2F320.mp3&ext=.mp3","trackType":"mp3"} Oct 03 17:51:42 localhost volumio[1313]: verbose: CURRENT POSITION 10 Oct 03 17:51:42 localhost volumio[1313]: info: CoreStateMachine::syncState stateService play Oct 03 17:51:42 localhost volumio[1313]: info: CoreStateMachine::syncState currentStatus play Oct 03 17:51:42 localhost volumio[1313]: info: Received an update from plugin. extracting info from payload Oct 03 17:51:42 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:51:42 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:51:42 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:51:42 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:51:42 localhost volumio[1313]: info: ------------------------------ 24ms Oct 03 17:51:42 localhost volumio[1313]: info: ------------------------------ 23ms Oct 03 17:51:42 localhost volumio[1313]: info: ------------------------------ 22ms Oct 03 17:51:42 localhost volumio[1313]: info: CoreCommandRouter::volumioGetQueue Oct 03 17:51:42 localhost volumio[1313]: info: CoreStateMachine::getQueue Oct 03 17:51:42 localhost volumio[1313]: info: CorePlayQueue::getQueue Oct 03 17:51:42 localhost volumio[1313]: info: CoreCommandRouter::volumioGetQueue Oct 03 17:51:42 localhost volumio[1313]: info: CoreStateMachine::getQueue Oct 03 17:51:42 localhost volumio[1313]: info: CorePlayQueue::getQueue Oct 03 17:51:42 localhost volumio[1313]: info: CoreCommandRouter::volumioGetQueue Oct 03 17:51:42 localhost volumio[1313]: info: CoreStateMachine::getQueue Oct 03 17:51:42 localhost volumio[1313]: info: CorePlayQueue::getQueue Oct 03 17:51:42 localhost volumio[1313]: info: CoreCommandRouter::volumioGetQueue Oct 03 17:51:42 localhost volumio[1313]: info: CoreStateMachine::getQueue Oct 03 17:51:42 localhost volumio[1313]: info: CorePlayQueue::getQueue Oct 03 17:51:42 localhost volumio[1313]: info: CoreCommandRouter::volumioGetQueue Oct 03 17:51:42 localhost volumio[1313]: info: CoreStateMachine::getQueue Oct 03 17:51:42 localhost volumio[1313]: info: CorePlayQueue::getQueue Oct 03 17:51:42 localhost volumio[1313]: info: CoreCommandRouter::volumioGetQueue Oct 03 17:51:42 localhost volumio[1313]: info: CoreStateMachine::getQueue Oct 03 17:51:42 localhost volumio[1313]: info: CorePlayQueue::getQueue Oct 03 17:51:42 localhost volumio[1313]: info: CoreCommandRouter::volumioGetQueue Oct 03 17:51:42 localhost volumio[1313]: info: CoreStateMachine::getQueue Oct 03 17:51:42 localhost volumio[1313]: info: CorePlayQueue::getQueue Oct 03 17:51:42 localhost volumio[1313]: info: CoreCommandRouter::volumioGetQueue Oct 03 17:51:42 localhost volumio[1313]: info: CoreStateMachine::getQueue Oct 03 17:51:42 localhost volumio[1313]: info: CorePlayQueue::getQueue Oct 03 17:51:42 localhost volumio[1313]: info: CoreCommandRouter::volumioGetQueue Oct 03 17:51:42 localhost volumio[1313]: info: CoreStateMachine::getQueue Oct 03 17:51:42 localhost volumio[1313]: info: CorePlayQueue::getQueue Oct 03 17:51:42 localhost volumio[1313]: info: CoreCommandRouter::volumioGetQueue Oct 03 17:51:42 localhost volumio[1313]: info: CoreStateMachine::getQueue Oct 03 17:51:42 localhost volumio[1313]: info: CorePlayQueue::getQueue Oct 03 17:51:44 localhost volumio[1313]: info: CoreCommandRouter::volumioSeek Oct 03 17:51:44 localhost volumio[1313]: info: CoreStateMachine::seek Oct 03 17:51:44 localhost volumio[1313]: info: CorePlayQueue::getTrack 10 Oct 03 17:51:44 localhost volumio[1313]: info: TRACKBLOCK {"id":"32974845:4909741@503646255:109423217","service":"yandex_music","type":"song","name":"Wasted Time","title":"Wasted Time","album":"Beatin' Around the Blues","artist":"Memphis Train","duration":380,"albumart":"https://avatars.yandex.net/get-music-content/38044/b6c6d85b.a.4909741-1/200x200","uri":"yandex_music/track/32974845:4909741@503646255:109423217","samplerate":"","bitdepth":"","trackType":"","codec":"mp3","bitrate":320} Oct 03 17:51:44 localhost volumio[1313]: info: CoreStateMachine::startPlaybackTimer Oct 03 17:51:44 localhost volumio[1313]: info: CorePlayQueue::getTrack 10 Oct 03 17:51:44 localhost volumio[1313]: info: ControllerMpd::seek Oct 03 17:51:44 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:51:44 localhost volumio[1313]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 17:51:44 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:51:44 localhost volumio[1313]: info: CoreCommandRouter::volumioGetQueue Oct 03 17:51:44 localhost volumio[1313]: info: CoreStateMachine::getQueue Oct 03 17:51:44 localhost volumio[1313]: info: CorePlayQueue::getQueue Oct 03 17:51:44 localhost volumio[1313]: error: null Oct 03 17:51:44 localhost volumio[1313]: info: Oct 03 17:51:44 localhost volumio[1313]: ---------------------------- MPD announces state update: player Oct 03 17:51:44 localhost volumio[1313]: info: ControllerMpd::getState Oct 03 17:51:44 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:51:44 localhost volumio[1313]: info: Oct 03 17:51:44 localhost volumio[1313]: ---------------------------- MPD announces state update: player Oct 03 17:51:44 localhost volumio[1313]: info: ControllerMpd::getState Oct 03 17:51:44 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:51:44 localhost volumio[1313]: info: Oct 03 17:51:44 localhost volumio[1313]: ---------------------------- MPD announces state update: player Oct 03 17:51:44 localhost volumio[1313]: info: ControllerMpd::getState Oct 03 17:51:44 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:51:44 localhost volumio[1313]: info: sendMpdCommand status took 1 milliseconds Oct 03 17:51:44 localhost volumio[1313]: info: sendMpdCommand status took 1 milliseconds Oct 03 17:51:44 localhost volumio[1313]: info: sendMpdCommand status took 1 milliseconds Oct 03 17:51:44 localhost volumio[1313]: verbose: ControllerMpd::parseState Oct 03 17:51:44 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 17:51:44 localhost volumio[1313]: verbose: ControllerMpd::parseState Oct 03 17:51:44 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 17:51:44 localhost volumio[1313]: verbose: ControllerMpd::parseState Oct 03 17:51:44 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 17:51:44 localhost volumio[1313]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 03 17:51:44 localhost volumio[1313]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 03 17:51:44 localhost volumio[1313]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 03 17:51:44 localhost volumio[1313]: verbose: ControllerMpd::parseTrackInfo Oct 03 17:51:44 localhost volumio[1313]: verbose: ControllerMpd::parseTrackInfo Oct 03 17:51:44 localhost volumio[1313]: info: ControllerMpd::pushState Oct 03 17:51:44 localhost volumio[1313]: info: CoreCommandRouter::servicePushState Oct 03 17:51:44 localhost volumio[1313]: info: CorePlayQueue::getTrack 10 Oct 03 17:51:44 localhost volumio[1313]: verbose: STATE SERVICE {"status":"play","position":0,"seek":48000,"duration":380,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Wasted Time","artist":"Memphis Train","album":"Beatin' Around the Blues","uri":"http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fext-strm-runvs04mts-01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D6eae0dcda1fdcba4b8fb8c6029230d68433e1d2e06b4e5e83eac8bf249a89a34%2Clid%3D1640%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6acdd9a8%2F59047%2F12900%2Fbcac0a04.48995498.10.32974845%2F320.mp3&ext=.mp3","trackType":"mp3"} Oct 03 17:51:44 localhost volumio[1313]: verbose: CURRENT POSITION 10 Oct 03 17:51:44 localhost volumio[1313]: info: CoreStateMachine::syncState stateService play Oct 03 17:51:44 localhost volumio[1313]: info: CoreStateMachine::syncState currentStatus play Oct 03 17:51:44 localhost volumio[1313]: info: Received an update from plugin. extracting info from payload Oct 03 17:51:44 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:51:44 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:51:44 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:51:44 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:51:44 localhost volumio[1313]: info: ControllerMpd::pushState Oct 03 17:51:44 localhost volumio[1313]: info: CoreCommandRouter::servicePushState Oct 03 17:51:44 localhost volumio[1313]: info: CorePlayQueue::getTrack 10 Oct 03 17:51:44 localhost volumio[1313]: verbose: STATE SERVICE {"status":"play","position":0,"seek":48000,"duration":380,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Wasted Time","artist":"Memphis Train","album":"Beatin' Around the Blues","uri":"http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fext-strm-runvs04mts-01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D6eae0dcda1fdcba4b8fb8c6029230d68433e1d2e06b4e5e83eac8bf249a89a34%2Clid%3D1640%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6acdd9a8%2F59047%2F12900%2Fbcac0a04.48995498.10.32974845%2F320.mp3&ext=.mp3","trackType":"mp3"} Oct 03 17:51:44 localhost volumio[1313]: verbose: CURRENT POSITION 10 Oct 03 17:51:44 localhost volumio[1313]: info: CoreStateMachine::syncState stateService play Oct 03 17:51:44 localhost volumio[1313]: info: CoreStateMachine::syncState currentStatus play Oct 03 17:51:44 localhost volumio[1313]: info: Received an update from plugin. extracting info from payload Oct 03 17:51:44 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:51:44 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:51:44 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:51:44 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:51:44 localhost volumio[1313]: info: ControllerMpd::pushState Oct 03 17:51:44 localhost volumio[1313]: info: CoreCommandRouter::servicePushState Oct 03 17:51:44 localhost volumio[1313]: info: CorePlayQueue::getTrack 10 Oct 03 17:51:44 localhost volumio[1313]: verbose: STATE SERVICE {"status":"play","position":0,"seek":48000,"duration":380,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Wasted Time","artist":"Memphis Train","album":"Beatin' Around the Blues","uri":"http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fext-strm-runvs04mts-01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D6eae0dcda1fdcba4b8fb8c6029230d68433e1d2e06b4e5e83eac8bf249a89a34%2Clid%3D1640%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6acdd9a8%2F59047%2F12900%2Fbcac0a04.48995498.10.32974845%2F320.mp3&ext=.mp3","trackType":"mp3"} Oct 03 17:51:44 localhost volumio[1313]: verbose: CURRENT POSITION 10 Oct 03 17:51:44 localhost volumio[1313]: info: CoreStateMachine::syncState stateService play Oct 03 17:51:44 localhost volumio[1313]: info: CoreStateMachine::syncState currentStatus play Oct 03 17:51:44 localhost volumio[1313]: info: Received an update from plugin. extracting info from payload Oct 03 17:51:44 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:51:44 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:51:44 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:51:44 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:51:44 localhost volumio[1313]: info: ------------------------------ 17ms Oct 03 17:51:44 localhost volumio[1313]: info: CoreCommandRouter::volumioGetQueue Oct 03 17:51:44 localhost volumio[1313]: info: CoreStateMachine::getQueue Oct 03 17:51:44 localhost volumio[1313]: info: CorePlayQueue::getQueue Oct 03 17:51:44 localhost volumio[1313]: info: CoreCommandRouter::volumioGetQueue Oct 03 17:51:44 localhost volumio[1313]: info: CoreStateMachine::getQueue Oct 03 17:51:44 localhost volumio[1313]: info: CorePlayQueue::getQueue Oct 03 17:51:44 localhost volumio[1313]: info: CoreCommandRouter::volumioGetQueue Oct 03 17:51:44 localhost volumio[1313]: info: CoreStateMachine::getQueue Oct 03 17:51:44 localhost volumio[1313]: info: CorePlayQueue::getQueue Oct 03 17:51:44 localhost volumio[1313]: info: CoreCommandRouter::volumioGetQueue Oct 03 17:51:44 localhost volumio[1313]: info: CoreStateMachine::getQueue Oct 03 17:51:44 localhost volumio[1313]: info: CorePlayQueue::getQueue Oct 03 17:51:44 localhost volumio[1313]: info: CoreCommandRouter::volumioGetQueue Oct 03 17:51:44 localhost volumio[1313]: info: CoreStateMachine::getQueue Oct 03 17:51:44 localhost volumio[1313]: info: CorePlayQueue::getQueue Oct 03 17:51:44 localhost volumio[1313]: info: CoreCommandRouter::volumioGetQueue Oct 03 17:51:44 localhost volumio[1313]: info: CoreStateMachine::getQueue Oct 03 17:51:44 localhost volumio[1313]: info: CorePlayQueue::getQueue Oct 03 17:51:47 localhost volumio[1313]: info: CoreCommandRouter::volumioSeek Oct 03 17:51:47 localhost volumio[1313]: info: CoreStateMachine::seek Oct 03 17:51:47 localhost volumio[1313]: info: CorePlayQueue::getTrack 10 Oct 03 17:51:47 localhost volumio[1313]: info: TRACKBLOCK {"id":"32974845:4909741@503646255:109423217","service":"yandex_music","type":"song","name":"Wasted Time","title":"Wasted Time","album":"Beatin' Around the Blues","artist":"Memphis Train","duration":380,"albumart":"https://avatars.yandex.net/get-music-content/38044/b6c6d85b.a.4909741-1/200x200","uri":"yandex_music/track/32974845:4909741@503646255:109423217","samplerate":"","bitdepth":"","trackType":"","codec":"mp3","bitrate":320} Oct 03 17:51:47 localhost volumio[1313]: info: CoreStateMachine::startPlaybackTimer Oct 03 17:51:47 localhost volumio[1313]: info: CorePlayQueue::getTrack 10 Oct 03 17:51:47 localhost volumio[1313]: info: ControllerMpd::seek Oct 03 17:51:47 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:51:47 localhost volumio[1313]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 17:51:47 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:51:47 localhost volumio[1313]: info: CoreCommandRouter::volumioGetQueue Oct 03 17:51:47 localhost volumio[1313]: info: CoreStateMachine::getQueue Oct 03 17:51:47 localhost volumio[1313]: info: CorePlayQueue::getQueue Oct 03 17:51:47 localhost volumio[1313]: error: null Oct 03 17:51:47 localhost volumio[1313]: info: Oct 03 17:51:47 localhost volumio[1313]: ---------------------------- MPD announces state update: player Oct 03 17:51:47 localhost volumio[1313]: info: ControllerMpd::getState Oct 03 17:51:47 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:51:47 localhost volumio[1313]: info: Oct 03 17:51:47 localhost volumio[1313]: ---------------------------- MPD announces state update: player Oct 03 17:51:47 localhost volumio[1313]: info: ControllerMpd::getState Oct 03 17:51:47 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:51:47 localhost volumio[1313]: info: Oct 03 17:51:47 localhost volumio[1313]: ---------------------------- MPD announces state update: player Oct 03 17:51:47 localhost volumio[1313]: info: ControllerMpd::getState Oct 03 17:51:47 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:51:47 localhost volumio[1313]: info: sendMpdCommand status took 1 milliseconds Oct 03 17:51:47 localhost volumio[1313]: info: sendMpdCommand status took 1 milliseconds Oct 03 17:51:47 localhost volumio[1313]: info: sendMpdCommand status took 0 milliseconds Oct 03 17:51:47 localhost volumio[1313]: verbose: ControllerMpd::parseState Oct 03 17:51:47 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 17:51:47 localhost volumio[1313]: verbose: ControllerMpd::parseState Oct 03 17:51:47 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 17:51:47 localhost volumio[1313]: verbose: ControllerMpd::parseState Oct 03 17:51:47 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 17:51:47 localhost volumio[1313]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 03 17:51:47 localhost volumio[1313]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 03 17:51:47 localhost volumio[1313]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 03 17:51:47 localhost volumio[1313]: verbose: ControllerMpd::parseTrackInfo Oct 03 17:51:47 localhost volumio[1313]: verbose: ControllerMpd::parseTrackInfo Oct 03 17:51:47 localhost volumio[1313]: verbose: ControllerMpd::parseTrackInfo Oct 03 17:51:47 localhost volumio[1313]: info: ControllerMpd::pushState Oct 03 17:51:47 localhost volumio[1313]: info: CoreCommandRouter::servicePushState Oct 03 17:51:47 localhost volumio[1313]: info: CorePlayQueue::getTrack 10 Oct 03 17:51:47 localhost volumio[1313]: verbose: STATE SERVICE {"status":"play","position":0,"seek":94000,"duration":380,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Wasted Time","artist":"Memphis Train","album":"Beatin' Around the Blues","uri":"http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fext-strm-runvs04mts-01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D6eae0dcda1fdcba4b8fb8c6029230d68433e1d2e06b4e5e83eac8bf249a89a34%2Clid%3D1640%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6acdd9a8%2F59047%2F12900%2Fbcac0a04.48995498.10.32974845%2F320.mp3&ext=.mp3","trackType":"mp3"} Oct 03 17:51:47 localhost volumio[1313]: verbose: CURRENT POSITION 10 Oct 03 17:51:47 localhost volumio[1313]: info: CoreStateMachine::syncState stateService play Oct 03 17:51:47 localhost volumio[1313]: info: CoreStateMachine::syncState currentStatus play Oct 03 17:51:47 localhost volumio[1313]: info: Received an update from plugin. extracting info from payload Oct 03 17:51:47 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:51:47 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:51:47 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:51:47 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:51:47 localhost volumio[1313]: info: ControllerMpd::pushState Oct 03 17:51:47 localhost volumio[1313]: info: CoreCommandRouter::servicePushState Oct 03 17:51:47 localhost volumio[1313]: info: CorePlayQueue::getTrack 10 Oct 03 17:51:47 localhost volumio[1313]: verbose: STATE SERVICE {"status":"play","position":0,"seek":94000,"duration":380,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Wasted Time","artist":"Memphis Train","album":"Beatin' Around the Blues","uri":"http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fext-strm-runvs04mts-01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D6eae0dcda1fdcba4b8fb8c6029230d68433e1d2e06b4e5e83eac8bf249a89a34%2Clid%3D1640%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6acdd9a8%2F59047%2F12900%2Fbcac0a04.48995498.10.32974845%2F320.mp3&ext=.mp3","trackType":"mp3"} Oct 03 17:51:47 localhost volumio[1313]: verbose: CURRENT POSITION 10 Oct 03 17:51:47 localhost volumio[1313]: info: CoreStateMachine::syncState stateService play Oct 03 17:51:47 localhost volumio[1313]: info: CoreStateMachine::syncState currentStatus play Oct 03 17:51:47 localhost volumio[1313]: info: Received an update from plugin. extracting info from payload Oct 03 17:51:47 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:51:47 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:51:47 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:51:47 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:51:47 localhost volumio[1313]: info: ControllerMpd::pushState Oct 03 17:51:47 localhost volumio[1313]: info: CoreCommandRouter::servicePushState Oct 03 17:51:47 localhost volumio[1313]: info: CorePlayQueue::getTrack 10 Oct 03 17:51:47 localhost volumio[1313]: verbose: STATE SERVICE {"status":"play","position":0,"seek":94000,"duration":380,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Wasted Time","artist":"Memphis Train","album":"Beatin' Around the Blues","uri":"http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fext-strm-runvs04mts-01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D6eae0dcda1fdcba4b8fb8c6029230d68433e1d2e06b4e5e83eac8bf249a89a34%2Clid%3D1640%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6acdd9a8%2F59047%2F12900%2Fbcac0a04.48995498.10.32974845%2F320.mp3&ext=.mp3","trackType":"mp3"} Oct 03 17:51:47 localhost volumio[1313]: verbose: CURRENT POSITION 10 Oct 03 17:51:47 localhost volumio[1313]: info: CoreStateMachine::syncState stateService play Oct 03 17:51:47 localhost volumio[1313]: info: CoreStateMachine::syncState currentStatus play Oct 03 17:51:47 localhost volumio[1313]: info: Received an update from plugin. extracting info from payload Oct 03 17:51:47 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:51:47 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:51:47 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:51:47 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:51:47 localhost volumio[1313]: info: ------------------------------ 10ms Oct 03 17:51:47 localhost volumio[1313]: info: ------------------------------ 10ms Oct 03 17:51:47 localhost volumio[1313]: info: ------------------------------ 10ms Oct 03 17:51:47 localhost volumio[1313]: info: CoreCommandRouter::volumioGetQueue Oct 03 17:51:47 localhost volumio[1313]: info: CoreStateMachine::getQueue Oct 03 17:51:47 localhost volumio[1313]: info: CorePlayQueue::getQueue Oct 03 17:51:47 localhost volumio[1313]: info: CoreCommandRouter::volumioGetQueue Oct 03 17:51:47 localhost volumio[1313]: info: CoreStateMachine::getQueue Oct 03 17:51:47 localhost volumio[1313]: info: CorePlayQueue::getQueue Oct 03 17:51:47 localhost volumio[1313]: info: CoreCommandRouter::volumioGetQueue Oct 03 17:51:47 localhost volumio[1313]: info: CoreStateMachine::getQueue Oct 03 17:51:47 localhost volumio[1313]: info: CorePlayQueue::getQueue Oct 03 17:51:47 localhost volumio[1313]: info: CoreCommandRouter::volumioGetQueue Oct 03 17:51:47 localhost volumio[1313]: info: CoreStateMachine::getQueue Oct 03 17:51:47 localhost volumio[1313]: info: CorePlayQueue::getQueue Oct 03 17:51:47 localhost volumio[1313]: info: CoreCommandRouter::volumioGetQueue Oct 03 17:51:47 localhost volumio[1313]: info: CoreStateMachine::getQueue Oct 03 17:51:47 localhost volumio[1313]: info: CorePlayQueue::getQueue Oct 03 17:51:47 localhost volumio[1313]: info: CoreCommandRouter::volumioGetQueue Oct 03 17:51:47 localhost volumio[1313]: info: CoreStateMachine::getQueue Oct 03 17:51:47 localhost volumio[1313]: info: CorePlayQueue::getQueue Oct 03 17:51:49 localhost volumio[1313]: info: CoreCommandRouter::volumioSeek Oct 03 17:51:49 localhost volumio[1313]: info: CoreStateMachine::seek Oct 03 17:51:49 localhost volumio[1313]: info: CorePlayQueue::getTrack 10 Oct 03 17:51:49 localhost volumio[1313]: info: TRACKBLOCK {"id":"32974845:4909741@503646255:109423217","service":"yandex_music","type":"song","name":"Wasted Time","title":"Wasted Time","album":"Beatin' Around the Blues","artist":"Memphis Train","duration":380,"albumart":"https://avatars.yandex.net/get-music-content/38044/b6c6d85b.a.4909741-1/200x200","uri":"yandex_music/track/32974845:4909741@503646255:109423217","samplerate":"","bitdepth":"","trackType":"","codec":"mp3","bitrate":320} Oct 03 17:51:49 localhost volumio[1313]: info: CoreStateMachine::startPlaybackTimer Oct 03 17:51:49 localhost volumio[1313]: info: CorePlayQueue::getTrack 10 Oct 03 17:51:49 localhost volumio[1313]: info: ControllerMpd::seek Oct 03 17:51:49 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:51:49 localhost volumio[1313]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 17:51:49 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:51:49 localhost volumio[1313]: info: CoreCommandRouter::volumioGetQueue Oct 03 17:51:49 localhost volumio[1313]: info: CoreStateMachine::getQueue Oct 03 17:51:49 localhost volumio[1313]: info: CorePlayQueue::getQueue Oct 03 17:51:49 localhost volumio[1313]: error: null Oct 03 17:51:49 localhost volumio[1313]: info: Oct 03 17:51:49 localhost volumio[1313]: ---------------------------- MPD announces state update: player Oct 03 17:51:49 localhost volumio[1313]: info: ControllerMpd::getState Oct 03 17:51:49 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:51:49 localhost volumio[1313]: info: sendMpdCommand status took 0 milliseconds Oct 03 17:51:49 localhost volumio[1313]: verbose: ControllerMpd::parseState Oct 03 17:51:49 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 17:51:49 localhost volumio[1313]: info: Oct 03 17:51:49 localhost volumio[1313]: ---------------------------- MPD announces state update: player Oct 03 17:51:49 localhost volumio[1313]: info: ControllerMpd::getState Oct 03 17:51:49 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:51:49 localhost volumio[1313]: info: Oct 03 17:51:49 localhost volumio[1313]: ---------------------------- MPD announces state update: player Oct 03 17:51:49 localhost volumio[1313]: info: ControllerMpd::getState Oct 03 17:51:49 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:51:49 localhost volumio[1313]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 03 17:51:49 localhost volumio[1313]: info: sendMpdCommand status took 1 milliseconds Oct 03 17:51:49 localhost volumio[1313]: info: sendMpdCommand status took 1 milliseconds Oct 03 17:51:49 localhost volumio[1313]: verbose: ControllerMpd::parseTrackInfo Oct 03 17:51:49 localhost volumio[1313]: verbose: ControllerMpd::parseState Oct 03 17:51:49 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 17:51:49 localhost volumio[1313]: verbose: ControllerMpd::parseState Oct 03 17:51:49 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 17:51:49 localhost volumio[1313]: info: ControllerMpd::pushState Oct 03 17:51:49 localhost volumio[1313]: info: CoreCommandRouter::servicePushState Oct 03 17:51:49 localhost volumio[1313]: info: CorePlayQueue::getTrack 10 Oct 03 17:51:49 localhost volumio[1313]: verbose: STATE SERVICE {"status":"play","position":0,"seek":148000,"duration":380,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Wasted Time","artist":"Memphis Train","album":"Beatin' Around the Blues","uri":"http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fext-strm-runvs04mts-01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D6eae0dcda1fdcba4b8fb8c6029230d68433e1d2e06b4e5e83eac8bf249a89a34%2Clid%3D1640%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6acdd9a8%2F59047%2F12900%2Fbcac0a04.48995498.10.32974845%2F320.mp3&ext=.mp3","trackType":"mp3"} Oct 03 17:51:49 localhost volumio[1313]: verbose: CURRENT POSITION 10 Oct 03 17:51:49 localhost volumio[1313]: info: CoreStateMachine::syncState stateService play Oct 03 17:51:49 localhost volumio[1313]: info: CoreStateMachine::syncState currentStatus play Oct 03 17:51:49 localhost volumio[1313]: info: Received an update from plugin. extracting info from payload Oct 03 17:51:49 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:51:49 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:51:49 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:51:49 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:51:49 localhost volumio[1313]: info: ------------------------------ 5ms Oct 03 17:51:49 localhost volumio[1313]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 03 17:51:49 localhost volumio[1313]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 03 17:51:49 localhost volumio[1313]: verbose: ControllerMpd::parseTrackInfo Oct 03 17:51:49 localhost volumio[1313]: verbose: ControllerMpd::parseTrackInfo Oct 03 17:51:49 localhost volumio[1313]: info: ControllerMpd::pushState Oct 03 17:51:49 localhost volumio[1313]: info: CoreCommandRouter::servicePushState Oct 03 17:51:49 localhost volumio[1313]: info: CorePlayQueue::getTrack 10 Oct 03 17:51:49 localhost volumio[1313]: verbose: STATE SERVICE {"status":"play","position":0,"seek":148000,"duration":380,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Wasted Time","artist":"Memphis Train","album":"Beatin' Around the Blues","uri":"http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fext-strm-runvs04mts-01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D6eae0dcda1fdcba4b8fb8c6029230d68433e1d2e06b4e5e83eac8bf249a89a34%2Clid%3D1640%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6acdd9a8%2F59047%2F12900%2Fbcac0a04.48995498.10.32974845%2F320.mp3&ext=.mp3","trackType":"mp3"} Oct 03 17:51:49 localhost volumio[1313]: verbose: CURRENT POSITION 10 Oct 03 17:51:49 localhost volumio[1313]: info: CoreStateMachine::syncState stateService play Oct 03 17:51:49 localhost volumio[1313]: info: CoreStateMachine::syncState currentStatus play Oct 03 17:51:49 localhost volumio[1313]: info: Received an update from plugin. extracting info from payload Oct 03 17:51:49 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:51:49 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:51:49 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:51:49 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:51:49 localhost volumio[1313]: info: ControllerMpd::pushState Oct 03 17:51:49 localhost volumio[1313]: info: CoreCommandRouter::servicePushState Oct 03 17:51:49 localhost volumio[1313]: info: CorePlayQueue::getTrack 10 Oct 03 17:51:49 localhost volumio[1313]: verbose: STATE SERVICE {"status":"play","position":0,"seek":148000,"duration":380,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Wasted Time","artist":"Memphis Train","album":"Beatin' Around the Blues","uri":"http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fext-strm-runvs04mts-01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D6eae0dcda1fdcba4b8fb8c6029230d68433e1d2e06b4e5e83eac8bf249a89a34%2Clid%3D1640%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6acdd9a8%2F59047%2F12900%2Fbcac0a04.48995498.10.32974845%2F320.mp3&ext=.mp3","trackType":"mp3"} Oct 03 17:51:49 localhost volumio[1313]: verbose: CURRENT POSITION 10 Oct 03 17:51:49 localhost volumio[1313]: info: CoreStateMachine::syncState stateService play Oct 03 17:51:49 localhost volumio[1313]: info: CoreStateMachine::syncState currentStatus play Oct 03 17:51:49 localhost volumio[1313]: info: Received an update from plugin. extracting info from payload Oct 03 17:51:49 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:51:49 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:51:49 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:51:49 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:51:49 localhost volumio[1313]: info: ------------------------------ 10ms Oct 03 17:51:49 localhost volumio[1313]: info: ------------------------------ 10ms Oct 03 17:51:49 localhost volumio[1313]: info: CoreCommandRouter::volumioGetQueue Oct 03 17:51:49 localhost volumio[1313]: info: CoreStateMachine::getQueue Oct 03 17:51:49 localhost volumio[1313]: info: CorePlayQueue::getQueue Oct 03 17:51:49 localhost volumio[1313]: info: CoreCommandRouter::volumioGetQueue Oct 03 17:51:49 localhost volumio[1313]: info: CoreStateMachine::getQueue Oct 03 17:51:49 localhost volumio[1313]: info: CorePlayQueue::getQueue Oct 03 17:51:49 localhost volumio[1313]: info: CoreCommandRouter::volumioGetQueue Oct 03 17:51:49 localhost volumio[1313]: info: CoreStateMachine::getQueue Oct 03 17:51:49 localhost volumio[1313]: info: CorePlayQueue::getQueue Oct 03 17:51:49 localhost volumio[1313]: info: CoreCommandRouter::volumioGetQueue Oct 03 17:51:49 localhost volumio[1313]: info: CoreStateMachine::getQueue Oct 03 17:51:49 localhost volumio[1313]: info: CorePlayQueue::getQueue Oct 03 17:51:49 localhost volumio[1313]: info: CoreCommandRouter::volumioGetQueue Oct 03 17:51:49 localhost volumio[1313]: info: CoreStateMachine::getQueue Oct 03 17:51:49 localhost volumio[1313]: info: CorePlayQueue::getQueue Oct 03 17:51:49 localhost volumio[1313]: info: CoreCommandRouter::volumioGetQueue Oct 03 17:51:49 localhost volumio[1313]: info: CoreStateMachine::getQueue Oct 03 17:51:49 localhost volumio[1313]: info: CorePlayQueue::getQueue Oct 03 17:51:51 localhost volumio[1313]: info: CoreCommandRouter::volumioNext Oct 03 17:51:51 localhost volumio[1313]: info: CoreStateMachine::next Oct 03 17:51:51 localhost volumio[1313]: info: ControllerMpd::next Oct 03 17:51:51 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand next Oct 03 17:51:51 localhost volumio[1313]: info: Oct 03 17:51:51 localhost volumio[1313]: ---------------------------- MPD announces system playlist update Oct 03 17:51:51 localhost volumio[1313]: info: Ignoring MPD Status Update Oct 03 17:51:51 localhost volumio[1313]: info: Oct 03 17:51:51 localhost volumio[1313]: ---------------------------- MPD announces state update: player Oct 03 17:51:51 localhost volumio[1313]: info: sendMpdCommand next took 2 milliseconds Oct 03 17:51:51 localhost volumio[1313]: info: ControllerMpd::getState Oct 03 17:51:51 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:51:51 localhost volumio[1313]: error: updateQueue error: null Oct 03 17:51:51 localhost volumio[1313]: info: ------------------------------ 0ms Oct 03 17:51:51 localhost volumio[1313]: info: Oct 03 17:51:51 localhost volumio[1313]: ---------------------------- MPD announces system playlist update Oct 03 17:51:51 localhost volumio[1313]: info: Ignoring MPD Status Update Oct 03 17:51:51 localhost volumio[1313]: info: Oct 03 17:51:51 localhost volumio[1313]: ---------------------------- MPD announces state update: player Oct 03 17:51:51 localhost volumio[1313]: info: ControllerMpd::getState Oct 03 17:51:51 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:51:51 localhost volumio[1313]: info: Oct 03 17:51:51 localhost volumio[1313]: ---------------------------- MPD announces system playlist update Oct 03 17:51:51 localhost volumio[1313]: info: Ignoring MPD Status Update Oct 03 17:51:51 localhost volumio[1313]: info: Oct 03 17:51:51 localhost volumio[1313]: ---------------------------- MPD announces state update: player Oct 03 17:51:51 localhost volumio[1313]: info: ControllerMpd::getState Oct 03 17:51:51 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:51:51 localhost volumio[1313]: error: updateQueue error: null Oct 03 17:51:51 localhost volumio[1313]: error: updateQueue error: null Oct 03 17:51:51 localhost volumio[1313]: info: sendMpdCommand status took 1 milliseconds Oct 03 17:51:51 localhost volumio[1313]: info: ------------------------------ 0ms Oct 03 17:51:51 localhost volumio[1313]: info: sendMpdCommand status took 1 milliseconds Oct 03 17:51:51 localhost volumio[1313]: info: ------------------------------ 1ms Oct 03 17:51:51 localhost volumio[1313]: info: sendMpdCommand status took 1 milliseconds Oct 03 17:51:51 localhost volumio[1313]: verbose: ControllerMpd::parseState Oct 03 17:51:51 localhost volumio[1313]: verbose: ControllerMpd::parseState Oct 03 17:51:51 localhost volumio[1313]: verbose: ControllerMpd::parseState Oct 03 17:51:51 localhost volumio[1313]: info: ControllerMpd::pushState Oct 03 17:51:51 localhost volumio[1313]: info: CoreCommandRouter::servicePushState Oct 03 17:51:51 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:51:51 localhost volumio[1313]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 17:51:51 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:51:51 localhost volumio[1313]: info: CorePlayQueue::getTrack 10 Oct 03 17:51:51 localhost volumio[1313]: 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} Oct 03 17:51:51 localhost volumio[1313]: verbose: CURRENT POSITION 10 Oct 03 17:51:51 localhost volumio[1313]: info: CoreStateMachine::syncState stateService stop Oct 03 17:51:51 localhost volumio[1313]: info: CoreStateMachine::syncState currentStatus play Oct 03 17:51:51 localhost volumio[1313]: info: CoreStateMachine::play index undefined Oct 03 17:51:51 localhost volumio[1313]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 03 17:51:51 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:51:51 localhost volumio[1313]: info: CorePlayQueue::getTrack 11 Oct 03 17:51:51 localhost volumio[1313]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 17:51:51 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:51:51 localhost volumio[1313]: info: ControllerMpd::pushState Oct 03 17:51:51 localhost volumio[1313]: info: CoreCommandRouter::servicePushState Oct 03 17:51:51 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:51:51 localhost volumio[1313]: info: CorePlayQueue::getTrack 11 Oct 03 17:51:51 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:51:51 localhost volumio[1313]: info: CorePlayQueue::getTrack 11 Oct 03 17:51:51 localhost volumio[1313]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd Oct 03 17:51:51 localhost volumio[1313]: info: ControllerMpd::pushState Oct 03 17:51:51 localhost volumio[1313]: info: CoreCommandRouter::servicePushState Oct 03 17:51:51 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:51:51 localhost volumio[1313]: info: CorePlayQueue::getTrack 11 Oct 03 17:51:51 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:51:51 localhost volumio[1313]: info: CorePlayQueue::getTrack 11 Oct 03 17:51:51 localhost volumio[1313]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd Oct 03 17:51:51 localhost volumio[1313]: info: CorePlayQueue::getTrack 11 Oct 03 17:51:51 localhost volumio[1313]: info: CoreStateMachine::startPlaybackTimer Oct 03 17:51:51 localhost volumio[1313]: info: CorePlayQueue::getTrack 11 Oct 03 17:51:51 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand stop Oct 03 17:51:51 localhost volumio[1313]: info: ------------------------------ 7ms Oct 03 17:51:51 localhost volumio[1313]: info: ------------------------------ 7ms Oct 03 17:51:51 localhost volumio[1313]: info: ------------------------------ 7ms Oct 03 17:51:51 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:51:51 localhost volumio[1313]: info: CorePlayQueue::getTrack 11 Oct 03 17:51:51 localhost volumio[1313]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 17:51:51 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:51:51 localhost volumio[1313]: info: sendMpdCommand stop took 4 milliseconds Oct 03 17:51:51 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand clear Oct 03 17:51:51 localhost volumio[1313]: info: CoreCommandRouter::volumioGetQueue Oct 03 17:51:51 localhost volumio[1313]: info: CoreStateMachine::getQueue Oct 03 17:51:51 localhost volumio[1313]: info: CorePlayQueue::getQueue Oct 03 17:51:51 localhost volumio[1313]: info: Oct 03 17:51:51 localhost volumio[1313]: ---------------------------- MPD announces system playlist update Oct 03 17:51:51 localhost volumio[1313]: info: Ignoring MPD Status Update Oct 03 17:51:51 localhost volumio[1313]: info: sendMpdCommand clear took 4 milliseconds Oct 03 17:51:51 localhost volumio[1313]: info: Oct 03 17:51:51 localhost volumio[1313]: ---------------------------- MPD announces system playlist update Oct 03 17:51:51 localhost volumio[1313]: info: Ignoring MPD Status Update Oct 03 17:51:51 localhost volumio[1313]: info: Oct 03 17:51:51 localhost volumio[1313]: ---------------------------- MPD announces system playlist update Oct 03 17:51:51 localhost volumio[1313]: info: Ignoring MPD Status Update Oct 03 17:51:51 localhost volumio[1313]: error: updateQueue error: null Oct 03 17:51:51 localhost volumio[1313]: error: updateQueue error: null Oct 03 17:51:51 localhost volumio[1313]: error: updateQueue error: null Oct 03 17:51:51 localhost volumio[1313]: info: ------------------------------ 7ms Oct 03 17:51:51 localhost volumio[1313]: info: ------------------------------ 4ms Oct 03 17:51:51 localhost volumio[1313]: info: ------------------------------ 3ms Oct 03 17:51:52 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fext-strm-ruvld01mts-01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Db79f238178ddea1eb9166301ff5869e8e8cf743f810f8b41c9f12c10052e067c%2Clid%3D1610%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6acef5cc%2F0%2F24222%2Ff182fe5f.213362512.5.146690741%2Fflac-mp4&ext=.flac" Oct 03 17:51:52 localhost volumio[1313]: info: Oct 03 17:51:52 localhost volumio[1313]: ---------------------------- MPD announces system playlist update Oct 03 17:51:52 localhost volumio[1313]: info: Ignoring MPD Status Update Oct 03 17:51:52 localhost volumio[1313]: info: sendMpdCommand addid "http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fext-strm-ruvld01mts-01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Db79f238178ddea1eb9166301ff5869e8e8cf743f810f8b41c9f12c10052e067c%2Clid%3D1610%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6acef5cc%2F0%2F24222%2Ff182fe5f.213362512.5.146690741%2Fflac-mp4&ext=.flac" took 1 milliseconds Oct 03 17:51:52 localhost volumio[1313]: verbose: MPD COMMAND [object Object] Oct 03 17:51:52 localhost volumio[1313]: verbose: MPD COMMAND [object Object] Oct 03 17:51:52 localhost volumio[1313]: verbose: MPD COMMAND [object Object] Oct 03 17:51:52 localhost volumio[1313]: info: Oct 03 17:51:52 localhost volumio[1313]: ---------------------------- MPD announces system playlist update Oct 03 17:51:52 localhost volumio[1313]: info: Ignoring MPD Status Update Oct 03 17:51:52 localhost volumio[1313]: info: ------------------------------ 1ms Oct 03 17:51:52 localhost volumio[1313]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 03 17:51:52 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand play Oct 03 17:51:52 localhost volumio[1313]: info: Oct 03 17:51:52 localhost volumio[1313]: ---------------------------- MPD announces system playlist update Oct 03 17:51:52 localhost volumio[1313]: info: Ignoring MPD Status Update Oct 03 17:51:52 localhost volumio[1313]: info: Oct 03 17:51:52 localhost volumio[1313]: ---------------------------- MPD announces system playlist update Oct 03 17:51:52 localhost volumio[1313]: info: Ignoring MPD Status Update Oct 03 17:51:52 localhost volumio[1313]: info: ------------------------------ 1ms Oct 03 17:51:52 localhost volumio[1313]: info: sendMpdCommand play took 1 milliseconds Oct 03 17:51:52 localhost volumio[1313]: info: ------------------------------ 1ms Oct 03 17:51:52 localhost volumio[1313]: info: ------------------------------ 1ms Oct 03 17:51:52 localhost volumio[1313]: info: Oct 03 17:51:52 localhost volumio[1313]: ---------------------------- MPD announces system playlist update Oct 03 17:51:52 localhost volumio[1313]: info: Ignoring MPD Status Update Oct 03 17:51:52 localhost volumio[1313]: info: Oct 03 17:51:52 localhost volumio[1313]: ---------------------------- MPD announces system playlist update Oct 03 17:51:52 localhost volumio[1313]: info: Ignoring MPD Status Update Oct 03 17:51:52 localhost volumio[1313]: info: ------------------------------ 1ms Oct 03 17:51:52 localhost volumio[1313]: info: ------------------------------ 1ms Oct 03 17:51:52 localhost nmbd[936]: [2026/10/03 17:51:52.942769, 0] ../source3/nmbd/nmbd_nameregister.c:73(register_name_response) Oct 03 17:51:52 localhost nmbd[936]: register_name_response: Answer name <00> differs from question name <00>. Oct 03 17:51:52 localhost nmbd[936]: [2026/10/03 17:51:52.942917, 0] ../source3/nmbd/nmbd_nameregister.c:73(register_name_response) Oct 03 17:51:52 localhost nmbd[936]: register_name_response: Answer name <03> differs from question name <03>. Oct 03 17:51:52 localhost nmbd[936]: [2026/10/03 17:51:52.943039, 0] ../source3/nmbd/nmbd_nameregister.c:73(register_name_response) Oct 03 17:51:52 localhost nmbd[936]: register_name_response: Answer name <20> differs from question name <20>. Oct 03 17:51:53 localhost volumio[1313]: info: CoreCommandRouter::volumioNext Oct 03 17:51:53 localhost volumio[1313]: info: CoreStateMachine::next Oct 03 17:51:53 localhost volumio[1313]: info: ControllerMpd::next Oct 03 17:51:53 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand next Oct 03 17:51:53 localhost volumio[1313]: info: Oct 03 17:51:53 localhost volumio[1313]: ---------------------------- MPD announces system playlist update Oct 03 17:51:53 localhost volumio[1313]: info: Ignoring MPD Status Update Oct 03 17:51:53 localhost volumio[1313]: info: Oct 03 17:51:53 localhost volumio[1313]: ---------------------------- MPD announces state update: player Oct 03 17:51:53 localhost volumio[1313]: info: sendMpdCommand next took 0 milliseconds Oct 03 17:51:53 localhost volumio[1313]: info: ControllerMpd::getState Oct 03 17:51:53 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:51:53 localhost volumio[1313]: info: Oct 03 17:51:53 localhost volumio[1313]: ---------------------------- MPD announces system playlist update Oct 03 17:51:53 localhost volumio[1313]: info: Ignoring MPD Status Update Oct 03 17:51:53 localhost volumio[1313]: info: Oct 03 17:51:53 localhost volumio[1313]: ---------------------------- MPD announces state update: player Oct 03 17:51:53 localhost volumio[1313]: info: ControllerMpd::getState Oct 03 17:51:53 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:51:53 localhost volumio[1313]: info: Oct 03 17:51:53 localhost volumio[1313]: ---------------------------- MPD announces system playlist update Oct 03 17:51:53 localhost volumio[1313]: info: Ignoring MPD Status Update Oct 03 17:51:53 localhost volumio[1313]: info: Oct 03 17:51:53 localhost volumio[1313]: ---------------------------- MPD announces state update: player Oct 03 17:51:53 localhost volumio[1313]: info: ControllerMpd::getState Oct 03 17:51:53 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:51:53 localhost volumio[1313]: error: updateQueue error: null Oct 03 17:51:53 localhost volumio[1313]: error: updateQueue error: null Oct 03 17:51:53 localhost volumio[1313]: error: updateQueue error: null Oct 03 17:51:53 localhost volumio[1313]: info: ------------------------------ 2ms Oct 03 17:51:53 localhost volumio[1313]: info: sendMpdCommand status took 1 milliseconds Oct 03 17:51:53 localhost volumio[1313]: info: ------------------------------ 1ms Oct 03 17:51:53 localhost volumio[1313]: info: sendMpdCommand status took 1 milliseconds Oct 03 17:51:53 localhost volumio[1313]: info: ------------------------------ 1ms Oct 03 17:51:53 localhost volumio[1313]: info: sendMpdCommand status took 1 milliseconds Oct 03 17:51:53 localhost volumio[1313]: verbose: ControllerMpd::parseState Oct 03 17:51:53 localhost volumio[1313]: verbose: ControllerMpd::parseState Oct 03 17:51:53 localhost volumio[1313]: verbose: ControllerMpd::parseState Oct 03 17:51:53 localhost volumio[1313]: info: ControllerMpd::pushState Oct 03 17:51:53 localhost volumio[1313]: info: CoreCommandRouter::servicePushState Oct 03 17:51:53 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:51:53 localhost volumio[1313]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 17:51:53 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:51:53 localhost volumio[1313]: info: CorePlayQueue::getTrack 11 Oct 03 17:51:53 localhost volumio[1313]: 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} Oct 03 17:51:53 localhost volumio[1313]: verbose: CURRENT POSITION 11 Oct 03 17:51:53 localhost volumio[1313]: info: CoreStateMachine::syncState stateService stop Oct 03 17:51:53 localhost volumio[1313]: info: CoreStateMachine::syncState currentStatus stop Oct 03 17:51:53 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:51:53 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:51:53 localhost volumio[1313]: info: No code Oct 03 17:51:53 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:51:53 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:51:53 localhost volumio[1313]: info: ControllerMpd::pushState Oct 03 17:51:53 localhost volumio[1313]: info: CoreCommandRouter::servicePushState Oct 03 17:51:53 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:51:53 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:51:53 localhost volumio[1313]: info: CorePlayQueue::getTrack 11 Oct 03 17:51:53 localhost volumio[1313]: 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} Oct 03 17:51:53 localhost volumio[1313]: verbose: CURRENT POSITION 11 Oct 03 17:51:53 localhost volumio[1313]: info: CoreStateMachine::syncState stateService stop Oct 03 17:51:53 localhost volumio[1313]: info: CoreStateMachine::syncState currentStatus stop Oct 03 17:51:53 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:51:53 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:51:53 localhost volumio[1313]: info: No code Oct 03 17:51:53 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:51:53 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:51:53 localhost volumio[1313]: info: ControllerMpd::pushState Oct 03 17:51:53 localhost volumio[1313]: info: CoreCommandRouter::servicePushState Oct 03 17:51:53 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:51:53 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:51:53 localhost volumio[1313]: info: CorePlayQueue::getTrack 11 Oct 03 17:51:53 localhost volumio[1313]: 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} Oct 03 17:51:53 localhost volumio[1313]: verbose: CURRENT POSITION 11 Oct 03 17:51:53 localhost volumio[1313]: info: CoreStateMachine::syncState stateService stop Oct 03 17:51:53 localhost volumio[1313]: info: CoreStateMachine::syncState currentStatus stop Oct 03 17:51:53 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:51:53 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:51:53 localhost volumio[1313]: info: No code Oct 03 17:51:53 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:51:53 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:51:53 localhost volumio[1313]: info: ------------------------------ 9ms Oct 03 17:51:53 localhost volumio[1313]: info: ------------------------------ 8ms Oct 03 17:51:53 localhost volumio[1313]: info: ------------------------------ 9ms Oct 03 17:51:58 localhost volumio[1313]: info: CoreCommandRouter::volumioPlay Oct 03 17:51:58 localhost volumio[1313]: info: CoreStateMachine::play index 12 Oct 03 17:51:58 localhost volumio[1313]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 03 17:51:58 localhost volumio[1313]: info: CoreStateMachine::stop Oct 03 17:51:58 localhost volumio[1313]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 03 17:51:58 localhost volumio[1313]: info: CoreStateMachine::play index undefined Oct 03 17:51:58 localhost volumio[1313]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 03 17:51:58 localhost volumio[1313]: info: CorePlayQueue::getTrack 12 Oct 03 17:51:58 localhost volumio[1313]: info: CoreStateMachine::startPlaybackTimer Oct 03 17:51:58 localhost volumio[1313]: info: CorePlayQueue::getTrack 12 Oct 03 17:51:58 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand stop Oct 03 17:51:58 localhost volumio[1313]: info: sendMpdCommand stop took 0 milliseconds Oct 03 17:51:58 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand clear Oct 03 17:51:58 localhost volumio[1313]: info: sendMpdCommand clear took 0 milliseconds Oct 03 17:51:58 localhost volumio[1313]: info: Oct 03 17:51:58 localhost volumio[1313]: ---------------------------- MPD announces system playlist update Oct 03 17:51:58 localhost volumio[1313]: info: Ignoring MPD Status Update Oct 03 17:51:58 localhost volumio[1313]: info: Oct 03 17:51:58 localhost volumio[1313]: ---------------------------- MPD announces system playlist update Oct 03 17:51:58 localhost volumio[1313]: info: Ignoring MPD Status Update Oct 03 17:51:58 localhost volumio[1313]: info: Oct 03 17:51:58 localhost volumio[1313]: ---------------------------- MPD announces system playlist update Oct 03 17:51:58 localhost volumio[1313]: info: Ignoring MPD Status Update Oct 03 17:51:58 localhost volumio[1313]: error: updateQueue error: null Oct 03 17:51:58 localhost volumio[1313]: error: updateQueue error: null Oct 03 17:51:58 localhost volumio[1313]: error: updateQueue error: null Oct 03 17:51:58 localhost volumio[1313]: info: ------------------------------ 1ms Oct 03 17:51:58 localhost volumio[1313]: info: ------------------------------ 1ms Oct 03 17:51:58 localhost volumio[1313]: info: ------------------------------ 0ms Oct 03 17:52:00 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fext-strm-ruvld01mts-01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dd023b945556b54afaccef3fc46df071c5d2582a6a75ea7366b93e123c8dfa66d%2Clid%3D1610%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6acf074c%2F0%2F1749106%2Ffce1ee8d.212705180.5.146239298%2Fflac-mp4&ext=.flac" Oct 03 17:52:00 localhost volumio[1313]: info: Oct 03 17:52:00 localhost volumio[1313]: ---------------------------- MPD announces system playlist update Oct 03 17:52:00 localhost volumio[1313]: info: Ignoring MPD Status Update Oct 03 17:52:00 localhost volumio[1313]: info: sendMpdCommand addid "http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fext-strm-ruvld01mts-01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dd023b945556b54afaccef3fc46df071c5d2582a6a75ea7366b93e123c8dfa66d%2Clid%3D1610%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6acf074c%2F0%2F1749106%2Ffce1ee8d.212705180.5.146239298%2Fflac-mp4&ext=.flac" took 1 milliseconds Oct 03 17:52:00 localhost volumio[1313]: verbose: MPD COMMAND [object Object] Oct 03 17:52:00 localhost volumio[1313]: verbose: MPD COMMAND [object Object] Oct 03 17:52:00 localhost volumio[1313]: verbose: MPD COMMAND [object Object] Oct 03 17:52:00 localhost volumio[1313]: info: Oct 03 17:52:00 localhost volumio[1313]: ---------------------------- MPD announces system playlist update Oct 03 17:52:00 localhost volumio[1313]: info: Ignoring MPD Status Update Oct 03 17:52:00 localhost volumio[1313]: info: Oct 03 17:52:00 localhost volumio[1313]: ---------------------------- MPD announces system playlist update Oct 03 17:52:00 localhost volumio[1313]: info: Ignoring MPD Status Update Oct 03 17:52:00 localhost volumio[1313]: info: Oct 03 17:52:00 localhost volumio[1313]: ---------------------------- MPD announces system playlist update Oct 03 17:52:00 localhost volumio[1313]: info: Ignoring MPD Status Update Oct 03 17:52:00 localhost volumio[1313]: info: ------------------------------ 2ms Oct 03 17:52:00 localhost volumio[1313]: info: ------------------------------ 1ms Oct 03 17:52:00 localhost volumio[1313]: info: ------------------------------ 1ms Oct 03 17:52:00 localhost volumio[1313]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 03 17:52:00 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand play Oct 03 17:52:00 localhost volumio[1313]: info: Oct 03 17:52:00 localhost volumio[1313]: ---------------------------- MPD announces system playlist update Oct 03 17:52:00 localhost volumio[1313]: info: Ignoring MPD Status Update Oct 03 17:52:00 localhost volumio[1313]: info: Oct 03 17:52:00 localhost volumio[1313]: ---------------------------- MPD announces system playlist update Oct 03 17:52:00 localhost volumio[1313]: info: Ignoring MPD Status Update Oct 03 17:52:00 localhost volumio[1313]: info: ------------------------------ 1ms Oct 03 17:52:00 localhost volumio[1313]: info: sendMpdCommand play took 1 milliseconds Oct 03 17:52:00 localhost volumio[1313]: info: ------------------------------ 1ms Oct 03 17:52:00 localhost volumio[1313]: info: ------------------------------ 0ms Oct 03 17:52:00 localhost volumio[1313]: info: Oct 03 17:52:00 localhost volumio[1313]: ---------------------------- MPD announces state update: player Oct 03 17:52:00 localhost volumio[1313]: info: ControllerMpd::getState Oct 03 17:52:00 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:52:00 localhost volumio[1313]: info: Oct 03 17:52:00 localhost volumio[1313]: ---------------------------- MPD announces state update: player Oct 03 17:52:00 localhost volumio[1313]: info: ControllerMpd::getState Oct 03 17:52:00 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:52:00 localhost volumio[1313]: info: Oct 03 17:52:00 localhost volumio[1313]: ---------------------------- MPD announces state update: player Oct 03 17:52:00 localhost volumio[1313]: info: ControllerMpd::getState Oct 03 17:52:00 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:52:01 localhost volumio[1313]: info: Oct 03 17:52:01 localhost volumio[1313]: ---------------------------- MPD announces state update: player Oct 03 17:52:01 localhost volumio[1313]: info: sendMpdCommand status took 13 milliseconds Oct 03 17:52:01 localhost volumio[1313]: info: sendMpdCommand status took 12 milliseconds Oct 03 17:52:01 localhost volumio[1313]: info: sendMpdCommand status took 12 milliseconds Oct 03 17:52:01 localhost volumio[1313]: info: ControllerMpd::getState Oct 03 17:52:01 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:52:01 localhost volumio[1313]: verbose: ControllerMpd::parseState Oct 03 17:52:01 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 17:52:01 localhost volumio[1313]: verbose: ControllerMpd::parseState Oct 03 17:52:01 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 17:52:01 localhost volumio[1313]: verbose: ControllerMpd::parseState Oct 03 17:52:01 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 17:52:01 localhost volumio[1313]: info: Oct 03 17:52:01 localhost volumio[1313]: ---------------------------- MPD announces state update: player Oct 03 17:52:01 localhost volumio[1313]: info: ControllerMpd::getState Oct 03 17:52:01 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:52:01 localhost volumio[1313]: info: Oct 03 17:52:01 localhost volumio[1313]: ---------------------------- MPD announces state update: player Oct 03 17:52:01 localhost volumio[1313]: info: ControllerMpd::getState Oct 03 17:52:01 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:52:01 localhost volumio[1313]: info: sendMpdCommand status took 2 milliseconds Oct 03 17:52:01 localhost volumio[1313]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 03 17:52:01 localhost volumio[1313]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 03 17:52:01 localhost volumio[1313]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 03 17:52:01 localhost volumio[1313]: info: sendMpdCommand status took 1 milliseconds Oct 03 17:52:01 localhost volumio[1313]: info: sendMpdCommand status took 1 milliseconds Oct 03 17:52:01 localhost volumio[1313]: verbose: ControllerMpd::parseState Oct 03 17:52:01 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 17:52:01 localhost volumio[1313]: verbose: ControllerMpd::parseTrackInfo Oct 03 17:52:01 localhost volumio[1313]: verbose: ControllerMpd::parseTrackInfo Oct 03 17:52:01 localhost volumio[1313]: verbose: ControllerMpd::parseTrackInfo Oct 03 17:52:01 localhost volumio[1313]: verbose: ControllerMpd::parseState Oct 03 17:52:01 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 17:52:01 localhost volumio[1313]: verbose: ControllerMpd::parseState Oct 03 17:52:01 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 17:52:01 localhost volumio[1313]: info: ControllerMpd::pushState Oct 03 17:52:01 localhost volumio[1313]: info: CoreCommandRouter::servicePushState Oct 03 17:52:01 localhost volumio[1313]: info: CorePlayQueue::getTrack 12 Oct 03 17:52:01 localhost volumio[1313]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":262,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Holy Wild","artist":"Kelly Boesch","album":"The Holy Wild","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fext-strm-ruvld01mts-01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dd023b945556b54afaccef3fc46df071c5d2582a6a75ea7366b93e123c8dfa66d%2Clid%3D1610%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6acf074c%2F0%2F1749106%2Ffce1ee8d.212705180.5.146239298%2Fflac-mp4&ext=.flac","trackType":"flac"} Oct 03 17:52:01 localhost volumio[1313]: verbose: CURRENT POSITION 12 Oct 03 17:52:01 localhost volumio[1313]: info: CoreStateMachine::syncState stateService play Oct 03 17:52:01 localhost volumio[1313]: info: CoreStateMachine::syncState currentStatus stop Oct 03 17:52:01 localhost volumio[1313]: info: ControllerMpd::pushState Oct 03 17:52:01 localhost volumio[1313]: info: CoreCommandRouter::servicePushState Oct 03 17:52:01 localhost volumio[1313]: info: CorePlayQueue::getTrack 12 Oct 03 17:52:01 localhost volumio[1313]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":262,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Holy Wild","artist":"Kelly Boesch","album":"The Holy Wild","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fext-strm-ruvld01mts-01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dd023b945556b54afaccef3fc46df071c5d2582a6a75ea7366b93e123c8dfa66d%2Clid%3D1610%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6acf074c%2F0%2F1749106%2Ffce1ee8d.212705180.5.146239298%2Fflac-mp4&ext=.flac","trackType":"flac"} Oct 03 17:52:01 localhost volumio[1313]: verbose: CURRENT POSITION 12 Oct 03 17:52:01 localhost volumio[1313]: info: CoreStateMachine::syncState stateService play Oct 03 17:52:01 localhost volumio[1313]: info: CoreStateMachine::syncState currentStatus play Oct 03 17:52:01 localhost volumio[1313]: info: Received an update from plugin. extracting info from payload Oct 03 17:52:01 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:52:01 localhost volumio[1313]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 17:52:01 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:52:01 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:52:01 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:52:01 localhost volumio[1313]: info: ControllerMpd::pushState Oct 03 17:52:01 localhost volumio[1313]: info: CoreCommandRouter::servicePushState Oct 03 17:52:01 localhost volumio[1313]: info: CorePlayQueue::getTrack 12 Oct 03 17:52:01 localhost volumio[1313]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":262,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Holy Wild","artist":"Kelly Boesch","album":"The Holy Wild","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fext-strm-ruvld01mts-01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dd023b945556b54afaccef3fc46df071c5d2582a6a75ea7366b93e123c8dfa66d%2Clid%3D1610%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6acf074c%2F0%2F1749106%2Ffce1ee8d.212705180.5.146239298%2Fflac-mp4&ext=.flac","trackType":"flac"} Oct 03 17:52:01 localhost volumio[1313]: verbose: CURRENT POSITION 12 Oct 03 17:52:01 localhost volumio[1313]: info: CoreStateMachine::syncState stateService play Oct 03 17:52:01 localhost volumio[1313]: info: CoreStateMachine::syncState currentStatus play Oct 03 17:52:01 localhost volumio[1313]: info: Received an update from plugin. extracting info from payload Oct 03 17:52:01 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:52:01 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:52:01 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:52:01 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:52:01 localhost volumio[1313]: info: ------------------------------ 21ms Oct 03 17:52:01 localhost volumio[1313]: info: ------------------------------ 22ms Oct 03 17:52:01 localhost volumio[1313]: info: ------------------------------ 23ms Oct 03 17:52:01 localhost volumio[1313]: info: sendMpdCommand playlistinfo took 8 milliseconds Oct 03 17:52:01 localhost volumio[1313]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 03 17:52:01 localhost volumio[1313]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 03 17:52:01 localhost volumio[1313]: verbose: ControllerMpd::parseTrackInfo Oct 03 17:52:01 localhost volumio[1313]: verbose: ControllerMpd::parseTrackInfo Oct 03 17:52:01 localhost volumio[1313]: verbose: ControllerMpd::parseTrackInfo Oct 03 17:52:01 localhost volumio[1313]: info: ControllerMpd::pushState Oct 03 17:52:01 localhost volumio[1313]: info: CoreCommandRouter::servicePushState Oct 03 17:52:01 localhost volumio[1313]: info: CorePlayQueue::getTrack 12 Oct 03 17:52:01 localhost volumio[1313]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":262,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Holy Wild","artist":"Kelly Boesch","album":"The Holy Wild","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fext-strm-ruvld01mts-01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dd023b945556b54afaccef3fc46df071c5d2582a6a75ea7366b93e123c8dfa66d%2Clid%3D1610%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6acf074c%2F0%2F1749106%2Ffce1ee8d.212705180.5.146239298%2Fflac-mp4&ext=.flac","trackType":"flac"} Oct 03 17:52:01 localhost volumio[1313]: verbose: CURRENT POSITION 12 Oct 03 17:52:01 localhost volumio[1313]: info: CoreStateMachine::syncState stateService play Oct 03 17:52:01 localhost volumio[1313]: info: CoreStateMachine::syncState currentStatus play Oct 03 17:52:01 localhost volumio[1313]: info: Received an update from plugin. extracting info from payload Oct 03 17:52:01 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:52:01 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:52:01 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:52:01 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:52:01 localhost volumio[1313]: info: ControllerMpd::pushState Oct 03 17:52:01 localhost volumio[1313]: info: CoreCommandRouter::servicePushState Oct 03 17:52:01 localhost volumio[1313]: info: CorePlayQueue::getTrack 12 Oct 03 17:52:01 localhost volumio[1313]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":262,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Holy Wild","artist":"Kelly Boesch","album":"The Holy Wild","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fext-strm-ruvld01mts-01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dd023b945556b54afaccef3fc46df071c5d2582a6a75ea7366b93e123c8dfa66d%2Clid%3D1610%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6acf074c%2F0%2F1749106%2Ffce1ee8d.212705180.5.146239298%2Fflac-mp4&ext=.flac","trackType":"flac"} Oct 03 17:52:01 localhost volumio[1313]: verbose: CURRENT POSITION 12 Oct 03 17:52:01 localhost volumio[1313]: info: CoreStateMachine::syncState stateService play Oct 03 17:52:01 localhost volumio[1313]: info: CoreStateMachine::syncState currentStatus play Oct 03 17:52:01 localhost volumio[1313]: info: Received an update from plugin. extracting info from payload Oct 03 17:52:01 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:52:01 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:52:01 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:52:01 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:52:01 localhost volumio[1313]: info: ControllerMpd::pushState Oct 03 17:52:01 localhost volumio[1313]: info: CoreCommandRouter::servicePushState Oct 03 17:52:01 localhost volumio[1313]: info: CorePlayQueue::getTrack 12 Oct 03 17:52:01 localhost volumio[1313]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":262,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Holy Wild","artist":"Kelly Boesch","album":"The Holy Wild","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fext-strm-ruvld01mts-01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dd023b945556b54afaccef3fc46df071c5d2582a6a75ea7366b93e123c8dfa66d%2Clid%3D1610%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6acf074c%2F0%2F1749106%2Ffce1ee8d.212705180.5.146239298%2Fflac-mp4&ext=.flac","trackType":"flac"} Oct 03 17:52:01 localhost volumio[1313]: verbose: CURRENT POSITION 12 Oct 03 17:52:01 localhost volumio[1313]: info: CoreStateMachine::syncState stateService play Oct 03 17:52:01 localhost volumio[1313]: info: CoreStateMachine::syncState currentStatus play Oct 03 17:52:01 localhost volumio[1313]: info: Received an update from plugin. extracting info from payload Oct 03 17:52:01 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:52:01 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:52:01 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:52:01 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:52:01 localhost volumio[1313]: info: ------------------------------ 28ms Oct 03 17:52:01 localhost volumio[1313]: info: ------------------------------ 26ms Oct 03 17:52:01 localhost volumio[1313]: info: ------------------------------ 27ms Oct 03 17:52:01 localhost volumio[1313]: info: CoreCommandRouter::volumioGetQueue Oct 03 17:52:01 localhost volumio[1313]: info: CoreStateMachine::getQueue Oct 03 17:52:01 localhost volumio[1313]: info: CorePlayQueue::getQueue Oct 03 17:52:01 localhost volumio[1313]: info: CoreCommandRouter::volumioGetQueue Oct 03 17:52:01 localhost volumio[1313]: info: CoreStateMachine::getQueue Oct 03 17:52:01 localhost volumio[1313]: info: CorePlayQueue::getQueue Oct 03 17:52:01 localhost volumio[1313]: info: CoreCommandRouter::volumioGetQueue Oct 03 17:52:01 localhost volumio[1313]: info: CoreStateMachine::getQueue Oct 03 17:52:01 localhost volumio[1313]: info: CorePlayQueue::getQueue Oct 03 17:52:01 localhost volumio[1313]: info: CoreCommandRouter::volumioGetQueue Oct 03 17:52:01 localhost volumio[1313]: info: CoreStateMachine::getQueue Oct 03 17:52:01 localhost volumio[1313]: info: CorePlayQueue::getQueue Oct 03 17:52:01 localhost volumio[1313]: info: CoreCommandRouter::volumioGetQueue Oct 03 17:52:01 localhost volumio[1313]: info: CoreStateMachine::getQueue Oct 03 17:52:01 localhost volumio[1313]: info: CorePlayQueue::getQueue Oct 03 17:52:01 localhost volumio[1313]: info: CoreCommandRouter::volumioGetQueue Oct 03 17:52:01 localhost volumio[1313]: info: CoreStateMachine::getQueue Oct 03 17:52:01 localhost volumio[1313]: info: CorePlayQueue::getQueue Oct 03 17:52:01 localhost volumio[1313]: info: CoreCommandRouter::volumioGetQueue Oct 03 17:52:01 localhost volumio[1313]: info: CoreStateMachine::getQueue Oct 03 17:52:01 localhost volumio[1313]: info: CorePlayQueue::getQueue Oct 03 17:52:01 localhost volumio[1313]: info: CoreCommandRouter::volumioGetQueue Oct 03 17:52:01 localhost volumio[1313]: info: CoreStateMachine::getQueue Oct 03 17:52:01 localhost volumio[1313]: info: CorePlayQueue::getQueue Oct 03 17:52:01 localhost volumio[1313]: info: CoreCommandRouter::volumioGetQueue Oct 03 17:52:01 localhost volumio[1313]: info: CoreStateMachine::getQueue Oct 03 17:52:01 localhost volumio[1313]: info: CorePlayQueue::getQueue Oct 03 17:52:01 localhost volumio[1313]: info: CoreCommandRouter::volumioGetQueue Oct 03 17:52:01 localhost volumio[1313]: info: CoreStateMachine::getQueue Oct 03 17:52:01 localhost volumio[1313]: info: CorePlayQueue::getQueue Oct 03 17:52:06 localhost volumio[1313]: info: CoreCommandRouter::volumioSeek Oct 03 17:52:06 localhost volumio[1313]: info: CoreStateMachine::seek Oct 03 17:52:06 localhost volumio[1313]: info: CorePlayQueue::getTrack 12 Oct 03 17:52:06 localhost volumio[1313]: info: TRACKBLOCK {"id":"146239298:39755136@503646255:109423217","service":"yandex_music","type":"song","name":"The Holy Wild","title":"The Holy Wild","album":"The Holy Wild","artist":"Kelly Boesch","duration":262,"albumart":"https://avatars.yandex.net/get-music-content/15406468/4a40531f.a.39755136-1/200x200","uri":"yandex_music/track/146239298:39755136@503646255:109423217","samplerate":"","bitdepth":"","trackType":"","codec":"flac-mp4","bitrate":0} Oct 03 17:52:06 localhost volumio[1313]: info: CoreStateMachine::startPlaybackTimer Oct 03 17:52:06 localhost volumio[1313]: info: CorePlayQueue::getTrack 12 Oct 03 17:52:06 localhost volumio[1313]: info: ControllerMpd::seek Oct 03 17:52:06 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:52:06 localhost volumio[1313]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 17:52:06 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:52:06 localhost volumio[1313]: info: CoreCommandRouter::volumioGetQueue Oct 03 17:52:06 localhost volumio[1313]: info: CoreStateMachine::getQueue Oct 03 17:52:06 localhost volumio[1313]: info: CorePlayQueue::getQueue Oct 03 17:52:07 localhost volumio[1313]: error: null Oct 03 17:52:07 localhost volumio[1313]: info: Oct 03 17:52:07 localhost volumio[1313]: ---------------------------- MPD announces state update: player Oct 03 17:52:07 localhost volumio[1313]: info: ControllerMpd::getState Oct 03 17:52:07 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:52:07 localhost volumio[1313]: info: sendMpdCommand status took 1 milliseconds Oct 03 17:52:07 localhost volumio[1313]: verbose: ControllerMpd::parseState Oct 03 17:52:07 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 17:52:07 localhost volumio[1313]: info: Oct 03 17:52:07 localhost volumio[1313]: ---------------------------- MPD announces state update: player Oct 03 17:52:07 localhost volumio[1313]: info: ControllerMpd::getState Oct 03 17:52:07 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:52:07 localhost volumio[1313]: info: Oct 03 17:52:07 localhost volumio[1313]: ---------------------------- MPD announces state update: player Oct 03 17:52:07 localhost volumio[1313]: info: ControllerMpd::getState Oct 03 17:52:07 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:52:07 localhost volumio[1313]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 03 17:52:07 localhost volumio[1313]: info: sendMpdCommand status took 1 milliseconds Oct 03 17:52:07 localhost volumio[1313]: info: sendMpdCommand status took 1 milliseconds Oct 03 17:52:07 localhost volumio[1313]: verbose: ControllerMpd::parseTrackInfo Oct 03 17:52:07 localhost volumio[1313]: verbose: ControllerMpd::parseState Oct 03 17:52:07 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 17:52:07 localhost volumio[1313]: verbose: ControllerMpd::parseState Oct 03 17:52:07 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 17:52:07 localhost volumio[1313]: info: ControllerMpd::pushState Oct 03 17:52:07 localhost volumio[1313]: info: CoreCommandRouter::servicePushState Oct 03 17:52:07 localhost volumio[1313]: info: CorePlayQueue::getTrack 12 Oct 03 17:52:07 localhost volumio[1313]: verbose: STATE SERVICE {"status":"play","position":0,"seek":29000,"duration":262,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1475 Kbps","isStreaming":false,"title":"The Holy Wild","artist":"Kelly Boesch","album":"The Holy Wild","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fext-strm-ruvld01mts-01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dd023b945556b54afaccef3fc46df071c5d2582a6a75ea7366b93e123c8dfa66d%2Clid%3D1610%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6acf074c%2F0%2F1749106%2Ffce1ee8d.212705180.5.146239298%2Fflac-mp4&ext=.flac","trackType":"flac"} Oct 03 17:52:07 localhost volumio[1313]: verbose: CURRENT POSITION 12 Oct 03 17:52:07 localhost volumio[1313]: info: CoreStateMachine::syncState stateService play Oct 03 17:52:07 localhost volumio[1313]: info: CoreStateMachine::syncState currentStatus play Oct 03 17:52:07 localhost volumio[1313]: info: Received an update from plugin. extracting info from payload Oct 03 17:52:07 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:52:07 localhost volumio[1313]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 17:52:07 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:52:07 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:52:07 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:52:07 localhost volumio[1313]: info: ------------------------------ 7ms Oct 03 17:52:07 localhost volumio[1313]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 03 17:52:07 localhost volumio[1313]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 03 17:52:07 localhost volumio[1313]: verbose: ControllerMpd::parseTrackInfo Oct 03 17:52:07 localhost volumio[1313]: verbose: ControllerMpd::parseTrackInfo Oct 03 17:52:07 localhost volumio[1313]: info: ControllerMpd::pushState Oct 03 17:52:07 localhost volumio[1313]: info: CoreCommandRouter::servicePushState Oct 03 17:52:07 localhost volumio[1313]: info: CorePlayQueue::getTrack 12 Oct 03 17:52:07 localhost volumio[1313]: verbose: STATE SERVICE {"status":"play","position":0,"seek":29000,"duration":262,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1475 Kbps","isStreaming":false,"title":"The Holy Wild","artist":"Kelly Boesch","album":"The Holy Wild","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fext-strm-ruvld01mts-01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dd023b945556b54afaccef3fc46df071c5d2582a6a75ea7366b93e123c8dfa66d%2Clid%3D1610%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6acf074c%2F0%2F1749106%2Ffce1ee8d.212705180.5.146239298%2Fflac-mp4&ext=.flac","trackType":"flac"} Oct 03 17:52:07 localhost volumio[1313]: verbose: CURRENT POSITION 12 Oct 03 17:52:07 localhost volumio[1313]: info: CoreStateMachine::syncState stateService play Oct 03 17:52:07 localhost volumio[1313]: info: CoreStateMachine::syncState currentStatus play Oct 03 17:52:07 localhost volumio[1313]: info: Received an update from plugin. extracting info from payload Oct 03 17:52:07 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:52:07 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:52:07 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:52:07 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:52:07 localhost volumio[1313]: info: ControllerMpd::pushState Oct 03 17:52:07 localhost volumio[1313]: info: CoreCommandRouter::servicePushState Oct 03 17:52:07 localhost volumio[1313]: info: CorePlayQueue::getTrack 12 Oct 03 17:52:07 localhost volumio[1313]: verbose: STATE SERVICE {"status":"play","position":0,"seek":29000,"duration":262,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1475 Kbps","isStreaming":false,"title":"The Holy Wild","artist":"Kelly Boesch","album":"The Holy Wild","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fext-strm-ruvld01mts-01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dd023b945556b54afaccef3fc46df071c5d2582a6a75ea7366b93e123c8dfa66d%2Clid%3D1610%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6acf074c%2F0%2F1749106%2Ffce1ee8d.212705180.5.146239298%2Fflac-mp4&ext=.flac","trackType":"flac"} Oct 03 17:52:07 localhost volumio[1313]: verbose: CURRENT POSITION 12 Oct 03 17:52:07 localhost volumio[1313]: info: CoreStateMachine::syncState stateService play Oct 03 17:52:07 localhost volumio[1313]: info: CoreStateMachine::syncState currentStatus play Oct 03 17:52:07 localhost volumio[1313]: info: Received an update from plugin. extracting info from payload Oct 03 17:52:07 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:52:07 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:52:07 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:52:07 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:52:07 localhost volumio[1313]: info: ------------------------------ 15ms Oct 03 17:52:07 localhost volumio[1313]: info: ------------------------------ 15ms Oct 03 17:52:07 localhost volumio[1313]: info: CoreCommandRouter::volumioGetQueue Oct 03 17:52:07 localhost volumio[1313]: info: CoreStateMachine::getQueue Oct 03 17:52:07 localhost volumio[1313]: info: CorePlayQueue::getQueue Oct 03 17:52:07 localhost volumio[1313]: info: CoreCommandRouter::volumioGetQueue Oct 03 17:52:07 localhost volumio[1313]: info: CoreStateMachine::getQueue Oct 03 17:52:07 localhost volumio[1313]: info: CorePlayQueue::getQueue Oct 03 17:52:07 localhost volumio[1313]: info: CoreCommandRouter::volumioGetQueue Oct 03 17:52:07 localhost volumio[1313]: info: CoreStateMachine::getQueue Oct 03 17:52:07 localhost volumio[1313]: info: CorePlayQueue::getQueue Oct 03 17:52:07 localhost volumio[1313]: info: CoreCommandRouter::volumioGetQueue Oct 03 17:52:07 localhost volumio[1313]: info: CoreStateMachine::getQueue Oct 03 17:52:07 localhost volumio[1313]: info: CorePlayQueue::getQueue Oct 03 17:52:07 localhost volumio[1313]: info: CoreCommandRouter::volumioGetQueue Oct 03 17:52:07 localhost volumio[1313]: info: CoreStateMachine::getQueue Oct 03 17:52:07 localhost volumio[1313]: info: CorePlayQueue::getQueue Oct 03 17:52:07 localhost volumio[1313]: info: CoreCommandRouter::volumioGetQueue Oct 03 17:52:07 localhost volumio[1313]: info: CoreStateMachine::getQueue Oct 03 17:52:07 localhost volumio[1313]: info: CorePlayQueue::getQueue Oct 03 17:52:15 localhost volumio[1313]: info: CoreCommandRouter::volumioSeek Oct 03 17:52:15 localhost volumio[1313]: info: CoreStateMachine::seek Oct 03 17:52:15 localhost volumio[1313]: info: CorePlayQueue::getTrack 12 Oct 03 17:52:15 localhost volumio[1313]: info: TRACKBLOCK {"id":"146239298:39755136@503646255:109423217","service":"yandex_music","type":"song","name":"The Holy Wild","title":"The Holy Wild","album":"The Holy Wild","artist":"Kelly Boesch","duration":262,"albumart":"https://avatars.yandex.net/get-music-content/15406468/4a40531f.a.39755136-1/200x200","uri":"yandex_music/track/146239298:39755136@503646255:109423217","samplerate":"","bitdepth":"","trackType":"","codec":"flac-mp4","bitrate":0} Oct 03 17:52:15 localhost volumio[1313]: info: CoreStateMachine::startPlaybackTimer Oct 03 17:52:15 localhost volumio[1313]: info: CorePlayQueue::getTrack 12 Oct 03 17:52:15 localhost volumio[1313]: info: ControllerMpd::seek Oct 03 17:52:15 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:52:15 localhost volumio[1313]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 17:52:15 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:52:15 localhost volumio[1313]: info: CoreCommandRouter::volumioGetQueue Oct 03 17:52:15 localhost volumio[1313]: info: CoreStateMachine::getQueue Oct 03 17:52:15 localhost volumio[1313]: info: CorePlayQueue::getQueue Oct 03 17:52:16 localhost volumio[1313]: error: null Oct 03 17:52:16 localhost volumio[1313]: info: Oct 03 17:52:16 localhost volumio[1313]: ---------------------------- MPD announces state update: player Oct 03 17:52:16 localhost volumio[1313]: info: ControllerMpd::getState Oct 03 17:52:16 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:52:16 localhost volumio[1313]: info: Oct 03 17:52:16 localhost volumio[1313]: ---------------------------- MPD announces state update: player Oct 03 17:52:16 localhost volumio[1313]: info: ControllerMpd::getState Oct 03 17:52:16 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:52:16 localhost volumio[1313]: info: Oct 03 17:52:16 localhost volumio[1313]: ---------------------------- MPD announces state update: player Oct 03 17:52:16 localhost volumio[1313]: info: ControllerMpd::getState Oct 03 17:52:16 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:52:16 localhost volumio[1313]: info: sendMpdCommand status took 1 milliseconds Oct 03 17:52:16 localhost volumio[1313]: info: sendMpdCommand status took 1 milliseconds Oct 03 17:52:16 localhost volumio[1313]: info: sendMpdCommand status took 1 milliseconds Oct 03 17:52:16 localhost volumio[1313]: verbose: ControllerMpd::parseState Oct 03 17:52:16 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 17:52:16 localhost volumio[1313]: verbose: ControllerMpd::parseState Oct 03 17:52:16 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 17:52:16 localhost volumio[1313]: verbose: ControllerMpd::parseState Oct 03 17:52:16 localhost volumio[1313]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 17:52:16 localhost volumio[1313]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 03 17:52:16 localhost volumio[1313]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 03 17:52:16 localhost volumio[1313]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 03 17:52:16 localhost volumio[1313]: verbose: ControllerMpd::parseTrackInfo Oct 03 17:52:16 localhost volumio[1313]: verbose: ControllerMpd::parseTrackInfo Oct 03 17:52:16 localhost volumio[1313]: verbose: ControllerMpd::parseTrackInfo Oct 03 17:52:16 localhost volumio[1313]: info: ControllerMpd::pushState Oct 03 17:52:16 localhost volumio[1313]: info: CoreCommandRouter::servicePushState Oct 03 17:52:16 localhost volumio[1313]: info: CorePlayQueue::getTrack 12 Oct 03 17:52:16 localhost volumio[1313]: verbose: STATE SERVICE {"status":"play","position":0,"seek":70000,"duration":262,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1475 Kbps","isStreaming":false,"title":"The Holy Wild","artist":"Kelly Boesch","album":"The Holy Wild","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fext-strm-ruvld01mts-01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dd023b945556b54afaccef3fc46df071c5d2582a6a75ea7366b93e123c8dfa66d%2Clid%3D1610%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6acf074c%2F0%2F1749106%2Ffce1ee8d.212705180.5.146239298%2Fflac-mp4&ext=.flac","trackType":"flac"} Oct 03 17:52:16 localhost volumio[1313]: verbose: CURRENT POSITION 12 Oct 03 17:52:16 localhost volumio[1313]: info: CoreStateMachine::syncState stateService play Oct 03 17:52:16 localhost volumio[1313]: info: CoreStateMachine::syncState currentStatus play Oct 03 17:52:16 localhost volumio[1313]: info: Received an update from plugin. extracting info from payload Oct 03 17:52:16 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:52:16 localhost volumio[1313]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 17:52:16 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:52:16 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:52:16 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:52:16 localhost volumio[1313]: info: ControllerMpd::pushState Oct 03 17:52:16 localhost volumio[1313]: info: CoreCommandRouter::servicePushState Oct 03 17:52:16 localhost volumio[1313]: info: CorePlayQueue::getTrack 12 Oct 03 17:52:16 localhost volumio[1313]: verbose: STATE SERVICE {"status":"play","position":0,"seek":70000,"duration":262,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1475 Kbps","isStreaming":false,"title":"The Holy Wild","artist":"Kelly Boesch","album":"The Holy Wild","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fext-strm-ruvld01mts-01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dd023b945556b54afaccef3fc46df071c5d2582a6a75ea7366b93e123c8dfa66d%2Clid%3D1610%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6acf074c%2F0%2F1749106%2Ffce1ee8d.212705180.5.146239298%2Fflac-mp4&ext=.flac","trackType":"flac"} Oct 03 17:52:16 localhost volumio[1313]: verbose: CURRENT POSITION 12 Oct 03 17:52:16 localhost volumio[1313]: info: CoreStateMachine::syncState stateService play Oct 03 17:52:16 localhost volumio[1313]: info: CoreStateMachine::syncState currentStatus play Oct 03 17:52:16 localhost volumio[1313]: info: Received an update from plugin. extracting info from payload Oct 03 17:52:16 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:52:16 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:52:16 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:52:16 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:52:16 localhost volumio[1313]: info: ControllerMpd::pushState Oct 03 17:52:16 localhost volumio[1313]: info: CoreCommandRouter::servicePushState Oct 03 17:52:16 localhost volumio[1313]: info: CorePlayQueue::getTrack 12 Oct 03 17:52:16 localhost volumio[1313]: verbose: STATE SERVICE {"status":"play","position":0,"seek":70000,"duration":262,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1475 Kbps","isStreaming":false,"title":"The Holy Wild","artist":"Kelly Boesch","album":"The Holy Wild","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fext-strm-ruvld01mts-01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dd023b945556b54afaccef3fc46df071c5d2582a6a75ea7366b93e123c8dfa66d%2Clid%3D1610%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6acf074c%2F0%2F1749106%2Ffce1ee8d.212705180.5.146239298%2Fflac-mp4&ext=.flac","trackType":"flac"} Oct 03 17:52:16 localhost volumio[1313]: verbose: CURRENT POSITION 12 Oct 03 17:52:16 localhost volumio[1313]: info: CoreStateMachine::syncState stateService play Oct 03 17:52:16 localhost volumio[1313]: info: CoreStateMachine::syncState currentStatus play Oct 03 17:52:16 localhost volumio[1313]: info: Received an update from plugin. extracting info from payload Oct 03 17:52:16 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:52:16 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:52:16 localhost volumio[1313]: info: CoreStateMachine::pushState Oct 03 17:52:16 localhost volumio[1313]: info: CoreCommandRouter::volumioPushState Oct 03 17:52:16 localhost volumio[1313]: info: ------------------------------ 15ms Oct 03 17:52:16 localhost volumio[1313]: info: CoreCommandRouter::volumioGetQueue Oct 03 17:52:16 localhost volumio[1313]: info: CoreStateMachine::getQueue Oct 03 17:52:16 localhost volumio[1313]: info: CorePlayQueue::getQueue Oct 03 17:52:16 localhost volumio[1313]: info: CoreCommandRouter::volumioGetQueue Oct 03 17:52:16 localhost volumio[1313]: info: CoreStateMachine::getQueue Oct 03 17:52:16 localhost volumio[1313]: info: CorePlayQueue::getQueue Oct 03 17:52:16 localhost volumio[1313]: info: CoreCommandRouter::volumioGetQueue Oct 03 17:52:16 localhost volumio[1313]: info: CoreStateMachine::getQueue Oct 03 17:52:16 localhost volumio[1313]: info: CorePlayQueue::getQueue Oct 03 17:52:16 localhost volumio[1313]: info: CoreCommandRouter::volumioGetQueue Oct 03 17:52:16 localhost volumio[1313]: info: CoreStateMachine::getQueue Oct 03 17:52:16 localhost volumio[1313]: info: CorePlayQueue::getQueue Oct 03 17:52:16 localhost volumio[1313]: info: CoreCommandRouter::volumioGetQueue Oct 03 17:52:16 localhost volumio[1313]: info: CoreStateMachine::getQueue Oct 03 17:52:16 localhost volumio[1313]: info: CorePlayQueue::getQueue Oct 03 17:52:16 localhost volumio[1313]: info: CoreCommandRouter::volumioGetQueue Oct 03 17:52:16 localhost volumio[1313]: info: CoreStateMachine::getQueue Oct 03 17:52:16 localhost volumio[1313]: info: CorePlayQueue::getQueue Oct 03 17:52:27 localhost volumio[1313]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 03 17:52:27 localhost volumio[1313]: Error: read ETIMEDOUT Oct 03 17:52:27 localhost volumio[1313]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:209:20) { Oct 03 17:52:27 localhost volumio[1313]: errno: -110, Oct 03 17:52:27 localhost volumio[1313]: code: 'ETIMEDOUT', Oct 03 17:52:27 localhost volumio[1313]: syscall: 'read' Oct 03 17:52:27 localhost volumio[1313]: } Oct 03 17:52:27 localhost volumio[1313]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 03 17:52:27 localhost sudo[11080]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-10-03 17:51 Oct 03 17:52:27 localhost sudo[11080]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="3dada8b1e619a5feb94867e0865ace17474d7bce" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="30045b259d1a704a832ca7c1460c1fdfa4b723f9" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 05 Mar 2026 09:58:18 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="54b3518c7dd8feba4753e0ad124ce801"