-- Logs begin at Sat 2026-04-11 12:18:21 +07, end at Sat 2026-04-11 22:07:06 +07. -- Apr 11 22:06:11 volumio volumio[956]: info: CoreCommandRouter::volumioNext Apr 11 22:06:11 volumio volumio[956]: info: CoreStateMachine::next Apr 11 22:06:11 volumio volumio[956]: info: ControllerMpd::next Apr 11 22:06:11 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand next Apr 11 22:06:11 volumio volumio[956]: info: Apr 11 22:06:11 volumio volumio[956]: ---------------------------- MPD announces system playlist update Apr 11 22:06:11 volumio volumio[956]: info: Ignoring MPD Status Update Apr 11 22:06:11 volumio volumio[956]: info: Apr 11 22:06:11 volumio volumio[956]: ---------------------------- MPD announces state update: player Apr 11 22:06:11 volumio volumio[956]: info: sendMpdCommand next took 35 milliseconds Apr 11 22:06:11 volumio volumio[956]: info: ControllerMpd::getState Apr 11 22:06:11 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand status Apr 11 22:06:11 volumio volumio[956]: info: Apr 11 22:06:11 volumio volumio[956]: ---------------------------- MPD announces system playlist update Apr 11 22:06:11 volumio volumio[956]: info: Ignoring MPD Status Update Apr 11 22:06:11 volumio volumio[956]: info: Apr 11 22:06:11 volumio volumio[956]: ---------------------------- MPD announces state update: player Apr 11 22:06:11 volumio volumio[956]: info: ControllerMpd::getState Apr 11 22:06:11 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand status Apr 11 22:06:11 volumio volumio[956]: info: Apr 11 22:06:11 volumio volumio[956]: ---------------------------- MPD announces system playlist update Apr 11 22:06:11 volumio volumio[956]: info: Ignoring MPD Status Update Apr 11 22:06:11 volumio volumio[956]: info: Apr 11 22:06:11 volumio volumio[956]: ---------------------------- MPD announces state update: player Apr 11 22:06:11 volumio volumio[956]: info: ControllerMpd::getState Apr 11 22:06:11 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand status Apr 11 22:06:11 volumio volumio[956]: error: updateQueue error: null Apr 11 22:06:11 volumio volumio[956]: error: updateQueue error: null Apr 11 22:06:11 volumio volumio[956]: info: ------------------------------ 9ms Apr 11 22:06:11 volumio volumio[956]: info: sendMpdCommand status took 8 milliseconds Apr 11 22:06:11 volumio volumio[956]: info: ------------------------------ 8ms Apr 11 22:06:11 volumio volumio[956]: info: sendMpdCommand status took 6 milliseconds Apr 11 22:06:11 volumio volumio[956]: verbose: ControllerMpd::parseState Apr 11 22:06:11 volumio volumio[956]: info: ControllerMpd::pushState Apr 11 22:06:11 volumio volumio[956]: info: CoreCommandRouter::servicePushState Apr 11 22:06:11 volumio volumio[956]: info: CoreStateMachine::pushState Apr 11 22:06:11 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 22:06:11 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Apr 11 22:06:11 volumio volumio[956]: info: CorePlayQueue::getTrack 24 Apr 11 22:06:11 volumio volumio[956]: 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} Apr 11 22:06:11 volumio volumio[956]: verbose: CURRENT POSITION 24 Apr 11 22:06:11 volumio volumio[956]: info: CoreStateMachine::syncState stateService stop Apr 11 22:06:11 volumio volumio[956]: info: CoreStateMachine::syncState currentStatus play Apr 11 22:06:11 volumio volumio[956]: info: CoreStateMachine::play index undefined Apr 11 22:06:11 volumio volumio[956]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 22:06:11 volumio volumio[956]: info: CoreStateMachine::pushState Apr 11 22:06:11 volumio volumio[956]: info: CorePlayQueue::getTrack 25 Apr 11 22:06:11 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 22:06:11 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Apr 11 22:06:11 volumio volumio[956]: info: ControllerMpd::pushState Apr 11 22:06:11 volumio volumio[956]: info: CoreCommandRouter::servicePushState Apr 11 22:06:11 volumio volumio[956]: info: CoreStateMachine::pushState Apr 11 22:06:11 volumio volumio[956]: info: CorePlayQueue::getTrack 25 Apr 11 22:06:11 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Apr 11 22:06:11 volumio volumio[956]: info: CorePlayQueue::getTrack 25 Apr 11 22:06:11 volumio volumio[956]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd Apr 11 22:06:11 volumio volumio[956]: info: CorePlayQueue::getTrack 25 Apr 11 22:06:11 volumio volumio[956]: info: CoreStateMachine::startPlaybackTimer Apr 11 22:06:11 volumio volumio[956]: info: CorePlayQueue::getTrack 25 Apr 11 22:06:11 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand stop Apr 11 22:06:11 volumio volumio[956]: info: ------------------------------ 57ms Apr 11 22:06:11 volumio volumio[956]: info: ------------------------------ 55ms Apr 11 22:06:11 volumio volumio[956]: info: CoreStateMachine::pushState Apr 11 22:06:11 volumio volumio[956]: info: CorePlayQueue::getTrack 25 Apr 11 22:06:11 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 22:06:11 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Apr 11 22:06:11 volumio volumio[956]: error: updateQueue error: null Apr 11 22:06:11 volumio volumio[956]: info: ------------------------------ 79ms Apr 11 22:06:11 volumio volumio[956]: info: sendMpdCommand status took 78 milliseconds Apr 11 22:06:11 volumio volumio[956]: info: sendMpdCommand stop took 29 milliseconds Apr 11 22:06:11 volumio volumio[956]: verbose: ControllerMpd::parseState Apr 11 22:06:11 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand clear Apr 11 22:06:11 volumio volumio[956]: info: ControllerMpd::pushState Apr 11 22:06:11 volumio volumio[956]: info: CoreCommandRouter::servicePushState Apr 11 22:06:11 volumio volumio[956]: info: CoreStateMachine::pushState Apr 11 22:06:11 volumio volumio[956]: info: CorePlayQueue::getTrack 25 Apr 11 22:06:11 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Apr 11 22:06:11 volumio volumio[956]: info: CorePlayQueue::getTrack 25 Apr 11 22:06:11 volumio volumio[956]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd Apr 11 22:06:11 volumio volumio[956]: info: ------------------------------ 92ms Apr 11 22:06:11 volumio volumio[956]: info: CoreCommandRouter::volumioGetQueue Apr 11 22:06:11 volumio volumio[956]: info: CoreStateMachine::getQueue Apr 11 22:06:11 volumio volumio[956]: info: CorePlayQueue::getQueue Apr 11 22:06:11 volumio volumio[956]: info: Apr 11 22:06:11 volumio volumio[956]: ---------------------------- MPD announces system playlist update Apr 11 22:06:11 volumio volumio[956]: info: Ignoring MPD Status Update Apr 11 22:06:11 volumio volumio[956]: info: sendMpdCommand clear took 32 milliseconds Apr 11 22:06:11 volumio volumio[956]: info: Apr 11 22:06:11 volumio volumio[956]: ---------------------------- MPD announces system playlist update Apr 11 22:06:11 volumio volumio[956]: info: Ignoring MPD Status Update Apr 11 22:06:11 volumio volumio[956]: info: Apr 11 22:06:11 volumio volumio[956]: ---------------------------- MPD announces system playlist update Apr 11 22:06:11 volumio volumio[956]: info: Ignoring MPD Status Update Apr 11 22:06:11 volumio volumio[956]: error: updateQueue error: null Apr 11 22:06:11 volumio volumio[956]: info: ------------------------------ 13ms Apr 11 22:06:11 volumio volumio[956]: error: updateQueue error: null Apr 11 22:06:11 volumio volumio[956]: info: ------------------------------ 4ms Apr 11 22:06:11 volumio volumio[956]: info: ------------------------------ 3ms Apr 11 22:06:12 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-195.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dc9b35be5bff27fa1ae4a5f98d522a999126b322ece7e6050159adcad765ddabc%2Clid%3D87%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69e7eba2%2F0%2F760086%2F5153f02a.184056580.20.127133755%2Fflac-mp4&ext=.flac" Apr 11 22:06:12 volumio volumio[956]: info: Apr 11 22:06:12 volumio volumio[956]: ---------------------------- MPD announces system playlist update Apr 11 22:06:12 volumio volumio[956]: info: Ignoring MPD Status Update Apr 11 22:06:12 volumio volumio[956]: info: sendMpdCommand addid "http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-195.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dc9b35be5bff27fa1ae4a5f98d522a999126b322ece7e6050159adcad765ddabc%2Clid%3D87%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69e7eba2%2F0%2F760086%2F5153f02a.184056580.20.127133755%2Fflac-mp4&ext=.flac" took 5 milliseconds Apr 11 22:06:12 volumio volumio[956]: verbose: MPD COMMAND [object Object] Apr 11 22:06:12 volumio volumio[956]: verbose: MPD COMMAND [object Object] Apr 11 22:06:12 volumio volumio[956]: info: Apr 11 22:06:12 volumio volumio[956]: ---------------------------- MPD announces system playlist update Apr 11 22:06:12 volumio volumio[956]: info: Ignoring MPD Status Update Apr 11 22:06:12 volumio volumio[956]: info: Apr 11 22:06:12 volumio volumio[956]: ---------------------------- MPD announces system playlist update Apr 11 22:06:12 volumio volumio[956]: info: Ignoring MPD Status Update Apr 11 22:06:12 volumio volumio[956]: info: Apr 11 22:06:12 volumio volumio[956]: ---------------------------- MPD announces system playlist update Apr 11 22:06:12 volumio volumio[956]: info: Ignoring MPD Status Update Apr 11 22:06:12 volumio volumio[956]: info: ------------------------------ 7ms Apr 11 22:06:12 volumio volumio[956]: info: ------------------------------ 5ms Apr 11 22:06:12 volumio volumio[956]: info: ------------------------------ 4ms Apr 11 22:06:12 volumio volumio[956]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 11 22:06:12 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand play Apr 11 22:06:12 volumio volumio[956]: info: Apr 11 22:06:12 volumio volumio[956]: ---------------------------- MPD announces system playlist update Apr 11 22:06:12 volumio volumio[956]: info: Ignoring MPD Status Update Apr 11 22:06:12 volumio volumio[956]: info: Apr 11 22:06:12 volumio volumio[956]: ---------------------------- MPD announces system playlist update Apr 11 22:06:12 volumio volumio[956]: info: Ignoring MPD Status Update Apr 11 22:06:12 volumio volumio[956]: info: ------------------------------ 5ms Apr 11 22:06:12 volumio volumio[956]: info: sendMpdCommand play took 4 milliseconds Apr 11 22:06:12 volumio volumio[956]: info: ------------------------------ 4ms Apr 11 22:06:12 volumio volumio[956]: info: ------------------------------ 3ms Apr 11 22:06:12 volumio volumio[956]: info: Apr 11 22:06:12 volumio volumio[956]: ---------------------------- MPD announces state update: player Apr 11 22:06:12 volumio volumio[956]: info: ControllerMpd::getState Apr 11 22:06:12 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand status Apr 11 22:06:12 volumio volumio[956]: info: Apr 11 22:06:12 volumio volumio[956]: ---------------------------- MPD announces state update: player Apr 11 22:06:12 volumio volumio[956]: info: ControllerMpd::getState Apr 11 22:06:12 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand status Apr 11 22:06:12 volumio volumio[956]: info: Apr 11 22:06:12 volumio volumio[956]: ---------------------------- MPD announces state update: player Apr 11 22:06:12 volumio volumio[956]: info: ControllerMpd::getState Apr 11 22:06:12 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand status Apr 11 22:06:12 volumio volumio[956]: info: Apr 11 22:06:12 volumio volumio[956]: ---------------------------- MPD announces state update: player Apr 11 22:06:12 volumio volumio[956]: info: sendMpdCommand status took 7 milliseconds Apr 11 22:06:12 volumio volumio[956]: info: ControllerMpd::getState Apr 11 22:06:12 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand status Apr 11 22:06:12 volumio volumio[956]: verbose: ControllerMpd::parseState Apr 11 22:06:12 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 22:06:12 volumio volumio[956]: info: sendMpdCommand status took 8 milliseconds Apr 11 22:06:12 volumio volumio[956]: info: sendMpdCommand status took 7 milliseconds Apr 11 22:06:12 volumio volumio[956]: info: sendMpdCommand status took 3 milliseconds Apr 11 22:06:12 volumio volumio[956]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 11 22:06:12 volumio volumio[956]: verbose: ControllerMpd::parseState Apr 11 22:06:12 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 22:06:12 volumio volumio[956]: verbose: ControllerMpd::parseState Apr 11 22:06:12 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 22:06:12 volumio volumio[956]: verbose: ControllerMpd::parseState Apr 11 22:06:12 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 22:06:12 volumio volumio[956]: verbose: ControllerMpd::parseTrackInfo Apr 11 22:06:12 volumio volumio[956]: info: ControllerMpd::pushState Apr 11 22:06:12 volumio volumio[956]: info: CoreCommandRouter::servicePushState Apr 11 22:06:12 volumio volumio[956]: info: CorePlayQueue::getTrack 25 Apr 11 22:06:12 volumio volumio[956]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":252,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Леди Агония","artist":"Sacrothorn","album":"Леди Агония","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-195.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dc9b35be5bff27fa1ae4a5f98d522a999126b322ece7e6050159adcad765ddabc%2Clid%3D87%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69e7eba2%2F0%2F760086%2F5153f02a.184056580.20.127133755%2Fflac-mp4&ext=.flac","trackType":"flac"} Apr 11 22:06:12 volumio volumio[956]: verbose: CURRENT POSITION 25 Apr 11 22:06:12 volumio volumio[956]: info: CoreStateMachine::syncState stateService play Apr 11 22:06:12 volumio volumio[956]: info: CoreStateMachine::syncState currentStatus stop Apr 11 22:06:12 volumio volumio[956]: info: ------------------------------ 15ms Apr 11 22:06:12 volumio volumio[956]: info: Apr 11 22:06:12 volumio volumio[956]: ---------------------------- MPD announces state update: player Apr 11 22:06:12 volumio volumio[956]: info: ControllerMpd::getState Apr 11 22:06:12 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand status Apr 11 22:06:12 volumio volumio[956]: info: Apr 11 22:06:12 volumio volumio[956]: ---------------------------- MPD announces state update: player Apr 11 22:06:12 volumio volumio[956]: info: ControllerMpd::getState Apr 11 22:06:12 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand status Apr 11 22:06:12 volumio volumio[956]: info: sendMpdCommand playlistinfo took 7 milliseconds Apr 11 22:06:12 volumio volumio[956]: info: sendMpdCommand playlistinfo took 7 milliseconds Apr 11 22:06:12 volumio volumio[956]: info: sendMpdCommand playlistinfo took 6 milliseconds Apr 11 22:06:12 volumio volumio[956]: info: sendMpdCommand status took 3 milliseconds Apr 11 22:06:12 volumio volumio[956]: info: sendMpdCommand status took 3 milliseconds Apr 11 22:06:12 volumio volumio[956]: verbose: ControllerMpd::parseTrackInfo Apr 11 22:06:12 volumio volumio[956]: verbose: ControllerMpd::parseTrackInfo Apr 11 22:06:12 volumio volumio[956]: verbose: ControllerMpd::parseTrackInfo Apr 11 22:06:12 volumio volumio[956]: verbose: ControllerMpd::parseState Apr 11 22:06:12 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 22:06:12 volumio volumio[956]: verbose: ControllerMpd::parseState Apr 11 22:06:12 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 22:06:12 volumio volumio[956]: info: ControllerMpd::pushState Apr 11 22:06:12 volumio volumio[956]: info: CoreCommandRouter::servicePushState Apr 11 22:06:12 volumio volumio[956]: info: CorePlayQueue::getTrack 25 Apr 11 22:06:12 volumio volumio[956]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":252,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Леди Агония","artist":"Sacrothorn","album":"Леди Агония","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-195.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dc9b35be5bff27fa1ae4a5f98d522a999126b322ece7e6050159adcad765ddabc%2Clid%3D87%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69e7eba2%2F0%2F760086%2F5153f02a.184056580.20.127133755%2Fflac-mp4&ext=.flac","trackType":"flac"} Apr 11 22:06:12 volumio volumio[956]: verbose: CURRENT POSITION 25 Apr 11 22:06:12 volumio volumio[956]: info: CoreStateMachine::syncState stateService play Apr 11 22:06:12 volumio volumio[956]: info: CoreStateMachine::syncState currentStatus play Apr 11 22:06:12 volumio volumio[956]: info: Received an update from plugin. extracting info from payload Apr 11 22:06:12 volumio volumio[956]: info: CoreStateMachine::pushState Apr 11 22:06:12 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 22:06:12 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Apr 11 22:06:12 volumio volumio[956]: info: CoreStateMachine::pushState Apr 11 22:06:12 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Apr 11 22:06:12 volumio volumio[956]: info: ControllerMpd::pushState Apr 11 22:06:12 volumio volumio[956]: info: CoreCommandRouter::servicePushState Apr 11 22:06:12 volumio volumio[956]: info: CorePlayQueue::getTrack 25 Apr 11 22:06:12 volumio volumio[956]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":252,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Леди Агония","artist":"Sacrothorn","album":"Леди Агония","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-195.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dc9b35be5bff27fa1ae4a5f98d522a999126b322ece7e6050159adcad765ddabc%2Clid%3D87%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69e7eba2%2F0%2F760086%2F5153f02a.184056580.20.127133755%2Fflac-mp4&ext=.flac","trackType":"flac"} Apr 11 22:06:12 volumio volumio[956]: verbose: CURRENT POSITION 25 Apr 11 22:06:12 volumio volumio[956]: info: CoreStateMachine::syncState stateService play Apr 11 22:06:12 volumio volumio[956]: info: CoreStateMachine::syncState currentStatus play Apr 11 22:06:12 volumio volumio[956]: info: Received an update from plugin. extracting info from payload Apr 11 22:06:12 volumio volumio[956]: info: CoreStateMachine::pushState Apr 11 22:06:12 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Apr 11 22:06:12 volumio volumio[956]: info: CoreStateMachine::pushState Apr 11 22:06:12 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Apr 11 22:06:12 volumio volumio[956]: info: ControllerMpd::pushState Apr 11 22:06:12 volumio volumio[956]: info: CoreCommandRouter::servicePushState Apr 11 22:06:12 volumio volumio[956]: info: CorePlayQueue::getTrack 25 Apr 11 22:06:12 volumio volumio[956]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":252,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Леди Агония","artist":"Sacrothorn","album":"Леди Агония","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-195.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dc9b35be5bff27fa1ae4a5f98d522a999126b322ece7e6050159adcad765ddabc%2Clid%3D87%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69e7eba2%2F0%2F760086%2F5153f02a.184056580.20.127133755%2Fflac-mp4&ext=.flac","trackType":"flac"} Apr 11 22:06:12 volumio volumio[956]: verbose: CURRENT POSITION 25 Apr 11 22:06:12 volumio volumio[956]: info: CoreStateMachine::syncState stateService play Apr 11 22:06:12 volumio volumio[956]: info: CoreStateMachine::syncState currentStatus play Apr 11 22:06:12 volumio volumio[956]: info: Received an update from plugin. extracting info from payload Apr 11 22:06:12 volumio volumio[956]: info: CoreStateMachine::pushState Apr 11 22:06:12 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Apr 11 22:06:12 volumio volumio[956]: info: CoreStateMachine::pushState Apr 11 22:06:12 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Apr 11 22:06:12 volumio volumio[956]: info: ------------------------------ 92ms Apr 11 22:06:12 volumio volumio[956]: info: ------------------------------ 92ms Apr 11 22:06:12 volumio volumio[956]: info: ------------------------------ 89ms Apr 11 22:06:12 volumio volumio[956]: info: sendMpdCommand playlistinfo took 76 milliseconds Apr 11 22:06:12 volumio volumio[956]: info: sendMpdCommand playlistinfo took 77 milliseconds Apr 11 22:06:12 volumio volumio[956]: verbose: ControllerMpd::parseTrackInfo Apr 11 22:06:12 volumio volumio[956]: verbose: ControllerMpd::parseTrackInfo Apr 11 22:06:12 volumio volumio[956]: info: ControllerMpd::pushState Apr 11 22:06:12 volumio volumio[956]: info: CoreCommandRouter::servicePushState Apr 11 22:06:12 volumio volumio[956]: info: CorePlayQueue::getTrack 25 Apr 11 22:06:12 volumio volumio[956]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":252,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Леди Агония","artist":"Sacrothorn","album":"Леди Агония","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-195.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dc9b35be5bff27fa1ae4a5f98d522a999126b322ece7e6050159adcad765ddabc%2Clid%3D87%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69e7eba2%2F0%2F760086%2F5153f02a.184056580.20.127133755%2Fflac-mp4&ext=.flac","trackType":"flac"} Apr 11 22:06:12 volumio volumio[956]: verbose: CURRENT POSITION 25 Apr 11 22:06:12 volumio volumio[956]: info: CoreStateMachine::syncState stateService play Apr 11 22:06:12 volumio volumio[956]: info: CoreStateMachine::syncState currentStatus play Apr 11 22:06:12 volumio volumio[956]: info: Received an update from plugin. extracting info from payload Apr 11 22:06:12 volumio volumio[956]: info: CoreStateMachine::pushState Apr 11 22:06:12 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 22:06:12 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Apr 11 22:06:12 volumio volumio[956]: info: CoreStateMachine::pushState Apr 11 22:06:12 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Apr 11 22:06:12 volumio volumio[956]: info: ControllerMpd::pushState Apr 11 22:06:12 volumio volumio[956]: info: CoreCommandRouter::servicePushState Apr 11 22:06:12 volumio volumio[956]: info: CorePlayQueue::getTrack 25 Apr 11 22:06:12 volumio volumio[956]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":252,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Леди Агония","artist":"Sacrothorn","album":"Леди Агония","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-195.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dc9b35be5bff27fa1ae4a5f98d522a999126b322ece7e6050159adcad765ddabc%2Clid%3D87%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69e7eba2%2F0%2F760086%2F5153f02a.184056580.20.127133755%2Fflac-mp4&ext=.flac","trackType":"flac"} Apr 11 22:06:12 volumio volumio[956]: verbose: CURRENT POSITION 25 Apr 11 22:06:12 volumio volumio[956]: info: CoreStateMachine::syncState stateService play Apr 11 22:06:12 volumio volumio[956]: info: CoreStateMachine::syncState currentStatus play Apr 11 22:06:12 volumio volumio[956]: info: Received an update from plugin. extracting info from payload Apr 11 22:06:12 volumio volumio[956]: info: CoreStateMachine::pushState Apr 11 22:06:12 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Apr 11 22:06:12 volumio volumio[956]: info: CoreStateMachine::pushState Apr 11 22:06:12 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Apr 11 22:06:12 volumio volumio[956]: info: ------------------------------ 125ms Apr 11 22:06:12 volumio volumio[956]: info: ------------------------------ 125ms Apr 11 22:06:12 volumio volumio[956]: info: CoreCommandRouter::volumioGetQueue Apr 11 22:06:12 volumio volumio[956]: info: CoreStateMachine::getQueue Apr 11 22:06:12 volumio volumio[956]: info: CorePlayQueue::getQueue Apr 11 22:06:12 volumio volumio[956]: info: CoreCommandRouter::volumioGetQueue Apr 11 22:06:12 volumio volumio[956]: info: CoreStateMachine::getQueue Apr 11 22:06:12 volumio volumio[956]: info: CorePlayQueue::getQueue Apr 11 22:06:12 volumio volumio[956]: info: CoreCommandRouter::volumioGetQueue Apr 11 22:06:12 volumio volumio[956]: info: CoreStateMachine::getQueue Apr 11 22:06:12 volumio volumio[956]: info: CorePlayQueue::getQueue Apr 11 22:06:12 volumio volumio[956]: info: CoreCommandRouter::volumioGetQueue Apr 11 22:06:12 volumio volumio[956]: info: CoreStateMachine::getQueue Apr 11 22:06:12 volumio volumio[956]: info: CorePlayQueue::getQueue Apr 11 22:06:13 volumio volumio[956]: info: CoreCommandRouter::volumioGetQueue Apr 11 22:06:13 volumio volumio[956]: info: CoreStateMachine::getQueue Apr 11 22:06:13 volumio volumio[956]: info: CorePlayQueue::getQueue Apr 11 22:06:13 volumio volumio[956]: info: CoreCommandRouter::volumioGetQueue Apr 11 22:06:13 volumio volumio[956]: info: CoreStateMachine::getQueue Apr 11 22:06:13 volumio volumio[956]: info: CorePlayQueue::getQueue Apr 11 22:06:13 volumio volumio[956]: info: CoreCommandRouter::volumioGetQueue Apr 11 22:06:13 volumio volumio[956]: info: CoreStateMachine::getQueue Apr 11 22:06:13 volumio volumio[956]: info: CorePlayQueue::getQueue Apr 11 22:06:13 volumio volumio[956]: info: CoreCommandRouter::volumioGetQueue Apr 11 22:06:13 volumio volumio[956]: info: CoreStateMachine::getQueue Apr 11 22:06:13 volumio volumio[956]: info: CorePlayQueue::getQueue Apr 11 22:06:13 volumio volumio[956]: info: CoreCommandRouter::volumioGetQueue Apr 11 22:06:13 volumio volumio[956]: info: CoreStateMachine::getQueue Apr 11 22:06:13 volumio volumio[956]: info: CorePlayQueue::getQueue Apr 11 22:06:13 volumio volumio[956]: info: CoreCommandRouter::volumioGetQueue Apr 11 22:06:13 volumio volumio[956]: info: CoreStateMachine::getQueue Apr 11 22:06:13 volumio volumio[956]: info: CorePlayQueue::getQueue Apr 11 22:06:13 volumio volumio[956]: info: CoreCommandRouter::volumioAddQueueItems Apr 11 22:06:13 volumio volumio[956]: info: CoreStateMachine::addQueueItems Apr 11 22:06:13 volumio volumio[956]: info: CorePlayQueue::addQueueItems Apr 11 22:06:13 volumio volumio[956]: info: Preload queue cleared Apr 11 22:06:13 volumio volumio[956]: info: Adding Item to queue: yandex_music/track/147864719:40508522@user:onyourwave Apr 11 22:06:13 volumio volumio[956]: info: Exploding uri yandex_music/track/147864719:40508522@user:onyourwave in service yandex_music Apr 11 22:06:13 volumio volumio[956]: info: CoreCommandRouter::volumioPushQueue Apr 11 22:06:13 volumio volumio[956]: info: CorePlayQueue::saveQueue Apr 11 22:06:13 volumio volumio[956]: info: CoreStateMachine::updateTrackBlock Apr 11 22:06:13 volumio volumio[956]: info: CorePlayQueue::getTrackBlock Apr 11 22:06:31 volumio volumio[956]: info: CoreCommandRouter::volumioNext Apr 11 22:06:31 volumio volumio[956]: info: CoreStateMachine::next Apr 11 22:06:31 volumio volumio[956]: info: ControllerMpd::next Apr 11 22:06:31 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand next Apr 11 22:06:31 volumio volumio[956]: info: sendMpdCommand next took 25 milliseconds Apr 11 22:06:31 volumio volumio[956]: info: Apr 11 22:06:31 volumio volumio[956]: ---------------------------- MPD announces system playlist update Apr 11 22:06:31 volumio volumio[956]: info: Ignoring MPD Status Update Apr 11 22:06:31 volumio volumio[956]: info: Apr 11 22:06:31 volumio volumio[956]: ---------------------------- MPD announces state update: player Apr 11 22:06:31 volumio volumio[956]: info: ControllerMpd::getState Apr 11 22:06:31 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand status Apr 11 22:06:31 volumio volumio[956]: info: Apr 11 22:06:31 volumio volumio[956]: ---------------------------- MPD announces system playlist update Apr 11 22:06:31 volumio volumio[956]: info: Ignoring MPD Status Update Apr 11 22:06:31 volumio volumio[956]: info: Apr 11 22:06:31 volumio volumio[956]: ---------------------------- MPD announces state update: player Apr 11 22:06:31 volumio volumio[956]: info: ControllerMpd::getState Apr 11 22:06:31 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand status Apr 11 22:06:31 volumio volumio[956]: info: Apr 11 22:06:31 volumio volumio[956]: ---------------------------- MPD announces system playlist update Apr 11 22:06:31 volumio volumio[956]: info: Ignoring MPD Status Update Apr 11 22:06:31 volumio volumio[956]: info: Apr 11 22:06:31 volumio volumio[956]: ---------------------------- MPD announces state update: player Apr 11 22:06:31 volumio volumio[956]: info: ControllerMpd::getState Apr 11 22:06:31 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand status Apr 11 22:06:31 volumio volumio[956]: error: updateQueue error: null Apr 11 22:06:31 volumio volumio[956]: error: updateQueue error: null Apr 11 22:06:31 volumio volumio[956]: info: ------------------------------ 7ms Apr 11 22:06:31 volumio volumio[956]: info: sendMpdCommand status took 6 milliseconds Apr 11 22:06:31 volumio volumio[956]: info: ------------------------------ 5ms Apr 11 22:06:31 volumio volumio[956]: info: sendMpdCommand status took 4 milliseconds Apr 11 22:06:31 volumio volumio[956]: info: ------------------------------ 4ms Apr 11 22:06:31 volumio volumio[956]: info: sendMpdCommand status took 4 milliseconds Apr 11 22:06:31 volumio volumio[956]: verbose: ControllerMpd::parseState Apr 11 22:06:31 volumio volumio[956]: verbose: ControllerMpd::parseState Apr 11 22:06:31 volumio volumio[956]: verbose: ControllerMpd::parseState Apr 11 22:06:31 volumio volumio[956]: info: ControllerMpd::pushState Apr 11 22:06:31 volumio volumio[956]: info: CoreCommandRouter::servicePushState Apr 11 22:06:31 volumio volumio[956]: info: CoreStateMachine::pushState Apr 11 22:06:31 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 22:06:31 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Apr 11 22:06:31 volumio volumio[956]: info: CorePlayQueue::getTrack 25 Apr 11 22:06:31 volumio volumio[956]: 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} Apr 11 22:06:31 volumio volumio[956]: verbose: CURRENT POSITION 25 Apr 11 22:06:31 volumio volumio[956]: info: CoreStateMachine::syncState stateService stop Apr 11 22:06:31 volumio volumio[956]: info: CoreStateMachine::syncState currentStatus play Apr 11 22:06:31 volumio volumio[956]: info: CoreStateMachine::play index undefined Apr 11 22:06:31 volumio volumio[956]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 22:06:31 volumio volumio[956]: info: CoreStateMachine::pushState Apr 11 22:06:31 volumio volumio[956]: info: CorePlayQueue::getTrack 26 Apr 11 22:06:31 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 22:06:31 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Apr 11 22:06:31 volumio volumio[956]: info: ControllerMpd::pushState Apr 11 22:06:31 volumio volumio[956]: info: CoreCommandRouter::servicePushState Apr 11 22:06:31 volumio volumio[956]: info: CoreStateMachine::pushState Apr 11 22:06:31 volumio volumio[956]: info: CorePlayQueue::getTrack 26 Apr 11 22:06:31 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Apr 11 22:06:31 volumio volumio[956]: info: CorePlayQueue::getTrack 26 Apr 11 22:06:31 volumio volumio[956]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd Apr 11 22:06:31 volumio volumio[956]: info: ControllerMpd::pushState Apr 11 22:06:31 volumio volumio[956]: info: CoreCommandRouter::servicePushState Apr 11 22:06:31 volumio volumio[956]: info: CoreStateMachine::pushState Apr 11 22:06:31 volumio volumio[956]: info: CorePlayQueue::getTrack 26 Apr 11 22:06:31 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Apr 11 22:06:31 volumio volumio[956]: info: CorePlayQueue::getTrack 26 Apr 11 22:06:31 volumio volumio[956]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd Apr 11 22:06:31 volumio volumio[956]: info: CorePlayQueue::getTrack 26 Apr 11 22:06:31 volumio volumio[956]: info: CoreStateMachine::startPlaybackTimer Apr 11 22:06:31 volumio volumio[956]: info: CorePlayQueue::getTrack 26 Apr 11 22:06:31 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand stop Apr 11 22:06:31 volumio volumio[956]: info: ------------------------------ 61ms Apr 11 22:06:31 volumio volumio[956]: info: ------------------------------ 59ms Apr 11 22:06:31 volumio volumio[956]: info: ------------------------------ 58ms Apr 11 22:06:31 volumio volumio[956]: info: CoreStateMachine::pushState Apr 11 22:06:31 volumio volumio[956]: info: CorePlayQueue::getTrack 26 Apr 11 22:06:31 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 22:06:31 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Apr 11 22:06:31 volumio volumio[956]: info: sendMpdCommand stop took 29 milliseconds Apr 11 22:06:31 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand clear Apr 11 22:06:31 volumio volumio[956]: info: CoreCommandRouter::volumioGetQueue Apr 11 22:06:31 volumio volumio[956]: info: CoreStateMachine::getQueue Apr 11 22:06:31 volumio volumio[956]: info: CorePlayQueue::getQueue Apr 11 22:06:31 volumio volumio[956]: info: Apr 11 22:06:31 volumio volumio[956]: ---------------------------- MPD announces system playlist update Apr 11 22:06:31 volumio volumio[956]: info: Ignoring MPD Status Update Apr 11 22:06:31 volumio volumio[956]: info: sendMpdCommand clear took 14 milliseconds Apr 11 22:06:31 volumio volumio[956]: info: Apr 11 22:06:31 volumio volumio[956]: ---------------------------- MPD announces system playlist update Apr 11 22:06:31 volumio volumio[956]: info: Ignoring MPD Status Update Apr 11 22:06:31 volumio volumio[956]: info: Apr 11 22:06:31 volumio volumio[956]: ---------------------------- MPD announces system playlist update Apr 11 22:06:31 volumio volumio[956]: info: Ignoring MPD Status Update Apr 11 22:06:31 volumio volumio[956]: error: updateQueue error: null Apr 11 22:06:31 volumio volumio[956]: error: updateQueue error: null Apr 11 22:06:31 volumio volumio[956]: info: ------------------------------ 15ms Apr 11 22:06:31 volumio volumio[956]: info: ------------------------------ 4ms Apr 11 22:06:31 volumio volumio[956]: info: ------------------------------ 3ms Apr 11 22:06:31 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fext-strm-rukja02rtk-02.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dd90749b68a73e72ba018250c0c2790b0814c11d0d7f82528960ecd3045f62e0f%2Clid%3D1718%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69e85b37%2F0%2F21604%2Fa5abe938.203771221.6.139339478%2Fflac-mp4&ext=.flac" Apr 11 22:06:31 volumio volumio[956]: info: Apr 11 22:06:31 volumio volumio[956]: ---------------------------- MPD announces system playlist update Apr 11 22:06:31 volumio volumio[956]: info: Ignoring MPD Status Update Apr 11 22:06:31 volumio volumio[956]: info: sendMpdCommand addid "http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fext-strm-rukja02rtk-02.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dd90749b68a73e72ba018250c0c2790b0814c11d0d7f82528960ecd3045f62e0f%2Clid%3D1718%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69e85b37%2F0%2F21604%2Fa5abe938.203771221.6.139339478%2Fflac-mp4&ext=.flac" took 4 milliseconds Apr 11 22:06:31 volumio volumio[956]: verbose: MPD COMMAND [object Object] Apr 11 22:06:31 volumio volumio[956]: verbose: MPD COMMAND [object Object] Apr 11 22:06:31 volumio volumio[956]: info: Apr 11 22:06:31 volumio volumio[956]: ---------------------------- MPD announces system playlist update Apr 11 22:06:31 volumio volumio[956]: info: Ignoring MPD Status Update Apr 11 22:06:31 volumio volumio[956]: info: Apr 11 22:06:31 volumio volumio[956]: ---------------------------- MPD announces system playlist update Apr 11 22:06:31 volumio volumio[956]: info: Ignoring MPD Status Update Apr 11 22:06:31 volumio volumio[956]: info: Apr 11 22:06:31 volumio volumio[956]: ---------------------------- MPD announces system playlist update Apr 11 22:06:31 volumio volumio[956]: info: Ignoring MPD Status Update Apr 11 22:06:31 volumio volumio[956]: info: ------------------------------ 12ms Apr 11 22:06:31 volumio volumio[956]: info: ------------------------------ 4ms Apr 11 22:06:31 volumio volumio[956]: info: ------------------------------ 4ms Apr 11 22:06:31 volumio volumio[956]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 11 22:06:31 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand play Apr 11 22:06:31 volumio volumio[956]: info: Apr 11 22:06:31 volumio volumio[956]: ---------------------------- MPD announces system playlist update Apr 11 22:06:31 volumio volumio[956]: info: Ignoring MPD Status Update Apr 11 22:06:31 volumio volumio[956]: info: Apr 11 22:06:31 volumio volumio[956]: ---------------------------- MPD announces system playlist update Apr 11 22:06:31 volumio volumio[956]: info: Ignoring MPD Status Update Apr 11 22:06:31 volumio volumio[956]: info: ------------------------------ 6ms Apr 11 22:06:31 volumio volumio[956]: info: sendMpdCommand play took 4 milliseconds Apr 11 22:06:31 volumio volumio[956]: info: ------------------------------ 17ms Apr 11 22:06:31 volumio volumio[956]: info: ------------------------------ 16ms Apr 11 22:06:32 volumio volumio[956]: info: Apr 11 22:06:32 volumio volumio[956]: ---------------------------- MPD announces state update: player Apr 11 22:06:32 volumio volumio[956]: info: ControllerMpd::getState Apr 11 22:06:32 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand status Apr 11 22:06:32 volumio volumio[956]: info: Apr 11 22:06:32 volumio volumio[956]: ---------------------------- MPD announces state update: player Apr 11 22:06:32 volumio volumio[956]: info: ControllerMpd::getState Apr 11 22:06:32 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand status Apr 11 22:06:32 volumio volumio[956]: info: Apr 11 22:06:32 volumio volumio[956]: ---------------------------- MPD announces state update: player Apr 11 22:06:32 volumio volumio[956]: info: ControllerMpd::getState Apr 11 22:06:32 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand status Apr 11 22:06:32 volumio volumio[956]: info: Apr 11 22:06:32 volumio volumio[956]: ---------------------------- MPD announces state update: player Apr 11 22:06:32 volumio volumio[956]: info: sendMpdCommand status took 5 milliseconds Apr 11 22:06:32 volumio volumio[956]: info: sendMpdCommand status took 4 milliseconds Apr 11 22:06:32 volumio volumio[956]: info: ControllerMpd::getState Apr 11 22:06:32 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand status Apr 11 22:06:32 volumio volumio[956]: verbose: ControllerMpd::parseState Apr 11 22:06:32 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 22:06:32 volumio volumio[956]: verbose: ControllerMpd::parseState Apr 11 22:06:32 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 22:06:32 volumio volumio[956]: info: sendMpdCommand status took 6 milliseconds Apr 11 22:06:32 volumio volumio[956]: info: sendMpdCommand status took 3 milliseconds Apr 11 22:06:32 volumio volumio[956]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 11 22:06:32 volumio volumio[956]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 11 22:06:32 volumio volumio[956]: verbose: ControllerMpd::parseState Apr 11 22:06:32 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 22:06:32 volumio volumio[956]: verbose: ControllerMpd::parseState Apr 11 22:06:32 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 22:06:32 volumio volumio[956]: verbose: ControllerMpd::parseTrackInfo Apr 11 22:06:32 volumio volumio[956]: verbose: ControllerMpd::parseTrackInfo Apr 11 22:06:32 volumio volumio[956]: info: ControllerMpd::pushState Apr 11 22:06:32 volumio volumio[956]: info: CoreCommandRouter::servicePushState Apr 11 22:06:32 volumio volumio[956]: info: CorePlayQueue::getTrack 26 Apr 11 22:06:32 volumio volumio[956]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":239,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Dance with Me","artist":"Victoria Nordmann","album":"Dance with Me","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fext-strm-rukja02rtk-02.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dd90749b68a73e72ba018250c0c2790b0814c11d0d7f82528960ecd3045f62e0f%2Clid%3D1718%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69e85b37%2F0%2F21604%2Fa5abe938.203771221.6.139339478%2Fflac-mp4&ext=.flac","trackType":"flac"} Apr 11 22:06:32 volumio volumio[956]: verbose: CURRENT POSITION 26 Apr 11 22:06:32 volumio volumio[956]: info: CoreStateMachine::syncState stateService play Apr 11 22:06:32 volumio volumio[956]: info: CoreStateMachine::syncState currentStatus stop Apr 11 22:06:32 volumio volumio[956]: info: ControllerMpd::pushState Apr 11 22:06:32 volumio volumio[956]: info: CoreCommandRouter::servicePushState Apr 11 22:06:32 volumio volumio[956]: info: CorePlayQueue::getTrack 26 Apr 11 22:06:32 volumio volumio[956]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":239,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Dance with Me","artist":"Victoria Nordmann","album":"Dance with Me","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fext-strm-rukja02rtk-02.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dd90749b68a73e72ba018250c0c2790b0814c11d0d7f82528960ecd3045f62e0f%2Clid%3D1718%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69e85b37%2F0%2F21604%2Fa5abe938.203771221.6.139339478%2Fflac-mp4&ext=.flac","trackType":"flac"} Apr 11 22:06:32 volumio volumio[956]: verbose: CURRENT POSITION 26 Apr 11 22:06:32 volumio volumio[956]: info: CoreStateMachine::syncState stateService play Apr 11 22:06:32 volumio volumio[956]: info: CoreStateMachine::syncState currentStatus play Apr 11 22:06:32 volumio volumio[956]: info: Received an update from plugin. extracting info from payload Apr 11 22:06:32 volumio volumio[956]: info: CoreStateMachine::pushState Apr 11 22:06:32 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 22:06:32 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Apr 11 22:06:32 volumio volumio[956]: info: CoreStateMachine::pushState Apr 11 22:06:32 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Apr 11 22:06:32 volumio volumio[956]: info: ------------------------------ 23ms Apr 11 22:06:32 volumio volumio[956]: info: ------------------------------ 38ms Apr 11 22:06:32 volumio volumio[956]: info: Apr 11 22:06:32 volumio volumio[956]: ---------------------------- MPD announces state update: player Apr 11 22:06:32 volumio volumio[956]: info: ControllerMpd::getState Apr 11 22:06:32 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand status Apr 11 22:06:32 volumio volumio[956]: info: Apr 11 22:06:32 volumio volumio[956]: ---------------------------- MPD announces state update: player Apr 11 22:06:32 volumio volumio[956]: info: ControllerMpd::getState Apr 11 22:06:32 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand status Apr 11 22:06:32 volumio volumio[956]: info: sendMpdCommand playlistinfo took 32 milliseconds Apr 11 22:06:32 volumio volumio[956]: info: sendMpdCommand playlistinfo took 32 milliseconds Apr 11 22:06:32 volumio volumio[956]: info: sendMpdCommand status took 3 milliseconds Apr 11 22:06:32 volumio volumio[956]: info: sendMpdCommand status took 2 milliseconds Apr 11 22:06:32 volumio volumio[956]: verbose: ControllerMpd::parseTrackInfo Apr 11 22:06:32 volumio volumio[956]: verbose: ControllerMpd::parseTrackInfo Apr 11 22:06:32 volumio volumio[956]: verbose: ControllerMpd::parseState Apr 11 22:06:32 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 22:06:32 volumio volumio[956]: verbose: ControllerMpd::parseState Apr 11 22:06:32 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 22:06:32 volumio volumio[956]: info: ControllerMpd::pushState Apr 11 22:06:32 volumio volumio[956]: info: CoreCommandRouter::servicePushState Apr 11 22:06:32 volumio volumio[956]: info: CorePlayQueue::getTrack 26 Apr 11 22:06:32 volumio volumio[956]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":239,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Dance with Me","artist":"Victoria Nordmann","album":"Dance with Me","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fext-strm-rukja02rtk-02.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dd90749b68a73e72ba018250c0c2790b0814c11d0d7f82528960ecd3045f62e0f%2Clid%3D1718%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69e85b37%2F0%2F21604%2Fa5abe938.203771221.6.139339478%2Fflac-mp4&ext=.flac","trackType":"flac"} Apr 11 22:06:32 volumio volumio[956]: verbose: CURRENT POSITION 26 Apr 11 22:06:32 volumio volumio[956]: info: CoreStateMachine::syncState stateService play Apr 11 22:06:32 volumio volumio[956]: info: CoreStateMachine::syncState currentStatus play Apr 11 22:06:32 volumio volumio[956]: info: Received an update from plugin. extracting info from payload Apr 11 22:06:32 volumio volumio[956]: info: CoreStateMachine::pushState Apr 11 22:06:32 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Apr 11 22:06:32 volumio volumio[956]: info: CoreStateMachine::pushState Apr 11 22:06:32 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Apr 11 22:06:32 volumio volumio[956]: info: ControllerMpd::pushState Apr 11 22:06:32 volumio volumio[956]: info: CoreCommandRouter::servicePushState Apr 11 22:06:32 volumio volumio[956]: info: CorePlayQueue::getTrack 26 Apr 11 22:06:32 volumio volumio[956]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":239,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Dance with Me","artist":"Victoria Nordmann","album":"Dance with Me","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fext-strm-rukja02rtk-02.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dd90749b68a73e72ba018250c0c2790b0814c11d0d7f82528960ecd3045f62e0f%2Clid%3D1718%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69e85b37%2F0%2F21604%2Fa5abe938.203771221.6.139339478%2Fflac-mp4&ext=.flac","trackType":"flac"} Apr 11 22:06:32 volumio volumio[956]: verbose: CURRENT POSITION 26 Apr 11 22:06:32 volumio volumio[956]: info: CoreStateMachine::syncState stateService play Apr 11 22:06:32 volumio volumio[956]: info: CoreStateMachine::syncState currentStatus play Apr 11 22:06:32 volumio volumio[956]: info: Received an update from plugin. extracting info from payload Apr 11 22:06:32 volumio volumio[956]: info: CoreStateMachine::pushState Apr 11 22:06:32 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Apr 11 22:06:32 volumio volumio[956]: info: CoreStateMachine::pushState Apr 11 22:06:32 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Apr 11 22:06:32 volumio volumio[956]: info: ------------------------------ 76ms Apr 11 22:06:32 volumio volumio[956]: info: ------------------------------ 75ms Apr 11 22:06:32 volumio volumio[956]: info: CoreCommandRouter::volumioGetQueue Apr 11 22:06:32 volumio volumio[956]: info: CoreStateMachine::getQueue Apr 11 22:06:32 volumio volumio[956]: info: CorePlayQueue::getQueue Apr 11 22:06:32 volumio volumio[956]: info: CoreCommandRouter::volumioGetQueue Apr 11 22:06:32 volumio volumio[956]: info: CoreStateMachine::getQueue Apr 11 22:06:32 volumio volumio[956]: info: CorePlayQueue::getQueue Apr 11 22:06:32 volumio volumio[956]: info: CoreCommandRouter::volumioGetQueue Apr 11 22:06:32 volumio volumio[956]: info: CoreStateMachine::getQueue Apr 11 22:06:32 volumio volumio[956]: info: CorePlayQueue::getQueue Apr 11 22:06:32 volumio volumio[956]: info: CoreCommandRouter::volumioGetQueue Apr 11 22:06:32 volumio volumio[956]: info: CoreStateMachine::getQueue Apr 11 22:06:32 volumio volumio[956]: info: CorePlayQueue::getQueue Apr 11 22:06:32 volumio volumio[956]: info: CoreCommandRouter::volumioGetQueue Apr 11 22:06:32 volumio volumio[956]: info: CoreStateMachine::getQueue Apr 11 22:06:32 volumio volumio[956]: info: CorePlayQueue::getQueue Apr 11 22:06:32 volumio volumio[956]: info: CoreCommandRouter::volumioGetQueue Apr 11 22:06:32 volumio volumio[956]: info: CoreStateMachine::getQueue Apr 11 22:06:32 volumio volumio[956]: info: CorePlayQueue::getQueue Apr 11 22:06:33 volumio volumio[956]: info: sendMpdCommand playlistinfo took 946 milliseconds Apr 11 22:06:33 volumio volumio[956]: info: sendMpdCommand playlistinfo took 946 milliseconds Apr 11 22:06:33 volumio volumio[956]: verbose: ControllerMpd::parseTrackInfo Apr 11 22:06:33 volumio volumio[956]: verbose: ControllerMpd::parseTrackInfo Apr 11 22:06:33 volumio volumio[956]: info: ControllerMpd::pushState Apr 11 22:06:33 volumio volumio[956]: info: CoreCommandRouter::servicePushState Apr 11 22:06:33 volumio volumio[956]: info: CorePlayQueue::getTrack 26 Apr 11 22:06:33 volumio volumio[956]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":239,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Dance with Me","artist":"Victoria Nordmann","album":"Dance with Me","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fext-strm-rukja02rtk-02.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dd90749b68a73e72ba018250c0c2790b0814c11d0d7f82528960ecd3045f62e0f%2Clid%3D1718%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69e85b37%2F0%2F21604%2Fa5abe938.203771221.6.139339478%2Fflac-mp4&ext=.flac","trackType":"flac"} Apr 11 22:06:33 volumio volumio[956]: verbose: CURRENT POSITION 26 Apr 11 22:06:33 volumio volumio[956]: info: CoreStateMachine::syncState stateService play Apr 11 22:06:33 volumio volumio[956]: info: CoreStateMachine::syncState currentStatus play Apr 11 22:06:33 volumio volumio[956]: info: Received an update from plugin. extracting info from payload Apr 11 22:06:33 volumio volumio[956]: info: CoreStateMachine::pushState Apr 11 22:06:33 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 22:06:33 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Apr 11 22:06:33 volumio volumio[956]: info: CoreStateMachine::pushState Apr 11 22:06:33 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Apr 11 22:06:33 volumio volumio[956]: info: ControllerMpd::pushState Apr 11 22:06:33 volumio volumio[956]: info: CoreCommandRouter::servicePushState Apr 11 22:06:33 volumio volumio[956]: info: CorePlayQueue::getTrack 26 Apr 11 22:06:33 volumio volumio[956]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":239,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Dance with Me","artist":"Victoria Nordmann","album":"Dance with Me","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fext-strm-rukja02rtk-02.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dd90749b68a73e72ba018250c0c2790b0814c11d0d7f82528960ecd3045f62e0f%2Clid%3D1718%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69e85b37%2F0%2F21604%2Fa5abe938.203771221.6.139339478%2Fflac-mp4&ext=.flac","trackType":"flac"} Apr 11 22:06:33 volumio volumio[956]: verbose: CURRENT POSITION 26 Apr 11 22:06:33 volumio volumio[956]: info: CoreStateMachine::syncState stateService play Apr 11 22:06:33 volumio volumio[956]: info: CoreStateMachine::syncState currentStatus play Apr 11 22:06:33 volumio volumio[956]: info: Received an update from plugin. extracting info from payload Apr 11 22:06:33 volumio volumio[956]: info: CoreStateMachine::pushState Apr 11 22:06:33 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Apr 11 22:06:33 volumio volumio[956]: info: CoreStateMachine::pushState Apr 11 22:06:33 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Apr 11 22:06:33 volumio volumio[956]: info: ------------------------------ 989ms Apr 11 22:06:33 volumio volumio[956]: info: ------------------------------ 989ms Apr 11 22:06:33 volumio volumio[956]: info: CoreCommandRouter::volumioGetQueue Apr 11 22:06:33 volumio volumio[956]: info: CoreStateMachine::getQueue Apr 11 22:06:33 volumio volumio[956]: info: CorePlayQueue::getQueue Apr 11 22:06:33 volumio volumio[956]: info: CoreCommandRouter::volumioGetQueue Apr 11 22:06:33 volumio volumio[956]: info: CoreStateMachine::getQueue Apr 11 22:06:33 volumio volumio[956]: info: CorePlayQueue::getQueue Apr 11 22:06:33 volumio volumio[956]: info: CoreCommandRouter::volumioGetQueue Apr 11 22:06:33 volumio volumio[956]: info: CoreStateMachine::getQueue Apr 11 22:06:33 volumio volumio[956]: info: CorePlayQueue::getQueue Apr 11 22:06:33 volumio volumio[956]: info: CoreCommandRouter::volumioGetQueue Apr 11 22:06:33 volumio volumio[956]: info: CoreStateMachine::getQueue Apr 11 22:06:33 volumio volumio[956]: info: CorePlayQueue::getQueue Apr 11 22:06:34 volumio volumio[956]: info: CoreCommandRouter::volumioAddQueueItems Apr 11 22:06:34 volumio volumio[956]: info: CoreStateMachine::addQueueItems Apr 11 22:06:34 volumio volumio[956]: info: CorePlayQueue::addQueueItems Apr 11 22:06:34 volumio volumio[956]: info: Preload queue cleared Apr 11 22:06:34 volumio volumio[956]: info: Adding Item to queue: yandex_music/track/133303043:34156769@user:onyourwave Apr 11 22:06:34 volumio volumio[956]: info: Exploding uri yandex_music/track/133303043:34156769@user:onyourwave in service yandex_music Apr 11 22:06:34 volumio volumio[956]: info: CoreCommandRouter::volumioAddQueueItems Apr 11 22:06:34 volumio volumio[956]: info: CoreStateMachine::addQueueItems Apr 11 22:06:34 volumio volumio[956]: info: CorePlayQueue::addQueueItems Apr 11 22:06:34 volumio volumio[956]: info: Preload queue cleared Apr 11 22:06:34 volumio volumio[956]: info: Adding Item to queue: yandex_music/track/56188813:8297435@user:onyourwave Apr 11 22:06:34 volumio volumio[956]: info: Exploding uri yandex_music/track/56188813:8297435@user:onyourwave in service yandex_music Apr 11 22:06:34 volumio volumio[956]: info: CoreCommandRouter::volumioAddQueueItems Apr 11 22:06:34 volumio volumio[956]: info: CoreStateMachine::addQueueItems Apr 11 22:06:34 volumio volumio[956]: info: CorePlayQueue::addQueueItems Apr 11 22:06:34 volumio volumio[956]: info: Preload queue cleared Apr 11 22:06:34 volumio volumio[956]: info: Adding Item to queue: yandex_music/track/149616019:41310990@user:onyourwave Apr 11 22:06:34 volumio volumio[956]: info: Exploding uri yandex_music/track/149616019:41310990@user:onyourwave in service yandex_music Apr 11 22:06:34 volumio volumio[956]: info: CoreCommandRouter::volumioPushQueue Apr 11 22:06:34 volumio volumio[956]: info: CorePlayQueue::saveQueue Apr 11 22:06:34 volumio volumio[956]: info: CoreCommandRouter::volumioPushQueue Apr 11 22:06:34 volumio volumio[956]: info: CorePlayQueue::saveQueue Apr 11 22:06:34 volumio volumio[956]: info: CoreCommandRouter::volumioPushQueue Apr 11 22:06:34 volumio volumio[956]: info: CorePlayQueue::saveQueue Apr 11 22:06:34 volumio volumio[956]: info: CoreStateMachine::updateTrackBlock Apr 11 22:06:34 volumio volumio[956]: info: CorePlayQueue::getTrackBlock Apr 11 22:06:34 volumio volumio[956]: info: CoreStateMachine::updateTrackBlock Apr 11 22:06:34 volumio volumio[956]: info: CorePlayQueue::getTrackBlock Apr 11 22:06:34 volumio volumio[956]: info: CoreStateMachine::updateTrackBlock Apr 11 22:06:34 volumio volumio[956]: info: CorePlayQueue::getTrackBlock Apr 11 22:06:58 volumio volumio[956]: info: CoreCommandRouter::volumioPause Apr 11 22:06:58 volumio volumio[956]: info: CoreStateMachine::pause Apr 11 22:06:58 volumio volumio[956]: info: CoreStateMachine::stPlaybackTimer Apr 11 22:06:58 volumio volumio[956]: info: CoreStateMachine::servicePause Apr 11 22:06:58 volumio volumio[956]: info: CorePlayQueue::getTrack 26 Apr 11 22:06:58 volumio volumio[956]: info: CoreCommandRouter::servicePause Apr 11 22:06:58 volumio volumio[956]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 11 22:06:58 volumio volumio[956]: info: ControllerMpd::pause Apr 11 22:06:58 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand pause Apr 11 22:06:58 volumio volumio[956]: info: sendMpdCommand pause took 1 milliseconds Apr 11 22:06:58 volumio volumio[956]: info: Apr 11 22:06:58 volumio volumio[956]: ---------------------------- MPD announces state update: player Apr 11 22:06:58 volumio volumio[956]: info: ControllerMpd::getState Apr 11 22:06:58 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand status Apr 11 22:06:58 volumio volumio[956]: info: Apr 11 22:06:58 volumio volumio[956]: ---------------------------- MPD announces state update: player Apr 11 22:06:58 volumio volumio[956]: info: ControllerMpd::getState Apr 11 22:06:58 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand status Apr 11 22:06:58 volumio volumio[956]: info: Apr 11 22:06:58 volumio volumio[956]: ---------------------------- MPD announces state update: player Apr 11 22:06:58 volumio volumio[956]: info: ControllerMpd::getState Apr 11 22:06:58 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand status Apr 11 22:06:58 volumio volumio[956]: info: sendMpdCommand status took 4 milliseconds Apr 11 22:06:58 volumio volumio[956]: info: sendMpdCommand status took 3 milliseconds Apr 11 22:06:58 volumio volumio[956]: info: sendMpdCommand status took 3 milliseconds Apr 11 22:06:58 volumio volumio[956]: verbose: ControllerMpd::parseState Apr 11 22:06:58 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 22:06:58 volumio volumio[956]: verbose: ControllerMpd::parseState Apr 11 22:06:58 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 22:06:58 volumio volumio[956]: verbose: ControllerMpd::parseState Apr 11 22:06:58 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 22:06:58 volumio volumio[956]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 11 22:06:58 volumio volumio[956]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 11 22:06:58 volumio volumio[956]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 11 22:06:58 volumio volumio[956]: verbose: ControllerMpd::parseTrackInfo Apr 11 22:06:58 volumio volumio[956]: verbose: ControllerMpd::parseTrackInfo Apr 11 22:06:58 volumio volumio[956]: verbose: ControllerMpd::parseTrackInfo Apr 11 22:06:58 volumio volumio[956]: info: ControllerMpd::pushState Apr 11 22:06:58 volumio volumio[956]: info: CoreCommandRouter::servicePushState Apr 11 22:06:58 volumio volumio[956]: info: CorePlayQueue::getTrack 26 Apr 11 22:06:58 volumio volumio[956]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":10537,"duration":239,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1051 Kbps","isStreaming":false,"title":"Dance with Me","artist":"Victoria Nordmann","album":"Dance with Me","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fext-strm-rukja02rtk-02.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dd90749b68a73e72ba018250c0c2790b0814c11d0d7f82528960ecd3045f62e0f%2Clid%3D1718%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69e85b37%2F0%2F21604%2Fa5abe938.203771221.6.139339478%2Fflac-mp4&ext=.flac","trackType":"flac"} Apr 11 22:06:58 volumio volumio[956]: verbose: CURRENT POSITION 26 Apr 11 22:06:58 volumio volumio[956]: info: CoreStateMachine::syncState stateService pause Apr 11 22:06:58 volumio volumio[956]: info: CoreStateMachine::syncState currentStatus pause Apr 11 22:06:58 volumio volumio[956]: info: CoreStateMachine::pushState Apr 11 22:06:58 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 22:06:58 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Apr 11 22:06:58 volumio volumio[956]: info: CoreStateMachine::stPlaybackTimer Apr 11 22:06:58 volumio volumio[956]: info: ControllerMpd::pushState Apr 11 22:06:58 volumio volumio[956]: info: CoreCommandRouter::servicePushState Apr 11 22:06:58 volumio volumio[956]: info: CorePlayQueue::getTrack 26 Apr 11 22:06:58 volumio volumio[956]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":10537,"duration":239,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1051 Kbps","isStreaming":false,"title":"Dance with Me","artist":"Victoria Nordmann","album":"Dance with Me","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fext-strm-rukja02rtk-02.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dd90749b68a73e72ba018250c0c2790b0814c11d0d7f82528960ecd3045f62e0f%2Clid%3D1718%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69e85b37%2F0%2F21604%2Fa5abe938.203771221.6.139339478%2Fflac-mp4&ext=.flac","trackType":"flac"} Apr 11 22:06:58 volumio volumio[956]: verbose: CURRENT POSITION 26 Apr 11 22:06:58 volumio volumio[956]: info: CoreStateMachine::syncState stateService pause Apr 11 22:06:58 volumio volumio[956]: info: CoreStateMachine::syncState currentStatus pause Apr 11 22:06:58 volumio volumio[956]: info: CoreStateMachine::pushState Apr 11 22:06:58 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Apr 11 22:06:58 volumio volumio[956]: info: CoreStateMachine::stPlaybackTimer Apr 11 22:06:58 volumio volumio[956]: info: ControllerMpd::pushState Apr 11 22:06:58 volumio volumio[956]: info: CoreCommandRouter::servicePushState Apr 11 22:06:58 volumio volumio[956]: info: CorePlayQueue::getTrack 26 Apr 11 22:06:58 volumio volumio[956]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":10537,"duration":239,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1051 Kbps","isStreaming":false,"title":"Dance with Me","artist":"Victoria Nordmann","album":"Dance with Me","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fext-strm-rukja02rtk-02.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dd90749b68a73e72ba018250c0c2790b0814c11d0d7f82528960ecd3045f62e0f%2Clid%3D1718%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69e85b37%2F0%2F21604%2Fa5abe938.203771221.6.139339478%2Fflac-mp4&ext=.flac","trackType":"flac"} Apr 11 22:06:58 volumio volumio[956]: verbose: CURRENT POSITION 26 Apr 11 22:06:58 volumio volumio[956]: info: CoreStateMachine::syncState stateService pause Apr 11 22:06:58 volumio volumio[956]: info: CoreStateMachine::syncState currentStatus pause Apr 11 22:06:58 volumio volumio[956]: info: CoreStateMachine::pushState Apr 11 22:06:58 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Apr 11 22:06:58 volumio volumio[956]: info: CoreStateMachine::stPlaybackTimer Apr 11 22:06:58 volumio volumio[956]: info: ------------------------------ 38ms Apr 11 22:06:58 volumio volumio[956]: info: ------------------------------ 38ms Apr 11 22:06:58 volumio volumio[956]: info: ------------------------------ 37ms Apr 11 22:06:58 volumio volumio[956]: info: CoreCommandRouter::volumioGetQueue Apr 11 22:06:58 volumio volumio[956]: info: CoreStateMachine::getQueue Apr 11 22:06:58 volumio volumio[956]: info: CorePlayQueue::getQueue Apr 11 22:06:58 volumio volumio[956]: info: CoreCommandRouter::volumioGetQueue Apr 11 22:06:58 volumio volumio[956]: info: CoreStateMachine::getQueue Apr 11 22:06:58 volumio volumio[956]: info: CorePlayQueue::getQueue Apr 11 22:06:58 volumio volumio[956]: info: CoreCommandRouter::volumioGetQueue Apr 11 22:06:58 volumio volumio[956]: info: CoreStateMachine::getQueue Apr 11 22:06:58 volumio volumio[956]: info: CorePlayQueue::getQueue Apr 11 22:07:02 volumio volumio[956]: info: CoreCommandRouter::volumioPlay Apr 11 22:07:02 volumio volumio[956]: info: CoreStateMachine::play index undefined Apr 11 22:07:02 volumio volumio[956]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 22:07:02 volumio volumio[956]: info: CorePlayQueue::getTrack 26 Apr 11 22:07:02 volumio volumio[956]: info: CoreStateMachine::startPlaybackTimer Apr 11 22:07:02 volumio volumio[956]: info: CorePlayQueue::getTrack 26 Apr 11 22:07:02 volumio volumio[956]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 11 22:07:02 volumio volumio[956]: info: ControllerMpd::resume Apr 11 22:07:02 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand play Apr 11 22:07:02 volumio volumio[956]: info: Apr 11 22:07:02 volumio volumio[956]: ---------------------------- MPD announces state update: player Apr 11 22:07:02 volumio volumio[956]: info: sendMpdCommand play took 7 milliseconds Apr 11 22:07:02 volumio volumio[956]: info: ControllerMpd::getState Apr 11 22:07:02 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand status Apr 11 22:07:02 volumio volumio[956]: info: Apr 11 22:07:02 volumio volumio[956]: ---------------------------- MPD announces state update: player Apr 11 22:07:02 volumio volumio[956]: info: ControllerMpd::getState Apr 11 22:07:02 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand status Apr 11 22:07:02 volumio volumio[956]: info: Apr 11 22:07:02 volumio volumio[956]: ---------------------------- MPD announces state update: player Apr 11 22:07:02 volumio volumio[956]: info: ControllerMpd::getState Apr 11 22:07:02 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand status Apr 11 22:07:02 volumio volumio[956]: info: sendMpdCommand status took 5 milliseconds Apr 11 22:07:02 volumio volumio[956]: info: sendMpdCommand status took 3 milliseconds Apr 11 22:07:02 volumio volumio[956]: info: sendMpdCommand status took 1 milliseconds Apr 11 22:07:02 volumio volumio[956]: verbose: ControllerMpd::parseState Apr 11 22:07:02 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 22:07:02 volumio volumio[956]: verbose: ControllerMpd::parseState Apr 11 22:07:02 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 22:07:02 volumio volumio[956]: verbose: ControllerMpd::parseState Apr 11 22:07:02 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 22:07:02 volumio volumio[956]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 11 22:07:02 volumio volumio[956]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 11 22:07:02 volumio volumio[956]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 11 22:07:02 volumio volumio[956]: verbose: ControllerMpd::parseTrackInfo Apr 11 22:07:02 volumio volumio[956]: verbose: ControllerMpd::parseTrackInfo Apr 11 22:07:02 volumio volumio[956]: info: ControllerMpd::pushState Apr 11 22:07:02 volumio volumio[956]: info: CoreCommandRouter::servicePushState Apr 11 22:07:02 volumio volumio[956]: info: CorePlayQueue::getTrack 26 Apr 11 22:07:02 volumio volumio[956]: verbose: STATE SERVICE {"status":"play","position":0,"seek":10537,"duration":239,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1051 Kbps","isStreaming":false,"title":"Dance with Me","artist":"Victoria Nordmann","album":"Dance with Me","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fext-strm-rukja02rtk-02.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dd90749b68a73e72ba018250c0c2790b0814c11d0d7f82528960ecd3045f62e0f%2Clid%3D1718%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69e85b37%2F0%2F21604%2Fa5abe938.203771221.6.139339478%2Fflac-mp4&ext=.flac","trackType":"flac"} Apr 11 22:07:02 volumio volumio[956]: verbose: CURRENT POSITION 26 Apr 11 22:07:02 volumio volumio[956]: info: CoreStateMachine::syncState stateService play Apr 11 22:07:02 volumio volumio[956]: info: CoreStateMachine::syncState currentStatus pause Apr 11 22:07:02 volumio volumio[956]: info: CoreStateMachine::pushState Apr 11 22:07:02 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 22:07:02 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Apr 11 22:07:02 volumio volumio[956]: info: ControllerMpd::pushState Apr 11 22:07:02 volumio volumio[956]: info: CoreCommandRouter::servicePushState Apr 11 22:07:02 volumio volumio[956]: info: CorePlayQueue::getTrack 26 Apr 11 22:07:02 volumio volumio[956]: verbose: STATE SERVICE {"status":"play","position":0,"seek":10537,"duration":239,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1051 Kbps","isStreaming":false,"title":"Dance with Me","artist":"Victoria Nordmann","album":"Dance with Me","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fext-strm-rukja02rtk-02.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dd90749b68a73e72ba018250c0c2790b0814c11d0d7f82528960ecd3045f62e0f%2Clid%3D1718%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69e85b37%2F0%2F21604%2Fa5abe938.203771221.6.139339478%2Fflac-mp4&ext=.flac","trackType":"flac"} Apr 11 22:07:02 volumio volumio[956]: verbose: CURRENT POSITION 26 Apr 11 22:07:02 volumio volumio[956]: info: CoreStateMachine::syncState stateService play Apr 11 22:07:02 volumio volumio[956]: info: CoreStateMachine::syncState currentStatus play Apr 11 22:07:02 volumio volumio[956]: info: Received an update from plugin. extracting info from payload Apr 11 22:07:02 volumio volumio[956]: info: CoreStateMachine::pushState Apr 11 22:07:02 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 22:07:02 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Apr 11 22:07:02 volumio volumio[956]: info: CoreStateMachine::pushState Apr 11 22:07:02 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Apr 11 22:07:02 volumio volumio[956]: info: ControllerMpd::pushState Apr 11 22:07:02 volumio volumio[956]: info: CoreCommandRouter::servicePushState Apr 11 22:07:02 volumio volumio[956]: info: CorePlayQueue::getTrack 26 Apr 11 22:07:02 volumio volumio[956]: verbose: STATE SERVICE {"status":"play","position":0,"seek":10537,"duration":239,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1051 Kbps","isStreaming":false,"title":"Dance with Me","artist":"Victoria Nordmann","album":"Dance with Me","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fext-strm-rukja02rtk-02.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dd90749b68a73e72ba018250c0c2790b0814c11d0d7f82528960ecd3045f62e0f%2Clid%3D1718%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69e85b37%2F0%2F21604%2Fa5abe938.203771221.6.139339478%2Fflac-mp4&ext=.flac","trackType":"flac"} Apr 11 22:07:02 volumio volumio[956]: verbose: CURRENT POSITION 26 Apr 11 22:07:02 volumio volumio[956]: info: CoreStateMachine::syncState stateService play Apr 11 22:07:02 volumio volumio[956]: info: CoreStateMachine::syncState currentStatus play Apr 11 22:07:02 volumio volumio[956]: info: Received an update from plugin. extracting info from payload Apr 11 22:07:02 volumio volumio[956]: info: CoreStateMachine::pushState Apr 11 22:07:02 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Apr 11 22:07:02 volumio volumio[956]: info: CoreStateMachine::pushState Apr 11 22:07:02 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Apr 11 22:07:02 volumio volumio[956]: info: ------------------------------ 59ms Apr 11 22:07:02 volumio volumio[956]: info: ------------------------------ 57ms Apr 11 22:07:02 volumio volumio[956]: info: ------------------------------ 57ms Apr 11 22:07:02 volumio volumio[956]: info: CoreCommandRouter::volumioGetQueue Apr 11 22:07:02 volumio volumio[956]: info: CoreStateMachine::getQueue Apr 11 22:07:02 volumio volumio[956]: info: CorePlayQueue::getQueue Apr 11 22:07:02 volumio volumio[956]: info: CoreCommandRouter::volumioGetQueue Apr 11 22:07:02 volumio volumio[956]: info: CoreStateMachine::getQueue Apr 11 22:07:02 volumio volumio[956]: info: CorePlayQueue::getQueue Apr 11 22:07:02 volumio volumio[956]: info: CoreCommandRouter::volumioGetQueue Apr 11 22:07:02 volumio volumio[956]: info: CoreStateMachine::getQueue Apr 11 22:07:02 volumio volumio[956]: info: CorePlayQueue::getQueue Apr 11 22:07:02 volumio volumio[956]: info: CoreCommandRouter::volumioGetQueue Apr 11 22:07:02 volumio volumio[956]: info: CoreStateMachine::getQueue Apr 11 22:07:02 volumio volumio[956]: info: CorePlayQueue::getQueue Apr 11 22:07:02 volumio volumio[956]: info: CoreCommandRouter::volumioGetQueue Apr 11 22:07:02 volumio volumio[956]: info: CoreStateMachine::getQueue Apr 11 22:07:02 volumio volumio[956]: info: CorePlayQueue::getQueue Apr 11 22:07:05 volumio volumio[956]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 11 22:07:05 volumio volumio[956]: Error: read ECONNRESET Apr 11 22:07:05 volumio volumio[956]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:209:20) { Apr 11 22:07:05 volumio volumio[956]: errno: -104, Apr 11 22:07:05 volumio volumio[956]: code: 'ECONNRESET', Apr 11 22:07:05 volumio volumio[956]: syscall: 'read' Apr 11 22:07:05 volumio volumio[956]: } Apr 11 22:07:05 volumio volumio[956]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 11 22:07:06 volumio sudo[16908]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-04-11 22:06 Apr 11 22:07:06 volumio sudo[16908]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 05:43:24 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="15d0241b0cd805792809f3ddf4a0fc4e"