-- Logs begin at Thu 2019-02-14 17:11:59 +07, end at Mon 2024-10-21 17:32:40 +07. -- Oct 21 17:31:00 volumio-gow volumio[914]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 21 17:31:01 volumio-gow volumio[914]: info: Preload queue cleared Oct 21 17:31:13 volumio-gow volumio[914]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 21 17:31:13 volumio-gow volumio[914]: info: Preload queue cleared Oct 21 17:31:15 volumio-gow volumio[914]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 21 17:31:15 volumio-gow volumio[914]: info: Preload queue cleared Oct 21 17:31:16 volumio-gow volumio[914]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 21 17:31:17 volumio-gow volumio[914]: info: Preload queue cleared Oct 21 17:31:20 volumio-gow volumio[914]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 21 17:31:20 volumio-gow volumio[914]: info: Preload queue cleared Oct 21 17:31:23 volumio-gow volumio[914]: info: Preload queue cleared Oct 21 17:31:23 volumio-gow volumio[914]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 21 17:31:23 volumio-gow volumio[914]: info: CoreStateMachine::ClearQueue Oct 21 17:31:23 volumio-gow volumio[914]: info: CoreStateMachine::stop Oct 21 17:31:23 volumio-gow volumio[914]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 21 17:31:23 volumio-gow volumio[914]: info: CorePlayQueue::clearPlayQueue Oct 21 17:31:23 volumio-gow volumio[914]: info: CorePlayQueue::saveQueue Oct 21 17:31:23 volumio-gow volumio[914]: info: CoreCommandRouter::volumioPushQueue Oct 21 17:31:23 volumio-gow volumio[914]: info: CoreStateMachine::addQueueItems Oct 21 17:31:23 volumio-gow volumio[914]: info: CorePlayQueue::addQueueItems Oct 21 17:31:23 volumio-gow volumio[914]: info: Preload queue cleared Oct 21 17:31:23 volumio-gow volumio[914]: info: Adding Item to queue: upnp/folder/http://192.168.1.101:2869/upnphost/udhisapi.dll?control=uuid:f89874f8-451a-42f5-bf25-755fa0b0763a+urn:upnp-org:serviceId:ContentDirectory@2DB1BE7C Oct 21 17:31:23 volumio-gow volumio[914]: info: Exploding uri upnp/folder/http://192.168.1.101:2869/upnphost/udhisapi.dll?control=uuid:f89874f8-451a-42f5-bf25-755fa0b0763a+urn:upnp-org:serviceId:ContentDirectory@2DB1BE7C in service upnp_browser Oct 21 17:31:23 volumio-gow volumio[914]: info: CoreCommandRouter::volumioPushQueue Oct 21 17:31:23 volumio-gow volumio[914]: info: CorePlayQueue::saveQueue Oct 21 17:31:23 volumio-gow volumio[914]: info: CoreStateMachine::updateTrackBlock Oct 21 17:31:23 volumio-gow volumio[914]: info: CorePlayQueue::getTrackBlock Oct 21 17:31:23 volumio-gow volumio[914]: info: CoreCommandRouter::volumioPlay Oct 21 17:31:23 volumio-gow volumio[914]: info: CoreStateMachine::play index 0 Oct 21 17:31:23 volumio-gow volumio[914]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 21 17:31:23 volumio-gow volumio[914]: info: CoreStateMachine::stop Oct 21 17:31:23 volumio-gow volumio[914]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 21 17:31:23 volumio-gow volumio[914]: info: CoreStateMachine::play index undefined Oct 21 17:31:23 volumio-gow volumio[914]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 21 17:31:23 volumio-gow volumio[914]: info: CorePlayQueue::getTrack 0 Oct 21 17:31:23 volumio-gow volumio[914]: info: CoreStateMachine::startPlaybackTimer Oct 21 17:31:23 volumio-gow volumio[914]: info: CorePlayQueue::getTrack 0 Oct 21 17:31:23 volumio-gow volumio[914]: info: [1729506683428] ControllerUPNPBrowser::clearAddPlayTrack Oct 21 17:31:23 volumio-gow volumio[914]: verbose: ControllerMpd::sendMpdCommand stop Oct 21 17:31:23 volumio-gow volumio[914]: info: Oct 21 17:31:23 volumio-gow volumio[914]: ---------------------------- MPD announces state update: player Oct 21 17:31:23 volumio-gow volumio[914]: info: sendMpdCommand stop took 17 milliseconds Oct 21 17:31:23 volumio-gow volumio[914]: info: ControllerMpd::getState Oct 21 17:31:23 volumio-gow volumio[914]: verbose: ControllerMpd::sendMpdCommand status Oct 21 17:31:23 volumio-gow volumio[914]: verbose: ControllerMpd::sendMpdCommand clear Oct 21 17:31:23 volumio-gow volumio[914]: info: Oct 21 17:31:23 volumio-gow volumio[914]: ---------------------------- MPD announces system playlist update Oct 21 17:31:23 volumio-gow volumio[914]: info: Ignoring MPD Status Update Oct 21 17:31:23 volumio-gow volumio[914]: info: sendMpdCommand status took 3 milliseconds Oct 21 17:31:23 volumio-gow volumio[914]: info: sendMpdCommand clear took 3 milliseconds Oct 21 17:31:23 volumio-gow volumio[914]: verbose: ControllerMpd::parseState Oct 21 17:31:23 volumio-gow volumio[914]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 21 17:31:23 volumio-gow volumio[914]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.101:10243/WMPNSSv4/669507152/0_ezAwMDU2ODlGLTZBRDEtNDU3QS1CMTM2LUY4M0Y5QTU5MEZFN30uMC4yREIxQkU3Qw.mp3" Oct 21 17:31:23 volumio-gow volumio[914]: info: Oct 21 17:31:23 volumio-gow volumio[914]: ---------------------------- MPD announces state update: player Oct 21 17:31:23 volumio-gow volumio[914]: info: ControllerMpd::getState Oct 21 17:31:23 volumio-gow volumio[914]: verbose: ControllerMpd::sendMpdCommand status Oct 21 17:31:23 volumio-gow volumio[914]: info: Oct 21 17:31:23 volumio-gow volumio[914]: ---------------------------- MPD announces state update: player Oct 21 17:31:23 volumio-gow volumio[914]: info: ControllerMpd::getState Oct 21 17:31:23 volumio-gow volumio[914]: verbose: ControllerMpd::sendMpdCommand status Oct 21 17:31:23 volumio-gow volumio[914]: error: updateQueue error: null Oct 21 17:31:23 volumio-gow volumio[914]: info: ------------------------------ 10ms Oct 21 17:31:23 volumio-gow volumio[914]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 21 17:31:23 volumio-gow volumio[914]: verbose: ControllerMpd::parseTrackInfo Oct 21 17:31:23 volumio-gow volumio[914]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 21 17:31:23 volumio-gow volumio[914]: info: ------------------------------ 16ms Oct 21 17:31:23 volumio-gow volumio[914]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.101:10243/WMPNSSv4/669507152/0_ezAwMDU2ODlGLTZBRDEtNDU3QS1CMTM2LUY4M0Y5QTU5MEZFN30uMC4yREIxQkU3Qw.mp3" Oct 21 17:31:23 volumio-gow volumio[914]: info: Oct 21 17:31:23 volumio-gow volumio[914]: ---------------------------- MPD announces system playlist update Oct 21 17:31:23 volumio-gow volumio[914]: info: Ignoring MPD Status Update Oct 21 17:31:23 volumio-gow volumio[914]: info: sendMpdCommand status took 11 milliseconds Oct 21 17:31:23 volumio-gow volumio[914]: info: sendMpdCommand status took 10 milliseconds Oct 21 17:31:23 volumio-gow volumio[914]: info: sendMpdCommand add "http://192.168.1.101:10243/WMPNSSv4/669507152/0_ezAwMDU2ODlGLTZBRDEtNDU3QS1CMTM2LUY4M0Y5QTU5MEZFN30uMC4yREIxQkU3Qw.mp3" took 4 milliseconds Oct 21 17:31:23 volumio-gow volumio[914]: verbose: ControllerMpd::parseState Oct 21 17:31:23 volumio-gow volumio[914]: verbose: ControllerMpd::parseState Oct 21 17:31:23 volumio-gow volumio[914]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 21 17:31:23 volumio-gow volumio[914]: verbose: ControllerMpd::sendMpdCommand play Oct 21 17:31:23 volumio-gow volumio[914]: info: ControllerMpd::pushState Oct 21 17:31:23 volumio-gow volumio[914]: info: CoreCommandRouter::servicePushState Oct 21 17:31:23 volumio-gow volumio[914]: info: CoreStateMachine::pushState Oct 21 17:31:23 volumio-gow volumio[914]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 21 17:31:23 volumio-gow volumio[914]: info: CoreCommandRouter::volumioPushState Oct 21 17:31:23 volumio-gow volumio[914]: info: CorePlayQueue::getTrack 0 Oct 21 17:31:23 volumio-gow volumio[914]: 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} Oct 21 17:31:23 volumio-gow volumio[914]: verbose: CURRENT POSITION 0 Oct 21 17:31:23 volumio-gow volumio[914]: info: CoreStateMachine::syncState stateService stop Oct 21 17:31:23 volumio-gow volumio[914]: info: CoreStateMachine::syncState currentStatus stop Oct 21 17:31:23 volumio-gow volumio[914]: info: CoreStateMachine::pushState Oct 21 17:31:23 volumio-gow volumio[914]: info: CoreCommandRouter::volumioPushState Oct 21 17:31:23 volumio-gow volumio[914]: info: No code Oct 21 17:31:23 volumio-gow volumio[914]: info: CoreStateMachine::pushState Oct 21 17:31:23 volumio-gow volumio[914]: info: CoreCommandRouter::volumioPushState Oct 21 17:31:23 volumio-gow volumio[914]: info: ControllerMpd::pushState Oct 21 17:31:23 volumio-gow volumio[914]: info: CoreCommandRouter::servicePushState Oct 21 17:31:23 volumio-gow volumio[914]: info: CoreStateMachine::pushState Oct 21 17:31:23 volumio-gow volumio[914]: info: CoreCommandRouter::volumioPushState Oct 21 17:31:23 volumio-gow volumio[914]: info: CorePlayQueue::getTrack 0 Oct 21 17:31:23 volumio-gow volumio[914]: 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} Oct 21 17:31:23 volumio-gow volumio[914]: verbose: CURRENT POSITION 0 Oct 21 17:31:23 volumio-gow volumio[914]: info: CoreStateMachine::syncState stateService stop Oct 21 17:31:23 volumio-gow volumio[914]: info: CoreStateMachine::syncState currentStatus stop Oct 21 17:31:23 volumio-gow volumio[914]: info: CoreStateMachine::pushState Oct 21 17:31:23 volumio-gow volumio[914]: info: CoreCommandRouter::volumioPushState Oct 21 17:31:23 volumio-gow volumio[914]: info: No code Oct 21 17:31:23 volumio-gow volumio[914]: info: CoreStateMachine::pushState Oct 21 17:31:23 volumio-gow volumio[914]: info: CoreCommandRouter::volumioPushState Oct 21 17:31:23 volumio-gow volumio[914]: info: ------------------------------ 128ms Oct 21 17:31:23 volumio-gow volumio[914]: info: ------------------------------ 128ms Oct 21 17:31:23 volumio-gow volumio[914]: info: Oct 21 17:31:23 volumio-gow volumio[914]: ---------------------------- MPD announces system playlist update Oct 21 17:31:23 volumio-gow volumio[914]: info: Ignoring MPD Status Update Oct 21 17:31:23 volumio-gow volumio[914]: info: Oct 21 17:31:23 volumio-gow volumio[914]: ---------------------------- MPD announces system playlist update Oct 21 17:31:23 volumio-gow volumio[914]: info: Ignoring MPD Status Update Oct 21 17:31:23 volumio-gow volumio[914]: info: ------------------------------ 132ms Oct 21 17:31:23 volumio-gow volumio[914]: info: sendMpdCommand play took 129 milliseconds Oct 21 17:31:23 volumio-gow volumio[914]: info: ------------------------------ 10ms Oct 21 17:31:23 volumio-gow volumio[914]: info: ------------------------------ 9ms Oct 21 17:31:23 volumio-gow volumio[914]: info: Oct 21 17:31:23 volumio-gow volumio[914]: ---------------------------- MPD announces system playlist update Oct 21 17:31:23 volumio-gow volumio[914]: info: Ignoring MPD Status Update Oct 21 17:31:23 volumio-gow volumio[914]: info: Oct 21 17:31:23 volumio-gow volumio[914]: ---------------------------- MPD announces system playlist update Oct 21 17:31:23 volumio-gow volumio[914]: info: Ignoring MPD Status Update Oct 21 17:31:23 volumio-gow volumio[914]: info: ------------------------------ 36ms Oct 21 17:31:23 volumio-gow volumio[914]: info: ------------------------------ 35ms Oct 21 17:31:23 volumio-gow volumio[914]: info: Oct 21 17:31:23 volumio-gow volumio[914]: ---------------------------- MPD announces state update: player Oct 21 17:31:23 volumio-gow volumio[914]: info: ControllerMpd::getState Oct 21 17:31:23 volumio-gow volumio[914]: verbose: ControllerMpd::sendMpdCommand status Oct 21 17:31:23 volumio-gow volumio[914]: info: Oct 21 17:31:23 volumio-gow volumio[914]: ---------------------------- MPD announces state update: player Oct 21 17:31:23 volumio-gow volumio[914]: info: ControllerMpd::getState Oct 21 17:31:23 volumio-gow volumio[914]: verbose: ControllerMpd::sendMpdCommand status Oct 21 17:31:23 volumio-gow volumio[914]: info: Oct 21 17:31:23 volumio-gow volumio[914]: ---------------------------- MPD announces state update: player Oct 21 17:31:23 volumio-gow volumio[914]: info: ControllerMpd::getState Oct 21 17:31:23 volumio-gow volumio[914]: verbose: ControllerMpd::sendMpdCommand status Oct 21 17:31:23 volumio-gow volumio[914]: info: Oct 21 17:31:23 volumio-gow volumio[914]: ---------------------------- MPD announces state update: player Oct 21 17:31:23 volumio-gow volumio[914]: info: ControllerMpd::getState Oct 21 17:31:23 volumio-gow volumio[914]: verbose: ControllerMpd::sendMpdCommand status Oct 21 17:31:23 volumio-gow volumio[914]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 21 17:31:23 volumio-gow volumio[914]: info: sendMpdCommand status took 21 milliseconds Oct 21 17:31:23 volumio-gow volumio[914]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 21 17:31:23 volumio-gow volumio[914]: info: sendMpdCommand status took 22 milliseconds Oct 21 17:31:23 volumio-gow volumio[914]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 21 17:31:23 volumio-gow volumio[914]: info: sendMpdCommand status took 22 milliseconds Oct 21 17:31:23 volumio-gow volumio[914]: verbose: ControllerMpd::parseState Oct 21 17:31:23 volumio-gow volumio[914]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 21 17:31:23 volumio-gow volumio[914]: verbose: ControllerMpd::parseState Oct 21 17:31:23 volumio-gow volumio[914]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 21 17:31:23 volumio-gow volumio[914]: verbose: ControllerMpd::parseState Oct 21 17:31:23 volumio-gow volumio[914]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 21 17:31:23 volumio-gow volumio[914]: info: Oct 21 17:31:23 volumio-gow volumio[914]: ---------------------------- MPD announces state update: player Oct 21 17:31:23 volumio-gow volumio[914]: info: ControllerMpd::getState Oct 21 17:31:23 volumio-gow volumio[914]: verbose: ControllerMpd::sendMpdCommand status Oct 21 17:31:23 volumio-gow volumio[914]: info: Oct 21 17:31:23 volumio-gow volumio[914]: ---------------------------- MPD announces state update: player Oct 21 17:31:23 volumio-gow volumio[914]: info: ControllerMpd::getState Oct 21 17:31:23 volumio-gow volumio[914]: verbose: ControllerMpd::sendMpdCommand status Oct 21 17:31:23 volumio-gow volumio[914]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 21 17:31:23 volumio-gow volumio[914]: info: sendMpdCommand status took 87 milliseconds Oct 21 17:31:23 volumio-gow volumio[914]: info: sendMpdCommand clearerror took 75 milliseconds Oct 21 17:31:23 volumio-gow volumio[914]: info: sendMpdCommand clearerror took 73 milliseconds Oct 21 17:31:23 volumio-gow volumio[914]: info: sendMpdCommand clearerror took 71 milliseconds Oct 21 17:31:23 volumio-gow volumio[914]: info: sendMpdCommand playlistinfo took 44 milliseconds Oct 21 17:31:23 volumio-gow volumio[914]: info: sendMpdCommand playlistinfo took 43 milliseconds Oct 21 17:31:23 volumio-gow volumio[914]: info: sendMpdCommand playlistinfo took 42 milliseconds Oct 21 17:31:23 volumio-gow volumio[914]: info: sendMpdCommand status took 39 milliseconds Oct 21 17:31:23 volumio-gow volumio[914]: info: sendMpdCommand status took 38 milliseconds Oct 21 17:31:23 volumio-gow volumio[914]: verbose: ControllerMpd::parseState Oct 21 17:31:23 volumio-gow volumio[914]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 21 17:31:23 volumio-gow volumio[914]: verbose: ControllerMpd::parseTrackInfo Oct 21 17:31:23 volumio-gow volumio[914]: verbose: ControllerMpd::parseTrackInfo Oct 21 17:31:23 volumio-gow volumio[914]: verbose: ControllerMpd::parseTrackInfo Oct 21 17:31:23 volumio-gow volumio[914]: verbose: ControllerMpd::parseState Oct 21 17:31:23 volumio-gow volumio[914]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 21 17:31:23 volumio-gow volumio[914]: verbose: ControllerMpd::parseState Oct 21 17:31:23 volumio-gow volumio[914]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 21 17:31:23 volumio-gow volumio[914]: info: ControllerMpd::pushState Oct 21 17:31:23 volumio-gow volumio[914]: info: CoreCommandRouter::servicePushState Oct 21 17:31:23 volumio-gow volumio[914]: info: CorePlayQueue::getTrack 0 Oct 21 17:31:23 volumio-gow volumio[914]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":192,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_ezAwMDU2ODlGLTZBRDEtNDU3QS1CMTM2LUY4M0Y5QTU5MEZFN30uMC4yREIxQkU3Qw.mp3","artist":null,"album":null,"uri":"http://192.168.1.101:10243/WMPNSSv4/669507152/0_ezAwMDU2ODlGLTZBRDEtNDU3QS1CMTM2LUY4M0Y5QTU5MEZFN30uMC4yREIxQkU3Qw.mp3","trackType":"mp3"} Oct 21 17:31:23 volumio-gow volumio[914]: verbose: CURRENT POSITION 0 Oct 21 17:31:23 volumio-gow volumio[914]: info: CoreStateMachine::syncState stateService pause Oct 21 17:31:23 volumio-gow volumio[914]: info: CoreStateMachine::syncState currentStatus stop Oct 21 17:31:23 volumio-gow volumio[914]: info: CoreStateMachine::pushState Oct 21 17:31:23 volumio-gow volumio[914]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 21 17:31:23 volumio-gow volumio[914]: info: CoreCommandRouter::volumioPushState Oct 21 17:31:23 volumio-gow volumio[914]: info: ControllerMpd::pushState Oct 21 17:31:23 volumio-gow volumio[914]: info: CoreCommandRouter::servicePushState Oct 21 17:31:23 volumio-gow volumio[914]: info: CorePlayQueue::getTrack 0 Oct 21 17:31:23 volumio-gow volumio[914]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":192,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_ezAwMDU2ODlGLTZBRDEtNDU3QS1CMTM2LUY4M0Y5QTU5MEZFN30uMC4yREIxQkU3Qw.mp3","artist":null,"album":null,"uri":"http://192.168.1.101:10243/WMPNSSv4/669507152/0_ezAwMDU2ODlGLTZBRDEtNDU3QS1CMTM2LUY4M0Y5QTU5MEZFN30uMC4yREIxQkU3Qw.mp3","trackType":"mp3"} Oct 21 17:31:23 volumio-gow volumio[914]: verbose: CURRENT POSITION 0 Oct 21 17:31:23 volumio-gow volumio[914]: info: CoreStateMachine::syncState stateService pause Oct 21 17:31:23 volumio-gow volumio[914]: info: CoreStateMachine::syncState currentStatus stop Oct 21 17:31:23 volumio-gow volumio[914]: info: CoreStateMachine::pushState Oct 21 17:31:23 volumio-gow volumio[914]: info: CoreCommandRouter::volumioPushState Oct 21 17:31:23 volumio-gow volumio[914]: info: ControllerMpd::pushState Oct 21 17:31:23 volumio-gow volumio[914]: info: CoreCommandRouter::servicePushState Oct 21 17:31:23 volumio-gow volumio[914]: info: CorePlayQueue::getTrack 0 Oct 21 17:31:23 volumio-gow volumio[914]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":192,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_ezAwMDU2ODlGLTZBRDEtNDU3QS1CMTM2LUY4M0Y5QTU5MEZFN30uMC4yREIxQkU3Qw.mp3","artist":null,"album":null,"uri":"http://192.168.1.101:10243/WMPNSSv4/669507152/0_ezAwMDU2ODlGLTZBRDEtNDU3QS1CMTM2LUY4M0Y5QTU5MEZFN30uMC4yREIxQkU3Qw.mp3","trackType":"mp3"} Oct 21 17:31:23 volumio-gow volumio[914]: verbose: CURRENT POSITION 0 Oct 21 17:31:23 volumio-gow volumio[914]: info: CoreStateMachine::syncState stateService pause Oct 21 17:31:23 volumio-gow volumio[914]: info: CoreStateMachine::syncState currentStatus stop Oct 21 17:31:23 volumio-gow volumio[914]: info: CoreStateMachine::pushState Oct 21 17:31:23 volumio-gow volumio[914]: info: CoreCommandRouter::volumioPushState Oct 21 17:31:24 volumio-gow volumio[914]: info: ------------------------------ 216ms Oct 21 17:31:24 volumio-gow volumio[914]: info: ------------------------------ 215ms Oct 21 17:31:24 volumio-gow volumio[914]: info: ------------------------------ 214ms Oct 21 17:31:24 volumio-gow volumio[914]: info: sendMpdCommand clearerror took 149 milliseconds Oct 21 17:31:24 volumio-gow volumio[914]: info: sendMpdCommand playlistinfo took 134 milliseconds Oct 21 17:31:24 volumio-gow volumio[914]: info: sendMpdCommand playlistinfo took 128 milliseconds Oct 21 17:31:24 volumio-gow volumio[914]: info: sendMpdCommand playlistinfo took 126 milliseconds Oct 21 17:31:24 volumio-gow volumio[914]: verbose: ControllerMpd::parseTrackInfo Oct 21 17:31:24 volumio-gow volumio[914]: verbose: ControllerMpd::parseTrackInfo Oct 21 17:31:24 volumio-gow volumio[914]: verbose: ControllerMpd::parseTrackInfo Oct 21 17:31:24 volumio-gow volumio[914]: info: ControllerMpd::pushState Oct 21 17:31:24 volumio-gow volumio[914]: info: CoreCommandRouter::servicePushState Oct 21 17:31:24 volumio-gow volumio[914]: info: CorePlayQueue::getTrack 0 Oct 21 17:31:24 volumio-gow volumio[914]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":192,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_ezAwMDU2ODlGLTZBRDEtNDU3QS1CMTM2LUY4M0Y5QTU5MEZFN30uMC4yREIxQkU3Qw.mp3","artist":null,"album":null,"uri":"http://192.168.1.101:10243/WMPNSSv4/669507152/0_ezAwMDU2ODlGLTZBRDEtNDU3QS1CMTM2LUY4M0Y5QTU5MEZFN30uMC4yREIxQkU3Qw.mp3","trackType":"mp3"} Oct 21 17:31:24 volumio-gow volumio[914]: verbose: CURRENT POSITION 0 Oct 21 17:31:24 volumio-gow volumio[914]: info: CoreStateMachine::syncState stateService pause Oct 21 17:31:24 volumio-gow volumio[914]: info: CoreStateMachine::syncState currentStatus stop Oct 21 17:31:24 volumio-gow volumio[914]: info: CoreStateMachine::pushState Oct 21 17:31:24 volumio-gow volumio[914]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 21 17:31:24 volumio-gow volumio[914]: info: CoreCommandRouter::volumioPushState Oct 21 17:31:24 volumio-gow volumio[914]: info: ControllerMpd::pushState Oct 21 17:31:24 volumio-gow volumio[914]: info: CoreCommandRouter::servicePushState Oct 21 17:31:24 volumio-gow volumio[914]: info: CorePlayQueue::getTrack 0 Oct 21 17:31:24 volumio-gow volumio[914]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":192,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_ezAwMDU2ODlGLTZBRDEtNDU3QS1CMTM2LUY4M0Y5QTU5MEZFN30uMC4yREIxQkU3Qw.mp3","artist":null,"album":null,"uri":"http://192.168.1.101:10243/WMPNSSv4/669507152/0_ezAwMDU2ODlGLTZBRDEtNDU3QS1CMTM2LUY4M0Y5QTU5MEZFN30uMC4yREIxQkU3Qw.mp3","trackType":"mp3"} Oct 21 17:31:24 volumio-gow volumio[914]: verbose: CURRENT POSITION 0 Oct 21 17:31:24 volumio-gow volumio[914]: info: CoreStateMachine::syncState stateService pause Oct 21 17:31:24 volumio-gow volumio[914]: info: CoreStateMachine::syncState currentStatus stop Oct 21 17:31:24 volumio-gow volumio[914]: info: CoreStateMachine::pushState Oct 21 17:31:24 volumio-gow volumio[914]: info: CoreCommandRouter::volumioPushState Oct 21 17:31:24 volumio-gow volumio[914]: info: ControllerMpd::pushState Oct 21 17:31:24 volumio-gow volumio[914]: info: CoreCommandRouter::servicePushState Oct 21 17:31:24 volumio-gow volumio[914]: info: CorePlayQueue::getTrack 0 Oct 21 17:31:24 volumio-gow volumio[914]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":192,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_ezAwMDU2ODlGLTZBRDEtNDU3QS1CMTM2LUY4M0Y5QTU5MEZFN30uMC4yREIxQkU3Qw.mp3","artist":null,"album":null,"uri":"http://192.168.1.101:10243/WMPNSSv4/669507152/0_ezAwMDU2ODlGLTZBRDEtNDU3QS1CMTM2LUY4M0Y5QTU5MEZFN30uMC4yREIxQkU3Qw.mp3","trackType":"mp3"} Oct 21 17:31:24 volumio-gow volumio[914]: verbose: CURRENT POSITION 0 Oct 21 17:31:24 volumio-gow volumio[914]: info: CoreStateMachine::syncState stateService pause Oct 21 17:31:24 volumio-gow volumio[914]: info: CoreStateMachine::syncState currentStatus stop Oct 21 17:31:24 volumio-gow volumio[914]: info: CoreStateMachine::pushState Oct 21 17:31:24 volumio-gow volumio[914]: info: CoreCommandRouter::volumioPushState Oct 21 17:31:24 volumio-gow volumio[914]: info: ------------------------------ 333ms Oct 21 17:31:24 volumio-gow volumio[914]: info: ------------------------------ 279ms Oct 21 17:31:24 volumio-gow volumio[914]: info: ------------------------------ 277ms Oct 21 17:31:31 volumio-gow volumio[914]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 21 17:31:31 volumio-gow volumio[914]: info: Preload queue cleared Oct 21 17:31:38 volumio-gow volumio[914]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 21 17:31:39 volumio-gow volumio[914]: info: Preload queue cleared Oct 21 17:31:39 volumio-gow volumio[914]: info: Preloading song: upnp/http://192.168.1.101:2869/upnphost/udhisapi.dll?control=uuid:f89874f8-451a-42f5-bf25-755fa0b0763a+urn:upnp-org:serviceId:ContentDirectory@{0005689F-6AD1-457A-B136-F83F9A590FE7}.0.102 Oct 21 17:31:39 volumio-gow volumio[914]: info: Preloading song: upnp/http://192.168.1.101:2869/upnphost/udhisapi.dll?control=uuid:f89874f8-451a-42f5-bf25-755fa0b0763a+urn:upnp-org:serviceId:ContentDirectory@{BDC25B81-5208-4842-AB7C-AFC13750DB3D}.0.102 Oct 21 17:31:39 volumio-gow volumio[914]: info: Preloading song: upnp/http://192.168.1.101:2869/upnphost/udhisapi.dll?control=uuid:f89874f8-451a-42f5-bf25-755fa0b0763a+urn:upnp-org:serviceId:ContentDirectory@{484FE4A2-FBCE-441A-AE33-6110D3A223AD}.0.102 Oct 21 17:31:39 volumio-gow volumio[914]: info: Preloading song: upnp/http://192.168.1.101:2869/upnphost/udhisapi.dll?control=uuid:f89874f8-451a-42f5-bf25-755fa0b0763a+urn:upnp-org:serviceId:ContentDirectory@{F40DA601-6FA9-45C8-94B0-69B0BD1F75E3}.0.102 Oct 21 17:31:39 volumio-gow volumio[914]: info: Preloading song: upnp/http://192.168.1.101:2869/upnphost/udhisapi.dll?control=uuid:f89874f8-451a-42f5-bf25-755fa0b0763a+urn:upnp-org:serviceId:ContentDirectory@{C0E60716-5930-4507-944A-B5AFF7B0DE8B}.0.102 Oct 21 17:31:39 volumio-gow volumio[914]: info: Preloading song: upnp/http://192.168.1.101:2869/upnphost/udhisapi.dll?control=uuid:f89874f8-451a-42f5-bf25-755fa0b0763a+urn:upnp-org:serviceId:ContentDirectory@{F1C559E2-F935-4C1D-AD0C-19C285887D0D}.0.102 Oct 21 17:31:39 volumio-gow volumio[914]: info: Preloading song: upnp/http://192.168.1.101:2869/upnphost/udhisapi.dll?control=uuid:f89874f8-451a-42f5-bf25-755fa0b0763a+urn:upnp-org:serviceId:ContentDirectory@{E406C62F-961A-4213-8878-A30B8003A77F}.0.102 Oct 21 17:31:39 volumio-gow volumio[914]: info: Preloading song: upnp/http://192.168.1.101:2869/upnphost/udhisapi.dll?control=uuid:f89874f8-451a-42f5-bf25-755fa0b0763a+urn:upnp-org:serviceId:ContentDirectory@{F8C0148A-9521-4AFE-B282-8E32B21E5202}.0.102 Oct 21 17:31:39 volumio-gow volumio[914]: info: Preloading song: upnp/http://192.168.1.101:2869/upnphost/udhisapi.dll?control=uuid:f89874f8-451a-42f5-bf25-755fa0b0763a+urn:upnp-org:serviceId:ContentDirectory@{91921D6F-D50A-474D-9D1C-6E862BB441B3}.0.102 Oct 21 17:31:39 volumio-gow volumio[914]: info: Preloading song: upnp/http://192.168.1.101:2869/upnphost/udhisapi.dll?control=uuid:f89874f8-451a-42f5-bf25-755fa0b0763a+urn:upnp-org:serviceId:ContentDirectory@{00E92314-0674-48D9-8C8A-685BBCCB68F7}.0.102 Oct 21 17:31:39 volumio-gow volumio[914]: info: Preloading song: upnp/http://192.168.1.101:2869/upnphost/udhisapi.dll?control=uuid:f89874f8-451a-42f5-bf25-755fa0b0763a+urn:upnp-org:serviceId:ContentDirectory@{CF9FB766-3F6D-4A2A-BB9B-E8E5F1910F9C}.0.102 Oct 21 17:31:39 volumio-gow volumio[914]: info: Preloading song: upnp/http://192.168.1.101:2869/upnphost/udhisapi.dll?control=uuid:f89874f8-451a-42f5-bf25-755fa0b0763a+urn:upnp-org:serviceId:ContentDirectory@{31473D2B-834F-4D7A-9128-CD7797DCC540}.0.102 Oct 21 17:31:39 volumio-gow volumio[914]: info: Preloading song: upnp/http://192.168.1.101:2869/upnphost/udhisapi.dll?control=uuid:f89874f8-451a-42f5-bf25-755fa0b0763a+urn:upnp-org:serviceId:ContentDirectory@{DE125999-2D08-47A6-BEA1-0EE7C71C4273}.0.102 Oct 21 17:31:39 volumio-gow volumio[914]: info: Preloading song: upnp/http://192.168.1.101:2869/upnphost/udhisapi.dll?control=uuid:f89874f8-451a-42f5-bf25-755fa0b0763a+urn:upnp-org:serviceId:ContentDirectory@{F564D3FA-6476-4013-A6D7-A6284659113C}.0.102 Oct 21 17:31:39 volumio-gow volumio[914]: info: Preloading song: upnp/http://192.168.1.101:2869/upnphost/udhisapi.dll?control=uuid:f89874f8-451a-42f5-bf25-755fa0b0763a+urn:upnp-org:serviceId:ContentDirectory@{6596312C-59E5-456E-944E-E4D41DF924E6}.0.102 Oct 21 17:31:39 volumio-gow volumio[914]: info: Preloading song: upnp/http://192.168.1.101:2869/upnphost/udhisapi.dll?control=uuid:f89874f8-451a-42f5-bf25-755fa0b0763a+urn:upnp-org:serviceId:ContentDirectory@{F250291F-90F6-4B37-9CDB-6C923CCC10C3}.0.102 Oct 21 17:31:39 volumio-gow volumio[914]: info: Preloading song: upnp/http://192.168.1.101:2869/upnphost/udhisapi.dll?control=uuid:f89874f8-451a-42f5-bf25-755fa0b0763a+urn:upnp-org:serviceId:ContentDirectory@{53FC4F3D-CAB3-46A7-81B0-896D299F28D5}.0.102 Oct 21 17:31:39 volumio-gow volumio[914]: info: Preloading song: upnp/http://192.168.1.101:2869/upnphost/udhisapi.dll?control=uuid:f89874f8-451a-42f5-bf25-755fa0b0763a+urn:upnp-org:serviceId:ContentDirectory@{FAE260EA-8801-4373-88C4-79F4C5A822EC}.0.102 Oct 21 17:31:39 volumio-gow volumio[914]: info: Preloading song: upnp/http://192.168.1.101:2869/upnphost/udhisapi.dll?control=uuid:f89874f8-451a-42f5-bf25-755fa0b0763a+urn:upnp-org:serviceId:ContentDirectory@{03403C4F-2ADF-4D10-983B-129618DD43D2}.0.102 Oct 21 17:31:39 volumio-gow volumio[914]: info: Preloading song: upnp/http://192.168.1.101:2869/upnphost/udhisapi.dll?control=uuid:f89874f8-451a-42f5-bf25-755fa0b0763a+urn:upnp-org:serviceId:ContentDirectory@{6BB4056B-1F36-41FF-B977-BF281672ECFB}.0.102 Oct 21 17:31:39 volumio-gow volumio[914]: info: Preloading song: upnp/http://192.168.1.101:2869/upnphost/udhisapi.dll?control=uuid:f89874f8-451a-42f5-bf25-755fa0b0763a+urn:upnp-org:serviceId:ContentDirectory@{83AA2D4D-6DD1-4C38-A0EB-641EB5488E93}.0.102 Oct 21 17:31:39 volumio-gow volumio[914]: info: Preloading song: upnp/http://192.168.1.101:2869/upnphost/udhisapi.dll?control=uuid:f89874f8-451a-42f5-bf25-755fa0b0763a+urn:upnp-org:serviceId:ContentDirectory@{59CFCBE9-07F9-4E60-9C53-685C4E40E229}.0.102 Oct 21 17:31:39 volumio-gow volumio[914]: info: Preloading song: upnp/http://192.168.1.101:2869/upnphost/udhisapi.dll?control=uuid:f89874f8-451a-42f5-bf25-755fa0b0763a+urn:upnp-org:serviceId:ContentDirectory@{676893A4-9E51-4FB6-BA70-6BBC96FBDF97}.0.102 Oct 21 17:31:39 volumio-gow volumio[914]: info: Preloading song: upnp/http://192.168.1.101:2869/upnphost/udhisapi.dll?control=uuid:f89874f8-451a-42f5-bf25-755fa0b0763a+urn:upnp-org:serviceId:ContentDirectory@{8DCFAD99-2112-4A5D-9BCA-25BB626A1FED}.0.102 Oct 21 17:31:39 volumio-gow volumio[914]: info: Exploding uri upnp/http://192.168.1.101:2869/upnphost/udhisapi.dll?control=uuid:f89874f8-451a-42f5-bf25-755fa0b0763a+urn:upnp-org:serviceId:ContentDirectory@{0005689F-6AD1-457A-B136-F83F9A590FE7}.0.102 in service upnp_browser Oct 21 17:31:39 volumio-gow volumio[914]: info: No valid Plugin REST Endpoint Oct 21 17:31:39 volumio-gow volumio[914]: info: Exploding uri upnp/http://192.168.1.101:2869/upnphost/udhisapi.dll?control=uuid:f89874f8-451a-42f5-bf25-755fa0b0763a+urn:upnp-org:serviceId:ContentDirectory@{BDC25B81-5208-4842-AB7C-AFC13750DB3D}.0.102 in service upnp_browser Oct 21 17:31:39 volumio-gow volumio[914]: info: Exploding uri upnp/http://192.168.1.101:2869/upnphost/udhisapi.dll?control=uuid:f89874f8-451a-42f5-bf25-755fa0b0763a+urn:upnp-org:serviceId:ContentDirectory@{484FE4A2-FBCE-441A-AE33-6110D3A223AD}.0.102 in service upnp_browser Oct 21 17:31:39 volumio-gow volumio[914]: info: Exploding uri upnp/http://192.168.1.101:2869/upnphost/udhisapi.dll?control=uuid:f89874f8-451a-42f5-bf25-755fa0b0763a+urn:upnp-org:serviceId:ContentDirectory@{F40DA601-6FA9-45C8-94B0-69B0BD1F75E3}.0.102 in service upnp_browser Oct 21 17:31:39 volumio-gow volumio[914]: info: Exploding uri upnp/http://192.168.1.101:2869/upnphost/udhisapi.dll?control=uuid:f89874f8-451a-42f5-bf25-755fa0b0763a+urn:upnp-org:serviceId:ContentDirectory@{C0E60716-5930-4507-944A-B5AFF7B0DE8B}.0.102 in service upnp_browser Oct 21 17:31:39 volumio-gow volumio[914]: info: Exploding uri upnp/http://192.168.1.101:2869/upnphost/udhisapi.dll?control=uuid:f89874f8-451a-42f5-bf25-755fa0b0763a+urn:upnp-org:serviceId:ContentDirectory@{F1C559E2-F935-4C1D-AD0C-19C285887D0D}.0.102 in service upnp_browser Oct 21 17:31:39 volumio-gow volumio[914]: info: Exploding uri upnp/http://192.168.1.101:2869/upnphost/udhisapi.dll?control=uuid:f89874f8-451a-42f5-bf25-755fa0b0763a+urn:upnp-org:serviceId:ContentDirectory@{E406C62F-961A-4213-8878-A30B8003A77F}.0.102 in service upnp_browser Oct 21 17:31:39 volumio-gow volumio[914]: info: Exploding uri upnp/http://192.168.1.101:2869/upnphost/udhisapi.dll?control=uuid:f89874f8-451a-42f5-bf25-755fa0b0763a+urn:upnp-org:serviceId:ContentDirectory@{F8C0148A-9521-4AFE-B282-8E32B21E5202}.0.102 in service upnp_browser Oct 21 17:31:39 volumio-gow volumio[914]: info: Exploding uri upnp/http://192.168.1.101:2869/upnphost/udhisapi.dll?control=uuid:f89874f8-451a-42f5-bf25-755fa0b0763a+urn:upnp-org:serviceId:ContentDirectory@{91921D6F-D50A-474D-9D1C-6E862BB441B3}.0.102 in service upnp_browser Oct 21 17:31:39 volumio-gow volumio[914]: info: Exploding uri upnp/http://192.168.1.101:2869/upnphost/udhisapi.dll?control=uuid:f89874f8-451a-42f5-bf25-755fa0b0763a+urn:upnp-org:serviceId:ContentDirectory@{00E92314-0674-48D9-8C8A-685BBCCB68F7}.0.102 in service upnp_browser Oct 21 17:31:39 volumio-gow volumio[914]: info: Exploding uri upnp/http://192.168.1.101:2869/upnphost/udhisapi.dll?control=uuid:f89874f8-451a-42f5-bf25-755fa0b0763a+urn:upnp-org:serviceId:ContentDirectory@{CF9FB766-3F6D-4A2A-BB9B-E8E5F1910F9C}.0.102 in service upnp_browser Oct 21 17:31:39 volumio-gow volumio[914]: info: Exploding uri upnp/http://192.168.1.101:2869/upnphost/udhisapi.dll?control=uuid:f89874f8-451a-42f5-bf25-755fa0b0763a+urn:upnp-org:serviceId:ContentDirectory@{31473D2B-834F-4D7A-9128-CD7797DCC540}.0.102 in service upnp_browser Oct 21 17:31:39 volumio-gow volumio[914]: info: Exploding uri upnp/http://192.168.1.101:2869/upnphost/udhisapi.dll?control=uuid:f89874f8-451a-42f5-bf25-755fa0b0763a+urn:upnp-org:serviceId:ContentDirectory@{DE125999-2D08-47A6-BEA1-0EE7C71C4273}.0.102 in service upnp_browser Oct 21 17:31:39 volumio-gow volumio[914]: info: Exploding uri upnp/http://192.168.1.101:2869/upnphost/udhisapi.dll?control=uuid:f89874f8-451a-42f5-bf25-755fa0b0763a+urn:upnp-org:serviceId:ContentDirectory@{F564D3FA-6476-4013-A6D7-A6284659113C}.0.102 in service upnp_browser Oct 21 17:31:39 volumio-gow volumio[914]: info: Exploding uri upnp/http://192.168.1.101:2869/upnphost/udhisapi.dll?control=uuid:f89874f8-451a-42f5-bf25-755fa0b0763a+urn:upnp-org:serviceId:ContentDirectory@{6596312C-59E5-456E-944E-E4D41DF924E6}.0.102 in service upnp_browser Oct 21 17:31:39 volumio-gow volumio[914]: info: Exploding uri upnp/http://192.168.1.101:2869/upnphost/udhisapi.dll?control=uuid:f89874f8-451a-42f5-bf25-755fa0b0763a+urn:upnp-org:serviceId:ContentDirectory@{F250291F-90F6-4B37-9CDB-6C923CCC10C3}.0.102 in service upnp_browser Oct 21 17:31:39 volumio-gow volumio[914]: info: Exploding uri upnp/http://192.168.1.101:2869/upnphost/udhisapi.dll?control=uuid:f89874f8-451a-42f5-bf25-755fa0b0763a+urn:upnp-org:serviceId:ContentDirectory@{53FC4F3D-CAB3-46A7-81B0-896D299F28D5}.0.102 in service upnp_browser Oct 21 17:31:39 volumio-gow volumio[914]: info: Exploding uri upnp/http://192.168.1.101:2869/upnphost/udhisapi.dll?control=uuid:f89874f8-451a-42f5-bf25-755fa0b0763a+urn:upnp-org:serviceId:ContentDirectory@{FAE260EA-8801-4373-88C4-79F4C5A822EC}.0.102 in service upnp_browser Oct 21 17:31:40 volumio-gow volumio[914]: info: Exploding uri upnp/http://192.168.1.101:2869/upnphost/udhisapi.dll?control=uuid:f89874f8-451a-42f5-bf25-755fa0b0763a+urn:upnp-org:serviceId:ContentDirectory@{03403C4F-2ADF-4D10-983B-129618DD43D2}.0.102 in service upnp_browser Oct 21 17:31:40 volumio-gow volumio[914]: info: Exploding uri upnp/http://192.168.1.101:2869/upnphost/udhisapi.dll?control=uuid:f89874f8-451a-42f5-bf25-755fa0b0763a+urn:upnp-org:serviceId:ContentDirectory@{6BB4056B-1F36-41FF-B977-BF281672ECFB}.0.102 in service upnp_browser Oct 21 17:31:40 volumio-gow volumio[914]: info: Exploding uri upnp/http://192.168.1.101:2869/upnphost/udhisapi.dll?control=uuid:f89874f8-451a-42f5-bf25-755fa0b0763a+urn:upnp-org:serviceId:ContentDirectory@{83AA2D4D-6DD1-4C38-A0EB-641EB5488E93}.0.102 in service upnp_browser Oct 21 17:31:40 volumio-gow volumio[914]: info: Exploding uri upnp/http://192.168.1.101:2869/upnphost/udhisapi.dll?control=uuid:f89874f8-451a-42f5-bf25-755fa0b0763a+urn:upnp-org:serviceId:ContentDirectory@{59CFCBE9-07F9-4E60-9C53-685C4E40E229}.0.102 in service upnp_browser Oct 21 17:31:40 volumio-gow volumio[914]: info: Exploding uri upnp/http://192.168.1.101:2869/upnphost/udhisapi.dll?control=uuid:f89874f8-451a-42f5-bf25-755fa0b0763a+urn:upnp-org:serviceId:ContentDirectory@{676893A4-9E51-4FB6-BA70-6BBC96FBDF97}.0.102 in service upnp_browser Oct 21 17:31:40 volumio-gow volumio[914]: info: Exploding uri upnp/http://192.168.1.101:2869/upnphost/udhisapi.dll?control=uuid:f89874f8-451a-42f5-bf25-755fa0b0763a+urn:upnp-org:serviceId:ContentDirectory@{8DCFAD99-2112-4A5D-9BCA-25BB626A1FED}.0.102 in service upnp_browser Oct 21 17:31:53 volumio-gow volumio[914]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 21 17:31:53 volumio-gow volumio[914]: info: CURURI: music-library Oct 21 17:31:53 volumio-gow volumio[914]: error: Failed LSINFO: null Oct 21 17:31:53 volumio-gow volumio[914]: info: Preload queue cleared Oct 21 17:31:56 volumio-gow volumio[914]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 21 17:31:56 volumio-gow volumio[914]: info: CURURI: playlists Oct 21 17:31:56 volumio-gow volumio[914]: info: Listing playlists Oct 21 17:31:56 volumio-gow volumio[914]: info: Preload queue cleared Oct 21 17:31:58 volumio-gow volumio[914]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 21 17:31:58 volumio-gow volumio[914]: info: CURURI: playlists/gow Oct 21 17:31:58 volumio-gow volumio[914]: info: Preload queue cleared Oct 21 17:32:00 volumio-gow volumio[914]: info: Preload queue cleared Oct 21 17:32:00 volumio-gow volumio[914]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 21 17:32:00 volumio-gow volumio[914]: info: CoreStateMachine::ClearQueue Oct 21 17:32:00 volumio-gow volumio[914]: info: CoreStateMachine::stop Oct 21 17:32:00 volumio-gow volumio[914]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 21 17:32:00 volumio-gow volumio[914]: info: CorePlayQueue::clearPlayQueue Oct 21 17:32:00 volumio-gow volumio[914]: info: CorePlayQueue::saveQueue Oct 21 17:32:00 volumio-gow volumio[914]: info: CoreCommandRouter::volumioPushQueue Oct 21 17:32:00 volumio-gow volumio[914]: info: CoreStateMachine::addQueueItems Oct 21 17:32:00 volumio-gow volumio[914]: info: CorePlayQueue::addQueueItems Oct 21 17:32:00 volumio-gow volumio[914]: info: Preload queue cleared Oct 21 17:32:00 volumio-gow volumio[914]: info: Adding Item to queue: upnp/folder/http://192.168.1.107:2869/upnphost/udhisapi.dll?control=uuid:f89874f8-451a-42f5-bf25-755fa0b0763a+urn:upnp-org:serviceId:ContentDirectory@4 Oct 21 17:32:00 volumio-gow volumio[914]: info: Exploding uri upnp/folder/http://192.168.1.107:2869/upnphost/udhisapi.dll?control=uuid:f89874f8-451a-42f5-bf25-755fa0b0763a+urn:upnp-org:serviceId:ContentDirectory@4 in service upnp_browser Oct 21 17:32:03 volumio-gow volumio[914]: error: connect EHOSTUNREACH 192.168.1.107:2869 {"errno":-113,"code":"EHOSTUNREACH","syscall":"connect","address":"192.168.1.107","port":2869} Oct 21 17:32:07 volumio-gow volumio[914]: info: Preload queue cleared Oct 21 17:32:07 volumio-gow volumio[914]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 21 17:32:07 volumio-gow volumio[914]: info: CoreStateMachine::ClearQueue Oct 21 17:32:07 volumio-gow volumio[914]: info: CoreStateMachine::stop Oct 21 17:32:07 volumio-gow volumio[914]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 21 17:32:07 volumio-gow volumio[914]: info: CorePlayQueue::clearPlayQueue Oct 21 17:32:07 volumio-gow volumio[914]: info: CorePlayQueue::saveQueue Oct 21 17:32:07 volumio-gow volumio[914]: info: CoreCommandRouter::volumioPushQueue Oct 21 17:32:07 volumio-gow volumio[914]: info: CoreStateMachine::addQueueItems Oct 21 17:32:07 volumio-gow volumio[914]: info: CorePlayQueue::addQueueItems Oct 21 17:32:07 volumio-gow volumio[914]: info: Preload queue cleared Oct 21 17:32:07 volumio-gow volumio[914]: info: Adding Item to queue: upnp/folder/http://192.168.1.107:2869/upnphost/udhisapi.dll?control=uuid:f89874f8-451a-42f5-bf25-755fa0b0763a+urn:upnp-org:serviceId:ContentDirectory@4 Oct 21 17:32:07 volumio-gow volumio[914]: info: Exploding uri upnp/folder/http://192.168.1.107:2869/upnphost/udhisapi.dll?control=uuid:f89874f8-451a-42f5-bf25-755fa0b0763a+urn:upnp-org:serviceId:ContentDirectory@4 in service upnp_browser Oct 21 17:32:10 volumio-gow volumio[914]: error: connect EHOSTUNREACH 192.168.1.107:2869 {"errno":-113,"code":"EHOSTUNREACH","syscall":"connect","address":"192.168.1.107","port":2869} Oct 21 17:32:11 volumio-gow volumio[914]: info: CoreCommandRouter::volumioPlay Oct 21 17:32:11 volumio-gow volumio[914]: info: CoreStateMachine::play index undefined Oct 21 17:32:11 volumio-gow volumio[914]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 21 17:32:11 volumio-gow volumio[914]: info: CorePlayQueue::getTrack 0 Oct 21 17:32:22 volumio-gow volumio[914]: info: CoreCommandRouter::volumioGetState Oct 21 17:32:22 volumio-gow volumio[914]: info: CorePlayQueue::getTrack 0 Oct 21 17:32:24 volumio-gow volumio[914]: info: CoreCommandRouter::volumioPlay Oct 21 17:32:24 volumio-gow volumio[914]: info: CoreStateMachine::play index undefined Oct 21 17:32:24 volumio-gow volumio[914]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 21 17:32:24 volumio-gow volumio[914]: info: CorePlayQueue::getTrack 0 Oct 21 17:32:31 volumio-gow volumio[914]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 21 17:32:31 volumio-gow volumio[914]: info: Preload queue cleared Oct 21 17:32:32 volumio-gow volumio[914]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 21 17:32:32 volumio-gow volumio[914]: info: Preload queue cleared Oct 21 17:32:35 volumio-gow volumio[914]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 21 17:32:35 volumio-gow volumio[914]: info: Preload queue cleared Oct 21 17:32:37 volumio-gow volumio[914]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 21 17:32:37 volumio-gow volumio[914]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 21 17:32:37 volumio-gow volumio[914]: TypeError: Cannot read property 'length' of undefined Oct 21 17:32:37 volumio-gow volumio[914]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Oct 21 17:32:37 volumio-gow volumio[914]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Oct 21 17:32:37 volumio-gow volumio[914]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Oct 21 17:32:37 volumio-gow volumio[914]: at Parser.emit (events.js:315:20) Oct 21 17:32:37 volumio-gow volumio[914]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Oct 21 17:32:37 volumio-gow volumio[914]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Oct 21 17:32:37 volumio-gow volumio[914]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Oct 21 17:32:37 volumio-gow volumio[914]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Oct 21 17:32:37 volumio-gow volumio[914]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Oct 21 17:32:37 volumio-gow volumio[914]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Oct 21 17:32:37 volumio-gow volumio[914]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Oct 21 17:32:37 volumio-gow volumio[914]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Oct 21 17:32:37 volumio-gow volumio[914]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Oct 21 17:32:37 volumio-gow volumio[914]: at IncomingMessage.emit (events.js:327:22) Oct 21 17:32:37 volumio-gow volumio[914]: at endReadableNT (internal/streams/readable.js:1327:12) Oct 21 17:32:37 volumio-gow volumio[914]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Oct 21 17:32:37 volumio-gow volumio[914]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 21 17:32:40 volumio-gow sudo[2149]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-21 17:31 Oct 21 17:32:40 volumio-gow sudo[2149]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"