-- Logs begin at Tue 2025-06-10 21:36:24 UTC, end at Tue 2025-06-10 21:41:58 UTC. -- Jun 10 21:40:01 volumio volumio[910]: info: Preload queue cleared Jun 10 21:40:01 volumio volumio[910]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 10 21:40:01 volumio volumio[910]: info: CoreStateMachine::ClearQueue Jun 10 21:40:01 volumio volumio[910]: info: CoreStateMachine::stop Jun 10 21:40:01 volumio volumio[910]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 10 21:40:01 volumio volumio[910]: info: CoreStateMachine::stPlaybackTimer Jun 10 21:40:01 volumio volumio[910]: info: CoreStateMachine::updateTrackBlock Jun 10 21:40:01 volumio volumio[910]: info: CorePlayQueue::getTrackBlock Jun 10 21:40:01 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:40:01 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:40:01 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 21:40:01 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:40:01 volumio volumio[910]: info: CoreStateMachine::serviceStop Jun 10 21:40:01 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:40:01 volumio volumio[910]: info: CoreCommandRouter::serviceStop Jun 10 21:40:01 volumio volumio[910]: info: [1749591601069] ControllerUPNPBrowser::stop Jun 10 21:40:01 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand stop Jun 10 21:40:01 volumio volumio[910]: info: CorePlayQueue::clearPlayQueue Jun 10 21:40:01 volumio volumio[910]: info: CorePlayQueue::saveQueue Jun 10 21:40:01 volumio volumio[910]: info: CoreCommandRouter::volumioPushQueue Jun 10 21:40:01 volumio volumio[910]: info: CoreStateMachine::addQueueItems Jun 10 21:40:01 volumio volumio[910]: info: CorePlayQueue::addQueueItems Jun 10 21:40:01 volumio volumio[910]: info: Preload queue cleared Jun 10 21:40:01 volumio volumio[910]: info: Adding Item to queue: upnp/folder/http://192.168.1.55:8200/ctl/ContentDir@64$0$5$0 Jun 10 21:40:01 volumio volumio[910]: info: Exploding uri upnp/folder/http://192.168.1.55:8200/ctl/ContentDir@64$0$5$0 in service upnp_browser Jun 10 21:40:01 volumio volumio[910]: info: CoreCommandRouter::volumioPushQueue Jun 10 21:40:01 volumio volumio[910]: info: CorePlayQueue::saveQueue Jun 10 21:40:01 volumio volumio[910]: info: CoreStateMachine::updateTrackBlock Jun 10 21:40:01 volumio volumio[910]: info: CorePlayQueue::getTrackBlock Jun 10 21:40:01 volumio volumio[910]: info: CoreCommandRouter::volumioPlay Jun 10 21:40:01 volumio volumio[910]: info: CoreStateMachine::play index 0 Jun 10 21:40:01 volumio volumio[910]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 10 21:40:01 volumio volumio[910]: info: CoreStateMachine::stop Jun 10 21:40:01 volumio volumio[910]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 10 21:40:01 volumio volumio[910]: info: CoreStateMachine::play index undefined Jun 10 21:40:01 volumio volumio[910]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 10 21:40:01 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:40:01 volumio volumio[910]: info: CoreStateMachine::startPlaybackTimer Jun 10 21:40:01 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:40:01 volumio volumio[910]: info: [1749591601134] ControllerUPNPBrowser::clearAddPlayTrack Jun 10 21:40:01 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand stop Jun 10 21:40:01 volumio volumio[910]: info: Jun 10 21:40:01 volumio volumio[910]: ---------------------------- MPD announces state update: player Jun 10 21:40:01 volumio volumio[910]: info: sendMpdCommand stop took 71 milliseconds Jun 10 21:40:01 volumio volumio[910]: info: ControllerMpd::getState Jun 10 21:40:01 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Jun 10 21:40:01 volumio volumio[910]: info: sendMpdCommand stop took 7 milliseconds Jun 10 21:40:01 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand clear Jun 10 21:40:01 volumio volumio[910]: info: Jun 10 21:40:01 volumio volumio[910]: ---------------------------- MPD announces state update: player Jun 10 21:40:01 volumio volumio[910]: info: ControllerMpd::getState Jun 10 21:40:01 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Jun 10 21:40:01 volumio volumio[910]: info: Jun 10 21:40:01 volumio volumio[910]: ---------------------------- MPD announces state update: player Jun 10 21:40:01 volumio volumio[910]: info: ControllerMpd::getState Jun 10 21:40:01 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Jun 10 21:40:01 volumio volumio[910]: info: Jun 10 21:40:01 volumio volumio[910]: ---------------------------- MPD announces system playlist update Jun 10 21:40:01 volumio volumio[910]: info: Ignoring MPD Status Update Jun 10 21:40:01 volumio volumio[910]: info: sendMpdCommand status took 13 milliseconds Jun 10 21:40:01 volumio volumio[910]: info: sendMpdCommand clear took 11 milliseconds Jun 10 21:40:01 volumio volumio[910]: info: sendMpdCommand status took 9 milliseconds Jun 10 21:40:01 volumio volumio[910]: info: sendMpdCommand status took 7 milliseconds Jun 10 21:40:01 volumio volumio[910]: verbose: ControllerMpd::parseState Jun 10 21:40:01 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 10 21:40:01 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.55:8200/MediaItems/227.flac" Jun 10 21:40:01 volumio volumio[910]: verbose: ControllerMpd::parseState Jun 10 21:40:01 volumio volumio[910]: verbose: ControllerMpd::parseState Jun 10 21:40:01 volumio volumio[910]: info: ControllerMpd::pushState Jun 10 21:40:01 volumio volumio[910]: info: CoreCommandRouter::servicePushState Jun 10 21:40:01 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:40:01 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:40:01 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 21:40:01 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:40:01 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:40:01 volumio volumio[910]: 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} Jun 10 21:40:01 volumio volumio[910]: verbose: CURRENT POSITION 0 Jun 10 21:40:01 volumio volumio[910]: info: CoreStateMachine::syncState stateService stop Jun 10 21:40:01 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus stop Jun 10 21:40:01 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:40:01 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:40:01 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:40:01 volumio volumio[910]: info: No code Jun 10 21:40:01 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:40:01 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:40:01 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:40:01 volumio volumio[910]: info: ControllerMpd::pushState Jun 10 21:40:01 volumio volumio[910]: info: CoreCommandRouter::servicePushState Jun 10 21:40:01 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:40:01 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:40:01 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:40:01 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:40:01 volumio volumio[910]: 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} Jun 10 21:40:01 volumio volumio[910]: verbose: CURRENT POSITION 0 Jun 10 21:40:01 volumio volumio[910]: info: CoreStateMachine::syncState stateService stop Jun 10 21:40:01 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus stop Jun 10 21:40:01 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:40:01 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:40:01 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:40:01 volumio volumio[910]: info: No code Jun 10 21:40:01 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:40:01 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:40:01 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:40:01 volumio volumio[910]: info: ------------------------------ 134ms Jun 10 21:40:01 volumio volumio[910]: info: ------------------------------ 133ms Jun 10 21:40:01 volumio volumio[910]: info: Jun 10 21:40:01 volumio volumio[910]: ---------------------------- MPD announces system playlist update Jun 10 21:40:01 volumio volumio[910]: info: Ignoring MPD Status Update Jun 10 21:40:01 volumio volumio[910]: info: Jun 10 21:40:01 volumio volumio[910]: ---------------------------- MPD announces system playlist update Jun 10 21:40:01 volumio volumio[910]: info: Ignoring MPD Status Update Jun 10 21:40:01 volumio volumio[910]: error: updateQueue error: null Jun 10 21:40:01 volumio volumio[910]: error: updateQueue error: null Jun 10 21:40:01 volumio volumio[910]: error: updateQueue error: null Jun 10 21:40:01 volumio volumio[910]: info: ------------------------------ 149ms Jun 10 21:40:01 volumio volumio[910]: info: sendMpdCommand playlistinfo took 144 milliseconds Jun 10 21:40:01 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.55:8200/MediaItems/227.flac" Jun 10 21:40:01 volumio volumio[910]: info: ------------------------------ 19ms Jun 10 21:40:01 volumio volumio[910]: info: ------------------------------ 18ms Jun 10 21:40:01 volumio volumio[910]: verbose: ControllerMpd::parseTrackInfo Jun 10 21:40:01 volumio volumio[910]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 10 21:40:01 volumio volumio[910]: info: ------------------------------ 168ms Jun 10 21:40:01 volumio volumio[910]: info: Jun 10 21:40:01 volumio volumio[910]: ---------------------------- MPD announces system playlist update Jun 10 21:40:01 volumio volumio[910]: info: Ignoring MPD Status Update Jun 10 21:40:01 volumio volumio[910]: info: sendMpdCommand add "http://192.168.1.55:8200/MediaItems/227.flac" took 28 milliseconds Jun 10 21:40:01 volumio volumio[910]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 10 21:40:01 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand play Jun 10 21:40:01 volumio volumio[910]: info: Jun 10 21:40:01 volumio volumio[910]: ---------------------------- MPD announces system playlist update Jun 10 21:40:01 volumio volumio[910]: info: Ignoring MPD Status Update Jun 10 21:40:01 volumio volumio[910]: info: Jun 10 21:40:01 volumio volumio[910]: ---------------------------- MPD announces system playlist update Jun 10 21:40:01 volumio volumio[910]: info: Ignoring MPD Status Update Jun 10 21:40:01 volumio volumio[910]: info: ------------------------------ 12ms Jun 10 21:40:01 volumio volumio[910]: info: sendMpdCommand play took 9 milliseconds Jun 10 21:40:01 volumio volumio[910]: info: ------------------------------ 6ms Jun 10 21:40:01 volumio volumio[910]: info: ------------------------------ 5ms Jun 10 21:40:01 volumio volumio[910]: info: Jun 10 21:40:01 volumio volumio[910]: ---------------------------- MPD announces state update: player Jun 10 21:40:01 volumio volumio[910]: info: ControllerMpd::getState Jun 10 21:40:01 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Jun 10 21:40:01 volumio volumio[910]: info: Jun 10 21:40:01 volumio volumio[910]: ---------------------------- MPD announces state update: player Jun 10 21:40:01 volumio volumio[910]: info: ControllerMpd::getState Jun 10 21:40:01 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Jun 10 21:40:01 volumio volumio[910]: info: Jun 10 21:40:01 volumio volumio[910]: ---------------------------- MPD announces state update: player Jun 10 21:40:01 volumio volumio[910]: info: ControllerMpd::getState Jun 10 21:40:01 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Jun 10 21:40:01 volumio volumio[910]: info: Jun 10 21:40:01 volumio volumio[910]: ---------------------------- MPD announces state update: player Jun 10 21:40:01 volumio volumio[910]: info: ControllerMpd::getState Jun 10 21:40:01 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Jun 10 21:40:01 volumio volumio[910]: info: sendMpdCommand status took 12 milliseconds Jun 10 21:40:01 volumio volumio[910]: info: sendMpdCommand status took 9 milliseconds Jun 10 21:40:01 volumio volumio[910]: verbose: ControllerMpd::parseState Jun 10 21:40:01 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 10 21:40:01 volumio volumio[910]: verbose: ControllerMpd::parseState Jun 10 21:40:01 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 10 21:40:01 volumio volumio[910]: info: Jun 10 21:40:01 volumio volumio[910]: ---------------------------- MPD announces state update: player Jun 10 21:40:01 volumio volumio[910]: info: ControllerMpd::getState Jun 10 21:40:01 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Jun 10 21:40:01 volumio volumio[910]: info: Jun 10 21:40:01 volumio volumio[910]: ---------------------------- MPD announces state update: player Jun 10 21:40:01 volumio volumio[910]: info: ControllerMpd::getState Jun 10 21:40:01 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Jun 10 21:40:01 volumio volumio[910]: info: Jun 10 21:40:01 volumio volumio[910]: ---------------------------- MPD announces system playlist update Jun 10 21:40:01 volumio volumio[910]: info: Ignoring MPD Status Update Jun 10 21:40:01 volumio volumio[910]: info: Jun 10 21:40:01 volumio volumio[910]: ---------------------------- MPD announces state update: player Jun 10 21:40:01 volumio volumio[910]: info: sendMpdCommand status took 30 milliseconds Jun 10 21:40:01 volumio volumio[910]: info: sendMpdCommand status took 26 milliseconds Jun 10 21:40:01 volumio volumio[910]: info: ControllerMpd::getState Jun 10 21:40:01 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Jun 10 21:40:01 volumio volumio[910]: info: sendMpdCommand playlistinfo took 23 milliseconds Jun 10 21:40:01 volumio volumio[910]: info: sendMpdCommand playlistinfo took 22 milliseconds Jun 10 21:40:01 volumio volumio[910]: info: sendMpdCommand status took 17 milliseconds Jun 10 21:40:01 volumio volumio[910]: info: sendMpdCommand status took 12 milliseconds Jun 10 21:40:01 volumio volumio[910]: verbose: ControllerMpd::parseState Jun 10 21:40:01 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 10 21:40:01 volumio volumio[910]: verbose: ControllerMpd::parseState Jun 10 21:40:01 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 10 21:40:01 volumio volumio[910]: verbose: ControllerMpd::parseTrackInfo Jun 10 21:40:01 volumio volumio[910]: verbose: ControllerMpd::parseTrackInfo Jun 10 21:40:01 volumio volumio[910]: verbose: ControllerMpd::parseState Jun 10 21:40:01 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 10 21:40:01 volumio volumio[910]: verbose: ControllerMpd::parseState Jun 10 21:40:01 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 10 21:40:01 volumio volumio[910]: info: ControllerMpd::pushState Jun 10 21:40:01 volumio volumio[910]: info: CoreCommandRouter::servicePushState Jun 10 21:40:01 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:40:01 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":2596,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"227.flac","artist":"Secret Garden","album":"Songs from a Secret Garden","uri":"http://192.168.1.55:8200/MediaItems/227.flac","trackType":"flac"} Jun 10 21:40:01 volumio volumio[910]: verbose: CURRENT POSITION 0 Jun 10 21:40:01 volumio volumio[910]: info: CoreStateMachine::syncState stateService play Jun 10 21:40:01 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus stop Jun 10 21:40:01 volumio volumio[910]: info: ControllerMpd::pushState Jun 10 21:40:01 volumio volumio[910]: info: CoreCommandRouter::servicePushState Jun 10 21:40:01 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:40:01 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":2596,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"227.flac","artist":"Secret Garden","album":"Songs from a Secret Garden","uri":"http://192.168.1.55:8200/MediaItems/227.flac","trackType":"flac"} Jun 10 21:40:01 volumio volumio[910]: verbose: CURRENT POSITION 0 Jun 10 21:40:01 volumio volumio[910]: info: CoreStateMachine::syncState stateService play Jun 10 21:40:01 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus play Jun 10 21:40:01 volumio volumio[910]: info: Received an update from plugin. extracting info from payload Jun 10 21:40:01 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:40:01 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 21:40:01 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:40:01 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:40:01 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:40:01 volumio volumio[910]: info: ------------------------------ 83ms Jun 10 21:40:01 volumio volumio[910]: info: ------------------------------ 107ms Jun 10 21:40:01 volumio volumio[910]: info: Jun 10 21:40:01 volumio volumio[910]: ---------------------------- MPD announces system playlist update Jun 10 21:40:01 volumio volumio[910]: info: Ignoring MPD Status Update Jun 10 21:40:01 volumio volumio[910]: info: Jun 10 21:40:01 volumio volumio[910]: ---------------------------- MPD announces state update: player Jun 10 21:40:01 volumio volumio[910]: info: ControllerMpd::getState Jun 10 21:40:01 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Jun 10 21:40:01 volumio volumio[910]: info: Jun 10 21:40:01 volumio volumio[910]: ---------------------------- MPD announces system playlist update Jun 10 21:40:01 volumio volumio[910]: info: Ignoring MPD Status Update Jun 10 21:40:01 volumio volumio[910]: info: Jun 10 21:40:01 volumio volumio[910]: ---------------------------- MPD announces state update: player Jun 10 21:40:01 volumio volumio[910]: info: ControllerMpd::getState Jun 10 21:40:01 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Jun 10 21:40:01 volumio volumio[910]: info: ------------------------------ 91ms Jun 10 21:40:01 volumio volumio[910]: info: sendMpdCommand status took 87 milliseconds Jun 10 21:40:01 volumio volumio[910]: info: sendMpdCommand playlistinfo took 84 milliseconds Jun 10 21:40:01 volumio volumio[910]: info: sendMpdCommand playlistinfo took 84 milliseconds Jun 10 21:40:01 volumio volumio[910]: info: sendMpdCommand playlistinfo took 80 milliseconds Jun 10 21:40:01 volumio volumio[910]: info: sendMpdCommand playlistinfo took 79 milliseconds Jun 10 21:40:01 volumio volumio[910]: info: ------------------------------ 20ms Jun 10 21:40:01 volumio volumio[910]: info: sendMpdCommand status took 17 milliseconds Jun 10 21:40:01 volumio volumio[910]: info: ------------------------------ 15ms Jun 10 21:40:01 volumio volumio[910]: info: sendMpdCommand status took 13 milliseconds Jun 10 21:40:01 volumio volumio[910]: verbose: ControllerMpd::parseState Jun 10 21:40:01 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 10 21:40:01 volumio volumio[910]: verbose: ControllerMpd::parseTrackInfo Jun 10 21:40:01 volumio volumio[910]: verbose: ControllerMpd::parseTrackInfo Jun 10 21:40:01 volumio volumio[910]: verbose: ControllerMpd::parseTrackInfo Jun 10 21:40:01 volumio volumio[910]: verbose: ControllerMpd::parseTrackInfo Jun 10 21:40:01 volumio volumio[910]: verbose: ControllerMpd::parseState Jun 10 21:40:01 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 10 21:40:01 volumio volumio[910]: verbose: ControllerMpd::parseState Jun 10 21:40:01 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 10 21:40:01 volumio volumio[910]: info: ControllerMpd::pushState Jun 10 21:40:01 volumio volumio[910]: info: CoreCommandRouter::servicePushState Jun 10 21:40:01 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:40:01 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":2596,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"227.flac","artist":"Secret Garden","album":"Songs from a Secret Garden","uri":"http://192.168.1.55:8200/MediaItems/227.flac","trackType":"flac"} Jun 10 21:40:01 volumio volumio[910]: verbose: CURRENT POSITION 0 Jun 10 21:40:01 volumio volumio[910]: info: CoreStateMachine::syncState stateService play Jun 10 21:40:01 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus play Jun 10 21:40:01 volumio volumio[910]: info: Received an update from plugin. extracting info from payload Jun 10 21:40:01 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:40:01 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:40:01 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:40:01 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:40:01 volumio volumio[910]: info: ControllerMpd::pushState Jun 10 21:40:01 volumio volumio[910]: info: CoreCommandRouter::servicePushState Jun 10 21:40:01 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:40:01 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":2596,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"227.flac","artist":"Secret Garden","album":"Songs from a Secret Garden","uri":"http://192.168.1.55:8200/MediaItems/227.flac","trackType":"flac"} Jun 10 21:40:01 volumio volumio[910]: verbose: CURRENT POSITION 0 Jun 10 21:40:01 volumio volumio[910]: info: CoreStateMachine::syncState stateService play Jun 10 21:40:01 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus play Jun 10 21:40:01 volumio volumio[910]: info: Received an update from plugin. extracting info from payload Jun 10 21:40:01 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:40:01 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:40:01 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:40:01 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:40:01 volumio volumio[910]: info: ControllerMpd::pushState Jun 10 21:40:01 volumio volumio[910]: info: CoreCommandRouter::servicePushState Jun 10 21:40:01 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:40:01 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":2596,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"734 Kbps","isStreaming":false,"title":"227.flac","artist":"Secret Garden","album":"Songs from a Secret Garden","uri":"http://192.168.1.55:8200/MediaItems/227.flac","trackType":"flac"} Jun 10 21:40:01 volumio volumio[910]: verbose: CURRENT POSITION 0 Jun 10 21:40:01 volumio volumio[910]: info: CoreStateMachine::syncState stateService play Jun 10 21:40:01 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus play Jun 10 21:40:01 volumio volumio[910]: info: Received an update from plugin. extracting info from payload Jun 10 21:40:01 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:40:01 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 21:40:01 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:40:01 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:40:01 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:40:01 volumio volumio[910]: info: ControllerMpd::pushState Jun 10 21:40:01 volumio volumio[910]: info: CoreCommandRouter::servicePushState Jun 10 21:40:01 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:40:01 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":2596,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"741 Kbps","isStreaming":false,"title":"227.flac","artist":"Secret Garden","album":"Songs from a Secret Garden","uri":"http://192.168.1.55:8200/MediaItems/227.flac","trackType":"flac"} Jun 10 21:40:01 volumio volumio[910]: verbose: CURRENT POSITION 0 Jun 10 21:40:01 volumio volumio[910]: info: CoreStateMachine::syncState stateService play Jun 10 21:40:01 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus play Jun 10 21:40:01 volumio volumio[910]: info: Received an update from plugin. extracting info from payload Jun 10 21:40:01 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:40:01 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 21:40:01 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:40:01 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:40:01 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:40:01 volumio volumio[910]: info: ------------------------------ 288ms Jun 10 21:40:01 volumio volumio[910]: info: ------------------------------ 283ms Jun 10 21:40:01 volumio volumio[910]: info: ------------------------------ 272ms Jun 10 21:40:01 volumio volumio[910]: info: ------------------------------ 268ms Jun 10 21:40:01 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Jun 10 21:40:01 volumio volumio[910]: info: CoreStateMachine::getQueue Jun 10 21:40:01 volumio volumio[910]: info: CorePlayQueue::getQueue Jun 10 21:40:01 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Jun 10 21:40:01 volumio volumio[910]: info: CoreStateMachine::getQueue Jun 10 21:40:01 volumio volumio[910]: info: CorePlayQueue::getQueue Jun 10 21:40:01 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Jun 10 21:40:01 volumio volumio[910]: info: CoreStateMachine::getQueue Jun 10 21:40:01 volumio volumio[910]: info: CorePlayQueue::getQueue Jun 10 21:40:01 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Jun 10 21:40:01 volumio volumio[910]: info: CoreStateMachine::getQueue Jun 10 21:40:01 volumio volumio[910]: info: CorePlayQueue::getQueue Jun 10 21:40:01 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Jun 10 21:40:01 volumio volumio[910]: info: CoreStateMachine::getQueue Jun 10 21:40:01 volumio volumio[910]: info: CorePlayQueue::getQueue Jun 10 21:40:01 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Jun 10 21:40:01 volumio volumio[910]: info: CoreStateMachine::getQueue Jun 10 21:40:01 volumio volumio[910]: info: CorePlayQueue::getQueue Jun 10 21:40:01 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Jun 10 21:40:01 volumio volumio[910]: info: CoreStateMachine::getQueue Jun 10 21:40:01 volumio volumio[910]: info: CorePlayQueue::getQueue Jun 10 21:40:01 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Jun 10 21:40:01 volumio volumio[910]: info: CoreStateMachine::getQueue Jun 10 21:40:01 volumio volumio[910]: info: CorePlayQueue::getQueue Jun 10 21:40:01 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Jun 10 21:40:01 volumio volumio[910]: info: CoreStateMachine::getQueue Jun 10 21:40:01 volumio volumio[910]: info: CorePlayQueue::getQueue Jun 10 21:40:01 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Jun 10 21:40:01 volumio volumio[910]: info: CoreStateMachine::getQueue Jun 10 21:40:01 volumio volumio[910]: info: CorePlayQueue::getQueue Jun 10 21:40:01 volumio volumio[910]: info: sendMpdCommand playlistinfo took 240 milliseconds Jun 10 21:40:01 volumio volumio[910]: info: sendMpdCommand playlistinfo took 235 milliseconds Jun 10 21:40:01 volumio volumio[910]: info: sendMpdCommand playlistinfo took 235 milliseconds Jun 10 21:40:01 volumio volumio[910]: verbose: ControllerMpd::parseTrackInfo Jun 10 21:40:01 volumio volumio[910]: verbose: ControllerMpd::parseTrackInfo Jun 10 21:40:01 volumio volumio[910]: verbose: ControllerMpd::parseTrackInfo Jun 10 21:40:01 volumio volumio[910]: info: ControllerMpd::pushState Jun 10 21:40:01 volumio volumio[910]: info: CoreCommandRouter::servicePushState Jun 10 21:40:01 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:40:01 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":2596,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"744 Kbps","isStreaming":false,"title":"227.flac","artist":"Secret Garden","album":"Songs from a Secret Garden","uri":"http://192.168.1.55:8200/MediaItems/227.flac","trackType":"flac"} Jun 10 21:40:01 volumio volumio[910]: verbose: CURRENT POSITION 0 Jun 10 21:40:01 volumio volumio[910]: info: CoreStateMachine::syncState stateService play Jun 10 21:40:01 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus play Jun 10 21:40:01 volumio volumio[910]: info: Received an update from plugin. extracting info from payload Jun 10 21:40:01 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:40:01 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 21:40:01 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:40:01 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:40:01 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:40:01 volumio volumio[910]: info: ControllerMpd::pushState Jun 10 21:40:01 volumio volumio[910]: info: CoreCommandRouter::servicePushState Jun 10 21:40:01 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:40:01 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":2596,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"744 Kbps","isStreaming":false,"title":"227.flac","artist":"Secret Garden","album":"Songs from a Secret Garden","uri":"http://192.168.1.55:8200/MediaItems/227.flac","trackType":"flac"} Jun 10 21:40:01 volumio volumio[910]: verbose: CURRENT POSITION 0 Jun 10 21:40:01 volumio volumio[910]: info: CoreStateMachine::syncState stateService play Jun 10 21:40:01 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus play Jun 10 21:40:01 volumio volumio[910]: info: Received an update from plugin. extracting info from payload Jun 10 21:40:01 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:40:01 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:40:01 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:40:01 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:40:01 volumio volumio[910]: info: ControllerMpd::pushState Jun 10 21:40:01 volumio volumio[910]: info: CoreCommandRouter::servicePushState Jun 10 21:40:01 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:40:01 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":2596,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"744 Kbps","isStreaming":false,"title":"227.flac","artist":"Secret Garden","album":"Songs from a Secret Garden","uri":"http://192.168.1.55:8200/MediaItems/227.flac","trackType":"flac"} Jun 10 21:40:01 volumio volumio[910]: verbose: CURRENT POSITION 0 Jun 10 21:40:01 volumio volumio[910]: info: CoreStateMachine::syncState stateService play Jun 10 21:40:01 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus play Jun 10 21:40:01 volumio volumio[910]: info: Received an update from plugin. extracting info from payload Jun 10 21:40:01 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:40:01 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:40:01 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:40:01 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:40:01 volumio volumio[910]: info: ------------------------------ 450ms Jun 10 21:40:01 volumio volumio[910]: info: ------------------------------ 374ms Jun 10 21:40:01 volumio volumio[910]: info: ------------------------------ 370ms Jun 10 21:40:01 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Jun 10 21:40:01 volumio volumio[910]: info: CoreStateMachine::getQueue Jun 10 21:40:01 volumio volumio[910]: info: CorePlayQueue::getQueue Jun 10 21:40:01 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Jun 10 21:40:01 volumio volumio[910]: info: CoreStateMachine::getQueue Jun 10 21:40:01 volumio volumio[910]: info: CorePlayQueue::getQueue Jun 10 21:40:01 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Jun 10 21:40:01 volumio volumio[910]: info: CoreStateMachine::getQueue Jun 10 21:40:01 volumio volumio[910]: info: CorePlayQueue::getQueue Jun 10 21:40:01 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Jun 10 21:40:01 volumio volumio[910]: info: CoreStateMachine::getQueue Jun 10 21:40:01 volumio volumio[910]: info: CorePlayQueue::getQueue Jun 10 21:40:01 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Jun 10 21:40:01 volumio volumio[910]: info: CoreStateMachine::getQueue Jun 10 21:40:01 volumio volumio[910]: info: CorePlayQueue::getQueue Jun 10 21:40:01 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Jun 10 21:40:01 volumio volumio[910]: info: CoreStateMachine::getQueue Jun 10 21:40:01 volumio volumio[910]: info: CorePlayQueue::getQueue Jun 10 21:40:04 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Jun 10 21:40:06 volumio volumio[910]: info: CoreCommandRouter::volumioSeek Jun 10 21:40:06 volumio volumio[910]: info: CoreStateMachine::seek Jun 10 21:40:06 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:40:06 volumio volumio[910]: info: TRACKBLOCK {"service":"upnp_browser","uri":"http://192.168.1.55:8200/MediaItems/227.flac","realUri":"upnp/folder/http://192.168.1.55:8200/ctl/ContentDir@64$0$5$0","type":"song","albumart":"http://192.168.1.55:8200/AlbumArt/141-227.jpg","artist":"Secret Garden","album":"Songs from a Secret Garden","name":"Secret Garden - Songs from a Secret Garden (UICY-15127)","title":"Secret Garden - Songs from a Secret Garden (UICY-15127)","duration":2595} Jun 10 21:40:06 volumio volumio[910]: info: CoreStateMachine::startPlaybackTimer Jun 10 21:40:06 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:40:06 volumio volumio[910]: info: [1749591606099] ControllerUPNPBrowser::seek Jun 10 21:40:06 volumio volumio[910]: info: ControllerMpd::seek Jun 10 21:40:06 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:40:06 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 21:40:06 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:40:06 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Jun 10 21:40:06 volumio volumio[910]: info: CoreStateMachine::getQueue Jun 10 21:40:06 volumio volumio[910]: info: CorePlayQueue::getQueue Jun 10 21:40:06 volumio volumio[910]: error: null Jun 10 21:40:06 volumio volumio[910]: info: Jun 10 21:40:06 volumio volumio[910]: ---------------------------- MPD announces state update: player Jun 10 21:40:06 volumio volumio[910]: info: ControllerMpd::getState Jun 10 21:40:06 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Jun 10 21:40:06 volumio volumio[910]: info: Jun 10 21:40:06 volumio volumio[910]: ---------------------------- MPD announces state update: player Jun 10 21:40:06 volumio volumio[910]: info: ControllerMpd::getState Jun 10 21:40:06 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Jun 10 21:40:06 volumio volumio[910]: info: Jun 10 21:40:06 volumio volumio[910]: ---------------------------- MPD announces state update: player Jun 10 21:40:06 volumio volumio[910]: info: ControllerMpd::getState Jun 10 21:40:06 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Jun 10 21:40:06 volumio volumio[910]: info: sendMpdCommand status took 8 milliseconds Jun 10 21:40:06 volumio volumio[910]: info: sendMpdCommand status took 5 milliseconds Jun 10 21:40:06 volumio volumio[910]: info: sendMpdCommand status took 3 milliseconds Jun 10 21:40:06 volumio volumio[910]: verbose: ControllerMpd::parseState Jun 10 21:40:06 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 10 21:40:06 volumio volumio[910]: verbose: ControllerMpd::parseState Jun 10 21:40:06 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 10 21:40:06 volumio volumio[910]: verbose: ControllerMpd::parseState Jun 10 21:40:06 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 10 21:40:06 volumio volumio[910]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 10 21:40:06 volumio volumio[910]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 10 21:40:06 volumio volumio[910]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 10 21:40:06 volumio volumio[910]: verbose: ControllerMpd::parseTrackInfo Jun 10 21:40:06 volumio volumio[910]: info: ControllerMpd::pushState Jun 10 21:40:06 volumio volumio[910]: info: CoreCommandRouter::servicePushState Jun 10 21:40:06 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:40:06 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1036000,"duration":2596,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"755 Kbps","isStreaming":false,"title":"227.flac","artist":"Secret Garden","album":"Songs from a Secret Garden","uri":"http://192.168.1.55:8200/MediaItems/227.flac","trackType":"flac"} Jun 10 21:40:06 volumio volumio[910]: verbose: CURRENT POSITION 0 Jun 10 21:40:06 volumio volumio[910]: info: CoreStateMachine::syncState stateService play Jun 10 21:40:06 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus play Jun 10 21:40:06 volumio volumio[910]: info: Received an update from plugin. extracting info from payload Jun 10 21:40:06 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:40:06 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 21:40:06 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:40:06 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:40:06 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:40:06 volumio volumio[910]: info: ControllerMpd::pushState Jun 10 21:40:06 volumio volumio[910]: info: CoreCommandRouter::servicePushState Jun 10 21:40:06 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:40:06 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1036000,"duration":2596,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"755 Kbps","isStreaming":false,"title":"227.flac","artist":"Secret Garden","album":"Songs from a Secret Garden","uri":"http://192.168.1.55:8200/MediaItems/227.flac","trackType":"flac"} Jun 10 21:40:06 volumio volumio[910]: verbose: CURRENT POSITION 0 Jun 10 21:40:06 volumio volumio[910]: info: CoreStateMachine::syncState stateService play Jun 10 21:40:06 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus play Jun 10 21:40:06 volumio volumio[910]: info: Received an update from plugin. extracting info from payload Jun 10 21:40:06 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:40:06 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:40:06 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:40:06 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:40:06 volumio volumio[910]: info: ControllerMpd::pushState Jun 10 21:40:06 volumio volumio[910]: info: CoreCommandRouter::servicePushState Jun 10 21:40:06 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:40:06 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1036000,"duration":2596,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"755 Kbps","isStreaming":false,"title":"227.flac","artist":"Secret Garden","album":"Songs from a Secret Garden","uri":"http://192.168.1.55:8200/MediaItems/227.flac","trackType":"flac"} Jun 10 21:40:06 volumio volumio[910]: verbose: CURRENT POSITION 0 Jun 10 21:40:06 volumio volumio[910]: info: CoreStateMachine::syncState stateService play Jun 10 21:40:06 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus play Jun 10 21:40:06 volumio volumio[910]: info: Received an update from plugin. extracting info from payload Jun 10 21:40:06 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:40:06 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:40:06 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:40:06 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:40:06 volumio volumio[910]: info: ------------------------------ 142ms Jun 10 21:40:06 volumio volumio[910]: info: ------------------------------ 141ms Jun 10 21:40:06 volumio volumio[910]: info: ------------------------------ 139ms Jun 10 21:40:06 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Jun 10 21:40:06 volumio volumio[910]: info: CoreStateMachine::getQueue Jun 10 21:40:06 volumio volumio[910]: info: CorePlayQueue::getQueue Jun 10 21:40:06 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Jun 10 21:40:06 volumio volumio[910]: info: CoreStateMachine::getQueue Jun 10 21:40:06 volumio volumio[910]: info: CorePlayQueue::getQueue Jun 10 21:40:06 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Jun 10 21:40:06 volumio volumio[910]: info: CoreStateMachine::getQueue Jun 10 21:40:06 volumio volumio[910]: info: CorePlayQueue::getQueue Jun 10 21:40:06 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Jun 10 21:40:06 volumio volumio[910]: info: CoreStateMachine::getQueue Jun 10 21:40:06 volumio volumio[910]: info: CorePlayQueue::getQueue Jun 10 21:40:06 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Jun 10 21:40:06 volumio volumio[910]: info: CoreStateMachine::getQueue Jun 10 21:40:06 volumio volumio[910]: info: CorePlayQueue::getQueue Jun 10 21:40:06 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Jun 10 21:40:06 volumio volumio[910]: info: CoreStateMachine::getQueue Jun 10 21:40:06 volumio volumio[910]: info: CorePlayQueue::getQueue Jun 10 21:40:07 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Jun 10 21:40:07 volumio volumio[910]: info: CoreCommandRouter::volumioNext Jun 10 21:40:07 volumio volumio[910]: info: CoreStateMachine::next Jun 10 21:40:07 volumio volumio[910]: info: ControllerMpd::next Jun 10 21:40:07 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand next Jun 10 21:40:07 volumio volumio[910]: info: sendMpdCommand next took 22 milliseconds Jun 10 21:40:07 volumio volumio[910]: info: Jun 10 21:40:07 volumio volumio[910]: ---------------------------- MPD announces state update: player Jun 10 21:40:07 volumio volumio[910]: info: ControllerMpd::getState Jun 10 21:40:07 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Jun 10 21:40:07 volumio volumio[910]: info: Jun 10 21:40:07 volumio volumio[910]: ---------------------------- MPD announces state update: player Jun 10 21:40:07 volumio volumio[910]: info: ControllerMpd::getState Jun 10 21:40:07 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Jun 10 21:40:07 volumio volumio[910]: info: Jun 10 21:40:07 volumio volumio[910]: ---------------------------- MPD announces state update: player Jun 10 21:40:07 volumio volumio[910]: info: ControllerMpd::getState Jun 10 21:40:07 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Jun 10 21:40:07 volumio volumio[910]: info: sendMpdCommand status took 6 milliseconds Jun 10 21:40:07 volumio volumio[910]: info: sendMpdCommand status took 4 milliseconds Jun 10 21:40:07 volumio volumio[910]: info: sendMpdCommand status took 2 milliseconds Jun 10 21:40:07 volumio volumio[910]: verbose: ControllerMpd::parseState Jun 10 21:40:07 volumio volumio[910]: verbose: ControllerMpd::parseState Jun 10 21:40:07 volumio volumio[910]: verbose: ControllerMpd::parseState Jun 10 21:40:07 volumio volumio[910]: info: ControllerMpd::pushState Jun 10 21:40:07 volumio volumio[910]: info: CoreCommandRouter::servicePushState Jun 10 21:40:07 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:40:07 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 21:40:07 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:40:07 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:40:07 volumio volumio[910]: 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} Jun 10 21:40:07 volumio volumio[910]: verbose: CURRENT POSITION 0 Jun 10 21:40:07 volumio volumio[910]: info: CoreStateMachine::syncState stateService stop Jun 10 21:40:07 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus play Jun 10 21:40:07 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:40:07 volumio volumio[910]: info: CoreStateMachine::stPlaybackTimer Jun 10 21:40:07 volumio volumio[910]: info: ControllerMpd::pushState Jun 10 21:40:07 volumio volumio[910]: info: CoreCommandRouter::servicePushState Jun 10 21:40:07 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:40:07 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 21:40:07 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:40:07 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:40:07 volumio volumio[910]: 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} Jun 10 21:40:07 volumio volumio[910]: verbose: CURRENT POSITION 0 Jun 10 21:40:07 volumio volumio[910]: info: CoreStateMachine::syncState stateService stop Jun 10 21:40:07 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus stop Jun 10 21:40:07 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:40:07 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:40:07 volumio volumio[910]: info: No code Jun 10 21:40:07 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:40:07 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:40:07 volumio volumio[910]: info: ControllerMpd::pushState Jun 10 21:40:07 volumio volumio[910]: info: CoreCommandRouter::servicePushState Jun 10 21:40:07 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:40:07 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:40:07 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:40:07 volumio volumio[910]: 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} Jun 10 21:40:07 volumio volumio[910]: verbose: CURRENT POSITION 0 Jun 10 21:40:07 volumio volumio[910]: info: CoreStateMachine::syncState stateService stop Jun 10 21:40:07 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus stop Jun 10 21:40:07 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:40:07 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:40:07 volumio volumio[910]: info: No code Jun 10 21:40:07 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:40:07 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:40:07 volumio volumio[910]: info: ------------------------------ 144ms Jun 10 21:40:07 volumio volumio[910]: info: ------------------------------ 142ms Jun 10 21:40:07 volumio volumio[910]: info: ------------------------------ 143ms Jun 10 21:40:07 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Jun 10 21:40:07 volumio volumio[910]: info: CoreStateMachine::getQueue Jun 10 21:40:07 volumio volumio[910]: info: CorePlayQueue::getQueue Jun 10 21:40:14 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 10 21:40:14 volumio volumio[910]: info: Preload queue cleared Jun 10 21:40:14 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$5$0$1 Jun 10 21:40:14 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$5$0$1 in service upnp_browser Jun 10 21:40:16 volumio volumio[910]: info: Preload queue cleared Jun 10 21:40:16 volumio volumio[910]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 10 21:40:16 volumio volumio[910]: info: CoreStateMachine::ClearQueue Jun 10 21:40:16 volumio volumio[910]: info: CoreStateMachine::stop Jun 10 21:40:16 volumio volumio[910]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 10 21:40:16 volumio volumio[910]: info: CorePlayQueue::clearPlayQueue Jun 10 21:40:16 volumio volumio[910]: info: CorePlayQueue::saveQueue Jun 10 21:40:16 volumio volumio[910]: info: CoreCommandRouter::volumioPushQueue Jun 10 21:40:16 volumio volumio[910]: info: CoreStateMachine::addQueueItems Jun 10 21:40:16 volumio volumio[910]: info: CorePlayQueue::addQueueItems Jun 10 21:40:16 volumio volumio[910]: info: Preload queue cleared Jun 10 21:40:16 volumio volumio[910]: info: Adding Item to queue: upnp/folder/http://192.168.1.55:8200/ctl/ContentDir@64$0$5$0$0 Jun 10 21:40:16 volumio volumio[910]: info: Exploding uri upnp/folder/http://192.168.1.55:8200/ctl/ContentDir@64$0$5$0$0 in service upnp_browser Jun 10 21:40:16 volumio volumio[910]: info: Adding Item to queue: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$5$0$1 Jun 10 21:40:16 volumio volumio[910]: info: Using cached record of: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$5$0$1 Jun 10 21:40:16 volumio volumio[910]: info: CoreCommandRouter::volumioPushQueue Jun 10 21:40:16 volumio volumio[910]: info: CorePlayQueue::saveQueue Jun 10 21:40:16 volumio volumio[910]: info: CoreStateMachine::updateTrackBlock Jun 10 21:40:16 volumio volumio[910]: info: CorePlayQueue::getTrackBlock Jun 10 21:40:16 volumio volumio[910]: info: CoreCommandRouter::volumioPlay Jun 10 21:40:16 volumio volumio[910]: info: CoreStateMachine::play index 1 Jun 10 21:40:16 volumio volumio[910]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 10 21:40:16 volumio volumio[910]: info: CoreStateMachine::stop Jun 10 21:40:16 volumio volumio[910]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 10 21:40:16 volumio volumio[910]: info: CoreStateMachine::play index undefined Jun 10 21:40:16 volumio volumio[910]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 10 21:40:16 volumio volumio[910]: info: CorePlayQueue::getTrack 1 Jun 10 21:40:17 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Jun 10 21:40:17 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:40:17 volumio volumio[910]: info: Listing playlists Jun 10 21:40:17 volumio volumio[910]: info: Listing playlists Jun 10 21:40:19 volumio volumio[910]: info: CoreCommandRouter::volumioSeek Jun 10 21:40:19 volumio volumio[910]: info: CoreStateMachine::seek Jun 10 21:40:19 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:40:19 volumio volumio[910]: info: TRACKBLOCK {"service":"upnp_browser","uri":"http://192.168.1.55:8200/MediaItems/227.flac","realUri":"upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$5$0$1","type":"song","albumart":"http://192.168.1.55:8200/AlbumArt/141-227.jpg","artist":"Secret Garden","album":"Songs from a Secret Garden","name":"Secret Garden - Songs from a Secret Garden (UICY-15127)","title":"Secret Garden - Songs from a Secret Garden (UICY-15127)","duration":2595} Jun 10 21:40:19 volumio volumio[910]: info: CoreStateMachine::startPlaybackTimer Jun 10 21:40:19 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:40:19 volumio volumio[910]: info: [1749591619682] ControllerUPNPBrowser::seek Jun 10 21:40:19 volumio volumio[910]: info: ControllerMpd::seek Jun 10 21:40:19 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:40:19 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:40:19 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 21:40:19 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:40:19 volumio volumio[910]: error: null Jun 10 21:40:19 volumio volumio[910]: info: Jun 10 21:40:19 volumio volumio[910]: ---------------------------- MPD announces state update: player Jun 10 21:40:19 volumio volumio[910]: info: ControllerMpd::getState Jun 10 21:40:19 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Jun 10 21:40:19 volumio volumio[910]: info: Jun 10 21:40:19 volumio volumio[910]: ---------------------------- MPD announces state update: player Jun 10 21:40:19 volumio volumio[910]: info: ControllerMpd::getState Jun 10 21:40:19 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Jun 10 21:40:19 volumio volumio[910]: info: Jun 10 21:40:19 volumio volumio[910]: ---------------------------- MPD announces state update: player Jun 10 21:40:19 volumio volumio[910]: info: ControllerMpd::getState Jun 10 21:40:19 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Jun 10 21:40:19 volumio volumio[910]: info: Jun 10 21:40:19 volumio volumio[910]: ---------------------------- MPD announces state update: player Jun 10 21:40:19 volumio volumio[910]: info: ControllerMpd::getState Jun 10 21:40:19 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Jun 10 21:40:19 volumio volumio[910]: info: sendMpdCommand status took 21 milliseconds Jun 10 21:40:19 volumio volumio[910]: info: sendMpdCommand status took 14 milliseconds Jun 10 21:40:19 volumio volumio[910]: info: sendMpdCommand status took 11 milliseconds Jun 10 21:40:19 volumio volumio[910]: verbose: ControllerMpd::parseState Jun 10 21:40:19 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 10 21:40:19 volumio volumio[910]: verbose: ControllerMpd::parseState Jun 10 21:40:19 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 10 21:40:19 volumio volumio[910]: verbose: ControllerMpd::parseState Jun 10 21:40:19 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 10 21:40:19 volumio volumio[910]: info: Jun 10 21:40:19 volumio volumio[910]: ---------------------------- MPD announces state update: player Jun 10 21:40:19 volumio volumio[910]: info: ControllerMpd::getState Jun 10 21:40:19 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Jun 10 21:40:19 volumio volumio[910]: info: Jun 10 21:40:19 volumio volumio[910]: ---------------------------- MPD announces state update: player Jun 10 21:40:19 volumio volumio[910]: info: ControllerMpd::getState Jun 10 21:40:19 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Jun 10 21:40:19 volumio volumio[910]: info: sendMpdCommand status took 17 milliseconds Jun 10 21:40:19 volumio volumio[910]: info: sendMpdCommand playlistinfo took 14 milliseconds Jun 10 21:40:19 volumio volumio[910]: info: sendMpdCommand playlistinfo took 13 milliseconds Jun 10 21:40:19 volumio volumio[910]: info: sendMpdCommand playlistinfo took 12 milliseconds Jun 10 21:40:19 volumio volumio[910]: info: sendMpdCommand status took 7 milliseconds Jun 10 21:40:19 volumio volumio[910]: info: sendMpdCommand status took 5 milliseconds Jun 10 21:40:19 volumio volumio[910]: verbose: ControllerMpd::parseState Jun 10 21:40:19 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 10 21:40:19 volumio volumio[910]: verbose: ControllerMpd::parseTrackInfo Jun 10 21:40:19 volumio volumio[910]: verbose: ControllerMpd::parseTrackInfo Jun 10 21:40:19 volumio volumio[910]: verbose: ControllerMpd::parseTrackInfo Jun 10 21:40:19 volumio volumio[910]: verbose: ControllerMpd::parseState Jun 10 21:40:19 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 10 21:40:19 volumio volumio[910]: verbose: ControllerMpd::parseState Jun 10 21:40:19 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 10 21:40:19 volumio volumio[910]: info: ControllerMpd::pushState Jun 10 21:40:19 volumio volumio[910]: info: CoreCommandRouter::servicePushState Jun 10 21:40:19 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:40:19 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":341000,"duration":2596,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"227.flac","artist":"Secret Garden","album":"Songs from a Secret Garden","uri":"http://192.168.1.55:8200/MediaItems/227.flac","trackType":"flac"} Jun 10 21:40:19 volumio volumio[910]: verbose: CURRENT POSITION 0 Jun 10 21:40:19 volumio volumio[910]: info: CoreStateMachine::syncState stateService play Jun 10 21:40:19 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus stop Jun 10 21:40:19 volumio volumio[910]: info: ControllerMpd::pushState Jun 10 21:40:19 volumio volumio[910]: info: CoreCommandRouter::servicePushState Jun 10 21:40:19 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:40:19 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":341000,"duration":2596,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"227.flac","artist":"Secret Garden","album":"Songs from a Secret Garden","uri":"http://192.168.1.55:8200/MediaItems/227.flac","trackType":"flac"} Jun 10 21:40:19 volumio volumio[910]: verbose: CURRENT POSITION 0 Jun 10 21:40:19 volumio volumio[910]: info: CoreStateMachine::syncState stateService play Jun 10 21:40:19 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus play Jun 10 21:40:19 volumio volumio[910]: info: Received an update from plugin. extracting info from payload Jun 10 21:40:19 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:40:19 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:40:19 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 21:40:19 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:40:19 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:40:19 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:40:19 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:40:19 volumio volumio[910]: info: ControllerMpd::pushState Jun 10 21:40:19 volumio volumio[910]: info: CoreCommandRouter::servicePushState Jun 10 21:40:19 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:40:19 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":341000,"duration":2596,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"227.flac","artist":"Secret Garden","album":"Songs from a Secret Garden","uri":"http://192.168.1.55:8200/MediaItems/227.flac","trackType":"flac"} Jun 10 21:40:19 volumio volumio[910]: verbose: CURRENT POSITION 0 Jun 10 21:40:19 volumio volumio[910]: info: CoreStateMachine::syncState stateService play Jun 10 21:40:19 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus play Jun 10 21:40:19 volumio volumio[910]: info: Received an update from plugin. extracting info from payload Jun 10 21:40:19 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:40:19 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:40:19 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:40:19 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:40:19 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:40:19 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:40:19 volumio volumio[910]: info: ------------------------------ 84ms Jun 10 21:40:19 volumio volumio[910]: info: ------------------------------ 129ms Jun 10 21:40:19 volumio volumio[910]: info: ------------------------------ 127ms Jun 10 21:40:19 volumio volumio[910]: info: sendMpdCommand playlistinfo took 97 milliseconds Jun 10 21:40:19 volumio volumio[910]: info: sendMpdCommand playlistinfo took 93 milliseconds Jun 10 21:40:19 volumio volumio[910]: info: sendMpdCommand playlistinfo took 93 milliseconds Jun 10 21:40:19 volumio volumio[910]: verbose: ControllerMpd::parseTrackInfo Jun 10 21:40:19 volumio volumio[910]: verbose: ControllerMpd::parseTrackInfo Jun 10 21:40:19 volumio volumio[910]: verbose: ControllerMpd::parseTrackInfo Jun 10 21:40:19 volumio volumio[910]: info: ControllerMpd::pushState Jun 10 21:40:19 volumio volumio[910]: info: CoreCommandRouter::servicePushState Jun 10 21:40:19 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:40:19 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":341000,"duration":2596,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"227.flac","artist":"Secret Garden","album":"Songs from a Secret Garden","uri":"http://192.168.1.55:8200/MediaItems/227.flac","trackType":"flac"} Jun 10 21:40:19 volumio volumio[910]: verbose: CURRENT POSITION 0 Jun 10 21:40:19 volumio volumio[910]: info: CoreStateMachine::syncState stateService play Jun 10 21:40:19 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus play Jun 10 21:40:19 volumio volumio[910]: info: Received an update from plugin. extracting info from payload Jun 10 21:40:19 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:40:19 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:40:19 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:40:19 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:40:19 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:40:19 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:40:19 volumio volumio[910]: info: ControllerMpd::pushState Jun 10 21:40:19 volumio volumio[910]: info: CoreCommandRouter::servicePushState Jun 10 21:40:19 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:40:19 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":341000,"duration":2596,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"227.flac","artist":"Secret Garden","album":"Songs from a Secret Garden","uri":"http://192.168.1.55:8200/MediaItems/227.flac","trackType":"flac"} Jun 10 21:40:19 volumio volumio[910]: verbose: CURRENT POSITION 0 Jun 10 21:40:19 volumio volumio[910]: info: CoreStateMachine::syncState stateService play Jun 10 21:40:19 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus play Jun 10 21:40:19 volumio volumio[910]: info: Received an update from plugin. extracting info from payload Jun 10 21:40:19 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:40:19 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:40:19 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:40:19 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:40:19 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:40:19 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:40:19 volumio volumio[910]: info: ControllerMpd::pushState Jun 10 21:40:19 volumio volumio[910]: info: CoreCommandRouter::servicePushState Jun 10 21:40:19 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:40:19 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":341000,"duration":2596,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"227.flac","artist":"Secret Garden","album":"Songs from a Secret Garden","uri":"http://192.168.1.55:8200/MediaItems/227.flac","trackType":"flac"} Jun 10 21:40:19 volumio volumio[910]: verbose: CURRENT POSITION 0 Jun 10 21:40:19 volumio volumio[910]: info: CoreStateMachine::syncState stateService play Jun 10 21:40:19 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus play Jun 10 21:40:19 volumio volumio[910]: info: Received an update from plugin. extracting info from payload Jun 10 21:40:19 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:40:19 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:40:19 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:40:19 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:40:19 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:40:19 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:40:20 volumio volumio[910]: info: ------------------------------ 285ms Jun 10 21:40:20 volumio volumio[910]: info: ------------------------------ 275ms Jun 10 21:40:20 volumio volumio[910]: info: ------------------------------ 272ms Jun 10 21:40:20 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Jun 10 21:40:20 volumio volumio[910]: info: CoreStateMachine::getQueue Jun 10 21:40:20 volumio volumio[910]: info: CorePlayQueue::getQueue Jun 10 21:40:20 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Jun 10 21:40:20 volumio volumio[910]: info: CoreStateMachine::getQueue Jun 10 21:40:20 volumio volumio[910]: info: CorePlayQueue::getQueue Jun 10 21:40:20 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Jun 10 21:40:20 volumio volumio[910]: info: CoreStateMachine::getQueue Jun 10 21:40:20 volumio volumio[910]: info: CorePlayQueue::getQueue Jun 10 21:40:20 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Jun 10 21:40:20 volumio volumio[910]: info: CoreStateMachine::getQueue Jun 10 21:40:20 volumio volumio[910]: info: CorePlayQueue::getQueue Jun 10 21:40:20 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Jun 10 21:40:20 volumio volumio[910]: info: CoreStateMachine::getQueue Jun 10 21:40:20 volumio volumio[910]: info: CorePlayQueue::getQueue Jun 10 21:40:20 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Jun 10 21:40:20 volumio volumio[910]: info: CoreStateMachine::getQueue Jun 10 21:40:20 volumio volumio[910]: info: CorePlayQueue::getQueue Jun 10 21:40:20 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Jun 10 21:40:20 volumio volumio[910]: info: CoreStateMachine::getQueue Jun 10 21:40:20 volumio volumio[910]: info: CorePlayQueue::getQueue Jun 10 21:40:20 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Jun 10 21:40:20 volumio volumio[910]: info: CoreStateMachine::getQueue Jun 10 21:40:20 volumio volumio[910]: info: CorePlayQueue::getQueue Jun 10 21:40:20 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Jun 10 21:40:20 volumio volumio[910]: info: CoreStateMachine::getQueue Jun 10 21:40:20 volumio volumio[910]: info: CorePlayQueue::getQueue Jun 10 21:40:20 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Jun 10 21:40:20 volumio volumio[910]: info: CoreStateMachine::getQueue Jun 10 21:40:20 volumio volumio[910]: info: CorePlayQueue::getQueue Jun 10 21:40:27 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Jun 10 21:40:27 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:40:37 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Jun 10 21:40:37 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:40:37 volumio volumio[910]: info: Listing playlists Jun 10 21:40:37 volumio volumio[910]: info: Listing playlists Jun 10 21:40:47 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Jun 10 21:40:47 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:40:52 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 10 21:40:52 volumio volumio[910]: info: Preload queue cleared Jun 10 21:40:52 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$3$0 Jun 10 21:40:52 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$3$1 Jun 10 21:40:52 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$3$2 Jun 10 21:40:52 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$3$3 Jun 10 21:40:52 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$3$4 Jun 10 21:40:52 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$3$5 Jun 10 21:40:52 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$3$6 Jun 10 21:40:52 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$3$7 Jun 10 21:40:52 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$3$8 Jun 10 21:40:52 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$3$0 in service upnp_browser Jun 10 21:40:52 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$3$1 in service upnp_browser Jun 10 21:40:52 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$3$2 in service upnp_browser Jun 10 21:40:52 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$3$3 in service upnp_browser Jun 10 21:40:52 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$3$4 in service upnp_browser Jun 10 21:40:52 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$3$5 in service upnp_browser Jun 10 21:40:52 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$3$6 in service upnp_browser Jun 10 21:40:52 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$3$7 in service upnp_browser Jun 10 21:40:52 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$3$8 in service upnp_browser Jun 10 21:40:53 volumio volumio[910]: info: Preload queue cleared Jun 10 21:40:53 volumio volumio[910]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 10 21:40:53 volumio volumio[910]: info: CoreStateMachine::ClearQueue Jun 10 21:40:53 volumio volumio[910]: info: CoreStateMachine::stop Jun 10 21:40:53 volumio volumio[910]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 10 21:40:53 volumio volumio[910]: info: CoreStateMachine::stPlaybackTimer Jun 10 21:40:53 volumio volumio[910]: info: CoreStateMachine::updateTrackBlock Jun 10 21:40:53 volumio volumio[910]: info: CorePlayQueue::getTrackBlock Jun 10 21:40:53 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:40:53 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:40:53 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 21:40:53 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:40:53 volumio volumio[910]: info: CoreStateMachine::serviceStop Jun 10 21:40:53 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:40:53 volumio volumio[910]: info: CoreCommandRouter::serviceStop Jun 10 21:40:53 volumio volumio[910]: info: [1749591653800] ControllerUPNPBrowser::stop Jun 10 21:40:53 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand stop Jun 10 21:40:53 volumio volumio[910]: info: CorePlayQueue::clearPlayQueue Jun 10 21:40:53 volumio volumio[910]: info: CorePlayQueue::saveQueue Jun 10 21:40:53 volumio volumio[910]: info: CoreCommandRouter::volumioPushQueue Jun 10 21:40:53 volumio volumio[910]: info: CoreStateMachine::addQueueItems Jun 10 21:40:53 volumio volumio[910]: info: CorePlayQueue::addQueueItems Jun 10 21:40:53 volumio volumio[910]: info: Preload queue cleared Jun 10 21:40:53 volumio volumio[910]: info: Adding Item to queue: upnp/folder/http://192.168.1.55:8200/ctl/ContentDir@64$0$3$9 Jun 10 21:40:53 volumio volumio[910]: info: Exploding uri upnp/folder/http://192.168.1.55:8200/ctl/ContentDir@64$0$3$9 in service upnp_browser Jun 10 21:40:53 volumio volumio[910]: info: Adding Item to queue: upnp/folder/http://192.168.1.55:8200/ctl/ContentDir@64$0$3$A Jun 10 21:40:53 volumio volumio[910]: info: Exploding uri upnp/folder/http://192.168.1.55:8200/ctl/ContentDir@64$0$3$A in service upnp_browser Jun 10 21:40:53 volumio volumio[910]: info: Adding Item to queue: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$3$0 Jun 10 21:40:53 volumio volumio[910]: info: Using cached record of: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$3$0 Jun 10 21:40:53 volumio volumio[910]: info: CoreCommandRouter::volumioPushQueue Jun 10 21:40:53 volumio volumio[910]: info: CorePlayQueue::saveQueue Jun 10 21:40:53 volumio volumio[910]: info: CoreStateMachine::updateTrackBlock Jun 10 21:40:53 volumio volumio[910]: info: CorePlayQueue::getTrackBlock Jun 10 21:40:53 volumio volumio[910]: info: CoreCommandRouter::volumioPlay Jun 10 21:40:53 volumio volumio[910]: info: CoreStateMachine::play index 2 Jun 10 21:40:53 volumio volumio[910]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 10 21:40:53 volumio volumio[910]: info: CoreStateMachine::addQueueItems Jun 10 21:40:53 volumio volumio[910]: info: CorePlayQueue::addQueueItems Jun 10 21:40:53 volumio volumio[910]: info: Preload queue cleared Jun 10 21:40:53 volumio volumio[910]: info: Adding Item to queue: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$3$1 Jun 10 21:40:53 volumio volumio[910]: info: Using cached record of: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$3$1 Jun 10 21:40:53 volumio volumio[910]: info: Adding Item to queue: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$3$2 Jun 10 21:40:53 volumio volumio[910]: info: Using cached record of: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$3$2 Jun 10 21:40:53 volumio volumio[910]: info: Adding Item to queue: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$3$3 Jun 10 21:40:53 volumio volumio[910]: info: Using cached record of: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$3$3 Jun 10 21:40:53 volumio volumio[910]: info: Adding Item to queue: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$3$4 Jun 10 21:40:53 volumio volumio[910]: info: Using cached record of: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$3$4 Jun 10 21:40:53 volumio volumio[910]: info: Adding Item to queue: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$3$5 Jun 10 21:40:53 volumio volumio[910]: info: Using cached record of: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$3$5 Jun 10 21:40:53 volumio volumio[910]: info: Adding Item to queue: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$3$6 Jun 10 21:40:53 volumio volumio[910]: info: Using cached record of: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$3$6 Jun 10 21:40:53 volumio volumio[910]: info: Adding Item to queue: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$3$7 Jun 10 21:40:53 volumio volumio[910]: info: Using cached record of: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$3$7 Jun 10 21:40:53 volumio volumio[910]: info: Adding Item to queue: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$3$8 Jun 10 21:40:53 volumio volumio[910]: info: Using cached record of: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$3$8 Jun 10 21:40:53 volumio volumio[910]: info: CoreStateMachine::stop Jun 10 21:40:53 volumio volumio[910]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 10 21:40:53 volumio volumio[910]: info: CoreCommandRouter::volumioPushQueue Jun 10 21:40:53 volumio volumio[910]: info: CorePlayQueue::saveQueue Jun 10 21:40:53 volumio volumio[910]: info: CoreStateMachine::play index undefined Jun 10 21:40:53 volumio volumio[910]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 10 21:40:53 volumio volumio[910]: info: CoreStateMachine::updateTrackBlock Jun 10 21:40:53 volumio volumio[910]: info: CorePlayQueue::getTrackBlock Jun 10 21:40:53 volumio volumio[910]: info: CorePlayQueue::getTrack 2 Jun 10 21:40:53 volumio volumio[910]: info: CoreStateMachine::startPlaybackTimer Jun 10 21:40:53 volumio volumio[910]: info: CorePlayQueue::getTrack 2 Jun 10 21:40:53 volumio volumio[910]: info: [1749591653903] ControllerUPNPBrowser::clearAddPlayTrack Jun 10 21:40:53 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand stop Jun 10 21:40:53 volumio volumio[910]: info: Jun 10 21:40:53 volumio volumio[910]: ---------------------------- MPD announces state update: player Jun 10 21:40:53 volumio volumio[910]: info: sendMpdCommand stop took 105 milliseconds Jun 10 21:40:53 volumio volumio[910]: info: ControllerMpd::getState Jun 10 21:40:53 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Jun 10 21:40:53 volumio volumio[910]: info: sendMpdCommand stop took 3 milliseconds Jun 10 21:40:53 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand clear Jun 10 21:40:53 volumio volumio[910]: info: Jun 10 21:40:53 volumio volumio[910]: ---------------------------- MPD announces state update: player Jun 10 21:40:53 volumio volumio[910]: info: ControllerMpd::getState Jun 10 21:40:53 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Jun 10 21:40:53 volumio volumio[910]: info: Jun 10 21:40:53 volumio volumio[910]: ---------------------------- MPD announces state update: player Jun 10 21:40:53 volumio volumio[910]: info: ControllerMpd::getState Jun 10 21:40:53 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Jun 10 21:40:53 volumio volumio[910]: info: Jun 10 21:40:53 volumio volumio[910]: ---------------------------- MPD announces system playlist update Jun 10 21:40:53 volumio volumio[910]: info: Ignoring MPD Status Update Jun 10 21:40:53 volumio volumio[910]: info: sendMpdCommand status took 14 milliseconds Jun 10 21:40:53 volumio volumio[910]: info: sendMpdCommand clear took 13 milliseconds Jun 10 21:40:53 volumio volumio[910]: info: sendMpdCommand status took 8 milliseconds Jun 10 21:40:53 volumio volumio[910]: info: sendMpdCommand status took 6 milliseconds Jun 10 21:40:53 volumio volumio[910]: verbose: ControllerMpd::parseState Jun 10 21:40:53 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 10 21:40:53 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.55:8200/MediaItems/99.flac" Jun 10 21:40:53 volumio volumio[910]: verbose: ControllerMpd::parseState Jun 10 21:40:53 volumio volumio[910]: verbose: ControllerMpd::parseState Jun 10 21:40:53 volumio volumio[910]: info: ControllerMpd::pushState Jun 10 21:40:53 volumio volumio[910]: info: CoreCommandRouter::servicePushState Jun 10 21:40:53 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:40:53 volumio volumio[910]: info: CorePlayQueue::getTrack 2 Jun 10 21:40:53 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 21:40:53 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:40:53 volumio volumio[910]: info: CorePlayQueue::getTrack 2 Jun 10 21:40:53 volumio volumio[910]: 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} Jun 10 21:40:53 volumio volumio[910]: verbose: CURRENT POSITION 2 Jun 10 21:40:53 volumio volumio[910]: info: CoreStateMachine::syncState stateService stop Jun 10 21:40:53 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus stop Jun 10 21:40:53 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:40:53 volumio volumio[910]: info: CorePlayQueue::getTrack 2 Jun 10 21:40:53 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:40:53 volumio volumio[910]: info: No code Jun 10 21:40:53 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:40:53 volumio volumio[910]: info: CorePlayQueue::getTrack 2 Jun 10 21:40:53 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:40:53 volumio volumio[910]: info: ControllerMpd::pushState Jun 10 21:40:53 volumio volumio[910]: info: CoreCommandRouter::servicePushState Jun 10 21:40:53 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:40:53 volumio volumio[910]: info: CorePlayQueue::getTrack 2 Jun 10 21:40:53 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:40:53 volumio volumio[910]: info: CorePlayQueue::getTrack 2 Jun 10 21:40:53 volumio volumio[910]: 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} Jun 10 21:40:53 volumio volumio[910]: verbose: CURRENT POSITION 2 Jun 10 21:40:53 volumio volumio[910]: info: CoreStateMachine::syncState stateService stop Jun 10 21:40:53 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus stop Jun 10 21:40:53 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:40:53 volumio volumio[910]: info: CorePlayQueue::getTrack 2 Jun 10 21:40:53 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:40:53 volumio volumio[910]: info: No code Jun 10 21:40:53 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:40:53 volumio volumio[910]: info: CorePlayQueue::getTrack 2 Jun 10 21:40:53 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:40:54 volumio volumio[910]: info: ------------------------------ 117ms Jun 10 21:40:54 volumio volumio[910]: info: ------------------------------ 114ms Jun 10 21:40:54 volumio volumio[910]: info: Jun 10 21:40:54 volumio volumio[910]: ---------------------------- MPD announces system playlist update Jun 10 21:40:54 volumio volumio[910]: info: Ignoring MPD Status Update Jun 10 21:40:54 volumio volumio[910]: info: Jun 10 21:40:54 volumio volumio[910]: ---------------------------- MPD announces system playlist update Jun 10 21:40:54 volumio volumio[910]: info: Ignoring MPD Status Update Jun 10 21:40:54 volumio volumio[910]: error: updateQueue error: null Jun 10 21:40:54 volumio volumio[910]: error: updateQueue error: null Jun 10 21:40:54 volumio volumio[910]: error: updateQueue error: null Jun 10 21:40:54 volumio volumio[910]: info: ------------------------------ 143ms Jun 10 21:40:54 volumio volumio[910]: info: sendMpdCommand playlistinfo took 141 milliseconds Jun 10 21:40:54 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.55:8200/MediaItems/99.flac" Jun 10 21:40:54 volumio volumio[910]: info: ------------------------------ 36ms Jun 10 21:40:54 volumio volumio[910]: info: ------------------------------ 36ms Jun 10 21:40:54 volumio volumio[910]: verbose: ControllerMpd::parseTrackInfo Jun 10 21:40:54 volumio volumio[910]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 10 21:40:54 volumio volumio[910]: info: ------------------------------ 172ms Jun 10 21:40:54 volumio volumio[910]: info: Jun 10 21:40:54 volumio volumio[910]: ---------------------------- MPD announces system playlist update Jun 10 21:40:54 volumio volumio[910]: info: Ignoring MPD Status Update Jun 10 21:40:54 volumio volumio[910]: info: sendMpdCommand add "http://192.168.1.55:8200/MediaItems/99.flac" took 26 milliseconds Jun 10 21:40:54 volumio volumio[910]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 10 21:40:54 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand play Jun 10 21:40:54 volumio volumio[910]: info: Jun 10 21:40:54 volumio volumio[910]: ---------------------------- MPD announces system playlist update Jun 10 21:40:54 volumio volumio[910]: info: Ignoring MPD Status Update Jun 10 21:40:54 volumio volumio[910]: info: Jun 10 21:40:54 volumio volumio[910]: ---------------------------- MPD announces system playlist update Jun 10 21:40:54 volumio volumio[910]: info: Ignoring MPD Status Update Jun 10 21:40:54 volumio volumio[910]: info: Jun 10 21:40:54 volumio volumio[910]: ---------------------------- MPD announces state update: player Jun 10 21:40:54 volumio volumio[910]: info: Jun 10 21:40:54 volumio volumio[910]: ---------------------------- MPD announces system playlist update Jun 10 21:40:54 volumio volumio[910]: info: Ignoring MPD Status Update Jun 10 21:40:54 volumio volumio[910]: info: Jun 10 21:40:54 volumio volumio[910]: ---------------------------- MPD announces state update: player Jun 10 21:40:54 volumio volumio[910]: info: ------------------------------ 90ms Jun 10 21:40:54 volumio volumio[910]: info: sendMpdCommand play took 84 milliseconds Jun 10 21:40:54 volumio volumio[910]: info: ControllerMpd::getState Jun 10 21:40:54 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Jun 10 21:40:54 volumio volumio[910]: info: ------------------------------ 16ms Jun 10 21:40:54 volumio volumio[910]: info: ControllerMpd::getState Jun 10 21:40:54 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Jun 10 21:40:54 volumio volumio[910]: info: Jun 10 21:40:54 volumio volumio[910]: ---------------------------- MPD announces system playlist update Jun 10 21:40:54 volumio volumio[910]: info: Ignoring MPD Status Update Jun 10 21:40:54 volumio volumio[910]: info: Jun 10 21:40:54 volumio volumio[910]: ---------------------------- MPD announces state update: player Jun 10 21:40:54 volumio volumio[910]: info: ControllerMpd::getState Jun 10 21:40:54 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Jun 10 21:40:54 volumio volumio[910]: info: ------------------------------ 29ms Jun 10 21:40:54 volumio volumio[910]: info: sendMpdCommand status took 20 milliseconds Jun 10 21:40:54 volumio volumio[910]: info: ------------------------------ 24ms Jun 10 21:40:54 volumio volumio[910]: info: sendMpdCommand status took 22 milliseconds Jun 10 21:40:54 volumio volumio[910]: info: ------------------------------ 15ms Jun 10 21:40:54 volumio volumio[910]: verbose: ControllerMpd::parseState Jun 10 21:40:54 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 10 21:40:54 volumio volumio[910]: verbose: ControllerMpd::parseState Jun 10 21:40:54 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 10 21:40:54 volumio volumio[910]: info: Jun 10 21:40:54 volumio volumio[910]: ---------------------------- MPD announces system playlist update Jun 10 21:40:54 volumio volumio[910]: info: Ignoring MPD Status Update Jun 10 21:40:54 volumio volumio[910]: info: Jun 10 21:40:54 volumio volumio[910]: ---------------------------- MPD announces state update: player Jun 10 21:40:54 volumio volumio[910]: info: ControllerMpd::getState Jun 10 21:40:54 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Jun 10 21:40:54 volumio volumio[910]: info: sendMpdCommand status took 24 milliseconds Jun 10 21:40:54 volumio volumio[910]: info: sendMpdCommand playlistinfo took 12 milliseconds Jun 10 21:40:54 volumio volumio[910]: info: sendMpdCommand playlistinfo took 12 milliseconds Jun 10 21:40:54 volumio volumio[910]: info: ------------------------------ 9ms Jun 10 21:40:54 volumio volumio[910]: info: sendMpdCommand status took 7 milliseconds Jun 10 21:40:54 volumio volumio[910]: verbose: ControllerMpd::parseState Jun 10 21:40:54 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 10 21:40:54 volumio volumio[910]: verbose: ControllerMpd::parseTrackInfo Jun 10 21:40:54 volumio volumio[910]: verbose: ControllerMpd::parseState Jun 10 21:40:54 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 10 21:40:54 volumio volumio[910]: info: ControllerMpd::pushState Jun 10 21:40:54 volumio volumio[910]: info: CoreCommandRouter::servicePushState Jun 10 21:40:54 volumio volumio[910]: info: CorePlayQueue::getTrack 2 Jun 10 21:40:54 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":151,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"980 Kbps","isStreaming":false,"title":"We Shall Dance","artist":"Paul Mauriat","album":"Mamy Blue","uri":"http://192.168.1.55:8200/MediaItems/99.flac","trackType":"flac"} Jun 10 21:40:54 volumio volumio[910]: verbose: CURRENT POSITION 2 Jun 10 21:40:54 volumio volumio[910]: info: CoreStateMachine::syncState stateService play Jun 10 21:40:54 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus stop Jun 10 21:40:54 volumio volumio[910]: info: ControllerMpd::pushState Jun 10 21:40:54 volumio volumio[910]: info: CoreCommandRouter::servicePushState Jun 10 21:40:54 volumio volumio[910]: info: CorePlayQueue::getTrack 2 Jun 10 21:40:54 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":151,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"980 Kbps","isStreaming":false,"title":"We Shall Dance","artist":"Paul Mauriat","album":"Mamy Blue","uri":"http://192.168.1.55:8200/MediaItems/99.flac","trackType":"flac"} Jun 10 21:40:54 volumio volumio[910]: verbose: CURRENT POSITION 2 Jun 10 21:40:54 volumio volumio[910]: info: CoreStateMachine::syncState stateService play Jun 10 21:40:54 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus play Jun 10 21:40:54 volumio volumio[910]: info: Received an update from plugin. extracting info from payload Jun 10 21:40:54 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:40:54 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 21:40:54 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:40:54 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:40:54 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:40:54 volumio volumio[910]: info: ------------------------------ 85ms Jun 10 21:40:54 volumio volumio[910]: info: ------------------------------ 111ms Jun 10 21:40:54 volumio volumio[910]: info: sendMpdCommand playlistinfo took 76 milliseconds Jun 10 21:40:54 volumio volumio[910]: info: sendMpdCommand playlistinfo took 75 milliseconds Jun 10 21:40:54 volumio volumio[910]: verbose: ControllerMpd::parseTrackInfo Jun 10 21:40:54 volumio volumio[910]: verbose: ControllerMpd::parseTrackInfo Jun 10 21:40:54 volumio volumio[910]: info: ControllerMpd::pushState Jun 10 21:40:54 volumio volumio[910]: info: CoreCommandRouter::servicePushState Jun 10 21:40:54 volumio volumio[910]: info: CorePlayQueue::getTrack 2 Jun 10 21:40:54 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":151,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"980 Kbps","isStreaming":false,"title":"We Shall Dance","artist":"Paul Mauriat","album":"Mamy Blue","uri":"http://192.168.1.55:8200/MediaItems/99.flac","trackType":"flac"} Jun 10 21:40:54 volumio volumio[910]: verbose: CURRENT POSITION 2 Jun 10 21:40:54 volumio volumio[910]: info: CoreStateMachine::syncState stateService play Jun 10 21:40:54 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus play Jun 10 21:40:54 volumio volumio[910]: info: Received an update from plugin. extracting info from payload Jun 10 21:40:54 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:40:54 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:40:54 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:40:54 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:40:54 volumio volumio[910]: info: ControllerMpd::pushState Jun 10 21:40:54 volumio volumio[910]: info: CoreCommandRouter::servicePushState Jun 10 21:40:54 volumio volumio[910]: info: CorePlayQueue::getTrack 2 Jun 10 21:40:54 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":151,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"980 Kbps","isStreaming":false,"title":"We Shall Dance","artist":"Paul Mauriat","album":"Mamy Blue","uri":"http://192.168.1.55:8200/MediaItems/99.flac","trackType":"flac"} Jun 10 21:40:54 volumio volumio[910]: verbose: CURRENT POSITION 2 Jun 10 21:40:54 volumio volumio[910]: info: CoreStateMachine::syncState stateService play Jun 10 21:40:54 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus play Jun 10 21:40:54 volumio volumio[910]: info: Received an update from plugin. extracting info from payload Jun 10 21:40:54 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:40:54 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:40:54 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:40:54 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:40:54 volumio volumio[910]: info: ------------------------------ 207ms Jun 10 21:40:54 volumio volumio[910]: info: ------------------------------ 187ms Jun 10 21:40:54 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Jun 10 21:40:54 volumio volumio[910]: info: CoreStateMachine::getQueue Jun 10 21:40:54 volumio volumio[910]: info: CorePlayQueue::getQueue Jun 10 21:40:54 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Jun 10 21:40:54 volumio volumio[910]: info: CoreStateMachine::getQueue Jun 10 21:40:54 volumio volumio[910]: info: CorePlayQueue::getQueue Jun 10 21:40:54 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Jun 10 21:40:54 volumio volumio[910]: info: CoreStateMachine::getQueue Jun 10 21:40:54 volumio volumio[910]: info: CorePlayQueue::getQueue Jun 10 21:40:54 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Jun 10 21:40:54 volumio volumio[910]: info: CoreStateMachine::getQueue Jun 10 21:40:54 volumio volumio[910]: info: CorePlayQueue::getQueue Jun 10 21:40:54 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Jun 10 21:40:54 volumio volumio[910]: info: CoreStateMachine::getQueue Jun 10 21:40:54 volumio volumio[910]: info: CorePlayQueue::getQueue Jun 10 21:40:54 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Jun 10 21:40:54 volumio volumio[910]: info: CoreStateMachine::getQueue Jun 10 21:40:54 volumio volumio[910]: info: CorePlayQueue::getQueue Jun 10 21:40:56 volumio volumio[910]: info: CoreCommandRouter::volumioSeek Jun 10 21:40:56 volumio volumio[910]: info: CoreStateMachine::seek Jun 10 21:40:56 volumio volumio[910]: info: CorePlayQueue::getTrack 2 Jun 10 21:40:56 volumio volumio[910]: info: TRACKBLOCK {"service":"upnp_browser","uri":"http://192.168.1.55:8200/MediaItems/99.flac","realUri":"upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$3$2","type":"song","albumart":"/albumart?cacheid=328&web=Paul%20Mauriat/Mamy%20Blue/extralarge&path=&metadata=false","artist":"Paul Mauriat","album":"Mamy Blue","name":"We Shall Dance","title":"We Shall Dance","duration":150} Jun 10 21:40:56 volumio volumio[910]: info: CoreStateMachine::startPlaybackTimer Jun 10 21:40:56 volumio volumio[910]: info: CorePlayQueue::getTrack 2 Jun 10 21:40:56 volumio volumio[910]: info: [1749591656529] ControllerUPNPBrowser::seek Jun 10 21:40:56 volumio volumio[910]: info: ControllerMpd::seek Jun 10 21:40:56 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:40:56 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 21:40:56 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:40:56 volumio volumio[910]: error: null Jun 10 21:40:56 volumio volumio[910]: info: Jun 10 21:40:56 volumio volumio[910]: ---------------------------- MPD announces state update: player Jun 10 21:40:56 volumio volumio[910]: info: ControllerMpd::getState Jun 10 21:40:56 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Jun 10 21:40:56 volumio volumio[910]: info: Jun 10 21:40:56 volumio volumio[910]: ---------------------------- MPD announces state update: player Jun 10 21:40:56 volumio volumio[910]: info: ControllerMpd::getState Jun 10 21:40:56 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Jun 10 21:40:56 volumio volumio[910]: info: Jun 10 21:40:56 volumio volumio[910]: ---------------------------- MPD announces state update: player Jun 10 21:40:56 volumio volumio[910]: info: ControllerMpd::getState Jun 10 21:40:56 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Jun 10 21:40:56 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Jun 10 21:40:56 volumio volumio[910]: info: CoreStateMachine::getQueue Jun 10 21:40:56 volumio volumio[910]: info: CorePlayQueue::getQueue Jun 10 21:40:56 volumio volumio[910]: info: sendMpdCommand status took 20 milliseconds Jun 10 21:40:56 volumio volumio[910]: info: sendMpdCommand status took 17 milliseconds Jun 10 21:40:56 volumio volumio[910]: info: sendMpdCommand status took 14 milliseconds Jun 10 21:40:56 volumio volumio[910]: verbose: ControllerMpd::parseState Jun 10 21:40:56 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 10 21:40:56 volumio volumio[910]: verbose: ControllerMpd::parseState Jun 10 21:40:56 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 10 21:40:56 volumio volumio[910]: verbose: ControllerMpd::parseState Jun 10 21:40:56 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 10 21:40:56 volumio volumio[910]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 10 21:40:56 volumio volumio[910]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 10 21:40:56 volumio volumio[910]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 10 21:40:56 volumio volumio[910]: verbose: ControllerMpd::parseTrackInfo Jun 10 21:40:56 volumio volumio[910]: verbose: ControllerMpd::parseTrackInfo Jun 10 21:40:56 volumio volumio[910]: verbose: ControllerMpd::parseTrackInfo Jun 10 21:40:56 volumio volumio[910]: info: ControllerMpd::pushState Jun 10 21:40:56 volumio volumio[910]: info: CoreCommandRouter::servicePushState Jun 10 21:40:56 volumio volumio[910]: info: CorePlayQueue::getTrack 2 Jun 10 21:40:56 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":15842,"duration":151,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1034 Kbps","isStreaming":false,"title":"We Shall Dance","artist":"Paul Mauriat","album":"Mamy Blue","uri":"http://192.168.1.55:8200/MediaItems/99.flac","trackType":"flac"} Jun 10 21:40:56 volumio volumio[910]: verbose: CURRENT POSITION 2 Jun 10 21:40:56 volumio volumio[910]: info: CoreStateMachine::syncState stateService play Jun 10 21:40:56 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus play Jun 10 21:40:56 volumio volumio[910]: info: Received an update from plugin. extracting info from payload Jun 10 21:40:56 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:40:56 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 21:40:56 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:40:56 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:40:56 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:40:56 volumio volumio[910]: info: ControllerMpd::pushState Jun 10 21:40:56 volumio volumio[910]: info: CoreCommandRouter::servicePushState Jun 10 21:40:56 volumio volumio[910]: info: CorePlayQueue::getTrack 2 Jun 10 21:40:56 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":15842,"duration":151,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1002 Kbps","isStreaming":false,"title":"We Shall Dance","artist":"Paul Mauriat","album":"Mamy Blue","uri":"http://192.168.1.55:8200/MediaItems/99.flac","trackType":"flac"} Jun 10 21:40:56 volumio volumio[910]: verbose: CURRENT POSITION 2 Jun 10 21:40:56 volumio volumio[910]: info: CoreStateMachine::syncState stateService play Jun 10 21:40:56 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus play Jun 10 21:40:56 volumio volumio[910]: info: Received an update from plugin. extracting info from payload Jun 10 21:40:56 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:40:56 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 21:40:56 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:40:56 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:40:56 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:40:56 volumio volumio[910]: info: ControllerMpd::pushState Jun 10 21:40:56 volumio volumio[910]: info: CoreCommandRouter::servicePushState Jun 10 21:40:56 volumio volumio[910]: info: CorePlayQueue::getTrack 2 Jun 10 21:40:56 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":15842,"duration":151,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1032 Kbps","isStreaming":false,"title":"We Shall Dance","artist":"Paul Mauriat","album":"Mamy Blue","uri":"http://192.168.1.55:8200/MediaItems/99.flac","trackType":"flac"} Jun 10 21:40:56 volumio volumio[910]: verbose: CURRENT POSITION 2 Jun 10 21:40:56 volumio volumio[910]: info: CoreStateMachine::syncState stateService play Jun 10 21:40:56 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus play Jun 10 21:40:56 volumio volumio[910]: info: Received an update from plugin. extracting info from payload Jun 10 21:40:56 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:40:56 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 21:40:56 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:40:56 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:40:56 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:40:56 volumio volumio[910]: info: ------------------------------ 110ms Jun 10 21:40:56 volumio volumio[910]: info: ------------------------------ 107ms Jun 10 21:40:56 volumio volumio[910]: info: ------------------------------ 105ms Jun 10 21:40:56 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Jun 10 21:40:56 volumio volumio[910]: info: CoreStateMachine::getQueue Jun 10 21:40:56 volumio volumio[910]: info: CorePlayQueue::getQueue Jun 10 21:40:56 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Jun 10 21:40:56 volumio volumio[910]: info: CoreStateMachine::getQueue Jun 10 21:40:56 volumio volumio[910]: info: CorePlayQueue::getQueue Jun 10 21:40:56 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Jun 10 21:40:56 volumio volumio[910]: info: CoreStateMachine::getQueue Jun 10 21:40:56 volumio volumio[910]: info: CorePlayQueue::getQueue Jun 10 21:40:56 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Jun 10 21:40:56 volumio volumio[910]: info: CoreStateMachine::getQueue Jun 10 21:40:56 volumio volumio[910]: info: CorePlayQueue::getQueue Jun 10 21:40:56 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Jun 10 21:40:56 volumio volumio[910]: info: CoreStateMachine::getQueue Jun 10 21:40:56 volumio volumio[910]: info: CorePlayQueue::getQueue Jun 10 21:40:56 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Jun 10 21:40:56 volumio volumio[910]: info: CoreStateMachine::getQueue Jun 10 21:40:56 volumio volumio[910]: info: CorePlayQueue::getQueue Jun 10 21:40:57 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Jun 10 21:40:57 volumio volumio[910]: info: Listing playlists Jun 10 21:40:57 volumio volumio[910]: info: Listing playlists Jun 10 21:41:07 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Jun 10 21:41:17 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Jun 10 21:41:17 volumio volumio[910]: info: Listing playlists Jun 10 21:41:17 volumio volumio[910]: info: Listing playlists Jun 10 21:41:20 volumio squeezeboxserver[1614]: [25-06-10 21:41:20.5483] main::main (202) Starting Logitech Media Server scanner (v8.3.1, 1676361197, Fri 17 Feb 2023 06:37:09 AM CET) perl 5.028001 Jun 10 21:41:22 volumio squeezeboxserver[1614]: [25-06-10 21:41:22.0177] Slim::Plugin::Deezer::Importer::isImportEnabled (42) Failed to get Deezer accounts: Can't connect to www.mysqueezebox.com:443 (No address associated with hostname) Jun 10 21:41:22 volumio squeezeboxserver[1614]: [25-06-10 21:41:22.0505] Slim::Music::Import::runImporter (579) Starting Slim::Plugin::WiMP::Importer scan Jun 10 21:41:22 volumio squeezeboxserver[1614]: [25-06-10 21:41:22.0636] Slim::Plugin::WiMP::Importer::startScan (44) Failed to get TIDAL accounts: Can't connect to www.mysqueezebox.com:443 (No address associated with hostname) Jun 10 21:41:22 volumio squeezeboxserver[1614]: [25-06-10 21:41:22.0648] Slim::Music::Import::runImporter (579) Starting Slim::Plugin::FullTextSearch::Plugin scan Jun 10 21:41:22 volumio squeezeboxserver[1614]: [25-06-10 21:41:22.0670] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (480) Starting fulltext index build Jun 10 21:41:22 volumio squeezeboxserver[1614]: [25-06-10 21:41:22.0678] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (488) Initialize fulltext table Jun 10 21:41:22 volumio squeezeboxserver[1614]: [25-06-10 21:41:22.0724] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (501) Create fulltext index for tracks Jun 10 21:41:22 volumio squeezeboxserver[1614]: [25-06-10 21:41:22.2593] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (511) Create fulltext index for albums Jun 10 21:41:22 volumio squeezeboxserver[1614]: [25-06-10 21:41:22.2656] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (520) Create fulltext index for contributors Jun 10 21:41:22 volumio squeezeboxserver[1614]: [25-06-10 21:41:22.2690] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (530) Create fulltext index for playlists Jun 10 21:41:22 volumio squeezeboxserver[1614]: [25-06-10 21:41:22.2776] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (545) Optimize fulltext index Jun 10 21:41:22 volumio squeezeboxserver[1614]: [25-06-10 21:41:22.2943] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (560) Fulltext index build done! Jun 10 21:41:22 volumio squeezeboxserver[1614]: [25-06-10 21:41:22.2955] Slim::Music::Import::endImporter (712) Completed Slim::Plugin::FullTextSearch::Plugin Scan in 0.231 seconds. Jun 10 21:41:22 volumio squeezeboxserver[1614]: [25-06-10 21:41:22.2970] Slim::Music::Import::runImporter (579) Starting Slim::Plugin::ExtendedBrowseModes::Libraries scan Jun 10 21:41:22 volumio squeezeboxserver[1614]: [25-06-10 21:41:22.2978] Slim::Music::Import::endImporter (712) Completed Slim::Plugin::ExtendedBrowseModes::Libraries Scan in 0.001 seconds. Jun 10 21:41:22 volumio squeezeboxserver[1614]: [25-06-10 21:41:22.2991] Slim::Music::Import::runImporter (579) Starting Slim::Plugin::OnlineLibrary::Importer::VirtualLibrariesCleanup scan Jun 10 21:41:22 volumio squeezeboxserver[1614]: [25-06-10 21:41:22.3017] Slim::Music::Import::endImporter (712) Completed Slim::Plugin::OnlineLibrary::Importer::VirtualLibrariesCleanup Scan in 0.002 seconds. Jun 10 21:41:22 volumio squeezeboxserver[1614]: [25-06-10 21:41:22.3047] Slim::Music::Artwork::updateStandaloneArtwork (234) Starting updateStandaloneArtwork for 0 albums Jun 10 21:41:22 volumio squeezeboxserver[1614]: [25-06-10 21:41:22.3055] Slim::Music::Import::endImporter (712) Completed updateStandaloneArtwork Scan in 0.002 seconds. Jun 10 21:41:22 volumio squeezeboxserver[1614]: [25-06-10 21:41:22.3079] Slim::Music::Artwork::precacheAllArtwork (657) Starting precacheArtwork for 0 albums Jun 10 21:41:22 volumio squeezeboxserver[1614]: [25-06-10 21:41:22.3088] Slim::Music::Import::endImporter (712) Completed precacheArtwork Scan in 0.002 seconds. Jun 10 21:41:22 volumio squeezeboxserver[1614]: [25-06-10 21:41:22.3103] Slim::Music::Import::runScanPostProcessing (481) Starting Database optimization. Jun 10 21:41:22 volumio squeezeboxserver[1614]: [25-06-10 21:41:22.6415] Slim::Music::Import::endImporter (712) Completed dbOptimize Scan in 0.330 seconds. Jun 10 21:41:24 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 10 21:41:24 volumio volumio[910]: Cannot compose Albumart path Jun 10 21:41:24 volumio volumio[910]: info: Preload queue cleared Jun 10 21:41:24 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$0 Jun 10 21:41:24 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$1 Jun 10 21:41:24 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$2 Jun 10 21:41:24 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$3 Jun 10 21:41:24 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$4 Jun 10 21:41:24 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$5 Jun 10 21:41:24 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$6 Jun 10 21:41:24 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$7 Jun 10 21:41:24 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$8 Jun 10 21:41:24 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$9 Jun 10 21:41:24 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$A Jun 10 21:41:24 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$B Jun 10 21:41:24 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$C Jun 10 21:41:24 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$D Jun 10 21:41:24 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$E Jun 10 21:41:24 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$F Jun 10 21:41:24 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$10 Jun 10 21:41:24 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$11 Jun 10 21:41:24 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$12 Jun 10 21:41:24 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$13 Jun 10 21:41:24 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$14 Jun 10 21:41:24 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$15 Jun 10 21:41:24 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$16 Jun 10 21:41:24 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$17 Jun 10 21:41:24 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$18 Jun 10 21:41:24 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$19 Jun 10 21:41:24 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$1A Jun 10 21:41:24 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$1B Jun 10 21:41:24 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$1C Jun 10 21:41:24 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$1D Jun 10 21:41:24 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$1E Jun 10 21:41:24 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$1F Jun 10 21:41:24 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$20 Jun 10 21:41:24 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$21 Jun 10 21:41:24 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$22 Jun 10 21:41:24 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$23 Jun 10 21:41:24 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$24 Jun 10 21:41:24 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$25 Jun 10 21:41:24 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$26 Jun 10 21:41:24 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$27 Jun 10 21:41:24 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$28 Jun 10 21:41:24 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$29 Jun 10 21:41:24 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$2A Jun 10 21:41:24 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$2B Jun 10 21:41:24 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$2C Jun 10 21:41:24 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$2D Jun 10 21:41:24 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$2E Jun 10 21:41:24 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$2F Jun 10 21:41:24 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$30 Jun 10 21:41:24 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$31 Jun 10 21:41:24 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$0 in service upnp_browser Jun 10 21:41:24 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$1 in service upnp_browser Jun 10 21:41:24 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$2 in service upnp_browser Jun 10 21:41:24 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$3 in service upnp_browser Jun 10 21:41:24 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$4 in service upnp_browser Jun 10 21:41:24 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$5 in service upnp_browser Jun 10 21:41:24 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$6 in service upnp_browser Jun 10 21:41:24 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$7 in service upnp_browser Jun 10 21:41:24 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$8 in service upnp_browser Jun 10 21:41:24 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$9 in service upnp_browser Jun 10 21:41:24 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$A in service upnp_browser Jun 10 21:41:24 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$B in service upnp_browser Jun 10 21:41:25 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$C in service upnp_browser Jun 10 21:41:25 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$D in service upnp_browser Jun 10 21:41:25 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$E in service upnp_browser Jun 10 21:41:25 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$F in service upnp_browser Jun 10 21:41:25 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$10 in service upnp_browser Jun 10 21:41:25 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$11 in service upnp_browser Jun 10 21:41:25 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$12 in service upnp_browser Jun 10 21:41:25 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$13 in service upnp_browser Jun 10 21:41:25 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$14 in service upnp_browser Jun 10 21:41:25 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$15 in service upnp_browser Jun 10 21:41:25 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$16 in service upnp_browser Jun 10 21:41:25 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$17 in service upnp_browser Jun 10 21:41:25 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$18 in service upnp_browser Jun 10 21:41:25 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$19 in service upnp_browser Jun 10 21:41:25 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$1A in service upnp_browser Jun 10 21:41:25 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$1B in service upnp_browser Jun 10 21:41:25 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$1C in service upnp_browser Jun 10 21:41:25 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$1D in service upnp_browser Jun 10 21:41:25 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$1E in service upnp_browser Jun 10 21:41:25 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$1F in service upnp_browser Jun 10 21:41:26 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$20 in service upnp_browser Jun 10 21:41:26 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$21 in service upnp_browser Jun 10 21:41:26 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$22 in service upnp_browser Jun 10 21:41:26 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$23 in service upnp_browser Jun 10 21:41:26 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$24 in service upnp_browser Jun 10 21:41:26 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$25 in service upnp_browser Jun 10 21:41:26 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$26 in service upnp_browser Jun 10 21:41:26 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$27 in service upnp_browser Jun 10 21:41:26 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$28 in service upnp_browser Jun 10 21:41:26 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$29 in service upnp_browser Jun 10 21:41:26 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$2A in service upnp_browser Jun 10 21:41:26 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$2B in service upnp_browser Jun 10 21:41:26 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$2C in service upnp_browser Jun 10 21:41:26 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$2D in service upnp_browser Jun 10 21:41:26 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$2E in service upnp_browser Jun 10 21:41:26 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$2F in service upnp_browser Jun 10 21:41:26 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$30 in service upnp_browser Jun 10 21:41:26 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 10 21:41:26 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$31 in service upnp_browser Jun 10 21:41:26 volumio volumio[910]: info: Preload queue cleared Jun 10 21:41:27 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Jun 10 21:41:28 volumio volumio[910]: info: Preload queue cleared Jun 10 21:41:28 volumio volumio[910]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 10 21:41:28 volumio volumio[910]: info: CoreStateMachine::ClearQueue Jun 10 21:41:28 volumio volumio[910]: info: CoreStateMachine::stop Jun 10 21:41:28 volumio volumio[910]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 10 21:41:28 volumio volumio[910]: info: CoreStateMachine::stPlaybackTimer Jun 10 21:41:28 volumio volumio[910]: info: CoreStateMachine::updateTrackBlock Jun 10 21:41:28 volumio volumio[910]: info: CorePlayQueue::getTrackBlock Jun 10 21:41:28 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:41:28 volumio volumio[910]: info: CorePlayQueue::getTrack 2 Jun 10 21:41:28 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 21:41:28 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:41:28 volumio volumio[910]: info: CoreStateMachine::serviceStop Jun 10 21:41:28 volumio volumio[910]: info: CorePlayQueue::getTrack 2 Jun 10 21:41:28 volumio volumio[910]: info: CoreCommandRouter::serviceStop Jun 10 21:41:28 volumio volumio[910]: info: [1749591688578] ControllerUPNPBrowser::stop Jun 10 21:41:28 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand stop Jun 10 21:41:28 volumio volumio[910]: info: CorePlayQueue::clearPlayQueue Jun 10 21:41:28 volumio volumio[910]: info: CorePlayQueue::saveQueue Jun 10 21:41:28 volumio volumio[910]: info: CoreCommandRouter::volumioPushQueue Jun 10 21:41:28 volumio volumio[910]: info: CoreStateMachine::addQueueItems Jun 10 21:41:28 volumio volumio[910]: info: CorePlayQueue::addQueueItems Jun 10 21:41:28 volumio volumio[910]: info: Preload queue cleared Jun 10 21:41:28 volumio volumio[910]: info: Adding Item to queue: upnp/folder/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$33$0 Jun 10 21:41:28 volumio volumio[910]: info: Exploding uri upnp/folder/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$33$0 in service upnp_browser Jun 10 21:41:28 volumio volumio[910]: info: CoreCommandRouter::volumioPushQueue Jun 10 21:41:28 volumio volumio[910]: info: CorePlayQueue::saveQueue Jun 10 21:41:28 volumio volumio[910]: info: CoreStateMachine::updateTrackBlock Jun 10 21:41:28 volumio volumio[910]: info: CorePlayQueue::getTrackBlock Jun 10 21:41:28 volumio volumio[910]: info: CoreCommandRouter::volumioPlay Jun 10 21:41:28 volumio volumio[910]: info: CoreStateMachine::play index 0 Jun 10 21:41:28 volumio volumio[910]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 10 21:41:28 volumio volumio[910]: info: CoreStateMachine::stop Jun 10 21:41:28 volumio volumio[910]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 10 21:41:28 volumio volumio[910]: info: CoreStateMachine::play index undefined Jun 10 21:41:28 volumio volumio[910]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 10 21:41:28 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:41:28 volumio volumio[910]: info: Jun 10 21:41:28 volumio volumio[910]: ---------------------------- MPD announces state update: player Jun 10 21:41:28 volumio volumio[910]: info: sendMpdCommand stop took 51 milliseconds Jun 10 21:41:28 volumio volumio[910]: info: ControllerMpd::getState Jun 10 21:41:28 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Jun 10 21:41:28 volumio volumio[910]: info: Jun 10 21:41:28 volumio volumio[910]: ---------------------------- MPD announces state update: player Jun 10 21:41:28 volumio volumio[910]: info: ControllerMpd::getState Jun 10 21:41:28 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Jun 10 21:41:28 volumio volumio[910]: info: Jun 10 21:41:28 volumio volumio[910]: ---------------------------- MPD announces state update: player Jun 10 21:41:28 volumio volumio[910]: info: ControllerMpd::getState Jun 10 21:41:28 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Jun 10 21:41:28 volumio volumio[910]: info: sendMpdCommand status took 11 milliseconds Jun 10 21:41:28 volumio volumio[910]: info: sendMpdCommand status took 7 milliseconds Jun 10 21:41:28 volumio volumio[910]: info: sendMpdCommand status took 6 milliseconds Jun 10 21:41:28 volumio volumio[910]: verbose: ControllerMpd::parseState Jun 10 21:41:28 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 10 21:41:28 volumio volumio[910]: verbose: ControllerMpd::parseState Jun 10 21:41:28 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 10 21:41:28 volumio volumio[910]: verbose: ControllerMpd::parseState Jun 10 21:41:28 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 10 21:41:28 volumio volumio[910]: info: sendMpdCommand playlistinfo took 6 milliseconds Jun 10 21:41:28 volumio volumio[910]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 10 21:41:28 volumio volumio[910]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 10 21:41:28 volumio volumio[910]: verbose: ControllerMpd::parseTrackInfo Jun 10 21:41:28 volumio volumio[910]: verbose: ControllerMpd::parseTrackInfo Jun 10 21:41:28 volumio volumio[910]: verbose: ControllerMpd::parseTrackInfo Jun 10 21:41:28 volumio volumio[910]: info: ControllerMpd::pushState Jun 10 21:41:28 volumio volumio[910]: info: CoreCommandRouter::servicePushState Jun 10 21:41:28 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:41:28 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:41:28 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 21:41:28 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:41:28 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:41:28 volumio volumio[910]: 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":"We Shall Dance","artist":"Paul Mauriat","album":"Mamy Blue","uri":"http://192.168.1.55:8200/MediaItems/99.flac","trackType":"flac"} Jun 10 21:41:28 volumio volumio[910]: verbose: CURRENT POSITION 0 Jun 10 21:41:28 volumio volumio[910]: info: CoreStateMachine::syncState stateService stop Jun 10 21:41:28 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus stop Jun 10 21:41:28 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:41:28 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:41:28 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:41:28 volumio volumio[910]: info: No code Jun 10 21:41:28 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:41:28 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:41:28 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:41:28 volumio volumio[910]: info: ControllerMpd::pushState Jun 10 21:41:28 volumio volumio[910]: info: CoreCommandRouter::servicePushState Jun 10 21:41:28 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:41:28 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:41:28 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:41:28 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:41:28 volumio volumio[910]: 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":"We Shall Dance","artist":"Paul Mauriat","album":"Mamy Blue","uri":"http://192.168.1.55:8200/MediaItems/99.flac","trackType":"flac"} Jun 10 21:41:28 volumio volumio[910]: verbose: CURRENT POSITION 0 Jun 10 21:41:28 volumio volumio[910]: info: CoreStateMachine::syncState stateService stop Jun 10 21:41:28 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus stop Jun 10 21:41:28 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:41:28 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:41:28 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:41:28 volumio volumio[910]: info: No code Jun 10 21:41:28 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:41:28 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:41:28 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:41:28 volumio volumio[910]: info: ControllerMpd::pushState Jun 10 21:41:28 volumio volumio[910]: info: CoreCommandRouter::servicePushState Jun 10 21:41:28 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:41:28 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:41:28 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:41:28 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:41:28 volumio volumio[910]: 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":"We Shall Dance","artist":"Paul Mauriat","album":"Mamy Blue","uri":"http://192.168.1.55:8200/MediaItems/99.flac","trackType":"flac"} Jun 10 21:41:28 volumio volumio[910]: verbose: CURRENT POSITION 0 Jun 10 21:41:28 volumio volumio[910]: info: CoreStateMachine::syncState stateService stop Jun 10 21:41:28 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus stop Jun 10 21:41:28 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:41:28 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:41:28 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:41:28 volumio volumio[910]: info: No code Jun 10 21:41:28 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:41:28 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:41:28 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:41:28 volumio volumio[910]: info: ------------------------------ 169ms Jun 10 21:41:28 volumio volumio[910]: info: ------------------------------ 166ms Jun 10 21:41:28 volumio volumio[910]: info: ------------------------------ 164ms Jun 10 21:41:33 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 10 21:41:33 volumio volumio[910]: info: Preload queue cleared Jun 10 21:41:37 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Jun 10 21:41:37 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:41:37 volumio volumio[910]: info: Listing playlists Jun 10 21:41:37 volumio volumio[910]: info: Listing playlists Jun 10 21:41:37 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 10 21:41:37 volumio volumio[910]: info: Preload queue cleared Jun 10 21:41:40 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 10 21:41:40 volumio volumio[910]: info: Preload queue cleared Jun 10 21:41:41 volumio nmbd[803]: [2025/06/10 21:41:41.416569, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jun 10 21:41:41 volumio nmbd[803]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.55 for name WORKGROUP<1d>. Jun 10 21:41:41 volumio nmbd[803]: This response was from IP 192.168.1.193, reporting an IP address of 192.168.1.193. Jun 10 21:41:45 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 10 21:41:45 volumio volumio[910]: info: Preload queue cleared Jun 10 21:41:46 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 10 21:41:46 volumio volumio[910]: info: Preload queue cleared Jun 10 21:41:47 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Jun 10 21:41:47 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:41:48 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 10 21:41:48 volumio volumio[910]: info: Preload queue cleared Jun 10 21:41:48 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$33$1$1$0 Jun 10 21:41:48 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$33$1$1$1 Jun 10 21:41:48 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$33$1$1$2 Jun 10 21:41:48 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$33$1$1$3 Jun 10 21:41:48 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$33$1$1$4 Jun 10 21:41:48 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$33$1$1$5 Jun 10 21:41:48 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$33$1$1$6 Jun 10 21:41:48 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$33$1$1$7 Jun 10 21:41:48 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$33$1$1$8 Jun 10 21:41:48 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$33$1$1$9 Jun 10 21:41:48 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$33$1$1$A Jun 10 21:41:48 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$33$1$1$B Jun 10 21:41:48 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$33$1$1$C Jun 10 21:41:48 volumio volumio[910]: info: Preloading song: upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$33$1$1$D Jun 10 21:41:48 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$33$1$1$0 in service upnp_browser Jun 10 21:41:48 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$33$1$1$1 in service upnp_browser Jun 10 21:41:48 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$33$1$1$2 in service upnp_browser Jun 10 21:41:48 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$33$1$1$3 in service upnp_browser Jun 10 21:41:48 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$33$1$1$4 in service upnp_browser Jun 10 21:41:48 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$33$1$1$5 in service upnp_browser Jun 10 21:41:48 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$33$1$1$6 in service upnp_browser Jun 10 21:41:48 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$33$1$1$7 in service upnp_browser Jun 10 21:41:48 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$33$1$1$8 in service upnp_browser Jun 10 21:41:48 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$33$1$1$9 in service upnp_browser Jun 10 21:41:48 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$33$1$1$A in service upnp_browser Jun 10 21:41:48 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$33$1$1$B in service upnp_browser Jun 10 21:41:48 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$33$1$1$C in service upnp_browser Jun 10 21:41:49 volumio volumio[910]: info: Exploding uri upnp/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$33$1$1$D in service upnp_browser Jun 10 21:41:50 volumio volumio[910]: info: Preload queue cleared Jun 10 21:41:50 volumio volumio[910]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 10 21:41:50 volumio volumio[910]: info: CoreStateMachine::ClearQueue Jun 10 21:41:50 volumio volumio[910]: info: CoreStateMachine::stop Jun 10 21:41:50 volumio volumio[910]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 10 21:41:50 volumio volumio[910]: info: CorePlayQueue::clearPlayQueue Jun 10 21:41:50 volumio volumio[910]: info: CorePlayQueue::saveQueue Jun 10 21:41:50 volumio volumio[910]: info: CoreCommandRouter::volumioPushQueue Jun 10 21:41:50 volumio volumio[910]: info: CoreStateMachine::addQueueItems Jun 10 21:41:50 volumio volumio[910]: info: CorePlayQueue::addQueueItems Jun 10 21:41:50 volumio volumio[910]: info: Preload queue cleared Jun 10 21:41:50 volumio volumio[910]: info: Adding Item to queue: upnp/folder/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$33$1$1 Jun 10 21:41:50 volumio volumio[910]: info: Exploding uri upnp/folder/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$33$1$1 in service upnp_browser Jun 10 21:41:50 volumio volumio[910]: info: CoreCommandRouter::volumioPushQueue Jun 10 21:41:50 volumio volumio[910]: info: CorePlayQueue::saveQueue Jun 10 21:41:50 volumio volumio[910]: info: CoreStateMachine::updateTrackBlock Jun 10 21:41:50 volumio volumio[910]: info: CorePlayQueue::getTrackBlock Jun 10 21:41:50 volumio volumio[910]: info: CoreCommandRouter::volumioPlay Jun 10 21:41:50 volumio volumio[910]: info: CoreStateMachine::play index 0 Jun 10 21:41:50 volumio volumio[910]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 10 21:41:50 volumio volumio[910]: info: CoreStateMachine::stop Jun 10 21:41:50 volumio volumio[910]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 10 21:41:50 volumio volumio[910]: info: CoreStateMachine::play index undefined Jun 10 21:41:50 volumio volumio[910]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 10 21:41:50 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:41:50 volumio volumio[910]: info: CoreStateMachine::startPlaybackTimer Jun 10 21:41:50 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:41:50 volumio volumio[910]: info: [1749591710258] ControllerUPNPBrowser::clearAddPlayTrack Jun 10 21:41:50 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand stop Jun 10 21:41:50 volumio volumio[910]: info: sendMpdCommand stop took 3 milliseconds Jun 10 21:41:50 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand clear Jun 10 21:41:50 volumio volumio[910]: info: Jun 10 21:41:50 volumio volumio[910]: ---------------------------- MPD announces system playlist update Jun 10 21:41:50 volumio volumio[910]: info: Ignoring MPD Status Update Jun 10 21:41:50 volumio volumio[910]: info: sendMpdCommand clear took 2 milliseconds Jun 10 21:41:50 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.55:8200/MediaItems/514.flac" Jun 10 21:41:50 volumio volumio[910]: info: Jun 10 21:41:50 volumio volumio[910]: ---------------------------- MPD announces system playlist update Jun 10 21:41:50 volumio volumio[910]: info: Ignoring MPD Status Update Jun 10 21:41:50 volumio volumio[910]: info: Jun 10 21:41:50 volumio volumio[910]: ---------------------------- MPD announces system playlist update Jun 10 21:41:50 volumio volumio[910]: info: Ignoring MPD Status Update Jun 10 21:41:50 volumio volumio[910]: error: updateQueue error: null Jun 10 21:41:50 volumio volumio[910]: info: ------------------------------ 8ms Jun 10 21:41:50 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.55:8200/MediaItems/514.flac" Jun 10 21:41:50 volumio volumio[910]: error: updateQueue error: null Jun 10 21:41:50 volumio volumio[910]: error: updateQueue error: null Jun 10 21:41:50 volumio volumio[910]: info: Jun 10 21:41:50 volumio volumio[910]: ---------------------------- MPD announces system playlist update Jun 10 21:41:50 volumio volumio[910]: info: Ignoring MPD Status Update Jun 10 21:41:50 volumio volumio[910]: info: ------------------------------ 39ms Jun 10 21:41:50 volumio volumio[910]: info: ------------------------------ 39ms Jun 10 21:41:50 volumio volumio[910]: info: sendMpdCommand add "http://192.168.1.55:8200/MediaItems/514.flac" took 4 milliseconds Jun 10 21:41:50 volumio volumio[910]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 10 21:41:50 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand play Jun 10 21:41:50 volumio volumio[910]: info: ------------------------------ 5ms Jun 10 21:41:50 volumio volumio[910]: info: Jun 10 21:41:50 volumio volumio[910]: ---------------------------- MPD announces system playlist update Jun 10 21:41:50 volumio volumio[910]: info: Ignoring MPD Status Update Jun 10 21:41:50 volumio volumio[910]: info: Jun 10 21:41:50 volumio volumio[910]: ---------------------------- MPD announces system playlist update Jun 10 21:41:50 volumio volumio[910]: info: Ignoring MPD Status Update Jun 10 21:41:50 volumio volumio[910]: info: sendMpdCommand play took 9 milliseconds Jun 10 21:41:50 volumio volumio[910]: info: ------------------------------ 5ms Jun 10 21:41:50 volumio volumio[910]: info: ------------------------------ 4ms Jun 10 21:41:50 volumio volumio[910]: info: Jun 10 21:41:50 volumio volumio[910]: ---------------------------- MPD announces state update: player Jun 10 21:41:50 volumio volumio[910]: info: ControllerMpd::getState Jun 10 21:41:50 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Jun 10 21:41:50 volumio volumio[910]: info: Jun 10 21:41:50 volumio volumio[910]: ---------------------------- MPD announces state update: player Jun 10 21:41:50 volumio volumio[910]: info: ControllerMpd::getState Jun 10 21:41:50 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Jun 10 21:41:50 volumio volumio[910]: info: Jun 10 21:41:50 volumio volumio[910]: ---------------------------- MPD announces state update: player Jun 10 21:41:50 volumio volumio[910]: info: ControllerMpd::getState Jun 10 21:41:50 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Jun 10 21:41:50 volumio volumio[910]: info: Jun 10 21:41:50 volumio volumio[910]: ---------------------------- MPD announces state update: player Jun 10 21:41:50 volumio volumio[910]: info: Jun 10 21:41:50 volumio volumio[910]: ---------------------------- MPD announces system playlist update Jun 10 21:41:50 volumio volumio[910]: info: Ignoring MPD Status Update Jun 10 21:41:50 volumio volumio[910]: info: Jun 10 21:41:50 volumio volumio[910]: ---------------------------- MPD announces state update: player Jun 10 21:41:50 volumio volumio[910]: info: ControllerMpd::getState Jun 10 21:41:50 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Jun 10 21:41:50 volumio volumio[910]: info: sendMpdCommand status took 13 milliseconds Jun 10 21:41:50 volumio volumio[910]: info: sendMpdCommand status took 10 milliseconds Jun 10 21:41:50 volumio volumio[910]: info: ControllerMpd::getState Jun 10 21:41:50 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Jun 10 21:41:50 volumio volumio[910]: verbose: ControllerMpd::parseState Jun 10 21:41:50 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 10 21:41:50 volumio volumio[910]: verbose: ControllerMpd::parseState Jun 10 21:41:50 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 10 21:41:50 volumio volumio[910]: info: Jun 10 21:41:50 volumio volumio[910]: ---------------------------- MPD announces state update: player Jun 10 21:41:50 volumio volumio[910]: info: ControllerMpd::getState Jun 10 21:41:50 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Jun 10 21:41:50 volumio volumio[910]: info: Jun 10 21:41:50 volumio volumio[910]: ---------------------------- MPD announces state update: player Jun 10 21:41:50 volumio volumio[910]: info: ControllerMpd::getState Jun 10 21:41:50 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Jun 10 21:41:50 volumio volumio[910]: info: sendMpdCommand status took 21 milliseconds Jun 10 21:41:50 volumio volumio[910]: info: sendMpdCommand status took 17 milliseconds Jun 10 21:41:50 volumio volumio[910]: info: ------------------------------ 20ms Jun 10 21:41:50 volumio volumio[910]: info: sendMpdCommand status took 17 milliseconds Jun 10 21:41:50 volumio volumio[910]: info: sendMpdCommand playlistinfo took 16 milliseconds Jun 10 21:41:50 volumio volumio[910]: info: sendMpdCommand playlistinfo took 15 milliseconds Jun 10 21:41:50 volumio volumio[910]: info: sendMpdCommand status took 13 milliseconds Jun 10 21:41:50 volumio volumio[910]: info: sendMpdCommand status took 12 milliseconds Jun 10 21:41:50 volumio volumio[910]: verbose: ControllerMpd::parseState Jun 10 21:41:50 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 10 21:41:50 volumio volumio[910]: verbose: ControllerMpd::parseState Jun 10 21:41:50 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 10 21:41:50 volumio volumio[910]: verbose: ControllerMpd::parseState Jun 10 21:41:50 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 10 21:41:50 volumio volumio[910]: verbose: ControllerMpd::parseTrackInfo Jun 10 21:41:50 volumio volumio[910]: verbose: ControllerMpd::parseTrackInfo Jun 10 21:41:50 volumio volumio[910]: verbose: ControllerMpd::parseState Jun 10 21:41:50 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 10 21:41:50 volumio volumio[910]: verbose: ControllerMpd::parseState Jun 10 21:41:50 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 10 21:41:50 volumio volumio[910]: info: ControllerMpd::pushState Jun 10 21:41:50 volumio volumio[910]: info: CoreCommandRouter::servicePushState Jun 10 21:41:50 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:41:50 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":284,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"I Believe I Can Fly","artist":"R. Kelly","album":"Kuschelrock 11 (CD 1)","uri":"http://192.168.1.55:8200/MediaItems/514.flac","trackType":"flac"} Jun 10 21:41:50 volumio volumio[910]: verbose: CURRENT POSITION 0 Jun 10 21:41:50 volumio volumio[910]: info: CoreStateMachine::syncState stateService play Jun 10 21:41:50 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus stop Jun 10 21:41:50 volumio volumio[910]: info: ControllerMpd::pushState Jun 10 21:41:50 volumio volumio[910]: info: CoreCommandRouter::servicePushState Jun 10 21:41:50 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:41:50 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":284,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"I Believe I Can Fly","artist":"R. Kelly","album":"Kuschelrock 11 (CD 1)","uri":"http://192.168.1.55:8200/MediaItems/514.flac","trackType":"flac"} Jun 10 21:41:50 volumio volumio[910]: verbose: CURRENT POSITION 0 Jun 10 21:41:50 volumio volumio[910]: info: CoreStateMachine::syncState stateService play Jun 10 21:41:50 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus play Jun 10 21:41:50 volumio volumio[910]: info: Received an update from plugin. extracting info from payload Jun 10 21:41:50 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:41:50 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 21:41:50 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:41:50 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:41:50 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:41:50 volumio volumio[910]: info: ------------------------------ 68ms Jun 10 21:41:50 volumio volumio[910]: info: ------------------------------ 92ms Jun 10 21:41:50 volumio volumio[910]: info: Jun 10 21:41:50 volumio volumio[910]: ---------------------------- MPD announces system playlist update Jun 10 21:41:50 volumio volumio[910]: info: Ignoring MPD Status Update Jun 10 21:41:50 volumio volumio[910]: info: Jun 10 21:41:50 volumio volumio[910]: ---------------------------- MPD announces state update: player Jun 10 21:41:50 volumio volumio[910]: info: ControllerMpd::getState Jun 10 21:41:50 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Jun 10 21:41:50 volumio volumio[910]: info: Jun 10 21:41:50 volumio volumio[910]: ---------------------------- MPD announces system playlist update Jun 10 21:41:50 volumio volumio[910]: info: Ignoring MPD Status Update Jun 10 21:41:50 volumio volumio[910]: info: Jun 10 21:41:50 volumio volumio[910]: ---------------------------- MPD announces state update: player Jun 10 21:41:50 volumio volumio[910]: info: ControllerMpd::getState Jun 10 21:41:50 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Jun 10 21:41:50 volumio volumio[910]: info: sendMpdCommand playlistinfo took 77 milliseconds Jun 10 21:41:50 volumio volumio[910]: info: sendMpdCommand playlistinfo took 77 milliseconds Jun 10 21:41:50 volumio volumio[910]: info: sendMpdCommand playlistinfo took 75 milliseconds Jun 10 21:41:50 volumio volumio[910]: info: sendMpdCommand playlistinfo took 74 milliseconds Jun 10 21:41:50 volumio volumio[910]: info: sendMpdCommand playlistinfo took 74 milliseconds Jun 10 21:41:50 volumio volumio[910]: info: ------------------------------ 21ms Jun 10 21:41:50 volumio volumio[910]: info: sendMpdCommand status took 18 milliseconds Jun 10 21:41:50 volumio volumio[910]: info: ------------------------------ 17ms Jun 10 21:41:50 volumio volumio[910]: info: sendMpdCommand status took 13 milliseconds Jun 10 21:41:50 volumio volumio[910]: verbose: ControllerMpd::parseTrackInfo Jun 10 21:41:50 volumio volumio[910]: verbose: ControllerMpd::parseTrackInfo Jun 10 21:41:50 volumio volumio[910]: verbose: ControllerMpd::parseTrackInfo Jun 10 21:41:50 volumio volumio[910]: verbose: ControllerMpd::parseState Jun 10 21:41:50 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 10 21:41:50 volumio volumio[910]: verbose: ControllerMpd::parseState Jun 10 21:41:50 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 10 21:41:50 volumio volumio[910]: info: ControllerMpd::pushState Jun 10 21:41:50 volumio volumio[910]: info: CoreCommandRouter::servicePushState Jun 10 21:41:50 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:41:50 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":284,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"776 Kbps","isStreaming":false,"title":"I Believe I Can Fly","artist":"R. Kelly","album":"Kuschelrock 11 (CD 1)","uri":"http://192.168.1.55:8200/MediaItems/514.flac","trackType":"flac"} Jun 10 21:41:50 volumio volumio[910]: verbose: CURRENT POSITION 0 Jun 10 21:41:50 volumio volumio[910]: info: CoreStateMachine::syncState stateService play Jun 10 21:41:50 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus play Jun 10 21:41:50 volumio volumio[910]: info: Received an update from plugin. extracting info from payload Jun 10 21:41:50 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:41:50 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 21:41:50 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:41:50 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:41:50 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:41:50 volumio volumio[910]: info: ControllerMpd::pushState Jun 10 21:41:50 volumio volumio[910]: info: CoreCommandRouter::servicePushState Jun 10 21:41:50 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:41:50 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":284,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"863 Kbps","isStreaming":false,"title":"I Believe I Can Fly","artist":"R. Kelly","album":"Kuschelrock 11 (CD 1)","uri":"http://192.168.1.55:8200/MediaItems/514.flac","trackType":"flac"} Jun 10 21:41:50 volumio volumio[910]: verbose: CURRENT POSITION 0 Jun 10 21:41:50 volumio volumio[910]: info: CoreStateMachine::syncState stateService play Jun 10 21:41:50 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus play Jun 10 21:41:50 volumio volumio[910]: info: Received an update from plugin. extracting info from payload Jun 10 21:41:50 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:41:50 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 21:41:50 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:41:50 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:41:50 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:41:50 volumio volumio[910]: info: ControllerMpd::pushState Jun 10 21:41:50 volumio volumio[910]: info: CoreCommandRouter::servicePushState Jun 10 21:41:50 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:41:50 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":284,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"886 Kbps","isStreaming":false,"title":"I Believe I Can Fly","artist":"R. Kelly","album":"Kuschelrock 11 (CD 1)","uri":"http://192.168.1.55:8200/MediaItems/514.flac","trackType":"flac"} Jun 10 21:41:50 volumio volumio[910]: verbose: CURRENT POSITION 0 Jun 10 21:41:50 volumio volumio[910]: info: CoreStateMachine::syncState stateService play Jun 10 21:41:50 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus play Jun 10 21:41:50 volumio volumio[910]: info: Received an update from plugin. extracting info from payload Jun 10 21:41:50 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:41:50 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 21:41:50 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:41:50 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:41:50 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:41:50 volumio volumio[910]: info: ControllerMpd::pushState Jun 10 21:41:50 volumio volumio[910]: info: CoreCommandRouter::servicePushState Jun 10 21:41:50 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:41:50 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":284,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"846 Kbps","isStreaming":false,"title":"I Believe I Can Fly","artist":"R. Kelly","album":"Kuschelrock 11 (CD 1)","uri":"http://192.168.1.55:8200/MediaItems/514.flac","trackType":"flac"} Jun 10 21:41:50 volumio volumio[910]: verbose: CURRENT POSITION 0 Jun 10 21:41:50 volumio volumio[910]: info: CoreStateMachine::syncState stateService play Jun 10 21:41:50 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus play Jun 10 21:41:50 volumio volumio[910]: info: Received an update from plugin. extracting info from payload Jun 10 21:41:50 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:41:50 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 21:41:50 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:41:50 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:41:50 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:41:50 volumio volumio[910]: info: ControllerMpd::pushState Jun 10 21:41:50 volumio volumio[910]: info: CoreCommandRouter::servicePushState Jun 10 21:41:50 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:41:50 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":284,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"846 Kbps","isStreaming":false,"title":"I Believe I Can Fly","artist":"R. Kelly","album":"Kuschelrock 11 (CD 1)","uri":"http://192.168.1.55:8200/MediaItems/514.flac","trackType":"flac"} Jun 10 21:41:50 volumio volumio[910]: verbose: CURRENT POSITION 0 Jun 10 21:41:50 volumio volumio[910]: info: CoreStateMachine::syncState stateService play Jun 10 21:41:50 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus play Jun 10 21:41:50 volumio volumio[910]: info: Received an update from plugin. extracting info from payload Jun 10 21:41:50 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:41:50 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:41:50 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:41:50 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:41:50 volumio volumio[910]: info: ------------------------------ 378ms Jun 10 21:41:50 volumio volumio[910]: info: ------------------------------ 377ms Jun 10 21:41:50 volumio volumio[910]: info: ------------------------------ 377ms Jun 10 21:41:50 volumio volumio[910]: info: ------------------------------ 372ms Jun 10 21:41:50 volumio volumio[910]: info: ------------------------------ 380ms Jun 10 21:41:50 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Jun 10 21:41:50 volumio volumio[910]: info: CoreStateMachine::getQueue Jun 10 21:41:50 volumio volumio[910]: info: CorePlayQueue::getQueue Jun 10 21:41:50 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Jun 10 21:41:50 volumio volumio[910]: info: CoreStateMachine::getQueue Jun 10 21:41:50 volumio volumio[910]: info: CorePlayQueue::getQueue Jun 10 21:41:50 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Jun 10 21:41:50 volumio volumio[910]: info: CoreStateMachine::getQueue Jun 10 21:41:50 volumio volumio[910]: info: CorePlayQueue::getQueue Jun 10 21:41:50 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Jun 10 21:41:50 volumio volumio[910]: info: CoreStateMachine::getQueue Jun 10 21:41:50 volumio volumio[910]: info: CorePlayQueue::getQueue Jun 10 21:41:50 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Jun 10 21:41:50 volumio volumio[910]: info: CoreStateMachine::getQueue Jun 10 21:41:50 volumio volumio[910]: info: CorePlayQueue::getQueue Jun 10 21:41:50 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Jun 10 21:41:50 volumio volumio[910]: info: CoreStateMachine::getQueue Jun 10 21:41:50 volumio volumio[910]: info: CorePlayQueue::getQueue Jun 10 21:41:50 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Jun 10 21:41:50 volumio volumio[910]: info: CoreStateMachine::getQueue Jun 10 21:41:50 volumio volumio[910]: info: CorePlayQueue::getQueue Jun 10 21:41:50 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Jun 10 21:41:50 volumio volumio[910]: info: CoreStateMachine::getQueue Jun 10 21:41:50 volumio volumio[910]: info: CorePlayQueue::getQueue Jun 10 21:41:50 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Jun 10 21:41:50 volumio volumio[910]: info: CoreStateMachine::getQueue Jun 10 21:41:50 volumio volumio[910]: info: CorePlayQueue::getQueue Jun 10 21:41:50 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Jun 10 21:41:50 volumio volumio[910]: info: CoreStateMachine::getQueue Jun 10 21:41:50 volumio volumio[910]: info: CorePlayQueue::getQueue Jun 10 21:41:50 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Jun 10 21:41:50 volumio volumio[910]: info: CoreStateMachine::getQueue Jun 10 21:41:50 volumio volumio[910]: info: CorePlayQueue::getQueue Jun 10 21:41:50 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Jun 10 21:41:50 volumio volumio[910]: info: CoreStateMachine::getQueue Jun 10 21:41:50 volumio volumio[910]: info: CorePlayQueue::getQueue Jun 10 21:41:50 volumio volumio[910]: info: sendMpdCommand playlistinfo took 373 milliseconds Jun 10 21:41:50 volumio volumio[910]: info: sendMpdCommand playlistinfo took 372 milliseconds Jun 10 21:41:50 volumio volumio[910]: verbose: ControllerMpd::parseTrackInfo Jun 10 21:41:50 volumio volumio[910]: verbose: ControllerMpd::parseTrackInfo Jun 10 21:41:50 volumio volumio[910]: info: ControllerMpd::pushState Jun 10 21:41:50 volumio volumio[910]: info: CoreCommandRouter::servicePushState Jun 10 21:41:50 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:41:50 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":284,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"846 Kbps","isStreaming":false,"title":"I Believe I Can Fly","artist":"R. Kelly","album":"Kuschelrock 11 (CD 1)","uri":"http://192.168.1.55:8200/MediaItems/514.flac","trackType":"flac"} Jun 10 21:41:50 volumio volumio[910]: verbose: CURRENT POSITION 0 Jun 10 21:41:50 volumio volumio[910]: info: CoreStateMachine::syncState stateService play Jun 10 21:41:50 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus play Jun 10 21:41:50 volumio volumio[910]: info: Received an update from plugin. extracting info from payload Jun 10 21:41:50 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:41:50 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 21:41:50 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:41:50 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:41:50 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:41:50 volumio volumio[910]: info: ControllerMpd::pushState Jun 10 21:41:50 volumio volumio[910]: info: CoreCommandRouter::servicePushState Jun 10 21:41:50 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:41:50 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":284,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"846 Kbps","isStreaming":false,"title":"I Believe I Can Fly","artist":"R. Kelly","album":"Kuschelrock 11 (CD 1)","uri":"http://192.168.1.55:8200/MediaItems/514.flac","trackType":"flac"} Jun 10 21:41:50 volumio volumio[910]: verbose: CURRENT POSITION 0 Jun 10 21:41:50 volumio volumio[910]: info: CoreStateMachine::syncState stateService play Jun 10 21:41:50 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus play Jun 10 21:41:50 volumio volumio[910]: info: Received an update from plugin. extracting info from payload Jun 10 21:41:50 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:41:50 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:41:50 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:41:50 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:41:50 volumio volumio[910]: info: ------------------------------ 452ms Jun 10 21:41:50 volumio volumio[910]: info: ------------------------------ 448ms Jun 10 21:41:50 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Jun 10 21:41:50 volumio volumio[910]: info: CoreStateMachine::getQueue Jun 10 21:41:50 volumio volumio[910]: info: CorePlayQueue::getQueue Jun 10 21:41:50 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Jun 10 21:41:50 volumio volumio[910]: info: CoreStateMachine::getQueue Jun 10 21:41:50 volumio volumio[910]: info: CorePlayQueue::getQueue Jun 10 21:41:50 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Jun 10 21:41:50 volumio volumio[910]: info: CoreStateMachine::getQueue Jun 10 21:41:50 volumio volumio[910]: info: CorePlayQueue::getQueue Jun 10 21:41:50 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Jun 10 21:41:50 volumio volumio[910]: info: CoreStateMachine::getQueue Jun 10 21:41:50 volumio volumio[910]: info: CorePlayQueue::getQueue Jun 10 21:41:52 volumio volumio[910]: info: CoreCommandRouter::volumioSeek Jun 10 21:41:52 volumio volumio[910]: info: CoreStateMachine::seek Jun 10 21:41:52 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:41:52 volumio volumio[910]: info: TRACKBLOCK {"service":"upnp_browser","uri":"http://192.168.1.55:8200/MediaItems/514.flac","realUri":"upnp/folder/http://192.168.1.55:8200/ctl/ContentDir@64$0$6$33$1$1","type":"song","albumart":"/albumart?cacheid=328&web=Various/Kuschelrock%2011%20(CD%201)/extralarge&path=&metadata=false","artist":"Various","album":"Kuschelrock 11 (CD 1)","name":"I Believe I Can Fly","title":"I Believe I Can Fly","duration":283} Jun 10 21:41:52 volumio volumio[910]: info: CoreStateMachine::startPlaybackTimer Jun 10 21:41:52 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:41:52 volumio volumio[910]: info: [1749591712795] ControllerUPNPBrowser::seek Jun 10 21:41:52 volumio volumio[910]: info: ControllerMpd::seek Jun 10 21:41:52 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:41:52 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 21:41:52 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:41:52 volumio volumio[910]: error: null Jun 10 21:41:52 volumio volumio[910]: info: Jun 10 21:41:52 volumio volumio[910]: ---------------------------- MPD announces state update: player Jun 10 21:41:52 volumio volumio[910]: info: ControllerMpd::getState Jun 10 21:41:52 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Jun 10 21:41:52 volumio volumio[910]: info: Jun 10 21:41:52 volumio volumio[910]: ---------------------------- MPD announces state update: player Jun 10 21:41:52 volumio volumio[910]: info: ControllerMpd::getState Jun 10 21:41:52 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Jun 10 21:41:52 volumio volumio[910]: info: Jun 10 21:41:52 volumio volumio[910]: ---------------------------- MPD announces state update: player Jun 10 21:41:52 volumio volumio[910]: info: ControllerMpd::getState Jun 10 21:41:52 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Jun 10 21:41:52 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Jun 10 21:41:52 volumio volumio[910]: info: CoreStateMachine::getQueue Jun 10 21:41:52 volumio volumio[910]: info: CorePlayQueue::getQueue Jun 10 21:41:52 volumio volumio[910]: info: sendMpdCommand status took 21 milliseconds Jun 10 21:41:52 volumio volumio[910]: info: sendMpdCommand status took 14 milliseconds Jun 10 21:41:52 volumio volumio[910]: info: sendMpdCommand status took 12 milliseconds Jun 10 21:41:52 volumio volumio[910]: verbose: ControllerMpd::parseState Jun 10 21:41:52 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 10 21:41:52 volumio volumio[910]: verbose: ControllerMpd::parseState Jun 10 21:41:52 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 10 21:41:52 volumio volumio[910]: verbose: ControllerMpd::parseState Jun 10 21:41:52 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 10 21:41:52 volumio volumio[910]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 10 21:41:52 volumio volumio[910]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 10 21:41:52 volumio volumio[910]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 10 21:41:52 volumio volumio[910]: verbose: ControllerMpd::parseTrackInfo Jun 10 21:41:52 volumio volumio[910]: verbose: ControllerMpd::parseTrackInfo Jun 10 21:41:52 volumio volumio[910]: verbose: ControllerMpd::parseTrackInfo Jun 10 21:41:52 volumio volumio[910]: info: ControllerMpd::pushState Jun 10 21:41:52 volumio volumio[910]: info: CoreCommandRouter::servicePushState Jun 10 21:41:52 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:41:52 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":43842,"duration":284,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"960 Kbps","isStreaming":false,"title":"I Believe I Can Fly","artist":"R. Kelly","album":"Kuschelrock 11 (CD 1)","uri":"http://192.168.1.55:8200/MediaItems/514.flac","trackType":"flac"} Jun 10 21:41:52 volumio volumio[910]: verbose: CURRENT POSITION 0 Jun 10 21:41:52 volumio volumio[910]: info: CoreStateMachine::syncState stateService play Jun 10 21:41:52 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus play Jun 10 21:41:52 volumio volumio[910]: info: Received an update from plugin. extracting info from payload Jun 10 21:41:52 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:41:52 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 21:41:52 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:41:52 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:41:52 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:41:52 volumio volumio[910]: info: ControllerMpd::pushState Jun 10 21:41:52 volumio volumio[910]: info: CoreCommandRouter::servicePushState Jun 10 21:41:52 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:41:52 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":43842,"duration":284,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"951 Kbps","isStreaming":false,"title":"I Believe I Can Fly","artist":"R. Kelly","album":"Kuschelrock 11 (CD 1)","uri":"http://192.168.1.55:8200/MediaItems/514.flac","trackType":"flac"} Jun 10 21:41:52 volumio volumio[910]: verbose: CURRENT POSITION 0 Jun 10 21:41:52 volumio volumio[910]: info: CoreStateMachine::syncState stateService play Jun 10 21:41:52 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus play Jun 10 21:41:52 volumio volumio[910]: info: Received an update from plugin. extracting info from payload Jun 10 21:41:52 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:41:52 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 21:41:52 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:41:52 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:41:52 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:41:52 volumio volumio[910]: info: ControllerMpd::pushState Jun 10 21:41:52 volumio volumio[910]: info: CoreCommandRouter::servicePushState Jun 10 21:41:52 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Jun 10 21:41:52 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":43842,"duration":284,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"951 Kbps","isStreaming":false,"title":"I Believe I Can Fly","artist":"R. Kelly","album":"Kuschelrock 11 (CD 1)","uri":"http://192.168.1.55:8200/MediaItems/514.flac","trackType":"flac"} Jun 10 21:41:52 volumio volumio[910]: verbose: CURRENT POSITION 0 Jun 10 21:41:52 volumio volumio[910]: info: CoreStateMachine::syncState stateService play Jun 10 21:41:52 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus play Jun 10 21:41:52 volumio volumio[910]: info: Received an update from plugin. extracting info from payload Jun 10 21:41:52 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:41:52 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:41:52 volumio volumio[910]: info: CoreStateMachine::pushState Jun 10 21:41:52 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Jun 10 21:41:52 volumio volumio[910]: info: ------------------------------ 137ms Jun 10 21:41:52 volumio volumio[910]: info: ------------------------------ 133ms Jun 10 21:41:52 volumio volumio[910]: info: ------------------------------ 130ms Jun 10 21:41:52 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Jun 10 21:41:52 volumio volumio[910]: info: CoreStateMachine::getQueue Jun 10 21:41:52 volumio volumio[910]: info: CorePlayQueue::getQueue Jun 10 21:41:52 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Jun 10 21:41:52 volumio volumio[910]: info: CoreStateMachine::getQueue Jun 10 21:41:52 volumio volumio[910]: info: CorePlayQueue::getQueue Jun 10 21:41:52 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Jun 10 21:41:52 volumio volumio[910]: info: CoreStateMachine::getQueue Jun 10 21:41:52 volumio volumio[910]: info: CorePlayQueue::getQueue Jun 10 21:41:52 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Jun 10 21:41:52 volumio volumio[910]: info: CoreStateMachine::getQueue Jun 10 21:41:52 volumio volumio[910]: info: CorePlayQueue::getQueue Jun 10 21:41:52 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Jun 10 21:41:52 volumio volumio[910]: info: CoreStateMachine::getQueue Jun 10 21:41:52 volumio volumio[910]: info: CorePlayQueue::getQueue Jun 10 21:41:52 volumio volumio[910]: info: CoreCommandRouter::volumioGetQueue Jun 10 21:41:52 volumio volumio[910]: info: CoreStateMachine::getQueue Jun 10 21:41:52 volumio volumio[910]: info: CorePlayQueue::getQueue Jun 10 21:41:57 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Jun 10 21:41:57 volumio volumio[910]: info: Listing playlists Jun 10 21:41:57 volumio volumio[910]: info: Listing playlists Jun 10 21:41:58 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 10 21:41:58 volumio volumio[910]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 10 21:41:58 volumio volumio[910]: TypeError: Cannot read property 'length' of undefined Jun 10 21:41:58 volumio volumio[910]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Jun 10 21:41:58 volumio volumio[910]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Jun 10 21:41:58 volumio volumio[910]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Jun 10 21:41:58 volumio volumio[910]: at Parser.emit (events.js:400:28) Jun 10 21:41:58 volumio volumio[910]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Jun 10 21:41:58 volumio volumio[910]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Jun 10 21:41:58 volumio volumio[910]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Jun 10 21:41:58 volumio volumio[910]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Jun 10 21:41:58 volumio volumio[910]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Jun 10 21:41:58 volumio volumio[910]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Jun 10 21:41:58 volumio volumio[910]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Jun 10 21:41:58 volumio volumio[910]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Jun 10 21:41:58 volumio volumio[910]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Jun 10 21:41:58 volumio volumio[910]: at IncomingMessage.emit (events.js:412:35) Jun 10 21:41:58 volumio volumio[910]: at endReadableNT (internal/streams/readable.js:1333:12) Jun 10 21:41:58 volumio volumio[910]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Jun 10 21:41:58 volumio volumio[910]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 10 21:41:58 volumio sudo[3252]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-10 21:40 Jun 10 21:41:58 volumio sudo[3252]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:50:12 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="39b0f8c200b2dcadf117e189b4b3632d"