-- Logs begin at Thu 2019-02-14 17:11:59 +07, end at Mon 2026-02-09 20:44:19 +07. -- Feb 09 20:43:14 volumio3 volumio[698]: info: CoreCommandRouter::volumioGetState Feb 09 20:43:16 volumio3 volumio[698]: info: CoreCommandRouter::volumioNext Feb 09 20:43:16 volumio3 volumio[698]: info: CoreStateMachine::next Feb 09 20:43:16 volumio3 volumio[698]: info: ControllerMpd::next Feb 09 20:43:16 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand next Feb 09 20:43:16 volumio3 volumio[698]: info: sendMpdCommand next took 49 milliseconds Feb 09 20:43:16 volumio3 volumio[698]: info: Feb 09 20:43:16 volumio3 volumio[698]: ---------------------------- MPD announces system playlist update Feb 09 20:43:16 volumio3 volumio[698]: info: Ignoring MPD Status Update Feb 09 20:43:16 volumio3 volumio[698]: info: Feb 09 20:43:16 volumio3 volumio[698]: ---------------------------- MPD announces state update: player Feb 09 20:43:16 volumio3 volumio[698]: info: ControllerMpd::getState Feb 09 20:43:16 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand status Feb 09 20:43:16 volumio3 volumio[698]: error: updateQueue error: null Feb 09 20:43:16 volumio3 volumio[698]: info: ------------------------------ 6ms Feb 09 20:43:16 volumio3 volumio[698]: info: sendMpdCommand status took 3 milliseconds Feb 09 20:43:16 volumio3 volumio[698]: verbose: ControllerMpd::parseState Feb 09 20:43:16 volumio3 volumio[698]: info: ControllerMpd::pushState Feb 09 20:43:16 volumio3 volumio[698]: info: CoreCommandRouter::servicePushState Feb 09 20:43:16 volumio3 volumio[698]: info: CoreStateMachine::pushState Feb 09 20:43:16 volumio3 volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 20:43:16 volumio3 volumio[698]: info: CoreCommandRouter::volumioPushState Feb 09 20:43:16 volumio3 volumio[698]: info: CorePlayQueue::getTrack 0 Feb 09 20:43:16 volumio3 volumio[698]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 09 20:43:16 volumio3 volumio[698]: verbose: CURRENT POSITION 0 Feb 09 20:43:16 volumio3 volumio[698]: info: CoreStateMachine::syncState stateService stop Feb 09 20:43:16 volumio3 volumio[698]: info: CoreStateMachine::syncState currentStatus play Feb 09 20:43:16 volumio3 volumio[698]: info: CoreStateMachine::play index undefined Feb 09 20:43:16 volumio3 volumio[698]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 20:43:16 volumio3 volumio[698]: info: CoreStateMachine::pushState Feb 09 20:43:16 volumio3 volumio[698]: info: CorePlayQueue::getTrack 1 Feb 09 20:43:16 volumio3 volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 20:43:16 volumio3 volumio[698]: info: CoreCommandRouter::volumioPushState Feb 09 20:43:16 volumio3 volumio[698]: info: CorePlayQueue::getTrack 1 Feb 09 20:43:16 volumio3 volumio[698]: info: CoreStateMachine::startPlaybackTimer Feb 09 20:43:16 volumio3 volumio[698]: info: CorePlayQueue::getTrack 1 Feb 09 20:43:16 volumio3 volumio[698]: info: [1770644596849] ControllerUPNPBrowser::clearAddPlayTrack Feb 09 20:43:16 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand stop Feb 09 20:43:16 volumio3 volumio[698]: info: ------------------------------ 44ms Feb 09 20:43:16 volumio3 volumio[698]: info: CoreStateMachine::pushState Feb 09 20:43:16 volumio3 volumio[698]: info: CorePlayQueue::getTrack 1 Feb 09 20:43:16 volumio3 volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 20:43:16 volumio3 volumio[698]: info: CoreCommandRouter::volumioPushState Feb 09 20:43:16 volumio3 volumio[698]: info: sendMpdCommand stop took 17 milliseconds Feb 09 20:43:16 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand clear Feb 09 20:43:16 volumio3 volumio[698]: info: CoreCommandRouter::volumioGetQueue Feb 09 20:43:16 volumio3 volumio[698]: info: CoreStateMachine::getQueue Feb 09 20:43:16 volumio3 volumio[698]: info: CorePlayQueue::getQueue Feb 09 20:43:16 volumio3 volumio[698]: info: Feb 09 20:43:16 volumio3 volumio[698]: ---------------------------- MPD announces system playlist update Feb 09 20:43:16 volumio3 volumio[698]: info: Ignoring MPD Status Update Feb 09 20:43:16 volumio3 volumio[698]: info: sendMpdCommand clear took 30 milliseconds Feb 09 20:43:16 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.0.22:10243/WMPNSSv4/4046149663/0_MTRfODczZjNhYmJfNjRiZTlmYmMtMzIyOA" Feb 09 20:43:16 volumio3 volumio[698]: error: updateQueue error: null Feb 09 20:43:16 volumio3 volumio[698]: info: ------------------------------ 12ms Feb 09 20:43:17 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.0.22:10243/WMPNSSv4/4046149663/0_MTRfODczZjNhYmJfNjRiZTlmYmMtMzIyOA" Feb 09 20:43:17 volumio3 volumio[698]: info: Feb 09 20:43:17 volumio3 volumio[698]: ---------------------------- MPD announces system playlist update Feb 09 20:43:17 volumio3 volumio[698]: info: Ignoring MPD Status Update Feb 09 20:43:17 volumio3 volumio[698]: info: sendMpdCommand add "http://192.168.0.22:10243/WMPNSSv4/4046149663/0_MTRfODczZjNhYmJfNjRiZTlmYmMtMzIyOA" took 3 milliseconds Feb 09 20:43:17 volumio3 volumio[698]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 09 20:43:17 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand play Feb 09 20:43:17 volumio3 volumio[698]: info: ------------------------------ 6ms Feb 09 20:43:17 volumio3 volumio[698]: info: sendMpdCommand play took 4 milliseconds Feb 09 20:43:17 volumio3 volumio[698]: info: Feb 09 20:43:17 volumio3 volumio[698]: ---------------------------- MPD announces state update: player Feb 09 20:43:17 volumio3 volumio[698]: info: ControllerMpd::getState Feb 09 20:43:17 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand status Feb 09 20:43:17 volumio3 volumio[698]: info: Feb 09 20:43:17 volumio3 volumio[698]: ---------------------------- MPD announces state update: player Feb 09 20:43:17 volumio3 volumio[698]: info: sendMpdCommand status took 97 milliseconds Feb 09 20:43:17 volumio3 volumio[698]: info: ControllerMpd::getState Feb 09 20:43:17 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand status Feb 09 20:43:17 volumio3 volumio[698]: verbose: ControllerMpd::parseState Feb 09 20:43:17 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 20:43:17 volumio3 volumio[698]: info: Feb 09 20:43:17 volumio3 volumio[698]: ---------------------------- MPD announces system playlist update Feb 09 20:43:17 volumio3 volumio[698]: info: Ignoring MPD Status Update Feb 09 20:43:17 volumio3 volumio[698]: info: sendMpdCommand status took 20 milliseconds Feb 09 20:43:17 volumio3 volumio[698]: info: sendMpdCommand playlistinfo took 16 milliseconds Feb 09 20:43:17 volumio3 volumio[698]: verbose: ControllerMpd::parseState Feb 09 20:43:17 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 20:43:17 volumio3 volumio[698]: verbose: ControllerMpd::parseTrackInfo Feb 09 20:43:17 volumio3 volumio[698]: info: ControllerMpd::pushState Feb 09 20:43:17 volumio3 volumio[698]: info: CoreCommandRouter::servicePushState Feb 09 20:43:17 volumio3 volumio[698]: info: CorePlayQueue::getTrack 1 Feb 09 20:43:17 volumio3 volumio[698]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":232,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"928 Kbps","isStreaming":false,"title":"Brass Devil","artist":"Parov Stelar","album":"Voodoo Sonic (The Album)","uri":"http://192.168.0.22:10243/WMPNSSv4/4046149663/0_MTRfODczZjNhYmJfNjRiZTlmYmMtMzIyOA","trackType":""} Feb 09 20:43:17 volumio3 volumio[698]: verbose: CURRENT POSITION 1 Feb 09 20:43:17 volumio3 volumio[698]: info: CoreStateMachine::syncState stateService play Feb 09 20:43:17 volumio3 volumio[698]: info: CoreStateMachine::syncState currentStatus stop Feb 09 20:43:17 volumio3 volumio[698]: info: ------------------------------ 150ms Feb 09 20:43:17 volumio3 volumio[698]: info: ------------------------------ 41ms Feb 09 20:43:17 volumio3 volumio[698]: info: sendMpdCommand playlistinfo took 33 milliseconds Feb 09 20:43:17 volumio3 volumio[698]: verbose: ControllerMpd::parseTrackInfo Feb 09 20:43:17 volumio3 volumio[698]: info: ControllerMpd::pushState Feb 09 20:43:17 volumio3 volumio[698]: info: CoreCommandRouter::servicePushState Feb 09 20:43:17 volumio3 volumio[698]: info: CorePlayQueue::getTrack 1 Feb 09 20:43:17 volumio3 volumio[698]: verbose: STATE SERVICE {"status":"play","position":0,"seek":845,"duration":232,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"723 Kbps","isStreaming":false,"title":"Brass Devil","artist":"Parov Stelar","album":"Voodoo Sonic (The Album)","uri":"http://192.168.0.22:10243/WMPNSSv4/4046149663/0_MTRfODczZjNhYmJfNjRiZTlmYmMtMzIyOA","trackType":""} Feb 09 20:43:17 volumio3 volumio[698]: verbose: CURRENT POSITION 1 Feb 09 20:43:17 volumio3 volumio[698]: info: CoreStateMachine::syncState stateService play Feb 09 20:43:17 volumio3 volumio[698]: info: CoreStateMachine::syncState currentStatus play Feb 09 20:43:17 volumio3 volumio[698]: info: Received an update from plugin. extracting info from payload Feb 09 20:43:17 volumio3 volumio[698]: info: CoreStateMachine::pushState Feb 09 20:43:17 volumio3 volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 20:43:17 volumio3 volumio[698]: info: CoreCommandRouter::volumioPushState Feb 09 20:43:17 volumio3 volumio[698]: info: CoreStateMachine::pushState Feb 09 20:43:17 volumio3 volumio[698]: info: CoreCommandRouter::volumioPushState Feb 09 20:43:17 volumio3 volumio[698]: info: ------------------------------ 196ms Feb 09 20:43:17 volumio3 volumio[698]: info: CoreCommandRouter::volumioGetQueue Feb 09 20:43:17 volumio3 volumio[698]: info: CoreStateMachine::getQueue Feb 09 20:43:17 volumio3 volumio[698]: info: CorePlayQueue::getQueue Feb 09 20:43:17 volumio3 volumio[698]: info: CoreCommandRouter::volumioGetQueue Feb 09 20:43:17 volumio3 volumio[698]: info: CoreStateMachine::getQueue Feb 09 20:43:17 volumio3 volumio[698]: info: CorePlayQueue::getQueue Feb 09 20:43:26 volumio3 volumio[698]: info: CoreCommandRouter::volumioNext Feb 09 20:43:26 volumio3 volumio[698]: info: CoreStateMachine::next Feb 09 20:43:26 volumio3 volumio[698]: info: ControllerMpd::next Feb 09 20:43:26 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand next Feb 09 20:43:26 volumio3 volumio[698]: info: sendMpdCommand next took 106 milliseconds Feb 09 20:43:26 volumio3 volumio[698]: info: Feb 09 20:43:26 volumio3 volumio[698]: ---------------------------- MPD announces system playlist update Feb 09 20:43:26 volumio3 volumio[698]: info: Ignoring MPD Status Update Feb 09 20:43:26 volumio3 volumio[698]: info: Feb 09 20:43:26 volumio3 volumio[698]: ---------------------------- MPD announces state update: player Feb 09 20:43:26 volumio3 volumio[698]: info: ControllerMpd::getState Feb 09 20:43:26 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand status Feb 09 20:43:26 volumio3 volumio[698]: error: updateQueue error: null Feb 09 20:43:26 volumio3 volumio[698]: info: ------------------------------ 4ms Feb 09 20:43:26 volumio3 volumio[698]: info: sendMpdCommand status took 3 milliseconds Feb 09 20:43:26 volumio3 volumio[698]: verbose: ControllerMpd::parseState Feb 09 20:43:26 volumio3 volumio[698]: info: ControllerMpd::pushState Feb 09 20:43:26 volumio3 volumio[698]: info: CoreCommandRouter::servicePushState Feb 09 20:43:26 volumio3 volumio[698]: info: CoreStateMachine::pushState Feb 09 20:43:26 volumio3 volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 20:43:26 volumio3 volumio[698]: info: CoreCommandRouter::volumioPushState Feb 09 20:43:26 volumio3 volumio[698]: info: CorePlayQueue::getTrack 1 Feb 09 20:43:26 volumio3 volumio[698]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 09 20:43:26 volumio3 volumio[698]: verbose: CURRENT POSITION 1 Feb 09 20:43:26 volumio3 volumio[698]: info: CoreStateMachine::syncState stateService stop Feb 09 20:43:26 volumio3 volumio[698]: info: CoreStateMachine::syncState currentStatus play Feb 09 20:43:26 volumio3 volumio[698]: info: CoreStateMachine::play index undefined Feb 09 20:43:26 volumio3 volumio[698]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 20:43:26 volumio3 volumio[698]: info: CoreStateMachine::pushState Feb 09 20:43:26 volumio3 volumio[698]: info: CorePlayQueue::getTrack 2 Feb 09 20:43:26 volumio3 volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 20:43:26 volumio3 volumio[698]: info: CoreCommandRouter::volumioPushState Feb 09 20:43:26 volumio3 volumio[698]: info: CorePlayQueue::getTrack 2 Feb 09 20:43:26 volumio3 volumio[698]: info: CoreStateMachine::startPlaybackTimer Feb 09 20:43:26 volumio3 volumio[698]: info: CorePlayQueue::getTrack 2 Feb 09 20:43:26 volumio3 volumio[698]: info: [1770644606317] ControllerUPNPBrowser::clearAddPlayTrack Feb 09 20:43:26 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand stop Feb 09 20:43:26 volumio3 volumio[698]: info: ------------------------------ 50ms Feb 09 20:43:26 volumio3 volumio[698]: info: CoreStateMachine::pushState Feb 09 20:43:26 volumio3 volumio[698]: info: CorePlayQueue::getTrack 2 Feb 09 20:43:26 volumio3 volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 20:43:26 volumio3 volumio[698]: info: CoreCommandRouter::volumioPushState Feb 09 20:43:26 volumio3 volumio[698]: info: sendMpdCommand stop took 17 milliseconds Feb 09 20:43:26 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand clear Feb 09 20:43:26 volumio3 volumio[698]: info: CoreCommandRouter::volumioGetQueue Feb 09 20:43:26 volumio3 volumio[698]: info: CoreStateMachine::getQueue Feb 09 20:43:26 volumio3 volumio[698]: info: CorePlayQueue::getQueue Feb 09 20:43:26 volumio3 volumio[698]: info: Feb 09 20:43:26 volumio3 volumio[698]: ---------------------------- MPD announces system playlist update Feb 09 20:43:26 volumio3 volumio[698]: info: Ignoring MPD Status Update Feb 09 20:43:26 volumio3 volumio[698]: info: sendMpdCommand clear took 25 milliseconds Feb 09 20:43:26 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.0.22:10243/WMPNSSv4/4046149663/0_MTRfODczZjNhYmJfNjRiZTlmYmMtMzIyOQ" Feb 09 20:43:26 volumio3 volumio[698]: error: updateQueue error: null Feb 09 20:43:26 volumio3 volumio[698]: info: ------------------------------ 6ms Feb 09 20:43:26 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.0.22:10243/WMPNSSv4/4046149663/0_MTRfODczZjNhYmJfNjRiZTlmYmMtMzIyOQ" Feb 09 20:43:26 volumio3 volumio[698]: info: Feb 09 20:43:26 volumio3 volumio[698]: ---------------------------- MPD announces system playlist update Feb 09 20:43:26 volumio3 volumio[698]: info: Ignoring MPD Status Update Feb 09 20:43:26 volumio3 volumio[698]: info: sendMpdCommand add "http://192.168.0.22:10243/WMPNSSv4/4046149663/0_MTRfODczZjNhYmJfNjRiZTlmYmMtMzIyOQ" took 1 milliseconds Feb 09 20:43:26 volumio3 volumio[698]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 09 20:43:26 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand play Feb 09 20:43:26 volumio3 volumio[698]: info: ------------------------------ 4ms Feb 09 20:43:26 volumio3 volumio[698]: info: sendMpdCommand play took 3 milliseconds Feb 09 20:43:26 volumio3 volumio[698]: info: Feb 09 20:43:26 volumio3 volumio[698]: ---------------------------- MPD announces state update: player Feb 09 20:43:26 volumio3 volumio[698]: info: ControllerMpd::getState Feb 09 20:43:26 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand status Feb 09 20:43:26 volumio3 volumio[698]: info: sendMpdCommand status took 100 milliseconds Feb 09 20:43:26 volumio3 volumio[698]: verbose: ControllerMpd::parseState Feb 09 20:43:26 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 20:43:26 volumio3 volumio[698]: info: Feb 09 20:43:26 volumio3 volumio[698]: ---------------------------- MPD announces state update: player Feb 09 20:43:26 volumio3 volumio[698]: info: ControllerMpd::getState Feb 09 20:43:26 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand status Feb 09 20:43:26 volumio3 volumio[698]: info: Feb 09 20:43:26 volumio3 volumio[698]: ---------------------------- MPD announces system playlist update Feb 09 20:43:26 volumio3 volumio[698]: info: Ignoring MPD Status Update Feb 09 20:43:26 volumio3 volumio[698]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 09 20:43:26 volumio3 volumio[698]: verbose: ControllerMpd::parseTrackInfo Feb 09 20:43:26 volumio3 volumio[698]: info: ControllerMpd::pushState Feb 09 20:43:26 volumio3 volumio[698]: info: CoreCommandRouter::servicePushState Feb 09 20:43:26 volumio3 volumio[698]: info: CorePlayQueue::getTrack 2 Feb 09 20:43:26 volumio3 volumio[698]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":311,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Come Back Home","artist":"Parov Stelar","album":"Voodoo Sonic (The Album)","uri":"http://192.168.0.22:10243/WMPNSSv4/4046149663/0_MTRfODczZjNhYmJfNjRiZTlmYmMtMzIyOQ","trackType":""} Feb 09 20:43:26 volumio3 volumio[698]: verbose: CURRENT POSITION 2 Feb 09 20:43:26 volumio3 volumio[698]: info: CoreStateMachine::syncState stateService play Feb 09 20:43:26 volumio3 volumio[698]: info: CoreStateMachine::syncState currentStatus stop Feb 09 20:43:26 volumio3 volumio[698]: info: ------------------------------ 114ms Feb 09 20:43:26 volumio3 volumio[698]: info: sendMpdCommand status took 10 milliseconds Feb 09 20:43:26 volumio3 volumio[698]: info: ------------------------------ 8ms Feb 09 20:43:26 volumio3 volumio[698]: verbose: ControllerMpd::parseState Feb 09 20:43:26 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 20:43:26 volumio3 volumio[698]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 09 20:43:26 volumio3 volumio[698]: verbose: ControllerMpd::parseTrackInfo Feb 09 20:43:26 volumio3 volumio[698]: info: ControllerMpd::pushState Feb 09 20:43:26 volumio3 volumio[698]: info: CoreCommandRouter::servicePushState Feb 09 20:43:26 volumio3 volumio[698]: info: CorePlayQueue::getTrack 2 Feb 09 20:43:26 volumio3 volumio[698]: verbose: STATE SERVICE {"status":"play","position":0,"seek":845,"duration":311,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"716 Kbps","isStreaming":false,"title":"Come Back Home","artist":"Parov Stelar","album":"Voodoo Sonic (The Album)","uri":"http://192.168.0.22:10243/WMPNSSv4/4046149663/0_MTRfODczZjNhYmJfNjRiZTlmYmMtMzIyOQ","trackType":""} Feb 09 20:43:26 volumio3 volumio[698]: verbose: CURRENT POSITION 2 Feb 09 20:43:26 volumio3 volumio[698]: info: CoreStateMachine::syncState stateService play Feb 09 20:43:26 volumio3 volumio[698]: info: CoreStateMachine::syncState currentStatus play Feb 09 20:43:26 volumio3 volumio[698]: info: Received an update from plugin. extracting info from payload Feb 09 20:43:26 volumio3 volumio[698]: info: CoreStateMachine::pushState Feb 09 20:43:26 volumio3 volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 20:43:26 volumio3 volumio[698]: info: CoreCommandRouter::volumioPushState Feb 09 20:43:26 volumio3 volumio[698]: info: CoreStateMachine::pushState Feb 09 20:43:26 volumio3 volumio[698]: info: CoreCommandRouter::volumioPushState Feb 09 20:43:26 volumio3 volumio[698]: info: ------------------------------ 57ms Feb 09 20:43:26 volumio3 volumio[698]: info: CoreCommandRouter::volumioGetQueue Feb 09 20:43:26 volumio3 volumio[698]: info: CoreStateMachine::getQueue Feb 09 20:43:26 volumio3 volumio[698]: info: CorePlayQueue::getQueue Feb 09 20:43:26 volumio3 volumio[698]: info: CoreCommandRouter::volumioGetQueue Feb 09 20:43:26 volumio3 volumio[698]: info: CoreStateMachine::getQueue Feb 09 20:43:26 volumio3 volumio[698]: info: CorePlayQueue::getQueue Feb 09 20:43:31 volumio3 volumio[698]: info: CoreCommandRouter::volumioNext Feb 09 20:43:31 volumio3 volumio[698]: info: CoreStateMachine::next Feb 09 20:43:31 volumio3 volumio[698]: info: ControllerMpd::next Feb 09 20:43:31 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand next Feb 09 20:43:31 volumio3 volumio[698]: info: Feb 09 20:43:31 volumio3 volumio[698]: ---------------------------- MPD announces system playlist update Feb 09 20:43:31 volumio3 volumio[698]: info: Ignoring MPD Status Update Feb 09 20:43:31 volumio3 volumio[698]: info: Feb 09 20:43:31 volumio3 volumio[698]: ---------------------------- MPD announces state update: player Feb 09 20:43:31 volumio3 volumio[698]: info: sendMpdCommand next took 107 milliseconds Feb 09 20:43:31 volumio3 volumio[698]: info: ControllerMpd::getState Feb 09 20:43:31 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand status Feb 09 20:43:31 volumio3 volumio[698]: error: updateQueue error: null Feb 09 20:43:31 volumio3 volumio[698]: info: ------------------------------ 5ms Feb 09 20:43:31 volumio3 volumio[698]: info: sendMpdCommand status took 3 milliseconds Feb 09 20:43:31 volumio3 volumio[698]: verbose: ControllerMpd::parseState Feb 09 20:43:31 volumio3 volumio[698]: info: ControllerMpd::pushState Feb 09 20:43:31 volumio3 volumio[698]: info: CoreCommandRouter::servicePushState Feb 09 20:43:31 volumio3 volumio[698]: info: CoreStateMachine::pushState Feb 09 20:43:31 volumio3 volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 20:43:31 volumio3 volumio[698]: info: CoreCommandRouter::volumioPushState Feb 09 20:43:31 volumio3 volumio[698]: info: CorePlayQueue::getTrack 2 Feb 09 20:43:31 volumio3 volumio[698]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 09 20:43:31 volumio3 volumio[698]: verbose: CURRENT POSITION 2 Feb 09 20:43:31 volumio3 volumio[698]: info: CoreStateMachine::syncState stateService stop Feb 09 20:43:31 volumio3 volumio[698]: info: CoreStateMachine::syncState currentStatus play Feb 09 20:43:31 volumio3 volumio[698]: info: CoreStateMachine::play index undefined Feb 09 20:43:31 volumio3 volumio[698]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 20:43:31 volumio3 volumio[698]: info: CoreStateMachine::pushState Feb 09 20:43:31 volumio3 volumio[698]: info: CorePlayQueue::getTrack 3 Feb 09 20:43:31 volumio3 volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 20:43:31 volumio3 volumio[698]: info: CoreCommandRouter::volumioPushState Feb 09 20:43:31 volumio3 volumio[698]: info: CorePlayQueue::getTrack 3 Feb 09 20:43:31 volumio3 volumio[698]: info: CoreStateMachine::startPlaybackTimer Feb 09 20:43:31 volumio3 volumio[698]: info: CorePlayQueue::getTrack 3 Feb 09 20:43:31 volumio3 volumio[698]: info: [1770644611507] ControllerUPNPBrowser::clearAddPlayTrack Feb 09 20:43:31 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand stop Feb 09 20:43:31 volumio3 volumio[698]: info: ------------------------------ 30ms Feb 09 20:43:31 volumio3 volumio[698]: info: CoreStateMachine::pushState Feb 09 20:43:31 volumio3 volumio[698]: info: CorePlayQueue::getTrack 3 Feb 09 20:43:31 volumio3 volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 20:43:31 volumio3 volumio[698]: info: CoreCommandRouter::volumioPushState Feb 09 20:43:31 volumio3 volumio[698]: info: sendMpdCommand stop took 19 milliseconds Feb 09 20:43:31 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand clear Feb 09 20:43:31 volumio3 volumio[698]: info: CoreCommandRouter::volumioGetQueue Feb 09 20:43:31 volumio3 volumio[698]: info: CoreStateMachine::getQueue Feb 09 20:43:31 volumio3 volumio[698]: info: CorePlayQueue::getQueue Feb 09 20:43:31 volumio3 volumio[698]: info: Feb 09 20:43:31 volumio3 volumio[698]: ---------------------------- MPD announces system playlist update Feb 09 20:43:31 volumio3 volumio[698]: info: Ignoring MPD Status Update Feb 09 20:43:31 volumio3 volumio[698]: info: sendMpdCommand clear took 23 milliseconds Feb 09 20:43:31 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.0.22:10243/WMPNSSv4/4046149663/0_MTRfODczZjNhYmJfNjRiZTlmYmMtMzIyNA" Feb 09 20:43:31 volumio3 volumio[698]: error: updateQueue error: null Feb 09 20:43:31 volumio3 volumio[698]: info: ------------------------------ 7ms Feb 09 20:43:31 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.0.22:10243/WMPNSSv4/4046149663/0_MTRfODczZjNhYmJfNjRiZTlmYmMtMzIyNA" Feb 09 20:43:31 volumio3 volumio[698]: info: Feb 09 20:43:31 volumio3 volumio[698]: ---------------------------- MPD announces system playlist update Feb 09 20:43:31 volumio3 volumio[698]: info: Ignoring MPD Status Update Feb 09 20:43:31 volumio3 volumio[698]: info: sendMpdCommand add "http://192.168.0.22:10243/WMPNSSv4/4046149663/0_MTRfODczZjNhYmJfNjRiZTlmYmMtMzIyNA" took 3 milliseconds Feb 09 20:43:31 volumio3 volumio[698]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 09 20:43:31 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand play Feb 09 20:43:31 volumio3 volumio[698]: info: ------------------------------ 5ms Feb 09 20:43:31 volumio3 volumio[698]: info: sendMpdCommand play took 3 milliseconds Feb 09 20:43:31 volumio3 volumio[698]: info: Feb 09 20:43:31 volumio3 volumio[698]: ---------------------------- MPD announces state update: player Feb 09 20:43:31 volumio3 volumio[698]: info: ControllerMpd::getState Feb 09 20:43:31 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand status Feb 09 20:43:31 volumio3 volumio[698]: info: Feb 09 20:43:31 volumio3 volumio[698]: ---------------------------- MPD announces state update: player Feb 09 20:43:31 volumio3 volumio[698]: info: sendMpdCommand status took 34 milliseconds Feb 09 20:43:31 volumio3 volumio[698]: info: ControllerMpd::getState Feb 09 20:43:31 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand status Feb 09 20:43:31 volumio3 volumio[698]: verbose: ControllerMpd::parseState Feb 09 20:43:31 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 20:43:31 volumio3 volumio[698]: info: sendMpdCommand status took 6 milliseconds Feb 09 20:43:31 volumio3 volumio[698]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 09 20:43:31 volumio3 volumio[698]: verbose: ControllerMpd::parseState Feb 09 20:43:31 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 20:43:31 volumio3 volumio[698]: verbose: ControllerMpd::parseTrackInfo Feb 09 20:43:31 volumio3 volumio[698]: info: ControllerMpd::pushState Feb 09 20:43:31 volumio3 volumio[698]: info: CoreCommandRouter::servicePushState Feb 09 20:43:31 volumio3 volumio[698]: info: CorePlayQueue::getTrack 3 Feb 09 20:43:31 volumio3 volumio[698]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":173,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_MTRfODczZjNhYmJfNjRiZTlmYmMtMzIyNA","artist":null,"album":null,"uri":"http://192.168.0.22:10243/WMPNSSv4/4046149663/0_MTRfODczZjNhYmJfNjRiZTlmYmMtMzIyNA","trackType":""} Feb 09 20:43:31 volumio3 volumio[698]: verbose: CURRENT POSITION 3 Feb 09 20:43:31 volumio3 volumio[698]: info: CoreStateMachine::syncState stateService play Feb 09 20:43:31 volumio3 volumio[698]: info: CoreStateMachine::syncState currentStatus stop Feb 09 20:43:31 volumio3 volumio[698]: info: ------------------------------ 69ms Feb 09 20:43:31 volumio3 volumio[698]: info: sendMpdCommand playlistinfo took 23 milliseconds Feb 09 20:43:31 volumio3 volumio[698]: verbose: ControllerMpd::parseTrackInfo Feb 09 20:43:31 volumio3 volumio[698]: info: ControllerMpd::pushState Feb 09 20:43:31 volumio3 volumio[698]: info: CoreCommandRouter::servicePushState Feb 09 20:43:31 volumio3 volumio[698]: info: CorePlayQueue::getTrack 3 Feb 09 20:43:31 volumio3 volumio[698]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":173,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_MTRfODczZjNhYmJfNjRiZTlmYmMtMzIyNA","artist":null,"album":null,"uri":"http://192.168.0.22:10243/WMPNSSv4/4046149663/0_MTRfODczZjNhYmJfNjRiZTlmYmMtMzIyNA","trackType":""} Feb 09 20:43:31 volumio3 volumio[698]: verbose: CURRENT POSITION 3 Feb 09 20:43:31 volumio3 volumio[698]: info: CoreStateMachine::syncState stateService play Feb 09 20:43:31 volumio3 volumio[698]: info: CoreStateMachine::syncState currentStatus play Feb 09 20:43:31 volumio3 volumio[698]: info: Received an update from plugin. extracting info from payload Feb 09 20:43:31 volumio3 volumio[698]: info: CoreStateMachine::pushState Feb 09 20:43:31 volumio3 volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 20:43:31 volumio3 volumio[698]: info: CoreCommandRouter::volumioPushState Feb 09 20:43:31 volumio3 volumio[698]: info: CoreStateMachine::pushState Feb 09 20:43:31 volumio3 volumio[698]: info: CoreCommandRouter::volumioPushState Feb 09 20:43:31 volumio3 volumio[698]: info: ------------------------------ 82ms Feb 09 20:43:31 volumio3 volumio[698]: info: Feb 09 20:43:31 volumio3 volumio[698]: ---------------------------- MPD announces system playlist update Feb 09 20:43:31 volumio3 volumio[698]: info: Ignoring MPD Status Update Feb 09 20:43:31 volumio3 volumio[698]: info: Feb 09 20:43:31 volumio3 volumio[698]: ---------------------------- MPD announces state update: player Feb 09 20:43:31 volumio3 volumio[698]: info: ControllerMpd::getState Feb 09 20:43:31 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand status Feb 09 20:43:31 volumio3 volumio[698]: info: ------------------------------ 17ms Feb 09 20:43:31 volumio3 volumio[698]: info: sendMpdCommand status took 15 milliseconds Feb 09 20:43:31 volumio3 volumio[698]: verbose: ControllerMpd::parseState Feb 09 20:43:31 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 20:43:31 volumio3 volumio[698]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 09 20:43:31 volumio3 volumio[698]: verbose: ControllerMpd::parseTrackInfo Feb 09 20:43:31 volumio3 volumio[698]: info: ControllerMpd::pushState Feb 09 20:43:31 volumio3 volumio[698]: info: CoreCommandRouter::servicePushState Feb 09 20:43:31 volumio3 volumio[698]: info: CorePlayQueue::getTrack 3 Feb 09 20:43:31 volumio3 volumio[698]: verbose: STATE SERVICE {"status":"play","position":0,"seek":845,"duration":173,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"932 Kbps","isStreaming":false,"title":"Crush & Crumble","artist":"Parov Stelar","album":"Voodoo Sonic (The Album)","uri":"http://192.168.0.22:10243/WMPNSSv4/4046149663/0_MTRfODczZjNhYmJfNjRiZTlmYmMtMzIyNA","trackType":""} Feb 09 20:43:31 volumio3 volumio[698]: verbose: CURRENT POSITION 3 Feb 09 20:43:31 volumio3 volumio[698]: info: CoreStateMachine::syncState stateService play Feb 09 20:43:31 volumio3 volumio[698]: info: CoreStateMachine::syncState currentStatus play Feb 09 20:43:31 volumio3 volumio[698]: info: Received an update from plugin. extracting info from payload Feb 09 20:43:31 volumio3 volumio[698]: info: CoreStateMachine::pushState Feb 09 20:43:31 volumio3 volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 20:43:31 volumio3 volumio[698]: info: CoreCommandRouter::volumioPushState Feb 09 20:43:31 volumio3 volumio[698]: info: CoreStateMachine::pushState Feb 09 20:43:31 volumio3 volumio[698]: info: CoreCommandRouter::volumioPushState Feb 09 20:43:31 volumio3 volumio[698]: info: ------------------------------ 67ms Feb 09 20:43:31 volumio3 volumio[698]: info: CoreCommandRouter::volumioGetQueue Feb 09 20:43:31 volumio3 volumio[698]: info: CoreStateMachine::getQueue Feb 09 20:43:31 volumio3 volumio[698]: info: CorePlayQueue::getQueue Feb 09 20:43:31 volumio3 volumio[698]: info: CoreCommandRouter::volumioGetQueue Feb 09 20:43:31 volumio3 volumio[698]: info: CoreStateMachine::getQueue Feb 09 20:43:31 volumio3 volumio[698]: info: CorePlayQueue::getQueue Feb 09 20:43:34 volumio3 volumio[698]: info: CoreCommandRouter::volumioNext Feb 09 20:43:34 volumio3 volumio[698]: info: CoreStateMachine::next Feb 09 20:43:34 volumio3 volumio[698]: info: ControllerMpd::next Feb 09 20:43:34 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand next Feb 09 20:43:34 volumio3 volumio[698]: info: sendMpdCommand next took 122 milliseconds Feb 09 20:43:34 volumio3 volumio[698]: info: Feb 09 20:43:34 volumio3 volumio[698]: ---------------------------- MPD announces system playlist update Feb 09 20:43:34 volumio3 volumio[698]: info: Ignoring MPD Status Update Feb 09 20:43:34 volumio3 volumio[698]: info: Feb 09 20:43:34 volumio3 volumio[698]: ---------------------------- MPD announces state update: player Feb 09 20:43:34 volumio3 volumio[698]: info: ControllerMpd::getState Feb 09 20:43:34 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand status Feb 09 20:43:34 volumio3 volumio[698]: error: updateQueue error: null Feb 09 20:43:34 volumio3 volumio[698]: info: ------------------------------ 3ms Feb 09 20:43:34 volumio3 volumio[698]: info: sendMpdCommand status took 3 milliseconds Feb 09 20:43:34 volumio3 volumio[698]: verbose: ControllerMpd::parseState Feb 09 20:43:34 volumio3 volumio[698]: info: ControllerMpd::pushState Feb 09 20:43:34 volumio3 volumio[698]: info: CoreCommandRouter::servicePushState Feb 09 20:43:34 volumio3 volumio[698]: info: CoreStateMachine::pushState Feb 09 20:43:34 volumio3 volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 20:43:34 volumio3 volumio[698]: info: CoreCommandRouter::volumioPushState Feb 09 20:43:34 volumio3 volumio[698]: info: CorePlayQueue::getTrack 3 Feb 09 20:43:34 volumio3 volumio[698]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 09 20:43:34 volumio3 volumio[698]: verbose: CURRENT POSITION 3 Feb 09 20:43:34 volumio3 volumio[698]: info: CoreStateMachine::syncState stateService stop Feb 09 20:43:34 volumio3 volumio[698]: info: CoreStateMachine::syncState currentStatus play Feb 09 20:43:34 volumio3 volumio[698]: info: CoreStateMachine::play index undefined Feb 09 20:43:34 volumio3 volumio[698]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 20:43:34 volumio3 volumio[698]: info: CoreStateMachine::pushState Feb 09 20:43:34 volumio3 volumio[698]: info: CorePlayQueue::getTrack 4 Feb 09 20:43:34 volumio3 volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 20:43:34 volumio3 volumio[698]: info: CoreCommandRouter::volumioPushState Feb 09 20:43:34 volumio3 volumio[698]: info: CorePlayQueue::getTrack 4 Feb 09 20:43:34 volumio3 volumio[698]: info: CoreStateMachine::startPlaybackTimer Feb 09 20:43:34 volumio3 volumio[698]: info: CorePlayQueue::getTrack 4 Feb 09 20:43:34 volumio3 volumio[698]: info: [1770644614220] ControllerUPNPBrowser::clearAddPlayTrack Feb 09 20:43:34 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand stop Feb 09 20:43:34 volumio3 volumio[698]: info: ------------------------------ 51ms Feb 09 20:43:34 volumio3 volumio[698]: info: CoreStateMachine::pushState Feb 09 20:43:34 volumio3 volumio[698]: info: CorePlayQueue::getTrack 4 Feb 09 20:43:34 volumio3 volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 20:43:34 volumio3 volumio[698]: info: CoreCommandRouter::volumioPushState Feb 09 20:43:34 volumio3 volumio[698]: info: sendMpdCommand stop took 15 milliseconds Feb 09 20:43:34 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand clear Feb 09 20:43:34 volumio3 volumio[698]: info: CoreCommandRouter::volumioGetQueue Feb 09 20:43:34 volumio3 volumio[698]: info: CoreStateMachine::getQueue Feb 09 20:43:34 volumio3 volumio[698]: info: CorePlayQueue::getQueue Feb 09 20:43:34 volumio3 volumio[698]: info: Feb 09 20:43:34 volumio3 volumio[698]: ---------------------------- MPD announces system playlist update Feb 09 20:43:34 volumio3 volumio[698]: info: Ignoring MPD Status Update Feb 09 20:43:34 volumio3 volumio[698]: info: sendMpdCommand clear took 26 milliseconds Feb 09 20:43:34 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.0.22:10243/WMPNSSv4/4046149663/0_MTRfODczZjNhYmJfNjRiZTlmYmMtMzIzMQ" Feb 09 20:43:34 volumio3 volumio[698]: error: updateQueue error: null Feb 09 20:43:34 volumio3 volumio[698]: info: ------------------------------ 5ms Feb 09 20:43:34 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.0.22:10243/WMPNSSv4/4046149663/0_MTRfODczZjNhYmJfNjRiZTlmYmMtMzIzMQ" Feb 09 20:43:34 volumio3 volumio[698]: info: Feb 09 20:43:34 volumio3 volumio[698]: ---------------------------- MPD announces system playlist update Feb 09 20:43:34 volumio3 volumio[698]: info: Ignoring MPD Status Update Feb 09 20:43:34 volumio3 volumio[698]: info: sendMpdCommand add "http://192.168.0.22:10243/WMPNSSv4/4046149663/0_MTRfODczZjNhYmJfNjRiZTlmYmMtMzIzMQ" took 4 milliseconds Feb 09 20:43:34 volumio3 volumio[698]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 09 20:43:34 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand play Feb 09 20:43:34 volumio3 volumio[698]: info: ------------------------------ 4ms Feb 09 20:43:34 volumio3 volumio[698]: info: sendMpdCommand play took 2 milliseconds Feb 09 20:43:34 volumio3 volumio[698]: info: Feb 09 20:43:34 volumio3 volumio[698]: ---------------------------- MPD announces state update: player Feb 09 20:43:34 volumio3 volumio[698]: info: ControllerMpd::getState Feb 09 20:43:34 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand status Feb 09 20:43:34 volumio3 volumio[698]: info: Feb 09 20:43:34 volumio3 volumio[698]: ---------------------------- MPD announces state update: player Feb 09 20:43:34 volumio3 volumio[698]: info: sendMpdCommand status took 41 milliseconds Feb 09 20:43:34 volumio3 volumio[698]: info: ControllerMpd::getState Feb 09 20:43:34 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand status Feb 09 20:43:34 volumio3 volumio[698]: verbose: ControllerMpd::parseState Feb 09 20:43:34 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 20:43:34 volumio3 volumio[698]: info: sendMpdCommand status took 2 milliseconds Feb 09 20:43:34 volumio3 volumio[698]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 09 20:43:34 volumio3 volumio[698]: verbose: ControllerMpd::parseState Feb 09 20:43:34 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 20:43:34 volumio3 volumio[698]: verbose: ControllerMpd::parseTrackInfo Feb 09 20:43:34 volumio3 volumio[698]: info: ControllerMpd::pushState Feb 09 20:43:34 volumio3 volumio[698]: info: CoreCommandRouter::servicePushState Feb 09 20:43:34 volumio3 volumio[698]: info: CorePlayQueue::getTrack 4 Feb 09 20:43:34 volumio3 volumio[698]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":202,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_MTRfODczZjNhYmJfNjRiZTlmYmMtMzIzMQ","artist":null,"album":null,"uri":"http://192.168.0.22:10243/WMPNSSv4/4046149663/0_MTRfODczZjNhYmJfNjRiZTlmYmMtMzIzMQ","trackType":""} Feb 09 20:43:34 volumio3 volumio[698]: verbose: CURRENT POSITION 4 Feb 09 20:43:34 volumio3 volumio[698]: info: CoreStateMachine::syncState stateService play Feb 09 20:43:34 volumio3 volumio[698]: info: CoreStateMachine::syncState currentStatus stop Feb 09 20:43:34 volumio3 volumio[698]: info: ------------------------------ 58ms Feb 09 20:43:34 volumio3 volumio[698]: info: sendMpdCommand playlistinfo took 10 milliseconds Feb 09 20:43:34 volumio3 volumio[698]: verbose: ControllerMpd::parseTrackInfo Feb 09 20:43:34 volumio3 volumio[698]: info: ControllerMpd::pushState Feb 09 20:43:34 volumio3 volumio[698]: info: CoreCommandRouter::servicePushState Feb 09 20:43:34 volumio3 volumio[698]: info: CorePlayQueue::getTrack 4 Feb 09 20:43:34 volumio3 volumio[698]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":202,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_MTRfODczZjNhYmJfNjRiZTlmYmMtMzIzMQ","artist":null,"album":null,"uri":"http://192.168.0.22:10243/WMPNSSv4/4046149663/0_MTRfODczZjNhYmJfNjRiZTlmYmMtMzIzMQ","trackType":""} Feb 09 20:43:34 volumio3 volumio[698]: verbose: CURRENT POSITION 4 Feb 09 20:43:34 volumio3 volumio[698]: info: CoreStateMachine::syncState stateService play Feb 09 20:43:34 volumio3 volumio[698]: info: CoreStateMachine::syncState currentStatus play Feb 09 20:43:34 volumio3 volumio[698]: info: Received an update from plugin. extracting info from payload Feb 09 20:43:34 volumio3 volumio[698]: info: CoreStateMachine::pushState Feb 09 20:43:34 volumio3 volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 20:43:34 volumio3 volumio[698]: info: CoreCommandRouter::volumioPushState Feb 09 20:43:34 volumio3 volumio[698]: info: CoreStateMachine::pushState Feb 09 20:43:34 volumio3 volumio[698]: info: CoreCommandRouter::volumioPushState Feb 09 20:43:34 volumio3 volumio[698]: info: ------------------------------ 75ms Feb 09 20:43:34 volumio3 volumio[698]: info: Feb 09 20:43:34 volumio3 volumio[698]: ---------------------------- MPD announces system playlist update Feb 09 20:43:34 volumio3 volumio[698]: info: Ignoring MPD Status Update Feb 09 20:43:34 volumio3 volumio[698]: info: Feb 09 20:43:34 volumio3 volumio[698]: ---------------------------- MPD announces state update: player Feb 09 20:43:34 volumio3 volumio[698]: info: ControllerMpd::getState Feb 09 20:43:34 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand status Feb 09 20:43:34 volumio3 volumio[698]: info: ------------------------------ 20ms Feb 09 20:43:34 volumio3 volumio[698]: info: sendMpdCommand status took 23 milliseconds Feb 09 20:43:34 volumio3 volumio[698]: verbose: ControllerMpd::parseState Feb 09 20:43:34 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 20:43:34 volumio3 volumio[698]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 09 20:43:34 volumio3 volumio[698]: verbose: ControllerMpd::parseTrackInfo Feb 09 20:43:34 volumio3 volumio[698]: info: ControllerMpd::pushState Feb 09 20:43:34 volumio3 volumio[698]: info: CoreCommandRouter::servicePushState Feb 09 20:43:34 volumio3 volumio[698]: info: CorePlayQueue::getTrack 4 Feb 09 20:43:34 volumio3 volumio[698]: verbose: STATE SERVICE {"status":"play","position":0,"seek":845,"duration":202,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"841 Kbps","isStreaming":false,"title":"Don't You Forget (feat. Lilja Bloom & Anduze)","artist":"Parov Stelar","album":"Voodoo Sonic (The Album)","uri":"http://192.168.0.22:10243/WMPNSSv4/4046149663/0_MTRfODczZjNhYmJfNjRiZTlmYmMtMzIzMQ","trackType":""} Feb 09 20:43:34 volumio3 volumio[698]: verbose: CURRENT POSITION 4 Feb 09 20:43:34 volumio3 volumio[698]: info: CoreStateMachine::syncState stateService play Feb 09 20:43:34 volumio3 volumio[698]: info: CoreStateMachine::syncState currentStatus play Feb 09 20:43:34 volumio3 volumio[698]: info: Received an update from plugin. extracting info from payload Feb 09 20:43:34 volumio3 volumio[698]: info: CoreStateMachine::pushState Feb 09 20:43:34 volumio3 volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 20:43:34 volumio3 volumio[698]: info: CoreCommandRouter::volumioPushState Feb 09 20:43:34 volumio3 volumio[698]: info: CoreStateMachine::pushState Feb 09 20:43:34 volumio3 volumio[698]: info: CoreCommandRouter::volumioPushState Feb 09 20:43:34 volumio3 volumio[698]: info: ------------------------------ 86ms Feb 09 20:43:34 volumio3 volumio[698]: info: CoreCommandRouter::volumioGetQueue Feb 09 20:43:34 volumio3 volumio[698]: info: CoreStateMachine::getQueue Feb 09 20:43:34 volumio3 volumio[698]: info: CorePlayQueue::getQueue Feb 09 20:43:34 volumio3 volumio[698]: info: CoreCommandRouter::volumioGetQueue Feb 09 20:43:34 volumio3 volumio[698]: info: CoreStateMachine::getQueue Feb 09 20:43:34 volumio3 volumio[698]: info: CorePlayQueue::getQueue Feb 09 20:43:45 volumio3 volumio[698]: info: CoreCommandRouter::volumioNext Feb 09 20:43:45 volumio3 volumio[698]: info: CoreStateMachine::next Feb 09 20:43:45 volumio3 volumio[698]: info: ControllerMpd::next Feb 09 20:43:45 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand next Feb 09 20:43:45 volumio3 volumio[698]: info: Feb 09 20:43:45 volumio3 volumio[698]: ---------------------------- MPD announces system playlist update Feb 09 20:43:45 volumio3 volumio[698]: info: Ignoring MPD Status Update Feb 09 20:43:45 volumio3 volumio[698]: info: Feb 09 20:43:45 volumio3 volumio[698]: ---------------------------- MPD announces state update: player Feb 09 20:43:45 volumio3 volumio[698]: info: sendMpdCommand next took 237 milliseconds Feb 09 20:43:45 volumio3 volumio[698]: info: ControllerMpd::getState Feb 09 20:43:45 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand status Feb 09 20:43:45 volumio3 volumio[698]: error: updateQueue error: null Feb 09 20:43:45 volumio3 volumio[698]: info: ------------------------------ 4ms Feb 09 20:43:45 volumio3 volumio[698]: info: sendMpdCommand status took 3 milliseconds Feb 09 20:43:45 volumio3 volumio[698]: verbose: ControllerMpd::parseState Feb 09 20:43:45 volumio3 volumio[698]: info: ControllerMpd::pushState Feb 09 20:43:45 volumio3 volumio[698]: info: CoreCommandRouter::servicePushState Feb 09 20:43:45 volumio3 volumio[698]: info: CoreStateMachine::pushState Feb 09 20:43:45 volumio3 volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 20:43:45 volumio3 volumio[698]: info: CoreCommandRouter::volumioPushState Feb 09 20:43:45 volumio3 volumio[698]: info: CorePlayQueue::getTrack 4 Feb 09 20:43:45 volumio3 volumio[698]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 09 20:43:45 volumio3 volumio[698]: verbose: CURRENT POSITION 4 Feb 09 20:43:45 volumio3 volumio[698]: info: CoreStateMachine::syncState stateService stop Feb 09 20:43:45 volumio3 volumio[698]: info: CoreStateMachine::syncState currentStatus play Feb 09 20:43:45 volumio3 volumio[698]: info: CoreStateMachine::play index undefined Feb 09 20:43:45 volumio3 volumio[698]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 20:43:45 volumio3 volumio[698]: info: CoreStateMachine::pushState Feb 09 20:43:45 volumio3 volumio[698]: info: CorePlayQueue::getTrack 5 Feb 09 20:43:45 volumio3 volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 20:43:45 volumio3 volumio[698]: info: CoreCommandRouter::volumioPushState Feb 09 20:43:45 volumio3 volumio[698]: info: CorePlayQueue::getTrack 5 Feb 09 20:43:45 volumio3 volumio[698]: info: CoreStateMachine::startPlaybackTimer Feb 09 20:43:45 volumio3 volumio[698]: info: CorePlayQueue::getTrack 5 Feb 09 20:43:45 volumio3 volumio[698]: info: [1770644625638] ControllerUPNPBrowser::clearAddPlayTrack Feb 09 20:43:45 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand stop Feb 09 20:43:45 volumio3 volumio[698]: info: ------------------------------ 28ms Feb 09 20:43:45 volumio3 volumio[698]: info: CoreStateMachine::pushState Feb 09 20:43:45 volumio3 volumio[698]: info: CorePlayQueue::getTrack 5 Feb 09 20:43:45 volumio3 volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 20:43:45 volumio3 volumio[698]: info: CoreCommandRouter::volumioPushState Feb 09 20:43:45 volumio3 volumio[698]: info: sendMpdCommand stop took 19 milliseconds Feb 09 20:43:45 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand clear Feb 09 20:43:45 volumio3 volumio[698]: info: CoreCommandRouter::volumioGetQueue Feb 09 20:43:45 volumio3 volumio[698]: info: CoreStateMachine::getQueue Feb 09 20:43:45 volumio3 volumio[698]: info: CorePlayQueue::getQueue Feb 09 20:43:45 volumio3 volumio[698]: info: Feb 09 20:43:45 volumio3 volumio[698]: ---------------------------- MPD announces system playlist update Feb 09 20:43:45 volumio3 volumio[698]: info: Ignoring MPD Status Update Feb 09 20:43:45 volumio3 volumio[698]: info: sendMpdCommand clear took 20 milliseconds Feb 09 20:43:45 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.0.22:10243/WMPNSSv4/4046149663/0_MTRfODczZjNhYmJfNjRiZTlmYmMtMzIzMg" Feb 09 20:43:45 volumio3 volumio[698]: error: updateQueue error: null Feb 09 20:43:45 volumio3 volumio[698]: info: ------------------------------ 8ms Feb 09 20:43:45 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.0.22:10243/WMPNSSv4/4046149663/0_MTRfODczZjNhYmJfNjRiZTlmYmMtMzIzMg" Feb 09 20:43:45 volumio3 volumio[698]: info: Feb 09 20:43:45 volumio3 volumio[698]: ---------------------------- MPD announces system playlist update Feb 09 20:43:45 volumio3 volumio[698]: info: Ignoring MPD Status Update Feb 09 20:43:45 volumio3 volumio[698]: info: sendMpdCommand add "http://192.168.0.22:10243/WMPNSSv4/4046149663/0_MTRfODczZjNhYmJfNjRiZTlmYmMtMzIzMg" took 8 milliseconds Feb 09 20:43:45 volumio3 volumio[698]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 09 20:43:45 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand play Feb 09 20:43:45 volumio3 volumio[698]: info: ------------------------------ 4ms Feb 09 20:43:45 volumio3 volumio[698]: info: sendMpdCommand play took 3 milliseconds Feb 09 20:43:45 volumio3 volumio[698]: info: Feb 09 20:43:45 volumio3 volumio[698]: ---------------------------- MPD announces state update: player Feb 09 20:43:45 volumio3 volumio[698]: info: ControllerMpd::getState Feb 09 20:43:45 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand status Feb 09 20:43:45 volumio3 volumio[698]: info: sendMpdCommand status took 30 milliseconds Feb 09 20:43:45 volumio3 volumio[698]: verbose: ControllerMpd::parseState Feb 09 20:43:45 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 20:43:45 volumio3 volumio[698]: info: Feb 09 20:43:45 volumio3 volumio[698]: ---------------------------- MPD announces state update: player Feb 09 20:43:45 volumio3 volumio[698]: info: ControllerMpd::getState Feb 09 20:43:45 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand status Feb 09 20:43:45 volumio3 volumio[698]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 09 20:43:45 volumio3 volumio[698]: verbose: ControllerMpd::parseTrackInfo Feb 09 20:43:45 volumio3 volumio[698]: info: ControllerMpd::pushState Feb 09 20:43:45 volumio3 volumio[698]: info: CoreCommandRouter::servicePushState Feb 09 20:43:45 volumio3 volumio[698]: info: CorePlayQueue::getTrack 5 Feb 09 20:43:45 volumio3 volumio[698]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":191,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_MTRfODczZjNhYmJfNjRiZTlmYmMtMzIzMg","artist":null,"album":null,"uri":"http://192.168.0.22:10243/WMPNSSv4/4046149663/0_MTRfODczZjNhYmJfNjRiZTlmYmMtMzIzMg","trackType":""} Feb 09 20:43:45 volumio3 volumio[698]: verbose: CURRENT POSITION 5 Feb 09 20:43:45 volumio3 volumio[698]: info: CoreStateMachine::syncState stateService play Feb 09 20:43:45 volumio3 volumio[698]: info: CoreStateMachine::syncState currentStatus stop Feb 09 20:43:45 volumio3 volumio[698]: info: ------------------------------ 36ms Feb 09 20:43:45 volumio3 volumio[698]: info: sendMpdCommand status took 4 milliseconds Feb 09 20:43:45 volumio3 volumio[698]: verbose: ControllerMpd::parseState Feb 09 20:43:45 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 20:43:45 volumio3 volumio[698]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 09 20:43:45 volumio3 volumio[698]: verbose: ControllerMpd::parseTrackInfo Feb 09 20:43:45 volumio3 volumio[698]: info: ControllerMpd::pushState Feb 09 20:43:45 volumio3 volumio[698]: info: CoreCommandRouter::servicePushState Feb 09 20:43:45 volumio3 volumio[698]: info: CorePlayQueue::getTrack 5 Feb 09 20:43:45 volumio3 volumio[698]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":191,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_MTRfODczZjNhYmJfNjRiZTlmYmMtMzIzMg","artist":null,"album":null,"uri":"http://192.168.0.22:10243/WMPNSSv4/4046149663/0_MTRfODczZjNhYmJfNjRiZTlmYmMtMzIzMg","trackType":""} Feb 09 20:43:45 volumio3 volumio[698]: verbose: CURRENT POSITION 5 Feb 09 20:43:45 volumio3 volumio[698]: info: CoreStateMachine::syncState stateService play Feb 09 20:43:45 volumio3 volumio[698]: info: CoreStateMachine::syncState currentStatus play Feb 09 20:43:45 volumio3 volumio[698]: info: Received an update from plugin. extracting info from payload Feb 09 20:43:45 volumio3 volumio[698]: info: CoreStateMachine::pushState Feb 09 20:43:45 volumio3 volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 20:43:45 volumio3 volumio[698]: info: CoreCommandRouter::volumioPushState Feb 09 20:43:45 volumio3 volumio[698]: info: CoreStateMachine::pushState Feb 09 20:43:45 volumio3 volumio[698]: info: CoreCommandRouter::volumioPushState Feb 09 20:43:45 volumio3 volumio[698]: info: ------------------------------ 41ms Feb 09 20:43:45 volumio3 volumio[698]: info: Feb 09 20:43:45 volumio3 volumio[698]: ---------------------------- MPD announces system playlist update Feb 09 20:43:45 volumio3 volumio[698]: info: Ignoring MPD Status Update Feb 09 20:43:45 volumio3 volumio[698]: info: Feb 09 20:43:45 volumio3 volumio[698]: ---------------------------- MPD announces state update: player Feb 09 20:43:45 volumio3 volumio[698]: info: ControllerMpd::getState Feb 09 20:43:45 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand status Feb 09 20:43:45 volumio3 volumio[698]: info: ------------------------------ 19ms Feb 09 20:43:45 volumio3 volumio[698]: info: sendMpdCommand status took 16 milliseconds Feb 09 20:43:45 volumio3 volumio[698]: verbose: ControllerMpd::parseState Feb 09 20:43:45 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 20:43:45 volumio3 volumio[698]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 09 20:43:45 volumio3 volumio[698]: verbose: ControllerMpd::parseTrackInfo Feb 09 20:43:45 volumio3 volumio[698]: info: ControllerMpd::pushState Feb 09 20:43:45 volumio3 volumio[698]: info: CoreCommandRouter::servicePushState Feb 09 20:43:45 volumio3 volumio[698]: info: CorePlayQueue::getTrack 5 Feb 09 20:43:45 volumio3 volumio[698]: verbose: STATE SERVICE {"status":"play","position":0,"seek":845,"duration":191,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"773 Kbps","isStreaming":false,"title":"Fade to Red (feat. ESCHES)","artist":"Parov Stelar","album":"Voodoo Sonic (The Album)","uri":"http://192.168.0.22:10243/WMPNSSv4/4046149663/0_MTRfODczZjNhYmJfNjRiZTlmYmMtMzIzMg","trackType":""} Feb 09 20:43:45 volumio3 volumio[698]: verbose: CURRENT POSITION 5 Feb 09 20:43:45 volumio3 volumio[698]: info: CoreStateMachine::syncState stateService play Feb 09 20:43:45 volumio3 volumio[698]: info: CoreStateMachine::syncState currentStatus play Feb 09 20:43:45 volumio3 volumio[698]: info: Received an update from plugin. extracting info from payload Feb 09 20:43:45 volumio3 volumio[698]: info: CoreStateMachine::pushState Feb 09 20:43:45 volumio3 volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 20:43:45 volumio3 volumio[698]: info: CoreCommandRouter::volumioPushState Feb 09 20:43:45 volumio3 volumio[698]: info: CoreStateMachine::pushState Feb 09 20:43:45 volumio3 volumio[698]: info: CoreCommandRouter::volumioPushState Feb 09 20:43:45 volumio3 volumio[698]: info: ------------------------------ 55ms Feb 09 20:43:45 volumio3 volumio[698]: info: CoreCommandRouter::volumioGetQueue Feb 09 20:43:45 volumio3 volumio[698]: info: CoreStateMachine::getQueue Feb 09 20:43:45 volumio3 volumio[698]: info: CorePlayQueue::getQueue Feb 09 20:43:45 volumio3 volumio[698]: info: CoreCommandRouter::volumioGetQueue Feb 09 20:43:45 volumio3 volumio[698]: info: CoreStateMachine::getQueue Feb 09 20:43:45 volumio3 volumio[698]: info: CorePlayQueue::getQueue Feb 09 20:43:54 volumio3 volumio[698]: info: CoreCommandRouter::volumioNext Feb 09 20:43:54 volumio3 volumio[698]: info: CoreStateMachine::next Feb 09 20:43:54 volumio3 volumio[698]: info: ControllerMpd::next Feb 09 20:43:54 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand next Feb 09 20:43:54 volumio3 volumio[698]: info: Feb 09 20:43:54 volumio3 volumio[698]: ---------------------------- MPD announces system playlist update Feb 09 20:43:54 volumio3 volumio[698]: info: Ignoring MPD Status Update Feb 09 20:43:54 volumio3 volumio[698]: info: Feb 09 20:43:54 volumio3 volumio[698]: ---------------------------- MPD announces state update: player Feb 09 20:43:54 volumio3 volumio[698]: info: sendMpdCommand next took 95 milliseconds Feb 09 20:43:54 volumio3 volumio[698]: info: ControllerMpd::getState Feb 09 20:43:54 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand status Feb 09 20:43:54 volumio3 volumio[698]: error: updateQueue error: null Feb 09 20:43:54 volumio3 volumio[698]: info: ------------------------------ 4ms Feb 09 20:43:54 volumio3 volumio[698]: info: sendMpdCommand status took 2 milliseconds Feb 09 20:43:54 volumio3 volumio[698]: verbose: ControllerMpd::parseState Feb 09 20:43:54 volumio3 volumio[698]: info: ControllerMpd::pushState Feb 09 20:43:54 volumio3 volumio[698]: info: CoreCommandRouter::servicePushState Feb 09 20:43:54 volumio3 volumio[698]: info: CoreStateMachine::pushState Feb 09 20:43:54 volumio3 volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 20:43:54 volumio3 volumio[698]: info: CoreCommandRouter::volumioPushState Feb 09 20:43:54 volumio3 volumio[698]: info: CorePlayQueue::getTrack 5 Feb 09 20:43:54 volumio3 volumio[698]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 09 20:43:54 volumio3 volumio[698]: verbose: CURRENT POSITION 5 Feb 09 20:43:54 volumio3 volumio[698]: info: CoreStateMachine::syncState stateService stop Feb 09 20:43:54 volumio3 volumio[698]: info: CoreStateMachine::syncState currentStatus play Feb 09 20:43:54 volumio3 volumio[698]: info: CoreStateMachine::play index undefined Feb 09 20:43:54 volumio3 volumio[698]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 20:43:54 volumio3 volumio[698]: info: CoreStateMachine::pushState Feb 09 20:43:54 volumio3 volumio[698]: info: CorePlayQueue::getTrack 6 Feb 09 20:43:54 volumio3 volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 20:43:54 volumio3 volumio[698]: info: CoreCommandRouter::volumioPushState Feb 09 20:43:54 volumio3 volumio[698]: info: CorePlayQueue::getTrack 6 Feb 09 20:43:54 volumio3 volumio[698]: info: CoreStateMachine::startPlaybackTimer Feb 09 20:43:54 volumio3 volumio[698]: info: CorePlayQueue::getTrack 6 Feb 09 20:43:54 volumio3 volumio[698]: info: [1770644634822] ControllerUPNPBrowser::clearAddPlayTrack Feb 09 20:43:54 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand stop Feb 09 20:43:54 volumio3 volumio[698]: info: ------------------------------ 26ms Feb 09 20:43:54 volumio3 volumio[698]: info: CoreStateMachine::pushState Feb 09 20:43:54 volumio3 volumio[698]: info: CorePlayQueue::getTrack 6 Feb 09 20:43:54 volumio3 volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 20:43:54 volumio3 volumio[698]: info: CoreCommandRouter::volumioPushState Feb 09 20:43:54 volumio3 volumio[698]: info: sendMpdCommand stop took 17 milliseconds Feb 09 20:43:54 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand clear Feb 09 20:43:54 volumio3 volumio[698]: info: CoreCommandRouter::volumioGetQueue Feb 09 20:43:54 volumio3 volumio[698]: info: CoreStateMachine::getQueue Feb 09 20:43:54 volumio3 volumio[698]: info: CorePlayQueue::getQueue Feb 09 20:43:54 volumio3 volumio[698]: info: Feb 09 20:43:54 volumio3 volumio[698]: ---------------------------- MPD announces system playlist update Feb 09 20:43:54 volumio3 volumio[698]: info: Ignoring MPD Status Update Feb 09 20:43:54 volumio3 volumio[698]: info: sendMpdCommand clear took 21 milliseconds Feb 09 20:43:54 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.0.22:10243/WMPNSSv4/4046149663/0_MTRfODczZjNhYmJfNjRiZTlmYmMtMzIzNA" Feb 09 20:43:54 volumio3 volumio[698]: error: updateQueue error: null Feb 09 20:43:54 volumio3 volumio[698]: info: ------------------------------ 7ms Feb 09 20:43:54 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.0.22:10243/WMPNSSv4/4046149663/0_MTRfODczZjNhYmJfNjRiZTlmYmMtMzIzNA" Feb 09 20:43:54 volumio3 volumio[698]: info: Feb 09 20:43:54 volumio3 volumio[698]: ---------------------------- MPD announces system playlist update Feb 09 20:43:54 volumio3 volumio[698]: info: Ignoring MPD Status Update Feb 09 20:43:54 volumio3 volumio[698]: info: sendMpdCommand add "http://192.168.0.22:10243/WMPNSSv4/4046149663/0_MTRfODczZjNhYmJfNjRiZTlmYmMtMzIzNA" took 5 milliseconds Feb 09 20:43:54 volumio3 volumio[698]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 09 20:43:54 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand play Feb 09 20:43:55 volumio3 volumio[698]: info: ------------------------------ 10ms Feb 09 20:43:55 volumio3 volumio[698]: info: sendMpdCommand play took 3 milliseconds Feb 09 20:43:55 volumio3 volumio[698]: info: Feb 09 20:43:55 volumio3 volumio[698]: ---------------------------- MPD announces state update: player Feb 09 20:43:55 volumio3 volumio[698]: info: ControllerMpd::getState Feb 09 20:43:55 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand status Feb 09 20:43:55 volumio3 volumio[698]: info: Feb 09 20:43:55 volumio3 volumio[698]: ---------------------------- MPD announces state update: player Feb 09 20:43:55 volumio3 volumio[698]: info: sendMpdCommand status took 40 milliseconds Feb 09 20:43:55 volumio3 volumio[698]: info: ControllerMpd::getState Feb 09 20:43:55 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand status Feb 09 20:43:55 volumio3 volumio[698]: verbose: ControllerMpd::parseState Feb 09 20:43:55 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 20:43:55 volumio3 volumio[698]: info: sendMpdCommand status took 2 milliseconds Feb 09 20:43:55 volumio3 volumio[698]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 09 20:43:55 volumio3 volumio[698]: verbose: ControllerMpd::parseState Feb 09 20:43:55 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 20:43:55 volumio3 volumio[698]: verbose: ControllerMpd::parseTrackInfo Feb 09 20:43:55 volumio3 volumio[698]: info: ControllerMpd::pushState Feb 09 20:43:55 volumio3 volumio[698]: info: CoreCommandRouter::servicePushState Feb 09 20:43:55 volumio3 volumio[698]: info: CorePlayQueue::getTrack 6 Feb 09 20:43:55 volumio3 volumio[698]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":169,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_MTRfODczZjNhYmJfNjRiZTlmYmMtMzIzNA","artist":null,"album":null,"uri":"http://192.168.0.22:10243/WMPNSSv4/4046149663/0_MTRfODczZjNhYmJfNjRiZTlmYmMtMzIzNA","trackType":""} Feb 09 20:43:55 volumio3 volumio[698]: verbose: CURRENT POSITION 6 Feb 09 20:43:55 volumio3 volumio[698]: info: CoreStateMachine::syncState stateService play Feb 09 20:43:55 volumio3 volumio[698]: info: CoreStateMachine::syncState currentStatus stop Feb 09 20:43:55 volumio3 volumio[698]: info: ------------------------------ 47ms Feb 09 20:43:55 volumio3 volumio[698]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 09 20:43:55 volumio3 volumio[698]: verbose: ControllerMpd::parseTrackInfo Feb 09 20:43:55 volumio3 volumio[698]: info: ControllerMpd::pushState Feb 09 20:43:55 volumio3 volumio[698]: info: CoreCommandRouter::servicePushState Feb 09 20:43:55 volumio3 volumio[698]: info: CorePlayQueue::getTrack 6 Feb 09 20:43:55 volumio3 volumio[698]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":169,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_MTRfODczZjNhYmJfNjRiZTlmYmMtMzIzNA","artist":null,"album":null,"uri":"http://192.168.0.22:10243/WMPNSSv4/4046149663/0_MTRfODczZjNhYmJfNjRiZTlmYmMtMzIzNA","trackType":""} Feb 09 20:43:55 volumio3 volumio[698]: verbose: CURRENT POSITION 6 Feb 09 20:43:55 volumio3 volumio[698]: info: CoreStateMachine::syncState stateService play Feb 09 20:43:55 volumio3 volumio[698]: info: CoreStateMachine::syncState currentStatus play Feb 09 20:43:55 volumio3 volumio[698]: info: Received an update from plugin. extracting info from payload Feb 09 20:43:55 volumio3 volumio[698]: info: CoreStateMachine::pushState Feb 09 20:43:55 volumio3 volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 20:43:55 volumio3 volumio[698]: info: CoreCommandRouter::volumioPushState Feb 09 20:43:55 volumio3 volumio[698]: info: CoreStateMachine::pushState Feb 09 20:43:55 volumio3 volumio[698]: info: CoreCommandRouter::volumioPushState Feb 09 20:43:55 volumio3 volumio[698]: info: ------------------------------ 38ms Feb 09 20:43:55 volumio3 volumio[698]: info: Feb 09 20:43:55 volumio3 volumio[698]: ---------------------------- MPD announces system playlist update Feb 09 20:43:55 volumio3 volumio[698]: info: Ignoring MPD Status Update Feb 09 20:43:55 volumio3 volumio[698]: info: Feb 09 20:43:55 volumio3 volumio[698]: ---------------------------- MPD announces state update: player Feb 09 20:43:55 volumio3 volumio[698]: info: ControllerMpd::getState Feb 09 20:43:55 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand status Feb 09 20:43:55 volumio3 volumio[698]: info: ------------------------------ 19ms Feb 09 20:43:55 volumio3 volumio[698]: info: sendMpdCommand status took 18 milliseconds Feb 09 20:43:55 volumio3 volumio[698]: verbose: ControllerMpd::parseState Feb 09 20:43:55 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 20:43:55 volumio3 volumio[698]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 09 20:43:55 volumio3 volumio[698]: verbose: ControllerMpd::parseTrackInfo Feb 09 20:43:55 volumio3 volumio[698]: info: ControllerMpd::pushState Feb 09 20:43:55 volumio3 volumio[698]: info: CoreCommandRouter::servicePushState Feb 09 20:43:55 volumio3 volumio[698]: info: CorePlayQueue::getTrack 6 Feb 09 20:43:55 volumio3 volumio[698]: verbose: STATE SERVICE {"status":"play","position":0,"seek":845,"duration":169,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"849 Kbps","isStreaming":false,"title":"Go Wake Up (feat. Lilja Bloom)","artist":"Parov Stelar","album":"Voodoo Sonic (The Album)","uri":"http://192.168.0.22:10243/WMPNSSv4/4046149663/0_MTRfODczZjNhYmJfNjRiZTlmYmMtMzIzNA","trackType":""} Feb 09 20:43:55 volumio3 volumio[698]: verbose: CURRENT POSITION 6 Feb 09 20:43:55 volumio3 volumio[698]: info: CoreStateMachine::syncState stateService play Feb 09 20:43:55 volumio3 volumio[698]: info: CoreStateMachine::syncState currentStatus play Feb 09 20:43:55 volumio3 volumio[698]: info: Received an update from plugin. extracting info from payload Feb 09 20:43:55 volumio3 volumio[698]: info: CoreStateMachine::pushState Feb 09 20:43:55 volumio3 volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 20:43:55 volumio3 volumio[698]: info: CoreCommandRouter::volumioPushState Feb 09 20:43:55 volumio3 volumio[698]: info: CoreStateMachine::pushState Feb 09 20:43:55 volumio3 volumio[698]: info: CoreCommandRouter::volumioPushState Feb 09 20:43:55 volumio3 volumio[698]: info: ------------------------------ 55ms Feb 09 20:43:55 volumio3 volumio[698]: info: CoreCommandRouter::volumioGetQueue Feb 09 20:43:55 volumio3 volumio[698]: info: CoreStateMachine::getQueue Feb 09 20:43:55 volumio3 volumio[698]: info: CorePlayQueue::getQueue Feb 09 20:43:55 volumio3 volumio[698]: info: CoreCommandRouter::volumioGetQueue Feb 09 20:43:55 volumio3 volumio[698]: info: CoreStateMachine::getQueue Feb 09 20:43:55 volumio3 volumio[698]: info: CorePlayQueue::getQueue Feb 09 20:44:10 volumio3 volumio[698]: info: CoreCommandRouter::volumioNext Feb 09 20:44:10 volumio3 volumio[698]: info: CoreStateMachine::next Feb 09 20:44:10 volumio3 volumio[698]: info: ControllerMpd::next Feb 09 20:44:10 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand next Feb 09 20:44:10 volumio3 volumio[698]: info: sendMpdCommand next took 33 milliseconds Feb 09 20:44:10 volumio3 volumio[698]: info: Feb 09 20:44:10 volumio3 volumio[698]: ---------------------------- MPD announces system playlist update Feb 09 20:44:10 volumio3 volumio[698]: info: Ignoring MPD Status Update Feb 09 20:44:10 volumio3 volumio[698]: info: Feb 09 20:44:10 volumio3 volumio[698]: ---------------------------- MPD announces state update: player Feb 09 20:44:10 volumio3 volumio[698]: info: ControllerMpd::getState Feb 09 20:44:10 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand status Feb 09 20:44:10 volumio3 volumio[698]: error: updateQueue error: null Feb 09 20:44:10 volumio3 volumio[698]: info: ------------------------------ 3ms Feb 09 20:44:10 volumio3 volumio[698]: info: sendMpdCommand status took 2 milliseconds Feb 09 20:44:10 volumio3 volumio[698]: verbose: ControllerMpd::parseState Feb 09 20:44:10 volumio3 volumio[698]: info: ControllerMpd::pushState Feb 09 20:44:10 volumio3 volumio[698]: info: CoreCommandRouter::servicePushState Feb 09 20:44:10 volumio3 volumio[698]: info: CoreStateMachine::pushState Feb 09 20:44:10 volumio3 volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 20:44:10 volumio3 volumio[698]: info: CoreCommandRouter::volumioPushState Feb 09 20:44:10 volumio3 volumio[698]: info: CorePlayQueue::getTrack 6 Feb 09 20:44:10 volumio3 volumio[698]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 09 20:44:10 volumio3 volumio[698]: verbose: CURRENT POSITION 6 Feb 09 20:44:10 volumio3 volumio[698]: info: CoreStateMachine::syncState stateService stop Feb 09 20:44:10 volumio3 volumio[698]: info: CoreStateMachine::syncState currentStatus play Feb 09 20:44:10 volumio3 volumio[698]: info: CoreStateMachine::play index undefined Feb 09 20:44:10 volumio3 volumio[698]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 20:44:10 volumio3 volumio[698]: info: CoreStateMachine::pushState Feb 09 20:44:10 volumio3 volumio[698]: info: CorePlayQueue::getTrack 7 Feb 09 20:44:10 volumio3 volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 20:44:10 volumio3 volumio[698]: info: CoreCommandRouter::volumioPushState Feb 09 20:44:10 volumio3 volumio[698]: info: CorePlayQueue::getTrack 7 Feb 09 20:44:10 volumio3 volumio[698]: info: CoreStateMachine::startPlaybackTimer Feb 09 20:44:10 volumio3 volumio[698]: info: CorePlayQueue::getTrack 7 Feb 09 20:44:10 volumio3 volumio[698]: info: [1770644650232] ControllerUPNPBrowser::clearAddPlayTrack Feb 09 20:44:10 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand stop Feb 09 20:44:10 volumio3 volumio[698]: info: ------------------------------ 26ms Feb 09 20:44:10 volumio3 volumio[698]: info: CoreStateMachine::pushState Feb 09 20:44:10 volumio3 volumio[698]: info: CorePlayQueue::getTrack 7 Feb 09 20:44:10 volumio3 volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 20:44:10 volumio3 volumio[698]: info: CoreCommandRouter::volumioPushState Feb 09 20:44:10 volumio3 volumio[698]: info: sendMpdCommand stop took 13 milliseconds Feb 09 20:44:10 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand clear Feb 09 20:44:10 volumio3 volumio[698]: info: CoreCommandRouter::volumioGetQueue Feb 09 20:44:10 volumio3 volumio[698]: info: CoreStateMachine::getQueue Feb 09 20:44:10 volumio3 volumio[698]: info: CorePlayQueue::getQueue Feb 09 20:44:10 volumio3 volumio[698]: info: Feb 09 20:44:10 volumio3 volumio[698]: ---------------------------- MPD announces system playlist update Feb 09 20:44:10 volumio3 volumio[698]: info: Ignoring MPD Status Update Feb 09 20:44:10 volumio3 volumio[698]: info: sendMpdCommand clear took 29 milliseconds Feb 09 20:44:10 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.0.22:10243/WMPNSSv4/4046149663/0_MTRfODczZjNhYmJfNjRiZTlmYmMtMzIzMw" Feb 09 20:44:10 volumio3 volumio[698]: error: updateQueue error: null Feb 09 20:44:10 volumio3 volumio[698]: info: ------------------------------ 127ms Feb 09 20:44:10 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.0.22:10243/WMPNSSv4/4046149663/0_MTRfODczZjNhYmJfNjRiZTlmYmMtMzIzMw" Feb 09 20:44:10 volumio3 volumio[698]: info: Feb 09 20:44:10 volumio3 volumio[698]: ---------------------------- MPD announces system playlist update Feb 09 20:44:10 volumio3 volumio[698]: info: Ignoring MPD Status Update Feb 09 20:44:10 volumio3 volumio[698]: info: sendMpdCommand add "http://192.168.0.22:10243/WMPNSSv4/4046149663/0_MTRfODczZjNhYmJfNjRiZTlmYmMtMzIzMw" took 2 milliseconds Feb 09 20:44:10 volumio3 volumio[698]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 09 20:44:10 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand play Feb 09 20:44:10 volumio3 volumio[698]: info: ------------------------------ 3ms Feb 09 20:44:10 volumio3 volumio[698]: info: sendMpdCommand play took 3 milliseconds Feb 09 20:44:10 volumio3 volumio[698]: info: Feb 09 20:44:10 volumio3 volumio[698]: ---------------------------- MPD announces state update: player Feb 09 20:44:10 volumio3 volumio[698]: info: ControllerMpd::getState Feb 09 20:44:10 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand status Feb 09 20:44:10 volumio3 volumio[698]: info: Feb 09 20:44:10 volumio3 volumio[698]: ---------------------------- MPD announces state update: player Feb 09 20:44:10 volumio3 volumio[698]: info: sendMpdCommand status took 44 milliseconds Feb 09 20:44:10 volumio3 volumio[698]: info: ControllerMpd::getState Feb 09 20:44:10 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand status Feb 09 20:44:10 volumio3 volumio[698]: verbose: ControllerMpd::parseState Feb 09 20:44:10 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 20:44:10 volumio3 volumio[698]: info: sendMpdCommand status took 4 milliseconds Feb 09 20:44:10 volumio3 volumio[698]: verbose: ControllerMpd::parseState Feb 09 20:44:10 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 20:44:10 volumio3 volumio[698]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 09 20:44:10 volumio3 volumio[698]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 09 20:44:10 volumio3 volumio[698]: verbose: ControllerMpd::parseTrackInfo Feb 09 20:44:10 volumio3 volumio[698]: verbose: ControllerMpd::parseTrackInfo Feb 09 20:44:10 volumio3 volumio[698]: info: ControllerMpd::pushState Feb 09 20:44:10 volumio3 volumio[698]: info: CoreCommandRouter::servicePushState Feb 09 20:44:10 volumio3 volumio[698]: info: CorePlayQueue::getTrack 7 Feb 09 20:44:10 volumio3 volumio[698]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":230,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_MTRfODczZjNhYmJfNjRiZTlmYmMtMzIzMw","artist":null,"album":null,"uri":"http://192.168.0.22:10243/WMPNSSv4/4046149663/0_MTRfODczZjNhYmJfNjRiZTlmYmMtMzIzMw","trackType":""} Feb 09 20:44:10 volumio3 volumio[698]: verbose: CURRENT POSITION 7 Feb 09 20:44:10 volumio3 volumio[698]: info: CoreStateMachine::syncState stateService play Feb 09 20:44:10 volumio3 volumio[698]: info: CoreStateMachine::syncState currentStatus stop Feb 09 20:44:10 volumio3 volumio[698]: info: ControllerMpd::pushState Feb 09 20:44:10 volumio3 volumio[698]: info: CoreCommandRouter::servicePushState Feb 09 20:44:10 volumio3 volumio[698]: info: CorePlayQueue::getTrack 7 Feb 09 20:44:10 volumio3 volumio[698]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":230,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_MTRfODczZjNhYmJfNjRiZTlmYmMtMzIzMw","artist":null,"album":null,"uri":"http://192.168.0.22:10243/WMPNSSv4/4046149663/0_MTRfODczZjNhYmJfNjRiZTlmYmMtMzIzMw","trackType":""} Feb 09 20:44:10 volumio3 volumio[698]: verbose: CURRENT POSITION 7 Feb 09 20:44:10 volumio3 volumio[698]: info: CoreStateMachine::syncState stateService play Feb 09 20:44:10 volumio3 volumio[698]: info: CoreStateMachine::syncState currentStatus play Feb 09 20:44:10 volumio3 volumio[698]: info: Received an update from plugin. extracting info from payload Feb 09 20:44:10 volumio3 volumio[698]: info: CoreStateMachine::pushState Feb 09 20:44:10 volumio3 volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 20:44:10 volumio3 volumio[698]: info: CoreCommandRouter::volumioPushState Feb 09 20:44:10 volumio3 volumio[698]: info: CoreStateMachine::pushState Feb 09 20:44:10 volumio3 volumio[698]: info: CoreCommandRouter::volumioPushState Feb 09 20:44:10 volumio3 volumio[698]: info: ------------------------------ 74ms Feb 09 20:44:10 volumio3 volumio[698]: info: ------------------------------ 51ms Feb 09 20:44:10 volumio3 volumio[698]: info: Feb 09 20:44:10 volumio3 volumio[698]: ---------------------------- MPD announces system playlist update Feb 09 20:44:10 volumio3 volumio[698]: info: Ignoring MPD Status Update Feb 09 20:44:10 volumio3 volumio[698]: info: Feb 09 20:44:10 volumio3 volumio[698]: ---------------------------- MPD announces state update: player Feb 09 20:44:10 volumio3 volumio[698]: info: ControllerMpd::getState Feb 09 20:44:10 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand status Feb 09 20:44:10 volumio3 volumio[698]: info: ------------------------------ 15ms Feb 09 20:44:10 volumio3 volumio[698]: info: sendMpdCommand status took 14 milliseconds Feb 09 20:44:10 volumio3 volumio[698]: verbose: ControllerMpd::parseState Feb 09 20:44:10 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 20:44:10 volumio3 volumio[698]: info: sendMpdCommand playlistinfo took 7 milliseconds Feb 09 20:44:10 volumio3 volumio[698]: verbose: ControllerMpd::parseTrackInfo Feb 09 20:44:10 volumio3 volumio[698]: info: ControllerMpd::pushState Feb 09 20:44:10 volumio3 volumio[698]: info: CoreCommandRouter::servicePushState Feb 09 20:44:10 volumio3 volumio[698]: info: CorePlayQueue::getTrack 7 Feb 09 20:44:10 volumio3 volumio[698]: verbose: STATE SERVICE {"status":"play","position":0,"seek":845,"duration":230,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"702 Kbps","isStreaming":false,"title":"Number One MC","artist":"Parov Stelar","album":"Voodoo Sonic (The Album)","uri":"http://192.168.0.22:10243/WMPNSSv4/4046149663/0_MTRfODczZjNhYmJfNjRiZTlmYmMtMzIzMw","trackType":""} Feb 09 20:44:10 volumio3 volumio[698]: verbose: CURRENT POSITION 7 Feb 09 20:44:10 volumio3 volumio[698]: info: CoreStateMachine::syncState stateService play Feb 09 20:44:10 volumio3 volumio[698]: info: CoreStateMachine::syncState currentStatus play Feb 09 20:44:10 volumio3 volumio[698]: info: Received an update from plugin. extracting info from payload Feb 09 20:44:10 volumio3 volumio[698]: info: CoreStateMachine::pushState Feb 09 20:44:10 volumio3 volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 20:44:10 volumio3 volumio[698]: info: CoreCommandRouter::volumioPushState Feb 09 20:44:10 volumio3 volumio[698]: info: CoreStateMachine::pushState Feb 09 20:44:10 volumio3 volumio[698]: info: CoreCommandRouter::volumioPushState Feb 09 20:44:10 volumio3 volumio[698]: info: ------------------------------ 55ms Feb 09 20:44:10 volumio3 volumio[698]: info: CoreCommandRouter::volumioGetQueue Feb 09 20:44:10 volumio3 volumio[698]: info: CoreStateMachine::getQueue Feb 09 20:44:10 volumio3 volumio[698]: info: CorePlayQueue::getQueue Feb 09 20:44:10 volumio3 volumio[698]: info: CoreCommandRouter::volumioGetQueue Feb 09 20:44:10 volumio3 volumio[698]: info: CoreStateMachine::getQueue Feb 09 20:44:10 volumio3 volumio[698]: info: CorePlayQueue::getQueue Feb 09 20:44:15 volumio3 volumio[698]: info: CoreCommandRouter::volumioNext Feb 09 20:44:15 volumio3 volumio[698]: info: CoreStateMachine::next Feb 09 20:44:15 volumio3 volumio[698]: info: ControllerMpd::next Feb 09 20:44:15 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand next Feb 09 20:44:15 volumio3 volumio[698]: info: sendMpdCommand next took 58 milliseconds Feb 09 20:44:15 volumio3 volumio[698]: info: Feb 09 20:44:15 volumio3 volumio[698]: ---------------------------- MPD announces system playlist update Feb 09 20:44:15 volumio3 volumio[698]: info: Ignoring MPD Status Update Feb 09 20:44:15 volumio3 volumio[698]: info: Feb 09 20:44:15 volumio3 volumio[698]: ---------------------------- MPD announces state update: player Feb 09 20:44:15 volumio3 volumio[698]: info: ControllerMpd::getState Feb 09 20:44:15 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand status Feb 09 20:44:15 volumio3 volumio[698]: error: updateQueue error: null Feb 09 20:44:15 volumio3 volumio[698]: info: ------------------------------ 3ms Feb 09 20:44:15 volumio3 volumio[698]: info: sendMpdCommand status took 2 milliseconds Feb 09 20:44:15 volumio3 volumio[698]: verbose: ControllerMpd::parseState Feb 09 20:44:15 volumio3 volumio[698]: info: ControllerMpd::pushState Feb 09 20:44:15 volumio3 volumio[698]: info: CoreCommandRouter::servicePushState Feb 09 20:44:15 volumio3 volumio[698]: info: CoreStateMachine::pushState Feb 09 20:44:15 volumio3 volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 20:44:15 volumio3 volumio[698]: info: CoreCommandRouter::volumioPushState Feb 09 20:44:15 volumio3 volumio[698]: info: CorePlayQueue::getTrack 7 Feb 09 20:44:15 volumio3 volumio[698]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 09 20:44:15 volumio3 volumio[698]: verbose: CURRENT POSITION 7 Feb 09 20:44:15 volumio3 volumio[698]: info: CoreStateMachine::syncState stateService stop Feb 09 20:44:15 volumio3 volumio[698]: info: CoreStateMachine::syncState currentStatus play Feb 09 20:44:15 volumio3 volumio[698]: info: CoreStateMachine::play index undefined Feb 09 20:44:15 volumio3 volumio[698]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 20:44:15 volumio3 volumio[698]: info: CoreStateMachine::pushState Feb 09 20:44:15 volumio3 volumio[698]: info: CorePlayQueue::getTrack 8 Feb 09 20:44:15 volumio3 volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 20:44:15 volumio3 volumio[698]: info: CoreCommandRouter::volumioPushState Feb 09 20:44:15 volumio3 volumio[698]: info: CorePlayQueue::getTrack 8 Feb 09 20:44:15 volumio3 volumio[698]: info: CoreStateMachine::startPlaybackTimer Feb 09 20:44:15 volumio3 volumio[698]: info: CorePlayQueue::getTrack 8 Feb 09 20:44:15 volumio3 volumio[698]: info: [1770644655143] ControllerUPNPBrowser::clearAddPlayTrack Feb 09 20:44:15 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand stop Feb 09 20:44:15 volumio3 volumio[698]: info: ------------------------------ 25ms Feb 09 20:44:15 volumio3 volumio[698]: info: CoreStateMachine::pushState Feb 09 20:44:15 volumio3 volumio[698]: info: CorePlayQueue::getTrack 8 Feb 09 20:44:15 volumio3 volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 20:44:15 volumio3 volumio[698]: info: CoreCommandRouter::volumioPushState Feb 09 20:44:15 volumio3 volumio[698]: info: sendMpdCommand stop took 13 milliseconds Feb 09 20:44:15 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand clear Feb 09 20:44:15 volumio3 volumio[698]: info: CoreCommandRouter::volumioGetQueue Feb 09 20:44:15 volumio3 volumio[698]: info: CoreStateMachine::getQueue Feb 09 20:44:15 volumio3 volumio[698]: info: CorePlayQueue::getQueue Feb 09 20:44:15 volumio3 volumio[698]: info: Feb 09 20:44:15 volumio3 volumio[698]: ---------------------------- MPD announces system playlist update Feb 09 20:44:15 volumio3 volumio[698]: info: Ignoring MPD Status Update Feb 09 20:44:15 volumio3 volumio[698]: info: sendMpdCommand clear took 32 milliseconds Feb 09 20:44:15 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.0.22:10243/WMPNSSv4/4046149663/0_MTRfODczZjNhYmJfNjRiZTlmYmMtMzIzMA" Feb 09 20:44:15 volumio3 volumio[698]: error: updateQueue error: null Feb 09 20:44:15 volumio3 volumio[698]: info: ------------------------------ 7ms Feb 09 20:44:15 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.0.22:10243/WMPNSSv4/4046149663/0_MTRfODczZjNhYmJfNjRiZTlmYmMtMzIzMA" Feb 09 20:44:15 volumio3 volumio[698]: info: Feb 09 20:44:15 volumio3 volumio[698]: ---------------------------- MPD announces system playlist update Feb 09 20:44:15 volumio3 volumio[698]: info: Ignoring MPD Status Update Feb 09 20:44:15 volumio3 volumio[698]: info: sendMpdCommand add "http://192.168.0.22:10243/WMPNSSv4/4046149663/0_MTRfODczZjNhYmJfNjRiZTlmYmMtMzIzMA" took 2 milliseconds Feb 09 20:44:15 volumio3 volumio[698]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 09 20:44:15 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand play Feb 09 20:44:15 volumio3 volumio[698]: info: ------------------------------ 5ms Feb 09 20:44:15 volumio3 volumio[698]: info: sendMpdCommand play took 3 milliseconds Feb 09 20:44:15 volumio3 volumio[698]: info: Feb 09 20:44:15 volumio3 volumio[698]: ---------------------------- MPD announces state update: player Feb 09 20:44:15 volumio3 volumio[698]: info: ControllerMpd::getState Feb 09 20:44:15 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand status Feb 09 20:44:15 volumio3 volumio[698]: info: Feb 09 20:44:15 volumio3 volumio[698]: ---------------------------- MPD announces state update: player Feb 09 20:44:15 volumio3 volumio[698]: info: sendMpdCommand status took 59 milliseconds Feb 09 20:44:15 volumio3 volumio[698]: info: ControllerMpd::getState Feb 09 20:44:15 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand status Feb 09 20:44:15 volumio3 volumio[698]: verbose: ControllerMpd::parseState Feb 09 20:44:15 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 20:44:15 volumio3 volumio[698]: info: Feb 09 20:44:15 volumio3 volumio[698]: ---------------------------- MPD announces system playlist update Feb 09 20:44:15 volumio3 volumio[698]: info: Ignoring MPD Status Update Feb 09 20:44:15 volumio3 volumio[698]: info: sendMpdCommand status took 3 milliseconds Feb 09 20:44:15 volumio3 volumio[698]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 09 20:44:15 volumio3 volumio[698]: verbose: ControllerMpd::parseState Feb 09 20:44:15 volumio3 volumio[698]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 20:44:15 volumio3 volumio[698]: verbose: ControllerMpd::parseTrackInfo Feb 09 20:44:15 volumio3 volumio[698]: info: ControllerMpd::pushState Feb 09 20:44:15 volumio3 volumio[698]: info: CoreCommandRouter::servicePushState Feb 09 20:44:15 volumio3 volumio[698]: info: CorePlayQueue::getTrack 8 Feb 09 20:44:15 volumio3 volumio[698]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":420,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"532 Kbps","isStreaming":false,"title":"Piano Boy","artist":"Parov Stelar","album":"Voodoo Sonic (The Album)","uri":"http://192.168.0.22:10243/WMPNSSv4/4046149663/0_MTRfODczZjNhYmJfNjRiZTlmYmMtMzIzMA","trackType":""} Feb 09 20:44:15 volumio3 volumio[698]: verbose: CURRENT POSITION 8 Feb 09 20:44:15 volumio3 volumio[698]: info: CoreStateMachine::syncState stateService play Feb 09 20:44:15 volumio3 volumio[698]: info: CoreStateMachine::syncState currentStatus stop Feb 09 20:44:15 volumio3 volumio[698]: info: ------------------------------ 68ms Feb 09 20:44:15 volumio3 volumio[698]: info: ------------------------------ 6ms Feb 09 20:44:15 volumio3 volumio[698]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 09 20:44:15 volumio3 volumio[698]: verbose: ControllerMpd::parseTrackInfo Feb 09 20:44:15 volumio3 volumio[698]: info: ControllerMpd::pushState Feb 09 20:44:15 volumio3 volumio[698]: info: CoreCommandRouter::servicePushState Feb 09 20:44:15 volumio3 volumio[698]: info: CorePlayQueue::getTrack 8 Feb 09 20:44:15 volumio3 volumio[698]: verbose: STATE SERVICE {"status":"play","position":0,"seek":845,"duration":420,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"545 Kbps","isStreaming":false,"title":"Piano Boy","artist":"Parov Stelar","album":"Voodoo Sonic (The Album)","uri":"http://192.168.0.22:10243/WMPNSSv4/4046149663/0_MTRfODczZjNhYmJfNjRiZTlmYmMtMzIzMA","trackType":""} Feb 09 20:44:15 volumio3 volumio[698]: verbose: CURRENT POSITION 8 Feb 09 20:44:15 volumio3 volumio[698]: info: CoreStateMachine::syncState stateService play Feb 09 20:44:15 volumio3 volumio[698]: info: CoreStateMachine::syncState currentStatus play Feb 09 20:44:15 volumio3 volumio[698]: info: Received an update from plugin. extracting info from payload Feb 09 20:44:15 volumio3 volumio[698]: info: CoreStateMachine::pushState Feb 09 20:44:15 volumio3 volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 20:44:15 volumio3 volumio[698]: info: CoreCommandRouter::volumioPushState Feb 09 20:44:15 volumio3 volumio[698]: info: CoreStateMachine::pushState Feb 09 20:44:15 volumio3 volumio[698]: info: CoreCommandRouter::volumioPushState Feb 09 20:44:15 volumio3 volumio[698]: info: ------------------------------ 46ms Feb 09 20:44:15 volumio3 volumio[698]: info: CoreCommandRouter::volumioGetQueue Feb 09 20:44:15 volumio3 volumio[698]: info: CoreStateMachine::getQueue Feb 09 20:44:15 volumio3 volumio[698]: info: CorePlayQueue::getQueue Feb 09 20:44:15 volumio3 volumio[698]: info: CoreCommandRouter::volumioGetQueue Feb 09 20:44:15 volumio3 volumio[698]: info: CoreStateMachine::getQueue Feb 09 20:44:15 volumio3 volumio[698]: info: CorePlayQueue::getQueue Feb 09 20:44:18 volumio3 volumio[698]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 09 20:44:18 volumio3 volumio[698]: Error: connect ETIMEDOUT 23.73.2.84:443 Feb 09 20:44:18 volumio3 volumio[698]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Feb 09 20:44:18 volumio3 volumio[698]: errno: -110, Feb 09 20:44:18 volumio3 volumio[698]: code: 'ETIMEDOUT', Feb 09 20:44:18 volumio3 volumio[698]: syscall: 'connect', Feb 09 20:44:18 volumio3 volumio[698]: address: '23.73.2.84', Feb 09 20:44:18 volumio3 volumio[698]: port: 443 Feb 09 20:44:18 volumio3 volumio[698]: } Feb 09 20:44:18 volumio3 volumio[698]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 09 20:44:19 volumio3 sudo[7267]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-09 20:43 Feb 09 20:44:19 volumio3 sudo[7267]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="b1c3cf61c2a0027c66bab1eb0a3795f80c1f2e95" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="e3eb3ee5b16063502f3a1735e11a28cfe54a0f46" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sun 25 Jun 2023 07:20:58 PM CEST" VOLUMIO_VERSION="3.512" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="946a42b62509f37b5e7e2d86a2932a62"