-- Logs begin at Thu 2019-02-14 13:11:58 MSK, end at Sun 2025-01-12 23:14:37 MSK. -- Jan 12 23:13:00 volumio volumio[1009]: info: CoreCommandRouter::volumioNext Jan 12 23:13:00 volumio volumio[1009]: info: CoreStateMachine::next Jan 12 23:13:00 volumio volumio[1009]: info: ControllerMpd::next Jan 12 23:13:00 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand next Jan 12 23:13:00 volumio volumio[1009]: info: sendMpdCommand next took 31 milliseconds Jan 12 23:13:00 volumio volumio[1009]: info: Jan 12 23:13:00 volumio volumio[1009]: ---------------------------- MPD announces system playlist update Jan 12 23:13:00 volumio volumio[1009]: info: Ignoring MPD Status Update Jan 12 23:13:00 volumio volumio[1009]: info: Jan 12 23:13:00 volumio volumio[1009]: ---------------------------- MPD announces state update: player Jan 12 23:13:00 volumio volumio[1009]: info: ControllerMpd::getState Jan 12 23:13:00 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Jan 12 23:13:00 volumio volumio[1009]: error: updateQueue error: null Jan 12 23:13:00 volumio volumio[1009]: info: ------------------------------ 2ms Jan 12 23:13:00 volumio volumio[1009]: info: sendMpdCommand status took 1 milliseconds Jan 12 23:13:00 volumio volumio[1009]: verbose: ControllerMpd::parseState Jan 12 23:13:00 volumio volumio[1009]: info: ControllerMpd::pushState Jan 12 23:13:00 volumio volumio[1009]: info: CoreCommandRouter::servicePushState Jan 12 23:13:00 volumio volumio[1009]: info: CoreStateMachine::pushState Jan 12 23:13:00 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 23:13:00 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jan 12 23:13:00 volumio volumio[1009]: info: CorePlayQueue::getTrack 19 Jan 12 23:13:00 volumio volumio[1009]: 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} Jan 12 23:13:00 volumio volumio[1009]: verbose: CURRENT POSITION 19 Jan 12 23:13:00 volumio volumio[1009]: info: CoreStateMachine::syncState stateService stop Jan 12 23:13:00 volumio volumio[1009]: info: CoreStateMachine::syncState currentStatus play Jan 12 23:13:00 volumio volumio[1009]: info: CoreStateMachine::play index undefined Jan 12 23:13:00 volumio volumio[1009]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 23:13:00 volumio volumio[1009]: info: CoreStateMachine::pushState Jan 12 23:13:00 volumio volumio[1009]: info: CorePlayQueue::getTrack 20 Jan 12 23:13:00 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 23:13:00 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jan 12 23:13:00 volumio volumio[1009]: info: CorePlayQueue::getTrack 20 Jan 12 23:13:00 volumio volumio[1009]: info: CoreStateMachine::startPlaybackTimer Jan 12 23:13:00 volumio volumio[1009]: info: CorePlayQueue::getTrack 20 Jan 12 23:13:00 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand stop Jan 12 23:13:00 volumio volumio[1009]: info: ------------------------------ 10ms Jan 12 23:13:00 volumio volumio[1009]: info: CoreStateMachine::pushState Jan 12 23:13:00 volumio volumio[1009]: info: CorePlayQueue::getTrack 20 Jan 12 23:13:00 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 23:13:00 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jan 12 23:13:00 volumio volumio[1009]: info: sendMpdCommand stop took 15 milliseconds Jan 12 23:13:00 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand clear Jan 12 23:13:00 volumio volumio[1009]: info: CoreCommandRouter::volumioGetQueue Jan 12 23:13:00 volumio volumio[1009]: info: CoreStateMachine::getQueue Jan 12 23:13:00 volumio volumio[1009]: info: CorePlayQueue::getQueue Jan 12 23:13:00 volumio volumio[1009]: info: Jan 12 23:13:00 volumio volumio[1009]: ---------------------------- MPD announces system playlist update Jan 12 23:13:00 volumio volumio[1009]: info: Ignoring MPD Status Update Jan 12 23:13:00 volumio volumio[1009]: info: sendMpdCommand clear took 22 milliseconds Jan 12 23:13:00 volumio volumio[1009]: error: updateQueue error: null Jan 12 23:13:00 volumio volumio[1009]: info: ------------------------------ 28ms Jan 12 23:13:00 volumio volumio[1009]: info: touch_display: Setting screensaver timeout to 0 seconds. Jan 12 23:13:00 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fext-strm-simfcrelcom01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D4262f6b53d5aa724d767d1a462209a84ca963e683316c5f566743695f062ba50%2Clid%3D1584%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6791b4cc%2F46963%2F16235%2F2af4f8fc.54247308.13.36041552%2F320.mp3&ext=.mp3" Jan 12 23:13:00 volumio volumio[1009]: info: Jan 12 23:13:00 volumio volumio[1009]: ---------------------------- MPD announces system playlist update Jan 12 23:13:00 volumio volumio[1009]: info: Ignoring MPD Status Update Jan 12 23:13:00 volumio volumio[1009]: info: sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fext-strm-simfcrelcom01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D4262f6b53d5aa724d767d1a462209a84ca963e683316c5f566743695f062ba50%2Clid%3D1584%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6791b4cc%2F46963%2F16235%2F2af4f8fc.54247308.13.36041552%2F320.mp3&ext=.mp3" took 1 milliseconds Jan 12 23:13:00 volumio volumio[1009]: verbose: MPD COMMAND [object Object] Jan 12 23:13:00 volumio volumio[1009]: verbose: MPD COMMAND [object Object] Jan 12 23:13:00 volumio volumio[1009]: info: Jan 12 23:13:00 volumio volumio[1009]: ---------------------------- MPD announces system playlist update Jan 12 23:13:00 volumio volumio[1009]: info: Ignoring MPD Status Update Jan 12 23:13:00 volumio volumio[1009]: info: ------------------------------ 2ms Jan 12 23:13:00 volumio volumio[1009]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 12 23:13:00 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand play Jan 12 23:13:00 volumio volumio[1009]: info: ------------------------------ 1ms Jan 12 23:13:00 volumio volumio[1009]: info: sendMpdCommand play took 1 milliseconds Jan 12 23:13:00 volumio volumio[1009]: info: Jan 12 23:13:00 volumio volumio[1009]: ---------------------------- MPD announces state update: player Jan 12 23:13:00 volumio volumio[1009]: info: ControllerMpd::getState Jan 12 23:13:00 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Jan 12 23:13:00 volumio volumio[1009]: info: Jan 12 23:13:00 volumio volumio[1009]: ---------------------------- MPD announces state update: player Jan 12 23:13:00 volumio volumio[1009]: info: sendMpdCommand status took 65 milliseconds Jan 12 23:13:00 volumio volumio[1009]: info: ControllerMpd::getState Jan 12 23:13:00 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Jan 12 23:13:00 volumio volumio[1009]: verbose: ControllerMpd::parseState Jan 12 23:13:00 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 23:13:00 volumio volumio[1009]: info: sendMpdCommand status took 1 milliseconds Jan 12 23:13:00 volumio volumio[1009]: verbose: ControllerMpd::parseState Jan 12 23:13:00 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 23:13:00 volumio volumio[1009]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 12 23:13:00 volumio volumio[1009]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 12 23:13:00 volumio volumio[1009]: verbose: ControllerMpd::parseTrackInfo Jan 12 23:13:00 volumio volumio[1009]: verbose: ControllerMpd::parseTrackInfo Jan 12 23:13:00 volumio volumio[1009]: info: ControllerMpd::pushState Jan 12 23:13:00 volumio volumio[1009]: info: CoreCommandRouter::servicePushState Jan 12 23:13:00 volumio volumio[1009]: info: CorePlayQueue::getTrack 20 Jan 12 23:13:00 volumio volumio[1009]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":302,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"2000 лет","artist":"Демо","album":"The Best","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fext-strm-simfcrelcom01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D4262f6b53d5aa724d767d1a462209a84ca963e683316c5f566743695f062ba50%2Clid%3D1584%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6791b4cc%2F46963%2F16235%2F2af4f8fc.54247308.13.36041552%2F320.mp3&ext=.mp3","trackType":"mp3"} Jan 12 23:13:00 volumio volumio[1009]: verbose: CURRENT POSITION 20 Jan 12 23:13:00 volumio volumio[1009]: info: CoreStateMachine::syncState stateService play Jan 12 23:13:00 volumio volumio[1009]: info: CoreStateMachine::syncState currentStatus stop Jan 12 23:13:00 volumio volumio[1009]: info: ControllerMpd::pushState Jan 12 23:13:00 volumio volumio[1009]: info: CoreCommandRouter::servicePushState Jan 12 23:13:00 volumio volumio[1009]: info: CorePlayQueue::getTrack 20 Jan 12 23:13:00 volumio volumio[1009]: verbose: STATE SERVICE {"status":"play","position":0,"seek":757,"duration":302,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"2000 лет","artist":"Демо","album":"The Best","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fext-strm-simfcrelcom01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D4262f6b53d5aa724d767d1a462209a84ca963e683316c5f566743695f062ba50%2Clid%3D1584%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6791b4cc%2F46963%2F16235%2F2af4f8fc.54247308.13.36041552%2F320.mp3&ext=.mp3","trackType":"mp3"} Jan 12 23:13:00 volumio volumio[1009]: verbose: CURRENT POSITION 20 Jan 12 23:13:00 volumio volumio[1009]: info: CoreStateMachine::syncState stateService play Jan 12 23:13:00 volumio volumio[1009]: info: CoreStateMachine::syncState currentStatus play Jan 12 23:13:00 volumio volumio[1009]: info: Received an update from plugin. extracting info from payload Jan 12 23:13:00 volumio volumio[1009]: info: CoreStateMachine::pushState Jan 12 23:13:00 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 23:13:00 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jan 12 23:13:00 volumio volumio[1009]: info: CoreStateMachine::pushState Jan 12 23:13:00 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jan 12 23:13:00 volumio volumio[1009]: info: ------------------------------ 74ms Jan 12 23:13:00 volumio volumio[1009]: info: ------------------------------ 11ms Jan 12 23:13:00 volumio volumio[1009]: info: CoreCommandRouter::volumioGetQueue Jan 12 23:13:00 volumio volumio[1009]: info: CoreStateMachine::getQueue Jan 12 23:13:00 volumio volumio[1009]: info: CorePlayQueue::getQueue Jan 12 23:13:00 volumio volumio[1009]: info: CoreCommandRouter::volumioGetQueue Jan 12 23:13:00 volumio volumio[1009]: info: CoreStateMachine::getQueue Jan 12 23:13:00 volumio volumio[1009]: info: CorePlayQueue::getQueue Jan 12 23:13:01 volumio volumio[1009]: info: CoreCommandRouter::volumioAddQueueItems Jan 12 23:13:01 volumio volumio[1009]: info: CoreStateMachine::addQueueItems Jan 12 23:13:01 volumio volumio[1009]: info: CorePlayQueue::addQueueItems Jan 12 23:13:01 volumio volumio[1009]: info: Preload queue cleared Jan 12 23:13:01 volumio volumio[1009]: info: Adding Item to queue: yandex_music/track/333245:33558@micro-genre:europop Jan 12 23:13:01 volumio volumio[1009]: info: Exploding uri yandex_music/track/333245:33558@micro-genre:europop in service yandex_music Jan 12 23:13:01 volumio volumio[1009]: info: CoreCommandRouter::volumioAddQueueItems Jan 12 23:13:01 volumio volumio[1009]: info: CoreStateMachine::addQueueItems Jan 12 23:13:01 volumio volumio[1009]: info: CorePlayQueue::addQueueItems Jan 12 23:13:01 volumio volumio[1009]: info: Preload queue cleared Jan 12 23:13:01 volumio volumio[1009]: info: Adding Item to queue: yandex_music/track/31441390:3810621@micro-genre:europop Jan 12 23:13:01 volumio volumio[1009]: info: Exploding uri yandex_music/track/31441390:3810621@micro-genre:europop in service yandex_music Jan 12 23:13:01 volumio volumio[1009]: info: CoreCommandRouter::volumioAddQueueItems Jan 12 23:13:01 volumio volumio[1009]: info: CoreStateMachine::addQueueItems Jan 12 23:13:01 volumio volumio[1009]: info: CorePlayQueue::addQueueItems Jan 12 23:13:01 volumio volumio[1009]: info: Preload queue cleared Jan 12 23:13:01 volumio volumio[1009]: info: Adding Item to queue: yandex_music/track/26421832:3143481@micro-genre:europop Jan 12 23:13:01 volumio volumio[1009]: info: Exploding uri yandex_music/track/26421832:3143481@micro-genre:europop in service yandex_music Jan 12 23:13:01 volumio volumio[1009]: info: CoreCommandRouter::volumioPushQueue Jan 12 23:13:01 volumio volumio[1009]: info: CorePlayQueue::saveQueue Jan 12 23:13:01 volumio volumio[1009]: info: CoreCommandRouter::volumioPushQueue Jan 12 23:13:01 volumio volumio[1009]: info: CorePlayQueue::saveQueue Jan 12 23:13:01 volumio volumio[1009]: info: CoreCommandRouter::volumioPushQueue Jan 12 23:13:01 volumio volumio[1009]: info: CorePlayQueue::saveQueue Jan 12 23:13:01 volumio volumio[1009]: info: CoreStateMachine::updateTrackBlock Jan 12 23:13:01 volumio volumio[1009]: info: CorePlayQueue::getTrackBlock Jan 12 23:13:01 volumio volumio[1009]: info: CoreStateMachine::updateTrackBlock Jan 12 23:13:01 volumio volumio[1009]: info: CorePlayQueue::getTrackBlock Jan 12 23:13:01 volumio volumio[1009]: info: CoreStateMachine::updateTrackBlock Jan 12 23:13:01 volumio volumio[1009]: info: CorePlayQueue::getTrackBlock Jan 12 23:13:44 volumio volumio[1009]: info: CoreCommandRouter::volumioNext Jan 12 23:13:44 volumio volumio[1009]: info: CoreStateMachine::next Jan 12 23:13:44 volumio volumio[1009]: info: ControllerMpd::next Jan 12 23:13:44 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand next Jan 12 23:13:44 volumio volumio[1009]: info: Jan 12 23:13:44 volumio volumio[1009]: ---------------------------- MPD announces system playlist update Jan 12 23:13:44 volumio volumio[1009]: info: Ignoring MPD Status Update Jan 12 23:13:44 volumio volumio[1009]: info: Jan 12 23:13:44 volumio volumio[1009]: ---------------------------- MPD announces state update: player Jan 12 23:13:44 volumio volumio[1009]: info: sendMpdCommand next took 37 milliseconds Jan 12 23:13:44 volumio volumio[1009]: info: ControllerMpd::getState Jan 12 23:13:44 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Jan 12 23:13:44 volumio volumio[1009]: error: updateQueue error: null Jan 12 23:13:44 volumio volumio[1009]: info: ------------------------------ 2ms Jan 12 23:13:44 volumio volumio[1009]: info: sendMpdCommand status took 1 milliseconds Jan 12 23:13:44 volumio volumio[1009]: verbose: ControllerMpd::parseState Jan 12 23:13:44 volumio volumio[1009]: info: ControllerMpd::pushState Jan 12 23:13:44 volumio volumio[1009]: info: CoreCommandRouter::servicePushState Jan 12 23:13:44 volumio volumio[1009]: info: CoreStateMachine::pushState Jan 12 23:13:44 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 23:13:44 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jan 12 23:13:44 volumio volumio[1009]: info: CorePlayQueue::getTrack 20 Jan 12 23:13:44 volumio volumio[1009]: 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} Jan 12 23:13:44 volumio volumio[1009]: verbose: CURRENT POSITION 20 Jan 12 23:13:44 volumio volumio[1009]: info: CoreStateMachine::syncState stateService stop Jan 12 23:13:44 volumio volumio[1009]: info: CoreStateMachine::syncState currentStatus play Jan 12 23:13:44 volumio volumio[1009]: info: CoreStateMachine::play index undefined Jan 12 23:13:44 volumio volumio[1009]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 23:13:44 volumio volumio[1009]: info: CoreStateMachine::pushState Jan 12 23:13:44 volumio volumio[1009]: info: CorePlayQueue::getTrack 21 Jan 12 23:13:44 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 23:13:44 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jan 12 23:13:44 volumio volumio[1009]: info: CorePlayQueue::getTrack 21 Jan 12 23:13:44 volumio volumio[1009]: info: CoreStateMachine::startPlaybackTimer Jan 12 23:13:44 volumio volumio[1009]: info: CorePlayQueue::getTrack 21 Jan 12 23:13:44 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand stop Jan 12 23:13:44 volumio volumio[1009]: info: ------------------------------ 13ms Jan 12 23:13:44 volumio volumio[1009]: info: CoreStateMachine::pushState Jan 12 23:13:44 volumio volumio[1009]: info: CorePlayQueue::getTrack 21 Jan 12 23:13:44 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 23:13:44 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jan 12 23:13:44 volumio volumio[1009]: info: sendMpdCommand stop took 14 milliseconds Jan 12 23:13:44 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand clear Jan 12 23:13:44 volumio volumio[1009]: info: CoreCommandRouter::volumioGetQueue Jan 12 23:13:44 volumio volumio[1009]: info: CoreStateMachine::getQueue Jan 12 23:13:44 volumio volumio[1009]: info: CorePlayQueue::getQueue Jan 12 23:13:44 volumio volumio[1009]: info: Jan 12 23:13:44 volumio volumio[1009]: ---------------------------- MPD announces system playlist update Jan 12 23:13:44 volumio volumio[1009]: info: Ignoring MPD Status Update Jan 12 23:13:44 volumio volumio[1009]: info: sendMpdCommand clear took 36 milliseconds Jan 12 23:13:44 volumio volumio[1009]: error: updateQueue error: null Jan 12 23:13:44 volumio volumio[1009]: info: ------------------------------ 31ms Jan 12 23:13:44 volumio volumio[1009]: info: touch_display: Setting screensaver timeout to 0 seconds. Jan 12 23:13:44 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=flac&url=https%3A%2F%2Fext-strm-simfcrelcom01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D744df8fe8cba8c66bdbb490b1e5109d633ac153bbd18aa8d0ccdfca1930d89e2%2Clid%3D1584%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6791a7bf%2F0%2F53559%2F4d25e704.59930673.13.2511650%2Fflac&ext=.flac" Jan 12 23:13:44 volumio volumio[1009]: info: Jan 12 23:13:44 volumio volumio[1009]: ---------------------------- MPD announces system playlist update Jan 12 23:13:44 volumio volumio[1009]: info: Ignoring MPD Status Update Jan 12 23:13:44 volumio volumio[1009]: info: sendMpdCommand addid "http://localhost:6601/?codec=flac&url=https%3A%2F%2Fext-strm-simfcrelcom01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D744df8fe8cba8c66bdbb490b1e5109d633ac153bbd18aa8d0ccdfca1930d89e2%2Clid%3D1584%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6791a7bf%2F0%2F53559%2F4d25e704.59930673.13.2511650%2Fflac&ext=.flac" took 2 milliseconds Jan 12 23:13:44 volumio volumio[1009]: verbose: MPD COMMAND [object Object] Jan 12 23:13:44 volumio volumio[1009]: verbose: MPD COMMAND [object Object] Jan 12 23:13:44 volumio volumio[1009]: info: Jan 12 23:13:44 volumio volumio[1009]: ---------------------------- MPD announces system playlist update Jan 12 23:13:44 volumio volumio[1009]: info: Ignoring MPD Status Update Jan 12 23:13:44 volumio volumio[1009]: info: ------------------------------ 2ms Jan 12 23:13:44 volumio volumio[1009]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 12 23:13:44 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand play Jan 12 23:13:44 volumio volumio[1009]: info: ------------------------------ 2ms Jan 12 23:13:44 volumio volumio[1009]: info: sendMpdCommand play took 1 milliseconds Jan 12 23:13:44 volumio volumio[1009]: info: Jan 12 23:13:44 volumio volumio[1009]: ---------------------------- MPD announces state update: player Jan 12 23:13:44 volumio volumio[1009]: info: ControllerMpd::getState Jan 12 23:13:44 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Jan 12 23:13:44 volumio volumio[1009]: info: Jan 12 23:13:44 volumio volumio[1009]: ---------------------------- MPD announces state update: player Jan 12 23:13:44 volumio volumio[1009]: info: sendMpdCommand status took 61 milliseconds Jan 12 23:13:44 volumio volumio[1009]: info: ControllerMpd::getState Jan 12 23:13:44 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Jan 12 23:13:44 volumio volumio[1009]: verbose: ControllerMpd::parseState Jan 12 23:13:44 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 23:13:44 volumio volumio[1009]: info: sendMpdCommand status took 0 milliseconds Jan 12 23:13:44 volumio volumio[1009]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 12 23:13:44 volumio volumio[1009]: verbose: ControllerMpd::parseState Jan 12 23:13:44 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 23:13:44 volumio volumio[1009]: verbose: ControllerMpd::parseTrackInfo Jan 12 23:13:44 volumio volumio[1009]: info: ControllerMpd::pushState Jan 12 23:13:44 volumio volumio[1009]: info: CoreCommandRouter::servicePushState Jan 12 23:13:44 volumio volumio[1009]: info: CorePlayQueue::getTrack 21 Jan 12 23:13:44 volumio volumio[1009]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":197,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"917 Kbps","isStreaming":false,"title":"Dr. Love","artist":"E-Rotic","album":"Kiss Me","uri":"http://localhost:6601/?codec=flac&url=https%3A%2F%2Fext-strm-simfcrelcom01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D744df8fe8cba8c66bdbb490b1e5109d633ac153bbd18aa8d0ccdfca1930d89e2%2Clid%3D1584%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6791a7bf%2F0%2F53559%2F4d25e704.59930673.13.2511650%2Fflac&ext=.flac","trackType":"flac"} Jan 12 23:13:44 volumio volumio[1009]: verbose: CURRENT POSITION 21 Jan 12 23:13:44 volumio volumio[1009]: info: CoreStateMachine::syncState stateService play Jan 12 23:13:44 volumio volumio[1009]: info: CoreStateMachine::syncState currentStatus stop Jan 12 23:13:44 volumio volumio[1009]: info: ------------------------------ 65ms Jan 12 23:13:44 volumio volumio[1009]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 12 23:13:44 volumio volumio[1009]: verbose: ControllerMpd::parseTrackInfo Jan 12 23:13:44 volumio volumio[1009]: info: ControllerMpd::pushState Jan 12 23:13:44 volumio volumio[1009]: info: CoreCommandRouter::servicePushState Jan 12 23:13:44 volumio volumio[1009]: info: CorePlayQueue::getTrack 21 Jan 12 23:13:44 volumio volumio[1009]: verbose: STATE SERVICE {"status":"play","position":0,"seek":709,"duration":197,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"917 Kbps","isStreaming":false,"title":"Dr. Love","artist":"E-Rotic","album":"Kiss Me","uri":"http://localhost:6601/?codec=flac&url=https%3A%2F%2Fext-strm-simfcrelcom01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D744df8fe8cba8c66bdbb490b1e5109d633ac153bbd18aa8d0ccdfca1930d89e2%2Clid%3D1584%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6791a7bf%2F0%2F53559%2F4d25e704.59930673.13.2511650%2Fflac&ext=.flac","trackType":"flac"} Jan 12 23:13:44 volumio volumio[1009]: verbose: CURRENT POSITION 21 Jan 12 23:13:44 volumio volumio[1009]: info: CoreStateMachine::syncState stateService play Jan 12 23:13:44 volumio volumio[1009]: info: CoreStateMachine::syncState currentStatus play Jan 12 23:13:44 volumio volumio[1009]: info: Received an update from plugin. extracting info from payload Jan 12 23:13:44 volumio volumio[1009]: info: CoreStateMachine::pushState Jan 12 23:13:44 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 23:13:44 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jan 12 23:13:44 volumio volumio[1009]: info: CoreStateMachine::pushState Jan 12 23:13:44 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jan 12 23:13:44 volumio volumio[1009]: info: ------------------------------ 11ms Jan 12 23:13:44 volumio volumio[1009]: info: CoreCommandRouter::volumioGetQueue Jan 12 23:13:44 volumio volumio[1009]: info: CoreStateMachine::getQueue Jan 12 23:13:44 volumio volumio[1009]: info: CorePlayQueue::getQueue Jan 12 23:13:44 volumio volumio[1009]: info: CoreCommandRouter::volumioGetQueue Jan 12 23:13:44 volumio volumio[1009]: info: CoreStateMachine::getQueue Jan 12 23:13:44 volumio volumio[1009]: info: CorePlayQueue::getQueue Jan 12 23:13:45 volumio volumio[1009]: info: CoreCommandRouter::volumioAddQueueItems Jan 12 23:13:45 volumio volumio[1009]: info: CoreStateMachine::addQueueItems Jan 12 23:13:45 volumio volumio[1009]: info: CorePlayQueue::addQueueItems Jan 12 23:13:45 volumio volumio[1009]: info: Preload queue cleared Jan 12 23:13:45 volumio volumio[1009]: info: Adding Item to queue: yandex_music/track/28123137:3359356@micro-genre:europop Jan 12 23:13:45 volumio volumio[1009]: info: Exploding uri yandex_music/track/28123137:3359356@micro-genre:europop in service yandex_music Jan 12 23:13:45 volumio volumio[1009]: info: CoreCommandRouter::volumioAddQueueItems Jan 12 23:13:45 volumio volumio[1009]: info: CoreStateMachine::addQueueItems Jan 12 23:13:45 volumio volumio[1009]: info: CorePlayQueue::addQueueItems Jan 12 23:13:45 volumio volumio[1009]: info: Preload queue cleared Jan 12 23:13:45 volumio volumio[1009]: info: Adding Item to queue: yandex_music/track/67112726:12192043@micro-genre:europop Jan 12 23:13:45 volumio volumio[1009]: info: Exploding uri yandex_music/track/67112726:12192043@micro-genre:europop in service yandex_music Jan 12 23:13:45 volumio volumio[1009]: info: CoreCommandRouter::volumioPushQueue Jan 12 23:13:45 volumio volumio[1009]: info: CorePlayQueue::saveQueue Jan 12 23:13:45 volumio volumio[1009]: info: CoreCommandRouter::volumioPushQueue Jan 12 23:13:45 volumio volumio[1009]: info: CorePlayQueue::saveQueue Jan 12 23:13:45 volumio volumio[1009]: info: CoreStateMachine::updateTrackBlock Jan 12 23:13:45 volumio volumio[1009]: info: CorePlayQueue::getTrackBlock Jan 12 23:13:45 volumio volumio[1009]: info: CoreStateMachine::updateTrackBlock Jan 12 23:13:45 volumio volumio[1009]: info: CorePlayQueue::getTrackBlock Jan 12 23:13:48 volumio volumio[1009]: info: CoreCommandRouter::volumioSeek Jan 12 23:13:48 volumio volumio[1009]: info: CoreStateMachine::seek Jan 12 23:13:48 volumio volumio[1009]: info: CorePlayQueue::getTrack 21 Jan 12 23:13:48 volumio volumio[1009]: info: TRACKBLOCK {"id":"2511650:252463@micro-genre:europop","service":"yandex_music","type":"song","name":"Dr. Love","title":"Dr. Love","album":"Kiss Me","artist":"E-Rotic","duration":197,"albumart":"https://avatars.yandex.net/get-music-content/99892/c3cc9408.a.252463-3/200x200","uri":"yandex_music/track/2511650:252463@micro-genre:europop","samplerate":"","bitdepth":"","trackType":"","codec":"flac","bitrate":0} Jan 12 23:13:48 volumio volumio[1009]: info: CoreStateMachine::startPlaybackTimer Jan 12 23:13:48 volumio volumio[1009]: info: CorePlayQueue::getTrack 21 Jan 12 23:13:48 volumio volumio[1009]: info: ControllerMpd::seek Jan 12 23:13:48 volumio volumio[1009]: info: CoreStateMachine::pushState Jan 12 23:13:48 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 23:13:48 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jan 12 23:13:48 volumio volumio[1009]: info: CoreCommandRouter::volumioGetQueue Jan 12 23:13:48 volumio volumio[1009]: info: CoreStateMachine::getQueue Jan 12 23:13:48 volumio volumio[1009]: info: CorePlayQueue::getQueue Jan 12 23:13:48 volumio volumio[1009]: error: [5@0] {seek} Decoder failed to seek Jan 12 23:13:48 volumio volumio[1009]: info: Jan 12 23:13:48 volumio volumio[1009]: ---------------------------- MPD announces state update: player Jan 12 23:13:48 volumio volumio[1009]: info: ControllerMpd::getState Jan 12 23:13:48 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Jan 12 23:13:48 volumio volumio[1009]: info: Jan 12 23:13:48 volumio volumio[1009]: ---------------------------- MPD announces state update: player Jan 12 23:13:48 volumio volumio[1009]: info: ControllerMpd::getState Jan 12 23:13:48 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Jan 12 23:13:48 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 12 23:13:48 volumio volumio[1009]: info: sendMpdCommand status took 8 milliseconds Jan 12 23:13:48 volumio volumio[1009]: verbose: ControllerMpd::parseState Jan 12 23:13:48 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 23:13:48 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 12 23:13:48 volumio volumio[1009]: info: sendMpdCommand status took 15 milliseconds Jan 12 23:13:48 volumio volumio[1009]: info: sendMpdCommand clearerror took 12 milliseconds Jan 12 23:13:48 volumio volumio[1009]: info: sendMpdCommand playlistinfo took 11 milliseconds Jan 12 23:13:48 volumio volumio[1009]: verbose: ControllerMpd::parseState Jan 12 23:13:48 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 23:13:48 volumio volumio[1009]: verbose: ControllerMpd::parseTrackInfo Jan 12 23:13:48 volumio volumio[1009]: info: ControllerMpd::pushState Jan 12 23:13:48 volumio volumio[1009]: info: CoreCommandRouter::servicePushState Jan 12 23:13:48 volumio volumio[1009]: info: CoreStateMachine::pushState Jan 12 23:13:48 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 23:13:48 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jan 12 23:13:48 volumio volumio[1009]: info: CorePlayQueue::getTrack 21 Jan 12 23:13:48 volumio volumio[1009]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Dr. Love","artist":"E-Rotic","album":"Kiss Me","uri":"http://localhost:6601/?codec=flac&url=https%3A%2F%2Fext-strm-simfcrelcom01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D744df8fe8cba8c66bdbb490b1e5109d633ac153bbd18aa8d0ccdfca1930d89e2%2Clid%3D1584%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6791a7bf%2F0%2F53559%2F4d25e704.59930673.13.2511650%2Fflac&ext=.flac","trackType":"flac"} Jan 12 23:13:48 volumio volumio[1009]: verbose: CURRENT POSITION 21 Jan 12 23:13:48 volumio volumio[1009]: info: CoreStateMachine::syncState stateService stop Jan 12 23:13:48 volumio volumio[1009]: info: CoreStateMachine::syncState currentStatus play Jan 12 23:13:48 volumio volumio[1009]: info: CoreStateMachine::play index undefined Jan 12 23:13:48 volumio volumio[1009]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 23:13:48 volumio volumio[1009]: info: CoreStateMachine::pushState Jan 12 23:13:48 volumio volumio[1009]: info: CorePlayQueue::getTrack 22 Jan 12 23:13:48 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 23:13:48 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jan 12 23:13:48 volumio volumio[1009]: info: CorePlayQueue::getTrack 22 Jan 12 23:13:48 volumio volumio[1009]: info: CoreStateMachine::startPlaybackTimer Jan 12 23:13:48 volumio volumio[1009]: info: CorePlayQueue::getTrack 22 Jan 12 23:13:48 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand stop Jan 12 23:13:48 volumio volumio[1009]: info: ------------------------------ 42ms Jan 12 23:13:48 volumio volumio[1009]: info: CoreStateMachine::pushState Jan 12 23:13:48 volumio volumio[1009]: info: CorePlayQueue::getTrack 22 Jan 12 23:13:48 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 23:13:48 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jan 12 23:13:48 volumio volumio[1009]: info: sendMpdCommand clearerror took 37 milliseconds Jan 12 23:13:48 volumio volumio[1009]: info: sendMpdCommand playlistinfo took 36 milliseconds Jan 12 23:13:48 volumio volumio[1009]: info: sendMpdCommand stop took 16 milliseconds Jan 12 23:13:48 volumio volumio[1009]: verbose: ControllerMpd::parseTrackInfo Jan 12 23:13:48 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand clear Jan 12 23:13:48 volumio volumio[1009]: info: ControllerMpd::pushState Jan 12 23:13:48 volumio volumio[1009]: info: CoreCommandRouter::servicePushState Jan 12 23:13:48 volumio volumio[1009]: info: CoreStateMachine::pushState Jan 12 23:13:48 volumio volumio[1009]: info: CorePlayQueue::getTrack 22 Jan 12 23:13:48 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jan 12 23:13:48 volumio volumio[1009]: info: CorePlayQueue::getTrack 22 Jan 12 23:13:48 volumio volumio[1009]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd Jan 12 23:13:48 volumio volumio[1009]: info: ------------------------------ 64ms Jan 12 23:13:48 volumio volumio[1009]: info: CoreCommandRouter::volumioGetQueue Jan 12 23:13:48 volumio volumio[1009]: info: CoreStateMachine::getQueue Jan 12 23:13:48 volumio volumio[1009]: info: CorePlayQueue::getQueue Jan 12 23:13:48 volumio volumio[1009]: info: Jan 12 23:13:48 volumio volumio[1009]: ---------------------------- MPD announces system playlist update Jan 12 23:13:48 volumio volumio[1009]: info: Ignoring MPD Status Update Jan 12 23:13:48 volumio volumio[1009]: info: sendMpdCommand clear took 59 milliseconds Jan 12 23:13:48 volumio volumio[1009]: error: updateQueue error: null Jan 12 23:13:48 volumio volumio[1009]: info: ------------------------------ 14ms Jan 12 23:13:48 volumio volumio[1009]: info: touch_display: Setting screensaver timeout to 0 seconds. Jan 12 23:13:48 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fext-strm-simfcrelcom01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D37ed8d49fbee51fccfd0aa07e42b423d1683c5fb8ad202ca5283bc9d5054ddc9%2Clid%3D1584%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6791b3ad%2F30527%2F67158%2F85f58688.13012461.5.76275%2F320.mp3&ext=.mp3" Jan 12 23:13:48 volumio volumio[1009]: info: Jan 12 23:13:48 volumio volumio[1009]: ---------------------------- MPD announces system playlist update Jan 12 23:13:48 volumio volumio[1009]: info: Ignoring MPD Status Update Jan 12 23:13:48 volumio volumio[1009]: info: sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fext-strm-simfcrelcom01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D37ed8d49fbee51fccfd0aa07e42b423d1683c5fb8ad202ca5283bc9d5054ddc9%2Clid%3D1584%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6791b3ad%2F30527%2F67158%2F85f58688.13012461.5.76275%2F320.mp3&ext=.mp3" took 3 milliseconds Jan 12 23:13:48 volumio volumio[1009]: verbose: MPD COMMAND [object Object] Jan 12 23:13:48 volumio volumio[1009]: verbose: MPD COMMAND [object Object] Jan 12 23:13:48 volumio volumio[1009]: info: Jan 12 23:13:48 volumio volumio[1009]: ---------------------------- MPD announces system playlist update Jan 12 23:13:48 volumio volumio[1009]: info: Ignoring MPD Status Update Jan 12 23:13:48 volumio volumio[1009]: info: ------------------------------ 4ms Jan 12 23:13:48 volumio volumio[1009]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 12 23:13:48 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand play Jan 12 23:13:48 volumio volumio[1009]: info: ------------------------------ 7ms Jan 12 23:13:48 volumio volumio[1009]: info: sendMpdCommand play took 6 milliseconds Jan 12 23:13:49 volumio volumio[1009]: info: Jan 12 23:13:49 volumio volumio[1009]: ---------------------------- MPD announces state update: player Jan 12 23:13:49 volumio volumio[1009]: info: ControllerMpd::getState Jan 12 23:13:49 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Jan 12 23:13:49 volumio volumio[1009]: info: Jan 12 23:13:49 volumio volumio[1009]: ---------------------------- MPD announces state update: player Jan 12 23:13:49 volumio volumio[1009]: info: sendMpdCommand status took 71 milliseconds Jan 12 23:13:49 volumio volumio[1009]: info: ControllerMpd::getState Jan 12 23:13:49 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Jan 12 23:13:49 volumio volumio[1009]: verbose: ControllerMpd::parseState Jan 12 23:13:49 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 23:13:49 volumio volumio[1009]: info: sendMpdCommand status took 2 milliseconds Jan 12 23:13:49 volumio volumio[1009]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 12 23:13:49 volumio volumio[1009]: verbose: ControllerMpd::parseState Jan 12 23:13:49 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 23:13:49 volumio volumio[1009]: verbose: ControllerMpd::parseTrackInfo Jan 12 23:13:49 volumio volumio[1009]: info: ControllerMpd::pushState Jan 12 23:13:49 volumio volumio[1009]: info: CoreCommandRouter::servicePushState Jan 12 23:13:49 volumio volumio[1009]: info: CorePlayQueue::getTrack 22 Jan 12 23:13:49 volumio volumio[1009]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":194,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Russian Lullaby","artist":"E-Type","album":"Russian Lullaby","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fext-strm-simfcrelcom01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D37ed8d49fbee51fccfd0aa07e42b423d1683c5fb8ad202ca5283bc9d5054ddc9%2Clid%3D1584%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6791b3ad%2F30527%2F67158%2F85f58688.13012461.5.76275%2F320.mp3&ext=.mp3","trackType":"mp3"} Jan 12 23:13:49 volumio volumio[1009]: verbose: CURRENT POSITION 22 Jan 12 23:13:49 volumio volumio[1009]: info: CoreStateMachine::syncState stateService play Jan 12 23:13:49 volumio volumio[1009]: info: CoreStateMachine::syncState currentStatus stop Jan 12 23:13:49 volumio volumio[1009]: info: ------------------------------ 78ms Jan 12 23:13:49 volumio volumio[1009]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 12 23:13:49 volumio volumio[1009]: verbose: ControllerMpd::parseTrackInfo Jan 12 23:13:49 volumio volumio[1009]: info: ControllerMpd::pushState Jan 12 23:13:49 volumio volumio[1009]: info: CoreCommandRouter::servicePushState Jan 12 23:13:49 volumio volumio[1009]: info: CorePlayQueue::getTrack 22 Jan 12 23:13:49 volumio volumio[1009]: verbose: STATE SERVICE {"status":"play","position":0,"seek":757,"duration":194,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Russian Lullaby","artist":"E-Type","album":"Russian Lullaby","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fext-strm-simfcrelcom01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D37ed8d49fbee51fccfd0aa07e42b423d1683c5fb8ad202ca5283bc9d5054ddc9%2Clid%3D1584%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6791b3ad%2F30527%2F67158%2F85f58688.13012461.5.76275%2F320.mp3&ext=.mp3","trackType":"mp3"} Jan 12 23:13:49 volumio volumio[1009]: verbose: CURRENT POSITION 22 Jan 12 23:13:49 volumio volumio[1009]: info: CoreStateMachine::syncState stateService play Jan 12 23:13:49 volumio volumio[1009]: info: CoreStateMachine::syncState currentStatus play Jan 12 23:13:49 volumio volumio[1009]: info: Received an update from plugin. extracting info from payload Jan 12 23:13:49 volumio volumio[1009]: info: CoreStateMachine::pushState Jan 12 23:13:49 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 23:13:49 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jan 12 23:13:49 volumio volumio[1009]: info: CoreStateMachine::pushState Jan 12 23:13:49 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jan 12 23:13:49 volumio volumio[1009]: info: ------------------------------ 30ms Jan 12 23:13:49 volumio volumio[1009]: info: CoreCommandRouter::volumioGetQueue Jan 12 23:13:49 volumio volumio[1009]: info: CoreStateMachine::getQueue Jan 12 23:13:49 volumio volumio[1009]: info: CorePlayQueue::getQueue Jan 12 23:13:49 volumio volumio[1009]: info: CoreCommandRouter::volumioGetQueue Jan 12 23:13:49 volumio volumio[1009]: info: CoreStateMachine::getQueue Jan 12 23:13:49 volumio volumio[1009]: info: CorePlayQueue::getQueue Jan 12 23:13:49 volumio volumio[1009]: info: CoreCommandRouter::volumioAddQueueItems Jan 12 23:13:49 volumio volumio[1009]: info: CoreStateMachine::addQueueItems Jan 12 23:13:49 volumio volumio[1009]: info: CorePlayQueue::addQueueItems Jan 12 23:13:49 volumio volumio[1009]: info: Preload queue cleared Jan 12 23:13:49 volumio volumio[1009]: info: Adding Item to queue: yandex_music/track/2470793:2459238@micro-genre:europop Jan 12 23:13:49 volumio volumio[1009]: info: Exploding uri yandex_music/track/2470793:2459238@micro-genre:europop in service yandex_music Jan 12 23:13:49 volumio volumio[1009]: info: CoreCommandRouter::volumioPushQueue Jan 12 23:13:49 volumio volumio[1009]: info: CorePlayQueue::saveQueue Jan 12 23:13:49 volumio volumio[1009]: info: CoreStateMachine::updateTrackBlock Jan 12 23:13:49 volumio volumio[1009]: info: CorePlayQueue::getTrackBlock Jan 12 23:13:51 volumio volumio[1009]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 8 Jan 12 23:13:52 volumio volumio[1009]: info: peppy_screensaver: Start PeppyMeter Jan 12 23:13:55 volumio volumio[1009]: info: CoreCommandRouter::volumioSeek Jan 12 23:13:55 volumio volumio[1009]: info: CoreStateMachine::seek Jan 12 23:13:55 volumio volumio[1009]: info: CorePlayQueue::getTrack 22 Jan 12 23:13:55 volumio volumio[1009]: info: TRACKBLOCK {"id":"76275:63325@micro-genre:europop","service":"yandex_music","type":"song","name":"Russian Lullaby","title":"Russian Lullaby","album":"Russian Lullaby","artist":"E-Type","duration":194,"albumart":"https://avatars.yandex.net/get-music-content/41288/650da36c.a.63325-1/200x200","uri":"yandex_music/track/76275:63325@micro-genre:europop","samplerate":"","bitdepth":"","trackType":"","codec":"mp3","bitrate":320} Jan 12 23:13:55 volumio volumio[1009]: info: CoreStateMachine::startPlaybackTimer Jan 12 23:13:55 volumio volumio[1009]: info: CorePlayQueue::getTrack 22 Jan 12 23:13:55 volumio volumio[1009]: info: ControllerMpd::seek Jan 12 23:13:55 volumio volumio[1009]: info: CoreStateMachine::pushState Jan 12 23:13:55 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 23:13:55 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jan 12 23:13:55 volumio volumio[1009]: info: CoreCommandRouter::volumioGetQueue Jan 12 23:13:55 volumio volumio[1009]: info: CoreStateMachine::getQueue Jan 12 23:13:55 volumio volumio[1009]: info: CorePlayQueue::getQueue Jan 12 23:13:55 volumio volumio[1009]: error: null Jan 12 23:13:55 volumio volumio[1009]: info: Jan 12 23:13:55 volumio volumio[1009]: ---------------------------- MPD announces state update: player Jan 12 23:13:55 volumio volumio[1009]: info: ControllerMpd::getState Jan 12 23:13:55 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Jan 12 23:13:55 volumio volumio[1009]: info: sendMpdCommand status took 3 milliseconds Jan 12 23:13:55 volumio volumio[1009]: verbose: ControllerMpd::parseState Jan 12 23:13:55 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 23:13:55 volumio volumio[1009]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 12 23:13:55 volumio volumio[1009]: verbose: ControllerMpd::parseTrackInfo Jan 12 23:13:55 volumio volumio[1009]: info: ControllerMpd::pushState Jan 12 23:13:55 volumio volumio[1009]: info: CoreCommandRouter::servicePushState Jan 12 23:13:55 volumio volumio[1009]: info: CorePlayQueue::getTrack 22 Jan 12 23:13:55 volumio volumio[1009]: verbose: STATE SERVICE {"status":"play","position":0,"seek":30731,"duration":194,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Russian Lullaby","artist":"E-Type","album":"Russian Lullaby","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fext-strm-simfcrelcom01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D37ed8d49fbee51fccfd0aa07e42b423d1683c5fb8ad202ca5283bc9d5054ddc9%2Clid%3D1584%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6791b3ad%2F30527%2F67158%2F85f58688.13012461.5.76275%2F320.mp3&ext=.mp3","trackType":"mp3"} Jan 12 23:13:55 volumio volumio[1009]: verbose: CURRENT POSITION 22 Jan 12 23:13:55 volumio volumio[1009]: info: CoreStateMachine::syncState stateService play Jan 12 23:13:55 volumio volumio[1009]: info: CoreStateMachine::syncState currentStatus play Jan 12 23:13:55 volumio volumio[1009]: info: Received an update from plugin. extracting info from payload Jan 12 23:13:55 volumio volumio[1009]: info: CoreStateMachine::pushState Jan 12 23:13:55 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jan 12 23:13:55 volumio volumio[1009]: info: CoreStateMachine::pushState Jan 12 23:13:55 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jan 12 23:13:55 volumio volumio[1009]: info: ------------------------------ 18ms Jan 12 23:13:55 volumio volumio[1009]: info: CoreCommandRouter::volumioGetQueue Jan 12 23:13:55 volumio volumio[1009]: info: CoreStateMachine::getQueue Jan 12 23:13:55 volumio volumio[1009]: info: CorePlayQueue::getQueue Jan 12 23:13:55 volumio volumio[1009]: info: CoreCommandRouter::volumioGetQueue Jan 12 23:13:55 volumio volumio[1009]: info: CoreStateMachine::getQueue Jan 12 23:13:55 volumio volumio[1009]: info: CorePlayQueue::getQueue Jan 12 23:13:58 volumio volumio[1009]: info: CoreCommandRouter::volumioNext Jan 12 23:13:58 volumio volumio[1009]: info: CoreStateMachine::next Jan 12 23:13:58 volumio volumio[1009]: info: ControllerMpd::next Jan 12 23:13:58 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand next Jan 12 23:13:58 volumio volumio[1009]: info: Jan 12 23:13:58 volumio volumio[1009]: ---------------------------- MPD announces system playlist update Jan 12 23:13:58 volumio volumio[1009]: info: Ignoring MPD Status Update Jan 12 23:13:58 volumio volumio[1009]: info: Jan 12 23:13:58 volumio volumio[1009]: ---------------------------- MPD announces state update: player Jan 12 23:13:58 volumio volumio[1009]: info: sendMpdCommand next took 32 milliseconds Jan 12 23:13:58 volumio volumio[1009]: info: ControllerMpd::getState Jan 12 23:13:58 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Jan 12 23:13:58 volumio volumio[1009]: error: updateQueue error: null Jan 12 23:13:58 volumio volumio[1009]: info: ------------------------------ 2ms Jan 12 23:13:58 volumio volumio[1009]: info: sendMpdCommand status took 1 milliseconds Jan 12 23:13:58 volumio volumio[1009]: verbose: ControllerMpd::parseState Jan 12 23:13:58 volumio volumio[1009]: info: ControllerMpd::pushState Jan 12 23:13:58 volumio volumio[1009]: info: CoreCommandRouter::servicePushState Jan 12 23:13:58 volumio volumio[1009]: info: CoreStateMachine::pushState Jan 12 23:13:58 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 23:13:58 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jan 12 23:13:58 volumio volumio[1009]: info: CorePlayQueue::getTrack 22 Jan 12 23:13:58 volumio volumio[1009]: 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} Jan 12 23:13:58 volumio volumio[1009]: verbose: CURRENT POSITION 22 Jan 12 23:13:58 volumio volumio[1009]: info: CoreStateMachine::syncState stateService stop Jan 12 23:13:58 volumio volumio[1009]: info: CoreStateMachine::syncState currentStatus play Jan 12 23:13:58 volumio volumio[1009]: info: CoreStateMachine::play index undefined Jan 12 23:13:58 volumio volumio[1009]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 23:13:58 volumio volumio[1009]: info: CoreStateMachine::pushState Jan 12 23:13:58 volumio volumio[1009]: info: CorePlayQueue::getTrack 23 Jan 12 23:13:58 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 23:13:58 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jan 12 23:13:58 volumio volumio[1009]: info: CorePlayQueue::getTrack 23 Jan 12 23:13:58 volumio volumio[1009]: info: CoreStateMachine::startPlaybackTimer Jan 12 23:13:58 volumio volumio[1009]: info: CorePlayQueue::getTrack 23 Jan 12 23:13:58 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand stop Jan 12 23:13:58 volumio volumio[1009]: info: ------------------------------ 15ms Jan 12 23:13:58 volumio volumio[1009]: info: CoreStateMachine::pushState Jan 12 23:13:58 volumio volumio[1009]: info: CorePlayQueue::getTrack 23 Jan 12 23:13:58 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 23:13:58 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jan 12 23:13:58 volumio volumio[1009]: info: sendMpdCommand stop took 19 milliseconds Jan 12 23:13:58 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand clear Jan 12 23:13:58 volumio volumio[1009]: info: CoreCommandRouter::volumioGetQueue Jan 12 23:13:58 volumio volumio[1009]: info: CoreStateMachine::getQueue Jan 12 23:13:58 volumio volumio[1009]: info: CorePlayQueue::getQueue Jan 12 23:13:58 volumio volumio[1009]: info: Jan 12 23:13:58 volumio volumio[1009]: ---------------------------- MPD announces system playlist update Jan 12 23:13:58 volumio volumio[1009]: info: Ignoring MPD Status Update Jan 12 23:13:58 volumio volumio[1009]: info: sendMpdCommand clear took 47 milliseconds Jan 12 23:13:58 volumio volumio[1009]: error: updateQueue error: null Jan 12 23:13:58 volumio volumio[1009]: info: ------------------------------ 13ms Jan 12 23:13:58 volumio volumio[1009]: info: touch_display: Setting screensaver timeout to 0 seconds. Jan 12 23:13:59 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2F1539.cold-rtc.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D3dcc950059eb2b282241ec844b03774bbc1d9b124b4a37c935cf8821afcd59a7%2Clid%3D1539%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6791b756%2F34267%2F9738%2F1b36b838.641865.4.103130%2F320.mp3&ext=.mp3" Jan 12 23:13:59 volumio volumio[1009]: info: Jan 12 23:13:59 volumio volumio[1009]: ---------------------------- MPD announces system playlist update Jan 12 23:13:59 volumio volumio[1009]: info: Ignoring MPD Status Update Jan 12 23:13:59 volumio volumio[1009]: info: sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2F1539.cold-rtc.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D3dcc950059eb2b282241ec844b03774bbc1d9b124b4a37c935cf8821afcd59a7%2Clid%3D1539%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6791b756%2F34267%2F9738%2F1b36b838.641865.4.103130%2F320.mp3&ext=.mp3" took 1 milliseconds Jan 12 23:13:59 volumio volumio[1009]: verbose: MPD COMMAND [object Object] Jan 12 23:13:59 volumio volumio[1009]: verbose: MPD COMMAND [object Object] Jan 12 23:13:59 volumio volumio[1009]: verbose: MPD COMMAND [object Object] Jan 12 23:13:59 volumio volumio[1009]: info: Jan 12 23:13:59 volumio volumio[1009]: ---------------------------- MPD announces system playlist update Jan 12 23:13:59 volumio volumio[1009]: info: Ignoring MPD Status Update Jan 12 23:13:59 volumio volumio[1009]: info: ------------------------------ 2ms Jan 12 23:13:59 volumio volumio[1009]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 12 23:13:59 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand play Jan 12 23:13:59 volumio volumio[1009]: info: ------------------------------ 2ms Jan 12 23:13:59 volumio volumio[1009]: info: sendMpdCommand play took 2 milliseconds Jan 12 23:13:59 volumio volumio[1009]: info: CoreCommandRouter::volumioAddQueueItems Jan 12 23:13:59 volumio volumio[1009]: info: CoreStateMachine::addQueueItems Jan 12 23:13:59 volumio volumio[1009]: info: CorePlayQueue::addQueueItems Jan 12 23:13:59 volumio volumio[1009]: info: Preload queue cleared Jan 12 23:13:59 volumio volumio[1009]: info: Adding Item to queue: yandex_music/track/29863157:16694537@micro-genre:europop Jan 12 23:13:59 volumio volumio[1009]: info: Exploding uri yandex_music/track/29863157:16694537@micro-genre:europop in service yandex_music Jan 12 23:13:59 volumio volumio[1009]: info: CoreCommandRouter::volumioPushQueue Jan 12 23:13:59 volumio volumio[1009]: info: CorePlayQueue::saveQueue Jan 12 23:13:59 volumio volumio[1009]: info: CoreStateMachine::updateTrackBlock Jan 12 23:13:59 volumio volumio[1009]: info: CorePlayQueue::getTrackBlock Jan 12 23:14:00 volumio volumio[1009]: info: Jan 12 23:14:00 volumio volumio[1009]: ---------------------------- MPD announces state update: player Jan 12 23:14:00 volumio volumio[1009]: info: ControllerMpd::getState Jan 12 23:14:00 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Jan 12 23:14:00 volumio volumio[1009]: info: Jan 12 23:14:00 volumio volumio[1009]: ---------------------------- MPD announces state update: player Jan 12 23:14:00 volumio volumio[1009]: info: sendMpdCommand status took 70 milliseconds Jan 12 23:14:00 volumio volumio[1009]: info: ControllerMpd::getState Jan 12 23:14:00 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Jan 12 23:14:00 volumio volumio[1009]: verbose: ControllerMpd::parseState Jan 12 23:14:00 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 23:14:00 volumio volumio[1009]: info: sendMpdCommand status took 2 milliseconds Jan 12 23:14:00 volumio volumio[1009]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 12 23:14:00 volumio volumio[1009]: verbose: ControllerMpd::parseState Jan 12 23:14:00 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 23:14:00 volumio volumio[1009]: verbose: ControllerMpd::parseTrackInfo Jan 12 23:14:00 volumio volumio[1009]: info: ControllerMpd::pushState Jan 12 23:14:00 volumio volumio[1009]: info: CoreCommandRouter::servicePushState Jan 12 23:14:00 volumio volumio[1009]: info: CorePlayQueue::getTrack 23 Jan 12 23:14:00 volumio volumio[1009]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":219,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Désenchantée","artist":"Kate Ryan","album":"Essential","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2F1539.cold-rtc.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D3dcc950059eb2b282241ec844b03774bbc1d9b124b4a37c935cf8821afcd59a7%2Clid%3D1539%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6791b756%2F34267%2F9738%2F1b36b838.641865.4.103130%2F320.mp3&ext=.mp3","trackType":"mp3"} Jan 12 23:14:00 volumio volumio[1009]: verbose: CURRENT POSITION 23 Jan 12 23:14:00 volumio volumio[1009]: info: CoreStateMachine::syncState stateService play Jan 12 23:14:00 volumio volumio[1009]: info: CoreStateMachine::syncState currentStatus stop Jan 12 23:14:00 volumio volumio[1009]: info: ------------------------------ 74ms Jan 12 23:14:00 volumio volumio[1009]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 12 23:14:00 volumio volumio[1009]: verbose: ControllerMpd::parseTrackInfo Jan 12 23:14:00 volumio volumio[1009]: info: ControllerMpd::pushState Jan 12 23:14:00 volumio volumio[1009]: info: CoreCommandRouter::servicePushState Jan 12 23:14:00 volumio volumio[1009]: info: CorePlayQueue::getTrack 23 Jan 12 23:14:00 volumio volumio[1009]: verbose: STATE SERVICE {"status":"play","position":0,"seek":757,"duration":219,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Désenchantée","artist":"Kate Ryan","album":"Essential","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2F1539.cold-rtc.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D3dcc950059eb2b282241ec844b03774bbc1d9b124b4a37c935cf8821afcd59a7%2Clid%3D1539%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6791b756%2F34267%2F9738%2F1b36b838.641865.4.103130%2F320.mp3&ext=.mp3","trackType":"mp3"} Jan 12 23:14:00 volumio volumio[1009]: verbose: CURRENT POSITION 23 Jan 12 23:14:00 volumio volumio[1009]: info: CoreStateMachine::syncState stateService play Jan 12 23:14:00 volumio volumio[1009]: info: CoreStateMachine::syncState currentStatus play Jan 12 23:14:00 volumio volumio[1009]: info: Received an update from plugin. extracting info from payload Jan 12 23:14:00 volumio volumio[1009]: info: CoreStateMachine::pushState Jan 12 23:14:00 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 23:14:00 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jan 12 23:14:00 volumio volumio[1009]: info: CoreStateMachine::pushState Jan 12 23:14:00 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jan 12 23:14:00 volumio volumio[1009]: info: ------------------------------ 18ms Jan 12 23:14:00 volumio volumio[1009]: info: CoreCommandRouter::volumioGetQueue Jan 12 23:14:00 volumio volumio[1009]: info: CoreStateMachine::getQueue Jan 12 23:14:00 volumio volumio[1009]: info: CorePlayQueue::getQueue Jan 12 23:14:00 volumio volumio[1009]: info: CoreCommandRouter::volumioGetQueue Jan 12 23:14:00 volumio volumio[1009]: info: CoreStateMachine::getQueue Jan 12 23:14:00 volumio volumio[1009]: info: CorePlayQueue::getQueue Jan 12 23:14:04 volumio volumio[1009]: info: CoreCommandRouter::volumioNext Jan 12 23:14:04 volumio volumio[1009]: info: CoreStateMachine::next Jan 12 23:14:04 volumio volumio[1009]: info: ControllerMpd::next Jan 12 23:14:04 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand next Jan 12 23:14:04 volumio volumio[1009]: info: sendMpdCommand next took 35 milliseconds Jan 12 23:14:04 volumio volumio[1009]: info: Jan 12 23:14:04 volumio volumio[1009]: ---------------------------- MPD announces system playlist update Jan 12 23:14:04 volumio volumio[1009]: info: Ignoring MPD Status Update Jan 12 23:14:04 volumio volumio[1009]: info: Jan 12 23:14:04 volumio volumio[1009]: ---------------------------- MPD announces state update: player Jan 12 23:14:04 volumio volumio[1009]: info: ControllerMpd::getState Jan 12 23:14:04 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Jan 12 23:14:04 volumio volumio[1009]: error: updateQueue error: null Jan 12 23:14:04 volumio volumio[1009]: info: ------------------------------ 3ms Jan 12 23:14:04 volumio volumio[1009]: info: sendMpdCommand status took 2 milliseconds Jan 12 23:14:04 volumio volumio[1009]: verbose: ControllerMpd::parseState Jan 12 23:14:04 volumio volumio[1009]: info: ControllerMpd::pushState Jan 12 23:14:04 volumio volumio[1009]: info: CoreCommandRouter::servicePushState Jan 12 23:14:04 volumio volumio[1009]: info: CoreStateMachine::pushState Jan 12 23:14:04 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 23:14:04 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jan 12 23:14:04 volumio volumio[1009]: info: CorePlayQueue::getTrack 23 Jan 12 23:14:04 volumio volumio[1009]: 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} Jan 12 23:14:04 volumio volumio[1009]: verbose: CURRENT POSITION 23 Jan 12 23:14:04 volumio volumio[1009]: info: CoreStateMachine::syncState stateService stop Jan 12 23:14:04 volumio volumio[1009]: info: CoreStateMachine::syncState currentStatus play Jan 12 23:14:04 volumio volumio[1009]: info: CoreStateMachine::play index undefined Jan 12 23:14:04 volumio volumio[1009]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 23:14:04 volumio volumio[1009]: info: CoreStateMachine::pushState Jan 12 23:14:04 volumio volumio[1009]: info: CorePlayQueue::getTrack 24 Jan 12 23:14:04 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 23:14:04 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jan 12 23:14:04 volumio volumio[1009]: info: CorePlayQueue::getTrack 24 Jan 12 23:14:04 volumio volumio[1009]: info: CoreStateMachine::startPlaybackTimer Jan 12 23:14:04 volumio volumio[1009]: info: CorePlayQueue::getTrack 24 Jan 12 23:14:04 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand stop Jan 12 23:14:04 volumio volumio[1009]: info: ------------------------------ 12ms Jan 12 23:14:04 volumio volumio[1009]: info: CoreStateMachine::pushState Jan 12 23:14:04 volumio volumio[1009]: info: CorePlayQueue::getTrack 24 Jan 12 23:14:04 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 23:14:04 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jan 12 23:14:04 volumio volumio[1009]: info: sendMpdCommand stop took 21 milliseconds Jan 12 23:14:04 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand clear Jan 12 23:14:04 volumio volumio[1009]: info: CoreCommandRouter::volumioGetQueue Jan 12 23:14:04 volumio volumio[1009]: info: CoreStateMachine::getQueue Jan 12 23:14:04 volumio volumio[1009]: info: CorePlayQueue::getQueue Jan 12 23:14:04 volumio volumio[1009]: info: Jan 12 23:14:04 volumio volumio[1009]: ---------------------------- MPD announces system playlist update Jan 12 23:14:04 volumio volumio[1009]: info: Ignoring MPD Status Update Jan 12 23:14:04 volumio volumio[1009]: info: sendMpdCommand clear took 51 milliseconds Jan 12 23:14:04 volumio volumio[1009]: error: updateQueue error: null Jan 12 23:14:04 volumio volumio[1009]: info: ------------------------------ 9ms Jan 12 23:14:04 volumio volumio[1009]: info: touch_display: Setting screensaver timeout to 0 seconds. Jan 12 23:14:04 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-mar-98.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Db72ebc8a3fc3c288321b2e9027d311a1f9e50decc0ddba4dd4414ad5c1f12565%2Clid%3D1548%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6791b017%2F32159%2F15424%2F0c657b46.118733572.7.100634%2F320.mp3&ext=.mp3" Jan 12 23:14:04 volumio volumio[1009]: info: Jan 12 23:14:04 volumio volumio[1009]: ---------------------------- MPD announces system playlist update Jan 12 23:14:04 volumio volumio[1009]: info: Ignoring MPD Status Update Jan 12 23:14:04 volumio volumio[1009]: info: sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-mar-98.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Db72ebc8a3fc3c288321b2e9027d311a1f9e50decc0ddba4dd4414ad5c1f12565%2Clid%3D1548%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6791b017%2F32159%2F15424%2F0c657b46.118733572.7.100634%2F320.mp3&ext=.mp3" took 2 milliseconds Jan 12 23:14:04 volumio volumio[1009]: verbose: MPD COMMAND [object Object] Jan 12 23:14:04 volumio volumio[1009]: verbose: MPD COMMAND [object Object] Jan 12 23:14:04 volumio volumio[1009]: verbose: MPD COMMAND [object Object] Jan 12 23:14:04 volumio volumio[1009]: info: Jan 12 23:14:04 volumio volumio[1009]: ---------------------------- MPD announces system playlist update Jan 12 23:14:04 volumio volumio[1009]: info: Ignoring MPD Status Update Jan 12 23:14:04 volumio volumio[1009]: info: ------------------------------ 2ms Jan 12 23:14:04 volumio volumio[1009]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 12 23:14:04 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand play Jan 12 23:14:04 volumio volumio[1009]: info: ------------------------------ 1ms Jan 12 23:14:04 volumio volumio[1009]: info: sendMpdCommand play took 1 milliseconds Jan 12 23:14:05 volumio volumio[1009]: info: Jan 12 23:14:05 volumio volumio[1009]: ---------------------------- MPD announces state update: player Jan 12 23:14:05 volumio volumio[1009]: info: ControllerMpd::getState Jan 12 23:14:05 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Jan 12 23:14:05 volumio volumio[1009]: info: Jan 12 23:14:05 volumio volumio[1009]: ---------------------------- MPD announces state update: player Jan 12 23:14:05 volumio volumio[1009]: info: sendMpdCommand status took 65 milliseconds Jan 12 23:14:05 volumio volumio[1009]: info: ControllerMpd::getState Jan 12 23:14:05 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Jan 12 23:14:05 volumio volumio[1009]: verbose: ControllerMpd::parseState Jan 12 23:14:05 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 23:14:05 volumio volumio[1009]: info: sendMpdCommand status took 1 milliseconds Jan 12 23:14:05 volumio volumio[1009]: info: sendMpdCommand playlistinfo took 0 milliseconds Jan 12 23:14:05 volumio volumio[1009]: verbose: ControllerMpd::parseState Jan 12 23:14:05 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 23:14:05 volumio volumio[1009]: verbose: ControllerMpd::parseTrackInfo Jan 12 23:14:05 volumio volumio[1009]: info: ControllerMpd::pushState Jan 12 23:14:05 volumio volumio[1009]: info: CoreCommandRouter::servicePushState Jan 12 23:14:05 volumio volumio[1009]: info: CorePlayQueue::getTrack 24 Jan 12 23:14:05 volumio volumio[1009]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":205,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"My Oh My","artist":"Aqua","album":"Aquarium","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-mar-98.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Db72ebc8a3fc3c288321b2e9027d311a1f9e50decc0ddba4dd4414ad5c1f12565%2Clid%3D1548%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6791b017%2F32159%2F15424%2F0c657b46.118733572.7.100634%2F320.mp3&ext=.mp3","trackType":"mp3"} Jan 12 23:14:05 volumio volumio[1009]: verbose: CURRENT POSITION 24 Jan 12 23:14:05 volumio volumio[1009]: info: CoreStateMachine::syncState stateService play Jan 12 23:14:05 volumio volumio[1009]: info: CoreStateMachine::syncState currentStatus stop Jan 12 23:14:05 volumio volumio[1009]: info: ------------------------------ 68ms Jan 12 23:14:05 volumio volumio[1009]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 12 23:14:05 volumio volumio[1009]: verbose: ControllerMpd::parseTrackInfo Jan 12 23:14:05 volumio volumio[1009]: info: ControllerMpd::pushState Jan 12 23:14:05 volumio volumio[1009]: info: CoreCommandRouter::servicePushState Jan 12 23:14:05 volumio volumio[1009]: info: CorePlayQueue::getTrack 24 Jan 12 23:14:05 volumio volumio[1009]: verbose: STATE SERVICE {"status":"play","position":0,"seek":757,"duration":205,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"My Oh My","artist":"Aqua","album":"Aquarium","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-mar-98.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Db72ebc8a3fc3c288321b2e9027d311a1f9e50decc0ddba4dd4414ad5c1f12565%2Clid%3D1548%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6791b017%2F32159%2F15424%2F0c657b46.118733572.7.100634%2F320.mp3&ext=.mp3","trackType":"mp3"} Jan 12 23:14:05 volumio volumio[1009]: verbose: CURRENT POSITION 24 Jan 12 23:14:05 volumio volumio[1009]: info: CoreStateMachine::syncState stateService play Jan 12 23:14:05 volumio volumio[1009]: info: CoreStateMachine::syncState currentStatus play Jan 12 23:14:05 volumio volumio[1009]: info: Received an update from plugin. extracting info from payload Jan 12 23:14:05 volumio volumio[1009]: info: CoreStateMachine::pushState Jan 12 23:14:05 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 23:14:05 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jan 12 23:14:05 volumio volumio[1009]: info: CoreStateMachine::pushState Jan 12 23:14:05 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jan 12 23:14:05 volumio volumio[1009]: info: ------------------------------ 12ms Jan 12 23:14:05 volumio volumio[1009]: info: CoreCommandRouter::volumioGetQueue Jan 12 23:14:05 volumio volumio[1009]: info: CoreStateMachine::getQueue Jan 12 23:14:05 volumio volumio[1009]: info: CorePlayQueue::getQueue Jan 12 23:14:05 volumio volumio[1009]: info: CoreCommandRouter::volumioGetQueue Jan 12 23:14:05 volumio volumio[1009]: info: CoreStateMachine::getQueue Jan 12 23:14:05 volumio volumio[1009]: info: CorePlayQueue::getQueue Jan 12 23:14:08 volumio volumio[1009]: info: CoreCommandRouter::volumioSeek Jan 12 23:14:08 volumio volumio[1009]: info: CoreStateMachine::seek Jan 12 23:14:08 volumio volumio[1009]: info: CorePlayQueue::getTrack 24 Jan 12 23:14:08 volumio volumio[1009]: info: TRACKBLOCK {"id":"100634:2510920@micro-genre:europop","service":"yandex_music","type":"song","name":"My Oh My","title":"My Oh My","album":"Aquarium","artist":"Aqua","duration":205,"albumart":"https://avatars.yandex.net/get-music-content/42108/857b253d.a.2510920-1/200x200","uri":"yandex_music/track/100634:2510920@micro-genre:europop","samplerate":"","bitdepth":"","trackType":"","codec":"mp3","bitrate":320} Jan 12 23:14:08 volumio volumio[1009]: info: CoreStateMachine::startPlaybackTimer Jan 12 23:14:08 volumio volumio[1009]: info: CorePlayQueue::getTrack 24 Jan 12 23:14:08 volumio volumio[1009]: info: ControllerMpd::seek Jan 12 23:14:08 volumio volumio[1009]: info: CoreStateMachine::pushState Jan 12 23:14:08 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 23:14:08 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jan 12 23:14:08 volumio volumio[1009]: info: CoreCommandRouter::volumioGetQueue Jan 12 23:14:08 volumio volumio[1009]: info: CoreStateMachine::getQueue Jan 12 23:14:08 volumio volumio[1009]: info: CorePlayQueue::getQueue Jan 12 23:14:11 volumio volumio[1009]: error: null Jan 12 23:14:11 volumio volumio[1009]: info: Jan 12 23:14:11 volumio volumio[1009]: ---------------------------- MPD announces state update: player Jan 12 23:14:11 volumio volumio[1009]: info: ControllerMpd::getState Jan 12 23:14:11 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Jan 12 23:14:11 volumio volumio[1009]: Upnp client error: Error: This socket has been ended by the other party Jan 12 23:14:11 volumio volumio[1009]: info: sendMpdCommand status took 2 milliseconds Jan 12 23:14:11 volumio volumio[1009]: verbose: ControllerMpd::parseState Jan 12 23:14:11 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 23:14:11 volumio volumio[1009]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 12 23:14:11 volumio volumio[1009]: verbose: ControllerMpd::parseTrackInfo Jan 12 23:14:11 volumio volumio[1009]: info: ControllerMpd::pushState Jan 12 23:14:11 volumio volumio[1009]: info: CoreCommandRouter::servicePushState Jan 12 23:14:11 volumio volumio[1009]: info: CorePlayQueue::getTrack 24 Jan 12 23:14:11 volumio volumio[1009]: verbose: STATE SERVICE {"status":"play","position":0,"seek":27000,"duration":205,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"My Oh My","artist":"Aqua","album":"Aquarium","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-mar-98.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Db72ebc8a3fc3c288321b2e9027d311a1f9e50decc0ddba4dd4414ad5c1f12565%2Clid%3D1548%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6791b017%2F32159%2F15424%2F0c657b46.118733572.7.100634%2F320.mp3&ext=.mp3","trackType":"mp3"} Jan 12 23:14:11 volumio volumio[1009]: verbose: CURRENT POSITION 24 Jan 12 23:14:11 volumio volumio[1009]: info: CoreStateMachine::syncState stateService play Jan 12 23:14:11 volumio volumio[1009]: info: CoreStateMachine::syncState currentStatus play Jan 12 23:14:11 volumio volumio[1009]: info: Received an update from plugin. extracting info from payload Jan 12 23:14:11 volumio volumio[1009]: info: CoreStateMachine::pushState Jan 12 23:14:11 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 23:14:11 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jan 12 23:14:11 volumio volumio[1009]: info: CoreStateMachine::pushState Jan 12 23:14:11 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jan 12 23:14:11 volumio volumio[1009]: info: ------------------------------ 11ms Jan 12 23:14:11 volumio volumio[1009]: info: CoreCommandRouter::volumioGetQueue Jan 12 23:14:11 volumio volumio[1009]: info: CoreStateMachine::getQueue Jan 12 23:14:11 volumio volumio[1009]: info: CorePlayQueue::getQueue Jan 12 23:14:11 volumio volumio[1009]: info: CoreCommandRouter::volumioGetQueue Jan 12 23:14:11 volumio volumio[1009]: info: CoreStateMachine::getQueue Jan 12 23:14:11 volumio volumio[1009]: info: CorePlayQueue::getQueue Jan 12 23:14:15 volumio volumio[1009]: info: CoreCommandRouter::volumioNext Jan 12 23:14:15 volumio volumio[1009]: info: CoreStateMachine::next Jan 12 23:14:15 volumio volumio[1009]: info: ControllerMpd::next Jan 12 23:14:15 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand next Jan 12 23:14:15 volumio volumio[1009]: info: Jan 12 23:14:15 volumio volumio[1009]: ---------------------------- MPD announces system playlist update Jan 12 23:14:15 volumio volumio[1009]: info: Ignoring MPD Status Update Jan 12 23:14:15 volumio volumio[1009]: info: Jan 12 23:14:15 volumio volumio[1009]: ---------------------------- MPD announces state update: player Jan 12 23:14:15 volumio volumio[1009]: info: sendMpdCommand next took 32 milliseconds Jan 12 23:14:15 volumio volumio[1009]: info: ControllerMpd::getState Jan 12 23:14:15 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Jan 12 23:14:15 volumio volumio[1009]: error: updateQueue error: null Jan 12 23:14:15 volumio volumio[1009]: info: ------------------------------ 2ms Jan 12 23:14:15 volumio volumio[1009]: info: sendMpdCommand status took 1 milliseconds Jan 12 23:14:15 volumio volumio[1009]: verbose: ControllerMpd::parseState Jan 12 23:14:15 volumio volumio[1009]: info: ControllerMpd::pushState Jan 12 23:14:15 volumio volumio[1009]: info: CoreCommandRouter::servicePushState Jan 12 23:14:15 volumio volumio[1009]: info: CoreStateMachine::pushState Jan 12 23:14:15 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 23:14:15 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jan 12 23:14:15 volumio volumio[1009]: info: CorePlayQueue::getTrack 24 Jan 12 23:14:15 volumio volumio[1009]: 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} Jan 12 23:14:15 volumio volumio[1009]: verbose: CURRENT POSITION 24 Jan 12 23:14:15 volumio volumio[1009]: info: CoreStateMachine::syncState stateService stop Jan 12 23:14:15 volumio volumio[1009]: info: CoreStateMachine::syncState currentStatus play Jan 12 23:14:15 volumio volumio[1009]: info: CoreStateMachine::play index undefined Jan 12 23:14:15 volumio volumio[1009]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 23:14:15 volumio volumio[1009]: info: CoreStateMachine::pushState Jan 12 23:14:15 volumio volumio[1009]: info: CorePlayQueue::getTrack 25 Jan 12 23:14:15 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 23:14:15 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jan 12 23:14:15 volumio volumio[1009]: info: CorePlayQueue::getTrack 25 Jan 12 23:14:15 volumio volumio[1009]: info: CoreStateMachine::startPlaybackTimer Jan 12 23:14:15 volumio volumio[1009]: info: CorePlayQueue::getTrack 25 Jan 12 23:14:15 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand stop Jan 12 23:14:15 volumio volumio[1009]: info: ------------------------------ 15ms Jan 12 23:14:15 volumio volumio[1009]: info: CoreStateMachine::pushState Jan 12 23:14:15 volumio volumio[1009]: info: CorePlayQueue::getTrack 25 Jan 12 23:14:15 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 23:14:15 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jan 12 23:14:15 volumio volumio[1009]: info: sendMpdCommand stop took 16 milliseconds Jan 12 23:14:15 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand clear Jan 12 23:14:15 volumio volumio[1009]: info: CoreCommandRouter::volumioGetQueue Jan 12 23:14:15 volumio volumio[1009]: info: CoreStateMachine::getQueue Jan 12 23:14:15 volumio volumio[1009]: info: CorePlayQueue::getQueue Jan 12 23:14:15 volumio volumio[1009]: info: Jan 12 23:14:15 volumio volumio[1009]: ---------------------------- MPD announces system playlist update Jan 12 23:14:15 volumio volumio[1009]: info: Ignoring MPD Status Update Jan 12 23:14:15 volumio volumio[1009]: info: sendMpdCommand clear took 20 milliseconds Jan 12 23:14:15 volumio volumio[1009]: error: updateQueue error: null Jan 12 23:14:15 volumio volumio[1009]: info: ------------------------------ 39ms Jan 12 23:14:15 volumio volumio[1009]: info: touch_display: Setting screensaver timeout to 0 seconds. Jan 12 23:14:16 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fext-strm-simfcrelcom01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D92edee2749f378ee797ea681378cea466dec43ae112f423dc2acad6ac29ea66d%2Clid%3D1584%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6792049a%2F29871%2F1769903%2F88655f35.13095548.3.2772886%2F320.mp3&ext=.mp3" Jan 12 23:14:16 volumio volumio[1009]: info: Jan 12 23:14:16 volumio volumio[1009]: ---------------------------- MPD announces system playlist update Jan 12 23:14:16 volumio volumio[1009]: info: Ignoring MPD Status Update Jan 12 23:14:16 volumio volumio[1009]: info: sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fext-strm-simfcrelcom01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D92edee2749f378ee797ea681378cea466dec43ae112f423dc2acad6ac29ea66d%2Clid%3D1584%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6792049a%2F29871%2F1769903%2F88655f35.13095548.3.2772886%2F320.mp3&ext=.mp3" took 2 milliseconds Jan 12 23:14:16 volumio volumio[1009]: verbose: MPD COMMAND [object Object] Jan 12 23:14:16 volumio volumio[1009]: verbose: MPD COMMAND [object Object] Jan 12 23:14:16 volumio volumio[1009]: verbose: MPD COMMAND [object Object] Jan 12 23:14:16 volumio volumio[1009]: info: Jan 12 23:14:16 volumio volumio[1009]: ---------------------------- MPD announces system playlist update Jan 12 23:14:16 volumio volumio[1009]: info: Ignoring MPD Status Update Jan 12 23:14:16 volumio volumio[1009]: info: ------------------------------ 2ms Jan 12 23:14:16 volumio volumio[1009]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 12 23:14:16 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand play Jan 12 23:14:16 volumio volumio[1009]: info: ------------------------------ 2ms Jan 12 23:14:16 volumio volumio[1009]: info: sendMpdCommand play took 1 milliseconds Jan 12 23:14:16 volumio volumio[1009]: info: Jan 12 23:14:16 volumio volumio[1009]: ---------------------------- MPD announces state update: player Jan 12 23:14:16 volumio volumio[1009]: info: ControllerMpd::getState Jan 12 23:14:16 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Jan 12 23:14:16 volumio volumio[1009]: info: sendMpdCommand status took 69 milliseconds Jan 12 23:14:16 volumio volumio[1009]: verbose: ControllerMpd::parseState Jan 12 23:14:16 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 23:14:16 volumio volumio[1009]: info: Jan 12 23:14:16 volumio volumio[1009]: ---------------------------- MPD announces state update: player Jan 12 23:14:16 volumio volumio[1009]: info: ControllerMpd::getState Jan 12 23:14:16 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Jan 12 23:14:16 volumio volumio[1009]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 12 23:14:16 volumio volumio[1009]: verbose: ControllerMpd::parseTrackInfo Jan 12 23:14:16 volumio volumio[1009]: info: ControllerMpd::pushState Jan 12 23:14:16 volumio volumio[1009]: info: CoreCommandRouter::servicePushState Jan 12 23:14:16 volumio volumio[1009]: info: CorePlayQueue::getTrack 25 Jan 12 23:14:16 volumio volumio[1009]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":190,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Love You Like A Love Song","artist":"Selena Gomez & the Scene","album":"Love You Like A Love Song","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fext-strm-simfcrelcom01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D92edee2749f378ee797ea681378cea466dec43ae112f423dc2acad6ac29ea66d%2Clid%3D1584%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6792049a%2F29871%2F1769903%2F88655f35.13095548.3.2772886%2F320.mp3&ext=.mp3","trackType":"mp3"} Jan 12 23:14:16 volumio volumio[1009]: verbose: CURRENT POSITION 25 Jan 12 23:14:16 volumio volumio[1009]: info: CoreStateMachine::syncState stateService play Jan 12 23:14:16 volumio volumio[1009]: info: CoreStateMachine::syncState currentStatus stop Jan 12 23:14:16 volumio volumio[1009]: info: ------------------------------ 74ms Jan 12 23:14:16 volumio volumio[1009]: info: sendMpdCommand status took 2 milliseconds Jan 12 23:14:16 volumio volumio[1009]: verbose: ControllerMpd::parseState Jan 12 23:14:16 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 23:14:16 volumio volumio[1009]: info: sendMpdCommand playlistinfo took 0 milliseconds Jan 12 23:14:16 volumio volumio[1009]: verbose: ControllerMpd::parseTrackInfo Jan 12 23:14:16 volumio volumio[1009]: info: ControllerMpd::pushState Jan 12 23:14:16 volumio volumio[1009]: info: CoreCommandRouter::servicePushState Jan 12 23:14:16 volumio volumio[1009]: info: CorePlayQueue::getTrack 25 Jan 12 23:14:16 volumio volumio[1009]: verbose: STATE SERVICE {"status":"play","position":0,"seek":563,"duration":190,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Love You Like A Love Song","artist":"Selena Gomez & the Scene","album":"Love You Like A Love Song","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fext-strm-simfcrelcom01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D92edee2749f378ee797ea681378cea466dec43ae112f423dc2acad6ac29ea66d%2Clid%3D1584%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6792049a%2F29871%2F1769903%2F88655f35.13095548.3.2772886%2F320.mp3&ext=.mp3","trackType":"mp3"} Jan 12 23:14:16 volumio volumio[1009]: verbose: CURRENT POSITION 25 Jan 12 23:14:16 volumio volumio[1009]: info: CoreStateMachine::syncState stateService play Jan 12 23:14:16 volumio volumio[1009]: info: CoreStateMachine::syncState currentStatus play Jan 12 23:14:16 volumio volumio[1009]: info: Received an update from plugin. extracting info from payload Jan 12 23:14:16 volumio volumio[1009]: info: CoreStateMachine::pushState Jan 12 23:14:16 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 23:14:16 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jan 12 23:14:16 volumio volumio[1009]: info: CoreStateMachine::pushState Jan 12 23:14:16 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jan 12 23:14:16 volumio volumio[1009]: info: ------------------------------ 12ms Jan 12 23:14:16 volumio volumio[1009]: info: CoreCommandRouter::volumioGetQueue Jan 12 23:14:16 volumio volumio[1009]: info: CoreStateMachine::getQueue Jan 12 23:14:16 volumio volumio[1009]: info: CorePlayQueue::getQueue Jan 12 23:14:16 volumio volumio[1009]: info: CoreCommandRouter::volumioGetQueue Jan 12 23:14:16 volumio volumio[1009]: info: CoreStateMachine::getQueue Jan 12 23:14:16 volumio volumio[1009]: info: CorePlayQueue::getQueue Jan 12 23:14:17 volumio volumio[1009]: info: CoreCommandRouter::volumioAddQueueItems Jan 12 23:14:17 volumio volumio[1009]: info: CoreStateMachine::addQueueItems Jan 12 23:14:17 volumio volumio[1009]: info: CorePlayQueue::addQueueItems Jan 12 23:14:17 volumio volumio[1009]: info: Preload queue cleared Jan 12 23:14:17 volumio volumio[1009]: info: Adding Item to queue: yandex_music/track/70150091:11827098@micro-genre:europop Jan 12 23:14:17 volumio volumio[1009]: info: Exploding uri yandex_music/track/70150091:11827098@micro-genre:europop in service yandex_music Jan 12 23:14:17 volumio volumio[1009]: info: CoreCommandRouter::volumioPushQueue Jan 12 23:14:17 volumio volumio[1009]: info: CorePlayQueue::saveQueue Jan 12 23:14:17 volumio volumio[1009]: info: CoreStateMachine::updateTrackBlock Jan 12 23:14:17 volumio volumio[1009]: info: CorePlayQueue::getTrackBlock Jan 12 23:14:25 volumio volumio[1009]: info: CoreCommandRouter::volumioNext Jan 12 23:14:25 volumio volumio[1009]: info: CoreStateMachine::next Jan 12 23:14:25 volumio volumio[1009]: info: ControllerMpd::next Jan 12 23:14:25 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand next Jan 12 23:14:25 volumio volumio[1009]: info: sendMpdCommand next took 50 milliseconds Jan 12 23:14:25 volumio volumio[1009]: info: Jan 12 23:14:25 volumio volumio[1009]: ---------------------------- MPD announces system playlist update Jan 12 23:14:25 volumio volumio[1009]: info: Ignoring MPD Status Update Jan 12 23:14:25 volumio volumio[1009]: info: Jan 12 23:14:25 volumio volumio[1009]: ---------------------------- MPD announces state update: player Jan 12 23:14:25 volumio volumio[1009]: info: ControllerMpd::getState Jan 12 23:14:25 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand status Jan 12 23:14:25 volumio volumio[1009]: error: updateQueue error: null Jan 12 23:14:25 volumio volumio[1009]: info: ------------------------------ 2ms Jan 12 23:14:25 volumio volumio[1009]: info: sendMpdCommand status took 1 milliseconds Jan 12 23:14:25 volumio volumio[1009]: verbose: ControllerMpd::parseState Jan 12 23:14:25 volumio volumio[1009]: info: ControllerMpd::pushState Jan 12 23:14:25 volumio volumio[1009]: info: CoreCommandRouter::servicePushState Jan 12 23:14:25 volumio volumio[1009]: info: CoreStateMachine::pushState Jan 12 23:14:25 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 23:14:25 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jan 12 23:14:25 volumio volumio[1009]: info: CorePlayQueue::getTrack 25 Jan 12 23:14:25 volumio volumio[1009]: 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} Jan 12 23:14:25 volumio volumio[1009]: verbose: CURRENT POSITION 25 Jan 12 23:14:25 volumio volumio[1009]: info: CoreStateMachine::syncState stateService stop Jan 12 23:14:25 volumio volumio[1009]: info: CoreStateMachine::syncState currentStatus play Jan 12 23:14:25 volumio volumio[1009]: info: CoreStateMachine::play index undefined Jan 12 23:14:25 volumio volumio[1009]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 23:14:25 volumio volumio[1009]: info: CoreStateMachine::pushState Jan 12 23:14:25 volumio volumio[1009]: info: CorePlayQueue::getTrack 26 Jan 12 23:14:25 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 23:14:25 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jan 12 23:14:25 volumio volumio[1009]: info: CorePlayQueue::getTrack 26 Jan 12 23:14:25 volumio volumio[1009]: info: CoreStateMachine::startPlaybackTimer Jan 12 23:14:25 volumio volumio[1009]: info: CorePlayQueue::getTrack 26 Jan 12 23:14:25 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand stop Jan 12 23:14:25 volumio volumio[1009]: info: ------------------------------ 10ms Jan 12 23:14:25 volumio volumio[1009]: info: CoreStateMachine::pushState Jan 12 23:14:25 volumio volumio[1009]: info: CorePlayQueue::getTrack 26 Jan 12 23:14:25 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 23:14:25 volumio volumio[1009]: info: CoreCommandRouter::volumioPushState Jan 12 23:14:25 volumio volumio[1009]: info: sendMpdCommand stop took 9 milliseconds Jan 12 23:14:25 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand clear Jan 12 23:14:25 volumio volumio[1009]: info: Jan 12 23:14:25 volumio volumio[1009]: ---------------------------- MPD announces system playlist update Jan 12 23:14:25 volumio volumio[1009]: info: Ignoring MPD Status Update Jan 12 23:14:25 volumio volumio[1009]: info: sendMpdCommand clear took 3 milliseconds Jan 12 23:14:25 volumio volumio[1009]: error: updateQueue error: null Jan 12 23:14:25 volumio volumio[1009]: info: ------------------------------ 5ms Jan 12 23:14:25 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=flac&url=https%3A%2F%2Fext-strm-simfcrelcom01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D5ae1e20d2d405a32d3028d3d0bf8c94f41e535797da2f406dfe0bf4e94c360c4%2Clid%3D1584%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67916c4f%2F0%2F5222349%2F41edbdef.99482670.12.66037736%2Fflac&ext=.flac" Jan 12 23:14:25 volumio volumio[1009]: info: Jan 12 23:14:25 volumio volumio[1009]: ---------------------------- MPD announces system playlist update Jan 12 23:14:25 volumio volumio[1009]: info: Ignoring MPD Status Update Jan 12 23:14:25 volumio volumio[1009]: info: sendMpdCommand addid "http://localhost:6601/?codec=flac&url=https%3A%2F%2Fext-strm-simfcrelcom01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D5ae1e20d2d405a32d3028d3d0bf8c94f41e535797da2f406dfe0bf4e94c360c4%2Clid%3D1584%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67916c4f%2F0%2F5222349%2F41edbdef.99482670.12.66037736%2Fflac&ext=.flac" took 2 milliseconds Jan 12 23:14:25 volumio volumio[1009]: verbose: MPD COMMAND [object Object] Jan 12 23:14:25 volumio volumio[1009]: verbose: MPD COMMAND [object Object] Jan 12 23:14:25 volumio volumio[1009]: verbose: MPD COMMAND [object Object] Jan 12 23:14:25 volumio volumio[1009]: info: Jan 12 23:14:25 volumio volumio[1009]: ---------------------------- MPD announces system playlist update Jan 12 23:14:25 volumio volumio[1009]: info: Ignoring MPD Status Update Jan 12 23:14:25 volumio volumio[1009]: info: ------------------------------ 2ms Jan 12 23:14:25 volumio volumio[1009]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 12 23:14:25 volumio volumio[1009]: verbose: ControllerMpd::sendMpdCommand play Jan 12 23:14:25 volumio volumio[1009]: info: ------------------------------ 1ms Jan 12 23:14:25 volumio volumio[1009]: info: sendMpdCommand play took 1 milliseconds Jan 12 23:14:26 volumio volumio[1009]: info: touch_display: Setting screensaver timeout to 0 seconds. Jan 12 23:14:27 volumio volumio[1009]: info: CoreCommandRouter::volumioGetQueue Jan 12 23:14:27 volumio volumio[1009]: info: CoreStateMachine::getQueue Jan 12 23:14:27 volumio volumio[1009]: info: CorePlayQueue::getQueue Jan 12 23:14:36 volumio nmbd[735]: [2025/01/12 23:14:36.987376, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jan 12 23:14:36 volumio nmbd[735]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.222 for name WORKGROUP<1d>. Jan 12 23:14:36 volumio nmbd[735]: This response was from IP 192.168.1.104, reporting an IP address of 192.168.1.104. Jan 12 23:14:37 volumio volumio[1009]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 12 23:14:37 volumio volumio[1009]: Error: read ECONNRESET Jan 12 23:14:37 volumio volumio[1009]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:209:20) { Jan 12 23:14:37 volumio volumio[1009]: errno: -104, Jan 12 23:14:37 volumio volumio[1009]: code: 'ECONNRESET', Jan 12 23:14:37 volumio volumio[1009]: syscall: 'read' Jan 12 23:14:37 volumio volumio[1009]: } Jan 12 23:14:37 volumio volumio[1009]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 12 23:14:37 volumio sudo[2704]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-12 23:13 Jan 12 23:14:37 volumio sudo[2704]: 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"