-- Logs begin at Tue 2024-01-30 16:04:04 CET, end at Fri 2024-07-26 19:16:49 CEST. -- Jul 26 19:15:07 volumio-ip46 volumio[724]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jul 26 19:15:07 volumio-ip46 volumio[724]: info: Preload queue cleared Jul 26 19:15:12 volumio-ip46 volumio[724]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jul 26 19:15:12 volumio-ip46 volumio[724]: info: Preload queue cleared Jul 26 19:15:16 volumio-ip46 volumio[724]: info: Preload queue cleared Jul 26 19:15:16 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 26 19:15:16 volumio-ip46 volumio[724]: info: CoreStateMachine::ClearQueue Jul 26 19:15:16 volumio-ip46 volumio[724]: info: CoreStateMachine::stop Jul 26 19:15:16 volumio-ip46 volumio[724]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 26 19:15:16 volumio-ip46 volumio[724]: info: CoreStateMachine::stPlaybackTimer Jul 26 19:15:16 volumio-ip46 volumio[724]: info: CoreStateMachine::updateTrackBlock Jul 26 19:15:16 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrackBlock Jul 26 19:15:16 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:15:16 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 3 Jul 26 19:15:16 volumio-ip46 volumio[724]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 26 19:15:16 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:15:16 volumio-ip46 volumio[724]: info: CoreStateMachine::serviceStop Jul 26 19:15:16 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 3 Jul 26 19:15:16 volumio-ip46 volumio[724]: info: CoreCommandRouter::serviceStop Jul 26 19:15:16 volumio-ip46 volumio[724]: info: ControllerMpd::stop Jul 26 19:15:16 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand stop Jul 26 19:15:16 volumio-ip46 volumio[724]: info: CorePlayQueue::clearPlayQueue Jul 26 19:15:16 volumio-ip46 volumio[724]: info: CorePlayQueue::saveQueue Jul 26 19:15:16 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushQueue Jul 26 19:15:16 volumio-ip46 volumio[724]: info: CoreStateMachine::addQueueItems Jul 26 19:15:16 volumio-ip46 volumio[724]: info: CorePlayQueue::addQueueItems Jul 26 19:15:16 volumio-ip46 volumio[724]: info: Preload queue cleared Jul 26 19:15:16 volumio-ip46 volumio[724]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s47800 Jul 26 19:15:16 volumio-ip46 volumio[724]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jul 26 19:15:17 volumio-ip46 volumio[724]: info: Jul 26 19:15:17 volumio-ip46 volumio[724]: ---------------------------- MPD announces state update: player Jul 26 19:15:17 volumio-ip46 volumio[724]: info: sendMpdCommand stop took 311 milliseconds Jul 26 19:15:17 volumio-ip46 volumio[724]: info: ControllerMpd::getState Jul 26 19:15:17 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand status Jul 26 19:15:17 volumio-ip46 volumio[724]: info: Jul 26 19:15:17 volumio-ip46 volumio[724]: ---------------------------- MPD announces state update: player Jul 26 19:15:17 volumio-ip46 volumio[724]: info: ControllerMpd::getState Jul 26 19:15:17 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand status Jul 26 19:15:17 volumio-ip46 volumio[724]: info: Jul 26 19:15:17 volumio-ip46 volumio[724]: ---------------------------- MPD announces state update: player Jul 26 19:15:17 volumio-ip46 volumio[724]: info: ControllerMpd::getState Jul 26 19:15:17 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand status Jul 26 19:15:17 volumio-ip46 volumio[724]: info: sendMpdCommand status took 28 milliseconds Jul 26 19:15:17 volumio-ip46 volumio[724]: info: sendMpdCommand status took 21 milliseconds Jul 26 19:15:17 volumio-ip46 volumio[724]: info: sendMpdCommand status took 15 milliseconds Jul 26 19:15:17 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseState Jul 26 19:15:17 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 26 19:15:17 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseState Jul 26 19:15:17 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 26 19:15:17 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseState Jul 26 19:15:17 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 26 19:15:17 volumio-ip46 volumio[724]: info: sendMpdCommand playlistinfo took 49 milliseconds Jul 26 19:15:17 volumio-ip46 volumio[724]: info: sendMpdCommand playlistinfo took 54 milliseconds Jul 26 19:15:17 volumio-ip46 volumio[724]: info: sendMpdCommand playlistinfo took 57 milliseconds Jul 26 19:15:17 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseTrackInfo Jul 26 19:15:17 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseTrackInfo Jul 26 19:15:17 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseTrackInfo Jul 26 19:15:17 volumio-ip46 volumio[724]: info: ControllerMpd::pushState Jul 26 19:15:17 volumio-ip46 volumio[724]: info: CoreCommandRouter::servicePushState Jul 26 19:15:17 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:15:17 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 3 Jul 26 19:15:17 volumio-ip46 volumio[724]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 26 19:15:17 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:15:17 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 3 Jul 26 19:15:17 volumio-ip46 volumio[724]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Miss Sarajevo (Single Radio Edit)","artist":"Passengers","album":"Pavarotti - The Duets","uri":"NAS/Volumio/Pavarotti - The Duets/04 - Miss Sarajevo (Single Radio Edit).mp3","trackType":"mp3"} Jul 26 19:15:17 volumio-ip46 volumio[724]: verbose: CURRENT POSITION 3 Jul 26 19:15:17 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState stateService stop Jul 26 19:15:17 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState currentStatus stop Jul 26 19:15:17 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:15:17 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 3 Jul 26 19:15:17 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:15:17 volumio-ip46 volumio[724]: info: No code Jul 26 19:15:17 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:15:17 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 3 Jul 26 19:15:17 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:15:17 volumio-ip46 volumio[724]: info: ControllerMpd::pushState Jul 26 19:15:17 volumio-ip46 volumio[724]: info: CoreCommandRouter::servicePushState Jul 26 19:15:17 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:15:17 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 3 Jul 26 19:15:17 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:15:17 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 3 Jul 26 19:15:17 volumio-ip46 volumio[724]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Miss Sarajevo (Single Radio Edit)","artist":"Passengers","album":"Pavarotti - The Duets","uri":"NAS/Volumio/Pavarotti - The Duets/04 - Miss Sarajevo (Single Radio Edit).mp3","trackType":"mp3"} Jul 26 19:15:17 volumio-ip46 volumio[724]: verbose: CURRENT POSITION 3 Jul 26 19:15:17 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState stateService stop Jul 26 19:15:17 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState currentStatus stop Jul 26 19:15:17 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:15:17 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 3 Jul 26 19:15:17 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:15:17 volumio-ip46 volumio[724]: info: No code Jul 26 19:15:17 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:15:17 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 3 Jul 26 19:15:17 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:15:17 volumio-ip46 volumio[724]: info: ControllerMpd::pushState Jul 26 19:15:17 volumio-ip46 volumio[724]: info: CoreCommandRouter::servicePushState Jul 26 19:15:17 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:15:17 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 3 Jul 26 19:15:17 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:15:17 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 3 Jul 26 19:15:17 volumio-ip46 volumio[724]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Miss Sarajevo (Single Radio Edit)","artist":"Passengers","album":"Pavarotti - The Duets","uri":"NAS/Volumio/Pavarotti - The Duets/04 - Miss Sarajevo (Single Radio Edit).mp3","trackType":"mp3"} Jul 26 19:15:17 volumio-ip46 volumio[724]: verbose: CURRENT POSITION 3 Jul 26 19:15:17 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState stateService stop Jul 26 19:15:17 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState currentStatus stop Jul 26 19:15:17 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:15:17 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 3 Jul 26 19:15:17 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:15:17 volumio-ip46 volumio[724]: info: No code Jul 26 19:15:17 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:15:17 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 3 Jul 26 19:15:17 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:15:17 volumio-ip46 volumio[724]: info: ------------------------------ 526ms Jul 26 19:15:17 volumio-ip46 volumio[724]: info: ------------------------------ 520ms Jul 26 19:15:17 volumio-ip46 volumio[724]: info: ------------------------------ 516ms Jul 26 19:15:18 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushQueue Jul 26 19:15:18 volumio-ip46 volumio[724]: info: CorePlayQueue::saveQueue Jul 26 19:15:18 volumio-ip46 volumio[724]: info: CoreStateMachine::updateTrackBlock Jul 26 19:15:18 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrackBlock Jul 26 19:15:18 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPlay Jul 26 19:15:18 volumio-ip46 volumio[724]: info: CoreStateMachine::play index 0 Jul 26 19:15:18 volumio-ip46 volumio[724]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 26 19:15:18 volumio-ip46 volumio[724]: info: CoreStateMachine::stop Jul 26 19:15:18 volumio-ip46 volumio[724]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 26 19:15:18 volumio-ip46 volumio[724]: info: CoreStateMachine::play index undefined Jul 26 19:15:18 volumio-ip46 volumio[724]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 26 19:15:18 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:15:18 volumio-ip46 volumio[724]: info: CoreStateMachine::startPlaybackTimer Jul 26 19:15:18 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:15:18 volumio-ip46 volumio[724]: info: [1722014118134] ControllerWebradio::clearAddPlayTrack Jul 26 19:15:18 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand stop Jul 26 19:15:18 volumio-ip46 volumio[724]: info: sendMpdCommand stop took 52 milliseconds Jul 26 19:15:18 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand clear Jul 26 19:15:18 volumio-ip46 volumio[724]: info: Jul 26 19:15:18 volumio-ip46 volumio[724]: ---------------------------- MPD announces system playlist update Jul 26 19:15:18 volumio-ip46 volumio[724]: info: Ignoring MPD Status Update Jul 26 19:15:18 volumio-ip46 volumio[724]: info: sendMpdCommand clear took 17 milliseconds Jul 26 19:15:18 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand load "https://rsaradio-live.cast.addradio.de/rsaradio/live/mp3/high?ar-distributor=f0a1" Jul 26 19:15:18 volumio-ip46 volumio[724]: info: Jul 26 19:15:18 volumio-ip46 volumio[724]: ---------------------------- MPD announces system playlist update Jul 26 19:15:18 volumio-ip46 volumio[724]: info: Ignoring MPD Status Update Jul 26 19:15:18 volumio-ip46 volumio[724]: info: Jul 26 19:15:18 volumio-ip46 volumio[724]: ---------------------------- MPD announces system playlist update Jul 26 19:15:18 volumio-ip46 volumio[724]: info: Ignoring MPD Status Update Jul 26 19:15:18 volumio-ip46 volumio[724]: error: updateQueue error: null Jul 26 19:15:18 volumio-ip46 volumio[724]: info: ------------------------------ 61ms Jul 26 19:15:23 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand add "https://rsaradio-live.cast.addradio.de/rsaradio/live/mp3/high?ar-distributor=f0a1" Jul 26 19:15:23 volumio-ip46 volumio[724]: error: updateQueue error: null Jul 26 19:15:23 volumio-ip46 volumio[724]: error: updateQueue error: null Jul 26 19:15:23 volumio-ip46 volumio[724]: info: ------------------------------ 5542ms Jul 26 19:15:23 volumio-ip46 volumio[724]: info: ------------------------------ 5533ms Jul 26 19:15:23 volumio-ip46 volumio[724]: Upnp client error: Error: This socket has been ended by the other party Jul 26 19:15:23 volumio-ip46 volumio[724]: Upnp client error: Error: This socket has been ended by the other party Jul 26 19:15:23 volumio-ip46 volumio[724]: info: Jul 26 19:15:23 volumio-ip46 volumio[724]: ---------------------------- MPD announces system playlist update Jul 26 19:15:23 volumio-ip46 volumio[724]: info: Ignoring MPD Status Update Jul 26 19:15:23 volumio-ip46 volumio[724]: info: sendMpdCommand add "https://rsaradio-live.cast.addradio.de/rsaradio/live/mp3/high?ar-distributor=f0a1" took 22 milliseconds Jul 26 19:15:23 volumio-ip46 volumio[724]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 26 19:15:23 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand play Jul 26 19:15:23 volumio-ip46 volumio[724]: info: Jul 26 19:15:23 volumio-ip46 volumio[724]: ---------------------------- MPD announces system playlist update Jul 26 19:15:23 volumio-ip46 volumio[724]: info: Ignoring MPD Status Update Jul 26 19:15:23 volumio-ip46 volumio[724]: info: Jul 26 19:15:23 volumio-ip46 volumio[724]: ---------------------------- MPD announces system playlist update Jul 26 19:15:23 volumio-ip46 volumio[724]: info: Ignoring MPD Status Update Jul 26 19:15:23 volumio-ip46 volumio[724]: info: ------------------------------ 43ms Jul 26 19:15:23 volumio-ip46 volumio[724]: info: sendMpdCommand play took 40 milliseconds Jul 26 19:15:23 volumio-ip46 volumio[724]: info: ------------------------------ 37ms Jul 26 19:15:23 volumio-ip46 volumio[724]: info: ------------------------------ 28ms Jul 26 19:15:25 volumio-ip46 volumio[724]: info: Preload queue cleared Jul 26 19:15:25 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 26 19:15:25 volumio-ip46 volumio[724]: info: CoreStateMachine::ClearQueue Jul 26 19:15:25 volumio-ip46 volumio[724]: info: CoreStateMachine::stop Jul 26 19:15:25 volumio-ip46 volumio[724]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 26 19:15:25 volumio-ip46 volumio[724]: info: CorePlayQueue::clearPlayQueue Jul 26 19:15:25 volumio-ip46 volumio[724]: info: CorePlayQueue::saveQueue Jul 26 19:15:25 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushQueue Jul 26 19:15:25 volumio-ip46 volumio[724]: info: CoreStateMachine::addQueueItems Jul 26 19:15:25 volumio-ip46 volumio[724]: info: CorePlayQueue::addQueueItems Jul 26 19:15:25 volumio-ip46 volumio[724]: info: Preload queue cleared Jul 26 19:15:25 volumio-ip46 volumio[724]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s47800 Jul 26 19:15:25 volumio-ip46 volumio[724]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jul 26 19:15:25 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushQueue Jul 26 19:15:25 volumio-ip46 volumio[724]: info: CorePlayQueue::saveQueue Jul 26 19:15:25 volumio-ip46 volumio[724]: info: CoreStateMachine::updateTrackBlock Jul 26 19:15:25 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrackBlock Jul 26 19:15:25 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPlay Jul 26 19:15:25 volumio-ip46 volumio[724]: info: CoreStateMachine::play index 0 Jul 26 19:15:25 volumio-ip46 volumio[724]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 26 19:15:25 volumio-ip46 volumio[724]: info: CoreStateMachine::stop Jul 26 19:15:25 volumio-ip46 volumio[724]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 26 19:15:25 volumio-ip46 volumio[724]: info: CoreStateMachine::play index undefined Jul 26 19:15:25 volumio-ip46 volumio[724]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 26 19:15:25 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:15:25 volumio-ip46 volumio[724]: info: CoreStateMachine::startPlaybackTimer Jul 26 19:15:25 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:15:25 volumio-ip46 volumio[724]: info: [1722014125956] ControllerWebradio::clearAddPlayTrack Jul 26 19:15:25 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand stop Jul 26 19:15:26 volumio-ip46 volumio[724]: info: Jul 26 19:15:26 volumio-ip46 volumio[724]: ---------------------------- MPD announces state update: player Jul 26 19:15:26 volumio-ip46 volumio[724]: info: sendMpdCommand stop took 58 milliseconds Jul 26 19:15:26 volumio-ip46 volumio[724]: info: ControllerMpd::getState Jul 26 19:15:26 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand status Jul 26 19:15:26 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand clear Jul 26 19:15:26 volumio-ip46 volumio[724]: info: Jul 26 19:15:26 volumio-ip46 volumio[724]: ---------------------------- MPD announces state update: player Jul 26 19:15:26 volumio-ip46 volumio[724]: info: ControllerMpd::getState Jul 26 19:15:26 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand status Jul 26 19:15:26 volumio-ip46 volumio[724]: info: Jul 26 19:15:26 volumio-ip46 volumio[724]: ---------------------------- MPD announces state update: player Jul 26 19:15:26 volumio-ip46 volumio[724]: info: ControllerMpd::getState Jul 26 19:15:26 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand status Jul 26 19:15:26 volumio-ip46 volumio[724]: info: Jul 26 19:15:26 volumio-ip46 volumio[724]: ---------------------------- MPD announces system playlist update Jul 26 19:15:26 volumio-ip46 volumio[724]: info: Ignoring MPD Status Update Jul 26 19:15:26 volumio-ip46 volumio[724]: info: sendMpdCommand status took 32 milliseconds Jul 26 19:15:26 volumio-ip46 volumio[724]: info: sendMpdCommand clear took 31 milliseconds Jul 26 19:15:26 volumio-ip46 volumio[724]: info: sendMpdCommand status took 23 milliseconds Jul 26 19:15:26 volumio-ip46 volumio[724]: info: sendMpdCommand status took 16 milliseconds Jul 26 19:15:26 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseState Jul 26 19:15:26 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 26 19:15:26 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand load "https://rsaradio-live.cast.addradio.de/rsaradio/live/mp3/high?ar-distributor=f0a1" Jul 26 19:15:26 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseState Jul 26 19:15:26 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseState Jul 26 19:15:26 volumio-ip46 volumio[724]: info: ControllerMpd::pushState Jul 26 19:15:26 volumio-ip46 volumio[724]: info: CoreCommandRouter::servicePushState Jul 26 19:15:26 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:15:26 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:15:26 volumio-ip46 volumio[724]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 26 19:15:26 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:15:26 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:15:26 volumio-ip46 volumio[724]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jul 26 19:15:26 volumio-ip46 volumio[724]: info: ControllerMpd::pushState Jul 26 19:15:26 volumio-ip46 volumio[724]: info: CoreCommandRouter::servicePushState Jul 26 19:15:26 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:15:26 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:15:26 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:15:26 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:15:26 volumio-ip46 volumio[724]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jul 26 19:15:26 volumio-ip46 volumio[724]: info: ------------------------------ 148ms Jul 26 19:15:26 volumio-ip46 volumio[724]: info: ------------------------------ 142ms Jul 26 19:15:26 volumio-ip46 volumio[724]: info: Jul 26 19:15:26 volumio-ip46 volumio[724]: ---------------------------- MPD announces system playlist update Jul 26 19:15:26 volumio-ip46 volumio[724]: info: Ignoring MPD Status Update Jul 26 19:15:26 volumio-ip46 volumio[724]: info: Jul 26 19:15:26 volumio-ip46 volumio[724]: ---------------------------- MPD announces system playlist update Jul 26 19:15:26 volumio-ip46 volumio[724]: info: Ignoring MPD Status Update Jul 26 19:15:26 volumio-ip46 volumio[724]: error: updateQueue error: null Jul 26 19:15:26 volumio-ip46 volumio[724]: info: ------------------------------ 151ms Jul 26 19:15:26 volumio-ip46 volumio[724]: info: sendMpdCommand playlistinfo took 136 milliseconds Jul 26 19:15:26 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseTrackInfo Jul 26 19:15:26 volumio-ip46 volumio[724]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 26 19:15:26 volumio-ip46 volumio[724]: info: ------------------------------ 195ms Jul 26 19:15:29 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand add "https://rsaradio-live.cast.addradio.de/rsaradio/live/mp3/high?ar-distributor=f0a1" Jul 26 19:15:29 volumio-ip46 volumio[724]: Upnp client error: Error: This socket has been ended by the other party Jul 26 19:15:29 volumio-ip46 volumio[724]: error: updateQueue error: null Jul 26 19:15:29 volumio-ip46 volumio[724]: error: updateQueue error: null Jul 26 19:15:29 volumio-ip46 volumio[724]: info: Jul 26 19:15:29 volumio-ip46 volumio[724]: ---------------------------- MPD announces system playlist update Jul 26 19:15:29 volumio-ip46 volumio[724]: info: Ignoring MPD Status Update Jul 26 19:15:29 volumio-ip46 volumio[724]: info: ------------------------------ 3654ms Jul 26 19:15:29 volumio-ip46 volumio[724]: info: ------------------------------ 3650ms Jul 26 19:15:29 volumio-ip46 volumio[724]: info: sendMpdCommand add "https://rsaradio-live.cast.addradio.de/rsaradio/live/mp3/high?ar-distributor=f0a1" took 20 milliseconds Jul 26 19:15:29 volumio-ip46 volumio[724]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 26 19:15:29 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand play Jul 26 19:15:29 volumio-ip46 volumio[724]: info: Jul 26 19:15:29 volumio-ip46 volumio[724]: ---------------------------- MPD announces system playlist update Jul 26 19:15:29 volumio-ip46 volumio[724]: info: Ignoring MPD Status Update Jul 26 19:15:29 volumio-ip46 volumio[724]: info: Jul 26 19:15:29 volumio-ip46 volumio[724]: ---------------------------- MPD announces system playlist update Jul 26 19:15:29 volumio-ip46 volumio[724]: info: Ignoring MPD Status Update Jul 26 19:15:29 volumio-ip46 volumio[724]: info: ------------------------------ 38ms Jul 26 19:15:29 volumio-ip46 volumio[724]: info: sendMpdCommand play took 29 milliseconds Jul 26 19:15:29 volumio-ip46 volumio[724]: info: ------------------------------ 21ms Jul 26 19:15:29 volumio-ip46 volumio[724]: info: ------------------------------ 20ms Jul 26 19:15:31 volumio-ip46 volumio[724]: info: Preload queue cleared Jul 26 19:15:31 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 26 19:15:31 volumio-ip46 volumio[724]: info: CoreStateMachine::ClearQueue Jul 26 19:15:31 volumio-ip46 volumio[724]: info: CoreStateMachine::stop Jul 26 19:15:31 volumio-ip46 volumio[724]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 26 19:15:31 volumio-ip46 volumio[724]: info: CorePlayQueue::clearPlayQueue Jul 26 19:15:31 volumio-ip46 volumio[724]: info: CorePlayQueue::saveQueue Jul 26 19:15:31 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushQueue Jul 26 19:15:31 volumio-ip46 volumio[724]: info: CoreStateMachine::addQueueItems Jul 26 19:15:31 volumio-ip46 volumio[724]: info: CorePlayQueue::addQueueItems Jul 26 19:15:31 volumio-ip46 volumio[724]: info: Preload queue cleared Jul 26 19:15:31 volumio-ip46 volumio[724]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s47800 Jul 26 19:15:31 volumio-ip46 volumio[724]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jul 26 19:15:31 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushQueue Jul 26 19:15:31 volumio-ip46 volumio[724]: info: CorePlayQueue::saveQueue Jul 26 19:15:31 volumio-ip46 volumio[724]: info: CoreStateMachine::updateTrackBlock Jul 26 19:15:31 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrackBlock Jul 26 19:15:31 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPlay Jul 26 19:15:31 volumio-ip46 volumio[724]: info: CoreStateMachine::play index 0 Jul 26 19:15:31 volumio-ip46 volumio[724]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 26 19:15:31 volumio-ip46 volumio[724]: info: CoreStateMachine::stop Jul 26 19:15:31 volumio-ip46 volumio[724]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 26 19:15:31 volumio-ip46 volumio[724]: info: CoreStateMachine::play index undefined Jul 26 19:15:31 volumio-ip46 volumio[724]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 26 19:15:31 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:15:31 volumio-ip46 volumio[724]: info: CoreStateMachine::startPlaybackTimer Jul 26 19:15:31 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:15:31 volumio-ip46 volumio[724]: info: [1722014131876] ControllerWebradio::clearAddPlayTrack Jul 26 19:15:31 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand stop Jul 26 19:15:31 volumio-ip46 volumio[724]: info: Jul 26 19:15:31 volumio-ip46 volumio[724]: ---------------------------- MPD announces state update: player Jul 26 19:15:31 volumio-ip46 volumio[724]: info: sendMpdCommand stop took 53 milliseconds Jul 26 19:15:31 volumio-ip46 volumio[724]: info: ControllerMpd::getState Jul 26 19:15:31 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand status Jul 26 19:15:31 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand clear Jul 26 19:15:31 volumio-ip46 volumio[724]: info: Jul 26 19:15:31 volumio-ip46 volumio[724]: ---------------------------- MPD announces state update: player Jul 26 19:15:31 volumio-ip46 volumio[724]: info: ControllerMpd::getState Jul 26 19:15:31 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand status Jul 26 19:15:31 volumio-ip46 volumio[724]: info: Jul 26 19:15:31 volumio-ip46 volumio[724]: ---------------------------- MPD announces state update: player Jul 26 19:15:31 volumio-ip46 volumio[724]: info: ControllerMpd::getState Jul 26 19:15:31 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand status Jul 26 19:15:31 volumio-ip46 volumio[724]: info: Jul 26 19:15:31 volumio-ip46 volumio[724]: ---------------------------- MPD announces system playlist update Jul 26 19:15:31 volumio-ip46 volumio[724]: info: Ignoring MPD Status Update Jul 26 19:15:31 volumio-ip46 volumio[724]: info: sendMpdCommand status took 30 milliseconds Jul 26 19:15:31 volumio-ip46 volumio[724]: info: sendMpdCommand clear took 30 milliseconds Jul 26 19:15:31 volumio-ip46 volumio[724]: info: sendMpdCommand status took 23 milliseconds Jul 26 19:15:31 volumio-ip46 volumio[724]: info: sendMpdCommand status took 16 milliseconds Jul 26 19:15:31 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseState Jul 26 19:15:31 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 26 19:15:31 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand load "https://rsaradio-live.cast.addradio.de/rsaradio/live/mp3/high?ar-distributor=f0a1" Jul 26 19:15:31 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseState Jul 26 19:15:31 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseState Jul 26 19:15:31 volumio-ip46 volumio[724]: info: ControllerMpd::pushState Jul 26 19:15:31 volumio-ip46 volumio[724]: info: CoreCommandRouter::servicePushState Jul 26 19:15:31 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:15:32 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:15:32 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:15:32 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:15:32 volumio-ip46 volumio[724]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jul 26 19:15:32 volumio-ip46 volumio[724]: info: ControllerMpd::pushState Jul 26 19:15:32 volumio-ip46 volumio[724]: info: CoreCommandRouter::servicePushState Jul 26 19:15:32 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:15:32 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:15:32 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:15:32 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:15:32 volumio-ip46 volumio[724]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jul 26 19:15:32 volumio-ip46 volumio[724]: info: ------------------------------ 161ms Jul 26 19:15:32 volumio-ip46 volumio[724]: info: ------------------------------ 155ms Jul 26 19:15:32 volumio-ip46 volumio[724]: info: Jul 26 19:15:32 volumio-ip46 volumio[724]: ---------------------------- MPD announces system playlist update Jul 26 19:15:32 volumio-ip46 volumio[724]: info: Ignoring MPD Status Update Jul 26 19:15:32 volumio-ip46 volumio[724]: info: Jul 26 19:15:32 volumio-ip46 volumio[724]: ---------------------------- MPD announces system playlist update Jul 26 19:15:32 volumio-ip46 volumio[724]: info: Ignoring MPD Status Update Jul 26 19:15:32 volumio-ip46 volumio[724]: error: updateQueue error: null Jul 26 19:15:32 volumio-ip46 volumio[724]: info: ------------------------------ 162ms Jul 26 19:15:32 volumio-ip46 volumio[724]: info: sendMpdCommand playlistinfo took 147 milliseconds Jul 26 19:15:32 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseTrackInfo Jul 26 19:15:32 volumio-ip46 volumio[724]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 26 19:15:32 volumio-ip46 volumio[724]: info: ------------------------------ 204ms Jul 26 19:15:34 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand add "https://rsaradio-live.cast.addradio.de/rsaradio/live/mp3/high?ar-distributor=f0a1" Jul 26 19:15:34 volumio-ip46 volumio[724]: Upnp client error: Error: This socket has been ended by the other party Jul 26 19:15:34 volumio-ip46 volumio[724]: error: updateQueue error: null Jul 26 19:15:34 volumio-ip46 volumio[724]: error: updateQueue error: null Jul 26 19:15:34 volumio-ip46 volumio[724]: info: Jul 26 19:15:34 volumio-ip46 volumio[724]: ---------------------------- MPD announces system playlist update Jul 26 19:15:34 volumio-ip46 volumio[724]: info: Ignoring MPD Status Update Jul 26 19:15:34 volumio-ip46 volumio[724]: info: ------------------------------ 2719ms Jul 26 19:15:34 volumio-ip46 volumio[724]: info: ------------------------------ 2715ms Jul 26 19:15:34 volumio-ip46 volumio[724]: info: sendMpdCommand add "https://rsaradio-live.cast.addradio.de/rsaradio/live/mp3/high?ar-distributor=f0a1" took 23 milliseconds Jul 26 19:15:34 volumio-ip46 volumio[724]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 26 19:15:34 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand play Jul 26 19:15:34 volumio-ip46 volumio[724]: info: Jul 26 19:15:34 volumio-ip46 volumio[724]: ---------------------------- MPD announces system playlist update Jul 26 19:15:34 volumio-ip46 volumio[724]: info: Ignoring MPD Status Update Jul 26 19:15:34 volumio-ip46 volumio[724]: info: Jul 26 19:15:34 volumio-ip46 volumio[724]: ---------------------------- MPD announces system playlist update Jul 26 19:15:34 volumio-ip46 volumio[724]: info: Ignoring MPD Status Update Jul 26 19:15:34 volumio-ip46 volumio[724]: info: ------------------------------ 37ms Jul 26 19:15:34 volumio-ip46 volumio[724]: info: sendMpdCommand play took 29 milliseconds Jul 26 19:15:34 volumio-ip46 volumio[724]: info: ------------------------------ 24ms Jul 26 19:15:34 volumio-ip46 volumio[724]: info: ------------------------------ 18ms Jul 26 19:15:35 volumio-ip46 volumio[724]: info: Preload queue cleared Jul 26 19:15:35 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 26 19:15:35 volumio-ip46 volumio[724]: info: CoreStateMachine::ClearQueue Jul 26 19:15:35 volumio-ip46 volumio[724]: info: CoreStateMachine::stop Jul 26 19:15:35 volumio-ip46 volumio[724]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 26 19:15:35 volumio-ip46 volumio[724]: info: CorePlayQueue::clearPlayQueue Jul 26 19:15:35 volumio-ip46 volumio[724]: info: CorePlayQueue::saveQueue Jul 26 19:15:35 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushQueue Jul 26 19:15:35 volumio-ip46 volumio[724]: info: CoreStateMachine::addQueueItems Jul 26 19:15:35 volumio-ip46 volumio[724]: info: CorePlayQueue::addQueueItems Jul 26 19:15:35 volumio-ip46 volumio[724]: info: Preload queue cleared Jul 26 19:15:35 volumio-ip46 volumio[724]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s55645 Jul 26 19:15:35 volumio-ip46 volumio[724]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jul 26 19:15:36 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushQueue Jul 26 19:15:36 volumio-ip46 volumio[724]: info: CorePlayQueue::saveQueue Jul 26 19:15:36 volumio-ip46 volumio[724]: info: CoreStateMachine::updateTrackBlock Jul 26 19:15:36 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrackBlock Jul 26 19:15:36 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPlay Jul 26 19:15:36 volumio-ip46 volumio[724]: info: CoreStateMachine::play index 0 Jul 26 19:15:36 volumio-ip46 volumio[724]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 26 19:15:36 volumio-ip46 volumio[724]: info: CoreStateMachine::stop Jul 26 19:15:36 volumio-ip46 volumio[724]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 26 19:15:36 volumio-ip46 volumio[724]: info: CoreStateMachine::play index undefined Jul 26 19:15:36 volumio-ip46 volumio[724]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 26 19:15:36 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:15:36 volumio-ip46 volumio[724]: info: CoreStateMachine::startPlaybackTimer Jul 26 19:15:36 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:15:36 volumio-ip46 volumio[724]: info: [1722014136257] ControllerWebradio::clearAddPlayTrack Jul 26 19:15:36 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand stop Jul 26 19:15:36 volumio-ip46 volumio[724]: info: sendMpdCommand stop took 73 milliseconds Jul 26 19:15:36 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand clear Jul 26 19:15:36 volumio-ip46 volumio[724]: info: Jul 26 19:15:36 volumio-ip46 volumio[724]: ---------------------------- MPD announces state update: player Jul 26 19:15:36 volumio-ip46 volumio[724]: info: Jul 26 19:15:36 volumio-ip46 volumio[724]: ---------------------------- MPD announces system playlist update Jul 26 19:15:36 volumio-ip46 volumio[724]: info: Ignoring MPD Status Update Jul 26 19:15:36 volumio-ip46 volumio[724]: info: ControllerMpd::getState Jul 26 19:15:36 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand status Jul 26 19:15:36 volumio-ip46 volumio[724]: info: sendMpdCommand clear took 26 milliseconds Jul 26 19:15:36 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand load "http://dispatcher.rndfnk.com/rbb/rbb888/live/mp3/mid?aggregator=tunein" Jul 26 19:15:36 volumio-ip46 volumio[724]: info: Jul 26 19:15:36 volumio-ip46 volumio[724]: ---------------------------- MPD announces state update: player Jul 26 19:15:36 volumio-ip46 volumio[724]: info: ControllerMpd::getState Jul 26 19:15:36 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand status Jul 26 19:15:36 volumio-ip46 volumio[724]: info: Jul 26 19:15:36 volumio-ip46 volumio[724]: ---------------------------- MPD announces state update: player Jul 26 19:15:36 volumio-ip46 volumio[724]: info: ControllerMpd::getState Jul 26 19:15:36 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand status Jul 26 19:15:36 volumio-ip46 volumio[724]: error: updateQueue error: null Jul 26 19:15:36 volumio-ip46 volumio[724]: info: sendMpdCommand status took 40 milliseconds Jul 26 19:15:36 volumio-ip46 volumio[724]: info: ------------------------------ 61ms Jul 26 19:15:36 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseState Jul 26 19:15:36 volumio-ip46 volumio[724]: info: ControllerMpd::pushState Jul 26 19:15:36 volumio-ip46 volumio[724]: info: CoreCommandRouter::servicePushState Jul 26 19:15:36 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:15:36 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:15:36 volumio-ip46 volumio[724]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 26 19:15:36 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:15:36 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:15:36 volumio-ip46 volumio[724]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jul 26 19:15:36 volumio-ip46 volumio[724]: info: ------------------------------ 119ms Jul 26 19:15:38 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand add "http://dispatcher.rndfnk.com/rbb/rbb888/live/mp3/mid?aggregator=tunein" Jul 26 19:15:38 volumio-ip46 volumio[724]: info: sendMpdCommand status took 1906 milliseconds Jul 26 19:15:38 volumio-ip46 volumio[724]: info: sendMpdCommand status took 1901 milliseconds Jul 26 19:15:38 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseState Jul 26 19:15:38 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseState Jul 26 19:15:38 volumio-ip46 volumio[724]: info: ControllerMpd::pushState Jul 26 19:15:38 volumio-ip46 volumio[724]: info: CoreCommandRouter::servicePushState Jul 26 19:15:38 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:15:38 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:15:38 volumio-ip46 volumio[724]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 26 19:15:38 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:15:38 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:15:38 volumio-ip46 volumio[724]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jul 26 19:15:38 volumio-ip46 volumio[724]: info: ControllerMpd::pushState Jul 26 19:15:38 volumio-ip46 volumio[724]: info: CoreCommandRouter::servicePushState Jul 26 19:15:38 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:15:38 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:15:38 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:15:38 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:15:38 volumio-ip46 volumio[724]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jul 26 19:15:38 volumio-ip46 volumio[724]: info: ------------------------------ 2017ms Jul 26 19:15:38 volumio-ip46 volumio[724]: info: ------------------------------ 2010ms Jul 26 19:15:38 volumio-ip46 volumio[724]: info: Jul 26 19:15:38 volumio-ip46 volumio[724]: ---------------------------- MPD announces system playlist update Jul 26 19:15:38 volumio-ip46 volumio[724]: info: Ignoring MPD Status Update Jul 26 19:15:38 volumio-ip46 volumio[724]: info: Jul 26 19:15:38 volumio-ip46 volumio[724]: ---------------------------- MPD announces system playlist update Jul 26 19:15:38 volumio-ip46 volumio[724]: info: Ignoring MPD Status Update Jul 26 19:15:38 volumio-ip46 volumio[724]: info: Jul 26 19:15:38 volumio-ip46 volumio[724]: ---------------------------- MPD announces system playlist update Jul 26 19:15:38 volumio-ip46 volumio[724]: info: Ignoring MPD Status Update Jul 26 19:15:38 volumio-ip46 volumio[724]: info: sendMpdCommand add "http://dispatcher.rndfnk.com/rbb/rbb888/live/mp3/mid?aggregator=tunein" took 227 milliseconds Jul 26 19:15:38 volumio-ip46 volumio[724]: info: ------------------------------ 104ms Jul 26 19:15:38 volumio-ip46 volumio[724]: info: ------------------------------ 98ms Jul 26 19:15:38 volumio-ip46 volumio[724]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 26 19:15:38 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand play Jul 26 19:15:38 volumio-ip46 volumio[724]: info: Jul 26 19:15:38 volumio-ip46 volumio[724]: ---------------------------- MPD announces system playlist update Jul 26 19:15:38 volumio-ip46 volumio[724]: info: Ignoring MPD Status Update Jul 26 19:15:38 volumio-ip46 volumio[724]: info: Jul 26 19:15:38 volumio-ip46 volumio[724]: ---------------------------- MPD announces system playlist update Jul 26 19:15:38 volumio-ip46 volumio[724]: info: Ignoring MPD Status Update Jul 26 19:15:38 volumio-ip46 volumio[724]: info: ------------------------------ 128ms Jul 26 19:15:38 volumio-ip46 volumio[724]: info: sendMpdCommand play took 108 milliseconds Jul 26 19:15:38 volumio-ip46 volumio[724]: info: ------------------------------ 100ms Jul 26 19:15:38 volumio-ip46 volumio[724]: info: ------------------------------ 89ms Jul 26 19:15:39 volumio-ip46 volumio[724]: info: Jul 26 19:15:39 volumio-ip46 volumio[724]: ---------------------------- MPD announces state update: player Jul 26 19:15:39 volumio-ip46 volumio[724]: info: ControllerMpd::getState Jul 26 19:15:39 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand status Jul 26 19:15:39 volumio-ip46 volumio[724]: info: Jul 26 19:15:39 volumio-ip46 volumio[724]: ---------------------------- MPD announces state update: player Jul 26 19:15:39 volumio-ip46 volumio[724]: info: ControllerMpd::getState Jul 26 19:15:39 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand status Jul 26 19:15:39 volumio-ip46 volumio[724]: info: Jul 26 19:15:39 volumio-ip46 volumio[724]: ---------------------------- MPD announces state update: player Jul 26 19:15:39 volumio-ip46 volumio[724]: info: ControllerMpd::getState Jul 26 19:15:39 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand status Jul 26 19:15:39 volumio-ip46 volumio[724]: info: sendMpdCommand status took 52 milliseconds Jul 26 19:15:39 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseState Jul 26 19:15:39 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 26 19:15:39 volumio-ip46 volumio[724]: info: sendMpdCommand status took 56 milliseconds Jul 26 19:15:39 volumio-ip46 volumio[724]: info: sendMpdCommand status took 53 milliseconds Jul 26 19:15:39 volumio-ip46 volumio[724]: info: sendMpdCommand playlistinfo took 8 milliseconds Jul 26 19:15:39 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseState Jul 26 19:15:39 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 26 19:15:39 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseState Jul 26 19:15:39 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 26 19:15:39 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseTrackInfo Jul 26 19:15:39 volumio-ip46 volumio[724]: info: ControllerMpd::pushState Jul 26 19:15:39 volumio-ip46 volumio[724]: info: CoreCommandRouter::servicePushState Jul 26 19:15:39 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:15:39 volumio-ip46 volumio[724]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mid?aggregator=tunein","artist":null,"album":null,"uri":"http://dispatcher.rndfnk.com/rbb/rbb888/live/mp3/mid?aggregator=tunein","trackType":""} Jul 26 19:15:39 volumio-ip46 volumio[724]: verbose: CURRENT POSITION 0 Jul 26 19:15:39 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState stateService play Jul 26 19:15:39 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState currentStatus stop Jul 26 19:15:39 volumio-ip46 volumio[724]: info: ------------------------------ 105ms Jul 26 19:15:39 volumio-ip46 volumio[724]: info: sendMpdCommand playlistinfo took 29 milliseconds Jul 26 19:15:39 volumio-ip46 volumio[724]: info: sendMpdCommand playlistinfo took 26 milliseconds Jul 26 19:15:39 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseTrackInfo Jul 26 19:15:39 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseTrackInfo Jul 26 19:15:39 volumio-ip46 volumio[724]: info: ControllerMpd::pushState Jul 26 19:15:39 volumio-ip46 volumio[724]: info: CoreCommandRouter::servicePushState Jul 26 19:15:39 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:15:39 volumio-ip46 volumio[724]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mid?aggregator=tunein","artist":null,"album":null,"uri":"http://dispatcher.rndfnk.com/rbb/rbb888/live/mp3/mid?aggregator=tunein","trackType":""} Jul 26 19:15:39 volumio-ip46 volumio[724]: verbose: CURRENT POSITION 0 Jul 26 19:15:39 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState stateService play Jul 26 19:15:39 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState currentStatus play Jul 26 19:15:39 volumio-ip46 volumio[724]: info: Received an update from plugin. extracting info from payload Jul 26 19:15:39 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:15:39 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:15:39 volumio-ip46 volumio[724]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 26 19:15:39 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:15:39 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:15:39 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:15:39 volumio-ip46 volumio[724]: info: ControllerMpd::pushState Jul 26 19:15:39 volumio-ip46 volumio[724]: info: CoreCommandRouter::servicePushState Jul 26 19:15:39 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:15:39 volumio-ip46 volumio[724]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mid?aggregator=tunein","artist":null,"album":null,"uri":"http://dispatcher.rndfnk.com/rbb/rbb888/live/mp3/mid?aggregator=tunein","trackType":""} Jul 26 19:15:39 volumio-ip46 volumio[724]: verbose: CURRENT POSITION 0 Jul 26 19:15:39 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState stateService play Jul 26 19:15:39 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState currentStatus play Jul 26 19:15:39 volumio-ip46 volumio[724]: info: Received an update from plugin. extracting info from payload Jul 26 19:15:39 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:15:39 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:15:39 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:15:39 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:15:39 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:15:39 volumio-ip46 volumio[724]: info: ------------------------------ 285ms Jul 26 19:15:39 volumio-ip46 volumio[724]: info: ------------------------------ 285ms Jul 26 19:15:39 volumio-ip46 volumio[724]: info: Jul 26 19:15:39 volumio-ip46 volumio[724]: ---------------------------- MPD announces system playlist update Jul 26 19:15:39 volumio-ip46 volumio[724]: info: Ignoring MPD Status Update Jul 26 19:15:39 volumio-ip46 volumio[724]: info: Jul 26 19:15:39 volumio-ip46 volumio[724]: ---------------------------- MPD announces state update: player Jul 26 19:15:39 volumio-ip46 volumio[724]: info: ControllerMpd::getState Jul 26 19:15:39 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand status Jul 26 19:15:39 volumio-ip46 volumio[724]: info: Jul 26 19:15:39 volumio-ip46 volumio[724]: ---------------------------- MPD announces system playlist update Jul 26 19:15:39 volumio-ip46 volumio[724]: info: Ignoring MPD Status Update Jul 26 19:15:39 volumio-ip46 volumio[724]: info: Jul 26 19:15:39 volumio-ip46 volumio[724]: ---------------------------- MPD announces state update: player Jul 26 19:15:39 volumio-ip46 volumio[724]: info: ControllerMpd::getState Jul 26 19:15:40 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand status Jul 26 19:15:40 volumio-ip46 volumio[724]: info: Jul 26 19:15:40 volumio-ip46 volumio[724]: ---------------------------- MPD announces system playlist update Jul 26 19:15:40 volumio-ip46 volumio[724]: info: Ignoring MPD Status Update Jul 26 19:15:40 volumio-ip46 volumio[724]: info: Jul 26 19:15:40 volumio-ip46 volumio[724]: ---------------------------- MPD announces state update: player Jul 26 19:15:40 volumio-ip46 volumio[724]: info: ControllerMpd::getState Jul 26 19:15:40 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand status Jul 26 19:15:40 volumio-ip46 volumio[724]: info: Jul 26 19:15:40 volumio-ip46 volumio[724]: ---------------------------- MPD announces system playlist update Jul 26 19:15:40 volumio-ip46 volumio[724]: info: Ignoring MPD Status Update Jul 26 19:15:40 volumio-ip46 volumio[724]: info: Jul 26 19:15:40 volumio-ip46 volumio[724]: ---------------------------- MPD announces state update: player Jul 26 19:15:40 volumio-ip46 volumio[724]: info: ------------------------------ 95ms Jul 26 19:15:40 volumio-ip46 volumio[724]: info: sendMpdCommand status took 71 milliseconds Jul 26 19:15:40 volumio-ip46 volumio[724]: info: ------------------------------ 73ms Jul 26 19:15:40 volumio-ip46 volumio[724]: info: ControllerMpd::getState Jul 26 19:15:40 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand status Jul 26 19:15:40 volumio-ip46 volumio[724]: info: sendMpdCommand status took 55 milliseconds Jul 26 19:15:40 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseState Jul 26 19:15:40 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 26 19:15:40 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseState Jul 26 19:15:40 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 26 19:15:40 volumio-ip46 volumio[724]: info: Jul 26 19:15:40 volumio-ip46 volumio[724]: ---------------------------- MPD announces system playlist update Jul 26 19:15:40 volumio-ip46 volumio[724]: info: Ignoring MPD Status Update Jul 26 19:15:40 volumio-ip46 volumio[724]: info: Jul 26 19:15:40 volumio-ip46 volumio[724]: ---------------------------- MPD announces system playlist update Jul 26 19:15:40 volumio-ip46 volumio[724]: info: Ignoring MPD Status Update Jul 26 19:15:40 volumio-ip46 volumio[724]: info: Jul 26 19:15:40 volumio-ip46 volumio[724]: ---------------------------- MPD announces state update: player Jul 26 19:15:40 volumio-ip46 volumio[724]: info: ControllerMpd::getState Jul 26 19:15:40 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand status Jul 26 19:15:40 volumio-ip46 volumio[724]: info: ------------------------------ 114ms Jul 26 19:15:40 volumio-ip46 volumio[724]: info: sendMpdCommand status took 106 milliseconds Jul 26 19:15:40 volumio-ip46 volumio[724]: info: ------------------------------ 104ms Jul 26 19:15:40 volumio-ip46 volumio[724]: info: sendMpdCommand status took 75 milliseconds Jul 26 19:15:40 volumio-ip46 volumio[724]: info: sendMpdCommand playlistinfo took 69 milliseconds Jul 26 19:15:40 volumio-ip46 volumio[724]: info: sendMpdCommand playlistinfo took 62 milliseconds Jul 26 19:15:40 volumio-ip46 volumio[724]: info: ------------------------------ 60ms Jul 26 19:15:40 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseState Jul 26 19:15:40 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 26 19:15:40 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseState Jul 26 19:15:40 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 26 19:15:40 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseTrackInfo Jul 26 19:15:40 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseTrackInfo Jul 26 19:15:40 volumio-ip46 volumio[724]: info: ControllerMpd::pushState Jul 26 19:15:40 volumio-ip46 volumio[724]: info: CoreCommandRouter::servicePushState Jul 26 19:15:40 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:15:40 volumio-ip46 volumio[724]: verbose: STATE SERVICE {"status":"play","position":0,"seek":536,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Musik: 80er/90er-Feier-Abend mit Sven Blümel","artist":"rbb88.8","album":null,"uri":"http://dispatcher.rndfnk.com/rbb/rbb888/live/mp3/mid?aggregator=tunein","trackType":""} Jul 26 19:15:40 volumio-ip46 volumio[724]: verbose: CURRENT POSITION 0 Jul 26 19:15:40 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState stateService play Jul 26 19:15:40 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState currentStatus play Jul 26 19:15:40 volumio-ip46 volumio[724]: info: Received an update from plugin. extracting info from payload Jul 26 19:15:40 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:15:40 volumio-ip46 volumio[724]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 26 19:15:40 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:15:40 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:15:40 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:15:40 volumio-ip46 volumio[724]: info: ControllerMpd::pushState Jul 26 19:15:40 volumio-ip46 volumio[724]: info: CoreCommandRouter::servicePushState Jul 26 19:15:40 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:15:40 volumio-ip46 volumio[724]: verbose: STATE SERVICE {"status":"play","position":0,"seek":981,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Musik: 80er/90er-Feier-Abend mit Sven Blümel","artist":"rbb88.8","album":null,"uri":"http://dispatcher.rndfnk.com/rbb/rbb888/live/mp3/mid?aggregator=tunein","trackType":""} Jul 26 19:15:40 volumio-ip46 volumio[724]: verbose: CURRENT POSITION 0 Jul 26 19:15:40 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState stateService play Jul 26 19:15:40 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState currentStatus play Jul 26 19:15:40 volumio-ip46 volumio[724]: info: Received an update from plugin. extracting info from payload Jul 26 19:15:40 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:15:40 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:15:40 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:15:40 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:15:40 volumio-ip46 volumio[724]: info: ------------------------------ 442ms Jul 26 19:15:40 volumio-ip46 volumio[724]: info: ------------------------------ 422ms Jul 26 19:15:40 volumio-ip46 volumio[724]: info: Jul 26 19:15:40 volumio-ip46 volumio[724]: ---------------------------- MPD announces state update: player Jul 26 19:15:40 volumio-ip46 volumio[724]: info: ControllerMpd::getState Jul 26 19:15:40 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand status Jul 26 19:15:40 volumio-ip46 volumio[724]: info: ------------------------------ 336ms Jul 26 19:15:40 volumio-ip46 volumio[724]: info: sendMpdCommand status took 319 milliseconds Jul 26 19:15:40 volumio-ip46 volumio[724]: info: sendMpdCommand playlistinfo took 283 milliseconds Jul 26 19:15:40 volumio-ip46 volumio[724]: info: sendMpdCommand playlistinfo took 284 milliseconds Jul 26 19:15:40 volumio-ip46 volumio[724]: info: sendMpdCommand status took 15 milliseconds Jul 26 19:15:40 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseState Jul 26 19:15:40 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 26 19:15:40 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseTrackInfo Jul 26 19:15:40 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseTrackInfo Jul 26 19:15:40 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseState Jul 26 19:15:40 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 26 19:15:40 volumio-ip46 volumio[724]: info: ControllerMpd::pushState Jul 26 19:15:40 volumio-ip46 volumio[724]: info: CoreCommandRouter::servicePushState Jul 26 19:15:40 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:15:40 volumio-ip46 volumio[724]: verbose: STATE SERVICE {"status":"play","position":0,"seek":981,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Musik: 80er/90er-Feier-Abend mit Sven Blümel","artist":"rbb88.8","album":null,"uri":"http://dispatcher.rndfnk.com/rbb/rbb888/live/mp3/mid?aggregator=tunein","trackType":""} Jul 26 19:15:40 volumio-ip46 volumio[724]: verbose: CURRENT POSITION 0 Jul 26 19:15:40 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState stateService play Jul 26 19:15:40 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState currentStatus play Jul 26 19:15:40 volumio-ip46 volumio[724]: info: Received an update from plugin. extracting info from payload Jul 26 19:15:40 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:15:40 volumio-ip46 volumio[724]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 26 19:15:40 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:15:40 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:15:40 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:15:40 volumio-ip46 volumio[724]: info: ControllerMpd::pushState Jul 26 19:15:40 volumio-ip46 volumio[724]: info: CoreCommandRouter::servicePushState Jul 26 19:15:40 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:15:40 volumio-ip46 volumio[724]: verbose: STATE SERVICE {"status":"play","position":0,"seek":981,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Musik: 80er/90er-Feier-Abend mit Sven Blümel","artist":"rbb88.8","album":null,"uri":"http://dispatcher.rndfnk.com/rbb/rbb888/live/mp3/mid?aggregator=tunein","trackType":""} Jul 26 19:15:40 volumio-ip46 volumio[724]: verbose: CURRENT POSITION 0 Jul 26 19:15:40 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState stateService play Jul 26 19:15:40 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState currentStatus play Jul 26 19:15:40 volumio-ip46 volumio[724]: info: Received an update from plugin. extracting info from payload Jul 26 19:15:40 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:15:40 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:15:40 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:15:40 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:15:40 volumio-ip46 volumio[724]: info: ------------------------------ 770ms Jul 26 19:15:40 volumio-ip46 volumio[724]: info: ------------------------------ 746ms Jul 26 19:15:40 volumio-ip46 volumio[724]: info: sendMpdCommand playlistinfo took 472 milliseconds Jul 26 19:15:40 volumio-ip46 volumio[724]: info: sendMpdCommand playlistinfo took 466 milliseconds Jul 26 19:15:40 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseTrackInfo Jul 26 19:15:40 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseTrackInfo Jul 26 19:15:40 volumio-ip46 volumio[724]: info: ControllerMpd::pushState Jul 26 19:15:40 volumio-ip46 volumio[724]: info: CoreCommandRouter::servicePushState Jul 26 19:15:40 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:15:40 volumio-ip46 volumio[724]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1107,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Musik: 80er/90er-Feier-Abend mit Sven Blümel","artist":"rbb88.8","album":null,"uri":"http://dispatcher.rndfnk.com/rbb/rbb888/live/mp3/mid?aggregator=tunein","trackType":""} Jul 26 19:15:40 volumio-ip46 volumio[724]: verbose: CURRENT POSITION 0 Jul 26 19:15:40 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState stateService play Jul 26 19:15:40 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState currentStatus play Jul 26 19:15:40 volumio-ip46 volumio[724]: info: Received an update from plugin. extracting info from payload Jul 26 19:15:40 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:15:40 volumio-ip46 volumio[724]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 26 19:15:40 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:15:40 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:15:40 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:15:40 volumio-ip46 volumio[724]: info: ControllerMpd::pushState Jul 26 19:15:40 volumio-ip46 volumio[724]: info: CoreCommandRouter::servicePushState Jul 26 19:15:40 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:15:40 volumio-ip46 volumio[724]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1359,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Musik: 80er/90er-Feier-Abend mit Sven Blümel","artist":"rbb88.8","album":null,"uri":"http://dispatcher.rndfnk.com/rbb/rbb888/live/mp3/mid?aggregator=tunein","trackType":""} Jul 26 19:15:40 volumio-ip46 volumio[724]: verbose: CURRENT POSITION 0 Jul 26 19:15:40 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState stateService play Jul 26 19:15:40 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState currentStatus play Jul 26 19:15:40 volumio-ip46 volumio[724]: info: Received an update from plugin. extracting info from payload Jul 26 19:15:40 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:15:40 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:15:41 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:15:41 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:15:41 volumio-ip46 volumio[724]: info: ------------------------------ 1062ms Jul 26 19:15:41 volumio-ip46 volumio[724]: info: ------------------------------ 750ms Jul 26 19:15:48 volumio-ip46 volumio[724]: info: Preload queue cleared Jul 26 19:15:48 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 26 19:15:48 volumio-ip46 volumio[724]: info: CoreStateMachine::ClearQueue Jul 26 19:15:48 volumio-ip46 volumio[724]: info: CoreStateMachine::stop Jul 26 19:15:48 volumio-ip46 volumio[724]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 26 19:15:48 volumio-ip46 volumio[724]: info: CoreStateMachine::stPlaybackTimer Jul 26 19:15:48 volumio-ip46 volumio[724]: info: CoreStateMachine::updateTrackBlock Jul 26 19:15:48 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrackBlock Jul 26 19:15:48 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:15:48 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:15:48 volumio-ip46 volumio[724]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 26 19:15:48 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:15:48 volumio-ip46 volumio[724]: info: CoreStateMachine::serviceStop Jul 26 19:15:48 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:15:48 volumio-ip46 volumio[724]: info: CoreCommandRouter::serviceStop Jul 26 19:15:48 volumio-ip46 volumio[724]: info: [1722014148271] ControllerWebradio::stop Jul 26 19:15:48 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand stop Jul 26 19:15:48 volumio-ip46 volumio[724]: info: CorePlayQueue::clearPlayQueue Jul 26 19:15:48 volumio-ip46 volumio[724]: info: CorePlayQueue::saveQueue Jul 26 19:15:48 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushQueue Jul 26 19:15:48 volumio-ip46 volumio[724]: info: CoreStateMachine::addQueueItems Jul 26 19:15:48 volumio-ip46 volumio[724]: info: CorePlayQueue::addQueueItems Jul 26 19:15:48 volumio-ip46 volumio[724]: info: Preload queue cleared Jul 26 19:15:48 volumio-ip46 volumio[724]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s107537 Jul 26 19:15:48 volumio-ip46 volumio[724]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jul 26 19:15:48 volumio-ip46 volumio[724]: info: Jul 26 19:15:48 volumio-ip46 volumio[724]: ---------------------------- MPD announces state update: player Jul 26 19:15:48 volumio-ip46 volumio[724]: info: sendMpdCommand stop took 152 milliseconds Jul 26 19:15:48 volumio-ip46 volumio[724]: info: ControllerMpd::getState Jul 26 19:15:48 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand status Jul 26 19:15:48 volumio-ip46 volumio[724]: info: Jul 26 19:15:48 volumio-ip46 volumio[724]: ---------------------------- MPD announces state update: player Jul 26 19:15:48 volumio-ip46 volumio[724]: info: ControllerMpd::getState Jul 26 19:15:48 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand status Jul 26 19:15:48 volumio-ip46 volumio[724]: info: Jul 26 19:15:48 volumio-ip46 volumio[724]: ---------------------------- MPD announces state update: player Jul 26 19:15:48 volumio-ip46 volumio[724]: info: ControllerMpd::getState Jul 26 19:15:48 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand status Jul 26 19:15:48 volumio-ip46 volumio[724]: info: sendMpdCommand status took 23 milliseconds Jul 26 19:15:48 volumio-ip46 volumio[724]: info: sendMpdCommand status took 17 milliseconds Jul 26 19:15:48 volumio-ip46 volumio[724]: info: sendMpdCommand status took 10 milliseconds Jul 26 19:15:48 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseState Jul 26 19:15:48 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 26 19:15:48 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseState Jul 26 19:15:48 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 26 19:15:48 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseState Jul 26 19:15:48 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 26 19:15:48 volumio-ip46 volumio[724]: info: sendMpdCommand playlistinfo took 27 milliseconds Jul 26 19:15:48 volumio-ip46 volumio[724]: info: sendMpdCommand playlistinfo took 27 milliseconds Jul 26 19:15:48 volumio-ip46 volumio[724]: info: sendMpdCommand playlistinfo took 26 milliseconds Jul 26 19:15:48 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseTrackInfo Jul 26 19:15:48 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseTrackInfo Jul 26 19:15:48 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseTrackInfo Jul 26 19:15:48 volumio-ip46 volumio[724]: info: ControllerMpd::pushState Jul 26 19:15:48 volumio-ip46 volumio[724]: info: CoreCommandRouter::servicePushState Jul 26 19:15:48 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:15:48 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:15:48 volumio-ip46 volumio[724]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 26 19:15:48 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:15:48 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:15:48 volumio-ip46 volumio[724]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Musik: 80er/90er-Feier-Abend mit Sven Blümel","artist":"rbb88.8","album":null,"uri":"http://dispatcher.rndfnk.com/rbb/rbb888/live/mp3/mid?aggregator=tunein","trackType":""} Jul 26 19:15:48 volumio-ip46 volumio[724]: verbose: CURRENT POSITION 0 Jul 26 19:15:48 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState stateService stop Jul 26 19:15:48 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState currentStatus stop Jul 26 19:15:48 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:15:48 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:15:48 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:15:48 volumio-ip46 volumio[724]: info: No code Jul 26 19:15:48 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:15:48 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:15:48 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:15:48 volumio-ip46 volumio[724]: info: ControllerMpd::pushState Jul 26 19:15:48 volumio-ip46 volumio[724]: info: CoreCommandRouter::servicePushState Jul 26 19:15:48 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:15:48 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:15:48 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:15:48 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:15:48 volumio-ip46 volumio[724]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Musik: 80er/90er-Feier-Abend mit Sven Blümel","artist":"rbb88.8","album":null,"uri":"http://dispatcher.rndfnk.com/rbb/rbb888/live/mp3/mid?aggregator=tunein","trackType":""} Jul 26 19:15:48 volumio-ip46 volumio[724]: verbose: CURRENT POSITION 0 Jul 26 19:15:48 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState stateService stop Jul 26 19:15:48 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState currentStatus stop Jul 26 19:15:48 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:15:48 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:15:48 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:15:48 volumio-ip46 volumio[724]: info: No code Jul 26 19:15:48 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:15:48 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:15:48 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:15:48 volumio-ip46 volumio[724]: info: ControllerMpd::pushState Jul 26 19:15:48 volumio-ip46 volumio[724]: info: CoreCommandRouter::servicePushState Jul 26 19:15:48 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:15:48 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:15:48 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:15:48 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:15:48 volumio-ip46 volumio[724]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Musik: 80er/90er-Feier-Abend mit Sven Blümel","artist":"rbb88.8","album":null,"uri":"http://dispatcher.rndfnk.com/rbb/rbb888/live/mp3/mid?aggregator=tunein","trackType":""} Jul 26 19:15:48 volumio-ip46 volumio[724]: verbose: CURRENT POSITION 0 Jul 26 19:15:48 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState stateService stop Jul 26 19:15:48 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState currentStatus stop Jul 26 19:15:48 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:15:48 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:15:48 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:15:48 volumio-ip46 volumio[724]: info: No code Jul 26 19:15:48 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:15:48 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:15:48 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:15:48 volumio-ip46 volumio[724]: info: ------------------------------ 468ms Jul 26 19:15:48 volumio-ip46 volumio[724]: info: ------------------------------ 463ms Jul 26 19:15:48 volumio-ip46 volumio[724]: info: ------------------------------ 460ms Jul 26 19:15:49 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushQueue Jul 26 19:15:49 volumio-ip46 volumio[724]: info: CorePlayQueue::saveQueue Jul 26 19:15:49 volumio-ip46 volumio[724]: info: CoreStateMachine::updateTrackBlock Jul 26 19:15:49 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrackBlock Jul 26 19:15:49 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPlay Jul 26 19:15:49 volumio-ip46 volumio[724]: info: CoreStateMachine::play index 0 Jul 26 19:15:49 volumio-ip46 volumio[724]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 26 19:15:49 volumio-ip46 volumio[724]: info: CoreStateMachine::stop Jul 26 19:15:49 volumio-ip46 volumio[724]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 26 19:15:49 volumio-ip46 volumio[724]: info: CoreStateMachine::play index undefined Jul 26 19:15:49 volumio-ip46 volumio[724]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 26 19:15:49 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:15:49 volumio-ip46 volumio[724]: info: CoreStateMachine::startPlaybackTimer Jul 26 19:15:49 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:15:49 volumio-ip46 volumio[724]: info: [1722014149226] ControllerWebradio::clearAddPlayTrack Jul 26 19:15:49 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand stop Jul 26 19:15:49 volumio-ip46 volumio[724]: info: sendMpdCommand stop took 14 milliseconds Jul 26 19:15:49 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand clear Jul 26 19:15:49 volumio-ip46 volumio[724]: info: Jul 26 19:15:49 volumio-ip46 volumio[724]: ---------------------------- MPD announces system playlist update Jul 26 19:15:49 volumio-ip46 volumio[724]: info: Ignoring MPD Status Update Jul 26 19:15:49 volumio-ip46 volumio[724]: info: sendMpdCommand clear took 12 milliseconds Jul 26 19:15:49 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand load "http://sc1.netstreamer.net:8120" Jul 26 19:15:49 volumio-ip46 volumio[724]: info: Jul 26 19:15:49 volumio-ip46 volumio[724]: ---------------------------- MPD announces system playlist update Jul 26 19:15:49 volumio-ip46 volumio[724]: info: Ignoring MPD Status Update Jul 26 19:15:49 volumio-ip46 volumio[724]: info: Jul 26 19:15:49 volumio-ip46 volumio[724]: ---------------------------- MPD announces system playlist update Jul 26 19:15:49 volumio-ip46 volumio[724]: info: Ignoring MPD Status Update Jul 26 19:15:49 volumio-ip46 volumio[724]: error: updateQueue error: null Jul 26 19:15:49 volumio-ip46 volumio[724]: info: ------------------------------ 31ms Jul 26 19:15:51 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand add "http://sc1.netstreamer.net:8120" Jul 26 19:15:51 volumio-ip46 volumio[724]: error: updateQueue error: null Jul 26 19:15:51 volumio-ip46 volumio[724]: error: updateQueue error: null Jul 26 19:15:51 volumio-ip46 volumio[724]: info: ------------------------------ 2391ms Jul 26 19:15:51 volumio-ip46 volumio[724]: info: ------------------------------ 2390ms Jul 26 19:15:51 volumio-ip46 volumio[724]: Upnp client error: Error: This socket has been ended by the other party Jul 26 19:15:51 volumio-ip46 volumio[724]: info: Jul 26 19:15:51 volumio-ip46 volumio[724]: ---------------------------- MPD announces system playlist update Jul 26 19:15:51 volumio-ip46 volumio[724]: info: Ignoring MPD Status Update Jul 26 19:15:51 volumio-ip46 volumio[724]: info: sendMpdCommand add "http://sc1.netstreamer.net:8120" took 30 milliseconds Jul 26 19:15:51 volumio-ip46 volumio[724]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 26 19:15:51 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand play Jul 26 19:15:51 volumio-ip46 volumio[724]: info: Jul 26 19:15:51 volumio-ip46 volumio[724]: ---------------------------- MPD announces system playlist update Jul 26 19:15:51 volumio-ip46 volumio[724]: info: Ignoring MPD Status Update Jul 26 19:15:51 volumio-ip46 volumio[724]: info: Jul 26 19:15:51 volumio-ip46 volumio[724]: ---------------------------- MPD announces system playlist update Jul 26 19:15:51 volumio-ip46 volumio[724]: info: Ignoring MPD Status Update Jul 26 19:15:51 volumio-ip46 volumio[724]: info: ------------------------------ 41ms Jul 26 19:15:51 volumio-ip46 volumio[724]: info: sendMpdCommand play took 33 milliseconds Jul 26 19:15:51 volumio-ip46 volumio[724]: info: ------------------------------ 29ms Jul 26 19:15:51 volumio-ip46 volumio[724]: info: ------------------------------ 27ms Jul 26 19:15:52 volumio-ip46 volumio[724]: info: Jul 26 19:15:52 volumio-ip46 volumio[724]: ---------------------------- MPD announces state update: player Jul 26 19:15:52 volumio-ip46 volumio[724]: info: ControllerMpd::getState Jul 26 19:15:52 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand status Jul 26 19:15:52 volumio-ip46 volumio[724]: info: Jul 26 19:15:52 volumio-ip46 volumio[724]: ---------------------------- MPD announces state update: player Jul 26 19:15:52 volumio-ip46 volumio[724]: info: ControllerMpd::getState Jul 26 19:15:52 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand status Jul 26 19:15:52 volumio-ip46 volumio[724]: info: Jul 26 19:15:52 volumio-ip46 volumio[724]: ---------------------------- MPD announces state update: player Jul 26 19:15:52 volumio-ip46 volumio[724]: info: ControllerMpd::getState Jul 26 19:15:52 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand status Jul 26 19:15:52 volumio-ip46 volumio[724]: info: Jul 26 19:15:52 volumio-ip46 volumio[724]: ---------------------------- MPD announces state update: player Jul 26 19:15:52 volumio-ip46 volumio[724]: info: ControllerMpd::getState Jul 26 19:15:52 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand status Jul 26 19:15:52 volumio-ip46 volumio[724]: info: sendMpdCommand status took 80 milliseconds Jul 26 19:15:52 volumio-ip46 volumio[724]: info: sendMpdCommand status took 58 milliseconds Jul 26 19:15:52 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseState Jul 26 19:15:52 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 26 19:15:52 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseState Jul 26 19:15:52 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 26 19:15:52 volumio-ip46 volumio[724]: info: Jul 26 19:15:52 volumio-ip46 volumio[724]: ---------------------------- MPD announces state update: player Jul 26 19:15:52 volumio-ip46 volumio[724]: info: ControllerMpd::getState Jul 26 19:15:52 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand status Jul 26 19:15:52 volumio-ip46 volumio[724]: info: Jul 26 19:15:52 volumio-ip46 volumio[724]: ---------------------------- MPD announces state update: player Jul 26 19:15:52 volumio-ip46 volumio[724]: info: ControllerMpd::getState Jul 26 19:15:52 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand status Jul 26 19:15:52 volumio-ip46 volumio[724]: info: sendMpdCommand status took 83 milliseconds Jul 26 19:15:52 volumio-ip46 volumio[724]: info: sendMpdCommand status took 77 milliseconds Jul 26 19:15:52 volumio-ip46 volumio[724]: info: sendMpdCommand playlistinfo took 49 milliseconds Jul 26 19:15:52 volumio-ip46 volumio[724]: info: sendMpdCommand playlistinfo took 45 milliseconds Jul 26 19:15:52 volumio-ip46 volumio[724]: info: sendMpdCommand status took 34 milliseconds Jul 26 19:15:52 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseState Jul 26 19:15:52 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 26 19:15:52 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseState Jul 26 19:15:52 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 26 19:15:52 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseTrackInfo Jul 26 19:15:52 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseTrackInfo Jul 26 19:15:52 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseState Jul 26 19:15:52 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 26 19:15:52 volumio-ip46 volumio[724]: info: ControllerMpd::pushState Jul 26 19:15:52 volumio-ip46 volumio[724]: info: CoreCommandRouter::servicePushState Jul 26 19:15:52 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:15:52 volumio-ip46 volumio[724]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"sc1.netstreamer.net:8120","artist":null,"album":null,"uri":"http://sc1.netstreamer.net:8120","trackType":"net:8120"} Jul 26 19:15:52 volumio-ip46 volumio[724]: verbose: CURRENT POSITION 0 Jul 26 19:15:52 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState stateService play Jul 26 19:15:52 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState currentStatus stop Jul 26 19:15:52 volumio-ip46 volumio[724]: info: ControllerMpd::pushState Jul 26 19:15:52 volumio-ip46 volumio[724]: info: CoreCommandRouter::servicePushState Jul 26 19:15:52 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:15:52 volumio-ip46 volumio[724]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"sc1.netstreamer.net:8120","artist":null,"album":null,"uri":"http://sc1.netstreamer.net:8120","trackType":"net:8120"} Jul 26 19:15:52 volumio-ip46 volumio[724]: verbose: CURRENT POSITION 0 Jul 26 19:15:52 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState stateService play Jul 26 19:15:52 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState currentStatus play Jul 26 19:15:52 volumio-ip46 volumio[724]: info: Received an update from plugin. extracting info from payload Jul 26 19:15:52 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:15:52 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:15:52 volumio-ip46 volumio[724]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 26 19:15:52 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:15:52 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:15:52 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:15:52 volumio-ip46 volumio[724]: info: ------------------------------ 297ms Jul 26 19:15:52 volumio-ip46 volumio[724]: info: ------------------------------ 361ms Jul 26 19:15:53 volumio-ip46 volumio[724]: info: Jul 26 19:15:53 volumio-ip46 volumio[724]: ---------------------------- MPD announces system playlist update Jul 26 19:15:53 volumio-ip46 volumio[724]: info: Ignoring MPD Status Update Jul 26 19:15:53 volumio-ip46 volumio[724]: info: Jul 26 19:15:53 volumio-ip46 volumio[724]: ---------------------------- MPD announces state update: player Jul 26 19:15:53 volumio-ip46 volumio[724]: info: ControllerMpd::getState Jul 26 19:15:53 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand status Jul 26 19:15:53 volumio-ip46 volumio[724]: info: Jul 26 19:15:53 volumio-ip46 volumio[724]: ---------------------------- MPD announces system playlist update Jul 26 19:15:53 volumio-ip46 volumio[724]: info: Ignoring MPD Status Update Jul 26 19:15:53 volumio-ip46 volumio[724]: info: Jul 26 19:15:53 volumio-ip46 volumio[724]: ---------------------------- MPD announces state update: player Jul 26 19:15:53 volumio-ip46 volumio[724]: info: ControllerMpd::getState Jul 26 19:15:53 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand status Jul 26 19:15:53 volumio-ip46 volumio[724]: info: Jul 26 19:15:53 volumio-ip46 volumio[724]: ---------------------------- MPD announces system playlist update Jul 26 19:15:53 volumio-ip46 volumio[724]: info: Ignoring MPD Status Update Jul 26 19:15:53 volumio-ip46 volumio[724]: info: Jul 26 19:15:53 volumio-ip46 volumio[724]: ---------------------------- MPD announces state update: player Jul 26 19:15:53 volumio-ip46 volumio[724]: info: Jul 26 19:15:53 volumio-ip46 volumio[724]: ---------------------------- MPD announces system playlist update Jul 26 19:15:53 volumio-ip46 volumio[724]: info: Ignoring MPD Status Update Jul 26 19:15:53 volumio-ip46 volumio[724]: info: Jul 26 19:15:53 volumio-ip46 volumio[724]: ---------------------------- MPD announces state update: player Jul 26 19:15:53 volumio-ip46 volumio[724]: info: sendMpdCommand status took 329 milliseconds Jul 26 19:15:53 volumio-ip46 volumio[724]: info: ControllerMpd::getState Jul 26 19:15:53 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand status Jul 26 19:15:53 volumio-ip46 volumio[724]: info: sendMpdCommand playlistinfo took 311 milliseconds Jul 26 19:15:53 volumio-ip46 volumio[724]: info: ControllerMpd::getState Jul 26 19:15:53 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand status Jul 26 19:15:53 volumio-ip46 volumio[724]: info: sendMpdCommand playlistinfo took 315 milliseconds Jul 26 19:15:53 volumio-ip46 volumio[724]: info: sendMpdCommand playlistinfo took 305 milliseconds Jul 26 19:15:53 volumio-ip46 volumio[724]: info: ------------------------------ 91ms Jul 26 19:15:53 volumio-ip46 volumio[724]: info: sendMpdCommand status took 75 milliseconds Jul 26 19:15:53 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseState Jul 26 19:15:53 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 26 19:15:53 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseTrackInfo Jul 26 19:15:53 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseTrackInfo Jul 26 19:15:53 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseTrackInfo Jul 26 19:15:53 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseState Jul 26 19:15:53 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 26 19:15:53 volumio-ip46 volumio[724]: info: ControllerMpd::pushState Jul 26 19:15:53 volumio-ip46 volumio[724]: info: CoreCommandRouter::servicePushState Jul 26 19:15:53 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:15:53 volumio-ip46 volumio[724]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Clint Mansell - Lux Aeterna (OST 'Requiem For A Dream')","artist":"Klassik1 - Das Radio mit den besten Noten","album":null,"uri":"http://sc1.netstreamer.net:8120","trackType":"net:8120"} Jul 26 19:15:53 volumio-ip46 volumio[724]: verbose: CURRENT POSITION 0 Jul 26 19:15:53 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState stateService play Jul 26 19:15:53 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState currentStatus play Jul 26 19:15:53 volumio-ip46 volumio[724]: info: Received an update from plugin. extracting info from payload Jul 26 19:15:53 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:15:53 volumio-ip46 volumio[724]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 26 19:15:53 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:15:53 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:15:53 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:15:53 volumio-ip46 volumio[724]: info: ControllerMpd::pushState Jul 26 19:15:53 volumio-ip46 volumio[724]: info: CoreCommandRouter::servicePushState Jul 26 19:15:53 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:15:53 volumio-ip46 volumio[724]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Clint Mansell - Lux Aeterna (OST 'Requiem For A Dream')","artist":"Klassik1 - Das Radio mit den besten Noten","album":null,"uri":"http://sc1.netstreamer.net:8120","trackType":"net:8120"} Jul 26 19:15:53 volumio-ip46 volumio[724]: verbose: CURRENT POSITION 0 Jul 26 19:15:53 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState stateService play Jul 26 19:15:53 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState currentStatus play Jul 26 19:15:53 volumio-ip46 volumio[724]: info: Received an update from plugin. extracting info from payload Jul 26 19:15:53 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:15:53 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:15:53 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:15:53 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:15:53 volumio-ip46 volumio[724]: info: ControllerMpd::pushState Jul 26 19:15:53 volumio-ip46 volumio[724]: info: CoreCommandRouter::servicePushState Jul 26 19:15:53 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:15:53 volumio-ip46 volumio[724]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Clint Mansell - Lux Aeterna (OST 'Requiem For A Dream')","artist":"Klassik1 - Das Radio mit den besten Noten","album":null,"uri":"http://sc1.netstreamer.net:8120","trackType":"net:8120"} Jul 26 19:15:53 volumio-ip46 volumio[724]: verbose: CURRENT POSITION 0 Jul 26 19:15:53 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState stateService play Jul 26 19:15:53 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState currentStatus play Jul 26 19:15:53 volumio-ip46 volumio[724]: info: Received an update from plugin. extracting info from payload Jul 26 19:15:53 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:15:53 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:15:53 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:15:53 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:15:53 volumio-ip46 volumio[724]: info: ------------------------------ 1005ms Jul 26 19:15:53 volumio-ip46 volumio[724]: info: ------------------------------ 991ms Jul 26 19:15:53 volumio-ip46 volumio[724]: info: ------------------------------ 945ms Jul 26 19:15:53 volumio-ip46 volumio[724]: info: Jul 26 19:15:53 volumio-ip46 volumio[724]: ---------------------------- MPD announces system playlist update Jul 26 19:15:53 volumio-ip46 volumio[724]: info: Ignoring MPD Status Update Jul 26 19:15:53 volumio-ip46 volumio[724]: info: Jul 26 19:15:53 volumio-ip46 volumio[724]: ---------------------------- MPD announces state update: player Jul 26 19:15:53 volumio-ip46 volumio[724]: info: ControllerMpd::getState Jul 26 19:15:53 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand status Jul 26 19:15:53 volumio-ip46 volumio[724]: info: Jul 26 19:15:53 volumio-ip46 volumio[724]: ---------------------------- MPD announces system playlist update Jul 26 19:15:53 volumio-ip46 volumio[724]: info: Ignoring MPD Status Update Jul 26 19:15:53 volumio-ip46 volumio[724]: info: Jul 26 19:15:53 volumio-ip46 volumio[724]: ---------------------------- MPD announces state update: player Jul 26 19:15:53 volumio-ip46 volumio[724]: info: ControllerMpd::getState Jul 26 19:15:53 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand status Jul 26 19:15:53 volumio-ip46 volumio[724]: info: ------------------------------ 802ms Jul 26 19:15:53 volumio-ip46 volumio[724]: info: sendMpdCommand status took 786 milliseconds Jul 26 19:15:53 volumio-ip46 volumio[724]: info: ------------------------------ 784ms Jul 26 19:15:53 volumio-ip46 volumio[724]: info: sendMpdCommand status took 753 milliseconds Jul 26 19:15:53 volumio-ip46 volumio[724]: info: ------------------------------ 776ms Jul 26 19:15:53 volumio-ip46 volumio[724]: info: sendMpdCommand status took 748 milliseconds Jul 26 19:15:53 volumio-ip46 volumio[724]: info: sendMpdCommand playlistinfo took 731 milliseconds Jul 26 19:15:53 volumio-ip46 volumio[724]: info: sendMpdCommand playlistinfo took 713 milliseconds Jul 26 19:15:53 volumio-ip46 volumio[724]: info: ------------------------------ 52ms Jul 26 19:15:53 volumio-ip46 volumio[724]: info: sendMpdCommand status took 44 milliseconds Jul 26 19:15:53 volumio-ip46 volumio[724]: info: ------------------------------ 42ms Jul 26 19:15:53 volumio-ip46 volumio[724]: info: sendMpdCommand status took 33 milliseconds Jul 26 19:15:53 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseState Jul 26 19:15:53 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 26 19:15:53 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseState Jul 26 19:15:53 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 26 19:15:53 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseState Jul 26 19:15:53 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 26 19:15:53 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseTrackInfo Jul 26 19:15:53 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseTrackInfo Jul 26 19:15:53 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseState Jul 26 19:15:53 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 26 19:15:53 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseState Jul 26 19:15:53 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 26 19:15:53 volumio-ip46 volumio[724]: info: ControllerMpd::pushState Jul 26 19:15:53 volumio-ip46 volumio[724]: info: CoreCommandRouter::servicePushState Jul 26 19:15:53 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:15:53 volumio-ip46 volumio[724]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Clint Mansell - Lux Aeterna (OST 'Requiem For A Dream')","artist":"Klassik1 - Das Radio mit den besten Noten","album":null,"uri":"http://sc1.netstreamer.net:8120","trackType":"net:8120"} Jul 26 19:15:53 volumio-ip46 volumio[724]: verbose: CURRENT POSITION 0 Jul 26 19:15:53 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState stateService play Jul 26 19:15:53 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState currentStatus play Jul 26 19:15:53 volumio-ip46 volumio[724]: info: Received an update from plugin. extracting info from payload Jul 26 19:15:53 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:15:53 volumio-ip46 volumio[724]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 26 19:15:53 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:15:53 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:15:53 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:15:53 volumio-ip46 volumio[724]: info: ControllerMpd::pushState Jul 26 19:15:53 volumio-ip46 volumio[724]: info: CoreCommandRouter::servicePushState Jul 26 19:15:53 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:15:53 volumio-ip46 volumio[724]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1087,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Clint Mansell - Lux Aeterna (OST 'Requiem For A Dream')","artist":"Klassik1 - Das Radio mit den besten Noten","album":null,"uri":"http://sc1.netstreamer.net:8120","trackType":"net:8120"} Jul 26 19:15:53 volumio-ip46 volumio[724]: verbose: CURRENT POSITION 0 Jul 26 19:15:53 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState stateService play Jul 26 19:15:53 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState currentStatus play Jul 26 19:15:53 volumio-ip46 volumio[724]: info: Received an update from plugin. extracting info from payload Jul 26 19:15:53 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:15:53 volumio-ip46 volumio[724]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 26 19:15:53 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:15:53 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:15:53 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:15:54 volumio-ip46 volumio[724]: info: ------------------------------ 1379ms Jul 26 19:15:54 volumio-ip46 volumio[724]: info: ------------------------------ 1097ms Jul 26 19:15:54 volumio-ip46 volumio[724]: info: sendMpdCommand playlistinfo took 277 milliseconds Jul 26 19:15:54 volumio-ip46 volumio[724]: info: sendMpdCommand playlistinfo took 286 milliseconds Jul 26 19:15:54 volumio-ip46 volumio[724]: info: sendMpdCommand playlistinfo took 287 milliseconds Jul 26 19:15:54 volumio-ip46 volumio[724]: info: sendMpdCommand playlistinfo took 281 milliseconds Jul 26 19:15:54 volumio-ip46 volumio[724]: info: sendMpdCommand playlistinfo took 281 milliseconds Jul 26 19:15:54 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseTrackInfo Jul 26 19:15:54 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseTrackInfo Jul 26 19:15:54 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseTrackInfo Jul 26 19:15:54 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseTrackInfo Jul 26 19:15:54 volumio-ip46 volumio[724]: info: ControllerMpd::pushState Jul 26 19:15:54 volumio-ip46 volumio[724]: info: CoreCommandRouter::servicePushState Jul 26 19:15:54 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:15:54 volumio-ip46 volumio[724]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1087,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Clint Mansell - Lux Aeterna (OST 'Requiem For A Dream')","artist":"Klassik1 - Das Radio mit den besten Noten","album":null,"uri":"http://sc1.netstreamer.net:8120","trackType":"net:8120"} Jul 26 19:15:54 volumio-ip46 volumio[724]: verbose: CURRENT POSITION 0 Jul 26 19:15:54 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState stateService play Jul 26 19:15:54 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState currentStatus play Jul 26 19:15:54 volumio-ip46 volumio[724]: info: Received an update from plugin. extracting info from payload Jul 26 19:15:54 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:15:54 volumio-ip46 volumio[724]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 26 19:15:54 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:15:54 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:15:54 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:15:54 volumio-ip46 volumio[724]: info: ControllerMpd::pushState Jul 26 19:15:54 volumio-ip46 volumio[724]: info: CoreCommandRouter::servicePushState Jul 26 19:15:54 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:15:54 volumio-ip46 volumio[724]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1087,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Clint Mansell - Lux Aeterna (OST 'Requiem For A Dream')","artist":"Klassik1 - Das Radio mit den besten Noten","album":null,"uri":"http://sc1.netstreamer.net:8120","trackType":"net:8120"} Jul 26 19:15:54 volumio-ip46 volumio[724]: verbose: CURRENT POSITION 0 Jul 26 19:15:54 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState stateService play Jul 26 19:15:54 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState currentStatus play Jul 26 19:15:54 volumio-ip46 volumio[724]: info: Received an update from plugin. extracting info from payload Jul 26 19:15:54 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:15:54 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:15:54 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:15:54 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:15:54 volumio-ip46 volumio[724]: info: ControllerMpd::pushState Jul 26 19:15:54 volumio-ip46 volumio[724]: info: CoreCommandRouter::servicePushState Jul 26 19:15:54 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:15:54 volumio-ip46 volumio[724]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1087,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Clint Mansell - Lux Aeterna (OST 'Requiem For A Dream')","artist":"Klassik1 - Das Radio mit den besten Noten","album":null,"uri":"http://sc1.netstreamer.net:8120","trackType":"net:8120"} Jul 26 19:15:54 volumio-ip46 volumio[724]: verbose: CURRENT POSITION 0 Jul 26 19:15:54 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState stateService play Jul 26 19:15:54 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState currentStatus play Jul 26 19:15:54 volumio-ip46 volumio[724]: info: Received an update from plugin. extracting info from payload Jul 26 19:15:54 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:15:54 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:15:54 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:15:54 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:15:54 volumio-ip46 volumio[724]: info: ControllerMpd::pushState Jul 26 19:15:54 volumio-ip46 volumio[724]: info: CoreCommandRouter::servicePushState Jul 26 19:15:54 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:15:54 volumio-ip46 volumio[724]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1786,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Clint Mansell - Lux Aeterna (OST 'Requiem For A Dream')","artist":"Klassik1 - Das Radio mit den besten Noten","album":null,"uri":"http://sc1.netstreamer.net:8120","trackType":"net:8120"} Jul 26 19:15:54 volumio-ip46 volumio[724]: verbose: CURRENT POSITION 0 Jul 26 19:15:54 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState stateService play Jul 26 19:15:54 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState currentStatus play Jul 26 19:15:54 volumio-ip46 volumio[724]: info: Received an update from plugin. extracting info from payload Jul 26 19:15:54 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:15:54 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:15:54 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:15:54 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:15:54 volumio-ip46 volumio[724]: info: ControllerMpd::pushState Jul 26 19:15:54 volumio-ip46 volumio[724]: info: CoreCommandRouter::servicePushState Jul 26 19:15:54 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:15:54 volumio-ip46 volumio[724]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1786,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Clint Mansell - Lux Aeterna (OST 'Requiem For A Dream')","artist":"Klassik1 - Das Radio mit den besten Noten","album":null,"uri":"http://sc1.netstreamer.net:8120","trackType":"net:8120"} Jul 26 19:15:54 volumio-ip46 volumio[724]: verbose: CURRENT POSITION 0 Jul 26 19:15:54 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState stateService play Jul 26 19:15:54 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState currentStatus play Jul 26 19:15:54 volumio-ip46 volumio[724]: info: Received an update from plugin. extracting info from payload Jul 26 19:15:54 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:15:54 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:15:54 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:15:54 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:15:54 volumio-ip46 volumio[724]: info: ------------------------------ 1764ms Jul 26 19:15:54 volumio-ip46 volumio[724]: info: ------------------------------ 1758ms Jul 26 19:15:54 volumio-ip46 volumio[724]: info: ------------------------------ 1752ms Jul 26 19:15:54 volumio-ip46 volumio[724]: info: ------------------------------ 1026ms Jul 26 19:15:54 volumio-ip46 volumio[724]: info: ------------------------------ 1017ms Jul 26 19:16:00 volumio-ip46 volumio[724]: info: Preload queue cleared Jul 26 19:16:00 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 26 19:16:00 volumio-ip46 volumio[724]: info: CoreStateMachine::ClearQueue Jul 26 19:16:00 volumio-ip46 volumio[724]: info: CoreStateMachine::stop Jul 26 19:16:00 volumio-ip46 volumio[724]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 26 19:16:00 volumio-ip46 volumio[724]: info: CoreStateMachine::stPlaybackTimer Jul 26 19:16:00 volumio-ip46 volumio[724]: info: CoreStateMachine::updateTrackBlock Jul 26 19:16:00 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrackBlock Jul 26 19:16:00 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:16:00 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:16:00 volumio-ip46 volumio[724]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 26 19:16:00 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:16:00 volumio-ip46 volumio[724]: info: CoreStateMachine::serviceStop Jul 26 19:16:00 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:16:00 volumio-ip46 volumio[724]: info: CoreCommandRouter::serviceStop Jul 26 19:16:00 volumio-ip46 volumio[724]: info: [1722014160980] ControllerWebradio::stop Jul 26 19:16:00 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand stop Jul 26 19:16:00 volumio-ip46 volumio[724]: info: CorePlayQueue::clearPlayQueue Jul 26 19:16:00 volumio-ip46 volumio[724]: info: CorePlayQueue::saveQueue Jul 26 19:16:00 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushQueue Jul 26 19:16:00 volumio-ip46 volumio[724]: info: CoreStateMachine::addQueueItems Jul 26 19:16:00 volumio-ip46 volumio[724]: info: CorePlayQueue::addQueueItems Jul 26 19:16:00 volumio-ip46 volumio[724]: info: Preload queue cleared Jul 26 19:16:00 volumio-ip46 volumio[724]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s25224 Jul 26 19:16:00 volumio-ip46 volumio[724]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jul 26 19:16:01 volumio-ip46 volumio[724]: info: Jul 26 19:16:01 volumio-ip46 volumio[724]: ---------------------------- MPD announces state update: player Jul 26 19:16:01 volumio-ip46 volumio[724]: info: sendMpdCommand stop took 146 milliseconds Jul 26 19:16:01 volumio-ip46 volumio[724]: info: ControllerMpd::getState Jul 26 19:16:01 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand status Jul 26 19:16:01 volumio-ip46 volumio[724]: info: Jul 26 19:16:01 volumio-ip46 volumio[724]: ---------------------------- MPD announces state update: player Jul 26 19:16:01 volumio-ip46 volumio[724]: info: ControllerMpd::getState Jul 26 19:16:01 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand status Jul 26 19:16:01 volumio-ip46 volumio[724]: info: Jul 26 19:16:01 volumio-ip46 volumio[724]: ---------------------------- MPD announces state update: player Jul 26 19:16:01 volumio-ip46 volumio[724]: info: ControllerMpd::getState Jul 26 19:16:01 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand status Jul 26 19:16:01 volumio-ip46 volumio[724]: info: sendMpdCommand status took 23 milliseconds Jul 26 19:16:01 volumio-ip46 volumio[724]: info: sendMpdCommand status took 17 milliseconds Jul 26 19:16:01 volumio-ip46 volumio[724]: info: sendMpdCommand status took 10 milliseconds Jul 26 19:16:01 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseState Jul 26 19:16:01 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 26 19:16:01 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseState Jul 26 19:16:01 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 26 19:16:01 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseState Jul 26 19:16:01 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 26 19:16:01 volumio-ip46 volumio[724]: info: sendMpdCommand playlistinfo took 26 milliseconds Jul 26 19:16:01 volumio-ip46 volumio[724]: info: sendMpdCommand playlistinfo took 25 milliseconds Jul 26 19:16:01 volumio-ip46 volumio[724]: info: sendMpdCommand playlistinfo took 24 milliseconds Jul 26 19:16:01 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseTrackInfo Jul 26 19:16:01 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseTrackInfo Jul 26 19:16:01 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseTrackInfo Jul 26 19:16:01 volumio-ip46 volumio[724]: info: ControllerMpd::pushState Jul 26 19:16:01 volumio-ip46 volumio[724]: info: CoreCommandRouter::servicePushState Jul 26 19:16:01 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:16:01 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:16:01 volumio-ip46 volumio[724]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 26 19:16:01 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:16:01 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:16:01 volumio-ip46 volumio[724]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Clint Mansell - Lux Aeterna (OST 'Requiem For A Dream')","artist":"Klassik1 - Das Radio mit den besten Noten","album":null,"uri":"http://sc1.netstreamer.net:8120","trackType":"net:8120"} Jul 26 19:16:01 volumio-ip46 volumio[724]: verbose: CURRENT POSITION 0 Jul 26 19:16:01 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState stateService stop Jul 26 19:16:01 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState currentStatus stop Jul 26 19:16:01 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:16:01 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:16:01 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:16:01 volumio-ip46 volumio[724]: info: No code Jul 26 19:16:01 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:16:01 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:16:01 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:16:01 volumio-ip46 volumio[724]: info: ControllerMpd::pushState Jul 26 19:16:01 volumio-ip46 volumio[724]: info: CoreCommandRouter::servicePushState Jul 26 19:16:01 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:16:01 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:16:01 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:16:01 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:16:01 volumio-ip46 volumio[724]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Clint Mansell - Lux Aeterna (OST 'Requiem For A Dream')","artist":"Klassik1 - Das Radio mit den besten Noten","album":null,"uri":"http://sc1.netstreamer.net:8120","trackType":"net:8120"} Jul 26 19:16:01 volumio-ip46 volumio[724]: verbose: CURRENT POSITION 0 Jul 26 19:16:01 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState stateService stop Jul 26 19:16:01 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState currentStatus stop Jul 26 19:16:01 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:16:01 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:16:01 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:16:01 volumio-ip46 volumio[724]: info: No code Jul 26 19:16:01 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:16:01 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:16:01 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:16:01 volumio-ip46 volumio[724]: info: ControllerMpd::pushState Jul 26 19:16:01 volumio-ip46 volumio[724]: info: CoreCommandRouter::servicePushState Jul 26 19:16:01 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:16:01 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:16:01 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:16:01 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:16:01 volumio-ip46 volumio[724]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Clint Mansell - Lux Aeterna (OST 'Requiem For A Dream')","artist":"Klassik1 - Das Radio mit den besten Noten","album":null,"uri":"http://sc1.netstreamer.net:8120","trackType":"net:8120"} Jul 26 19:16:01 volumio-ip46 volumio[724]: verbose: CURRENT POSITION 0 Jul 26 19:16:01 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState stateService stop Jul 26 19:16:01 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState currentStatus stop Jul 26 19:16:01 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:16:01 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:16:01 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:16:01 volumio-ip46 volumio[724]: info: No code Jul 26 19:16:01 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:16:01 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:16:01 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:16:01 volumio-ip46 volumio[724]: info: ------------------------------ 457ms Jul 26 19:16:01 volumio-ip46 volumio[724]: info: ------------------------------ 451ms Jul 26 19:16:01 volumio-ip46 volumio[724]: info: ------------------------------ 447ms Jul 26 19:16:02 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushQueue Jul 26 19:16:02 volumio-ip46 volumio[724]: info: CorePlayQueue::saveQueue Jul 26 19:16:02 volumio-ip46 volumio[724]: info: CoreStateMachine::updateTrackBlock Jul 26 19:16:02 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrackBlock Jul 26 19:16:02 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPlay Jul 26 19:16:02 volumio-ip46 volumio[724]: info: CoreStateMachine::play index 0 Jul 26 19:16:02 volumio-ip46 volumio[724]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 26 19:16:02 volumio-ip46 volumio[724]: info: CoreStateMachine::stop Jul 26 19:16:02 volumio-ip46 volumio[724]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 26 19:16:02 volumio-ip46 volumio[724]: info: CoreStateMachine::play index undefined Jul 26 19:16:02 volumio-ip46 volumio[724]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 26 19:16:02 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:16:02 volumio-ip46 volumio[724]: info: CoreStateMachine::startPlaybackTimer Jul 26 19:16:02 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:16:02 volumio-ip46 volumio[724]: info: [1722014162100] ControllerWebradio::clearAddPlayTrack Jul 26 19:16:02 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand stop Jul 26 19:16:02 volumio-ip46 volumio[724]: info: sendMpdCommand stop took 14 milliseconds Jul 26 19:16:02 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand clear Jul 26 19:16:02 volumio-ip46 volumio[724]: info: Jul 26 19:16:02 volumio-ip46 volumio[724]: ---------------------------- MPD announces system playlist update Jul 26 19:16:02 volumio-ip46 volumio[724]: info: Ignoring MPD Status Update Jul 26 19:16:02 volumio-ip46 volumio[724]: info: sendMpdCommand clear took 9 milliseconds Jul 26 19:16:02 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand load "http://stream.104.6rtl.com/rtl-live/mp3-192/tunein/play.m3u" Jul 26 19:16:02 volumio-ip46 volumio[724]: info: Jul 26 19:16:02 volumio-ip46 volumio[724]: ---------------------------- MPD announces system playlist update Jul 26 19:16:02 volumio-ip46 volumio[724]: info: Ignoring MPD Status Update Jul 26 19:16:02 volumio-ip46 volumio[724]: info: Jul 26 19:16:02 volumio-ip46 volumio[724]: ---------------------------- MPD announces system playlist update Jul 26 19:16:02 volumio-ip46 volumio[724]: info: Ignoring MPD Status Update Jul 26 19:16:02 volumio-ip46 volumio[724]: error: updateQueue error: null Jul 26 19:16:02 volumio-ip46 volumio[724]: info: ------------------------------ 32ms Jul 26 19:16:02 volumio-ip46 volumio[724]: info: Jul 26 19:16:02 volumio-ip46 volumio[724]: ---------------------------- MPD announces system playlist update Jul 26 19:16:02 volumio-ip46 volumio[724]: info: Ignoring MPD Status Update Jul 26 19:16:02 volumio-ip46 volumio[724]: info: sendMpdCommand load "http://stream.104.6rtl.com/rtl-live/mp3-192/tunein/play.m3u" took 127 milliseconds Jul 26 19:16:02 volumio-ip46 volumio[724]: info: ------------------------------ 124ms Jul 26 19:16:02 volumio-ip46 volumio[724]: info: ------------------------------ 120ms Jul 26 19:16:02 volumio-ip46 volumio[724]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 26 19:16:02 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand play Jul 26 19:16:02 volumio-ip46 volumio[724]: info: Jul 26 19:16:02 volumio-ip46 volumio[724]: ---------------------------- MPD announces system playlist update Jul 26 19:16:02 volumio-ip46 volumio[724]: info: Ignoring MPD Status Update Jul 26 19:16:02 volumio-ip46 volumio[724]: info: Jul 26 19:16:02 volumio-ip46 volumio[724]: ---------------------------- MPD announces system playlist update Jul 26 19:16:02 volumio-ip46 volumio[724]: info: Ignoring MPD Status Update Jul 26 19:16:02 volumio-ip46 volumio[724]: info: ------------------------------ 37ms Jul 26 19:16:02 volumio-ip46 volumio[724]: info: sendMpdCommand play took 24 milliseconds Jul 26 19:16:02 volumio-ip46 volumio[724]: info: ------------------------------ 20ms Jul 26 19:16:02 volumio-ip46 volumio[724]: info: ------------------------------ 14ms Jul 26 19:16:04 volumio-ip46 volumio[724]: info: Jul 26 19:16:04 volumio-ip46 volumio[724]: ---------------------------- MPD announces state update: player Jul 26 19:16:04 volumio-ip46 volumio[724]: info: ControllerMpd::getState Jul 26 19:16:04 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand status Jul 26 19:16:04 volumio-ip46 volumio[724]: info: Jul 26 19:16:04 volumio-ip46 volumio[724]: ---------------------------- MPD announces state update: player Jul 26 19:16:04 volumio-ip46 volumio[724]: info: ControllerMpd::getState Jul 26 19:16:04 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand status Jul 26 19:16:04 volumio-ip46 volumio[724]: info: Jul 26 19:16:04 volumio-ip46 volumio[724]: ---------------------------- MPD announces state update: player Jul 26 19:16:04 volumio-ip46 volumio[724]: info: ControllerMpd::getState Jul 26 19:16:04 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand status Jul 26 19:16:04 volumio-ip46 volumio[724]: info: sendMpdCommand status took 42 milliseconds Jul 26 19:16:04 volumio-ip46 volumio[724]: info: sendMpdCommand status took 36 milliseconds Jul 26 19:16:04 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseState Jul 26 19:16:04 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 26 19:16:04 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseState Jul 26 19:16:04 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 26 19:16:04 volumio-ip46 volumio[724]: info: sendMpdCommand status took 53 milliseconds Jul 26 19:16:04 volumio-ip46 volumio[724]: info: sendMpdCommand playlistinfo took 32 milliseconds Jul 26 19:16:04 volumio-ip46 volumio[724]: info: sendMpdCommand playlistinfo took 30 milliseconds Jul 26 19:16:04 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseState Jul 26 19:16:04 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 26 19:16:04 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseTrackInfo Jul 26 19:16:04 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseTrackInfo Jul 26 19:16:04 volumio-ip46 volumio[724]: info: ControllerMpd::pushState Jul 26 19:16:04 volumio-ip46 volumio[724]: info: CoreCommandRouter::servicePushState Jul 26 19:16:04 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:16:04 volumio-ip46 volumio[724]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rtlb-1046rtllive-mp3-192-4947175?sABC=66n3q9q2%230%231sp419sq84q5190prps71q43p7ns0n9r%23gharva&aw_0_1st.playerid=tunein&amsparams=playerid:tunein;skey:1722014162","artist":null,"album":null,"uri":"http://rtlberlin.streamabc.net/rtlb-1046rtllive-mp3-192-4947175?sABC=66n3q9q2%230%231sp419sq84q5190prps71q43p7ns0n9r%23gharva&aw_0_1st.playerid=tunein&amsparams=playerid:tunein;skey:1722014162","trackType":""} Jul 26 19:16:04 volumio-ip46 volumio[724]: verbose: CURRENT POSITION 0 Jul 26 19:16:04 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState stateService play Jul 26 19:16:04 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState currentStatus stop Jul 26 19:16:04 volumio-ip46 volumio[724]: info: ControllerMpd::pushState Jul 26 19:16:04 volumio-ip46 volumio[724]: info: CoreCommandRouter::servicePushState Jul 26 19:16:04 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:16:04 volumio-ip46 volumio[724]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rtlb-1046rtllive-mp3-192-4947175?sABC=66n3q9q2%230%231sp419sq84q5190prps71q43p7ns0n9r%23gharva&aw_0_1st.playerid=tunein&amsparams=playerid:tunein;skey:1722014162","artist":null,"album":null,"uri":"http://rtlberlin.streamabc.net/rtlb-1046rtllive-mp3-192-4947175?sABC=66n3q9q2%230%231sp419sq84q5190prps71q43p7ns0n9r%23gharva&aw_0_1st.playerid=tunein&amsparams=playerid:tunein;skey:1722014162","trackType":""} Jul 26 19:16:04 volumio-ip46 volumio[724]: verbose: CURRENT POSITION 0 Jul 26 19:16:04 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState stateService play Jul 26 19:16:04 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState currentStatus play Jul 26 19:16:04 volumio-ip46 volumio[724]: info: Received an update from plugin. extracting info from payload Jul 26 19:16:04 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:16:04 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:16:04 volumio-ip46 volumio[724]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 26 19:16:04 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:16:04 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:16:04 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:16:04 volumio-ip46 volumio[724]: info: ------------------------------ 309ms Jul 26 19:16:04 volumio-ip46 volumio[724]: info: ------------------------------ 381ms Jul 26 19:16:04 volumio-ip46 volumio[724]: info: Jul 26 19:16:04 volumio-ip46 volumio[724]: ---------------------------- MPD announces system playlist update Jul 26 19:16:04 volumio-ip46 volumio[724]: info: Ignoring MPD Status Update Jul 26 19:16:04 volumio-ip46 volumio[724]: info: Jul 26 19:16:04 volumio-ip46 volumio[724]: ---------------------------- MPD announces state update: player Jul 26 19:16:04 volumio-ip46 volumio[724]: info: sendMpdCommand playlistinfo took 308 milliseconds Jul 26 19:16:04 volumio-ip46 volumio[724]: info: ControllerMpd::getState Jul 26 19:16:04 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand status Jul 26 19:16:04 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseTrackInfo Jul 26 19:16:04 volumio-ip46 volumio[724]: info: ControllerMpd::pushState Jul 26 19:16:04 volumio-ip46 volumio[724]: info: CoreCommandRouter::servicePushState Jul 26 19:16:04 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:16:04 volumio-ip46 volumio[724]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rtlb-1046rtllive-mp3-192-4947175?sABC=66n3q9q2%230%231sp419sq84q5190prps71q43p7ns0n9r%23gharva&aw_0_1st.playerid=tunein&amsparams=playerid:tunein;skey:1722014162","artist":null,"album":null,"uri":"http://rtlberlin.streamabc.net/rtlb-1046rtllive-mp3-192-4947175?sABC=66n3q9q2%230%231sp419sq84q5190prps71q43p7ns0n9r%23gharva&aw_0_1st.playerid=tunein&amsparams=playerid:tunein;skey:1722014162","trackType":""} Jul 26 19:16:04 volumio-ip46 volumio[724]: verbose: CURRENT POSITION 0 Jul 26 19:16:04 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState stateService play Jul 26 19:16:04 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState currentStatus play Jul 26 19:16:04 volumio-ip46 volumio[724]: info: Received an update from plugin. extracting info from payload Jul 26 19:16:04 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:16:04 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:16:04 volumio-ip46 volumio[724]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 26 19:16:04 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:16:04 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:16:04 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:16:04 volumio-ip46 volumio[724]: info: ------------------------------ 578ms Jul 26 19:16:04 volumio-ip46 volumio[724]: info: Jul 26 19:16:04 volumio-ip46 volumio[724]: ---------------------------- MPD announces system playlist update Jul 26 19:16:04 volumio-ip46 volumio[724]: info: Ignoring MPD Status Update Jul 26 19:16:04 volumio-ip46 volumio[724]: info: Jul 26 19:16:04 volumio-ip46 volumio[724]: ---------------------------- MPD announces state update: player Jul 26 19:16:05 volumio-ip46 volumio[724]: info: ControllerMpd::getState Jul 26 19:16:05 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand status Jul 26 19:16:05 volumio-ip46 volumio[724]: info: Jul 26 19:16:05 volumio-ip46 volumio[724]: ---------------------------- MPD announces system playlist update Jul 26 19:16:05 volumio-ip46 volumio[724]: info: Ignoring MPD Status Update Jul 26 19:16:05 volumio-ip46 volumio[724]: info: Jul 26 19:16:05 volumio-ip46 volumio[724]: ---------------------------- MPD announces state update: player Jul 26 19:16:05 volumio-ip46 volumio[724]: info: ControllerMpd::getState Jul 26 19:16:05 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand status Jul 26 19:16:05 volumio-ip46 volumio[724]: info: Jul 26 19:16:05 volumio-ip46 volumio[724]: ---------------------------- MPD announces system playlist update Jul 26 19:16:05 volumio-ip46 volumio[724]: info: Ignoring MPD Status Update Jul 26 19:16:05 volumio-ip46 volumio[724]: info: Jul 26 19:16:05 volumio-ip46 volumio[724]: ---------------------------- MPD announces state update: player Jul 26 19:16:05 volumio-ip46 volumio[724]: info: ControllerMpd::getState Jul 26 19:16:05 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand status Jul 26 19:16:05 volumio-ip46 volumio[724]: info: ------------------------------ 572ms Jul 26 19:16:05 volumio-ip46 volumio[724]: info: sendMpdCommand status took 555 milliseconds Jul 26 19:16:05 volumio-ip46 volumio[724]: info: ------------------------------ 144ms Jul 26 19:16:05 volumio-ip46 volumio[724]: info: sendMpdCommand status took 73 milliseconds Jul 26 19:16:05 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseState Jul 26 19:16:05 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 26 19:16:05 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseState Jul 26 19:16:05 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 26 19:16:05 volumio-ip46 volumio[724]: info: Jul 26 19:16:05 volumio-ip46 volumio[724]: ---------------------------- MPD announces system playlist update Jul 26 19:16:05 volumio-ip46 volumio[724]: info: Ignoring MPD Status Update Jul 26 19:16:05 volumio-ip46 volumio[724]: info: Jul 26 19:16:05 volumio-ip46 volumio[724]: ---------------------------- MPD announces state update: player Jul 26 19:16:05 volumio-ip46 volumio[724]: info: ControllerMpd::getState Jul 26 19:16:05 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand status Jul 26 19:16:05 volumio-ip46 volumio[724]: info: Jul 26 19:16:05 volumio-ip46 volumio[724]: ---------------------------- MPD announces system playlist update Jul 26 19:16:05 volumio-ip46 volumio[724]: info: Ignoring MPD Status Update Jul 26 19:16:05 volumio-ip46 volumio[724]: info: Jul 26 19:16:05 volumio-ip46 volumio[724]: ---------------------------- MPD announces state update: player Jul 26 19:16:05 volumio-ip46 volumio[724]: info: ControllerMpd::getState Jul 26 19:16:05 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand status Jul 26 19:16:05 volumio-ip46 volumio[724]: info: Jul 26 19:16:05 volumio-ip46 volumio[724]: ---------------------------- MPD announces system playlist update Jul 26 19:16:05 volumio-ip46 volumio[724]: info: Ignoring MPD Status Update Jul 26 19:16:05 volumio-ip46 volumio[724]: info: Jul 26 19:16:05 volumio-ip46 volumio[724]: ---------------------------- MPD announces state update: player Jul 26 19:16:05 volumio-ip46 volumio[724]: info: ------------------------------ 131ms Jul 26 19:16:05 volumio-ip46 volumio[724]: info: sendMpdCommand status took 111 milliseconds Jul 26 19:16:05 volumio-ip46 volumio[724]: info: ------------------------------ 109ms Jul 26 19:16:05 volumio-ip46 volumio[724]: info: sendMpdCommand status took 91 milliseconds Jul 26 19:16:05 volumio-ip46 volumio[724]: info: ControllerMpd::getState Jul 26 19:16:05 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand status Jul 26 19:16:05 volumio-ip46 volumio[724]: info: sendMpdCommand playlistinfo took 73 milliseconds Jul 26 19:16:05 volumio-ip46 volumio[724]: info: sendMpdCommand playlistinfo took 62 milliseconds Jul 26 19:16:05 volumio-ip46 volumio[724]: info: ------------------------------ 58ms Jul 26 19:16:05 volumio-ip46 volumio[724]: info: sendMpdCommand status took 48 milliseconds Jul 26 19:16:05 volumio-ip46 volumio[724]: info: ------------------------------ 45ms Jul 26 19:16:05 volumio-ip46 volumio[724]: info: sendMpdCommand status took 37 milliseconds Jul 26 19:16:05 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseState Jul 26 19:16:05 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 26 19:16:05 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseState Jul 26 19:16:05 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 26 19:16:05 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseTrackInfo Jul 26 19:16:05 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseTrackInfo Jul 26 19:16:05 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseState Jul 26 19:16:05 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 26 19:16:05 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseState Jul 26 19:16:05 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 26 19:16:05 volumio-ip46 volumio[724]: info: ControllerMpd::pushState Jul 26 19:16:05 volumio-ip46 volumio[724]: info: CoreCommandRouter::servicePushState Jul 26 19:16:05 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:16:05 volumio-ip46 volumio[724]: verbose: STATE SERVICE {"status":"play","position":0,"seek":988,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Udo Lindenberg & Apache 207 - Komet","artist":"104.6 RTL Berlin Livestream","album":null,"uri":"http://rtlberlin.streamabc.net/rtlb-1046rtllive-mp3-192-4947175?sABC=66n3q9q2%230%231sp419sq84q5190prps71q43p7ns0n9r%23gharva&aw_0_1st.playerid=tunein&amsparams=playerid:tunein;skey:1722014162","trackType":""} Jul 26 19:16:05 volumio-ip46 volumio[724]: verbose: CURRENT POSITION 0 Jul 26 19:16:05 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState stateService play Jul 26 19:16:05 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState currentStatus play Jul 26 19:16:05 volumio-ip46 volumio[724]: info: Received an update from plugin. extracting info from payload Jul 26 19:16:05 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:16:05 volumio-ip46 volumio[724]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 26 19:16:05 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:16:05 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:16:05 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:16:05 volumio-ip46 volumio[724]: info: ControllerMpd::pushState Jul 26 19:16:05 volumio-ip46 volumio[724]: info: CoreCommandRouter::servicePushState Jul 26 19:16:05 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:16:05 volumio-ip46 volumio[724]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1480,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Udo Lindenberg & Apache 207 - Komet","artist":"104.6 RTL Berlin Livestream","album":null,"uri":"http://rtlberlin.streamabc.net/rtlb-1046rtllive-mp3-192-4947175?sABC=66n3q9q2%230%231sp419sq84q5190prps71q43p7ns0n9r%23gharva&aw_0_1st.playerid=tunein&amsparams=playerid:tunein;skey:1722014162","trackType":""} Jul 26 19:16:05 volumio-ip46 volumio[724]: verbose: CURRENT POSITION 0 Jul 26 19:16:05 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState stateService play Jul 26 19:16:05 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState currentStatus play Jul 26 19:16:05 volumio-ip46 volumio[724]: info: Received an update from plugin. extracting info from payload Jul 26 19:16:05 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:16:05 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:16:05 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:16:05 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:16:05 volumio-ip46 volumio[724]: info: ------------------------------ 870ms Jul 26 19:16:05 volumio-ip46 volumio[724]: info: ------------------------------ 428ms Jul 26 19:16:05 volumio-ip46 volumio[724]: info: Jul 26 19:16:05 volumio-ip46 volumio[724]: ---------------------------- MPD announces system playlist update Jul 26 19:16:05 volumio-ip46 volumio[724]: info: Ignoring MPD Status Update Jul 26 19:16:05 volumio-ip46 volumio[724]: info: Jul 26 19:16:05 volumio-ip46 volumio[724]: ---------------------------- MPD announces state update: player Jul 26 19:16:05 volumio-ip46 volumio[724]: info: ControllerMpd::getState Jul 26 19:16:05 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand status Jul 26 19:16:05 volumio-ip46 volumio[724]: info: Jul 26 19:16:05 volumio-ip46 volumio[724]: ---------------------------- MPD announces system playlist update Jul 26 19:16:05 volumio-ip46 volumio[724]: info: Ignoring MPD Status Update Jul 26 19:16:05 volumio-ip46 volumio[724]: info: Jul 26 19:16:05 volumio-ip46 volumio[724]: ---------------------------- MPD announces state update: player Jul 26 19:16:05 volumio-ip46 volumio[724]: info: ControllerMpd::getState Jul 26 19:16:05 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand status Jul 26 19:16:05 volumio-ip46 volumio[724]: info: ------------------------------ 286ms Jul 26 19:16:05 volumio-ip46 volumio[724]: info: sendMpdCommand status took 280 milliseconds Jul 26 19:16:05 volumio-ip46 volumio[724]: info: sendMpdCommand playlistinfo took 268 milliseconds Jul 26 19:16:05 volumio-ip46 volumio[724]: info: sendMpdCommand playlistinfo took 266 milliseconds Jul 26 19:16:05 volumio-ip46 volumio[724]: info: sendMpdCommand playlistinfo took 258 milliseconds Jul 26 19:16:05 volumio-ip46 volumio[724]: info: sendMpdCommand playlistinfo took 256 milliseconds Jul 26 19:16:05 volumio-ip46 volumio[724]: info: ------------------------------ 81ms Jul 26 19:16:05 volumio-ip46 volumio[724]: info: sendMpdCommand status took 74 milliseconds Jul 26 19:16:05 volumio-ip46 volumio[724]: info: ------------------------------ 71ms Jul 26 19:16:05 volumio-ip46 volumio[724]: info: sendMpdCommand status took 53 milliseconds Jul 26 19:16:05 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseState Jul 26 19:16:05 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 26 19:16:05 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseTrackInfo Jul 26 19:16:05 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseTrackInfo Jul 26 19:16:05 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseTrackInfo Jul 26 19:16:05 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseTrackInfo Jul 26 19:16:05 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseState Jul 26 19:16:05 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 26 19:16:05 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseState Jul 26 19:16:05 volumio-ip46 volumio[724]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 26 19:16:05 volumio-ip46 volumio[724]: info: ControllerMpd::pushState Jul 26 19:16:05 volumio-ip46 volumio[724]: info: CoreCommandRouter::servicePushState Jul 26 19:16:05 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:16:05 volumio-ip46 volumio[724]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1480,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Udo Lindenberg & Apache 207 - Komet","artist":"104.6 RTL Berlin Livestream","album":null,"uri":"http://rtlberlin.streamabc.net/rtlb-1046rtllive-mp3-192-4947175?sABC=66n3q9q2%230%231sp419sq84q5190prps71q43p7ns0n9r%23gharva&aw_0_1st.playerid=tunein&amsparams=playerid:tunein;skey:1722014162","trackType":""} Jul 26 19:16:05 volumio-ip46 volumio[724]: verbose: CURRENT POSITION 0 Jul 26 19:16:05 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState stateService play Jul 26 19:16:05 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState currentStatus play Jul 26 19:16:05 volumio-ip46 volumio[724]: info: Received an update from plugin. extracting info from payload Jul 26 19:16:05 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:16:05 volumio-ip46 volumio[724]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 26 19:16:05 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:16:05 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:16:05 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:16:05 volumio-ip46 volumio[724]: info: ControllerMpd::pushState Jul 26 19:16:05 volumio-ip46 volumio[724]: info: CoreCommandRouter::servicePushState Jul 26 19:16:05 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:16:05 volumio-ip46 volumio[724]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1583,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Udo Lindenberg & Apache 207 - Komet","artist":"104.6 RTL Berlin Livestream","album":null,"uri":"http://rtlberlin.streamabc.net/rtlb-1046rtllive-mp3-192-4947175?sABC=66n3q9q2%230%231sp419sq84q5190prps71q43p7ns0n9r%23gharva&aw_0_1st.playerid=tunein&amsparams=playerid:tunein;skey:1722014162","trackType":""} Jul 26 19:16:05 volumio-ip46 volumio[724]: verbose: CURRENT POSITION 0 Jul 26 19:16:05 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState stateService play Jul 26 19:16:05 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState currentStatus play Jul 26 19:16:05 volumio-ip46 volumio[724]: info: Received an update from plugin. extracting info from payload Jul 26 19:16:05 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:16:05 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:16:05 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:16:05 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:16:05 volumio-ip46 volumio[724]: info: ControllerMpd::pushState Jul 26 19:16:05 volumio-ip46 volumio[724]: info: CoreCommandRouter::servicePushState Jul 26 19:16:05 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:16:05 volumio-ip46 volumio[724]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1583,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Udo Lindenberg & Apache 207 - Komet","artist":"104.6 RTL Berlin Livestream","album":null,"uri":"http://rtlberlin.streamabc.net/rtlb-1046rtllive-mp3-192-4947175?sABC=66n3q9q2%230%231sp419sq84q5190prps71q43p7ns0n9r%23gharva&aw_0_1st.playerid=tunein&amsparams=playerid:tunein;skey:1722014162","trackType":""} Jul 26 19:16:05 volumio-ip46 volumio[724]: verbose: CURRENT POSITION 0 Jul 26 19:16:05 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState stateService play Jul 26 19:16:05 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState currentStatus play Jul 26 19:16:05 volumio-ip46 volumio[724]: info: Received an update from plugin. extracting info from payload Jul 26 19:16:05 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:16:05 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:16:05 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:16:05 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:16:05 volumio-ip46 volumio[724]: info: ControllerMpd::pushState Jul 26 19:16:05 volumio-ip46 volumio[724]: info: CoreCommandRouter::servicePushState Jul 26 19:16:05 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:16:05 volumio-ip46 volumio[724]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1583,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Udo Lindenberg & Apache 207 - Komet","artist":"104.6 RTL Berlin Livestream","album":null,"uri":"http://rtlberlin.streamabc.net/rtlb-1046rtllive-mp3-192-4947175?sABC=66n3q9q2%230%231sp419sq84q5190prps71q43p7ns0n9r%23gharva&aw_0_1st.playerid=tunein&amsparams=playerid:tunein;skey:1722014162","trackType":""} Jul 26 19:16:05 volumio-ip46 volumio[724]: verbose: CURRENT POSITION 0 Jul 26 19:16:05 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState stateService play Jul 26 19:16:05 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState currentStatus play Jul 26 19:16:05 volumio-ip46 volumio[724]: info: Received an update from plugin. extracting info from payload Jul 26 19:16:05 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:16:05 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:16:05 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:16:05 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:16:05 volumio-ip46 volumio[724]: info: ------------------------------ 827ms Jul 26 19:16:05 volumio-ip46 volumio[724]: info: ------------------------------ 804ms Jul 26 19:16:05 volumio-ip46 volumio[724]: info: ------------------------------ 746ms Jul 26 19:16:05 volumio-ip46 volumio[724]: info: ------------------------------ 736ms Jul 26 19:16:06 volumio-ip46 volumio[724]: info: sendMpdCommand playlistinfo took 607 milliseconds Jul 26 19:16:06 volumio-ip46 volumio[724]: info: sendMpdCommand playlistinfo took 586 milliseconds Jul 26 19:16:06 volumio-ip46 volumio[724]: info: sendMpdCommand playlistinfo took 585 milliseconds Jul 26 19:16:06 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseTrackInfo Jul 26 19:16:06 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseTrackInfo Jul 26 19:16:06 volumio-ip46 volumio[724]: verbose: ControllerMpd::parseTrackInfo Jul 26 19:16:06 volumio-ip46 volumio[724]: info: ControllerMpd::pushState Jul 26 19:16:06 volumio-ip46 volumio[724]: info: CoreCommandRouter::servicePushState Jul 26 19:16:06 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:16:06 volumio-ip46 volumio[724]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1686,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Udo Lindenberg & Apache 207 - Komet","artist":"104.6 RTL Berlin Livestream","album":null,"uri":"http://rtlberlin.streamabc.net/rtlb-1046rtllive-mp3-192-4947175?sABC=66n3q9q2%230%231sp419sq84q5190prps71q43p7ns0n9r%23gharva&aw_0_1st.playerid=tunein&amsparams=playerid:tunein;skey:1722014162","trackType":""} Jul 26 19:16:06 volumio-ip46 volumio[724]: verbose: CURRENT POSITION 0 Jul 26 19:16:06 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState stateService play Jul 26 19:16:06 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState currentStatus play Jul 26 19:16:06 volumio-ip46 volumio[724]: info: Received an update from plugin. extracting info from payload Jul 26 19:16:06 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:16:06 volumio-ip46 volumio[724]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 26 19:16:06 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:16:06 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:16:06 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:16:06 volumio-ip46 volumio[724]: info: ControllerMpd::pushState Jul 26 19:16:06 volumio-ip46 volumio[724]: info: CoreCommandRouter::servicePushState Jul 26 19:16:06 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:16:06 volumio-ip46 volumio[724]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1881,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Udo Lindenberg & Apache 207 - Komet","artist":"104.6 RTL Berlin Livestream","album":null,"uri":"http://rtlberlin.streamabc.net/rtlb-1046rtllive-mp3-192-4947175?sABC=66n3q9q2%230%231sp419sq84q5190prps71q43p7ns0n9r%23gharva&aw_0_1st.playerid=tunein&amsparams=playerid:tunein;skey:1722014162","trackType":""} Jul 26 19:16:06 volumio-ip46 volumio[724]: verbose: CURRENT POSITION 0 Jul 26 19:16:06 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState stateService play Jul 26 19:16:06 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState currentStatus play Jul 26 19:16:06 volumio-ip46 volumio[724]: info: Received an update from plugin. extracting info from payload Jul 26 19:16:06 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:16:06 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:16:06 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:16:06 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:16:06 volumio-ip46 volumio[724]: info: ControllerMpd::pushState Jul 26 19:16:06 volumio-ip46 volumio[724]: info: CoreCommandRouter::servicePushState Jul 26 19:16:06 volumio-ip46 volumio[724]: info: CorePlayQueue::getTrack 0 Jul 26 19:16:06 volumio-ip46 volumio[724]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1881,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Udo Lindenberg & Apache 207 - Komet","artist":"104.6 RTL Berlin Livestream","album":null,"uri":"http://rtlberlin.streamabc.net/rtlb-1046rtllive-mp3-192-4947175?sABC=66n3q9q2%230%231sp419sq84q5190prps71q43p7ns0n9r%23gharva&aw_0_1st.playerid=tunein&amsparams=playerid:tunein;skey:1722014162","trackType":""} Jul 26 19:16:06 volumio-ip46 volumio[724]: verbose: CURRENT POSITION 0 Jul 26 19:16:06 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState stateService play Jul 26 19:16:06 volumio-ip46 volumio[724]: info: CoreStateMachine::syncState currentStatus play Jul 26 19:16:06 volumio-ip46 volumio[724]: info: Received an update from plugin. extracting info from payload Jul 26 19:16:06 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:16:06 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:16:06 volumio-ip46 volumio[724]: info: CoreStateMachine::pushState Jul 26 19:16:06 volumio-ip46 volumio[724]: info: CoreCommandRouter::volumioPushState Jul 26 19:16:06 volumio-ip46 volumio[724]: info: ------------------------------ 1211ms Jul 26 19:16:06 volumio-ip46 volumio[724]: info: ------------------------------ 977ms Jul 26 19:16:06 volumio-ip46 volumio[724]: info: ------------------------------ 967ms Jul 26 19:16:27 volumio-ip46 volumio[724]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 26 19:16:27 volumio-ip46 volumio[724]: info: Preload queue cleared Jul 26 19:16:35 volumio-ip46 volumio[724]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 26 19:16:37 volumio-ip46 volumio[724]: info: Preload queue cleared Jul 26 19:16:43 volumio-ip46 volumio[724]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 26 19:16:44 volumio-ip46 volumio[724]: info: Preload queue cleared Jul 26 19:16:46 volumio-ip46 volumio[724]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 26 19:16:47 volumio-ip46 volumio[724]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 26 19:16:47 volumio-ip46 volumio[724]: TypeError: Cannot read property 'length' of undefined Jul 26 19:16:47 volumio-ip46 volumio[724]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Jul 26 19:16:47 volumio-ip46 volumio[724]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Jul 26 19:16:47 volumio-ip46 volumio[724]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Jul 26 19:16:47 volumio-ip46 volumio[724]: at Parser.emit (events.js:315:20) Jul 26 19:16:47 volumio-ip46 volumio[724]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Jul 26 19:16:47 volumio-ip46 volumio[724]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Jul 26 19:16:47 volumio-ip46 volumio[724]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Jul 26 19:16:47 volumio-ip46 volumio[724]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Jul 26 19:16:47 volumio-ip46 volumio[724]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Jul 26 19:16:47 volumio-ip46 volumio[724]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Jul 26 19:16:47 volumio-ip46 volumio[724]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Jul 26 19:16:47 volumio-ip46 volumio[724]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Jul 26 19:16:47 volumio-ip46 volumio[724]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Jul 26 19:16:47 volumio-ip46 volumio[724]: at IncomingMessage.emit (events.js:327:22) Jul 26 19:16:47 volumio-ip46 volumio[724]: at endReadableNT (internal/streams/readable.js:1327:12) Jul 26 19:16:47 volumio-ip46 volumio[724]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jul 26 19:16:47 volumio-ip46 volumio[724]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 26 19:16:49 volumio-ip46 sudo[3654]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-26 19:15 Jul 26 19:16:49 volumio-ip46 sudo[3654]: 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="f8baf7ad070f376535a93fcc6590774528ddb153" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="0e3cf2fcfe1906c5090fab62b4cc2a5ff0069fd8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 30 Jan 2024 03:58:37 PM CET" VOLUMIO_VERSION="3.611" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="1d6379ebb89c40061afa8aee78176887"