-- Logs begin at Thu 2019-02-14 13:11:59 MSK, end at Sun 2025-05-25 15:48:50 MSK. -- May 25 15:47:15 volumio kernel: TCP: out of memory -- consider tuning tcp_mem May 25 15:47:46 volumio kernel: TCP: out of memory -- consider tuning tcp_mem May 25 15:48:18 volumio volumio[1072]: info: Preload queue cleared May 25 15:48:18 volumio volumio[1072]: info: CoreCommandRouter::volumioReplaceandPlayItems May 25 15:48:18 volumio volumio[1072]: info: CoreStateMachine::ClearQueue May 25 15:48:18 volumio volumio[1072]: info: CoreStateMachine::stop May 25 15:48:18 volumio volumio[1072]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 15:48:18 volumio volumio[1072]: info: CoreStateMachine::stPlaybackTimer May 25 15:48:18 volumio volumio[1072]: info: CoreStateMachine::updateTrackBlock May 25 15:48:18 volumio volumio[1072]: info: CorePlayQueue::getTrackBlock May 25 15:48:18 volumio volumio[1072]: info: CoreStateMachine::pushState May 25 15:48:18 volumio volumio[1072]: info: CorePlayQueue::getTrack 6 May 25 15:48:18 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 15:48:18 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState May 25 15:48:18 volumio volumio[1072]: info: CoreStateMachine::serviceStop May 25 15:48:18 volumio volumio[1072]: info: CorePlayQueue::getTrack 6 May 25 15:48:18 volumio volumio[1072]: info: CoreCommandRouter::serviceStop May 25 15:48:18 volumio volumio[1072]: info: ControllerMpd::stop May 25 15:48:18 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand stop May 25 15:48:18 volumio volumio[1072]: info: CorePlayQueue::clearPlayQueue May 25 15:48:18 volumio volumio[1072]: info: CorePlayQueue::saveQueue May 25 15:48:18 volumio volumio[1072]: info: CoreCommandRouter::volumioPushQueue May 25 15:48:18 volumio volumio[1072]: info: CoreStateMachine::addQueueItems May 25 15:48:18 volumio volumio[1072]: info: CorePlayQueue::addQueueItems May 25 15:48:18 volumio volumio[1072]: info: Preload queue cleared May 25 15:48:18 volumio volumio[1072]: info: Adding Item to queue: genres://Progressive%20Rock/Chillhop%20Guitar May 25 15:48:18 volumio volumio[1072]: info: Exploding uri genres://Progressive%20Rock/Chillhop%20Guitar in service mpd May 25 15:48:18 volumio volumio[1072]: info: May 25 15:48:18 volumio volumio[1072]: ---------------------------- MPD announces state update: player May 25 15:48:18 volumio volumio[1072]: info: sendMpdCommand stop took 43 milliseconds May 25 15:48:18 volumio volumio[1072]: info: ControllerMpd::getState May 25 15:48:18 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand status May 25 15:48:18 volumio volumio[1072]: info: CoreCommandRouter::volumioPushQueue May 25 15:48:18 volumio volumio[1072]: info: CorePlayQueue::saveQueue May 25 15:48:18 volumio volumio[1072]: info: CoreStateMachine::updateTrackBlock May 25 15:48:18 volumio volumio[1072]: info: CorePlayQueue::getTrackBlock May 25 15:48:18 volumio volumio[1072]: info: CoreCommandRouter::volumioPlay May 25 15:48:18 volumio volumio[1072]: info: CoreStateMachine::play index 0 May 25 15:48:18 volumio volumio[1072]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 15:48:18 volumio volumio[1072]: info: CoreStateMachine::stop May 25 15:48:18 volumio volumio[1072]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 15:48:18 volumio volumio[1072]: info: CoreStateMachine::play index undefined May 25 15:48:18 volumio volumio[1072]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 15:48:18 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 May 25 15:48:18 volumio volumio[1072]: info: CoreStateMachine::startPlaybackTimer May 25 15:48:18 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 May 25 15:48:18 volumio volumio[1072]: verbose: ControllerMpd::clearAddPlayTracks USB/ADATA_HD650/HeiRes/01 - Guitar Nomad.flac May 25 15:48:18 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand stop May 25 15:48:18 volumio volumio[1072]: info: sendMpdCommand status took 41 milliseconds May 25 15:48:18 volumio volumio[1072]: info: sendMpdCommand stop took 13 milliseconds May 25 15:48:18 volumio volumio[1072]: verbose: ControllerMpd::parseState May 25 15:48:18 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 15:48:18 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand clear May 25 15:48:18 volumio volumio[1072]: info: May 25 15:48:18 volumio volumio[1072]: ---------------------------- MPD announces state update: player May 25 15:48:18 volumio volumio[1072]: info: ControllerMpd::getState May 25 15:48:18 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand status May 25 15:48:18 volumio volumio[1072]: info: May 25 15:48:18 volumio volumio[1072]: ---------------------------- MPD announces state update: player May 25 15:48:18 volumio volumio[1072]: info: ControllerMpd::getState May 25 15:48:18 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand status May 25 15:48:18 volumio volumio[1072]: info: May 25 15:48:18 volumio volumio[1072]: ---------------------------- MPD announces state update: player May 25 15:48:18 volumio volumio[1072]: info: ControllerMpd::getState May 25 15:48:18 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand status May 25 15:48:18 volumio volumio[1072]: info: May 25 15:48:18 volumio volumio[1072]: ---------------------------- MPD announces state update: player May 25 15:48:18 volumio volumio[1072]: info: ControllerMpd::getState May 25 15:48:18 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand status May 25 15:48:18 volumio volumio[1072]: info: May 25 15:48:18 volumio volumio[1072]: ---------------------------- MPD announces system playlist update May 25 15:48:18 volumio volumio[1072]: info: Ignoring MPD Status Update May 25 15:48:18 volumio volumio[1072]: info: sendMpdCommand playlistinfo took 156 milliseconds May 25 15:48:18 volumio volumio[1072]: info: sendMpdCommand clear took 156 milliseconds May 25 15:48:18 volumio volumio[1072]: info: sendMpdCommand status took 154 milliseconds May 25 15:48:18 volumio volumio[1072]: info: sendMpdCommand status took 151 milliseconds May 25 15:48:18 volumio volumio[1072]: info: sendMpdCommand status took 150 milliseconds May 25 15:48:18 volumio volumio[1072]: info: sendMpdCommand status took 149 milliseconds May 25 15:48:18 volumio volumio[1072]: verbose: ControllerMpd::parseTrackInfo May 25 15:48:18 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand add "USB/ADATA_HD650/HeiRes/01 - Guitar Nomad.flac" May 25 15:48:18 volumio volumio[1072]: verbose: ControllerMpd::parseState May 25 15:48:18 volumio volumio[1072]: verbose: ControllerMpd::parseState May 25 15:48:18 volumio volumio[1072]: verbose: ControllerMpd::parseState May 25 15:48:18 volumio volumio[1072]: verbose: ControllerMpd::parseState May 25 15:48:18 volumio volumio[1072]: info: ControllerMpd::pushState May 25 15:48:18 volumio volumio[1072]: info: CoreCommandRouter::servicePushState May 25 15:48:18 volumio volumio[1072]: info: CoreStateMachine::pushState May 25 15:48:18 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 May 25 15:48:18 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 15:48:18 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState May 25 15:48:18 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 May 25 15:48:18 volumio volumio[1072]: 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":"The touch","artist":"Chillhop Guitar","album":"Guitar Chillosophy","uri":"USB/ADATA_HD650/HeiRes/07 - The touch.flac","trackType":"flac"} May 25 15:48:18 volumio volumio[1072]: verbose: CURRENT POSITION 0 May 25 15:48:18 volumio volumio[1072]: info: CoreStateMachine::syncState stateService stop May 25 15:48:18 volumio volumio[1072]: info: CoreStateMachine::syncState currentStatus stop May 25 15:48:18 volumio volumio[1072]: info: CoreStateMachine::pushState May 25 15:48:18 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 May 25 15:48:18 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState May 25 15:48:18 volumio volumio[1072]: info: No code May 25 15:48:18 volumio volumio[1072]: info: CoreStateMachine::pushState May 25 15:48:18 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 May 25 15:48:18 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState May 25 15:48:18 volumio volumio[1072]: info: ControllerMpd::pushState May 25 15:48:18 volumio volumio[1072]: info: CoreCommandRouter::servicePushState May 25 15:48:18 volumio volumio[1072]: info: CoreStateMachine::pushState May 25 15:48:18 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 May 25 15:48:18 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState May 25 15:48:18 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 May 25 15:48:18 volumio volumio[1072]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 25 15:48:18 volumio volumio[1072]: verbose: CURRENT POSITION 0 May 25 15:48:18 volumio volumio[1072]: info: CoreStateMachine::syncState stateService stop May 25 15:48:18 volumio volumio[1072]: info: CoreStateMachine::syncState currentStatus stop May 25 15:48:18 volumio volumio[1072]: info: CoreStateMachine::pushState May 25 15:48:18 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 May 25 15:48:18 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState May 25 15:48:18 volumio volumio[1072]: info: No code May 25 15:48:18 volumio volumio[1072]: info: CoreStateMachine::pushState May 25 15:48:18 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 May 25 15:48:18 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState May 25 15:48:18 volumio volumio[1072]: info: ControllerMpd::pushState May 25 15:48:18 volumio volumio[1072]: info: CoreCommandRouter::servicePushState May 25 15:48:18 volumio volumio[1072]: info: CoreStateMachine::pushState May 25 15:48:18 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 May 25 15:48:18 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState May 25 15:48:18 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 May 25 15:48:18 volumio volumio[1072]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 25 15:48:18 volumio volumio[1072]: verbose: CURRENT POSITION 0 May 25 15:48:18 volumio volumio[1072]: info: CoreStateMachine::syncState stateService stop May 25 15:48:18 volumio volumio[1072]: info: CoreStateMachine::syncState currentStatus stop May 25 15:48:18 volumio volumio[1072]: info: CoreStateMachine::pushState May 25 15:48:18 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 May 25 15:48:18 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState May 25 15:48:18 volumio volumio[1072]: info: No code May 25 15:48:18 volumio volumio[1072]: info: CoreStateMachine::pushState May 25 15:48:18 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 May 25 15:48:18 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState May 25 15:48:18 volumio volumio[1072]: info: ControllerMpd::pushState May 25 15:48:18 volumio volumio[1072]: info: CoreCommandRouter::servicePushState May 25 15:48:18 volumio volumio[1072]: info: CoreStateMachine::pushState May 25 15:48:18 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 May 25 15:48:18 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState May 25 15:48:18 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 May 25 15:48:18 volumio volumio[1072]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 25 15:48:18 volumio volumio[1072]: verbose: CURRENT POSITION 0 May 25 15:48:18 volumio volumio[1072]: info: CoreStateMachine::syncState stateService stop May 25 15:48:18 volumio volumio[1072]: info: CoreStateMachine::syncState currentStatus stop May 25 15:48:18 volumio volumio[1072]: info: CoreStateMachine::pushState May 25 15:48:18 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 May 25 15:48:18 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState May 25 15:48:18 volumio volumio[1072]: info: No code May 25 15:48:18 volumio volumio[1072]: info: CoreStateMachine::pushState May 25 15:48:18 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 May 25 15:48:18 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState May 25 15:48:18 volumio volumio[1072]: info: ControllerMpd::pushState May 25 15:48:18 volumio volumio[1072]: info: CoreCommandRouter::servicePushState May 25 15:48:18 volumio volumio[1072]: info: CoreStateMachine::pushState May 25 15:48:18 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 May 25 15:48:18 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState May 25 15:48:18 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 May 25 15:48:18 volumio volumio[1072]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 25 15:48:18 volumio volumio[1072]: verbose: CURRENT POSITION 0 May 25 15:48:18 volumio volumio[1072]: info: CoreStateMachine::syncState stateService stop May 25 15:48:18 volumio volumio[1072]: info: CoreStateMachine::syncState currentStatus stop May 25 15:48:18 volumio volumio[1072]: info: CoreStateMachine::pushState May 25 15:48:18 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 May 25 15:48:18 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState May 25 15:48:18 volumio volumio[1072]: info: No code May 25 15:48:18 volumio volumio[1072]: info: CoreStateMachine::pushState May 25 15:48:18 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 May 25 15:48:18 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState May 25 15:48:18 volumio volumio[1072]: info: ------------------------------ 339ms May 25 15:48:18 volumio volumio[1072]: info: ------------------------------ 293ms May 25 15:48:18 volumio volumio[1072]: info: ------------------------------ 293ms May 25 15:48:18 volumio volumio[1072]: info: ------------------------------ 293ms May 25 15:48:18 volumio volumio[1072]: info: ------------------------------ 292ms May 25 15:48:18 volumio volumio[1072]: info: May 25 15:48:18 volumio volumio[1072]: ---------------------------- MPD announces system playlist update May 25 15:48:18 volumio volumio[1072]: info: Ignoring MPD Status Update May 25 15:48:18 volumio volumio[1072]: info: May 25 15:48:18 volumio volumio[1072]: ---------------------------- MPD announces system playlist update May 25 15:48:18 volumio volumio[1072]: info: Ignoring MPD Status Update May 25 15:48:18 volumio volumio[1072]: info: May 25 15:48:18 volumio volumio[1072]: ---------------------------- MPD announces system playlist update May 25 15:48:18 volumio volumio[1072]: info: Ignoring MPD Status Update May 25 15:48:19 volumio volumio[1072]: info: May 25 15:48:19 volumio volumio[1072]: ---------------------------- MPD announces system playlist update May 25 15:48:19 volumio volumio[1072]: info: Ignoring MPD Status Update May 25 15:48:19 volumio volumio[1072]: error: updateQueue error: null May 25 15:48:19 volumio volumio[1072]: info: May 25 15:48:19 volumio volumio[1072]: ---------------------------- MPD announces system playlist update May 25 15:48:19 volumio volumio[1072]: info: Ignoring MPD Status Update May 25 15:48:19 volumio volumio[1072]: info: ------------------------------ 171ms May 25 15:48:19 volumio volumio[1072]: info: sendMpdCommand add "USB/ADATA_HD650/HeiRes/01 - Guitar Nomad.flac" took 160 milliseconds May 25 15:48:19 volumio volumio[1072]: info: ------------------------------ 21ms May 25 15:48:19 volumio volumio[1072]: info: ------------------------------ 19ms May 25 15:48:19 volumio volumio[1072]: info: ------------------------------ 18ms May 25 15:48:19 volumio volumio[1072]: info: ------------------------------ 16ms May 25 15:48:19 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand play May 25 15:48:19 volumio volumio[1072]: info: May 25 15:48:19 volumio volumio[1072]: ---------------------------- MPD announces system playlist update May 25 15:48:19 volumio volumio[1072]: info: Ignoring MPD Status Update May 25 15:48:19 volumio volumio[1072]: info: May 25 15:48:19 volumio volumio[1072]: ---------------------------- MPD announces system playlist update May 25 15:48:19 volumio volumio[1072]: info: Ignoring MPD Status Update May 25 15:48:19 volumio volumio[1072]: info: May 25 15:48:19 volumio volumio[1072]: ---------------------------- MPD announces system playlist update May 25 15:48:19 volumio volumio[1072]: info: Ignoring MPD Status Update May 25 15:48:19 volumio volumio[1072]: info: May 25 15:48:19 volumio volumio[1072]: ---------------------------- MPD announces system playlist update May 25 15:48:19 volumio volumio[1072]: info: Ignoring MPD Status Update May 25 15:48:19 volumio volumio[1072]: info: touch_display: Setting screensaver timeout to 120 seconds. May 25 15:48:19 volumio volumio[1072]: info: ------------------------------ 51ms May 25 15:48:19 volumio volumio[1072]: info: sendMpdCommand play took 47 milliseconds May 25 15:48:19 volumio volumio[1072]: info: ------------------------------ 48ms May 25 15:48:19 volumio volumio[1072]: info: ------------------------------ 47ms May 25 15:48:19 volumio volumio[1072]: info: ------------------------------ 47ms May 25 15:48:19 volumio volumio[1072]: info: ------------------------------ 46ms May 25 15:48:19 volumio volumio[1072]: info: camilladsp spawned new process with pid 15806, instance 1, run: true May 25 15:48:19 volumio volumio[1072]: info: May 25 15:48:19 volumio volumio[1072]: ---------------------------- MPD announces state update: player May 25 15:48:19 volumio volumio[1072]: info: ControllerMpd::getState May 25 15:48:19 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand status May 25 15:48:19 volumio volumio[1072]: info: May 25 15:48:19 volumio volumio[1072]: ---------------------------- MPD announces state update: player May 25 15:48:19 volumio volumio[1072]: info: ControllerMpd::getState May 25 15:48:19 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand status May 25 15:48:19 volumio volumio[1072]: info: May 25 15:48:19 volumio volumio[1072]: ---------------------------- MPD announces state update: player May 25 15:48:19 volumio volumio[1072]: info: ControllerMpd::getState May 25 15:48:19 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand status May 25 15:48:19 volumio volumio[1072]: info: May 25 15:48:19 volumio volumio[1072]: ---------------------------- MPD announces state update: player May 25 15:48:19 volumio volumio[1072]: info: ControllerMpd::getState May 25 15:48:19 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand status May 25 15:48:19 volumio volumio[1072]: info: May 25 15:48:19 volumio volumio[1072]: ---------------------------- MPD announces state update: player May 25 15:48:19 volumio volumio[1072]: info: ControllerMpd::getState May 25 15:48:19 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand status May 25 15:48:19 volumio volumio[1072]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 25 15:48:19 volumio volumio[1072]: info: FusionDsp - ---- read samplerate from file: 44100 May 25 15:48:19 volumio volumio[1072]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 25 15:48:19 volumio volumio[1072]: info: May 25 15:48:19 volumio volumio[1072]: ---------------------------- MPD announces state update: player May 25 15:48:19 volumio volumio[1072]: info: ControllerMpd::getState May 25 15:48:19 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand status May 25 15:48:19 volumio volumio[1072]: info: sendMpdCommand status took 163 milliseconds May 25 15:48:19 volumio volumio[1072]: info: sendMpdCommand status took 160 milliseconds May 25 15:48:19 volumio volumio[1072]: info: sendMpdCommand status took 153 milliseconds May 25 15:48:19 volumio volumio[1072]: info: sendMpdCommand status took 150 milliseconds May 25 15:48:19 volumio volumio[1072]: info: sendMpdCommand status took 148 milliseconds May 25 15:48:19 volumio volumio[1072]: verbose: ControllerMpd::parseState May 25 15:48:19 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 15:48:19 volumio volumio[1072]: verbose: ControllerMpd::parseState May 25 15:48:19 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 15:48:19 volumio volumio[1072]: verbose: ControllerMpd::parseState May 25 15:48:19 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 15:48:19 volumio volumio[1072]: verbose: ControllerMpd::parseState May 25 15:48:19 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 15:48:19 volumio volumio[1072]: verbose: ControllerMpd::parseState May 25 15:48:19 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 15:48:19 volumio volumio[1072]: info: May 25 15:48:19 volumio volumio[1072]: ---------------------------- MPD announces state update: player May 25 15:48:19 volumio volumio[1072]: info: ControllerMpd::getState May 25 15:48:19 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand status May 25 15:48:19 volumio volumio[1072]: info: May 25 15:48:19 volumio volumio[1072]: ---------------------------- MPD announces state update: player May 25 15:48:19 volumio volumio[1072]: info: ControllerMpd::getState May 25 15:48:19 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand status May 25 15:48:19 volumio volumio[1072]: info: May 25 15:48:19 volumio volumio[1072]: ---------------------------- MPD announces state update: player May 25 15:48:19 volumio volumio[1072]: info: ControllerMpd::getState May 25 15:48:19 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand status May 25 15:48:19 volumio volumio[1072]: info: May 25 15:48:19 volumio volumio[1072]: ---------------------------- MPD announces state update: player May 25 15:48:19 volumio volumio[1072]: info: ControllerMpd::getState May 25 15:48:19 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand status May 25 15:48:19 volumio volumio[1072]: info: sendMpdCommand status took 36 milliseconds May 25 15:48:19 volumio volumio[1072]: info: sendMpdCommand playlistinfo took 30 milliseconds May 25 15:48:19 volumio volumio[1072]: info: sendMpdCommand playlistinfo took 30 milliseconds May 25 15:48:19 volumio volumio[1072]: info: sendMpdCommand playlistinfo took 30 milliseconds May 25 15:48:19 volumio volumio[1072]: info: sendMpdCommand playlistinfo took 31 milliseconds May 25 15:48:19 volumio volumio[1072]: info: sendMpdCommand playlistinfo took 31 milliseconds May 25 15:48:19 volumio volumio[1072]: info: sendMpdCommand status took 23 milliseconds May 25 15:48:19 volumio volumio[1072]: info: sendMpdCommand status took 19 milliseconds May 25 15:48:19 volumio volumio[1072]: info: sendMpdCommand status took 17 milliseconds May 25 15:48:19 volumio volumio[1072]: info: sendMpdCommand status took 15 milliseconds May 25 15:48:19 volumio volumio[1072]: verbose: ControllerMpd::parseState May 25 15:48:19 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 15:48:19 volumio volumio[1072]: verbose: ControllerMpd::parseTrackInfo May 25 15:48:19 volumio volumio[1072]: verbose: ControllerMpd::parseTrackInfo May 25 15:48:19 volumio volumio[1072]: verbose: ControllerMpd::parseTrackInfo May 25 15:48:19 volumio volumio[1072]: verbose: ControllerMpd::parseTrackInfo May 25 15:48:19 volumio volumio[1072]: verbose: ControllerMpd::parseTrackInfo May 25 15:48:19 volumio volumio[1072]: verbose: ControllerMpd::parseState May 25 15:48:19 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 15:48:19 volumio volumio[1072]: verbose: ControllerMpd::parseState May 25 15:48:19 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 15:48:19 volumio volumio[1072]: verbose: ControllerMpd::parseState May 25 15:48:19 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 15:48:19 volumio volumio[1072]: verbose: ControllerMpd::parseState May 25 15:48:19 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 15:48:19 volumio volumio[1072]: info: ControllerMpd::pushState May 25 15:48:19 volumio volumio[1072]: info: CoreCommandRouter::servicePushState May 25 15:48:19 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 May 25 15:48:19 volumio volumio[1072]: verbose: STATE SERVICE {"status":"play","position":0,"seek":102,"duration":257,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1 Kbps","isStreaming":false,"title":"Guitar Nomad","artist":"Chillhop Guitar","album":"Guitar Chillosophy","uri":"USB/ADATA_HD650/HeiRes/01 - Guitar Nomad.flac","trackType":"flac"} May 25 15:48:19 volumio volumio[1072]: verbose: CURRENT POSITION 0 May 25 15:48:19 volumio volumio[1072]: info: CoreStateMachine::syncState stateService play May 25 15:48:19 volumio volumio[1072]: info: CoreStateMachine::syncState currentStatus stop May 25 15:48:19 volumio volumio[1072]: info: ControllerMpd::pushState May 25 15:48:19 volumio volumio[1072]: info: CoreCommandRouter::servicePushState May 25 15:48:19 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 May 25 15:48:19 volumio volumio[1072]: verbose: STATE SERVICE {"status":"play","position":0,"seek":342,"duration":257,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1 Kbps","isStreaming":false,"title":"Guitar Nomad","artist":"Chillhop Guitar","album":"Guitar Chillosophy","uri":"USB/ADATA_HD650/HeiRes/01 - Guitar Nomad.flac","trackType":"flac"} May 25 15:48:19 volumio volumio[1072]: verbose: CURRENT POSITION 0 May 25 15:48:19 volumio volumio[1072]: info: CoreStateMachine::syncState stateService play May 25 15:48:19 volumio volumio[1072]: info: CoreStateMachine::syncState currentStatus play May 25 15:48:19 volumio volumio[1072]: info: Received an update from plugin. extracting info from payload May 25 15:48:19 volumio volumio[1072]: info: CoreStateMachine::pushState May 25 15:48:19 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 May 25 15:48:19 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 15:48:19 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState May 25 15:48:19 volumio volumio[1072]: info: CoreStateMachine::pushState May 25 15:48:19 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 May 25 15:48:19 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState May 25 15:48:19 volumio volumio[1072]: info: ControllerMpd::pushState May 25 15:48:19 volumio volumio[1072]: info: CoreCommandRouter::servicePushState May 25 15:48:19 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 May 25 15:48:19 volumio volumio[1072]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":257,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1063 Kbps","isStreaming":false,"title":"Guitar Nomad","artist":"Chillhop Guitar","album":"Guitar Chillosophy","uri":"USB/ADATA_HD650/HeiRes/01 - Guitar Nomad.flac","trackType":"flac"} May 25 15:48:19 volumio volumio[1072]: verbose: CURRENT POSITION 0 May 25 15:48:19 volumio volumio[1072]: info: CoreStateMachine::syncState stateService play May 25 15:48:19 volumio volumio[1072]: info: CoreStateMachine::syncState currentStatus play May 25 15:48:19 volumio volumio[1072]: info: Received an update from plugin. extracting info from payload May 25 15:48:19 volumio volumio[1072]: info: CoreStateMachine::pushState May 25 15:48:19 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 May 25 15:48:19 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 15:48:19 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState May 25 15:48:19 volumio volumio[1072]: info: CoreStateMachine::pushState May 25 15:48:19 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 May 25 15:48:19 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState May 25 15:48:19 volumio volumio[1072]: info: ControllerMpd::pushState May 25 15:48:19 volumio volumio[1072]: info: CoreCommandRouter::servicePushState May 25 15:48:19 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 May 25 15:48:19 volumio volumio[1072]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":257,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1063 Kbps","isStreaming":false,"title":"Guitar Nomad","artist":"Chillhop Guitar","album":"Guitar Chillosophy","uri":"USB/ADATA_HD650/HeiRes/01 - Guitar Nomad.flac","trackType":"flac"} May 25 15:48:19 volumio volumio[1072]: verbose: CURRENT POSITION 0 May 25 15:48:19 volumio volumio[1072]: info: CoreStateMachine::syncState stateService play May 25 15:48:19 volumio volumio[1072]: info: CoreStateMachine::syncState currentStatus play May 25 15:48:19 volumio volumio[1072]: info: Received an update from plugin. extracting info from payload May 25 15:48:19 volumio volumio[1072]: info: CoreStateMachine::pushState May 25 15:48:19 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 May 25 15:48:19 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState May 25 15:48:19 volumio volumio[1072]: info: CoreStateMachine::pushState May 25 15:48:19 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 May 25 15:48:19 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState May 25 15:48:19 volumio volumio[1072]: info: ControllerMpd::pushState May 25 15:48:19 volumio volumio[1072]: info: CoreCommandRouter::servicePushState May 25 15:48:19 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 May 25 15:48:19 volumio volumio[1072]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":257,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1063 Kbps","isStreaming":false,"title":"Guitar Nomad","artist":"Chillhop Guitar","album":"Guitar Chillosophy","uri":"USB/ADATA_HD650/HeiRes/01 - Guitar Nomad.flac","trackType":"flac"} May 25 15:48:19 volumio volumio[1072]: verbose: CURRENT POSITION 0 May 25 15:48:19 volumio volumio[1072]: info: CoreStateMachine::syncState stateService play May 25 15:48:19 volumio volumio[1072]: info: CoreStateMachine::syncState currentStatus play May 25 15:48:19 volumio volumio[1072]: info: Received an update from plugin. extracting info from payload May 25 15:48:19 volumio volumio[1072]: info: CoreStateMachine::pushState May 25 15:48:19 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 May 25 15:48:19 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState May 25 15:48:19 volumio volumio[1072]: info: CoreStateMachine::pushState May 25 15:48:19 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 May 25 15:48:19 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState May 25 15:48:19 volumio volumio[1072]: info: ------------------------------ 327ms May 25 15:48:19 volumio volumio[1072]: info: ------------------------------ 369ms May 25 15:48:19 volumio volumio[1072]: info: ------------------------------ 370ms May 25 15:48:19 volumio volumio[1072]: info: ------------------------------ 367ms May 25 15:48:19 volumio volumio[1072]: info: ------------------------------ 367ms May 25 15:48:19 volumio volumio[1072]: info: sendMpdCommand playlistinfo took 182 milliseconds May 25 15:48:19 volumio volumio[1072]: info: sendMpdCommand playlistinfo took 176 milliseconds May 25 15:48:19 volumio volumio[1072]: info: sendMpdCommand playlistinfo took 176 milliseconds May 25 15:48:20 volumio volumio[1072]: info: sendMpdCommand playlistinfo took 183 milliseconds May 25 15:48:20 volumio volumio[1072]: info: sendMpdCommand playlistinfo took 183 milliseconds May 25 15:48:20 volumio volumio[1072]: verbose: ControllerMpd::parseTrackInfo May 25 15:48:20 volumio volumio[1072]: verbose: ControllerMpd::parseTrackInfo May 25 15:48:20 volumio volumio[1072]: verbose: ControllerMpd::parseTrackInfo May 25 15:48:20 volumio volumio[1072]: verbose: ControllerMpd::parseTrackInfo May 25 15:48:20 volumio volumio[1072]: verbose: ControllerMpd::parseTrackInfo May 25 15:48:20 volumio volumio[1072]: info: ControllerMpd::pushState May 25 15:48:20 volumio volumio[1072]: info: CoreCommandRouter::servicePushState May 25 15:48:20 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 May 25 15:48:20 volumio volumio[1072]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":257,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1063 Kbps","isStreaming":false,"title":"Guitar Nomad","artist":"Chillhop Guitar","album":"Guitar Chillosophy","uri":"USB/ADATA_HD650/HeiRes/01 - Guitar Nomad.flac","trackType":"flac"} May 25 15:48:20 volumio volumio[1072]: verbose: CURRENT POSITION 0 May 25 15:48:20 volumio volumio[1072]: info: CoreStateMachine::syncState stateService play May 25 15:48:20 volumio volumio[1072]: info: CoreStateMachine::syncState currentStatus play May 25 15:48:20 volumio volumio[1072]: info: Received an update from plugin. extracting info from payload May 25 15:48:20 volumio volumio[1072]: info: CoreStateMachine::pushState May 25 15:48:20 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 May 25 15:48:20 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState May 25 15:48:20 volumio volumio[1072]: info: CoreStateMachine::pushState May 25 15:48:20 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 May 25 15:48:20 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState May 25 15:48:20 volumio volumio[1072]: info: ControllerMpd::pushState May 25 15:48:20 volumio volumio[1072]: info: CoreCommandRouter::servicePushState May 25 15:48:20 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 May 25 15:48:20 volumio volumio[1072]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":257,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1063 Kbps","isStreaming":false,"title":"Guitar Nomad","artist":"Chillhop Guitar","album":"Guitar Chillosophy","uri":"USB/ADATA_HD650/HeiRes/01 - Guitar Nomad.flac","trackType":"flac"} May 25 15:48:20 volumio volumio[1072]: verbose: CURRENT POSITION 0 May 25 15:48:20 volumio volumio[1072]: info: CoreStateMachine::syncState stateService play May 25 15:48:20 volumio volumio[1072]: info: CoreStateMachine::syncState currentStatus play May 25 15:48:20 volumio volumio[1072]: info: Received an update from plugin. extracting info from payload May 25 15:48:20 volumio volumio[1072]: info: CoreStateMachine::pushState May 25 15:48:20 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 May 25 15:48:20 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState May 25 15:48:20 volumio volumio[1072]: info: CoreStateMachine::pushState May 25 15:48:20 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 May 25 15:48:20 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState May 25 15:48:20 volumio volumio[1072]: info: ControllerMpd::pushState May 25 15:48:20 volumio volumio[1072]: info: CoreCommandRouter::servicePushState May 25 15:48:20 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 May 25 15:48:20 volumio volumio[1072]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":257,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1063 Kbps","isStreaming":false,"title":"Guitar Nomad","artist":"Chillhop Guitar","album":"Guitar Chillosophy","uri":"USB/ADATA_HD650/HeiRes/01 - Guitar Nomad.flac","trackType":"flac"} May 25 15:48:20 volumio volumio[1072]: verbose: CURRENT POSITION 0 May 25 15:48:20 volumio volumio[1072]: info: CoreStateMachine::syncState stateService play May 25 15:48:20 volumio volumio[1072]: info: CoreStateMachine::syncState currentStatus play May 25 15:48:20 volumio volumio[1072]: info: Received an update from plugin. extracting info from payload May 25 15:48:20 volumio volumio[1072]: info: CoreStateMachine::pushState May 25 15:48:20 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 May 25 15:48:20 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState May 25 15:48:20 volumio volumio[1072]: info: CoreStateMachine::pushState May 25 15:48:20 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 May 25 15:48:20 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState May 25 15:48:20 volumio volumio[1072]: info: ControllerMpd::pushState May 25 15:48:20 volumio volumio[1072]: info: CoreCommandRouter::servicePushState May 25 15:48:20 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 May 25 15:48:20 volumio volumio[1072]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":257,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1063 Kbps","isStreaming":false,"title":"Guitar Nomad","artist":"Chillhop Guitar","album":"Guitar Chillosophy","uri":"USB/ADATA_HD650/HeiRes/01 - Guitar Nomad.flac","trackType":"flac"} May 25 15:48:20 volumio volumio[1072]: verbose: CURRENT POSITION 0 May 25 15:48:20 volumio volumio[1072]: info: CoreStateMachine::syncState stateService play May 25 15:48:20 volumio volumio[1072]: info: CoreStateMachine::syncState currentStatus play May 25 15:48:20 volumio volumio[1072]: info: Received an update from plugin. extracting info from payload May 25 15:48:20 volumio volumio[1072]: info: CoreStateMachine::pushState May 25 15:48:20 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 May 25 15:48:20 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState May 25 15:48:20 volumio volumio[1072]: info: CoreStateMachine::pushState May 25 15:48:20 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 May 25 15:48:20 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState May 25 15:48:20 volumio volumio[1072]: info: ControllerMpd::pushState May 25 15:48:20 volumio volumio[1072]: info: CoreCommandRouter::servicePushState May 25 15:48:20 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 May 25 15:48:20 volumio volumio[1072]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":257,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1063 Kbps","isStreaming":false,"title":"Guitar Nomad","artist":"Chillhop Guitar","album":"Guitar Chillosophy","uri":"USB/ADATA_HD650/HeiRes/01 - Guitar Nomad.flac","trackType":"flac"} May 25 15:48:20 volumio volumio[1072]: verbose: CURRENT POSITION 0 May 25 15:48:20 volumio volumio[1072]: info: CoreStateMachine::syncState stateService play May 25 15:48:20 volumio volumio[1072]: info: CoreStateMachine::syncState currentStatus play May 25 15:48:20 volumio volumio[1072]: info: Received an update from plugin. extracting info from payload May 25 15:48:20 volumio volumio[1072]: info: CoreStateMachine::pushState May 25 15:48:20 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 May 25 15:48:20 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState May 25 15:48:20 volumio volumio[1072]: info: CoreStateMachine::pushState May 25 15:48:20 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 May 25 15:48:20 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState May 25 15:48:20 volumio volumio[1072]: info: ------------------------------ 364ms May 25 15:48:20 volumio volumio[1072]: info: ------------------------------ 349ms May 25 15:48:20 volumio volumio[1072]: info: ------------------------------ 347ms May 25 15:48:20 volumio volumio[1072]: info: ------------------------------ 346ms May 25 15:48:20 volumio volumio[1072]: info: ------------------------------ 346ms May 25 15:48:20 volumio volumio[1072]: info: FusionDsp - {"Reload":{"result":"Ok"}} May 25 15:48:37 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: motherearthradio , handleBrowseUri May 25 15:48:37 volumio volumio[1072]: info: Preload queue cleared May 25 15:48:43 volumio volumio[1072]: info: Preload queue cleared May 25 15:48:43 volumio volumio[1072]: info: CoreCommandRouter::volumioReplaceandPlayItems May 25 15:48:43 volumio volumio[1072]: info: CoreStateMachine::ClearQueue May 25 15:48:43 volumio volumio[1072]: info: CoreStateMachine::stop May 25 15:48:43 volumio volumio[1072]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 15:48:43 volumio volumio[1072]: info: CoreStateMachine::stPlaybackTimer May 25 15:48:43 volumio volumio[1072]: info: CoreStateMachine::updateTrackBlock May 25 15:48:43 volumio volumio[1072]: info: CorePlayQueue::getTrackBlock May 25 15:48:43 volumio volumio[1072]: info: CoreStateMachine::pushState May 25 15:48:43 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 May 25 15:48:43 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 15:48:43 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState May 25 15:48:43 volumio volumio[1072]: info: CoreStateMachine::serviceStop May 25 15:48:43 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 May 25 15:48:43 volumio volumio[1072]: info: CoreCommandRouter::serviceStop May 25 15:48:43 volumio volumio[1072]: info: ControllerMpd::stop May 25 15:48:43 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand stop May 25 15:48:43 volumio volumio[1072]: info: CorePlayQueue::clearPlayQueue May 25 15:48:43 volumio volumio[1072]: info: CorePlayQueue::saveQueue May 25 15:48:43 volumio volumio[1072]: info: CoreCommandRouter::volumioPushQueue May 25 15:48:43 volumio volumio[1072]: info: CoreStateMachine::addQueueItems May 25 15:48:43 volumio volumio[1072]: info: CorePlayQueue::addQueueItems May 25 15:48:43 volumio volumio[1072]: info: Preload queue cleared May 25 15:48:43 volumio volumio[1072]: info: Adding Item to queue: webmer/3 May 25 15:48:43 volumio volumio[1072]: info: Exploding uri webmer/3 in service motherearthradio May 25 15:48:43 volumio volumio[1072]: info: CoreCommandRouter::volumioPushQueue May 25 15:48:43 volumio volumio[1072]: info: CorePlayQueue::saveQueue May 25 15:48:43 volumio volumio[1072]: info: CoreStateMachine::updateTrackBlock May 25 15:48:43 volumio volumio[1072]: info: CorePlayQueue::getTrackBlock May 25 15:48:43 volumio volumio[1072]: info: CoreCommandRouter::volumioPlay May 25 15:48:43 volumio volumio[1072]: info: CoreStateMachine::play index 0 May 25 15:48:43 volumio volumio[1072]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 15:48:43 volumio volumio[1072]: info: CoreStateMachine::stop May 25 15:48:43 volumio volumio[1072]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 15:48:43 volumio volumio[1072]: info: CoreStateMachine::play index undefined May 25 15:48:43 volumio volumio[1072]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 15:48:43 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 May 25 15:48:43 volumio volumio[1072]: info: CoreStateMachine::startPlaybackTimer May 25 15:48:43 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 May 25 15:48:43 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand stop May 25 15:48:43 volumio volumio[1072]: info: May 25 15:48:43 volumio volumio[1072]: ---------------------------- MPD announces state update: player May 25 15:48:43 volumio volumio[1072]: info: sendMpdCommand stop took 62 milliseconds May 25 15:48:43 volumio volumio[1072]: info: ControllerMpd::getState May 25 15:48:43 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand status May 25 15:48:43 volumio volumio[1072]: info: sendMpdCommand stop took 21 milliseconds May 25 15:48:43 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand clear May 25 15:48:43 volumio volumio[1072]: info: May 25 15:48:43 volumio volumio[1072]: ---------------------------- MPD announces state update: player May 25 15:48:43 volumio volumio[1072]: info: ControllerMpd::getState May 25 15:48:43 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand status May 25 15:48:43 volumio volumio[1072]: info: May 25 15:48:43 volumio volumio[1072]: ---------------------------- MPD announces state update: player May 25 15:48:43 volumio volumio[1072]: info: ControllerMpd::getState May 25 15:48:43 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand status May 25 15:48:43 volumio volumio[1072]: info: May 25 15:48:43 volumio volumio[1072]: ---------------------------- MPD announces state update: player May 25 15:48:43 volumio volumio[1072]: info: ControllerMpd::getState May 25 15:48:43 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand status May 25 15:48:43 volumio volumio[1072]: info: May 25 15:48:43 volumio volumio[1072]: ---------------------------- MPD announces state update: player May 25 15:48:43 volumio volumio[1072]: info: ControllerMpd::getState May 25 15:48:43 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand status May 25 15:48:43 volumio volumio[1072]: info: May 25 15:48:43 volumio volumio[1072]: ---------------------------- MPD announces system playlist update May 25 15:48:43 volumio volumio[1072]: info: Ignoring MPD Status Update May 25 15:48:43 volumio volumio[1072]: info: sendMpdCommand status took 37 milliseconds May 25 15:48:43 volumio volumio[1072]: info: sendMpdCommand clear took 35 milliseconds May 25 15:48:43 volumio volumio[1072]: info: sendMpdCommand status took 24 milliseconds May 25 15:48:43 volumio volumio[1072]: info: sendMpdCommand status took 26 milliseconds May 25 15:48:43 volumio volumio[1072]: info: sendMpdCommand status took 23 milliseconds May 25 15:48:43 volumio volumio[1072]: info: sendMpdCommand status took 20 milliseconds May 25 15:48:43 volumio volumio[1072]: verbose: ControllerMpd::parseState May 25 15:48:43 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 15:48:43 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand consume 1 May 25 15:48:43 volumio volumio[1072]: verbose: ControllerMpd::parseState May 25 15:48:43 volumio volumio[1072]: verbose: ControllerMpd::parseState May 25 15:48:43 volumio volumio[1072]: verbose: ControllerMpd::parseState May 25 15:48:43 volumio volumio[1072]: verbose: ControllerMpd::parseState May 25 15:48:43 volumio volumio[1072]: info: ControllerMpd::pushState May 25 15:48:43 volumio volumio[1072]: info: CoreCommandRouter::servicePushState May 25 15:48:43 volumio volumio[1072]: info: CoreStateMachine::pushState May 25 15:48:43 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 May 25 15:48:43 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 15:48:43 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState May 25 15:48:43 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 May 25 15:48:43 volumio volumio[1072]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd May 25 15:48:43 volumio volumio[1072]: info: ControllerMpd::pushState May 25 15:48:43 volumio volumio[1072]: info: CoreCommandRouter::servicePushState May 25 15:48:43 volumio volumio[1072]: info: CoreStateMachine::pushState May 25 15:48:43 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 May 25 15:48:43 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState May 25 15:48:43 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 May 25 15:48:43 volumio volumio[1072]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd May 25 15:48:43 volumio volumio[1072]: info: ControllerMpd::pushState May 25 15:48:43 volumio volumio[1072]: info: CoreCommandRouter::servicePushState May 25 15:48:43 volumio volumio[1072]: info: CoreStateMachine::pushState May 25 15:48:43 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 May 25 15:48:43 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState May 25 15:48:43 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 May 25 15:48:43 volumio volumio[1072]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd May 25 15:48:43 volumio volumio[1072]: info: ControllerMpd::pushState May 25 15:48:43 volumio volumio[1072]: info: CoreCommandRouter::servicePushState May 25 15:48:43 volumio volumio[1072]: info: CoreStateMachine::pushState May 25 15:48:43 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 May 25 15:48:43 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState May 25 15:48:43 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 May 25 15:48:43 volumio volumio[1072]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd May 25 15:48:43 volumio volumio[1072]: info: ------------------------------ 77ms May 25 15:48:43 volumio volumio[1072]: info: ------------------------------ 67ms May 25 15:48:43 volumio volumio[1072]: info: ------------------------------ 65ms May 25 15:48:43 volumio volumio[1072]: info: ------------------------------ 62ms May 25 15:48:43 volumio volumio[1072]: info: May 25 15:48:43 volumio volumio[1072]: ---------------------------- MPD announces system playlist update May 25 15:48:43 volumio volumio[1072]: info: Ignoring MPD Status Update May 25 15:48:43 volumio volumio[1072]: info: May 25 15:48:43 volumio volumio[1072]: ---------------------------- MPD announces system playlist update May 25 15:48:43 volumio volumio[1072]: info: Ignoring MPD Status Update May 25 15:48:43 volumio volumio[1072]: info: May 25 15:48:43 volumio volumio[1072]: ---------------------------- MPD announces system playlist update May 25 15:48:43 volumio volumio[1072]: info: Ignoring MPD Status Update May 25 15:48:43 volumio volumio[1072]: info: May 25 15:48:43 volumio volumio[1072]: ---------------------------- MPD announces system playlist update May 25 15:48:43 volumio volumio[1072]: info: Ignoring MPD Status Update May 25 15:48:43 volumio volumio[1072]: error: updateQueue error: null May 25 15:48:43 volumio volumio[1072]: error: updateQueue error: null May 25 15:48:43 volumio volumio[1072]: error: updateQueue error: null May 25 15:48:43 volumio volumio[1072]: error: updateQueue error: null May 25 15:48:43 volumio volumio[1072]: error: updateQueue error: null May 25 15:48:43 volumio volumio[1072]: info: ------------------------------ 142ms May 25 15:48:43 volumio volumio[1072]: info: sendMpdCommand playlistinfo took 132 milliseconds May 25 15:48:43 volumio volumio[1072]: info: sendMpdCommand consume 1 took 131 milliseconds May 25 15:48:43 volumio volumio[1072]: info: ------------------------------ 92ms May 25 15:48:43 volumio volumio[1072]: info: ------------------------------ 90ms May 25 15:48:43 volumio volumio[1072]: info: ------------------------------ 88ms May 25 15:48:43 volumio volumio[1072]: info: ------------------------------ 86ms May 25 15:48:43 volumio volumio[1072]: verbose: ControllerMpd::parseTrackInfo May 25 15:48:43 volumio volumio[1072]: info: [1748177323486] [MotherEarth] set to consume mode, adding url: https://motherearth.streamserver24.com/listen/motherearth_jazz/motherearth.jazz May 25 15:48:43 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand add "https://motherearth.streamserver24.com/listen/motherearth_jazz/motherearth.jazz" May 25 15:48:43 volumio volumio[1072]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 25 15:48:43 volumio volumio[1072]: info: ------------------------------ 223ms May 25 15:48:43 volumio volumio[1072]: info: touch_display: Setting screensaver timeout to 120 seconds. May 25 15:48:43 volumio volumio[1072]: info: May 25 15:48:43 volumio volumio[1072]: ---------------------------- MPD announces system playlist update May 25 15:48:43 volumio volumio[1072]: info: Ignoring MPD Status Update May 25 15:48:43 volumio volumio[1072]: info: sendMpdCommand add "https://motherearth.streamserver24.com/listen/motherearth_jazz/motherearth.jazz" took 113 milliseconds May 25 15:48:43 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand play May 25 15:48:43 volumio volumio[1072]: info: May 25 15:48:43 volumio volumio[1072]: ---------------------------- MPD announces system playlist update May 25 15:48:43 volumio volumio[1072]: info: Ignoring MPD Status Update May 25 15:48:43 volumio volumio[1072]: info: May 25 15:48:43 volumio volumio[1072]: ---------------------------- MPD announces system playlist update May 25 15:48:43 volumio volumio[1072]: info: Ignoring MPD Status Update May 25 15:48:43 volumio volumio[1072]: info: May 25 15:48:43 volumio volumio[1072]: ---------------------------- MPD announces system playlist update May 25 15:48:43 volumio volumio[1072]: info: Ignoring MPD Status Update May 25 15:48:43 volumio volumio[1072]: info: May 25 15:48:43 volumio volumio[1072]: ---------------------------- MPD announces system playlist update May 25 15:48:43 volumio volumio[1072]: info: Ignoring MPD Status Update May 25 15:48:43 volumio volumio[1072]: info: ------------------------------ 44ms May 25 15:48:43 volumio volumio[1072]: info: sendMpdCommand play took 34 milliseconds May 25 15:48:43 volumio volumio[1072]: info: ------------------------------ 33ms May 25 15:48:43 volumio volumio[1072]: info: ------------------------------ 31ms May 25 15:48:43 volumio volumio[1072]: info: ------------------------------ 29ms May 25 15:48:43 volumio volumio[1072]: info: ------------------------------ 27ms May 25 15:48:44 volumio volumio[1072]: info: camilladsp spawned new process with pid 15874, instance 1, run: true May 25 15:48:45 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 May 25 15:48:45 volumio volumio[1072]: info: CoreCommandRouter::servicePushState May 25 15:48:45 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 May 25 15:48:45 volumio volumio[1072]: verbose: STATE SERVICE {"status":"play","service":"motherearthradio","type":"webradio","trackType":"flac","radioType":"mer","albumart":"https://motherearth.streamserver24.com/api/station/motherearth_jazz/art/3300085b78aca1d59e645003-1683740984.jpg","uri":"https://motherearth.streamserver24.com/listen/motherearth_jazz/motherearth.jazz","name":"John McLaughlin - Extrapolation - Side A","title":"Extrapolation - Side A","artist":"John McLaughlin","album":"Extrapolation","streaming":true,"disableUiControls":true,"duration":126,"seek":0,"bitdepth":"24 bit","channels":2} May 25 15:48:45 volumio volumio[1072]: verbose: CURRENT POSITION 0 May 25 15:48:45 volumio volumio[1072]: info: CoreStateMachine::syncState stateService play May 25 15:48:45 volumio volumio[1072]: info: CoreStateMachine::syncState currentStatus stop May 25 15:48:45 volumio volumio[1072]: info: [1748177325138] [MotherEarth] setting new timer with duration of 126000 seconds. May 25 15:48:45 volumio volumio[1072]: info: [1748177325203] [MotherEarth] Error: read ECONNRESET May 25 15:48:45 volumio volumio[1072]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 15:48:46 volumio volumio[1072]: Error: Unable to resolve or reject the same promise twice May 25 15:48:46 volumio volumio[1072]: at Promise.resolve (/data/plugins/music_service/motherearthradio/node_modules/kew/kew.js:140:43) May 25 15:48:46 volumio volumio[1072]: at ClientRequest. (/data/plugins/music_service/motherearthradio/index.js:394:17) May 25 15:48:46 volumio volumio[1072]: at ClientRequest.emit (events.js:400:28) May 25 15:48:46 volumio volumio[1072]: at TLSSocket.socketErrorListener (_http_client.js:475:9) May 25 15:48:46 volumio volumio[1072]: at TLSSocket.emit (events.js:400:28) May 25 15:48:46 volumio volumio[1072]: at emitErrorNT (internal/streams/destroy.js:106:8) May 25 15:48:46 volumio volumio[1072]: at emitErrorCloseNT (internal/streams/destroy.js:74:3) May 25 15:48:46 volumio volumio[1072]: at processTicksAndRejections (internal/process/task_queues.js:82:21) May 25 15:48:46 volumio volumio[1072]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 15:48:50 volumio sudo[15896]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-25 15:47 May 25 15:48:50 volumio sudo[15896]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 20 Sep 2024 10:02:06 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="orangepione" VOLUMIO_DEVICENAME="Orange Pi One" VOLUMIO_HASH="196dbf07455fd638bf5080f981aa4211"