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