-- Logs begin at Thu 2019-02-14 10:11:58 GMT, end at Thu 2025-10-30 14:52:26 GMT. -- Oct 30 14:51:56 volumio volumio[879]: info: Preload queue cleared Oct 30 14:51:56 volumio volumio[879]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 30 14:51:56 volumio volumio[879]: info: CoreStateMachine::ClearQueue Oct 30 14:51:56 volumio volumio[879]: info: CoreStateMachine::stop Oct 30 14:51:56 volumio volumio[879]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 14:51:56 volumio volumio[879]: info: CorePlayQueue::clearPlayQueue Oct 30 14:51:56 volumio volumio[879]: info: CorePlayQueue::saveQueue Oct 30 14:51:56 volumio volumio[879]: info: CoreCommandRouter::volumioPushQueue Oct 30 14:51:56 volumio volumio[879]: info: CoreStateMachine::addQueueItems Oct 30 14:51:56 volumio volumio[879]: info: CorePlayQueue::addQueueItems Oct 30 14:51:56 volumio volumio[879]: info: Preload queue cleared Oct 30 14:51:56 volumio volumio[879]: info: Adding Item to queue: upnp/http://192.168.1.150:32469/ContentDirectory/a6dd7c96-321d-5e34-6375-8796f4593bcf/control.xml@8ff7edaa42f773ab2b33 Oct 30 14:51:56 volumio volumio[879]: info: Exploding uri upnp/http://192.168.1.150:32469/ContentDirectory/a6dd7c96-321d-5e34-6375-8796f4593bcf/control.xml@8ff7edaa42f773ab2b33 in service upnp_browser Oct 30 14:51:56 volumio volumio[879]: info: CoreCommandRouter::volumioPushQueue Oct 30 14:51:56 volumio volumio[879]: info: CorePlayQueue::saveQueue Oct 30 14:51:56 volumio volumio[879]: info: CoreStateMachine::updateTrackBlock Oct 30 14:51:56 volumio volumio[879]: info: CorePlayQueue::getTrackBlock Oct 30 14:51:56 volumio volumio[879]: info: CoreCommandRouter::volumioPlay Oct 30 14:51:56 volumio volumio[879]: info: CoreStateMachine::play index 0 Oct 30 14:51:56 volumio volumio[879]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 14:51:56 volumio volumio[879]: info: CoreStateMachine::addQueueItems Oct 30 14:51:56 volumio volumio[879]: info: CorePlayQueue::addQueueItems Oct 30 14:51:56 volumio volumio[879]: info: Preload queue cleared Oct 30 14:51:56 volumio volumio[879]: info: Adding Item to queue: upnp/http://192.168.1.150:32469/ContentDirectory/a6dd7c96-321d-5e34-6375-8796f4593bcf/control.xml@1b6a8f9cab2ef1928a9b Oct 30 14:51:56 volumio volumio[879]: info: Exploding uri upnp/http://192.168.1.150:32469/ContentDirectory/a6dd7c96-321d-5e34-6375-8796f4593bcf/control.xml@1b6a8f9cab2ef1928a9b in service upnp_browser Oct 30 14:51:56 volumio volumio[879]: info: Adding Item to queue: upnp/http://192.168.1.150:32469/ContentDirectory/a6dd7c96-321d-5e34-6375-8796f4593bcf/control.xml@28e7bbf5e825a20e78be Oct 30 14:51:56 volumio volumio[879]: info: Exploding uri upnp/http://192.168.1.150:32469/ContentDirectory/a6dd7c96-321d-5e34-6375-8796f4593bcf/control.xml@28e7bbf5e825a20e78be in service upnp_browser Oct 30 14:51:56 volumio volumio[879]: info: Adding Item to queue: upnp/http://192.168.1.150:32469/ContentDirectory/a6dd7c96-321d-5e34-6375-8796f4593bcf/control.xml@d738c56b5d15d69e5a23 Oct 30 14:51:56 volumio volumio[879]: info: Exploding uri upnp/http://192.168.1.150:32469/ContentDirectory/a6dd7c96-321d-5e34-6375-8796f4593bcf/control.xml@d738c56b5d15d69e5a23 in service upnp_browser Oct 30 14:51:56 volumio volumio[879]: info: Adding Item to queue: upnp/http://192.168.1.150:32469/ContentDirectory/a6dd7c96-321d-5e34-6375-8796f4593bcf/control.xml@8dabb8b24f35336bc921 Oct 30 14:51:56 volumio volumio[879]: info: Exploding uri upnp/http://192.168.1.150:32469/ContentDirectory/a6dd7c96-321d-5e34-6375-8796f4593bcf/control.xml@8dabb8b24f35336bc921 in service upnp_browser Oct 30 14:51:56 volumio volumio[879]: info: Adding Item to queue: upnp/http://192.168.1.150:32469/ContentDirectory/a6dd7c96-321d-5e34-6375-8796f4593bcf/control.xml@c36318312860840abcef Oct 30 14:51:56 volumio volumio[879]: info: Exploding uri upnp/http://192.168.1.150:32469/ContentDirectory/a6dd7c96-321d-5e34-6375-8796f4593bcf/control.xml@c36318312860840abcef in service upnp_browser Oct 30 14:51:56 volumio volumio[879]: info: Adding Item to queue: upnp/http://192.168.1.150:32469/ContentDirectory/a6dd7c96-321d-5e34-6375-8796f4593bcf/control.xml@3b56ba830fd1658f1dda Oct 30 14:51:56 volumio volumio[879]: info: Exploding uri upnp/http://192.168.1.150:32469/ContentDirectory/a6dd7c96-321d-5e34-6375-8796f4593bcf/control.xml@3b56ba830fd1658f1dda in service upnp_browser Oct 30 14:51:56 volumio volumio[879]: info: Adding Item to queue: upnp/http://192.168.1.150:32469/ContentDirectory/a6dd7c96-321d-5e34-6375-8796f4593bcf/control.xml@85895ee9188de0c4bc04 Oct 30 14:51:56 volumio volumio[879]: info: Exploding uri upnp/http://192.168.1.150:32469/ContentDirectory/a6dd7c96-321d-5e34-6375-8796f4593bcf/control.xml@85895ee9188de0c4bc04 in service upnp_browser Oct 30 14:51:56 volumio volumio[879]: info: Adding Item to queue: upnp/http://192.168.1.150:32469/ContentDirectory/a6dd7c96-321d-5e34-6375-8796f4593bcf/control.xml@fc47f92c408a0cbc38d7 Oct 30 14:51:56 volumio volumio[879]: info: Exploding uri upnp/http://192.168.1.150:32469/ContentDirectory/a6dd7c96-321d-5e34-6375-8796f4593bcf/control.xml@fc47f92c408a0cbc38d7 in service upnp_browser Oct 30 14:51:56 volumio volumio[879]: info: Adding Item to queue: upnp/http://192.168.1.150:32469/ContentDirectory/a6dd7c96-321d-5e34-6375-8796f4593bcf/control.xml@fc1a894dcda0f5762bde Oct 30 14:51:56 volumio volumio[879]: info: Exploding uri upnp/http://192.168.1.150:32469/ContentDirectory/a6dd7c96-321d-5e34-6375-8796f4593bcf/control.xml@fc1a894dcda0f5762bde in service upnp_browser Oct 30 14:51:56 volumio volumio[879]: info: Adding Item to queue: upnp/http://192.168.1.150:32469/ContentDirectory/a6dd7c96-321d-5e34-6375-8796f4593bcf/control.xml@9f3adc2fd01067095d72 Oct 30 14:51:56 volumio volumio[879]: info: Exploding uri upnp/http://192.168.1.150:32469/ContentDirectory/a6dd7c96-321d-5e34-6375-8796f4593bcf/control.xml@9f3adc2fd01067095d72 in service upnp_browser Oct 30 14:51:56 volumio volumio[879]: info: CoreStateMachine::stop Oct 30 14:51:56 volumio volumio[879]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 14:51:56 volumio volumio[879]: info: CoreStateMachine::play index undefined Oct 30 14:51:56 volumio volumio[879]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 14:51:56 volumio volumio[879]: info: CorePlayQueue::getTrack 0 Oct 30 14:51:56 volumio volumio[879]: info: CoreStateMachine::startPlaybackTimer Oct 30 14:51:56 volumio volumio[879]: info: CorePlayQueue::getTrack 0 Oct 30 14:51:56 volumio volumio[879]: info: [1761835916825] ControllerUPNPBrowser::clearAddPlayTrack Oct 30 14:51:56 volumio volumio[879]: verbose: ControllerMpd::sendMpdCommand stop Oct 30 14:51:56 volumio volumio[879]: info: sendMpdCommand stop took 6 milliseconds Oct 30 14:51:56 volumio volumio[879]: verbose: ControllerMpd::sendMpdCommand clear Oct 30 14:51:56 volumio volumio[879]: info: Oct 30 14:51:56 volumio volumio[879]: ---------------------------- MPD announces system playlist update Oct 30 14:51:56 volumio volumio[879]: info: Ignoring MPD Status Update Oct 30 14:51:56 volumio volumio[879]: info: sendMpdCommand clear took 4 milliseconds Oct 30 14:51:56 volumio volumio[879]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.150:32469/object/8ff7edaa42f773ab2b33/file.flac" Oct 30 14:51:56 volumio volumio[879]: info: Oct 30 14:51:56 volumio volumio[879]: ---------------------------- MPD announces system playlist update Oct 30 14:51:56 volumio volumio[879]: info: Ignoring MPD Status Update Oct 30 14:51:56 volumio volumio[879]: info: Oct 30 14:51:56 volumio volumio[879]: ---------------------------- MPD announces system playlist update Oct 30 14:51:56 volumio volumio[879]: info: Ignoring MPD Status Update Oct 30 14:51:56 volumio volumio[879]: error: updateQueue error: null Oct 30 14:51:56 volumio volumio[879]: info: ------------------------------ 6ms Oct 30 14:51:56 volumio volumio[879]: error: Upnp client error: Error: This socket has been ended by the other party Oct 30 14:51:56 volumio volumio[879]: info: CoreCommandRouter::volumioPushQueue Oct 30 14:51:56 volumio volumio[879]: info: CorePlayQueue::saveQueue Oct 30 14:51:56 volumio volumio[879]: info: CoreStateMachine::updateTrackBlock Oct 30 14:51:56 volumio volumio[879]: info: CorePlayQueue::getTrackBlock Oct 30 14:51:56 volumio volumio[879]: error: updateQueue error: null Oct 30 14:51:56 volumio volumio[879]: error: updateQueue error: null Oct 30 14:51:56 volumio volumio[879]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.150:32469/object/8ff7edaa42f773ab2b33/file.flac" Oct 30 14:51:56 volumio volumio[879]: info: ------------------------------ 96ms Oct 30 14:51:56 volumio volumio[879]: info: ------------------------------ 95ms Oct 30 14:51:56 volumio volumio[879]: info: Oct 30 14:51:56 volumio volumio[879]: ---------------------------- MPD announces system playlist update Oct 30 14:51:56 volumio volumio[879]: info: Ignoring MPD Status Update Oct 30 14:51:56 volumio volumio[879]: info: sendMpdCommand add "http://192.168.1.150:32469/object/8ff7edaa42f773ab2b33/file.flac" took 3 milliseconds Oct 30 14:51:56 volumio volumio[879]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 30 14:51:56 volumio volumio[879]: verbose: ControllerMpd::sendMpdCommand play Oct 30 14:51:56 volumio volumio[879]: info: Oct 30 14:51:56 volumio volumio[879]: ---------------------------- MPD announces system playlist update Oct 30 14:51:56 volumio volumio[879]: info: Ignoring MPD Status Update Oct 30 14:51:56 volumio volumio[879]: info: Oct 30 14:51:56 volumio volumio[879]: ---------------------------- MPD announces system playlist update Oct 30 14:51:56 volumio volumio[879]: info: Ignoring MPD Status Update Oct 30 14:51:56 volumio volumio[879]: info: ------------------------------ 6ms Oct 30 14:51:56 volumio volumio[879]: info: sendMpdCommand play took 15 milliseconds Oct 30 14:51:56 volumio volumio[879]: info: ------------------------------ 18ms Oct 30 14:51:56 volumio volumio[879]: info: ------------------------------ 21ms Oct 30 14:51:56 volumio volumio[879]: info: Oct 30 14:51:56 volumio volumio[879]: ---------------------------- MPD announces state update: player Oct 30 14:51:56 volumio volumio[879]: info: ControllerMpd::getState Oct 30 14:51:56 volumio volumio[879]: verbose: ControllerMpd::sendMpdCommand status Oct 30 14:51:56 volumio volumio[879]: info: Oct 30 14:51:56 volumio volumio[879]: ---------------------------- MPD announces state update: player Oct 30 14:51:56 volumio volumio[879]: info: ControllerMpd::getState Oct 30 14:51:56 volumio volumio[879]: verbose: ControllerMpd::sendMpdCommand status Oct 30 14:51:56 volumio volumio[879]: info: Oct 30 14:51:56 volumio volumio[879]: ---------------------------- MPD announces state update: player Oct 30 14:51:56 volumio volumio[879]: info: ControllerMpd::getState Oct 30 14:51:56 volumio volumio[879]: verbose: ControllerMpd::sendMpdCommand status Oct 30 14:51:57 volumio volumio[879]: info: Oct 30 14:51:57 volumio volumio[879]: ---------------------------- MPD announces system playlist update Oct 30 14:51:57 volumio volumio[879]: info: Ignoring MPD Status Update Oct 30 14:51:57 volumio volumio[879]: info: Oct 30 14:51:57 volumio volumio[879]: ---------------------------- MPD announces state update: player Oct 30 14:51:57 volumio volumio[879]: info: ControllerMpd::getState Oct 30 14:51:57 volumio volumio[879]: verbose: ControllerMpd::sendMpdCommand status Oct 30 14:51:57 volumio volumio[879]: info: sendMpdCommand status took 118 milliseconds Oct 30 14:51:57 volumio volumio[879]: info: sendMpdCommand status took 114 milliseconds Oct 30 14:51:57 volumio volumio[879]: info: sendMpdCommand status took 106 milliseconds Oct 30 14:51:57 volumio volumio[879]: verbose: ControllerMpd::parseState Oct 30 14:51:57 volumio volumio[879]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 14:51:57 volumio volumio[879]: verbose: ControllerMpd::parseState Oct 30 14:51:57 volumio volumio[879]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 14:51:57 volumio volumio[879]: verbose: ControllerMpd::parseState Oct 30 14:51:57 volumio volumio[879]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 14:51:57 volumio volumio[879]: info: Oct 30 14:51:57 volumio volumio[879]: ---------------------------- MPD announces system playlist update Oct 30 14:51:57 volumio volumio[879]: info: Ignoring MPD Status Update Oct 30 14:51:57 volumio volumio[879]: info: Oct 30 14:51:57 volumio volumio[879]: ---------------------------- MPD announces state update: player Oct 30 14:51:57 volumio volumio[879]: info: ControllerMpd::getState Oct 30 14:51:57 volumio volumio[879]: verbose: ControllerMpd::sendMpdCommand status Oct 30 14:51:57 volumio volumio[879]: info: Oct 30 14:51:57 volumio volumio[879]: ---------------------------- MPD announces system playlist update Oct 30 14:51:57 volumio volumio[879]: info: Ignoring MPD Status Update Oct 30 14:51:57 volumio volumio[879]: info: Oct 30 14:51:57 volumio volumio[879]: ---------------------------- MPD announces state update: player Oct 30 14:51:57 volumio volumio[879]: info: ControllerMpd::getState Oct 30 14:51:57 volumio volumio[879]: verbose: ControllerMpd::sendMpdCommand status Oct 30 14:51:57 volumio volumio[879]: info: ------------------------------ 144ms Oct 30 14:51:57 volumio volumio[879]: info: sendMpdCommand status took 104 milliseconds Oct 30 14:51:57 volumio volumio[879]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 30 14:51:57 volumio volumio[879]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 30 14:51:57 volumio volumio[879]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 30 14:51:57 volumio volumio[879]: info: ------------------------------ 5ms Oct 30 14:51:57 volumio volumio[879]: info: sendMpdCommand status took 4 milliseconds Oct 30 14:51:57 volumio volumio[879]: info: ------------------------------ 4ms Oct 30 14:51:57 volumio volumio[879]: info: sendMpdCommand status took 3 milliseconds Oct 30 14:51:57 volumio volumio[879]: verbose: ControllerMpd::parseState Oct 30 14:51:57 volumio volumio[879]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 14:51:57 volumio volumio[879]: verbose: ControllerMpd::parseTrackInfo Oct 30 14:51:57 volumio volumio[879]: verbose: ControllerMpd::parseTrackInfo Oct 30 14:51:57 volumio volumio[879]: verbose: ControllerMpd::parseTrackInfo Oct 30 14:51:57 volumio volumio[879]: verbose: ControllerMpd::parseState Oct 30 14:51:57 volumio volumio[879]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 14:51:57 volumio volumio[879]: verbose: ControllerMpd::parseState Oct 30 14:51:57 volumio volumio[879]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 14:51:57 volumio volumio[879]: info: ControllerMpd::pushState Oct 30 14:51:57 volumio volumio[879]: info: CoreCommandRouter::servicePushState Oct 30 14:51:57 volumio volumio[879]: info: CorePlayQueue::getTrack 0 Oct 30 14:51:57 volumio volumio[879]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":183,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"464 Kbps","isStreaming":false,"title":"Rock or Bust","artist":"AC/DC","album":"Rock or Bust","uri":"http://192.168.1.150:32469/object/8ff7edaa42f773ab2b33/file.flac","trackType":"flac"} Oct 30 14:51:57 volumio volumio[879]: verbose: CURRENT POSITION 0 Oct 30 14:51:57 volumio volumio[879]: info: CoreStateMachine::syncState stateService play Oct 30 14:51:57 volumio volumio[879]: info: CoreStateMachine::syncState currentStatus stop Oct 30 14:51:57 volumio volumio[879]: info: ControllerMpd::pushState Oct 30 14:51:57 volumio volumio[879]: info: CoreCommandRouter::servicePushState Oct 30 14:51:57 volumio volumio[879]: info: CorePlayQueue::getTrack 0 Oct 30 14:51:57 volumio volumio[879]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":183,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"464 Kbps","isStreaming":false,"title":"Rock or Bust","artist":"AC/DC","album":"Rock or Bust","uri":"http://192.168.1.150:32469/object/8ff7edaa42f773ab2b33/file.flac","trackType":"flac"} Oct 30 14:51:57 volumio volumio[879]: verbose: CURRENT POSITION 0 Oct 30 14:51:57 volumio volumio[879]: info: CoreStateMachine::syncState stateService play Oct 30 14:51:57 volumio volumio[879]: info: CoreStateMachine::syncState currentStatus play Oct 30 14:51:57 volumio volumio[879]: info: Received an update from plugin. extracting info from payload Oct 30 14:51:57 volumio volumio[879]: info: CoreStateMachine::pushState Oct 30 14:51:57 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 14:51:57 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Oct 30 14:51:57 volumio volumio[879]: info: CoreStateMachine::pushState Oct 30 14:51:57 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Oct 30 14:51:57 volumio volumio[879]: info: ControllerMpd::pushState Oct 30 14:51:57 volumio volumio[879]: info: CoreCommandRouter::servicePushState Oct 30 14:51:57 volumio volumio[879]: info: CorePlayQueue::getTrack 0 Oct 30 14:51:57 volumio volumio[879]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":183,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"464 Kbps","isStreaming":false,"title":"Rock or Bust","artist":"AC/DC","album":"Rock or Bust","uri":"http://192.168.1.150:32469/object/8ff7edaa42f773ab2b33/file.flac","trackType":"flac"} Oct 30 14:51:57 volumio volumio[879]: verbose: CURRENT POSITION 0 Oct 30 14:51:57 volumio volumio[879]: info: CoreStateMachine::syncState stateService play Oct 30 14:51:57 volumio volumio[879]: info: CoreStateMachine::syncState currentStatus play Oct 30 14:51:57 volumio volumio[879]: info: Received an update from plugin. extracting info from payload Oct 30 14:51:57 volumio volumio[879]: info: CoreStateMachine::pushState Oct 30 14:51:57 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Oct 30 14:51:57 volumio volumio[879]: info: CoreStateMachine::pushState Oct 30 14:51:57 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Oct 30 14:51:57 volumio volumio[879]: info: ------------------------------ 225ms Oct 30 14:51:57 volumio volumio[879]: info: ------------------------------ 220ms Oct 30 14:51:57 volumio volumio[879]: info: ------------------------------ 206ms Oct 30 14:51:57 volumio volumio[879]: info: sendMpdCommand playlistinfo took 28 milliseconds Oct 30 14:51:57 volumio volumio[879]: info: sendMpdCommand playlistinfo took 27 milliseconds Oct 30 14:51:57 volumio volumio[879]: verbose: ControllerMpd::parseTrackInfo Oct 30 14:51:57 volumio volumio[879]: info: ControllerMpd::pushState Oct 30 14:51:57 volumio volumio[879]: info: CoreCommandRouter::servicePushState Oct 30 14:51:57 volumio volumio[879]: info: CorePlayQueue::getTrack 0 Oct 30 14:51:57 volumio volumio[879]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":183,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"465 Kbps","isStreaming":false,"title":"Rock or Bust","artist":"AC/DC","album":"Rock or Bust","uri":"http://192.168.1.150:32469/object/8ff7edaa42f773ab2b33/file.flac","trackType":"flac"} Oct 30 14:51:57 volumio volumio[879]: verbose: CURRENT POSITION 0 Oct 30 14:51:57 volumio volumio[879]: info: CoreStateMachine::syncState stateService play Oct 30 14:51:57 volumio volumio[879]: info: CoreStateMachine::syncState currentStatus play Oct 30 14:51:57 volumio volumio[879]: info: Received an update from plugin. extracting info from payload Oct 30 14:51:57 volumio volumio[879]: info: CoreStateMachine::pushState Oct 30 14:51:57 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 14:51:57 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Oct 30 14:51:57 volumio volumio[879]: info: CoreStateMachine::pushState Oct 30 14:51:57 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Oct 30 14:51:57 volumio volumio[879]: info: ControllerMpd::pushState Oct 30 14:51:57 volumio volumio[879]: info: CoreCommandRouter::servicePushState Oct 30 14:51:57 volumio volumio[879]: info: CorePlayQueue::getTrack 0 Oct 30 14:51:57 volumio volumio[879]: verbose: STATE SERVICE {"status":"play","position":0,"seek":960,"duration":183,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"465 Kbps","isStreaming":false,"title":"Rock or Bust","artist":"AC/DC","album":"Rock or Bust","uri":"http://192.168.1.150:32469/object/8ff7edaa42f773ab2b33/file.flac","trackType":"flac"} Oct 30 14:51:57 volumio volumio[879]: verbose: CURRENT POSITION 0 Oct 30 14:51:57 volumio volumio[879]: info: CoreStateMachine::syncState stateService play Oct 30 14:51:57 volumio volumio[879]: info: CoreStateMachine::syncState currentStatus play Oct 30 14:51:57 volumio volumio[879]: info: Received an update from plugin. extracting info from payload Oct 30 14:51:57 volumio volumio[879]: info: CoreStateMachine::pushState Oct 30 14:51:57 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Oct 30 14:51:57 volumio volumio[879]: info: CoreStateMachine::pushState Oct 30 14:51:57 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Oct 30 14:51:57 volumio volumio[879]: info: ControllerMpd::pushState Oct 30 14:51:57 volumio volumio[879]: info: CoreCommandRouter::servicePushState Oct 30 14:51:57 volumio volumio[879]: info: CorePlayQueue::getTrack 0 Oct 30 14:51:57 volumio volumio[879]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1075,"duration":183,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"459 Kbps","isStreaming":false,"title":"Rock or Bust","artist":"AC/DC","album":"Rock or Bust","uri":"http://192.168.1.150:32469/object/8ff7edaa42f773ab2b33/file.flac","trackType":"flac"} Oct 30 14:51:57 volumio volumio[879]: verbose: CURRENT POSITION 0 Oct 30 14:51:57 volumio volumio[879]: info: CoreStateMachine::syncState stateService play Oct 30 14:51:57 volumio volumio[879]: info: CoreStateMachine::syncState currentStatus play Oct 30 14:51:57 volumio volumio[879]: info: Received an update from plugin. extracting info from payload Oct 30 14:51:57 volumio volumio[879]: info: CoreStateMachine::pushState Oct 30 14:51:57 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 14:51:57 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Oct 30 14:51:57 volumio volumio[879]: info: CoreStateMachine::pushState Oct 30 14:51:57 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Oct 30 14:51:57 volumio volumio[879]: info: ------------------------------ 225ms Oct 30 14:51:57 volumio volumio[879]: info: ------------------------------ 98ms Oct 30 14:51:57 volumio volumio[879]: info: ------------------------------ 118ms Oct 30 14:51:57 volumio volumio[879]: info: CoreCommandRouter::volumioGetQueue Oct 30 14:51:57 volumio volumio[879]: info: CoreStateMachine::getQueue Oct 30 14:51:57 volumio volumio[879]: info: CorePlayQueue::getQueue Oct 30 14:51:57 volumio volumio[879]: info: CoreCommandRouter::volumioGetQueue Oct 30 14:51:57 volumio volumio[879]: info: CoreStateMachine::getQueue Oct 30 14:51:57 volumio volumio[879]: info: CorePlayQueue::getQueue Oct 30 14:51:57 volumio volumio[879]: info: CoreCommandRouter::volumioGetQueue Oct 30 14:51:57 volumio volumio[879]: info: CoreStateMachine::getQueue Oct 30 14:51:57 volumio volumio[879]: info: CorePlayQueue::getQueue Oct 30 14:51:57 volumio volumio[879]: info: CoreCommandRouter::volumioGetQueue Oct 30 14:51:57 volumio volumio[879]: info: CoreStateMachine::getQueue Oct 30 14:51:57 volumio volumio[879]: info: CorePlayQueue::getQueue Oct 30 14:51:57 volumio volumio[879]: info: CoreCommandRouter::volumioGetQueue Oct 30 14:51:57 volumio volumio[879]: info: CoreStateMachine::getQueue Oct 30 14:51:57 volumio volumio[879]: info: CorePlayQueue::getQueue Oct 30 14:51:57 volumio volumio[879]: info: CoreCommandRouter::volumioGetQueue Oct 30 14:51:57 volumio volumio[879]: info: CoreStateMachine::getQueue Oct 30 14:51:57 volumio volumio[879]: info: CorePlayQueue::getQueue Oct 30 14:51:57 volumio volumio[879]: info: CoreCommandRouter::volumioGetQueue Oct 30 14:51:57 volumio volumio[879]: info: CoreStateMachine::getQueue Oct 30 14:51:57 volumio volumio[879]: info: CorePlayQueue::getQueue Oct 30 14:51:57 volumio volumio[879]: info: CoreCommandRouter::volumioGetQueue Oct 30 14:51:57 volumio volumio[879]: info: CoreStateMachine::getQueue Oct 30 14:51:57 volumio volumio[879]: info: CorePlayQueue::getQueue Oct 30 14:51:57 volumio volumio[879]: info: CoreCommandRouter::volumioGetQueue Oct 30 14:51:57 volumio volumio[879]: info: CoreStateMachine::getQueue Oct 30 14:51:57 volumio volumio[879]: info: CorePlayQueue::getQueue Oct 30 14:51:57 volumio volumio[879]: info: CoreCommandRouter::volumioGetQueue Oct 30 14:51:57 volumio volumio[879]: info: CoreStateMachine::getQueue Oct 30 14:51:57 volumio volumio[879]: info: CorePlayQueue::getQueue Oct 30 14:52:01 volumio volumio[879]: info: Preload queue cleared Oct 30 14:52:01 volumio volumio[879]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::ClearQueue Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::stop Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::stPlaybackTimer Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::updateTrackBlock Oct 30 14:52:01 volumio volumio[879]: info: CorePlayQueue::getTrackBlock Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::pushState Oct 30 14:52:01 volumio volumio[879]: info: CorePlayQueue::getTrack 0 Oct 30 14:52:01 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 14:52:01 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::serviceStop Oct 30 14:52:01 volumio volumio[879]: info: CorePlayQueue::getTrack 0 Oct 30 14:52:01 volumio volumio[879]: info: CoreCommandRouter::serviceStop Oct 30 14:52:01 volumio volumio[879]: info: [1761835921210] ControllerUPNPBrowser::stop Oct 30 14:52:01 volumio volumio[879]: verbose: ControllerMpd::sendMpdCommand stop Oct 30 14:52:01 volumio volumio[879]: info: CorePlayQueue::clearPlayQueue Oct 30 14:52:01 volumio volumio[879]: info: CorePlayQueue::saveQueue Oct 30 14:52:01 volumio volumio[879]: info: CoreCommandRouter::volumioPushQueue Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::addQueueItems Oct 30 14:52:01 volumio volumio[879]: info: CorePlayQueue::addQueueItems Oct 30 14:52:01 volumio volumio[879]: info: Preload queue cleared Oct 30 14:52:01 volumio volumio[879]: info: Adding Item to queue: upnp/folder/http://192.168.1.150:32469/ContentDirectory/a6dd7c96-321d-5e34-6375-8796f4593bcf/control.xml@cb75fb99f569dfb495a4 Oct 30 14:52:01 volumio volumio[879]: info: Exploding uri upnp/folder/http://192.168.1.150:32469/ContentDirectory/a6dd7c96-321d-5e34-6375-8796f4593bcf/control.xml@cb75fb99f569dfb495a4 in service upnp_browser Oct 30 14:52:01 volumio volumio[879]: info: Oct 30 14:52:01 volumio volumio[879]: ---------------------------- MPD announces state update: player Oct 30 14:52:01 volumio volumio[879]: info: sendMpdCommand stop took 36 milliseconds Oct 30 14:52:01 volumio volumio[879]: info: ControllerMpd::getState Oct 30 14:52:01 volumio volumio[879]: verbose: ControllerMpd::sendMpdCommand status Oct 30 14:52:01 volumio volumio[879]: info: Oct 30 14:52:01 volumio volumio[879]: ---------------------------- MPD announces state update: player Oct 30 14:52:01 volumio volumio[879]: info: ControllerMpd::getState Oct 30 14:52:01 volumio volumio[879]: verbose: ControllerMpd::sendMpdCommand status Oct 30 14:52:01 volumio volumio[879]: info: Oct 30 14:52:01 volumio volumio[879]: ---------------------------- MPD announces state update: player Oct 30 14:52:01 volumio volumio[879]: info: ControllerMpd::getState Oct 30 14:52:01 volumio volumio[879]: verbose: ControllerMpd::sendMpdCommand status Oct 30 14:52:01 volumio volumio[879]: info: sendMpdCommand status took 5 milliseconds Oct 30 14:52:01 volumio volumio[879]: info: sendMpdCommand status took 3 milliseconds Oct 30 14:52:01 volumio volumio[879]: info: sendMpdCommand status took 1 milliseconds Oct 30 14:52:01 volumio volumio[879]: verbose: ControllerMpd::parseState Oct 30 14:52:01 volumio volumio[879]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 14:52:01 volumio volumio[879]: verbose: ControllerMpd::parseState Oct 30 14:52:01 volumio volumio[879]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 14:52:01 volumio volumio[879]: verbose: ControllerMpd::parseState Oct 30 14:52:01 volumio volumio[879]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 14:52:01 volumio volumio[879]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 30 14:52:01 volumio volumio[879]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 30 14:52:01 volumio volumio[879]: verbose: ControllerMpd::parseTrackInfo Oct 30 14:52:01 volumio volumio[879]: verbose: ControllerMpd::parseTrackInfo Oct 30 14:52:01 volumio volumio[879]: verbose: ControllerMpd::parseTrackInfo Oct 30 14:52:01 volumio volumio[879]: info: ControllerMpd::pushState Oct 30 14:52:01 volumio volumio[879]: info: CoreCommandRouter::servicePushState Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::pushState Oct 30 14:52:01 volumio volumio[879]: info: CorePlayQueue::getTrack 0 Oct 30 14:52:01 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 14:52:01 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Oct 30 14:52:01 volumio volumio[879]: info: CorePlayQueue::getTrack 0 Oct 30 14:52:01 volumio volumio[879]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Rock or Bust","artist":"AC/DC","album":"Rock or Bust","uri":"http://192.168.1.150:32469/object/8ff7edaa42f773ab2b33/file.flac","trackType":"flac"} Oct 30 14:52:01 volumio volumio[879]: verbose: CURRENT POSITION 0 Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::syncState stateService stop Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::syncState currentStatus stop Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::pushState Oct 30 14:52:01 volumio volumio[879]: info: CorePlayQueue::getTrack 0 Oct 30 14:52:01 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Oct 30 14:52:01 volumio volumio[879]: info: No code Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::pushState Oct 30 14:52:01 volumio volumio[879]: info: CorePlayQueue::getTrack 0 Oct 30 14:52:01 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Oct 30 14:52:01 volumio volumio[879]: info: ControllerMpd::pushState Oct 30 14:52:01 volumio volumio[879]: info: CoreCommandRouter::servicePushState Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::pushState Oct 30 14:52:01 volumio volumio[879]: info: CorePlayQueue::getTrack 0 Oct 30 14:52:01 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Oct 30 14:52:01 volumio volumio[879]: info: CorePlayQueue::getTrack 0 Oct 30 14:52:01 volumio volumio[879]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Rock or Bust","artist":"AC/DC","album":"Rock or Bust","uri":"http://192.168.1.150:32469/object/8ff7edaa42f773ab2b33/file.flac","trackType":"flac"} Oct 30 14:52:01 volumio volumio[879]: verbose: CURRENT POSITION 0 Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::syncState stateService stop Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::syncState currentStatus stop Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::pushState Oct 30 14:52:01 volumio volumio[879]: info: CorePlayQueue::getTrack 0 Oct 30 14:52:01 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Oct 30 14:52:01 volumio volumio[879]: info: No code Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::pushState Oct 30 14:52:01 volumio volumio[879]: info: CorePlayQueue::getTrack 0 Oct 30 14:52:01 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Oct 30 14:52:01 volumio volumio[879]: info: ControllerMpd::pushState Oct 30 14:52:01 volumio volumio[879]: info: CoreCommandRouter::servicePushState Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::pushState Oct 30 14:52:01 volumio volumio[879]: info: CorePlayQueue::getTrack 0 Oct 30 14:52:01 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Oct 30 14:52:01 volumio volumio[879]: info: CorePlayQueue::getTrack 0 Oct 30 14:52:01 volumio volumio[879]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Rock or Bust","artist":"AC/DC","album":"Rock or Bust","uri":"http://192.168.1.150:32469/object/8ff7edaa42f773ab2b33/file.flac","trackType":"flac"} Oct 30 14:52:01 volumio volumio[879]: verbose: CURRENT POSITION 0 Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::syncState stateService stop Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::syncState currentStatus stop Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::pushState Oct 30 14:52:01 volumio volumio[879]: info: CorePlayQueue::getTrack 0 Oct 30 14:52:01 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Oct 30 14:52:01 volumio volumio[879]: info: No code Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::pushState Oct 30 14:52:01 volumio volumio[879]: info: CorePlayQueue::getTrack 0 Oct 30 14:52:01 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Oct 30 14:52:01 volumio volumio[879]: info: ------------------------------ 59ms Oct 30 14:52:01 volumio volumio[879]: info: ------------------------------ 57ms Oct 30 14:52:01 volumio volumio[879]: info: ------------------------------ 56ms Oct 30 14:52:01 volumio volumio[879]: info: touch_display: Setting screensaver timeout to 120 seconds. Oct 30 14:52:01 volumio volumio[879]: info: CoreCommandRouter::volumioPushQueue Oct 30 14:52:01 volumio volumio[879]: info: CorePlayQueue::saveQueue Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::updateTrackBlock Oct 30 14:52:01 volumio volumio[879]: info: CorePlayQueue::getTrackBlock Oct 30 14:52:01 volumio volumio[879]: info: CoreCommandRouter::volumioPlay Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::play index 0 Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::stop Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::play index undefined Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 14:52:01 volumio volumio[879]: info: CorePlayQueue::getTrack 0 Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::startPlaybackTimer Oct 30 14:52:01 volumio volumio[879]: info: CorePlayQueue::getTrack 0 Oct 30 14:52:01 volumio volumio[879]: info: [1761835921374] ControllerUPNPBrowser::clearAddPlayTrack Oct 30 14:52:01 volumio volumio[879]: verbose: ControllerMpd::sendMpdCommand stop Oct 30 14:52:01 volumio volumio[879]: info: sendMpdCommand stop took 10 milliseconds Oct 30 14:52:01 volumio volumio[879]: verbose: ControllerMpd::sendMpdCommand clear Oct 30 14:52:01 volumio volumio[879]: info: Oct 30 14:52:01 volumio volumio[879]: ---------------------------- MPD announces system playlist update Oct 30 14:52:01 volumio volumio[879]: info: Ignoring MPD Status Update Oct 30 14:52:01 volumio volumio[879]: info: sendMpdCommand clear took 11 milliseconds Oct 30 14:52:01 volumio volumio[879]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.150:32469/object/8ff7edaa42f773ab2b33/file.flac" Oct 30 14:52:01 volumio volumio[879]: info: Oct 30 14:52:01 volumio volumio[879]: ---------------------------- MPD announces system playlist update Oct 30 14:52:01 volumio volumio[879]: info: Ignoring MPD Status Update Oct 30 14:52:01 volumio volumio[879]: info: Oct 30 14:52:01 volumio volumio[879]: ---------------------------- MPD announces system playlist update Oct 30 14:52:01 volumio volumio[879]: info: Ignoring MPD Status Update Oct 30 14:52:01 volumio volumio[879]: error: updateQueue error: null Oct 30 14:52:01 volumio volumio[879]: error: updateQueue error: null Oct 30 14:52:01 volumio volumio[879]: info: ------------------------------ 27ms Oct 30 14:52:01 volumio volumio[879]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.150:32469/object/8ff7edaa42f773ab2b33/file.flac" Oct 30 14:52:01 volumio volumio[879]: info: ------------------------------ 24ms Oct 30 14:52:01 volumio volumio[879]: info: Oct 30 14:52:01 volumio volumio[879]: ---------------------------- MPD announces system playlist update Oct 30 14:52:01 volumio volumio[879]: info: Ignoring MPD Status Update Oct 30 14:52:01 volumio volumio[879]: info: sendMpdCommand add "http://192.168.1.150:32469/object/8ff7edaa42f773ab2b33/file.flac" took 11 milliseconds Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 30 14:52:01 volumio volumio[879]: verbose: ControllerMpd::sendMpdCommand play Oct 30 14:52:01 volumio volumio[879]: info: Oct 30 14:52:01 volumio volumio[879]: ---------------------------- MPD announces system playlist update Oct 30 14:52:01 volumio volumio[879]: info: Ignoring MPD Status Update Oct 30 14:52:01 volumio volumio[879]: info: Oct 30 14:52:01 volumio volumio[879]: ---------------------------- MPD announces system playlist update Oct 30 14:52:01 volumio volumio[879]: info: Ignoring MPD Status Update Oct 30 14:52:01 volumio volumio[879]: info: ------------------------------ 13ms Oct 30 14:52:01 volumio volumio[879]: info: sendMpdCommand play took 9 milliseconds Oct 30 14:52:01 volumio volumio[879]: info: ------------------------------ 5ms Oct 30 14:52:01 volumio volumio[879]: info: ------------------------------ 4ms Oct 30 14:52:01 volumio volumio[879]: info: Oct 30 14:52:01 volumio volumio[879]: ---------------------------- MPD announces state update: player Oct 30 14:52:01 volumio volumio[879]: info: ControllerMpd::getState Oct 30 14:52:01 volumio volumio[879]: verbose: ControllerMpd::sendMpdCommand status Oct 30 14:52:01 volumio volumio[879]: info: Oct 30 14:52:01 volumio volumio[879]: ---------------------------- MPD announces state update: player Oct 30 14:52:01 volumio volumio[879]: info: ControllerMpd::getState Oct 30 14:52:01 volumio volumio[879]: verbose: ControllerMpd::sendMpdCommand status Oct 30 14:52:01 volumio volumio[879]: info: Oct 30 14:52:01 volumio volumio[879]: ---------------------------- MPD announces state update: player Oct 30 14:52:01 volumio volumio[879]: info: ControllerMpd::getState Oct 30 14:52:01 volumio volumio[879]: verbose: ControllerMpd::sendMpdCommand status Oct 30 14:52:01 volumio volumio[879]: info: sendMpdCommand status took 18 milliseconds Oct 30 14:52:01 volumio volumio[879]: verbose: ControllerMpd::parseState Oct 30 14:52:01 volumio volumio[879]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 14:52:01 volumio volumio[879]: info: Oct 30 14:52:01 volumio volumio[879]: ---------------------------- MPD announces state update: player Oct 30 14:52:01 volumio volumio[879]: info: Oct 30 14:52:01 volumio volumio[879]: ---------------------------- MPD announces system playlist update Oct 30 14:52:01 volumio volumio[879]: info: Ignoring MPD Status Update Oct 30 14:52:01 volumio volumio[879]: info: Oct 30 14:52:01 volumio volumio[879]: ---------------------------- MPD announces state update: player Oct 30 14:52:01 volumio volumio[879]: info: ControllerMpd::getState Oct 30 14:52:01 volumio volumio[879]: verbose: ControllerMpd::sendMpdCommand status Oct 30 14:52:01 volumio volumio[879]: info: sendMpdCommand status took 25 milliseconds Oct 30 14:52:01 volumio volumio[879]: info: sendMpdCommand status took 21 milliseconds Oct 30 14:52:01 volumio volumio[879]: info: ControllerMpd::getState Oct 30 14:52:01 volumio volumio[879]: verbose: ControllerMpd::sendMpdCommand status Oct 30 14:52:01 volumio volumio[879]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 30 14:52:01 volumio volumio[879]: verbose: ControllerMpd::parseState Oct 30 14:52:01 volumio volumio[879]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 14:52:01 volumio volumio[879]: verbose: ControllerMpd::parseState Oct 30 14:52:01 volumio volumio[879]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 14:52:01 volumio volumio[879]: verbose: ControllerMpd::parseTrackInfo Oct 30 14:52:01 volumio volumio[879]: info: ControllerMpd::pushState Oct 30 14:52:01 volumio volumio[879]: info: CoreCommandRouter::servicePushState Oct 30 14:52:01 volumio volumio[879]: info: CorePlayQueue::getTrack 0 Oct 30 14:52:01 volumio volumio[879]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":183,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Rock or Bust","artist":"AC/DC","album":"Rock or Bust","uri":"http://192.168.1.150:32469/object/8ff7edaa42f773ab2b33/file.flac","trackType":"flac"} Oct 30 14:52:01 volumio volumio[879]: verbose: CURRENT POSITION 0 Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::syncState stateService play Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::syncState currentStatus stop Oct 30 14:52:01 volumio volumio[879]: info: ------------------------------ 32ms Oct 30 14:52:01 volumio volumio[879]: info: Oct 30 14:52:01 volumio volumio[879]: ---------------------------- MPD announces state update: player Oct 30 14:52:01 volumio volumio[879]: info: ControllerMpd::getState Oct 30 14:52:01 volumio volumio[879]: verbose: ControllerMpd::sendMpdCommand status Oct 30 14:52:01 volumio volumio[879]: info: Oct 30 14:52:01 volumio volumio[879]: ---------------------------- MPD announces state update: player Oct 30 14:52:01 volumio volumio[879]: info: ControllerMpd::getState Oct 30 14:52:01 volumio volumio[879]: verbose: ControllerMpd::sendMpdCommand status Oct 30 14:52:01 volumio volumio[879]: info: sendMpdCommand status took 8 milliseconds Oct 30 14:52:01 volumio volumio[879]: info: ------------------------------ 9ms Oct 30 14:52:01 volumio volumio[879]: info: sendMpdCommand status took 8 milliseconds Oct 30 14:52:01 volumio volumio[879]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 30 14:52:01 volumio volumio[879]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 30 14:52:01 volumio volumio[879]: info: sendMpdCommand status took 13 milliseconds Oct 30 14:52:01 volumio volumio[879]: info: sendMpdCommand status took 13 milliseconds Oct 30 14:52:01 volumio volumio[879]: verbose: ControllerMpd::parseState Oct 30 14:52:01 volumio volumio[879]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 14:52:01 volumio volumio[879]: verbose: ControllerMpd::parseState Oct 30 14:52:01 volumio volumio[879]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 14:52:01 volumio volumio[879]: verbose: ControllerMpd::parseTrackInfo Oct 30 14:52:01 volumio volumio[879]: verbose: ControllerMpd::parseTrackInfo Oct 30 14:52:01 volumio volumio[879]: verbose: ControllerMpd::parseState Oct 30 14:52:01 volumio volumio[879]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 14:52:01 volumio volumio[879]: verbose: ControllerMpd::parseState Oct 30 14:52:01 volumio volumio[879]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 14:52:01 volumio volumio[879]: info: ControllerMpd::pushState Oct 30 14:52:01 volumio volumio[879]: info: CoreCommandRouter::servicePushState Oct 30 14:52:01 volumio volumio[879]: info: CorePlayQueue::getTrack 0 Oct 30 14:52:01 volumio volumio[879]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":183,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Rock or Bust","artist":"AC/DC","album":"Rock or Bust","uri":"http://192.168.1.150:32469/object/8ff7edaa42f773ab2b33/file.flac","trackType":"flac"} Oct 30 14:52:01 volumio volumio[879]: verbose: CURRENT POSITION 0 Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::syncState stateService play Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::syncState currentStatus play Oct 30 14:52:01 volumio volumio[879]: info: Received an update from plugin. extracting info from payload Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::pushState Oct 30 14:52:01 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 14:52:01 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::pushState Oct 30 14:52:01 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Oct 30 14:52:01 volumio volumio[879]: info: ControllerMpd::pushState Oct 30 14:52:01 volumio volumio[879]: info: CoreCommandRouter::servicePushState Oct 30 14:52:01 volumio volumio[879]: info: CorePlayQueue::getTrack 0 Oct 30 14:52:01 volumio volumio[879]: verbose: STATE SERVICE {"status":"play","position":0,"seek":91,"duration":183,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1 Kbps","isStreaming":false,"title":"Rock or Bust","artist":"AC/DC","album":"Rock or Bust","uri":"http://192.168.1.150:32469/object/8ff7edaa42f773ab2b33/file.flac","trackType":"flac"} Oct 30 14:52:01 volumio volumio[879]: verbose: CURRENT POSITION 0 Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::syncState stateService play Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::syncState currentStatus play Oct 30 14:52:01 volumio volumio[879]: info: Received an update from plugin. extracting info from payload Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::pushState Oct 30 14:52:01 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 14:52:01 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::pushState Oct 30 14:52:01 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Oct 30 14:52:01 volumio volumio[879]: info: ------------------------------ 76ms Oct 30 14:52:01 volumio volumio[879]: info: ------------------------------ 76ms Oct 30 14:52:01 volumio volumio[879]: info: Oct 30 14:52:01 volumio volumio[879]: ---------------------------- MPD announces system playlist update Oct 30 14:52:01 volumio volumio[879]: info: Ignoring MPD Status Update Oct 30 14:52:01 volumio volumio[879]: info: Oct 30 14:52:01 volumio volumio[879]: ---------------------------- MPD announces state update: player Oct 30 14:52:01 volumio volumio[879]: info: ControllerMpd::getState Oct 30 14:52:01 volumio volumio[879]: verbose: ControllerMpd::sendMpdCommand status Oct 30 14:52:01 volumio volumio[879]: info: Oct 30 14:52:01 volumio volumio[879]: ---------------------------- MPD announces system playlist update Oct 30 14:52:01 volumio volumio[879]: info: Ignoring MPD Status Update Oct 30 14:52:01 volumio volumio[879]: info: Oct 30 14:52:01 volumio volumio[879]: ---------------------------- MPD announces state update: player Oct 30 14:52:01 volumio volumio[879]: info: ControllerMpd::getState Oct 30 14:52:01 volumio volumio[879]: verbose: ControllerMpd::sendMpdCommand status Oct 30 14:52:01 volumio volumio[879]: info: sendMpdCommand playlistinfo took 37 milliseconds Oct 30 14:52:01 volumio volumio[879]: info: sendMpdCommand playlistinfo took 36 milliseconds Oct 30 14:52:01 volumio volumio[879]: info: sendMpdCommand playlistinfo took 35 milliseconds Oct 30 14:52:01 volumio volumio[879]: info: sendMpdCommand playlistinfo took 35 milliseconds Oct 30 14:52:01 volumio volumio[879]: info: ------------------------------ 6ms Oct 30 14:52:01 volumio volumio[879]: info: sendMpdCommand status took 4 milliseconds Oct 30 14:52:01 volumio volumio[879]: info: ------------------------------ 4ms Oct 30 14:52:01 volumio volumio[879]: info: sendMpdCommand status took 2 milliseconds Oct 30 14:52:01 volumio volumio[879]: verbose: ControllerMpd::parseTrackInfo Oct 30 14:52:01 volumio volumio[879]: verbose: ControllerMpd::parseTrackInfo Oct 30 14:52:01 volumio volumio[879]: verbose: ControllerMpd::parseTrackInfo Oct 30 14:52:01 volumio volumio[879]: verbose: ControllerMpd::parseTrackInfo Oct 30 14:52:01 volumio volumio[879]: verbose: ControllerMpd::parseState Oct 30 14:52:01 volumio volumio[879]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 14:52:01 volumio volumio[879]: verbose: ControllerMpd::parseState Oct 30 14:52:01 volumio volumio[879]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 14:52:01 volumio volumio[879]: info: ControllerMpd::pushState Oct 30 14:52:01 volumio volumio[879]: info: CoreCommandRouter::servicePushState Oct 30 14:52:01 volumio volumio[879]: info: CorePlayQueue::getTrack 0 Oct 30 14:52:01 volumio volumio[879]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":183,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"464 Kbps","isStreaming":false,"title":"Rock or Bust","artist":"AC/DC","album":"Rock or Bust","uri":"http://192.168.1.150:32469/object/8ff7edaa42f773ab2b33/file.flac","trackType":"flac"} Oct 30 14:52:01 volumio volumio[879]: verbose: CURRENT POSITION 0 Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::syncState stateService play Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::syncState currentStatus play Oct 30 14:52:01 volumio volumio[879]: info: Received an update from plugin. extracting info from payload Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::pushState Oct 30 14:52:01 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 14:52:01 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::pushState Oct 30 14:52:01 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Oct 30 14:52:01 volumio volumio[879]: info: ControllerMpd::pushState Oct 30 14:52:01 volumio volumio[879]: info: CoreCommandRouter::servicePushState Oct 30 14:52:01 volumio volumio[879]: info: CorePlayQueue::getTrack 0 Oct 30 14:52:01 volumio volumio[879]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":183,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"464 Kbps","isStreaming":false,"title":"Rock or Bust","artist":"AC/DC","album":"Rock or Bust","uri":"http://192.168.1.150:32469/object/8ff7edaa42f773ab2b33/file.flac","trackType":"flac"} Oct 30 14:52:01 volumio volumio[879]: verbose: CURRENT POSITION 0 Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::syncState stateService play Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::syncState currentStatus play Oct 30 14:52:01 volumio volumio[879]: info: Received an update from plugin. extracting info from payload Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::pushState Oct 30 14:52:01 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::pushState Oct 30 14:52:01 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Oct 30 14:52:01 volumio volumio[879]: info: ControllerMpd::pushState Oct 30 14:52:01 volumio volumio[879]: info: CoreCommandRouter::servicePushState Oct 30 14:52:01 volumio volumio[879]: info: CorePlayQueue::getTrack 0 Oct 30 14:52:01 volumio volumio[879]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":183,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"464 Kbps","isStreaming":false,"title":"Rock or Bust","artist":"AC/DC","album":"Rock or Bust","uri":"http://192.168.1.150:32469/object/8ff7edaa42f773ab2b33/file.flac","trackType":"flac"} Oct 30 14:52:01 volumio volumio[879]: verbose: CURRENT POSITION 0 Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::syncState stateService play Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::syncState currentStatus play Oct 30 14:52:01 volumio volumio[879]: info: Received an update from plugin. extracting info from payload Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::pushState Oct 30 14:52:01 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::pushState Oct 30 14:52:01 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Oct 30 14:52:01 volumio volumio[879]: info: ControllerMpd::pushState Oct 30 14:52:01 volumio volumio[879]: info: CoreCommandRouter::servicePushState Oct 30 14:52:01 volumio volumio[879]: info: CorePlayQueue::getTrack 0 Oct 30 14:52:01 volumio volumio[879]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":183,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"464 Kbps","isStreaming":false,"title":"Rock or Bust","artist":"AC/DC","album":"Rock or Bust","uri":"http://192.168.1.150:32469/object/8ff7edaa42f773ab2b33/file.flac","trackType":"flac"} Oct 30 14:52:01 volumio volumio[879]: verbose: CURRENT POSITION 0 Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::syncState stateService play Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::syncState currentStatus play Oct 30 14:52:01 volumio volumio[879]: info: Received an update from plugin. extracting info from payload Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::pushState Oct 30 14:52:01 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::pushState Oct 30 14:52:01 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Oct 30 14:52:01 volumio volumio[879]: info: ------------------------------ 119ms Oct 30 14:52:01 volumio volumio[879]: info: ------------------------------ 119ms Oct 30 14:52:01 volumio volumio[879]: info: ------------------------------ 113ms Oct 30 14:52:01 volumio volumio[879]: info: ------------------------------ 113ms Oct 30 14:52:01 volumio volumio[879]: info: CoreCommandRouter::volumioGetQueue Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::getQueue Oct 30 14:52:01 volumio volumio[879]: info: CorePlayQueue::getQueue Oct 30 14:52:01 volumio volumio[879]: info: CoreCommandRouter::volumioGetQueue Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::getQueue Oct 30 14:52:01 volumio volumio[879]: info: CorePlayQueue::getQueue Oct 30 14:52:01 volumio volumio[879]: info: CoreCommandRouter::volumioGetQueue Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::getQueue Oct 30 14:52:01 volumio volumio[879]: info: CorePlayQueue::getQueue Oct 30 14:52:01 volumio volumio[879]: info: CoreCommandRouter::volumioGetQueue Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::getQueue Oct 30 14:52:01 volumio volumio[879]: info: CorePlayQueue::getQueue Oct 30 14:52:01 volumio volumio[879]: info: CoreCommandRouter::volumioGetQueue Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::getQueue Oct 30 14:52:01 volumio volumio[879]: info: CorePlayQueue::getQueue Oct 30 14:52:01 volumio volumio[879]: info: CoreCommandRouter::volumioGetQueue Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::getQueue Oct 30 14:52:01 volumio volumio[879]: info: CorePlayQueue::getQueue Oct 30 14:52:01 volumio volumio[879]: info: CoreCommandRouter::volumioGetQueue Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::getQueue Oct 30 14:52:01 volumio volumio[879]: info: CorePlayQueue::getQueue Oct 30 14:52:01 volumio volumio[879]: info: CoreCommandRouter::volumioGetQueue Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::getQueue Oct 30 14:52:01 volumio volumio[879]: info: CorePlayQueue::getQueue Oct 30 14:52:01 volumio volumio[879]: info: CoreCommandRouter::volumioGetQueue Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::getQueue Oct 30 14:52:01 volumio volumio[879]: info: CorePlayQueue::getQueue Oct 30 14:52:01 volumio volumio[879]: info: CoreCommandRouter::volumioGetQueue Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::getQueue Oct 30 14:52:01 volumio volumio[879]: info: CorePlayQueue::getQueue Oct 30 14:52:01 volumio volumio[879]: info: CoreCommandRouter::volumioGetQueue Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::getQueue Oct 30 14:52:01 volumio volumio[879]: info: CorePlayQueue::getQueue Oct 30 14:52:01 volumio volumio[879]: info: CoreCommandRouter::volumioGetQueue Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::getQueue Oct 30 14:52:01 volumio volumio[879]: info: CorePlayQueue::getQueue Oct 30 14:52:01 volumio volumio[879]: info: sendMpdCommand playlistinfo took 99 milliseconds Oct 30 14:52:01 volumio volumio[879]: info: sendMpdCommand playlistinfo took 99 milliseconds Oct 30 14:52:01 volumio volumio[879]: verbose: ControllerMpd::parseTrackInfo Oct 30 14:52:01 volumio volumio[879]: verbose: ControllerMpd::parseTrackInfo Oct 30 14:52:01 volumio volumio[879]: info: ControllerMpd::pushState Oct 30 14:52:01 volumio volumio[879]: info: CoreCommandRouter::servicePushState Oct 30 14:52:01 volumio volumio[879]: info: CorePlayQueue::getTrack 0 Oct 30 14:52:01 volumio volumio[879]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":183,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"464 Kbps","isStreaming":false,"title":"Rock or Bust","artist":"AC/DC","album":"Rock or Bust","uri":"http://192.168.1.150:32469/object/8ff7edaa42f773ab2b33/file.flac","trackType":"flac"} Oct 30 14:52:01 volumio volumio[879]: verbose: CURRENT POSITION 0 Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::syncState stateService play Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::syncState currentStatus play Oct 30 14:52:01 volumio volumio[879]: info: Received an update from plugin. extracting info from payload Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::pushState Oct 30 14:52:01 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 14:52:01 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::pushState Oct 30 14:52:01 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Oct 30 14:52:01 volumio volumio[879]: info: ControllerMpd::pushState Oct 30 14:52:01 volumio volumio[879]: info: CoreCommandRouter::servicePushState Oct 30 14:52:01 volumio volumio[879]: info: CorePlayQueue::getTrack 0 Oct 30 14:52:01 volumio volumio[879]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":183,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"464 Kbps","isStreaming":false,"title":"Rock or Bust","artist":"AC/DC","album":"Rock or Bust","uri":"http://192.168.1.150:32469/object/8ff7edaa42f773ab2b33/file.flac","trackType":"flac"} Oct 30 14:52:01 volumio volumio[879]: verbose: CURRENT POSITION 0 Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::syncState stateService play Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::syncState currentStatus play Oct 30 14:52:01 volumio volumio[879]: info: Received an update from plugin. extracting info from payload Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::pushState Oct 30 14:52:01 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::pushState Oct 30 14:52:01 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Oct 30 14:52:01 volumio volumio[879]: info: ------------------------------ 139ms Oct 30 14:52:01 volumio volumio[879]: info: ------------------------------ 138ms Oct 30 14:52:01 volumio volumio[879]: info: CoreCommandRouter::volumioGetQueue Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::getQueue Oct 30 14:52:01 volumio volumio[879]: info: CorePlayQueue::getQueue Oct 30 14:52:01 volumio volumio[879]: info: CoreCommandRouter::volumioGetQueue Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::getQueue Oct 30 14:52:01 volumio volumio[879]: info: CorePlayQueue::getQueue Oct 30 14:52:01 volumio volumio[879]: info: CoreCommandRouter::volumioGetQueue Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::getQueue Oct 30 14:52:01 volumio volumio[879]: info: CorePlayQueue::getQueue Oct 30 14:52:01 volumio volumio[879]: info: CoreCommandRouter::volumioGetQueue Oct 30 14:52:01 volumio volumio[879]: info: CoreStateMachine::getQueue Oct 30 14:52:01 volumio volumio[879]: info: CorePlayQueue::getQueue Oct 30 14:52:04 volumio volumio[879]: info: CoreCommandRouter::volumioPause Oct 30 14:52:04 volumio volumio[879]: info: CoreStateMachine::pause Oct 30 14:52:04 volumio volumio[879]: info: CoreStateMachine::stPlaybackTimer Oct 30 14:52:04 volumio volumio[879]: info: CoreStateMachine::servicePause Oct 30 14:52:04 volumio volumio[879]: info: CorePlayQueue::getTrack 0 Oct 30 14:52:04 volumio volumio[879]: info: CoreCommandRouter::servicePause Oct 30 14:52:04 volumio volumio[879]: info: [1761835924231] ControllerUPNPBrowser::pause Oct 30 14:52:04 volumio volumio[879]: verbose: ControllerMpd::sendMpdCommand pause Oct 30 14:52:04 volumio volumio[879]: info: Oct 30 14:52:04 volumio volumio[879]: ---------------------------- MPD announces state update: player Oct 30 14:52:04 volumio volumio[879]: info: sendMpdCommand pause took 3 milliseconds Oct 30 14:52:04 volumio volumio[879]: info: ControllerMpd::getState Oct 30 14:52:04 volumio volumio[879]: verbose: ControllerMpd::sendMpdCommand status Oct 30 14:52:04 volumio volumio[879]: info: Oct 30 14:52:04 volumio volumio[879]: ---------------------------- MPD announces state update: player Oct 30 14:52:04 volumio volumio[879]: info: ControllerMpd::getState Oct 30 14:52:04 volumio volumio[879]: verbose: ControllerMpd::sendMpdCommand status Oct 30 14:52:04 volumio volumio[879]: info: Oct 30 14:52:04 volumio volumio[879]: ---------------------------- MPD announces state update: player Oct 30 14:52:04 volumio volumio[879]: info: ControllerMpd::getState Oct 30 14:52:04 volumio volumio[879]: verbose: ControllerMpd::sendMpdCommand status Oct 30 14:52:04 volumio volumio[879]: info: sendMpdCommand status took 2 milliseconds Oct 30 14:52:04 volumio volumio[879]: info: sendMpdCommand status took 1 milliseconds Oct 30 14:52:04 volumio volumio[879]: info: sendMpdCommand status took 1 milliseconds Oct 30 14:52:04 volumio volumio[879]: verbose: ControllerMpd::parseState Oct 30 14:52:04 volumio volumio[879]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 14:52:04 volumio volumio[879]: verbose: ControllerMpd::parseState Oct 30 14:52:04 volumio volumio[879]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 14:52:04 volumio volumio[879]: verbose: ControllerMpd::parseState Oct 30 14:52:04 volumio volumio[879]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 14:52:04 volumio volumio[879]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 30 14:52:04 volumio volumio[879]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 30 14:52:04 volumio volumio[879]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 30 14:52:04 volumio volumio[879]: verbose: ControllerMpd::parseTrackInfo Oct 30 14:52:04 volumio volumio[879]: verbose: ControllerMpd::parseTrackInfo Oct 30 14:52:04 volumio volumio[879]: verbose: ControllerMpd::parseTrackInfo Oct 30 14:52:04 volumio volumio[879]: info: ControllerMpd::pushState Oct 30 14:52:04 volumio volumio[879]: info: CoreCommandRouter::servicePushState Oct 30 14:52:04 volumio volumio[879]: info: CorePlayQueue::getTrack 0 Oct 30 14:52:04 volumio volumio[879]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":3569,"duration":183,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"765 Kbps","isStreaming":false,"title":"Rock or Bust","artist":"AC/DC","album":"Rock or Bust","uri":"http://192.168.1.150:32469/object/8ff7edaa42f773ab2b33/file.flac","trackType":"flac"} Oct 30 14:52:04 volumio volumio[879]: verbose: CURRENT POSITION 0 Oct 30 14:52:04 volumio volumio[879]: info: CoreStateMachine::syncState stateService pause Oct 30 14:52:04 volumio volumio[879]: info: CoreStateMachine::syncState currentStatus pause Oct 30 14:52:04 volumio volumio[879]: info: CoreStateMachine::pushState Oct 30 14:52:04 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 14:52:04 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Oct 30 14:52:04 volumio volumio[879]: info: CoreStateMachine::stPlaybackTimer Oct 30 14:52:04 volumio volumio[879]: info: ControllerMpd::pushState Oct 30 14:52:04 volumio volumio[879]: info: CoreCommandRouter::servicePushState Oct 30 14:52:04 volumio volumio[879]: info: CorePlayQueue::getTrack 0 Oct 30 14:52:04 volumio volumio[879]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":3569,"duration":183,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"765 Kbps","isStreaming":false,"title":"Rock or Bust","artist":"AC/DC","album":"Rock or Bust","uri":"http://192.168.1.150:32469/object/8ff7edaa42f773ab2b33/file.flac","trackType":"flac"} Oct 30 14:52:04 volumio volumio[879]: verbose: CURRENT POSITION 0 Oct 30 14:52:04 volumio volumio[879]: info: CoreStateMachine::syncState stateService pause Oct 30 14:52:04 volumio volumio[879]: info: CoreStateMachine::syncState currentStatus pause Oct 30 14:52:04 volumio volumio[879]: info: CoreStateMachine::pushState Oct 30 14:52:04 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Oct 30 14:52:04 volumio volumio[879]: info: CoreStateMachine::stPlaybackTimer Oct 30 14:52:04 volumio volumio[879]: info: ControllerMpd::pushState Oct 30 14:52:04 volumio volumio[879]: info: CoreCommandRouter::servicePushState Oct 30 14:52:04 volumio volumio[879]: info: CorePlayQueue::getTrack 0 Oct 30 14:52:04 volumio volumio[879]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":3569,"duration":183,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"765 Kbps","isStreaming":false,"title":"Rock or Bust","artist":"AC/DC","album":"Rock or Bust","uri":"http://192.168.1.150:32469/object/8ff7edaa42f773ab2b33/file.flac","trackType":"flac"} Oct 30 14:52:04 volumio volumio[879]: verbose: CURRENT POSITION 0 Oct 30 14:52:04 volumio volumio[879]: info: CoreStateMachine::syncState stateService pause Oct 30 14:52:04 volumio volumio[879]: info: CoreStateMachine::syncState currentStatus pause Oct 30 14:52:04 volumio volumio[879]: info: CoreStateMachine::pushState Oct 30 14:52:04 volumio volumio[879]: info: CoreCommandRouter::volumioPushState Oct 30 14:52:04 volumio volumio[879]: info: CoreStateMachine::stPlaybackTimer Oct 30 14:52:04 volumio volumio[879]: info: ------------------------------ 20ms Oct 30 14:52:04 volumio volumio[879]: info: ------------------------------ 18ms Oct 30 14:52:04 volumio volumio[879]: info: CoreCommandRouter::volumioGetQueue Oct 30 14:52:04 volumio volumio[879]: info: CoreStateMachine::getQueue Oct 30 14:52:04 volumio volumio[879]: info: CorePlayQueue::getQueue Oct 30 14:52:04 volumio volumio[879]: info: CoreCommandRouter::volumioGetQueue Oct 30 14:52:04 volumio volumio[879]: info: CoreStateMachine::getQueue Oct 30 14:52:04 volumio volumio[879]: info: CorePlayQueue::getQueue Oct 30 14:52:04 volumio volumio[879]: info: CoreCommandRouter::volumioGetQueue Oct 30 14:52:04 volumio volumio[879]: info: CoreStateMachine::getQueue Oct 30 14:52:04 volumio volumio[879]: info: CorePlayQueue::getQueue Oct 30 14:52:04 volumio volumio[879]: info: touch_display: Setting screensaver timeout to 120 seconds. Oct 30 14:52:25 volumio volumio[879]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 30 14:52:25 volumio volumio[879]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 30 14:52:25 volumio volumio[879]: TypeError: Cannot read property 'length' of undefined Oct 30 14:52:25 volumio volumio[879]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Oct 30 14:52:25 volumio volumio[879]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Oct 30 14:52:25 volumio volumio[879]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Oct 30 14:52:25 volumio volumio[879]: at Parser.emit (events.js:315:20) Oct 30 14:52:25 volumio volumio[879]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Oct 30 14:52:25 volumio volumio[879]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Oct 30 14:52:25 volumio volumio[879]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Oct 30 14:52:25 volumio volumio[879]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Oct 30 14:52:25 volumio volumio[879]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Oct 30 14:52:25 volumio volumio[879]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Oct 30 14:52:25 volumio volumio[879]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Oct 30 14:52:25 volumio volumio[879]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Oct 30 14:52:25 volumio volumio[879]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Oct 30 14:52:25 volumio volumio[879]: at IncomingMessage.emit (events.js:327:22) Oct 30 14:52:25 volumio volumio[879]: at endReadableNT (internal/streams/readable.js:1327:12) Oct 30 14:52:25 volumio volumio[879]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Oct 30 14:52:25 volumio volumio[879]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 30 14:52:26 volumio sudo[7903]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-30 14:51 Oct 30 14:52:26 volumio sudo[7903]: 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="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"