-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Tue 2025-12-30 15:43:28 CET. -- Dec 30 15:42:06 volumio volumio[1181]: info: CoreCommandRouter::volumioGetState Dec 30 15:42:06 volumio volumio[1181]: info: CorePlayQueue::getTrack 0 Dec 30 15:42:06 volumio volumio[1181]: info: Listing playlists Dec 30 15:42:06 volumio volumio[1181]: info: Listing playlists Dec 30 15:42:16 volumio volumio[1181]: info: CoreCommandRouter::volumioGetState Dec 30 15:42:16 volumio volumio[1181]: info: CorePlayQueue::getTrack 0 Dec 30 15:42:26 volumio volumio[1181]: info: CoreCommandRouter::volumioGetState Dec 30 15:42:26 volumio volumio[1181]: info: CorePlayQueue::getTrack 0 Dec 30 15:42:26 volumio volumio[1181]: info: Listing playlists Dec 30 15:42:26 volumio volumio[1181]: info: Listing playlists Dec 30 15:42:36 volumio volumio[1181]: info: CoreCommandRouter::volumioGetState Dec 30 15:42:36 volumio volumio[1181]: info: CorePlayQueue::getTrack 0 Dec 30 15:42:46 volumio volumio[1181]: info: CoreCommandRouter::volumioGetState Dec 30 15:42:46 volumio volumio[1181]: info: CorePlayQueue::getTrack 0 Dec 30 15:42:46 volumio volumio[1181]: info: Listing playlists Dec 30 15:42:46 volumio volumio[1181]: info: Listing playlists Dec 30 15:42:52 volumio volumio[1181]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Dec 30 15:42:52 volumio volumio[1181]: info: CoreCommandRouter::volumioGetQueue Dec 30 15:42:52 volumio volumio[1181]: info: CoreStateMachine::getQueue Dec 30 15:42:52 volumio volumio[1181]: info: CorePlayQueue::getQueue Dec 30 15:42:52 volumio volumio[1181]: info: Preload queue cleared Dec 30 15:42:53 volumio volumio[1181]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Dec 30 15:42:53 volumio volumio[1181]: info: Preload queue cleared Dec 30 15:42:53 volumio volumio[1181]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Dec 30 15:42:54 volumio volumio[1181]: info: Preload queue cleared Dec 30 15:42:54 volumio volumio[1181]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Dec 30 15:42:54 volumio volumio[1181]: info: Preload queue cleared Dec 30 15:42:55 volumio volumio[1181]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Dec 30 15:42:55 volumio volumio[1181]: info: Preload queue cleared Dec 30 15:42:56 volumio volumio[1181]: info: CoreCommandRouter::volumioGetState Dec 30 15:42:56 volumio volumio[1181]: info: CorePlayQueue::getTrack 0 Dec 30 15:42:58 volumio volumio[1181]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Dec 30 15:42:59 volumio volumio[1181]: info: Preload queue cleared Dec 30 15:42:59 volumio volumio[1181]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Dec 30 15:43:00 volumio volumio[1181]: info: Preload queue cleared Dec 30 15:43:03 volumio volumio[1181]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Dec 30 15:43:03 volumio volumio[1181]: info: Preload queue cleared Dec 30 15:43:03 volumio volumio[1181]: info: Preloading song: upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159777045$1584922884 Dec 30 15:43:03 volumio volumio[1181]: info: Preloading song: upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159777045$1584988420 Dec 30 15:43:03 volumio volumio[1181]: info: Preloading song: upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159777045$1585053956 Dec 30 15:43:03 volumio volumio[1181]: info: Preloading song: upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159777045$1585119492 Dec 30 15:43:03 volumio volumio[1181]: info: Preloading song: upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159777045$1585185028 Dec 30 15:43:03 volumio volumio[1181]: info: Preloading song: upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159777045$1585250564 Dec 30 15:43:03 volumio volumio[1181]: info: Preloading song: upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159777045$1585316100 Dec 30 15:43:03 volumio volumio[1181]: info: Preloading song: upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159777045$1585381636 Dec 30 15:43:03 volumio volumio[1181]: info: Preloading song: upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159777045$1585447172 Dec 30 15:43:03 volumio volumio[1181]: info: Preloading song: upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159777045$1585512708 Dec 30 15:43:03 volumio volumio[1181]: info: Preloading song: upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159777045$1585578244 Dec 30 15:43:03 volumio volumio[1181]: info: Preloading song: upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159777045$1585643780 Dec 30 15:43:03 volumio volumio[1181]: info: Exploding uri upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159777045$1584922884 in service upnp_browser Dec 30 15:43:03 volumio volumio[1181]: info: Exploding uri upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159777045$1584988420 in service upnp_browser Dec 30 15:43:03 volumio volumio[1181]: info: Exploding uri upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159777045$1585053956 in service upnp_browser Dec 30 15:43:03 volumio volumio[1181]: info: Exploding uri upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159777045$1585119492 in service upnp_browser Dec 30 15:43:03 volumio volumio[1181]: info: Exploding uri upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159777045$1585185028 in service upnp_browser Dec 30 15:43:03 volumio volumio[1181]: info: Exploding uri upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159777045$1585250564 in service upnp_browser Dec 30 15:43:03 volumio volumio[1181]: info: Exploding uri upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159777045$1585316100 in service upnp_browser Dec 30 15:43:03 volumio volumio[1181]: info: Exploding uri upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159777045$1585381636 in service upnp_browser Dec 30 15:43:03 volumio volumio[1181]: info: Exploding uri upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159777045$1585447172 in service upnp_browser Dec 30 15:43:03 volumio volumio[1181]: info: Exploding uri upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159777045$1585512708 in service upnp_browser Dec 30 15:43:03 volumio volumio[1181]: info: Exploding uri upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159777045$1585578244 in service upnp_browser Dec 30 15:43:03 volumio volumio[1181]: info: Exploding uri upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159777045$1585643780 in service upnp_browser Dec 30 15:43:04 volumio volumio[1181]: info: Preload queue cleared Dec 30 15:43:04 volumio volumio[1181]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 30 15:43:04 volumio volumio[1181]: info: CoreStateMachine::ClearQueue Dec 30 15:43:04 volumio volumio[1181]: info: CoreStateMachine::stop Dec 30 15:43:04 volumio volumio[1181]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 15:43:04 volumio volumio[1181]: info: CorePlayQueue::clearPlayQueue Dec 30 15:43:04 volumio volumio[1181]: info: CorePlayQueue::saveQueue Dec 30 15:43:04 volumio volumio[1181]: info: CoreCommandRouter::volumioPushQueue Dec 30 15:43:04 volumio volumio[1181]: info: CoreStateMachine::addQueueItems Dec 30 15:43:04 volumio volumio[1181]: info: CorePlayQueue::addQueueItems Dec 30 15:43:04 volumio volumio[1181]: info: Preload queue cleared Dec 30 15:43:04 volumio volumio[1181]: info: Adding Item to queue: upnp/folder/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159777045 Dec 30 15:43:04 volumio volumio[1181]: info: Exploding uri upnp/folder/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159777045 in service upnp_browser Dec 30 15:43:04 volumio volumio[1181]: info: CoreCommandRouter::volumioPushQueue Dec 30 15:43:04 volumio volumio[1181]: info: CorePlayQueue::saveQueue Dec 30 15:43:04 volumio volumio[1181]: info: CoreStateMachine::updateTrackBlock Dec 30 15:43:04 volumio volumio[1181]: info: CorePlayQueue::getTrackBlock Dec 30 15:43:04 volumio volumio[1181]: info: CoreCommandRouter::volumioPlay Dec 30 15:43:04 volumio volumio[1181]: info: CoreStateMachine::play index 0 Dec 30 15:43:04 volumio volumio[1181]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 15:43:04 volumio volumio[1181]: info: CoreStateMachine::stop Dec 30 15:43:04 volumio volumio[1181]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 15:43:04 volumio volumio[1181]: info: CoreStateMachine::play index undefined Dec 30 15:43:04 volumio volumio[1181]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 15:43:04 volumio volumio[1181]: info: CorePlayQueue::getTrack 0 Dec 30 15:43:04 volumio volumio[1181]: info: CoreStateMachine::startPlaybackTimer Dec 30 15:43:04 volumio volumio[1181]: info: CorePlayQueue::getTrack 0 Dec 30 15:43:04 volumio volumio[1181]: info: [1767105784384] ControllerUPNPBrowser::clearAddPlayTrack Dec 30 15:43:04 volumio volumio[1181]: verbose: ControllerMpd::sendMpdCommand stop Dec 30 15:43:04 volumio volumio[1181]: info: sendMpdCommand stop took 3 milliseconds Dec 30 15:43:04 volumio volumio[1181]: verbose: ControllerMpd::sendMpdCommand clear Dec 30 15:43:04 volumio volumio[1181]: info: Dec 30 15:43:04 volumio volumio[1181]: ---------------------------- MPD announces system playlist update Dec 30 15:43:04 volumio volumio[1181]: info: Ignoring MPD Status Update Dec 30 15:43:04 volumio volumio[1181]: info: sendMpdCommand clear took 1 milliseconds Dec 30 15:43:04 volumio volumio[1181]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.0.58:47820/MediaItems/A0$128$146$65813$159645973$159711509$159777045$1584922884.mp3?type=1,client=33,mime=audio/mpeg,pn=MP3,ext=.mp3" Dec 30 15:43:04 volumio volumio[1181]: info: Dec 30 15:43:04 volumio volumio[1181]: ---------------------------- MPD announces system playlist update Dec 30 15:43:04 volumio volumio[1181]: info: Ignoring MPD Status Update Dec 30 15:43:04 volumio volumio[1181]: info: Dec 30 15:43:04 volumio volumio[1181]: ---------------------------- MPD announces system playlist update Dec 30 15:43:04 volumio volumio[1181]: info: Ignoring MPD Status Update Dec 30 15:43:04 volumio volumio[1181]: error: updateQueue error: null Dec 30 15:43:04 volumio volumio[1181]: info: ------------------------------ 3ms Dec 30 15:43:04 volumio volumio[1181]: error: Upnp client error: Error: This socket has been ended by the other party Dec 30 15:43:04 volumio volumio[1181]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.0.58:47820/MediaItems/A0$128$146$65813$159645973$159711509$159777045$1584922884.mp3?type=1,client=33,mime=audio/mpeg,pn=MP3,ext=.mp3" Dec 30 15:43:04 volumio volumio[1181]: error: updateQueue error: null Dec 30 15:43:04 volumio volumio[1181]: error: updateQueue error: null Dec 30 15:43:04 volumio volumio[1181]: info: Dec 30 15:43:04 volumio volumio[1181]: ---------------------------- MPD announces system playlist update Dec 30 15:43:04 volumio volumio[1181]: info: Ignoring MPD Status Update Dec 30 15:43:04 volumio volumio[1181]: info: ------------------------------ 136ms Dec 30 15:43:04 volumio volumio[1181]: info: ------------------------------ 135ms Dec 30 15:43:04 volumio volumio[1181]: info: sendMpdCommand add "http://192.168.0.58:47820/MediaItems/A0$128$146$65813$159645973$159711509$159777045$1584922884.mp3?type=1,client=33,mime=audio/mpeg,pn=MP3,ext=.mp3" took 2 milliseconds Dec 30 15:43:04 volumio volumio[1181]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 30 15:43:04 volumio volumio[1181]: verbose: ControllerMpd::sendMpdCommand play Dec 30 15:43:04 volumio volumio[1181]: info: Dec 30 15:43:04 volumio volumio[1181]: ---------------------------- MPD announces system playlist update Dec 30 15:43:04 volumio volumio[1181]: info: Ignoring MPD Status Update Dec 30 15:43:04 volumio volumio[1181]: info: Dec 30 15:43:04 volumio volumio[1181]: ---------------------------- MPD announces system playlist update Dec 30 15:43:04 volumio volumio[1181]: info: Ignoring MPD Status Update Dec 30 15:43:04 volumio volumio[1181]: info: ------------------------------ 3ms Dec 30 15:43:04 volumio volumio[1181]: info: sendMpdCommand play took 2 milliseconds Dec 30 15:43:04 volumio volumio[1181]: info: ------------------------------ 2ms Dec 30 15:43:04 volumio volumio[1181]: info: ------------------------------ 1ms Dec 30 15:43:04 volumio volumio[1181]: info: Dec 30 15:43:04 volumio volumio[1181]: ---------------------------- MPD announces state update: player Dec 30 15:43:04 volumio volumio[1181]: info: ControllerMpd::getState Dec 30 15:43:04 volumio volumio[1181]: verbose: ControllerMpd::sendMpdCommand status Dec 30 15:43:04 volumio volumio[1181]: info: Dec 30 15:43:04 volumio volumio[1181]: ---------------------------- MPD announces state update: player Dec 30 15:43:04 volumio volumio[1181]: info: ControllerMpd::getState Dec 30 15:43:04 volumio volumio[1181]: verbose: ControllerMpd::sendMpdCommand status Dec 30 15:43:04 volumio volumio[1181]: info: Dec 30 15:43:04 volumio volumio[1181]: ---------------------------- MPD announces state update: player Dec 30 15:43:04 volumio volumio[1181]: info: ControllerMpd::getState Dec 30 15:43:04 volumio volumio[1181]: verbose: ControllerMpd::sendMpdCommand status Dec 30 15:43:04 volumio volumio[1181]: info: Dec 30 15:43:04 volumio volumio[1181]: ---------------------------- MPD announces state update: player Dec 30 15:43:04 volumio volumio[1181]: info: sendMpdCommand status took 22 milliseconds Dec 30 15:43:04 volumio volumio[1181]: info: sendMpdCommand status took 21 milliseconds Dec 30 15:43:04 volumio volumio[1181]: info: sendMpdCommand status took 20 milliseconds Dec 30 15:43:04 volumio volumio[1181]: info: ControllerMpd::getState Dec 30 15:43:04 volumio volumio[1181]: verbose: ControllerMpd::sendMpdCommand status Dec 30 15:43:04 volumio volumio[1181]: verbose: ControllerMpd::parseState Dec 30 15:43:04 volumio volumio[1181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 15:43:04 volumio volumio[1181]: verbose: ControllerMpd::parseState Dec 30 15:43:04 volumio volumio[1181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 15:43:04 volumio volumio[1181]: verbose: ControllerMpd::parseState Dec 30 15:43:04 volumio volumio[1181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 15:43:04 volumio volumio[1181]: info: Dec 30 15:43:04 volumio volumio[1181]: ---------------------------- MPD announces state update: player Dec 30 15:43:04 volumio volumio[1181]: info: ControllerMpd::getState Dec 30 15:43:04 volumio volumio[1181]: verbose: ControllerMpd::sendMpdCommand status Dec 30 15:43:04 volumio volumio[1181]: info: Dec 30 15:43:04 volumio volumio[1181]: ---------------------------- MPD announces state update: player Dec 30 15:43:04 volumio volumio[1181]: info: ControllerMpd::getState Dec 30 15:43:04 volumio volumio[1181]: verbose: ControllerMpd::sendMpdCommand status Dec 30 15:43:04 volumio volumio[1181]: info: sendMpdCommand status took 22 milliseconds Dec 30 15:43:04 volumio volumio[1181]: info: sendMpdCommand playlistinfo took 22 milliseconds Dec 30 15:43:04 volumio volumio[1181]: info: sendMpdCommand playlistinfo took 22 milliseconds Dec 30 15:43:04 volumio volumio[1181]: info: sendMpdCommand status took 20 milliseconds Dec 30 15:43:04 volumio volumio[1181]: verbose: ControllerMpd::parseState Dec 30 15:43:04 volumio volumio[1181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 15:43:04 volumio volumio[1181]: verbose: ControllerMpd::parseTrackInfo Dec 30 15:43:04 volumio volumio[1181]: verbose: ControllerMpd::parseTrackInfo Dec 30 15:43:04 volumio volumio[1181]: verbose: ControllerMpd::parseTrackInfo Dec 30 15:43:04 volumio volumio[1181]: verbose: ControllerMpd::parseState Dec 30 15:43:04 volumio volumio[1181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 15:43:04 volumio volumio[1181]: info: ControllerMpd::pushState Dec 30 15:43:04 volumio volumio[1181]: info: CoreCommandRouter::servicePushState Dec 30 15:43:04 volumio volumio[1181]: info: CorePlayQueue::getTrack 0 Dec 30 15:43:04 volumio volumio[1181]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":152,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"mpeg,pn=MP3,ext=.mp3","artist":null,"album":null,"uri":"http://192.168.0.58:47820/MediaItems/A0$128$146$65813$159645973$159711509$159777045$1584922884.mp3?type=1,client=33,mime=audio/mpeg,pn=MP3,ext=.mp3","trackType":"mp3"} Dec 30 15:43:04 volumio volumio[1181]: verbose: CURRENT POSITION 0 Dec 30 15:43:04 volumio volumio[1181]: info: CoreStateMachine::syncState stateService play Dec 30 15:43:04 volumio volumio[1181]: info: CoreStateMachine::syncState currentStatus stop Dec 30 15:43:04 volumio volumio[1181]: info: ControllerMpd::pushState Dec 30 15:43:04 volumio volumio[1181]: info: CoreCommandRouter::servicePushState Dec 30 15:43:04 volumio volumio[1181]: info: CorePlayQueue::getTrack 0 Dec 30 15:43:04 volumio volumio[1181]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":152,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"mpeg,pn=MP3,ext=.mp3","artist":null,"album":null,"uri":"http://192.168.0.58:47820/MediaItems/A0$128$146$65813$159645973$159711509$159777045$1584922884.mp3?type=1,client=33,mime=audio/mpeg,pn=MP3,ext=.mp3","trackType":"mp3"} Dec 30 15:43:04 volumio volumio[1181]: verbose: CURRENT POSITION 0 Dec 30 15:43:04 volumio volumio[1181]: info: CoreStateMachine::syncState stateService play Dec 30 15:43:04 volumio volumio[1181]: info: CoreStateMachine::syncState currentStatus play Dec 30 15:43:04 volumio volumio[1181]: info: Received an update from plugin. extracting info from payload Dec 30 15:43:04 volumio volumio[1181]: info: CoreStateMachine::pushState Dec 30 15:43:04 volumio volumio[1181]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 15:43:04 volumio volumio[1181]: info: CoreCommandRouter::volumioPushState Dec 30 15:43:04 volumio volumio[1181]: info: CoreStateMachine::pushState Dec 30 15:43:04 volumio volumio[1181]: info: CoreCommandRouter::volumioPushState Dec 30 15:43:04 volumio volumio[1181]: info: ControllerMpd::pushState Dec 30 15:43:04 volumio volumio[1181]: info: CoreCommandRouter::servicePushState Dec 30 15:43:04 volumio volumio[1181]: info: CorePlayQueue::getTrack 0 Dec 30 15:43:04 volumio volumio[1181]: verbose: STATE SERVICE {"status":"play","position":0,"seek":45,"duration":152,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"mpeg,pn=MP3,ext=.mp3","artist":null,"album":null,"uri":"http://192.168.0.58:47820/MediaItems/A0$128$146$65813$159645973$159711509$159777045$1584922884.mp3?type=1,client=33,mime=audio/mpeg,pn=MP3,ext=.mp3","trackType":"mp3"} Dec 30 15:43:04 volumio volumio[1181]: verbose: CURRENT POSITION 0 Dec 30 15:43:04 volumio volumio[1181]: info: CoreStateMachine::syncState stateService play Dec 30 15:43:04 volumio volumio[1181]: info: CoreStateMachine::syncState currentStatus play Dec 30 15:43:04 volumio volumio[1181]: info: Received an update from plugin. extracting info from payload Dec 30 15:43:04 volumio volumio[1181]: info: CoreStateMachine::pushState Dec 30 15:43:04 volumio volumio[1181]: info: CoreCommandRouter::volumioPushState Dec 30 15:43:04 volumio volumio[1181]: info: CoreStateMachine::pushState Dec 30 15:43:04 volumio volumio[1181]: info: CoreCommandRouter::volumioPushState Dec 30 15:43:04 volumio volumio[1181]: info: ------------------------------ 60ms Dec 30 15:43:04 volumio volumio[1181]: info: ------------------------------ 70ms Dec 30 15:43:04 volumio volumio[1181]: info: ------------------------------ 70ms Dec 30 15:43:04 volumio volumio[1181]: info: sendMpdCommand status took 46 milliseconds Dec 30 15:43:04 volumio volumio[1181]: info: sendMpdCommand playlistinfo took 26 milliseconds Dec 30 15:43:04 volumio volumio[1181]: info: sendMpdCommand playlistinfo took 24 milliseconds Dec 30 15:43:04 volumio volumio[1181]: verbose: ControllerMpd::parseState Dec 30 15:43:04 volumio volumio[1181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 15:43:04 volumio volumio[1181]: verbose: ControllerMpd::parseTrackInfo Dec 30 15:43:04 volumio volumio[1181]: verbose: ControllerMpd::parseTrackInfo Dec 30 15:43:04 volumio volumio[1181]: info: ControllerMpd::pushState Dec 30 15:43:04 volumio volumio[1181]: info: CoreCommandRouter::servicePushState Dec 30 15:43:04 volumio volumio[1181]: info: CorePlayQueue::getTrack 0 Dec 30 15:43:04 volumio volumio[1181]: verbose: STATE SERVICE {"status":"play","position":0,"seek":262,"duration":152,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"mpeg,pn=MP3,ext=.mp3","artist":null,"album":null,"uri":"http://192.168.0.58:47820/MediaItems/A0$128$146$65813$159645973$159711509$159777045$1584922884.mp3?type=1,client=33,mime=audio/mpeg,pn=MP3,ext=.mp3","trackType":"mp3"} Dec 30 15:43:04 volumio volumio[1181]: verbose: CURRENT POSITION 0 Dec 30 15:43:04 volumio volumio[1181]: info: CoreStateMachine::syncState stateService play Dec 30 15:43:04 volumio volumio[1181]: info: CoreStateMachine::syncState currentStatus play Dec 30 15:43:04 volumio volumio[1181]: info: Received an update from plugin. extracting info from payload Dec 30 15:43:04 volumio volumio[1181]: info: CoreStateMachine::pushState Dec 30 15:43:04 volumio volumio[1181]: info: CoreCommandRouter::volumioPushState Dec 30 15:43:04 volumio volumio[1181]: info: CoreStateMachine::pushState Dec 30 15:43:04 volumio volumio[1181]: info: CoreCommandRouter::volumioPushState Dec 30 15:43:04 volumio volumio[1181]: info: ControllerMpd::pushState Dec 30 15:43:04 volumio volumio[1181]: info: CoreCommandRouter::servicePushState Dec 30 15:43:04 volumio volumio[1181]: info: CorePlayQueue::getTrack 0 Dec 30 15:43:04 volumio volumio[1181]: verbose: STATE SERVICE {"status":"play","position":0,"seek":274,"duration":152,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"mpeg,pn=MP3,ext=.mp3","artist":null,"album":null,"uri":"http://192.168.0.58:47820/MediaItems/A0$128$146$65813$159645973$159711509$159777045$1584922884.mp3?type=1,client=33,mime=audio/mpeg,pn=MP3,ext=.mp3","trackType":"mp3"} Dec 30 15:43:04 volumio volumio[1181]: verbose: CURRENT POSITION 0 Dec 30 15:43:04 volumio volumio[1181]: info: CoreStateMachine::syncState stateService play Dec 30 15:43:04 volumio volumio[1181]: info: CoreStateMachine::syncState currentStatus play Dec 30 15:43:04 volumio volumio[1181]: info: Received an update from plugin. extracting info from payload Dec 30 15:43:04 volumio volumio[1181]: info: CoreStateMachine::pushState Dec 30 15:43:04 volumio volumio[1181]: info: CoreCommandRouter::volumioPushState Dec 30 15:43:04 volumio volumio[1181]: info: CoreStateMachine::pushState Dec 30 15:43:04 volumio volumio[1181]: info: CoreCommandRouter::volumioPushState Dec 30 15:43:04 volumio volumio[1181]: info: ------------------------------ 79ms Dec 30 15:43:04 volumio volumio[1181]: info: ------------------------------ 76ms Dec 30 15:43:04 volumio volumio[1181]: info: sendMpdCommand playlistinfo took 33 milliseconds Dec 30 15:43:04 volumio volumio[1181]: verbose: ControllerMpd::parseTrackInfo Dec 30 15:43:04 volumio volumio[1181]: info: ControllerMpd::pushState Dec 30 15:43:04 volumio volumio[1181]: info: CoreCommandRouter::servicePushState Dec 30 15:43:04 volumio volumio[1181]: info: CorePlayQueue::getTrack 0 Dec 30 15:43:04 volumio volumio[1181]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":152,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"mpeg,pn=MP3,ext=.mp3","artist":null,"album":null,"uri":"http://192.168.0.58:47820/MediaItems/A0$128$146$65813$159645973$159711509$159777045$1584922884.mp3?type=1,client=33,mime=audio/mpeg,pn=MP3,ext=.mp3","trackType":"mp3"} Dec 30 15:43:04 volumio volumio[1181]: verbose: CURRENT POSITION 0 Dec 30 15:43:04 volumio volumio[1181]: info: CoreStateMachine::syncState stateService play Dec 30 15:43:04 volumio volumio[1181]: info: CoreStateMachine::syncState currentStatus play Dec 30 15:43:04 volumio volumio[1181]: info: Received an update from plugin. extracting info from payload Dec 30 15:43:04 volumio volumio[1181]: info: CoreStateMachine::pushState Dec 30 15:43:04 volumio volumio[1181]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 15:43:04 volumio volumio[1181]: info: CoreCommandRouter::volumioPushState Dec 30 15:43:04 volumio volumio[1181]: info: CoreStateMachine::pushState Dec 30 15:43:04 volumio volumio[1181]: info: CoreCommandRouter::volumioPushState Dec 30 15:43:04 volumio volumio[1181]: info: ------------------------------ 88ms Dec 30 15:43:06 volumio volumio[1181]: info: CoreCommandRouter::volumioGetState Dec 30 15:43:06 volumio volumio[1181]: info: Listing playlists Dec 30 15:43:06 volumio volumio[1181]: info: Listing playlists Dec 30 15:43:13 volumio volumio[1181]: info: CoreCommandRouter::volumioPause Dec 30 15:43:13 volumio volumio[1181]: info: CoreStateMachine::pause Dec 30 15:43:13 volumio volumio[1181]: info: CoreStateMachine::stPlaybackTimer Dec 30 15:43:13 volumio volumio[1181]: info: CoreStateMachine::servicePause Dec 30 15:43:13 volumio volumio[1181]: info: CorePlayQueue::getTrack 0 Dec 30 15:43:13 volumio volumio[1181]: info: CoreCommandRouter::servicePause Dec 30 15:43:13 volumio volumio[1181]: info: [1767105793544] ControllerUPNPBrowser::pause Dec 30 15:43:13 volumio volumio[1181]: verbose: ControllerMpd::sendMpdCommand pause Dec 30 15:43:13 volumio volumio[1181]: info: sendMpdCommand pause took 1 milliseconds Dec 30 15:43:13 volumio volumio[1181]: info: Dec 30 15:43:13 volumio volumio[1181]: ---------------------------- MPD announces state update: player Dec 30 15:43:13 volumio volumio[1181]: info: ControllerMpd::getState Dec 30 15:43:13 volumio volumio[1181]: verbose: ControllerMpd::sendMpdCommand status Dec 30 15:43:13 volumio volumio[1181]: info: Dec 30 15:43:13 volumio volumio[1181]: ---------------------------- MPD announces state update: player Dec 30 15:43:13 volumio volumio[1181]: info: ControllerMpd::getState Dec 30 15:43:13 volumio volumio[1181]: verbose: ControllerMpd::sendMpdCommand status Dec 30 15:43:13 volumio volumio[1181]: info: Dec 30 15:43:13 volumio volumio[1181]: ---------------------------- MPD announces state update: player Dec 30 15:43:13 volumio volumio[1181]: info: ControllerMpd::getState Dec 30 15:43:13 volumio volumio[1181]: verbose: ControllerMpd::sendMpdCommand status Dec 30 15:43:13 volumio volumio[1181]: info: sendMpdCommand status took 2 milliseconds Dec 30 15:43:13 volumio volumio[1181]: info: sendMpdCommand status took 1 milliseconds Dec 30 15:43:13 volumio volumio[1181]: info: sendMpdCommand status took 1 milliseconds Dec 30 15:43:13 volumio volumio[1181]: verbose: ControllerMpd::parseState Dec 30 15:43:13 volumio volumio[1181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 15:43:13 volumio volumio[1181]: verbose: ControllerMpd::parseState Dec 30 15:43:13 volumio volumio[1181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 15:43:13 volumio volumio[1181]: verbose: ControllerMpd::parseState Dec 30 15:43:13 volumio volumio[1181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 15:43:13 volumio volumio[1181]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 30 15:43:13 volumio volumio[1181]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 30 15:43:13 volumio volumio[1181]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 30 15:43:13 volumio volumio[1181]: verbose: ControllerMpd::parseTrackInfo Dec 30 15:43:13 volumio volumio[1181]: verbose: ControllerMpd::parseTrackInfo Dec 30 15:43:13 volumio volumio[1181]: verbose: ControllerMpd::parseTrackInfo Dec 30 15:43:13 volumio volumio[1181]: info: ControllerMpd::pushState Dec 30 15:43:13 volumio volumio[1181]: info: CoreCommandRouter::servicePushState Dec 30 15:43:13 volumio volumio[1181]: info: CorePlayQueue::getTrack 0 Dec 30 15:43:13 volumio volumio[1181]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":9782,"duration":152,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"mpeg,pn=MP3,ext=.mp3","artist":null,"album":null,"uri":"http://192.168.0.58:47820/MediaItems/A0$128$146$65813$159645973$159711509$159777045$1584922884.mp3?type=1,client=33,mime=audio/mpeg,pn=MP3,ext=.mp3","trackType":"mp3"} Dec 30 15:43:13 volumio volumio[1181]: verbose: CURRENT POSITION 0 Dec 30 15:43:13 volumio volumio[1181]: info: CoreStateMachine::syncState stateService pause Dec 30 15:43:13 volumio volumio[1181]: info: CoreStateMachine::syncState currentStatus pause Dec 30 15:43:13 volumio volumio[1181]: info: CoreStateMachine::pushState Dec 30 15:43:13 volumio volumio[1181]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 15:43:13 volumio volumio[1181]: info: CoreCommandRouter::volumioPushState Dec 30 15:43:13 volumio volumio[1181]: info: CoreStateMachine::stPlaybackTimer Dec 30 15:43:13 volumio volumio[1181]: info: ControllerMpd::pushState Dec 30 15:43:13 volumio volumio[1181]: info: CoreCommandRouter::servicePushState Dec 30 15:43:13 volumio volumio[1181]: info: CorePlayQueue::getTrack 0 Dec 30 15:43:13 volumio volumio[1181]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":9782,"duration":152,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"mpeg,pn=MP3,ext=.mp3","artist":null,"album":null,"uri":"http://192.168.0.58:47820/MediaItems/A0$128$146$65813$159645973$159711509$159777045$1584922884.mp3?type=1,client=33,mime=audio/mpeg,pn=MP3,ext=.mp3","trackType":"mp3"} Dec 30 15:43:13 volumio volumio[1181]: verbose: CURRENT POSITION 0 Dec 30 15:43:13 volumio volumio[1181]: info: CoreStateMachine::syncState stateService pause Dec 30 15:43:13 volumio volumio[1181]: info: CoreStateMachine::syncState currentStatus pause Dec 30 15:43:13 volumio volumio[1181]: info: CoreStateMachine::pushState Dec 30 15:43:13 volumio volumio[1181]: info: CoreCommandRouter::volumioPushState Dec 30 15:43:13 volumio volumio[1181]: info: CoreStateMachine::stPlaybackTimer Dec 30 15:43:13 volumio volumio[1181]: info: ControllerMpd::pushState Dec 30 15:43:13 volumio volumio[1181]: info: CoreCommandRouter::servicePushState Dec 30 15:43:13 volumio volumio[1181]: info: CorePlayQueue::getTrack 0 Dec 30 15:43:13 volumio volumio[1181]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":9782,"duration":152,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"mpeg,pn=MP3,ext=.mp3","artist":null,"album":null,"uri":"http://192.168.0.58:47820/MediaItems/A0$128$146$65813$159645973$159711509$159777045$1584922884.mp3?type=1,client=33,mime=audio/mpeg,pn=MP3,ext=.mp3","trackType":"mp3"} Dec 30 15:43:13 volumio volumio[1181]: verbose: CURRENT POSITION 0 Dec 30 15:43:13 volumio volumio[1181]: info: CoreStateMachine::syncState stateService pause Dec 30 15:43:13 volumio volumio[1181]: info: CoreStateMachine::syncState currentStatus pause Dec 30 15:43:13 volumio volumio[1181]: info: CoreStateMachine::pushState Dec 30 15:43:13 volumio volumio[1181]: info: CoreCommandRouter::volumioPushState Dec 30 15:43:13 volumio volumio[1181]: info: CoreStateMachine::stPlaybackTimer Dec 30 15:43:13 volumio volumio[1181]: info: ------------------------------ 19ms Dec 30 15:43:13 volumio volumio[1181]: info: ------------------------------ 19ms Dec 30 15:43:13 volumio volumio[1181]: info: ------------------------------ 18ms Dec 30 15:43:16 volumio volumio[1181]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Dec 30 15:43:16 volumio volumio[1181]: info: Preload queue cleared Dec 30 15:43:16 volumio volumio[1181]: info: Preloading song: upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159842581$1585709316 Dec 30 15:43:16 volumio volumio[1181]: info: Preloading song: upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159842581$1585774852 Dec 30 15:43:16 volumio volumio[1181]: info: Preloading song: upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159842581$1585840388 Dec 30 15:43:16 volumio volumio[1181]: info: Preloading song: upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159842581$1585905924 Dec 30 15:43:16 volumio volumio[1181]: info: Preloading song: upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159842581$1585971460 Dec 30 15:43:16 volumio volumio[1181]: info: Preloading song: upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159842581$1586036996 Dec 30 15:43:16 volumio volumio[1181]: info: Preloading song: upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159842581$1586102532 Dec 30 15:43:16 volumio volumio[1181]: info: Preloading song: upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159842581$1586168068 Dec 30 15:43:16 volumio volumio[1181]: info: Preloading song: upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159842581$1586233604 Dec 30 15:43:16 volumio volumio[1181]: info: Preloading song: upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159842581$1586299140 Dec 30 15:43:16 volumio volumio[1181]: info: Preloading song: upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159842581$1586364676 Dec 30 15:43:16 volumio volumio[1181]: info: Preloading song: upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159842581$1586430212 Dec 30 15:43:16 volumio volumio[1181]: info: Preloading song: upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159842581$1586495748 Dec 30 15:43:16 volumio volumio[1181]: info: Preloading song: upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159842581$1586561284 Dec 30 15:43:16 volumio volumio[1181]: info: Preloading song: upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159842581$1586626820 Dec 30 15:43:16 volumio volumio[1181]: info: Preloading song: upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159842581$1586692356 Dec 30 15:43:16 volumio volumio[1181]: info: CoreCommandRouter::volumioGetState Dec 30 15:43:16 volumio volumio[1181]: info: Exploding uri upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159842581$1585709316 in service upnp_browser Dec 30 15:43:16 volumio volumio[1181]: info: Exploding uri upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159842581$1585774852 in service upnp_browser Dec 30 15:43:16 volumio volumio[1181]: info: Exploding uri upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159842581$1585840388 in service upnp_browser Dec 30 15:43:16 volumio volumio[1181]: info: Exploding uri upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159842581$1585905924 in service upnp_browser Dec 30 15:43:16 volumio volumio[1181]: info: Exploding uri upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159842581$1585971460 in service upnp_browser Dec 30 15:43:16 volumio volumio[1181]: info: Exploding uri upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159842581$1586036996 in service upnp_browser Dec 30 15:43:16 volumio volumio[1181]: info: Exploding uri upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159842581$1586102532 in service upnp_browser Dec 30 15:43:17 volumio volumio[1181]: info: Exploding uri upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159842581$1586168068 in service upnp_browser Dec 30 15:43:17 volumio volumio[1181]: info: Exploding uri upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159842581$1586233604 in service upnp_browser Dec 30 15:43:17 volumio volumio[1181]: info: Exploding uri upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159842581$1586299140 in service upnp_browser Dec 30 15:43:17 volumio volumio[1181]: info: Exploding uri upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159842581$1586364676 in service upnp_browser Dec 30 15:43:17 volumio volumio[1181]: info: Exploding uri upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159842581$1586430212 in service upnp_browser Dec 30 15:43:17 volumio volumio[1181]: info: Exploding uri upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159842581$1586495748 in service upnp_browser Dec 30 15:43:17 volumio volumio[1181]: info: Exploding uri upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159842581$1586561284 in service upnp_browser Dec 30 15:43:17 volumio volumio[1181]: info: Exploding uri upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159842581$1586626820 in service upnp_browser Dec 30 15:43:17 volumio volumio[1181]: info: Exploding uri upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159842581$1586692356 in service upnp_browser Dec 30 15:43:17 volumio volumio[1181]: info: Preload queue cleared Dec 30 15:43:17 volumio volumio[1181]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 30 15:43:17 volumio volumio[1181]: info: CoreStateMachine::ClearQueue Dec 30 15:43:17 volumio volumio[1181]: info: CoreStateMachine::stop Dec 30 15:43:17 volumio volumio[1181]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 15:43:17 volumio volumio[1181]: info: CoreStateMachine::updateTrackBlock Dec 30 15:43:17 volumio volumio[1181]: info: CorePlayQueue::getTrackBlock Dec 30 15:43:17 volumio volumio[1181]: info: CoreStateMachine::stPlaybackTimer Dec 30 15:43:17 volumio volumio[1181]: info: CoreStateMachine::pushState Dec 30 15:43:17 volumio volumio[1181]: info: CorePlayQueue::getTrack 0 Dec 30 15:43:17 volumio volumio[1181]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 15:43:17 volumio volumio[1181]: info: CoreCommandRouter::volumioPushState Dec 30 15:43:17 volumio volumio[1181]: info: CoreStateMachine::serviceStop Dec 30 15:43:17 volumio volumio[1181]: info: CorePlayQueue::getTrack 0 Dec 30 15:43:17 volumio volumio[1181]: info: CoreCommandRouter::serviceStop Dec 30 15:43:17 volumio volumio[1181]: info: [1767105797621] ControllerUPNPBrowser::stop Dec 30 15:43:17 volumio volumio[1181]: verbose: ControllerMpd::sendMpdCommand stop Dec 30 15:43:17 volumio volumio[1181]: info: CorePlayQueue::clearPlayQueue Dec 30 15:43:17 volumio volumio[1181]: info: CorePlayQueue::saveQueue Dec 30 15:43:17 volumio volumio[1181]: info: CoreCommandRouter::volumioPushQueue Dec 30 15:43:17 volumio volumio[1181]: info: CoreStateMachine::addQueueItems Dec 30 15:43:17 volumio volumio[1181]: info: CorePlayQueue::addQueueItems Dec 30 15:43:17 volumio volumio[1181]: info: Preload queue cleared Dec 30 15:43:17 volumio volumio[1181]: info: Adding Item to queue: upnp/folder/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159842581 Dec 30 15:43:17 volumio volumio[1181]: info: Exploding uri upnp/folder/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159842581 in service upnp_browser Dec 30 15:43:17 volumio volumio[1181]: info: Dec 30 15:43:17 volumio volumio[1181]: ---------------------------- MPD announces state update: player Dec 30 15:43:17 volumio volumio[1181]: info: sendMpdCommand stop took 9 milliseconds Dec 30 15:43:17 volumio volumio[1181]: info: ControllerMpd::getState Dec 30 15:43:17 volumio volumio[1181]: verbose: ControllerMpd::sendMpdCommand status Dec 30 15:43:17 volumio volumio[1181]: info: sendMpdCommand status took 1 milliseconds Dec 30 15:43:17 volumio volumio[1181]: verbose: ControllerMpd::parseState Dec 30 15:43:17 volumio volumio[1181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 15:43:17 volumio volumio[1181]: info: Dec 30 15:43:17 volumio volumio[1181]: ---------------------------- MPD announces state update: player Dec 30 15:43:17 volumio volumio[1181]: info: ControllerMpd::getState Dec 30 15:43:17 volumio volumio[1181]: verbose: ControllerMpd::sendMpdCommand status Dec 30 15:43:17 volumio volumio[1181]: info: Dec 30 15:43:17 volumio volumio[1181]: ---------------------------- MPD announces state update: player Dec 30 15:43:17 volumio volumio[1181]: info: ControllerMpd::getState Dec 30 15:43:17 volumio volumio[1181]: verbose: ControllerMpd::sendMpdCommand status Dec 30 15:43:17 volumio volumio[1181]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 30 15:43:17 volumio volumio[1181]: info: sendMpdCommand status took 2 milliseconds Dec 30 15:43:17 volumio volumio[1181]: info: sendMpdCommand status took 1 milliseconds Dec 30 15:43:17 volumio volumio[1181]: verbose: ControllerMpd::parseTrackInfo Dec 30 15:43:17 volumio volumio[1181]: verbose: ControllerMpd::parseState Dec 30 15:43:17 volumio volumio[1181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 15:43:17 volumio volumio[1181]: verbose: ControllerMpd::parseState Dec 30 15:43:17 volumio volumio[1181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 15:43:17 volumio volumio[1181]: info: ControllerMpd::pushState Dec 30 15:43:17 volumio volumio[1181]: info: CoreCommandRouter::servicePushState Dec 30 15:43:17 volumio volumio[1181]: info: CoreStateMachine::pushState Dec 30 15:43:17 volumio volumio[1181]: info: CorePlayQueue::getTrack 0 Dec 30 15:43:17 volumio volumio[1181]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 15:43:17 volumio volumio[1181]: info: CoreCommandRouter::volumioPushState Dec 30 15:43:17 volumio volumio[1181]: info: CorePlayQueue::getTrack 0 Dec 30 15:43:17 volumio volumio[1181]: 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":"mpeg,pn=MP3,ext=.mp3","artist":null,"album":null,"uri":"http://192.168.0.58:47820/MediaItems/A0$128$146$65813$159645973$159711509$159777045$1584922884.mp3?type=1,client=33,mime=audio/mpeg,pn=MP3,ext=.mp3","trackType":"mp3"} Dec 30 15:43:17 volumio volumio[1181]: verbose: CURRENT POSITION 0 Dec 30 15:43:17 volumio volumio[1181]: info: CoreStateMachine::syncState stateService stop Dec 30 15:43:17 volumio volumio[1181]: info: CoreStateMachine::syncState currentStatus stop Dec 30 15:43:17 volumio volumio[1181]: info: CoreStateMachine::pushState Dec 30 15:43:17 volumio volumio[1181]: info: CorePlayQueue::getTrack 0 Dec 30 15:43:17 volumio volumio[1181]: info: CoreCommandRouter::volumioPushState Dec 30 15:43:17 volumio volumio[1181]: info: No code Dec 30 15:43:17 volumio volumio[1181]: info: CoreStateMachine::pushState Dec 30 15:43:17 volumio volumio[1181]: info: CorePlayQueue::getTrack 0 Dec 30 15:43:17 volumio volumio[1181]: info: CoreCommandRouter::volumioPushState Dec 30 15:43:17 volumio volumio[1181]: info: ------------------------------ 20ms Dec 30 15:43:17 volumio volumio[1181]: info: sendMpdCommand playlistinfo took 18 milliseconds Dec 30 15:43:17 volumio volumio[1181]: info: sendMpdCommand playlistinfo took 17 milliseconds Dec 30 15:43:17 volumio volumio[1181]: verbose: ControllerMpd::parseTrackInfo Dec 30 15:43:17 volumio volumio[1181]: verbose: ControllerMpd::parseTrackInfo Dec 30 15:43:17 volumio volumio[1181]: info: ControllerMpd::pushState Dec 30 15:43:17 volumio volumio[1181]: info: CoreCommandRouter::servicePushState Dec 30 15:43:17 volumio volumio[1181]: info: CoreStateMachine::pushState Dec 30 15:43:17 volumio volumio[1181]: info: CorePlayQueue::getTrack 0 Dec 30 15:43:17 volumio volumio[1181]: info: CoreCommandRouter::volumioPushState Dec 30 15:43:17 volumio volumio[1181]: info: CorePlayQueue::getTrack 0 Dec 30 15:43:17 volumio volumio[1181]: 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":"mpeg,pn=MP3,ext=.mp3","artist":null,"album":null,"uri":"http://192.168.0.58:47820/MediaItems/A0$128$146$65813$159645973$159711509$159777045$1584922884.mp3?type=1,client=33,mime=audio/mpeg,pn=MP3,ext=.mp3","trackType":"mp3"} Dec 30 15:43:17 volumio volumio[1181]: verbose: CURRENT POSITION 0 Dec 30 15:43:17 volumio volumio[1181]: info: CoreStateMachine::syncState stateService stop Dec 30 15:43:17 volumio volumio[1181]: info: CoreStateMachine::syncState currentStatus stop Dec 30 15:43:17 volumio volumio[1181]: info: CoreStateMachine::pushState Dec 30 15:43:17 volumio volumio[1181]: info: CorePlayQueue::getTrack 0 Dec 30 15:43:17 volumio volumio[1181]: info: CoreCommandRouter::volumioPushState Dec 30 15:43:17 volumio volumio[1181]: info: No code Dec 30 15:43:17 volumio volumio[1181]: info: CoreStateMachine::pushState Dec 30 15:43:17 volumio volumio[1181]: info: CorePlayQueue::getTrack 0 Dec 30 15:43:17 volumio volumio[1181]: info: CoreCommandRouter::volumioPushState Dec 30 15:43:17 volumio volumio[1181]: info: ControllerMpd::pushState Dec 30 15:43:17 volumio volumio[1181]: info: CoreCommandRouter::servicePushState Dec 30 15:43:17 volumio volumio[1181]: info: CoreStateMachine::pushState Dec 30 15:43:17 volumio volumio[1181]: info: CorePlayQueue::getTrack 0 Dec 30 15:43:17 volumio volumio[1181]: info: CoreCommandRouter::volumioPushState Dec 30 15:43:17 volumio volumio[1181]: info: CorePlayQueue::getTrack 0 Dec 30 15:43:17 volumio volumio[1181]: 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":"mpeg,pn=MP3,ext=.mp3","artist":null,"album":null,"uri":"http://192.168.0.58:47820/MediaItems/A0$128$146$65813$159645973$159711509$159777045$1584922884.mp3?type=1,client=33,mime=audio/mpeg,pn=MP3,ext=.mp3","trackType":"mp3"} Dec 30 15:43:17 volumio volumio[1181]: verbose: CURRENT POSITION 0 Dec 30 15:43:17 volumio volumio[1181]: info: CoreStateMachine::syncState stateService stop Dec 30 15:43:17 volumio volumio[1181]: info: CoreStateMachine::syncState currentStatus stop Dec 30 15:43:17 volumio volumio[1181]: info: CoreStateMachine::pushState Dec 30 15:43:17 volumio volumio[1181]: info: CorePlayQueue::getTrack 0 Dec 30 15:43:17 volumio volumio[1181]: info: CoreCommandRouter::volumioPushState Dec 30 15:43:17 volumio volumio[1181]: info: No code Dec 30 15:43:17 volumio volumio[1181]: info: CoreStateMachine::pushState Dec 30 15:43:17 volumio volumio[1181]: info: CorePlayQueue::getTrack 0 Dec 30 15:43:17 volumio volumio[1181]: info: CoreCommandRouter::volumioPushState Dec 30 15:43:17 volumio volumio[1181]: info: ------------------------------ 46ms Dec 30 15:43:17 volumio volumio[1181]: info: ------------------------------ 46ms Dec 30 15:43:17 volumio volumio[1181]: info: CoreCommandRouter::volumioPushQueue Dec 30 15:43:17 volumio volumio[1181]: info: CorePlayQueue::saveQueue Dec 30 15:43:17 volumio volumio[1181]: info: CoreStateMachine::updateTrackBlock Dec 30 15:43:17 volumio volumio[1181]: info: CorePlayQueue::getTrackBlock Dec 30 15:43:17 volumio volumio[1181]: info: CoreCommandRouter::volumioPlay Dec 30 15:43:17 volumio volumio[1181]: info: CoreStateMachine::play index 0 Dec 30 15:43:17 volumio volumio[1181]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 15:43:17 volumio volumio[1181]: info: CoreStateMachine::stop Dec 30 15:43:17 volumio volumio[1181]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 15:43:17 volumio volumio[1181]: info: CoreStateMachine::play index undefined Dec 30 15:43:17 volumio volumio[1181]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 15:43:17 volumio volumio[1181]: info: CorePlayQueue::getTrack 0 Dec 30 15:43:17 volumio volumio[1181]: info: CoreStateMachine::startPlaybackTimer Dec 30 15:43:17 volumio volumio[1181]: info: CorePlayQueue::getTrack 0 Dec 30 15:43:17 volumio volumio[1181]: info: [1767105797703] ControllerUPNPBrowser::clearAddPlayTrack Dec 30 15:43:17 volumio volumio[1181]: verbose: ControllerMpd::sendMpdCommand stop Dec 30 15:43:17 volumio volumio[1181]: info: sendMpdCommand stop took 5 milliseconds Dec 30 15:43:17 volumio volumio[1181]: verbose: ControllerMpd::sendMpdCommand clear Dec 30 15:43:17 volumio volumio[1181]: info: Dec 30 15:43:17 volumio volumio[1181]: ---------------------------- MPD announces system playlist update Dec 30 15:43:17 volumio volumio[1181]: info: Ignoring MPD Status Update Dec 30 15:43:17 volumio volumio[1181]: info: sendMpdCommand clear took 2 milliseconds Dec 30 15:43:17 volumio volumio[1181]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.0.58:47820/MediaItems/A0$128$146$65813$159645973$159711509$159842581$1585709316.mp3?type=1,client=33,mime=audio/mpeg,pn=MP3,ext=.mp3" Dec 30 15:43:17 volumio volumio[1181]: info: Dec 30 15:43:17 volumio volumio[1181]: ---------------------------- MPD announces system playlist update Dec 30 15:43:17 volumio volumio[1181]: info: Ignoring MPD Status Update Dec 30 15:43:17 volumio volumio[1181]: info: Dec 30 15:43:17 volumio volumio[1181]: ---------------------------- MPD announces system playlist update Dec 30 15:43:17 volumio volumio[1181]: info: Ignoring MPD Status Update Dec 30 15:43:17 volumio volumio[1181]: error: updateQueue error: null Dec 30 15:43:17 volumio volumio[1181]: info: ------------------------------ 4ms Dec 30 15:43:17 volumio volumio[1181]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.0.58:47820/MediaItems/A0$128$146$65813$159645973$159711509$159842581$1585709316.mp3?type=1,client=33,mime=audio/mpeg,pn=MP3,ext=.mp3" Dec 30 15:43:17 volumio volumio[1181]: error: updateQueue error: null Dec 30 15:43:17 volumio volumio[1181]: error: updateQueue error: null Dec 30 15:43:17 volumio volumio[1181]: info: Dec 30 15:43:17 volumio volumio[1181]: ---------------------------- MPD announces system playlist update Dec 30 15:43:17 volumio volumio[1181]: info: Ignoring MPD Status Update Dec 30 15:43:17 volumio volumio[1181]: info: ------------------------------ 93ms Dec 30 15:43:17 volumio volumio[1181]: info: ------------------------------ 92ms Dec 30 15:43:17 volumio volumio[1181]: info: sendMpdCommand add "http://192.168.0.58:47820/MediaItems/A0$128$146$65813$159645973$159711509$159842581$1585709316.mp3?type=1,client=33,mime=audio/mpeg,pn=MP3,ext=.mp3" took 1 milliseconds Dec 30 15:43:17 volumio volumio[1181]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 30 15:43:17 volumio volumio[1181]: verbose: ControllerMpd::sendMpdCommand play Dec 30 15:43:17 volumio volumio[1181]: info: Dec 30 15:43:17 volumio volumio[1181]: ---------------------------- MPD announces system playlist update Dec 30 15:43:17 volumio volumio[1181]: info: Ignoring MPD Status Update Dec 30 15:43:17 volumio volumio[1181]: info: Dec 30 15:43:17 volumio volumio[1181]: ---------------------------- MPD announces system playlist update Dec 30 15:43:17 volumio volumio[1181]: info: Ignoring MPD Status Update Dec 30 15:43:17 volumio volumio[1181]: info: ------------------------------ 3ms Dec 30 15:43:17 volumio volumio[1181]: info: sendMpdCommand play took 2 milliseconds Dec 30 15:43:17 volumio volumio[1181]: info: ------------------------------ 2ms Dec 30 15:43:17 volumio volumio[1181]: info: ------------------------------ 1ms Dec 30 15:43:17 volumio volumio[1181]: info: Dec 30 15:43:17 volumio volumio[1181]: ---------------------------- MPD announces state update: player Dec 30 15:43:17 volumio volumio[1181]: info: ControllerMpd::getState Dec 30 15:43:17 volumio volumio[1181]: verbose: ControllerMpd::sendMpdCommand status Dec 30 15:43:17 volumio volumio[1181]: info: Dec 30 15:43:17 volumio volumio[1181]: ---------------------------- MPD announces state update: player Dec 30 15:43:17 volumio volumio[1181]: info: ControllerMpd::getState Dec 30 15:43:17 volumio volumio[1181]: verbose: ControllerMpd::sendMpdCommand status Dec 30 15:43:17 volumio volumio[1181]: info: Dec 30 15:43:17 volumio volumio[1181]: ---------------------------- MPD announces state update: player Dec 30 15:43:17 volumio volumio[1181]: info: ControllerMpd::getState Dec 30 15:43:17 volumio volumio[1181]: verbose: ControllerMpd::sendMpdCommand status Dec 30 15:43:17 volumio volumio[1181]: info: sendMpdCommand status took 15 milliseconds Dec 30 15:43:17 volumio volumio[1181]: verbose: ControllerMpd::parseState Dec 30 15:43:17 volumio volumio[1181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 15:43:17 volumio volumio[1181]: info: Dec 30 15:43:17 volumio volumio[1181]: ---------------------------- MPD announces state update: player Dec 30 15:43:17 volumio volumio[1181]: info: ControllerMpd::getState Dec 30 15:43:17 volumio volumio[1181]: verbose: ControllerMpd::sendMpdCommand status Dec 30 15:43:17 volumio volumio[1181]: info: Dec 30 15:43:17 volumio volumio[1181]: ---------------------------- MPD announces state update: player Dec 30 15:43:17 volumio volumio[1181]: info: sendMpdCommand status took 17 milliseconds Dec 30 15:43:17 volumio volumio[1181]: info: ControllerMpd::getState Dec 30 15:43:17 volumio volumio[1181]: verbose: ControllerMpd::sendMpdCommand status Dec 30 15:43:17 volumio volumio[1181]: info: sendMpdCommand status took 15 milliseconds Dec 30 15:43:17 volumio volumio[1181]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 30 15:43:17 volumio volumio[1181]: verbose: ControllerMpd::parseState Dec 30 15:43:17 volumio volumio[1181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 15:43:17 volumio volumio[1181]: verbose: ControllerMpd::parseState Dec 30 15:43:17 volumio volumio[1181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 15:43:17 volumio volumio[1181]: verbose: ControllerMpd::parseTrackInfo Dec 30 15:43:17 volumio volumio[1181]: info: ControllerMpd::pushState Dec 30 15:43:17 volumio volumio[1181]: info: CoreCommandRouter::servicePushState Dec 30 15:43:17 volumio volumio[1181]: info: CorePlayQueue::getTrack 0 Dec 30 15:43:17 volumio volumio[1181]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":130,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mpeg,pn=MP3,ext=.mp3","artist":null,"album":null,"uri":"http://192.168.0.58:47820/MediaItems/A0$128$146$65813$159645973$159711509$159842581$1585709316.mp3?type=1,client=33,mime=audio/mpeg,pn=MP3,ext=.mp3","trackType":"mp3"} Dec 30 15:43:17 volumio volumio[1181]: verbose: CURRENT POSITION 0 Dec 30 15:43:17 volumio volumio[1181]: info: CoreStateMachine::syncState stateService play Dec 30 15:43:17 volumio volumio[1181]: info: CoreStateMachine::syncState currentStatus stop Dec 30 15:43:17 volumio volumio[1181]: info: ------------------------------ 21ms Dec 30 15:43:17 volumio volumio[1181]: info: Dec 30 15:43:17 volumio volumio[1181]: ---------------------------- MPD announces state update: player Dec 30 15:43:17 volumio volumio[1181]: info: ControllerMpd::getState Dec 30 15:43:17 volumio volumio[1181]: verbose: ControllerMpd::sendMpdCommand status Dec 30 15:43:17 volumio volumio[1181]: info: sendMpdCommand status took 5 milliseconds Dec 30 15:43:17 volumio volumio[1181]: info: sendMpdCommand status took 4 milliseconds Dec 30 15:43:17 volumio volumio[1181]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 30 15:43:17 volumio volumio[1181]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 30 15:43:17 volumio volumio[1181]: info: sendMpdCommand status took 2 milliseconds Dec 30 15:43:17 volumio volumio[1181]: verbose: ControllerMpd::parseState Dec 30 15:43:17 volumio volumio[1181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 15:43:17 volumio volumio[1181]: verbose: ControllerMpd::parseState Dec 30 15:43:17 volumio volumio[1181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 15:43:17 volumio volumio[1181]: verbose: ControllerMpd::parseTrackInfo Dec 30 15:43:17 volumio volumio[1181]: verbose: ControllerMpd::parseTrackInfo Dec 30 15:43:17 volumio volumio[1181]: verbose: ControllerMpd::parseState Dec 30 15:43:17 volumio volumio[1181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 15:43:17 volumio volumio[1181]: info: ControllerMpd::pushState Dec 30 15:43:17 volumio volumio[1181]: info: CoreCommandRouter::servicePushState Dec 30 15:43:17 volumio volumio[1181]: info: CorePlayQueue::getTrack 0 Dec 30 15:43:17 volumio volumio[1181]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":130,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mpeg,pn=MP3,ext=.mp3","artist":null,"album":null,"uri":"http://192.168.0.58:47820/MediaItems/A0$128$146$65813$159645973$159711509$159842581$1585709316.mp3?type=1,client=33,mime=audio/mpeg,pn=MP3,ext=.mp3","trackType":"mp3"} Dec 30 15:43:17 volumio volumio[1181]: verbose: CURRENT POSITION 0 Dec 30 15:43:17 volumio volumio[1181]: info: CoreStateMachine::syncState stateService play Dec 30 15:43:17 volumio volumio[1181]: info: CoreStateMachine::syncState currentStatus play Dec 30 15:43:17 volumio volumio[1181]: info: Received an update from plugin. extracting info from payload Dec 30 15:43:17 volumio volumio[1181]: info: CoreStateMachine::pushState Dec 30 15:43:17 volumio volumio[1181]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 15:43:17 volumio volumio[1181]: info: CoreCommandRouter::volumioPushState Dec 30 15:43:17 volumio volumio[1181]: info: CoreStateMachine::pushState Dec 30 15:43:17 volumio volumio[1181]: info: CoreCommandRouter::volumioPushState Dec 30 15:43:17 volumio volumio[1181]: info: ControllerMpd::pushState Dec 30 15:43:17 volumio volumio[1181]: info: CoreCommandRouter::servicePushState Dec 30 15:43:17 volumio volumio[1181]: info: CorePlayQueue::getTrack 0 Dec 30 15:43:17 volumio volumio[1181]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":130,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mpeg,pn=MP3,ext=.mp3","artist":null,"album":null,"uri":"http://192.168.0.58:47820/MediaItems/A0$128$146$65813$159645973$159711509$159842581$1585709316.mp3?type=1,client=33,mime=audio/mpeg,pn=MP3,ext=.mp3","trackType":"mp3"} Dec 30 15:43:17 volumio volumio[1181]: verbose: CURRENT POSITION 0 Dec 30 15:43:17 volumio volumio[1181]: info: CoreStateMachine::syncState stateService play Dec 30 15:43:17 volumio volumio[1181]: info: CoreStateMachine::syncState currentStatus play Dec 30 15:43:17 volumio volumio[1181]: info: Received an update from plugin. extracting info from payload Dec 30 15:43:17 volumio volumio[1181]: info: CoreStateMachine::pushState Dec 30 15:43:17 volumio volumio[1181]: info: CoreCommandRouter::volumioPushState Dec 30 15:43:17 volumio volumio[1181]: info: CoreStateMachine::pushState Dec 30 15:43:17 volumio volumio[1181]: info: CoreCommandRouter::volumioPushState Dec 30 15:43:17 volumio volumio[1181]: info: ------------------------------ 43ms Dec 30 15:43:17 volumio volumio[1181]: info: ------------------------------ 43ms Dec 30 15:43:17 volumio volumio[1181]: info: Dec 30 15:43:17 volumio volumio[1181]: ---------------------------- MPD announces system playlist update Dec 30 15:43:17 volumio volumio[1181]: info: Ignoring MPD Status Update Dec 30 15:43:17 volumio volumio[1181]: info: Dec 30 15:43:17 volumio volumio[1181]: ---------------------------- MPD announces state update: player Dec 30 15:43:17 volumio volumio[1181]: info: sendMpdCommand playlistinfo took 23 milliseconds Dec 30 15:43:17 volumio volumio[1181]: info: ControllerMpd::getState Dec 30 15:43:17 volumio volumio[1181]: verbose: ControllerMpd::sendMpdCommand status Dec 30 15:43:17 volumio volumio[1181]: info: sendMpdCommand playlistinfo took 24 milliseconds Dec 30 15:43:17 volumio volumio[1181]: info: sendMpdCommand playlistinfo took 23 milliseconds Dec 30 15:43:17 volumio volumio[1181]: verbose: ControllerMpd::parseTrackInfo Dec 30 15:43:17 volumio volumio[1181]: verbose: ControllerMpd::parseTrackInfo Dec 30 15:43:17 volumio volumio[1181]: verbose: ControllerMpd::parseTrackInfo Dec 30 15:43:17 volumio volumio[1181]: info: ControllerMpd::pushState Dec 30 15:43:17 volumio volumio[1181]: info: CoreCommandRouter::servicePushState Dec 30 15:43:17 volumio volumio[1181]: info: CorePlayQueue::getTrack 0 Dec 30 15:43:17 volumio volumio[1181]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":130,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Betty Lou","artist":"The Kentucky Boys","album":"At The Rockabilly-Bop-Night","uri":"http://192.168.0.58:47820/MediaItems/A0$128$146$65813$159645973$159711509$159842581$1585709316.mp3?type=1,client=33,mime=audio/mpeg,pn=MP3,ext=.mp3","trackType":"mp3"} Dec 30 15:43:17 volumio volumio[1181]: verbose: CURRENT POSITION 0 Dec 30 15:43:17 volumio volumio[1181]: info: CoreStateMachine::syncState stateService play Dec 30 15:43:17 volumio volumio[1181]: info: CoreStateMachine::syncState currentStatus play Dec 30 15:43:17 volumio volumio[1181]: info: Received an update from plugin. extracting info from payload Dec 30 15:43:17 volumio volumio[1181]: info: CoreStateMachine::pushState Dec 30 15:43:17 volumio volumio[1181]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 15:43:17 volumio volumio[1181]: info: CoreCommandRouter::volumioPushState Dec 30 15:43:17 volumio volumio[1181]: info: CoreStateMachine::pushState Dec 30 15:43:17 volumio volumio[1181]: info: CoreCommandRouter::volumioPushState Dec 30 15:43:17 volumio volumio[1181]: info: ControllerMpd::pushState Dec 30 15:43:17 volumio volumio[1181]: info: CoreCommandRouter::servicePushState Dec 30 15:43:17 volumio volumio[1181]: info: CorePlayQueue::getTrack 0 Dec 30 15:43:17 volumio volumio[1181]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":130,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Betty Lou","artist":"The Kentucky Boys","album":"At The Rockabilly-Bop-Night","uri":"http://192.168.0.58:47820/MediaItems/A0$128$146$65813$159645973$159711509$159842581$1585709316.mp3?type=1,client=33,mime=audio/mpeg,pn=MP3,ext=.mp3","trackType":"mp3"} Dec 30 15:43:17 volumio volumio[1181]: verbose: CURRENT POSITION 0 Dec 30 15:43:17 volumio volumio[1181]: info: CoreStateMachine::syncState stateService play Dec 30 15:43:17 volumio volumio[1181]: info: CoreStateMachine::syncState currentStatus play Dec 30 15:43:17 volumio volumio[1181]: info: Received an update from plugin. extracting info from payload Dec 30 15:43:17 volumio volumio[1181]: info: CoreStateMachine::pushState Dec 30 15:43:17 volumio volumio[1181]: info: CoreCommandRouter::volumioPushState Dec 30 15:43:17 volumio volumio[1181]: info: CoreStateMachine::pushState Dec 30 15:43:17 volumio volumio[1181]: info: CoreCommandRouter::volumioPushState Dec 30 15:43:17 volumio volumio[1181]: info: ControllerMpd::pushState Dec 30 15:43:17 volumio volumio[1181]: info: CoreCommandRouter::servicePushState Dec 30 15:43:17 volumio volumio[1181]: info: CorePlayQueue::getTrack 0 Dec 30 15:43:17 volumio volumio[1181]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":130,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Betty Lou","artist":"The Kentucky Boys","album":"At The Rockabilly-Bop-Night","uri":"http://192.168.0.58:47820/MediaItems/A0$128$146$65813$159645973$159711509$159842581$1585709316.mp3?type=1,client=33,mime=audio/mpeg,pn=MP3,ext=.mp3","trackType":"mp3"} Dec 30 15:43:17 volumio volumio[1181]: verbose: CURRENT POSITION 0 Dec 30 15:43:17 volumio volumio[1181]: info: CoreStateMachine::syncState stateService play Dec 30 15:43:17 volumio volumio[1181]: info: CoreStateMachine::syncState currentStatus play Dec 30 15:43:17 volumio volumio[1181]: info: Received an update from plugin. extracting info from payload Dec 30 15:43:17 volumio volumio[1181]: info: CoreStateMachine::pushState Dec 30 15:43:17 volumio volumio[1181]: info: CoreCommandRouter::volumioPushState Dec 30 15:43:17 volumio volumio[1181]: info: CoreStateMachine::pushState Dec 30 15:43:17 volumio volumio[1181]: info: CoreCommandRouter::volumioPushState Dec 30 15:43:17 volumio volumio[1181]: info: ------------------------------ 58ms Dec 30 15:43:17 volumio volumio[1181]: info: ------------------------------ 58ms Dec 30 15:43:17 volumio volumio[1181]: info: ------------------------------ 55ms Dec 30 15:43:17 volumio volumio[1181]: info: Dec 30 15:43:17 volumio volumio[1181]: ---------------------------- MPD announces system playlist update Dec 30 15:43:17 volumio volumio[1181]: info: Ignoring MPD Status Update Dec 30 15:43:17 volumio volumio[1181]: info: Dec 30 15:43:17 volumio volumio[1181]: ---------------------------- MPD announces system playlist update Dec 30 15:43:17 volumio volumio[1181]: info: Ignoring MPD Status Update Dec 30 15:43:17 volumio volumio[1181]: info: CoreCommandRouter::volumioGetQueue Dec 30 15:43:17 volumio volumio[1181]: info: CoreStateMachine::getQueue Dec 30 15:43:17 volumio volumio[1181]: info: CorePlayQueue::getQueue Dec 30 15:43:17 volumio volumio[1181]: info: CoreCommandRouter::volumioGetQueue Dec 30 15:43:17 volumio volumio[1181]: info: CoreStateMachine::getQueue Dec 30 15:43:17 volumio volumio[1181]: info: CorePlayQueue::getQueue Dec 30 15:43:17 volumio volumio[1181]: info: CoreCommandRouter::volumioGetQueue Dec 30 15:43:17 volumio volumio[1181]: info: CoreStateMachine::getQueue Dec 30 15:43:17 volumio volumio[1181]: info: CorePlayQueue::getQueue Dec 30 15:43:17 volumio volumio[1181]: info: CoreCommandRouter::volumioGetQueue Dec 30 15:43:17 volumio volumio[1181]: info: CoreStateMachine::getQueue Dec 30 15:43:17 volumio volumio[1181]: info: CorePlayQueue::getQueue Dec 30 15:43:17 volumio volumio[1181]: info: CoreCommandRouter::volumioGetQueue Dec 30 15:43:17 volumio volumio[1181]: info: CoreStateMachine::getQueue Dec 30 15:43:17 volumio volumio[1181]: info: CorePlayQueue::getQueue Dec 30 15:43:17 volumio volumio[1181]: info: CoreCommandRouter::volumioGetQueue Dec 30 15:43:17 volumio volumio[1181]: info: CoreStateMachine::getQueue Dec 30 15:43:17 volumio volumio[1181]: info: CorePlayQueue::getQueue Dec 30 15:43:17 volumio volumio[1181]: info: ------------------------------ 58ms Dec 30 15:43:17 volumio volumio[1181]: info: sendMpdCommand status took 57 milliseconds Dec 30 15:43:17 volumio volumio[1181]: info: ------------------------------ 28ms Dec 30 15:43:17 volumio volumio[1181]: info: ------------------------------ 27ms Dec 30 15:43:17 volumio volumio[1181]: verbose: ControllerMpd::parseState Dec 30 15:43:17 volumio volumio[1181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 15:43:17 volumio volumio[1181]: info: Dec 30 15:43:17 volumio volumio[1181]: ---------------------------- MPD announces state update: player Dec 30 15:43:17 volumio volumio[1181]: info: ControllerMpd::getState Dec 30 15:43:17 volumio volumio[1181]: verbose: ControllerMpd::sendMpdCommand status Dec 30 15:43:17 volumio volumio[1181]: info: Dec 30 15:43:17 volumio volumio[1181]: ---------------------------- MPD announces state update: player Dec 30 15:43:17 volumio volumio[1181]: info: ControllerMpd::getState Dec 30 15:43:17 volumio volumio[1181]: verbose: ControllerMpd::sendMpdCommand status Dec 30 15:43:17 volumio volumio[1181]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 30 15:43:17 volumio volumio[1181]: info: sendMpdCommand status took 2 milliseconds Dec 30 15:43:17 volumio volumio[1181]: info: sendMpdCommand status took 1 milliseconds Dec 30 15:43:17 volumio volumio[1181]: verbose: ControllerMpd::parseTrackInfo Dec 30 15:43:17 volumio volumio[1181]: verbose: ControllerMpd::parseState Dec 30 15:43:17 volumio volumio[1181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 15:43:17 volumio volumio[1181]: verbose: ControllerMpd::parseState Dec 30 15:43:17 volumio volumio[1181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 15:43:17 volumio volumio[1181]: info: ControllerMpd::pushState Dec 30 15:43:17 volumio volumio[1181]: info: CoreCommandRouter::servicePushState Dec 30 15:43:17 volumio volumio[1181]: info: CorePlayQueue::getTrack 0 Dec 30 15:43:17 volumio volumio[1181]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":130,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Betty Lou","artist":"The Kentucky Boys","album":"At The Rockabilly-Bop-Night","uri":"http://192.168.0.58:47820/MediaItems/A0$128$146$65813$159645973$159711509$159842581$1585709316.mp3?type=1,client=33,mime=audio/mpeg,pn=MP3,ext=.mp3","trackType":"mp3"} Dec 30 15:43:17 volumio volumio[1181]: verbose: CURRENT POSITION 0 Dec 30 15:43:17 volumio volumio[1181]: info: CoreStateMachine::syncState stateService play Dec 30 15:43:17 volumio volumio[1181]: info: CoreStateMachine::syncState currentStatus play Dec 30 15:43:17 volumio volumio[1181]: info: Received an update from plugin. extracting info from payload Dec 30 15:43:17 volumio volumio[1181]: info: CoreStateMachine::pushState Dec 30 15:43:17 volumio volumio[1181]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 15:43:17 volumio volumio[1181]: info: CoreCommandRouter::volumioPushState Dec 30 15:43:17 volumio volumio[1181]: info: CoreStateMachine::pushState Dec 30 15:43:17 volumio volumio[1181]: info: CoreCommandRouter::volumioPushState Dec 30 15:43:17 volumio volumio[1181]: info: ------------------------------ 71ms Dec 30 15:43:17 volumio volumio[1181]: info: sendMpdCommand playlistinfo took 11 milliseconds Dec 30 15:43:17 volumio volumio[1181]: info: sendMpdCommand playlistinfo took 12 milliseconds Dec 30 15:43:17 volumio volumio[1181]: verbose: ControllerMpd::parseTrackInfo Dec 30 15:43:17 volumio volumio[1181]: verbose: ControllerMpd::parseTrackInfo Dec 30 15:43:17 volumio volumio[1181]: info: ControllerMpd::pushState Dec 30 15:43:17 volumio volumio[1181]: info: CoreCommandRouter::servicePushState Dec 30 15:43:17 volumio volumio[1181]: info: CorePlayQueue::getTrack 0 Dec 30 15:43:17 volumio volumio[1181]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":130,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Betty Lou","artist":"The Kentucky Boys","album":"At The Rockabilly-Bop-Night","uri":"http://192.168.0.58:47820/MediaItems/A0$128$146$65813$159645973$159711509$159842581$1585709316.mp3?type=1,client=33,mime=audio/mpeg,pn=MP3,ext=.mp3","trackType":"mp3"} Dec 30 15:43:17 volumio volumio[1181]: verbose: CURRENT POSITION 0 Dec 30 15:43:17 volumio volumio[1181]: info: CoreStateMachine::syncState stateService play Dec 30 15:43:17 volumio volumio[1181]: info: CoreStateMachine::syncState currentStatus play Dec 30 15:43:17 volumio volumio[1181]: info: Received an update from plugin. extracting info from payload Dec 30 15:43:17 volumio volumio[1181]: info: CoreStateMachine::pushState Dec 30 15:43:17 volumio volumio[1181]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 15:43:17 volumio volumio[1181]: info: CoreCommandRouter::volumioPushState Dec 30 15:43:17 volumio volumio[1181]: info: CoreStateMachine::pushState Dec 30 15:43:17 volumio volumio[1181]: info: CoreCommandRouter::volumioPushState Dec 30 15:43:17 volumio volumio[1181]: info: ControllerMpd::pushState Dec 30 15:43:17 volumio volumio[1181]: info: CoreCommandRouter::servicePushState Dec 30 15:43:17 volumio volumio[1181]: info: CorePlayQueue::getTrack 0 Dec 30 15:43:17 volumio volumio[1181]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":130,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Betty Lou","artist":"The Kentucky Boys","album":"At The Rockabilly-Bop-Night","uri":"http://192.168.0.58:47820/MediaItems/A0$128$146$65813$159645973$159711509$159842581$1585709316.mp3?type=1,client=33,mime=audio/mpeg,pn=MP3,ext=.mp3","trackType":"mp3"} Dec 30 15:43:17 volumio volumio[1181]: verbose: CURRENT POSITION 0 Dec 30 15:43:17 volumio volumio[1181]: info: CoreStateMachine::syncState stateService play Dec 30 15:43:17 volumio volumio[1181]: info: CoreStateMachine::syncState currentStatus play Dec 30 15:43:17 volumio volumio[1181]: info: Received an update from plugin. extracting info from payload Dec 30 15:43:17 volumio volumio[1181]: info: CoreStateMachine::pushState Dec 30 15:43:17 volumio volumio[1181]: info: CoreCommandRouter::volumioPushState Dec 30 15:43:17 volumio volumio[1181]: info: CoreStateMachine::pushState Dec 30 15:43:17 volumio volumio[1181]: info: CoreCommandRouter::volumioPushState Dec 30 15:43:17 volumio volumio[1181]: info: ------------------------------ 33ms Dec 30 15:43:17 volumio volumio[1181]: info: ------------------------------ 32ms Dec 30 15:43:17 volumio volumio[1181]: info: CoreCommandRouter::volumioGetQueue Dec 30 15:43:17 volumio volumio[1181]: info: CoreStateMachine::getQueue Dec 30 15:43:17 volumio volumio[1181]: info: CorePlayQueue::getQueue Dec 30 15:43:17 volumio volumio[1181]: info: CoreCommandRouter::volumioGetQueue Dec 30 15:43:17 volumio volumio[1181]: info: CoreStateMachine::getQueue Dec 30 15:43:17 volumio volumio[1181]: info: CorePlayQueue::getQueue Dec 30 15:43:17 volumio volumio[1181]: info: CoreCommandRouter::volumioGetQueue Dec 30 15:43:17 volumio volumio[1181]: info: CoreStateMachine::getQueue Dec 30 15:43:17 volumio volumio[1181]: info: CorePlayQueue::getQueue Dec 30 15:43:17 volumio volumio[1181]: info: CoreCommandRouter::volumioGetQueue Dec 30 15:43:17 volumio volumio[1181]: info: CoreStateMachine::getQueue Dec 30 15:43:17 volumio volumio[1181]: info: CorePlayQueue::getQueue Dec 30 15:43:17 volumio volumio[1181]: info: CoreCommandRouter::volumioGetQueue Dec 30 15:43:17 volumio volumio[1181]: info: CoreStateMachine::getQueue Dec 30 15:43:17 volumio volumio[1181]: info: CorePlayQueue::getQueue Dec 30 15:43:17 volumio volumio[1181]: info: CoreCommandRouter::volumioGetQueue Dec 30 15:43:17 volumio volumio[1181]: info: CoreStateMachine::getQueue Dec 30 15:43:17 volumio volumio[1181]: info: CorePlayQueue::getQueue Dec 30 15:43:20 volumio volumio[1181]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Dec 30 15:43:20 volumio volumio[1181]: info: Preload queue cleared Dec 30 15:43:20 volumio volumio[1181]: info: Preloading song: upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159908117$1586757892 Dec 30 15:43:20 volumio volumio[1181]: info: Preloading song: upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159908117$1586823428 Dec 30 15:43:20 volumio volumio[1181]: info: Preloading song: upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159908117$1586888964 Dec 30 15:43:20 volumio volumio[1181]: info: Preloading song: upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159908117$1586954500 Dec 30 15:43:20 volumio volumio[1181]: info: Preloading song: upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159908117$1587020036 Dec 30 15:43:20 volumio volumio[1181]: info: Preloading song: upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159908117$1587085572 Dec 30 15:43:20 volumio volumio[1181]: info: Preloading song: upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159908117$1587151108 Dec 30 15:43:20 volumio volumio[1181]: info: Preloading song: upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159908117$1587216644 Dec 30 15:43:20 volumio volumio[1181]: info: Preloading song: upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159908117$1587282180 Dec 30 15:43:20 volumio volumio[1181]: info: Preloading song: upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159908117$1587347716 Dec 30 15:43:20 volumio volumio[1181]: info: Preloading song: upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159908117$1587413252 Dec 30 15:43:20 volumio volumio[1181]: info: Preloading song: upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159908117$1587478788 Dec 30 15:43:20 volumio volumio[1181]: info: Preloading song: upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159908117$1587544324 Dec 30 15:43:20 volumio volumio[1181]: info: Preloading song: upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159908117$1587609860 Dec 30 15:43:20 volumio volumio[1181]: info: Preloading song: upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159908117$1587675396 Dec 30 15:43:20 volumio volumio[1181]: info: Exploding uri upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159908117$1586757892 in service upnp_browser Dec 30 15:43:21 volumio volumio[1181]: info: Exploding uri upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159908117$1586823428 in service upnp_browser Dec 30 15:43:21 volumio volumio[1181]: info: Exploding uri upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159908117$1586888964 in service upnp_browser Dec 30 15:43:21 volumio volumio[1181]: info: Exploding uri upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159908117$1586954500 in service upnp_browser Dec 30 15:43:21 volumio volumio[1181]: info: Exploding uri upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159908117$1587020036 in service upnp_browser Dec 30 15:43:21 volumio volumio[1181]: info: Exploding uri upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159908117$1587085572 in service upnp_browser Dec 30 15:43:21 volumio volumio[1181]: info: Exploding uri upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159908117$1587151108 in service upnp_browser Dec 30 15:43:21 volumio volumio[1181]: info: Exploding uri upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159908117$1587216644 in service upnp_browser Dec 30 15:43:21 volumio volumio[1181]: info: Exploding uri upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159908117$1587282180 in service upnp_browser Dec 30 15:43:21 volumio volumio[1181]: info: Exploding uri upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159908117$1587347716 in service upnp_browser Dec 30 15:43:21 volumio volumio[1181]: info: Exploding uri upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159908117$1587413252 in service upnp_browser Dec 30 15:43:21 volumio volumio[1181]: info: Exploding uri upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159908117$1587478788 in service upnp_browser Dec 30 15:43:21 volumio volumio[1181]: info: Exploding uri upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159908117$1587544324 in service upnp_browser Dec 30 15:43:21 volumio volumio[1181]: info: Exploding uri upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159908117$1587609860 in service upnp_browser Dec 30 15:43:21 volumio volumio[1181]: info: Exploding uri upnp/http://192.168.0.58:47820/ctl/ContentDir@A0$128$146$65813$159645973$159711509$159908117$1587675396 in service upnp_browser Dec 30 15:43:23 volumio volumio[1181]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Atomic%20Hi%20Tones/Ice%20Cold%20Baby/fecc8a4f-22b4-4369-bcdc-509a4277f73e.jpg' Dec 30 15:43:23 volumio volumio[1181]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Atomic%20Hi%20Tones/Ice%20Cold%20Baby/fecc8a4f-22b4-4369-bcdc-509a4277f73e.jpg' Dec 30 15:43:23 volumio volumio[1181]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Atomic%20Hi%20Tones/Ice%20Cold%20Baby/fecc8a4f-22b4-4369-bcdc-509a4277f73e.jpg' Dec 30 15:43:23 volumio volumio[1181]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Atomic%20Hi%20Tones/Ice%20Cold%20Baby/fecc8a4f-22b4-4369-bcdc-509a4277f73e.jpg' Dec 30 15:43:23 volumio volumio[1181]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Atomic%20Hi%20Tones/Ice%20Cold%20Baby/d2811513-0bc9-40ee-b0d2-cc45058285a0.jpg' Dec 30 15:43:24 volumio volumio[1181]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Atomic%20Hi%20Tones/Ice%20Cold%20Baby/d2811513-0bc9-40ee-b0d2-cc45058285a0.jpg' Dec 30 15:43:24 volumio volumio[1181]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Atomic%20Hi%20Tones/Ice%20Cold%20Baby/d2811513-0bc9-40ee-b0d2-cc45058285a0.jpg' Dec 30 15:43:24 volumio volumio[1181]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Atomic%20Hi%20Tones/Ice%20Cold%20Baby/d2811513-0bc9-40ee-b0d2-cc45058285a0.jpg' Dec 30 15:43:24 volumio volumio[1181]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Atomic%20Hi%20Tones/Ice%20Cold%20Baby/d2811513-0bc9-40ee-b0d2-cc45058285a0.jpg' Dec 30 15:43:24 volumio volumio[1181]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Atomic%20Hi%20Tones/Ice%20Cold%20Baby/d2811513-0bc9-40ee-b0d2-cc45058285a0.jpg' Dec 30 15:43:25 volumio volumio[1181]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Atomic%20Hi%20Tones/Ice%20Cold%20Baby/d2811513-0bc9-40ee-b0d2-cc45058285a0.jpg' Dec 30 15:43:26 volumio volumio[1181]: info: CoreCommandRouter::volumioGetState Dec 30 15:43:26 volumio volumio[1181]: info: Listing playlists Dec 30 15:43:26 volumio volumio[1181]: info: Listing playlists Dec 30 15:43:27 volumio volumio[1181]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Dec 30 15:43:27 volumio volumio[1181]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 30 15:43:27 volumio volumio[1181]: TypeError: Cannot read property 'length' of undefined Dec 30 15:43:27 volumio volumio[1181]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Dec 30 15:43:27 volumio volumio[1181]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Dec 30 15:43:27 volumio volumio[1181]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Dec 30 15:43:27 volumio volumio[1181]: at Parser.emit (events.js:315:20) Dec 30 15:43:27 volumio volumio[1181]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Dec 30 15:43:27 volumio volumio[1181]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Dec 30 15:43:27 volumio volumio[1181]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Dec 30 15:43:27 volumio volumio[1181]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Dec 30 15:43:27 volumio volumio[1181]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Dec 30 15:43:27 volumio volumio[1181]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Dec 30 15:43:27 volumio volumio[1181]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Dec 30 15:43:27 volumio volumio[1181]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Dec 30 15:43:27 volumio volumio[1181]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Dec 30 15:43:27 volumio volumio[1181]: at IncomingMessage.emit (events.js:327:22) Dec 30 15:43:27 volumio volumio[1181]: at endReadableNT (internal/streams/readable.js:1327:12) Dec 30 15:43:27 volumio volumio[1181]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Dec 30 15:43:27 volumio volumio[1181]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 30 15:43:28 volumio sudo[13712]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-30 15:42 Dec 30 15:43:28 volumio sudo[13712]: 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"