Dec 11 23:06:00 volumio1 volumio[998]: info: Preload queue cleared Dec 11 23:06:00 volumio1 volumio[998]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 11 23:06:00 volumio1 volumio[998]: info: CoreStateMachine::ClearQueue Dec 11 23:06:00 volumio1 volumio[998]: info: CoreStateMachine::stop Dec 11 23:06:00 volumio1 volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 23:06:00 volumio1 volumio[998]: info: CorePlayQueue::clearPlayQueue Dec 11 23:06:00 volumio1 volumio[998]: info: CorePlayQueue::saveQueue Dec 11 23:06:00 volumio1 volumio[998]: info: CoreCommandRouter::volumioPushQueue Dec 11 23:06:00 volumio1 volumio[998]: info: CoreStateMachine::addQueueItems Dec 11 23:06:00 volumio1 volumio[998]: info: CorePlayQueue::addQueueItems Dec 11 23:06:00 volumio1 volumio[998]: info: Preload queue cleared Dec 11 23:06:00 volumio1 volumio[998]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99371708 Dec 11 23:06:00 volumio1 volumio[998]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Dec 11 23:06:00 volumio1 volumio[998]: info: CoreCommandRouter::volumioPushQueue Dec 11 23:06:00 volumio1 volumio[998]: info: CorePlayQueue::saveQueue Dec 11 23:06:00 volumio1 volumio[998]: info: CoreStateMachine::updateTrackBlock Dec 11 23:06:00 volumio1 volumio[998]: info: CorePlayQueue::getTrackBlock Dec 11 23:06:00 volumio1 volumio[998]: info: CoreCommandRouter::volumioPlay Dec 11 23:06:00 volumio1 volumio[998]: info: CoreStateMachine::play index 0 Dec 11 23:06:00 volumio1 volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 23:06:00 volumio1 volumio[998]: info: CoreStateMachine::stop Dec 11 23:06:00 volumio1 volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 23:06:00 volumio1 volumio[998]: info: CoreStateMachine::play index undefined Dec 11 23:06:00 volumio1 volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 23:06:00 volumio1 volumio[998]: info: CorePlayQueue::getTrack 0 Dec 11 23:06:00 volumio1 volumio[998]: info: CoreStateMachine::startPlaybackTimer Dec 11 23:06:00 volumio1 volumio[998]: info: CorePlayQueue::getTrack 0 Dec 11 23:06:00 volumio1 volumio[998]: info: [1765490760970] ControllerWebradio::clearAddPlayTrack Dec 11 23:06:00 volumio1 volumio[998]: verbose: ControllerMpd::sendMpdCommand stop Dec 11 23:06:00 volumio1 volumio[998]: info: sendMpdCommand stop took 4 milliseconds Dec 11 23:06:00 volumio1 volumio[998]: verbose: ControllerMpd::sendMpdCommand clear Dec 11 23:06:00 volumio1 volumio[998]: info: Dec 11 23:06:00 volumio1 volumio[998]: ---------------------------- MPD announces system playlist update Dec 11 23:06:00 volumio1 volumio[998]: info: Ignoring MPD Status Update Dec 11 23:06:00 volumio1 volumio[998]: info: sendMpdCommand clear took 2 milliseconds Dec 11 23:06:00 volumio1 volumio[998]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99371708" Dec 11 23:06:00 volumio1 volumio[998]: error: updateQueue error: null Dec 11 23:06:00 volumio1 volumio[998]: info: ------------------------------ 2ms Dec 11 23:06:01 volumio1 volumio[998]: info: Dec 11 23:06:01 volumio1 volumio[998]: ---------------------------- MPD announces system playlist update Dec 11 23:06:01 volumio1 volumio[998]: info: Ignoring MPD Status Update Dec 11 23:06:01 volumio1 volumio[998]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99371708" took 132 milliseconds Dec 11 23:06:01 volumio1 volumio[998]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 11 23:06:01 volumio1 volumio[998]: verbose: ControllerMpd::sendMpdCommand play Dec 11 23:06:01 volumio1 volumio[998]: info: ------------------------------ 3ms Dec 11 23:06:01 volumio1 volumio[998]: info: sendMpdCommand play took 2 milliseconds Dec 11 23:06:01 volumio1 volumio[998]: info: Dec 11 23:06:01 volumio1 volumio[998]: ---------------------------- MPD announces state update: player Dec 11 23:06:01 volumio1 volumio[998]: info: ControllerMpd::getState Dec 11 23:06:01 volumio1 volumio[998]: verbose: ControllerMpd::sendMpdCommand status Dec 11 23:06:01 volumio1 volumio[998]: info: Dec 11 23:06:01 volumio1 volumio[998]: ---------------------------- MPD announces state update: player Dec 11 23:06:01 volumio1 volumio[998]: info: ControllerMpd::getState Dec 11 23:06:01 volumio1 volumio[998]: verbose: ControllerMpd::sendMpdCommand status Dec 11 23:06:01 volumio1 volumio[998]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 11 23:06:01 volumio1 volumio[998]: info: sendMpdCommand status took 9 milliseconds Dec 11 23:06:01 volumio1 volumio[998]: verbose: ControllerMpd::parseState Dec 11 23:06:01 volumio1 volumio[998]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 23:06:01 volumio1 volumio[998]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 11 23:06:01 volumio1 volumio[998]: info: sendMpdCommand status took 18 milliseconds Dec 11 23:06:01 volumio1 volumio[998]: info: sendMpdCommand clearerror took 11 milliseconds Dec 11 23:06:01 volumio1 volumio[998]: info: sendMpdCommand playlistinfo took 9 milliseconds Dec 11 23:06:01 volumio1 volumio[998]: verbose: ControllerMpd::parseState Dec 11 23:06:01 volumio1 volumio[998]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 23:06:01 volumio1 volumio[998]: verbose: ControllerMpd::parseTrackInfo Dec 11 23:06:01 volumio1 volumio[998]: info: ControllerMpd::pushState Dec 11 23:06:01 volumio1 volumio[998]: info: CoreCommandRouter::servicePushState Dec 11 23:06:01 volumio1 volumio[998]: info: CoreStateMachine::pushState Dec 11 23:06:01 volumio1 volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 23:06:01 volumio1 volumio[998]: info: CoreCommandRouter::volumioPushState Dec 11 23:06:01 volumio1 volumio[998]: info: CorePlayQueue::getTrack 0 Dec 11 23:06:01 volumio1 volumio[998]: 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":"omrock1","artist":"OMROCK1","album":null,"uri":"http://listen.shoutcast.com/omrock1","trackType":""} Dec 11 23:06:01 volumio1 volumio[998]: verbose: CURRENT POSITION 0 Dec 11 23:06:01 volumio1 volumio[998]: info: CoreStateMachine::syncState stateService stop Dec 11 23:06:01 volumio1 volumio[998]: info: CoreStateMachine::syncState currentStatus stop Dec 11 23:06:01 volumio1 volumio[998]: info: CoreStateMachine::pushState Dec 11 23:06:01 volumio1 volumio[998]: info: CoreCommandRouter::volumioPushState Dec 11 23:06:01 volumio1 volumio[998]: info: No code Dec 11 23:06:01 volumio1 volumio[998]: info: CoreStateMachine::pushState Dec 11 23:06:01 volumio1 volumio[998]: info: CoreCommandRouter::volumioPushState Dec 11 23:06:01 volumio1 volumio[998]: info: ------------------------------ 64ms Dec 11 23:06:01 volumio1 volumio[998]: info: sendMpdCommand clearerror took 56 milliseconds Dec 11 23:06:01 volumio1 volumio[998]: info: sendMpdCommand playlistinfo took 55 milliseconds Dec 11 23:06:01 volumio1 volumio[998]: verbose: ControllerMpd::parseTrackInfo Dec 11 23:06:01 volumio1 volumio[998]: info: ControllerMpd::pushState Dec 11 23:06:01 volumio1 volumio[998]: info: CoreCommandRouter::servicePushState Dec 11 23:06:01 volumio1 volumio[998]: info: CoreStateMachine::pushState Dec 11 23:06:01 volumio1 volumio[998]: info: CoreCommandRouter::volumioPushState Dec 11 23:06:01 volumio1 volumio[998]: info: CorePlayQueue::getTrack 0 Dec 11 23:06:01 volumio1 volumio[998]: 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":"omrock1","artist":"OMROCK1","album":null,"uri":"http://listen.shoutcast.com/omrock1","trackType":""} Dec 11 23:06:01 volumio1 volumio[998]: verbose: CURRENT POSITION 0 Dec 11 23:06:01 volumio1 volumio[998]: info: CoreStateMachine::syncState stateService stop Dec 11 23:06:01 volumio1 volumio[998]: info: CoreStateMachine::syncState currentStatus stop Dec 11 23:06:01 volumio1 volumio[998]: info: CoreStateMachine::pushState Dec 11 23:06:01 volumio1 volumio[998]: info: CoreCommandRouter::volumioPushState Dec 11 23:06:01 volumio1 volumio[998]: info: No code Dec 11 23:06:01 volumio1 volumio[998]: info: CoreStateMachine::pushState Dec 11 23:06:01 volumio1 volumio[998]: info: CoreCommandRouter::volumioPushState Dec 11 23:06:01 volumio1 volumio[998]: info: ------------------------------ 116ms Dec 11 23:06:03 volumio1 volumio[998]: info: Preload queue cleared Dec 11 23:06:03 volumio1 volumio[998]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 11 23:06:03 volumio1 volumio[998]: info: CoreStateMachine::ClearQueue Dec 11 23:06:03 volumio1 volumio[998]: info: CoreStateMachine::stop Dec 11 23:06:03 volumio1 volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 23:06:03 volumio1 volumio[998]: info: CorePlayQueue::clearPlayQueue Dec 11 23:06:03 volumio1 volumio[998]: info: CorePlayQueue::saveQueue Dec 11 23:06:03 volumio1 volumio[998]: info: CoreCommandRouter::volumioPushQueue Dec 11 23:06:03 volumio1 volumio[998]: info: CoreStateMachine::addQueueItems Dec 11 23:06:03 volumio1 volumio[998]: info: CorePlayQueue::addQueueItems Dec 11 23:06:03 volumio1 volumio[998]: info: Preload queue cleared Dec 11 23:06:03 volumio1 volumio[998]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99371708 Dec 11 23:06:03 volumio1 volumio[998]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Dec 11 23:06:03 volumio1 volumio[998]: info: CoreCommandRouter::volumioPushQueue Dec 11 23:06:03 volumio1 volumio[998]: info: CorePlayQueue::saveQueue Dec 11 23:06:03 volumio1 volumio[998]: info: CoreStateMachine::updateTrackBlock Dec 11 23:06:03 volumio1 volumio[998]: info: CorePlayQueue::getTrackBlock Dec 11 23:06:03 volumio1 volumio[998]: info: CoreCommandRouter::volumioPlay Dec 11 23:06:03 volumio1 volumio[998]: info: CoreStateMachine::play index 0 Dec 11 23:06:03 volumio1 volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 23:06:03 volumio1 volumio[998]: info: CoreStateMachine::stop Dec 11 23:06:03 volumio1 volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 23:06:03 volumio1 volumio[998]: info: CoreStateMachine::play index undefined Dec 11 23:06:03 volumio1 volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 23:06:03 volumio1 volumio[998]: info: CorePlayQueue::getTrack 0 Dec 11 23:06:03 volumio1 volumio[998]: info: CoreStateMachine::startPlaybackTimer Dec 11 23:06:03 volumio1 volumio[998]: info: CorePlayQueue::getTrack 0 Dec 11 23:06:03 volumio1 volumio[998]: info: [1765490763205] ControllerWebradio::clearAddPlayTrack Dec 11 23:06:03 volumio1 volumio[998]: verbose: ControllerMpd::sendMpdCommand stop Dec 11 23:06:03 volumio1 volumio[998]: info: sendMpdCommand stop took 5 milliseconds Dec 11 23:06:03 volumio1 volumio[998]: verbose: ControllerMpd::sendMpdCommand clear Dec 11 23:06:03 volumio1 volumio[998]: info: Dec 11 23:06:03 volumio1 volumio[998]: ---------------------------- MPD announces system playlist update Dec 11 23:06:03 volumio1 volumio[998]: info: Ignoring MPD Status Update Dec 11 23:06:03 volumio1 volumio[998]: info: sendMpdCommand clear took 1 milliseconds Dec 11 23:06:03 volumio1 volumio[998]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99371708" Dec 11 23:06:03 volumio1 volumio[998]: error: updateQueue error: null Dec 11 23:06:03 volumio1 volumio[998]: info: ------------------------------ 3ms Dec 11 23:06:03 volumio1 volumio[998]: info: Dec 11 23:06:03 volumio1 volumio[998]: ---------------------------- MPD announces system playlist update Dec 11 23:06:03 volumio1 volumio[998]: info: Ignoring MPD Status Update Dec 11 23:06:03 volumio1 volumio[998]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99371708" took 135 milliseconds Dec 11 23:06:03 volumio1 volumio[998]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 11 23:06:03 volumio1 volumio[998]: verbose: ControllerMpd::sendMpdCommand play Dec 11 23:06:03 volumio1 volumio[998]: info: ------------------------------ 2ms Dec 11 23:06:03 volumio1 volumio[998]: info: sendMpdCommand play took 2 milliseconds Dec 11 23:06:03 volumio1 volumio[998]: info: Dec 11 23:06:03 volumio1 volumio[998]: ---------------------------- MPD announces state update: player Dec 11 23:06:03 volumio1 volumio[998]: info: ControllerMpd::getState Dec 11 23:06:03 volumio1 volumio[998]: verbose: ControllerMpd::sendMpdCommand status Dec 11 23:06:03 volumio1 volumio[998]: info: Dec 11 23:06:03 volumio1 volumio[998]: ---------------------------- MPD announces state update: player Dec 11 23:06:03 volumio1 volumio[998]: info: ControllerMpd::getState Dec 11 23:06:03 volumio1 volumio[998]: verbose: ControllerMpd::sendMpdCommand status Dec 11 23:06:03 volumio1 volumio[998]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 11 23:06:03 volumio1 volumio[998]: info: sendMpdCommand status took 9 milliseconds Dec 11 23:06:03 volumio1 volumio[998]: verbose: ControllerMpd::parseState Dec 11 23:06:03 volumio1 volumio[998]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 23:06:03 volumio1 volumio[998]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 11 23:06:03 volumio1 volumio[998]: info: sendMpdCommand status took 18 milliseconds Dec 11 23:06:03 volumio1 volumio[998]: info: sendMpdCommand clearerror took 10 milliseconds Dec 11 23:06:03 volumio1 volumio[998]: info: sendMpdCommand playlistinfo took 9 milliseconds Dec 11 23:06:03 volumio1 volumio[998]: verbose: ControllerMpd::parseState Dec 11 23:06:03 volumio1 volumio[998]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 23:06:03 volumio1 volumio[998]: verbose: ControllerMpd::parseTrackInfo Dec 11 23:06:03 volumio1 volumio[998]: info: ControllerMpd::pushState Dec 11 23:06:03 volumio1 volumio[998]: info: CoreCommandRouter::servicePushState Dec 11 23:06:03 volumio1 volumio[998]: info: CoreStateMachine::pushState Dec 11 23:06:03 volumio1 volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 23:06:03 volumio1 volumio[998]: info: CoreCommandRouter::volumioPushState Dec 11 23:06:03 volumio1 volumio[998]: info: CorePlayQueue::getTrack 0 Dec 11 23:06:03 volumio1 volumio[998]: 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":"omrock1","artist":"OMROCK1","album":null,"uri":"http://listen.shoutcast.com/omrock1","trackType":""} Dec 11 23:06:03 volumio1 volumio[998]: verbose: CURRENT POSITION 0 Dec 11 23:06:03 volumio1 volumio[998]: info: CoreStateMachine::syncState stateService stop Dec 11 23:06:03 volumio1 volumio[998]: info: CoreStateMachine::syncState currentStatus stop Dec 11 23:06:03 volumio1 volumio[998]: info: CoreStateMachine::pushState Dec 11 23:06:03 volumio1 volumio[998]: info: CoreCommandRouter::volumioPushState Dec 11 23:06:03 volumio1 volumio[998]: info: No code Dec 11 23:06:03 volumio1 volumio[998]: info: CoreStateMachine::pushState Dec 11 23:06:03 volumio1 volumio[998]: info: CoreCommandRouter::volumioPushState Dec 11 23:06:03 volumio1 volumio[998]: info: ------------------------------ 60ms Dec 11 23:06:03 volumio1 volumio[998]: info: sendMpdCommand clearerror took 54 milliseconds Dec 11 23:06:03 volumio1 volumio[998]: info: sendMpdCommand playlistinfo took 52 milliseconds Dec 11 23:06:03 volumio1 volumio[998]: verbose: ControllerMpd::parseTrackInfo Dec 11 23:06:03 volumio1 volumio[998]: info: ControllerMpd::pushState Dec 11 23:06:03 volumio1 volumio[998]: info: CoreCommandRouter::servicePushState Dec 11 23:06:03 volumio1 volumio[998]: info: CoreStateMachine::pushState Dec 11 23:06:03 volumio1 volumio[998]: info: CoreCommandRouter::volumioPushState Dec 11 23:06:03 volumio1 volumio[998]: info: CorePlayQueue::getTrack 0 Dec 11 23:06:03 volumio1 volumio[998]: 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":"omrock1","artist":"OMROCK1","album":null,"uri":"http://listen.shoutcast.com/omrock1","trackType":""} Dec 11 23:06:03 volumio1 volumio[998]: verbose: CURRENT POSITION 0 Dec 11 23:06:03 volumio1 volumio[998]: info: CoreStateMachine::syncState stateService stop Dec 11 23:06:03 volumio1 volumio[998]: info: CoreStateMachine::syncState currentStatus stop Dec 11 23:06:03 volumio1 volumio[998]: info: CoreStateMachine::pushState Dec 11 23:06:03 volumio1 volumio[998]: info: CoreCommandRouter::volumioPushState Dec 11 23:06:03 volumio1 volumio[998]: info: No code Dec 11 23:06:03 volumio1 volumio[998]: info: CoreStateMachine::pushState Dec 11 23:06:03 volumio1 volumio[998]: info: CoreCommandRouter::volumioPushState Dec 11 23:06:03 volumio1 volumio[998]: info: ------------------------------ 113ms Dec 11 23:06:05 volumio1 volumio[998]: info: Preload queue cleared Dec 11 23:06:05 volumio1 volumio[998]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 11 23:06:05 volumio1 volumio[998]: info: CoreStateMachine::ClearQueue Dec 11 23:06:05 volumio1 volumio[998]: info: CoreStateMachine::stop Dec 11 23:06:05 volumio1 volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 23:06:05 volumio1 volumio[998]: info: CorePlayQueue::clearPlayQueue Dec 11 23:06:05 volumio1 volumio[998]: info: CorePlayQueue::saveQueue Dec 11 23:06:05 volumio1 volumio[998]: info: CoreCommandRouter::volumioPushQueue Dec 11 23:06:05 volumio1 volumio[998]: info: CoreStateMachine::addQueueItems Dec 11 23:06:05 volumio1 volumio[998]: info: CorePlayQueue::addQueueItems Dec 11 23:06:05 volumio1 volumio[998]: info: Preload queue cleared Dec 11 23:06:05 volumio1 volumio[998]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99349784 Dec 11 23:06:05 volumio1 volumio[998]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Dec 11 23:06:05 volumio1 volumio[998]: info: CoreCommandRouter::volumioPushQueue Dec 11 23:06:05 volumio1 volumio[998]: info: CorePlayQueue::saveQueue Dec 11 23:06:05 volumio1 volumio[998]: info: CoreStateMachine::updateTrackBlock Dec 11 23:06:05 volumio1 volumio[998]: info: CorePlayQueue::getTrackBlock Dec 11 23:06:05 volumio1 volumio[998]: info: CoreCommandRouter::volumioPlay Dec 11 23:06:05 volumio1 volumio[998]: info: CoreStateMachine::play index 0 Dec 11 23:06:05 volumio1 volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 23:06:05 volumio1 volumio[998]: info: CoreStateMachine::stop Dec 11 23:06:05 volumio1 volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 23:06:05 volumio1 volumio[998]: info: CoreStateMachine::play index undefined Dec 11 23:06:05 volumio1 volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 23:06:05 volumio1 volumio[998]: info: CorePlayQueue::getTrack 0 Dec 11 23:06:05 volumio1 volumio[998]: info: CoreStateMachine::startPlaybackTimer Dec 11 23:06:05 volumio1 volumio[998]: info: CorePlayQueue::getTrack 0 Dec 11 23:06:05 volumio1 volumio[998]: info: [1765490765872] ControllerWebradio::clearAddPlayTrack Dec 11 23:06:05 volumio1 volumio[998]: verbose: ControllerMpd::sendMpdCommand stop Dec 11 23:06:05 volumio1 volumio[998]: info: sendMpdCommand stop took 4 milliseconds Dec 11 23:06:05 volumio1 volumio[998]: verbose: ControllerMpd::sendMpdCommand clear Dec 11 23:06:05 volumio1 volumio[998]: info: Dec 11 23:06:05 volumio1 volumio[998]: ---------------------------- MPD announces system playlist update Dec 11 23:06:05 volumio1 volumio[998]: info: Ignoring MPD Status Update Dec 11 23:06:05 volumio1 volumio[998]: info: sendMpdCommand clear took 2 milliseconds Dec 11 23:06:05 volumio1 volumio[998]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99349784" Dec 11 23:06:05 volumio1 volumio[998]: error: updateQueue error: null Dec 11 23:06:05 volumio1 volumio[998]: info: ------------------------------ 2ms Dec 11 23:06:06 volumio1 volumio[998]: info: Dec 11 23:06:06 volumio1 volumio[998]: ---------------------------- MPD announces system playlist update Dec 11 23:06:06 volumio1 volumio[998]: info: Ignoring MPD Status Update Dec 11 23:06:06 volumio1 volumio[998]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99349784" took 139 milliseconds Dec 11 23:06:06 volumio1 volumio[998]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 11 23:06:06 volumio1 volumio[998]: verbose: ControllerMpd::sendMpdCommand play Dec 11 23:06:06 volumio1 volumio[998]: info: ------------------------------ 3ms Dec 11 23:06:06 volumio1 volumio[998]: info: sendMpdCommand play took 2 milliseconds Dec 11 23:06:06 volumio1 volumio[998]: info: Dec 11 23:06:06 volumio1 volumio[998]: ---------------------------- MPD announces state update: player Dec 11 23:06:06 volumio1 volumio[998]: info: ControllerMpd::getState Dec 11 23:06:06 volumio1 volumio[998]: verbose: ControllerMpd::sendMpdCommand status Dec 11 23:06:06 volumio1 volumio[998]: info: Dec 11 23:06:06 volumio1 volumio[998]: ---------------------------- MPD announces state update: player Dec 11 23:06:06 volumio1 volumio[998]: info: ControllerMpd::getState Dec 11 23:06:06 volumio1 volumio[998]: verbose: ControllerMpd::sendMpdCommand status Dec 11 23:06:06 volumio1 volumio[998]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 11 23:06:06 volumio1 volumio[998]: info: sendMpdCommand status took 10 milliseconds Dec 11 23:06:06 volumio1 volumio[998]: verbose: ControllerMpd::parseState Dec 11 23:06:06 volumio1 volumio[998]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 23:06:06 volumio1 volumio[998]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 11 23:06:06 volumio1 volumio[998]: info: sendMpdCommand status took 17 milliseconds Dec 11 23:06:06 volumio1 volumio[998]: info: sendMpdCommand clearerror took 9 milliseconds Dec 11 23:06:06 volumio1 volumio[998]: info: sendMpdCommand playlistinfo took 9 milliseconds Dec 11 23:06:06 volumio1 volumio[998]: verbose: ControllerMpd::parseState Dec 11 23:06:06 volumio1 volumio[998]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 23:06:06 volumio1 volumio[998]: verbose: ControllerMpd::parseTrackInfo Dec 11 23:06:06 volumio1 volumio[998]: info: ControllerMpd::pushState Dec 11 23:06:06 volumio1 volumio[998]: info: CoreCommandRouter::servicePushState Dec 11 23:06:06 volumio1 volumio[998]: info: CoreStateMachine::pushState Dec 11 23:06:06 volumio1 volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 23:06:06 volumio1 volumio[998]: info: CoreCommandRouter::volumioPushState Dec 11 23:06:06 volumio1 volumio[998]: info: CorePlayQueue::getTrack 0 Dec 11 23:06:06 volumio1 volumio[998]: 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":"omroepmoonlight","artist":"omroep moonlight","album":null,"uri":"http://listen.shoutcast.com/omroepmoonlight","trackType":""} Dec 11 23:06:06 volumio1 volumio[998]: verbose: CURRENT POSITION 0 Dec 11 23:06:06 volumio1 volumio[998]: info: CoreStateMachine::syncState stateService stop Dec 11 23:06:06 volumio1 volumio[998]: info: CoreStateMachine::syncState currentStatus stop Dec 11 23:06:06 volumio1 volumio[998]: info: CoreStateMachine::pushState Dec 11 23:06:06 volumio1 volumio[998]: info: CoreCommandRouter::volumioPushState Dec 11 23:06:06 volumio1 volumio[998]: info: No code Dec 11 23:06:06 volumio1 volumio[998]: info: CoreStateMachine::pushState Dec 11 23:06:06 volumio1 volumio[998]: info: CoreCommandRouter::volumioPushState Dec 11 23:06:06 volumio1 volumio[998]: info: ------------------------------ 65ms Dec 11 23:06:06 volumio1 volumio[998]: info: sendMpdCommand clearerror took 62 milliseconds Dec 11 23:06:06 volumio1 volumio[998]: info: sendMpdCommand playlistinfo took 60 milliseconds Dec 11 23:06:06 volumio1 volumio[998]: verbose: ControllerMpd::parseTrackInfo Dec 11 23:06:06 volumio1 volumio[998]: info: ControllerMpd::pushState Dec 11 23:06:06 volumio1 volumio[998]: info: CoreCommandRouter::servicePushState Dec 11 23:06:06 volumio1 volumio[998]: info: CoreStateMachine::pushState Dec 11 23:06:06 volumio1 volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 23:06:06 volumio1 volumio[998]: info: CoreCommandRouter::volumioPushState Dec 11 23:06:06 volumio1 volumio[998]: info: CorePlayQueue::getTrack 0 Dec 11 23:06:06 volumio1 volumio[998]: 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":"omroepmoonlight","artist":"omroep moonlight","album":null,"uri":"http://listen.shoutcast.com/omroepmoonlight","trackType":""} Dec 11 23:06:06 volumio1 volumio[998]: verbose: CURRENT POSITION 0 Dec 11 23:06:06 volumio1 volumio[998]: info: CoreStateMachine::syncState stateService stop Dec 11 23:06:06 volumio1 volumio[998]: info: CoreStateMachine::syncState currentStatus stop Dec 11 23:06:06 volumio1 volumio[998]: info: CoreStateMachine::pushState Dec 11 23:06:06 volumio1 volumio[998]: info: CoreCommandRouter::volumioPushState Dec 11 23:06:06 volumio1 volumio[998]: info: No code Dec 11 23:06:06 volumio1 volumio[998]: info: CoreStateMachine::pushState Dec 11 23:06:06 volumio1 volumio[998]: info: CoreCommandRouter::volumioPushState Dec 11 23:06:06 volumio1 volumio[998]: info: ------------------------------ 116ms Dec 11 23:06:08 volumio1 volumio[998]: info: Preload queue cleared Dec 11 23:06:08 volumio1 volumio[998]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 11 23:06:08 volumio1 volumio[998]: info: CoreStateMachine::ClearQueue Dec 11 23:06:08 volumio1 volumio[998]: info: CoreStateMachine::stop Dec 11 23:06:08 volumio1 volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 23:06:08 volumio1 volumio[998]: info: CorePlayQueue::clearPlayQueue Dec 11 23:06:08 volumio1 volumio[998]: info: CorePlayQueue::saveQueue Dec 11 23:06:08 volumio1 volumio[998]: info: CoreCommandRouter::volumioPushQueue Dec 11 23:06:08 volumio1 volumio[998]: info: CoreStateMachine::addQueueItems Dec 11 23:06:08 volumio1 volumio[998]: info: CorePlayQueue::addQueueItems Dec 11 23:06:08 volumio1 volumio[998]: info: Preload queue cleared Dec 11 23:06:08 volumio1 volumio[998]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99374856 Dec 11 23:06:08 volumio1 volumio[998]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Dec 11 23:06:08 volumio1 volumio[998]: info: CoreCommandRouter::volumioPushQueue Dec 11 23:06:08 volumio1 volumio[998]: info: CorePlayQueue::saveQueue Dec 11 23:06:08 volumio1 volumio[998]: info: CoreStateMachine::updateTrackBlock Dec 11 23:06:08 volumio1 volumio[998]: info: CorePlayQueue::getTrackBlock Dec 11 23:06:08 volumio1 volumio[998]: info: CoreCommandRouter::volumioPlay Dec 11 23:06:08 volumio1 volumio[998]: info: CoreStateMachine::play index 0 Dec 11 23:06:08 volumio1 volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 23:06:08 volumio1 volumio[998]: info: CoreStateMachine::stop Dec 11 23:06:08 volumio1 volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 23:06:08 volumio1 volumio[998]: info: CoreStateMachine::play index undefined Dec 11 23:06:08 volumio1 volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 23:06:08 volumio1 volumio[998]: info: CorePlayQueue::getTrack 0 Dec 11 23:06:08 volumio1 volumio[998]: info: CoreStateMachine::startPlaybackTimer Dec 11 23:06:08 volumio1 volumio[998]: info: CorePlayQueue::getTrack 0 Dec 11 23:06:08 volumio1 volumio[998]: info: [1765490768067] ControllerWebradio::clearAddPlayTrack Dec 11 23:06:08 volumio1 volumio[998]: verbose: ControllerMpd::sendMpdCommand stop Dec 11 23:06:08 volumio1 volumio[998]: info: sendMpdCommand stop took 4 milliseconds Dec 11 23:06:08 volumio1 volumio[998]: verbose: ControllerMpd::sendMpdCommand clear Dec 11 23:06:08 volumio1 volumio[998]: info: Dec 11 23:06:08 volumio1 volumio[998]: ---------------------------- MPD announces system playlist update Dec 11 23:06:08 volumio1 volumio[998]: info: Ignoring MPD Status Update Dec 11 23:06:08 volumio1 volumio[998]: info: sendMpdCommand clear took 2 milliseconds Dec 11 23:06:08 volumio1 volumio[998]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99374856" Dec 11 23:06:08 volumio1 volumio[998]: error: updateQueue error: null Dec 11 23:06:08 volumio1 volumio[998]: info: ------------------------------ 2ms Dec 11 23:06:08 volumio1 volumio[998]: info: Dec 11 23:06:08 volumio1 volumio[998]: ---------------------------- MPD announces system playlist update Dec 11 23:06:08 volumio1 volumio[998]: info: Ignoring MPD Status Update Dec 11 23:06:08 volumio1 volumio[998]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99374856" took 130 milliseconds Dec 11 23:06:08 volumio1 volumio[998]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 11 23:06:08 volumio1 volumio[998]: verbose: ControllerMpd::sendMpdCommand play Dec 11 23:06:08 volumio1 volumio[998]: info: ------------------------------ 2ms Dec 11 23:06:08 volumio1 volumio[998]: info: sendMpdCommand play took 2 milliseconds Dec 11 23:06:08 volumio1 volumio[998]: info: Dec 11 23:06:08 volumio1 volumio[998]: ---------------------------- MPD announces state update: player Dec 11 23:06:08 volumio1 volumio[998]: info: ControllerMpd::getState Dec 11 23:06:08 volumio1 volumio[998]: verbose: ControllerMpd::sendMpdCommand status Dec 11 23:06:08 volumio1 volumio[998]: info: Dec 11 23:06:08 volumio1 volumio[998]: ---------------------------- MPD announces state update: player Dec 11 23:06:08 volumio1 volumio[998]: info: ControllerMpd::getState Dec 11 23:06:08 volumio1 volumio[998]: verbose: ControllerMpd::sendMpdCommand status Dec 11 23:06:08 volumio1 volumio[998]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 11 23:06:08 volumio1 volumio[998]: info: sendMpdCommand status took 10 milliseconds Dec 11 23:06:08 volumio1 volumio[998]: verbose: ControllerMpd::parseState Dec 11 23:06:08 volumio1 volumio[998]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 23:06:08 volumio1 volumio[998]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 11 23:06:08 volumio1 volumio[998]: info: sendMpdCommand status took 18 milliseconds Dec 11 23:06:08 volumio1 volumio[998]: info: sendMpdCommand clearerror took 10 milliseconds Dec 11 23:06:08 volumio1 volumio[998]: info: sendMpdCommand playlistinfo took 9 milliseconds Dec 11 23:06:08 volumio1 volumio[998]: verbose: ControllerMpd::parseState Dec 11 23:06:08 volumio1 volumio[998]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 23:06:08 volumio1 volumio[998]: verbose: ControllerMpd::parseTrackInfo Dec 11 23:06:08 volumio1 volumio[998]: info: ControllerMpd::pushState Dec 11 23:06:08 volumio1 volumio[998]: info: CoreCommandRouter::servicePushState Dec 11 23:06:08 volumio1 volumio[998]: info: CoreStateMachine::pushState Dec 11 23:06:08 volumio1 volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 23:06:08 volumio1 volumio[998]: info: CoreCommandRouter::volumioPushState Dec 11 23:06:08 volumio1 volumio[998]: info: CorePlayQueue::getTrack 0 Dec 11 23:06:08 volumio1 volumio[998]: 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":"imrocking","artist":"ImRocking","album":null,"uri":"http://listen.shoutcast.com/imrocking","trackType":""} Dec 11 23:06:08 volumio1 volumio[998]: verbose: CURRENT POSITION 0 Dec 11 23:06:08 volumio1 volumio[998]: info: CoreStateMachine::syncState stateService stop Dec 11 23:06:08 volumio1 volumio[998]: info: CoreStateMachine::syncState currentStatus stop Dec 11 23:06:08 volumio1 volumio[998]: info: CoreStateMachine::pushState Dec 11 23:06:08 volumio1 volumio[998]: info: CoreCommandRouter::volumioPushState Dec 11 23:06:08 volumio1 volumio[998]: info: No code Dec 11 23:06:08 volumio1 volumio[998]: info: CoreStateMachine::pushState Dec 11 23:06:08 volumio1 volumio[998]: info: CoreCommandRouter::volumioPushState Dec 11 23:06:08 volumio1 volumio[998]: info: ------------------------------ 38ms Dec 11 23:06:08 volumio1 volumio[998]: info: sendMpdCommand clearerror took 24 milliseconds Dec 11 23:06:08 volumio1 volumio[998]: info: sendMpdCommand playlistinfo took 23 milliseconds Dec 11 23:06:08 volumio1 volumio[998]: verbose: ControllerMpd::parseTrackInfo Dec 11 23:06:08 volumio1 volumio[998]: info: ControllerMpd::pushState Dec 11 23:06:08 volumio1 volumio[998]: info: CoreCommandRouter::servicePushState Dec 11 23:06:08 volumio1 volumio[998]: info: CoreStateMachine::pushState Dec 11 23:06:08 volumio1 volumio[998]: info: CoreCommandRouter::volumioPushState Dec 11 23:06:08 volumio1 volumio[998]: info: CorePlayQueue::getTrack 0 Dec 11 23:06:08 volumio1 volumio[998]: 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":"imrocking","artist":"ImRocking","album":null,"uri":"http://listen.shoutcast.com/imrocking","trackType":""} Dec 11 23:06:08 volumio1 volumio[998]: verbose: CURRENT POSITION 0 Dec 11 23:06:08 volumio1 volumio[998]: info: CoreStateMachine::syncState stateService stop Dec 11 23:06:08 volumio1 volumio[998]: info: CoreStateMachine::syncState currentStatus stop Dec 11 23:06:08 volumio1 volumio[998]: info: CoreStateMachine::pushState Dec 11 23:06:08 volumio1 volumio[998]: info: CoreCommandRouter::volumioPushState Dec 11 23:06:08 volumio1 volumio[998]: info: No code Dec 11 23:06:08 volumio1 volumio[998]: info: CoreStateMachine::pushState Dec 11 23:06:08 volumio1 volumio[998]: info: CoreCommandRouter::volumioPushState Dec 11 23:06:08 volumio1 volumio[998]: info: ------------------------------ 57ms Dec 11 23:06:09 volumio1 volumio[998]: info: Preload queue cleared Dec 11 23:06:09 volumio1 volumio[998]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 11 23:06:09 volumio1 volumio[998]: info: CoreStateMachine::ClearQueue Dec 11 23:06:09 volumio1 volumio[998]: info: CoreStateMachine::stop Dec 11 23:06:09 volumio1 volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 23:06:09 volumio1 volumio[998]: info: CorePlayQueue::clearPlayQueue Dec 11 23:06:09 volumio1 volumio[998]: info: CorePlayQueue::saveQueue Dec 11 23:06:09 volumio1 volumio[998]: info: CoreCommandRouter::volumioPushQueue Dec 11 23:06:09 volumio1 volumio[998]: info: CoreStateMachine::addQueueItems Dec 11 23:06:09 volumio1 volumio[998]: info: CorePlayQueue::addQueueItems Dec 11 23:06:09 volumio1 volumio[998]: info: Preload queue cleared Dec 11 23:06:09 volumio1 volumio[998]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99374856 Dec 11 23:06:09 volumio1 volumio[998]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Dec 11 23:06:09 volumio1 volumio[998]: info: CoreCommandRouter::volumioPushQueue Dec 11 23:06:09 volumio1 volumio[998]: info: CorePlayQueue::saveQueue Dec 11 23:06:09 volumio1 volumio[998]: info: CoreStateMachine::updateTrackBlock Dec 11 23:06:09 volumio1 volumio[998]: info: CorePlayQueue::getTrackBlock Dec 11 23:06:09 volumio1 volumio[998]: info: CoreCommandRouter::volumioPlay Dec 11 23:06:09 volumio1 volumio[998]: info: CoreStateMachine::play index 0 Dec 11 23:06:09 volumio1 volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 23:06:09 volumio1 volumio[998]: info: CoreStateMachine::stop Dec 11 23:06:09 volumio1 volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 23:06:09 volumio1 volumio[998]: info: CoreStateMachine::play index undefined Dec 11 23:06:09 volumio1 volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 23:06:09 volumio1 volumio[998]: info: CorePlayQueue::getTrack 0 Dec 11 23:06:09 volumio1 volumio[998]: info: CoreStateMachine::startPlaybackTimer Dec 11 23:06:09 volumio1 volumio[998]: info: CorePlayQueue::getTrack 0 Dec 11 23:06:09 volumio1 volumio[998]: info: [1765490769349] ControllerWebradio::clearAddPlayTrack Dec 11 23:06:09 volumio1 volumio[998]: verbose: ControllerMpd::sendMpdCommand stop Dec 11 23:06:09 volumio1 volumio[998]: info: sendMpdCommand stop took 2 milliseconds Dec 11 23:06:09 volumio1 volumio[998]: verbose: ControllerMpd::sendMpdCommand clear Dec 11 23:06:09 volumio1 volumio[998]: info: Dec 11 23:06:09 volumio1 volumio[998]: ---------------------------- MPD announces system playlist update Dec 11 23:06:09 volumio1 volumio[998]: info: Ignoring MPD Status Update Dec 11 23:06:09 volumio1 volumio[998]: info: sendMpdCommand clear took 2 milliseconds Dec 11 23:06:09 volumio1 volumio[998]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99374856" Dec 11 23:06:09 volumio1 volumio[998]: error: updateQueue error: null Dec 11 23:06:09 volumio1 volumio[998]: info: ------------------------------ 2ms Dec 11 23:06:09 volumio1 volumio[998]: info: Dec 11 23:06:09 volumio1 volumio[998]: ---------------------------- MPD announces system playlist update Dec 11 23:06:09 volumio1 volumio[998]: info: Ignoring MPD Status Update Dec 11 23:06:09 volumio1 volumio[998]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99374856" took 339 milliseconds Dec 11 23:06:09 volumio1 volumio[998]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 11 23:06:09 volumio1 volumio[998]: verbose: ControllerMpd::sendMpdCommand play Dec 11 23:06:09 volumio1 volumio[998]: info: ------------------------------ 2ms Dec 11 23:06:09 volumio1 volumio[998]: info: sendMpdCommand play took 2 milliseconds Dec 11 23:06:09 volumio1 volumio[998]: info: Dec 11 23:06:09 volumio1 volumio[998]: ---------------------------- MPD announces state update: player Dec 11 23:06:09 volumio1 volumio[998]: info: ControllerMpd::getState Dec 11 23:06:09 volumio1 volumio[998]: verbose: ControllerMpd::sendMpdCommand status Dec 11 23:06:09 volumio1 volumio[998]: info: Dec 11 23:06:09 volumio1 volumio[998]: ---------------------------- MPD announces state update: player Dec 11 23:06:09 volumio1 volumio[998]: info: ControllerMpd::getState Dec 11 23:06:09 volumio1 volumio[998]: verbose: ControllerMpd::sendMpdCommand status Dec 11 23:06:09 volumio1 volumio[998]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 11 23:06:09 volumio1 volumio[998]: info: sendMpdCommand status took 4 milliseconds Dec 11 23:06:09 volumio1 volumio[998]: verbose: ControllerMpd::parseState Dec 11 23:06:09 volumio1 volumio[998]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 23:06:09 volumio1 volumio[998]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 11 23:06:09 volumio1 volumio[998]: info: sendMpdCommand status took 7 milliseconds Dec 11 23:06:09 volumio1 volumio[998]: info: sendMpdCommand clearerror took 5 milliseconds Dec 11 23:06:09 volumio1 volumio[998]: verbose: ControllerMpd::parseState Dec 11 23:06:09 volumio1 volumio[998]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 23:06:09 volumio1 volumio[998]: info: sendMpdCommand playlistinfo took 7 milliseconds Dec 11 23:06:09 volumio1 volumio[998]: info: sendMpdCommand clearerror took 4 milliseconds Dec 11 23:06:09 volumio1 volumio[998]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 11 23:06:09 volumio1 volumio[998]: verbose: ControllerMpd::parseTrackInfo Dec 11 23:06:09 volumio1 volumio[998]: verbose: ControllerMpd::parseTrackInfo Dec 11 23:06:10 volumio1 volumio[998]: info: ControllerMpd::pushState Dec 11 23:06:10 volumio1 volumio[998]: info: CoreCommandRouter::servicePushState Dec 11 23:06:10 volumio1 volumio[998]: info: CoreStateMachine::pushState Dec 11 23:06:10 volumio1 volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 23:06:10 volumio1 volumio[998]: info: CoreCommandRouter::volumioPushState Dec 11 23:06:10 volumio1 volumio[998]: info: CorePlayQueue::getTrack 0 Dec 11 23:06:10 volumio1 volumio[998]: 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":"imrocking","artist":"ImRocking","album":null,"uri":"http://listen.shoutcast.com/imrocking","trackType":""} Dec 11 23:06:10 volumio1 volumio[998]: verbose: CURRENT POSITION 0 Dec 11 23:06:10 volumio1 volumio[998]: info: CoreStateMachine::syncState stateService stop Dec 11 23:06:10 volumio1 volumio[998]: info: CoreStateMachine::syncState currentStatus stop Dec 11 23:06:10 volumio1 volumio[998]: info: CoreStateMachine::pushState Dec 11 23:06:10 volumio1 volumio[998]: info: CoreCommandRouter::volumioPushState Dec 11 23:06:10 volumio1 volumio[998]: info: No code Dec 11 23:06:10 volumio1 volumio[998]: info: CoreStateMachine::pushState Dec 11 23:06:10 volumio1 volumio[998]: info: CoreCommandRouter::volumioPushState Dec 11 23:06:10 volumio1 volumio[998]: info: ControllerMpd::pushState Dec 11 23:06:10 volumio1 volumio[998]: info: CoreCommandRouter::servicePushState Dec 11 23:06:10 volumio1 volumio[998]: info: CoreStateMachine::pushState Dec 11 23:06:10 volumio1 volumio[998]: info: CoreCommandRouter::volumioPushState Dec 11 23:06:10 volumio1 volumio[998]: info: CorePlayQueue::getTrack 0 Dec 11 23:06:10 volumio1 volumio[998]: 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":"imrocking","artist":"ImRocking","album":null,"uri":"http://listen.shoutcast.com/imrocking","trackType":""} Dec 11 23:06:10 volumio1 volumio[998]: verbose: CURRENT POSITION 0 Dec 11 23:06:10 volumio1 volumio[998]: info: CoreStateMachine::syncState stateService stop Dec 11 23:06:10 volumio1 volumio[998]: info: CoreStateMachine::syncState currentStatus stop Dec 11 23:06:10 volumio1 volumio[998]: info: CoreStateMachine::pushState Dec 11 23:06:10 volumio1 volumio[998]: info: CoreCommandRouter::volumioPushState Dec 11 23:06:10 volumio1 volumio[998]: info: No code Dec 11 23:06:10 volumio1 volumio[998]: info: CoreStateMachine::pushState Dec 11 23:06:10 volumio1 volumio[998]: info: CoreCommandRouter::volumioPushState Dec 11 23:06:10 volumio1 volumio[998]: info: ------------------------------ 42ms Dec 11 23:06:10 volumio1 volumio[998]: info: ------------------------------ 41ms Dec 11 23:06:12 volumio1 volumio[998]: verbose: New Socket.io Connection to 192.168.50.169:3000 from 192.168.50.88 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Dec 11 23:06:13 volumio1 volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 11 23:06:13 volumio1 volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 11 23:06:13 volumio1 volumio[998]: info: Discovery: Getting this device information Dec 11 23:06:13 volumio1 volumio[998]: info: CoreCommandRouter::volumioGetState Dec 11 23:06:13 volumio1 volumio[998]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 11 23:06:14 volumio1 volumio[998]: verbose: New Socket.io Connection to 192.168.50.169:3000 from 192.168.50.88 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Dec 11 23:06:14 volumio1 volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 11 23:06:14 volumio1 volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 11 23:06:14 volumio1 volumio[998]: info: Discovery: Getting this device information Dec 11 23:06:14 volumio1 volumio[998]: info: CoreCommandRouter::volumioGetState Dec 11 23:06:14 volumio1 volumio[998]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 11 23:06:16 volumio1 volumio[998]: verbose: New Socket.io Connection to 192.168.50.169:3000 from 192.168.50.88 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Dec 11 23:06:16 volumio1 volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 11 23:06:16 volumio1 volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 11 23:06:16 volumio1 volumio[998]: info: Discovery: Getting this device information Dec 11 23:06:16 volumio1 volumio[998]: info: CoreCommandRouter::volumioGetState Dec 11 23:06:16 volumio1 volumio[998]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 11 23:06:21 volumio1 volumio[998]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Dec 11 23:06:21 volumio1 volumio[998]: info: Preload queue cleared Dec 11 23:06:22 volumio1 volumio[998]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Dec 11 23:06:22 volumio1 volumio[998]: info: Preload queue cleared Dec 11 23:06:24 volumio1 volumio[998]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Dec 11 23:06:24 volumio1 volumio[998]: info: Preload queue cleared Dec 11 23:06:27 volumio1 volumio[998]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Dec 11 23:06:29 volumio1 volumio[998]: info: Preload queue cleared Dec 11 23:06:33 volumio1 volumio[998]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Dec 11 23:06:35 volumio1 volumio[998]: info: Preload queue cleared Dec 11 23:06:35 volumio1 volumio[998]: info: Preloading song: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119532 Dec 11 23:06:35 volumio1 volumio[998]: info: Preloading song: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119528 Dec 11 23:06:35 volumio1 volumio[998]: info: Preloading song: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119524 Dec 11 23:06:35 volumio1 volumio[998]: info: Preloading song: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119526 Dec 11 23:06:35 volumio1 volumio[998]: info: Preloading song: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119529 Dec 11 23:06:35 volumio1 volumio[998]: info: Preloading song: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119525 Dec 11 23:06:35 volumio1 volumio[998]: info: Preloading song: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119523 Dec 11 23:06:35 volumio1 volumio[998]: info: Preloading song: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119531 Dec 11 23:06:35 volumio1 volumio[998]: info: Preloading song: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119527 Dec 11 23:06:35 volumio1 volumio[998]: info: Preloading song: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119530 Dec 11 23:06:35 volumio1 volumio[998]: info: Exploding uri upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119532 in service upnp_browser Dec 11 23:06:35 volumio1 volumio[998]: info: Exploding uri upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119528 in service upnp_browser Dec 11 23:06:35 volumio1 volumio[998]: info: Exploding uri upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119524 in service upnp_browser Dec 11 23:06:35 volumio1 volumio[998]: info: Exploding uri upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119526 in service upnp_browser Dec 11 23:06:35 volumio1 volumio[998]: info: Exploding uri upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119529 in service upnp_browser Dec 11 23:06:35 volumio1 volumio[998]: info: Exploding uri upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119525 in service upnp_browser Dec 11 23:06:35 volumio1 volumio[998]: info: Exploding uri upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119523 in service upnp_browser Dec 11 23:06:35 volumio1 volumio[998]: info: Exploding uri upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119531 in service upnp_browser Dec 11 23:06:35 volumio1 volumio[998]: info: Exploding uri upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119527 in service upnp_browser Dec 11 23:06:35 volumio1 volumio[998]: info: Exploding uri upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119530 in service upnp_browser Dec 11 23:06:41 volumio1 volumio[998]: info: Preload queue cleared Dec 11 23:06:41 volumio1 volumio[998]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 11 23:06:41 volumio1 volumio[998]: info: CoreStateMachine::ClearQueue Dec 11 23:06:41 volumio1 volumio[998]: info: CoreStateMachine::stop Dec 11 23:06:41 volumio1 volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 23:06:41 volumio1 volumio[998]: info: CorePlayQueue::clearPlayQueue Dec 11 23:06:41 volumio1 volumio[998]: info: CorePlayQueue::saveQueue Dec 11 23:06:41 volumio1 volumio[998]: info: CoreCommandRouter::volumioPushQueue Dec 11 23:06:41 volumio1 volumio[998]: info: CoreStateMachine::addQueueItems Dec 11 23:06:41 volumio1 volumio[998]: info: CorePlayQueue::addQueueItems Dec 11 23:06:41 volumio1 volumio[998]: info: Preload queue cleared Dec 11 23:06:41 volumio1 volumio[998]: info: Adding Item to queue: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119532 Dec 11 23:06:41 volumio1 volumio[998]: info: Using cached record of: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119532 Dec 11 23:06:41 volumio1 volumio[998]: info: CoreCommandRouter::volumioPushQueue Dec 11 23:06:41 volumio1 volumio[998]: info: CorePlayQueue::saveQueue Dec 11 23:06:41 volumio1 volumio[998]: info: CoreStateMachine::updateTrackBlock Dec 11 23:06:41 volumio1 volumio[998]: info: CorePlayQueue::getTrackBlock Dec 11 23:06:41 volumio1 volumio[998]: info: CoreCommandRouter::volumioPlay Dec 11 23:06:41 volumio1 volumio[998]: info: CoreStateMachine::play index 0 Dec 11 23:06:41 volumio1 volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 23:06:41 volumio1 volumio[998]: info: CoreStateMachine::addQueueItems Dec 11 23:06:41 volumio1 volumio[998]: info: CorePlayQueue::addQueueItems Dec 11 23:06:41 volumio1 volumio[998]: info: Preload queue cleared Dec 11 23:06:41 volumio1 volumio[998]: info: Adding Item to queue: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119528 Dec 11 23:06:41 volumio1 volumio[998]: info: Using cached record of: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119528 Dec 11 23:06:41 volumio1 volumio[998]: info: Adding Item to queue: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119524 Dec 11 23:06:41 volumio1 volumio[998]: info: Using cached record of: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119524 Dec 11 23:06:41 volumio1 volumio[998]: info: Adding Item to queue: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119526 Dec 11 23:06:41 volumio1 volumio[998]: info: Using cached record of: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119526 Dec 11 23:06:41 volumio1 volumio[998]: info: Adding Item to queue: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119529 Dec 11 23:06:41 volumio1 volumio[998]: info: Using cached record of: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119529 Dec 11 23:06:41 volumio1 volumio[998]: info: Adding Item to queue: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119525 Dec 11 23:06:41 volumio1 volumio[998]: info: Using cached record of: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119525 Dec 11 23:06:41 volumio1 volumio[998]: info: Adding Item to queue: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119523 Dec 11 23:06:41 volumio1 volumio[998]: info: Using cached record of: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119523 Dec 11 23:06:41 volumio1 volumio[998]: info: Adding Item to queue: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119531 Dec 11 23:06:41 volumio1 volumio[998]: info: Using cached record of: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119531 Dec 11 23:06:41 volumio1 volumio[998]: info: Adding Item to queue: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119527 Dec 11 23:06:41 volumio1 volumio[998]: info: Using cached record of: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119527 Dec 11 23:06:41 volumio1 volumio[998]: info: Adding Item to queue: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119530 Dec 11 23:06:41 volumio1 volumio[998]: info: Using cached record of: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119530 Dec 11 23:06:41 volumio1 volumio[998]: info: CoreStateMachine::stop Dec 11 23:06:41 volumio1 volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 23:06:41 volumio1 volumio[998]: info: CoreCommandRouter::volumioPushQueue Dec 11 23:06:41 volumio1 volumio[998]: info: CorePlayQueue::saveQueue Dec 11 23:06:41 volumio1 volumio[998]: info: CoreStateMachine::play index undefined Dec 11 23:06:41 volumio1 volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 23:06:41 volumio1 volumio[998]: info: CoreStateMachine::updateTrackBlock Dec 11 23:06:41 volumio1 volumio[998]: info: CorePlayQueue::getTrackBlock Dec 11 23:06:41 volumio1 volumio[998]: info: CorePlayQueue::getTrack 0 Dec 11 23:06:41 volumio1 volumio[998]: verbose: New Socket.io Connection to 192.168.50.169:3000 from 192.168.50.88 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Dec 11 23:06:42 volumio1 volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 11 23:06:42 volumio1 volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 11 23:06:42 volumio1 volumio[998]: info: Discovery: Getting this device information Dec 11 23:06:42 volumio1 volumio[998]: info: CoreCommandRouter::volumioGetState Dec 11 23:06:42 volumio1 volumio[998]: info: CorePlayQueue::getTrack 0 Dec 11 23:06:42 volumio1 volumio[998]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 11 23:06:43 volumio1 volumio[998]: verbose: New Socket.io Connection to 192.168.50.169:3000 from 192.168.50.88 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Dec 11 23:06:43 volumio1 volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 11 23:06:43 volumio1 volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 11 23:06:43 volumio1 volumio[998]: info: Discovery: Getting this device information Dec 11 23:06:43 volumio1 volumio[998]: info: CoreCommandRouter::volumioGetState Dec 11 23:06:43 volumio1 volumio[998]: info: CorePlayQueue::getTrack 0 Dec 11 23:06:43 volumio1 volumio[998]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 11 23:06:45 volumio1 volumio[998]: info: Preload queue cleared Dec 11 23:06:45 volumio1 volumio[998]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 11 23:06:45 volumio1 volumio[998]: info: CoreStateMachine::ClearQueue Dec 11 23:06:45 volumio1 volumio[998]: info: CoreStateMachine::stop Dec 11 23:06:45 volumio1 volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 23:06:45 volumio1 volumio[998]: info: CorePlayQueue::clearPlayQueue Dec 11 23:06:45 volumio1 volumio[998]: info: CorePlayQueue::saveQueue Dec 11 23:06:45 volumio1 volumio[998]: info: CoreCommandRouter::volumioPushQueue Dec 11 23:06:45 volumio1 volumio[998]: info: CoreStateMachine::addQueueItems Dec 11 23:06:45 volumio1 volumio[998]: info: CorePlayQueue::addQueueItems Dec 11 23:06:45 volumio1 volumio[998]: info: Preload queue cleared Dec 11 23:06:45 volumio1 volumio[998]: info: Adding Item to queue: upnp/folder/http://192.168.50.196:50001/ContentDirectory/control@27$119523 Dec 11 23:06:45 volumio1 volumio[998]: info: Exploding uri upnp/folder/http://192.168.50.196:50001/ContentDirectory/control@27$119523 in service upnp_browser Dec 11 23:06:46 volumio1 volumio[998]: verbose: New Socket.io Connection to 192.168.50.169:3000 from 192.168.50.88 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Dec 11 23:06:46 volumio1 volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 11 23:06:46 volumio1 volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 11 23:06:46 volumio1 volumio[998]: info: Discovery: Getting this device information Dec 11 23:06:46 volumio1 volumio[998]: info: CoreCommandRouter::volumioGetState Dec 11 23:06:46 volumio1 volumio[998]: info: CorePlayQueue::getTrack 0 Dec 11 23:06:46 volumio1 volumio[998]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 11 23:06:47 volumio1 volumio[998]: info: CoreCommandRouter::volumioPushQueue Dec 11 23:06:47 volumio1 volumio[998]: info: CorePlayQueue::saveQueue Dec 11 23:06:47 volumio1 volumio[998]: info: CoreStateMachine::updateTrackBlock Dec 11 23:06:47 volumio1 volumio[998]: info: CorePlayQueue::getTrackBlock Dec 11 23:06:47 volumio1 volumio[998]: info: CoreCommandRouter::volumioPlay Dec 11 23:06:47 volumio1 volumio[998]: info: CoreStateMachine::play index 0 Dec 11 23:06:47 volumio1 volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 23:06:47 volumio1 volumio[998]: info: CoreStateMachine::stop Dec 11 23:06:47 volumio1 volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 23:06:47 volumio1 volumio[998]: info: CoreStateMachine::play index undefined Dec 11 23:06:47 volumio1 volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 23:06:47 volumio1 volumio[998]: info: CorePlayQueue::getTrack 0 Dec 11 23:06:47 volumio1 volumio[998]: info: CoreStateMachine::startPlaybackTimer Dec 11 23:06:47 volumio1 volumio[998]: info: CorePlayQueue::getTrack 0 Dec 11 23:06:47 volumio1 volumio[998]: info: [1765490807423] ControllerUPNPBrowser::clearAddPlayTrack Dec 11 23:06:47 volumio1 volumio[998]: verbose: ControllerMpd::sendMpdCommand stop Dec 11 23:06:47 volumio1 volumio[998]: info: sendMpdCommand stop took 2 milliseconds Dec 11 23:06:47 volumio1 volumio[998]: verbose: ControllerMpd::sendMpdCommand clear Dec 11 23:06:47 volumio1 volumio[998]: info: Dec 11 23:06:47 volumio1 volumio[998]: ---------------------------- MPD announces system playlist update Dec 11 23:06:47 volumio1 volumio[998]: info: Ignoring MPD Status Update Dec 11 23:06:47 volumio1 volumio[998]: info: sendMpdCommand clear took 2 milliseconds Dec 11 23:06:47 volumio1 volumio[998]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.50.196:50002/transcoder/genericoder.cgi/id=119532.wav?format=WAV&downsample=false" Dec 11 23:06:47 volumio1 volumio[998]: error: updateQueue error: null Dec 11 23:06:47 volumio1 volumio[998]: info: ------------------------------ 7ms Dec 11 23:06:48 volumio1 volumio[998]: verbose: New Socket.io Connection to 192.168.50.169:3000 from 192.168.50.88 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Dec 11 23:06:48 volumio1 volumio[998]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.50.196:50002/transcoder/genericoder.cgi/id=119532.wav?format=WAV&downsample=false" Dec 11 23:06:48 volumio1 volumio[998]: info: Dec 11 23:06:48 volumio1 volumio[998]: ---------------------------- MPD announces system playlist update Dec 11 23:06:48 volumio1 volumio[998]: info: Ignoring MPD Status Update Dec 11 23:06:48 volumio1 volumio[998]: info: sendMpdCommand add "http://192.168.50.196:50002/transcoder/genericoder.cgi/id=119532.wav?format=WAV&downsample=false" took 2 milliseconds Dec 11 23:06:48 volumio1 volumio[998]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 11 23:06:48 volumio1 volumio[998]: verbose: ControllerMpd::sendMpdCommand play Dec 11 23:06:48 volumio1 volumio[998]: info: ------------------------------ 2ms Dec 11 23:06:48 volumio1 volumio[998]: info: sendMpdCommand play took 1 milliseconds Dec 11 23:06:48 volumio1 volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 11 23:06:48 volumio1 volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 11 23:06:48 volumio1 volumio[998]: info: Discovery: Getting this device information Dec 11 23:06:48 volumio1 volumio[998]: info: CoreCommandRouter::volumioGetState Dec 11 23:06:48 volumio1 volumio[998]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 11 23:06:49 volumio1 volumio[998]: info: Preload queue cleared Dec 11 23:06:49 volumio1 volumio[998]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 11 23:06:49 volumio1 volumio[998]: info: CoreStateMachine::ClearQueue Dec 11 23:06:49 volumio1 volumio[998]: info: CoreStateMachine::stop Dec 11 23:06:49 volumio1 volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 23:06:49 volumio1 volumio[998]: info: CorePlayQueue::clearPlayQueue Dec 11 23:06:49 volumio1 volumio[998]: info: CorePlayQueue::saveQueue Dec 11 23:06:49 volumio1 volumio[998]: info: CoreCommandRouter::volumioPushQueue Dec 11 23:06:49 volumio1 volumio[998]: info: CoreStateMachine::addQueueItems Dec 11 23:06:49 volumio1 volumio[998]: info: CorePlayQueue::addQueueItems Dec 11 23:06:49 volumio1 volumio[998]: info: Preload queue cleared Dec 11 23:06:49 volumio1 volumio[998]: info: Adding Item to queue: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119532 Dec 11 23:06:49 volumio1 volumio[998]: info: Using cached record of: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119532 Dec 11 23:06:49 volumio1 volumio[998]: info: CoreCommandRouter::volumioPushQueue Dec 11 23:06:49 volumio1 volumio[998]: info: CorePlayQueue::saveQueue Dec 11 23:06:49 volumio1 volumio[998]: info: CoreStateMachine::updateTrackBlock Dec 11 23:06:49 volumio1 volumio[998]: info: CorePlayQueue::getTrackBlock Dec 11 23:06:49 volumio1 volumio[998]: info: CoreCommandRouter::volumioPlay Dec 11 23:06:49 volumio1 volumio[998]: info: CoreStateMachine::play index 0 Dec 11 23:06:49 volumio1 volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 23:06:49 volumio1 volumio[998]: info: CoreStateMachine::addQueueItems Dec 11 23:06:49 volumio1 volumio[998]: info: CorePlayQueue::addQueueItems Dec 11 23:06:49 volumio1 volumio[998]: info: Preload queue cleared Dec 11 23:06:49 volumio1 volumio[998]: info: Adding Item to queue: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119528 Dec 11 23:06:49 volumio1 volumio[998]: info: Using cached record of: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119528 Dec 11 23:06:49 volumio1 volumio[998]: info: Adding Item to queue: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119524 Dec 11 23:06:49 volumio1 volumio[998]: info: Using cached record of: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119524 Dec 11 23:06:49 volumio1 volumio[998]: info: Adding Item to queue: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119526 Dec 11 23:06:49 volumio1 volumio[998]: info: Using cached record of: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119526 Dec 11 23:06:49 volumio1 volumio[998]: info: Adding Item to queue: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119529 Dec 11 23:06:49 volumio1 volumio[998]: info: Using cached record of: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119529 Dec 11 23:06:49 volumio1 volumio[998]: info: Adding Item to queue: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119525 Dec 11 23:06:49 volumio1 volumio[998]: info: Using cached record of: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119525 Dec 11 23:06:49 volumio1 volumio[998]: info: Adding Item to queue: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119523 Dec 11 23:06:49 volumio1 volumio[998]: info: Using cached record of: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119523 Dec 11 23:06:49 volumio1 volumio[998]: info: Adding Item to queue: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119531 Dec 11 23:06:49 volumio1 volumio[998]: info: Using cached record of: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119531 Dec 11 23:06:49 volumio1 volumio[998]: info: Adding Item to queue: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119527 Dec 11 23:06:49 volumio1 volumio[998]: info: Using cached record of: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119527 Dec 11 23:06:49 volumio1 volumio[998]: info: Adding Item to queue: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119530 Dec 11 23:06:49 volumio1 volumio[998]: info: Using cached record of: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119530 Dec 11 23:06:49 volumio1 volumio[998]: info: CoreStateMachine::stop Dec 11 23:06:49 volumio1 volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 23:06:49 volumio1 volumio[998]: info: CoreCommandRouter::volumioPushQueue Dec 11 23:06:49 volumio1 volumio[998]: info: CorePlayQueue::saveQueue Dec 11 23:06:49 volumio1 volumio[998]: info: CoreStateMachine::play index undefined Dec 11 23:06:49 volumio1 volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 23:06:49 volumio1 volumio[998]: info: CoreStateMachine::updateTrackBlock Dec 11 23:06:49 volumio1 volumio[998]: info: CorePlayQueue::getTrackBlock Dec 11 23:06:49 volumio1 volumio[998]: info: CorePlayQueue::getTrack 0 Dec 11 23:06:50 volumio1 volumio[998]: info: Preload queue cleared Dec 11 23:06:50 volumio1 volumio[998]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 11 23:06:50 volumio1 volumio[998]: info: CoreStateMachine::ClearQueue Dec 11 23:06:50 volumio1 volumio[998]: info: CoreStateMachine::stop Dec 11 23:06:50 volumio1 volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 23:06:50 volumio1 volumio[998]: info: CorePlayQueue::clearPlayQueue Dec 11 23:06:50 volumio1 volumio[998]: info: CorePlayQueue::saveQueue Dec 11 23:06:50 volumio1 volumio[998]: info: CoreCommandRouter::volumioPushQueue Dec 11 23:06:50 volumio1 volumio[998]: info: CoreStateMachine::addQueueItems Dec 11 23:06:50 volumio1 volumio[998]: info: CorePlayQueue::addQueueItems Dec 11 23:06:50 volumio1 volumio[998]: info: Preload queue cleared Dec 11 23:06:50 volumio1 volumio[998]: info: Adding Item to queue: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119532 Dec 11 23:06:50 volumio1 volumio[998]: info: Using cached record of: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119532 Dec 11 23:06:50 volumio1 volumio[998]: info: CoreCommandRouter::volumioPushQueue Dec 11 23:06:50 volumio1 volumio[998]: info: CorePlayQueue::saveQueue Dec 11 23:06:50 volumio1 volumio[998]: info: CoreStateMachine::updateTrackBlock Dec 11 23:06:50 volumio1 volumio[998]: info: CorePlayQueue::getTrackBlock Dec 11 23:06:50 volumio1 volumio[998]: info: CoreCommandRouter::volumioPlay Dec 11 23:06:50 volumio1 volumio[998]: info: CoreStateMachine::play index 0 Dec 11 23:06:50 volumio1 volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 23:06:50 volumio1 volumio[998]: info: CoreStateMachine::addQueueItems Dec 11 23:06:50 volumio1 volumio[998]: info: CorePlayQueue::addQueueItems Dec 11 23:06:50 volumio1 volumio[998]: info: Preload queue cleared Dec 11 23:06:50 volumio1 volumio[998]: info: Adding Item to queue: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119528 Dec 11 23:06:50 volumio1 volumio[998]: info: Using cached record of: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119528 Dec 11 23:06:50 volumio1 volumio[998]: info: Adding Item to queue: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119524 Dec 11 23:06:50 volumio1 volumio[998]: info: Using cached record of: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119524 Dec 11 23:06:50 volumio1 volumio[998]: info: Adding Item to queue: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119526 Dec 11 23:06:50 volumio1 volumio[998]: info: Using cached record of: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119526 Dec 11 23:06:50 volumio1 volumio[998]: info: Adding Item to queue: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119529 Dec 11 23:06:50 volumio1 volumio[998]: info: Using cached record of: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119529 Dec 11 23:06:50 volumio1 volumio[998]: info: Adding Item to queue: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119525 Dec 11 23:06:50 volumio1 volumio[998]: info: Using cached record of: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119525 Dec 11 23:06:50 volumio1 volumio[998]: info: Adding Item to queue: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119523 Dec 11 23:06:50 volumio1 volumio[998]: info: Using cached record of: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119523 Dec 11 23:06:50 volumio1 volumio[998]: info: Adding Item to queue: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119531 Dec 11 23:06:50 volumio1 volumio[998]: info: Using cached record of: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119531 Dec 11 23:06:50 volumio1 volumio[998]: info: Adding Item to queue: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119527 Dec 11 23:06:50 volumio1 volumio[998]: info: Using cached record of: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119527 Dec 11 23:06:50 volumio1 volumio[998]: info: Adding Item to queue: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119530 Dec 11 23:06:50 volumio1 volumio[998]: info: Using cached record of: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119530 Dec 11 23:06:50 volumio1 volumio[998]: info: CoreStateMachine::stop Dec 11 23:06:50 volumio1 volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 23:06:50 volumio1 volumio[998]: info: CoreCommandRouter::volumioPushQueue Dec 11 23:06:50 volumio1 volumio[998]: info: CorePlayQueue::saveQueue Dec 11 23:06:50 volumio1 volumio[998]: info: CoreStateMachine::play index undefined Dec 11 23:06:50 volumio1 volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 23:06:50 volumio1 volumio[998]: info: CoreStateMachine::updateTrackBlock Dec 11 23:06:50 volumio1 volumio[998]: info: CorePlayQueue::getTrackBlock Dec 11 23:06:50 volumio1 volumio[998]: info: CorePlayQueue::getTrack 0 Dec 11 23:06:50 volumio1 volumio[998]: verbose: New Socket.io Connection to 192.168.50.169:3000 from 192.168.50.88 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Dec 11 23:06:51 volumio1 volumio[998]: info: Preload queue cleared Dec 11 23:06:51 volumio1 volumio[998]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 11 23:06:51 volumio1 volumio[998]: info: CoreStateMachine::ClearQueue Dec 11 23:06:51 volumio1 volumio[998]: info: CoreStateMachine::stop Dec 11 23:06:51 volumio1 volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 23:06:51 volumio1 volumio[998]: info: CorePlayQueue::clearPlayQueue Dec 11 23:06:51 volumio1 volumio[998]: info: CorePlayQueue::saveQueue Dec 11 23:06:51 volumio1 volumio[998]: info: CoreCommandRouter::volumioPushQueue Dec 11 23:06:51 volumio1 volumio[998]: info: CoreStateMachine::addQueueItems Dec 11 23:06:51 volumio1 volumio[998]: info: CorePlayQueue::addQueueItems Dec 11 23:06:51 volumio1 volumio[998]: info: Preload queue cleared Dec 11 23:06:51 volumio1 volumio[998]: info: Adding Item to queue: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119532 Dec 11 23:06:51 volumio1 volumio[998]: info: Using cached record of: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119532 Dec 11 23:06:51 volumio1 volumio[998]: info: CoreCommandRouter::volumioPushQueue Dec 11 23:06:51 volumio1 volumio[998]: info: CorePlayQueue::saveQueue Dec 11 23:06:51 volumio1 volumio[998]: info: CoreStateMachine::updateTrackBlock Dec 11 23:06:51 volumio1 volumio[998]: info: CorePlayQueue::getTrackBlock Dec 11 23:06:51 volumio1 volumio[998]: info: CoreCommandRouter::volumioPlay Dec 11 23:06:51 volumio1 volumio[998]: info: CoreStateMachine::play index 0 Dec 11 23:06:51 volumio1 volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 23:06:51 volumio1 volumio[998]: info: CoreStateMachine::addQueueItems Dec 11 23:06:51 volumio1 volumio[998]: info: CorePlayQueue::addQueueItems Dec 11 23:06:51 volumio1 volumio[998]: info: Preload queue cleared Dec 11 23:06:51 volumio1 volumio[998]: info: Adding Item to queue: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119528 Dec 11 23:06:51 volumio1 volumio[998]: info: Using cached record of: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119528 Dec 11 23:06:51 volumio1 volumio[998]: info: Adding Item to queue: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119524 Dec 11 23:06:51 volumio1 volumio[998]: info: Using cached record of: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119524 Dec 11 23:06:51 volumio1 volumio[998]: info: Adding Item to queue: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119526 Dec 11 23:06:51 volumio1 volumio[998]: info: Using cached record of: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119526 Dec 11 23:06:51 volumio1 volumio[998]: info: Adding Item to queue: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119529 Dec 11 23:06:51 volumio1 volumio[998]: info: Using cached record of: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119529 Dec 11 23:06:51 volumio1 volumio[998]: info: Adding Item to queue: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119525 Dec 11 23:06:51 volumio1 volumio[998]: info: Using cached record of: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119525 Dec 11 23:06:51 volumio1 volumio[998]: info: Adding Item to queue: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119523 Dec 11 23:06:51 volumio1 volumio[998]: info: Using cached record of: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119523 Dec 11 23:06:51 volumio1 volumio[998]: info: Adding Item to queue: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119531 Dec 11 23:06:51 volumio1 volumio[998]: info: Using cached record of: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119531 Dec 11 23:06:51 volumio1 volumio[998]: info: Adding Item to queue: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119527 Dec 11 23:06:51 volumio1 volumio[998]: info: Using cached record of: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119527 Dec 11 23:06:51 volumio1 volumio[998]: info: Adding Item to queue: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119530 Dec 11 23:06:51 volumio1 volumio[998]: info: Using cached record of: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119530 Dec 11 23:06:51 volumio1 volumio[998]: info: CoreStateMachine::stop Dec 11 23:06:51 volumio1 volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 23:06:51 volumio1 volumio[998]: info: CoreCommandRouter::volumioPushQueue Dec 11 23:06:51 volumio1 volumio[998]: info: CorePlayQueue::saveQueue Dec 11 23:06:51 volumio1 volumio[998]: info: CoreStateMachine::play index undefined Dec 11 23:06:51 volumio1 volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 23:06:51 volumio1 volumio[998]: info: CoreStateMachine::updateTrackBlock Dec 11 23:06:51 volumio1 volumio[998]: info: CorePlayQueue::getTrackBlock Dec 11 23:06:51 volumio1 volumio[998]: info: CorePlayQueue::getTrack 0 Dec 11 23:06:51 volumio1 volumio[998]: info: Preload queue cleared Dec 11 23:06:51 volumio1 volumio[998]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 11 23:06:51 volumio1 volumio[998]: info: CoreStateMachine::ClearQueue Dec 11 23:06:51 volumio1 volumio[998]: info: CoreStateMachine::stop Dec 11 23:06:51 volumio1 volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 23:06:51 volumio1 volumio[998]: info: CorePlayQueue::clearPlayQueue Dec 11 23:06:51 volumio1 volumio[998]: info: CorePlayQueue::saveQueue Dec 11 23:06:51 volumio1 volumio[998]: info: CoreCommandRouter::volumioPushQueue Dec 11 23:06:51 volumio1 volumio[998]: info: CoreStateMachine::addQueueItems Dec 11 23:06:51 volumio1 volumio[998]: info: CorePlayQueue::addQueueItems Dec 11 23:06:51 volumio1 volumio[998]: info: Preload queue cleared Dec 11 23:06:51 volumio1 volumio[998]: info: Adding Item to queue: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119532 Dec 11 23:06:51 volumio1 volumio[998]: info: Using cached record of: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119532 Dec 11 23:06:51 volumio1 volumio[998]: info: CoreCommandRouter::volumioPushQueue Dec 11 23:06:51 volumio1 volumio[998]: info: CorePlayQueue::saveQueue Dec 11 23:06:51 volumio1 volumio[998]: info: CoreStateMachine::updateTrackBlock Dec 11 23:06:51 volumio1 volumio[998]: info: CorePlayQueue::getTrackBlock Dec 11 23:06:51 volumio1 volumio[998]: info: CoreCommandRouter::volumioPlay Dec 11 23:06:51 volumio1 volumio[998]: info: CoreStateMachine::play index 0 Dec 11 23:06:51 volumio1 volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 23:06:51 volumio1 volumio[998]: info: CoreStateMachine::addQueueItems Dec 11 23:06:51 volumio1 volumio[998]: info: CorePlayQueue::addQueueItems Dec 11 23:06:51 volumio1 volumio[998]: info: Preload queue cleared Dec 11 23:06:51 volumio1 volumio[998]: info: Adding Item to queue: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119528 Dec 11 23:06:51 volumio1 volumio[998]: info: Using cached record of: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119528 Dec 11 23:06:51 volumio1 volumio[998]: info: Adding Item to queue: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119524 Dec 11 23:06:51 volumio1 volumio[998]: info: Using cached record of: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119524 Dec 11 23:06:51 volumio1 volumio[998]: info: Adding Item to queue: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119526 Dec 11 23:06:51 volumio1 volumio[998]: info: Using cached record of: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119526 Dec 11 23:06:51 volumio1 volumio[998]: info: Adding Item to queue: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119529 Dec 11 23:06:51 volumio1 volumio[998]: info: Using cached record of: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119529 Dec 11 23:06:51 volumio1 volumio[998]: info: Adding Item to queue: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119525 Dec 11 23:06:51 volumio1 volumio[998]: info: Using cached record of: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119525 Dec 11 23:06:51 volumio1 volumio[998]: info: Adding Item to queue: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119523 Dec 11 23:06:51 volumio1 volumio[998]: info: Using cached record of: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119523 Dec 11 23:06:51 volumio1 volumio[998]: info: Adding Item to queue: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119531 Dec 11 23:06:51 volumio1 volumio[998]: info: Using cached record of: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119531 Dec 11 23:06:51 volumio1 volumio[998]: info: Adding Item to queue: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119527 Dec 11 23:06:51 volumio1 volumio[998]: info: Using cached record of: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119527 Dec 11 23:06:51 volumio1 volumio[998]: info: Adding Item to queue: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119530 Dec 11 23:06:51 volumio1 volumio[998]: info: Using cached record of: upnp/http://192.168.50.196:50001/ContentDirectory/control@27$119523$@119530 Dec 11 23:06:51 volumio1 volumio[998]: info: CoreStateMachine::stop Dec 11 23:06:51 volumio1 volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 23:06:51 volumio1 volumio[998]: info: CoreCommandRouter::volumioPushQueue Dec 11 23:06:51 volumio1 volumio[998]: info: CorePlayQueue::saveQueue Dec 11 23:06:51 volumio1 volumio[998]: info: CoreStateMachine::play index undefined Dec 11 23:06:51 volumio1 volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 23:06:51 volumio1 volumio[998]: info: CoreStateMachine::updateTrackBlock Dec 11 23:06:51 volumio1 volumio[998]: info: CorePlayQueue::getTrackBlock Dec 11 23:06:51 volumio1 volumio[998]: info: CorePlayQueue::getTrack 0 Dec 11 23:06:52 volumio1 volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 11 23:06:52 volumio1 volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 11 23:06:52 volumio1 volumio[998]: info: Discovery: Getting this device information Dec 11 23:06:52 volumio1 volumio[998]: info: CoreCommandRouter::volumioGetState Dec 11 23:06:52 volumio1 volumio[998]: info: CorePlayQueue::getTrack 0 Dec 11 23:06:52 volumio1 volumio[998]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 11 23:06:52 volumio1 volumio[998]: info: Dec 11 23:06:52 volumio1 volumio[998]: ---------------------------- MPD announces state update: player Dec 11 23:06:52 volumio1 volumio[998]: info: ControllerMpd::getState Dec 11 23:06:52 volumio1 volumio[998]: verbose: ControllerMpd::sendMpdCommand status Dec 11 23:06:52 volumio1 volumio[998]: info: Dec 11 23:06:52 volumio1 volumio[998]: ---------------------------- MPD announces state update: player Dec 11 23:06:52 volumio1 volumio[998]: info: sendMpdCommand status took 24 milliseconds Dec 11 23:06:52 volumio1 volumio[998]: info: ControllerMpd::getState Dec 11 23:06:52 volumio1 volumio[998]: verbose: ControllerMpd::sendMpdCommand status Dec 11 23:06:52 volumio1 volumio[998]: verbose: ControllerMpd::parseState Dec 11 23:06:52 volumio1 volumio[998]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 23:06:52 volumio1 volumio[998]: info: sendMpdCommand status took 2 milliseconds Dec 11 23:06:52 volumio1 volumio[998]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 11 23:06:52 volumio1 volumio[998]: verbose: ControllerMpd::parseState Dec 11 23:06:52 volumio1 volumio[998]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 23:06:52 volumio1 volumio[998]: verbose: ControllerMpd::parseTrackInfo Dec 11 23:06:52 volumio1 volumio[998]: info: ControllerMpd::pushState Dec 11 23:06:52 volumio1 volumio[998]: info: CoreCommandRouter::servicePushState Dec 11 23:06:52 volumio1 volumio[998]: info: CorePlayQueue::getTrack 0 Dec 11 23:06:52 volumio1 volumio[998]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":268,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"id=119532.wav?format=WAV&downsample=false","artist":null,"album":null,"uri":"http://192.168.50.196:50002/transcoder/genericoder.cgi/id=119532.wav?format=WAV&downsample=false","trackType":""} Dec 11 23:06:52 volumio1 volumio[998]: verbose: CURRENT POSITION 0 Dec 11 23:06:52 volumio1 volumio[998]: info: CoreStateMachine::syncState stateService play Dec 11 23:06:52 volumio1 volumio[998]: info: CoreStateMachine::syncState currentStatus stop Dec 11 23:06:52 volumio1 volumio[998]: info: ------------------------------ 30ms Dec 11 23:06:52 volumio1 volumio[998]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 11 23:06:52 volumio1 volumio[998]: verbose: ControllerMpd::parseTrackInfo Dec 11 23:06:52 volumio1 volumio[998]: info: ControllerMpd::pushState Dec 11 23:06:52 volumio1 volumio[998]: info: CoreCommandRouter::servicePushState Dec 11 23:06:52 volumio1 volumio[998]: info: CorePlayQueue::getTrack 0 Dec 11 23:06:52 volumio1 volumio[998]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":268,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"id=119532.wav?format=WAV&downsample=false","artist":null,"album":null,"uri":"http://192.168.50.196:50002/transcoder/genericoder.cgi/id=119532.wav?format=WAV&downsample=false","trackType":""} Dec 11 23:06:52 volumio1 volumio[998]: verbose: CURRENT POSITION 0 Dec 11 23:06:52 volumio1 volumio[998]: info: CoreStateMachine::syncState stateService play Dec 11 23:06:52 volumio1 volumio[998]: info: CoreStateMachine::syncState currentStatus play Dec 11 23:06:52 volumio1 volumio[998]: info: Received an update from plugin. extracting info from payload Dec 11 23:06:52 volumio1 volumio[998]: info: CoreStateMachine::pushState Dec 11 23:06:52 volumio1 volumio[998]: info: CorePlayQueue::getTrack 0 Dec 11 23:06:52 volumio1 volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 23:06:52 volumio1 volumio[998]: info: CoreCommandRouter::volumioPushState Dec 11 23:06:52 volumio1 volumio[998]: info: CoreStateMachine::pushState Dec 11 23:06:52 volumio1 volumio[998]: info: CorePlayQueue::getTrack 0 Dec 11 23:06:52 volumio1 volumio[998]: info: CoreCommandRouter::volumioPushState Dec 11 23:06:52 volumio1 volumio[998]: info: ------------------------------ 18ms Dec 11 23:06:56 volumio1 volumio[998]: verbose: New Socket.io Connection to 192.168.50.169:3000 from 192.168.50.88 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Dec 11 23:06:57 volumio1 volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 11 23:06:57 volumio1 volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 11 23:06:57 volumio1 volumio[998]: info: Discovery: Getting this device information Dec 11 23:06:57 volumio1 volumio[998]: info: CoreCommandRouter::volumioGetState Dec 11 23:06:57 volumio1 volumio[998]: info: CorePlayQueue::getTrack 0 Dec 11 23:06:57 volumio1 volumio[998]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 11 23:06:58 volumio1 volumio[998]: verbose: New Socket.io Connection to 192.168.50.169:3000 from 192.168.50.88 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Dec 11 23:06:59 volumio1 volumio[998]: info: VolumeController::SetAlsaVolume+ Dec 11 23:06:59 volumio1 volumio[998]: info: CoreStateMachine::pushState Dec 11 23:06:59 volumio1 volumio[998]: info: CorePlayQueue::getTrack 0 Dec 11 23:06:59 volumio1 volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 23:06:59 volumio1 volumio[998]: info: CoreCommandRouter::volumioPushState Dec 11 23:06:59 volumio1 volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 11 23:06:59 volumio1 volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 11 23:06:59 volumio1 volumio[998]: info: Discovery: Getting this device information Dec 11 23:06:59 volumio1 volumio[998]: info: CoreCommandRouter::volumioGetState Dec 11 23:06:59 volumio1 volumio[998]: info: CorePlayQueue::getTrack 0 Dec 11 23:06:59 volumio1 volumio[998]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 11 23:07:00 volumio1 volumio[998]: info: VolumeController::SetAlsaVolume+ Dec 11 23:07:00 volumio1 volumio[998]: info: CoreStateMachine::pushState Dec 11 23:07:00 volumio1 volumio[998]: info: CorePlayQueue::getTrack 0 Dec 11 23:07:00 volumio1 volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 23:07:00 volumio1 volumio[998]: info: CoreCommandRouter::volumioPushState Dec 11 23:07:01 volumio1 volumio[998]: verbose: New Socket.io Connection to 192.168.50.169:3000 from 192.168.50.88 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Dec 11 23:07:02 volumio1 volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 11 23:07:02 volumio1 volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 11 23:07:02 volumio1 volumio[998]: info: Discovery: Getting this device information Dec 11 23:07:02 volumio1 volumio[998]: info: CoreCommandRouter::volumioGetState Dec 11 23:07:02 volumio1 volumio[998]: info: CorePlayQueue::getTrack 0 Dec 11 23:07:02 volumio1 volumio[998]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 11 23:07:05 volumio1 volumio[998]: info: VolumeController::SetAlsaVolume+ Dec 11 23:07:05 volumio1 volumio[998]: info: CoreStateMachine::pushState Dec 11 23:07:05 volumio1 volumio[998]: info: CorePlayQueue::getTrack 0 Dec 11 23:07:05 volumio1 volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 23:07:05 volumio1 volumio[998]: info: CoreCommandRouter::volumioPushState Dec 11 23:07:05 volumio1 volumio[998]: verbose: New Socket.io Connection to 192.168.50.169:3000 from 192.168.50.88 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Dec 11 23:07:06 volumio1 volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 11 23:07:06 volumio1 volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 11 23:07:06 volumio1 volumio[998]: info: Discovery: Getting this device information Dec 11 23:07:06 volumio1 volumio[998]: info: CoreCommandRouter::volumioGetState Dec 11 23:07:06 volumio1 volumio[998]: info: CorePlayQueue::getTrack 0 Dec 11 23:07:06 volumio1 volumio[998]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 11 23:07:16 volumio1 volumio[998]: info: VolumeController::SetAlsaVolume29 Dec 11 23:07:16 volumio1 volumio[998]: info: CoreStateMachine::pushState Dec 11 23:07:16 volumio1 volumio[998]: info: CorePlayQueue::getTrack 0 Dec 11 23:07:16 volumio1 volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 23:07:16 volumio1 volumio[998]: info: CoreCommandRouter::volumioPushState Dec 11 23:07:23 volumio1 volumio[998]: verbose: New Socket.io Connection to 192.168.50.169:3000 from 192.168.50.88 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Dec 11 23:07:25 volumio1 volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 11 23:07:25 volumio1 volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 11 23:07:25 volumio1 volumio[998]: info: Discovery: Getting this device information Dec 11 23:07:25 volumio1 volumio[998]: info: CoreCommandRouter::volumioGetState Dec 11 23:07:25 volumio1 volumio[998]: info: CorePlayQueue::getTrack 0 Dec 11 23:07:25 volumio1 volumio[998]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 11 23:07:28 volumio1 volumio[998]: verbose: New Socket.io Connection to 192.168.50.169:3000 from 192.168.50.88 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Dec 11 23:07:30 volumio1 volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 11 23:07:30 volumio1 volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 11 23:07:30 volumio1 volumio[998]: info: Discovery: Getting this device information Dec 11 23:07:30 volumio1 volumio[998]: info: CoreCommandRouter::volumioGetState Dec 11 23:07:30 volumio1 volumio[998]: info: CorePlayQueue::getTrack 0 Dec 11 23:07:30 volumio1 volumio[998]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 11 23:07:34 volumio1 volumio[998]: verbose: New Socket.io Connection to 192.168.50.169:3000 from 192.168.50.88 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Dec 11 23:07:37 volumio1 volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 11 23:07:37 volumio1 volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 11 23:07:37 volumio1 volumio[998]: info: Discovery: Getting this device information Dec 11 23:07:37 volumio1 volumio[998]: info: CoreCommandRouter::volumioGetState Dec 11 23:07:37 volumio1 volumio[998]: info: CorePlayQueue::getTrack 0 Dec 11 23:07:37 volumio1 volumio[998]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 11 23:07:39 volumio1 volumio[998]: info: CoreCommandRouter::volumioPlay Dec 11 23:07:39 volumio1 volumio[998]: info: CoreStateMachine::play index undefined Dec 11 23:07:39 volumio1 volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 23:07:39 volumio1 volumio[998]: info: CorePlayQueue::getTrack 0 Dec 11 23:07:39 volumio1 volumio[998]: verbose: New Socket.io Connection to 192.168.50.169:3000 from 192.168.50.88 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Dec 11 23:07:41 volumio1 volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 11 23:07:41 volumio1 volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 11 23:07:41 volumio1 volumio[998]: info: Discovery: Getting this device information Dec 11 23:07:41 volumio1 volumio[998]: info: CoreCommandRouter::volumioGetState Dec 11 23:07:41 volumio1 volumio[998]: info: CorePlayQueue::getTrack 0 Dec 11 23:07:41 volumio1 volumio[998]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 11 23:07:44 volumio1 volumio[998]: info: CoreCommandRouter::volumioPlay Dec 11 23:07:44 volumio1 volumio[998]: info: CoreStateMachine::play index undefined Dec 11 23:07:44 volumio1 volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 23:07:44 volumio1 volumio[998]: info: CorePlayQueue::getTrack 0 Dec 11 23:07:44 volumio1 volumio[998]: verbose: New Socket.io Connection to 192.168.50.169:3000 from 192.168.50.88 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Dec 11 23:07:47 volumio1 volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 11 23:07:47 volumio1 volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 11 23:07:47 volumio1 volumio[998]: info: Discovery: Getting this device information Dec 11 23:07:47 volumio1 volumio[998]: info: CoreCommandRouter::volumioGetState Dec 11 23:07:47 volumio1 volumio[998]: info: CorePlayQueue::getTrack 0 Dec 11 23:07:47 volumio1 volumio[998]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 11 23:07:47 volumio1 volumio[998]: info: CoreCommandRouter::volumioPrevious Dec 11 23:07:47 volumio1 volumio[998]: info: CoreStateMachine::previous Dec 11 23:07:47 volumio1 volumio[998]: info: CorePlayQueue::getTrack 0 Dec 11 23:07:47 volumio1 volumio[998]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 11 23:07:47 volumio1 volumio[998]: TypeError: Cannot read properties of undefined (reading 'service') Dec 11 23:07:47 volumio1 volumio[998]: at CoreStateMachine.previous (/volumio/app/statemachine.js:1329:97) Dec 11 23:07:47 volumio1 volumio[998]: at CoreCommandRouter.volumioPrevious (/volumio/app/index.js:97:28) Dec 11 23:07:47 volumio1 volumio[998]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:270:33) Dec 11 23:07:47 volumio1 volumio[998]: at Socket.emit (node:events:514:28) Dec 11 23:07:47 volumio1 volumio[998]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Dec 11 23:07:47 volumio1 volumio[998]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Dec 11 23:07:47 volumio1 volumio[998]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 11 23:07:50 volumio1 sudo[5745]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-11 23:06' Dec 11 23:07:50 volumio1 sudo[5745]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"