-- Logs begin at Thu 2025-01-16 11:48:36 +05, end at Thu 2025-01-16 17:46:06 +05. -- Jan 16 17:45:27 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 16 17:45:27 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 16 17:45:27 volumio volumio[1004]: info: Discovery: Getting this device information Jan 16 17:45:27 volumio volumio[1004]: info: CoreCommandRouter::volumioGetState Jan 16 17:45:27 volumio volumio[1004]: info: CorePlayQueue::getTrack 89 Jan 16 17:45:27 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 16 17:45:27 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 16 17:45:27 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 16 17:45:27 volumio volumio[1004]: info: Discovery: Getting this device information Jan 16 17:45:27 volumio volumio[1004]: info: CoreCommandRouter::volumioGetState Jan 16 17:45:27 volumio volumio[1004]: info: CorePlayQueue::getTrack 89 Jan 16 17:45:27 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 16 17:45:28 volumio volumio[1004]: verbose: New Socket.io Connection to 192.168.2.153 from 192.168.2.116 UA: Mozilla/5.0 (Linux; Android 9; JAT-LX1 Build/HONORJAT-LX1; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/131.0.6778.260 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Jan 16 17:45:28 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 16 17:45:28 volumio volumio[1004]: info: CoreCommandRouter::volumioGetVisibleSources Jan 16 17:45:28 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 16 17:45:28 volumio volumio[1004]: info: CoreCommandRouter::volumioGetState Jan 16 17:45:28 volumio volumio[1004]: info: CorePlayQueue::getTrack 89 Jan 16 17:45:28 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 16 17:45:28 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 16 17:45:28 volumio volumio[1004]: info: Received Get System Info Jan 16 17:45:28 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 16 17:45:28 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 16 17:45:28 volumio volumio[1004]: info: Discovery: Getting this device information Jan 16 17:45:28 volumio volumio[1004]: info: CoreCommandRouter::volumioGetState Jan 16 17:45:28 volumio volumio[1004]: info: CorePlayQueue::getTrack 89 Jan 16 17:45:28 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 16 17:45:28 volumio volumio[1004]: info: CoreCommandRouter::volumioGetState Jan 16 17:45:28 volumio volumio[1004]: info: CorePlayQueue::getTrack 89 Jan 16 17:45:28 volumio volumio[1004]: info: Listing playlists Jan 16 17:45:28 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jan 16 17:45:28 volumio volumio[1004]: verbose: New Socket.io Connection to 192.168.2.153:3000 from 192.168.2.116 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Jan 16 17:45:29 volumio volumio[1004]: info: CoreCommandRouter::volumioNext Jan 16 17:45:29 volumio volumio[1004]: info: CoreStateMachine::next Jan 16 17:45:29 volumio volumio[1004]: info: CoreStateMachine::stop Jan 16 17:45:29 volumio volumio[1004]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 16 17:45:29 volumio volumio[1004]: info: CoreStateMachine::stPlaybackTimer Jan 16 17:45:29 volumio volumio[1004]: info: CoreStateMachine::updateTrackBlock Jan 16 17:45:29 volumio volumio[1004]: info: CorePlayQueue::getTrackBlock Jan 16 17:45:29 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:29 volumio volumio[1004]: info: CorePlayQueue::getTrack 89 Jan 16 17:45:29 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 16 17:45:29 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:29 volumio volumio[1004]: info: CoreStateMachine::serviceStop Jan 16 17:45:29 volumio volumio[1004]: info: CorePlayQueue::getTrack 89 Jan 16 17:45:29 volumio volumio[1004]: info: CoreCommandRouter::serviceStop Jan 16 17:45:29 volumio volumio[1004]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 16 17:45:29 volumio volumio[1004]: info: ControllerMpd::stop Jan 16 17:45:29 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand stop Jan 16 17:45:29 volumio volumio[1004]: info: sendMpdCommand stop took 8 milliseconds Jan 16 17:45:29 volumio volumio[1004]: info: CoreStateMachine::play index undefined Jan 16 17:45:29 volumio volumio[1004]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 16 17:45:29 volumio volumio[1004]: info: CorePlayQueue::getTrack 90 Jan 16 17:45:29 volumio volumio[1004]: info: CoreStateMachine::startPlaybackTimer Jan 16 17:45:29 volumio volumio[1004]: info: CorePlayQueue::getTrack 90 Jan 16 17:45:29 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand stop Jan 16 17:45:29 volumio volumio[1004]: info: CoreStateMachine::updateTrackBlock Jan 16 17:45:29 volumio volumio[1004]: info: CorePlayQueue::getTrackBlock Jan 16 17:45:29 volumio volumio[1004]: info: Jan 16 17:45:29 volumio volumio[1004]: ---------------------------- MPD announces state update: player Jan 16 17:45:29 volumio volumio[1004]: info: ControllerMpd::getState Jan 16 17:45:29 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Jan 16 17:45:29 volumio volumio[1004]: info: sendMpdCommand stop took 13 milliseconds Jan 16 17:45:29 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand clear Jan 16 17:45:29 volumio volumio[1004]: info: Jan 16 17:45:29 volumio volumio[1004]: ---------------------------- MPD announces state update: player Jan 16 17:45:29 volumio volumio[1004]: info: ControllerMpd::getState Jan 16 17:45:29 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Jan 16 17:45:29 volumio volumio[1004]: info: Jan 16 17:45:29 volumio volumio[1004]: ---------------------------- MPD announces state update: player Jan 16 17:45:29 volumio volumio[1004]: info: ControllerMpd::getState Jan 16 17:45:29 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Jan 16 17:45:29 volumio volumio[1004]: info: Jan 16 17:45:29 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:29 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:29 volumio volumio[1004]: info: sendMpdCommand status took 17 milliseconds Jan 16 17:45:29 volumio volumio[1004]: info: sendMpdCommand clear took 16 milliseconds Jan 16 17:45:29 volumio volumio[1004]: info: sendMpdCommand status took 14 milliseconds Jan 16 17:45:29 volumio volumio[1004]: info: sendMpdCommand status took 12 milliseconds Jan 16 17:45:29 volumio volumio[1004]: verbose: ControllerMpd::parseState Jan 16 17:45:29 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 16 17:45:29 volumio volumio[1004]: verbose: ControllerMpd::parseState Jan 16 17:45:29 volumio volumio[1004]: verbose: ControllerMpd::parseState Jan 16 17:45:29 volumio volumio[1004]: info: ControllerMpd::pushState Jan 16 17:45:29 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Jan 16 17:45:29 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:29 volumio volumio[1004]: info: CorePlayQueue::getTrack 90 Jan 16 17:45:29 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 16 17:45:29 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:29 volumio volumio[1004]: info: CorePlayQueue::getTrack 90 Jan 16 17:45:29 volumio volumio[1004]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd Jan 16 17:45:29 volumio volumio[1004]: info: ControllerMpd::pushState Jan 16 17:45:29 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Jan 16 17:45:29 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:29 volumio volumio[1004]: info: CorePlayQueue::getTrack 90 Jan 16 17:45:29 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:29 volumio volumio[1004]: info: CorePlayQueue::getTrack 90 Jan 16 17:45:29 volumio volumio[1004]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd Jan 16 17:45:29 volumio volumio[1004]: info: ------------------------------ 39ms Jan 16 17:45:29 volumio volumio[1004]: info: ------------------------------ 37ms Jan 16 17:45:29 volumio volumio[1004]: info: Jan 16 17:45:29 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:29 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:29 volumio volumio[1004]: info: Jan 16 17:45:29 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:29 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:29 volumio volumio[1004]: error: updateQueue error: null Jan 16 17:45:29 volumio volumio[1004]: error: updateQueue error: null Jan 16 17:45:29 volumio volumio[1004]: error: updateQueue error: null Jan 16 17:45:29 volumio volumio[1004]: info: ------------------------------ 44ms Jan 16 17:45:29 volumio volumio[1004]: info: sendMpdCommand playlistinfo took 40 milliseconds Jan 16 17:45:29 volumio volumio[1004]: info: ------------------------------ 10ms Jan 16 17:45:29 volumio volumio[1004]: info: ------------------------------ 9ms Jan 16 17:45:29 volumio volumio[1004]: verbose: ControllerMpd::parseTrackInfo Jan 16 17:45:29 volumio volumio[1004]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jan 16 17:45:29 volumio volumio[1004]: info: ------------------------------ 65ms Jan 16 17:45:29 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=flac&url=https%3A%2F%2Fstrm-m9-116.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D85f96f0abd359c91e5deeb0d23c2769b8e9688ff927aca81f9d2fa3ae95bcafe%2Clid%3D1528%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67969bcd%2F0%2F4590280%2F8ce0542b.196814767.8.27538898%2Fflac&ext=.flac" Jan 16 17:45:29 volumio volumio[1004]: info: Jan 16 17:45:29 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:29 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:29 volumio volumio[1004]: info: sendMpdCommand addid "http://localhost:6601/?codec=flac&url=https%3A%2F%2Fstrm-m9-116.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D85f96f0abd359c91e5deeb0d23c2769b8e9688ff927aca81f9d2fa3ae95bcafe%2Clid%3D1528%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67969bcd%2F0%2F4590280%2F8ce0542b.196814767.8.27538898%2Fflac&ext=.flac" took 14 milliseconds Jan 16 17:45:29 volumio volumio[1004]: verbose: MPD COMMAND [object Object] Jan 16 17:45:29 volumio volumio[1004]: info: Jan 16 17:45:29 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:29 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:29 volumio volumio[1004]: info: Jan 16 17:45:29 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:29 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:29 volumio volumio[1004]: info: Jan 16 17:45:29 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:29 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:29 volumio volumio[1004]: info: ------------------------------ 16ms Jan 16 17:45:29 volumio volumio[1004]: info: ------------------------------ 7ms Jan 16 17:45:29 volumio volumio[1004]: info: ------------------------------ 6ms Jan 16 17:45:29 volumio volumio[1004]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 16 17:45:29 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand play Jan 16 17:45:29 volumio volumio[1004]: info: Jan 16 17:45:29 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:29 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:29 volumio volumio[1004]: info: Jan 16 17:45:29 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:29 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:29 volumio volumio[1004]: info: ------------------------------ 10ms Jan 16 17:45:29 volumio volumio[1004]: info: sendMpdCommand play took 7 milliseconds Jan 16 17:45:29 volumio volumio[1004]: info: ------------------------------ 6ms Jan 16 17:45:29 volumio volumio[1004]: info: ------------------------------ 4ms Jan 16 17:45:30 volumio volumio[1004]: info: Jan 16 17:45:30 volumio volumio[1004]: ---------------------------- MPD announces state update: player Jan 16 17:45:30 volumio volumio[1004]: info: ControllerMpd::getState Jan 16 17:45:30 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Jan 16 17:45:30 volumio volumio[1004]: info: Jan 16 17:45:30 volumio volumio[1004]: ---------------------------- MPD announces state update: player Jan 16 17:45:30 volumio volumio[1004]: info: ControllerMpd::getState Jan 16 17:45:30 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Jan 16 17:45:30 volumio volumio[1004]: info: Jan 16 17:45:30 volumio volumio[1004]: ---------------------------- MPD announces state update: player Jan 16 17:45:30 volumio volumio[1004]: info: ControllerMpd::getState Jan 16 17:45:30 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Jan 16 17:45:30 volumio volumio[1004]: info: Jan 16 17:45:30 volumio volumio[1004]: ---------------------------- MPD announces state update: player Jan 16 17:45:30 volumio volumio[1004]: info: ControllerMpd::getState Jan 16 17:45:30 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Jan 16 17:45:30 volumio volumio[1004]: info: Jan 16 17:45:30 volumio volumio[1004]: ---------------------------- MPD announces state update: player Jan 16 17:45:30 volumio volumio[1004]: info: sendMpdCommand status took 22 milliseconds Jan 16 17:45:30 volumio volumio[1004]: info: ControllerMpd::getState Jan 16 17:45:30 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Jan 16 17:45:30 volumio volumio[1004]: info: sendMpdCommand status took 18 milliseconds Jan 16 17:45:30 volumio volumio[1004]: info: sendMpdCommand status took 16 milliseconds Jan 16 17:45:30 volumio volumio[1004]: info: sendMpdCommand status took 14 milliseconds Jan 16 17:45:30 volumio volumio[1004]: verbose: ControllerMpd::parseState Jan 16 17:45:30 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 16 17:45:30 volumio volumio[1004]: verbose: ControllerMpd::parseState Jan 16 17:45:30 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 16 17:45:30 volumio volumio[1004]: verbose: ControllerMpd::parseState Jan 16 17:45:30 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 16 17:45:30 volumio volumio[1004]: verbose: ControllerMpd::parseState Jan 16 17:45:30 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 16 17:45:30 volumio volumio[1004]: info: sendMpdCommand status took 11 milliseconds Jan 16 17:45:30 volumio volumio[1004]: info: sendMpdCommand playlistinfo took 9 milliseconds Jan 16 17:45:30 volumio volumio[1004]: info: sendMpdCommand playlistinfo took 8 milliseconds Jan 16 17:45:30 volumio volumio[1004]: info: sendMpdCommand playlistinfo took 8 milliseconds Jan 16 17:45:30 volumio volumio[1004]: info: sendMpdCommand playlistinfo took 8 milliseconds Jan 16 17:45:30 volumio volumio[1004]: verbose: ControllerMpd::parseState Jan 16 17:45:30 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 16 17:45:30 volumio volumio[1004]: verbose: ControllerMpd::parseTrackInfo Jan 16 17:45:30 volumio volumio[1004]: verbose: ControllerMpd::parseTrackInfo Jan 16 17:45:30 volumio volumio[1004]: verbose: ControllerMpd::parseTrackInfo Jan 16 17:45:30 volumio volumio[1004]: verbose: ControllerMpd::parseTrackInfo Jan 16 17:45:30 volumio volumio[1004]: info: ControllerMpd::pushState Jan 16 17:45:30 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Jan 16 17:45:30 volumio volumio[1004]: info: CorePlayQueue::getTrack 90 Jan 16 17:45:30 volumio volumio[1004]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":237,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Rame (Beloved)","artist":"SNAP!","album":"Welcome to Tomorrow","uri":"http://localhost:6601/?codec=flac&url=https%3A%2F%2Fstrm-m9-116.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D85f96f0abd359c91e5deeb0d23c2769b8e9688ff927aca81f9d2fa3ae95bcafe%2Clid%3D1528%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67969bcd%2F0%2F4590280%2F8ce0542b.196814767.8.27538898%2Fflac&ext=.flac","trackType":"flac"} Jan 16 17:45:30 volumio volumio[1004]: verbose: CURRENT POSITION 90 Jan 16 17:45:30 volumio volumio[1004]: info: CoreStateMachine::syncState stateService play Jan 16 17:45:30 volumio volumio[1004]: info: CoreStateMachine::syncState currentStatus stop Jan 16 17:45:30 volumio volumio[1004]: info: ControllerMpd::pushState Jan 16 17:45:30 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Jan 16 17:45:30 volumio volumio[1004]: info: CorePlayQueue::getTrack 90 Jan 16 17:45:30 volumio volumio[1004]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":237,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Rame (Beloved)","artist":"SNAP!","album":"Welcome to Tomorrow","uri":"http://localhost:6601/?codec=flac&url=https%3A%2F%2Fstrm-m9-116.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D85f96f0abd359c91e5deeb0d23c2769b8e9688ff927aca81f9d2fa3ae95bcafe%2Clid%3D1528%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67969bcd%2F0%2F4590280%2F8ce0542b.196814767.8.27538898%2Fflac&ext=.flac","trackType":"flac"} Jan 16 17:45:30 volumio volumio[1004]: verbose: CURRENT POSITION 90 Jan 16 17:45:30 volumio volumio[1004]: info: CoreStateMachine::syncState stateService play Jan 16 17:45:30 volumio volumio[1004]: info: CoreStateMachine::syncState currentStatus play Jan 16 17:45:30 volumio volumio[1004]: info: Received an update from plugin. extracting info from payload Jan 16 17:45:30 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:30 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 16 17:45:30 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:30 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:30 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:30 volumio volumio[1004]: info: ControllerMpd::pushState Jan 16 17:45:30 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Jan 16 17:45:30 volumio volumio[1004]: info: CorePlayQueue::getTrack 90 Jan 16 17:45:30 volumio volumio[1004]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":237,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Rame (Beloved)","artist":"SNAP!","album":"Welcome to Tomorrow","uri":"http://localhost:6601/?codec=flac&url=https%3A%2F%2Fstrm-m9-116.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D85f96f0abd359c91e5deeb0d23c2769b8e9688ff927aca81f9d2fa3ae95bcafe%2Clid%3D1528%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67969bcd%2F0%2F4590280%2F8ce0542b.196814767.8.27538898%2Fflac&ext=.flac","trackType":"flac"} Jan 16 17:45:30 volumio volumio[1004]: verbose: CURRENT POSITION 90 Jan 16 17:45:30 volumio volumio[1004]: info: CoreStateMachine::syncState stateService play Jan 16 17:45:30 volumio volumio[1004]: info: CoreStateMachine::syncState currentStatus play Jan 16 17:45:30 volumio volumio[1004]: info: Received an update from plugin. extracting info from payload Jan 16 17:45:30 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:30 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:30 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:30 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:30 volumio volumio[1004]: info: ControllerMpd::pushState Jan 16 17:45:30 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Jan 16 17:45:30 volumio volumio[1004]: info: CorePlayQueue::getTrack 90 Jan 16 17:45:30 volumio volumio[1004]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":237,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Rame (Beloved)","artist":"SNAP!","album":"Welcome to Tomorrow","uri":"http://localhost:6601/?codec=flac&url=https%3A%2F%2Fstrm-m9-116.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D85f96f0abd359c91e5deeb0d23c2769b8e9688ff927aca81f9d2fa3ae95bcafe%2Clid%3D1528%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67969bcd%2F0%2F4590280%2F8ce0542b.196814767.8.27538898%2Fflac&ext=.flac","trackType":"flac"} Jan 16 17:45:30 volumio volumio[1004]: verbose: CURRENT POSITION 90 Jan 16 17:45:30 volumio volumio[1004]: info: CoreStateMachine::syncState stateService play Jan 16 17:45:30 volumio volumio[1004]: info: CoreStateMachine::syncState currentStatus play Jan 16 17:45:30 volumio volumio[1004]: info: Received an update from plugin. extracting info from payload Jan 16 17:45:30 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:30 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:30 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:30 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:30 volumio volumio[1004]: info: ------------------------------ 79ms Jan 16 17:45:30 volumio volumio[1004]: info: ------------------------------ 99ms Jan 16 17:45:30 volumio volumio[1004]: info: ------------------------------ 94ms Jan 16 17:45:30 volumio volumio[1004]: info: ------------------------------ 94ms Jan 16 17:45:30 volumio volumio[1004]: info: Jan 16 17:45:30 volumio volumio[1004]: ---------------------------- MPD announces state update: player Jan 16 17:45:30 volumio volumio[1004]: info: ControllerMpd::getState Jan 16 17:45:30 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Jan 16 17:45:30 volumio volumio[1004]: info: sendMpdCommand playlistinfo took 78 milliseconds Jan 16 17:45:30 volumio volumio[1004]: info: sendMpdCommand status took 13 milliseconds Jan 16 17:45:30 volumio volumio[1004]: verbose: ControllerMpd::parseTrackInfo Jan 16 17:45:30 volumio volumio[1004]: verbose: ControllerMpd::parseState Jan 16 17:45:30 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 16 17:45:30 volumio volumio[1004]: info: ControllerMpd::pushState Jan 16 17:45:30 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Jan 16 17:45:30 volumio volumio[1004]: info: CorePlayQueue::getTrack 90 Jan 16 17:45:30 volumio volumio[1004]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":237,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Rame (Beloved)","artist":"SNAP!","album":"Welcome to Tomorrow","uri":"http://localhost:6601/?codec=flac&url=https%3A%2F%2Fstrm-m9-116.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D85f96f0abd359c91e5deeb0d23c2769b8e9688ff927aca81f9d2fa3ae95bcafe%2Clid%3D1528%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67969bcd%2F0%2F4590280%2F8ce0542b.196814767.8.27538898%2Fflac&ext=.flac","trackType":"flac"} Jan 16 17:45:30 volumio volumio[1004]: verbose: CURRENT POSITION 90 Jan 16 17:45:30 volumio volumio[1004]: info: CoreStateMachine::syncState stateService play Jan 16 17:45:30 volumio volumio[1004]: info: CoreStateMachine::syncState currentStatus play Jan 16 17:45:30 volumio volumio[1004]: info: Received an update from plugin. extracting info from payload Jan 16 17:45:30 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:30 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:30 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:30 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:30 volumio volumio[1004]: info: ------------------------------ 122ms Jan 16 17:45:30 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Jan 16 17:45:30 volumio volumio[1004]: info: CoreStateMachine::getQueue Jan 16 17:45:30 volumio volumio[1004]: info: CorePlayQueue::getQueue Jan 16 17:45:30 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Jan 16 17:45:30 volumio volumio[1004]: info: CoreStateMachine::getQueue Jan 16 17:45:30 volumio volumio[1004]: info: CorePlayQueue::getQueue Jan 16 17:45:30 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Jan 16 17:45:30 volumio volumio[1004]: info: CoreStateMachine::getQueue Jan 16 17:45:30 volumio volumio[1004]: info: CorePlayQueue::getQueue Jan 16 17:45:30 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Jan 16 17:45:30 volumio volumio[1004]: info: CoreStateMachine::getQueue Jan 16 17:45:30 volumio volumio[1004]: info: CorePlayQueue::getQueue Jan 16 17:45:30 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Jan 16 17:45:30 volumio volumio[1004]: info: CoreStateMachine::getQueue Jan 16 17:45:30 volumio volumio[1004]: info: CorePlayQueue::getQueue Jan 16 17:45:30 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Jan 16 17:45:30 volumio volumio[1004]: info: CoreStateMachine::getQueue Jan 16 17:45:30 volumio volumio[1004]: info: CorePlayQueue::getQueue Jan 16 17:45:30 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Jan 16 17:45:30 volumio volumio[1004]: info: CoreStateMachine::getQueue Jan 16 17:45:30 volumio volumio[1004]: info: CorePlayQueue::getQueue Jan 16 17:45:30 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Jan 16 17:45:30 volumio volumio[1004]: info: CoreStateMachine::getQueue Jan 16 17:45:30 volumio volumio[1004]: info: CorePlayQueue::getQueue Jan 16 17:45:30 volumio volumio[1004]: info: sendMpdCommand playlistinfo took 125 milliseconds Jan 16 17:45:30 volumio volumio[1004]: verbose: ControllerMpd::parseTrackInfo Jan 16 17:45:30 volumio volumio[1004]: info: ControllerMpd::pushState Jan 16 17:45:30 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Jan 16 17:45:30 volumio volumio[1004]: info: CorePlayQueue::getTrack 90 Jan 16 17:45:30 volumio volumio[1004]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":237,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Rame (Beloved)","artist":"SNAP!","album":"Welcome to Tomorrow","uri":"http://localhost:6601/?codec=flac&url=https%3A%2F%2Fstrm-m9-116.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D85f96f0abd359c91e5deeb0d23c2769b8e9688ff927aca81f9d2fa3ae95bcafe%2Clid%3D1528%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67969bcd%2F0%2F4590280%2F8ce0542b.196814767.8.27538898%2Fflac&ext=.flac","trackType":"flac"} Jan 16 17:45:30 volumio volumio[1004]: verbose: CURRENT POSITION 90 Jan 16 17:45:30 volumio volumio[1004]: info: CoreStateMachine::syncState stateService play Jan 16 17:45:30 volumio volumio[1004]: info: CoreStateMachine::syncState currentStatus play Jan 16 17:45:30 volumio volumio[1004]: info: Received an update from plugin. extracting info from payload Jan 16 17:45:30 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:30 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:30 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:30 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:30 volumio volumio[1004]: info: ------------------------------ 163ms Jan 16 17:45:30 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Jan 16 17:45:30 volumio volumio[1004]: info: CoreStateMachine::getQueue Jan 16 17:45:30 volumio volumio[1004]: info: CorePlayQueue::getQueue Jan 16 17:45:30 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Jan 16 17:45:30 volumio volumio[1004]: info: CoreStateMachine::getQueue Jan 16 17:45:30 volumio volumio[1004]: info: CorePlayQueue::getQueue Jan 16 17:45:31 volumio volumio[1004]: info: CoreCommandRouter::volumioAddQueueItems Jan 16 17:45:31 volumio volumio[1004]: info: CoreStateMachine::addQueueItems Jan 16 17:45:31 volumio volumio[1004]: info: CorePlayQueue::addQueueItems Jan 16 17:45:31 volumio volumio[1004]: info: Preload queue cleared Jan 16 17:45:31 volumio volumio[1004]: info: Adding Item to queue: yandex_music/track/28123140:3359356@user:onyourwave Jan 16 17:45:31 volumio volumio[1004]: info: Exploding uri yandex_music/track/28123140:3359356@user:onyourwave in service yandex_music Jan 16 17:45:31 volumio volumio[1004]: info: CoreCommandRouter::volumioAddQueueItems Jan 16 17:45:31 volumio volumio[1004]: info: CoreStateMachine::addQueueItems Jan 16 17:45:31 volumio volumio[1004]: info: CorePlayQueue::addQueueItems Jan 16 17:45:31 volumio volumio[1004]: info: Preload queue cleared Jan 16 17:45:31 volumio volumio[1004]: info: Adding Item to queue: yandex_music/track/128466753:32235938@user:onyourwave Jan 16 17:45:31 volumio volumio[1004]: info: Exploding uri yandex_music/track/128466753:32235938@user:onyourwave in service yandex_music Jan 16 17:45:31 volumio volumio[1004]: info: CoreCommandRouter::volumioAddQueueItems Jan 16 17:45:31 volumio volumio[1004]: info: CoreStateMachine::addQueueItems Jan 16 17:45:31 volumio volumio[1004]: info: CorePlayQueue::addQueueItems Jan 16 17:45:31 volumio volumio[1004]: info: Preload queue cleared Jan 16 17:45:31 volumio volumio[1004]: info: Adding Item to queue: yandex_music/track/4608857:22049018@user:onyourwave Jan 16 17:45:31 volumio volumio[1004]: info: Exploding uri yandex_music/track/4608857:22049018@user:onyourwave in service yandex_music Jan 16 17:45:31 volumio volumio[1004]: info: CoreCommandRouter::volumioAddQueueItems Jan 16 17:45:31 volumio volumio[1004]: info: CoreStateMachine::addQueueItems Jan 16 17:45:31 volumio volumio[1004]: info: CorePlayQueue::addQueueItems Jan 16 17:45:31 volumio volumio[1004]: info: Preload queue cleared Jan 16 17:45:31 volumio volumio[1004]: info: Adding Item to queue: yandex_music/track/129559483:32649985@user:onyourwave Jan 16 17:45:31 volumio volumio[1004]: info: Exploding uri yandex_music/track/129559483:32649985@user:onyourwave in service yandex_music Jan 16 17:45:31 volumio volumio[1004]: info: CoreCommandRouter::volumioPushQueue Jan 16 17:45:31 volumio volumio[1004]: info: CorePlayQueue::saveQueue Jan 16 17:45:31 volumio volumio[1004]: info: CoreCommandRouter::volumioPushQueue Jan 16 17:45:31 volumio volumio[1004]: info: CorePlayQueue::saveQueue Jan 16 17:45:31 volumio volumio[1004]: info: CoreCommandRouter::volumioPushQueue Jan 16 17:45:31 volumio volumio[1004]: info: CorePlayQueue::saveQueue Jan 16 17:45:31 volumio volumio[1004]: info: CoreCommandRouter::volumioPushQueue Jan 16 17:45:31 volumio volumio[1004]: info: CorePlayQueue::saveQueue Jan 16 17:45:31 volumio volumio[1004]: info: CoreStateMachine::updateTrackBlock Jan 16 17:45:31 volumio volumio[1004]: info: CorePlayQueue::getTrackBlock Jan 16 17:45:31 volumio volumio[1004]: info: CoreStateMachine::updateTrackBlock Jan 16 17:45:31 volumio volumio[1004]: info: CorePlayQueue::getTrackBlock Jan 16 17:45:31 volumio volumio[1004]: info: CoreStateMachine::updateTrackBlock Jan 16 17:45:31 volumio volumio[1004]: info: CorePlayQueue::getTrackBlock Jan 16 17:45:31 volumio volumio[1004]: info: CoreStateMachine::updateTrackBlock Jan 16 17:45:31 volumio volumio[1004]: info: CorePlayQueue::getTrackBlock Jan 16 17:45:32 volumio volumio[1004]: info: CoreCommandRouter::volumioNext Jan 16 17:45:32 volumio volumio[1004]: info: CoreStateMachine::next Jan 16 17:45:32 volumio volumio[1004]: info: ControllerMpd::next Jan 16 17:45:32 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand next Jan 16 17:45:32 volumio volumio[1004]: info: Jan 16 17:45:32 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:32 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:32 volumio volumio[1004]: info: Jan 16 17:45:32 volumio volumio[1004]: ---------------------------- MPD announces state update: player Jan 16 17:45:32 volumio volumio[1004]: info: sendMpdCommand next took 20 milliseconds Jan 16 17:45:32 volumio volumio[1004]: info: ControllerMpd::getState Jan 16 17:45:32 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Jan 16 17:45:32 volumio volumio[1004]: error: updateQueue error: null Jan 16 17:45:32 volumio volumio[1004]: info: ------------------------------ 7ms Jan 16 17:45:32 volumio volumio[1004]: info: sendMpdCommand status took 4 milliseconds Jan 16 17:45:32 volumio volumio[1004]: verbose: ControllerMpd::parseState Jan 16 17:45:32 volumio volumio[1004]: info: ControllerMpd::pushState Jan 16 17:45:32 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Jan 16 17:45:32 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:32 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 16 17:45:32 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:32 volumio volumio[1004]: info: CorePlayQueue::getTrack 90 Jan 16 17:45:32 volumio volumio[1004]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 16 17:45:32 volumio volumio[1004]: verbose: CURRENT POSITION 90 Jan 16 17:45:32 volumio volumio[1004]: info: CoreStateMachine::syncState stateService stop Jan 16 17:45:32 volumio volumio[1004]: info: CoreStateMachine::syncState currentStatus play Jan 16 17:45:32 volumio volumio[1004]: info: CoreStateMachine::play index undefined Jan 16 17:45:32 volumio volumio[1004]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 16 17:45:32 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:32 volumio volumio[1004]: info: CorePlayQueue::getTrack 91 Jan 16 17:45:32 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 16 17:45:32 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:32 volumio volumio[1004]: info: CorePlayQueue::getTrack 91 Jan 16 17:45:32 volumio volumio[1004]: info: CoreStateMachine::startPlaybackTimer Jan 16 17:45:32 volumio volumio[1004]: info: CorePlayQueue::getTrack 91 Jan 16 17:45:32 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand stop Jan 16 17:45:32 volumio volumio[1004]: info: ------------------------------ 34ms Jan 16 17:45:32 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:32 volumio volumio[1004]: info: CorePlayQueue::getTrack 91 Jan 16 17:45:32 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 16 17:45:32 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:32 volumio volumio[1004]: info: Jan 16 17:45:32 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:32 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:32 volumio volumio[1004]: info: Jan 16 17:45:32 volumio volumio[1004]: ---------------------------- MPD announces state update: player Jan 16 17:45:32 volumio volumio[1004]: info: ControllerMpd::getState Jan 16 17:45:32 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Jan 16 17:45:32 volumio volumio[1004]: info: Jan 16 17:45:32 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:32 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:32 volumio volumio[1004]: info: Jan 16 17:45:32 volumio volumio[1004]: ---------------------------- MPD announces state update: player Jan 16 17:45:32 volumio volumio[1004]: info: ControllerMpd::getState Jan 16 17:45:32 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Jan 16 17:45:32 volumio volumio[1004]: error: updateQueue error: null Jan 16 17:45:32 volumio volumio[1004]: error: updateQueue error: null Jan 16 17:45:32 volumio volumio[1004]: info: sendMpdCommand stop took 31 milliseconds Jan 16 17:45:32 volumio volumio[1004]: info: ------------------------------ 9ms Jan 16 17:45:32 volumio volumio[1004]: info: sendMpdCommand status took 8 milliseconds Jan 16 17:45:32 volumio volumio[1004]: info: ------------------------------ 7ms Jan 16 17:45:32 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand clear Jan 16 17:45:32 volumio volumio[1004]: verbose: ControllerMpd::parseState Jan 16 17:45:32 volumio volumio[1004]: info: ControllerMpd::pushState Jan 16 17:45:32 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Jan 16 17:45:32 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:32 volumio volumio[1004]: info: CorePlayQueue::getTrack 91 Jan 16 17:45:32 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:32 volumio volumio[1004]: info: CorePlayQueue::getTrack 91 Jan 16 17:45:32 volumio volumio[1004]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd Jan 16 17:45:32 volumio volumio[1004]: info: ------------------------------ 21ms Jan 16 17:45:32 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Jan 16 17:45:32 volumio volumio[1004]: info: CoreStateMachine::getQueue Jan 16 17:45:32 volumio volumio[1004]: info: CorePlayQueue::getQueue Jan 16 17:45:32 volumio volumio[1004]: info: Jan 16 17:45:32 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:32 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:32 volumio volumio[1004]: info: sendMpdCommand status took 33 milliseconds Jan 16 17:45:32 volumio volumio[1004]: info: sendMpdCommand clear took 27 milliseconds Jan 16 17:45:32 volumio volumio[1004]: verbose: ControllerMpd::parseState Jan 16 17:45:32 volumio volumio[1004]: info: ControllerMpd::pushState Jan 16 17:45:32 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Jan 16 17:45:32 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:32 volumio volumio[1004]: info: CorePlayQueue::getTrack 91 Jan 16 17:45:32 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:32 volumio volumio[1004]: info: CorePlayQueue::getTrack 91 Jan 16 17:45:32 volumio volumio[1004]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd Jan 16 17:45:32 volumio volumio[1004]: info: ------------------------------ 46ms Jan 16 17:45:32 volumio volumio[1004]: info: Jan 16 17:45:32 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:32 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:32 volumio volumio[1004]: info: Jan 16 17:45:32 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:32 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:32 volumio volumio[1004]: error: updateQueue error: null Jan 16 17:45:32 volumio volumio[1004]: error: updateQueue error: null Jan 16 17:45:32 volumio volumio[1004]: error: updateQueue error: null Jan 16 17:45:32 volumio volumio[1004]: info: ------------------------------ 32ms Jan 16 17:45:32 volumio volumio[1004]: info: ------------------------------ 10ms Jan 16 17:45:32 volumio volumio[1004]: info: ------------------------------ 8ms Jan 16 17:45:33 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=flac&url=https%3A%2F%2Fstrm-m9-114.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D580f3da302eac74ace1271940fa75f8e5f1adbe9f008a9d62e77691c009c1706%2Clid%3D1528%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67964335%2F0%2F766255%2Ff1676db6.59074080.13.14510153%2Fflac&ext=.flac" Jan 16 17:45:33 volumio volumio[1004]: info: Jan 16 17:45:33 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:33 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:33 volumio volumio[1004]: info: sendMpdCommand addid "http://localhost:6601/?codec=flac&url=https%3A%2F%2Fstrm-m9-114.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D580f3da302eac74ace1271940fa75f8e5f1adbe9f008a9d62e77691c009c1706%2Clid%3D1528%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67964335%2F0%2F766255%2Ff1676db6.59074080.13.14510153%2Fflac&ext=.flac" took 4 milliseconds Jan 16 17:45:33 volumio volumio[1004]: verbose: MPD COMMAND [object Object] Jan 16 17:45:33 volumio volumio[1004]: verbose: MPD COMMAND [object Object] Jan 16 17:45:33 volumio volumio[1004]: verbose: MPD COMMAND [object Object] Jan 16 17:45:33 volumio volumio[1004]: info: Jan 16 17:45:33 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:33 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:33 volumio volumio[1004]: info: Jan 16 17:45:33 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:33 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:33 volumio volumio[1004]: info: Jan 16 17:45:33 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:33 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:33 volumio volumio[1004]: info: ------------------------------ 9ms Jan 16 17:45:33 volumio volumio[1004]: info: ------------------------------ 6ms Jan 16 17:45:33 volumio volumio[1004]: info: ------------------------------ 4ms Jan 16 17:45:33 volumio volumio[1004]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 16 17:45:33 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand play Jan 16 17:45:33 volumio volumio[1004]: info: Jan 16 17:45:33 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:33 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:33 volumio volumio[1004]: info: ------------------------------ 8ms Jan 16 17:45:33 volumio volumio[1004]: info: sendMpdCommand play took 5 milliseconds Jan 16 17:45:33 volumio volumio[1004]: info: Jan 16 17:45:33 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:33 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:33 volumio volumio[1004]: info: ------------------------------ 21ms Jan 16 17:45:33 volumio volumio[1004]: info: ------------------------------ 6ms Jan 16 17:45:33 volumio volumio[1004]: info: Jan 16 17:45:33 volumio volumio[1004]: ---------------------------- MPD announces state update: player Jan 16 17:45:33 volumio volumio[1004]: info: ControllerMpd::getState Jan 16 17:45:33 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Jan 16 17:45:33 volumio volumio[1004]: info: Jan 16 17:45:33 volumio volumio[1004]: ---------------------------- MPD announces state update: player Jan 16 17:45:33 volumio volumio[1004]: info: ControllerMpd::getState Jan 16 17:45:33 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Jan 16 17:45:33 volumio volumio[1004]: info: Jan 16 17:45:33 volumio volumio[1004]: ---------------------------- MPD announces state update: player Jan 16 17:45:33 volumio volumio[1004]: info: ControllerMpd::getState Jan 16 17:45:33 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Jan 16 17:45:33 volumio volumio[1004]: info: Jan 16 17:45:33 volumio volumio[1004]: ---------------------------- MPD announces state update: player Jan 16 17:45:33 volumio volumio[1004]: info: sendMpdCommand status took 9 milliseconds Jan 16 17:45:33 volumio volumio[1004]: info: sendMpdCommand status took 7 milliseconds Jan 16 17:45:33 volumio volumio[1004]: info: ControllerMpd::getState Jan 16 17:45:33 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Jan 16 17:45:33 volumio volumio[1004]: info: sendMpdCommand status took 6 milliseconds Jan 16 17:45:33 volumio volumio[1004]: verbose: ControllerMpd::parseState Jan 16 17:45:33 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 16 17:45:33 volumio volumio[1004]: verbose: ControllerMpd::parseState Jan 16 17:45:33 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 16 17:45:33 volumio volumio[1004]: verbose: ControllerMpd::parseState Jan 16 17:45:33 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 16 17:45:33 volumio volumio[1004]: info: Jan 16 17:45:33 volumio volumio[1004]: ---------------------------- MPD announces state update: player Jan 16 17:45:33 volumio volumio[1004]: info: ControllerMpd::getState Jan 16 17:45:33 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Jan 16 17:45:33 volumio volumio[1004]: info: Jan 16 17:45:33 volumio volumio[1004]: ---------------------------- MPD announces state update: player Jan 16 17:45:33 volumio volumio[1004]: info: ControllerMpd::getState Jan 16 17:45:33 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Jan 16 17:45:33 volumio volumio[1004]: info: sendMpdCommand status took 16 milliseconds Jan 16 17:45:33 volumio volumio[1004]: info: sendMpdCommand playlistinfo took 14 milliseconds Jan 16 17:45:33 volumio volumio[1004]: info: sendMpdCommand playlistinfo took 12 milliseconds Jan 16 17:45:33 volumio volumio[1004]: info: sendMpdCommand status took 10 milliseconds Jan 16 17:45:33 volumio volumio[1004]: info: sendMpdCommand status took 8 milliseconds Jan 16 17:45:33 volumio volumio[1004]: verbose: ControllerMpd::parseState Jan 16 17:45:33 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 16 17:45:33 volumio volumio[1004]: verbose: ControllerMpd::parseTrackInfo Jan 16 17:45:33 volumio volumio[1004]: verbose: ControllerMpd::parseTrackInfo Jan 16 17:45:33 volumio volumio[1004]: verbose: ControllerMpd::parseTrackInfo Jan 16 17:45:33 volumio volumio[1004]: verbose: ControllerMpd::parseState Jan 16 17:45:33 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 16 17:45:33 volumio volumio[1004]: verbose: ControllerMpd::parseState Jan 16 17:45:33 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 16 17:45:33 volumio volumio[1004]: info: ControllerMpd::pushState Jan 16 17:45:33 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Jan 16 17:45:33 volumio volumio[1004]: info: CorePlayQueue::getTrack 91 Jan 16 17:45:33 volumio volumio[1004]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":216,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Shut Up (And Sleep with Me)","artist":"Sin With Sebastian","album":"Shut Up (And Sleep with Me)","uri":"http://localhost:6601/?codec=flac&url=https%3A%2F%2Fstrm-m9-114.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D580f3da302eac74ace1271940fa75f8e5f1adbe9f008a9d62e77691c009c1706%2Clid%3D1528%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67964335%2F0%2F766255%2Ff1676db6.59074080.13.14510153%2Fflac&ext=.flac","trackType":"flac"} Jan 16 17:45:33 volumio volumio[1004]: verbose: CURRENT POSITION 91 Jan 16 17:45:33 volumio volumio[1004]: info: CoreStateMachine::syncState stateService play Jan 16 17:45:33 volumio volumio[1004]: info: CoreStateMachine::syncState currentStatus stop Jan 16 17:45:33 volumio volumio[1004]: info: ControllerMpd::pushState Jan 16 17:45:33 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Jan 16 17:45:33 volumio volumio[1004]: info: CorePlayQueue::getTrack 91 Jan 16 17:45:33 volumio volumio[1004]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":216,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Shut Up (And Sleep with Me)","artist":"Sin With Sebastian","album":"Shut Up (And Sleep with Me)","uri":"http://localhost:6601/?codec=flac&url=https%3A%2F%2Fstrm-m9-114.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D580f3da302eac74ace1271940fa75f8e5f1adbe9f008a9d62e77691c009c1706%2Clid%3D1528%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67964335%2F0%2F766255%2Ff1676db6.59074080.13.14510153%2Fflac&ext=.flac","trackType":"flac"} Jan 16 17:45:33 volumio volumio[1004]: verbose: CURRENT POSITION 91 Jan 16 17:45:33 volumio volumio[1004]: info: CoreStateMachine::syncState stateService play Jan 16 17:45:33 volumio volumio[1004]: info: CoreStateMachine::syncState currentStatus play Jan 16 17:45:33 volumio volumio[1004]: info: Received an update from plugin. extracting info from payload Jan 16 17:45:33 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:33 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 16 17:45:33 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:33 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:33 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:33 volumio volumio[1004]: info: ControllerMpd::pushState Jan 16 17:45:33 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Jan 16 17:45:33 volumio volumio[1004]: info: CorePlayQueue::getTrack 91 Jan 16 17:45:33 volumio volumio[1004]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":216,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Shut Up (And Sleep with Me)","artist":"Sin With Sebastian","album":"Shut Up (And Sleep with Me)","uri":"http://localhost:6601/?codec=flac&url=https%3A%2F%2Fstrm-m9-114.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D580f3da302eac74ace1271940fa75f8e5f1adbe9f008a9d62e77691c009c1706%2Clid%3D1528%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67964335%2F0%2F766255%2Ff1676db6.59074080.13.14510153%2Fflac&ext=.flac","trackType":"flac"} Jan 16 17:45:33 volumio volumio[1004]: verbose: CURRENT POSITION 91 Jan 16 17:45:33 volumio volumio[1004]: info: CoreStateMachine::syncState stateService play Jan 16 17:45:33 volumio volumio[1004]: info: CoreStateMachine::syncState currentStatus play Jan 16 17:45:33 volumio volumio[1004]: info: Received an update from plugin. extracting info from payload Jan 16 17:45:33 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:33 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:33 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:33 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:33 volumio volumio[1004]: info: ------------------------------ 59ms Jan 16 17:45:33 volumio volumio[1004]: info: ------------------------------ 74ms Jan 16 17:45:33 volumio volumio[1004]: info: ------------------------------ 73ms Jan 16 17:45:33 volumio volumio[1004]: info: sendMpdCommand playlistinfo took 48 milliseconds Jan 16 17:45:33 volumio volumio[1004]: info: sendMpdCommand playlistinfo took 46 milliseconds Jan 16 17:45:33 volumio volumio[1004]: info: sendMpdCommand playlistinfo took 46 milliseconds Jan 16 17:45:33 volumio volumio[1004]: verbose: ControllerMpd::parseTrackInfo Jan 16 17:45:33 volumio volumio[1004]: verbose: ControllerMpd::parseTrackInfo Jan 16 17:45:33 volumio volumio[1004]: verbose: ControllerMpd::parseTrackInfo Jan 16 17:45:33 volumio volumio[1004]: info: ControllerMpd::pushState Jan 16 17:45:33 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Jan 16 17:45:33 volumio volumio[1004]: info: CorePlayQueue::getTrack 91 Jan 16 17:45:33 volumio volumio[1004]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":216,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Shut Up (And Sleep with Me)","artist":"Sin With Sebastian","album":"Shut Up (And Sleep with Me)","uri":"http://localhost:6601/?codec=flac&url=https%3A%2F%2Fstrm-m9-114.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D580f3da302eac74ace1271940fa75f8e5f1adbe9f008a9d62e77691c009c1706%2Clid%3D1528%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67964335%2F0%2F766255%2Ff1676db6.59074080.13.14510153%2Fflac&ext=.flac","trackType":"flac"} Jan 16 17:45:33 volumio volumio[1004]: verbose: CURRENT POSITION 91 Jan 16 17:45:33 volumio volumio[1004]: info: CoreStateMachine::syncState stateService play Jan 16 17:45:33 volumio volumio[1004]: info: CoreStateMachine::syncState currentStatus play Jan 16 17:45:33 volumio volumio[1004]: info: Received an update from plugin. extracting info from payload Jan 16 17:45:33 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:33 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 16 17:45:33 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:33 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:33 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:33 volumio volumio[1004]: info: ControllerMpd::pushState Jan 16 17:45:33 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Jan 16 17:45:33 volumio volumio[1004]: info: CorePlayQueue::getTrack 91 Jan 16 17:45:33 volumio volumio[1004]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":216,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Shut Up (And Sleep with Me)","artist":"Sin With Sebastian","album":"Shut Up (And Sleep with Me)","uri":"http://localhost:6601/?codec=flac&url=https%3A%2F%2Fstrm-m9-114.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D580f3da302eac74ace1271940fa75f8e5f1adbe9f008a9d62e77691c009c1706%2Clid%3D1528%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67964335%2F0%2F766255%2Ff1676db6.59074080.13.14510153%2Fflac&ext=.flac","trackType":"flac"} Jan 16 17:45:33 volumio volumio[1004]: verbose: CURRENT POSITION 91 Jan 16 17:45:33 volumio volumio[1004]: info: CoreStateMachine::syncState stateService play Jan 16 17:45:33 volumio volumio[1004]: info: CoreStateMachine::syncState currentStatus play Jan 16 17:45:33 volumio volumio[1004]: info: Received an update from plugin. extracting info from payload Jan 16 17:45:33 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:33 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:33 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:33 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:33 volumio volumio[1004]: info: ControllerMpd::pushState Jan 16 17:45:33 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Jan 16 17:45:33 volumio volumio[1004]: info: CorePlayQueue::getTrack 91 Jan 16 17:45:33 volumio volumio[1004]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":216,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Shut Up (And Sleep with Me)","artist":"Sin With Sebastian","album":"Shut Up (And Sleep with Me)","uri":"http://localhost:6601/?codec=flac&url=https%3A%2F%2Fstrm-m9-114.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D580f3da302eac74ace1271940fa75f8e5f1adbe9f008a9d62e77691c009c1706%2Clid%3D1528%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67964335%2F0%2F766255%2Ff1676db6.59074080.13.14510153%2Fflac&ext=.flac","trackType":"flac"} Jan 16 17:45:33 volumio volumio[1004]: verbose: CURRENT POSITION 91 Jan 16 17:45:33 volumio volumio[1004]: info: CoreStateMachine::syncState stateService play Jan 16 17:45:33 volumio volumio[1004]: info: CoreStateMachine::syncState currentStatus play Jan 16 17:45:33 volumio volumio[1004]: info: Received an update from plugin. extracting info from payload Jan 16 17:45:33 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:33 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:33 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:33 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:33 volumio volumio[1004]: info: ------------------------------ 135ms Jan 16 17:45:33 volumio volumio[1004]: info: ------------------------------ 127ms Jan 16 17:45:33 volumio volumio[1004]: info: ------------------------------ 126ms Jan 16 17:45:33 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Jan 16 17:45:33 volumio volumio[1004]: info: CoreStateMachine::getQueue Jan 16 17:45:33 volumio volumio[1004]: info: CorePlayQueue::getQueue Jan 16 17:45:33 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Jan 16 17:45:33 volumio volumio[1004]: info: CoreStateMachine::getQueue Jan 16 17:45:33 volumio volumio[1004]: info: CorePlayQueue::getQueue Jan 16 17:45:33 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Jan 16 17:45:33 volumio volumio[1004]: info: CoreStateMachine::getQueue Jan 16 17:45:33 volumio volumio[1004]: info: CorePlayQueue::getQueue Jan 16 17:45:33 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Jan 16 17:45:33 volumio volumio[1004]: info: CoreStateMachine::getQueue Jan 16 17:45:33 volumio volumio[1004]: info: CorePlayQueue::getQueue Jan 16 17:45:33 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Jan 16 17:45:33 volumio volumio[1004]: info: CoreStateMachine::getQueue Jan 16 17:45:33 volumio volumio[1004]: info: CorePlayQueue::getQueue Jan 16 17:45:33 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Jan 16 17:45:33 volumio volumio[1004]: info: CoreStateMachine::getQueue Jan 16 17:45:33 volumio volumio[1004]: info: CorePlayQueue::getQueue Jan 16 17:45:33 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Jan 16 17:45:33 volumio volumio[1004]: info: CoreStateMachine::getQueue Jan 16 17:45:33 volumio volumio[1004]: info: CorePlayQueue::getQueue Jan 16 17:45:33 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Jan 16 17:45:33 volumio volumio[1004]: info: CoreStateMachine::getQueue Jan 16 17:45:33 volumio volumio[1004]: info: CorePlayQueue::getQueue Jan 16 17:45:33 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Jan 16 17:45:33 volumio volumio[1004]: info: CoreStateMachine::getQueue Jan 16 17:45:33 volumio volumio[1004]: info: CorePlayQueue::getQueue Jan 16 17:45:33 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Jan 16 17:45:33 volumio volumio[1004]: info: CoreStateMachine::getQueue Jan 16 17:45:33 volumio volumio[1004]: info: CorePlayQueue::getQueue Jan 16 17:45:36 volumio volumio[1004]: info: CoreCommandRouter::volumioAddQueueItems Jan 16 17:45:36 volumio volumio[1004]: info: CoreStateMachine::addQueueItems Jan 16 17:45:36 volumio volumio[1004]: info: CorePlayQueue::addQueueItems Jan 16 17:45:36 volumio volumio[1004]: info: Preload queue cleared Jan 16 17:45:36 volumio volumio[1004]: info: Adding Item to queue: yandex_music/track/28123141:3359356@user:onyourwave Jan 16 17:45:36 volumio volumio[1004]: info: Exploding uri yandex_music/track/28123141:3359356@user:onyourwave in service yandex_music Jan 16 17:45:36 volumio volumio[1004]: info: CoreCommandRouter::volumioAddQueueItems Jan 16 17:45:36 volumio volumio[1004]: info: CoreStateMachine::addQueueItems Jan 16 17:45:36 volumio volumio[1004]: info: CorePlayQueue::addQueueItems Jan 16 17:45:36 volumio volumio[1004]: info: Preload queue cleared Jan 16 17:45:36 volumio volumio[1004]: info: Adding Item to queue: yandex_music/track/809119:90336@user:onyourwave Jan 16 17:45:36 volumio volumio[1004]: info: Exploding uri yandex_music/track/809119:90336@user:onyourwave in service yandex_music Jan 16 17:45:36 volumio volumio[1004]: info: CoreCommandRouter::volumioAddQueueItems Jan 16 17:45:36 volumio volumio[1004]: info: CoreStateMachine::addQueueItems Jan 16 17:45:36 volumio volumio[1004]: info: CorePlayQueue::addQueueItems Jan 16 17:45:36 volumio volumio[1004]: info: Preload queue cleared Jan 16 17:45:36 volumio volumio[1004]: info: Adding Item to queue: yandex_music/track/30034566:3623844@user:onyourwave Jan 16 17:45:36 volumio volumio[1004]: info: Exploding uri yandex_music/track/30034566:3623844@user:onyourwave in service yandex_music Jan 16 17:45:36 volumio volumio[1004]: info: CoreCommandRouter::volumioPushQueue Jan 16 17:45:36 volumio volumio[1004]: info: CorePlayQueue::saveQueue Jan 16 17:45:36 volumio volumio[1004]: info: CoreCommandRouter::volumioPushQueue Jan 16 17:45:36 volumio volumio[1004]: info: CorePlayQueue::saveQueue Jan 16 17:45:36 volumio volumio[1004]: info: CoreCommandRouter::volumioPushQueue Jan 16 17:45:36 volumio volumio[1004]: info: CorePlayQueue::saveQueue Jan 16 17:45:36 volumio volumio[1004]: info: CoreStateMachine::updateTrackBlock Jan 16 17:45:36 volumio volumio[1004]: info: CorePlayQueue::getTrackBlock Jan 16 17:45:36 volumio volumio[1004]: info: CoreStateMachine::updateTrackBlock Jan 16 17:45:36 volumio volumio[1004]: info: CorePlayQueue::getTrackBlock Jan 16 17:45:36 volumio volumio[1004]: info: CoreStateMachine::updateTrackBlock Jan 16 17:45:36 volumio volumio[1004]: info: CorePlayQueue::getTrackBlock Jan 16 17:45:37 volumio volumio[1004]: info: CoreCommandRouter::volumioNext Jan 16 17:45:37 volumio volumio[1004]: info: CoreStateMachine::next Jan 16 17:45:37 volumio volumio[1004]: info: ControllerMpd::next Jan 16 17:45:37 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand next Jan 16 17:45:37 volumio volumio[1004]: info: Jan 16 17:45:37 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:37 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:37 volumio volumio[1004]: info: Jan 16 17:45:37 volumio volumio[1004]: ---------------------------- MPD announces state update: player Jan 16 17:45:37 volumio volumio[1004]: info: sendMpdCommand next took 13 milliseconds Jan 16 17:45:37 volumio volumio[1004]: info: ControllerMpd::getState Jan 16 17:45:37 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Jan 16 17:45:37 volumio volumio[1004]: info: Jan 16 17:45:37 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:37 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:37 volumio volumio[1004]: info: Jan 16 17:45:37 volumio volumio[1004]: ---------------------------- MPD announces state update: player Jan 16 17:45:37 volumio volumio[1004]: info: ControllerMpd::getState Jan 16 17:45:37 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Jan 16 17:45:37 volumio volumio[1004]: info: Jan 16 17:45:37 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:37 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:37 volumio volumio[1004]: info: Jan 16 17:45:37 volumio volumio[1004]: ---------------------------- MPD announces state update: player Jan 16 17:45:37 volumio volumio[1004]: info: ControllerMpd::getState Jan 16 17:45:37 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Jan 16 17:45:37 volumio volumio[1004]: error: updateQueue error: null Jan 16 17:45:37 volumio volumio[1004]: info: ------------------------------ 20ms Jan 16 17:45:37 volumio volumio[1004]: info: sendMpdCommand status took 20 milliseconds Jan 16 17:45:37 volumio volumio[1004]: info: ------------------------------ 17ms Jan 16 17:45:37 volumio volumio[1004]: info: sendMpdCommand status took 14 milliseconds Jan 16 17:45:37 volumio volumio[1004]: verbose: ControllerMpd::parseState Jan 16 17:45:37 volumio volumio[1004]: verbose: ControllerMpd::parseState Jan 16 17:45:37 volumio volumio[1004]: info: ControllerMpd::pushState Jan 16 17:45:37 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Jan 16 17:45:37 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:37 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 16 17:45:37 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:37 volumio volumio[1004]: info: CorePlayQueue::getTrack 91 Jan 16 17:45:37 volumio volumio[1004]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 16 17:45:37 volumio volumio[1004]: verbose: CURRENT POSITION 91 Jan 16 17:45:37 volumio volumio[1004]: info: CoreStateMachine::syncState stateService stop Jan 16 17:45:37 volumio volumio[1004]: info: CoreStateMachine::syncState currentStatus play Jan 16 17:45:37 volumio volumio[1004]: info: CoreStateMachine::play index undefined Jan 16 17:45:37 volumio volumio[1004]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 16 17:45:37 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:37 volumio volumio[1004]: info: CorePlayQueue::getTrack 92 Jan 16 17:45:37 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 16 17:45:37 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:37 volumio volumio[1004]: info: ControllerMpd::pushState Jan 16 17:45:37 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Jan 16 17:45:37 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:37 volumio volumio[1004]: info: CorePlayQueue::getTrack 92 Jan 16 17:45:37 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:37 volumio volumio[1004]: info: CorePlayQueue::getTrack 92 Jan 16 17:45:37 volumio volumio[1004]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd Jan 16 17:45:37 volumio volumio[1004]: info: CorePlayQueue::getTrack 92 Jan 16 17:45:37 volumio volumio[1004]: info: CoreStateMachine::startPlaybackTimer Jan 16 17:45:37 volumio volumio[1004]: info: CorePlayQueue::getTrack 92 Jan 16 17:45:37 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand stop Jan 16 17:45:37 volumio volumio[1004]: info: ------------------------------ 55ms Jan 16 17:45:37 volumio volumio[1004]: info: ------------------------------ 49ms Jan 16 17:45:37 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:37 volumio volumio[1004]: info: CorePlayQueue::getTrack 92 Jan 16 17:45:37 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 16 17:45:37 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:37 volumio volumio[1004]: error: updateQueue error: null Jan 16 17:45:37 volumio volumio[1004]: info: ------------------------------ 64ms Jan 16 17:45:37 volumio volumio[1004]: info: sendMpdCommand status took 59 milliseconds Jan 16 17:45:37 volumio volumio[1004]: info: sendMpdCommand stop took 21 milliseconds Jan 16 17:45:37 volumio volumio[1004]: verbose: ControllerMpd::parseState Jan 16 17:45:37 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand clear Jan 16 17:45:37 volumio volumio[1004]: info: ControllerMpd::pushState Jan 16 17:45:37 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Jan 16 17:45:37 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:37 volumio volumio[1004]: info: CorePlayQueue::getTrack 92 Jan 16 17:45:37 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:37 volumio volumio[1004]: info: CorePlayQueue::getTrack 92 Jan 16 17:45:37 volumio volumio[1004]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd Jan 16 17:45:37 volumio volumio[1004]: info: ------------------------------ 73ms Jan 16 17:45:37 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Jan 16 17:45:37 volumio volumio[1004]: info: CoreStateMachine::getQueue Jan 16 17:45:37 volumio volumio[1004]: info: CorePlayQueue::getQueue Jan 16 17:45:37 volumio volumio[1004]: info: Jan 16 17:45:37 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:37 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:37 volumio volumio[1004]: info: sendMpdCommand clear took 26 milliseconds Jan 16 17:45:37 volumio volumio[1004]: info: Jan 16 17:45:37 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:37 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:37 volumio volumio[1004]: info: Jan 16 17:45:37 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:37 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:37 volumio volumio[1004]: error: updateQueue error: null Jan 16 17:45:37 volumio volumio[1004]: error: updateQueue error: null Jan 16 17:45:37 volumio volumio[1004]: error: updateQueue error: null Jan 16 17:45:37 volumio volumio[1004]: info: ------------------------------ 17ms Jan 16 17:45:37 volumio volumio[1004]: info: ------------------------------ 6ms Jan 16 17:45:37 volumio volumio[1004]: info: ------------------------------ 5ms Jan 16 17:45:37 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=flac&url=https%3A%2F%2Fstrm-mar-64.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D09b40cdc531f04fe6c1d3a6dc53d9b38ed122a37017e6a40455ab1a97303fc0e%2Clid%3D1559%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6797600b%2F0%2F5235176%2Fad9f6704.52947065.18.15867057%2Fflac&ext=.flac" Jan 16 17:45:37 volumio volumio[1004]: info: Jan 16 17:45:37 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:37 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:37 volumio volumio[1004]: info: sendMpdCommand addid "http://localhost:6601/?codec=flac&url=https%3A%2F%2Fstrm-mar-64.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D09b40cdc531f04fe6c1d3a6dc53d9b38ed122a37017e6a40455ab1a97303fc0e%2Clid%3D1559%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6797600b%2F0%2F5235176%2Fad9f6704.52947065.18.15867057%2Fflac&ext=.flac" took 3 milliseconds Jan 16 17:45:37 volumio volumio[1004]: verbose: MPD COMMAND [object Object] Jan 16 17:45:37 volumio volumio[1004]: verbose: MPD COMMAND [object Object] Jan 16 17:45:37 volumio volumio[1004]: verbose: MPD COMMAND [object Object] Jan 16 17:45:37 volumio volumio[1004]: info: Jan 16 17:45:37 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:37 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:37 volumio volumio[1004]: info: Jan 16 17:45:37 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:37 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:37 volumio volumio[1004]: info: Jan 16 17:45:37 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:37 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:37 volumio volumio[1004]: info: ------------------------------ 10ms Jan 16 17:45:37 volumio volumio[1004]: info: ------------------------------ 6ms Jan 16 17:45:37 volumio volumio[1004]: info: ------------------------------ 5ms Jan 16 17:45:37 volumio volumio[1004]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 16 17:45:37 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand play Jan 16 17:45:37 volumio volumio[1004]: info: Jan 16 17:45:37 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:37 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:37 volumio volumio[1004]: info: ------------------------------ 18ms Jan 16 17:45:37 volumio volumio[1004]: info: sendMpdCommand play took 16 milliseconds Jan 16 17:45:37 volumio volumio[1004]: info: ------------------------------ 15ms Jan 16 17:45:37 volumio volumio[1004]: info: Jan 16 17:45:37 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:37 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:37 volumio volumio[1004]: info: ------------------------------ 11ms Jan 16 17:45:38 volumio volumio[1004]: info: Jan 16 17:45:38 volumio volumio[1004]: ---------------------------- MPD announces state update: player Jan 16 17:45:38 volumio volumio[1004]: info: ControllerMpd::getState Jan 16 17:45:38 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Jan 16 17:45:38 volumio volumio[1004]: info: Jan 16 17:45:38 volumio volumio[1004]: ---------------------------- MPD announces state update: player Jan 16 17:45:38 volumio volumio[1004]: info: ControllerMpd::getState Jan 16 17:45:38 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Jan 16 17:45:38 volumio volumio[1004]: info: Jan 16 17:45:38 volumio volumio[1004]: ---------------------------- MPD announces state update: player Jan 16 17:45:38 volumio volumio[1004]: info: ControllerMpd::getState Jan 16 17:45:38 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Jan 16 17:45:38 volumio volumio[1004]: info: Jan 16 17:45:38 volumio volumio[1004]: ---------------------------- MPD announces state update: player Jan 16 17:45:38 volumio volumio[1004]: info: ControllerMpd::getState Jan 16 17:45:38 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Jan 16 17:45:38 volumio volumio[1004]: info: Jan 16 17:45:38 volumio volumio[1004]: ---------------------------- MPD announces state update: player Jan 16 17:45:38 volumio volumio[1004]: info: ControllerMpd::getState Jan 16 17:45:38 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Jan 16 17:45:38 volumio volumio[1004]: info: Jan 16 17:45:38 volumio volumio[1004]: ---------------------------- MPD announces state update: player Jan 16 17:45:38 volumio volumio[1004]: info: sendMpdCommand status took 12 milliseconds Jan 16 17:45:38 volumio volumio[1004]: info: sendMpdCommand status took 11 milliseconds Jan 16 17:45:38 volumio volumio[1004]: info: sendMpdCommand status took 10 milliseconds Jan 16 17:45:38 volumio volumio[1004]: info: ControllerMpd::getState Jan 16 17:45:38 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Jan 16 17:45:38 volumio volumio[1004]: info: sendMpdCommand status took 7 milliseconds Jan 16 17:45:38 volumio volumio[1004]: verbose: ControllerMpd::parseState Jan 16 17:45:38 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 16 17:45:38 volumio volumio[1004]: verbose: ControllerMpd::parseState Jan 16 17:45:38 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 16 17:45:38 volumio volumio[1004]: verbose: ControllerMpd::parseState Jan 16 17:45:38 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 16 17:45:38 volumio volumio[1004]: verbose: ControllerMpd::parseState Jan 16 17:45:38 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 16 17:45:38 volumio volumio[1004]: info: sendMpdCommand status took 12 milliseconds Jan 16 17:45:38 volumio volumio[1004]: info: sendMpdCommand status took 9 milliseconds Jan 16 17:45:38 volumio volumio[1004]: info: sendMpdCommand playlistinfo took 7 milliseconds Jan 16 17:45:38 volumio volumio[1004]: info: sendMpdCommand playlistinfo took 7 milliseconds Jan 16 17:45:38 volumio volumio[1004]: info: sendMpdCommand playlistinfo took 6 milliseconds Jan 16 17:45:38 volumio volumio[1004]: info: sendMpdCommand playlistinfo took 6 milliseconds Jan 16 17:45:38 volumio volumio[1004]: verbose: ControllerMpd::parseState Jan 16 17:45:38 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 16 17:45:38 volumio volumio[1004]: verbose: ControllerMpd::parseState Jan 16 17:45:38 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 16 17:45:38 volumio volumio[1004]: verbose: ControllerMpd::parseTrackInfo Jan 16 17:45:38 volumio volumio[1004]: verbose: ControllerMpd::parseTrackInfo Jan 16 17:45:38 volumio volumio[1004]: info: ControllerMpd::pushState Jan 16 17:45:38 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Jan 16 17:45:38 volumio volumio[1004]: info: CorePlayQueue::getTrack 92 Jan 16 17:45:38 volumio volumio[1004]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":258,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"I Need You","artist":"Gysnoize","album":"Lounge With Me (Romantic Chillout)","uri":"http://localhost:6601/?codec=flac&url=https%3A%2F%2Fstrm-mar-64.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D09b40cdc531f04fe6c1d3a6dc53d9b38ed122a37017e6a40455ab1a97303fc0e%2Clid%3D1559%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6797600b%2F0%2F5235176%2Fad9f6704.52947065.18.15867057%2Fflac&ext=.flac","trackType":"flac"} Jan 16 17:45:38 volumio volumio[1004]: verbose: CURRENT POSITION 92 Jan 16 17:45:38 volumio volumio[1004]: info: CoreStateMachine::syncState stateService play Jan 16 17:45:38 volumio volumio[1004]: info: CoreStateMachine::syncState currentStatus stop Jan 16 17:45:38 volumio volumio[1004]: info: ControllerMpd::pushState Jan 16 17:45:38 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Jan 16 17:45:38 volumio volumio[1004]: info: CorePlayQueue::getTrack 92 Jan 16 17:45:38 volumio volumio[1004]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":258,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"I Need You","artist":"Gysnoize","album":"Lounge With Me (Romantic Chillout)","uri":"http://localhost:6601/?codec=flac&url=https%3A%2F%2Fstrm-mar-64.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D09b40cdc531f04fe6c1d3a6dc53d9b38ed122a37017e6a40455ab1a97303fc0e%2Clid%3D1559%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6797600b%2F0%2F5235176%2Fad9f6704.52947065.18.15867057%2Fflac&ext=.flac","trackType":"flac"} Jan 16 17:45:38 volumio volumio[1004]: verbose: CURRENT POSITION 92 Jan 16 17:45:38 volumio volumio[1004]: info: CoreStateMachine::syncState stateService play Jan 16 17:45:38 volumio volumio[1004]: info: CoreStateMachine::syncState currentStatus play Jan 16 17:45:38 volumio volumio[1004]: info: Received an update from plugin. extracting info from payload Jan 16 17:45:38 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:38 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 16 17:45:38 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:38 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:38 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:38 volumio volumio[1004]: info: ControllerMpd::pushState Jan 16 17:45:38 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Jan 16 17:45:38 volumio volumio[1004]: info: CorePlayQueue::getTrack 92 Jan 16 17:45:38 volumio volumio[1004]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":258,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"I Need You","artist":"Gysnoize","album":"Lounge With Me (Romantic Chillout)","uri":"http://localhost:6601/?codec=flac&url=https%3A%2F%2Fstrm-mar-64.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D09b40cdc531f04fe6c1d3a6dc53d9b38ed122a37017e6a40455ab1a97303fc0e%2Clid%3D1559%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6797600b%2F0%2F5235176%2Fad9f6704.52947065.18.15867057%2Fflac&ext=.flac","trackType":"flac"} Jan 16 17:45:38 volumio volumio[1004]: verbose: CURRENT POSITION 92 Jan 16 17:45:38 volumio volumio[1004]: info: CoreStateMachine::syncState stateService play Jan 16 17:45:38 volumio volumio[1004]: info: CoreStateMachine::syncState currentStatus play Jan 16 17:45:38 volumio volumio[1004]: info: Received an update from plugin. extracting info from payload Jan 16 17:45:38 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:38 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:38 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:38 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:38 volumio volumio[1004]: info: ControllerMpd::pushState Jan 16 17:45:38 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Jan 16 17:45:38 volumio volumio[1004]: info: CorePlayQueue::getTrack 92 Jan 16 17:45:38 volumio volumio[1004]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":258,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"I Need You","artist":"Gysnoize","album":"Lounge With Me (Romantic Chillout)","uri":"http://localhost:6601/?codec=flac&url=https%3A%2F%2Fstrm-mar-64.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D09b40cdc531f04fe6c1d3a6dc53d9b38ed122a37017e6a40455ab1a97303fc0e%2Clid%3D1559%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6797600b%2F0%2F5235176%2Fad9f6704.52947065.18.15867057%2Fflac&ext=.flac","trackType":"flac"} Jan 16 17:45:38 volumio volumio[1004]: verbose: CURRENT POSITION 92 Jan 16 17:45:38 volumio volumio[1004]: info: CoreStateMachine::syncState stateService play Jan 16 17:45:38 volumio volumio[1004]: info: CoreStateMachine::syncState currentStatus play Jan 16 17:45:38 volumio volumio[1004]: info: Received an update from plugin. extracting info from payload Jan 16 17:45:38 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:38 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:38 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:38 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:38 volumio volumio[1004]: info: ------------------------------ 55ms Jan 16 17:45:38 volumio volumio[1004]: info: ------------------------------ 72ms Jan 16 17:45:38 volumio volumio[1004]: info: ------------------------------ 71ms Jan 16 17:45:38 volumio volumio[1004]: info: ------------------------------ 69ms Jan 16 17:45:38 volumio volumio[1004]: info: sendMpdCommand playlistinfo took 61 milliseconds Jan 16 17:45:38 volumio volumio[1004]: info: sendMpdCommand playlistinfo took 60 milliseconds Jan 16 17:45:38 volumio volumio[1004]: verbose: ControllerMpd::parseTrackInfo Jan 16 17:45:38 volumio volumio[1004]: info: ControllerMpd::pushState Jan 16 17:45:38 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Jan 16 17:45:38 volumio volumio[1004]: info: CorePlayQueue::getTrack 92 Jan 16 17:45:38 volumio volumio[1004]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":258,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"I Need You","artist":"Gysnoize","album":"Lounge With Me (Romantic Chillout)","uri":"http://localhost:6601/?codec=flac&url=https%3A%2F%2Fstrm-mar-64.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D09b40cdc531f04fe6c1d3a6dc53d9b38ed122a37017e6a40455ab1a97303fc0e%2Clid%3D1559%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6797600b%2F0%2F5235176%2Fad9f6704.52947065.18.15867057%2Fflac&ext=.flac","trackType":"flac"} Jan 16 17:45:38 volumio volumio[1004]: verbose: CURRENT POSITION 92 Jan 16 17:45:38 volumio volumio[1004]: info: CoreStateMachine::syncState stateService play Jan 16 17:45:38 volumio volumio[1004]: info: CoreStateMachine::syncState currentStatus play Jan 16 17:45:38 volumio volumio[1004]: info: Received an update from plugin. extracting info from payload Jan 16 17:45:38 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:38 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 16 17:45:38 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:38 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:38 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:38 volumio volumio[1004]: info: ControllerMpd::pushState Jan 16 17:45:38 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Jan 16 17:45:38 volumio volumio[1004]: info: CorePlayQueue::getTrack 92 Jan 16 17:45:38 volumio volumio[1004]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":258,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"I Need You","artist":"Gysnoize","album":"Lounge With Me (Romantic Chillout)","uri":"http://localhost:6601/?codec=flac&url=https%3A%2F%2Fstrm-mar-64.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D09b40cdc531f04fe6c1d3a6dc53d9b38ed122a37017e6a40455ab1a97303fc0e%2Clid%3D1559%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6797600b%2F0%2F5235176%2Fad9f6704.52947065.18.15867057%2Fflac&ext=.flac","trackType":"flac"} Jan 16 17:45:38 volumio volumio[1004]: verbose: CURRENT POSITION 92 Jan 16 17:45:38 volumio volumio[1004]: info: CoreStateMachine::syncState stateService play Jan 16 17:45:38 volumio volumio[1004]: info: CoreStateMachine::syncState currentStatus play Jan 16 17:45:38 volumio volumio[1004]: info: Received an update from plugin. extracting info from payload Jan 16 17:45:38 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:38 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:38 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:38 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:38 volumio volumio[1004]: info: ------------------------------ 116ms Jan 16 17:45:38 volumio volumio[1004]: info: ------------------------------ 114ms Jan 16 17:45:38 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Jan 16 17:45:38 volumio volumio[1004]: info: CoreStateMachine::getQueue Jan 16 17:45:38 volumio volumio[1004]: info: CorePlayQueue::getQueue Jan 16 17:45:38 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Jan 16 17:45:38 volumio volumio[1004]: info: CoreStateMachine::getQueue Jan 16 17:45:38 volumio volumio[1004]: info: CorePlayQueue::getQueue Jan 16 17:45:38 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Jan 16 17:45:38 volumio volumio[1004]: info: CoreStateMachine::getQueue Jan 16 17:45:38 volumio volumio[1004]: info: CorePlayQueue::getQueue Jan 16 17:45:38 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Jan 16 17:45:38 volumio volumio[1004]: info: CoreStateMachine::getQueue Jan 16 17:45:38 volumio volumio[1004]: info: CorePlayQueue::getQueue Jan 16 17:45:38 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Jan 16 17:45:38 volumio volumio[1004]: info: CoreStateMachine::getQueue Jan 16 17:45:38 volumio volumio[1004]: info: CorePlayQueue::getQueue Jan 16 17:45:38 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Jan 16 17:45:38 volumio volumio[1004]: info: CoreStateMachine::getQueue Jan 16 17:45:38 volumio volumio[1004]: info: CorePlayQueue::getQueue Jan 16 17:45:38 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Jan 16 17:45:38 volumio volumio[1004]: info: CoreStateMachine::getQueue Jan 16 17:45:38 volumio volumio[1004]: info: CorePlayQueue::getQueue Jan 16 17:45:38 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Jan 16 17:45:38 volumio volumio[1004]: info: CoreStateMachine::getQueue Jan 16 17:45:38 volumio volumio[1004]: info: CorePlayQueue::getQueue Jan 16 17:45:38 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Jan 16 17:45:38 volumio volumio[1004]: info: CoreStateMachine::getQueue Jan 16 17:45:38 volumio volumio[1004]: info: CorePlayQueue::getQueue Jan 16 17:45:38 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Jan 16 17:45:38 volumio volumio[1004]: info: CoreStateMachine::getQueue Jan 16 17:45:38 volumio volumio[1004]: info: CorePlayQueue::getQueue Jan 16 17:45:39 volumio volumio[1004]: info: CoreCommandRouter::volumioNext Jan 16 17:45:39 volumio volumio[1004]: info: CoreStateMachine::next Jan 16 17:45:39 volumio volumio[1004]: info: ControllerMpd::next Jan 16 17:45:39 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand next Jan 16 17:45:39 volumio volumio[1004]: info: Jan 16 17:45:39 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:39 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:39 volumio volumio[1004]: info: Jan 16 17:45:39 volumio volumio[1004]: ---------------------------- MPD announces state update: player Jan 16 17:45:39 volumio volumio[1004]: info: sendMpdCommand next took 22 milliseconds Jan 16 17:45:39 volumio volumio[1004]: info: ControllerMpd::getState Jan 16 17:45:39 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Jan 16 17:45:39 volumio volumio[1004]: error: updateQueue error: null Jan 16 17:45:39 volumio volumio[1004]: info: ------------------------------ 13ms Jan 16 17:45:39 volumio volumio[1004]: info: Jan 16 17:45:39 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:39 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:39 volumio volumio[1004]: info: Jan 16 17:45:39 volumio volumio[1004]: ---------------------------- MPD announces state update: player Jan 16 17:45:39 volumio volumio[1004]: info: ControllerMpd::getState Jan 16 17:45:39 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Jan 16 17:45:39 volumio volumio[1004]: info: Jan 16 17:45:39 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:39 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:39 volumio volumio[1004]: info: Jan 16 17:45:39 volumio volumio[1004]: ---------------------------- MPD announces state update: player Jan 16 17:45:39 volumio volumio[1004]: info: ControllerMpd::getState Jan 16 17:45:39 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Jan 16 17:45:39 volumio volumio[1004]: error: updateQueue error: null Jan 16 17:45:39 volumio volumio[1004]: error: updateQueue error: null Jan 16 17:45:39 volumio volumio[1004]: info: sendMpdCommand status took 17 milliseconds Jan 16 17:45:39 volumio volumio[1004]: info: ------------------------------ 11ms Jan 16 17:45:39 volumio volumio[1004]: info: sendMpdCommand status took 9 milliseconds Jan 16 17:45:39 volumio volumio[1004]: info: ------------------------------ 7ms Jan 16 17:45:39 volumio volumio[1004]: info: sendMpdCommand status took 5 milliseconds Jan 16 17:45:39 volumio volumio[1004]: verbose: ControllerMpd::parseState Jan 16 17:45:39 volumio volumio[1004]: verbose: ControllerMpd::parseState Jan 16 17:45:39 volumio volumio[1004]: verbose: ControllerMpd::parseState Jan 16 17:45:39 volumio volumio[1004]: info: ControllerMpd::pushState Jan 16 17:45:39 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Jan 16 17:45:39 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:39 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 16 17:45:39 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:39 volumio volumio[1004]: info: CorePlayQueue::getTrack 92 Jan 16 17:45:39 volumio volumio[1004]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 16 17:45:39 volumio volumio[1004]: verbose: CURRENT POSITION 92 Jan 16 17:45:39 volumio volumio[1004]: info: CoreStateMachine::syncState stateService stop Jan 16 17:45:39 volumio volumio[1004]: info: CoreStateMachine::syncState currentStatus play Jan 16 17:45:39 volumio volumio[1004]: info: CoreStateMachine::play index undefined Jan 16 17:45:39 volumio volumio[1004]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 16 17:45:39 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:39 volumio volumio[1004]: info: CorePlayQueue::getTrack 93 Jan 16 17:45:39 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 16 17:45:39 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:39 volumio volumio[1004]: info: ControllerMpd::pushState Jan 16 17:45:39 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Jan 16 17:45:39 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:39 volumio volumio[1004]: info: CorePlayQueue::getTrack 93 Jan 16 17:45:39 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:39 volumio volumio[1004]: info: CorePlayQueue::getTrack 93 Jan 16 17:45:39 volumio volumio[1004]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd Jan 16 17:45:39 volumio volumio[1004]: info: ControllerMpd::pushState Jan 16 17:45:39 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Jan 16 17:45:39 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:39 volumio volumio[1004]: info: CorePlayQueue::getTrack 93 Jan 16 17:45:39 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:39 volumio volumio[1004]: info: CorePlayQueue::getTrack 93 Jan 16 17:45:39 volumio volumio[1004]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd Jan 16 17:45:39 volumio volumio[1004]: info: CorePlayQueue::getTrack 93 Jan 16 17:45:39 volumio volumio[1004]: info: CoreStateMachine::startPlaybackTimer Jan 16 17:45:39 volumio volumio[1004]: info: CorePlayQueue::getTrack 93 Jan 16 17:45:39 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand stop Jan 16 17:45:39 volumio volumio[1004]: info: ------------------------------ 68ms Jan 16 17:45:39 volumio volumio[1004]: info: ------------------------------ 55ms Jan 16 17:45:39 volumio volumio[1004]: info: ------------------------------ 52ms Jan 16 17:45:39 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:39 volumio volumio[1004]: info: CorePlayQueue::getTrack 93 Jan 16 17:45:39 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 16 17:45:39 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:39 volumio volumio[1004]: info: sendMpdCommand stop took 25 milliseconds Jan 16 17:45:39 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand clear Jan 16 17:45:39 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Jan 16 17:45:39 volumio volumio[1004]: info: CoreStateMachine::getQueue Jan 16 17:45:39 volumio volumio[1004]: info: CorePlayQueue::getQueue Jan 16 17:45:39 volumio volumio[1004]: info: Jan 16 17:45:39 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:39 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:39 volumio volumio[1004]: info: sendMpdCommand clear took 20 milliseconds Jan 16 17:45:39 volumio volumio[1004]: info: Jan 16 17:45:39 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:39 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:39 volumio volumio[1004]: info: Jan 16 17:45:39 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:39 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:39 volumio volumio[1004]: error: updateQueue error: null Jan 16 17:45:39 volumio volumio[1004]: info: ------------------------------ 27ms Jan 16 17:45:39 volumio volumio[1004]: error: updateQueue error: null Jan 16 17:45:39 volumio volumio[1004]: info: ------------------------------ 9ms Jan 16 17:45:39 volumio volumio[1004]: info: ------------------------------ 7ms Jan 16 17:45:39 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-79.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D988f241acad87b81487ad438c4e56270d46e10f134983686c1449dbab03429f5%2Clid%3D1528%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67970138%2F0%2F61381%2F7eb16f1e.83335837.5.56021986%2F320.mp3&ext=.mp3" Jan 16 17:45:39 volumio volumio[1004]: info: Jan 16 17:45:39 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:39 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:39 volumio volumio[1004]: info: sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-79.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D988f241acad87b81487ad438c4e56270d46e10f134983686c1449dbab03429f5%2Clid%3D1528%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67970138%2F0%2F61381%2F7eb16f1e.83335837.5.56021986%2F320.mp3&ext=.mp3" took 5 milliseconds Jan 16 17:45:39 volumio volumio[1004]: verbose: MPD COMMAND [object Object] Jan 16 17:45:39 volumio volumio[1004]: verbose: MPD COMMAND [object Object] Jan 16 17:45:39 volumio volumio[1004]: verbose: MPD COMMAND [object Object] Jan 16 17:45:39 volumio volumio[1004]: info: Jan 16 17:45:39 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:39 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:39 volumio volumio[1004]: info: Jan 16 17:45:39 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:39 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:39 volumio volumio[1004]: info: Jan 16 17:45:39 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:39 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:39 volumio volumio[1004]: info: ------------------------------ 9ms Jan 16 17:45:39 volumio volumio[1004]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 16 17:45:39 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand play Jan 16 17:45:39 volumio volumio[1004]: info: ------------------------------ 9ms Jan 16 17:45:39 volumio volumio[1004]: info: ------------------------------ 8ms Jan 16 17:45:39 volumio volumio[1004]: info: Jan 16 17:45:39 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:39 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:39 volumio volumio[1004]: info: Jan 16 17:45:39 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:39 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:39 volumio volumio[1004]: info: ------------------------------ 13ms Jan 16 17:45:39 volumio volumio[1004]: info: sendMpdCommand play took 12 milliseconds Jan 16 17:45:39 volumio volumio[1004]: info: ------------------------------ 7ms Jan 16 17:45:39 volumio volumio[1004]: info: ------------------------------ 5ms Jan 16 17:45:40 volumio volumio[1004]: info: Jan 16 17:45:40 volumio volumio[1004]: ---------------------------- MPD announces state update: player Jan 16 17:45:40 volumio volumio[1004]: info: ControllerMpd::getState Jan 16 17:45:40 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Jan 16 17:45:40 volumio volumio[1004]: info: Jan 16 17:45:40 volumio volumio[1004]: ---------------------------- MPD announces state update: player Jan 16 17:45:40 volumio volumio[1004]: info: ControllerMpd::getState Jan 16 17:45:40 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Jan 16 17:45:40 volumio volumio[1004]: info: Jan 16 17:45:40 volumio volumio[1004]: ---------------------------- MPD announces state update: player Jan 16 17:45:40 volumio volumio[1004]: info: ControllerMpd::getState Jan 16 17:45:40 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Jan 16 17:45:40 volumio volumio[1004]: info: CoreCommandRouter::volumioAddQueueItems Jan 16 17:45:40 volumio volumio[1004]: info: CoreStateMachine::addQueueItems Jan 16 17:45:40 volumio volumio[1004]: info: CorePlayQueue::addQueueItems Jan 16 17:45:40 volumio volumio[1004]: info: Preload queue cleared Jan 16 17:45:40 volumio volumio[1004]: info: Adding Item to queue: yandex_music/track/51933585:7275556@user:onyourwave Jan 16 17:45:40 volumio volumio[1004]: info: Exploding uri yandex_music/track/51933585:7275556@user:onyourwave in service yandex_music Jan 16 17:45:40 volumio volumio[1004]: info: CoreCommandRouter::volumioAddQueueItems Jan 16 17:45:40 volumio volumio[1004]: info: CoreStateMachine::addQueueItems Jan 16 17:45:40 volumio volumio[1004]: info: CorePlayQueue::addQueueItems Jan 16 17:45:40 volumio volumio[1004]: info: Preload queue cleared Jan 16 17:45:40 volumio volumio[1004]: info: Adding Item to queue: yandex_music/track/127814499:31965604@user:onyourwave Jan 16 17:45:40 volumio volumio[1004]: info: Exploding uri yandex_music/track/127814499:31965604@user:onyourwave in service yandex_music Jan 16 17:45:40 volumio volumio[1004]: info: CoreCommandRouter::volumioPushQueue Jan 16 17:45:40 volumio volumio[1004]: info: CorePlayQueue::saveQueue Jan 16 17:45:40 volumio volumio[1004]: info: CoreCommandRouter::volumioPushQueue Jan 16 17:45:40 volumio volumio[1004]: info: CorePlayQueue::saveQueue Jan 16 17:45:40 volumio volumio[1004]: info: CoreStateMachine::updateTrackBlock Jan 16 17:45:40 volumio volumio[1004]: info: CorePlayQueue::getTrackBlock Jan 16 17:45:40 volumio volumio[1004]: info: CoreStateMachine::updateTrackBlock Jan 16 17:45:40 volumio volumio[1004]: info: CorePlayQueue::getTrackBlock Jan 16 17:45:41 volumio volumio[1004]: info: Jan 16 17:45:41 volumio volumio[1004]: ---------------------------- MPD announces state update: player Jan 16 17:45:41 volumio volumio[1004]: info: sendMpdCommand status took 128 milliseconds Jan 16 17:45:41 volumio volumio[1004]: info: ControllerMpd::getState Jan 16 17:45:41 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Jan 16 17:45:41 volumio volumio[1004]: info: sendMpdCommand status took 126 milliseconds Jan 16 17:45:41 volumio volumio[1004]: info: sendMpdCommand status took 124 milliseconds Jan 16 17:45:41 volumio volumio[1004]: verbose: ControllerMpd::parseState Jan 16 17:45:41 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 16 17:45:41 volumio volumio[1004]: verbose: ControllerMpd::parseState Jan 16 17:45:41 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 16 17:45:41 volumio volumio[1004]: verbose: ControllerMpd::parseState Jan 16 17:45:41 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 16 17:45:41 volumio volumio[1004]: info: Jan 16 17:45:41 volumio volumio[1004]: ---------------------------- MPD announces state update: player Jan 16 17:45:41 volumio volumio[1004]: info: ControllerMpd::getState Jan 16 17:45:41 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Jan 16 17:45:41 volumio volumio[1004]: info: Jan 16 17:45:41 volumio volumio[1004]: ---------------------------- MPD announces state update: player Jan 16 17:45:41 volumio volumio[1004]: info: ControllerMpd::getState Jan 16 17:45:41 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Jan 16 17:45:41 volumio volumio[1004]: info: sendMpdCommand status took 122 milliseconds Jan 16 17:45:41 volumio volumio[1004]: info: sendMpdCommand playlistinfo took 121 milliseconds Jan 16 17:45:41 volumio volumio[1004]: info: sendMpdCommand playlistinfo took 115 milliseconds Jan 16 17:45:41 volumio volumio[1004]: info: sendMpdCommand status took 111 milliseconds Jan 16 17:45:41 volumio volumio[1004]: info: sendMpdCommand status took 107 milliseconds Jan 16 17:45:41 volumio volumio[1004]: verbose: ControllerMpd::parseState Jan 16 17:45:41 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 16 17:45:41 volumio volumio[1004]: verbose: ControllerMpd::parseTrackInfo Jan 16 17:45:41 volumio volumio[1004]: verbose: ControllerMpd::parseTrackInfo Jan 16 17:45:41 volumio volumio[1004]: verbose: ControllerMpd::parseState Jan 16 17:45:41 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 16 17:45:41 volumio volumio[1004]: verbose: ControllerMpd::parseState Jan 16 17:45:41 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 16 17:45:41 volumio volumio[1004]: info: ControllerMpd::pushState Jan 16 17:45:41 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Jan 16 17:45:41 volumio volumio[1004]: info: CorePlayQueue::getTrack 93 Jan 16 17:45:41 volumio volumio[1004]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":344,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Sun Goes Down","artist":"Marenberg Kollektiv","album":"Circumstance Compilation","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-79.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D988f241acad87b81487ad438c4e56270d46e10f134983686c1449dbab03429f5%2Clid%3D1528%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67970138%2F0%2F61381%2F7eb16f1e.83335837.5.56021986%2F320.mp3&ext=.mp3","trackType":"mp3"} Jan 16 17:45:41 volumio volumio[1004]: verbose: CURRENT POSITION 93 Jan 16 17:45:41 volumio volumio[1004]: info: CoreStateMachine::syncState stateService play Jan 16 17:45:41 volumio volumio[1004]: info: CoreStateMachine::syncState currentStatus stop Jan 16 17:45:41 volumio volumio[1004]: info: ControllerMpd::pushState Jan 16 17:45:41 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Jan 16 17:45:41 volumio volumio[1004]: info: CorePlayQueue::getTrack 93 Jan 16 17:45:41 volumio volumio[1004]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":344,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Sun Goes Down","artist":"Marenberg Kollektiv","album":"Circumstance Compilation","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-79.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D988f241acad87b81487ad438c4e56270d46e10f134983686c1449dbab03429f5%2Clid%3D1528%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67970138%2F0%2F61381%2F7eb16f1e.83335837.5.56021986%2F320.mp3&ext=.mp3","trackType":"mp3"} Jan 16 17:45:41 volumio volumio[1004]: verbose: CURRENT POSITION 93 Jan 16 17:45:41 volumio volumio[1004]: info: CoreStateMachine::syncState stateService play Jan 16 17:45:41 volumio volumio[1004]: info: CoreStateMachine::syncState currentStatus play Jan 16 17:45:41 volumio volumio[1004]: info: Received an update from plugin. extracting info from payload Jan 16 17:45:41 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:41 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 16 17:45:41 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:41 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:41 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:41 volumio volumio[1004]: info: ControllerMpd::pushState Jan 16 17:45:41 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Jan 16 17:45:41 volumio volumio[1004]: info: CorePlayQueue::getTrack 93 Jan 16 17:45:41 volumio volumio[1004]: verbose: STATE SERVICE {"status":"play","position":0,"seek":67,"duration":344,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Sun Goes Down","artist":"Marenberg Kollektiv","album":"Circumstance Compilation","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-79.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D988f241acad87b81487ad438c4e56270d46e10f134983686c1449dbab03429f5%2Clid%3D1528%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67970138%2F0%2F61381%2F7eb16f1e.83335837.5.56021986%2F320.mp3&ext=.mp3","trackType":"mp3"} Jan 16 17:45:41 volumio volumio[1004]: verbose: CURRENT POSITION 93 Jan 16 17:45:41 volumio volumio[1004]: info: CoreStateMachine::syncState stateService play Jan 16 17:45:41 volumio volumio[1004]: info: CoreStateMachine::syncState currentStatus play Jan 16 17:45:41 volumio volumio[1004]: info: Received an update from plugin. extracting info from payload Jan 16 17:45:41 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:41 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:41 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:41 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:41 volumio volumio[1004]: info: ------------------------------ 303ms Jan 16 17:45:41 volumio volumio[1004]: info: ------------------------------ 306ms Jan 16 17:45:41 volumio volumio[1004]: info: ------------------------------ 305ms Jan 16 17:45:41 volumio volumio[1004]: info: CoreCommandRouter::volumioNext Jan 16 17:45:41 volumio volumio[1004]: info: CoreStateMachine::next Jan 16 17:45:41 volumio volumio[1004]: info: ControllerMpd::next Jan 16 17:45:41 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand next Jan 16 17:45:41 volumio volumio[1004]: info: Jan 16 17:45:41 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:41 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:41 volumio volumio[1004]: info: Jan 16 17:45:41 volumio volumio[1004]: ---------------------------- MPD announces state update: player Jan 16 17:45:41 volumio volumio[1004]: info: sendMpdCommand playlistinfo took 81 milliseconds Jan 16 17:45:41 volumio volumio[1004]: info: sendMpdCommand playlistinfo took 76 milliseconds Jan 16 17:45:41 volumio volumio[1004]: info: sendMpdCommand playlistinfo took 74 milliseconds Jan 16 17:45:41 volumio volumio[1004]: info: sendMpdCommand next took 22 milliseconds Jan 16 17:45:41 volumio volumio[1004]: info: ControllerMpd::getState Jan 16 17:45:41 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Jan 16 17:45:41 volumio volumio[1004]: verbose: ControllerMpd::parseTrackInfo Jan 16 17:45:41 volumio volumio[1004]: verbose: ControllerMpd::parseTrackInfo Jan 16 17:45:41 volumio volumio[1004]: verbose: ControllerMpd::parseTrackInfo Jan 16 17:45:41 volumio volumio[1004]: info: ControllerMpd::pushState Jan 16 17:45:41 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Jan 16 17:45:41 volumio volumio[1004]: info: CorePlayQueue::getTrack 93 Jan 16 17:45:41 volumio volumio[1004]: verbose: STATE SERVICE {"status":"play","position":0,"seek":913,"duration":344,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Sun Goes Down","artist":"Marenberg Kollektiv","album":"Circumstance Compilation","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-79.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D988f241acad87b81487ad438c4e56270d46e10f134983686c1449dbab03429f5%2Clid%3D1528%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67970138%2F0%2F61381%2F7eb16f1e.83335837.5.56021986%2F320.mp3&ext=.mp3","trackType":"mp3"} Jan 16 17:45:41 volumio volumio[1004]: verbose: CURRENT POSITION 93 Jan 16 17:45:41 volumio volumio[1004]: info: CoreStateMachine::syncState stateService play Jan 16 17:45:41 volumio volumio[1004]: info: CoreStateMachine::syncState currentStatus play Jan 16 17:45:41 volumio volumio[1004]: info: Received an update from plugin. extracting info from payload Jan 16 17:45:41 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:41 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 16 17:45:41 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:41 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:41 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:41 volumio volumio[1004]: info: ControllerMpd::pushState Jan 16 17:45:41 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Jan 16 17:45:41 volumio volumio[1004]: info: CorePlayQueue::getTrack 93 Jan 16 17:45:41 volumio volumio[1004]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1004,"duration":344,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Sun Goes Down","artist":"Marenberg Kollektiv","album":"Circumstance Compilation","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-79.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D988f241acad87b81487ad438c4e56270d46e10f134983686c1449dbab03429f5%2Clid%3D1528%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67970138%2F0%2F61381%2F7eb16f1e.83335837.5.56021986%2F320.mp3&ext=.mp3","trackType":"mp3"} Jan 16 17:45:41 volumio volumio[1004]: verbose: CURRENT POSITION 93 Jan 16 17:45:41 volumio volumio[1004]: info: CoreStateMachine::syncState stateService play Jan 16 17:45:41 volumio volumio[1004]: info: CoreStateMachine::syncState currentStatus play Jan 16 17:45:41 volumio volumio[1004]: info: Received an update from plugin. extracting info from payload Jan 16 17:45:41 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:41 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:41 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:41 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:41 volumio volumio[1004]: info: ControllerMpd::pushState Jan 16 17:45:41 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Jan 16 17:45:41 volumio volumio[1004]: info: CorePlayQueue::getTrack 93 Jan 16 17:45:41 volumio volumio[1004]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1004,"duration":344,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Sun Goes Down","artist":"Marenberg Kollektiv","album":"Circumstance Compilation","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-79.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D988f241acad87b81487ad438c4e56270d46e10f134983686c1449dbab03429f5%2Clid%3D1528%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67970138%2F0%2F61381%2F7eb16f1e.83335837.5.56021986%2F320.mp3&ext=.mp3","trackType":"mp3"} Jan 16 17:45:41 volumio volumio[1004]: verbose: CURRENT POSITION 93 Jan 16 17:45:41 volumio volumio[1004]: info: CoreStateMachine::syncState stateService play Jan 16 17:45:41 volumio volumio[1004]: info: CoreStateMachine::syncState currentStatus play Jan 16 17:45:41 volumio volumio[1004]: info: Received an update from plugin. extracting info from payload Jan 16 17:45:41 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:41 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:41 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:41 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:41 volumio volumio[1004]: info: ------------------------------ 260ms Jan 16 17:45:41 volumio volumio[1004]: info: ------------------------------ 249ms Jan 16 17:45:41 volumio volumio[1004]: info: ------------------------------ 246ms Jan 16 17:45:41 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Jan 16 17:45:41 volumio volumio[1004]: info: CoreStateMachine::getQueue Jan 16 17:45:41 volumio volumio[1004]: info: CorePlayQueue::getQueue Jan 16 17:45:41 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Jan 16 17:45:41 volumio volumio[1004]: info: CoreStateMachine::getQueue Jan 16 17:45:41 volumio volumio[1004]: info: CorePlayQueue::getQueue Jan 16 17:45:41 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Jan 16 17:45:41 volumio volumio[1004]: info: CoreStateMachine::getQueue Jan 16 17:45:41 volumio volumio[1004]: info: CorePlayQueue::getQueue Jan 16 17:45:41 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Jan 16 17:45:41 volumio volumio[1004]: info: CoreStateMachine::getQueue Jan 16 17:45:41 volumio volumio[1004]: info: CorePlayQueue::getQueue Jan 16 17:45:41 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Jan 16 17:45:41 volumio volumio[1004]: info: CoreStateMachine::getQueue Jan 16 17:45:41 volumio volumio[1004]: info: CorePlayQueue::getQueue Jan 16 17:45:41 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Jan 16 17:45:41 volumio volumio[1004]: info: CoreStateMachine::getQueue Jan 16 17:45:41 volumio volumio[1004]: info: CorePlayQueue::getQueue Jan 16 17:45:41 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Jan 16 17:45:41 volumio volumio[1004]: info: CoreStateMachine::getQueue Jan 16 17:45:41 volumio volumio[1004]: info: CorePlayQueue::getQueue Jan 16 17:45:41 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Jan 16 17:45:41 volumio volumio[1004]: info: CoreStateMachine::getQueue Jan 16 17:45:41 volumio volumio[1004]: info: CorePlayQueue::getQueue Jan 16 17:45:41 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Jan 16 17:45:41 volumio volumio[1004]: info: CoreStateMachine::getQueue Jan 16 17:45:41 volumio volumio[1004]: info: CorePlayQueue::getQueue Jan 16 17:45:41 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Jan 16 17:45:41 volumio volumio[1004]: info: CoreStateMachine::getQueue Jan 16 17:45:41 volumio volumio[1004]: info: CorePlayQueue::getQueue Jan 16 17:45:41 volumio volumio[1004]: info: Jan 16 17:45:41 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:41 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:41 volumio volumio[1004]: info: Jan 16 17:45:41 volumio volumio[1004]: ---------------------------- MPD announces state update: player Jan 16 17:45:41 volumio volumio[1004]: info: ControllerMpd::getState Jan 16 17:45:41 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Jan 16 17:45:41 volumio volumio[1004]: info: Jan 16 17:45:41 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:41 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:41 volumio volumio[1004]: info: Jan 16 17:45:41 volumio volumio[1004]: ---------------------------- MPD announces state update: player Jan 16 17:45:41 volumio volumio[1004]: info: ControllerMpd::getState Jan 16 17:45:41 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Jan 16 17:45:41 volumio volumio[1004]: error: updateQueue error: null Jan 16 17:45:41 volumio volumio[1004]: error: updateQueue error: null Jan 16 17:45:41 volumio volumio[1004]: error: updateQueue error: null Jan 16 17:45:41 volumio volumio[1004]: info: ------------------------------ 239ms Jan 16 17:45:41 volumio volumio[1004]: info: sendMpdCommand status took 233 milliseconds Jan 16 17:45:41 volumio volumio[1004]: info: ------------------------------ 10ms Jan 16 17:45:41 volumio volumio[1004]: info: sendMpdCommand status took 9 milliseconds Jan 16 17:45:41 volumio volumio[1004]: info: ------------------------------ 8ms Jan 16 17:45:41 volumio volumio[1004]: info: sendMpdCommand status took 7 milliseconds Jan 16 17:45:41 volumio volumio[1004]: verbose: ControllerMpd::parseState Jan 16 17:45:41 volumio volumio[1004]: verbose: ControllerMpd::parseState Jan 16 17:45:41 volumio volumio[1004]: verbose: ControllerMpd::parseState Jan 16 17:45:41 volumio volumio[1004]: info: ControllerMpd::pushState Jan 16 17:45:41 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Jan 16 17:45:41 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:41 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 16 17:45:41 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:41 volumio volumio[1004]: info: CorePlayQueue::getTrack 93 Jan 16 17:45:41 volumio volumio[1004]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 16 17:45:41 volumio volumio[1004]: verbose: CURRENT POSITION 93 Jan 16 17:45:41 volumio volumio[1004]: info: CoreStateMachine::syncState stateService stop Jan 16 17:45:41 volumio volumio[1004]: info: CoreStateMachine::syncState currentStatus play Jan 16 17:45:41 volumio volumio[1004]: info: CoreStateMachine::play index undefined Jan 16 17:45:41 volumio volumio[1004]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 16 17:45:41 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:41 volumio volumio[1004]: info: CorePlayQueue::getTrack 94 Jan 16 17:45:41 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 16 17:45:41 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:41 volumio volumio[1004]: info: ControllerMpd::pushState Jan 16 17:45:41 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Jan 16 17:45:41 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:41 volumio volumio[1004]: info: CorePlayQueue::getTrack 94 Jan 16 17:45:41 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:41 volumio volumio[1004]: info: CorePlayQueue::getTrack 94 Jan 16 17:45:41 volumio volumio[1004]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd Jan 16 17:45:41 volumio volumio[1004]: info: ControllerMpd::pushState Jan 16 17:45:41 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Jan 16 17:45:41 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:41 volumio volumio[1004]: info: CorePlayQueue::getTrack 94 Jan 16 17:45:41 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:41 volumio volumio[1004]: info: CorePlayQueue::getTrack 94 Jan 16 17:45:41 volumio volumio[1004]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd Jan 16 17:45:41 volumio volumio[1004]: info: CorePlayQueue::getTrack 94 Jan 16 17:45:41 volumio volumio[1004]: info: CoreStateMachine::startPlaybackTimer Jan 16 17:45:41 volumio volumio[1004]: info: CorePlayQueue::getTrack 94 Jan 16 17:45:41 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand stop Jan 16 17:45:41 volumio volumio[1004]: info: ------------------------------ 278ms Jan 16 17:45:41 volumio volumio[1004]: info: ------------------------------ 51ms Jan 16 17:45:41 volumio volumio[1004]: info: ------------------------------ 47ms Jan 16 17:45:41 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:41 volumio volumio[1004]: info: CorePlayQueue::getTrack 94 Jan 16 17:45:41 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 16 17:45:41 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:41 volumio volumio[1004]: info: sendMpdCommand stop took 22 milliseconds Jan 16 17:45:41 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand clear Jan 16 17:45:41 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Jan 16 17:45:41 volumio volumio[1004]: info: CoreStateMachine::getQueue Jan 16 17:45:41 volumio volumio[1004]: info: CorePlayQueue::getQueue Jan 16 17:45:41 volumio volumio[1004]: info: Jan 16 17:45:41 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:41 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:41 volumio volumio[1004]: info: sendMpdCommand clear took 27 milliseconds Jan 16 17:45:41 volumio volumio[1004]: info: Jan 16 17:45:41 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:41 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:41 volumio volumio[1004]: info: Jan 16 17:45:41 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:41 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:41 volumio volumio[1004]: error: updateQueue error: null Jan 16 17:45:41 volumio volumio[1004]: info: ------------------------------ 25ms Jan 16 17:45:41 volumio volumio[1004]: error: updateQueue error: null Jan 16 17:45:41 volumio volumio[1004]: error: updateQueue error: null Jan 16 17:45:41 volumio volumio[1004]: info: ------------------------------ 9ms Jan 16 17:45:41 volumio volumio[1004]: info: ------------------------------ 7ms Jan 16 17:45:41 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-mar-124.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D7d9d7219c6760ee0d70b3d630f29d06189c0f245e35bc2b84dc42f1721a6bcf9%2Clid%3D1559%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67968217%2F0%2F31538%2Fd870ae44.12616238.6.9866317%2F320.mp3&ext=.mp3" Jan 16 17:45:41 volumio volumio[1004]: info: Jan 16 17:45:41 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:41 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:41 volumio volumio[1004]: info: sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-mar-124.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D7d9d7219c6760ee0d70b3d630f29d06189c0f245e35bc2b84dc42f1721a6bcf9%2Clid%3D1559%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67968217%2F0%2F31538%2Fd870ae44.12616238.6.9866317%2F320.mp3&ext=.mp3" took 3 milliseconds Jan 16 17:45:41 volumio volumio[1004]: verbose: MPD COMMAND [object Object] Jan 16 17:45:41 volumio volumio[1004]: verbose: MPD COMMAND [object Object] Jan 16 17:45:41 volumio volumio[1004]: verbose: MPD COMMAND [object Object] Jan 16 17:45:41 volumio volumio[1004]: info: Jan 16 17:45:41 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:41 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:41 volumio volumio[1004]: info: Jan 16 17:45:41 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:41 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:41 volumio volumio[1004]: info: Jan 16 17:45:41 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:41 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:41 volumio volumio[1004]: info: ------------------------------ 10ms Jan 16 17:45:41 volumio volumio[1004]: info: ------------------------------ 6ms Jan 16 17:45:41 volumio volumio[1004]: info: ------------------------------ 5ms Jan 16 17:45:41 volumio volumio[1004]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 16 17:45:41 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand play Jan 16 17:45:41 volumio volumio[1004]: info: Jan 16 17:45:41 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:41 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:41 volumio volumio[1004]: info: Jan 16 17:45:41 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:41 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:41 volumio volumio[1004]: info: ------------------------------ 15ms Jan 16 17:45:41 volumio volumio[1004]: info: sendMpdCommand play took 12 milliseconds Jan 16 17:45:41 volumio volumio[1004]: info: ------------------------------ 11ms Jan 16 17:45:41 volumio volumio[1004]: info: ------------------------------ 10ms Jan 16 17:45:42 volumio volumio[1004]: info: Jan 16 17:45:42 volumio volumio[1004]: ---------------------------- MPD announces state update: player Jan 16 17:45:42 volumio volumio[1004]: info: ControllerMpd::getState Jan 16 17:45:42 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Jan 16 17:45:42 volumio volumio[1004]: info: Jan 16 17:45:42 volumio volumio[1004]: ---------------------------- MPD announces state update: player Jan 16 17:45:42 volumio volumio[1004]: info: ControllerMpd::getState Jan 16 17:45:42 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Jan 16 17:45:42 volumio volumio[1004]: info: Jan 16 17:45:42 volumio volumio[1004]: ---------------------------- MPD announces state update: player Jan 16 17:45:42 volumio volumio[1004]: info: ControllerMpd::getState Jan 16 17:45:42 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Jan 16 17:45:42 volumio volumio[1004]: info: CoreCommandRouter::volumioAddQueueItems Jan 16 17:45:42 volumio volumio[1004]: info: CoreStateMachine::addQueueItems Jan 16 17:45:42 volumio volumio[1004]: info: CorePlayQueue::addQueueItems Jan 16 17:45:42 volumio volumio[1004]: info: Preload queue cleared Jan 16 17:45:42 volumio volumio[1004]: info: Adding Item to queue: yandex_music/track/126913100:31600935@user:onyourwave Jan 16 17:45:42 volumio volumio[1004]: info: Exploding uri yandex_music/track/126913100:31600935@user:onyourwave in service yandex_music Jan 16 17:45:42 volumio volumio[1004]: info: CoreCommandRouter::volumioAddQueueItems Jan 16 17:45:42 volumio volumio[1004]: info: CoreStateMachine::addQueueItems Jan 16 17:45:42 volumio volumio[1004]: info: CorePlayQueue::addQueueItems Jan 16 17:45:42 volumio volumio[1004]: info: Preload queue cleared Jan 16 17:45:42 volumio volumio[1004]: info: Adding Item to queue: yandex_music/track/91541426:18239257@user:onyourwave Jan 16 17:45:42 volumio volumio[1004]: info: Exploding uri yandex_music/track/91541426:18239257@user:onyourwave in service yandex_music Jan 16 17:45:42 volumio volumio[1004]: info: CoreCommandRouter::volumioPushQueue Jan 16 17:45:42 volumio volumio[1004]: info: CorePlayQueue::saveQueue Jan 16 17:45:42 volumio volumio[1004]: info: CoreCommandRouter::volumioPushQueue Jan 16 17:45:42 volumio volumio[1004]: info: CorePlayQueue::saveQueue Jan 16 17:45:42 volumio volumio[1004]: info: CoreStateMachine::updateTrackBlock Jan 16 17:45:42 volumio volumio[1004]: info: CorePlayQueue::getTrackBlock Jan 16 17:45:42 volumio volumio[1004]: info: CoreStateMachine::updateTrackBlock Jan 16 17:45:42 volumio volumio[1004]: info: CorePlayQueue::getTrackBlock Jan 16 17:45:42 volumio volumio[1004]: info: Jan 16 17:45:42 volumio volumio[1004]: ---------------------------- MPD announces state update: player Jan 16 17:45:42 volumio volumio[1004]: info: ControllerMpd::getState Jan 16 17:45:42 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Jan 16 17:45:42 volumio volumio[1004]: info: sendMpdCommand status took 205 milliseconds Jan 16 17:45:42 volumio volumio[1004]: info: sendMpdCommand status took 204 milliseconds Jan 16 17:45:42 volumio volumio[1004]: info: sendMpdCommand status took 202 milliseconds Jan 16 17:45:42 volumio volumio[1004]: verbose: ControllerMpd::parseState Jan 16 17:45:42 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 16 17:45:42 volumio volumio[1004]: verbose: ControllerMpd::parseState Jan 16 17:45:42 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 16 17:45:42 volumio volumio[1004]: verbose: ControllerMpd::parseState Jan 16 17:45:42 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 16 17:45:42 volumio volumio[1004]: info: Jan 16 17:45:42 volumio volumio[1004]: ---------------------------- MPD announces state update: player Jan 16 17:45:42 volumio volumio[1004]: info: ControllerMpd::getState Jan 16 17:45:42 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Jan 16 17:45:42 volumio volumio[1004]: info: Jan 16 17:45:42 volumio volumio[1004]: ---------------------------- MPD announces state update: player Jan 16 17:45:42 volumio volumio[1004]: info: ControllerMpd::getState Jan 16 17:45:42 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Jan 16 17:45:43 volumio volumio[1004]: info: sendMpdCommand status took 268 milliseconds Jan 16 17:45:43 volumio volumio[1004]: info: sendMpdCommand playlistinfo took 259 milliseconds Jan 16 17:45:43 volumio volumio[1004]: info: sendMpdCommand playlistinfo took 260 milliseconds Jan 16 17:45:43 volumio volumio[1004]: info: sendMpdCommand playlistinfo took 259 milliseconds Jan 16 17:45:43 volumio volumio[1004]: info: sendMpdCommand status took 248 milliseconds Jan 16 17:45:43 volumio volumio[1004]: info: sendMpdCommand status took 244 milliseconds Jan 16 17:45:43 volumio volumio[1004]: verbose: ControllerMpd::parseState Jan 16 17:45:43 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 16 17:45:43 volumio volumio[1004]: verbose: ControllerMpd::parseTrackInfo Jan 16 17:45:43 volumio volumio[1004]: verbose: ControllerMpd::parseState Jan 16 17:45:43 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 16 17:45:43 volumio volumio[1004]: verbose: ControllerMpd::parseState Jan 16 17:45:43 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 16 17:45:43 volumio volumio[1004]: info: ControllerMpd::pushState Jan 16 17:45:43 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Jan 16 17:45:43 volumio volumio[1004]: info: CorePlayQueue::getTrack 94 Jan 16 17:45:43 volumio volumio[1004]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":456,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Such a Rush","artist":"Kurtz","album":"Work on Me EP","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-mar-124.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D7d9d7219c6760ee0d70b3d630f29d06189c0f245e35bc2b84dc42f1721a6bcf9%2Clid%3D1559%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67968217%2F0%2F31538%2Fd870ae44.12616238.6.9866317%2F320.mp3&ext=.mp3","trackType":"mp3"} Jan 16 17:45:43 volumio volumio[1004]: verbose: CURRENT POSITION 94 Jan 16 17:45:43 volumio volumio[1004]: info: CoreStateMachine::syncState stateService play Jan 16 17:45:43 volumio volumio[1004]: info: CoreStateMachine::syncState currentStatus stop Jan 16 17:45:43 volumio volumio[1004]: info: ControllerMpd::pushState Jan 16 17:45:43 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Jan 16 17:45:43 volumio volumio[1004]: info: CorePlayQueue::getTrack 94 Jan 16 17:45:43 volumio volumio[1004]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":456,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Such a Rush","artist":"Kurtz","album":"Work on Me EP","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-mar-124.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D7d9d7219c6760ee0d70b3d630f29d06189c0f245e35bc2b84dc42f1721a6bcf9%2Clid%3D1559%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67968217%2F0%2F31538%2Fd870ae44.12616238.6.9866317%2F320.mp3&ext=.mp3","trackType":"mp3"} Jan 16 17:45:43 volumio volumio[1004]: verbose: CURRENT POSITION 94 Jan 16 17:45:43 volumio volumio[1004]: info: CoreStateMachine::syncState stateService play Jan 16 17:45:43 volumio volumio[1004]: info: CoreStateMachine::syncState currentStatus play Jan 16 17:45:43 volumio volumio[1004]: info: Received an update from plugin. extracting info from payload Jan 16 17:45:43 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:43 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 16 17:45:43 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:43 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:43 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:43 volumio volumio[1004]: info: ControllerMpd::pushState Jan 16 17:45:43 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Jan 16 17:45:43 volumio volumio[1004]: info: CorePlayQueue::getTrack 94 Jan 16 17:45:43 volumio volumio[1004]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":456,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Such a Rush","artist":"Kurtz","album":"Work on Me EP","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-mar-124.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D7d9d7219c6760ee0d70b3d630f29d06189c0f245e35bc2b84dc42f1721a6bcf9%2Clid%3D1559%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67968217%2F0%2F31538%2Fd870ae44.12616238.6.9866317%2F320.mp3&ext=.mp3","trackType":"mp3"} Jan 16 17:45:43 volumio volumio[1004]: verbose: CURRENT POSITION 94 Jan 16 17:45:43 volumio volumio[1004]: info: CoreStateMachine::syncState stateService play Jan 16 17:45:43 volumio volumio[1004]: info: CoreStateMachine::syncState currentStatus play Jan 16 17:45:43 volumio volumio[1004]: info: Received an update from plugin. extracting info from payload Jan 16 17:45:43 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:43 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:43 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:43 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:43 volumio volumio[1004]: info: ------------------------------ 520ms Jan 16 17:45:43 volumio volumio[1004]: info: ------------------------------ 527ms Jan 16 17:45:43 volumio volumio[1004]: info: ------------------------------ 525ms Jan 16 17:45:43 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Jan 16 17:45:43 volumio volumio[1004]: info: CoreStateMachine::getQueue Jan 16 17:45:43 volumio volumio[1004]: info: CorePlayQueue::getQueue Jan 16 17:45:43 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Jan 16 17:45:43 volumio volumio[1004]: info: CoreStateMachine::getQueue Jan 16 17:45:43 volumio volumio[1004]: info: CorePlayQueue::getQueue Jan 16 17:45:43 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Jan 16 17:45:43 volumio volumio[1004]: info: CoreStateMachine::getQueue Jan 16 17:45:43 volumio volumio[1004]: info: CorePlayQueue::getQueue Jan 16 17:45:43 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Jan 16 17:45:43 volumio volumio[1004]: info: CoreStateMachine::getQueue Jan 16 17:45:43 volumio volumio[1004]: info: CorePlayQueue::getQueue Jan 16 17:45:43 volumio volumio[1004]: info: sendMpdCommand playlistinfo took 563 milliseconds Jan 16 17:45:43 volumio volumio[1004]: info: sendMpdCommand playlistinfo took 562 milliseconds Jan 16 17:45:43 volumio volumio[1004]: verbose: ControllerMpd::parseTrackInfo Jan 16 17:45:43 volumio volumio[1004]: info: ControllerMpd::pushState Jan 16 17:45:43 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Jan 16 17:45:43 volumio volumio[1004]: info: CorePlayQueue::getTrack 94 Jan 16 17:45:43 volumio volumio[1004]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1004,"duration":456,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Such a Rush","artist":"Kurtz","album":"Work on Me EP","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-mar-124.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D7d9d7219c6760ee0d70b3d630f29d06189c0f245e35bc2b84dc42f1721a6bcf9%2Clid%3D1559%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67968217%2F0%2F31538%2Fd870ae44.12616238.6.9866317%2F320.mp3&ext=.mp3","trackType":"mp3"} Jan 16 17:45:43 volumio volumio[1004]: verbose: CURRENT POSITION 94 Jan 16 17:45:43 volumio volumio[1004]: info: CoreStateMachine::syncState stateService play Jan 16 17:45:43 volumio volumio[1004]: info: CoreStateMachine::syncState currentStatus play Jan 16 17:45:43 volumio volumio[1004]: info: Received an update from plugin. extracting info from payload Jan 16 17:45:43 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:43 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 16 17:45:43 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:43 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:43 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:43 volumio volumio[1004]: info: ControllerMpd::pushState Jan 16 17:45:43 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Jan 16 17:45:43 volumio volumio[1004]: info: CorePlayQueue::getTrack 94 Jan 16 17:45:43 volumio volumio[1004]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1004,"duration":456,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Such a Rush","artist":"Kurtz","album":"Work on Me EP","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-mar-124.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D7d9d7219c6760ee0d70b3d630f29d06189c0f245e35bc2b84dc42f1721a6bcf9%2Clid%3D1559%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67968217%2F0%2F31538%2Fd870ae44.12616238.6.9866317%2F320.mp3&ext=.mp3","trackType":"mp3"} Jan 16 17:45:43 volumio volumio[1004]: verbose: CURRENT POSITION 94 Jan 16 17:45:43 volumio volumio[1004]: info: CoreStateMachine::syncState stateService play Jan 16 17:45:43 volumio volumio[1004]: info: CoreStateMachine::syncState currentStatus play Jan 16 17:45:43 volumio volumio[1004]: info: Received an update from plugin. extracting info from payload Jan 16 17:45:43 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:43 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:43 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:43 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:43 volumio volumio[1004]: info: ControllerMpd::pushState Jan 16 17:45:43 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Jan 16 17:45:43 volumio volumio[1004]: info: CorePlayQueue::getTrack 94 Jan 16 17:45:43 volumio volumio[1004]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1107,"duration":456,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Such a Rush","artist":"Kurtz","album":"Work on Me EP","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-mar-124.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D7d9d7219c6760ee0d70b3d630f29d06189c0f245e35bc2b84dc42f1721a6bcf9%2Clid%3D1559%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67968217%2F0%2F31538%2Fd870ae44.12616238.6.9866317%2F320.mp3&ext=.mp3","trackType":"mp3"} Jan 16 17:45:43 volumio volumio[1004]: verbose: CURRENT POSITION 94 Jan 16 17:45:43 volumio volumio[1004]: info: CoreStateMachine::syncState stateService play Jan 16 17:45:43 volumio volumio[1004]: info: CoreStateMachine::syncState currentStatus play Jan 16 17:45:43 volumio volumio[1004]: info: Received an update from plugin. extracting info from payload Jan 16 17:45:43 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:43 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:43 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:43 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:43 volumio volumio[1004]: info: ------------------------------ 948ms Jan 16 17:45:43 volumio volumio[1004]: info: ------------------------------ 924ms Jan 16 17:45:43 volumio volumio[1004]: info: ------------------------------ 922ms Jan 16 17:45:43 volumio volumio[1004]: info: CoreCommandRouter::volumioNext Jan 16 17:45:43 volumio volumio[1004]: info: CoreStateMachine::next Jan 16 17:45:43 volumio volumio[1004]: info: ControllerMpd::next Jan 16 17:45:43 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand next Jan 16 17:45:43 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Jan 16 17:45:43 volumio volumio[1004]: info: CoreStateMachine::getQueue Jan 16 17:45:43 volumio volumio[1004]: info: CorePlayQueue::getQueue Jan 16 17:45:43 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Jan 16 17:45:43 volumio volumio[1004]: info: CoreStateMachine::getQueue Jan 16 17:45:43 volumio volumio[1004]: info: CorePlayQueue::getQueue Jan 16 17:45:43 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Jan 16 17:45:43 volumio volumio[1004]: info: CoreStateMachine::getQueue Jan 16 17:45:43 volumio volumio[1004]: info: CorePlayQueue::getQueue Jan 16 17:45:43 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Jan 16 17:45:43 volumio volumio[1004]: info: CoreStateMachine::getQueue Jan 16 17:45:43 volumio volumio[1004]: info: CorePlayQueue::getQueue Jan 16 17:45:43 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Jan 16 17:45:43 volumio volumio[1004]: info: CoreStateMachine::getQueue Jan 16 17:45:43 volumio volumio[1004]: info: CorePlayQueue::getQueue Jan 16 17:45:44 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Jan 16 17:45:44 volumio volumio[1004]: info: CoreStateMachine::getQueue Jan 16 17:45:44 volumio volumio[1004]: info: CorePlayQueue::getQueue Jan 16 17:45:44 volumio volumio[1004]: info: Jan 16 17:45:44 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:44 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:44 volumio volumio[1004]: info: Jan 16 17:45:44 volumio volumio[1004]: ---------------------------- MPD announces state update: player Jan 16 17:45:44 volumio volumio[1004]: info: sendMpdCommand next took 272 milliseconds Jan 16 17:45:44 volumio volumio[1004]: info: ControllerMpd::getState Jan 16 17:45:44 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Jan 16 17:45:44 volumio volumio[1004]: info: Jan 16 17:45:44 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:44 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:44 volumio volumio[1004]: info: Jan 16 17:45:44 volumio volumio[1004]: ---------------------------- MPD announces state update: player Jan 16 17:45:44 volumio volumio[1004]: info: ControllerMpd::getState Jan 16 17:45:44 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Jan 16 17:45:44 volumio volumio[1004]: info: Jan 16 17:45:44 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:44 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:44 volumio volumio[1004]: info: Jan 16 17:45:44 volumio volumio[1004]: ---------------------------- MPD announces state update: player Jan 16 17:45:44 volumio volumio[1004]: info: ControllerMpd::getState Jan 16 17:45:44 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Jan 16 17:45:44 volumio volumio[1004]: error: updateQueue error: null Jan 16 17:45:44 volumio volumio[1004]: info: ------------------------------ 14ms Jan 16 17:45:44 volumio volumio[1004]: info: sendMpdCommand status took 12 milliseconds Jan 16 17:45:44 volumio volumio[1004]: info: ------------------------------ 12ms Jan 16 17:45:44 volumio volumio[1004]: info: sendMpdCommand status took 10 milliseconds Jan 16 17:45:44 volumio volumio[1004]: info: ------------------------------ 9ms Jan 16 17:45:44 volumio volumio[1004]: info: sendMpdCommand status took 8 milliseconds Jan 16 17:45:44 volumio volumio[1004]: verbose: ControllerMpd::parseState Jan 16 17:45:44 volumio volumio[1004]: verbose: ControllerMpd::parseState Jan 16 17:45:44 volumio volumio[1004]: verbose: ControllerMpd::parseState Jan 16 17:45:44 volumio volumio[1004]: info: ControllerMpd::pushState Jan 16 17:45:44 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Jan 16 17:45:44 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:44 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 16 17:45:44 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:44 volumio volumio[1004]: info: CorePlayQueue::getTrack 94 Jan 16 17:45:44 volumio volumio[1004]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 16 17:45:44 volumio volumio[1004]: verbose: CURRENT POSITION 94 Jan 16 17:45:44 volumio volumio[1004]: info: CoreStateMachine::syncState stateService stop Jan 16 17:45:44 volumio volumio[1004]: info: CoreStateMachine::syncState currentStatus play Jan 16 17:45:44 volumio volumio[1004]: info: CoreStateMachine::play index undefined Jan 16 17:45:44 volumio volumio[1004]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 16 17:45:44 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:44 volumio volumio[1004]: info: CorePlayQueue::getTrack 95 Jan 16 17:45:44 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 16 17:45:44 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:44 volumio volumio[1004]: info: ControllerMpd::pushState Jan 16 17:45:44 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Jan 16 17:45:44 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:44 volumio volumio[1004]: info: CorePlayQueue::getTrack 95 Jan 16 17:45:44 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:44 volumio volumio[1004]: info: CorePlayQueue::getTrack 95 Jan 16 17:45:44 volumio volumio[1004]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd Jan 16 17:45:44 volumio volumio[1004]: info: ControllerMpd::pushState Jan 16 17:45:44 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Jan 16 17:45:44 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:44 volumio volumio[1004]: info: CorePlayQueue::getTrack 95 Jan 16 17:45:44 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:44 volumio volumio[1004]: info: CorePlayQueue::getTrack 95 Jan 16 17:45:44 volumio volumio[1004]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd Jan 16 17:45:44 volumio volumio[1004]: info: CorePlayQueue::getTrack 95 Jan 16 17:45:44 volumio volumio[1004]: info: CoreStateMachine::startPlaybackTimer Jan 16 17:45:44 volumio volumio[1004]: info: CorePlayQueue::getTrack 95 Jan 16 17:45:44 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand stop Jan 16 17:45:44 volumio volumio[1004]: info: ------------------------------ 56ms Jan 16 17:45:44 volumio volumio[1004]: info: ------------------------------ 55ms Jan 16 17:45:44 volumio volumio[1004]: info: ------------------------------ 53ms Jan 16 17:45:44 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:44 volumio volumio[1004]: info: CorePlayQueue::getTrack 95 Jan 16 17:45:44 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 16 17:45:44 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:44 volumio volumio[1004]: info: sendMpdCommand stop took 22 milliseconds Jan 16 17:45:44 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand clear Jan 16 17:45:44 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Jan 16 17:45:44 volumio volumio[1004]: info: CoreStateMachine::getQueue Jan 16 17:45:44 volumio volumio[1004]: info: CorePlayQueue::getQueue Jan 16 17:45:44 volumio volumio[1004]: info: Jan 16 17:45:44 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:44 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:44 volumio volumio[1004]: info: sendMpdCommand clear took 16 milliseconds Jan 16 17:45:44 volumio volumio[1004]: info: Jan 16 17:45:44 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:44 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:44 volumio volumio[1004]: info: Jan 16 17:45:44 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:44 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:44 volumio volumio[1004]: error: updateQueue error: null Jan 16 17:45:44 volumio volumio[1004]: error: updateQueue error: null Jan 16 17:45:44 volumio volumio[1004]: error: updateQueue error: null Jan 16 17:45:44 volumio volumio[1004]: info: ------------------------------ 14ms Jan 16 17:45:44 volumio volumio[1004]: info: ------------------------------ 6ms Jan 16 17:45:44 volumio volumio[1004]: info: ------------------------------ 5ms Jan 16 17:45:44 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=flac&url=https%3A%2F%2Fstrm-std-22.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dfa2e0ba4dd9f9cc424a9a548ce65f9adb76c714da2bd1ad5b26679660bfc90eb%2Clid%3D1537%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67968b06%2F0%2F788404%2F8382eec0.94746894.14.63310575%2Fflac&ext=.flac" Jan 16 17:45:44 volumio volumio[1004]: info: Jan 16 17:45:44 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:44 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:44 volumio volumio[1004]: info: sendMpdCommand addid "http://localhost:6601/?codec=flac&url=https%3A%2F%2Fstrm-std-22.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dfa2e0ba4dd9f9cc424a9a548ce65f9adb76c714da2bd1ad5b26679660bfc90eb%2Clid%3D1537%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67968b06%2F0%2F788404%2F8382eec0.94746894.14.63310575%2Fflac&ext=.flac" took 6 milliseconds Jan 16 17:45:44 volumio volumio[1004]: verbose: MPD COMMAND [object Object] Jan 16 17:45:44 volumio volumio[1004]: verbose: MPD COMMAND [object Object] Jan 16 17:45:44 volumio volumio[1004]: info: Jan 16 17:45:44 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:44 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:44 volumio volumio[1004]: info: Jan 16 17:45:44 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:44 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:44 volumio volumio[1004]: info: Jan 16 17:45:44 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:44 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:44 volumio volumio[1004]: info: ------------------------------ 14ms Jan 16 17:45:44 volumio volumio[1004]: info: ------------------------------ 10ms Jan 16 17:45:44 volumio volumio[1004]: info: ------------------------------ 9ms Jan 16 17:45:44 volumio volumio[1004]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 16 17:45:44 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand play Jan 16 17:45:44 volumio volumio[1004]: info: Jan 16 17:45:44 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:44 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:44 volumio volumio[1004]: info: Jan 16 17:45:44 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:44 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:44 volumio volumio[1004]: info: ------------------------------ 10ms Jan 16 17:45:44 volumio volumio[1004]: info: sendMpdCommand play took 7 milliseconds Jan 16 17:45:44 volumio volumio[1004]: info: ------------------------------ 6ms Jan 16 17:45:44 volumio volumio[1004]: info: ------------------------------ 4ms Jan 16 17:45:44 volumio volumio[1004]: info: Jan 16 17:45:44 volumio volumio[1004]: ---------------------------- MPD announces state update: player Jan 16 17:45:44 volumio volumio[1004]: info: ControllerMpd::getState Jan 16 17:45:44 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Jan 16 17:45:44 volumio volumio[1004]: info: Jan 16 17:45:44 volumio volumio[1004]: ---------------------------- MPD announces state update: player Jan 16 17:45:44 volumio volumio[1004]: info: ControllerMpd::getState Jan 16 17:45:44 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Jan 16 17:45:44 volumio volumio[1004]: info: Jan 16 17:45:44 volumio volumio[1004]: ---------------------------- MPD announces state update: player Jan 16 17:45:44 volumio volumio[1004]: info: ControllerMpd::getState Jan 16 17:45:44 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Jan 16 17:45:44 volumio volumio[1004]: info: sendMpdCommand status took 23 milliseconds Jan 16 17:45:44 volumio volumio[1004]: verbose: ControllerMpd::parseState Jan 16 17:45:44 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 16 17:45:44 volumio volumio[1004]: info: Jan 16 17:45:44 volumio volumio[1004]: ---------------------------- MPD announces state update: player Jan 16 17:45:44 volumio volumio[1004]: info: ControllerMpd::getState Jan 16 17:45:44 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Jan 16 17:45:44 volumio volumio[1004]: info: sendMpdCommand status took 26 milliseconds Jan 16 17:45:44 volumio volumio[1004]: info: sendMpdCommand status took 24 milliseconds Jan 16 17:45:44 volumio volumio[1004]: verbose: ControllerMpd::parseState Jan 16 17:45:44 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 16 17:45:44 volumio volumio[1004]: verbose: ControllerMpd::parseState Jan 16 17:45:44 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 16 17:45:44 volumio volumio[1004]: info: Jan 16 17:45:44 volumio volumio[1004]: ---------------------------- MPD announces state update: player Jan 16 17:45:44 volumio volumio[1004]: info: ControllerMpd::getState Jan 16 17:45:44 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Jan 16 17:45:44 volumio volumio[1004]: info: Jan 16 17:45:44 volumio volumio[1004]: ---------------------------- MPD announces state update: player Jan 16 17:45:44 volumio volumio[1004]: info: ControllerMpd::getState Jan 16 17:45:44 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Jan 16 17:45:44 volumio volumio[1004]: info: sendMpdCommand playlistinfo took 14 milliseconds Jan 16 17:45:44 volumio volumio[1004]: info: sendMpdCommand status took 13 milliseconds Jan 16 17:45:44 volumio volumio[1004]: info: sendMpdCommand playlistinfo took 10 milliseconds Jan 16 17:45:44 volumio volumio[1004]: verbose: ControllerMpd::parseTrackInfo Jan 16 17:45:44 volumio volumio[1004]: verbose: ControllerMpd::parseState Jan 16 17:45:44 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 16 17:45:44 volumio volumio[1004]: verbose: ControllerMpd::parseTrackInfo Jan 16 17:45:44 volumio volumio[1004]: info: ControllerMpd::pushState Jan 16 17:45:44 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Jan 16 17:45:44 volumio volumio[1004]: info: CorePlayQueue::getTrack 95 Jan 16 17:45:44 volumio volumio[1004]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":292,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Unity","artist":"Subtension","album":"Tom Yum","uri":"http://localhost:6601/?codec=flac&url=https%3A%2F%2Fstrm-std-22.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dfa2e0ba4dd9f9cc424a9a548ce65f9adb76c714da2bd1ad5b26679660bfc90eb%2Clid%3D1537%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67968b06%2F0%2F788404%2F8382eec0.94746894.14.63310575%2Fflac&ext=.flac","trackType":"flac"} Jan 16 17:45:44 volumio volumio[1004]: verbose: CURRENT POSITION 95 Jan 16 17:45:44 volumio volumio[1004]: info: CoreStateMachine::syncState stateService play Jan 16 17:45:44 volumio volumio[1004]: info: CoreStateMachine::syncState currentStatus stop Jan 16 17:45:44 volumio volumio[1004]: info: ControllerMpd::pushState Jan 16 17:45:44 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Jan 16 17:45:44 volumio volumio[1004]: info: CorePlayQueue::getTrack 95 Jan 16 17:45:44 volumio volumio[1004]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":292,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Unity","artist":"Subtension","album":"Tom Yum","uri":"http://localhost:6601/?codec=flac&url=https%3A%2F%2Fstrm-std-22.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dfa2e0ba4dd9f9cc424a9a548ce65f9adb76c714da2bd1ad5b26679660bfc90eb%2Clid%3D1537%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67968b06%2F0%2F788404%2F8382eec0.94746894.14.63310575%2Fflac&ext=.flac","trackType":"flac"} Jan 16 17:45:44 volumio volumio[1004]: verbose: CURRENT POSITION 95 Jan 16 17:45:44 volumio volumio[1004]: info: CoreStateMachine::syncState stateService play Jan 16 17:45:44 volumio volumio[1004]: info: CoreStateMachine::syncState currentStatus play Jan 16 17:45:44 volumio volumio[1004]: info: Received an update from plugin. extracting info from payload Jan 16 17:45:44 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:44 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 16 17:45:44 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:44 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:44 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:44 volumio volumio[1004]: info: ControllerMpd::pushState Jan 16 17:45:44 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Jan 16 17:45:44 volumio volumio[1004]: info: CorePlayQueue::getTrack 95 Jan 16 17:45:44 volumio volumio[1004]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":292,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Unity","artist":"Subtension","album":"Tom Yum","uri":"http://localhost:6601/?codec=flac&url=https%3A%2F%2Fstrm-std-22.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dfa2e0ba4dd9f9cc424a9a548ce65f9adb76c714da2bd1ad5b26679660bfc90eb%2Clid%3D1537%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67968b06%2F0%2F788404%2F8382eec0.94746894.14.63310575%2Fflac&ext=.flac","trackType":"flac"} Jan 16 17:45:44 volumio volumio[1004]: verbose: CURRENT POSITION 95 Jan 16 17:45:44 volumio volumio[1004]: info: CoreStateMachine::syncState stateService play Jan 16 17:45:44 volumio volumio[1004]: info: CoreStateMachine::syncState currentStatus play Jan 16 17:45:44 volumio volumio[1004]: info: Received an update from plugin. extracting info from payload Jan 16 17:45:44 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:44 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:44 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:44 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:44 volumio volumio[1004]: info: ------------------------------ 64ms Jan 16 17:45:44 volumio volumio[1004]: info: ------------------------------ 72ms Jan 16 17:45:44 volumio volumio[1004]: info: ------------------------------ 71ms Jan 16 17:45:44 volumio volumio[1004]: info: sendMpdCommand status took 57 milliseconds Jan 16 17:45:44 volumio volumio[1004]: info: sendMpdCommand status took 56 milliseconds Jan 16 17:45:44 volumio volumio[1004]: info: sendMpdCommand playlistinfo took 51 milliseconds Jan 16 17:45:44 volumio volumio[1004]: verbose: ControllerMpd::parseState Jan 16 17:45:44 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 16 17:45:44 volumio volumio[1004]: verbose: ControllerMpd::parseState Jan 16 17:45:44 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 16 17:45:44 volumio volumio[1004]: verbose: ControllerMpd::parseTrackInfo Jan 16 17:45:44 volumio volumio[1004]: info: ControllerMpd::pushState Jan 16 17:45:44 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Jan 16 17:45:44 volumio volumio[1004]: info: CorePlayQueue::getTrack 95 Jan 16 17:45:44 volumio volumio[1004]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":292,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Unity","artist":"Subtension","album":"Tom Yum","uri":"http://localhost:6601/?codec=flac&url=https%3A%2F%2Fstrm-std-22.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dfa2e0ba4dd9f9cc424a9a548ce65f9adb76c714da2bd1ad5b26679660bfc90eb%2Clid%3D1537%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67968b06%2F0%2F788404%2F8382eec0.94746894.14.63310575%2Fflac&ext=.flac","trackType":"flac"} Jan 16 17:45:44 volumio volumio[1004]: verbose: CURRENT POSITION 95 Jan 16 17:45:44 volumio volumio[1004]: info: CoreStateMachine::syncState stateService play Jan 16 17:45:44 volumio volumio[1004]: info: CoreStateMachine::syncState currentStatus play Jan 16 17:45:44 volumio volumio[1004]: info: Received an update from plugin. extracting info from payload Jan 16 17:45:44 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:44 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 16 17:45:44 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:44 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:44 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:44 volumio volumio[1004]: info: ------------------------------ 87ms Jan 16 17:45:44 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Jan 16 17:45:44 volumio volumio[1004]: info: CoreStateMachine::getQueue Jan 16 17:45:44 volumio volumio[1004]: info: CorePlayQueue::getQueue Jan 16 17:45:44 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Jan 16 17:45:44 volumio volumio[1004]: info: CoreStateMachine::getQueue Jan 16 17:45:44 volumio volumio[1004]: info: CorePlayQueue::getQueue Jan 16 17:45:44 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Jan 16 17:45:44 volumio volumio[1004]: info: CoreStateMachine::getQueue Jan 16 17:45:44 volumio volumio[1004]: info: CorePlayQueue::getQueue Jan 16 17:45:44 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Jan 16 17:45:44 volumio volumio[1004]: info: CoreStateMachine::getQueue Jan 16 17:45:44 volumio volumio[1004]: info: CorePlayQueue::getQueue Jan 16 17:45:44 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Jan 16 17:45:44 volumio volumio[1004]: info: CoreStateMachine::getQueue Jan 16 17:45:44 volumio volumio[1004]: info: CorePlayQueue::getQueue Jan 16 17:45:44 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Jan 16 17:45:44 volumio volumio[1004]: info: CoreStateMachine::getQueue Jan 16 17:45:44 volumio volumio[1004]: info: CorePlayQueue::getQueue Jan 16 17:45:44 volumio volumio[1004]: info: sendMpdCommand playlistinfo took 69 milliseconds Jan 16 17:45:44 volumio volumio[1004]: info: sendMpdCommand playlistinfo took 69 milliseconds Jan 16 17:45:44 volumio volumio[1004]: verbose: ControllerMpd::parseTrackInfo Jan 16 17:45:44 volumio volumio[1004]: verbose: ControllerMpd::parseTrackInfo Jan 16 17:45:44 volumio volumio[1004]: info: ControllerMpd::pushState Jan 16 17:45:44 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Jan 16 17:45:44 volumio volumio[1004]: info: CorePlayQueue::getTrack 95 Jan 16 17:45:44 volumio volumio[1004]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":292,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Unity","artist":"Subtension","album":"Tom Yum","uri":"http://localhost:6601/?codec=flac&url=https%3A%2F%2Fstrm-std-22.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dfa2e0ba4dd9f9cc424a9a548ce65f9adb76c714da2bd1ad5b26679660bfc90eb%2Clid%3D1537%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67968b06%2F0%2F788404%2F8382eec0.94746894.14.63310575%2Fflac&ext=.flac","trackType":"flac"} Jan 16 17:45:44 volumio volumio[1004]: verbose: CURRENT POSITION 95 Jan 16 17:45:44 volumio volumio[1004]: info: CoreStateMachine::syncState stateService play Jan 16 17:45:44 volumio volumio[1004]: info: CoreStateMachine::syncState currentStatus play Jan 16 17:45:44 volumio volumio[1004]: info: Received an update from plugin. extracting info from payload Jan 16 17:45:44 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:44 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:44 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:44 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:44 volumio volumio[1004]: info: ControllerMpd::pushState Jan 16 17:45:44 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Jan 16 17:45:44 volumio volumio[1004]: info: CorePlayQueue::getTrack 95 Jan 16 17:45:44 volumio volumio[1004]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":292,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Unity","artist":"Subtension","album":"Tom Yum","uri":"http://localhost:6601/?codec=flac&url=https%3A%2F%2Fstrm-std-22.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dfa2e0ba4dd9f9cc424a9a548ce65f9adb76c714da2bd1ad5b26679660bfc90eb%2Clid%3D1537%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67968b06%2F0%2F788404%2F8382eec0.94746894.14.63310575%2Fflac&ext=.flac","trackType":"flac"} Jan 16 17:45:44 volumio volumio[1004]: verbose: CURRENT POSITION 95 Jan 16 17:45:44 volumio volumio[1004]: info: CoreStateMachine::syncState stateService play Jan 16 17:45:44 volumio volumio[1004]: info: CoreStateMachine::syncState currentStatus play Jan 16 17:45:44 volumio volumio[1004]: info: Received an update from plugin. extracting info from payload Jan 16 17:45:44 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:44 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:44 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:44 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:44 volumio volumio[1004]: info: ------------------------------ 157ms Jan 16 17:45:44 volumio volumio[1004]: info: ------------------------------ 156ms Jan 16 17:45:45 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Jan 16 17:45:45 volumio volumio[1004]: info: CoreStateMachine::getQueue Jan 16 17:45:45 volumio volumio[1004]: info: CorePlayQueue::getQueue Jan 16 17:45:45 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Jan 16 17:45:45 volumio volumio[1004]: info: CoreStateMachine::getQueue Jan 16 17:45:45 volumio volumio[1004]: info: CorePlayQueue::getQueue Jan 16 17:45:45 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Jan 16 17:45:45 volumio volumio[1004]: info: CoreStateMachine::getQueue Jan 16 17:45:45 volumio volumio[1004]: info: CorePlayQueue::getQueue Jan 16 17:45:45 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Jan 16 17:45:45 volumio volumio[1004]: info: CoreStateMachine::getQueue Jan 16 17:45:45 volumio volumio[1004]: info: CorePlayQueue::getQueue Jan 16 17:45:46 volumio volumio[1004]: info: CoreCommandRouter::volumioNext Jan 16 17:45:46 volumio volumio[1004]: info: CoreStateMachine::next Jan 16 17:45:46 volumio volumio[1004]: info: ControllerMpd::next Jan 16 17:45:46 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand next Jan 16 17:45:46 volumio volumio[1004]: info: Jan 16 17:45:46 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:46 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:46 volumio volumio[1004]: info: Jan 16 17:45:46 volumio volumio[1004]: ---------------------------- MPD announces state update: player Jan 16 17:45:46 volumio volumio[1004]: info: sendMpdCommand next took 33 milliseconds Jan 16 17:45:46 volumio volumio[1004]: info: ControllerMpd::getState Jan 16 17:45:46 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Jan 16 17:45:46 volumio volumio[1004]: info: Jan 16 17:45:46 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:46 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:46 volumio volumio[1004]: info: Jan 16 17:45:46 volumio volumio[1004]: ---------------------------- MPD announces state update: player Jan 16 17:45:46 volumio volumio[1004]: info: ControllerMpd::getState Jan 16 17:45:46 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Jan 16 17:45:46 volumio volumio[1004]: info: Jan 16 17:45:46 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:46 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:46 volumio volumio[1004]: info: Jan 16 17:45:46 volumio volumio[1004]: ---------------------------- MPD announces state update: player Jan 16 17:45:46 volumio volumio[1004]: info: ControllerMpd::getState Jan 16 17:45:46 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Jan 16 17:45:46 volumio volumio[1004]: error: updateQueue error: null Jan 16 17:45:46 volumio volumio[1004]: error: updateQueue error: null Jan 16 17:45:46 volumio volumio[1004]: error: updateQueue error: null Jan 16 17:45:46 volumio volumio[1004]: info: ------------------------------ 13ms Jan 16 17:45:46 volumio volumio[1004]: info: sendMpdCommand status took 11 milliseconds Jan 16 17:45:46 volumio volumio[1004]: info: ------------------------------ 11ms Jan 16 17:45:46 volumio volumio[1004]: info: sendMpdCommand status took 8 milliseconds Jan 16 17:45:46 volumio volumio[1004]: info: ------------------------------ 8ms Jan 16 17:45:46 volumio volumio[1004]: verbose: ControllerMpd::parseState Jan 16 17:45:46 volumio volumio[1004]: verbose: ControllerMpd::parseState Jan 16 17:45:46 volumio volumio[1004]: info: ControllerMpd::pushState Jan 16 17:45:46 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Jan 16 17:45:46 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:46 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 16 17:45:46 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:46 volumio volumio[1004]: info: CorePlayQueue::getTrack 95 Jan 16 17:45:46 volumio volumio[1004]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 16 17:45:46 volumio volumio[1004]: verbose: CURRENT POSITION 95 Jan 16 17:45:46 volumio volumio[1004]: info: CoreStateMachine::syncState stateService stop Jan 16 17:45:46 volumio volumio[1004]: info: CoreStateMachine::syncState currentStatus play Jan 16 17:45:46 volumio volumio[1004]: info: CoreStateMachine::play index undefined Jan 16 17:45:46 volumio volumio[1004]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 16 17:45:46 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:46 volumio volumio[1004]: info: CorePlayQueue::getTrack 96 Jan 16 17:45:46 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 16 17:45:46 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:46 volumio volumio[1004]: info: ControllerMpd::pushState Jan 16 17:45:46 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Jan 16 17:45:46 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:46 volumio volumio[1004]: info: CorePlayQueue::getTrack 96 Jan 16 17:45:46 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:46 volumio volumio[1004]: info: CorePlayQueue::getTrack 96 Jan 16 17:45:46 volumio volumio[1004]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd Jan 16 17:45:46 volumio volumio[1004]: info: CorePlayQueue::getTrack 96 Jan 16 17:45:46 volumio volumio[1004]: info: CoreStateMachine::startPlaybackTimer Jan 16 17:45:46 volumio volumio[1004]: info: CorePlayQueue::getTrack 96 Jan 16 17:45:46 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand stop Jan 16 17:45:46 volumio volumio[1004]: info: ------------------------------ 39ms Jan 16 17:45:46 volumio volumio[1004]: info: ------------------------------ 37ms Jan 16 17:45:46 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:46 volumio volumio[1004]: info: CorePlayQueue::getTrack 96 Jan 16 17:45:46 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 16 17:45:46 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:46 volumio volumio[1004]: info: sendMpdCommand status took 48 milliseconds Jan 16 17:45:46 volumio volumio[1004]: info: sendMpdCommand stop took 19 milliseconds Jan 16 17:45:46 volumio volumio[1004]: verbose: ControllerMpd::parseState Jan 16 17:45:46 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand clear Jan 16 17:45:46 volumio volumio[1004]: info: ControllerMpd::pushState Jan 16 17:45:46 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Jan 16 17:45:46 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:46 volumio volumio[1004]: info: CorePlayQueue::getTrack 96 Jan 16 17:45:46 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 16 17:45:46 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:46 volumio volumio[1004]: info: CorePlayQueue::getTrack 96 Jan 16 17:45:46 volumio volumio[1004]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd Jan 16 17:45:46 volumio volumio[1004]: info: ------------------------------ 62ms Jan 16 17:45:46 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Jan 16 17:45:46 volumio volumio[1004]: info: CoreStateMachine::getQueue Jan 16 17:45:46 volumio volumio[1004]: info: CorePlayQueue::getQueue Jan 16 17:45:46 volumio volumio[1004]: info: Jan 16 17:45:46 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:46 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:46 volumio volumio[1004]: info: sendMpdCommand clear took 25 milliseconds Jan 16 17:45:46 volumio volumio[1004]: info: Jan 16 17:45:46 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:46 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:46 volumio volumio[1004]: info: Jan 16 17:45:46 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:46 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:46 volumio volumio[1004]: error: updateQueue error: null Jan 16 17:45:46 volumio volumio[1004]: error: updateQueue error: null Jan 16 17:45:46 volumio volumio[1004]: info: ------------------------------ 18ms Jan 16 17:45:46 volumio volumio[1004]: info: ------------------------------ 6ms Jan 16 17:45:46 volumio volumio[1004]: info: ------------------------------ 5ms Jan 16 17:45:46 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=flac&url=https%3A%2F%2Fstrm-std-20.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D66dd3c79541a4cd32a21acd2e4f3d4deb4ac55f90008573b08e9c74ca61c0006%2Clid%3D1537%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67966da1%2F0%2F15289%2F4a1810b3.196061318.22.125908%2Fflac&ext=.flac" Jan 16 17:45:46 volumio volumio[1004]: info: Jan 16 17:45:46 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:46 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:46 volumio volumio[1004]: info: sendMpdCommand addid "http://localhost:6601/?codec=flac&url=https%3A%2F%2Fstrm-std-20.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D66dd3c79541a4cd32a21acd2e4f3d4deb4ac55f90008573b08e9c74ca61c0006%2Clid%3D1537%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67966da1%2F0%2F15289%2F4a1810b3.196061318.22.125908%2Fflac&ext=.flac" took 4 milliseconds Jan 16 17:45:46 volumio volumio[1004]: verbose: MPD COMMAND [object Object] Jan 16 17:45:46 volumio volumio[1004]: verbose: MPD COMMAND [object Object] Jan 16 17:45:46 volumio volumio[1004]: verbose: MPD COMMAND [object Object] Jan 16 17:45:46 volumio volumio[1004]: info: Jan 16 17:45:46 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:46 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:46 volumio volumio[1004]: info: Jan 16 17:45:46 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:46 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:46 volumio volumio[1004]: info: Jan 16 17:45:46 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:46 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:46 volumio volumio[1004]: info: ------------------------------ 9ms Jan 16 17:45:46 volumio volumio[1004]: info: ------------------------------ 6ms Jan 16 17:45:46 volumio volumio[1004]: info: ------------------------------ 5ms Jan 16 17:45:46 volumio volumio[1004]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 16 17:45:46 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand play Jan 16 17:45:46 volumio volumio[1004]: info: Jan 16 17:45:46 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:46 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:46 volumio volumio[1004]: info: Jan 16 17:45:46 volumio volumio[1004]: ---------------------------- MPD announces system playlist update Jan 16 17:45:46 volumio volumio[1004]: info: Ignoring MPD Status Update Jan 16 17:45:46 volumio volumio[1004]: info: ------------------------------ 10ms Jan 16 17:45:46 volumio volumio[1004]: info: sendMpdCommand play took 7 milliseconds Jan 16 17:45:46 volumio volumio[1004]: info: ------------------------------ 5ms Jan 16 17:45:46 volumio volumio[1004]: info: ------------------------------ 4ms Jan 16 17:45:46 volumio volumio[1004]: info: CoreCommandRouter::volumioAddQueueItems Jan 16 17:45:46 volumio volumio[1004]: info: CoreStateMachine::addQueueItems Jan 16 17:45:46 volumio volumio[1004]: info: CorePlayQueue::addQueueItems Jan 16 17:45:46 volumio volumio[1004]: info: Preload queue cleared Jan 16 17:45:46 volumio volumio[1004]: info: Adding Item to queue: yandex_music/track/134394287:34606604@user:onyourwave Jan 16 17:45:46 volumio volumio[1004]: info: Exploding uri yandex_music/track/134394287:34606604@user:onyourwave in service yandex_music Jan 16 17:45:46 volumio volumio[1004]: info: CoreCommandRouter::volumioPushQueue Jan 16 17:45:46 volumio volumio[1004]: info: CorePlayQueue::saveQueue Jan 16 17:45:46 volumio volumio[1004]: info: CoreStateMachine::updateTrackBlock Jan 16 17:45:46 volumio volumio[1004]: info: CorePlayQueue::getTrackBlock Jan 16 17:45:46 volumio volumio[1004]: info: Jan 16 17:45:46 volumio volumio[1004]: ---------------------------- MPD announces state update: player Jan 16 17:45:46 volumio volumio[1004]: info: ControllerMpd::getState Jan 16 17:45:46 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Jan 16 17:45:46 volumio volumio[1004]: info: Jan 16 17:45:46 volumio volumio[1004]: ---------------------------- MPD announces state update: player Jan 16 17:45:46 volumio volumio[1004]: info: ControllerMpd::getState Jan 16 17:45:46 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Jan 16 17:45:46 volumio volumio[1004]: info: Jan 16 17:45:46 volumio volumio[1004]: ---------------------------- MPD announces state update: player Jan 16 17:45:46 volumio volumio[1004]: info: ControllerMpd::getState Jan 16 17:45:46 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Jan 16 17:45:46 volumio volumio[1004]: info: Jan 16 17:45:46 volumio volumio[1004]: ---------------------------- MPD announces state update: player Jan 16 17:45:46 volumio volumio[1004]: info: sendMpdCommand status took 7 milliseconds Jan 16 17:45:46 volumio volumio[1004]: info: sendMpdCommand status took 6 milliseconds Jan 16 17:45:46 volumio volumio[1004]: info: ControllerMpd::getState Jan 16 17:45:46 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Jan 16 17:45:46 volumio volumio[1004]: info: sendMpdCommand status took 5 milliseconds Jan 16 17:45:46 volumio volumio[1004]: verbose: ControllerMpd::parseState Jan 16 17:45:46 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 16 17:45:46 volumio volumio[1004]: verbose: ControllerMpd::parseState Jan 16 17:45:46 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 16 17:45:46 volumio volumio[1004]: verbose: ControllerMpd::parseState Jan 16 17:45:46 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 16 17:45:46 volumio volumio[1004]: info: Jan 16 17:45:46 volumio volumio[1004]: ---------------------------- MPD announces state update: player Jan 16 17:45:46 volumio volumio[1004]: info: ControllerMpd::getState Jan 16 17:45:46 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Jan 16 17:45:46 volumio volumio[1004]: info: Jan 16 17:45:46 volumio volumio[1004]: ---------------------------- MPD announces state update: player Jan 16 17:45:46 volumio volumio[1004]: info: ControllerMpd::getState Jan 16 17:45:46 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Jan 16 17:45:46 volumio volumio[1004]: info: sendMpdCommand status took 28 milliseconds Jan 16 17:45:46 volumio volumio[1004]: info: sendMpdCommand playlistinfo took 30 milliseconds Jan 16 17:45:46 volumio volumio[1004]: info: sendMpdCommand playlistinfo took 29 milliseconds Jan 16 17:45:46 volumio volumio[1004]: info: sendMpdCommand status took 26 milliseconds Jan 16 17:45:46 volumio volumio[1004]: info: sendMpdCommand status took 25 milliseconds Jan 16 17:45:46 volumio volumio[1004]: verbose: ControllerMpd::parseState Jan 16 17:45:46 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 16 17:45:46 volumio volumio[1004]: verbose: ControllerMpd::parseTrackInfo Jan 16 17:45:46 volumio volumio[1004]: verbose: ControllerMpd::parseTrackInfo Jan 16 17:45:46 volumio volumio[1004]: verbose: ControllerMpd::parseTrackInfo Jan 16 17:45:46 volumio volumio[1004]: verbose: ControllerMpd::parseState Jan 16 17:45:46 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 16 17:45:46 volumio volumio[1004]: verbose: ControllerMpd::parseState Jan 16 17:45:46 volumio volumio[1004]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 16 17:45:46 volumio volumio[1004]: info: ControllerMpd::pushState Jan 16 17:45:46 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Jan 16 17:45:46 volumio volumio[1004]: info: CorePlayQueue::getTrack 96 Jan 16 17:45:46 volumio volumio[1004]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":394,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Paradise (Not For Me)","artist":"Madonna","album":"Music","uri":"http://localhost:6601/?codec=flac&url=https%3A%2F%2Fstrm-std-20.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D66dd3c79541a4cd32a21acd2e4f3d4deb4ac55f90008573b08e9c74ca61c0006%2Clid%3D1537%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67966da1%2F0%2F15289%2F4a1810b3.196061318.22.125908%2Fflac&ext=.flac","trackType":"flac"} Jan 16 17:45:46 volumio volumio[1004]: verbose: CURRENT POSITION 96 Jan 16 17:45:46 volumio volumio[1004]: info: CoreStateMachine::syncState stateService play Jan 16 17:45:46 volumio volumio[1004]: info: CoreStateMachine::syncState currentStatus stop Jan 16 17:45:46 volumio volumio[1004]: info: ControllerMpd::pushState Jan 16 17:45:46 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Jan 16 17:45:46 volumio volumio[1004]: info: CorePlayQueue::getTrack 96 Jan 16 17:45:46 volumio volumio[1004]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":394,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Paradise (Not For Me)","artist":"Madonna","album":"Music","uri":"http://localhost:6601/?codec=flac&url=https%3A%2F%2Fstrm-std-20.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D66dd3c79541a4cd32a21acd2e4f3d4deb4ac55f90008573b08e9c74ca61c0006%2Clid%3D1537%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67966da1%2F0%2F15289%2F4a1810b3.196061318.22.125908%2Fflac&ext=.flac","trackType":"flac"} Jan 16 17:45:46 volumio volumio[1004]: verbose: CURRENT POSITION 96 Jan 16 17:45:46 volumio volumio[1004]: info: CoreStateMachine::syncState stateService play Jan 16 17:45:46 volumio volumio[1004]: info: CoreStateMachine::syncState currentStatus play Jan 16 17:45:46 volumio volumio[1004]: info: Received an update from plugin. extracting info from payload Jan 16 17:45:46 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:46 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 16 17:45:46 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:46 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:46 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:46 volumio volumio[1004]: info: ControllerMpd::pushState Jan 16 17:45:46 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Jan 16 17:45:46 volumio volumio[1004]: info: CorePlayQueue::getTrack 96 Jan 16 17:45:46 volumio volumio[1004]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":394,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Paradise (Not For Me)","artist":"Madonna","album":"Music","uri":"http://localhost:6601/?codec=flac&url=https%3A%2F%2Fstrm-std-20.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D66dd3c79541a4cd32a21acd2e4f3d4deb4ac55f90008573b08e9c74ca61c0006%2Clid%3D1537%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67966da1%2F0%2F15289%2F4a1810b3.196061318.22.125908%2Fflac&ext=.flac","trackType":"flac"} Jan 16 17:45:46 volumio volumio[1004]: verbose: CURRENT POSITION 96 Jan 16 17:45:46 volumio volumio[1004]: info: CoreStateMachine::syncState stateService play Jan 16 17:45:46 volumio volumio[1004]: info: CoreStateMachine::syncState currentStatus play Jan 16 17:45:46 volumio volumio[1004]: info: Received an update from plugin. extracting info from payload Jan 16 17:45:46 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:46 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:46 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:46 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:46 volumio volumio[1004]: info: ------------------------------ 78ms Jan 16 17:45:46 volumio volumio[1004]: info: ------------------------------ 84ms Jan 16 17:45:46 volumio volumio[1004]: info: ------------------------------ 83ms Jan 16 17:45:47 volumio volumio[1004]: info: sendMpdCommand playlistinfo took 51 milliseconds Jan 16 17:45:47 volumio volumio[1004]: info: sendMpdCommand playlistinfo took 50 milliseconds Jan 16 17:45:47 volumio volumio[1004]: info: sendMpdCommand playlistinfo took 51 milliseconds Jan 16 17:45:47 volumio volumio[1004]: verbose: ControllerMpd::parseTrackInfo Jan 16 17:45:47 volumio volumio[1004]: verbose: ControllerMpd::parseTrackInfo Jan 16 17:45:47 volumio volumio[1004]: info: ControllerMpd::pushState Jan 16 17:45:47 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Jan 16 17:45:47 volumio volumio[1004]: info: CorePlayQueue::getTrack 96 Jan 16 17:45:47 volumio volumio[1004]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":394,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Paradise (Not For Me)","artist":"Madonna","album":"Music","uri":"http://localhost:6601/?codec=flac&url=https%3A%2F%2Fstrm-std-20.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D66dd3c79541a4cd32a21acd2e4f3d4deb4ac55f90008573b08e9c74ca61c0006%2Clid%3D1537%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67966da1%2F0%2F15289%2F4a1810b3.196061318.22.125908%2Fflac&ext=.flac","trackType":"flac"} Jan 16 17:45:47 volumio volumio[1004]: verbose: CURRENT POSITION 96 Jan 16 17:45:47 volumio volumio[1004]: info: CoreStateMachine::syncState stateService play Jan 16 17:45:47 volumio volumio[1004]: info: CoreStateMachine::syncState currentStatus play Jan 16 17:45:47 volumio volumio[1004]: info: Received an update from plugin. extracting info from payload Jan 16 17:45:47 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:47 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:47 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:47 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:47 volumio volumio[1004]: info: ControllerMpd::pushState Jan 16 17:45:47 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Jan 16 17:45:47 volumio volumio[1004]: info: CorePlayQueue::getTrack 96 Jan 16 17:45:47 volumio volumio[1004]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":394,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Paradise (Not For Me)","artist":"Madonna","album":"Music","uri":"http://localhost:6601/?codec=flac&url=https%3A%2F%2Fstrm-std-20.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D66dd3c79541a4cd32a21acd2e4f3d4deb4ac55f90008573b08e9c74ca61c0006%2Clid%3D1537%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67966da1%2F0%2F15289%2F4a1810b3.196061318.22.125908%2Fflac&ext=.flac","trackType":"flac"} Jan 16 17:45:47 volumio volumio[1004]: verbose: CURRENT POSITION 96 Jan 16 17:45:47 volumio volumio[1004]: info: CoreStateMachine::syncState stateService play Jan 16 17:45:47 volumio volumio[1004]: info: CoreStateMachine::syncState currentStatus play Jan 16 17:45:47 volumio volumio[1004]: info: Received an update from plugin. extracting info from payload Jan 16 17:45:47 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:47 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:47 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:47 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:47 volumio volumio[1004]: info: ControllerMpd::pushState Jan 16 17:45:47 volumio volumio[1004]: info: CoreCommandRouter::servicePushState Jan 16 17:45:47 volumio volumio[1004]: info: CorePlayQueue::getTrack 96 Jan 16 17:45:47 volumio volumio[1004]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":394,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Paradise (Not For Me)","artist":"Madonna","album":"Music","uri":"http://localhost:6601/?codec=flac&url=https%3A%2F%2Fstrm-std-20.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D66dd3c79541a4cd32a21acd2e4f3d4deb4ac55f90008573b08e9c74ca61c0006%2Clid%3D1537%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D67966da1%2F0%2F15289%2F4a1810b3.196061318.22.125908%2Fflac&ext=.flac","trackType":"flac"} Jan 16 17:45:47 volumio volumio[1004]: verbose: CURRENT POSITION 96 Jan 16 17:45:47 volumio volumio[1004]: info: CoreStateMachine::syncState stateService play Jan 16 17:45:47 volumio volumio[1004]: info: CoreStateMachine::syncState currentStatus play Jan 16 17:45:47 volumio volumio[1004]: info: Received an update from plugin. extracting info from payload Jan 16 17:45:47 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:47 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:47 volumio volumio[1004]: info: CoreStateMachine::pushState Jan 16 17:45:47 volumio volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 16 17:45:47 volumio volumio[1004]: info: ------------------------------ 140ms Jan 16 17:45:47 volumio volumio[1004]: info: ------------------------------ 133ms Jan 16 17:45:47 volumio volumio[1004]: info: ------------------------------ 131ms Jan 16 17:45:47 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Jan 16 17:45:47 volumio volumio[1004]: info: CoreStateMachine::getQueue Jan 16 17:45:47 volumio volumio[1004]: info: CorePlayQueue::getQueue Jan 16 17:45:47 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Jan 16 17:45:47 volumio volumio[1004]: info: CoreStateMachine::getQueue Jan 16 17:45:47 volumio volumio[1004]: info: CorePlayQueue::getQueue Jan 16 17:45:47 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Jan 16 17:45:47 volumio volumio[1004]: info: CoreStateMachine::getQueue Jan 16 17:45:47 volumio volumio[1004]: info: CorePlayQueue::getQueue Jan 16 17:45:47 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Jan 16 17:45:47 volumio volumio[1004]: info: CoreStateMachine::getQueue Jan 16 17:45:47 volumio volumio[1004]: info: CorePlayQueue::getQueue Jan 16 17:45:47 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Jan 16 17:45:47 volumio volumio[1004]: info: CoreStateMachine::getQueue Jan 16 17:45:47 volumio volumio[1004]: info: CorePlayQueue::getQueue Jan 16 17:45:47 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Jan 16 17:45:47 volumio volumio[1004]: info: CoreStateMachine::getQueue Jan 16 17:45:47 volumio volumio[1004]: info: CorePlayQueue::getQueue Jan 16 17:45:47 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Jan 16 17:45:47 volumio volumio[1004]: info: CoreStateMachine::getQueue Jan 16 17:45:47 volumio volumio[1004]: info: CorePlayQueue::getQueue Jan 16 17:45:47 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Jan 16 17:45:47 volumio volumio[1004]: info: CoreStateMachine::getQueue Jan 16 17:45:47 volumio volumio[1004]: info: CorePlayQueue::getQueue Jan 16 17:45:47 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Jan 16 17:45:47 volumio volumio[1004]: info: CoreStateMachine::getQueue Jan 16 17:45:47 volumio volumio[1004]: info: CorePlayQueue::getQueue Jan 16 17:45:47 volumio volumio[1004]: info: CoreCommandRouter::volumioGetQueue Jan 16 17:45:47 volumio volumio[1004]: info: CoreStateMachine::getQueue Jan 16 17:45:47 volumio volumio[1004]: info: CorePlayQueue::getQueue Jan 16 17:45:47 volumio volumio[1004]: info: CoreCommandRouter::volumioAddQueueItems Jan 16 17:45:47 volumio volumio[1004]: info: CoreStateMachine::addQueueItems Jan 16 17:45:47 volumio volumio[1004]: info: CorePlayQueue::addQueueItems Jan 16 17:45:47 volumio volumio[1004]: info: Preload queue cleared Jan 16 17:45:47 volumio volumio[1004]: info: Adding Item to queue: yandex_music/track/2409918:238416@user:onyourwave Jan 16 17:45:47 volumio volumio[1004]: info: Exploding uri yandex_music/track/2409918:238416@user:onyourwave in service yandex_music Jan 16 17:45:47 volumio volumio[1004]: info: CoreCommandRouter::volumioAddQueueItems Jan 16 17:45:47 volumio volumio[1004]: info: CoreStateMachine::addQueueItems Jan 16 17:45:47 volumio volumio[1004]: info: CorePlayQueue::addQueueItems Jan 16 17:45:47 volumio volumio[1004]: info: Preload queue cleared Jan 16 17:45:47 volumio volumio[1004]: info: Adding Item to queue: yandex_music/track/2448861:242721@user:onyourwave Jan 16 17:45:47 volumio volumio[1004]: info: Exploding uri yandex_music/track/2448861:242721@user:onyourwave in service yandex_music Jan 16 17:45:47 volumio volumio[1004]: info: CoreCommandRouter::volumioPushQueue Jan 16 17:45:48 volumio volumio[1004]: info: CorePlayQueue::saveQueue Jan 16 17:45:48 volumio volumio[1004]: info: CoreCommandRouter::volumioPushQueue Jan 16 17:45:48 volumio volumio[1004]: info: CorePlayQueue::saveQueue Jan 16 17:45:48 volumio volumio[1004]: info: CoreStateMachine::updateTrackBlock Jan 16 17:45:48 volumio volumio[1004]: info: CorePlayQueue::getTrackBlock Jan 16 17:45:48 volumio volumio[1004]: info: CoreStateMachine::updateTrackBlock Jan 16 17:45:48 volumio volumio[1004]: info: CorePlayQueue::getTrackBlock Jan 16 17:45:55 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 16 17:45:55 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 16 17:45:55 volumio volumio[1004]: info: Discovery: Getting this device information Jan 16 17:45:55 volumio volumio[1004]: info: CoreCommandRouter::volumioGetState Jan 16 17:45:55 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 16 17:46:05 volumio volumio[1004]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 16 17:46:05 volumio volumio[1004]: Error: read ECONNRESET Jan 16 17:46:05 volumio volumio[1004]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:209:20) { Jan 16 17:46:05 volumio volumio[1004]: errno: -104, Jan 16 17:46:05 volumio volumio[1004]: code: 'ECONNRESET', Jan 16 17:46:05 volumio volumio[1004]: syscall: 'read' Jan 16 17:46:05 volumio volumio[1004]: } Jan 16 17:46:05 volumio volumio[1004]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 16 17:46:06 volumio sudo[28760]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-16 17:45 Jan 16 17:46:06 volumio sudo[28760]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 07:25:16 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="66c567362bdbb3dc1644a18f7879afe7"