-- Logs begin at Thu 2019-02-14 13:11:59 MSK, end at Sat 2025-02-15 20:33:57 MSK. -- Feb 15 20:32:02 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: yandex_music , handleBrowseUri Feb 15 20:32:02 volumio volumio[1047]: info: Preload queue cleared Feb 15 20:32:02 volumio volumio[1047]: info: Preloading song: yandex_music/track/51290348:7143175@7143175 Feb 15 20:32:02 volumio volumio[1047]: info: Exploding uri yandex_music/track/51290348:7143175@7143175 in service yandex_music Feb 15 20:32:03 volumio volumio[1047]: info: Preload queue cleared Feb 15 20:32:03 volumio volumio[1047]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 15 20:32:03 volumio volumio[1047]: info: CoreStateMachine::ClearQueue Feb 15 20:32:03 volumio volumio[1047]: info: CoreStateMachine::stop Feb 15 20:32:03 volumio volumio[1047]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 15 20:32:03 volumio volumio[1047]: info: CoreStateMachine::stPlaybackTimer Feb 15 20:32:03 volumio volumio[1047]: info: CoreStateMachine::updateTrackBlock Feb 15 20:32:03 volumio volumio[1047]: info: CorePlayQueue::getTrackBlock Feb 15 20:32:03 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:32:03 volumio volumio[1047]: info: CorePlayQueue::getTrack 3 Feb 15 20:32:03 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 20:32:03 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:32:03 volumio volumio[1047]: info: CoreStateMachine::serviceStop Feb 15 20:32:03 volumio volumio[1047]: info: CorePlayQueue::getTrack 3 Feb 15 20:32:03 volumio volumio[1047]: info: CoreCommandRouter::serviceStop Feb 15 20:32:03 volumio volumio[1047]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 15 20:32:03 volumio volumio[1047]: info: ControllerMpd::stop Feb 15 20:32:03 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand stop Feb 15 20:32:03 volumio volumio[1047]: info: CorePlayQueue::clearPlayQueue Feb 15 20:32:03 volumio volumio[1047]: info: CorePlayQueue::saveQueue Feb 15 20:32:03 volumio volumio[1047]: info: CoreCommandRouter::volumioPushQueue Feb 15 20:32:03 volumio volumio[1047]: info: CoreStateMachine::addQueueItems Feb 15 20:32:03 volumio volumio[1047]: info: CorePlayQueue::addQueueItems Feb 15 20:32:03 volumio volumio[1047]: info: Preload queue cleared Feb 15 20:32:03 volumio volumio[1047]: info: Adding Item to queue: yandex_music/track/51290348:7143175@7143175 Feb 15 20:32:03 volumio volumio[1047]: info: Using cached record of: yandex_music/track/51290348:7143175@7143175 Feb 15 20:32:03 volumio volumio[1047]: info: CoreCommandRouter::volumioPushQueue Feb 15 20:32:03 volumio volumio[1047]: info: CorePlayQueue::saveQueue Feb 15 20:32:03 volumio volumio[1047]: info: CoreStateMachine::updateTrackBlock Feb 15 20:32:03 volumio volumio[1047]: info: CorePlayQueue::getTrackBlock Feb 15 20:32:03 volumio volumio[1047]: info: CoreCommandRouter::volumioPlay Feb 15 20:32:03 volumio volumio[1047]: info: CoreStateMachine::play index 0 Feb 15 20:32:03 volumio volumio[1047]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 15 20:32:03 volumio volumio[1047]: info: CoreStateMachine::stop Feb 15 20:32:03 volumio volumio[1047]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 15 20:32:03 volumio volumio[1047]: info: CoreStateMachine::play index undefined Feb 15 20:32:03 volumio volumio[1047]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 15 20:32:03 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 15 20:32:03 volumio volumio[1047]: info: CoreStateMachine::startPlaybackTimer Feb 15 20:32:03 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 15 20:32:03 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand stop Feb 15 20:32:03 volumio volumio[1047]: info: Feb 15 20:32:03 volumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 15 20:32:03 volumio volumio[1047]: info: sendMpdCommand stop took 33 milliseconds Feb 15 20:32:03 volumio volumio[1047]: info: ControllerMpd::getState Feb 15 20:32:03 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:32:03 volumio volumio[1047]: info: sendMpdCommand stop took 27 milliseconds Feb 15 20:32:03 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand clear Feb 15 20:32:03 volumio volumio[1047]: info: Feb 15 20:32:03 volumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 15 20:32:03 volumio volumio[1047]: info: ControllerMpd::getState Feb 15 20:32:03 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:32:03 volumio volumio[1047]: info: Feb 15 20:32:03 volumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 15 20:32:03 volumio volumio[1047]: info: ControllerMpd::getState Feb 15 20:32:03 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:32:03 volumio volumio[1047]: info: Feb 15 20:32:03 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:32:03 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:32:03 volumio volumio[1047]: info: sendMpdCommand status took 3 milliseconds Feb 15 20:32:03 volumio volumio[1047]: info: sendMpdCommand clear took 2 milliseconds Feb 15 20:32:03 volumio volumio[1047]: info: sendMpdCommand status took 2 milliseconds Feb 15 20:32:03 volumio volumio[1047]: info: sendMpdCommand status took 1 milliseconds Feb 15 20:32:03 volumio volumio[1047]: verbose: ControllerMpd::parseState Feb 15 20:32:03 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 20:32:03 volumio volumio[1047]: verbose: ControllerMpd::parseState Feb 15 20:32:03 volumio volumio[1047]: verbose: ControllerMpd::parseState Feb 15 20:32:03 volumio volumio[1047]: info: ControllerMpd::pushState Feb 15 20:32:03 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 15 20:32:03 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:32:03 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 15 20:32:03 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 20:32:03 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:32:03 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 15 20:32:03 volumio volumio[1047]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd Feb 15 20:32:03 volumio volumio[1047]: info: ControllerMpd::pushState Feb 15 20:32:03 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 15 20:32:03 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:32:03 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 15 20:32:03 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:32:03 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 15 20:32:03 volumio volumio[1047]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd Feb 15 20:32:03 volumio volumio[1047]: info: ------------------------------ 9ms Feb 15 20:32:03 volumio volumio[1047]: info: ------------------------------ 8ms Feb 15 20:32:03 volumio volumio[1047]: info: Feb 15 20:32:03 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:32:03 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:32:03 volumio volumio[1047]: info: Feb 15 20:32:03 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:32:03 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:32:03 volumio volumio[1047]: error: updateQueue error: null Feb 15 20:32:03 volumio volumio[1047]: error: updateQueue error: null Feb 15 20:32:03 volumio volumio[1047]: info: ------------------------------ 13ms Feb 15 20:32:03 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 12 milliseconds Feb 15 20:32:03 volumio volumio[1047]: info: ------------------------------ 2ms Feb 15 20:32:03 volumio volumio[1047]: info: ------------------------------ 1ms Feb 15 20:32:03 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Feb 15 20:32:03 volumio volumio[1047]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 15 20:32:03 volumio volumio[1047]: info: ------------------------------ 16ms Feb 15 20:32:03 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2F1557.cold-rtc.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dc7ed71074afec94961f8d3d694de4e5b5f41d00509ceab5def1314527740a705%2Clid%3D1557%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67bea101%2F0%2F1542931%2F7b6e62eb.76205888.9.51290348%2F320.mp3&ext=.mp3" Feb 15 20:32:03 volumio volumio[1047]: info: Feb 15 20:32:03 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:32:03 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:32:03 volumio volumio[1047]: info: sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2F1557.cold-rtc.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dc7ed71074afec94961f8d3d694de4e5b5f41d00509ceab5def1314527740a705%2Clid%3D1557%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67bea101%2F0%2F1542931%2F7b6e62eb.76205888.9.51290348%2F320.mp3&ext=.mp3" took 1 milliseconds Feb 15 20:32:03 volumio volumio[1047]: verbose: MPD COMMAND [object Object] Feb 15 20:32:03 volumio volumio[1047]: verbose: MPD COMMAND [object Object] Feb 15 20:32:03 volumio volumio[1047]: info: Feb 15 20:32:03 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:32:03 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:32:03 volumio volumio[1047]: info: Feb 15 20:32:03 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:32:03 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:32:03 volumio volumio[1047]: info: Feb 15 20:32:03 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:32:03 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:32:03 volumio volumio[1047]: info: ------------------------------ 2ms Feb 15 20:32:03 volumio volumio[1047]: info: ------------------------------ 2ms Feb 15 20:32:03 volumio volumio[1047]: info: ------------------------------ 1ms Feb 15 20:32:03 volumio volumio[1047]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 15 20:32:03 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand play Feb 15 20:32:03 volumio volumio[1047]: info: Feb 15 20:32:03 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:32:03 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:32:03 volumio volumio[1047]: info: Feb 15 20:32:03 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:32:03 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:32:03 volumio volumio[1047]: info: ------------------------------ 2ms Feb 15 20:32:03 volumio volumio[1047]: info: sendMpdCommand play took 2 milliseconds Feb 15 20:32:03 volumio volumio[1047]: info: ------------------------------ 1ms Feb 15 20:32:03 volumio volumio[1047]: info: ------------------------------ 2ms Feb 15 20:32:04 volumio volumio[1047]: info: Feb 15 20:32:04 volumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 15 20:32:04 volumio volumio[1047]: info: ControllerMpd::getState Feb 15 20:32:04 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:32:04 volumio volumio[1047]: info: Feb 15 20:32:04 volumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 15 20:32:04 volumio volumio[1047]: info: ControllerMpd::getState Feb 15 20:32:04 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:32:04 volumio volumio[1047]: info: Feb 15 20:32:04 volumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 15 20:32:04 volumio volumio[1047]: info: ControllerMpd::getState Feb 15 20:32:04 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:32:04 volumio volumio[1047]: info: Feb 15 20:32:04 volumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 15 20:32:04 volumio volumio[1047]: info: sendMpdCommand status took 7 milliseconds Feb 15 20:32:04 volumio volumio[1047]: info: sendMpdCommand status took 6 milliseconds Feb 15 20:32:04 volumio volumio[1047]: info: ControllerMpd::getState Feb 15 20:32:04 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:32:04 volumio volumio[1047]: info: sendMpdCommand status took 2 milliseconds Feb 15 20:32:04 volumio volumio[1047]: verbose: ControllerMpd::parseState Feb 15 20:32:04 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 20:32:04 volumio volumio[1047]: verbose: ControllerMpd::parseState Feb 15 20:32:04 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 20:32:04 volumio volumio[1047]: verbose: ControllerMpd::parseState Feb 15 20:32:04 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 20:32:04 volumio volumio[1047]: info: Feb 15 20:32:04 volumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 15 20:32:04 volumio volumio[1047]: info: ControllerMpd::getState Feb 15 20:32:04 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:32:04 volumio volumio[1047]: info: Feb 15 20:32:04 volumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 15 20:32:04 volumio volumio[1047]: info: ControllerMpd::getState Feb 15 20:32:04 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:32:04 volumio volumio[1047]: info: sendMpdCommand status took 5 milliseconds Feb 15 20:32:04 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 15 20:32:04 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 15 20:32:04 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 15 20:32:04 volumio volumio[1047]: info: sendMpdCommand status took 2 milliseconds Feb 15 20:32:04 volumio volumio[1047]: info: sendMpdCommand status took 1 milliseconds Feb 15 20:32:04 volumio volumio[1047]: verbose: ControllerMpd::parseState Feb 15 20:32:04 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 20:32:04 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Feb 15 20:32:04 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Feb 15 20:32:04 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Feb 15 20:32:04 volumio volumio[1047]: verbose: ControllerMpd::parseState Feb 15 20:32:04 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 20:32:04 volumio volumio[1047]: verbose: ControllerMpd::parseState Feb 15 20:32:04 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 20:32:04 volumio volumio[1047]: info: ControllerMpd::pushState Feb 15 20:32:04 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 15 20:32:04 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 15 20:32:04 volumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":38,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Burlesque in C Major","artist":"AM Records","album":"Burlesque in C Major","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2F1557.cold-rtc.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dc7ed71074afec94961f8d3d694de4e5b5f41d00509ceab5def1314527740a705%2Clid%3D1557%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67bea101%2F0%2F1542931%2F7b6e62eb.76205888.9.51290348%2F320.mp3&ext=.mp3","trackType":"mp3"} Feb 15 20:32:04 volumio volumio[1047]: verbose: CURRENT POSITION 0 Feb 15 20:32:04 volumio volumio[1047]: info: CoreStateMachine::syncState stateService play Feb 15 20:32:04 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus stop Feb 15 20:32:04 volumio volumio[1047]: info: ControllerMpd::pushState Feb 15 20:32:04 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 15 20:32:04 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 15 20:32:04 volumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":38,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Burlesque in C Major","artist":"AM Records","album":"Burlesque in C Major","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2F1557.cold-rtc.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dc7ed71074afec94961f8d3d694de4e5b5f41d00509ceab5def1314527740a705%2Clid%3D1557%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67bea101%2F0%2F1542931%2F7b6e62eb.76205888.9.51290348%2F320.mp3&ext=.mp3","trackType":"mp3"} Feb 15 20:32:04 volumio volumio[1047]: verbose: CURRENT POSITION 0 Feb 15 20:32:04 volumio volumio[1047]: info: CoreStateMachine::syncState stateService play Feb 15 20:32:04 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus play Feb 15 20:32:04 volumio volumio[1047]: info: Received an update from plugin. extracting info from payload Feb 15 20:32:04 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:32:04 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 20:32:04 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:32:04 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:32:04 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:32:04 volumio volumio[1047]: info: ControllerMpd::pushState Feb 15 20:32:04 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 15 20:32:04 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 15 20:32:04 volumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":38,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Burlesque in C Major","artist":"AM Records","album":"Burlesque in C Major","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2F1557.cold-rtc.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dc7ed71074afec94961f8d3d694de4e5b5f41d00509ceab5def1314527740a705%2Clid%3D1557%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67bea101%2F0%2F1542931%2F7b6e62eb.76205888.9.51290348%2F320.mp3&ext=.mp3","trackType":"mp3"} Feb 15 20:32:04 volumio volumio[1047]: verbose: CURRENT POSITION 0 Feb 15 20:32:04 volumio volumio[1047]: info: CoreStateMachine::syncState stateService play Feb 15 20:32:04 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus play Feb 15 20:32:04 volumio volumio[1047]: info: Received an update from plugin. extracting info from payload Feb 15 20:32:04 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:32:04 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:32:04 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:32:04 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:32:04 volumio volumio[1047]: info: ------------------------------ 21ms Feb 15 20:32:04 volumio volumio[1047]: info: ------------------------------ 25ms Feb 15 20:32:04 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 14 milliseconds Feb 15 20:32:04 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 13 milliseconds Feb 15 20:32:04 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Feb 15 20:32:04 volumio volumio[1047]: info: ControllerMpd::pushState Feb 15 20:32:04 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 15 20:32:04 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 15 20:32:04 volumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":494,"duration":38,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Burlesque in C Major","artist":"AM Records","album":"Burlesque in C Major","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2F1557.cold-rtc.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dc7ed71074afec94961f8d3d694de4e5b5f41d00509ceab5def1314527740a705%2Clid%3D1557%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67bea101%2F0%2F1542931%2F7b6e62eb.76205888.9.51290348%2F320.mp3&ext=.mp3","trackType":"mp3"} Feb 15 20:32:04 volumio volumio[1047]: verbose: CURRENT POSITION 0 Feb 15 20:32:04 volumio volumio[1047]: info: CoreStateMachine::syncState stateService play Feb 15 20:32:04 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus play Feb 15 20:32:04 volumio volumio[1047]: info: Received an update from plugin. extracting info from payload Feb 15 20:32:04 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:32:04 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 20:32:04 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:32:04 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:32:04 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:32:04 volumio volumio[1047]: info: ControllerMpd::pushState Feb 15 20:32:04 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 15 20:32:04 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 15 20:32:04 volumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":883,"duration":38,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Burlesque in C Major","artist":"AM Records","album":"Burlesque in C Major","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2F1557.cold-rtc.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dc7ed71074afec94961f8d3d694de4e5b5f41d00509ceab5def1314527740a705%2Clid%3D1557%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67bea101%2F0%2F1542931%2F7b6e62eb.76205888.9.51290348%2F320.mp3&ext=.mp3","trackType":"mp3"} Feb 15 20:32:04 volumio volumio[1047]: verbose: CURRENT POSITION 0 Feb 15 20:32:04 volumio volumio[1047]: info: CoreStateMachine::syncState stateService play Feb 15 20:32:04 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus play Feb 15 20:32:04 volumio volumio[1047]: info: Received an update from plugin. extracting info from payload Feb 15 20:32:04 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:32:04 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:32:04 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:32:04 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:32:04 volumio volumio[1047]: info: ControllerMpd::pushState Feb 15 20:32:04 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 15 20:32:04 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 15 20:32:04 volumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":883,"duration":38,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Burlesque in C Major","artist":"AM Records","album":"Burlesque in C Major","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2F1557.cold-rtc.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dc7ed71074afec94961f8d3d694de4e5b5f41d00509ceab5def1314527740a705%2Clid%3D1557%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67bea101%2F0%2F1542931%2F7b6e62eb.76205888.9.51290348%2F320.mp3&ext=.mp3","trackType":"mp3"} Feb 15 20:32:04 volumio volumio[1047]: verbose: CURRENT POSITION 0 Feb 15 20:32:04 volumio volumio[1047]: info: CoreStateMachine::syncState stateService play Feb 15 20:32:04 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus play Feb 15 20:32:04 volumio volumio[1047]: info: Received an update from plugin. extracting info from payload Feb 15 20:32:04 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:32:04 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:32:04 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:32:04 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:32:04 volumio volumio[1047]: info: ------------------------------ 35ms Feb 15 20:32:04 volumio volumio[1047]: info: ------------------------------ 33ms Feb 15 20:32:04 volumio volumio[1047]: info: ------------------------------ 32ms Feb 15 20:32:04 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:32:04 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:32:04 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:32:04 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:32:04 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:32:04 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:32:04 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:32:04 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:32:04 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:32:04 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:32:04 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:32:04 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:32:04 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:32:04 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:32:04 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:32:04 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:32:04 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:32:04 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:32:04 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:32:04 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:32:04 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:32:04 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:32:04 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:32:04 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:32:04 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:32:04 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:32:04 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:32:04 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:32:04 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:32:04 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:32:11 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: yandex_music , handleBrowseUri Feb 15 20:32:12 volumio volumio[1047]: info: Preload queue cleared Feb 15 20:32:12 volumio volumio[1047]: info: Preloading song: yandex_music/track/19103545:2139578@2139578 Feb 15 20:32:12 volumio volumio[1047]: info: Preloading song: yandex_music/track/19103537:2139578@2139578 Feb 15 20:32:12 volumio volumio[1047]: info: Preloading song: yandex_music/track/19103544:2139578@2139578 Feb 15 20:32:12 volumio volumio[1047]: info: Preloading song: yandex_music/track/19103541:2139578@2139578 Feb 15 20:32:12 volumio volumio[1047]: info: Preloading song: yandex_music/track/19103539:2139578@2139578 Feb 15 20:32:12 volumio volumio[1047]: info: Preloading song: yandex_music/track/19103551:2139578@2139578 Feb 15 20:32:12 volumio volumio[1047]: info: Preloading song: yandex_music/track/19103540:2139578@2139578 Feb 15 20:32:12 volumio volumio[1047]: info: Preloading song: yandex_music/track/19103553:2139578@2139578 Feb 15 20:32:12 volumio volumio[1047]: info: Preloading song: yandex_music/track/19103546:2139578@2139578 Feb 15 20:32:12 volumio volumio[1047]: info: Preloading song: yandex_music/track/19103542:2139578@2139578 Feb 15 20:32:12 volumio volumio[1047]: info: Preloading song: yandex_music/track/19103543:2139578@2139578 Feb 15 20:32:12 volumio volumio[1047]: info: Preloading song: yandex_music/track/19103550:2139578@2139578 Feb 15 20:32:12 volumio volumio[1047]: info: Preloading song: yandex_music/track/19103547:2139578@2139578 Feb 15 20:32:12 volumio volumio[1047]: info: Preloading song: yandex_music/track/19103552:2139578@2139578 Feb 15 20:32:12 volumio volumio[1047]: info: Preloading song: yandex_music/track/19103554:2139578@2139578 Feb 15 20:32:12 volumio volumio[1047]: info: Preloading song: yandex_music/track/19103549:2139578@2139578 Feb 15 20:32:12 volumio volumio[1047]: info: Preloading song: yandex_music/track/19103538:2139578@2139578 Feb 15 20:32:12 volumio volumio[1047]: info: Preloading song: yandex_music/track/19103548:2139578@2139578 Feb 15 20:32:12 volumio volumio[1047]: info: Exploding uri yandex_music/track/19103545:2139578@2139578 in service yandex_music Feb 15 20:32:12 volumio volumio[1047]: info: Exploding uri yandex_music/track/19103537:2139578@2139578 in service yandex_music Feb 15 20:32:12 volumio volumio[1047]: info: Exploding uri yandex_music/track/19103544:2139578@2139578 in service yandex_music Feb 15 20:32:12 volumio volumio[1047]: info: Exploding uri yandex_music/track/19103541:2139578@2139578 in service yandex_music Feb 15 20:32:12 volumio volumio[1047]: info: Exploding uri yandex_music/track/19103539:2139578@2139578 in service yandex_music Feb 15 20:32:12 volumio volumio[1047]: info: Exploding uri yandex_music/track/19103551:2139578@2139578 in service yandex_music Feb 15 20:32:12 volumio volumio[1047]: info: Exploding uri yandex_music/track/19103540:2139578@2139578 in service yandex_music Feb 15 20:32:12 volumio volumio[1047]: info: Exploding uri yandex_music/track/19103553:2139578@2139578 in service yandex_music Feb 15 20:32:12 volumio volumio[1047]: info: Exploding uri yandex_music/track/19103546:2139578@2139578 in service yandex_music Feb 15 20:32:12 volumio volumio[1047]: info: Exploding uri yandex_music/track/19103542:2139578@2139578 in service yandex_music Feb 15 20:32:12 volumio volumio[1047]: info: Exploding uri yandex_music/track/19103543:2139578@2139578 in service yandex_music Feb 15 20:32:12 volumio volumio[1047]: info: Exploding uri yandex_music/track/19103550:2139578@2139578 in service yandex_music Feb 15 20:32:12 volumio volumio[1047]: info: Exploding uri yandex_music/track/19103547:2139578@2139578 in service yandex_music Feb 15 20:32:12 volumio volumio[1047]: info: Exploding uri yandex_music/track/19103552:2139578@2139578 in service yandex_music Feb 15 20:32:12 volumio volumio[1047]: info: Exploding uri yandex_music/track/19103554:2139578@2139578 in service yandex_music Feb 15 20:32:12 volumio volumio[1047]: info: Exploding uri yandex_music/track/19103549:2139578@2139578 in service yandex_music Feb 15 20:32:13 volumio volumio[1047]: info: Exploding uri yandex_music/track/19103538:2139578@2139578 in service yandex_music Feb 15 20:32:13 volumio volumio[1047]: info: Exploding uri yandex_music/track/19103548:2139578@2139578 in service yandex_music Feb 15 20:32:13 volumio volumio[1047]: info: Preload queue cleared Feb 15 20:32:13 volumio volumio[1047]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 15 20:32:13 volumio volumio[1047]: info: CoreStateMachine::ClearQueue Feb 15 20:32:13 volumio volumio[1047]: info: CoreStateMachine::stop Feb 15 20:32:13 volumio volumio[1047]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 15 20:32:13 volumio volumio[1047]: info: CoreStateMachine::stPlaybackTimer Feb 15 20:32:13 volumio volumio[1047]: info: CoreStateMachine::updateTrackBlock Feb 15 20:32:13 volumio volumio[1047]: info: CorePlayQueue::getTrackBlock Feb 15 20:32:13 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:32:13 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 15 20:32:13 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 20:32:13 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:32:13 volumio volumio[1047]: info: CoreStateMachine::serviceStop Feb 15 20:32:13 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 15 20:32:13 volumio volumio[1047]: info: CoreCommandRouter::serviceStop Feb 15 20:32:13 volumio volumio[1047]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 15 20:32:13 volumio volumio[1047]: info: ControllerMpd::stop Feb 15 20:32:13 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand stop Feb 15 20:32:13 volumio volumio[1047]: info: CorePlayQueue::clearPlayQueue Feb 15 20:32:13 volumio volumio[1047]: info: CorePlayQueue::saveQueue Feb 15 20:32:13 volumio volumio[1047]: info: CoreCommandRouter::volumioPushQueue Feb 15 20:32:13 volumio volumio[1047]: info: CoreStateMachine::addQueueItems Feb 15 20:32:13 volumio volumio[1047]: info: CorePlayQueue::addQueueItems Feb 15 20:32:13 volumio volumio[1047]: info: Preload queue cleared Feb 15 20:32:13 volumio volumio[1047]: info: Adding Item to queue: yandex_music/track/19103545:2139578@2139578 Feb 15 20:32:13 volumio volumio[1047]: info: Using cached record of: yandex_music/track/19103545:2139578@2139578 Feb 15 20:32:13 volumio volumio[1047]: info: CoreCommandRouter::volumioPushQueue Feb 15 20:32:13 volumio volumio[1047]: info: CorePlayQueue::saveQueue Feb 15 20:32:13 volumio volumio[1047]: info: CoreStateMachine::updateTrackBlock Feb 15 20:32:13 volumio volumio[1047]: info: CorePlayQueue::getTrackBlock Feb 15 20:32:13 volumio volumio[1047]: info: CoreCommandRouter::volumioPlay Feb 15 20:32:13 volumio volumio[1047]: info: CoreStateMachine::play index 0 Feb 15 20:32:13 volumio volumio[1047]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 15 20:32:13 volumio volumio[1047]: info: CoreStateMachine::addQueueItems Feb 15 20:32:13 volumio volumio[1047]: info: CorePlayQueue::addQueueItems Feb 15 20:32:13 volumio volumio[1047]: info: Preload queue cleared Feb 15 20:32:13 volumio volumio[1047]: info: Adding Item to queue: yandex_music/track/19103537:2139578@2139578 Feb 15 20:32:13 volumio volumio[1047]: info: Using cached record of: yandex_music/track/19103537:2139578@2139578 Feb 15 20:32:13 volumio volumio[1047]: info: Adding Item to queue: yandex_music/track/19103544:2139578@2139578 Feb 15 20:32:13 volumio volumio[1047]: info: Using cached record of: yandex_music/track/19103544:2139578@2139578 Feb 15 20:32:13 volumio volumio[1047]: info: Adding Item to queue: yandex_music/track/19103541:2139578@2139578 Feb 15 20:32:13 volumio volumio[1047]: info: Using cached record of: yandex_music/track/19103541:2139578@2139578 Feb 15 20:32:13 volumio volumio[1047]: info: Adding Item to queue: yandex_music/track/19103539:2139578@2139578 Feb 15 20:32:13 volumio volumio[1047]: info: Using cached record of: yandex_music/track/19103539:2139578@2139578 Feb 15 20:32:13 volumio volumio[1047]: info: Adding Item to queue: yandex_music/track/19103551:2139578@2139578 Feb 15 20:32:13 volumio volumio[1047]: info: Using cached record of: yandex_music/track/19103551:2139578@2139578 Feb 15 20:32:13 volumio volumio[1047]: info: Adding Item to queue: yandex_music/track/19103540:2139578@2139578 Feb 15 20:32:13 volumio volumio[1047]: info: Using cached record of: yandex_music/track/19103540:2139578@2139578 Feb 15 20:32:13 volumio volumio[1047]: info: Adding Item to queue: yandex_music/track/19103553:2139578@2139578 Feb 15 20:32:13 volumio volumio[1047]: info: Using cached record of: yandex_music/track/19103553:2139578@2139578 Feb 15 20:32:13 volumio volumio[1047]: info: Adding Item to queue: yandex_music/track/19103546:2139578@2139578 Feb 15 20:32:13 volumio volumio[1047]: info: Using cached record of: yandex_music/track/19103546:2139578@2139578 Feb 15 20:32:13 volumio volumio[1047]: info: Adding Item to queue: yandex_music/track/19103542:2139578@2139578 Feb 15 20:32:13 volumio volumio[1047]: info: Using cached record of: yandex_music/track/19103542:2139578@2139578 Feb 15 20:32:13 volumio volumio[1047]: info: Adding Item to queue: yandex_music/track/19103543:2139578@2139578 Feb 15 20:32:13 volumio volumio[1047]: info: Using cached record of: yandex_music/track/19103543:2139578@2139578 Feb 15 20:32:13 volumio volumio[1047]: info: Adding Item to queue: yandex_music/track/19103550:2139578@2139578 Feb 15 20:32:13 volumio volumio[1047]: info: Using cached record of: yandex_music/track/19103550:2139578@2139578 Feb 15 20:32:13 volumio volumio[1047]: info: Adding Item to queue: yandex_music/track/19103547:2139578@2139578 Feb 15 20:32:13 volumio volumio[1047]: info: Using cached record of: yandex_music/track/19103547:2139578@2139578 Feb 15 20:32:13 volumio volumio[1047]: info: Adding Item to queue: yandex_music/track/19103552:2139578@2139578 Feb 15 20:32:13 volumio volumio[1047]: info: Using cached record of: yandex_music/track/19103552:2139578@2139578 Feb 15 20:32:13 volumio volumio[1047]: info: Adding Item to queue: yandex_music/track/19103554:2139578@2139578 Feb 15 20:32:13 volumio volumio[1047]: info: Using cached record of: yandex_music/track/19103554:2139578@2139578 Feb 15 20:32:13 volumio volumio[1047]: info: Adding Item to queue: yandex_music/track/19103549:2139578@2139578 Feb 15 20:32:13 volumio volumio[1047]: info: Using cached record of: yandex_music/track/19103549:2139578@2139578 Feb 15 20:32:13 volumio volumio[1047]: info: Adding Item to queue: yandex_music/track/19103538:2139578@2139578 Feb 15 20:32:13 volumio volumio[1047]: info: Using cached record of: yandex_music/track/19103538:2139578@2139578 Feb 15 20:32:13 volumio volumio[1047]: info: Adding Item to queue: yandex_music/track/19103548:2139578@2139578 Feb 15 20:32:13 volumio volumio[1047]: info: Using cached record of: yandex_music/track/19103548:2139578@2139578 Feb 15 20:32:13 volumio volumio[1047]: info: CoreStateMachine::stop Feb 15 20:32:13 volumio volumio[1047]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 15 20:32:13 volumio volumio[1047]: info: CoreCommandRouter::volumioPushQueue Feb 15 20:32:13 volumio volumio[1047]: info: CorePlayQueue::saveQueue Feb 15 20:32:13 volumio volumio[1047]: info: CoreStateMachine::play index undefined Feb 15 20:32:13 volumio volumio[1047]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 15 20:32:13 volumio volumio[1047]: info: CoreStateMachine::updateTrackBlock Feb 15 20:32:13 volumio volumio[1047]: info: CorePlayQueue::getTrackBlock Feb 15 20:32:13 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 15 20:32:13 volumio volumio[1047]: info: CoreStateMachine::startPlaybackTimer Feb 15 20:32:13 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 15 20:32:13 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand stop Feb 15 20:32:13 volumio volumio[1047]: info: sendMpdCommand stop took 25 milliseconds Feb 15 20:32:13 volumio volumio[1047]: info: Feb 15 20:32:13 volumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 15 20:32:13 volumio volumio[1047]: info: ControllerMpd::getState Feb 15 20:32:13 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:32:13 volumio volumio[1047]: info: sendMpdCommand stop took 13 milliseconds Feb 15 20:32:13 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand clear Feb 15 20:32:13 volumio volumio[1047]: info: Feb 15 20:32:13 volumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 15 20:32:13 volumio volumio[1047]: info: ControllerMpd::getState Feb 15 20:32:13 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:32:13 volumio volumio[1047]: info: Feb 15 20:32:13 volumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 15 20:32:13 volumio volumio[1047]: info: ControllerMpd::getState Feb 15 20:32:13 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:32:13 volumio volumio[1047]: info: Feb 15 20:32:13 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:32:13 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:32:13 volumio volumio[1047]: info: sendMpdCommand status took 5 milliseconds Feb 15 20:32:13 volumio volumio[1047]: info: sendMpdCommand clear took 4 milliseconds Feb 15 20:32:13 volumio volumio[1047]: info: sendMpdCommand status took 1 milliseconds Feb 15 20:32:13 volumio volumio[1047]: verbose: ControllerMpd::parseState Feb 15 20:32:13 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 20:32:13 volumio volumio[1047]: verbose: ControllerMpd::parseState Feb 15 20:32:13 volumio volumio[1047]: info: ControllerMpd::pushState Feb 15 20:32:13 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 15 20:32:13 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:32:13 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 15 20:32:13 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 20:32:13 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:32:13 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 15 20:32:13 volumio volumio[1047]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd Feb 15 20:32:13 volumio volumio[1047]: info: ------------------------------ 12ms Feb 15 20:32:13 volumio volumio[1047]: info: Feb 15 20:32:13 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:32:13 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:32:13 volumio volumio[1047]: error: updateQueue error: null Feb 15 20:32:13 volumio volumio[1047]: error: updateQueue error: null Feb 15 20:32:13 volumio volumio[1047]: info: sendMpdCommand status took 14 milliseconds Feb 15 20:32:13 volumio volumio[1047]: info: ------------------------------ 13ms Feb 15 20:32:13 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 13 milliseconds Feb 15 20:32:13 volumio volumio[1047]: info: ------------------------------ 2ms Feb 15 20:32:13 volumio volumio[1047]: verbose: ControllerMpd::parseState Feb 15 20:32:13 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Feb 15 20:32:13 volumio volumio[1047]: info: ControllerMpd::pushState Feb 15 20:32:13 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 15 20:32:13 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:32:13 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 15 20:32:13 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:32:13 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 15 20:32:13 volumio volumio[1047]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd Feb 15 20:32:13 volumio volumio[1047]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 15 20:32:13 volumio volumio[1047]: info: ------------------------------ 18ms Feb 15 20:32:13 volumio volumio[1047]: info: ------------------------------ 22ms Feb 15 20:32:13 volumio volumio[1047]: info: Feb 15 20:32:13 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:32:13 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:32:13 volumio volumio[1047]: error: updateQueue error: null Feb 15 20:32:13 volumio volumio[1047]: info: ------------------------------ 2ms Feb 15 20:32:13 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2F1525.cold-rtc.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D3c317a8c6198ece83184dd6acb43becca27ca79fd2b48c1eb51c0448b081045f%2Clid%3D1525%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67bf2283%2F73859%2F55759%2F4b11b484.27886909.10.19103545%2F320.mp3&ext=.mp3" Feb 15 20:32:13 volumio volumio[1047]: info: Feb 15 20:32:13 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:32:13 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:32:13 volumio volumio[1047]: info: sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2F1525.cold-rtc.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D3c317a8c6198ece83184dd6acb43becca27ca79fd2b48c1eb51c0448b081045f%2Clid%3D1525%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67bf2283%2F73859%2F55759%2F4b11b484.27886909.10.19103545%2F320.mp3&ext=.mp3" took 2 milliseconds Feb 15 20:32:13 volumio volumio[1047]: verbose: MPD COMMAND [object Object] Feb 15 20:32:13 volumio volumio[1047]: info: Feb 15 20:32:13 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:32:13 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:32:13 volumio volumio[1047]: info: Feb 15 20:32:13 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:32:13 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:32:13 volumio volumio[1047]: info: Feb 15 20:32:13 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:32:13 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:32:13 volumio volumio[1047]: info: ------------------------------ 4ms Feb 15 20:32:13 volumio volumio[1047]: info: ------------------------------ 2ms Feb 15 20:32:13 volumio volumio[1047]: info: ------------------------------ 2ms Feb 15 20:32:13 volumio volumio[1047]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 15 20:32:13 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand play Feb 15 20:32:13 volumio volumio[1047]: info: Feb 15 20:32:13 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:32:13 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:32:13 volumio volumio[1047]: info: Feb 15 20:32:13 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:32:13 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:32:13 volumio volumio[1047]: info: ------------------------------ 3ms Feb 15 20:32:13 volumio volumio[1047]: info: sendMpdCommand play took 2 milliseconds Feb 15 20:32:13 volumio volumio[1047]: info: ------------------------------ 3ms Feb 15 20:32:13 volumio volumio[1047]: info: ------------------------------ 4ms Feb 15 20:32:14 volumio volumio[1047]: info: Feb 15 20:32:14 volumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 15 20:32:14 volumio volumio[1047]: info: ControllerMpd::getState Feb 15 20:32:14 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:32:14 volumio volumio[1047]: info: Feb 15 20:32:14 volumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 15 20:32:14 volumio volumio[1047]: info: ControllerMpd::getState Feb 15 20:32:14 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:32:14 volumio volumio[1047]: info: Feb 15 20:32:14 volumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 15 20:32:14 volumio volumio[1047]: info: ControllerMpd::getState Feb 15 20:32:14 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:32:14 volumio volumio[1047]: info: Feb 15 20:32:14 volumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 15 20:32:14 volumio volumio[1047]: info: sendMpdCommand status took 5 milliseconds Feb 15 20:32:14 volumio volumio[1047]: info: sendMpdCommand status took 4 milliseconds Feb 15 20:32:14 volumio volumio[1047]: info: ControllerMpd::getState Feb 15 20:32:14 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:32:14 volumio volumio[1047]: verbose: ControllerMpd::parseState Feb 15 20:32:14 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 20:32:14 volumio volumio[1047]: verbose: ControllerMpd::parseState Feb 15 20:32:14 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 20:32:14 volumio volumio[1047]: verbose: ControllerMpd::parseState Feb 15 20:32:14 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 20:32:14 volumio volumio[1047]: info: Feb 15 20:32:14 volumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 15 20:32:14 volumio volumio[1047]: info: ControllerMpd::getState Feb 15 20:32:14 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:32:14 volumio volumio[1047]: info: Feb 15 20:32:14 volumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 15 20:32:14 volumio volumio[1047]: info: ControllerMpd::getState Feb 15 20:32:14 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:32:14 volumio volumio[1047]: info: sendMpdCommand status took 5 milliseconds Feb 15 20:32:14 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 15 20:32:14 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 15 20:32:14 volumio volumio[1047]: info: sendMpdCommand status took 5 milliseconds Feb 15 20:32:14 volumio volumio[1047]: info: sendMpdCommand status took 4 milliseconds Feb 15 20:32:14 volumio volumio[1047]: verbose: ControllerMpd::parseState Feb 15 20:32:14 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 20:32:14 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Feb 15 20:32:14 volumio volumio[1047]: verbose: ControllerMpd::parseState Feb 15 20:32:14 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 20:32:14 volumio volumio[1047]: verbose: ControllerMpd::parseState Feb 15 20:32:14 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 20:32:14 volumio volumio[1047]: info: ControllerMpd::pushState Feb 15 20:32:14 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 15 20:32:14 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 15 20:32:14 volumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":477,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Viva Verdi!","artist":"Джузеппе Верди","album":"The Best of The Classics Volume 11","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2F1525.cold-rtc.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D3c317a8c6198ece83184dd6acb43becca27ca79fd2b48c1eb51c0448b081045f%2Clid%3D1525%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67bf2283%2F73859%2F55759%2F4b11b484.27886909.10.19103545%2F320.mp3&ext=.mp3","trackType":"mp3"} Feb 15 20:32:14 volumio volumio[1047]: verbose: CURRENT POSITION 0 Feb 15 20:32:14 volumio volumio[1047]: info: CoreStateMachine::syncState stateService play Feb 15 20:32:14 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus stop Feb 15 20:32:14 volumio volumio[1047]: info: ControllerMpd::pushState Feb 15 20:32:14 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 15 20:32:14 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 15 20:32:14 volumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":477,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Viva Verdi!","artist":"Джузеппе Верди","album":"The Best of The Classics Volume 11","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2F1525.cold-rtc.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D3c317a8c6198ece83184dd6acb43becca27ca79fd2b48c1eb51c0448b081045f%2Clid%3D1525%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67bf2283%2F73859%2F55759%2F4b11b484.27886909.10.19103545%2F320.mp3&ext=.mp3","trackType":"mp3"} Feb 15 20:32:14 volumio volumio[1047]: verbose: CURRENT POSITION 0 Feb 15 20:32:14 volumio volumio[1047]: info: CoreStateMachine::syncState stateService play Feb 15 20:32:14 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus play Feb 15 20:32:14 volumio volumio[1047]: info: Received an update from plugin. extracting info from payload Feb 15 20:32:14 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:32:14 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 20:32:14 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:32:14 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:32:14 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:32:14 volumio volumio[1047]: info: ControllerMpd::pushState Feb 15 20:32:14 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 15 20:32:14 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 15 20:32:14 volumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":477,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Viva Verdi!","artist":"Джузеппе Верди","album":"The Best of The Classics Volume 11","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2F1525.cold-rtc.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D3c317a8c6198ece83184dd6acb43becca27ca79fd2b48c1eb51c0448b081045f%2Clid%3D1525%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67bf2283%2F73859%2F55759%2F4b11b484.27886909.10.19103545%2F320.mp3&ext=.mp3","trackType":"mp3"} Feb 15 20:32:14 volumio volumio[1047]: verbose: CURRENT POSITION 0 Feb 15 20:32:14 volumio volumio[1047]: info: CoreStateMachine::syncState stateService play Feb 15 20:32:14 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus play Feb 15 20:32:14 volumio volumio[1047]: info: Received an update from plugin. extracting info from payload Feb 15 20:32:14 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:32:14 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:32:14 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:32:14 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:32:14 volumio volumio[1047]: info: ------------------------------ 18ms Feb 15 20:32:14 volumio volumio[1047]: info: ------------------------------ 22ms Feb 15 20:32:14 volumio volumio[1047]: info: ------------------------------ 23ms Feb 15 20:32:14 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 14 milliseconds Feb 15 20:32:14 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 13 milliseconds Feb 15 20:32:14 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 13 milliseconds Feb 15 20:32:14 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Feb 15 20:32:14 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Feb 15 20:32:14 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Feb 15 20:32:14 volumio volumio[1047]: info: ControllerMpd::pushState Feb 15 20:32:14 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 15 20:32:14 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 15 20:32:14 volumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":477,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Viva Verdi!","artist":"Джузеппе Верди","album":"The Best of The Classics Volume 11","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2F1525.cold-rtc.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D3c317a8c6198ece83184dd6acb43becca27ca79fd2b48c1eb51c0448b081045f%2Clid%3D1525%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67bf2283%2F73859%2F55759%2F4b11b484.27886909.10.19103545%2F320.mp3&ext=.mp3","trackType":"mp3"} Feb 15 20:32:14 volumio volumio[1047]: verbose: CURRENT POSITION 0 Feb 15 20:32:14 volumio volumio[1047]: info: CoreStateMachine::syncState stateService play Feb 15 20:32:14 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus play Feb 15 20:32:14 volumio volumio[1047]: info: Received an update from plugin. extracting info from payload Feb 15 20:32:14 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:32:14 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 20:32:14 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:32:14 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:32:14 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:32:14 volumio volumio[1047]: info: ControllerMpd::pushState Feb 15 20:32:14 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 15 20:32:14 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 15 20:32:14 volumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":477,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Viva Verdi!","artist":"Джузеппе Верди","album":"The Best of The Classics Volume 11","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2F1525.cold-rtc.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D3c317a8c6198ece83184dd6acb43becca27ca79fd2b48c1eb51c0448b081045f%2Clid%3D1525%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67bf2283%2F73859%2F55759%2F4b11b484.27886909.10.19103545%2F320.mp3&ext=.mp3","trackType":"mp3"} Feb 15 20:32:14 volumio volumio[1047]: verbose: CURRENT POSITION 0 Feb 15 20:32:14 volumio volumio[1047]: info: CoreStateMachine::syncState stateService play Feb 15 20:32:14 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus play Feb 15 20:32:14 volumio volumio[1047]: info: Received an update from plugin. extracting info from payload Feb 15 20:32:14 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:32:14 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 20:32:14 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:32:14 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:32:14 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:32:14 volumio volumio[1047]: info: ControllerMpd::pushState Feb 15 20:32:14 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 15 20:32:14 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 15 20:32:14 volumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":477,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Viva Verdi!","artist":"Джузеппе Верди","album":"The Best of The Classics Volume 11","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2F1525.cold-rtc.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D3c317a8c6198ece83184dd6acb43becca27ca79fd2b48c1eb51c0448b081045f%2Clid%3D1525%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67bf2283%2F73859%2F55759%2F4b11b484.27886909.10.19103545%2F320.mp3&ext=.mp3","trackType":"mp3"} Feb 15 20:32:14 volumio volumio[1047]: verbose: CURRENT POSITION 0 Feb 15 20:32:14 volumio volumio[1047]: info: CoreStateMachine::syncState stateService play Feb 15 20:32:14 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus play Feb 15 20:32:14 volumio volumio[1047]: info: Received an update from plugin. extracting info from payload Feb 15 20:32:14 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:32:14 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:32:14 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:32:14 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:32:14 volumio volumio[1047]: info: ------------------------------ 31ms Feb 15 20:32:14 volumio volumio[1047]: info: ------------------------------ 29ms Feb 15 20:32:14 volumio volumio[1047]: info: ------------------------------ 29ms Feb 15 20:32:14 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:32:14 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:32:14 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:32:14 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:32:14 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:32:14 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:32:14 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:32:14 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:32:14 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:32:14 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:32:14 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:32:14 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:32:14 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:32:14 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:32:14 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:32:14 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:32:14 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:32:14 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:32:14 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:32:14 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:32:14 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:32:14 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:32:14 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:32:14 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:32:14 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:32:14 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:32:14 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:32:14 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:32:14 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:32:14 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:32:18 volumio volumio[1047]: info: CoreCommandRouter::volumioPause Feb 15 20:32:18 volumio volumio[1047]: info: CoreStateMachine::pause Feb 15 20:32:18 volumio volumio[1047]: info: CoreStateMachine::stPlaybackTimer Feb 15 20:32:18 volumio volumio[1047]: info: CoreStateMachine::servicePause Feb 15 20:32:18 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 15 20:32:18 volumio volumio[1047]: info: CoreCommandRouter::servicePause Feb 15 20:32:18 volumio volumio[1047]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 15 20:32:18 volumio volumio[1047]: info: ControllerMpd::pause Feb 15 20:32:18 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand pause Feb 15 20:32:18 volumio volumio[1047]: info: Feb 15 20:32:18 volumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 15 20:32:18 volumio volumio[1047]: info: sendMpdCommand pause took 1 milliseconds Feb 15 20:32:18 volumio volumio[1047]: info: ControllerMpd::getState Feb 15 20:32:18 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:32:18 volumio volumio[1047]: info: Feb 15 20:32:18 volumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 15 20:32:18 volumio volumio[1047]: info: ControllerMpd::getState Feb 15 20:32:18 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:32:18 volumio volumio[1047]: info: Feb 15 20:32:18 volumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 15 20:32:18 volumio volumio[1047]: info: ControllerMpd::getState Feb 15 20:32:18 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:32:18 volumio volumio[1047]: info: sendMpdCommand status took 2 milliseconds Feb 15 20:32:18 volumio volumio[1047]: info: sendMpdCommand status took 1 milliseconds Feb 15 20:32:18 volumio volumio[1047]: info: sendMpdCommand status took 1 milliseconds Feb 15 20:32:18 volumio volumio[1047]: verbose: ControllerMpd::parseState Feb 15 20:32:18 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 20:32:18 volumio volumio[1047]: verbose: ControllerMpd::parseState Feb 15 20:32:18 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 20:32:18 volumio volumio[1047]: verbose: ControllerMpd::parseState Feb 15 20:32:18 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 20:32:18 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 15 20:32:18 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 15 20:32:18 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 15 20:32:18 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Feb 15 20:32:18 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Feb 15 20:32:18 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Feb 15 20:32:18 volumio volumio[1047]: info: ControllerMpd::pushState Feb 15 20:32:18 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 15 20:32:18 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 15 20:32:18 volumio volumio[1047]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":4506,"duration":477,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Viva Verdi!","artist":"Джузеппе Верди","album":"The Best of The Classics Volume 11","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2F1525.cold-rtc.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D3c317a8c6198ece83184dd6acb43becca27ca79fd2b48c1eb51c0448b081045f%2Clid%3D1525%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67bf2283%2F73859%2F55759%2F4b11b484.27886909.10.19103545%2F320.mp3&ext=.mp3","trackType":"mp3"} Feb 15 20:32:18 volumio volumio[1047]: verbose: CURRENT POSITION 0 Feb 15 20:32:18 volumio volumio[1047]: info: CoreStateMachine::syncState stateService pause Feb 15 20:32:18 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus pause Feb 15 20:32:18 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:32:18 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 20:32:18 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:32:18 volumio volumio[1047]: info: CoreStateMachine::stPlaybackTimer Feb 15 20:32:18 volumio volumio[1047]: info: ControllerMpd::pushState Feb 15 20:32:18 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 15 20:32:18 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 15 20:32:18 volumio volumio[1047]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":4506,"duration":477,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Viva Verdi!","artist":"Джузеппе Верди","album":"The Best of The Classics Volume 11","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2F1525.cold-rtc.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D3c317a8c6198ece83184dd6acb43becca27ca79fd2b48c1eb51c0448b081045f%2Clid%3D1525%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67bf2283%2F73859%2F55759%2F4b11b484.27886909.10.19103545%2F320.mp3&ext=.mp3","trackType":"mp3"} Feb 15 20:32:18 volumio volumio[1047]: verbose: CURRENT POSITION 0 Feb 15 20:32:18 volumio volumio[1047]: info: CoreStateMachine::syncState stateService pause Feb 15 20:32:18 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus pause Feb 15 20:32:18 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:32:18 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:32:18 volumio volumio[1047]: info: CoreStateMachine::stPlaybackTimer Feb 15 20:32:18 volumio volumio[1047]: info: ControllerMpd::pushState Feb 15 20:32:18 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 15 20:32:18 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 15 20:32:18 volumio volumio[1047]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":4506,"duration":477,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Viva Verdi!","artist":"Джузеппе Верди","album":"The Best of The Classics Volume 11","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2F1525.cold-rtc.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D3c317a8c6198ece83184dd6acb43becca27ca79fd2b48c1eb51c0448b081045f%2Clid%3D1525%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67bf2283%2F73859%2F55759%2F4b11b484.27886909.10.19103545%2F320.mp3&ext=.mp3","trackType":"mp3"} Feb 15 20:32:18 volumio volumio[1047]: verbose: CURRENT POSITION 0 Feb 15 20:32:18 volumio volumio[1047]: info: CoreStateMachine::syncState stateService pause Feb 15 20:32:18 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus pause Feb 15 20:32:18 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:32:18 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:32:18 volumio volumio[1047]: info: CoreStateMachine::stPlaybackTimer Feb 15 20:32:18 volumio volumio[1047]: info: ------------------------------ 11ms Feb 15 20:32:18 volumio volumio[1047]: info: ------------------------------ 11ms Feb 15 20:32:18 volumio volumio[1047]: info: ------------------------------ 11ms Feb 15 20:32:18 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:32:18 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:32:18 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:32:18 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:32:18 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:32:18 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:32:18 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:32:18 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:32:18 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:32:22 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: yandex_music , handleBrowseUri Feb 15 20:32:22 volumio volumio[1047]: info: Preload queue cleared Feb 15 20:32:22 volumio volumio[1047]: info: Preloading song: yandex_music/track/15480651:1691725@1691725 Feb 15 20:32:22 volumio volumio[1047]: info: Preloading song: yandex_music/track/15480652:1691725@1691725 Feb 15 20:32:22 volumio volumio[1047]: info: Preloading song: yandex_music/track/15480646:1691725@1691725 Feb 15 20:32:22 volumio volumio[1047]: info: Preloading song: yandex_music/track/15480658:1691725@1691725 Feb 15 20:32:22 volumio volumio[1047]: info: Preloading song: yandex_music/track/15480659:1691725@1691725 Feb 15 20:32:22 volumio volumio[1047]: info: Preloading song: yandex_music/track/15480655:1691725@1691725 Feb 15 20:32:22 volumio volumio[1047]: info: Preloading song: yandex_music/track/15480649:1691725@1691725 Feb 15 20:32:22 volumio volumio[1047]: info: Preloading song: yandex_music/track/15480654:1691725@1691725 Feb 15 20:32:22 volumio volumio[1047]: info: Preloading song: yandex_music/track/15480653:1691725@1691725 Feb 15 20:32:22 volumio volumio[1047]: info: Preloading song: yandex_music/track/15480647:1691725@1691725 Feb 15 20:32:22 volumio volumio[1047]: info: Preloading song: yandex_music/track/15480656:1691725@1691725 Feb 15 20:32:22 volumio volumio[1047]: info: Preloading song: yandex_music/track/15480660:1691725@1691725 Feb 15 20:32:22 volumio volumio[1047]: info: Preloading song: yandex_music/track/15480661:1691725@1691725 Feb 15 20:32:22 volumio volumio[1047]: info: Preloading song: yandex_music/track/15480657:1691725@1691725 Feb 15 20:32:22 volumio volumio[1047]: info: Preloading song: yandex_music/track/15480662:1691725@1691725 Feb 15 20:32:22 volumio volumio[1047]: info: Preloading song: yandex_music/track/15480650:1691725@1691725 Feb 15 20:32:22 volumio volumio[1047]: info: Preloading song: yandex_music/track/15480648:1691725@1691725 Feb 15 20:32:23 volumio volumio[1047]: info: Exploding uri yandex_music/track/15480651:1691725@1691725 in service yandex_music Feb 15 20:32:23 volumio volumio[1047]: info: Exploding uri yandex_music/track/15480652:1691725@1691725 in service yandex_music Feb 15 20:32:23 volumio volumio[1047]: info: Exploding uri yandex_music/track/15480646:1691725@1691725 in service yandex_music Feb 15 20:32:23 volumio volumio[1047]: info: Exploding uri yandex_music/track/15480658:1691725@1691725 in service yandex_music Feb 15 20:32:23 volumio volumio[1047]: info: Exploding uri yandex_music/track/15480659:1691725@1691725 in service yandex_music Feb 15 20:32:23 volumio volumio[1047]: info: Exploding uri yandex_music/track/15480655:1691725@1691725 in service yandex_music Feb 15 20:32:23 volumio volumio[1047]: info: Exploding uri yandex_music/track/15480649:1691725@1691725 in service yandex_music Feb 15 20:32:23 volumio volumio[1047]: info: Exploding uri yandex_music/track/15480654:1691725@1691725 in service yandex_music Feb 15 20:32:23 volumio volumio[1047]: info: Exploding uri yandex_music/track/15480653:1691725@1691725 in service yandex_music Feb 15 20:32:23 volumio volumio[1047]: info: Exploding uri yandex_music/track/15480647:1691725@1691725 in service yandex_music Feb 15 20:32:23 volumio volumio[1047]: info: Exploding uri yandex_music/track/15480656:1691725@1691725 in service yandex_music Feb 15 20:32:23 volumio volumio[1047]: info: Exploding uri yandex_music/track/15480660:1691725@1691725 in service yandex_music Feb 15 20:32:23 volumio volumio[1047]: info: Exploding uri yandex_music/track/15480661:1691725@1691725 in service yandex_music Feb 15 20:32:23 volumio volumio[1047]: info: Exploding uri yandex_music/track/15480657:1691725@1691725 in service yandex_music Feb 15 20:32:23 volumio volumio[1047]: info: Exploding uri yandex_music/track/15480662:1691725@1691725 in service yandex_music Feb 15 20:32:23 volumio volumio[1047]: info: Exploding uri yandex_music/track/15480650:1691725@1691725 in service yandex_music Feb 15 20:32:23 volumio volumio[1047]: info: Exploding uri yandex_music/track/15480648:1691725@1691725 in service yandex_music Feb 15 20:32:24 volumio volumio[1047]: info: Preload queue cleared Feb 15 20:32:24 volumio volumio[1047]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 15 20:32:24 volumio volumio[1047]: info: CoreStateMachine::ClearQueue Feb 15 20:32:24 volumio volumio[1047]: info: CoreStateMachine::stop Feb 15 20:32:24 volumio volumio[1047]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 15 20:32:24 volumio volumio[1047]: info: CoreStateMachine::updateTrackBlock Feb 15 20:32:24 volumio volumio[1047]: info: CorePlayQueue::getTrackBlock Feb 15 20:32:24 volumio volumio[1047]: info: CoreStateMachine::stPlaybackTimer Feb 15 20:32:24 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:32:24 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 15 20:32:24 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 20:32:24 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:32:24 volumio volumio[1047]: info: CoreStateMachine::serviceStop Feb 15 20:32:24 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 15 20:32:24 volumio volumio[1047]: info: CoreCommandRouter::serviceStop Feb 15 20:32:24 volumio volumio[1047]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 15 20:32:24 volumio volumio[1047]: info: ControllerMpd::stop Feb 15 20:32:24 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand stop Feb 15 20:32:24 volumio volumio[1047]: info: CorePlayQueue::clearPlayQueue Feb 15 20:32:24 volumio volumio[1047]: info: CorePlayQueue::saveQueue Feb 15 20:32:24 volumio volumio[1047]: info: CoreCommandRouter::volumioPushQueue Feb 15 20:32:24 volumio volumio[1047]: info: CoreStateMachine::addQueueItems Feb 15 20:32:24 volumio volumio[1047]: info: CorePlayQueue::addQueueItems Feb 15 20:32:24 volumio volumio[1047]: info: Preload queue cleared Feb 15 20:32:24 volumio volumio[1047]: info: Adding Item to queue: yandex_music/track/15480651:1691725@1691725 Feb 15 20:32:24 volumio volumio[1047]: info: Using cached record of: yandex_music/track/15480651:1691725@1691725 Feb 15 20:32:24 volumio volumio[1047]: info: CoreCommandRouter::volumioPushQueue Feb 15 20:32:24 volumio volumio[1047]: info: CorePlayQueue::saveQueue Feb 15 20:32:24 volumio volumio[1047]: info: CoreStateMachine::updateTrackBlock Feb 15 20:32:24 volumio volumio[1047]: info: CorePlayQueue::getTrackBlock Feb 15 20:32:24 volumio volumio[1047]: info: CoreCommandRouter::volumioPlay Feb 15 20:32:24 volumio volumio[1047]: info: CoreStateMachine::play index 0 Feb 15 20:32:24 volumio volumio[1047]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 15 20:32:24 volumio volumio[1047]: info: CoreStateMachine::addQueueItems Feb 15 20:32:24 volumio volumio[1047]: info: CorePlayQueue::addQueueItems Feb 15 20:32:24 volumio volumio[1047]: info: Preload queue cleared Feb 15 20:32:24 volumio volumio[1047]: info: Adding Item to queue: yandex_music/track/15480652:1691725@1691725 Feb 15 20:32:24 volumio volumio[1047]: info: Using cached record of: yandex_music/track/15480652:1691725@1691725 Feb 15 20:32:24 volumio volumio[1047]: info: Adding Item to queue: yandex_music/track/15480646:1691725@1691725 Feb 15 20:32:24 volumio volumio[1047]: info: Using cached record of: yandex_music/track/15480646:1691725@1691725 Feb 15 20:32:24 volumio volumio[1047]: info: Adding Item to queue: yandex_music/track/15480658:1691725@1691725 Feb 15 20:32:24 volumio volumio[1047]: info: Using cached record of: yandex_music/track/15480658:1691725@1691725 Feb 15 20:32:24 volumio volumio[1047]: info: Adding Item to queue: yandex_music/track/15480659:1691725@1691725 Feb 15 20:32:24 volumio volumio[1047]: info: Using cached record of: yandex_music/track/15480659:1691725@1691725 Feb 15 20:32:24 volumio volumio[1047]: info: Adding Item to queue: yandex_music/track/15480655:1691725@1691725 Feb 15 20:32:24 volumio volumio[1047]: info: Using cached record of: yandex_music/track/15480655:1691725@1691725 Feb 15 20:32:24 volumio volumio[1047]: info: Adding Item to queue: yandex_music/track/15480649:1691725@1691725 Feb 15 20:32:24 volumio volumio[1047]: info: Using cached record of: yandex_music/track/15480649:1691725@1691725 Feb 15 20:32:24 volumio volumio[1047]: info: Adding Item to queue: yandex_music/track/15480654:1691725@1691725 Feb 15 20:32:24 volumio volumio[1047]: info: Using cached record of: yandex_music/track/15480654:1691725@1691725 Feb 15 20:32:24 volumio volumio[1047]: info: Adding Item to queue: yandex_music/track/15480653:1691725@1691725 Feb 15 20:32:24 volumio volumio[1047]: info: Using cached record of: yandex_music/track/15480653:1691725@1691725 Feb 15 20:32:24 volumio volumio[1047]: info: Adding Item to queue: yandex_music/track/15480647:1691725@1691725 Feb 15 20:32:24 volumio volumio[1047]: info: Using cached record of: yandex_music/track/15480647:1691725@1691725 Feb 15 20:32:24 volumio volumio[1047]: info: Adding Item to queue: yandex_music/track/15480656:1691725@1691725 Feb 15 20:32:24 volumio volumio[1047]: info: Using cached record of: yandex_music/track/15480656:1691725@1691725 Feb 15 20:32:24 volumio volumio[1047]: info: Adding Item to queue: yandex_music/track/15480660:1691725@1691725 Feb 15 20:32:24 volumio volumio[1047]: info: Using cached record of: yandex_music/track/15480660:1691725@1691725 Feb 15 20:32:24 volumio volumio[1047]: info: Adding Item to queue: yandex_music/track/15480661:1691725@1691725 Feb 15 20:32:24 volumio volumio[1047]: info: Using cached record of: yandex_music/track/15480661:1691725@1691725 Feb 15 20:32:24 volumio volumio[1047]: info: Adding Item to queue: yandex_music/track/15480657:1691725@1691725 Feb 15 20:32:24 volumio volumio[1047]: info: Using cached record of: yandex_music/track/15480657:1691725@1691725 Feb 15 20:32:24 volumio volumio[1047]: info: Adding Item to queue: yandex_music/track/15480662:1691725@1691725 Feb 15 20:32:24 volumio volumio[1047]: info: Using cached record of: yandex_music/track/15480662:1691725@1691725 Feb 15 20:32:24 volumio volumio[1047]: info: Adding Item to queue: yandex_music/track/15480650:1691725@1691725 Feb 15 20:32:24 volumio volumio[1047]: info: Using cached record of: yandex_music/track/15480650:1691725@1691725 Feb 15 20:32:24 volumio volumio[1047]: info: Adding Item to queue: yandex_music/track/15480648:1691725@1691725 Feb 15 20:32:24 volumio volumio[1047]: info: Using cached record of: yandex_music/track/15480648:1691725@1691725 Feb 15 20:32:24 volumio volumio[1047]: info: CoreStateMachine::stop Feb 15 20:32:24 volumio volumio[1047]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 15 20:32:24 volumio volumio[1047]: info: CoreCommandRouter::volumioPushQueue Feb 15 20:32:24 volumio volumio[1047]: info: CorePlayQueue::saveQueue Feb 15 20:32:24 volumio volumio[1047]: info: CoreStateMachine::play index undefined Feb 15 20:32:24 volumio volumio[1047]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 15 20:32:24 volumio volumio[1047]: info: CoreStateMachine::updateTrackBlock Feb 15 20:32:24 volumio volumio[1047]: info: CorePlayQueue::getTrackBlock Feb 15 20:32:24 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 15 20:32:24 volumio volumio[1047]: info: CoreStateMachine::startPlaybackTimer Feb 15 20:32:24 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 15 20:32:24 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand stop Feb 15 20:32:24 volumio volumio[1047]: info: Feb 15 20:32:24 volumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 15 20:32:24 volumio volumio[1047]: info: sendMpdCommand stop took 14 milliseconds Feb 15 20:32:24 volumio volumio[1047]: info: ControllerMpd::getState Feb 15 20:32:24 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:32:24 volumio volumio[1047]: info: sendMpdCommand stop took 3 milliseconds Feb 15 20:32:24 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand clear Feb 15 20:32:24 volumio volumio[1047]: info: Feb 15 20:32:24 volumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 15 20:32:24 volumio volumio[1047]: info: ControllerMpd::getState Feb 15 20:32:24 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:32:24 volumio volumio[1047]: info: Feb 15 20:32:24 volumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 15 20:32:24 volumio volumio[1047]: info: ControllerMpd::getState Feb 15 20:32:24 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:32:24 volumio volumio[1047]: info: Feb 15 20:32:24 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:32:24 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:32:24 volumio volumio[1047]: info: sendMpdCommand status took 2 milliseconds Feb 15 20:32:24 volumio volumio[1047]: info: sendMpdCommand clear took 2 milliseconds Feb 15 20:32:24 volumio volumio[1047]: info: sendMpdCommand status took 2 milliseconds Feb 15 20:32:24 volumio volumio[1047]: info: sendMpdCommand status took 1 milliseconds Feb 15 20:32:24 volumio volumio[1047]: verbose: ControllerMpd::parseState Feb 15 20:32:24 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 20:32:24 volumio volumio[1047]: verbose: ControllerMpd::parseState Feb 15 20:32:24 volumio volumio[1047]: verbose: ControllerMpd::parseState Feb 15 20:32:24 volumio volumio[1047]: info: ControllerMpd::pushState Feb 15 20:32:24 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 15 20:32:24 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:32:24 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 15 20:32:24 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 20:32:24 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:32:24 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 15 20:32:24 volumio volumio[1047]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd Feb 15 20:32:24 volumio volumio[1047]: info: ControllerMpd::pushState Feb 15 20:32:24 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 15 20:32:24 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:32:24 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 15 20:32:24 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:32:24 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 15 20:32:24 volumio volumio[1047]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd Feb 15 20:32:24 volumio volumio[1047]: info: ------------------------------ 9ms Feb 15 20:32:24 volumio volumio[1047]: info: ------------------------------ 10ms Feb 15 20:32:24 volumio volumio[1047]: info: Feb 15 20:32:24 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:32:24 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:32:24 volumio volumio[1047]: info: Feb 15 20:32:24 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:32:24 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:32:24 volumio volumio[1047]: error: updateQueue error: null Feb 15 20:32:24 volumio volumio[1047]: error: updateQueue error: null Feb 15 20:32:24 volumio volumio[1047]: error: updateQueue error: null Feb 15 20:32:24 volumio volumio[1047]: info: ------------------------------ 14ms Feb 15 20:32:24 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 13 milliseconds Feb 15 20:32:24 volumio volumio[1047]: info: ------------------------------ 2ms Feb 15 20:32:24 volumio volumio[1047]: info: ------------------------------ 1ms Feb 15 20:32:24 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Feb 15 20:32:24 volumio volumio[1047]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 15 20:32:24 volumio volumio[1047]: info: ------------------------------ 17ms Feb 15 20:32:24 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2F1557.cold-rtc.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Df6b118f32d70d9c174b84102749678afe90891c2234c15396e0d9e772a599934%2Clid%3D1557%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67bf2cf1%2F0%2F48177%2Fa84c7acf.15480651%2F320.mp3&ext=.mp3" Feb 15 20:32:24 volumio volumio[1047]: info: Feb 15 20:32:24 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:32:24 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:32:24 volumio volumio[1047]: info: sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2F1557.cold-rtc.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Df6b118f32d70d9c174b84102749678afe90891c2234c15396e0d9e772a599934%2Clid%3D1557%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67bf2cf1%2F0%2F48177%2Fa84c7acf.15480651%2F320.mp3&ext=.mp3" took 1 milliseconds Feb 15 20:32:24 volumio volumio[1047]: verbose: MPD COMMAND [object Object] Feb 15 20:32:24 volumio volumio[1047]: verbose: MPD COMMAND [object Object] Feb 15 20:32:24 volumio volumio[1047]: info: Feb 15 20:32:24 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:32:24 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:32:24 volumio volumio[1047]: info: Feb 15 20:32:24 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:32:24 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:32:24 volumio volumio[1047]: info: Feb 15 20:32:24 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:32:24 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:32:24 volumio volumio[1047]: info: ------------------------------ 3ms Feb 15 20:32:24 volumio volumio[1047]: info: ------------------------------ 2ms Feb 15 20:32:24 volumio volumio[1047]: info: ------------------------------ 1ms Feb 15 20:32:24 volumio volumio[1047]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 15 20:32:24 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand play Feb 15 20:32:24 volumio volumio[1047]: info: Feb 15 20:32:24 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:32:24 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:32:24 volumio volumio[1047]: info: Feb 15 20:32:24 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:32:24 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:32:24 volumio volumio[1047]: info: ------------------------------ 3ms Feb 15 20:32:24 volumio volumio[1047]: info: sendMpdCommand play took 2 milliseconds Feb 15 20:32:24 volumio volumio[1047]: info: ------------------------------ 2ms Feb 15 20:32:24 volumio volumio[1047]: info: ------------------------------ 1ms Feb 15 20:32:25 volumio volumio[1047]: info: Feb 15 20:32:25 volumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 15 20:32:25 volumio volumio[1047]: info: ControllerMpd::getState Feb 15 20:32:25 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:32:25 volumio volumio[1047]: info: Feb 15 20:32:25 volumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 15 20:32:25 volumio volumio[1047]: info: ControllerMpd::getState Feb 15 20:32:25 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:32:25 volumio volumio[1047]: info: Feb 15 20:32:25 volumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 15 20:32:25 volumio volumio[1047]: info: ControllerMpd::getState Feb 15 20:32:25 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:32:25 volumio volumio[1047]: info: Feb 15 20:32:25 volumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 15 20:32:25 volumio volumio[1047]: info: sendMpdCommand status took 3 milliseconds Feb 15 20:32:25 volumio volumio[1047]: info: sendMpdCommand status took 2 milliseconds Feb 15 20:32:25 volumio volumio[1047]: info: ControllerMpd::getState Feb 15 20:32:25 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:32:25 volumio volumio[1047]: verbose: ControllerMpd::parseState Feb 15 20:32:25 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 20:32:25 volumio volumio[1047]: verbose: ControllerMpd::parseState Feb 15 20:32:25 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 20:32:25 volumio volumio[1047]: info: Feb 15 20:32:25 volumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 15 20:32:25 volumio volumio[1047]: info: ControllerMpd::getState Feb 15 20:32:25 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:32:25 volumio volumio[1047]: info: Feb 15 20:32:25 volumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 15 20:32:25 volumio volumio[1047]: info: ControllerMpd::getState Feb 15 20:32:25 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:32:25 volumio volumio[1047]: info: sendMpdCommand status took 5 milliseconds Feb 15 20:32:25 volumio volumio[1047]: info: sendMpdCommand status took 5 milliseconds Feb 15 20:32:25 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 15 20:32:25 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 15 20:32:25 volumio volumio[1047]: info: sendMpdCommand status took 3 milliseconds Feb 15 20:32:25 volumio volumio[1047]: info: sendMpdCommand status took 3 milliseconds Feb 15 20:32:25 volumio volumio[1047]: verbose: ControllerMpd::parseState Feb 15 20:32:25 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 20:32:25 volumio volumio[1047]: verbose: ControllerMpd::parseState Feb 15 20:32:25 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 20:32:25 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Feb 15 20:32:25 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Feb 15 20:32:25 volumio volumio[1047]: verbose: ControllerMpd::parseState Feb 15 20:32:25 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 20:32:25 volumio volumio[1047]: verbose: ControllerMpd::parseState Feb 15 20:32:25 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 20:32:25 volumio volumio[1047]: info: ControllerMpd::pushState Feb 15 20:32:25 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 15 20:32:25 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 15 20:32:25 volumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":63,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Bells of the Mannheim Christ Church","artist":"Леопольд Моцарт","album":"Merry Christmas, Vol. 1","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2F1557.cold-rtc.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Df6b118f32d70d9c174b84102749678afe90891c2234c15396e0d9e772a599934%2Clid%3D1557%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67bf2cf1%2F0%2F48177%2Fa84c7acf.15480651%2F320.mp3&ext=.mp3","trackType":"mp3"} Feb 15 20:32:25 volumio volumio[1047]: verbose: CURRENT POSITION 0 Feb 15 20:32:25 volumio volumio[1047]: info: CoreStateMachine::syncState stateService play Feb 15 20:32:25 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus stop Feb 15 20:32:25 volumio volumio[1047]: info: ControllerMpd::pushState Feb 15 20:32:25 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 15 20:32:25 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 15 20:32:25 volumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":63,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Bells of the Mannheim Christ Church","artist":"Леопольд Моцарт","album":"Merry Christmas, Vol. 1","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2F1557.cold-rtc.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Df6b118f32d70d9c174b84102749678afe90891c2234c15396e0d9e772a599934%2Clid%3D1557%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67bf2cf1%2F0%2F48177%2Fa84c7acf.15480651%2F320.mp3&ext=.mp3","trackType":"mp3"} Feb 15 20:32:25 volumio volumio[1047]: verbose: CURRENT POSITION 0 Feb 15 20:32:25 volumio volumio[1047]: info: CoreStateMachine::syncState stateService play Feb 15 20:32:25 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus play Feb 15 20:32:25 volumio volumio[1047]: info: Received an update from plugin. extracting info from payload Feb 15 20:32:25 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:32:25 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 20:32:25 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:32:25 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:32:25 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:32:25 volumio volumio[1047]: info: ------------------------------ 16ms Feb 15 20:32:25 volumio volumio[1047]: info: ------------------------------ 17ms Feb 15 20:32:25 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 9 milliseconds Feb 15 20:32:25 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 8 milliseconds Feb 15 20:32:25 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 8 milliseconds Feb 15 20:32:25 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 7 milliseconds Feb 15 20:32:25 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Feb 15 20:32:25 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Feb 15 20:32:25 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Feb 15 20:32:25 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Feb 15 20:32:25 volumio volumio[1047]: info: ControllerMpd::pushState Feb 15 20:32:25 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 15 20:32:25 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 15 20:32:25 volumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":63,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Bells of the Mannheim Christ Church","artist":"Леопольд Моцарт","album":"Merry Christmas, Vol. 1","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2F1557.cold-rtc.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Df6b118f32d70d9c174b84102749678afe90891c2234c15396e0d9e772a599934%2Clid%3D1557%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67bf2cf1%2F0%2F48177%2Fa84c7acf.15480651%2F320.mp3&ext=.mp3","trackType":"mp3"} Feb 15 20:32:25 volumio volumio[1047]: verbose: CURRENT POSITION 0 Feb 15 20:32:25 volumio volumio[1047]: info: CoreStateMachine::syncState stateService play Feb 15 20:32:25 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus play Feb 15 20:32:25 volumio volumio[1047]: info: Received an update from plugin. extracting info from payload Feb 15 20:32:25 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:32:25 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:32:25 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:32:25 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:32:25 volumio volumio[1047]: info: ControllerMpd::pushState Feb 15 20:32:25 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 15 20:32:25 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 15 20:32:25 volumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":63,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Bells of the Mannheim Christ Church","artist":"Леопольд Моцарт","album":"Merry Christmas, Vol. 1","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2F1557.cold-rtc.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Df6b118f32d70d9c174b84102749678afe90891c2234c15396e0d9e772a599934%2Clid%3D1557%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67bf2cf1%2F0%2F48177%2Fa84c7acf.15480651%2F320.mp3&ext=.mp3","trackType":"mp3"} Feb 15 20:32:25 volumio volumio[1047]: verbose: CURRENT POSITION 0 Feb 15 20:32:25 volumio volumio[1047]: info: CoreStateMachine::syncState stateService play Feb 15 20:32:25 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus play Feb 15 20:32:25 volumio volumio[1047]: info: Received an update from plugin. extracting info from payload Feb 15 20:32:25 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:32:25 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 20:32:25 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:32:25 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:32:25 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:32:25 volumio volumio[1047]: info: ControllerMpd::pushState Feb 15 20:32:25 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 15 20:32:25 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 15 20:32:25 volumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":68,"duration":63,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Bells of the Mannheim Christ Church","artist":"Леопольд Моцарт","album":"Merry Christmas, Vol. 1","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2F1557.cold-rtc.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Df6b118f32d70d9c174b84102749678afe90891c2234c15396e0d9e772a599934%2Clid%3D1557%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67bf2cf1%2F0%2F48177%2Fa84c7acf.15480651%2F320.mp3&ext=.mp3","trackType":"mp3"} Feb 15 20:32:25 volumio volumio[1047]: verbose: CURRENT POSITION 0 Feb 15 20:32:25 volumio volumio[1047]: info: CoreStateMachine::syncState stateService play Feb 15 20:32:25 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus play Feb 15 20:32:25 volumio volumio[1047]: info: Received an update from plugin. extracting info from payload Feb 15 20:32:25 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:32:25 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:32:25 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:32:25 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:32:25 volumio volumio[1047]: info: ControllerMpd::pushState Feb 15 20:32:25 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 15 20:32:25 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 15 20:32:25 volumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":63,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Bells of the Mannheim Christ Church","artist":"Леопольд Моцарт","album":"Merry Christmas, Vol. 1","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2F1557.cold-rtc.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Df6b118f32d70d9c174b84102749678afe90891c2234c15396e0d9e772a599934%2Clid%3D1557%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67bf2cf1%2F0%2F48177%2Fa84c7acf.15480651%2F320.mp3&ext=.mp3","trackType":"mp3"} Feb 15 20:32:25 volumio volumio[1047]: verbose: CURRENT POSITION 0 Feb 15 20:32:25 volumio volumio[1047]: info: CoreStateMachine::syncState stateService play Feb 15 20:32:25 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus play Feb 15 20:32:25 volumio volumio[1047]: info: Received an update from plugin. extracting info from payload Feb 15 20:32:25 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:32:25 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:32:25 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:32:25 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:32:25 volumio volumio[1047]: info: ------------------------------ 33ms Feb 15 20:32:25 volumio volumio[1047]: info: ------------------------------ 33ms Feb 15 20:32:25 volumio volumio[1047]: info: ------------------------------ 30ms Feb 15 20:32:25 volumio volumio[1047]: info: ------------------------------ 30ms Feb 15 20:32:25 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:32:25 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:32:25 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:32:25 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:32:25 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:32:25 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:32:25 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:32:25 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:32:25 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:32:25 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:32:25 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:32:25 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:32:25 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:32:25 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:32:25 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:32:25 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:32:25 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:32:25 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:32:25 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:32:25 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:32:25 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:32:25 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:32:25 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:32:25 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:32:25 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:32:25 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:32:25 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:32:25 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:32:25 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:32:25 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:32:37 volumio volumio[1047]: info: CoreCommandRouter::volumioNext Feb 15 20:32:37 volumio volumio[1047]: info: CoreStateMachine::next Feb 15 20:32:37 volumio volumio[1047]: info: ControllerMpd::next Feb 15 20:32:37 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand next Feb 15 20:32:37 volumio volumio[1047]: info: Feb 15 20:32:37 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:32:37 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:32:37 volumio volumio[1047]: info: Feb 15 20:32:37 volumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 15 20:32:37 volumio volumio[1047]: info: sendMpdCommand next took 17 milliseconds Feb 15 20:32:37 volumio volumio[1047]: info: ControllerMpd::getState Feb 15 20:32:37 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:32:37 volumio volumio[1047]: info: Feb 15 20:32:37 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:32:37 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:32:37 volumio volumio[1047]: info: Feb 15 20:32:37 volumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 15 20:32:37 volumio volumio[1047]: info: ControllerMpd::getState Feb 15 20:32:37 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:32:37 volumio volumio[1047]: info: Feb 15 20:32:37 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:32:37 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:32:37 volumio volumio[1047]: info: Feb 15 20:32:37 volumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 15 20:32:37 volumio volumio[1047]: info: ControllerMpd::getState Feb 15 20:32:37 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:32:37 volumio volumio[1047]: error: updateQueue error: null Feb 15 20:32:37 volumio volumio[1047]: info: ------------------------------ 3ms Feb 15 20:32:37 volumio volumio[1047]: info: sendMpdCommand status took 2 milliseconds Feb 15 20:32:37 volumio volumio[1047]: verbose: ControllerMpd::parseState Feb 15 20:32:37 volumio volumio[1047]: info: ControllerMpd::pushState Feb 15 20:32:37 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 15 20:32:37 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:32:37 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 20:32:37 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:32:37 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 15 20:32:37 volumio volumio[1047]: 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} Feb 15 20:32:37 volumio volumio[1047]: verbose: CURRENT POSITION 0 Feb 15 20:32:37 volumio volumio[1047]: info: CoreStateMachine::syncState stateService stop Feb 15 20:32:37 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus play Feb 15 20:32:37 volumio volumio[1047]: info: CoreStateMachine::play index undefined Feb 15 20:32:37 volumio volumio[1047]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 15 20:32:37 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:32:37 volumio volumio[1047]: info: CorePlayQueue::getTrack 1 Feb 15 20:32:37 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 20:32:37 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:32:37 volumio volumio[1047]: info: CorePlayQueue::getTrack 1 Feb 15 20:32:37 volumio volumio[1047]: info: CoreStateMachine::startPlaybackTimer Feb 15 20:32:37 volumio volumio[1047]: info: CorePlayQueue::getTrack 1 Feb 15 20:32:37 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand stop Feb 15 20:32:37 volumio volumio[1047]: info: ------------------------------ 8ms Feb 15 20:32:37 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:32:37 volumio volumio[1047]: info: CorePlayQueue::getTrack 1 Feb 15 20:32:37 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 20:32:37 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:32:37 volumio volumio[1047]: error: updateQueue error: null Feb 15 20:32:37 volumio volumio[1047]: error: updateQueue error: null Feb 15 20:32:37 volumio volumio[1047]: info: ------------------------------ 10ms Feb 15 20:32:37 volumio volumio[1047]: info: sendMpdCommand status took 9 milliseconds Feb 15 20:32:37 volumio volumio[1047]: info: ------------------------------ 9ms Feb 15 20:32:37 volumio volumio[1047]: info: sendMpdCommand status took 9 milliseconds Feb 15 20:32:37 volumio volumio[1047]: info: sendMpdCommand stop took 3 milliseconds Feb 15 20:32:37 volumio volumio[1047]: verbose: ControllerMpd::parseState Feb 15 20:32:37 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand clear Feb 15 20:32:37 volumio volumio[1047]: info: ControllerMpd::pushState Feb 15 20:32:37 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 15 20:32:37 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:32:37 volumio volumio[1047]: info: CorePlayQueue::getTrack 1 Feb 15 20:32:37 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:32:37 volumio volumio[1047]: info: CorePlayQueue::getTrack 1 Feb 15 20:32:37 volumio volumio[1047]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd Feb 15 20:32:37 volumio volumio[1047]: info: ControllerMpd::pushState Feb 15 20:32:37 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 15 20:32:37 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:32:37 volumio volumio[1047]: info: CorePlayQueue::getTrack 1 Feb 15 20:32:37 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:32:37 volumio volumio[1047]: info: CorePlayQueue::getTrack 1 Feb 15 20:32:37 volumio volumio[1047]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd Feb 15 20:32:37 volumio volumio[1047]: info: ------------------------------ 18ms Feb 15 20:32:37 volumio volumio[1047]: info: ------------------------------ 17ms Feb 15 20:32:37 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:32:37 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:32:37 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:32:37 volumio volumio[1047]: info: Feb 15 20:32:37 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:32:37 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:32:37 volumio volumio[1047]: info: sendMpdCommand clear took 12 milliseconds Feb 15 20:32:37 volumio volumio[1047]: info: Feb 15 20:32:37 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:32:37 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:32:37 volumio volumio[1047]: info: Feb 15 20:32:37 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:32:37 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:32:37 volumio volumio[1047]: error: updateQueue error: null Feb 15 20:32:37 volumio volumio[1047]: error: updateQueue error: null Feb 15 20:32:37 volumio volumio[1047]: info: ------------------------------ 6ms Feb 15 20:32:37 volumio volumio[1047]: info: ------------------------------ 2ms Feb 15 20:32:37 volumio volumio[1047]: error: updateQueue error: null Feb 15 20:32:37 volumio volumio[1047]: info: ------------------------------ 2ms Feb 15 20:32:37 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2F1557.cold-rtc.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Db49fd9ea45015f8a4e4fa44d1c0be9b9e2592802160208e16e5dc342c2faf939%2Clid%3D1557%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67be88ad%2F0%2F22952%2F131b85de.15480652%2F320.mp3&ext=.mp3" Feb 15 20:32:37 volumio volumio[1047]: info: Feb 15 20:32:37 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:32:37 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:32:37 volumio volumio[1047]: info: sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2F1557.cold-rtc.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Db49fd9ea45015f8a4e4fa44d1c0be9b9e2592802160208e16e5dc342c2faf939%2Clid%3D1557%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67be88ad%2F0%2F22952%2F131b85de.15480652%2F320.mp3&ext=.mp3" took 2 milliseconds Feb 15 20:32:37 volumio volumio[1047]: verbose: MPD COMMAND [object Object] Feb 15 20:32:37 volumio volumio[1047]: verbose: MPD COMMAND [object Object] Feb 15 20:32:37 volumio volumio[1047]: info: Feb 15 20:32:37 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:32:37 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:32:37 volumio volumio[1047]: info: Feb 15 20:32:37 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:32:37 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:32:37 volumio volumio[1047]: info: Feb 15 20:32:37 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:32:37 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:32:37 volumio volumio[1047]: info: ------------------------------ 2ms Feb 15 20:32:37 volumio volumio[1047]: info: ------------------------------ 2ms Feb 15 20:32:37 volumio volumio[1047]: info: ------------------------------ 2ms Feb 15 20:32:37 volumio volumio[1047]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 15 20:32:37 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand play Feb 15 20:32:37 volumio volumio[1047]: info: Feb 15 20:32:37 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:32:37 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:32:37 volumio volumio[1047]: info: Feb 15 20:32:37 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:32:37 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:32:37 volumio volumio[1047]: info: ------------------------------ 2ms Feb 15 20:32:37 volumio volumio[1047]: info: sendMpdCommand play took 2 milliseconds Feb 15 20:32:37 volumio volumio[1047]: info: ------------------------------ 2ms Feb 15 20:32:37 volumio volumio[1047]: info: ------------------------------ 1ms Feb 15 20:32:38 volumio volumio[1047]: info: Feb 15 20:32:38 volumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 15 20:32:38 volumio volumio[1047]: info: ControllerMpd::getState Feb 15 20:32:38 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:32:38 volumio volumio[1047]: info: Feb 15 20:32:38 volumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 15 20:32:38 volumio volumio[1047]: info: ControllerMpd::getState Feb 15 20:32:38 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:32:38 volumio volumio[1047]: info: Feb 15 20:32:38 volumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 15 20:32:38 volumio volumio[1047]: info: ControllerMpd::getState Feb 15 20:32:38 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:32:38 volumio volumio[1047]: info: Feb 15 20:32:38 volumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 15 20:32:38 volumio volumio[1047]: info: ControllerMpd::getState Feb 15 20:32:38 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:32:38 volumio volumio[1047]: info: sendMpdCommand status took 2 milliseconds Feb 15 20:32:38 volumio volumio[1047]: info: sendMpdCommand status took 1 milliseconds Feb 15 20:32:38 volumio volumio[1047]: verbose: ControllerMpd::parseState Feb 15 20:32:38 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 20:32:38 volumio volumio[1047]: verbose: ControllerMpd::parseState Feb 15 20:32:38 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 20:32:38 volumio volumio[1047]: verbose: ControllerMpd::parseState Feb 15 20:32:38 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 20:32:38 volumio volumio[1047]: info: Feb 15 20:32:38 volumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 15 20:32:38 volumio volumio[1047]: info: ControllerMpd::getState Feb 15 20:32:38 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:32:38 volumio volumio[1047]: info: Feb 15 20:32:38 volumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 15 20:32:38 volumio volumio[1047]: info: ControllerMpd::getState Feb 15 20:32:38 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:32:38 volumio volumio[1047]: info: sendMpdCommand status took 4 milliseconds Feb 15 20:32:38 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 15 20:32:38 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 15 20:32:38 volumio volumio[1047]: info: sendMpdCommand status took 3 milliseconds Feb 15 20:32:38 volumio volumio[1047]: verbose: ControllerMpd::parseState Feb 15 20:32:38 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 20:32:38 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Feb 15 20:32:38 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Feb 15 20:32:38 volumio volumio[1047]: verbose: ControllerMpd::parseState Feb 15 20:32:38 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 20:32:38 volumio volumio[1047]: info: ControllerMpd::pushState Feb 15 20:32:38 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 15 20:32:38 volumio volumio[1047]: info: CorePlayQueue::getTrack 1 Feb 15 20:32:38 volumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":185,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Joy to the World","artist":"Леопольд Моцарт","album":"Merry Christmas, Vol. 1","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2F1557.cold-rtc.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Db49fd9ea45015f8a4e4fa44d1c0be9b9e2592802160208e16e5dc342c2faf939%2Clid%3D1557%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67be88ad%2F0%2F22952%2F131b85de.15480652%2F320.mp3&ext=.mp3","trackType":"mp3"} Feb 15 20:32:38 volumio volumio[1047]: verbose: CURRENT POSITION 1 Feb 15 20:32:38 volumio volumio[1047]: info: CoreStateMachine::syncState stateService play Feb 15 20:32:38 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus stop Feb 15 20:32:38 volumio volumio[1047]: info: ControllerMpd::pushState Feb 15 20:32:38 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 15 20:32:38 volumio volumio[1047]: info: CorePlayQueue::getTrack 1 Feb 15 20:32:38 volumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":185,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Joy to the World","artist":"Леопольд Моцарт","album":"Merry Christmas, Vol. 1","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2F1557.cold-rtc.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Db49fd9ea45015f8a4e4fa44d1c0be9b9e2592802160208e16e5dc342c2faf939%2Clid%3D1557%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67be88ad%2F0%2F22952%2F131b85de.15480652%2F320.mp3&ext=.mp3","trackType":"mp3"} Feb 15 20:32:38 volumio volumio[1047]: verbose: CURRENT POSITION 1 Feb 15 20:32:38 volumio volumio[1047]: info: CoreStateMachine::syncState stateService play Feb 15 20:32:38 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus play Feb 15 20:32:38 volumio volumio[1047]: info: Received an update from plugin. extracting info from payload Feb 15 20:32:38 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:32:38 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 20:32:38 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:32:38 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:32:38 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:32:38 volumio volumio[1047]: info: ControllerMpd::pushState Feb 15 20:32:38 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 15 20:32:38 volumio volumio[1047]: info: CorePlayQueue::getTrack 1 Feb 15 20:32:38 volumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":185,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Joy to the World","artist":"Леопольд Моцарт","album":"Merry Christmas, Vol. 1","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2F1557.cold-rtc.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Db49fd9ea45015f8a4e4fa44d1c0be9b9e2592802160208e16e5dc342c2faf939%2Clid%3D1557%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67be88ad%2F0%2F22952%2F131b85de.15480652%2F320.mp3&ext=.mp3","trackType":"mp3"} Feb 15 20:32:38 volumio volumio[1047]: verbose: CURRENT POSITION 1 Feb 15 20:32:38 volumio volumio[1047]: info: CoreStateMachine::syncState stateService play Feb 15 20:32:38 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus play Feb 15 20:32:38 volumio volumio[1047]: info: Received an update from plugin. extracting info from payload Feb 15 20:32:38 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:32:38 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:32:38 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:32:38 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:32:38 volumio volumio[1047]: info: ------------------------------ 14ms Feb 15 20:32:38 volumio volumio[1047]: info: ------------------------------ 19ms Feb 15 20:32:38 volumio volumio[1047]: info: ------------------------------ 18ms Feb 15 20:32:38 volumio volumio[1047]: info: sendMpdCommand status took 15 milliseconds Feb 15 20:32:38 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 13 milliseconds Feb 15 20:32:38 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 13 milliseconds Feb 15 20:32:38 volumio volumio[1047]: verbose: ControllerMpd::parseState Feb 15 20:32:38 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 20:32:38 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Feb 15 20:32:38 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Feb 15 20:32:38 volumio volumio[1047]: info: ControllerMpd::pushState Feb 15 20:32:38 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 15 20:32:38 volumio volumio[1047]: info: CorePlayQueue::getTrack 1 Feb 15 20:32:38 volumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":185,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Joy to the World","artist":"Леопольд Моцарт","album":"Merry Christmas, Vol. 1","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2F1557.cold-rtc.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Db49fd9ea45015f8a4e4fa44d1c0be9b9e2592802160208e16e5dc342c2faf939%2Clid%3D1557%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67be88ad%2F0%2F22952%2F131b85de.15480652%2F320.mp3&ext=.mp3","trackType":"mp3"} Feb 15 20:32:38 volumio volumio[1047]: verbose: CURRENT POSITION 1 Feb 15 20:32:38 volumio volumio[1047]: info: CoreStateMachine::syncState stateService play Feb 15 20:32:38 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus play Feb 15 20:32:38 volumio volumio[1047]: info: Received an update from plugin. extracting info from payload Feb 15 20:32:38 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:32:38 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 20:32:38 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:32:38 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:32:38 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:32:38 volumio volumio[1047]: info: ControllerMpd::pushState Feb 15 20:32:38 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 15 20:32:38 volumio volumio[1047]: info: CorePlayQueue::getTrack 1 Feb 15 20:32:38 volumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":185,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Joy to the World","artist":"Леопольд Моцарт","album":"Merry Christmas, Vol. 1","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2F1557.cold-rtc.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Db49fd9ea45015f8a4e4fa44d1c0be9b9e2592802160208e16e5dc342c2faf939%2Clid%3D1557%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67be88ad%2F0%2F22952%2F131b85de.15480652%2F320.mp3&ext=.mp3","trackType":"mp3"} Feb 15 20:32:38 volumio volumio[1047]: verbose: CURRENT POSITION 1 Feb 15 20:32:38 volumio volumio[1047]: info: CoreStateMachine::syncState stateService play Feb 15 20:32:38 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus play Feb 15 20:32:38 volumio volumio[1047]: info: Received an update from plugin. extracting info from payload Feb 15 20:32:38 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:32:38 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:32:38 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:32:38 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:32:38 volumio volumio[1047]: info: ------------------------------ 30ms Feb 15 20:32:38 volumio volumio[1047]: info: ------------------------------ 28ms Feb 15 20:32:38 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:32:38 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:32:38 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:32:38 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:32:38 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:32:38 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:32:38 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:32:38 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:32:38 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:32:38 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:32:38 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:32:38 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:32:38 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:32:38 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:32:38 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:32:38 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:32:38 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:32:38 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:32:38 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:32:38 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:32:38 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:32:38 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:32:38 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:32:38 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:32:38 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 50 milliseconds Feb 15 20:32:38 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Feb 15 20:32:38 volumio volumio[1047]: info: ControllerMpd::pushState Feb 15 20:32:38 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 15 20:32:38 volumio volumio[1047]: info: CorePlayQueue::getTrack 1 Feb 15 20:32:38 volumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":185,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Joy to the World","artist":"Леопольд Моцарт","album":"Merry Christmas, Vol. 1","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2F1557.cold-rtc.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Db49fd9ea45015f8a4e4fa44d1c0be9b9e2592802160208e16e5dc342c2faf939%2Clid%3D1557%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67be88ad%2F0%2F22952%2F131b85de.15480652%2F320.mp3&ext=.mp3","trackType":"mp3"} Feb 15 20:32:38 volumio volumio[1047]: verbose: CURRENT POSITION 1 Feb 15 20:32:38 volumio volumio[1047]: info: CoreStateMachine::syncState stateService play Feb 15 20:32:38 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus play Feb 15 20:32:38 volumio volumio[1047]: info: Received an update from plugin. extracting info from payload Feb 15 20:32:38 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:32:38 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 20:32:38 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:32:38 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:32:38 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:32:38 volumio volumio[1047]: info: ------------------------------ 71ms Feb 15 20:32:38 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:32:38 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:32:38 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:32:38 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:32:38 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:32:38 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:32:40 volumio volumio[1047]: info: CoreCommandRouter::volumioNext Feb 15 20:32:40 volumio volumio[1047]: info: CoreStateMachine::next Feb 15 20:32:40 volumio volumio[1047]: info: ControllerMpd::next Feb 15 20:32:40 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand next Feb 15 20:32:40 volumio volumio[1047]: info: Feb 15 20:32:40 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:32:40 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:32:40 volumio volumio[1047]: info: Feb 15 20:32:40 volumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 15 20:32:40 volumio volumio[1047]: info: sendMpdCommand next took 20 milliseconds Feb 15 20:32:40 volumio volumio[1047]: info: ControllerMpd::getState Feb 15 20:32:40 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:32:40 volumio volumio[1047]: info: Feb 15 20:32:40 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:32:40 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:32:40 volumio volumio[1047]: info: Feb 15 20:32:40 volumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 15 20:32:40 volumio volumio[1047]: info: ControllerMpd::getState Feb 15 20:32:40 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:32:40 volumio volumio[1047]: info: Feb 15 20:32:40 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:32:40 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:32:40 volumio volumio[1047]: info: Feb 15 20:32:40 volumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 15 20:32:40 volumio volumio[1047]: info: ControllerMpd::getState Feb 15 20:32:40 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:32:40 volumio volumio[1047]: error: updateQueue error: null Feb 15 20:32:40 volumio volumio[1047]: info: ------------------------------ 3ms Feb 15 20:32:40 volumio volumio[1047]: info: sendMpdCommand status took 2 milliseconds Feb 15 20:32:40 volumio volumio[1047]: verbose: ControllerMpd::parseState Feb 15 20:32:40 volumio volumio[1047]: info: ControllerMpd::pushState Feb 15 20:32:40 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 15 20:32:40 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:32:40 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 20:32:40 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:32:40 volumio volumio[1047]: info: CorePlayQueue::getTrack 1 Feb 15 20:32:40 volumio volumio[1047]: 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} Feb 15 20:32:40 volumio volumio[1047]: verbose: CURRENT POSITION 1 Feb 15 20:32:40 volumio volumio[1047]: info: CoreStateMachine::syncState stateService stop Feb 15 20:32:40 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus play Feb 15 20:32:40 volumio volumio[1047]: info: CoreStateMachine::play index undefined Feb 15 20:32:40 volumio volumio[1047]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 15 20:32:40 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:32:40 volumio volumio[1047]: info: CorePlayQueue::getTrack 2 Feb 15 20:32:40 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 20:32:40 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:32:40 volumio volumio[1047]: info: CorePlayQueue::getTrack 2 Feb 15 20:32:40 volumio volumio[1047]: info: CoreStateMachine::startPlaybackTimer Feb 15 20:32:40 volumio volumio[1047]: info: CorePlayQueue::getTrack 2 Feb 15 20:32:40 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand stop Feb 15 20:32:40 volumio volumio[1047]: info: ------------------------------ 8ms Feb 15 20:32:40 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:32:40 volumio volumio[1047]: info: CorePlayQueue::getTrack 2 Feb 15 20:32:40 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 20:32:40 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:32:40 volumio volumio[1047]: error: updateQueue error: null Feb 15 20:32:40 volumio volumio[1047]: info: ------------------------------ 11ms Feb 15 20:32:40 volumio volumio[1047]: info: sendMpdCommand status took 10 milliseconds Feb 15 20:32:40 volumio volumio[1047]: info: ------------------------------ 10ms Feb 15 20:32:40 volumio volumio[1047]: info: sendMpdCommand status took 11 milliseconds Feb 15 20:32:40 volumio volumio[1047]: info: sendMpdCommand stop took 5 milliseconds Feb 15 20:32:40 volumio volumio[1047]: verbose: ControllerMpd::parseState Feb 15 20:32:40 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand clear Feb 15 20:32:40 volumio volumio[1047]: info: ControllerMpd::pushState Feb 15 20:32:40 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 15 20:32:40 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:32:40 volumio volumio[1047]: info: CorePlayQueue::getTrack 2 Feb 15 20:32:40 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:32:40 volumio volumio[1047]: info: CorePlayQueue::getTrack 2 Feb 15 20:32:40 volumio volumio[1047]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd Feb 15 20:32:40 volumio volumio[1047]: info: ControllerMpd::pushState Feb 15 20:32:40 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 15 20:32:40 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:32:40 volumio volumio[1047]: info: CorePlayQueue::getTrack 2 Feb 15 20:32:40 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:32:40 volumio volumio[1047]: info: CorePlayQueue::getTrack 2 Feb 15 20:32:40 volumio volumio[1047]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd Feb 15 20:32:40 volumio volumio[1047]: info: ------------------------------ 16ms Feb 15 20:32:40 volumio volumio[1047]: info: ------------------------------ 16ms Feb 15 20:32:40 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:32:40 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:32:40 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:32:40 volumio volumio[1047]: info: Feb 15 20:32:40 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:32:40 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:32:40 volumio volumio[1047]: info: sendMpdCommand clear took 12 milliseconds Feb 15 20:32:40 volumio volumio[1047]: info: Feb 15 20:32:40 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:32:40 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:32:40 volumio volumio[1047]: info: Feb 15 20:32:40 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:32:40 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:32:40 volumio volumio[1047]: error: updateQueue error: null Feb 15 20:32:40 volumio volumio[1047]: info: ------------------------------ 6ms Feb 15 20:32:40 volumio volumio[1047]: info: ------------------------------ 2ms Feb 15 20:32:40 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2F1557.cold-rtc.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Da49f3d463c697250dc1ec59e72e25fcfabad0817d496afab74edd22c33c7fce0%2Clid%3D1557%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67be2df0%2F0%2F52308%2F77089ff2.20390922.6.15480646%2F320.mp3&ext=.mp3" Feb 15 20:32:40 volumio volumio[1047]: info: Feb 15 20:32:40 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:32:40 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:32:40 volumio volumio[1047]: info: sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2F1557.cold-rtc.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Da49f3d463c697250dc1ec59e72e25fcfabad0817d496afab74edd22c33c7fce0%2Clid%3D1557%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67be2df0%2F0%2F52308%2F77089ff2.20390922.6.15480646%2F320.mp3&ext=.mp3" took 1 milliseconds Feb 15 20:32:40 volumio volumio[1047]: verbose: MPD COMMAND [object Object] Feb 15 20:32:40 volumio volumio[1047]: verbose: MPD COMMAND [object Object] Feb 15 20:32:40 volumio volumio[1047]: info: Feb 15 20:32:40 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:32:40 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:32:40 volumio volumio[1047]: info: Feb 15 20:32:40 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:32:40 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:32:40 volumio volumio[1047]: info: Feb 15 20:32:40 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:32:40 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:32:40 volumio volumio[1047]: info: ------------------------------ 5ms Feb 15 20:32:40 volumio volumio[1047]: info: ------------------------------ 4ms Feb 15 20:32:40 volumio volumio[1047]: info: ------------------------------ 3ms Feb 15 20:32:40 volumio volumio[1047]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 15 20:32:40 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand play Feb 15 20:32:40 volumio volumio[1047]: info: Feb 15 20:32:40 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:32:40 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:32:40 volumio volumio[1047]: info: Feb 15 20:32:40 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:32:40 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:32:40 volumio volumio[1047]: info: ------------------------------ 4ms Feb 15 20:32:40 volumio volumio[1047]: info: sendMpdCommand play took 2 milliseconds Feb 15 20:32:40 volumio volumio[1047]: info: ------------------------------ 2ms Feb 15 20:32:40 volumio volumio[1047]: info: ------------------------------ 2ms Feb 15 20:32:41 volumio volumio[1047]: info: Feb 15 20:32:41 volumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 15 20:32:41 volumio volumio[1047]: info: ControllerMpd::getState Feb 15 20:32:41 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:32:41 volumio volumio[1047]: info: Feb 15 20:32:41 volumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 15 20:32:41 volumio volumio[1047]: info: ControllerMpd::getState Feb 15 20:32:41 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:32:41 volumio volumio[1047]: info: Feb 15 20:32:41 volumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 15 20:32:41 volumio volumio[1047]: info: ControllerMpd::getState Feb 15 20:32:41 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:32:41 volumio volumio[1047]: info: Feb 15 20:32:41 volumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 15 20:32:41 volumio volumio[1047]: info: ControllerMpd::getState Feb 15 20:32:41 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:32:41 volumio volumio[1047]: info: sendMpdCommand status took 3 milliseconds Feb 15 20:32:41 volumio volumio[1047]: info: sendMpdCommand status took 3 milliseconds Feb 15 20:32:41 volumio volumio[1047]: verbose: ControllerMpd::parseState Feb 15 20:32:41 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 20:32:41 volumio volumio[1047]: verbose: ControllerMpd::parseState Feb 15 20:32:41 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 20:32:41 volumio volumio[1047]: info: Feb 15 20:32:41 volumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 15 20:32:41 volumio volumio[1047]: info: ControllerMpd::getState Feb 15 20:32:41 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:32:41 volumio volumio[1047]: info: Feb 15 20:32:41 volumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 15 20:32:41 volumio volumio[1047]: info: ControllerMpd::getState Feb 15 20:32:41 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:32:41 volumio volumio[1047]: info: sendMpdCommand status took 6 milliseconds Feb 15 20:32:41 volumio volumio[1047]: info: sendMpdCommand status took 6 milliseconds Feb 15 20:32:41 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 15 20:32:41 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 15 20:32:41 volumio volumio[1047]: info: sendMpdCommand status took 3 milliseconds Feb 15 20:32:41 volumio volumio[1047]: verbose: ControllerMpd::parseState Feb 15 20:32:41 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 20:32:41 volumio volumio[1047]: verbose: ControllerMpd::parseState Feb 15 20:32:41 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 20:32:41 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Feb 15 20:32:41 volumio volumio[1047]: verbose: ControllerMpd::parseState Feb 15 20:32:41 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 20:32:41 volumio volumio[1047]: info: ControllerMpd::pushState Feb 15 20:32:41 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 15 20:32:41 volumio volumio[1047]: info: CorePlayQueue::getTrack 2 Feb 15 20:32:41 volumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":110,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Carol of the Bells","artist":"Леопольд Моцарт","album":"Merry Christmas, Vol. 1","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2F1557.cold-rtc.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Da49f3d463c697250dc1ec59e72e25fcfabad0817d496afab74edd22c33c7fce0%2Clid%3D1557%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67be2df0%2F0%2F52308%2F77089ff2.20390922.6.15480646%2F320.mp3&ext=.mp3","trackType":"mp3"} Feb 15 20:32:41 volumio volumio[1047]: verbose: CURRENT POSITION 2 Feb 15 20:32:41 volumio volumio[1047]: info: CoreStateMachine::syncState stateService play Feb 15 20:32:41 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus stop Feb 15 20:32:41 volumio volumio[1047]: info: ControllerMpd::pushState Feb 15 20:32:41 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 15 20:32:41 volumio volumio[1047]: info: CorePlayQueue::getTrack 2 Feb 15 20:32:41 volumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":110,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Carol of the Bells","artist":"Леопольд Моцарт","album":"Merry Christmas, Vol. 1","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2F1557.cold-rtc.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Da49f3d463c697250dc1ec59e72e25fcfabad0817d496afab74edd22c33c7fce0%2Clid%3D1557%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67be2df0%2F0%2F52308%2F77089ff2.20390922.6.15480646%2F320.mp3&ext=.mp3","trackType":"mp3"} Feb 15 20:32:41 volumio volumio[1047]: verbose: CURRENT POSITION 2 Feb 15 20:32:41 volumio volumio[1047]: info: CoreStateMachine::syncState stateService play Feb 15 20:32:41 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus play Feb 15 20:32:41 volumio volumio[1047]: info: Received an update from plugin. extracting info from payload Feb 15 20:32:41 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:32:41 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 20:32:41 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:32:41 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:32:41 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:32:41 volumio volumio[1047]: info: ------------------------------ 14ms Feb 15 20:32:41 volumio volumio[1047]: info: ------------------------------ 14ms Feb 15 20:32:41 volumio volumio[1047]: info: sendMpdCommand status took 8 milliseconds Feb 15 20:32:41 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 15 20:32:41 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 15 20:32:41 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 7 milliseconds Feb 15 20:32:41 volumio volumio[1047]: verbose: ControllerMpd::parseState Feb 15 20:32:41 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 20:32:41 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Feb 15 20:32:41 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Feb 15 20:32:41 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Feb 15 20:32:41 volumio volumio[1047]: info: ControllerMpd::pushState Feb 15 20:32:41 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 15 20:32:41 volumio volumio[1047]: info: CorePlayQueue::getTrack 2 Feb 15 20:32:41 volumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":110,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Carol of the Bells","artist":"Леопольд Моцарт","album":"Merry Christmas, Vol. 1","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2F1557.cold-rtc.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Da49f3d463c697250dc1ec59e72e25fcfabad0817d496afab74edd22c33c7fce0%2Clid%3D1557%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67be2df0%2F0%2F52308%2F77089ff2.20390922.6.15480646%2F320.mp3&ext=.mp3","trackType":"mp3"} Feb 15 20:32:41 volumio volumio[1047]: verbose: CURRENT POSITION 2 Feb 15 20:32:41 volumio volumio[1047]: info: CoreStateMachine::syncState stateService play Feb 15 20:32:41 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus play Feb 15 20:32:41 volumio volumio[1047]: info: Received an update from plugin. extracting info from payload Feb 15 20:32:41 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:32:41 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 20:32:41 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:32:41 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:32:41 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:32:41 volumio volumio[1047]: info: ControllerMpd::pushState Feb 15 20:32:41 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 15 20:32:41 volumio volumio[1047]: info: CorePlayQueue::getTrack 2 Feb 15 20:32:41 volumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":110,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Carol of the Bells","artist":"Леопольд Моцарт","album":"Merry Christmas, Vol. 1","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2F1557.cold-rtc.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Da49f3d463c697250dc1ec59e72e25fcfabad0817d496afab74edd22c33c7fce0%2Clid%3D1557%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67be2df0%2F0%2F52308%2F77089ff2.20390922.6.15480646%2F320.mp3&ext=.mp3","trackType":"mp3"} Feb 15 20:32:41 volumio volumio[1047]: verbose: CURRENT POSITION 2 Feb 15 20:32:41 volumio volumio[1047]: info: CoreStateMachine::syncState stateService play Feb 15 20:32:41 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus play Feb 15 20:32:41 volumio volumio[1047]: info: Received an update from plugin. extracting info from payload Feb 15 20:32:41 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:32:41 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:32:41 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:32:41 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:32:41 volumio volumio[1047]: info: ControllerMpd::pushState Feb 15 20:32:41 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 15 20:32:41 volumio volumio[1047]: info: CorePlayQueue::getTrack 2 Feb 15 20:32:41 volumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":110,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Carol of the Bells","artist":"Леопольд Моцарт","album":"Merry Christmas, Vol. 1","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2F1557.cold-rtc.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Da49f3d463c697250dc1ec59e72e25fcfabad0817d496afab74edd22c33c7fce0%2Clid%3D1557%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67be2df0%2F0%2F52308%2F77089ff2.20390922.6.15480646%2F320.mp3&ext=.mp3","trackType":"mp3"} Feb 15 20:32:41 volumio volumio[1047]: verbose: CURRENT POSITION 2 Feb 15 20:32:41 volumio volumio[1047]: info: CoreStateMachine::syncState stateService play Feb 15 20:32:41 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus play Feb 15 20:32:41 volumio volumio[1047]: info: Received an update from plugin. extracting info from payload Feb 15 20:32:41 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:32:41 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 20:32:41 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:32:41 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:32:41 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:32:41 volumio volumio[1047]: info: ------------------------------ 27ms Feb 15 20:32:41 volumio volumio[1047]: info: ------------------------------ 27ms Feb 15 20:32:41 volumio volumio[1047]: info: ------------------------------ 24ms Feb 15 20:32:41 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:32:41 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:32:41 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:32:41 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:32:41 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:32:41 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:32:41 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:32:41 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:32:41 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:32:41 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:32:41 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:32:41 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:32:41 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:32:41 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:32:41 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:32:41 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:32:41 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:32:41 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:32:41 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:32:41 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:32:41 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:32:41 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:32:41 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:32:41 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:32:41 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 50 milliseconds Feb 15 20:32:41 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Feb 15 20:32:41 volumio volumio[1047]: info: ControllerMpd::pushState Feb 15 20:32:41 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 15 20:32:41 volumio volumio[1047]: info: CorePlayQueue::getTrack 2 Feb 15 20:32:41 volumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":110,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Carol of the Bells","artist":"Леопольд Моцарт","album":"Merry Christmas, Vol. 1","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2F1557.cold-rtc.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Da49f3d463c697250dc1ec59e72e25fcfabad0817d496afab74edd22c33c7fce0%2Clid%3D1557%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67be2df0%2F0%2F52308%2F77089ff2.20390922.6.15480646%2F320.mp3&ext=.mp3","trackType":"mp3"} Feb 15 20:32:41 volumio volumio[1047]: verbose: CURRENT POSITION 2 Feb 15 20:32:41 volumio volumio[1047]: info: CoreStateMachine::syncState stateService play Feb 15 20:32:41 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus play Feb 15 20:32:41 volumio volumio[1047]: info: Received an update from plugin. extracting info from payload Feb 15 20:32:41 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:32:41 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:32:41 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:32:41 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:32:41 volumio volumio[1047]: info: ------------------------------ 64ms Feb 15 20:32:41 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:32:41 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:32:41 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:32:41 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:32:41 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:32:41 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:33:05 volumio volumio[1047]: info: CoreCommandRouter::volumioNext Feb 15 20:33:05 volumio volumio[1047]: info: CoreStateMachine::next Feb 15 20:33:05 volumio volumio[1047]: info: ControllerMpd::next Feb 15 20:33:05 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand next Feb 15 20:33:05 volumio volumio[1047]: info: sendMpdCommand next took 33 milliseconds Feb 15 20:33:05 volumio volumio[1047]: info: Feb 15 20:33:05 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:33:05 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:33:05 volumio volumio[1047]: info: Feb 15 20:33:05 volumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 15 20:33:05 volumio volumio[1047]: info: ControllerMpd::getState Feb 15 20:33:05 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:33:05 volumio volumio[1047]: info: Feb 15 20:33:05 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:33:05 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:33:05 volumio volumio[1047]: info: Feb 15 20:33:05 volumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 15 20:33:05 volumio volumio[1047]: info: ControllerMpd::getState Feb 15 20:33:05 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:33:05 volumio volumio[1047]: info: Feb 15 20:33:05 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:33:05 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:33:05 volumio volumio[1047]: info: Feb 15 20:33:05 volumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 15 20:33:05 volumio volumio[1047]: info: ControllerMpd::getState Feb 15 20:33:05 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:33:05 volumio volumio[1047]: error: updateQueue error: null Feb 15 20:33:05 volumio volumio[1047]: error: updateQueue error: null Feb 15 20:33:05 volumio volumio[1047]: info: ------------------------------ 3ms Feb 15 20:33:05 volumio volumio[1047]: info: sendMpdCommand status took 3 milliseconds Feb 15 20:33:05 volumio volumio[1047]: info: ------------------------------ 2ms Feb 15 20:33:05 volumio volumio[1047]: verbose: ControllerMpd::parseState Feb 15 20:33:05 volumio volumio[1047]: info: ControllerMpd::pushState Feb 15 20:33:05 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 15 20:33:05 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:33:05 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 20:33:05 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:33:05 volumio volumio[1047]: info: CorePlayQueue::getTrack 2 Feb 15 20:33:05 volumio volumio[1047]: 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} Feb 15 20:33:05 volumio volumio[1047]: verbose: CURRENT POSITION 2 Feb 15 20:33:05 volumio volumio[1047]: info: CoreStateMachine::syncState stateService stop Feb 15 20:33:05 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus play Feb 15 20:33:05 volumio volumio[1047]: info: CoreStateMachine::play index undefined Feb 15 20:33:05 volumio volumio[1047]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 15 20:33:05 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:33:05 volumio volumio[1047]: info: CorePlayQueue::getTrack 3 Feb 15 20:33:05 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 20:33:05 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:33:05 volumio volumio[1047]: info: CorePlayQueue::getTrack 3 Feb 15 20:33:05 volumio volumio[1047]: info: CoreStateMachine::startPlaybackTimer Feb 15 20:33:05 volumio volumio[1047]: info: CorePlayQueue::getTrack 3 Feb 15 20:33:05 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand stop Feb 15 20:33:05 volumio volumio[1047]: info: ------------------------------ 8ms Feb 15 20:33:05 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:33:05 volumio volumio[1047]: info: CorePlayQueue::getTrack 3 Feb 15 20:33:05 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 20:33:05 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:33:05 volumio volumio[1047]: error: updateQueue error: null Feb 15 20:33:05 volumio volumio[1047]: info: sendMpdCommand status took 10 milliseconds Feb 15 20:33:05 volumio volumio[1047]: info: ------------------------------ 10ms Feb 15 20:33:05 volumio volumio[1047]: info: sendMpdCommand status took 10 milliseconds Feb 15 20:33:05 volumio volumio[1047]: info: sendMpdCommand stop took 5 milliseconds Feb 15 20:33:05 volumio volumio[1047]: verbose: ControllerMpd::parseState Feb 15 20:33:05 volumio volumio[1047]: verbose: ControllerMpd::parseState Feb 15 20:33:05 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand clear Feb 15 20:33:05 volumio volumio[1047]: info: ControllerMpd::pushState Feb 15 20:33:05 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 15 20:33:05 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:33:05 volumio volumio[1047]: info: CorePlayQueue::getTrack 3 Feb 15 20:33:05 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:33:05 volumio volumio[1047]: info: CorePlayQueue::getTrack 3 Feb 15 20:33:05 volumio volumio[1047]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd Feb 15 20:33:05 volumio volumio[1047]: info: ControllerMpd::pushState Feb 15 20:33:05 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 15 20:33:05 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:33:05 volumio volumio[1047]: info: CorePlayQueue::getTrack 3 Feb 15 20:33:05 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:33:05 volumio volumio[1047]: info: CorePlayQueue::getTrack 3 Feb 15 20:33:05 volumio volumio[1047]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd Feb 15 20:33:05 volumio volumio[1047]: info: ------------------------------ 15ms Feb 15 20:33:05 volumio volumio[1047]: info: ------------------------------ 14ms Feb 15 20:33:05 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:33:05 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:33:05 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:33:05 volumio volumio[1047]: info: Feb 15 20:33:05 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:33:05 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:33:05 volumio volumio[1047]: info: sendMpdCommand clear took 12 milliseconds Feb 15 20:33:05 volumio volumio[1047]: info: Feb 15 20:33:05 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:33:05 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:33:05 volumio volumio[1047]: info: Feb 15 20:33:05 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:33:05 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:33:05 volumio volumio[1047]: error: updateQueue error: null Feb 15 20:33:05 volumio volumio[1047]: error: updateQueue error: null Feb 15 20:33:05 volumio volumio[1047]: error: updateQueue error: null Feb 15 20:33:05 volumio volumio[1047]: info: ------------------------------ 7ms Feb 15 20:33:05 volumio volumio[1047]: info: ------------------------------ 2ms Feb 15 20:33:05 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2F1556.cold-rtc.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dcb3ce0ec63581f2602d5453ea076f880b99ca5ba72289720dbb480d087e5a5a9%2Clid%3D1556%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67bea602%2F0%2F15649%2F699498f4.15480658%2F2.mp3&ext=.mp3" Feb 15 20:33:05 volumio volumio[1047]: info: Feb 15 20:33:05 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:33:05 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:33:05 volumio volumio[1047]: info: sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2F1556.cold-rtc.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dcb3ce0ec63581f2602d5453ea076f880b99ca5ba72289720dbb480d087e5a5a9%2Clid%3D1556%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67bea602%2F0%2F15649%2F699498f4.15480658%2F2.mp3&ext=.mp3" took 1 milliseconds Feb 15 20:33:05 volumio volumio[1047]: verbose: MPD COMMAND [object Object] Feb 15 20:33:05 volumio volumio[1047]: verbose: MPD COMMAND [object Object] Feb 15 20:33:05 volumio volumio[1047]: info: Feb 15 20:33:05 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:33:05 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:33:05 volumio volumio[1047]: info: Feb 15 20:33:05 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:33:05 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:33:05 volumio volumio[1047]: info: Feb 15 20:33:05 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:33:05 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:33:05 volumio volumio[1047]: info: ------------------------------ 5ms Feb 15 20:33:05 volumio volumio[1047]: info: ------------------------------ 5ms Feb 15 20:33:05 volumio volumio[1047]: info: ------------------------------ 4ms Feb 15 20:33:05 volumio volumio[1047]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 15 20:33:05 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand play Feb 15 20:33:05 volumio volumio[1047]: info: Feb 15 20:33:05 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:33:05 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:33:05 volumio volumio[1047]: info: Feb 15 20:33:05 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:33:05 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:33:05 volumio volumio[1047]: info: ------------------------------ 6ms Feb 15 20:33:05 volumio volumio[1047]: info: sendMpdCommand play took 2 milliseconds Feb 15 20:33:05 volumio volumio[1047]: info: ------------------------------ 2ms Feb 15 20:33:05 volumio volumio[1047]: info: ------------------------------ 1ms Feb 15 20:33:06 volumio volumio[1047]: info: Feb 15 20:33:06 volumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 15 20:33:06 volumio volumio[1047]: info: ControllerMpd::getState Feb 15 20:33:06 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:33:06 volumio volumio[1047]: info: Feb 15 20:33:06 volumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 15 20:33:06 volumio volumio[1047]: info: ControllerMpd::getState Feb 15 20:33:06 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:33:06 volumio volumio[1047]: info: Feb 15 20:33:06 volumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 15 20:33:06 volumio volumio[1047]: info: ControllerMpd::getState Feb 15 20:33:06 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:33:06 volumio volumio[1047]: info: Feb 15 20:33:06 volumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 15 20:33:06 volumio volumio[1047]: info: sendMpdCommand status took 3 milliseconds Feb 15 20:33:06 volumio volumio[1047]: info: sendMpdCommand status took 2 milliseconds Feb 15 20:33:06 volumio volumio[1047]: info: ControllerMpd::getState Feb 15 20:33:06 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:33:06 volumio volumio[1047]: verbose: ControllerMpd::parseState Feb 15 20:33:06 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 20:33:06 volumio volumio[1047]: verbose: ControllerMpd::parseState Feb 15 20:33:06 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 20:33:06 volumio volumio[1047]: info: sendMpdCommand status took 3 milliseconds Feb 15 20:33:06 volumio volumio[1047]: info: sendMpdCommand status took 2 milliseconds Feb 15 20:33:06 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 15 20:33:06 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 15 20:33:06 volumio volumio[1047]: verbose: ControllerMpd::parseState Feb 15 20:33:06 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 20:33:06 volumio volumio[1047]: verbose: ControllerMpd::parseState Feb 15 20:33:06 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 20:33:06 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Feb 15 20:33:06 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Feb 15 20:33:06 volumio volumio[1047]: info: ControllerMpd::pushState Feb 15 20:33:06 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 15 20:33:06 volumio volumio[1047]: info: CorePlayQueue::getTrack 3 Feb 15 20:33:06 volumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":71,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Coventry Carol","artist":"Леопольд Моцарт","album":"Merry Christmas, Vol. 1","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2F1556.cold-rtc.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dcb3ce0ec63581f2602d5453ea076f880b99ca5ba72289720dbb480d087e5a5a9%2Clid%3D1556%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67bea602%2F0%2F15649%2F699498f4.15480658%2F2.mp3&ext=.mp3","trackType":"mp3"} Feb 15 20:33:06 volumio volumio[1047]: verbose: CURRENT POSITION 3 Feb 15 20:33:06 volumio volumio[1047]: info: CoreStateMachine::syncState stateService play Feb 15 20:33:06 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus stop Feb 15 20:33:06 volumio volumio[1047]: info: ControllerMpd::pushState Feb 15 20:33:06 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 15 20:33:06 volumio volumio[1047]: info: CorePlayQueue::getTrack 3 Feb 15 20:33:06 volumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":71,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Coventry Carol","artist":"Леопольд Моцарт","album":"Merry Christmas, Vol. 1","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2F1556.cold-rtc.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dcb3ce0ec63581f2602d5453ea076f880b99ca5ba72289720dbb480d087e5a5a9%2Clid%3D1556%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67bea602%2F0%2F15649%2F699498f4.15480658%2F2.mp3&ext=.mp3","trackType":"mp3"} Feb 15 20:33:06 volumio volumio[1047]: verbose: CURRENT POSITION 3 Feb 15 20:33:06 volumio volumio[1047]: info: CoreStateMachine::syncState stateService play Feb 15 20:33:06 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus play Feb 15 20:33:06 volumio volumio[1047]: info: Received an update from plugin. extracting info from payload Feb 15 20:33:06 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:33:06 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 20:33:06 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:33:06 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:33:06 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:33:06 volumio volumio[1047]: info: ------------------------------ 10ms Feb 15 20:33:06 volumio volumio[1047]: info: ------------------------------ 12ms Feb 15 20:33:06 volumio volumio[1047]: info: Feb 15 20:33:06 volumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 15 20:33:06 volumio volumio[1047]: info: ControllerMpd::getState Feb 15 20:33:06 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:33:06 volumio volumio[1047]: info: Feb 15 20:33:06 volumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 15 20:33:06 volumio volumio[1047]: info: ControllerMpd::getState Feb 15 20:33:06 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:33:06 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 9 milliseconds Feb 15 20:33:06 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 9 milliseconds Feb 15 20:33:06 volumio volumio[1047]: info: sendMpdCommand status took 1 milliseconds Feb 15 20:33:06 volumio volumio[1047]: info: sendMpdCommand status took 1 milliseconds Feb 15 20:33:06 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Feb 15 20:33:06 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Feb 15 20:33:06 volumio volumio[1047]: verbose: ControllerMpd::parseState Feb 15 20:33:06 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 20:33:06 volumio volumio[1047]: verbose: ControllerMpd::parseState Feb 15 20:33:06 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 20:33:06 volumio volumio[1047]: info: ControllerMpd::pushState Feb 15 20:33:06 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 15 20:33:06 volumio volumio[1047]: info: CorePlayQueue::getTrack 3 Feb 15 20:33:06 volumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":71,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Coventry Carol","artist":"Леопольд Моцарт","album":"Merry Christmas, Vol. 1","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2F1556.cold-rtc.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dcb3ce0ec63581f2602d5453ea076f880b99ca5ba72289720dbb480d087e5a5a9%2Clid%3D1556%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67bea602%2F0%2F15649%2F699498f4.15480658%2F2.mp3&ext=.mp3","trackType":"mp3"} Feb 15 20:33:06 volumio volumio[1047]: verbose: CURRENT POSITION 3 Feb 15 20:33:06 volumio volumio[1047]: info: CoreStateMachine::syncState stateService play Feb 15 20:33:06 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus play Feb 15 20:33:06 volumio volumio[1047]: info: Received an update from plugin. extracting info from payload Feb 15 20:33:06 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:33:06 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:33:06 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:33:06 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:33:06 volumio volumio[1047]: info: ControllerMpd::pushState Feb 15 20:33:06 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 15 20:33:06 volumio volumio[1047]: info: CorePlayQueue::getTrack 3 Feb 15 20:33:06 volumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":71,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Coventry Carol","artist":"Леопольд Моцарт","album":"Merry Christmas, Vol. 1","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2F1556.cold-rtc.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dcb3ce0ec63581f2602d5453ea076f880b99ca5ba72289720dbb480d087e5a5a9%2Clid%3D1556%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67bea602%2F0%2F15649%2F699498f4.15480658%2F2.mp3&ext=.mp3","trackType":"mp3"} Feb 15 20:33:06 volumio volumio[1047]: verbose: CURRENT POSITION 3 Feb 15 20:33:06 volumio volumio[1047]: info: CoreStateMachine::syncState stateService play Feb 15 20:33:06 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus play Feb 15 20:33:06 volumio volumio[1047]: info: Received an update from plugin. extracting info from payload Feb 15 20:33:06 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:33:06 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:33:06 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:33:06 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:33:06 volumio volumio[1047]: info: ------------------------------ 23ms Feb 15 20:33:06 volumio volumio[1047]: info: ------------------------------ 23ms Feb 15 20:33:06 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:33:06 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:33:06 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:33:06 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:33:06 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:33:06 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:33:06 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:33:06 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:33:06 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:33:06 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:33:06 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:33:06 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:33:06 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:33:06 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:33:06 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:33:06 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:33:06 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:33:06 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:33:06 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 37 milliseconds Feb 15 20:33:06 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 37 milliseconds Feb 15 20:33:06 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Feb 15 20:33:06 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Feb 15 20:33:06 volumio volumio[1047]: info: ControllerMpd::pushState Feb 15 20:33:06 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 15 20:33:06 volumio volumio[1047]: info: CorePlayQueue::getTrack 3 Feb 15 20:33:06 volumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":883,"duration":71,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"The Coventry Carol","artist":"Леопольд Моцарт","album":"Merry Christmas, Vol. 1","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2F1556.cold-rtc.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dcb3ce0ec63581f2602d5453ea076f880b99ca5ba72289720dbb480d087e5a5a9%2Clid%3D1556%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67bea602%2F0%2F15649%2F699498f4.15480658%2F2.mp3&ext=.mp3","trackType":"mp3"} Feb 15 20:33:06 volumio volumio[1047]: verbose: CURRENT POSITION 3 Feb 15 20:33:06 volumio volumio[1047]: info: CoreStateMachine::syncState stateService play Feb 15 20:33:06 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus play Feb 15 20:33:06 volumio volumio[1047]: info: Received an update from plugin. extracting info from payload Feb 15 20:33:06 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:33:06 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 20:33:06 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:33:06 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:33:06 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:33:06 volumio volumio[1047]: info: ControllerMpd::pushState Feb 15 20:33:06 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 15 20:33:06 volumio volumio[1047]: info: CorePlayQueue::getTrack 3 Feb 15 20:33:06 volumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":883,"duration":71,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"The Coventry Carol","artist":"Леопольд Моцарт","album":"Merry Christmas, Vol. 1","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2F1556.cold-rtc.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dcb3ce0ec63581f2602d5453ea076f880b99ca5ba72289720dbb480d087e5a5a9%2Clid%3D1556%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67bea602%2F0%2F15649%2F699498f4.15480658%2F2.mp3&ext=.mp3","trackType":"mp3"} Feb 15 20:33:06 volumio volumio[1047]: verbose: CURRENT POSITION 3 Feb 15 20:33:06 volumio volumio[1047]: info: CoreStateMachine::syncState stateService play Feb 15 20:33:06 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus play Feb 15 20:33:06 volumio volumio[1047]: info: Received an update from plugin. extracting info from payload Feb 15 20:33:06 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:33:06 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:33:06 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:33:06 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:33:06 volumio volumio[1047]: info: ------------------------------ 47ms Feb 15 20:33:06 volumio volumio[1047]: info: ------------------------------ 48ms Feb 15 20:33:06 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:33:06 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:33:06 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:33:06 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:33:06 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:33:06 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:33:06 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:33:06 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:33:06 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:33:06 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:33:06 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:33:06 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:33:10 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: yandex_music , handleBrowseUri Feb 15 20:33:11 volumio volumio[1047]: info: Preload queue cleared Feb 15 20:33:11 volumio volumio[1047]: info: Preloading song: yandex_music/track/58167323:8798848@8798848 Feb 15 20:33:11 volumio volumio[1047]: info: Preloading song: yandex_music/track/58167331:8798848@8798848 Feb 15 20:33:11 volumio volumio[1047]: info: Preloading song: yandex_music/track/58167342:8798848@8798848 Feb 15 20:33:11 volumio volumio[1047]: info: Preloading song: yandex_music/track/58167353:8798848@8798848 Feb 15 20:33:11 volumio volumio[1047]: info: Preloading song: yandex_music/track/58167357:8798848@8798848 Feb 15 20:33:11 volumio volumio[1047]: info: Preloading song: yandex_music/track/58167362:8798848@8798848 Feb 15 20:33:11 volumio volumio[1047]: info: Preloading song: yandex_music/track/58167366:8798848@8798848 Feb 15 20:33:11 volumio volumio[1047]: info: Preloading song: yandex_music/track/58167378:8798848@8798848 Feb 15 20:33:11 volumio volumio[1047]: info: Preloading song: yandex_music/track/58167381:8798848@8798848 Feb 15 20:33:11 volumio volumio[1047]: info: Preloading song: yandex_music/track/58167387:8798848@8798848 Feb 15 20:33:11 volumio volumio[1047]: info: Preloading song: yandex_music/track/58167392:8798848@8798848 Feb 15 20:33:11 volumio volumio[1047]: info: Preloading song: yandex_music/track/58167396:8798848@8798848 Feb 15 20:33:11 volumio volumio[1047]: info: Preloading song: yandex_music/track/58167401:8798848@8798848 Feb 15 20:33:11 volumio volumio[1047]: info: Preloading song: yandex_music/track/58167403:8798848@8798848 Feb 15 20:33:11 volumio volumio[1047]: info: Preloading song: yandex_music/track/58167405:8798848@8798848 Feb 15 20:33:11 volumio volumio[1047]: info: Preloading song: yandex_music/track/58167408:8798848@8798848 Feb 15 20:33:11 volumio volumio[1047]: info: Preloading song: yandex_music/track/58167410:8798848@8798848 Feb 15 20:33:11 volumio volumio[1047]: info: Preloading song: yandex_music/track/58167413:8798848@8798848 Feb 15 20:33:11 volumio volumio[1047]: info: Preloading song: yandex_music/track/58167416:8798848@8798848 Feb 15 20:33:11 volumio volumio[1047]: info: Preloading song: yandex_music/track/58167421:8798848@8798848 Feb 15 20:33:11 volumio volumio[1047]: info: Preloading song: yandex_music/track/58167422:8798848@8798848 Feb 15 20:33:11 volumio volumio[1047]: info: Preloading song: yandex_music/track/58167426:8798848@8798848 Feb 15 20:33:11 volumio volumio[1047]: info: Preloading song: yandex_music/track/58167427:8798848@8798848 Feb 15 20:33:11 volumio volumio[1047]: info: Exploding uri yandex_music/track/58167323:8798848@8798848 in service yandex_music Feb 15 20:33:11 volumio volumio[1047]: info: Exploding uri yandex_music/track/58167331:8798848@8798848 in service yandex_music Feb 15 20:33:11 volumio volumio[1047]: info: Exploding uri yandex_music/track/58167342:8798848@8798848 in service yandex_music Feb 15 20:33:11 volumio volumio[1047]: info: Exploding uri yandex_music/track/58167353:8798848@8798848 in service yandex_music Feb 15 20:33:11 volumio volumio[1047]: info: Exploding uri yandex_music/track/58167357:8798848@8798848 in service yandex_music Feb 15 20:33:11 volumio volumio[1047]: info: Exploding uri yandex_music/track/58167362:8798848@8798848 in service yandex_music Feb 15 20:33:11 volumio volumio[1047]: info: Exploding uri yandex_music/track/58167366:8798848@8798848 in service yandex_music Feb 15 20:33:11 volumio volumio[1047]: info: Exploding uri yandex_music/track/58167378:8798848@8798848 in service yandex_music Feb 15 20:33:11 volumio volumio[1047]: info: Exploding uri yandex_music/track/58167381:8798848@8798848 in service yandex_music Feb 15 20:33:11 volumio volumio[1047]: info: Exploding uri yandex_music/track/58167387:8798848@8798848 in service yandex_music Feb 15 20:33:11 volumio volumio[1047]: info: Exploding uri yandex_music/track/58167392:8798848@8798848 in service yandex_music Feb 15 20:33:11 volumio volumio[1047]: info: Exploding uri yandex_music/track/58167396:8798848@8798848 in service yandex_music Feb 15 20:33:11 volumio volumio[1047]: info: Exploding uri yandex_music/track/58167401:8798848@8798848 in service yandex_music Feb 15 20:33:11 volumio volumio[1047]: info: Exploding uri yandex_music/track/58167403:8798848@8798848 in service yandex_music Feb 15 20:33:11 volumio volumio[1047]: info: Exploding uri yandex_music/track/58167405:8798848@8798848 in service yandex_music Feb 15 20:33:11 volumio volumio[1047]: info: Exploding uri yandex_music/track/58167408:8798848@8798848 in service yandex_music Feb 15 20:33:12 volumio volumio[1047]: info: Exploding uri yandex_music/track/58167410:8798848@8798848 in service yandex_music Feb 15 20:33:12 volumio volumio[1047]: info: Exploding uri yandex_music/track/58167413:8798848@8798848 in service yandex_music Feb 15 20:33:12 volumio volumio[1047]: info: Exploding uri yandex_music/track/58167416:8798848@8798848 in service yandex_music Feb 15 20:33:12 volumio volumio[1047]: info: Exploding uri yandex_music/track/58167421:8798848@8798848 in service yandex_music Feb 15 20:33:12 volumio volumio[1047]: info: Exploding uri yandex_music/track/58167422:8798848@8798848 in service yandex_music Feb 15 20:33:12 volumio volumio[1047]: info: Preload queue cleared Feb 15 20:33:12 volumio volumio[1047]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 15 20:33:12 volumio volumio[1047]: info: CoreStateMachine::ClearQueue Feb 15 20:33:12 volumio volumio[1047]: info: CoreStateMachine::stop Feb 15 20:33:12 volumio volumio[1047]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 15 20:33:12 volumio volumio[1047]: info: CoreStateMachine::stPlaybackTimer Feb 15 20:33:12 volumio volumio[1047]: info: CoreStateMachine::updateTrackBlock Feb 15 20:33:12 volumio volumio[1047]: info: CorePlayQueue::getTrackBlock Feb 15 20:33:12 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:33:12 volumio volumio[1047]: info: CorePlayQueue::getTrack 3 Feb 15 20:33:12 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 20:33:12 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:33:12 volumio volumio[1047]: info: CoreStateMachine::serviceStop Feb 15 20:33:12 volumio volumio[1047]: info: CorePlayQueue::getTrack 3 Feb 15 20:33:12 volumio volumio[1047]: info: CoreCommandRouter::serviceStop Feb 15 20:33:12 volumio volumio[1047]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 15 20:33:12 volumio volumio[1047]: info: ControllerMpd::stop Feb 15 20:33:12 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand stop Feb 15 20:33:12 volumio volumio[1047]: info: CorePlayQueue::clearPlayQueue Feb 15 20:33:12 volumio volumio[1047]: info: CorePlayQueue::saveQueue Feb 15 20:33:12 volumio volumio[1047]: info: CoreCommandRouter::volumioPushQueue Feb 15 20:33:12 volumio volumio[1047]: info: CoreStateMachine::addQueueItems Feb 15 20:33:12 volumio volumio[1047]: info: CorePlayQueue::addQueueItems Feb 15 20:33:12 volumio volumio[1047]: info: Preload queue cleared Feb 15 20:33:12 volumio volumio[1047]: info: Adding Item to queue: yandex_music/track/58167323:8798848@8798848 Feb 15 20:33:12 volumio volumio[1047]: info: Using cached record of: yandex_music/track/58167323:8798848@8798848 Feb 15 20:33:12 volumio volumio[1047]: info: CoreCommandRouter::volumioPushQueue Feb 15 20:33:12 volumio volumio[1047]: info: CorePlayQueue::saveQueue Feb 15 20:33:12 volumio volumio[1047]: info: CoreStateMachine::updateTrackBlock Feb 15 20:33:12 volumio volumio[1047]: info: CorePlayQueue::getTrackBlock Feb 15 20:33:12 volumio volumio[1047]: info: CoreCommandRouter::volumioPlay Feb 15 20:33:12 volumio volumio[1047]: info: CoreStateMachine::play index 0 Feb 15 20:33:12 volumio volumio[1047]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 15 20:33:12 volumio volumio[1047]: info: CoreStateMachine::addQueueItems Feb 15 20:33:12 volumio volumio[1047]: info: CorePlayQueue::addQueueItems Feb 15 20:33:12 volumio volumio[1047]: info: Preload queue cleared Feb 15 20:33:12 volumio volumio[1047]: info: Adding Item to queue: yandex_music/track/58167331:8798848@8798848 Feb 15 20:33:12 volumio volumio[1047]: info: Using cached record of: yandex_music/track/58167331:8798848@8798848 Feb 15 20:33:12 volumio volumio[1047]: info: Adding Item to queue: yandex_music/track/58167342:8798848@8798848 Feb 15 20:33:12 volumio volumio[1047]: info: Using cached record of: yandex_music/track/58167342:8798848@8798848 Feb 15 20:33:12 volumio volumio[1047]: info: Adding Item to queue: yandex_music/track/58167353:8798848@8798848 Feb 15 20:33:12 volumio volumio[1047]: info: Using cached record of: yandex_music/track/58167353:8798848@8798848 Feb 15 20:33:12 volumio volumio[1047]: info: Adding Item to queue: yandex_music/track/58167357:8798848@8798848 Feb 15 20:33:12 volumio volumio[1047]: info: Using cached record of: yandex_music/track/58167357:8798848@8798848 Feb 15 20:33:12 volumio volumio[1047]: info: Adding Item to queue: yandex_music/track/58167362:8798848@8798848 Feb 15 20:33:12 volumio volumio[1047]: info: Using cached record of: yandex_music/track/58167362:8798848@8798848 Feb 15 20:33:12 volumio volumio[1047]: info: Adding Item to queue: yandex_music/track/58167366:8798848@8798848 Feb 15 20:33:12 volumio volumio[1047]: info: Using cached record of: yandex_music/track/58167366:8798848@8798848 Feb 15 20:33:12 volumio volumio[1047]: info: Adding Item to queue: yandex_music/track/58167378:8798848@8798848 Feb 15 20:33:12 volumio volumio[1047]: info: Using cached record of: yandex_music/track/58167378:8798848@8798848 Feb 15 20:33:12 volumio volumio[1047]: info: Adding Item to queue: yandex_music/track/58167381:8798848@8798848 Feb 15 20:33:12 volumio volumio[1047]: info: Using cached record of: yandex_music/track/58167381:8798848@8798848 Feb 15 20:33:12 volumio volumio[1047]: info: Adding Item to queue: yandex_music/track/58167387:8798848@8798848 Feb 15 20:33:12 volumio volumio[1047]: info: Using cached record of: yandex_music/track/58167387:8798848@8798848 Feb 15 20:33:12 volumio volumio[1047]: info: Adding Item to queue: yandex_music/track/58167392:8798848@8798848 Feb 15 20:33:12 volumio volumio[1047]: info: Using cached record of: yandex_music/track/58167392:8798848@8798848 Feb 15 20:33:12 volumio volumio[1047]: info: Adding Item to queue: yandex_music/track/58167396:8798848@8798848 Feb 15 20:33:12 volumio volumio[1047]: info: Using cached record of: yandex_music/track/58167396:8798848@8798848 Feb 15 20:33:12 volumio volumio[1047]: info: Adding Item to queue: yandex_music/track/58167401:8798848@8798848 Feb 15 20:33:12 volumio volumio[1047]: info: Using cached record of: yandex_music/track/58167401:8798848@8798848 Feb 15 20:33:12 volumio volumio[1047]: info: Adding Item to queue: yandex_music/track/58167403:8798848@8798848 Feb 15 20:33:12 volumio volumio[1047]: info: Using cached record of: yandex_music/track/58167403:8798848@8798848 Feb 15 20:33:12 volumio volumio[1047]: info: Adding Item to queue: yandex_music/track/58167405:8798848@8798848 Feb 15 20:33:12 volumio volumio[1047]: info: Using cached record of: yandex_music/track/58167405:8798848@8798848 Feb 15 20:33:12 volumio volumio[1047]: info: Adding Item to queue: yandex_music/track/58167408:8798848@8798848 Feb 15 20:33:12 volumio volumio[1047]: info: Using cached record of: yandex_music/track/58167408:8798848@8798848 Feb 15 20:33:12 volumio volumio[1047]: info: Adding Item to queue: yandex_music/track/58167410:8798848@8798848 Feb 15 20:33:12 volumio volumio[1047]: info: Using cached record of: yandex_music/track/58167410:8798848@8798848 Feb 15 20:33:12 volumio volumio[1047]: info: Adding Item to queue: yandex_music/track/58167413:8798848@8798848 Feb 15 20:33:12 volumio volumio[1047]: info: Using cached record of: yandex_music/track/58167413:8798848@8798848 Feb 15 20:33:12 volumio volumio[1047]: info: Adding Item to queue: yandex_music/track/58167416:8798848@8798848 Feb 15 20:33:12 volumio volumio[1047]: info: Using cached record of: yandex_music/track/58167416:8798848@8798848 Feb 15 20:33:12 volumio volumio[1047]: info: Adding Item to queue: yandex_music/track/58167421:8798848@8798848 Feb 15 20:33:12 volumio volumio[1047]: info: Using cached record of: yandex_music/track/58167421:8798848@8798848 Feb 15 20:33:12 volumio volumio[1047]: info: Adding Item to queue: yandex_music/track/58167422:8798848@8798848 Feb 15 20:33:12 volumio volumio[1047]: info: Using cached record of: yandex_music/track/58167422:8798848@8798848 Feb 15 20:33:12 volumio volumio[1047]: info: Adding Item to queue: yandex_music/track/58167426:8798848@8798848 Feb 15 20:33:12 volumio volumio[1047]: info: Exploding uri yandex_music/track/58167426:8798848@8798848 in service yandex_music Feb 15 20:33:12 volumio volumio[1047]: info: Adding Item to queue: yandex_music/track/58167427:8798848@8798848 Feb 15 20:33:12 volumio volumio[1047]: info: Exploding uri yandex_music/track/58167427:8798848@8798848 in service yandex_music Feb 15 20:33:12 volumio volumio[1047]: info: CoreStateMachine::stop Feb 15 20:33:12 volumio volumio[1047]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 15 20:33:12 volumio volumio[1047]: info: CoreStateMachine::play index undefined Feb 15 20:33:12 volumio volumio[1047]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 15 20:33:12 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 15 20:33:12 volumio volumio[1047]: info: CoreStateMachine::startPlaybackTimer Feb 15 20:33:12 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 15 20:33:12 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand stop Feb 15 20:33:12 volumio volumio[1047]: info: CoreCommandRouter::volumioPushQueue Feb 15 20:33:12 volumio volumio[1047]: info: CorePlayQueue::saveQueue Feb 15 20:33:12 volumio volumio[1047]: info: CoreStateMachine::updateTrackBlock Feb 15 20:33:12 volumio volumio[1047]: info: CorePlayQueue::getTrackBlock Feb 15 20:33:12 volumio volumio[1047]: info: Feb 15 20:33:12 volumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 15 20:33:12 volumio volumio[1047]: info: sendMpdCommand stop took 21 milliseconds Feb 15 20:33:12 volumio volumio[1047]: info: ControllerMpd::getState Feb 15 20:33:12 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:33:12 volumio volumio[1047]: info: sendMpdCommand stop took 12 milliseconds Feb 15 20:33:12 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand clear Feb 15 20:33:12 volumio volumio[1047]: info: Feb 15 20:33:12 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:33:12 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:33:12 volumio volumio[1047]: info: sendMpdCommand status took 2 milliseconds Feb 15 20:33:12 volumio volumio[1047]: info: sendMpdCommand clear took 2 milliseconds Feb 15 20:33:12 volumio volumio[1047]: verbose: ControllerMpd::parseState Feb 15 20:33:12 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 20:33:12 volumio volumio[1047]: info: Feb 15 20:33:12 volumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 15 20:33:12 volumio volumio[1047]: info: ControllerMpd::getState Feb 15 20:33:12 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:33:12 volumio volumio[1047]: info: Feb 15 20:33:12 volumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 15 20:33:12 volumio volumio[1047]: info: ControllerMpd::getState Feb 15 20:33:12 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:33:12 volumio volumio[1047]: error: updateQueue error: null Feb 15 20:33:12 volumio volumio[1047]: info: ------------------------------ 6ms Feb 15 20:33:12 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 15 20:33:12 volumio volumio[1047]: info: sendMpdCommand status took 1 milliseconds Feb 15 20:33:12 volumio volumio[1047]: info: sendMpdCommand status took 1 milliseconds Feb 15 20:33:12 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Feb 15 20:33:12 volumio volumio[1047]: verbose: ControllerMpd::parseState Feb 15 20:33:12 volumio volumio[1047]: verbose: ControllerMpd::parseState Feb 15 20:33:12 volumio volumio[1047]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 15 20:33:12 volumio volumio[1047]: info: ControllerMpd::pushState Feb 15 20:33:12 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 15 20:33:12 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:33:12 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 15 20:33:12 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 20:33:12 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:33:12 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 15 20:33:12 volumio volumio[1047]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd Feb 15 20:33:12 volumio volumio[1047]: info: ControllerMpd::pushState Feb 15 20:33:12 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 15 20:33:12 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:33:12 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 15 20:33:12 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:33:12 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 15 20:33:12 volumio volumio[1047]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd Feb 15 20:33:12 volumio volumio[1047]: info: ------------------------------ 12ms Feb 15 20:33:12 volumio volumio[1047]: info: ------------------------------ 7ms Feb 15 20:33:12 volumio volumio[1047]: info: ------------------------------ 6ms Feb 15 20:33:12 volumio volumio[1047]: info: Feb 15 20:33:12 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:33:12 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:33:12 volumio volumio[1047]: info: Feb 15 20:33:12 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:33:12 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:33:12 volumio volumio[1047]: error: updateQueue error: null Feb 15 20:33:12 volumio volumio[1047]: error: updateQueue error: null Feb 15 20:33:12 volumio volumio[1047]: info: ------------------------------ 2ms Feb 15 20:33:12 volumio volumio[1047]: info: ------------------------------ 1ms Feb 15 20:33:12 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2F1525.cold-rtc.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Da852d46639e5eac88de08263874b67f21a4e642fb206892a6f4934c7488962e4%2Clid%3D1525%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67bec25a%2F0%2F1757602%2F0b2f8050.86411602.6.58167323%2F320.mp3&ext=.mp3" Feb 15 20:33:12 volumio volumio[1047]: info: Feb 15 20:33:12 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:33:12 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:33:12 volumio volumio[1047]: info: sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2F1525.cold-rtc.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Da852d46639e5eac88de08263874b67f21a4e642fb206892a6f4934c7488962e4%2Clid%3D1525%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67bec25a%2F0%2F1757602%2F0b2f8050.86411602.6.58167323%2F320.mp3&ext=.mp3" took 1 milliseconds Feb 15 20:33:12 volumio volumio[1047]: verbose: MPD COMMAND [object Object] Feb 15 20:33:12 volumio volumio[1047]: verbose: MPD COMMAND [object Object] Feb 15 20:33:12 volumio volumio[1047]: info: Feb 15 20:33:12 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:33:12 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:33:12 volumio volumio[1047]: info: Feb 15 20:33:12 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:33:12 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:33:12 volumio volumio[1047]: info: Feb 15 20:33:12 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:33:12 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:33:12 volumio volumio[1047]: info: ------------------------------ 2ms Feb 15 20:33:12 volumio volumio[1047]: info: ------------------------------ 1ms Feb 15 20:33:12 volumio volumio[1047]: info: ------------------------------ 1ms Feb 15 20:33:12 volumio volumio[1047]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 15 20:33:12 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand play Feb 15 20:33:12 volumio volumio[1047]: info: Feb 15 20:33:12 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:33:12 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:33:12 volumio volumio[1047]: info: Feb 15 20:33:12 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:33:12 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:33:12 volumio volumio[1047]: info: ------------------------------ 2ms Feb 15 20:33:12 volumio volumio[1047]: info: sendMpdCommand play took 2 milliseconds Feb 15 20:33:12 volumio volumio[1047]: info: ------------------------------ 2ms Feb 15 20:33:12 volumio volumio[1047]: info: ------------------------------ 1ms Feb 15 20:33:13 volumio volumio[1047]: info: Feb 15 20:33:13 volumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 15 20:33:13 volumio volumio[1047]: info: ControllerMpd::getState Feb 15 20:33:13 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:33:13 volumio volumio[1047]: info: Feb 15 20:33:13 volumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 15 20:33:13 volumio volumio[1047]: info: ControllerMpd::getState Feb 15 20:33:13 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:33:13 volumio volumio[1047]: info: Feb 15 20:33:13 volumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 15 20:33:13 volumio volumio[1047]: info: ControllerMpd::getState Feb 15 20:33:13 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:33:13 volumio volumio[1047]: info: sendMpdCommand status took 2 milliseconds Feb 15 20:33:13 volumio volumio[1047]: verbose: ControllerMpd::parseState Feb 15 20:33:13 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 20:33:13 volumio volumio[1047]: info: Feb 15 20:33:13 volumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 15 20:33:13 volumio volumio[1047]: info: ControllerMpd::getState Feb 15 20:33:13 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:33:13 volumio volumio[1047]: info: Feb 15 20:33:13 volumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 15 20:33:13 volumio volumio[1047]: info: ControllerMpd::getState Feb 15 20:33:13 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:33:13 volumio volumio[1047]: info: Feb 15 20:33:13 volumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 15 20:33:13 volumio volumio[1047]: info: ControllerMpd::getState Feb 15 20:33:13 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:33:13 volumio volumio[1047]: info: sendMpdCommand status took 5 milliseconds Feb 15 20:33:13 volumio volumio[1047]: info: sendMpdCommand status took 5 milliseconds Feb 15 20:33:13 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 15 20:33:13 volumio volumio[1047]: info: sendMpdCommand status took 2 milliseconds Feb 15 20:33:13 volumio volumio[1047]: verbose: ControllerMpd::parseState Feb 15 20:33:13 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 20:33:13 volumio volumio[1047]: verbose: ControllerMpd::parseState Feb 15 20:33:13 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 20:33:13 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Feb 15 20:33:13 volumio volumio[1047]: verbose: ControllerMpd::parseState Feb 15 20:33:13 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 20:33:13 volumio volumio[1047]: info: ControllerMpd::pushState Feb 15 20:33:13 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 15 20:33:13 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 15 20:33:13 volumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":203,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Symphony in F Major, Eisen F6: I. Allegro","artist":"Slowakisches Kammerorchester","album":"Mozart: 7 Symphonies","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2F1525.cold-rtc.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Da852d46639e5eac88de08263874b67f21a4e642fb206892a6f4934c7488962e4%2Clid%3D1525%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67bec25a%2F0%2F1757602%2F0b2f8050.86411602.6.58167323%2F320.mp3&ext=.mp3","trackType":"mp3"} Feb 15 20:33:13 volumio volumio[1047]: verbose: CURRENT POSITION 0 Feb 15 20:33:13 volumio volumio[1047]: info: CoreStateMachine::syncState stateService play Feb 15 20:33:13 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus stop Feb 15 20:33:13 volumio volumio[1047]: info: ------------------------------ 8ms Feb 15 20:33:13 volumio volumio[1047]: info: sendMpdCommand status took 5 milliseconds Feb 15 20:33:13 volumio volumio[1047]: info: sendMpdCommand status took 3 milliseconds Feb 15 20:33:13 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 15 20:33:13 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 15 20:33:13 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 15 20:33:13 volumio volumio[1047]: verbose: ControllerMpd::parseState Feb 15 20:33:13 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 20:33:13 volumio volumio[1047]: verbose: ControllerMpd::parseState Feb 15 20:33:13 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 20:33:13 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Feb 15 20:33:13 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Feb 15 20:33:13 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Feb 15 20:33:13 volumio volumio[1047]: info: ControllerMpd::pushState Feb 15 20:33:13 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 15 20:33:13 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 15 20:33:13 volumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":203,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Symphony in F Major, Eisen F6: I. Allegro","artist":"Slowakisches Kammerorchester","album":"Mozart: 7 Symphonies","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2F1525.cold-rtc.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Da852d46639e5eac88de08263874b67f21a4e642fb206892a6f4934c7488962e4%2Clid%3D1525%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67bec25a%2F0%2F1757602%2F0b2f8050.86411602.6.58167323%2F320.mp3&ext=.mp3","trackType":"mp3"} Feb 15 20:33:13 volumio volumio[1047]: verbose: CURRENT POSITION 0 Feb 15 20:33:13 volumio volumio[1047]: info: CoreStateMachine::syncState stateService play Feb 15 20:33:13 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus play Feb 15 20:33:13 volumio volumio[1047]: info: Received an update from plugin. extracting info from payload Feb 15 20:33:13 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:33:13 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 20:33:13 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:33:13 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:33:13 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:33:13 volumio volumio[1047]: info: ControllerMpd::pushState Feb 15 20:33:13 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 15 20:33:13 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 15 20:33:13 volumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":203,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Symphony in F Major, Eisen F6: I. Allegro","artist":"Slowakisches Kammerorchester","album":"Mozart: 7 Symphonies","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2F1525.cold-rtc.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Da852d46639e5eac88de08263874b67f21a4e642fb206892a6f4934c7488962e4%2Clid%3D1525%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67bec25a%2F0%2F1757602%2F0b2f8050.86411602.6.58167323%2F320.mp3&ext=.mp3","trackType":"mp3"} Feb 15 20:33:13 volumio volumio[1047]: verbose: CURRENT POSITION 0 Feb 15 20:33:13 volumio volumio[1047]: info: CoreStateMachine::syncState stateService play Feb 15 20:33:13 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus play Feb 15 20:33:13 volumio volumio[1047]: info: Received an update from plugin. extracting info from payload Feb 15 20:33:13 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:33:13 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:33:13 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:33:13 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:33:13 volumio volumio[1047]: info: ControllerMpd::pushState Feb 15 20:33:13 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 15 20:33:13 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 15 20:33:13 volumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":203,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Symphony in F Major, Eisen F6: I. Allegro","artist":"Slowakisches Kammerorchester","album":"Mozart: 7 Symphonies","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2F1525.cold-rtc.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Da852d46639e5eac88de08263874b67f21a4e642fb206892a6f4934c7488962e4%2Clid%3D1525%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67bec25a%2F0%2F1757602%2F0b2f8050.86411602.6.58167323%2F320.mp3&ext=.mp3","trackType":"mp3"} Feb 15 20:33:13 volumio volumio[1047]: verbose: CURRENT POSITION 0 Feb 15 20:33:13 volumio volumio[1047]: info: CoreStateMachine::syncState stateService play Feb 15 20:33:13 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus play Feb 15 20:33:13 volumio volumio[1047]: info: Received an update from plugin. extracting info from payload Feb 15 20:33:13 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:33:13 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:33:13 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:33:13 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:33:13 volumio volumio[1047]: info: ------------------------------ 22ms Feb 15 20:33:13 volumio volumio[1047]: info: ------------------------------ 22ms Feb 15 20:33:13 volumio volumio[1047]: info: ------------------------------ 20ms Feb 15 20:33:13 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 14 milliseconds Feb 15 20:33:13 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 14 milliseconds Feb 15 20:33:13 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Feb 15 20:33:13 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Feb 15 20:33:13 volumio volumio[1047]: info: ControllerMpd::pushState Feb 15 20:33:13 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 15 20:33:13 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 15 20:33:13 volumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":883,"duration":203,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Symphony in F Major, Eisen F6: I. Allegro","artist":"Slowakisches Kammerorchester","album":"Mozart: 7 Symphonies","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2F1525.cold-rtc.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Da852d46639e5eac88de08263874b67f21a4e642fb206892a6f4934c7488962e4%2Clid%3D1525%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67bec25a%2F0%2F1757602%2F0b2f8050.86411602.6.58167323%2F320.mp3&ext=.mp3","trackType":"mp3"} Feb 15 20:33:13 volumio volumio[1047]: verbose: CURRENT POSITION 0 Feb 15 20:33:13 volumio volumio[1047]: info: CoreStateMachine::syncState stateService play Feb 15 20:33:13 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus play Feb 15 20:33:13 volumio volumio[1047]: info: Received an update from plugin. extracting info from payload Feb 15 20:33:13 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:33:13 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 20:33:13 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:33:13 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:33:13 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:33:13 volumio volumio[1047]: info: ControllerMpd::pushState Feb 15 20:33:13 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 15 20:33:13 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 15 20:33:13 volumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":883,"duration":203,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Symphony in F Major, Eisen F6: I. Allegro","artist":"Slowakisches Kammerorchester","album":"Mozart: 7 Symphonies","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2F1525.cold-rtc.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Da852d46639e5eac88de08263874b67f21a4e642fb206892a6f4934c7488962e4%2Clid%3D1525%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67bec25a%2F0%2F1757602%2F0b2f8050.86411602.6.58167323%2F320.mp3&ext=.mp3","trackType":"mp3"} Feb 15 20:33:13 volumio volumio[1047]: verbose: CURRENT POSITION 0 Feb 15 20:33:13 volumio volumio[1047]: info: CoreStateMachine::syncState stateService play Feb 15 20:33:13 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus play Feb 15 20:33:13 volumio volumio[1047]: info: Received an update from plugin. extracting info from payload Feb 15 20:33:13 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:33:13 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:33:13 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:33:13 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:33:13 volumio volumio[1047]: info: ------------------------------ 27ms Feb 15 20:33:13 volumio volumio[1047]: info: ------------------------------ 26ms Feb 15 20:33:13 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:33:13 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:33:13 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:33:13 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:33:13 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:33:13 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:33:13 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:33:13 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:33:13 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:33:13 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:33:13 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:33:13 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:33:13 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:33:13 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:33:13 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:33:13 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:33:13 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:33:13 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:33:13 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:33:13 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:33:13 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:33:13 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:33:13 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:33:13 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:33:13 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:33:13 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:33:13 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:33:13 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:33:13 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:33:13 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:33:42 volumio volumio[1047]: info: CoreCommandRouter::volumioNext Feb 15 20:33:42 volumio volumio[1047]: info: CoreStateMachine::next Feb 15 20:33:42 volumio volumio[1047]: info: ControllerMpd::next Feb 15 20:33:42 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand next Feb 15 20:33:42 volumio volumio[1047]: info: sendMpdCommand next took 21 milliseconds Feb 15 20:33:42 volumio volumio[1047]: info: Feb 15 20:33:42 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:33:42 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:33:42 volumio volumio[1047]: info: Feb 15 20:33:42 volumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 15 20:33:42 volumio volumio[1047]: info: ControllerMpd::getState Feb 15 20:33:42 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:33:42 volumio volumio[1047]: info: Feb 15 20:33:42 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:33:42 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:33:42 volumio volumio[1047]: info: Feb 15 20:33:42 volumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 15 20:33:42 volumio volumio[1047]: info: ControllerMpd::getState Feb 15 20:33:42 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:33:42 volumio volumio[1047]: info: Feb 15 20:33:42 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:33:42 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:33:42 volumio volumio[1047]: info: Feb 15 20:33:42 volumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 15 20:33:42 volumio volumio[1047]: info: ControllerMpd::getState Feb 15 20:33:42 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:33:42 volumio volumio[1047]: error: updateQueue error: null Feb 15 20:33:42 volumio volumio[1047]: error: updateQueue error: null Feb 15 20:33:42 volumio volumio[1047]: error: updateQueue error: null Feb 15 20:33:42 volumio volumio[1047]: info: ------------------------------ 3ms Feb 15 20:33:42 volumio volumio[1047]: info: sendMpdCommand status took 3 milliseconds Feb 15 20:33:42 volumio volumio[1047]: info: ------------------------------ 2ms Feb 15 20:33:42 volumio volumio[1047]: info: sendMpdCommand status took 2 milliseconds Feb 15 20:33:42 volumio volumio[1047]: info: ------------------------------ 2ms Feb 15 20:33:42 volumio volumio[1047]: info: sendMpdCommand status took 1 milliseconds Feb 15 20:33:42 volumio volumio[1047]: verbose: ControllerMpd::parseState Feb 15 20:33:42 volumio volumio[1047]: verbose: ControllerMpd::parseState Feb 15 20:33:42 volumio volumio[1047]: verbose: ControllerMpd::parseState Feb 15 20:33:42 volumio volumio[1047]: info: ControllerMpd::pushState Feb 15 20:33:42 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 15 20:33:42 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:33:42 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 20:33:42 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:33:42 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Feb 15 20:33:42 volumio volumio[1047]: 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} Feb 15 20:33:42 volumio volumio[1047]: verbose: CURRENT POSITION 0 Feb 15 20:33:42 volumio volumio[1047]: info: CoreStateMachine::syncState stateService stop Feb 15 20:33:42 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus play Feb 15 20:33:42 volumio volumio[1047]: info: CoreStateMachine::play index undefined Feb 15 20:33:42 volumio volumio[1047]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 15 20:33:42 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:33:42 volumio volumio[1047]: info: CorePlayQueue::getTrack 1 Feb 15 20:33:42 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 20:33:42 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:33:42 volumio volumio[1047]: info: ControllerMpd::pushState Feb 15 20:33:42 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 15 20:33:42 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:33:42 volumio volumio[1047]: info: CorePlayQueue::getTrack 1 Feb 15 20:33:42 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:33:42 volumio volumio[1047]: info: CorePlayQueue::getTrack 1 Feb 15 20:33:42 volumio volumio[1047]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd Feb 15 20:33:42 volumio volumio[1047]: info: ControllerMpd::pushState Feb 15 20:33:42 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 15 20:33:42 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:33:42 volumio volumio[1047]: info: CorePlayQueue::getTrack 1 Feb 15 20:33:42 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:33:42 volumio volumio[1047]: info: CorePlayQueue::getTrack 1 Feb 15 20:33:42 volumio volumio[1047]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd Feb 15 20:33:42 volumio volumio[1047]: info: CorePlayQueue::getTrack 1 Feb 15 20:33:42 volumio volumio[1047]: info: CoreStateMachine::startPlaybackTimer Feb 15 20:33:42 volumio volumio[1047]: info: CorePlayQueue::getTrack 1 Feb 15 20:33:42 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand stop Feb 15 20:33:42 volumio volumio[1047]: info: ------------------------------ 11ms Feb 15 20:33:42 volumio volumio[1047]: info: ------------------------------ 11ms Feb 15 20:33:42 volumio volumio[1047]: info: ------------------------------ 10ms Feb 15 20:33:42 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:33:42 volumio volumio[1047]: info: CorePlayQueue::getTrack 1 Feb 15 20:33:42 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 20:33:42 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:33:42 volumio volumio[1047]: info: sendMpdCommand stop took 4 milliseconds Feb 15 20:33:42 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand clear Feb 15 20:33:42 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:33:42 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:33:42 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:33:42 volumio volumio[1047]: info: Feb 15 20:33:42 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:33:42 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:33:42 volumio volumio[1047]: info: sendMpdCommand clear took 8 milliseconds Feb 15 20:33:42 volumio volumio[1047]: info: Feb 15 20:33:42 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:33:42 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:33:42 volumio volumio[1047]: info: Feb 15 20:33:42 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:33:42 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:33:42 volumio volumio[1047]: error: updateQueue error: null Feb 15 20:33:42 volumio volumio[1047]: error: updateQueue error: null Feb 15 20:33:42 volumio volumio[1047]: error: updateQueue error: null Feb 15 20:33:42 volumio volumio[1047]: info: ------------------------------ 11ms Feb 15 20:33:42 volumio volumio[1047]: info: ------------------------------ 5ms Feb 15 20:33:42 volumio volumio[1047]: info: ------------------------------ 4ms Feb 15 20:33:43 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2F1556.cold-rtc.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3De33dc88afa310cd92ad039b1be7638ab05c32d481038037631e239a88377c909%2Clid%3D1556%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67be03f4%2F0%2F1747268%2F82842efb.86411611.7.58167331%2F320.mp3&ext=.mp3" Feb 15 20:33:43 volumio volumio[1047]: info: Feb 15 20:33:43 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:33:43 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:33:43 volumio volumio[1047]: info: sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2F1556.cold-rtc.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3De33dc88afa310cd92ad039b1be7638ab05c32d481038037631e239a88377c909%2Clid%3D1556%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67be03f4%2F0%2F1747268%2F82842efb.86411611.7.58167331%2F320.mp3&ext=.mp3" took 1 milliseconds Feb 15 20:33:43 volumio volumio[1047]: verbose: MPD COMMAND [object Object] Feb 15 20:33:43 volumio volumio[1047]: verbose: MPD COMMAND [object Object] Feb 15 20:33:43 volumio volumio[1047]: info: Feb 15 20:33:43 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:33:43 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:33:43 volumio volumio[1047]: info: Feb 15 20:33:43 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:33:43 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:33:43 volumio volumio[1047]: info: Feb 15 20:33:43 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:33:43 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:33:43 volumio volumio[1047]: info: ------------------------------ 3ms Feb 15 20:33:43 volumio volumio[1047]: info: ------------------------------ 2ms Feb 15 20:33:43 volumio volumio[1047]: info: ------------------------------ 1ms Feb 15 20:33:43 volumio volumio[1047]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 15 20:33:43 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand play Feb 15 20:33:43 volumio volumio[1047]: info: Feb 15 20:33:43 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:33:43 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:33:43 volumio volumio[1047]: info: Feb 15 20:33:43 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Feb 15 20:33:43 volumio volumio[1047]: info: Ignoring MPD Status Update Feb 15 20:33:43 volumio volumio[1047]: info: ------------------------------ 3ms Feb 15 20:33:43 volumio volumio[1047]: info: sendMpdCommand play took 2 milliseconds Feb 15 20:33:43 volumio volumio[1047]: info: ------------------------------ 2ms Feb 15 20:33:43 volumio volumio[1047]: info: ------------------------------ 1ms Feb 15 20:33:44 volumio volumio[1047]: info: Feb 15 20:33:44 volumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 15 20:33:44 volumio volumio[1047]: info: ControllerMpd::getState Feb 15 20:33:44 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:33:44 volumio volumio[1047]: info: Feb 15 20:33:44 volumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 15 20:33:44 volumio volumio[1047]: info: ControllerMpd::getState Feb 15 20:33:44 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:33:44 volumio volumio[1047]: info: Feb 15 20:33:44 volumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 15 20:33:44 volumio volumio[1047]: info: ControllerMpd::getState Feb 15 20:33:44 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:33:44 volumio volumio[1047]: info: Feb 15 20:33:44 volumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 15 20:33:44 volumio volumio[1047]: info: sendMpdCommand status took 3 milliseconds Feb 15 20:33:44 volumio volumio[1047]: info: ControllerMpd::getState Feb 15 20:33:44 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:33:44 volumio volumio[1047]: info: sendMpdCommand status took 2 milliseconds Feb 15 20:33:44 volumio volumio[1047]: info: sendMpdCommand status took 1 milliseconds Feb 15 20:33:44 volumio volumio[1047]: verbose: ControllerMpd::parseState Feb 15 20:33:44 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 20:33:44 volumio volumio[1047]: verbose: ControllerMpd::parseState Feb 15 20:33:44 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 20:33:44 volumio volumio[1047]: verbose: ControllerMpd::parseState Feb 15 20:33:44 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 20:33:44 volumio volumio[1047]: info: Feb 15 20:33:44 volumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 15 20:33:44 volumio volumio[1047]: info: ControllerMpd::getState Feb 15 20:33:44 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:33:44 volumio volumio[1047]: info: Feb 15 20:33:44 volumio volumio[1047]: ---------------------------- MPD announces state update: player Feb 15 20:33:44 volumio volumio[1047]: info: ControllerMpd::getState Feb 15 20:33:44 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:33:44 volumio volumio[1047]: info: sendMpdCommand status took 6 milliseconds Feb 15 20:33:44 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 15 20:33:44 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 15 20:33:44 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 15 20:33:44 volumio volumio[1047]: info: sendMpdCommand status took 5 milliseconds Feb 15 20:33:44 volumio volumio[1047]: verbose: ControllerMpd::parseState Feb 15 20:33:44 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 20:33:44 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Feb 15 20:33:44 volumio volumio[1047]: verbose: ControllerMpd::parseState Feb 15 20:33:44 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 20:33:44 volumio volumio[1047]: verbose: ControllerMpd::parseState Feb 15 20:33:44 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 20:33:44 volumio volumio[1047]: info: ControllerMpd::pushState Feb 15 20:33:44 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 15 20:33:44 volumio volumio[1047]: info: CorePlayQueue::getTrack 1 Feb 15 20:33:44 volumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":184,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Symphony in F Major, Eisen F6: II. Andante","artist":"Slowakisches Kammerorchester","album":"Mozart: 7 Symphonies","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2F1556.cold-rtc.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3De33dc88afa310cd92ad039b1be7638ab05c32d481038037631e239a88377c909%2Clid%3D1556%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67be03f4%2F0%2F1747268%2F82842efb.86411611.7.58167331%2F320.mp3&ext=.mp3","trackType":"mp3"} Feb 15 20:33:44 volumio volumio[1047]: verbose: CURRENT POSITION 1 Feb 15 20:33:44 volumio volumio[1047]: info: CoreStateMachine::syncState stateService play Feb 15 20:33:44 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus stop Feb 15 20:33:44 volumio volumio[1047]: info: ControllerMpd::pushState Feb 15 20:33:44 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 15 20:33:44 volumio volumio[1047]: info: CorePlayQueue::getTrack 1 Feb 15 20:33:44 volumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":184,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Symphony in F Major, Eisen F6: II. Andante","artist":"Slowakisches Kammerorchester","album":"Mozart: 7 Symphonies","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2F1556.cold-rtc.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3De33dc88afa310cd92ad039b1be7638ab05c32d481038037631e239a88377c909%2Clid%3D1556%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67be03f4%2F0%2F1747268%2F82842efb.86411611.7.58167331%2F320.mp3&ext=.mp3","trackType":"mp3"} Feb 15 20:33:44 volumio volumio[1047]: verbose: CURRENT POSITION 1 Feb 15 20:33:44 volumio volumio[1047]: info: CoreStateMachine::syncState stateService play Feb 15 20:33:44 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus play Feb 15 20:33:44 volumio volumio[1047]: info: Received an update from plugin. extracting info from payload Feb 15 20:33:44 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:33:44 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 20:33:44 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:33:44 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:33:44 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:33:44 volumio volumio[1047]: info: ControllerMpd::pushState Feb 15 20:33:44 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 15 20:33:44 volumio volumio[1047]: info: CorePlayQueue::getTrack 1 Feb 15 20:33:44 volumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":184,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Symphony in F Major, Eisen F6: II. Andante","artist":"Slowakisches Kammerorchester","album":"Mozart: 7 Symphonies","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2F1556.cold-rtc.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3De33dc88afa310cd92ad039b1be7638ab05c32d481038037631e239a88377c909%2Clid%3D1556%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67be03f4%2F0%2F1747268%2F82842efb.86411611.7.58167331%2F320.mp3&ext=.mp3","trackType":"mp3"} Feb 15 20:33:44 volumio volumio[1047]: verbose: CURRENT POSITION 1 Feb 15 20:33:44 volumio volumio[1047]: info: CoreStateMachine::syncState stateService play Feb 15 20:33:44 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus play Feb 15 20:33:44 volumio volumio[1047]: info: Received an update from plugin. extracting info from payload Feb 15 20:33:44 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:33:44 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:33:44 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:33:44 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:33:44 volumio volumio[1047]: info: ------------------------------ 20ms Feb 15 20:33:44 volumio volumio[1047]: info: ------------------------------ 25ms Feb 15 20:33:44 volumio volumio[1047]: info: ------------------------------ 25ms Feb 15 20:33:44 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 15 milliseconds Feb 15 20:33:44 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 15 milliseconds Feb 15 20:33:44 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 15 milliseconds Feb 15 20:33:44 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Feb 15 20:33:44 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Feb 15 20:33:44 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Feb 15 20:33:44 volumio volumio[1047]: info: ControllerMpd::pushState Feb 15 20:33:44 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 15 20:33:44 volumio volumio[1047]: info: CorePlayQueue::getTrack 1 Feb 15 20:33:44 volumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":184,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Symphony in F Major, Eisen F6: II. Andante","artist":"Slowakisches Kammerorchester","album":"Mozart: 7 Symphonies","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2F1556.cold-rtc.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3De33dc88afa310cd92ad039b1be7638ab05c32d481038037631e239a88377c909%2Clid%3D1556%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67be03f4%2F0%2F1747268%2F82842efb.86411611.7.58167331%2F320.mp3&ext=.mp3","trackType":"mp3"} Feb 15 20:33:44 volumio volumio[1047]: verbose: CURRENT POSITION 1 Feb 15 20:33:44 volumio volumio[1047]: info: CoreStateMachine::syncState stateService play Feb 15 20:33:44 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus play Feb 15 20:33:44 volumio volumio[1047]: info: Received an update from plugin. extracting info from payload Feb 15 20:33:44 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:33:44 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:33:44 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:33:44 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:33:44 volumio volumio[1047]: info: ControllerMpd::pushState Feb 15 20:33:44 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 15 20:33:44 volumio volumio[1047]: info: CorePlayQueue::getTrack 1 Feb 15 20:33:44 volumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":184,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Symphony in F Major, Eisen F6: II. Andante","artist":"Slowakisches Kammerorchester","album":"Mozart: 7 Symphonies","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2F1556.cold-rtc.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3De33dc88afa310cd92ad039b1be7638ab05c32d481038037631e239a88377c909%2Clid%3D1556%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67be03f4%2F0%2F1747268%2F82842efb.86411611.7.58167331%2F320.mp3&ext=.mp3","trackType":"mp3"} Feb 15 20:33:44 volumio volumio[1047]: verbose: CURRENT POSITION 1 Feb 15 20:33:44 volumio volumio[1047]: info: CoreStateMachine::syncState stateService play Feb 15 20:33:44 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus play Feb 15 20:33:44 volumio volumio[1047]: info: Received an update from plugin. extracting info from payload Feb 15 20:33:44 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:33:44 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:33:44 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:33:44 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:33:44 volumio volumio[1047]: info: ControllerMpd::pushState Feb 15 20:33:44 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Feb 15 20:33:44 volumio volumio[1047]: info: CorePlayQueue::getTrack 1 Feb 15 20:33:44 volumio volumio[1047]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":184,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Symphony in F Major, Eisen F6: II. Andante","artist":"Slowakisches Kammerorchester","album":"Mozart: 7 Symphonies","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2F1556.cold-rtc.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3De33dc88afa310cd92ad039b1be7638ab05c32d481038037631e239a88377c909%2Clid%3D1556%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67be03f4%2F0%2F1747268%2F82842efb.86411611.7.58167331%2F320.mp3&ext=.mp3","trackType":"mp3"} Feb 15 20:33:44 volumio volumio[1047]: verbose: CURRENT POSITION 1 Feb 15 20:33:44 volumio volumio[1047]: info: CoreStateMachine::syncState stateService play Feb 15 20:33:44 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus play Feb 15 20:33:44 volumio volumio[1047]: info: Received an update from plugin. extracting info from payload Feb 15 20:33:44 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:33:44 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:33:44 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:33:44 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:33:44 volumio volumio[1047]: info: ------------------------------ 34ms Feb 15 20:33:44 volumio volumio[1047]: info: ------------------------------ 32ms Feb 15 20:33:44 volumio volumio[1047]: info: ------------------------------ 32ms Feb 15 20:33:44 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:33:44 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:33:44 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:33:44 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:33:44 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:33:44 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:33:44 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:33:44 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:33:44 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:33:44 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:33:44 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:33:44 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:33:44 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:33:44 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:33:44 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:33:44 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:33:44 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:33:44 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:33:44 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:33:44 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:33:44 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:33:44 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:33:44 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:33:44 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:33:44 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:33:44 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:33:44 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:33:44 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:33:44 volumio volumio[1047]: info: CoreStateMachine::getQueue Feb 15 20:33:44 volumio volumio[1047]: info: CorePlayQueue::getQueue Feb 15 20:33:47 volumio volumio[1047]: info: CoreCommandRouter::volumioSeek Feb 15 20:33:47 volumio volumio[1047]: info: CoreStateMachine::seek Feb 15 20:33:47 volumio volumio[1047]: info: CorePlayQueue::getTrack 1 Feb 15 20:33:47 volumio volumio[1047]: info: TRACKBLOCK {"id":"58167331:8798848@8798848","service":"yandex_music","type":"song","name":"Symphony in F Major, Eisen F6: II. Andante","title":"Symphony in F Major, Eisen F6: II. Andante","album":"Mozart: 7 Symphonies","artist":"Slowakisches Kammerorchester","duration":183,"albumart":"https://avatars.yandex.net/get-music-content/143117/f9eb9250.a.8798848-1/200x200","uri":"yandex_music/track/58167331:8798848@8798848","samplerate":"","bitdepth":"","trackType":"","codec":"mp3","bitrate":320} Feb 15 20:33:47 volumio volumio[1047]: info: CoreStateMachine::startPlaybackTimer Feb 15 20:33:47 volumio volumio[1047]: info: CorePlayQueue::getTrack 1 Feb 15 20:33:47 volumio volumio[1047]: info: ControllerMpd::seek Feb 15 20:33:47 volumio volumio[1047]: info: CoreStateMachine::pushState Feb 15 20:33:47 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 20:33:47 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Feb 15 20:33:51 volumio systemd[1]: Stopping Music Player Daemon... Feb 15 20:33:56 volumio volumio[1047]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 15 20:33:56 volumio volumio[1047]: Error: read ECONNRESET Feb 15 20:33:56 volumio volumio[1047]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:209:20) { Feb 15 20:33:56 volumio volumio[1047]: errno: -104, Feb 15 20:33:56 volumio volumio[1047]: code: 'ECONNRESET', Feb 15 20:33:56 volumio volumio[1047]: syscall: 'read' Feb 15 20:33:56 volumio volumio[1047]: } Feb 15 20:33:56 volumio volumio[1047]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 15 20:33:57 volumio sudo[11484]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-15 20:32 Feb 15 20:33:57 volumio sudo[11484]: 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"