-- Logs begin at Sat 2024-05-04 10:09:26 BST, end at Sat 2024-05-04 14:40:31 BST. -- May 04 14:39:02 volumio volumio[845]: info: Preload queue cleared May 04 14:39:02 volumio volumio[845]: info: CoreCommandRouter::volumioReplaceandPlayItems May 04 14:39:02 volumio volumio[845]: info: CoreStateMachine::ClearQueue May 04 14:39:02 volumio volumio[845]: info: CoreStateMachine::stop May 04 14:39:02 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 14:39:02 volumio volumio[845]: info: CorePlayQueue::clearPlayQueue May 04 14:39:02 volumio volumio[845]: info: CorePlayQueue::saveQueue May 04 14:39:02 volumio volumio[845]: info: CoreCommandRouter::volumioPushQueue May 04 14:39:02 volumio volumio[845]: info: CoreStateMachine::addQueueItems May 04 14:39:02 volumio volumio[845]: info: CorePlayQueue::addQueueItems May 04 14:39:02 volumio volumio[845]: info: Preload queue cleared May 04 14:39:02 volumio volumio[845]: info: Adding Item to queue: http://89.16.185.174:8004/stream May 04 14:39:02 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 04 14:39:02 volumio volumio[845]: info: CoreCommandRouter::volumioPushQueue May 04 14:39:02 volumio volumio[845]: info: CorePlayQueue::saveQueue May 04 14:39:02 volumio volumio[845]: info: CoreStateMachine::updateTrackBlock May 04 14:39:02 volumio volumio[845]: info: CorePlayQueue::getTrackBlock May 04 14:39:02 volumio volumio[845]: info: CoreCommandRouter::volumioPlay May 04 14:39:02 volumio volumio[845]: info: CoreStateMachine::play index 0 May 04 14:39:02 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 14:39:02 volumio volumio[845]: info: CoreStateMachine::stop May 04 14:39:02 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 14:39:02 volumio volumio[845]: info: CoreStateMachine::play index undefined May 04 14:39:02 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 14:39:02 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 04 14:39:02 volumio volumio[845]: info: CoreStateMachine::startPlaybackTimer May 04 14:39:02 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 04 14:39:02 volumio volumio[845]: info: [1714829942008] ControllerWebradio::clearAddPlayTrack May 04 14:39:02 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand stop May 04 14:39:03 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand add "http://89.16.185.174:8004/stream" May 04 14:39:03 volumio volumio[845]: info: sendMpdCommand stop took 1589 milliseconds May 04 14:39:03 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand clear May 04 14:39:03 volumio volumio[845]: Upnp client error: Error: This socket has been ended by the other party May 04 14:39:03 volumio volumio[845]: Upnp client error: Error: This socket has been ended by the other party May 04 14:39:03 volumio volumio[845]: Upnp client error: Error: This socket has been ended by the other party May 04 14:39:03 volumio volumio[845]: Upnp client error: Error: This socket has been ended by the other party May 04 14:39:03 volumio volumio[845]: info: May 04 14:39:03 volumio volumio[845]: ---------------------------- MPD announces system playlist update May 04 14:39:03 volumio volumio[845]: info: Ignoring MPD Status Update May 04 14:39:03 volumio volumio[845]: info: May 04 14:39:03 volumio volumio[845]: ---------------------------- MPD announces system playlist update May 04 14:39:03 volumio volumio[845]: info: Ignoring MPD Status Update May 04 14:39:03 volumio volumio[845]: info: sendMpdCommand add "http://89.16.185.174:8004/stream" took 2 milliseconds May 04 14:39:03 volumio volumio[845]: info: sendMpdCommand clear took 2 milliseconds May 04 14:39:03 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService mpd May 04 14:39:03 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand play May 04 14:39:03 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand load "http://89.16.185.174:8004/stream" May 04 14:39:03 volumio volumio[845]: error: updateQueue error: null May 04 14:39:03 volumio volumio[845]: error: updateQueue error: null May 04 14:39:03 volumio volumio[845]: info: ------------------------------ 2ms May 04 14:39:03 volumio volumio[845]: info: ------------------------------ 2ms May 04 14:39:03 volumio volumio[845]: info: sendMpdCommand play took 1 milliseconds May 04 14:39:04 volumio volumio[845]: info: Preload queue cleared May 04 14:39:04 volumio volumio[845]: info: CoreCommandRouter::volumioReplaceandPlayItems May 04 14:39:04 volumio volumio[845]: info: CoreStateMachine::ClearQueue May 04 14:39:04 volumio volumio[845]: info: CoreStateMachine::stop May 04 14:39:04 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 14:39:04 volumio volumio[845]: info: CorePlayQueue::clearPlayQueue May 04 14:39:04 volumio volumio[845]: info: CorePlayQueue::saveQueue May 04 14:39:04 volumio volumio[845]: info: CoreCommandRouter::volumioPushQueue May 04 14:39:04 volumio volumio[845]: info: CoreStateMachine::addQueueItems May 04 14:39:04 volumio volumio[845]: info: CorePlayQueue::addQueueItems May 04 14:39:04 volumio volumio[845]: info: Preload queue cleared May 04 14:39:04 volumio volumio[845]: info: Adding Item to queue: http://89.16.185.174:8004/stream May 04 14:39:04 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 04 14:39:04 volumio volumio[845]: info: CoreCommandRouter::volumioPushQueue May 04 14:39:04 volumio volumio[845]: info: CorePlayQueue::saveQueue May 04 14:39:04 volumio volumio[845]: info: CoreStateMachine::updateTrackBlock May 04 14:39:04 volumio volumio[845]: info: CorePlayQueue::getTrackBlock May 04 14:39:04 volumio volumio[845]: info: CoreCommandRouter::volumioPlay May 04 14:39:04 volumio volumio[845]: info: CoreStateMachine::play index 0 May 04 14:39:04 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 14:39:04 volumio volumio[845]: info: CoreStateMachine::stop May 04 14:39:04 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 14:39:04 volumio volumio[845]: info: CoreStateMachine::play index undefined May 04 14:39:04 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 14:39:04 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 04 14:39:04 volumio volumio[845]: info: CoreStateMachine::startPlaybackTimer May 04 14:39:04 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 04 14:39:04 volumio volumio[845]: info: [1714829944412] ControllerWebradio::clearAddPlayTrack May 04 14:39:04 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand stop May 04 14:39:06 volumio volumio[845]: info: Preload queue cleared May 04 14:39:06 volumio volumio[845]: info: CoreCommandRouter::volumioReplaceandPlayItems May 04 14:39:06 volumio volumio[845]: info: CoreStateMachine::ClearQueue May 04 14:39:06 volumio volumio[845]: info: CoreStateMachine::stop May 04 14:39:06 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 14:39:06 volumio volumio[845]: info: CorePlayQueue::clearPlayQueue May 04 14:39:06 volumio volumio[845]: info: CorePlayQueue::saveQueue May 04 14:39:06 volumio volumio[845]: info: CoreCommandRouter::volumioPushQueue May 04 14:39:06 volumio volumio[845]: info: CoreStateMachine::addQueueItems May 04 14:39:06 volumio volumio[845]: info: CorePlayQueue::addQueueItems May 04 14:39:06 volumio volumio[845]: info: Preload queue cleared May 04 14:39:06 volumio volumio[845]: info: Adding Item to queue: http://89.16.185.174:8003/stream May 04 14:39:06 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 04 14:39:06 volumio volumio[845]: info: CoreCommandRouter::volumioPushQueue May 04 14:39:06 volumio volumio[845]: info: CorePlayQueue::saveQueue May 04 14:39:06 volumio volumio[845]: info: CoreStateMachine::updateTrackBlock May 04 14:39:06 volumio volumio[845]: info: CorePlayQueue::getTrackBlock May 04 14:39:06 volumio volumio[845]: info: CoreCommandRouter::volumioPlay May 04 14:39:06 volumio volumio[845]: info: CoreStateMachine::play index 0 May 04 14:39:06 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 14:39:06 volumio volumio[845]: info: CoreStateMachine::stop May 04 14:39:06 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 14:39:06 volumio volumio[845]: info: CoreStateMachine::play index undefined May 04 14:39:06 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 14:39:06 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 04 14:39:06 volumio volumio[845]: info: CoreStateMachine::startPlaybackTimer May 04 14:39:06 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 04 14:39:06 volumio volumio[845]: info: [1714829946334] ControllerWebradio::clearAddPlayTrack May 04 14:39:06 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand stop May 04 14:39:08 volumio volumio[845]: info: Preload queue cleared May 04 14:39:08 volumio volumio[845]: info: CoreCommandRouter::volumioReplaceandPlayItems May 04 14:39:08 volumio volumio[845]: info: CoreStateMachine::ClearQueue May 04 14:39:08 volumio volumio[845]: info: CoreStateMachine::stop May 04 14:39:08 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 14:39:08 volumio volumio[845]: info: CorePlayQueue::clearPlayQueue May 04 14:39:08 volumio volumio[845]: info: CorePlayQueue::saveQueue May 04 14:39:08 volumio volumio[845]: info: CoreCommandRouter::volumioPushQueue May 04 14:39:08 volumio volumio[845]: info: CoreStateMachine::addQueueItems May 04 14:39:08 volumio volumio[845]: info: CorePlayQueue::addQueueItems May 04 14:39:08 volumio volumio[845]: info: Preload queue cleared May 04 14:39:08 volumio volumio[845]: info: Adding Item to queue: http://89.16.185.174:8003/stream May 04 14:39:08 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 04 14:39:08 volumio volumio[845]: info: CoreCommandRouter::volumioPushQueue May 04 14:39:08 volumio volumio[845]: info: CorePlayQueue::saveQueue May 04 14:39:08 volumio volumio[845]: info: CoreStateMachine::updateTrackBlock May 04 14:39:08 volumio volumio[845]: info: CorePlayQueue::getTrackBlock May 04 14:39:08 volumio volumio[845]: info: CoreCommandRouter::volumioPlay May 04 14:39:08 volumio volumio[845]: info: CoreStateMachine::play index 0 May 04 14:39:08 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 14:39:08 volumio volumio[845]: info: CoreStateMachine::stop May 04 14:39:08 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 14:39:08 volumio volumio[845]: info: CoreStateMachine::play index undefined May 04 14:39:08 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 14:39:08 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 04 14:39:08 volumio volumio[845]: info: CoreStateMachine::startPlaybackTimer May 04 14:39:08 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 04 14:39:08 volumio volumio[845]: info: [1714829948772] ControllerWebradio::clearAddPlayTrack May 04 14:39:08 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand stop May 04 14:39:11 volumio volumio[845]: info: Preload queue cleared May 04 14:39:11 volumio volumio[845]: info: CoreCommandRouter::volumioReplaceandPlayItems May 04 14:39:11 volumio volumio[845]: info: CoreStateMachine::ClearQueue May 04 14:39:11 volumio volumio[845]: info: CoreStateMachine::stop May 04 14:39:11 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 14:39:11 volumio volumio[845]: info: CorePlayQueue::clearPlayQueue May 04 14:39:11 volumio volumio[845]: info: CorePlayQueue::saveQueue May 04 14:39:11 volumio volumio[845]: info: CoreCommandRouter::volumioPushQueue May 04 14:39:11 volumio volumio[845]: info: CoreStateMachine::addQueueItems May 04 14:39:11 volumio volumio[845]: info: CorePlayQueue::addQueueItems May 04 14:39:11 volumio volumio[845]: info: Preload queue cleared May 04 14:39:11 volumio volumio[845]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s220687 May 04 14:39:11 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 04 14:39:11 volumio volumio[845]: info: CoreCommandRouter::volumioPushQueue May 04 14:39:11 volumio volumio[845]: info: CorePlayQueue::saveQueue May 04 14:39:11 volumio volumio[845]: info: CoreStateMachine::updateTrackBlock May 04 14:39:11 volumio volumio[845]: info: CorePlayQueue::getTrackBlock May 04 14:39:11 volumio volumio[845]: info: CoreCommandRouter::volumioPlay May 04 14:39:11 volumio volumio[845]: info: CoreStateMachine::play index 0 May 04 14:39:11 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 14:39:11 volumio volumio[845]: info: CoreStateMachine::stop May 04 14:39:11 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 14:39:11 volumio volumio[845]: info: CoreStateMachine::play index undefined May 04 14:39:11 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 14:39:11 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 04 14:39:11 volumio volumio[845]: info: CoreStateMachine::startPlaybackTimer May 04 14:39:11 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 04 14:39:11 volumio volumio[845]: info: [1714829951490] ControllerWebradio::clearAddPlayTrack May 04 14:39:11 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand stop May 04 14:39:13 volumio volumio[845]: info: Preload queue cleared May 04 14:39:13 volumio volumio[845]: info: CoreCommandRouter::volumioReplaceandPlayItems May 04 14:39:13 volumio volumio[845]: info: CoreStateMachine::ClearQueue May 04 14:39:13 volumio volumio[845]: info: CoreStateMachine::stop May 04 14:39:13 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 14:39:13 volumio volumio[845]: info: CorePlayQueue::clearPlayQueue May 04 14:39:13 volumio volumio[845]: info: CorePlayQueue::saveQueue May 04 14:39:13 volumio volumio[845]: info: CoreCommandRouter::volumioPushQueue May 04 14:39:13 volumio volumio[845]: info: CoreStateMachine::addQueueItems May 04 14:39:13 volumio volumio[845]: info: CorePlayQueue::addQueueItems May 04 14:39:13 volumio volumio[845]: info: Preload queue cleared May 04 14:39:13 volumio volumio[845]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s220687 May 04 14:39:13 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 04 14:39:13 volumio volumio[845]: info: CoreCommandRouter::volumioPushQueue May 04 14:39:13 volumio volumio[845]: info: CorePlayQueue::saveQueue May 04 14:39:13 volumio volumio[845]: info: CoreStateMachine::updateTrackBlock May 04 14:39:13 volumio volumio[845]: info: CorePlayQueue::getTrackBlock May 04 14:39:13 volumio volumio[845]: info: CoreCommandRouter::volumioPlay May 04 14:39:13 volumio volumio[845]: info: CoreStateMachine::play index 0 May 04 14:39:13 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 14:39:13 volumio volumio[845]: info: CoreStateMachine::stop May 04 14:39:13 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 14:39:13 volumio volumio[845]: info: CoreStateMachine::play index undefined May 04 14:39:13 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 14:39:13 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 04 14:39:13 volumio volumio[845]: info: CoreStateMachine::startPlaybackTimer May 04 14:39:13 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 04 14:39:13 volumio volumio[845]: info: [1714829953177] ControllerWebradio::clearAddPlayTrack May 04 14:39:13 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand stop May 04 14:39:14 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand add "http://89.16.185.174:8004/stream" May 04 14:39:14 volumio volumio[845]: info: May 04 14:39:14 volumio volumio[845]: ---------------------------- MPD announces system playlist update May 04 14:39:14 volumio volumio[845]: info: Ignoring MPD Status Update May 04 14:39:14 volumio volumio[845]: info: sendMpdCommand stop took 10186 milliseconds May 04 14:39:14 volumio volumio[845]: info: sendMpdCommand stop took 8264 milliseconds May 04 14:39:14 volumio volumio[845]: info: sendMpdCommand stop took 5825 milliseconds May 04 14:39:14 volumio volumio[845]: info: sendMpdCommand stop took 3108 milliseconds May 04 14:39:14 volumio volumio[845]: info: sendMpdCommand stop took 1421 milliseconds May 04 14:39:14 volumio volumio[845]: info: sendMpdCommand add "http://89.16.185.174:8004/stream" took 1 milliseconds May 04 14:39:14 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand clear May 04 14:39:14 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand clear May 04 14:39:14 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService mpd May 04 14:39:14 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand play May 04 14:39:14 volumio volumio[845]: Upnp client error: Error: This socket has been ended by the other party May 04 14:39:14 volumio volumio[845]: Upnp client error: Error: This socket has been ended by the other party May 04 14:39:14 volumio volumio[845]: Upnp client error: Error: This socket has been ended by the other party May 04 14:39:14 volumio volumio[845]: info: May 04 14:39:14 volumio volumio[845]: ---------------------------- MPD announces system playlist update May 04 14:39:14 volumio volumio[845]: info: Ignoring MPD Status Update May 04 14:39:14 volumio volumio[845]: info: May 04 14:39:14 volumio volumio[845]: ---------------------------- MPD announces system playlist update May 04 14:39:14 volumio volumio[845]: info: Ignoring MPD Status Update May 04 14:39:14 volumio volumio[845]: info: May 04 14:39:14 volumio volumio[845]: ---------------------------- MPD announces system playlist update May 04 14:39:14 volumio volumio[845]: info: Ignoring MPD Status Update May 04 14:39:14 volumio volumio[845]: info: May 04 14:39:14 volumio volumio[845]: ---------------------------- MPD announces system playlist update May 04 14:39:14 volumio volumio[845]: info: Ignoring MPD Status Update May 04 14:39:14 volumio volumio[845]: info: May 04 14:39:14 volumio volumio[845]: ---------------------------- MPD announces system playlist update May 04 14:39:14 volumio volumio[845]: info: Ignoring MPD Status Update May 04 14:39:14 volumio volumio[845]: info: ------------------------------ 3ms May 04 14:39:14 volumio volumio[845]: info: sendMpdCommand clear took 3 milliseconds May 04 14:39:14 volumio volumio[845]: info: sendMpdCommand clear took 4 milliseconds May 04 14:39:14 volumio volumio[845]: info: sendMpdCommand clear took 3 milliseconds May 04 14:39:14 volumio volumio[845]: info: sendMpdCommand play took 3 milliseconds May 04 14:39:14 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand load "http://89.16.185.174:8004/stream" May 04 14:39:14 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand load "http://89.16.185.174:8003/stream" May 04 14:39:14 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand load "http://89.16.185.174:8003/stream" May 04 14:39:14 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand load "http://media-ice.musicradio.com/LBCUKMP3" May 04 14:39:14 volumio volumio[845]: error: updateQueue error: null May 04 14:39:14 volumio volumio[845]: error: updateQueue error: null May 04 14:39:14 volumio volumio[845]: error: updateQueue error: null May 04 14:39:14 volumio volumio[845]: error: updateQueue error: null May 04 14:39:14 volumio volumio[845]: error: updateQueue error: null May 04 14:39:14 volumio volumio[845]: info: ------------------------------ 4ms May 04 14:39:14 volumio volumio[845]: info: ------------------------------ 3ms May 04 14:39:14 volumio volumio[845]: info: ------------------------------ 3ms May 04 14:39:15 volumio volumio[845]: info: Preload queue cleared May 04 14:39:15 volumio volumio[845]: info: CoreCommandRouter::volumioReplaceandPlayItems May 04 14:39:15 volumio volumio[845]: info: CoreStateMachine::ClearQueue May 04 14:39:15 volumio volumio[845]: info: CoreStateMachine::stop May 04 14:39:15 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 14:39:15 volumio volumio[845]: info: CorePlayQueue::clearPlayQueue May 04 14:39:15 volumio volumio[845]: info: CorePlayQueue::saveQueue May 04 14:39:15 volumio volumio[845]: info: CoreCommandRouter::volumioPushQueue May 04 14:39:15 volumio volumio[845]: info: CoreStateMachine::addQueueItems May 04 14:39:15 volumio volumio[845]: info: CorePlayQueue::addQueueItems May 04 14:39:15 volumio volumio[845]: info: Preload queue cleared May 04 14:39:15 volumio volumio[845]: info: Adding Item to queue: http://stream.live.vc.bbcmedia.co.uk/bbc_radio_three May 04 14:39:15 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 04 14:39:15 volumio volumio[845]: info: CoreCommandRouter::volumioPushQueue May 04 14:39:15 volumio volumio[845]: info: CorePlayQueue::saveQueue May 04 14:39:15 volumio volumio[845]: info: CoreStateMachine::updateTrackBlock May 04 14:39:15 volumio volumio[845]: info: CorePlayQueue::getTrackBlock May 04 14:39:15 volumio volumio[845]: info: CoreCommandRouter::volumioPlay May 04 14:39:15 volumio volumio[845]: info: CoreStateMachine::play index 0 May 04 14:39:15 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 14:39:15 volumio volumio[845]: info: CoreStateMachine::stop May 04 14:39:15 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 14:39:15 volumio volumio[845]: info: CoreStateMachine::play index undefined May 04 14:39:15 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 14:39:15 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 04 14:39:15 volumio volumio[845]: info: CoreStateMachine::startPlaybackTimer May 04 14:39:15 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 04 14:39:15 volumio volumio[845]: info: [1714829955664] ControllerWebradio::clearAddPlayTrack May 04 14:39:15 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand stop May 04 14:39:16 volumio volumio[845]: info: Preload queue cleared May 04 14:39:16 volumio volumio[845]: info: CoreCommandRouter::volumioReplaceandPlayItems May 04 14:39:16 volumio volumio[845]: info: CoreStateMachine::ClearQueue May 04 14:39:16 volumio volumio[845]: info: CoreStateMachine::stop May 04 14:39:16 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 14:39:16 volumio volumio[845]: info: CorePlayQueue::clearPlayQueue May 04 14:39:16 volumio volumio[845]: info: CorePlayQueue::saveQueue May 04 14:39:16 volumio volumio[845]: info: CoreCommandRouter::volumioPushQueue May 04 14:39:16 volumio volumio[845]: info: CoreStateMachine::addQueueItems May 04 14:39:16 volumio volumio[845]: info: CorePlayQueue::addQueueItems May 04 14:39:16 volumio volumio[845]: info: Preload queue cleared May 04 14:39:16 volumio volumio[845]: info: Adding Item to queue: http://stream.live.vc.bbcmedia.co.uk/bbc_radio_three May 04 14:39:16 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 04 14:39:16 volumio volumio[845]: info: CoreCommandRouter::volumioPushQueue May 04 14:39:16 volumio volumio[845]: info: CorePlayQueue::saveQueue May 04 14:39:16 volumio volumio[845]: info: CoreStateMachine::updateTrackBlock May 04 14:39:16 volumio volumio[845]: info: CorePlayQueue::getTrackBlock May 04 14:39:16 volumio volumio[845]: info: CoreCommandRouter::volumioPlay May 04 14:39:16 volumio volumio[845]: info: CoreStateMachine::play index 0 May 04 14:39:16 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 14:39:16 volumio volumio[845]: info: CoreStateMachine::stop May 04 14:39:16 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 14:39:16 volumio volumio[845]: info: CoreStateMachine::play index undefined May 04 14:39:16 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 14:39:16 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 04 14:39:16 volumio volumio[845]: info: CoreStateMachine::startPlaybackTimer May 04 14:39:16 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 04 14:39:16 volumio volumio[845]: info: [1714829956869] ControllerWebradio::clearAddPlayTrack May 04 14:39:16 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand stop May 04 14:39:19 volumio volumio[845]: info: CoreCommandRouter::volumioPlay May 04 14:39:19 volumio volumio[845]: info: CoreStateMachine::play index undefined May 04 14:39:19 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 14:39:19 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 04 14:39:19 volumio volumio[845]: info: CoreStateMachine::startPlaybackTimer May 04 14:39:19 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 04 14:39:19 volumio volumio[845]: info: [1714829959683] ControllerWebradio::clearAddPlayTrack May 04 14:39:19 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand stop May 04 14:39:21 volumio volumio[845]: info: CoreCommandRouter::volumioPlay May 04 14:39:21 volumio volumio[845]: info: CoreStateMachine::play index undefined May 04 14:39:21 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 14:39:21 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 04 14:39:21 volumio volumio[845]: info: CoreStateMachine::startPlaybackTimer May 04 14:39:21 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 04 14:39:21 volumio volumio[845]: info: [1714829961996] ControllerWebradio::clearAddPlayTrack May 04 14:39:21 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand stop May 04 14:39:25 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand add "http://89.16.185.174:8004/stream" May 04 14:39:25 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 04 14:39:25 volumio volumio[845]: info: Preload queue cleared May 04 14:39:28 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 04 14:39:28 volumio volumio[845]: info: Preload queue cleared May 04 14:39:29 volumio volumio[845]: info: Preload queue cleared May 04 14:39:29 volumio volumio[845]: info: CoreCommandRouter::volumioReplaceandPlayItems May 04 14:39:29 volumio volumio[845]: info: CoreStateMachine::ClearQueue May 04 14:39:29 volumio volumio[845]: info: CoreStateMachine::stop May 04 14:39:29 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 14:39:29 volumio volumio[845]: info: CorePlayQueue::clearPlayQueue May 04 14:39:29 volumio volumio[845]: info: CorePlayQueue::saveQueue May 04 14:39:29 volumio volumio[845]: info: CoreCommandRouter::volumioPushQueue May 04 14:39:29 volumio volumio[845]: info: CoreStateMachine::addQueueItems May 04 14:39:29 volumio volumio[845]: info: CorePlayQueue::addQueueItems May 04 14:39:29 volumio volumio[845]: info: Preload queue cleared May 04 14:39:29 volumio volumio[845]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s17569 May 04 14:39:29 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 04 14:39:29 volumio volumio[845]: info: CoreCommandRouter::volumioPushQueue May 04 14:39:29 volumio volumio[845]: info: CorePlayQueue::saveQueue May 04 14:39:29 volumio volumio[845]: info: CoreStateMachine::updateTrackBlock May 04 14:39:29 volumio volumio[845]: info: CorePlayQueue::getTrackBlock May 04 14:39:29 volumio volumio[845]: info: CoreCommandRouter::volumioPlay May 04 14:39:29 volumio volumio[845]: info: CoreStateMachine::play index 0 May 04 14:39:29 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 14:39:29 volumio volumio[845]: info: CoreStateMachine::stop May 04 14:39:29 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 14:39:29 volumio volumio[845]: info: CoreStateMachine::play index undefined May 04 14:39:29 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 14:39:29 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 04 14:39:29 volumio volumio[845]: info: CoreStateMachine::startPlaybackTimer May 04 14:39:29 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 04 14:39:29 volumio volumio[845]: info: [1714829969731] ControllerWebradio::clearAddPlayTrack May 04 14:39:29 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand stop May 04 14:39:34 volumio volumio[845]: info: Preload queue cleared May 04 14:39:34 volumio volumio[845]: info: CoreCommandRouter::volumioReplaceandPlayItems May 04 14:39:34 volumio volumio[845]: info: CoreStateMachine::ClearQueue May 04 14:39:34 volumio volumio[845]: info: CoreStateMachine::stop May 04 14:39:34 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 14:39:34 volumio volumio[845]: info: CorePlayQueue::clearPlayQueue May 04 14:39:34 volumio volumio[845]: info: CorePlayQueue::saveQueue May 04 14:39:34 volumio volumio[845]: info: CoreCommandRouter::volumioPushQueue May 04 14:39:34 volumio volumio[845]: info: CoreStateMachine::addQueueItems May 04 14:39:34 volumio volumio[845]: info: CorePlayQueue::addQueueItems May 04 14:39:34 volumio volumio[845]: info: Preload queue cleared May 04 14:39:34 volumio volumio[845]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s6909 May 04 14:39:34 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 04 14:39:34 volumio volumio[845]: info: CoreCommandRouter::volumioPushQueue May 04 14:39:34 volumio volumio[845]: info: CorePlayQueue::saveQueue May 04 14:39:34 volumio volumio[845]: info: CoreStateMachine::updateTrackBlock May 04 14:39:34 volumio volumio[845]: info: CorePlayQueue::getTrackBlock May 04 14:39:34 volumio volumio[845]: info: CoreCommandRouter::volumioPlay May 04 14:39:34 volumio volumio[845]: info: CoreStateMachine::play index 0 May 04 14:39:34 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 14:39:34 volumio volumio[845]: info: CoreStateMachine::stop May 04 14:39:34 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 14:39:34 volumio volumio[845]: info: CoreStateMachine::play index undefined May 04 14:39:34 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 14:39:34 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 04 14:39:34 volumio volumio[845]: info: CoreStateMachine::startPlaybackTimer May 04 14:39:34 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 04 14:39:34 volumio volumio[845]: info: [1714829974319] ControllerWebradio::clearAddPlayTrack May 04 14:39:34 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand stop May 04 14:39:34 volumio volumio[845]: info: Preload queue cleared May 04 14:39:34 volumio volumio[845]: info: CoreCommandRouter::volumioReplaceandPlayItems May 04 14:39:34 volumio volumio[845]: info: CoreStateMachine::ClearQueue May 04 14:39:34 volumio volumio[845]: info: CoreStateMachine::stop May 04 14:39:34 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 14:39:34 volumio volumio[845]: info: CorePlayQueue::clearPlayQueue May 04 14:39:34 volumio volumio[845]: info: CorePlayQueue::saveQueue May 04 14:39:34 volumio volumio[845]: info: CoreCommandRouter::volumioPushQueue May 04 14:39:34 volumio volumio[845]: info: CoreStateMachine::addQueueItems May 04 14:39:34 volumio volumio[845]: info: CorePlayQueue::addQueueItems May 04 14:39:34 volumio volumio[845]: info: Preload queue cleared May 04 14:39:34 volumio volumio[845]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s6909 May 04 14:39:34 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 04 14:39:34 volumio volumio[845]: info: CoreCommandRouter::volumioPushQueue May 04 14:39:34 volumio volumio[845]: info: CorePlayQueue::saveQueue May 04 14:39:34 volumio volumio[845]: info: CoreStateMachine::updateTrackBlock May 04 14:39:34 volumio volumio[845]: info: CorePlayQueue::getTrackBlock May 04 14:39:34 volumio volumio[845]: info: CoreCommandRouter::volumioPlay May 04 14:39:34 volumio volumio[845]: info: CoreStateMachine::play index 0 May 04 14:39:34 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 14:39:34 volumio volumio[845]: info: CoreStateMachine::stop May 04 14:39:34 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 14:39:34 volumio volumio[845]: info: CoreStateMachine::play index undefined May 04 14:39:34 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 14:39:34 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 04 14:39:34 volumio volumio[845]: info: CoreStateMachine::startPlaybackTimer May 04 14:39:34 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 04 14:39:34 volumio volumio[845]: info: [1714829974515] ControllerWebradio::clearAddPlayTrack May 04 14:39:34 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand stop May 04 14:39:34 volumio volumio[845]: info: Preload queue cleared May 04 14:39:34 volumio volumio[845]: info: CoreCommandRouter::volumioReplaceandPlayItems May 04 14:39:34 volumio volumio[845]: info: CoreStateMachine::ClearQueue May 04 14:39:34 volumio volumio[845]: info: CoreStateMachine::stop May 04 14:39:34 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 14:39:34 volumio volumio[845]: info: CorePlayQueue::clearPlayQueue May 04 14:39:34 volumio volumio[845]: info: CorePlayQueue::saveQueue May 04 14:39:34 volumio volumio[845]: info: CoreCommandRouter::volumioPushQueue May 04 14:39:34 volumio volumio[845]: info: CoreStateMachine::addQueueItems May 04 14:39:34 volumio volumio[845]: info: CorePlayQueue::addQueueItems May 04 14:39:34 volumio volumio[845]: info: Preload queue cleared May 04 14:39:34 volumio volumio[845]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s6909 May 04 14:39:34 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 04 14:39:34 volumio volumio[845]: info: CoreCommandRouter::volumioPushQueue May 04 14:39:34 volumio volumio[845]: info: CorePlayQueue::saveQueue May 04 14:39:34 volumio volumio[845]: info: CoreStateMachine::updateTrackBlock May 04 14:39:34 volumio volumio[845]: info: CorePlayQueue::getTrackBlock May 04 14:39:34 volumio volumio[845]: info: CoreCommandRouter::volumioPlay May 04 14:39:34 volumio volumio[845]: info: CoreStateMachine::play index 0 May 04 14:39:34 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 14:39:34 volumio volumio[845]: info: CoreStateMachine::stop May 04 14:39:34 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 14:39:34 volumio volumio[845]: info: CoreStateMachine::play index undefined May 04 14:39:34 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 14:39:34 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 04 14:39:34 volumio volumio[845]: info: CoreStateMachine::startPlaybackTimer May 04 14:39:34 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 04 14:39:34 volumio volumio[845]: info: [1714829974749] ControllerWebradio::clearAddPlayTrack May 04 14:39:34 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand stop May 04 14:39:37 volumio volumio[845]: info: CoreCommandRouter::volumioPlay May 04 14:39:37 volumio volumio[845]: info: CoreStateMachine::play index undefined May 04 14:39:37 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 14:39:37 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 04 14:39:37 volumio volumio[845]: info: CoreStateMachine::startPlaybackTimer May 04 14:39:37 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 04 14:39:37 volumio volumio[845]: info: [1714829977858] ControllerWebradio::clearAddPlayTrack May 04 14:39:37 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand stop May 04 14:39:38 volumio volumio[845]: info: CoreCommandRouter::volumioPlay May 04 14:39:38 volumio volumio[845]: info: CoreStateMachine::play index undefined May 04 14:39:38 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 14:39:38 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 04 14:39:38 volumio volumio[845]: info: CoreStateMachine::startPlaybackTimer May 04 14:39:38 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 04 14:39:38 volumio volumio[845]: info: [1714829978500] ControllerWebradio::clearAddPlayTrack May 04 14:39:38 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand stop May 04 14:39:43 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 04 14:39:43 volumio volumio[845]: info: CURURI: music-library May 04 14:39:48 volumio volumio[845]: Upnp client error: Error: This socket has been ended by the other party May 04 14:39:48 volumio volumio[845]: Upnp client error: Error: This socket has been ended by the other party May 04 14:39:48 volumio volumio[845]: info: May 04 14:39:48 volumio volumio[845]: ---------------------------- MPD announces system playlist update May 04 14:39:48 volumio volumio[845]: info: Ignoring MPD Status Update May 04 14:39:48 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand add "http://89.16.185.174:8003/stream" May 04 14:39:48 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand add "http://media-ice.musicradio.com/LBCUKMP3" May 04 14:39:48 volumio volumio[845]: info: sendMpdCommand stop took 32977 milliseconds May 04 14:39:48 volumio volumio[845]: info: sendMpdCommand stop took 31772 milliseconds May 04 14:39:48 volumio volumio[845]: info: sendMpdCommand stop took 28958 milliseconds May 04 14:39:48 volumio volumio[845]: info: sendMpdCommand stop took 26644 milliseconds May 04 14:39:48 volumio volumio[845]: info: sendMpdCommand add "http://89.16.185.174:8004/stream" took 23044 milliseconds May 04 14:39:48 volumio volumio[845]: info: sendMpdCommand stop took 18910 milliseconds May 04 14:39:48 volumio volumio[845]: info: sendMpdCommand stop took 14322 milliseconds May 04 14:39:48 volumio volumio[845]: info: sendMpdCommand stop took 14126 milliseconds May 04 14:39:48 volumio volumio[845]: info: sendMpdCommand stop took 13892 milliseconds May 04 14:39:48 volumio volumio[845]: info: sendMpdCommand stop took 10783 milliseconds May 04 14:39:48 volumio volumio[845]: info: sendMpdCommand stop took 10141 milliseconds May 04 14:39:48 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand clear May 04 14:39:48 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand clear May 04 14:39:48 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand clear May 04 14:39:48 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand clear May 04 14:39:48 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService mpd May 04 14:39:48 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand play May 04 14:39:48 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand clear May 04 14:39:48 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand clear May 04 14:39:48 volumio volumio[845]: Upnp client error: Error: This socket has been ended by the other party May 04 14:39:48 volumio volumio[845]: Upnp client error: Error: This socket has been ended by the other party May 04 14:39:48 volumio volumio[845]: Upnp client error: Error: This socket has been ended by the other party May 04 14:39:48 volumio volumio[845]: Upnp client error: Error: This socket has been ended by the other party May 04 14:39:48 volumio volumio[845]: Upnp client error: Error: This socket has been ended by the other party May 04 14:39:48 volumio volumio[845]: info: May 04 14:39:48 volumio volumio[845]: ---------------------------- MPD announces system playlist update May 04 14:39:48 volumio volumio[845]: info: Ignoring MPD Status Update May 04 14:39:48 volumio volumio[845]: info: May 04 14:39:48 volumio volumio[845]: ---------------------------- MPD announces system playlist update May 04 14:39:48 volumio volumio[845]: info: Ignoring MPD Status Update May 04 14:39:48 volumio volumio[845]: info: May 04 14:39:48 volumio volumio[845]: ---------------------------- MPD announces system playlist update May 04 14:39:48 volumio volumio[845]: info: Ignoring MPD Status Update May 04 14:39:48 volumio volumio[845]: info: May 04 14:39:48 volumio volumio[845]: ---------------------------- MPD announces system playlist update May 04 14:39:48 volumio volumio[845]: info: Ignoring MPD Status Update May 04 14:39:48 volumio volumio[845]: info: May 04 14:39:48 volumio volumio[845]: ---------------------------- MPD announces system playlist update May 04 14:39:48 volumio volumio[845]: info: Ignoring MPD Status Update May 04 14:39:48 volumio volumio[845]: info: May 04 14:39:48 volumio volumio[845]: ---------------------------- MPD announces system playlist update May 04 14:39:48 volumio volumio[845]: info: Ignoring MPD Status Update May 04 14:39:48 volumio volumio[845]: info: May 04 14:39:48 volumio volumio[845]: ---------------------------- MPD announces system playlist update May 04 14:39:48 volumio volumio[845]: info: Ignoring MPD Status Update May 04 14:39:48 volumio volumio[845]: info: May 04 14:39:48 volumio volumio[845]: ---------------------------- MPD announces system playlist update May 04 14:39:48 volumio volumio[845]: info: Ignoring MPD Status Update May 04 14:39:48 volumio volumio[845]: info: May 04 14:39:48 volumio volumio[845]: ---------------------------- MPD announces system playlist update May 04 14:39:48 volumio volumio[845]: info: Ignoring MPD Status Update May 04 14:39:48 volumio volumio[845]: info: May 04 14:39:48 volumio volumio[845]: ---------------------------- MPD announces system playlist update May 04 14:39:48 volumio volumio[845]: info: Ignoring MPD Status Update May 04 14:39:48 volumio volumio[845]: info: May 04 14:39:48 volumio volumio[845]: ---------------------------- MPD announces system playlist update May 04 14:39:48 volumio volumio[845]: info: Ignoring MPD Status Update May 04 14:39:48 volumio volumio[845]: info: May 04 14:39:48 volumio volumio[845]: ---------------------------- MPD announces system playlist update May 04 14:39:48 volumio volumio[845]: info: Ignoring MPD Status Update May 04 14:39:48 volumio volumio[845]: info: May 04 14:39:48 volumio volumio[845]: ---------------------------- MPD announces system playlist update May 04 14:39:48 volumio volumio[845]: info: Ignoring MPD Status Update May 04 14:39:48 volumio volumio[845]: info: May 04 14:39:48 volumio volumio[845]: ---------------------------- MPD announces system playlist update May 04 14:39:48 volumio volumio[845]: info: Ignoring MPD Status Update May 04 14:39:48 volumio volumio[845]: info: sendMpdCommand add "http://89.16.185.174:8003/stream" took 10 milliseconds May 04 14:39:48 volumio volumio[845]: info: sendMpdCommand add "http://media-ice.musicradio.com/LBCUKMP3" took 10 milliseconds May 04 14:39:48 volumio volumio[845]: info: ------------------------------ 10ms May 04 14:39:48 volumio volumio[845]: info: sendMpdCommand clear took 8 milliseconds May 04 14:39:48 volumio volumio[845]: info: sendMpdCommand clear took 7 milliseconds May 04 14:39:48 volumio volumio[845]: info: sendMpdCommand play took 7 milliseconds May 04 14:39:48 volumio volumio[845]: info: sendMpdCommand clear took 7 milliseconds May 04 14:39:48 volumio volumio[845]: info: sendMpdCommand clear took 8 milliseconds May 04 14:39:48 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService mpd May 04 14:39:48 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand play May 04 14:39:48 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService mpd May 04 14:39:48 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand play May 04 14:39:48 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService mpd May 04 14:39:48 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand play May 04 14:39:48 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService mpd May 04 14:39:48 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand play May 04 14:39:48 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand load "http://stream.live.vc.bbcmedia.co.uk/bbc_radio_three" May 04 14:39:48 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand load "http://stream.live.vc.bbcmedia.co.uk/bbc_radio_three" May 04 14:39:48 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand load "http://stream.live.vc.bbcmedia.co.uk/bbc_radio_three" May 04 14:39:48 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand load "http://media-ice.musicradio.com/LBCLondonMP3" May 04 14:39:48 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand load "http://media-ice.musicradio.com/SmoothLondonMP3" May 04 14:39:48 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand load "http://media-ice.musicradio.com/SmoothLondonMP3" May 04 14:39:48 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand load "http://media-ice.musicradio.com/SmoothLondonMP3" May 04 14:39:48 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand load "http://media-ice.musicradio.com/SmoothLondonMP3" May 04 14:39:48 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand load "http://media-ice.musicradio.com/SmoothLondonMP3" May 04 14:39:48 volumio volumio[845]: error: updateQueue error: null May 04 14:39:48 volumio volumio[845]: error: updateQueue error: null May 04 14:39:48 volumio volumio[845]: error: updateQueue error: null May 04 14:39:48 volumio volumio[845]: error: updateQueue error: null May 04 14:39:48 volumio volumio[845]: error: updateQueue error: null May 04 14:39:48 volumio volumio[845]: error: updateQueue error: null May 04 14:39:48 volumio volumio[845]: error: updateQueue error: null May 04 14:39:48 volumio volumio[845]: error: updateQueue error: null May 04 14:39:48 volumio volumio[845]: error: updateQueue error: null May 04 14:39:48 volumio volumio[845]: error: updateQueue error: null May 04 14:39:48 volumio volumio[845]: error: updateQueue error: null May 04 14:39:48 volumio volumio[845]: info: ------------------------------ 11ms May 04 14:39:48 volumio volumio[845]: info: ------------------------------ 11ms May 04 14:39:48 volumio volumio[845]: info: ------------------------------ 10ms May 04 14:39:48 volumio volumio[845]: info: ------------------------------ 10ms May 04 14:39:48 volumio volumio[845]: info: ------------------------------ 10ms May 04 14:39:48 volumio volumio[845]: info: ------------------------------ 9ms May 04 14:39:48 volumio volumio[845]: info: ------------------------------ 9ms May 04 14:39:48 volumio volumio[845]: info: sendMpdCommand play took 7 milliseconds May 04 14:39:48 volumio volumio[845]: info: sendMpdCommand play took 8 milliseconds May 04 14:39:48 volumio volumio[845]: info: sendMpdCommand play took 7 milliseconds May 04 14:39:48 volumio volumio[845]: info: Preload queue cleared May 04 14:39:49 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand add "http://stream.live.vc.bbcmedia.co.uk/bbc_radio_three" May 04 14:39:49 volumio volumio[845]: info: May 04 14:39:49 volumio volumio[845]: ---------------------------- MPD announces system playlist update May 04 14:39:49 volumio volumio[845]: info: Ignoring MPD Status Update May 04 14:39:49 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand add "http://stream.live.vc.bbcmedia.co.uk/bbc_radio_three" May 04 14:39:49 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand add "http://media-ice.musicradio.com/LBCLondonMP3" May 04 14:39:49 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand add "http://media-ice.musicradio.com/SmoothLondonMP3" May 04 14:39:49 volumio volumio[845]: info: sendMpdCommand add "http://stream.live.vc.bbcmedia.co.uk/bbc_radio_three" took 226 milliseconds May 04 14:39:49 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService mpd May 04 14:39:49 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand play May 04 14:39:49 volumio volumio[845]: Upnp client error: Error: This socket has been ended by the other party May 04 14:39:49 volumio volumio[845]: info: May 04 14:39:49 volumio volumio[845]: ---------------------------- MPD announces system playlist update May 04 14:39:49 volumio volumio[845]: info: Ignoring MPD Status Update May 04 14:39:49 volumio volumio[845]: info: May 04 14:39:49 volumio volumio[845]: ---------------------------- MPD announces system playlist update May 04 14:39:49 volumio volumio[845]: info: Ignoring MPD Status Update May 04 14:39:49 volumio volumio[845]: info: May 04 14:39:49 volumio volumio[845]: ---------------------------- MPD announces system playlist update May 04 14:39:49 volumio volumio[845]: info: Ignoring MPD Status Update May 04 14:39:49 volumio volumio[845]: info: May 04 14:39:49 volumio volumio[845]: ---------------------------- MPD announces system playlist update May 04 14:39:49 volumio volumio[845]: info: Ignoring MPD Status Update May 04 14:39:49 volumio volumio[845]: info: May 04 14:39:49 volumio volumio[845]: ---------------------------- MPD announces system playlist update May 04 14:39:49 volumio volumio[845]: info: Ignoring MPD Status Update May 04 14:39:49 volumio volumio[845]: info: May 04 14:39:49 volumio volumio[845]: ---------------------------- MPD announces system playlist update May 04 14:39:49 volumio volumio[845]: info: Ignoring MPD Status Update May 04 14:39:49 volumio volumio[845]: info: May 04 14:39:49 volumio volumio[845]: ---------------------------- MPD announces system playlist update May 04 14:39:49 volumio volumio[845]: info: Ignoring MPD Status Update May 04 14:39:49 volumio volumio[845]: info: May 04 14:39:49 volumio volumio[845]: ---------------------------- MPD announces system playlist update May 04 14:39:49 volumio volumio[845]: info: Ignoring MPD Status Update May 04 14:39:49 volumio volumio[845]: info: May 04 14:39:49 volumio volumio[845]: ---------------------------- MPD announces system playlist update May 04 14:39:49 volumio volumio[845]: info: Ignoring MPD Status Update May 04 14:39:49 volumio volumio[845]: info: sendMpdCommand add "http://stream.live.vc.bbcmedia.co.uk/bbc_radio_three" took 10 milliseconds May 04 14:39:49 volumio volumio[845]: info: sendMpdCommand add "http://stream.live.vc.bbcmedia.co.uk/bbc_radio_three" took 11 milliseconds May 04 14:39:49 volumio volumio[845]: info: sendMpdCommand add "http://stream.live.vc.bbcmedia.co.uk/bbc_radio_three" took 10 milliseconds May 04 14:39:49 volumio volumio[845]: info: sendMpdCommand add "http://media-ice.musicradio.com/LBCLondonMP3" took 10 milliseconds May 04 14:39:49 volumio volumio[845]: info: sendMpdCommand add "http://media-ice.musicradio.com/SmoothLondonMP3" took 10 milliseconds May 04 14:39:49 volumio volumio[845]: info: sendMpdCommand add "http://media-ice.musicradio.com/SmoothLondonMP3" took 11 milliseconds May 04 14:39:49 volumio volumio[845]: info: sendMpdCommand add "http://media-ice.musicradio.com/SmoothLondonMP3" took 11 milliseconds May 04 14:39:49 volumio volumio[845]: info: sendMpdCommand add "http://media-ice.musicradio.com/SmoothLondonMP3" took 11 milliseconds May 04 14:39:49 volumio volumio[845]: info: sendMpdCommand add "http://media-ice.musicradio.com/SmoothLondonMP3" took 11 milliseconds May 04 14:39:49 volumio volumio[845]: info: ------------------------------ 12ms May 04 14:39:49 volumio volumio[845]: info: sendMpdCommand play took 10 milliseconds May 04 14:39:49 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService mpd May 04 14:39:49 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand play May 04 14:39:49 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService mpd May 04 14:39:49 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand play May 04 14:39:49 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService mpd May 04 14:39:49 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand play May 04 14:39:49 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService mpd May 04 14:39:49 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand play May 04 14:39:49 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService mpd May 04 14:39:49 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand play May 04 14:39:49 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService mpd May 04 14:39:49 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand play May 04 14:39:49 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService mpd May 04 14:39:49 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand play May 04 14:39:49 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService mpd May 04 14:39:49 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand play May 04 14:39:49 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService mpd May 04 14:39:49 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand play May 04 14:39:49 volumio volumio[845]: info: ------------------------------ 18ms May 04 14:39:49 volumio volumio[845]: info: ------------------------------ 17ms May 04 14:39:49 volumio volumio[845]: info: ------------------------------ 17ms May 04 14:39:49 volumio volumio[845]: info: ------------------------------ 16ms May 04 14:39:49 volumio volumio[845]: info: ------------------------------ 17ms May 04 14:39:49 volumio volumio[845]: info: ------------------------------ 16ms May 04 14:39:49 volumio volumio[845]: info: ------------------------------ 16ms May 04 14:39:49 volumio volumio[845]: info: ------------------------------ 15ms May 04 14:39:49 volumio volumio[845]: info: ------------------------------ 14ms May 04 14:39:49 volumio volumio[845]: info: sendMpdCommand play took 11 milliseconds May 04 14:39:49 volumio volumio[845]: info: sendMpdCommand play took 11 milliseconds May 04 14:39:49 volumio volumio[845]: info: sendMpdCommand play took 11 milliseconds May 04 14:39:49 volumio volumio[845]: info: sendMpdCommand play took 10 milliseconds May 04 14:39:49 volumio volumio[845]: info: sendMpdCommand play took 9 milliseconds May 04 14:39:49 volumio volumio[845]: info: sendMpdCommand play took 9 milliseconds May 04 14:39:49 volumio volumio[845]: info: sendMpdCommand play took 9 milliseconds May 04 14:39:49 volumio volumio[845]: info: sendMpdCommand play took 8 milliseconds May 04 14:39:49 volumio volumio[845]: info: sendMpdCommand play took 8 milliseconds May 04 14:39:50 volumio volumio[845]: info: May 04 14:39:50 volumio volumio[845]: ---------------------------- MPD announces state update: player May 04 14:39:50 volumio volumio[845]: info: ControllerMpd::getState May 04 14:39:50 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand status May 04 14:39:50 volumio volumio[845]: info: May 04 14:39:50 volumio volumio[845]: ---------------------------- MPD announces state update: player May 04 14:39:50 volumio volumio[845]: info: sendMpdCommand status took 2 milliseconds May 04 14:39:50 volumio volumio[845]: info: ControllerMpd::getState May 04 14:39:50 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand status May 04 14:39:50 volumio volumio[845]: verbose: ControllerMpd::parseState May 04 14:39:50 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 14:39:50 volumio volumio[845]: info: sendMpdCommand status took 1 milliseconds May 04 14:39:50 volumio volumio[845]: info: sendMpdCommand playlistinfo took 0 milliseconds May 04 14:39:50 volumio volumio[845]: verbose: ControllerMpd::parseState May 04 14:39:50 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 14:39:50 volumio volumio[845]: verbose: ControllerMpd::parseTrackInfo May 04 14:39:50 volumio volumio[845]: info: ControllerMpd::pushState May 04 14:39:50 volumio volumio[845]: info: CoreCommandRouter::servicePushState May 04 14:39:50 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 04 14:39:50 volumio volumio[845]: verbose: STATE SERVICE {"status":"play","position":4,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"LBCLondonMP3","artist":null,"album":null,"uri":"http://media-ice.musicradio.com/LBCLondonMP3","trackType":""} May 04 14:39:50 volumio volumio[845]: verbose: CURRENT POSITION 0 May 04 14:39:50 volumio volumio[845]: info: CoreStateMachine::syncState stateService play May 04 14:39:50 volumio volumio[845]: info: CoreStateMachine::syncState currentStatus stop May 04 14:39:50 volumio volumio[845]: info: ------------------------------ 7ms May 04 14:39:50 volumio volumio[845]: info: sendMpdCommand playlistinfo took 2 milliseconds May 04 14:39:50 volumio volumio[845]: verbose: ControllerMpd::parseTrackInfo May 04 14:39:50 volumio volumio[845]: info: ControllerMpd::pushState May 04 14:39:50 volumio volumio[845]: info: CoreCommandRouter::servicePushState May 04 14:39:50 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 04 14:39:50 volumio volumio[845]: verbose: STATE SERVICE {"status":"play","position":4,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"LBCLondonMP3","artist":null,"album":null,"uri":"http://media-ice.musicradio.com/LBCLondonMP3","trackType":""} May 04 14:39:50 volumio volumio[845]: verbose: CURRENT POSITION 0 May 04 14:39:50 volumio volumio[845]: info: CoreStateMachine::syncState stateService play May 04 14:39:50 volumio volumio[845]: info: CoreStateMachine::syncState currentStatus play May 04 14:39:50 volumio volumio[845]: info: Received an update from plugin. extracting info from payload May 04 14:39:50 volumio volumio[845]: info: CoreStateMachine::pushState May 04 14:39:50 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 04 14:39:50 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 14:39:50 volumio volumio[845]: info: CoreCommandRouter::volumioPushState May 04 14:39:50 volumio volumio[845]: info: CoreStateMachine::pushState May 04 14:39:50 volumio volumio[845]: info: CoreCommandRouter::volumioPushState May 04 14:39:50 volumio volumio[845]: info: ------------------------------ 14ms May 04 14:39:50 volumio volumio[845]: info: May 04 14:39:50 volumio volumio[845]: ---------------------------- MPD announces system playlist update May 04 14:39:50 volumio volumio[845]: info: Ignoring MPD Status Update May 04 14:39:50 volumio volumio[845]: info: May 04 14:39:50 volumio volumio[845]: ---------------------------- MPD announces state update: player May 04 14:39:50 volumio volumio[845]: info: ControllerMpd::getState May 04 14:39:50 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand status May 04 14:39:50 volumio volumio[845]: info: ------------------------------ 5ms May 04 14:39:50 volumio volumio[845]: info: sendMpdCommand status took 4 milliseconds May 04 14:39:50 volumio volumio[845]: verbose: ControllerMpd::parseState May 04 14:39:50 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 14:39:50 volumio volumio[845]: info: sendMpdCommand playlistinfo took 1 milliseconds May 04 14:39:50 volumio volumio[845]: verbose: ControllerMpd::parseTrackInfo May 04 14:39:50 volumio volumio[845]: info: ControllerMpd::pushState May 04 14:39:50 volumio volumio[845]: info: CoreCommandRouter::servicePushState May 04 14:39:50 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 04 14:39:50 volumio volumio[845]: verbose: STATE SERVICE {"status":"play","position":4,"seek":846,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"48 Kbps","isStreaming":false,"title":"Leading Britain's Conversation - Ali Miraj","artist":"LBC London","album":null,"uri":"http://media-ice.musicradio.com/LBCLondonMP3","trackType":""} May 04 14:39:50 volumio volumio[845]: verbose: CURRENT POSITION 0 May 04 14:39:50 volumio volumio[845]: info: CoreStateMachine::syncState stateService play May 04 14:39:50 volumio volumio[845]: info: CoreStateMachine::syncState currentStatus play May 04 14:39:50 volumio volumio[845]: info: Received an update from plugin. extracting info from payload May 04 14:39:50 volumio volumio[845]: info: CoreStateMachine::pushState May 04 14:39:50 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 14:39:50 volumio volumio[845]: info: CoreCommandRouter::volumioPushState May 04 14:39:50 volumio volumio[845]: info: CoreStateMachine::pushState May 04 14:39:50 volumio volumio[845]: info: CoreCommandRouter::volumioPushState May 04 14:39:50 volumio volumio[845]: info: ------------------------------ 17ms May 04 14:39:50 volumio volumio[845]: Upnp client error: Error: This socket has been ended by the other party May 04 14:39:50 volumio volumio[845]: info: May 04 14:39:50 volumio volumio[845]: ---------------------------- MPD announces system playlist update May 04 14:39:50 volumio volumio[845]: info: Ignoring MPD Status Update May 04 14:39:50 volumio volumio[845]: info: May 04 14:39:50 volumio volumio[845]: ---------------------------- MPD announces state update: player May 04 14:39:50 volumio volumio[845]: info: ControllerMpd::getState May 04 14:39:50 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand status May 04 14:39:50 volumio volumio[845]: info: ------------------------------ 1ms May 04 14:39:50 volumio volumio[845]: info: sendMpdCommand status took 1 milliseconds May 04 14:39:50 volumio volumio[845]: verbose: ControllerMpd::parseState May 04 14:39:50 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 14:39:50 volumio volumio[845]: info: sendMpdCommand playlistinfo took 0 milliseconds May 04 14:39:50 volumio volumio[845]: verbose: ControllerMpd::parseTrackInfo May 04 14:39:50 volumio volumio[845]: info: ControllerMpd::pushState May 04 14:39:50 volumio volumio[845]: info: CoreCommandRouter::servicePushState May 04 14:39:50 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 04 14:39:50 volumio volumio[845]: verbose: STATE SERVICE {"status":"play","position":4,"seek":1584,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"48 Kbps","isStreaming":false,"title":"Leading Britain's Conversation - Ali Miraj","artist":"LBC London","album":null,"uri":"http://media-ice.musicradio.com/LBCLondonMP3","trackType":""} May 04 14:39:50 volumio volumio[845]: verbose: CURRENT POSITION 0 May 04 14:39:50 volumio volumio[845]: info: CoreStateMachine::syncState stateService play May 04 14:39:50 volumio volumio[845]: info: CoreStateMachine::syncState currentStatus play May 04 14:39:50 volumio volumio[845]: info: Received an update from plugin. extracting info from payload May 04 14:39:50 volumio volumio[845]: info: CoreStateMachine::pushState May 04 14:39:50 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 14:39:50 volumio volumio[845]: info: CoreCommandRouter::volumioPushState May 04 14:39:50 volumio volumio[845]: info: CoreStateMachine::pushState May 04 14:39:50 volumio volumio[845]: info: CoreCommandRouter::volumioPushState May 04 14:39:50 volumio volumio[845]: info: ------------------------------ 8ms May 04 14:39:50 volumio volumio[845]: Upnp client error: Error: This socket has been ended by the other party May 04 14:39:51 volumio wpa_supplicant[754]: RRM: Ignoring radio measurement request: Not RRM network May 04 14:39:52 volumio volumio[845]: info: May 04 14:39:52 volumio volumio[845]: ---------------------------- MPD announces system playlist update May 04 14:39:52 volumio volumio[845]: info: Ignoring MPD Status Update May 04 14:39:52 volumio volumio[845]: info: May 04 14:39:52 volumio volumio[845]: ---------------------------- MPD announces state update: player May 04 14:39:52 volumio volumio[845]: info: ControllerMpd::getState May 04 14:39:52 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand status May 04 14:39:52 volumio volumio[845]: info: ------------------------------ 3ms May 04 14:39:52 volumio volumio[845]: info: sendMpdCommand status took 2 milliseconds May 04 14:39:52 volumio volumio[845]: verbose: ControllerMpd::parseState May 04 14:39:52 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 14:39:52 volumio volumio[845]: info: sendMpdCommand playlistinfo took 1 milliseconds May 04 14:39:52 volumio volumio[845]: verbose: ControllerMpd::parseTrackInfo May 04 14:39:52 volumio volumio[845]: info: ControllerMpd::pushState May 04 14:39:52 volumio volumio[845]: info: CoreCommandRouter::servicePushState May 04 14:39:52 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 04 14:39:52 volumio volumio[845]: verbose: STATE SERVICE {"status":"play","position":4,"seek":3589,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"48 Kbps","isStreaming":false,"title":"Leading Britain's Conversation - Ali Miraj","artist":"LBC London","album":null,"uri":"http://media-ice.musicradio.com/LBCLondonMP3","trackType":""} May 04 14:39:52 volumio volumio[845]: verbose: CURRENT POSITION 0 May 04 14:39:52 volumio volumio[845]: info: CoreStateMachine::syncState stateService play May 04 14:39:52 volumio volumio[845]: info: CoreStateMachine::syncState currentStatus play May 04 14:39:52 volumio volumio[845]: info: Received an update from plugin. extracting info from payload May 04 14:39:52 volumio volumio[845]: info: CoreStateMachine::pushState May 04 14:39:52 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 14:39:52 volumio volumio[845]: info: CoreCommandRouter::volumioPushState May 04 14:39:52 volumio volumio[845]: info: CoreStateMachine::pushState May 04 14:39:52 volumio volumio[845]: info: CoreCommandRouter::volumioPushState May 04 14:39:52 volumio volumio[845]: info: ------------------------------ 10ms May 04 14:39:52 volumio volumio[845]: Upnp client error: Error: This socket has been ended by the other party May 04 14:39:53 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 04 14:39:53 volumio volumio[845]: info: CURURI: music-library/NAS May 04 14:39:54 volumio volumio[845]: info: Preload queue cleared May 04 14:39:55 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 04 14:39:55 volumio volumio[845]: info: CURURI: music-library/NAS/NAS-Audio May 04 14:39:55 volumio volumio[845]: info: Preload queue cleared May 04 14:39:55 volumio volumio[845]: info: May 04 14:39:55 volumio volumio[845]: ---------------------------- MPD announces system playlist update May 04 14:39:55 volumio volumio[845]: info: Ignoring MPD Status Update May 04 14:39:55 volumio volumio[845]: info: May 04 14:39:55 volumio volumio[845]: ---------------------------- MPD announces state update: player May 04 14:39:55 volumio volumio[845]: info: ControllerMpd::getState May 04 14:39:55 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand status May 04 14:39:55 volumio volumio[845]: info: ------------------------------ 2ms May 04 14:39:55 volumio volumio[845]: info: sendMpdCommand status took 1 milliseconds May 04 14:39:55 volumio volumio[845]: verbose: ControllerMpd::parseState May 04 14:39:55 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 14:39:55 volumio volumio[845]: info: sendMpdCommand playlistinfo took 1 milliseconds May 04 14:39:55 volumio volumio[845]: verbose: ControllerMpd::parseTrackInfo May 04 14:39:55 volumio volumio[845]: info: ControllerMpd::pushState May 04 14:39:55 volumio volumio[845]: info: CoreCommandRouter::servicePushState May 04 14:39:55 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 04 14:39:55 volumio volumio[845]: verbose: STATE SERVICE {"status":"play","position":4,"seek":6839,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"48 Kbps","isStreaming":false,"title":"Leading Britain's Conversation - Ali Miraj","artist":"LBC London","album":null,"uri":"http://media-ice.musicradio.com/LBCLondonMP3","trackType":""} May 04 14:39:55 volumio volumio[845]: verbose: CURRENT POSITION 0 May 04 14:39:55 volumio volumio[845]: info: CoreStateMachine::syncState stateService play May 04 14:39:55 volumio volumio[845]: info: CoreStateMachine::syncState currentStatus play May 04 14:39:55 volumio volumio[845]: info: Received an update from plugin. extracting info from payload May 04 14:39:55 volumio volumio[845]: info: CoreStateMachine::pushState May 04 14:39:55 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 14:39:55 volumio volumio[845]: info: CoreCommandRouter::volumioPushState May 04 14:39:55 volumio volumio[845]: info: CoreStateMachine::pushState May 04 14:39:55 volumio volumio[845]: info: CoreCommandRouter::volumioPushState May 04 14:39:55 volumio volumio[845]: info: ------------------------------ 11ms May 04 14:39:57 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 04 14:39:57 volumio volumio[845]: info: CURURI: music-library/NAS/NAS-Audio/Test May 04 14:39:57 volumio volumio[845]: info: Preload queue cleared May 04 14:40:00 volumio volumio[845]: info: May 04 14:40:00 volumio volumio[845]: ---------------------------- MPD announces system playlist update May 04 14:40:00 volumio volumio[845]: info: Ignoring MPD Status Update May 04 14:40:00 volumio volumio[845]: info: May 04 14:40:00 volumio volumio[845]: ---------------------------- MPD announces state update: player May 04 14:40:00 volumio volumio[845]: info: ControllerMpd::getState May 04 14:40:00 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand status May 04 14:40:00 volumio volumio[845]: info: ------------------------------ 2ms May 04 14:40:00 volumio volumio[845]: info: sendMpdCommand status took 1 milliseconds May 04 14:40:00 volumio volumio[845]: verbose: ControllerMpd::parseState May 04 14:40:00 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 14:40:00 volumio volumio[845]: info: sendMpdCommand playlistinfo took 0 milliseconds May 04 14:40:00 volumio volumio[845]: verbose: ControllerMpd::parseTrackInfo May 04 14:40:00 volumio volumio[845]: info: ControllerMpd::pushState May 04 14:40:00 volumio volumio[845]: info: CoreCommandRouter::servicePushState May 04 14:40:00 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 04 14:40:00 volumio volumio[845]: verbose: STATE SERVICE {"status":"play","position":4,"seek":10843,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"48 Kbps","isStreaming":false,"title":"Leading Britain's Conversation - Ali Miraj","artist":"LBC London","album":null,"uri":"http://media-ice.musicradio.com/LBCLondonMP3","trackType":""} May 04 14:40:00 volumio volumio[845]: verbose: CURRENT POSITION 0 May 04 14:40:00 volumio volumio[845]: info: CoreStateMachine::syncState stateService play May 04 14:40:00 volumio volumio[845]: info: CoreStateMachine::syncState currentStatus play May 04 14:40:00 volumio volumio[845]: info: Received an update from plugin. extracting info from payload May 04 14:40:00 volumio volumio[845]: info: CoreStateMachine::pushState May 04 14:40:00 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 14:40:00 volumio volumio[845]: info: CoreCommandRouter::volumioPushState May 04 14:40:00 volumio volumio[845]: info: CoreStateMachine::pushState May 04 14:40:00 volumio volumio[845]: info: CoreCommandRouter::volumioPushState May 04 14:40:00 volumio volumio[845]: info: ------------------------------ 9ms May 04 14:40:03 volumio volumio[845]: info: May 04 14:40:03 volumio volumio[845]: ---------------------------- MPD announces system playlist update May 04 14:40:03 volumio volumio[845]: info: Ignoring MPD Status Update May 04 14:40:03 volumio volumio[845]: info: May 04 14:40:03 volumio volumio[845]: ---------------------------- MPD announces state update: player May 04 14:40:03 volumio volumio[845]: info: ControllerMpd::getState May 04 14:40:03 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand status May 04 14:40:03 volumio volumio[845]: info: ------------------------------ 2ms May 04 14:40:03 volumio volumio[845]: info: sendMpdCommand status took 1 milliseconds May 04 14:40:03 volumio volumio[845]: verbose: ControllerMpd::parseState May 04 14:40:03 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 14:40:03 volumio volumio[845]: info: sendMpdCommand playlistinfo took 1 milliseconds May 04 14:40:03 volumio volumio[845]: verbose: ControllerMpd::parseTrackInfo May 04 14:40:03 volumio volumio[845]: info: ControllerMpd::pushState May 04 14:40:03 volumio volumio[845]: info: CoreCommandRouter::servicePushState May 04 14:40:03 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 04 14:40:03 volumio volumio[845]: verbose: STATE SERVICE {"status":"play","position":4,"seek":13846,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"48 Kbps","isStreaming":false,"title":"Leading Britain's Conversation - Ali Miraj","artist":"LBC London","album":null,"uri":"http://media-ice.musicradio.com/LBCLondonMP3","trackType":""} May 04 14:40:03 volumio volumio[845]: verbose: CURRENT POSITION 0 May 04 14:40:03 volumio volumio[845]: info: CoreStateMachine::syncState stateService play May 04 14:40:03 volumio volumio[845]: info: CoreStateMachine::syncState currentStatus play May 04 14:40:03 volumio volumio[845]: info: Received an update from plugin. extracting info from payload May 04 14:40:03 volumio volumio[845]: info: CoreStateMachine::pushState May 04 14:40:03 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 14:40:03 volumio volumio[845]: info: CoreCommandRouter::volumioPushState May 04 14:40:03 volumio volumio[845]: info: CoreStateMachine::pushState May 04 14:40:03 volumio volumio[845]: info: CoreCommandRouter::volumioPushState May 04 14:40:03 volumio volumio[845]: info: ------------------------------ 11ms May 04 14:40:05 volumio volumio[845]: info: May 04 14:40:05 volumio volumio[845]: ---------------------------- MPD announces system playlist update May 04 14:40:05 volumio volumio[845]: info: Ignoring MPD Status Update May 04 14:40:05 volumio volumio[845]: info: May 04 14:40:05 volumio volumio[845]: ---------------------------- MPD announces state update: player May 04 14:40:05 volumio volumio[845]: info: ControllerMpd::getState May 04 14:40:05 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand status May 04 14:40:05 volumio volumio[845]: info: ------------------------------ 1ms May 04 14:40:05 volumio volumio[845]: info: sendMpdCommand status took 1 milliseconds May 04 14:40:05 volumio volumio[845]: verbose: ControllerMpd::parseState May 04 14:40:05 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 14:40:05 volumio volumio[845]: info: sendMpdCommand playlistinfo took 0 milliseconds May 04 14:40:05 volumio volumio[845]: verbose: ControllerMpd::parseTrackInfo May 04 14:40:05 volumio volumio[845]: info: ControllerMpd::pushState May 04 14:40:05 volumio volumio[845]: info: CoreCommandRouter::servicePushState May 04 14:40:05 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 04 14:40:05 volumio volumio[845]: verbose: STATE SERVICE {"status":"play","position":4,"seek":15971,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"48 Kbps","isStreaming":false,"title":"Leading Britain's Conversation - Ali Miraj","artist":"LBC London","album":null,"uri":"http://media-ice.musicradio.com/LBCLondonMP3","trackType":""} May 04 14:40:05 volumio volumio[845]: verbose: CURRENT POSITION 0 May 04 14:40:05 volumio volumio[845]: info: CoreStateMachine::syncState stateService play May 04 14:40:05 volumio volumio[845]: info: CoreStateMachine::syncState currentStatus play May 04 14:40:05 volumio volumio[845]: info: Received an update from plugin. extracting info from payload May 04 14:40:05 volumio volumio[845]: info: CoreStateMachine::pushState May 04 14:40:05 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 14:40:05 volumio volumio[845]: info: CoreCommandRouter::volumioPushState May 04 14:40:05 volumio volumio[845]: info: CoreStateMachine::pushState May 04 14:40:05 volumio volumio[845]: info: CoreCommandRouter::volumioPushState May 04 14:40:05 volumio volumio[845]: info: ------------------------------ 6ms May 04 14:40:07 volumio volumio[845]: info: May 04 14:40:07 volumio volumio[845]: ---------------------------- MPD announces system playlist update May 04 14:40:07 volumio volumio[845]: info: Ignoring MPD Status Update May 04 14:40:07 volumio volumio[845]: info: May 04 14:40:07 volumio volumio[845]: ---------------------------- MPD announces state update: player May 04 14:40:07 volumio volumio[845]: info: ControllerMpd::getState May 04 14:40:07 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand status May 04 14:40:07 volumio volumio[845]: info: ------------------------------ 3ms May 04 14:40:07 volumio volumio[845]: info: sendMpdCommand status took 2 milliseconds May 04 14:40:07 volumio volumio[845]: verbose: ControllerMpd::parseState May 04 14:40:07 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 14:40:07 volumio volumio[845]: info: sendMpdCommand playlistinfo took 0 milliseconds May 04 14:40:07 volumio volumio[845]: verbose: ControllerMpd::parseTrackInfo May 04 14:40:07 volumio volumio[845]: info: ControllerMpd::pushState May 04 14:40:07 volumio volumio[845]: info: CoreCommandRouter::servicePushState May 04 14:40:07 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 04 14:40:07 volumio volumio[845]: verbose: STATE SERVICE {"status":"play","position":4,"seek":18593,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"48 Kbps","isStreaming":false,"title":"Leading Britain's Conversation - Ali Miraj","artist":"LBC London","album":null,"uri":"http://media-ice.musicradio.com/LBCLondonMP3","trackType":""} May 04 14:40:07 volumio volumio[845]: verbose: CURRENT POSITION 0 May 04 14:40:07 volumio volumio[845]: info: CoreStateMachine::syncState stateService play May 04 14:40:07 volumio volumio[845]: info: CoreStateMachine::syncState currentStatus play May 04 14:40:07 volumio volumio[845]: info: Received an update from plugin. extracting info from payload May 04 14:40:07 volumio volumio[845]: info: CoreStateMachine::pushState May 04 14:40:07 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 14:40:07 volumio volumio[845]: info: CoreCommandRouter::volumioPushState May 04 14:40:07 volumio volumio[845]: info: CoreStateMachine::pushState May 04 14:40:07 volumio volumio[845]: info: CoreCommandRouter::volumioPushState May 04 14:40:07 volumio volumio[845]: info: ------------------------------ 8ms May 04 14:40:09 volumio volumio[845]: info: May 04 14:40:09 volumio volumio[845]: ---------------------------- MPD announces system playlist update May 04 14:40:09 volumio volumio[845]: info: Ignoring MPD Status Update May 04 14:40:09 volumio volumio[845]: info: May 04 14:40:09 volumio volumio[845]: ---------------------------- MPD announces state update: player May 04 14:40:09 volumio volumio[845]: info: ControllerMpd::getState May 04 14:40:09 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand status May 04 14:40:09 volumio volumio[845]: info: ------------------------------ 2ms May 04 14:40:09 volumio volumio[845]: info: sendMpdCommand status took 1 milliseconds May 04 14:40:09 volumio volumio[845]: verbose: ControllerMpd::parseState May 04 14:40:09 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 14:40:09 volumio volumio[845]: info: sendMpdCommand playlistinfo took 1 milliseconds May 04 14:40:09 volumio volumio[845]: verbose: ControllerMpd::parseTrackInfo May 04 14:40:09 volumio volumio[845]: info: ControllerMpd::pushState May 04 14:40:09 volumio volumio[845]: info: CoreCommandRouter::servicePushState May 04 14:40:09 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 04 14:40:09 volumio volumio[845]: verbose: STATE SERVICE {"status":"play","position":4,"seek":20349,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"48 Kbps","isStreaming":false,"title":"Leading Britain's Conversation - Ali Miraj","artist":"LBC London","album":null,"uri":"http://media-ice.musicradio.com/LBCLondonMP3","trackType":""} May 04 14:40:09 volumio volumio[845]: verbose: CURRENT POSITION 0 May 04 14:40:09 volumio volumio[845]: info: CoreStateMachine::syncState stateService play May 04 14:40:09 volumio volumio[845]: info: CoreStateMachine::syncState currentStatus play May 04 14:40:09 volumio volumio[845]: info: Received an update from plugin. extracting info from payload May 04 14:40:09 volumio volumio[845]: info: CoreStateMachine::pushState May 04 14:40:09 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 14:40:09 volumio volumio[845]: info: CoreCommandRouter::volumioPushState May 04 14:40:09 volumio volumio[845]: info: CoreStateMachine::pushState May 04 14:40:09 volumio volumio[845]: info: CoreCommandRouter::volumioPushState May 04 14:40:09 volumio volumio[845]: info: ------------------------------ 8ms May 04 14:40:09 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 04 14:40:09 volumio volumio[845]: info: CURURI: music-library/NAS/NAS-Audio/Test/Demo Disk May 04 14:40:09 volumio volumio[845]: info: Preload queue cleared May 04 14:40:09 volumio volumio[845]: info: Preloading song: music-library/NAS/NAS-Audio/Test/Demo Disk/18 Water Colors-Janis Ian.flac May 04 14:40:09 volumio volumio[845]: info: Preloading song: music-library/NAS/NAS-Audio/Test/Demo Disk/07 Rolling-Elysian Fields.flac May 04 14:40:09 volumio volumio[845]: info: Preloading song: music-library/NAS/NAS-Audio/Test/Demo Disk/02 Dilate-Ani DiFranco.flac May 04 14:40:09 volumio volumio[845]: info: Preloading song: music-library/NAS/NAS-Audio/Test/Demo Disk/21 You Can Watch Me.flac May 04 14:40:09 volumio volumio[845]: info: Preloading song: music-library/NAS/NAS-Audio/Test/Demo Disk/20 How Deep It Goes- Heart (DCC).flac May 04 14:40:09 volumio volumio[845]: info: Preloading song: music-library/NAS/NAS-Audio/Test/Demo Disk/15 Wandering Star-Portishead.flac May 04 14:40:09 volumio volumio[845]: info: Preloading song: music-library/NAS/NAS-Audio/Test/Demo Disk/04 Ice-Sarah McLachlan.flac May 04 14:40:09 volumio volumio[845]: info: Preloading song: music-library/NAS/NAS-Audio/Test/Demo Disk/03 Night Train-Christian McBride.flac May 04 14:40:09 volumio volumio[845]: info: Preloading song: music-library/NAS/NAS-Audio/Test/Demo Disk/11 I Honestly Love You (Remix)-ONJ.flac May 04 14:40:09 volumio volumio[845]: info: Preloading song: music-library/NAS/NAS-Audio/Test/Demo Disk/16 She's Already Made Up Her Mind.flac May 04 14:40:09 volumio volumio[845]: info: Preloading song: music-library/NAS/NAS-Audio/Test/Demo Disk/01 Every Morning-Keb' Mo'.flac May 04 14:40:09 volumio volumio[845]: info: Preloading song: music-library/NAS/NAS-Audio/Test/Demo Disk/06 Potter's Wheel-Freyda and Acoustic.flac May 04 14:40:09 volumio volumio[845]: info: Preloading song: music-library/NAS/NAS-Audio/Test/Demo Disk/10 Oh Me-Nirvana.flac May 04 14:40:09 volumio volumio[845]: info: Preloading song: music-library/NAS/NAS-Audio/Test/Demo Disk/13 Breakin' Up-The Violent Femmes.flac May 04 14:40:09 volumio volumio[845]: info: Preloading song: music-library/NAS/NAS-Audio/Test/Demo Disk/09 Sleepy Language-Layo and Bushwacka.flac May 04 14:40:09 volumio volumio[845]: info: Preloading song: music-library/NAS/NAS-Audio/Test/Demo Disk/08 Sweet Potato-John Renbourne.flac May 04 14:40:09 volumio volumio[845]: info: Preloading song: music-library/NAS/NAS-Audio/Test/Demo Disk/19 Sleepy Little Sailor-Oh Susannah.flac May 04 14:40:09 volumio volumio[845]: info: Preloading song: music-library/NAS/NAS-Audio/Test/Demo Disk/14 Dawg After Dark-David Grisman and Tony Rice.flac May 04 14:40:09 volumio volumio[845]: info: Preloading song: music-library/NAS/NAS-Audio/Test/Demo Disk/05 Moog Island-Morcheeba.flac May 04 14:40:09 volumio volumio[845]: info: Preloading song: music-library/NAS/NAS-Audio/Test/Demo Disk/12 Youth Oriented-Happy Apple.flac May 04 14:40:09 volumio volumio[845]: info: Exploding uri music-library/NAS/NAS-Audio/Test/Demo Disk/18 Water Colors-Janis Ian.flac in service mpd May 04 14:40:09 volumio volumio[845]: info: ALBUMART /albumart?cacheid=457&web=Janis%20Ian/Between%20The%20Lines/extralarge&path=%2Fmnt%2FNAS%2FNAS-Audio%2FTest%2FDemo%20Disk%2F18%20Water%20Colors-Janis%20Ian.flac&metadata=false May 04 14:40:09 volumio volumio[845]: info: URI /mnt/NAS/NAS-Audio/Test/Demo Disk/18 Water Colors-Janis Ian.flac May 04 14:40:09 volumio volumio[845]: info: Exploding uri music-library/NAS/NAS-Audio/Test/Demo Disk/07 Rolling-Elysian Fields.flac in service mpd May 04 14:40:09 volumio volumio[845]: info: ALBUMART /albumart?cacheid=457&web=Elysian%20Fields/Bleed%20Your%20Cedar/extralarge&path=%2Fmnt%2FNAS%2FNAS-Audio%2FTest%2FDemo%20Disk%2F07%20Rolling-Elysian%20Fields.flac&metadata=false May 04 14:40:09 volumio volumio[845]: info: URI /mnt/NAS/NAS-Audio/Test/Demo Disk/07 Rolling-Elysian Fields.flac May 04 14:40:09 volumio volumio[845]: info: Exploding uri music-library/NAS/NAS-Audio/Test/Demo Disk/02 Dilate-Ani DiFranco.flac in service mpd May 04 14:40:09 volumio volumio[845]: info: ALBUMART /albumart?cacheid=457&web=Ani%20DiFranco/Dilate/extralarge&path=%2Fmnt%2FNAS%2FNAS-Audio%2FTest%2FDemo%20Disk%2F02%20Dilate-Ani%20DiFranco.flac&metadata=false May 04 14:40:09 volumio volumio[845]: info: URI /mnt/NAS/NAS-Audio/Test/Demo Disk/02 Dilate-Ani DiFranco.flac May 04 14:40:09 volumio volumio[845]: info: Exploding uri music-library/NAS/NAS-Audio/Test/Demo Disk/21 You Can Watch Me.flac in service mpd May 04 14:40:09 volumio volumio[845]: info: ALBUMART /albumart?cacheid=457&web=Greg%20Brown/Dream%20Caf%C3%A9/extralarge&path=%2Fmnt%2FNAS%2FNAS-Audio%2FTest%2FDemo%20Disk%2F21%20%20You%20Can%20Watch%20Me.flac&metadata=false May 04 14:40:09 volumio volumio[845]: info: URI /mnt/NAS/NAS-Audio/Test/Demo Disk/21 You Can Watch Me.flac May 04 14:40:10 volumio volumio[845]: info: Exploding uri music-library/NAS/NAS-Audio/Test/Demo Disk/20 How Deep It Goes- Heart (DCC).flac in service mpd May 04 14:40:10 volumio volumio[845]: info: ALBUMART /albumart?cacheid=457&web=Heart/Dreamboat%20Annie%20(DCC)/extralarge&path=%2Fmnt%2FNAS%2FNAS-Audio%2FTest%2FDemo%20Disk%2F20%20How%20Deep%20It%20Goes-%20Heart%20(DCC).flac&metadata=false May 04 14:40:10 volumio volumio[845]: info: URI /mnt/NAS/NAS-Audio/Test/Demo Disk/20 How Deep It Goes- Heart (DCC).flac May 04 14:40:10 volumio volumio[845]: info: Exploding uri music-library/NAS/NAS-Audio/Test/Demo Disk/15 Wandering Star-Portishead.flac in service mpd May 04 14:40:10 volumio volumio[845]: info: ALBUMART /albumart?cacheid=457&web=Portishead/Dummy/extralarge&path=%2Fmnt%2FNAS%2FNAS-Audio%2FTest%2FDemo%20Disk%2F15%20Wandering%20Star-Portishead.flac&metadata=false May 04 14:40:10 volumio volumio[845]: info: URI /mnt/NAS/NAS-Audio/Test/Demo Disk/15 Wandering Star-Portishead.flac May 04 14:40:10 volumio volumio[845]: info: Exploding uri music-library/NAS/NAS-Audio/Test/Demo Disk/04 Ice-Sarah McLachlan.flac in service mpd May 04 14:40:10 volumio volumio[845]: info: ALBUMART /albumart?cacheid=457&web=Sarah%20McLachlan/Fumbling%20Towards%20Ecstasy/extralarge&path=%2Fmnt%2FNAS%2FNAS-Audio%2FTest%2FDemo%20Disk%2F04%20Ice-Sarah%20McLachlan.flac&metadata=false May 04 14:40:10 volumio volumio[845]: info: URI /mnt/NAS/NAS-Audio/Test/Demo Disk/04 Ice-Sarah McLachlan.flac May 04 14:40:10 volumio volumio[845]: info: Exploding uri music-library/NAS/NAS-Audio/Test/Demo Disk/03 Night Train-Christian McBride.flac in service mpd May 04 14:40:10 volumio volumio[845]: info: ALBUMART /albumart?cacheid=457&web=Christian%20McBride/Gettin'%20To%20It/extralarge&path=%2Fmnt%2FNAS%2FNAS-Audio%2FTest%2FDemo%20Disk%2F03%20Night%20Train-Christian%20McBride.flac&metadata=false May 04 14:40:10 volumio volumio[845]: info: URI /mnt/NAS/NAS-Audio/Test/Demo Disk/03 Night Train-Christian McBride.flac May 04 14:40:10 volumio volumio[845]: info: Exploding uri music-library/NAS/NAS-Audio/Test/Demo Disk/11 I Honestly Love You (Remix)-ONJ.flac in service mpd May 04 14:40:10 volumio volumio[845]: info: ALBUMART /albumart?cacheid=457&web=Olivia%20Newton-John/I%20Honestly%20Love%20You/extralarge&path=%2Fmnt%2FNAS%2FNAS-Audio%2FTest%2FDemo%20Disk%2F11%20I%20Honestly%20Love%20You%20(Remix)-ONJ.flac&metadata=false May 04 14:40:10 volumio volumio[845]: info: URI /mnt/NAS/NAS-Audio/Test/Demo Disk/11 I Honestly Love You (Remix)-ONJ.flac May 04 14:40:10 volumio volumio[845]: info: Exploding uri music-library/NAS/NAS-Audio/Test/Demo Disk/16 She's Already Made Up Her Mind.flac in service mpd May 04 14:40:10 volumio volumio[845]: info: ALBUMART /albumart?cacheid=457&web=Lyle%20Lovett/Joshua%20Judges%20Ruth/extralarge&path=%2Fmnt%2FNAS%2FNAS-Audio%2FTest%2FDemo%20Disk%2F16%20She's%20Already%20Made%20Up%20Her%20Mind.flac&metadata=false May 04 14:40:10 volumio volumio[845]: info: URI /mnt/NAS/NAS-Audio/Test/Demo Disk/16 She's Already Made Up Her Mind.flac May 04 14:40:10 volumio volumio[845]: info: Exploding uri music-library/NAS/NAS-Audio/Test/Demo Disk/01 Every Morning-Keb' Mo'.flac in service mpd May 04 14:40:10 volumio volumio[845]: info: ALBUMART /albumart?cacheid=457&web=Keb'%20Mo'/Keb'%20Mo'%20%7Bmfsl%7D/extralarge&path=%2Fmnt%2FNAS%2FNAS-Audio%2FTest%2FDemo%20Disk%2F01%20Every%20Morning-Keb'%20Mo'.flac&metadata=false May 04 14:40:10 volumio volumio[845]: info: URI /mnt/NAS/NAS-Audio/Test/Demo Disk/01 Every Morning-Keb' Mo'.flac May 04 14:40:10 volumio volumio[845]: info: Exploding uri music-library/NAS/NAS-Audio/Test/Demo Disk/06 Potter's Wheel-Freyda and Acoustic.flac in service mpd May 04 14:40:10 volumio volumio[845]: info: ALBUMART /albumart?cacheid=457&web=Freyda%20%26%20Acoustic%20AttaTude/Midnight%20At%20Cabell%20Hall/extralarge&path=%2Fmnt%2FNAS%2FNAS-Audio%2FTest%2FDemo%20Disk%2F06%20Potter's%20Wheel-Freyda%20and%20Acoustic.flac&metadata=false May 04 14:40:10 volumio volumio[845]: info: URI /mnt/NAS/NAS-Audio/Test/Demo Disk/06 Potter's Wheel-Freyda and Acoustic.flac May 04 14:40:10 volumio volumio[845]: info: Exploding uri music-library/NAS/NAS-Audio/Test/Demo Disk/10 Oh Me-Nirvana.flac in service mpd May 04 14:40:10 volumio volumio[845]: info: ALBUMART /albumart?cacheid=457&web=Nirvana/MTV%20Unplugged%20In%20New%20York/extralarge&path=%2Fmnt%2FNAS%2FNAS-Audio%2FTest%2FDemo%20Disk%2F10%20Oh%20Me-Nirvana.flac&metadata=false May 04 14:40:10 volumio volumio[845]: info: URI /mnt/NAS/NAS-Audio/Test/Demo Disk/10 Oh Me-Nirvana.flac May 04 14:40:10 volumio volumio[845]: info: Exploding uri music-library/NAS/NAS-Audio/Test/Demo Disk/13 Breakin' Up-The Violent Femmes.flac in service mpd May 04 14:40:10 volumio volumio[845]: info: ALBUMART /albumart?cacheid=457&web=Violent%20Femmes/New%20Times/extralarge&path=%2Fmnt%2FNAS%2FNAS-Audio%2FTest%2FDemo%20Disk%2F13%20Breakin'%20Up-The%20Violent%20Femmes.flac&metadata=false May 04 14:40:10 volumio volumio[845]: info: URI /mnt/NAS/NAS-Audio/Test/Demo Disk/13 Breakin' Up-The Violent Femmes.flac May 04 14:40:10 volumio volumio[845]: info: Exploding uri music-library/NAS/NAS-Audio/Test/Demo Disk/09 Sleepy Language-Layo and Bushwacka.flac in service mpd May 04 14:40:10 volumio volumio[845]: info: ALBUMART /albumart?cacheid=457&web=Layo%20%26%20Bushwacka!/Night%20Works/extralarge&path=%2Fmnt%2FNAS%2FNAS-Audio%2FTest%2FDemo%20Disk%2F09%20Sleepy%20Language-Layo%20and%20Bushwacka.flac&metadata=false May 04 14:40:10 volumio volumio[845]: info: URI /mnt/NAS/NAS-Audio/Test/Demo Disk/09 Sleepy Language-Layo and Bushwacka.flac May 04 14:40:10 volumio volumio[845]: info: Exploding uri music-library/NAS/NAS-Audio/Test/Demo Disk/08 Sweet Potato-John Renbourne.flac in service mpd May 04 14:40:10 volumio volumio[845]: info: ALBUMART /albumart?cacheid=457&web=John%20Renbourn/Sir%20John%20Alot/extralarge&path=%2Fmnt%2FNAS%2FNAS-Audio%2FTest%2FDemo%20Disk%2F08%20Sweet%20Potato-John%20Renbourne.flac&metadata=false May 04 14:40:10 volumio volumio[845]: info: URI /mnt/NAS/NAS-Audio/Test/Demo Disk/08 Sweet Potato-John Renbourne.flac May 04 14:40:10 volumio volumio[845]: info: Exploding uri music-library/NAS/NAS-Audio/Test/Demo Disk/19 Sleepy Little Sailor-Oh Susannah.flac in service mpd May 04 14:40:10 volumio volumio[845]: info: ALBUMART /albumart?cacheid=457&web=Oh%20Susanna/Sleepy%20Little%20Sailor/extralarge&path=%2Fmnt%2FNAS%2FNAS-Audio%2FTest%2FDemo%20Disk%2F19%20Sleepy%20Little%20Sailor-Oh%20Susannah.flac&metadata=false May 04 14:40:10 volumio volumio[845]: info: URI /mnt/NAS/NAS-Audio/Test/Demo Disk/19 Sleepy Little Sailor-Oh Susannah.flac May 04 14:40:10 volumio volumio[845]: info: Exploding uri music-library/NAS/NAS-Audio/Test/Demo Disk/14 Dawg After Dark-David Grisman and Tony Rice.flac in service mpd May 04 14:40:10 volumio volumio[845]: info: ALBUMART /albumart?cacheid=457&web=Tony%20Rice/Tone%20Poems/extralarge&path=%2Fmnt%2FNAS%2FNAS-Audio%2FTest%2FDemo%20Disk%2F14%20Dawg%20After%20Dark-David%20Grisman%20and%20Tony%20Rice.flac&metadata=false May 04 14:40:10 volumio volumio[845]: info: URI /mnt/NAS/NAS-Audio/Test/Demo Disk/14 Dawg After Dark-David Grisman and Tony Rice.flac May 04 14:40:10 volumio volumio[845]: info: Exploding uri music-library/NAS/NAS-Audio/Test/Demo Disk/05 Moog Island-Morcheeba.flac in service mpd May 04 14:40:10 volumio volumio[845]: info: ALBUMART /albumart?cacheid=457&web=Morcheeba/Who%20Can%20You%20Trust%3F/extralarge&path=%2Fmnt%2FNAS%2FNAS-Audio%2FTest%2FDemo%20Disk%2F05%20Moog%20Island-Morcheeba.flac&metadata=false May 04 14:40:10 volumio volumio[845]: info: URI /mnt/NAS/NAS-Audio/Test/Demo Disk/05 Moog Island-Morcheeba.flac May 04 14:40:10 volumio volumio[845]: info: Exploding uri music-library/NAS/NAS-Audio/Test/Demo Disk/12 Youth Oriented-Happy Apple.flac in service mpd May 04 14:40:10 volumio volumio[845]: info: ALBUMART /albumart?cacheid=457&web=Happy%20Apple/Youth%20Oriented/extralarge&path=%2Fmnt%2FNAS%2FNAS-Audio%2FTest%2FDemo%20Disk%2F12%20Youth%20Oriented-Happy%20Apple.flac&metadata=false May 04 14:40:10 volumio volumio[845]: info: URI /mnt/NAS/NAS-Audio/Test/Demo Disk/12 Youth Oriented-Happy Apple.flac May 04 14:40:10 volumio volumio[845]: info: May 04 14:40:10 volumio volumio[845]: ---------------------------- MPD announces system playlist update May 04 14:40:10 volumio volumio[845]: info: Ignoring MPD Status Update May 04 14:40:10 volumio volumio[845]: info: May 04 14:40:10 volumio volumio[845]: ---------------------------- MPD announces state update: player May 04 14:40:10 volumio volumio[845]: info: ControllerMpd::getState May 04 14:40:10 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand status May 04 14:40:10 volumio volumio[845]: info: ------------------------------ 2ms May 04 14:40:10 volumio volumio[845]: info: sendMpdCommand status took 1 milliseconds May 04 14:40:10 volumio volumio[845]: verbose: ControllerMpd::parseState May 04 14:40:10 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 14:40:10 volumio volumio[845]: info: sendMpdCommand playlistinfo took 1 milliseconds May 04 14:40:10 volumio volumio[845]: verbose: ControllerMpd::parseTrackInfo May 04 14:40:10 volumio volumio[845]: info: ControllerMpd::pushState May 04 14:40:10 volumio volumio[845]: info: CoreCommandRouter::servicePushState May 04 14:40:10 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 04 14:40:10 volumio volumio[845]: verbose: STATE SERVICE {"status":"play","position":4,"seek":21848,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"48 Kbps","isStreaming":false,"title":"Leading Britain's Conversation - Ali Miraj","artist":"LBC London","album":null,"uri":"http://media-ice.musicradio.com/LBCLondonMP3","trackType":""} May 04 14:40:10 volumio volumio[845]: verbose: CURRENT POSITION 0 May 04 14:40:10 volumio volumio[845]: info: CoreStateMachine::syncState stateService play May 04 14:40:10 volumio volumio[845]: info: CoreStateMachine::syncState currentStatus play May 04 14:40:10 volumio volumio[845]: info: Received an update from plugin. extracting info from payload May 04 14:40:10 volumio volumio[845]: info: CoreStateMachine::pushState May 04 14:40:10 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 14:40:10 volumio volumio[845]: info: CoreCommandRouter::volumioPushState May 04 14:40:10 volumio volumio[845]: info: CoreStateMachine::pushState May 04 14:40:10 volumio volumio[845]: info: CoreCommandRouter::volumioPushState May 04 14:40:10 volumio volumio[845]: info: ------------------------------ 7ms May 04 14:40:13 volumio volumio[845]: info: May 04 14:40:13 volumio volumio[845]: ---------------------------- MPD announces system playlist update May 04 14:40:13 volumio volumio[845]: info: Ignoring MPD Status Update May 04 14:40:13 volumio volumio[845]: info: May 04 14:40:13 volumio volumio[845]: ---------------------------- MPD announces state update: player May 04 14:40:13 volumio volumio[845]: info: ControllerMpd::getState May 04 14:40:13 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand status May 04 14:40:13 volumio volumio[845]: info: ------------------------------ 2ms May 04 14:40:13 volumio volumio[845]: info: sendMpdCommand status took 1 milliseconds May 04 14:40:13 volumio volumio[845]: verbose: ControllerMpd::parseState May 04 14:40:13 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 14:40:13 volumio volumio[845]: info: sendMpdCommand playlistinfo took 0 milliseconds May 04 14:40:13 volumio volumio[845]: verbose: ControllerMpd::parseTrackInfo May 04 14:40:13 volumio volumio[845]: info: ControllerMpd::pushState May 04 14:40:13 volumio volumio[845]: info: CoreCommandRouter::servicePushState May 04 14:40:13 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 04 14:40:13 volumio volumio[845]: verbose: STATE SERVICE {"status":"play","position":4,"seek":24099,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"48 Kbps","isStreaming":false,"title":"Leading Britain's Conversation - Ali Miraj","artist":"LBC London","album":null,"uri":"http://media-ice.musicradio.com/LBCLondonMP3","trackType":""} May 04 14:40:13 volumio volumio[845]: verbose: CURRENT POSITION 0 May 04 14:40:13 volumio volumio[845]: info: CoreStateMachine::syncState stateService play May 04 14:40:13 volumio volumio[845]: info: CoreStateMachine::syncState currentStatus play May 04 14:40:13 volumio volumio[845]: info: Received an update from plugin. extracting info from payload May 04 14:40:13 volumio volumio[845]: info: CoreStateMachine::pushState May 04 14:40:13 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 14:40:13 volumio volumio[845]: info: CoreCommandRouter::volumioPushState May 04 14:40:13 volumio volumio[845]: info: CoreStateMachine::pushState May 04 14:40:13 volumio volumio[845]: info: CoreCommandRouter::volumioPushState May 04 14:40:13 volumio volumio[845]: info: ------------------------------ 8ms May 04 14:40:16 volumio volumio[845]: info: May 04 14:40:16 volumio volumio[845]: ---------------------------- MPD announces system playlist update May 04 14:40:16 volumio volumio[845]: info: Ignoring MPD Status Update May 04 14:40:16 volumio volumio[845]: info: May 04 14:40:16 volumio volumio[845]: ---------------------------- MPD announces state update: player May 04 14:40:16 volumio volumio[845]: info: ControllerMpd::getState May 04 14:40:16 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand status May 04 14:40:16 volumio volumio[845]: info: ------------------------------ 2ms May 04 14:40:16 volumio volumio[845]: info: sendMpdCommand status took 1 milliseconds May 04 14:40:16 volumio volumio[845]: verbose: ControllerMpd::parseState May 04 14:40:16 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 14:40:16 volumio volumio[845]: info: sendMpdCommand playlistinfo took 0 milliseconds May 04 14:40:16 volumio volumio[845]: verbose: ControllerMpd::parseTrackInfo May 04 14:40:16 volumio volumio[845]: info: ControllerMpd::pushState May 04 14:40:16 volumio volumio[845]: info: CoreCommandRouter::servicePushState May 04 14:40:16 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 04 14:40:16 volumio volumio[845]: verbose: STATE SERVICE {"status":"play","position":4,"seek":27103,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"48 Kbps","isStreaming":false,"title":"Leading Britain's Conversation - Ali Miraj","artist":"LBC London","album":null,"uri":"http://media-ice.musicradio.com/LBCLondonMP3","trackType":""} May 04 14:40:16 volumio volumio[845]: verbose: CURRENT POSITION 0 May 04 14:40:16 volumio volumio[845]: info: CoreStateMachine::syncState stateService play May 04 14:40:16 volumio volumio[845]: info: CoreStateMachine::syncState currentStatus play May 04 14:40:16 volumio volumio[845]: info: Received an update from plugin. extracting info from payload May 04 14:40:16 volumio volumio[845]: info: CoreStateMachine::pushState May 04 14:40:16 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 14:40:16 volumio volumio[845]: info: CoreCommandRouter::volumioPushState May 04 14:40:16 volumio volumio[845]: info: CoreStateMachine::pushState May 04 14:40:16 volumio volumio[845]: info: CoreCommandRouter::volumioPushState May 04 14:40:16 volumio volumio[845]: info: ------------------------------ 10ms May 04 14:40:17 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 04 14:40:17 volumio volumio[845]: info: CURURI: music-library/NAS/NAS-Audio/Test/High-End Test CD May 04 14:40:18 volumio volumio[845]: info: May 04 14:40:18 volumio volumio[845]: ---------------------------- MPD announces system playlist update May 04 14:40:18 volumio volumio[845]: info: Ignoring MPD Status Update May 04 14:40:18 volumio volumio[845]: info: May 04 14:40:18 volumio volumio[845]: ---------------------------- MPD announces state update: player May 04 14:40:18 volumio volumio[845]: info: ControllerMpd::getState May 04 14:40:18 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand status May 04 14:40:18 volumio volumio[845]: info: ------------------------------ 3ms May 04 14:40:18 volumio volumio[845]: info: sendMpdCommand status took 2 milliseconds May 04 14:40:18 volumio volumio[845]: verbose: ControllerMpd::parseState May 04 14:40:18 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 14:40:18 volumio volumio[845]: info: sendMpdCommand playlistinfo took 1 milliseconds May 04 14:40:18 volumio volumio[845]: verbose: ControllerMpd::parseTrackInfo May 04 14:40:18 volumio volumio[845]: info: ControllerMpd::pushState May 04 14:40:18 volumio volumio[845]: info: CoreCommandRouter::servicePushState May 04 14:40:18 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 04 14:40:18 volumio volumio[845]: verbose: STATE SERVICE {"status":"play","position":4,"seek":28833,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"48 Kbps","isStreaming":false,"title":"Leading Britain's Conversation - Ali Miraj","artist":"LBC London","album":null,"uri":"http://media-ice.musicradio.com/LBCLondonMP3","trackType":""} May 04 14:40:18 volumio volumio[845]: verbose: CURRENT POSITION 0 May 04 14:40:18 volumio volumio[845]: info: CoreStateMachine::syncState stateService play May 04 14:40:18 volumio volumio[845]: info: CoreStateMachine::syncState currentStatus play May 04 14:40:18 volumio volumio[845]: info: Received an update from plugin. extracting info from payload May 04 14:40:18 volumio volumio[845]: info: CoreStateMachine::pushState May 04 14:40:18 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 14:40:18 volumio volumio[845]: info: CoreCommandRouter::volumioPushState May 04 14:40:18 volumio volumio[845]: info: CoreStateMachine::pushState May 04 14:40:18 volumio volumio[845]: info: CoreCommandRouter::volumioPushState May 04 14:40:18 volumio volumio[845]: info: ------------------------------ 20ms May 04 14:40:18 volumio volumio[845]: info: Preload queue cleared May 04 14:40:20 volumio volumio[845]: info: May 04 14:40:20 volumio volumio[845]: ---------------------------- MPD announces system playlist update May 04 14:40:20 volumio volumio[845]: info: Ignoring MPD Status Update May 04 14:40:20 volumio volumio[845]: info: May 04 14:40:20 volumio volumio[845]: ---------------------------- MPD announces state update: player May 04 14:40:20 volumio volumio[845]: info: ControllerMpd::getState May 04 14:40:20 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand status May 04 14:40:20 volumio volumio[845]: info: ------------------------------ 4ms May 04 14:40:20 volumio volumio[845]: info: sendMpdCommand status took 1 milliseconds May 04 14:40:20 volumio volumio[845]: verbose: ControllerMpd::parseState May 04 14:40:20 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 14:40:20 volumio volumio[845]: info: sendMpdCommand playlistinfo took 1 milliseconds May 04 14:40:20 volumio volumio[845]: verbose: ControllerMpd::parseTrackInfo May 04 14:40:20 volumio volumio[845]: info: ControllerMpd::pushState May 04 14:40:20 volumio volumio[845]: info: CoreCommandRouter::servicePushState May 04 14:40:20 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 04 14:40:20 volumio volumio[845]: verbose: STATE SERVICE {"status":"play","position":4,"seek":31211,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"48 Kbps","isStreaming":false,"title":"Leading Britain's Conversation - Ali Miraj","artist":"LBC London","album":null,"uri":"http://media-ice.musicradio.com/LBCLondonMP3","trackType":""} May 04 14:40:20 volumio volumio[845]: verbose: CURRENT POSITION 0 May 04 14:40:20 volumio volumio[845]: info: CoreStateMachine::syncState stateService play May 04 14:40:20 volumio volumio[845]: info: CoreStateMachine::syncState currentStatus play May 04 14:40:20 volumio volumio[845]: info: Received an update from plugin. extracting info from payload May 04 14:40:20 volumio volumio[845]: info: CoreStateMachine::pushState May 04 14:40:20 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 14:40:20 volumio volumio[845]: info: CoreCommandRouter::volumioPushState May 04 14:40:20 volumio volumio[845]: info: CoreStateMachine::pushState May 04 14:40:20 volumio volumio[845]: info: CoreCommandRouter::volumioPushState May 04 14:40:20 volumio volumio[845]: info: ------------------------------ 11ms May 04 14:40:22 volumio volumio[845]: info: May 04 14:40:22 volumio volumio[845]: ---------------------------- MPD announces system playlist update May 04 14:40:22 volumio volumio[845]: info: Ignoring MPD Status Update May 04 14:40:22 volumio volumio[845]: info: May 04 14:40:22 volumio volumio[845]: ---------------------------- MPD announces state update: player May 04 14:40:22 volumio volumio[845]: info: ControllerMpd::getState May 04 14:40:22 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand status May 04 14:40:22 volumio volumio[845]: info: ------------------------------ 1ms May 04 14:40:22 volumio volumio[845]: info: sendMpdCommand status took 1 milliseconds May 04 14:40:22 volumio volumio[845]: verbose: ControllerMpd::parseState May 04 14:40:22 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 14:40:22 volumio volumio[845]: info: sendMpdCommand playlistinfo took 0 milliseconds May 04 14:40:22 volumio volumio[845]: verbose: ControllerMpd::parseTrackInfo May 04 14:40:22 volumio volumio[845]: info: ControllerMpd::pushState May 04 14:40:22 volumio volumio[845]: info: CoreCommandRouter::servicePushState May 04 14:40:22 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 04 14:40:22 volumio volumio[845]: verbose: STATE SERVICE {"status":"play","position":4,"seek":33085,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"48 Kbps","isStreaming":false,"title":"Leading Britain's Conversation - Ali Miraj","artist":"LBC London","album":null,"uri":"http://media-ice.musicradio.com/LBCLondonMP3","trackType":""} May 04 14:40:22 volumio volumio[845]: verbose: CURRENT POSITION 0 May 04 14:40:22 volumio volumio[845]: info: CoreStateMachine::syncState stateService play May 04 14:40:22 volumio volumio[845]: info: CoreStateMachine::syncState currentStatus play May 04 14:40:22 volumio volumio[845]: info: Received an update from plugin. extracting info from payload May 04 14:40:22 volumio volumio[845]: info: CoreStateMachine::pushState May 04 14:40:22 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 14:40:22 volumio volumio[845]: info: CoreCommandRouter::volumioPushState May 04 14:40:22 volumio volumio[845]: info: CoreStateMachine::pushState May 04 14:40:22 volumio volumio[845]: info: CoreCommandRouter::volumioPushState May 04 14:40:22 volumio volumio[845]: info: ------------------------------ 7ms May 04 14:40:23 volumio volumio[845]: info: May 04 14:40:23 volumio volumio[845]: ---------------------------- MPD announces system playlist update May 04 14:40:23 volumio volumio[845]: info: Ignoring MPD Status Update May 04 14:40:23 volumio volumio[845]: info: May 04 14:40:23 volumio volumio[845]: ---------------------------- MPD announces state update: player May 04 14:40:23 volumio volumio[845]: info: ControllerMpd::getState May 04 14:40:23 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand status May 04 14:40:23 volumio volumio[845]: info: ------------------------------ 3ms May 04 14:40:23 volumio volumio[845]: info: sendMpdCommand status took 1 milliseconds May 04 14:40:23 volumio volumio[845]: verbose: ControllerMpd::parseState May 04 14:40:23 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 14:40:23 volumio volumio[845]: info: sendMpdCommand playlistinfo took 0 milliseconds May 04 14:40:23 volumio volumio[845]: verbose: ControllerMpd::parseTrackInfo May 04 14:40:23 volumio volumio[845]: info: ControllerMpd::pushState May 04 14:40:23 volumio volumio[845]: info: CoreCommandRouter::servicePushState May 04 14:40:23 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 04 14:40:23 volumio volumio[845]: verbose: STATE SERVICE {"status":"play","position":4,"seek":34220,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"48 Kbps","isStreaming":false,"title":"Leading Britain's Conversation - Ali Miraj","artist":"LBC London","album":null,"uri":"http://media-ice.musicradio.com/LBCLondonMP3","trackType":""} May 04 14:40:23 volumio volumio[845]: verbose: CURRENT POSITION 0 May 04 14:40:23 volumio volumio[845]: info: CoreStateMachine::syncState stateService play May 04 14:40:23 volumio volumio[845]: info: CoreStateMachine::syncState currentStatus play May 04 14:40:23 volumio volumio[845]: info: Received an update from plugin. extracting info from payload May 04 14:40:23 volumio volumio[845]: info: CoreStateMachine::pushState May 04 14:40:23 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 14:40:23 volumio volumio[845]: info: CoreCommandRouter::volumioPushState May 04 14:40:23 volumio volumio[845]: info: CoreStateMachine::pushState May 04 14:40:23 volumio volumio[845]: info: CoreCommandRouter::volumioPushState May 04 14:40:23 volumio volumio[845]: info: ------------------------------ 12ms May 04 14:40:24 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 04 14:40:24 volumio volumio[845]: info: CURURI: music-library/NAS/NAS-Audio/Test/SACD Compilation May 04 14:40:24 volumio volumio[845]: info: Preload queue cleared May 04 14:40:24 volumio volumio[845]: info: Preloading song: music-library/NAS/NAS-Audio/Test/SACD Compilation/10 - Eric Clapton - I Shot The Sheriff.dsf May 04 14:40:24 volumio volumio[845]: info: Preloading song: music-library/NAS/NAS-Audio/Test/SACD Compilation/03 - Asia - Heat Of The Moment.dsf May 04 14:40:24 volumio volumio[845]: info: Preloading song: music-library/NAS/NAS-Audio/Test/SACD Compilation/11 - Dire Straits (2013 MFSL) - Money For Nothing.dsf May 04 14:40:24 volumio volumio[845]: info: Preloading song: music-library/NAS/NAS-Audio/Test/SACD Compilation/04 - Derek And The Dominos - Layla.dsf May 04 14:40:24 volumio volumio[845]: info: Preloading song: music-library/NAS/NAS-Audio/Test/SACD Compilation/09 - Toto - Africa.dsf May 04 14:40:24 volumio volumio[845]: info: Preloading song: music-library/NAS/NAS-Audio/Test/SACD Compilation/05 - Eric Clapton - Cocaine.dsf May 04 14:40:24 volumio volumio[845]: info: Preloading song: music-library/NAS/NAS-Audio/Test/SACD Compilation/08 - The Police & Henry Mancini And His Orchestra - Every Breath You Take Theme From Peter Gunn (Mr. Ruggerio.dsf May 04 14:40:24 volumio volumio[845]: info: Preloading song: music-library/NAS/NAS-Audio/Test/SACD Compilation/07 - Cake - Frank Sinatra.dsf May 04 14:40:24 volumio volumio[845]: info: Preloading song: music-library/NAS/NAS-Audio/Test/SACD Compilation/12 - Roger Waters - Perfect Sense (Parts I & II).dsf May 04 14:40:24 volumio volumio[845]: info: Preloading song: music-library/NAS/NAS-Audio/Test/SACD Compilation/02 - Phil Collins - In The Air Tonight.dsf May 04 14:40:24 volumio volumio[845]: info: Preloading song: music-library/NAS/NAS-Audio/Test/SACD Compilation/01 - Alice Cooper - School's Out.dsf May 04 14:40:24 volumio volumio[845]: info: Preloading song: music-library/NAS/NAS-Audio/Test/SACD Compilation/06 - Bad Company - Can't Get Enough.dsf May 04 14:40:24 volumio volumio[845]: info: Exploding uri music-library/NAS/NAS-Audio/Test/SACD Compilation/10 - Eric Clapton - I Shot The Sheriff.dsf in service mpd May 04 14:40:24 volumio volumio[845]: info: ALBUMART /albumart?cacheid=457&web=ERIC%20CLAPTON/461%20OCEAN%20BOULEVARD/extralarge&path=%2Fmnt%2FNAS%2FNAS-Audio%2FTest%2FSACD%20Compilation%2F10%20-%20Eric%20Clapton%20-%20I%20Shot%20The%20Sheriff.dsf&metadata=false May 04 14:40:24 volumio volumio[845]: info: URI /mnt/NAS/NAS-Audio/Test/SACD Compilation/10 - Eric Clapton - I Shot The Sheriff.dsf May 04 14:40:24 volumio volumio[845]: info: Exploding uri music-library/NAS/NAS-Audio/Test/SACD Compilation/03 - Asia - Heat Of The Moment.dsf in service mpd May 04 14:40:24 volumio volumio[845]: info: ALBUMART /albumart?cacheid=457&web=Asia/Asia/extralarge&path=%2Fmnt%2FNAS%2FNAS-Audio%2FTest%2FSACD%20Compilation%2F03%20-%20Asia%20-%20Heat%20Of%20The%20Moment.dsf&metadata=false May 04 14:40:24 volumio volumio[845]: info: URI /mnt/NAS/NAS-Audio/Test/SACD Compilation/03 - Asia - Heat Of The Moment.dsf May 04 14:40:24 volumio volumio[845]: info: Exploding uri music-library/NAS/NAS-Audio/Test/SACD Compilation/11 - Dire Straits (2013 MFSL) - Money For Nothing.dsf in service mpd May 04 14:40:24 volumio volumio[845]: info: ALBUMART /albumart?cacheid=457&web=Dire%20Straits/Brothers%20In%20Arms/extralarge&path=%2Fmnt%2FNAS%2FNAS-Audio%2FTest%2FSACD%20Compilation%2F11%20-%20Dire%20Straits%20(2013%20MFSL)%20-%20Money%20For%20Nothing.dsf&metadata=false May 04 14:40:24 volumio volumio[845]: info: URI /mnt/NAS/NAS-Audio/Test/SACD Compilation/11 - Dire Straits (2013 MFSL) - Money For Nothing.dsf May 04 14:40:24 volumio volumio[845]: info: Exploding uri music-library/NAS/NAS-Audio/Test/SACD Compilation/04 - Derek And The Dominos - Layla.dsf in service mpd May 04 14:40:24 volumio volumio[845]: info: ALBUMART /albumart?cacheid=457&web=Derek%20And%20The%20Dominos/Layla%20And%20Other%20Assorted%20Love%20Songs/extralarge&path=%2Fmnt%2FNAS%2FNAS-Audio%2FTest%2FSACD%20Compilation%2F04%20-%20Derek%20And%20The%20Dominos%20-%20Layla.dsf&metadata=false May 04 14:40:24 volumio volumio[845]: info: URI /mnt/NAS/NAS-Audio/Test/SACD Compilation/04 - Derek And The Dominos - Layla.dsf May 04 14:40:24 volumio volumio[845]: info: Exploding uri music-library/NAS/NAS-Audio/Test/SACD Compilation/09 - Toto - Africa.dsf in service mpd May 04 14:40:24 volumio volumio[845]: info: ALBUMART /albumart?cacheid=457&web=Toto/SACD%20sampler/extralarge&path=%2Fmnt%2FNAS%2FNAS-Audio%2FTest%2FSACD%20Compilation%2F09%20-%20Toto%20-%20Africa.dsf&metadata=false May 04 14:40:24 volumio volumio[845]: info: URI /mnt/NAS/NAS-Audio/Test/SACD Compilation/09 - Toto - Africa.dsf May 04 14:40:24 volumio volumio[845]: info: Exploding uri music-library/NAS/NAS-Audio/Test/SACD Compilation/05 - Eric Clapton - Cocaine.dsf in service mpd May 04 14:40:24 volumio volumio[845]: info: ALBUMART /albumart?cacheid=457&web=Eric%20Clapton/Slowhand/extralarge&path=%2Fmnt%2FNAS%2FNAS-Audio%2FTest%2FSACD%20Compilation%2F05%20-%20Eric%20Clapton%20-%20Cocaine.dsf&metadata=false May 04 14:40:24 volumio volumio[845]: info: URI /mnt/NAS/NAS-Audio/Test/SACD Compilation/05 - Eric Clapton - Cocaine.dsf May 04 14:40:24 volumio volumio[845]: info: Exploding uri music-library/NAS/NAS-Audio/Test/SACD Compilation/08 - The Police & Henry Mancini And His Orchestra - Every Breath You Take Theme From Peter Gunn (Mr. Ruggerio.dsf in service mpd May 04 14:40:24 volumio volumio[845]: info: ALBUMART /albumart?cacheid=457&web=THE%20POLICE%20%26%20HENRY%20MANCINI%20AND%20HIS%20ORCHESTRA/THE%20SOPRANOS%20-%20MUSIC%20FROM%20THE%20HBO%20ORIGINAL%20SERIES%20-%20PEPPERS%20%26%20EGGS/extralarge&path=%2Fmnt%2FNAS%2FNAS-Audio%2FTest%2FSACD%20Compilation%2F08%20-%20The%20Police%20%26%20Henry%20Mancini%20And%20His%20Orchestra%20-%20Every%20Breath%20You%20Take%20Theme%20From%20Peter%20Gunn%20(Mr.%20Ruggerio.dsf&metadata=false May 04 14:40:24 volumio volumio[845]: info: URI /mnt/NAS/NAS-Audio/Test/SACD Compilation/08 - The Police & Henry Mancini And His Orchestra - Every Breath You Take Theme From Peter Gunn (Mr. Ruggerio.dsf May 04 14:40:24 volumio volumio[845]: info: Exploding uri music-library/NAS/NAS-Audio/Test/SACD Compilation/07 - Cake - Frank Sinatra.dsf in service mpd May 04 14:40:24 volumio volumio[845]: info: ALBUMART /albumart?cacheid=457&web=CAKE/THE%20SOPRANOS%20-%20MUSIC%20FROM%20THE%20HBO%20ORIGINAL%20SERIES%20-%20PEPPERS%20%26%20EGGS/extralarge&path=%2Fmnt%2FNAS%2FNAS-Audio%2FTest%2FSACD%20Compilation%2F07%20-%20Cake%20-%20Frank%20Sinatra.dsf&metadata=false May 04 14:40:24 volumio volumio[845]: info: URI /mnt/NAS/NAS-Audio/Test/SACD Compilation/07 - Cake - Frank Sinatra.dsf May 04 14:40:25 volumio volumio[845]: info: Exploding uri music-library/NAS/NAS-Audio/Test/SACD Compilation/12 - Roger Waters - Perfect Sense (Parts I & II).dsf in service mpd May 04 14:40:25 volumio volumio[845]: info: ALBUMART /albumart?cacheid=457&web=ROGER%20WATERS/THE%20ULTIMATE%20COLLECTION%20VOLUME%20I/extralarge&path=%2Fmnt%2FNAS%2FNAS-Audio%2FTest%2FSACD%20Compilation%2F12%20-%20Roger%20Waters%20-%20Perfect%20Sense%20(Parts%20I%20%26%20II).dsf&metadata=false May 04 14:40:25 volumio volumio[845]: info: URI /mnt/NAS/NAS-Audio/Test/SACD Compilation/12 - Roger Waters - Perfect Sense (Parts I & II).dsf May 04 14:40:25 volumio volumio[845]: info: Exploding uri music-library/NAS/NAS-Audio/Test/SACD Compilation/02 - Phil Collins - In The Air Tonight.dsf in service mpd May 04 14:40:25 volumio volumio[845]: info: ALBUMART /albumart?cacheid=457&web=Phil%20Collins/Time-Life%20Legends%3A%20Crank%20It%20Up/extralarge&path=%2Fmnt%2FNAS%2FNAS-Audio%2FTest%2FSACD%20Compilation%2F02%20-%20Phil%20Collins%20-%20In%20The%20Air%20Tonight.dsf&metadata=false May 04 14:40:25 volumio volumio[845]: info: URI /mnt/NAS/NAS-Audio/Test/SACD Compilation/02 - Phil Collins - In The Air Tonight.dsf May 04 14:40:25 volumio volumio[845]: info: Exploding uri music-library/NAS/NAS-Audio/Test/SACD Compilation/01 - Alice Cooper - School's Out.dsf in service mpd May 04 14:40:25 volumio volumio[845]: info: ALBUMART /albumart?cacheid=457&web=Alice%20Cooper/Time-Life%20Legends%3A%20Get%20It%20On/extralarge&path=%2Fmnt%2FNAS%2FNAS-Audio%2FTest%2FSACD%20Compilation%2F01%20-%20Alice%20Cooper%20-%20School's%20Out.dsf&metadata=false May 04 14:40:25 volumio volumio[845]: info: URI /mnt/NAS/NAS-Audio/Test/SACD Compilation/01 - Alice Cooper - School's Out.dsf May 04 14:40:25 volumio volumio[845]: info: Exploding uri music-library/NAS/NAS-Audio/Test/SACD Compilation/06 - Bad Company - Can't Get Enough.dsf in service mpd May 04 14:40:25 volumio volumio[845]: info: ALBUMART /albumart?cacheid=457&web=Bad%20Company/Time-Life%20Legends%3A%20Get%20It%20On/extralarge&path=%2Fmnt%2FNAS%2FNAS-Audio%2FTest%2FSACD%20Compilation%2F06%20-%20Bad%20Company%20-%20Can't%20Get%20Enough.dsf&metadata=false May 04 14:40:25 volumio volumio[845]: info: URI /mnt/NAS/NAS-Audio/Test/SACD Compilation/06 - Bad Company - Can't Get Enough.dsf May 04 14:40:25 volumio volumio[845]: info: May 04 14:40:25 volumio volumio[845]: ---------------------------- MPD announces system playlist update May 04 14:40:25 volumio volumio[845]: info: Ignoring MPD Status Update May 04 14:40:25 volumio volumio[845]: info: May 04 14:40:25 volumio volumio[845]: ---------------------------- MPD announces state update: player May 04 14:40:25 volumio volumio[845]: info: ControllerMpd::getState May 04 14:40:25 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand status May 04 14:40:25 volumio volumio[845]: info: ------------------------------ 3ms May 04 14:40:25 volumio volumio[845]: info: sendMpdCommand status took 1 milliseconds May 04 14:40:25 volumio volumio[845]: verbose: ControllerMpd::parseState May 04 14:40:25 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 14:40:25 volumio volumio[845]: info: sendMpdCommand playlistinfo took 1 milliseconds May 04 14:40:25 volumio volumio[845]: verbose: ControllerMpd::parseTrackInfo May 04 14:40:25 volumio volumio[845]: info: ControllerMpd::pushState May 04 14:40:25 volumio volumio[845]: info: CoreCommandRouter::servicePushState May 04 14:40:25 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 04 14:40:25 volumio volumio[845]: verbose: STATE SERVICE {"status":"play","position":4,"seek":36219,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"48 Kbps","isStreaming":false,"title":"Leading Britain's Conversation - Ali Miraj","artist":"LBC London","album":null,"uri":"http://media-ice.musicradio.com/LBCLondonMP3","trackType":""} May 04 14:40:25 volumio volumio[845]: verbose: CURRENT POSITION 0 May 04 14:40:25 volumio volumio[845]: info: CoreStateMachine::syncState stateService play May 04 14:40:25 volumio volumio[845]: info: CoreStateMachine::syncState currentStatus play May 04 14:40:25 volumio volumio[845]: info: Received an update from plugin. extracting info from payload May 04 14:40:25 volumio volumio[845]: info: CoreStateMachine::pushState May 04 14:40:25 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 14:40:25 volumio volumio[845]: info: CoreCommandRouter::volumioPushState May 04 14:40:25 volumio volumio[845]: info: CoreStateMachine::pushState May 04 14:40:25 volumio volumio[845]: info: CoreCommandRouter::volumioPushState May 04 14:40:25 volumio volumio[845]: info: ------------------------------ 9ms May 04 14:40:26 volumio volumio[845]: info: May 04 14:40:26 volumio volumio[845]: ---------------------------- MPD announces system playlist update May 04 14:40:26 volumio volumio[845]: info: Ignoring MPD Status Update May 04 14:40:26 volumio volumio[845]: info: May 04 14:40:26 volumio volumio[845]: ---------------------------- MPD announces state update: player May 04 14:40:26 volumio volumio[845]: info: ControllerMpd::getState May 04 14:40:26 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand status May 04 14:40:26 volumio volumio[845]: info: ------------------------------ 3ms May 04 14:40:26 volumio volumio[845]: info: sendMpdCommand status took 1 milliseconds May 04 14:40:26 volumio volumio[845]: verbose: ControllerMpd::parseState May 04 14:40:26 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 14:40:26 volumio volumio[845]: info: sendMpdCommand playlistinfo took 1 milliseconds May 04 14:40:26 volumio volumio[845]: verbose: ControllerMpd::parseTrackInfo May 04 14:40:26 volumio volumio[845]: info: ControllerMpd::pushState May 04 14:40:26 volumio volumio[845]: info: CoreCommandRouter::servicePushState May 04 14:40:26 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 04 14:40:26 volumio volumio[845]: verbose: STATE SERVICE {"status":"play","position":4,"seek":37851,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"48 Kbps","isStreaming":false,"title":"Leading Britain's Conversation - Ali Miraj","artist":"LBC London","album":null,"uri":"http://media-ice.musicradio.com/LBCLondonMP3","trackType":""} May 04 14:40:26 volumio volumio[845]: verbose: CURRENT POSITION 0 May 04 14:40:26 volumio volumio[845]: info: CoreStateMachine::syncState stateService play May 04 14:40:26 volumio volumio[845]: info: CoreStateMachine::syncState currentStatus play May 04 14:40:26 volumio volumio[845]: info: Received an update from plugin. extracting info from payload May 04 14:40:26 volumio volumio[845]: info: CoreStateMachine::pushState May 04 14:40:26 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 14:40:26 volumio volumio[845]: info: CoreCommandRouter::volumioPushState May 04 14:40:26 volumio volumio[845]: info: CoreStateMachine::pushState May 04 14:40:26 volumio volumio[845]: info: CoreCommandRouter::volumioPushState May 04 14:40:26 volumio volumio[845]: info: ------------------------------ 10ms May 04 14:40:28 volumio volumio[845]: info: May 04 14:40:28 volumio volumio[845]: ---------------------------- MPD announces system playlist update May 04 14:40:28 volumio volumio[845]: info: Ignoring MPD Status Update May 04 14:40:28 volumio volumio[845]: info: May 04 14:40:28 volumio volumio[845]: ---------------------------- MPD announces state update: player May 04 14:40:28 volumio volumio[845]: info: ControllerMpd::getState May 04 14:40:28 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand status May 04 14:40:28 volumio volumio[845]: info: ------------------------------ 3ms May 04 14:40:28 volumio volumio[845]: info: sendMpdCommand status took 1 milliseconds May 04 14:40:28 volumio volumio[845]: verbose: ControllerMpd::parseState May 04 14:40:28 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 14:40:28 volumio volumio[845]: info: sendMpdCommand playlistinfo took 1 milliseconds May 04 14:40:28 volumio volumio[845]: verbose: ControllerMpd::parseTrackInfo May 04 14:40:28 volumio volumio[845]: info: ControllerMpd::pushState May 04 14:40:28 volumio volumio[845]: info: CoreCommandRouter::servicePushState May 04 14:40:28 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 04 14:40:28 volumio volumio[845]: verbose: STATE SERVICE {"status":"play","position":4,"seek":39350,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"48 Kbps","isStreaming":false,"title":"Leading Britain's Conversation - Ali Miraj","artist":"LBC London","album":null,"uri":"http://media-ice.musicradio.com/LBCLondonMP3","trackType":""} May 04 14:40:28 volumio volumio[845]: verbose: CURRENT POSITION 0 May 04 14:40:28 volumio volumio[845]: info: CoreStateMachine::syncState stateService play May 04 14:40:28 volumio volumio[845]: info: CoreStateMachine::syncState currentStatus play May 04 14:40:28 volumio volumio[845]: info: Received an update from plugin. extracting info from payload May 04 14:40:28 volumio volumio[845]: info: CoreStateMachine::pushState May 04 14:40:28 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 14:40:28 volumio volumio[845]: info: CoreCommandRouter::volumioPushState May 04 14:40:28 volumio volumio[845]: info: CoreStateMachine::pushState May 04 14:40:28 volumio volumio[845]: info: CoreCommandRouter::volumioPushState May 04 14:40:28 volumio volumio[845]: info: ------------------------------ 10ms May 04 14:40:31 volumio volumio[845]: info: Preload queue cleared May 04 14:40:31 volumio volumio[845]: info: CoreCommandRouter::volumioReplaceandPlayItems May 04 14:40:31 volumio volumio[845]: info: CoreStateMachine::ClearQueue May 04 14:40:31 volumio volumio[845]: info: CoreStateMachine::stop May 04 14:40:31 volumio volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 14:40:31 volumio volumio[845]: info: CoreStateMachine::stPlaybackTimer May 04 14:40:31 volumio volumio[845]: info: CoreStateMachine::updateTrackBlock May 04 14:40:31 volumio volumio[845]: info: CorePlayQueue::getTrackBlock May 04 14:40:31 volumio volumio[845]: info: CoreStateMachine::pushState May 04 14:40:31 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 04 14:40:31 volumio volumio[845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 14:40:31 volumio volumio[845]: info: CoreCommandRouter::volumioPushState May 04 14:40:31 volumio volumio[845]: info: CoreStateMachine::serviceStop May 04 14:40:31 volumio volumio[845]: info: CorePlayQueue::getTrack 0 May 04 14:40:31 volumio volumio[845]: info: CoreCommandRouter::serviceStop May 04 14:40:31 volumio volumio[845]: info: [1714830031042] ControllerWebradio::stop May 04 14:40:31 volumio volumio[845]: verbose: ControllerMpd::sendMpdCommand stop May 04 14:40:31 volumio volumio[845]: info: CorePlayQueue::clearPlayQueue May 04 14:40:31 volumio volumio[845]: info: CorePlayQueue::saveQueue May 04 14:40:31 volumio volumio[845]: info: CoreCommandRouter::volumioPushQueue May 04 14:40:31 volumio volumio[845]: info: CoreStateMachine::addQueueItems May 04 14:40:31 volumio volumio[845]: info: CorePlayQueue::addQueueItems May 04 14:40:31 volumio volumio[845]: info: Preload queue cleared May 04 14:40:31 volumio volumio[845]: info: Adding Item to queue: music-library/NAS/NAS-Audio/Test/High-End Test CD May 04 14:40:31 volumio volumio[845]: info: Exploding uri music-library/NAS/NAS-Audio/Test/High-End Test CD in service mpd May 04 14:40:31 volumio volumio[845]: info: sendMpdCommand stop took 65 milliseconds May 04 14:40:31 volumio volumio[845]: info: May 04 14:40:31 volumio volumio[845]: ---------------------------- MPD announces state update: player May 04 14:40:31 volumio volumio[845]: info: ALBUMART /albumart?cacheid=457&web=Track01/High-End%20Test%20CD/extralarge&path=%2Fmnt%2FNAS%2FNAS-Audio%2FTest%2FHigh-End%20Test%20CD%2FVarious%20-%20High-End%20Test%20CD.cue&metadata=false May 04 14:40:31 volumio volumio[845]: info: URI /mnt/NAS/NAS-Audio/Test/High-End Test CD/Various - High-End Test CD.cue May 04 14:40:31 volumio volumio[845]: info: ALBUMART /albumart?cacheid=457&web=Track02/High-End%20Test%20CD/extralarge&path=%2Fmnt%2FNAS%2FNAS-Audio%2FTest%2FHigh-End%20Test%20CD%2FVarious%20-%20High-End%20Test%20CD.cue&metadata=false May 04 14:40:31 volumio volumio[845]: info: URI /mnt/NAS/NAS-Audio/Test/High-End Test CD/Various - High-End Test CD.cue May 04 14:40:31 volumio volumio[845]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 04 14:40:31 volumio volumio[845]: Error: Unable to resolve or reject the same promise twice May 04 14:40:31 volumio volumio[845]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) May 04 14:40:31 volumio volumio[845]: at /volumio/app/plugins/music_service/mpd/index.js:2568:21 May 04 14:40:31 volumio volumio[845]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) May 04 14:40:31 volumio volumio[845]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) May 04 14:40:31 volumio volumio[845]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) May 04 14:40:31 volumio volumio[845]: at Socket.emit (events.js:315:20) May 04 14:40:31 volumio volumio[845]: at addChunk (internal/streams/readable.js:309:12) May 04 14:40:31 volumio volumio[845]: at readableAddChunk (internal/streams/readable.js:280:11) May 04 14:40:31 volumio volumio[845]: at Socket.Readable.push (internal/streams/readable.js:223:10) May 04 14:40:31 volumio volumio[845]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) May 04 14:40:31 volumio volumio[845]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 04 14:40:31 volumio sudo[6128]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-04 14:39 May 04 14:40:31 volumio sudo[6128]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"