-- 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"