-- Logs begin at Mon 2026-08-31 04:35:12 CEST, end at Mon 2026-08-31 19:07:36 CEST. -- Aug 31 19:06:01 volumio volumio[1113]: info: CoreCommandRouter::volumioNext Aug 31 19:06:01 volumio volumio[1113]: info: CoreStateMachine::next Aug 31 19:06:01 volumio volumio[1113]: info: ControllerMpd::next Aug 31 19:06:01 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand next Aug 31 19:06:01 volumio volumio[1113]: info: Aug 31 19:06:01 volumio volumio[1113]: ---------------------------- MPD announces system playlist update Aug 31 19:06:01 volumio volumio[1113]: info: Ignoring MPD Status Update Aug 31 19:06:01 volumio volumio[1113]: info: Aug 31 19:06:01 volumio volumio[1113]: ---------------------------- MPD announces state update: player Aug 31 19:06:01 volumio volumio[1113]: info: sendMpdCommand next took 19 milliseconds Aug 31 19:06:01 volumio volumio[1113]: info: ControllerMpd::getState Aug 31 19:06:01 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Aug 31 19:06:01 volumio volumio[1113]: error: updateQueue error: null Aug 31 19:06:01 volumio volumio[1113]: info: ------------------------------ 6ms Aug 31 19:06:01 volumio volumio[1113]: info: sendMpdCommand status took 2 milliseconds Aug 31 19:06:01 volumio volumio[1113]: verbose: ControllerMpd::parseState Aug 31 19:06:01 volumio volumio[1113]: info: ControllerMpd::pushState Aug 31 19:06:01 volumio volumio[1113]: info: CoreCommandRouter::servicePushState Aug 31 19:06:01 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:06:01 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 19:06:01 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:06:01 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:06:01 volumio volumio[1113]: info: CorePlayQueue::getTrack 0 Aug 31 19:06:01 volumio volumio[1113]: 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} Aug 31 19:06:01 volumio volumio[1113]: verbose: CURRENT POSITION 0 Aug 31 19:06:01 volumio volumio[1113]: info: CoreStateMachine::syncState stateService stop Aug 31 19:06:01 volumio volumio[1113]: info: CoreStateMachine::syncState currentStatus play Aug 31 19:06:01 volumio volumio[1113]: info: CoreStateMachine::play index undefined Aug 31 19:06:01 volumio volumio[1113]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 19:06:01 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:06:01 volumio volumio[1113]: info: CorePlayQueue::getTrack 1 Aug 31 19:06:01 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 19:06:01 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:06:01 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:06:01 volumio volumio[1113]: info: CorePlayQueue::getTrack 1 Aug 31 19:06:01 volumio volumio[1113]: info: CorePlayQueue::getTrack 1 Aug 31 19:06:01 volumio volumio[1113]: info: CoreStateMachine::startPlaybackTimer Aug 31 19:06:01 volumio volumio[1113]: info: CorePlayQueue::getTrack 1 Aug 31 19:06:01 volumio volumio[1113]: info: [1788195961184] ControllerUPNPBrowser::clearAddPlayTrack Aug 31 19:06:01 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 19:06:01 volumio volumio[1113]: info: ------------------------------ 55ms Aug 31 19:06:01 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:06:01 volumio volumio[1113]: info: CorePlayQueue::getTrack 1 Aug 31 19:06:01 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 19:06:01 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:06:01 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:06:01 volumio volumio[1113]: info: CorePlayQueue::getTrack 1 Aug 31 19:06:01 volumio volumio[1113]: info: sendMpdCommand stop took 10 milliseconds Aug 31 19:06:01 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand clear Aug 31 19:06:01 volumio volumio[1113]: info: CoreCommandRouter::volumioGetQueue Aug 31 19:06:01 volumio volumio[1113]: info: CoreStateMachine::getQueue Aug 31 19:06:01 volumio volumio[1113]: info: CorePlayQueue::getQueue Aug 31 19:06:01 volumio volumio[1113]: info: Aug 31 19:06:01 volumio volumio[1113]: ---------------------------- MPD announces system playlist update Aug 31 19:06:01 volumio volumio[1113]: info: Ignoring MPD Status Update Aug 31 19:06:01 volumio volumio[1113]: info: sendMpdCommand clear took 15 milliseconds Aug 31 19:06:01 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.178.88:9790/minimserver/*/music/Michael*20Jackson*20-*20Invincible*20(2001)*20*5b24bit*20Hi-Res*5d/02*20-*20Heartbreaker*20(feat.*20Fats).flac" Aug 31 19:06:01 volumio volumio[1113]: error: updateQueue error: null Aug 31 19:06:01 volumio volumio[1113]: info: ------------------------------ 4ms Aug 31 19:06:01 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.178.88:9790/minimserver/*/music/Michael*20Jackson*20-*20Invincible*20(2001)*20*5b24bit*20Hi-Res*5d/02*20-*20Heartbreaker*20(feat.*20Fats).flac" Aug 31 19:06:01 volumio volumio[1113]: info: Aug 31 19:06:01 volumio volumio[1113]: ---------------------------- MPD announces system playlist update Aug 31 19:06:01 volumio volumio[1113]: info: Ignoring MPD Status Update Aug 31 19:06:01 volumio volumio[1113]: info: sendMpdCommand add "http://192.168.178.88:9790/minimserver/*/music/Michael*20Jackson*20-*20Invincible*20(2001)*20*5b24bit*20Hi-Res*5d/02*20-*20Heartbreaker*20(feat.*20Fats).flac" took 1 milliseconds Aug 31 19:06:01 volumio volumio[1113]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 31 19:06:01 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand play Aug 31 19:06:01 volumio volumio[1113]: info: ------------------------------ 3ms Aug 31 19:06:01 volumio volumio[1113]: info: sendMpdCommand play took 2 milliseconds Aug 31 19:06:01 volumio volumio[1113]: info: Aug 31 19:06:01 volumio volumio[1113]: ---------------------------- MPD announces state update: player Aug 31 19:06:01 volumio volumio[1113]: info: ControllerMpd::getState Aug 31 19:06:01 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Aug 31 19:06:01 volumio volumio[1113]: info: Aug 31 19:06:01 volumio volumio[1113]: ---------------------------- MPD announces state update: player Aug 31 19:06:01 volumio volumio[1113]: info: sendMpdCommand status took 2 milliseconds Aug 31 19:06:01 volumio volumio[1113]: info: ControllerMpd::getState Aug 31 19:06:01 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Aug 31 19:06:01 volumio volumio[1113]: verbose: ControllerMpd::parseState Aug 31 19:06:01 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 19:06:01 volumio volumio[1113]: info: sendMpdCommand status took 1 milliseconds Aug 31 19:06:01 volumio volumio[1113]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 31 19:06:01 volumio volumio[1113]: verbose: ControllerMpd::parseState Aug 31 19:06:01 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 19:06:01 volumio volumio[1113]: verbose: ControllerMpd::parseTrackInfo Aug 31 19:06:01 volumio volumio[1113]: info: ControllerMpd::pushState Aug 31 19:06:01 volumio volumio[1113]: info: CoreCommandRouter::servicePushState Aug 31 19:06:01 volumio volumio[1113]: info: CorePlayQueue::getTrack 1 Aug 31 19:06:01 volumio volumio[1113]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":308,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"02*20-*20Heartbreaker*20(feat.*20Fats).flac","artist":null,"album":null,"uri":"http://192.168.178.88:9790/minimserver/*/music/Michael*20Jackson*20-*20Invincible*20(2001)*20*5b24bit*20Hi-Res*5d/02*20-*20Heartbreaker*20(feat.*20Fats).flac","trackType":"flac"} Aug 31 19:06:01 volumio volumio[1113]: verbose: CURRENT POSITION 1 Aug 31 19:06:01 volumio volumio[1113]: info: CoreStateMachine::syncState stateService play Aug 31 19:06:01 volumio volumio[1113]: info: CoreStateMachine::syncState currentStatus stop Aug 31 19:06:01 volumio volumio[1113]: info: ------------------------------ 10ms Aug 31 19:06:01 volumio volumio[1113]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 31 19:06:01 volumio volumio[1113]: verbose: ControllerMpd::parseTrackInfo Aug 31 19:06:01 volumio volumio[1113]: info: ControllerMpd::pushState Aug 31 19:06:01 volumio volumio[1113]: info: CoreCommandRouter::servicePushState Aug 31 19:06:01 volumio volumio[1113]: info: CorePlayQueue::getTrack 1 Aug 31 19:06:01 volumio volumio[1113]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":308,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"02*20-*20Heartbreaker*20(feat.*20Fats).flac","artist":null,"album":null,"uri":"http://192.168.178.88:9790/minimserver/*/music/Michael*20Jackson*20-*20Invincible*20(2001)*20*5b24bit*20Hi-Res*5d/02*20-*20Heartbreaker*20(feat.*20Fats).flac","trackType":"flac"} Aug 31 19:06:01 volumio volumio[1113]: verbose: CURRENT POSITION 1 Aug 31 19:06:01 volumio volumio[1113]: info: CoreStateMachine::syncState stateService play Aug 31 19:06:01 volumio volumio[1113]: info: CoreStateMachine::syncState currentStatus play Aug 31 19:06:01 volumio volumio[1113]: info: Received an update from plugin. extracting info from payload Aug 31 19:06:01 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:06:01 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 19:06:01 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:06:01 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:06:01 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:06:01 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:06:01 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:06:01 volumio volumio[1113]: info: ------------------------------ 50ms Aug 31 19:06:02 volumio volumio[1113]: info: Aug 31 19:06:02 volumio volumio[1113]: ---------------------------- MPD announces system playlist update Aug 31 19:06:02 volumio volumio[1113]: info: Ignoring MPD Status Update Aug 31 19:06:02 volumio volumio[1113]: info: Aug 31 19:06:02 volumio volumio[1113]: ---------------------------- MPD announces state update: player Aug 31 19:06:02 volumio volumio[1113]: info: ControllerMpd::getState Aug 31 19:06:02 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Aug 31 19:06:02 volumio volumio[1113]: info: ------------------------------ 3ms Aug 31 19:06:02 volumio volumio[1113]: info: sendMpdCommand status took 2 milliseconds Aug 31 19:06:02 volumio volumio[1113]: verbose: ControllerMpd::parseState Aug 31 19:06:02 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 19:06:02 volumio volumio[1113]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 31 19:06:02 volumio volumio[1113]: verbose: ControllerMpd::parseTrackInfo Aug 31 19:06:02 volumio volumio[1113]: info: ControllerMpd::pushState Aug 31 19:06:02 volumio volumio[1113]: info: CoreCommandRouter::servicePushState Aug 31 19:06:02 volumio volumio[1113]: info: CorePlayQueue::getTrack 1 Aug 31 19:06:02 volumio volumio[1113]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":308,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1131 Kbps","isStreaming":false,"title":"Heartbreaker (feat. Fats)","artist":"Michael Jackson","album":"Invincible","uri":"http://192.168.178.88:9790/minimserver/*/music/Michael*20Jackson*20-*20Invincible*20(2001)*20*5b24bit*20Hi-Res*5d/02*20-*20Heartbreaker*20(feat.*20Fats).flac","trackType":"flac"} Aug 31 19:06:02 volumio volumio[1113]: verbose: CURRENT POSITION 1 Aug 31 19:06:02 volumio volumio[1113]: info: CoreStateMachine::syncState stateService play Aug 31 19:06:02 volumio volumio[1113]: info: CoreStateMachine::syncState currentStatus play Aug 31 19:06:02 volumio volumio[1113]: info: Received an update from plugin. extracting info from payload Aug 31 19:06:02 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:06:02 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 19:06:02 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:06:02 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:06:02 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:06:02 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:06:02 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:06:02 volumio volumio[1113]: info: ------------------------------ 30ms Aug 31 19:06:02 volumio volumio[1113]: info: CoreCommandRouter::volumioGetQueue Aug 31 19:06:02 volumio volumio[1113]: info: CoreStateMachine::getQueue Aug 31 19:06:02 volumio volumio[1113]: info: CorePlayQueue::getQueue Aug 31 19:06:02 volumio volumio[1113]: info: CoreCommandRouter::volumioGetQueue Aug 31 19:06:02 volumio volumio[1113]: info: CoreStateMachine::getQueue Aug 31 19:06:02 volumio volumio[1113]: info: CorePlayQueue::getQueue Aug 31 19:06:04 volumio volumio[1113]: info: Executing endpoint metavolumio Aug 31 19:06:04 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 31 19:06:04 volumio volumio[1113]: info: Executing endpoint metavolumio Aug 31 19:06:04 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 31 19:06:04 volumio volumio[1113]: info: Executing endpoint metavolumio Aug 31 19:06:04 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 31 19:06:08 volumio volumio[1113]: info: CoreCommandRouter::volumioNext Aug 31 19:06:08 volumio volumio[1113]: info: CoreStateMachine::next Aug 31 19:06:08 volumio volumio[1113]: info: ControllerMpd::next Aug 31 19:06:08 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand next Aug 31 19:06:08 volumio volumio[1113]: info: Aug 31 19:06:08 volumio volumio[1113]: ---------------------------- MPD announces system playlist update Aug 31 19:06:08 volumio volumio[1113]: info: Ignoring MPD Status Update Aug 31 19:06:08 volumio volumio[1113]: info: Aug 31 19:06:08 volumio volumio[1113]: ---------------------------- MPD announces state update: player Aug 31 19:06:08 volumio volumio[1113]: info: sendMpdCommand next took 7 milliseconds Aug 31 19:06:08 volumio volumio[1113]: info: ControllerMpd::getState Aug 31 19:06:08 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Aug 31 19:06:08 volumio volumio[1113]: error: updateQueue error: null Aug 31 19:06:08 volumio volumio[1113]: info: ------------------------------ 2ms Aug 31 19:06:08 volumio volumio[1113]: info: sendMpdCommand status took 1 milliseconds Aug 31 19:06:08 volumio volumio[1113]: verbose: ControllerMpd::parseState Aug 31 19:06:08 volumio volumio[1113]: info: ControllerMpd::pushState Aug 31 19:06:08 volumio volumio[1113]: info: CoreCommandRouter::servicePushState Aug 31 19:06:08 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:06:08 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 19:06:08 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:06:08 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:06:08 volumio volumio[1113]: info: CorePlayQueue::getTrack 1 Aug 31 19:06:08 volumio volumio[1113]: 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} Aug 31 19:06:08 volumio volumio[1113]: verbose: CURRENT POSITION 1 Aug 31 19:06:08 volumio volumio[1113]: info: CoreStateMachine::syncState stateService stop Aug 31 19:06:08 volumio volumio[1113]: info: CoreStateMachine::syncState currentStatus play Aug 31 19:06:08 volumio volumio[1113]: info: CoreStateMachine::play index undefined Aug 31 19:06:08 volumio volumio[1113]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 19:06:08 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:06:08 volumio volumio[1113]: info: CorePlayQueue::getTrack 2 Aug 31 19:06:08 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 19:06:08 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:06:08 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:06:08 volumio volumio[1113]: info: CorePlayQueue::getTrack 2 Aug 31 19:06:08 volumio volumio[1113]: info: CorePlayQueue::getTrack 2 Aug 31 19:06:08 volumio volumio[1113]: info: CoreStateMachine::startPlaybackTimer Aug 31 19:06:08 volumio volumio[1113]: info: CorePlayQueue::getTrack 2 Aug 31 19:06:08 volumio volumio[1113]: info: [1788195968475] ControllerUPNPBrowser::clearAddPlayTrack Aug 31 19:06:08 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 19:06:08 volumio volumio[1113]: info: ------------------------------ 16ms Aug 31 19:06:08 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:06:08 volumio volumio[1113]: info: CorePlayQueue::getTrack 2 Aug 31 19:06:08 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 19:06:08 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:06:08 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:06:08 volumio volumio[1113]: info: CorePlayQueue::getTrack 2 Aug 31 19:06:08 volumio volumio[1113]: info: sendMpdCommand stop took 7 milliseconds Aug 31 19:06:08 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand clear Aug 31 19:06:08 volumio volumio[1113]: info: CoreCommandRouter::volumioGetQueue Aug 31 19:06:08 volumio volumio[1113]: info: CoreStateMachine::getQueue Aug 31 19:06:08 volumio volumio[1113]: info: CorePlayQueue::getQueue Aug 31 19:06:08 volumio volumio[1113]: info: Aug 31 19:06:08 volumio volumio[1113]: ---------------------------- MPD announces system playlist update Aug 31 19:06:08 volumio volumio[1113]: info: Ignoring MPD Status Update Aug 31 19:06:08 volumio volumio[1113]: info: sendMpdCommand clear took 31 milliseconds Aug 31 19:06:08 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.178.88:9790/minimserver/*/music/Michael*20Jackson*20-*20Invincible*20(2001)*20*5b24bit*20Hi-Res*5d/03*20-*20Invincible*20(feat.*20Fats).flac" Aug 31 19:06:08 volumio volumio[1113]: error: updateQueue error: null Aug 31 19:06:08 volumio volumio[1113]: info: ------------------------------ 7ms Aug 31 19:06:08 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:06:08 volumio volumio[1113]: info: CorePlayQueue::getTrack 2 Aug 31 19:06:09 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.178.88:9790/minimserver/*/music/Michael*20Jackson*20-*20Invincible*20(2001)*20*5b24bit*20Hi-Res*5d/03*20-*20Invincible*20(feat.*20Fats).flac" Aug 31 19:06:09 volumio volumio[1113]: info: Aug 31 19:06:09 volumio volumio[1113]: ---------------------------- MPD announces system playlist update Aug 31 19:06:09 volumio volumio[1113]: info: Ignoring MPD Status Update Aug 31 19:06:09 volumio volumio[1113]: info: sendMpdCommand add "http://192.168.178.88:9790/minimserver/*/music/Michael*20Jackson*20-*20Invincible*20(2001)*20*5b24bit*20Hi-Res*5d/03*20-*20Invincible*20(feat.*20Fats).flac" took 1 milliseconds Aug 31 19:06:09 volumio volumio[1113]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 31 19:06:09 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand play Aug 31 19:06:09 volumio volumio[1113]: info: ------------------------------ 2ms Aug 31 19:06:09 volumio volumio[1113]: info: sendMpdCommand play took 2 milliseconds Aug 31 19:06:09 volumio volumio[1113]: info: Aug 31 19:06:09 volumio volumio[1113]: ---------------------------- MPD announces state update: player Aug 31 19:06:09 volumio volumio[1113]: info: ControllerMpd::getState Aug 31 19:06:09 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Aug 31 19:06:09 volumio volumio[1113]: info: sendMpdCommand status took 2 milliseconds Aug 31 19:06:09 volumio volumio[1113]: verbose: ControllerMpd::parseState Aug 31 19:06:09 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 19:06:09 volumio volumio[1113]: info: Aug 31 19:06:09 volumio volumio[1113]: ---------------------------- MPD announces state update: player Aug 31 19:06:09 volumio volumio[1113]: info: ControllerMpd::getState Aug 31 19:06:09 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Aug 31 19:06:09 volumio volumio[1113]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 31 19:06:09 volumio volumio[1113]: verbose: ControllerMpd::parseTrackInfo Aug 31 19:06:09 volumio volumio[1113]: info: ControllerMpd::pushState Aug 31 19:06:09 volumio volumio[1113]: info: CoreCommandRouter::servicePushState Aug 31 19:06:09 volumio volumio[1113]: info: CorePlayQueue::getTrack 2 Aug 31 19:06:09 volumio volumio[1113]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":286,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"03*20-*20Invincible*20(feat.*20Fats).flac","artist":null,"album":null,"uri":"http://192.168.178.88:9790/minimserver/*/music/Michael*20Jackson*20-*20Invincible*20(2001)*20*5b24bit*20Hi-Res*5d/03*20-*20Invincible*20(feat.*20Fats).flac","trackType":"flac"} Aug 31 19:06:09 volumio volumio[1113]: verbose: CURRENT POSITION 2 Aug 31 19:06:09 volumio volumio[1113]: info: CoreStateMachine::syncState stateService play Aug 31 19:06:09 volumio volumio[1113]: info: CoreStateMachine::syncState currentStatus stop Aug 31 19:06:09 volumio volumio[1113]: info: ------------------------------ 6ms Aug 31 19:06:09 volumio volumio[1113]: info: sendMpdCommand status took 1 milliseconds Aug 31 19:06:09 volumio volumio[1113]: verbose: ControllerMpd::parseState Aug 31 19:06:09 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 19:06:09 volumio volumio[1113]: info: sendMpdCommand playlistinfo took 0 milliseconds Aug 31 19:06:09 volumio volumio[1113]: verbose: ControllerMpd::parseTrackInfo Aug 31 19:06:09 volumio volumio[1113]: info: ControllerMpd::pushState Aug 31 19:06:09 volumio volumio[1113]: info: CoreCommandRouter::servicePushState Aug 31 19:06:09 volumio volumio[1113]: info: CorePlayQueue::getTrack 2 Aug 31 19:06:09 volumio volumio[1113]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":286,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"03*20-*20Invincible*20(feat.*20Fats).flac","artist":null,"album":null,"uri":"http://192.168.178.88:9790/minimserver/*/music/Michael*20Jackson*20-*20Invincible*20(2001)*20*5b24bit*20Hi-Res*5d/03*20-*20Invincible*20(feat.*20Fats).flac","trackType":"flac"} Aug 31 19:06:09 volumio volumio[1113]: verbose: CURRENT POSITION 2 Aug 31 19:06:09 volumio volumio[1113]: info: CoreStateMachine::syncState stateService play Aug 31 19:06:09 volumio volumio[1113]: info: CoreStateMachine::syncState currentStatus play Aug 31 19:06:09 volumio volumio[1113]: info: Received an update from plugin. extracting info from payload Aug 31 19:06:09 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:06:09 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 19:06:09 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:06:09 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:06:09 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:06:09 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:06:09 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:06:09 volumio volumio[1113]: info: ------------------------------ 42ms Aug 31 19:06:09 volumio volumio[1113]: info: Aug 31 19:06:09 volumio volumio[1113]: ---------------------------- MPD announces system playlist update Aug 31 19:06:09 volumio volumio[1113]: info: Ignoring MPD Status Update Aug 31 19:06:09 volumio volumio[1113]: info: Aug 31 19:06:09 volumio volumio[1113]: ---------------------------- MPD announces state update: player Aug 31 19:06:09 volumio volumio[1113]: info: ControllerMpd::getState Aug 31 19:06:09 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Aug 31 19:06:09 volumio volumio[1113]: info: ------------------------------ 2ms Aug 31 19:06:09 volumio volumio[1113]: info: sendMpdCommand status took 2 milliseconds Aug 31 19:06:09 volumio volumio[1113]: verbose: ControllerMpd::parseState Aug 31 19:06:09 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 19:06:09 volumio volumio[1113]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 31 19:06:09 volumio volumio[1113]: verbose: ControllerMpd::parseTrackInfo Aug 31 19:06:09 volumio volumio[1113]: info: ControllerMpd::pushState Aug 31 19:06:09 volumio volumio[1113]: info: CoreCommandRouter::servicePushState Aug 31 19:06:09 volumio volumio[1113]: info: CorePlayQueue::getTrack 2 Aug 31 19:06:09 volumio volumio[1113]: verbose: STATE SERVICE {"status":"play","position":0,"seek":114,"duration":286,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1323 Kbps","isStreaming":false,"title":"Invincible (feat. Fats)","artist":"Michael Jackson","album":"Invincible","uri":"http://192.168.178.88:9790/minimserver/*/music/Michael*20Jackson*20-*20Invincible*20(2001)*20*5b24bit*20Hi-Res*5d/03*20-*20Invincible*20(feat.*20Fats).flac","trackType":"flac"} Aug 31 19:06:09 volumio volumio[1113]: verbose: CURRENT POSITION 2 Aug 31 19:06:09 volumio volumio[1113]: info: CoreStateMachine::syncState stateService play Aug 31 19:06:09 volumio volumio[1113]: info: CoreStateMachine::syncState currentStatus play Aug 31 19:06:09 volumio volumio[1113]: info: Received an update from plugin. extracting info from payload Aug 31 19:06:09 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:06:09 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 19:06:09 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:06:09 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:06:09 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:06:09 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:06:09 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:06:09 volumio volumio[1113]: info: ------------------------------ 26ms Aug 31 19:06:09 volumio volumio[1113]: info: CoreCommandRouter::volumioGetQueue Aug 31 19:06:09 volumio volumio[1113]: info: CoreStateMachine::getQueue Aug 31 19:06:09 volumio volumio[1113]: info: CorePlayQueue::getQueue Aug 31 19:06:09 volumio volumio[1113]: info: CoreCommandRouter::volumioGetQueue Aug 31 19:06:09 volumio volumio[1113]: info: CoreStateMachine::getQueue Aug 31 19:06:09 volumio volumio[1113]: info: CorePlayQueue::getQueue Aug 31 19:06:10 volumio volumio[1113]: verbose: New Socket.io Connection to 192.168.178.71 from 192.168.178.103 UA: Mozilla/5.0 (Linux; Android 16; SM-A566B Build/BP4A.251205.006; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/151.0.7922.200 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Aug 31 19:06:10 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 31 19:06:10 volumio volumio[1113]: info: CoreCommandRouter::volumioGetVisibleSources Aug 31 19:06:10 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 31 19:06:10 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:06:10 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 31 19:06:10 volumio volumio[1113]: info: CoreCommandRouter::volumioGetQueue Aug 31 19:06:10 volumio volumio[1113]: info: CoreStateMachine::getQueue Aug 31 19:06:10 volumio volumio[1113]: info: CorePlayQueue::getQueue Aug 31 19:06:10 volumio volumio[1113]: info: Listing playlists Aug 31 19:06:10 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 31 19:06:10 volumio volumio[1113]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Aug 31 19:06:10 volumio volumio[1113]: info: Received Get System Info Aug 31 19:06:10 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 19:06:10 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 19:06:10 volumio volumio[1113]: info: Discovery: Getting this device information Aug 31 19:06:10 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:06:10 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 19:06:10 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:06:10 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 31 19:06:11 volumio volumio[1113]: info: Executing endpoint metavolumio Aug 31 19:06:11 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 31 19:06:12 volumio volumio[1113]: info: Executing endpoint metavolumio Aug 31 19:06:12 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 31 19:06:12 volumio volumio[1113]: info: Executing endpoint metavolumio Aug 31 19:06:12 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 31 19:06:12 volumio volumio[1113]: error: Failed request for metavolumio API Aug 31 19:06:12 volumio volumio[1113]: error: Failed request for metavolumio API Aug 31 19:06:14 volumio volumio[1113]: info: CoreCommandRouter::volumioNext Aug 31 19:06:14 volumio volumio[1113]: info: CoreStateMachine::next Aug 31 19:06:14 volumio volumio[1113]: info: ControllerMpd::next Aug 31 19:06:14 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand next Aug 31 19:06:14 volumio volumio[1113]: info: Aug 31 19:06:14 volumio volumio[1113]: ---------------------------- MPD announces system playlist update Aug 31 19:06:14 volumio volumio[1113]: info: Ignoring MPD Status Update Aug 31 19:06:14 volumio volumio[1113]: info: Aug 31 19:06:14 volumio volumio[1113]: ---------------------------- MPD announces state update: player Aug 31 19:06:14 volumio volumio[1113]: info: sendMpdCommand next took 6 milliseconds Aug 31 19:06:14 volumio volumio[1113]: info: ControllerMpd::getState Aug 31 19:06:14 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Aug 31 19:06:14 volumio volumio[1113]: error: updateQueue error: null Aug 31 19:06:14 volumio volumio[1113]: info: ------------------------------ 2ms Aug 31 19:06:14 volumio volumio[1113]: info: sendMpdCommand status took 1 milliseconds Aug 31 19:06:14 volumio volumio[1113]: verbose: ControllerMpd::parseState Aug 31 19:06:14 volumio volumio[1113]: info: ControllerMpd::pushState Aug 31 19:06:14 volumio volumio[1113]: info: CoreCommandRouter::servicePushState Aug 31 19:06:14 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:06:14 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 19:06:14 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:06:14 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:06:14 volumio volumio[1113]: info: CorePlayQueue::getTrack 2 Aug 31 19:06:14 volumio volumio[1113]: 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} Aug 31 19:06:14 volumio volumio[1113]: verbose: CURRENT POSITION 2 Aug 31 19:06:14 volumio volumio[1113]: info: CoreStateMachine::syncState stateService stop Aug 31 19:06:14 volumio volumio[1113]: info: CoreStateMachine::syncState currentStatus play Aug 31 19:06:14 volumio volumio[1113]: info: CoreStateMachine::play index undefined Aug 31 19:06:14 volumio volumio[1113]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 19:06:14 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:06:14 volumio volumio[1113]: info: CorePlayQueue::getTrack 3 Aug 31 19:06:14 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 19:06:14 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:06:14 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:06:14 volumio volumio[1113]: info: CorePlayQueue::getTrack 3 Aug 31 19:06:14 volumio volumio[1113]: info: CorePlayQueue::getTrack 3 Aug 31 19:06:14 volumio volumio[1113]: info: CoreStateMachine::startPlaybackTimer Aug 31 19:06:14 volumio volumio[1113]: info: CorePlayQueue::getTrack 3 Aug 31 19:06:14 volumio volumio[1113]: info: [1788195974319] ControllerUPNPBrowser::clearAddPlayTrack Aug 31 19:06:14 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 19:06:14 volumio volumio[1113]: info: ------------------------------ 25ms Aug 31 19:06:14 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:06:14 volumio volumio[1113]: info: CorePlayQueue::getTrack 3 Aug 31 19:06:14 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 19:06:14 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:06:14 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:06:14 volumio volumio[1113]: info: CorePlayQueue::getTrack 3 Aug 31 19:06:14 volumio volumio[1113]: info: sendMpdCommand stop took 9 milliseconds Aug 31 19:06:14 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand clear Aug 31 19:06:14 volumio volumio[1113]: info: CoreCommandRouter::volumioGetQueue Aug 31 19:06:14 volumio volumio[1113]: info: CoreStateMachine::getQueue Aug 31 19:06:14 volumio volumio[1113]: info: CorePlayQueue::getQueue Aug 31 19:06:14 volumio volumio[1113]: info: Aug 31 19:06:14 volumio volumio[1113]: ---------------------------- MPD announces system playlist update Aug 31 19:06:14 volumio volumio[1113]: info: Ignoring MPD Status Update Aug 31 19:06:14 volumio volumio[1113]: info: sendMpdCommand clear took 20 milliseconds Aug 31 19:06:14 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.178.88:9790/minimserver/*/music/Michael*20Jackson*20-*20Invincible*20(2001)*20*5b24bit*20Hi-Res*5d/04*20-*20Break*20of*20Dawn.flac" Aug 31 19:06:15 volumio volumio[1113]: error: updateQueue error: null Aug 31 19:06:15 volumio volumio[1113]: info: ------------------------------ 769ms Aug 31 19:06:15 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.178.88:9790/minimserver/*/music/Michael*20Jackson*20-*20Invincible*20(2001)*20*5b24bit*20Hi-Res*5d/04*20-*20Break*20of*20Dawn.flac" Aug 31 19:06:15 volumio volumio[1113]: info: Aug 31 19:06:15 volumio volumio[1113]: ---------------------------- MPD announces system playlist update Aug 31 19:06:15 volumio volumio[1113]: info: Ignoring MPD Status Update Aug 31 19:06:15 volumio volumio[1113]: info: sendMpdCommand add "http://192.168.178.88:9790/minimserver/*/music/Michael*20Jackson*20-*20Invincible*20(2001)*20*5b24bit*20Hi-Res*5d/04*20-*20Break*20of*20Dawn.flac" took 1 milliseconds Aug 31 19:06:15 volumio volumio[1113]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 31 19:06:15 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand play Aug 31 19:06:15 volumio volumio[1113]: info: ------------------------------ 2ms Aug 31 19:06:15 volumio volumio[1113]: info: sendMpdCommand play took 3 milliseconds Aug 31 19:06:15 volumio volumio[1113]: info: Aug 31 19:06:15 volumio volumio[1113]: ---------------------------- MPD announces state update: player Aug 31 19:06:15 volumio volumio[1113]: info: ControllerMpd::getState Aug 31 19:06:15 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Aug 31 19:06:15 volumio volumio[1113]: info: Aug 31 19:06:15 volumio volumio[1113]: ---------------------------- MPD announces state update: player Aug 31 19:06:15 volumio volumio[1113]: info: sendMpdCommand status took 3 milliseconds Aug 31 19:06:15 volumio volumio[1113]: info: ControllerMpd::getState Aug 31 19:06:15 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Aug 31 19:06:15 volumio volumio[1113]: verbose: ControllerMpd::parseState Aug 31 19:06:15 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 19:06:15 volumio volumio[1113]: info: sendMpdCommand status took 4 milliseconds Aug 31 19:06:15 volumio volumio[1113]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 31 19:06:15 volumio volumio[1113]: verbose: ControllerMpd::parseState Aug 31 19:06:15 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 19:06:15 volumio volumio[1113]: verbose: ControllerMpd::parseTrackInfo Aug 31 19:06:15 volumio volumio[1113]: info: ControllerMpd::pushState Aug 31 19:06:15 volumio volumio[1113]: info: CoreCommandRouter::servicePushState Aug 31 19:06:15 volumio volumio[1113]: info: CorePlayQueue::getTrack 3 Aug 31 19:06:15 volumio volumio[1113]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":333,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"04*20-*20Break*20of*20Dawn.flac","artist":null,"album":null,"uri":"http://192.168.178.88:9790/minimserver/*/music/Michael*20Jackson*20-*20Invincible*20(2001)*20*5b24bit*20Hi-Res*5d/04*20-*20Break*20of*20Dawn.flac","trackType":"flac"} Aug 31 19:06:15 volumio volumio[1113]: verbose: CURRENT POSITION 3 Aug 31 19:06:15 volumio volumio[1113]: info: CoreStateMachine::syncState stateService play Aug 31 19:06:15 volumio volumio[1113]: info: CoreStateMachine::syncState currentStatus stop Aug 31 19:06:15 volumio volumio[1113]: info: ------------------------------ 21ms Aug 31 19:06:15 volumio volumio[1113]: info: sendMpdCommand playlistinfo took 9 milliseconds Aug 31 19:06:15 volumio volumio[1113]: verbose: ControllerMpd::parseTrackInfo Aug 31 19:06:15 volumio volumio[1113]: info: ControllerMpd::pushState Aug 31 19:06:15 volumio volumio[1113]: info: CoreCommandRouter::servicePushState Aug 31 19:06:15 volumio volumio[1113]: info: CorePlayQueue::getTrack 3 Aug 31 19:06:15 volumio volumio[1113]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":333,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"04*20-*20Break*20of*20Dawn.flac","artist":null,"album":null,"uri":"http://192.168.178.88:9790/minimserver/*/music/Michael*20Jackson*20-*20Invincible*20(2001)*20*5b24bit*20Hi-Res*5d/04*20-*20Break*20of*20Dawn.flac","trackType":"flac"} Aug 31 19:06:15 volumio volumio[1113]: verbose: CURRENT POSITION 3 Aug 31 19:06:15 volumio volumio[1113]: info: CoreStateMachine::syncState stateService play Aug 31 19:06:15 volumio volumio[1113]: info: CoreStateMachine::syncState currentStatus play Aug 31 19:06:15 volumio volumio[1113]: info: Received an update from plugin. extracting info from payload Aug 31 19:06:15 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:06:15 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 19:06:15 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:06:15 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:06:15 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:06:15 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:06:15 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:06:15 volumio volumio[1113]: info: ------------------------------ 52ms Aug 31 19:06:15 volumio volumio[1113]: info: Aug 31 19:06:15 volumio volumio[1113]: ---------------------------- MPD announces system playlist update Aug 31 19:06:15 volumio volumio[1113]: info: Ignoring MPD Status Update Aug 31 19:06:15 volumio volumio[1113]: info: Aug 31 19:06:15 volumio volumio[1113]: ---------------------------- MPD announces state update: player Aug 31 19:06:15 volumio volumio[1113]: info: ControllerMpd::getState Aug 31 19:06:15 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Aug 31 19:06:15 volumio volumio[1113]: info: ------------------------------ 4ms Aug 31 19:06:15 volumio volumio[1113]: info: sendMpdCommand status took 1 milliseconds Aug 31 19:06:15 volumio volumio[1113]: verbose: ControllerMpd::parseState Aug 31 19:06:15 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 19:06:15 volumio volumio[1113]: info: sendMpdCommand playlistinfo took 13 milliseconds Aug 31 19:06:15 volumio volumio[1113]: verbose: ControllerMpd::parseTrackInfo Aug 31 19:06:15 volumio volumio[1113]: info: ControllerMpd::pushState Aug 31 19:06:15 volumio volumio[1113]: info: CoreCommandRouter::servicePushState Aug 31 19:06:15 volumio volumio[1113]: info: CorePlayQueue::getTrack 3 Aug 31 19:06:15 volumio volumio[1113]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":333,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"824 Kbps","isStreaming":false,"title":"Break of Dawn","artist":"Michael Jackson","album":"Invincible","uri":"http://192.168.178.88:9790/minimserver/*/music/Michael*20Jackson*20-*20Invincible*20(2001)*20*5b24bit*20Hi-Res*5d/04*20-*20Break*20of*20Dawn.flac","trackType":"flac"} Aug 31 19:06:15 volumio volumio[1113]: verbose: CURRENT POSITION 3 Aug 31 19:06:15 volumio volumio[1113]: info: CoreStateMachine::syncState stateService play Aug 31 19:06:15 volumio volumio[1113]: info: CoreStateMachine::syncState currentStatus play Aug 31 19:06:15 volumio volumio[1113]: info: Received an update from plugin. extracting info from payload Aug 31 19:06:15 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:06:15 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 19:06:15 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:06:15 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:06:15 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:06:15 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:06:15 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:06:15 volumio volumio[1113]: info: ------------------------------ 31ms Aug 31 19:06:15 volumio volumio[1113]: info: CoreCommandRouter::volumioGetQueue Aug 31 19:06:15 volumio volumio[1113]: info: CoreStateMachine::getQueue Aug 31 19:06:15 volumio volumio[1113]: info: CorePlayQueue::getQueue Aug 31 19:06:15 volumio volumio[1113]: info: CoreCommandRouter::volumioGetQueue Aug 31 19:06:15 volumio volumio[1113]: info: CoreStateMachine::getQueue Aug 31 19:06:15 volumio volumio[1113]: info: CorePlayQueue::getQueue Aug 31 19:06:17 volumio volumio[1113]: info: Executing endpoint metavolumio Aug 31 19:06:17 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 31 19:06:17 volumio volumio[1113]: info: Executing endpoint metavolumio Aug 31 19:06:17 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 31 19:06:17 volumio volumio[1113]: info: Executing endpoint metavolumio Aug 31 19:06:17 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 31 19:06:18 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:06:18 volumio volumio[1113]: info: Listing playlists Aug 31 19:06:18 volumio volumio[1113]: info: Listing playlists Aug 31 19:06:19 volumio volumio[1113]: error: Failed request for metavolumio API Aug 31 19:06:19 volumio volumio[1113]: error: Failed request for metavolumio API Aug 31 19:06:25 volumio volumio[1113]: error: Failed request for metavolumio API Aug 31 19:06:25 volumio volumio[1113]: error: Failed request for metavolumio API Aug 31 19:06:25 volumio volumio[1113]: verbose: New Socket.io Connection to 192.168.178.71 from 192.168.178.103 UA: Mozilla/5.0 (Linux; Android 16; SM-A566B Build/BP4A.251205.006; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/151.0.7922.200 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Aug 31 19:06:25 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 31 19:06:26 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 31 19:06:26 volumio volumio[1113]: info: CoreCommandRouter::volumioGetVisibleSources Aug 31 19:06:26 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 31 19:06:26 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:06:26 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 31 19:06:26 volumio volumio[1113]: info: CoreCommandRouter::volumioGetQueue Aug 31 19:06:26 volumio volumio[1113]: info: CoreStateMachine::getQueue Aug 31 19:06:26 volumio volumio[1113]: info: CorePlayQueue::getQueue Aug 31 19:06:26 volumio volumio[1113]: info: Listing playlists Aug 31 19:06:26 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 31 19:06:26 volumio volumio[1113]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Aug 31 19:06:26 volumio volumio[1113]: info: Received Get System Info Aug 31 19:06:26 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 19:06:26 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 19:06:26 volumio volumio[1113]: info: Discovery: Getting this device information Aug 31 19:06:26 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:06:26 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 19:06:26 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:06:28 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:06:29 volumio volumio[1113]: info: CoreCommandRouter::volumioNext Aug 31 19:06:29 volumio volumio[1113]: info: CoreStateMachine::next Aug 31 19:06:29 volumio volumio[1113]: info: ControllerMpd::next Aug 31 19:06:29 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand next Aug 31 19:06:29 volumio volumio[1113]: info: Aug 31 19:06:29 volumio volumio[1113]: ---------------------------- MPD announces system playlist update Aug 31 19:06:29 volumio volumio[1113]: info: Ignoring MPD Status Update Aug 31 19:06:29 volumio volumio[1113]: info: Aug 31 19:06:29 volumio volumio[1113]: ---------------------------- MPD announces state update: player Aug 31 19:06:29 volumio volumio[1113]: info: sendMpdCommand next took 17 milliseconds Aug 31 19:06:29 volumio volumio[1113]: info: ControllerMpd::getState Aug 31 19:06:29 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Aug 31 19:06:29 volumio volumio[1113]: error: updateQueue error: null Aug 31 19:06:29 volumio volumio[1113]: info: ------------------------------ 2ms Aug 31 19:06:29 volumio volumio[1113]: info: sendMpdCommand status took 2 milliseconds Aug 31 19:06:29 volumio volumio[1113]: verbose: ControllerMpd::parseState Aug 31 19:06:29 volumio volumio[1113]: info: ControllerMpd::pushState Aug 31 19:06:29 volumio volumio[1113]: info: CoreCommandRouter::servicePushState Aug 31 19:06:29 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:06:29 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 19:06:29 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:06:29 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:06:29 volumio volumio[1113]: info: CorePlayQueue::getTrack 3 Aug 31 19:06:29 volumio volumio[1113]: 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} Aug 31 19:06:29 volumio volumio[1113]: verbose: CURRENT POSITION 3 Aug 31 19:06:29 volumio volumio[1113]: info: CoreStateMachine::syncState stateService stop Aug 31 19:06:29 volumio volumio[1113]: info: CoreStateMachine::syncState currentStatus play Aug 31 19:06:29 volumio volumio[1113]: info: CoreStateMachine::play index undefined Aug 31 19:06:29 volumio volumio[1113]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 19:06:29 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:06:29 volumio volumio[1113]: info: CorePlayQueue::getTrack 4 Aug 31 19:06:29 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 19:06:29 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:06:29 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:06:29 volumio volumio[1113]: info: CorePlayQueue::getTrack 4 Aug 31 19:06:29 volumio volumio[1113]: info: CoreStateMachine::startPlaybackTimer Aug 31 19:06:29 volumio volumio[1113]: info: CorePlayQueue::getTrack 4 Aug 31 19:06:29 volumio volumio[1113]: info: [1788195989628] ControllerUPNPBrowser::clearAddPlayTrack Aug 31 19:06:29 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 19:06:29 volumio volumio[1113]: info: ------------------------------ 19ms Aug 31 19:06:29 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:06:29 volumio volumio[1113]: info: CorePlayQueue::getTrack 4 Aug 31 19:06:29 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 19:06:29 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:06:29 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:06:29 volumio volumio[1113]: info: CorePlayQueue::getTrack 4 Aug 31 19:06:29 volumio volumio[1113]: info: sendMpdCommand stop took 9 milliseconds Aug 31 19:06:29 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand clear Aug 31 19:06:29 volumio volumio[1113]: info: CoreCommandRouter::volumioGetQueue Aug 31 19:06:29 volumio volumio[1113]: info: CoreStateMachine::getQueue Aug 31 19:06:29 volumio volumio[1113]: info: CorePlayQueue::getQueue Aug 31 19:06:29 volumio volumio[1113]: info: Aug 31 19:06:29 volumio volumio[1113]: ---------------------------- MPD announces system playlist update Aug 31 19:06:29 volumio volumio[1113]: info: Ignoring MPD Status Update Aug 31 19:06:29 volumio volumio[1113]: info: sendMpdCommand clear took 21 milliseconds Aug 31 19:06:29 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.178.88:9790/minimserver/*/music/Michael*20Jackson*20-*20Invincible*20(2001)*20*5b24bit*20Hi-Res*5d/05*20-*20Heaven*20Can*20Wait.flac" Aug 31 19:06:29 volumio volumio[1113]: error: updateQueue error: null Aug 31 19:06:29 volumio volumio[1113]: info: ------------------------------ 5ms Aug 31 19:06:30 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.178.88:9790/minimserver/*/music/Michael*20Jackson*20-*20Invincible*20(2001)*20*5b24bit*20Hi-Res*5d/05*20-*20Heaven*20Can*20Wait.flac" Aug 31 19:06:30 volumio volumio[1113]: info: Aug 31 19:06:30 volumio volumio[1113]: ---------------------------- MPD announces system playlist update Aug 31 19:06:30 volumio volumio[1113]: info: Ignoring MPD Status Update Aug 31 19:06:30 volumio volumio[1113]: info: sendMpdCommand add "http://192.168.178.88:9790/minimserver/*/music/Michael*20Jackson*20-*20Invincible*20(2001)*20*5b24bit*20Hi-Res*5d/05*20-*20Heaven*20Can*20Wait.flac" took 1 milliseconds Aug 31 19:06:30 volumio volumio[1113]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 31 19:06:30 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand play Aug 31 19:06:30 volumio volumio[1113]: info: ------------------------------ 2ms Aug 31 19:06:30 volumio volumio[1113]: info: sendMpdCommand play took 1 milliseconds Aug 31 19:06:30 volumio volumio[1113]: info: Aug 31 19:06:30 volumio volumio[1113]: ---------------------------- MPD announces state update: player Aug 31 19:06:30 volumio volumio[1113]: info: ControllerMpd::getState Aug 31 19:06:30 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Aug 31 19:06:30 volumio volumio[1113]: info: Aug 31 19:06:30 volumio volumio[1113]: ---------------------------- MPD announces state update: player Aug 31 19:06:30 volumio volumio[1113]: info: sendMpdCommand status took 2 milliseconds Aug 31 19:06:30 volumio volumio[1113]: info: ControllerMpd::getState Aug 31 19:06:30 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Aug 31 19:06:30 volumio volumio[1113]: verbose: ControllerMpd::parseState Aug 31 19:06:30 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 19:06:30 volumio volumio[1113]: info: sendMpdCommand status took 2 milliseconds Aug 31 19:06:30 volumio volumio[1113]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 31 19:06:30 volumio volumio[1113]: verbose: ControllerMpd::parseState Aug 31 19:06:30 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 19:06:30 volumio volumio[1113]: verbose: ControllerMpd::parseTrackInfo Aug 31 19:06:30 volumio volumio[1113]: info: ControllerMpd::pushState Aug 31 19:06:30 volumio volumio[1113]: info: CoreCommandRouter::servicePushState Aug 31 19:06:30 volumio volumio[1113]: info: CorePlayQueue::getTrack 4 Aug 31 19:06:30 volumio volumio[1113]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":290,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"05*20-*20Heaven*20Can*20Wait.flac","artist":null,"album":null,"uri":"http://192.168.178.88:9790/minimserver/*/music/Michael*20Jackson*20-*20Invincible*20(2001)*20*5b24bit*20Hi-Res*5d/05*20-*20Heaven*20Can*20Wait.flac","trackType":"flac"} Aug 31 19:06:30 volumio volumio[1113]: verbose: CURRENT POSITION 4 Aug 31 19:06:30 volumio volumio[1113]: info: CoreStateMachine::syncState stateService play Aug 31 19:06:30 volumio volumio[1113]: info: CoreStateMachine::syncState currentStatus stop Aug 31 19:06:30 volumio volumio[1113]: info: ------------------------------ 8ms Aug 31 19:06:30 volumio volumio[1113]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 31 19:06:30 volumio volumio[1113]: verbose: ControllerMpd::parseTrackInfo Aug 31 19:06:30 volumio volumio[1113]: info: ControllerMpd::pushState Aug 31 19:06:30 volumio volumio[1113]: info: CoreCommandRouter::servicePushState Aug 31 19:06:30 volumio volumio[1113]: info: CorePlayQueue::getTrack 4 Aug 31 19:06:30 volumio volumio[1113]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":290,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"05*20-*20Heaven*20Can*20Wait.flac","artist":null,"album":null,"uri":"http://192.168.178.88:9790/minimserver/*/music/Michael*20Jackson*20-*20Invincible*20(2001)*20*5b24bit*20Hi-Res*5d/05*20-*20Heaven*20Can*20Wait.flac","trackType":"flac"} Aug 31 19:06:30 volumio volumio[1113]: verbose: CURRENT POSITION 4 Aug 31 19:06:30 volumio volumio[1113]: info: CoreStateMachine::syncState stateService play Aug 31 19:06:30 volumio volumio[1113]: info: CoreStateMachine::syncState currentStatus play Aug 31 19:06:30 volumio volumio[1113]: info: Received an update from plugin. extracting info from payload Aug 31 19:06:30 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:06:30 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 19:06:30 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:06:30 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:06:30 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:06:30 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:06:30 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:06:30 volumio volumio[1113]: info: ------------------------------ 18ms Aug 31 19:06:30 volumio volumio[1113]: info: Aug 31 19:06:30 volumio volumio[1113]: ---------------------------- MPD announces system playlist update Aug 31 19:06:30 volumio volumio[1113]: info: Ignoring MPD Status Update Aug 31 19:06:30 volumio volumio[1113]: info: Aug 31 19:06:30 volumio volumio[1113]: ---------------------------- MPD announces state update: player Aug 31 19:06:30 volumio volumio[1113]: info: ControllerMpd::getState Aug 31 19:06:30 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Aug 31 19:06:30 volumio volumio[1113]: info: ------------------------------ 2ms Aug 31 19:06:30 volumio volumio[1113]: info: sendMpdCommand status took 2 milliseconds Aug 31 19:06:30 volumio volumio[1113]: verbose: ControllerMpd::parseState Aug 31 19:06:30 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 19:06:30 volumio volumio[1113]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 31 19:06:30 volumio volumio[1113]: verbose: ControllerMpd::parseTrackInfo Aug 31 19:06:30 volumio volumio[1113]: info: ControllerMpd::pushState Aug 31 19:06:30 volumio volumio[1113]: info: CoreCommandRouter::servicePushState Aug 31 19:06:30 volumio volumio[1113]: info: CorePlayQueue::getTrack 4 Aug 31 19:06:30 volumio volumio[1113]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":290,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1126 Kbps","isStreaming":false,"title":"Heaven Can Wait","artist":"Michael Jackson","album":"Invincible","uri":"http://192.168.178.88:9790/minimserver/*/music/Michael*20Jackson*20-*20Invincible*20(2001)*20*5b24bit*20Hi-Res*5d/05*20-*20Heaven*20Can*20Wait.flac","trackType":"flac"} Aug 31 19:06:30 volumio volumio[1113]: verbose: CURRENT POSITION 4 Aug 31 19:06:30 volumio volumio[1113]: info: CoreStateMachine::syncState stateService play Aug 31 19:06:30 volumio volumio[1113]: info: CoreStateMachine::syncState currentStatus play Aug 31 19:06:30 volumio volumio[1113]: info: Received an update from plugin. extracting info from payload Aug 31 19:06:30 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:06:30 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 19:06:30 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:06:30 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:06:30 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:06:30 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:06:30 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:06:30 volumio volumio[1113]: info: ------------------------------ 18ms Aug 31 19:06:30 volumio volumio[1113]: info: CoreCommandRouter::volumioGetQueue Aug 31 19:06:30 volumio volumio[1113]: info: CoreStateMachine::getQueue Aug 31 19:06:30 volumio volumio[1113]: info: CorePlayQueue::getQueue Aug 31 19:06:30 volumio volumio[1113]: info: CoreCommandRouter::volumioGetQueue Aug 31 19:06:30 volumio volumio[1113]: info: CoreStateMachine::getQueue Aug 31 19:06:30 volumio volumio[1113]: info: CorePlayQueue::getQueue Aug 31 19:06:33 volumio volumio[1113]: info: Executing endpoint metavolumio Aug 31 19:06:33 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 31 19:06:33 volumio volumio[1113]: info: Executing endpoint metavolumio Aug 31 19:06:33 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 31 19:06:33 volumio volumio[1113]: info: Executing endpoint metavolumio Aug 31 19:06:33 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 31 19:06:34 volumio volumio[1113]: info: CoreCommandRouter::volumioNext Aug 31 19:06:34 volumio volumio[1113]: info: CoreStateMachine::next Aug 31 19:06:34 volumio volumio[1113]: info: ControllerMpd::next Aug 31 19:06:34 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand next Aug 31 19:06:34 volumio volumio[1113]: info: Aug 31 19:06:34 volumio volumio[1113]: ---------------------------- MPD announces system playlist update Aug 31 19:06:34 volumio volumio[1113]: info: Ignoring MPD Status Update Aug 31 19:06:34 volumio volumio[1113]: info: Aug 31 19:06:34 volumio volumio[1113]: ---------------------------- MPD announces state update: player Aug 31 19:06:34 volumio volumio[1113]: info: sendMpdCommand next took 5 milliseconds Aug 31 19:06:34 volumio volumio[1113]: info: ControllerMpd::getState Aug 31 19:06:34 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Aug 31 19:06:34 volumio volumio[1113]: error: updateQueue error: null Aug 31 19:06:34 volumio volumio[1113]: info: ------------------------------ 1ms Aug 31 19:06:34 volumio volumio[1113]: info: sendMpdCommand status took 1 milliseconds Aug 31 19:06:34 volumio volumio[1113]: verbose: ControllerMpd::parseState Aug 31 19:06:34 volumio volumio[1113]: info: ControllerMpd::pushState Aug 31 19:06:34 volumio volumio[1113]: info: CoreCommandRouter::servicePushState Aug 31 19:06:34 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:06:34 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 19:06:34 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:06:34 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:06:34 volumio volumio[1113]: info: CorePlayQueue::getTrack 4 Aug 31 19:06:34 volumio volumio[1113]: 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} Aug 31 19:06:34 volumio volumio[1113]: verbose: CURRENT POSITION 4 Aug 31 19:06:34 volumio volumio[1113]: info: CoreStateMachine::syncState stateService stop Aug 31 19:06:34 volumio volumio[1113]: info: CoreStateMachine::syncState currentStatus play Aug 31 19:06:34 volumio volumio[1113]: info: CoreStateMachine::play index undefined Aug 31 19:06:34 volumio volumio[1113]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 19:06:34 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:06:34 volumio volumio[1113]: info: CorePlayQueue::getTrack 5 Aug 31 19:06:34 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 19:06:34 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:06:34 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:06:34 volumio volumio[1113]: info: CorePlayQueue::getTrack 5 Aug 31 19:06:34 volumio volumio[1113]: info: CorePlayQueue::getTrack 5 Aug 31 19:06:34 volumio volumio[1113]: info: CoreStateMachine::startPlaybackTimer Aug 31 19:06:34 volumio volumio[1113]: info: CorePlayQueue::getTrack 5 Aug 31 19:06:34 volumio volumio[1113]: info: [1788195994783] ControllerUPNPBrowser::clearAddPlayTrack Aug 31 19:06:34 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 19:06:34 volumio volumio[1113]: info: ------------------------------ 12ms Aug 31 19:06:34 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:06:34 volumio volumio[1113]: info: CorePlayQueue::getTrack 5 Aug 31 19:06:34 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 19:06:34 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:06:34 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:06:34 volumio volumio[1113]: info: CorePlayQueue::getTrack 5 Aug 31 19:06:34 volumio volumio[1113]: info: sendMpdCommand stop took 7 milliseconds Aug 31 19:06:34 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand clear Aug 31 19:06:34 volumio volumio[1113]: info: CoreCommandRouter::volumioGetQueue Aug 31 19:06:34 volumio volumio[1113]: info: CoreStateMachine::getQueue Aug 31 19:06:34 volumio volumio[1113]: info: CorePlayQueue::getQueue Aug 31 19:06:34 volumio volumio[1113]: info: Aug 31 19:06:34 volumio volumio[1113]: ---------------------------- MPD announces system playlist update Aug 31 19:06:34 volumio volumio[1113]: info: Ignoring MPD Status Update Aug 31 19:06:34 volumio volumio[1113]: info: sendMpdCommand clear took 22 milliseconds Aug 31 19:06:34 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.178.88:9790/minimserver/*/music/Michael*20Jackson*20-*20Invincible*20(2001)*20*5b24bit*20Hi-Res*5d/06*20-*20You*20Rock*20My*20World*20(with*20intro).flac" Aug 31 19:06:34 volumio volumio[1113]: error: updateQueue error: null Aug 31 19:06:34 volumio volumio[1113]: info: ------------------------------ 3ms Aug 31 19:06:35 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.178.88:9790/minimserver/*/music/Michael*20Jackson*20-*20Invincible*20(2001)*20*5b24bit*20Hi-Res*5d/06*20-*20You*20Rock*20My*20World*20(with*20intro).flac" Aug 31 19:06:35 volumio volumio[1113]: info: Aug 31 19:06:35 volumio volumio[1113]: ---------------------------- MPD announces system playlist update Aug 31 19:06:35 volumio volumio[1113]: info: Ignoring MPD Status Update Aug 31 19:06:35 volumio volumio[1113]: info: sendMpdCommand add "http://192.168.178.88:9790/minimserver/*/music/Michael*20Jackson*20-*20Invincible*20(2001)*20*5b24bit*20Hi-Res*5d/06*20-*20You*20Rock*20My*20World*20(with*20intro).flac" took 1 milliseconds Aug 31 19:06:35 volumio volumio[1113]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 31 19:06:35 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand play Aug 31 19:06:35 volumio volumio[1113]: info: ------------------------------ 3ms Aug 31 19:06:35 volumio volumio[1113]: info: sendMpdCommand play took 3 milliseconds Aug 31 19:06:35 volumio volumio[1113]: info: Aug 31 19:06:35 volumio volumio[1113]: ---------------------------- MPD announces state update: player Aug 31 19:06:35 volumio volumio[1113]: info: ControllerMpd::getState Aug 31 19:06:35 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Aug 31 19:06:35 volumio volumio[1113]: info: Aug 31 19:06:35 volumio volumio[1113]: ---------------------------- MPD announces state update: player Aug 31 19:06:35 volumio volumio[1113]: info: sendMpdCommand status took 2 milliseconds Aug 31 19:06:35 volumio volumio[1113]: info: ControllerMpd::getState Aug 31 19:06:35 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Aug 31 19:06:35 volumio volumio[1113]: verbose: ControllerMpd::parseState Aug 31 19:06:35 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 19:06:35 volumio volumio[1113]: info: sendMpdCommand status took 2 milliseconds Aug 31 19:06:35 volumio volumio[1113]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 31 19:06:35 volumio volumio[1113]: verbose: ControllerMpd::parseState Aug 31 19:06:35 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 19:06:35 volumio volumio[1113]: verbose: ControllerMpd::parseTrackInfo Aug 31 19:06:35 volumio volumio[1113]: info: ControllerMpd::pushState Aug 31 19:06:35 volumio volumio[1113]: info: CoreCommandRouter::servicePushState Aug 31 19:06:35 volumio volumio[1113]: info: CorePlayQueue::getTrack 5 Aug 31 19:06:35 volumio volumio[1113]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":339,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"06*20-*20You*20Rock*20My*20World*20(with*20intro).flac","artist":null,"album":null,"uri":"http://192.168.178.88:9790/minimserver/*/music/Michael*20Jackson*20-*20Invincible*20(2001)*20*5b24bit*20Hi-Res*5d/06*20-*20You*20Rock*20My*20World*20(with*20intro).flac","trackType":"flac"} Aug 31 19:06:35 volumio volumio[1113]: verbose: CURRENT POSITION 5 Aug 31 19:06:35 volumio volumio[1113]: info: CoreStateMachine::syncState stateService play Aug 31 19:06:35 volumio volumio[1113]: info: CoreStateMachine::syncState currentStatus stop Aug 31 19:06:35 volumio volumio[1113]: info: ------------------------------ 10ms Aug 31 19:06:35 volumio volumio[1113]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 31 19:06:35 volumio volumio[1113]: verbose: ControllerMpd::parseTrackInfo Aug 31 19:06:35 volumio volumio[1113]: info: ControllerMpd::pushState Aug 31 19:06:35 volumio volumio[1113]: info: CoreCommandRouter::servicePushState Aug 31 19:06:35 volumio volumio[1113]: info: CorePlayQueue::getTrack 5 Aug 31 19:06:35 volumio volumio[1113]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":339,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"06*20-*20You*20Rock*20My*20World*20(with*20intro).flac","artist":null,"album":null,"uri":"http://192.168.178.88:9790/minimserver/*/music/Michael*20Jackson*20-*20Invincible*20(2001)*20*5b24bit*20Hi-Res*5d/06*20-*20You*20Rock*20My*20World*20(with*20intro).flac","trackType":"flac"} Aug 31 19:06:35 volumio volumio[1113]: verbose: CURRENT POSITION 5 Aug 31 19:06:35 volumio volumio[1113]: info: CoreStateMachine::syncState stateService play Aug 31 19:06:35 volumio volumio[1113]: info: CoreStateMachine::syncState currentStatus play Aug 31 19:06:35 volumio volumio[1113]: info: Received an update from plugin. extracting info from payload Aug 31 19:06:35 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:06:35 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 19:06:35 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:06:35 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:06:35 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:06:35 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:06:35 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:06:35 volumio volumio[1113]: info: ------------------------------ 30ms Aug 31 19:06:36 volumio volumio[1113]: info: Aug 31 19:06:36 volumio volumio[1113]: ---------------------------- MPD announces system playlist update Aug 31 19:06:36 volumio volumio[1113]: info: Ignoring MPD Status Update Aug 31 19:06:36 volumio volumio[1113]: info: Aug 31 19:06:36 volumio volumio[1113]: ---------------------------- MPD announces state update: player Aug 31 19:06:36 volumio volumio[1113]: info: ControllerMpd::getState Aug 31 19:06:36 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Aug 31 19:06:36 volumio volumio[1113]: info: ------------------------------ 2ms Aug 31 19:06:36 volumio volumio[1113]: info: sendMpdCommand status took 1 milliseconds Aug 31 19:06:36 volumio volumio[1113]: verbose: ControllerMpd::parseState Aug 31 19:06:36 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 19:06:36 volumio volumio[1113]: info: sendMpdCommand playlistinfo took 0 milliseconds Aug 31 19:06:36 volumio volumio[1113]: verbose: ControllerMpd::parseTrackInfo Aug 31 19:06:36 volumio volumio[1113]: info: ControllerMpd::pushState Aug 31 19:06:36 volumio volumio[1113]: info: CoreCommandRouter::servicePushState Aug 31 19:06:36 volumio volumio[1113]: info: CorePlayQueue::getTrack 5 Aug 31 19:06:36 volumio volumio[1113]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":339,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1292 Kbps","isStreaming":false,"title":"You Rock My World (with intro)","artist":"Michael Jackson","album":"Invincible","uri":"http://192.168.178.88:9790/minimserver/*/music/Michael*20Jackson*20-*20Invincible*20(2001)*20*5b24bit*20Hi-Res*5d/06*20-*20You*20Rock*20My*20World*20(with*20intro).flac","trackType":"flac"} Aug 31 19:06:36 volumio volumio[1113]: verbose: CURRENT POSITION 5 Aug 31 19:06:36 volumio volumio[1113]: info: CoreStateMachine::syncState stateService play Aug 31 19:06:36 volumio volumio[1113]: info: CoreStateMachine::syncState currentStatus play Aug 31 19:06:36 volumio volumio[1113]: info: Received an update from plugin. extracting info from payload Aug 31 19:06:36 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:06:36 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 19:06:36 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:06:36 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:06:36 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:06:36 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:06:36 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:06:36 volumio volumio[1113]: info: ------------------------------ 14ms Aug 31 19:06:36 volumio volumio[1113]: info: CoreCommandRouter::volumioGetQueue Aug 31 19:06:36 volumio volumio[1113]: info: CoreStateMachine::getQueue Aug 31 19:06:36 volumio volumio[1113]: info: CorePlayQueue::getQueue Aug 31 19:06:36 volumio volumio[1113]: info: CoreCommandRouter::volumioGetQueue Aug 31 19:06:36 volumio volumio[1113]: info: CoreStateMachine::getQueue Aug 31 19:06:36 volumio volumio[1113]: info: CorePlayQueue::getQueue Aug 31 19:06:38 volumio volumio[1113]: info: Executing endpoint metavolumio Aug 31 19:06:38 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 31 19:06:38 volumio volumio[1113]: info: Executing endpoint metavolumio Aug 31 19:06:38 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 31 19:06:38 volumio volumio[1113]: info: Executing endpoint metavolumio Aug 31 19:06:38 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 31 19:06:39 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:06:39 volumio volumio[1113]: info: CoreCommandRouter::volumioNext Aug 31 19:06:39 volumio volumio[1113]: info: CoreStateMachine::next Aug 31 19:06:39 volumio volumio[1113]: info: ControllerMpd::next Aug 31 19:06:39 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand next Aug 31 19:06:39 volumio volumio[1113]: info: Aug 31 19:06:39 volumio volumio[1113]: ---------------------------- MPD announces system playlist update Aug 31 19:06:39 volumio volumio[1113]: info: Ignoring MPD Status Update Aug 31 19:06:39 volumio volumio[1113]: info: Aug 31 19:06:39 volumio volumio[1113]: ---------------------------- MPD announces state update: player Aug 31 19:06:39 volumio volumio[1113]: info: sendMpdCommand next took 4 milliseconds Aug 31 19:06:39 volumio volumio[1113]: info: ControllerMpd::getState Aug 31 19:06:39 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Aug 31 19:06:39 volumio volumio[1113]: error: updateQueue error: null Aug 31 19:06:39 volumio volumio[1113]: info: ------------------------------ 1ms Aug 31 19:06:39 volumio volumio[1113]: info: sendMpdCommand status took 1 milliseconds Aug 31 19:06:39 volumio volumio[1113]: verbose: ControllerMpd::parseState Aug 31 19:06:39 volumio volumio[1113]: info: ControllerMpd::pushState Aug 31 19:06:39 volumio volumio[1113]: info: CoreCommandRouter::servicePushState Aug 31 19:06:39 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:06:39 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 19:06:39 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:06:39 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:06:39 volumio volumio[1113]: info: CorePlayQueue::getTrack 5 Aug 31 19:06:39 volumio volumio[1113]: 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} Aug 31 19:06:39 volumio volumio[1113]: verbose: CURRENT POSITION 5 Aug 31 19:06:39 volumio volumio[1113]: info: CoreStateMachine::syncState stateService stop Aug 31 19:06:39 volumio volumio[1113]: info: CoreStateMachine::syncState currentStatus play Aug 31 19:06:39 volumio volumio[1113]: info: CoreStateMachine::play index undefined Aug 31 19:06:39 volumio volumio[1113]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 19:06:39 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:06:39 volumio volumio[1113]: info: CorePlayQueue::getTrack 6 Aug 31 19:06:39 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 19:06:39 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:06:39 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:06:39 volumio volumio[1113]: info: CorePlayQueue::getTrack 6 Aug 31 19:06:39 volumio volumio[1113]: info: CorePlayQueue::getTrack 6 Aug 31 19:06:39 volumio volumio[1113]: info: CoreStateMachine::startPlaybackTimer Aug 31 19:06:39 volumio volumio[1113]: info: CorePlayQueue::getTrack 6 Aug 31 19:06:39 volumio volumio[1113]: info: [1788195999197] ControllerUPNPBrowser::clearAddPlayTrack Aug 31 19:06:39 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 19:06:39 volumio volumio[1113]: info: ------------------------------ 13ms Aug 31 19:06:39 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:06:39 volumio volumio[1113]: info: CorePlayQueue::getTrack 6 Aug 31 19:06:39 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 19:06:39 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:06:39 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:06:39 volumio volumio[1113]: info: CorePlayQueue::getTrack 6 Aug 31 19:06:39 volumio volumio[1113]: info: sendMpdCommand stop took 29 milliseconds Aug 31 19:06:39 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand clear Aug 31 19:06:39 volumio volumio[1113]: info: CoreCommandRouter::volumioGetQueue Aug 31 19:06:39 volumio volumio[1113]: info: CoreStateMachine::getQueue Aug 31 19:06:39 volumio volumio[1113]: info: CorePlayQueue::getQueue Aug 31 19:06:39 volumio volumio[1113]: info: Aug 31 19:06:39 volumio volumio[1113]: ---------------------------- MPD announces system playlist update Aug 31 19:06:39 volumio volumio[1113]: info: Ignoring MPD Status Update Aug 31 19:06:39 volumio volumio[1113]: info: sendMpdCommand clear took 31 milliseconds Aug 31 19:06:39 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.178.88:9790/minimserver/*/music/Michael*20Jackson*20-*20Invincible*20(2001)*20*5b24bit*20Hi-Res*5d/07*20-*20Butterflies.flac" Aug 31 19:06:39 volumio volumio[1113]: error: updateQueue error: null Aug 31 19:06:39 volumio volumio[1113]: info: ------------------------------ 8ms Aug 31 19:06:39 volumio volumio[1113]: info: Listing playlists Aug 31 19:06:39 volumio volumio[1113]: info: Listing playlists Aug 31 19:06:40 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.178.88:9790/minimserver/*/music/Michael*20Jackson*20-*20Invincible*20(2001)*20*5b24bit*20Hi-Res*5d/07*20-*20Butterflies.flac" Aug 31 19:06:40 volumio volumio[1113]: info: Aug 31 19:06:40 volumio volumio[1113]: ---------------------------- MPD announces system playlist update Aug 31 19:06:40 volumio volumio[1113]: info: Ignoring MPD Status Update Aug 31 19:06:40 volumio volumio[1113]: info: sendMpdCommand add "http://192.168.178.88:9790/minimserver/*/music/Michael*20Jackson*20-*20Invincible*20(2001)*20*5b24bit*20Hi-Res*5d/07*20-*20Butterflies.flac" took 1 milliseconds Aug 31 19:06:40 volumio volumio[1113]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 31 19:06:40 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand play Aug 31 19:06:40 volumio volumio[1113]: info: ------------------------------ 2ms Aug 31 19:06:40 volumio volumio[1113]: info: sendMpdCommand play took 2 milliseconds Aug 31 19:06:40 volumio volumio[1113]: info: Aug 31 19:06:40 volumio volumio[1113]: ---------------------------- MPD announces state update: player Aug 31 19:06:40 volumio volumio[1113]: info: ControllerMpd::getState Aug 31 19:06:40 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Aug 31 19:06:40 volumio volumio[1113]: info: Aug 31 19:06:40 volumio volumio[1113]: ---------------------------- MPD announces state update: player Aug 31 19:06:40 volumio volumio[1113]: info: sendMpdCommand status took 2 milliseconds Aug 31 19:06:40 volumio volumio[1113]: info: ControllerMpd::getState Aug 31 19:06:40 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Aug 31 19:06:40 volumio volumio[1113]: verbose: ControllerMpd::parseState Aug 31 19:06:40 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 19:06:40 volumio volumio[1113]: info: sendMpdCommand status took 1 milliseconds Aug 31 19:06:40 volumio volumio[1113]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 31 19:06:40 volumio volumio[1113]: verbose: ControllerMpd::parseState Aug 31 19:06:40 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 19:06:40 volumio volumio[1113]: verbose: ControllerMpd::parseTrackInfo Aug 31 19:06:40 volumio volumio[1113]: info: ControllerMpd::pushState Aug 31 19:06:40 volumio volumio[1113]: info: CoreCommandRouter::servicePushState Aug 31 19:06:40 volumio volumio[1113]: info: CorePlayQueue::getTrack 6 Aug 31 19:06:40 volumio volumio[1113]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":281,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"07*20-*20Butterflies.flac","artist":null,"album":null,"uri":"http://192.168.178.88:9790/minimserver/*/music/Michael*20Jackson*20-*20Invincible*20(2001)*20*5b24bit*20Hi-Res*5d/07*20-*20Butterflies.flac","trackType":"flac"} Aug 31 19:06:40 volumio volumio[1113]: verbose: CURRENT POSITION 6 Aug 31 19:06:40 volumio volumio[1113]: info: CoreStateMachine::syncState stateService play Aug 31 19:06:40 volumio volumio[1113]: info: CoreStateMachine::syncState currentStatus stop Aug 31 19:06:40 volumio volumio[1113]: info: ------------------------------ 9ms Aug 31 19:06:40 volumio volumio[1113]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 31 19:06:40 volumio volumio[1113]: verbose: ControllerMpd::parseTrackInfo Aug 31 19:06:40 volumio volumio[1113]: info: ControllerMpd::pushState Aug 31 19:06:40 volumio volumio[1113]: info: CoreCommandRouter::servicePushState Aug 31 19:06:40 volumio volumio[1113]: info: CorePlayQueue::getTrack 6 Aug 31 19:06:40 volumio volumio[1113]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":281,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"07*20-*20Butterflies.flac","artist":null,"album":null,"uri":"http://192.168.178.88:9790/minimserver/*/music/Michael*20Jackson*20-*20Invincible*20(2001)*20*5b24bit*20Hi-Res*5d/07*20-*20Butterflies.flac","trackType":"flac"} Aug 31 19:06:40 volumio volumio[1113]: verbose: CURRENT POSITION 6 Aug 31 19:06:40 volumio volumio[1113]: info: CoreStateMachine::syncState stateService play Aug 31 19:06:40 volumio volumio[1113]: info: CoreStateMachine::syncState currentStatus play Aug 31 19:06:40 volumio volumio[1113]: info: Received an update from plugin. extracting info from payload Aug 31 19:06:40 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:06:40 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 19:06:40 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:06:40 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:06:40 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:06:40 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:06:40 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:06:40 volumio volumio[1113]: info: ------------------------------ 41ms Aug 31 19:06:40 volumio volumio[1113]: info: Aug 31 19:06:40 volumio volumio[1113]: ---------------------------- MPD announces system playlist update Aug 31 19:06:40 volumio volumio[1113]: info: Ignoring MPD Status Update Aug 31 19:06:40 volumio volumio[1113]: info: Aug 31 19:06:40 volumio volumio[1113]: ---------------------------- MPD announces state update: player Aug 31 19:06:40 volumio volumio[1113]: info: ControllerMpd::getState Aug 31 19:06:40 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Aug 31 19:06:40 volumio volumio[1113]: info: ------------------------------ 3ms Aug 31 19:06:40 volumio volumio[1113]: info: sendMpdCommand status took 1 milliseconds Aug 31 19:06:40 volumio volumio[1113]: verbose: ControllerMpd::parseState Aug 31 19:06:40 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 19:06:40 volumio volumio[1113]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 31 19:06:40 volumio volumio[1113]: verbose: ControllerMpd::parseTrackInfo Aug 31 19:06:40 volumio volumio[1113]: info: ControllerMpd::pushState Aug 31 19:06:40 volumio volumio[1113]: info: CoreCommandRouter::servicePushState Aug 31 19:06:40 volumio volumio[1113]: info: CorePlayQueue::getTrack 6 Aug 31 19:06:40 volumio volumio[1113]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":281,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1196 Kbps","isStreaming":false,"title":"Butterflies","artist":"Michael Jackson","album":"Invincible","uri":"http://192.168.178.88:9790/minimserver/*/music/Michael*20Jackson*20-*20Invincible*20(2001)*20*5b24bit*20Hi-Res*5d/07*20-*20Butterflies.flac","trackType":"flac"} Aug 31 19:06:40 volumio volumio[1113]: verbose: CURRENT POSITION 6 Aug 31 19:06:40 volumio volumio[1113]: info: CoreStateMachine::syncState stateService play Aug 31 19:06:40 volumio volumio[1113]: info: CoreStateMachine::syncState currentStatus play Aug 31 19:06:40 volumio volumio[1113]: info: Received an update from plugin. extracting info from payload Aug 31 19:06:40 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:06:40 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 19:06:40 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:06:40 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:06:40 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:06:40 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:06:40 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:06:40 volumio volumio[1113]: info: ------------------------------ 25ms Aug 31 19:06:40 volumio volumio[1113]: info: CoreCommandRouter::volumioGetQueue Aug 31 19:06:40 volumio volumio[1113]: info: CoreStateMachine::getQueue Aug 31 19:06:40 volumio volumio[1113]: info: CorePlayQueue::getQueue Aug 31 19:06:40 volumio volumio[1113]: info: CoreCommandRouter::volumioGetQueue Aug 31 19:06:40 volumio volumio[1113]: info: CoreStateMachine::getQueue Aug 31 19:06:40 volumio volumio[1113]: info: CorePlayQueue::getQueue Aug 31 19:06:40 volumio volumio[1113]: error: Failed request for metavolumio API Aug 31 19:06:40 volumio volumio[1113]: error: Failed request for metavolumio API Aug 31 19:06:41 volumio volumio[1113]: verbose: New Socket.io Connection to 192.168.178.71 from 192.168.178.103 UA: Mozilla/5.0 (Linux; Android 16; SM-A566B Build/BP4A.251205.006; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/151.0.7922.200 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Aug 31 19:06:41 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 31 19:06:41 volumio volumio[1113]: info: CoreCommandRouter::volumioGetVisibleSources Aug 31 19:06:41 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 31 19:06:41 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:06:41 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 31 19:06:41 volumio volumio[1113]: info: CoreCommandRouter::volumioGetQueue Aug 31 19:06:41 volumio volumio[1113]: info: CoreStateMachine::getQueue Aug 31 19:06:41 volumio volumio[1113]: info: CorePlayQueue::getQueue Aug 31 19:06:41 volumio volumio[1113]: info: Listing playlists Aug 31 19:06:41 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 31 19:06:41 volumio volumio[1113]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Aug 31 19:06:41 volumio volumio[1113]: info: Received Get System Info Aug 31 19:06:41 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 19:06:41 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 19:06:41 volumio volumio[1113]: info: Discovery: Getting this device information Aug 31 19:06:41 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:06:41 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 19:06:41 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:06:42 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 31 19:06:42 volumio volumio[1113]: info: Executing endpoint metavolumio Aug 31 19:06:42 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 31 19:06:42 volumio volumio[1113]: info: Executing endpoint metavolumio Aug 31 19:06:42 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 31 19:06:42 volumio volumio[1113]: info: Executing endpoint metavolumio Aug 31 19:06:42 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 31 19:06:44 volumio volumio[1113]: info: CoreCommandRouter::volumioNext Aug 31 19:06:44 volumio volumio[1113]: info: CoreStateMachine::next Aug 31 19:06:44 volumio volumio[1113]: info: ControllerMpd::next Aug 31 19:06:44 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand next Aug 31 19:06:44 volumio volumio[1113]: info: Aug 31 19:06:44 volumio volumio[1113]: ---------------------------- MPD announces system playlist update Aug 31 19:06:44 volumio volumio[1113]: info: Ignoring MPD Status Update Aug 31 19:06:44 volumio volumio[1113]: info: Aug 31 19:06:44 volumio volumio[1113]: ---------------------------- MPD announces state update: player Aug 31 19:06:44 volumio volumio[1113]: info: sendMpdCommand next took 8 milliseconds Aug 31 19:06:44 volumio volumio[1113]: info: ControllerMpd::getState Aug 31 19:06:44 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Aug 31 19:06:44 volumio volumio[1113]: error: updateQueue error: null Aug 31 19:06:44 volumio volumio[1113]: info: ------------------------------ 3ms Aug 31 19:06:44 volumio volumio[1113]: info: sendMpdCommand status took 1 milliseconds Aug 31 19:06:44 volumio volumio[1113]: verbose: ControllerMpd::parseState Aug 31 19:06:44 volumio volumio[1113]: info: ControllerMpd::pushState Aug 31 19:06:44 volumio volumio[1113]: info: CoreCommandRouter::servicePushState Aug 31 19:06:44 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:06:44 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 19:06:44 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:06:44 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:06:44 volumio volumio[1113]: info: CorePlayQueue::getTrack 6 Aug 31 19:06:44 volumio volumio[1113]: 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} Aug 31 19:06:44 volumio volumio[1113]: verbose: CURRENT POSITION 6 Aug 31 19:06:44 volumio volumio[1113]: info: CoreStateMachine::syncState stateService stop Aug 31 19:06:44 volumio volumio[1113]: info: CoreStateMachine::syncState currentStatus play Aug 31 19:06:44 volumio volumio[1113]: info: CoreStateMachine::play index undefined Aug 31 19:06:44 volumio volumio[1113]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 19:06:44 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:06:44 volumio volumio[1113]: info: CorePlayQueue::getTrack 7 Aug 31 19:06:44 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 19:06:44 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:06:44 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:06:44 volumio volumio[1113]: info: CorePlayQueue::getTrack 7 Aug 31 19:06:44 volumio volumio[1113]: info: CorePlayQueue::getTrack 7 Aug 31 19:06:44 volumio volumio[1113]: info: CoreStateMachine::startPlaybackTimer Aug 31 19:06:44 volumio volumio[1113]: info: CorePlayQueue::getTrack 7 Aug 31 19:06:44 volumio volumio[1113]: info: [1788196004761] ControllerUPNPBrowser::clearAddPlayTrack Aug 31 19:06:44 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 19:06:44 volumio volumio[1113]: info: ------------------------------ 17ms Aug 31 19:06:44 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:06:44 volumio volumio[1113]: info: CorePlayQueue::getTrack 7 Aug 31 19:06:44 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 19:06:44 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:06:44 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:06:44 volumio volumio[1113]: info: CorePlayQueue::getTrack 7 Aug 31 19:06:44 volumio volumio[1113]: info: sendMpdCommand stop took 12 milliseconds Aug 31 19:06:44 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand clear Aug 31 19:06:44 volumio volumio[1113]: info: CoreCommandRouter::volumioGetQueue Aug 31 19:06:44 volumio volumio[1113]: info: CoreStateMachine::getQueue Aug 31 19:06:44 volumio volumio[1113]: info: CorePlayQueue::getQueue Aug 31 19:06:44 volumio volumio[1113]: info: Aug 31 19:06:44 volumio volumio[1113]: ---------------------------- MPD announces system playlist update Aug 31 19:06:44 volumio volumio[1113]: info: Ignoring MPD Status Update Aug 31 19:06:44 volumio volumio[1113]: info: sendMpdCommand clear took 17 milliseconds Aug 31 19:06:44 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.178.88:9790/minimserver/*/music/Michael*20Jackson*20-*20Invincible*20(2001)*20*5b24bit*20Hi-Res*5d/08*20-*20Speechless.flac" Aug 31 19:06:44 volumio volumio[1113]: error: updateQueue error: null Aug 31 19:06:44 volumio volumio[1113]: info: ------------------------------ 5ms Aug 31 19:06:45 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.178.88:9790/minimserver/*/music/Michael*20Jackson*20-*20Invincible*20(2001)*20*5b24bit*20Hi-Res*5d/08*20-*20Speechless.flac" Aug 31 19:06:45 volumio volumio[1113]: info: Aug 31 19:06:45 volumio volumio[1113]: ---------------------------- MPD announces system playlist update Aug 31 19:06:45 volumio volumio[1113]: info: Ignoring MPD Status Update Aug 31 19:06:45 volumio volumio[1113]: info: sendMpdCommand add "http://192.168.178.88:9790/minimserver/*/music/Michael*20Jackson*20-*20Invincible*20(2001)*20*5b24bit*20Hi-Res*5d/08*20-*20Speechless.flac" took 1 milliseconds Aug 31 19:06:45 volumio volumio[1113]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 31 19:06:45 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand play Aug 31 19:06:45 volumio volumio[1113]: info: ------------------------------ 2ms Aug 31 19:06:45 volumio volumio[1113]: info: sendMpdCommand play took 2 milliseconds Aug 31 19:06:45 volumio volumio[1113]: info: Aug 31 19:06:45 volumio volumio[1113]: ---------------------------- MPD announces state update: player Aug 31 19:06:45 volumio volumio[1113]: info: ControllerMpd::getState Aug 31 19:06:45 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Aug 31 19:06:45 volumio volumio[1113]: info: sendMpdCommand status took 4 milliseconds Aug 31 19:06:45 volumio volumio[1113]: verbose: ControllerMpd::parseState Aug 31 19:06:45 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 19:06:45 volumio volumio[1113]: info: Aug 31 19:06:45 volumio volumio[1113]: ---------------------------- MPD announces state update: player Aug 31 19:06:45 volumio volumio[1113]: info: ControllerMpd::getState Aug 31 19:06:45 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Aug 31 19:06:45 volumio volumio[1113]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 31 19:06:45 volumio volumio[1113]: verbose: ControllerMpd::parseTrackInfo Aug 31 19:06:45 volumio volumio[1113]: info: ControllerMpd::pushState Aug 31 19:06:45 volumio volumio[1113]: info: CoreCommandRouter::servicePushState Aug 31 19:06:45 volumio volumio[1113]: info: CorePlayQueue::getTrack 7 Aug 31 19:06:45 volumio volumio[1113]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":199,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"08*20-*20Speechless.flac","artist":null,"album":null,"uri":"http://192.168.178.88:9790/minimserver/*/music/Michael*20Jackson*20-*20Invincible*20(2001)*20*5b24bit*20Hi-Res*5d/08*20-*20Speechless.flac","trackType":"flac"} Aug 31 19:06:45 volumio volumio[1113]: verbose: CURRENT POSITION 7 Aug 31 19:06:45 volumio volumio[1113]: info: CoreStateMachine::syncState stateService play Aug 31 19:06:45 volumio volumio[1113]: info: CoreStateMachine::syncState currentStatus stop Aug 31 19:06:45 volumio volumio[1113]: info: ------------------------------ 15ms Aug 31 19:06:45 volumio volumio[1113]: info: sendMpdCommand status took 7 milliseconds Aug 31 19:06:45 volumio volumio[1113]: verbose: ControllerMpd::parseState Aug 31 19:06:45 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 19:06:45 volumio volumio[1113]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 31 19:06:45 volumio volumio[1113]: verbose: ControllerMpd::parseTrackInfo Aug 31 19:06:45 volumio volumio[1113]: info: ControllerMpd::pushState Aug 31 19:06:45 volumio volumio[1113]: info: CoreCommandRouter::servicePushState Aug 31 19:06:45 volumio volumio[1113]: info: CorePlayQueue::getTrack 7 Aug 31 19:06:45 volumio volumio[1113]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":199,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"08*20-*20Speechless.flac","artist":null,"album":null,"uri":"http://192.168.178.88:9790/minimserver/*/music/Michael*20Jackson*20-*20Invincible*20(2001)*20*5b24bit*20Hi-Res*5d/08*20-*20Speechless.flac","trackType":"flac"} Aug 31 19:06:45 volumio volumio[1113]: verbose: CURRENT POSITION 7 Aug 31 19:06:45 volumio volumio[1113]: info: CoreStateMachine::syncState stateService play Aug 31 19:06:45 volumio volumio[1113]: info: CoreStateMachine::syncState currentStatus play Aug 31 19:06:45 volumio volumio[1113]: info: Received an update from plugin. extracting info from payload Aug 31 19:06:45 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:06:45 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 19:06:45 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:06:45 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:06:45 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:06:45 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:06:45 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:06:45 volumio volumio[1113]: info: ------------------------------ 43ms Aug 31 19:06:45 volumio volumio[1113]: info: Aug 31 19:06:45 volumio volumio[1113]: ---------------------------- MPD announces system playlist update Aug 31 19:06:45 volumio volumio[1113]: info: Ignoring MPD Status Update Aug 31 19:06:45 volumio volumio[1113]: info: Aug 31 19:06:45 volumio volumio[1113]: ---------------------------- MPD announces state update: player Aug 31 19:06:45 volumio volumio[1113]: info: ControllerMpd::getState Aug 31 19:06:45 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Aug 31 19:06:45 volumio volumio[1113]: info: ------------------------------ 4ms Aug 31 19:06:45 volumio volumio[1113]: info: sendMpdCommand status took 2 milliseconds Aug 31 19:06:45 volumio volumio[1113]: verbose: ControllerMpd::parseState Aug 31 19:06:45 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 19:06:45 volumio volumio[1113]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 31 19:06:45 volumio volumio[1113]: verbose: ControllerMpd::parseTrackInfo Aug 31 19:06:45 volumio volumio[1113]: info: ControllerMpd::pushState Aug 31 19:06:45 volumio volumio[1113]: info: CoreCommandRouter::servicePushState Aug 31 19:06:45 volumio volumio[1113]: info: CorePlayQueue::getTrack 7 Aug 31 19:06:45 volumio volumio[1113]: verbose: STATE SERVICE {"status":"play","position":0,"seek":148,"duration":199,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"188 Kbps","isStreaming":false,"title":"Speechless","artist":"Michael Jackson","album":"Invincible","uri":"http://192.168.178.88:9790/minimserver/*/music/Michael*20Jackson*20-*20Invincible*20(2001)*20*5b24bit*20Hi-Res*5d/08*20-*20Speechless.flac","trackType":"flac"} Aug 31 19:06:45 volumio volumio[1113]: verbose: CURRENT POSITION 7 Aug 31 19:06:45 volumio volumio[1113]: info: CoreStateMachine::syncState stateService play Aug 31 19:06:45 volumio volumio[1113]: info: CoreStateMachine::syncState currentStatus play Aug 31 19:06:45 volumio volumio[1113]: info: Received an update from plugin. extracting info from payload Aug 31 19:06:45 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:06:45 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 19:06:45 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:06:45 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:06:45 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:06:45 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:06:45 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:06:45 volumio volumio[1113]: info: ------------------------------ 76ms Aug 31 19:06:45 volumio volumio[1113]: info: CoreCommandRouter::volumioGetQueue Aug 31 19:06:45 volumio volumio[1113]: info: CoreStateMachine::getQueue Aug 31 19:06:45 volumio volumio[1113]: info: CorePlayQueue::getQueue Aug 31 19:06:45 volumio volumio[1113]: info: CoreCommandRouter::volumioGetQueue Aug 31 19:06:45 volumio volumio[1113]: info: CoreStateMachine::getQueue Aug 31 19:06:45 volumio volumio[1113]: info: CorePlayQueue::getQueue Aug 31 19:06:46 volumio volumio[1113]: error: Failed request for metavolumio API Aug 31 19:06:46 volumio volumio[1113]: error: Failed request for metavolumio API Aug 31 19:06:48 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:06:48 volumio volumio[1113]: info: Executing endpoint metavolumio Aug 31 19:06:48 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 31 19:06:48 volumio volumio[1113]: info: Executing endpoint metavolumio Aug 31 19:06:48 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 31 19:06:48 volumio volumio[1113]: info: Executing endpoint metavolumio Aug 31 19:06:48 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 31 19:06:49 volumio volumio[1113]: info: CoreCommandRouter::volumioNext Aug 31 19:06:49 volumio volumio[1113]: info: CoreStateMachine::next Aug 31 19:06:49 volumio volumio[1113]: info: ControllerMpd::next Aug 31 19:06:49 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand next Aug 31 19:06:49 volumio volumio[1113]: info: Aug 31 19:06:49 volumio volumio[1113]: ---------------------------- MPD announces system playlist update Aug 31 19:06:49 volumio volumio[1113]: info: Ignoring MPD Status Update Aug 31 19:06:49 volumio volumio[1113]: info: Aug 31 19:06:49 volumio volumio[1113]: ---------------------------- MPD announces state update: player Aug 31 19:06:49 volumio volumio[1113]: info: sendMpdCommand next took 5 milliseconds Aug 31 19:06:49 volumio volumio[1113]: info: ControllerMpd::getState Aug 31 19:06:49 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Aug 31 19:06:49 volumio volumio[1113]: error: updateQueue error: null Aug 31 19:06:49 volumio volumio[1113]: info: ------------------------------ 2ms Aug 31 19:06:49 volumio volumio[1113]: info: sendMpdCommand status took 1 milliseconds Aug 31 19:06:49 volumio volumio[1113]: verbose: ControllerMpd::parseState Aug 31 19:06:49 volumio volumio[1113]: info: ControllerMpd::pushState Aug 31 19:06:49 volumio volumio[1113]: info: CoreCommandRouter::servicePushState Aug 31 19:06:49 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:06:49 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 19:06:49 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:06:49 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:06:49 volumio volumio[1113]: info: CorePlayQueue::getTrack 7 Aug 31 19:06:49 volumio volumio[1113]: 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} Aug 31 19:06:49 volumio volumio[1113]: verbose: CURRENT POSITION 7 Aug 31 19:06:49 volumio volumio[1113]: info: CoreStateMachine::syncState stateService stop Aug 31 19:06:49 volumio volumio[1113]: info: CoreStateMachine::syncState currentStatus play Aug 31 19:06:49 volumio volumio[1113]: info: CoreStateMachine::play index undefined Aug 31 19:06:49 volumio volumio[1113]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 19:06:49 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:06:49 volumio volumio[1113]: info: CorePlayQueue::getTrack 8 Aug 31 19:06:49 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 19:06:49 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:06:49 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:06:49 volumio volumio[1113]: info: CorePlayQueue::getTrack 8 Aug 31 19:06:49 volumio volumio[1113]: info: CorePlayQueue::getTrack 8 Aug 31 19:06:49 volumio volumio[1113]: info: CoreStateMachine::startPlaybackTimer Aug 31 19:06:49 volumio volumio[1113]: info: CorePlayQueue::getTrack 8 Aug 31 19:06:49 volumio volumio[1113]: info: [1788196009922] ControllerUPNPBrowser::clearAddPlayTrack Aug 31 19:06:49 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 19:06:49 volumio volumio[1113]: info: ------------------------------ 18ms Aug 31 19:06:49 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:06:49 volumio volumio[1113]: info: CorePlayQueue::getTrack 8 Aug 31 19:06:49 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 19:06:49 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:06:49 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:06:49 volumio volumio[1113]: info: CorePlayQueue::getTrack 8 Aug 31 19:06:49 volumio volumio[1113]: info: sendMpdCommand stop took 14 milliseconds Aug 31 19:06:49 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand clear Aug 31 19:06:49 volumio volumio[1113]: info: CoreCommandRouter::volumioGetQueue Aug 31 19:06:49 volumio volumio[1113]: info: CoreStateMachine::getQueue Aug 31 19:06:49 volumio volumio[1113]: info: CorePlayQueue::getQueue Aug 31 19:06:49 volumio volumio[1113]: info: Aug 31 19:06:49 volumio volumio[1113]: ---------------------------- MPD announces system playlist update Aug 31 19:06:49 volumio volumio[1113]: info: Ignoring MPD Status Update Aug 31 19:06:49 volumio volumio[1113]: info: sendMpdCommand clear took 35 milliseconds Aug 31 19:06:49 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.178.88:9790/minimserver/*/music/Michael*20Jackson*20-*20Invincible*20(2001)*20*5b24bit*20Hi-Res*5d/09*20-*202000*20Watts.flac" Aug 31 19:06:49 volumio volumio[1113]: error: updateQueue error: null Aug 31 19:06:49 volumio volumio[1113]: info: ------------------------------ 3ms Aug 31 19:06:50 volumio volumio[1113]: error: Failed request for metavolumio API Aug 31 19:06:50 volumio volumio[1113]: error: Failed request for metavolumio API Aug 31 19:06:50 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.178.88:9790/minimserver/*/music/Michael*20Jackson*20-*20Invincible*20(2001)*20*5b24bit*20Hi-Res*5d/09*20-*202000*20Watts.flac" Aug 31 19:06:50 volumio volumio[1113]: info: Aug 31 19:06:50 volumio volumio[1113]: ---------------------------- MPD announces system playlist update Aug 31 19:06:50 volumio volumio[1113]: info: Ignoring MPD Status Update Aug 31 19:06:50 volumio volumio[1113]: info: sendMpdCommand add "http://192.168.178.88:9790/minimserver/*/music/Michael*20Jackson*20-*20Invincible*20(2001)*20*5b24bit*20Hi-Res*5d/09*20-*202000*20Watts.flac" took 3 milliseconds Aug 31 19:06:50 volumio volumio[1113]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 31 19:06:50 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand play Aug 31 19:06:50 volumio volumio[1113]: info: ------------------------------ 3ms Aug 31 19:06:50 volumio volumio[1113]: info: sendMpdCommand play took 1 milliseconds Aug 31 19:06:50 volumio volumio[1113]: info: Aug 31 19:06:50 volumio volumio[1113]: ---------------------------- MPD announces state update: player Aug 31 19:06:50 volumio volumio[1113]: info: ControllerMpd::getState Aug 31 19:06:50 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Aug 31 19:06:50 volumio volumio[1113]: info: Aug 31 19:06:50 volumio volumio[1113]: ---------------------------- MPD announces state update: player Aug 31 19:06:50 volumio volumio[1113]: info: sendMpdCommand status took 2 milliseconds Aug 31 19:06:50 volumio volumio[1113]: info: ControllerMpd::getState Aug 31 19:06:50 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Aug 31 19:06:50 volumio volumio[1113]: verbose: ControllerMpd::parseState Aug 31 19:06:50 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 19:06:50 volumio volumio[1113]: info: sendMpdCommand status took 5 milliseconds Aug 31 19:06:50 volumio volumio[1113]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 31 19:06:50 volumio volumio[1113]: verbose: ControllerMpd::parseState Aug 31 19:06:50 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 19:06:50 volumio volumio[1113]: verbose: ControllerMpd::parseTrackInfo Aug 31 19:06:50 volumio volumio[1113]: info: ControllerMpd::pushState Aug 31 19:06:50 volumio volumio[1113]: info: CoreCommandRouter::servicePushState Aug 31 19:06:50 volumio volumio[1113]: info: CorePlayQueue::getTrack 8 Aug 31 19:06:50 volumio volumio[1113]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":265,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"09*20-*202000*20Watts.flac","artist":null,"album":null,"uri":"http://192.168.178.88:9790/minimserver/*/music/Michael*20Jackson*20-*20Invincible*20(2001)*20*5b24bit*20Hi-Res*5d/09*20-*202000*20Watts.flac","trackType":"flac"} Aug 31 19:06:50 volumio volumio[1113]: verbose: CURRENT POSITION 8 Aug 31 19:06:50 volumio volumio[1113]: info: CoreStateMachine::syncState stateService play Aug 31 19:06:50 volumio volumio[1113]: info: CoreStateMachine::syncState currentStatus stop Aug 31 19:06:50 volumio volumio[1113]: info: ------------------------------ 12ms Aug 31 19:06:50 volumio volumio[1113]: info: sendMpdCommand playlistinfo took 5 milliseconds Aug 31 19:06:50 volumio volumio[1113]: verbose: ControllerMpd::parseTrackInfo Aug 31 19:06:50 volumio volumio[1113]: info: ControllerMpd::pushState Aug 31 19:06:50 volumio volumio[1113]: info: CoreCommandRouter::servicePushState Aug 31 19:06:50 volumio volumio[1113]: info: CorePlayQueue::getTrack 8 Aug 31 19:06:50 volumio volumio[1113]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":265,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"09*20-*202000*20Watts.flac","artist":null,"album":null,"uri":"http://192.168.178.88:9790/minimserver/*/music/Michael*20Jackson*20-*20Invincible*20(2001)*20*5b24bit*20Hi-Res*5d/09*20-*202000*20Watts.flac","trackType":"flac"} Aug 31 19:06:50 volumio volumio[1113]: verbose: CURRENT POSITION 8 Aug 31 19:06:50 volumio volumio[1113]: info: CoreStateMachine::syncState stateService play Aug 31 19:06:50 volumio volumio[1113]: info: CoreStateMachine::syncState currentStatus play Aug 31 19:06:50 volumio volumio[1113]: info: Received an update from plugin. extracting info from payload Aug 31 19:06:50 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:06:50 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 19:06:50 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:06:50 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:06:50 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:06:50 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:06:50 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:06:50 volumio volumio[1113]: info: ------------------------------ 39ms Aug 31 19:06:52 volumio volumio[1113]: info: Aug 31 19:06:52 volumio volumio[1113]: ---------------------------- MPD announces system playlist update Aug 31 19:06:52 volumio volumio[1113]: info: Ignoring MPD Status Update Aug 31 19:06:52 volumio volumio[1113]: info: Aug 31 19:06:52 volumio volumio[1113]: ---------------------------- MPD announces state update: player Aug 31 19:06:52 volumio volumio[1113]: info: ControllerMpd::getState Aug 31 19:06:52 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Aug 31 19:06:52 volumio volumio[1113]: info: ------------------------------ 2ms Aug 31 19:06:52 volumio volumio[1113]: info: sendMpdCommand status took 2 milliseconds Aug 31 19:06:52 volumio volumio[1113]: verbose: ControllerMpd::parseState Aug 31 19:06:52 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 19:06:52 volumio volumio[1113]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 31 19:06:52 volumio volumio[1113]: verbose: ControllerMpd::parseTrackInfo Aug 31 19:06:52 volumio volumio[1113]: info: ControllerMpd::pushState Aug 31 19:06:52 volumio volumio[1113]: info: CoreCommandRouter::servicePushState Aug 31 19:06:52 volumio volumio[1113]: info: CorePlayQueue::getTrack 8 Aug 31 19:06:52 volumio volumio[1113]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":265,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1377 Kbps","isStreaming":false,"title":"2000 Watts","artist":"Michael Jackson","album":"Invincible","uri":"http://192.168.178.88:9790/minimserver/*/music/Michael*20Jackson*20-*20Invincible*20(2001)*20*5b24bit*20Hi-Res*5d/09*20-*202000*20Watts.flac","trackType":"flac"} Aug 31 19:06:52 volumio volumio[1113]: verbose: CURRENT POSITION 8 Aug 31 19:06:52 volumio volumio[1113]: info: CoreStateMachine::syncState stateService play Aug 31 19:06:52 volumio volumio[1113]: info: CoreStateMachine::syncState currentStatus play Aug 31 19:06:52 volumio volumio[1113]: info: Received an update from plugin. extracting info from payload Aug 31 19:06:52 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:06:52 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 19:06:52 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:06:52 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:06:52 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:06:52 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:06:52 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:06:52 volumio volumio[1113]: info: ------------------------------ 34ms Aug 31 19:06:52 volumio volumio[1113]: info: CoreCommandRouter::volumioGetQueue Aug 31 19:06:52 volumio volumio[1113]: info: CoreStateMachine::getQueue Aug 31 19:06:52 volumio volumio[1113]: info: CorePlayQueue::getQueue Aug 31 19:06:52 volumio volumio[1113]: info: CoreCommandRouter::volumioGetQueue Aug 31 19:06:52 volumio volumio[1113]: info: CoreStateMachine::getQueue Aug 31 19:06:52 volumio volumio[1113]: info: CorePlayQueue::getQueue Aug 31 19:06:54 volumio volumio[1113]: info: Executing endpoint metavolumio Aug 31 19:06:54 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 31 19:06:54 volumio volumio[1113]: info: Executing endpoint metavolumio Aug 31 19:06:54 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 31 19:06:54 volumio volumio[1113]: info: Executing endpoint metavolumio Aug 31 19:06:54 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 31 19:06:54 volumio volumio[1113]: info: CoreCommandRouter::volumioNext Aug 31 19:06:54 volumio volumio[1113]: info: CoreStateMachine::next Aug 31 19:06:54 volumio volumio[1113]: info: ControllerMpd::next Aug 31 19:06:54 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand next Aug 31 19:06:54 volumio volumio[1113]: info: Aug 31 19:06:54 volumio volumio[1113]: ---------------------------- MPD announces system playlist update Aug 31 19:06:54 volumio volumio[1113]: info: Ignoring MPD Status Update Aug 31 19:06:54 volumio volumio[1113]: info: Aug 31 19:06:54 volumio volumio[1113]: ---------------------------- MPD announces state update: player Aug 31 19:06:54 volumio volumio[1113]: info: sendMpdCommand next took 3 milliseconds Aug 31 19:06:54 volumio volumio[1113]: info: ControllerMpd::getState Aug 31 19:06:54 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Aug 31 19:06:54 volumio volumio[1113]: error: updateQueue error: null Aug 31 19:06:54 volumio volumio[1113]: info: ------------------------------ 2ms Aug 31 19:06:54 volumio volumio[1113]: info: sendMpdCommand status took 1 milliseconds Aug 31 19:06:54 volumio volumio[1113]: verbose: ControllerMpd::parseState Aug 31 19:06:54 volumio volumio[1113]: info: ControllerMpd::pushState Aug 31 19:06:54 volumio volumio[1113]: info: CoreCommandRouter::servicePushState Aug 31 19:06:54 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:06:54 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 19:06:54 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:06:54 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:06:54 volumio volumio[1113]: info: CorePlayQueue::getTrack 8 Aug 31 19:06:54 volumio volumio[1113]: 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} Aug 31 19:06:54 volumio volumio[1113]: verbose: CURRENT POSITION 8 Aug 31 19:06:54 volumio volumio[1113]: info: CoreStateMachine::syncState stateService stop Aug 31 19:06:54 volumio volumio[1113]: info: CoreStateMachine::syncState currentStatus play Aug 31 19:06:54 volumio volumio[1113]: info: CoreStateMachine::play index undefined Aug 31 19:06:54 volumio volumio[1113]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 19:06:54 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:06:54 volumio volumio[1113]: info: CorePlayQueue::getTrack 9 Aug 31 19:06:54 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 19:06:54 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:06:54 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:06:54 volumio volumio[1113]: info: CorePlayQueue::getTrack 9 Aug 31 19:06:54 volumio volumio[1113]: info: CorePlayQueue::getTrack 9 Aug 31 19:06:54 volumio volumio[1113]: info: CoreStateMachine::startPlaybackTimer Aug 31 19:06:54 volumio volumio[1113]: info: CorePlayQueue::getTrack 9 Aug 31 19:06:54 volumio volumio[1113]: info: [1788196014895] ControllerUPNPBrowser::clearAddPlayTrack Aug 31 19:06:54 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 19:06:54 volumio volumio[1113]: info: ------------------------------ 17ms Aug 31 19:06:54 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:06:54 volumio volumio[1113]: info: CorePlayQueue::getTrack 9 Aug 31 19:06:54 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 19:06:54 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:06:54 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:06:54 volumio volumio[1113]: info: CorePlayQueue::getTrack 9 Aug 31 19:06:54 volumio volumio[1113]: info: sendMpdCommand stop took 22 milliseconds Aug 31 19:06:54 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand clear Aug 31 19:06:54 volumio volumio[1113]: info: CoreCommandRouter::volumioGetQueue Aug 31 19:06:54 volumio volumio[1113]: info: CoreStateMachine::getQueue Aug 31 19:06:54 volumio volumio[1113]: info: CorePlayQueue::getQueue Aug 31 19:06:54 volumio volumio[1113]: info: Aug 31 19:06:54 volumio volumio[1113]: ---------------------------- MPD announces system playlist update Aug 31 19:06:54 volumio volumio[1113]: info: Ignoring MPD Status Update Aug 31 19:06:54 volumio volumio[1113]: info: sendMpdCommand clear took 27 milliseconds Aug 31 19:06:54 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.178.88:9790/minimserver/*/music/Michael*20Jackson*20-*20Invincible*20(2001)*20*5b24bit*20Hi-Res*5d/10*20-*20You*20Are*20My*20Life.flac" Aug 31 19:06:54 volumio volumio[1113]: error: updateQueue error: null Aug 31 19:06:54 volumio volumio[1113]: info: ------------------------------ 5ms Aug 31 19:06:55 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.178.88:9790/minimserver/*/music/Michael*20Jackson*20-*20Invincible*20(2001)*20*5b24bit*20Hi-Res*5d/10*20-*20You*20Are*20My*20Life.flac" Aug 31 19:06:55 volumio volumio[1113]: info: Aug 31 19:06:55 volumio volumio[1113]: ---------------------------- MPD announces system playlist update Aug 31 19:06:55 volumio volumio[1113]: info: Ignoring MPD Status Update Aug 31 19:06:55 volumio volumio[1113]: info: sendMpdCommand add "http://192.168.178.88:9790/minimserver/*/music/Michael*20Jackson*20-*20Invincible*20(2001)*20*5b24bit*20Hi-Res*5d/10*20-*20You*20Are*20My*20Life.flac" took 2 milliseconds Aug 31 19:06:55 volumio volumio[1113]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 31 19:06:55 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand play Aug 31 19:06:55 volumio volumio[1113]: info: ------------------------------ 4ms Aug 31 19:06:55 volumio volumio[1113]: info: sendMpdCommand play took 2 milliseconds Aug 31 19:06:55 volumio volumio[1113]: info: Aug 31 19:06:55 volumio volumio[1113]: ---------------------------- MPD announces state update: player Aug 31 19:06:55 volumio volumio[1113]: info: ControllerMpd::getState Aug 31 19:06:55 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Aug 31 19:06:55 volumio volumio[1113]: info: Aug 31 19:06:55 volumio volumio[1113]: ---------------------------- MPD announces state update: player Aug 31 19:06:55 volumio volumio[1113]: info: sendMpdCommand status took 4 milliseconds Aug 31 19:06:55 volumio volumio[1113]: info: ControllerMpd::getState Aug 31 19:06:55 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Aug 31 19:06:55 volumio volumio[1113]: verbose: ControllerMpd::parseState Aug 31 19:06:55 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 19:06:55 volumio volumio[1113]: info: sendMpdCommand status took 3 milliseconds Aug 31 19:06:55 volumio volumio[1113]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 31 19:06:55 volumio volumio[1113]: verbose: ControllerMpd::parseState Aug 31 19:06:55 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 19:06:55 volumio volumio[1113]: verbose: ControllerMpd::parseTrackInfo Aug 31 19:06:55 volumio volumio[1113]: info: ControllerMpd::pushState Aug 31 19:06:55 volumio volumio[1113]: info: CoreCommandRouter::servicePushState Aug 31 19:06:55 volumio volumio[1113]: info: CorePlayQueue::getTrack 9 Aug 31 19:06:55 volumio volumio[1113]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":274,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"10*20-*20You*20Are*20My*20Life.flac","artist":null,"album":null,"uri":"http://192.168.178.88:9790/minimserver/*/music/Michael*20Jackson*20-*20Invincible*20(2001)*20*5b24bit*20Hi-Res*5d/10*20-*20You*20Are*20My*20Life.flac","trackType":"flac"} Aug 31 19:06:55 volumio volumio[1113]: verbose: CURRENT POSITION 9 Aug 31 19:06:55 volumio volumio[1113]: info: CoreStateMachine::syncState stateService play Aug 31 19:06:55 volumio volumio[1113]: info: CoreStateMachine::syncState currentStatus stop Aug 31 19:06:55 volumio volumio[1113]: info: ------------------------------ 13ms Aug 31 19:06:55 volumio volumio[1113]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 31 19:06:55 volumio volumio[1113]: verbose: ControllerMpd::parseTrackInfo Aug 31 19:06:55 volumio volumio[1113]: info: ControllerMpd::pushState Aug 31 19:06:55 volumio volumio[1113]: info: CoreCommandRouter::servicePushState Aug 31 19:06:55 volumio volumio[1113]: info: CorePlayQueue::getTrack 9 Aug 31 19:06:55 volumio volumio[1113]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":274,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"10*20-*20You*20Are*20My*20Life.flac","artist":null,"album":null,"uri":"http://192.168.178.88:9790/minimserver/*/music/Michael*20Jackson*20-*20Invincible*20(2001)*20*5b24bit*20Hi-Res*5d/10*20-*20You*20Are*20My*20Life.flac","trackType":"flac"} Aug 31 19:06:55 volumio volumio[1113]: verbose: CURRENT POSITION 9 Aug 31 19:06:55 volumio volumio[1113]: info: CoreStateMachine::syncState stateService play Aug 31 19:06:55 volumio volumio[1113]: info: CoreStateMachine::syncState currentStatus play Aug 31 19:06:55 volumio volumio[1113]: info: Received an update from plugin. extracting info from payload Aug 31 19:06:55 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:06:55 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 19:06:55 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:06:55 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:06:55 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:06:55 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:06:55 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:06:55 volumio volumio[1113]: info: ------------------------------ 28ms Aug 31 19:06:55 volumio volumio[1113]: info: Aug 31 19:06:55 volumio volumio[1113]: ---------------------------- MPD announces system playlist update Aug 31 19:06:55 volumio volumio[1113]: info: Ignoring MPD Status Update Aug 31 19:06:55 volumio volumio[1113]: info: Aug 31 19:06:55 volumio volumio[1113]: ---------------------------- MPD announces state update: player Aug 31 19:06:55 volumio volumio[1113]: info: ControllerMpd::getState Aug 31 19:06:55 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Aug 31 19:06:55 volumio volumio[1113]: info: ------------------------------ 5ms Aug 31 19:06:55 volumio volumio[1113]: info: sendMpdCommand status took 4 milliseconds Aug 31 19:06:55 volumio volumio[1113]: verbose: ControllerMpd::parseState Aug 31 19:06:55 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 19:06:55 volumio volumio[1113]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 31 19:06:55 volumio volumio[1113]: verbose: ControllerMpd::parseTrackInfo Aug 31 19:06:55 volumio volumio[1113]: info: ControllerMpd::pushState Aug 31 19:06:55 volumio volumio[1113]: info: CoreCommandRouter::servicePushState Aug 31 19:06:55 volumio volumio[1113]: info: CorePlayQueue::getTrack 9 Aug 31 19:06:55 volumio volumio[1113]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":274,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1472 Kbps","isStreaming":false,"title":"You Are My Life","artist":"Michael Jackson","album":"Invincible","uri":"http://192.168.178.88:9790/minimserver/*/music/Michael*20Jackson*20-*20Invincible*20(2001)*20*5b24bit*20Hi-Res*5d/10*20-*20You*20Are*20My*20Life.flac","trackType":"flac"} Aug 31 19:06:55 volumio volumio[1113]: verbose: CURRENT POSITION 9 Aug 31 19:06:55 volumio volumio[1113]: info: CoreStateMachine::syncState stateService play Aug 31 19:06:55 volumio volumio[1113]: info: CoreStateMachine::syncState currentStatus play Aug 31 19:06:55 volumio volumio[1113]: info: Received an update from plugin. extracting info from payload Aug 31 19:06:55 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:06:55 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 19:06:55 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:06:55 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:06:55 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:06:55 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:06:55 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:06:55 volumio volumio[1113]: info: ------------------------------ 27ms Aug 31 19:06:55 volumio volumio[1113]: info: CoreCommandRouter::volumioGetQueue Aug 31 19:06:55 volumio volumio[1113]: info: CoreStateMachine::getQueue Aug 31 19:06:55 volumio volumio[1113]: info: CorePlayQueue::getQueue Aug 31 19:06:55 volumio volumio[1113]: info: CoreCommandRouter::volumioGetQueue Aug 31 19:06:55 volumio volumio[1113]: info: CoreStateMachine::getQueue Aug 31 19:06:55 volumio volumio[1113]: info: CorePlayQueue::getQueue Aug 31 19:06:56 volumio volumio[1113]: error: Failed request for metavolumio API Aug 31 19:06:56 volumio volumio[1113]: error: Failed request for metavolumio API Aug 31 19:06:58 volumio volumio[1113]: info: Executing endpoint metavolumio Aug 31 19:06:58 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 31 19:06:58 volumio volumio[1113]: info: Executing endpoint metavolumio Aug 31 19:06:58 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 31 19:06:58 volumio volumio[1113]: info: Executing endpoint metavolumio Aug 31 19:06:58 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 31 19:06:58 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:06:58 volumio volumio[1113]: info: Listing playlists Aug 31 19:06:58 volumio volumio[1113]: info: Listing playlists Aug 31 19:07:00 volumio volumio[1113]: info: CoreCommandRouter::volumioNext Aug 31 19:07:00 volumio volumio[1113]: info: CoreStateMachine::next Aug 31 19:07:00 volumio volumio[1113]: info: ControllerMpd::next Aug 31 19:07:00 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand next Aug 31 19:07:00 volumio volumio[1113]: info: Aug 31 19:07:00 volumio volumio[1113]: ---------------------------- MPD announces system playlist update Aug 31 19:07:00 volumio volumio[1113]: info: Ignoring MPD Status Update Aug 31 19:07:00 volumio volumio[1113]: info: Aug 31 19:07:00 volumio volumio[1113]: ---------------------------- MPD announces state update: player Aug 31 19:07:00 volumio volumio[1113]: info: sendMpdCommand next took 5 milliseconds Aug 31 19:07:00 volumio volumio[1113]: info: ControllerMpd::getState Aug 31 19:07:00 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Aug 31 19:07:00 volumio volumio[1113]: error: updateQueue error: null Aug 31 19:07:00 volumio volumio[1113]: info: ------------------------------ 2ms Aug 31 19:07:00 volumio volumio[1113]: info: sendMpdCommand status took 1 milliseconds Aug 31 19:07:00 volumio volumio[1113]: verbose: ControllerMpd::parseState Aug 31 19:07:00 volumio volumio[1113]: info: ControllerMpd::pushState Aug 31 19:07:00 volumio volumio[1113]: info: CoreCommandRouter::servicePushState Aug 31 19:07:00 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:07:00 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 19:07:00 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:07:00 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:07:00 volumio volumio[1113]: info: CorePlayQueue::getTrack 9 Aug 31 19:07:00 volumio volumio[1113]: 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} Aug 31 19:07:00 volumio volumio[1113]: verbose: CURRENT POSITION 9 Aug 31 19:07:00 volumio volumio[1113]: info: CoreStateMachine::syncState stateService stop Aug 31 19:07:00 volumio volumio[1113]: info: CoreStateMachine::syncState currentStatus play Aug 31 19:07:00 volumio volumio[1113]: info: CoreStateMachine::play index undefined Aug 31 19:07:00 volumio volumio[1113]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 19:07:00 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:07:00 volumio volumio[1113]: info: CorePlayQueue::getTrack 10 Aug 31 19:07:00 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 19:07:00 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:07:00 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:07:00 volumio volumio[1113]: info: CorePlayQueue::getTrack 10 Aug 31 19:07:00 volumio volumio[1113]: info: CorePlayQueue::getTrack 10 Aug 31 19:07:00 volumio volumio[1113]: info: CoreStateMachine::startPlaybackTimer Aug 31 19:07:00 volumio volumio[1113]: info: CorePlayQueue::getTrack 10 Aug 31 19:07:00 volumio volumio[1113]: info: [1788196020822] ControllerUPNPBrowser::clearAddPlayTrack Aug 31 19:07:00 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 19:07:00 volumio volumio[1113]: info: ------------------------------ 14ms Aug 31 19:07:00 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:07:00 volumio volumio[1113]: info: CorePlayQueue::getTrack 10 Aug 31 19:07:00 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 19:07:00 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:07:00 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:07:00 volumio volumio[1113]: info: CorePlayQueue::getTrack 10 Aug 31 19:07:00 volumio volumio[1113]: info: sendMpdCommand stop took 7 milliseconds Aug 31 19:07:00 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand clear Aug 31 19:07:00 volumio volumio[1113]: info: CoreCommandRouter::volumioGetQueue Aug 31 19:07:00 volumio volumio[1113]: info: CoreStateMachine::getQueue Aug 31 19:07:00 volumio volumio[1113]: info: CorePlayQueue::getQueue Aug 31 19:07:00 volumio volumio[1113]: info: Aug 31 19:07:00 volumio volumio[1113]: ---------------------------- MPD announces system playlist update Aug 31 19:07:00 volumio volumio[1113]: info: Ignoring MPD Status Update Aug 31 19:07:00 volumio volumio[1113]: info: sendMpdCommand clear took 16 milliseconds Aug 31 19:07:00 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.178.88:9790/minimserver/*/music/Michael*20Jackson*20-*20Invincible*20(2001)*20*5b24bit*20Hi-Res*5d/11*20-*20Privacy*20(feat.*20Slash).flac" Aug 31 19:07:00 volumio volumio[1113]: error: updateQueue error: null Aug 31 19:07:00 volumio volumio[1113]: info: ------------------------------ 4ms Aug 31 19:07:01 volumio volumio[1113]: error: Failed request for metavolumio API Aug 31 19:07:01 volumio volumio[1113]: error: Failed request for metavolumio API Aug 31 19:07:03 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.178.88:9790/minimserver/*/music/Michael*20Jackson*20-*20Invincible*20(2001)*20*5b24bit*20Hi-Res*5d/11*20-*20Privacy*20(feat.*20Slash).flac" Aug 31 19:07:03 volumio volumio[1113]: info: Aug 31 19:07:03 volumio volumio[1113]: ---------------------------- MPD announces system playlist update Aug 31 19:07:03 volumio volumio[1113]: info: Ignoring MPD Status Update Aug 31 19:07:03 volumio volumio[1113]: info: sendMpdCommand add "http://192.168.178.88:9790/minimserver/*/music/Michael*20Jackson*20-*20Invincible*20(2001)*20*5b24bit*20Hi-Res*5d/11*20-*20Privacy*20(feat.*20Slash).flac" took 2 milliseconds Aug 31 19:07:03 volumio volumio[1113]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 31 19:07:03 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand play Aug 31 19:07:03 volumio volumio[1113]: info: ------------------------------ 3ms Aug 31 19:07:03 volumio volumio[1113]: info: sendMpdCommand play took 3 milliseconds Aug 31 19:07:03 volumio volumio[1113]: info: Aug 31 19:07:03 volumio volumio[1113]: ---------------------------- MPD announces state update: player Aug 31 19:07:03 volumio volumio[1113]: info: ControllerMpd::getState Aug 31 19:07:03 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Aug 31 19:07:03 volumio volumio[1113]: info: Aug 31 19:07:03 volumio volumio[1113]: ---------------------------- MPD announces state update: player Aug 31 19:07:03 volumio volumio[1113]: info: ControllerMpd::getState Aug 31 19:07:03 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Aug 31 19:07:03 volumio volumio[1113]: info: sendMpdCommand status took 2 milliseconds Aug 31 19:07:03 volumio volumio[1113]: verbose: ControllerMpd::parseState Aug 31 19:07:03 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 19:07:03 volumio volumio[1113]: info: sendMpdCommand status took 3 milliseconds Aug 31 19:07:03 volumio volumio[1113]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 31 19:07:03 volumio volumio[1113]: verbose: ControllerMpd::parseState Aug 31 19:07:03 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 19:07:03 volumio volumio[1113]: verbose: ControllerMpd::parseTrackInfo Aug 31 19:07:03 volumio volumio[1113]: info: ControllerMpd::pushState Aug 31 19:07:03 volumio volumio[1113]: info: CoreCommandRouter::servicePushState Aug 31 19:07:03 volumio volumio[1113]: info: CorePlayQueue::getTrack 10 Aug 31 19:07:03 volumio volumio[1113]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":305,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"11*20-*20Privacy*20(feat.*20Slash).flac","artist":null,"album":null,"uri":"http://192.168.178.88:9790/minimserver/*/music/Michael*20Jackson*20-*20Invincible*20(2001)*20*5b24bit*20Hi-Res*5d/11*20-*20Privacy*20(feat.*20Slash).flac","trackType":"flac"} Aug 31 19:07:03 volumio volumio[1113]: verbose: CURRENT POSITION 10 Aug 31 19:07:03 volumio volumio[1113]: info: CoreStateMachine::syncState stateService play Aug 31 19:07:03 volumio volumio[1113]: info: CoreStateMachine::syncState currentStatus stop Aug 31 19:07:03 volumio volumio[1113]: info: ------------------------------ 10ms Aug 31 19:07:03 volumio volumio[1113]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 31 19:07:03 volumio volumio[1113]: verbose: ControllerMpd::parseTrackInfo Aug 31 19:07:03 volumio volumio[1113]: info: ControllerMpd::pushState Aug 31 19:07:03 volumio volumio[1113]: info: CoreCommandRouter::servicePushState Aug 31 19:07:03 volumio volumio[1113]: info: CorePlayQueue::getTrack 10 Aug 31 19:07:03 volumio volumio[1113]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":305,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"11*20-*20Privacy*20(feat.*20Slash).flac","artist":null,"album":null,"uri":"http://192.168.178.88:9790/minimserver/*/music/Michael*20Jackson*20-*20Invincible*20(2001)*20*5b24bit*20Hi-Res*5d/11*20-*20Privacy*20(feat.*20Slash).flac","trackType":"flac"} Aug 31 19:07:03 volumio volumio[1113]: verbose: CURRENT POSITION 10 Aug 31 19:07:03 volumio volumio[1113]: info: CoreStateMachine::syncState stateService play Aug 31 19:07:03 volumio volumio[1113]: info: CoreStateMachine::syncState currentStatus play Aug 31 19:07:03 volumio volumio[1113]: info: Received an update from plugin. extracting info from payload Aug 31 19:07:03 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:07:03 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 19:07:03 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:07:03 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:07:03 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:07:03 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:07:03 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:07:03 volumio volumio[1113]: info: ------------------------------ 33ms Aug 31 19:07:03 volumio volumio[1113]: info: Aug 31 19:07:03 volumio volumio[1113]: ---------------------------- MPD announces system playlist update Aug 31 19:07:03 volumio volumio[1113]: info: Ignoring MPD Status Update Aug 31 19:07:03 volumio volumio[1113]: info: Aug 31 19:07:03 volumio volumio[1113]: ---------------------------- MPD announces state update: player Aug 31 19:07:03 volumio volumio[1113]: info: ControllerMpd::getState Aug 31 19:07:03 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Aug 31 19:07:03 volumio volumio[1113]: info: ------------------------------ 3ms Aug 31 19:07:03 volumio volumio[1113]: info: sendMpdCommand status took 3 milliseconds Aug 31 19:07:03 volumio volumio[1113]: verbose: ControllerMpd::parseState Aug 31 19:07:03 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 19:07:03 volumio volumio[1113]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 31 19:07:03 volumio volumio[1113]: verbose: ControllerMpd::parseTrackInfo Aug 31 19:07:03 volumio volumio[1113]: info: ControllerMpd::pushState Aug 31 19:07:03 volumio volumio[1113]: info: CoreCommandRouter::servicePushState Aug 31 19:07:03 volumio volumio[1113]: info: CorePlayQueue::getTrack 10 Aug 31 19:07:03 volumio volumio[1113]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":305,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1227 Kbps","isStreaming":false,"title":"Privacy (feat. Slash)","artist":"Michael Jackson","album":"Invincible","uri":"http://192.168.178.88:9790/minimserver/*/music/Michael*20Jackson*20-*20Invincible*20(2001)*20*5b24bit*20Hi-Res*5d/11*20-*20Privacy*20(feat.*20Slash).flac","trackType":"flac"} Aug 31 19:07:03 volumio volumio[1113]: verbose: CURRENT POSITION 10 Aug 31 19:07:03 volumio volumio[1113]: info: CoreStateMachine::syncState stateService play Aug 31 19:07:03 volumio volumio[1113]: info: CoreStateMachine::syncState currentStatus play Aug 31 19:07:03 volumio volumio[1113]: info: Received an update from plugin. extracting info from payload Aug 31 19:07:03 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:07:03 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 19:07:03 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:07:03 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:07:03 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:07:03 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:07:03 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:07:03 volumio volumio[1113]: info: ------------------------------ 22ms Aug 31 19:07:03 volumio volumio[1113]: info: CoreCommandRouter::volumioGetQueue Aug 31 19:07:03 volumio volumio[1113]: info: CoreStateMachine::getQueue Aug 31 19:07:03 volumio volumio[1113]: info: CorePlayQueue::getQueue Aug 31 19:07:03 volumio volumio[1113]: info: CoreCommandRouter::volumioGetQueue Aug 31 19:07:03 volumio volumio[1113]: info: CoreStateMachine::getQueue Aug 31 19:07:03 volumio volumio[1113]: info: CorePlayQueue::getQueue Aug 31 19:07:05 volumio volumio[1113]: info: Executing endpoint metavolumio Aug 31 19:07:05 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 31 19:07:05 volumio volumio[1113]: info: Executing endpoint metavolumio Aug 31 19:07:05 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 31 19:07:05 volumio volumio[1113]: info: Executing endpoint metavolumio Aug 31 19:07:05 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 31 19:07:06 volumio volumio[1113]: error: Failed request for metavolumio API Aug 31 19:07:06 volumio volumio[1113]: error: Failed request for metavolumio API Aug 31 19:07:07 volumio volumio[1113]: info: CoreCommandRouter::volumioNext Aug 31 19:07:07 volumio volumio[1113]: info: CoreStateMachine::next Aug 31 19:07:07 volumio volumio[1113]: info: ControllerMpd::next Aug 31 19:07:07 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand next Aug 31 19:07:07 volumio volumio[1113]: info: Aug 31 19:07:07 volumio volumio[1113]: ---------------------------- MPD announces system playlist update Aug 31 19:07:07 volumio volumio[1113]: info: Ignoring MPD Status Update Aug 31 19:07:07 volumio volumio[1113]: info: Aug 31 19:07:07 volumio volumio[1113]: ---------------------------- MPD announces state update: player Aug 31 19:07:07 volumio volumio[1113]: info: sendMpdCommand next took 20 milliseconds Aug 31 19:07:07 volumio volumio[1113]: info: ControllerMpd::getState Aug 31 19:07:07 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Aug 31 19:07:07 volumio volumio[1113]: error: updateQueue error: null Aug 31 19:07:07 volumio volumio[1113]: info: ------------------------------ 3ms Aug 31 19:07:07 volumio volumio[1113]: info: sendMpdCommand status took 2 milliseconds Aug 31 19:07:07 volumio volumio[1113]: verbose: ControllerMpd::parseState Aug 31 19:07:07 volumio volumio[1113]: info: ControllerMpd::pushState Aug 31 19:07:07 volumio volumio[1113]: info: CoreCommandRouter::servicePushState Aug 31 19:07:07 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:07:07 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 19:07:07 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:07:07 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:07:07 volumio volumio[1113]: info: CorePlayQueue::getTrack 10 Aug 31 19:07:07 volumio volumio[1113]: 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} Aug 31 19:07:07 volumio volumio[1113]: verbose: CURRENT POSITION 10 Aug 31 19:07:07 volumio volumio[1113]: info: CoreStateMachine::syncState stateService stop Aug 31 19:07:07 volumio volumio[1113]: info: CoreStateMachine::syncState currentStatus play Aug 31 19:07:07 volumio volumio[1113]: info: CoreStateMachine::play index undefined Aug 31 19:07:07 volumio volumio[1113]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 19:07:07 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:07:07 volumio volumio[1113]: info: CorePlayQueue::getTrack 11 Aug 31 19:07:07 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 19:07:07 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:07:07 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:07:07 volumio volumio[1113]: info: CorePlayQueue::getTrack 11 Aug 31 19:07:07 volumio volumio[1113]: info: CorePlayQueue::getTrack 11 Aug 31 19:07:07 volumio volumio[1113]: info: CoreStateMachine::startPlaybackTimer Aug 31 19:07:07 volumio volumio[1113]: info: CorePlayQueue::getTrack 11 Aug 31 19:07:07 volumio volumio[1113]: info: [1788196027691] ControllerUPNPBrowser::clearAddPlayTrack Aug 31 19:07:07 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 19:07:07 volumio volumio[1113]: info: ------------------------------ 37ms Aug 31 19:07:07 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:07:07 volumio volumio[1113]: info: CorePlayQueue::getTrack 11 Aug 31 19:07:07 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 19:07:07 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:07:07 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:07:07 volumio volumio[1113]: info: CorePlayQueue::getTrack 11 Aug 31 19:07:07 volumio volumio[1113]: info: sendMpdCommand stop took 75 milliseconds Aug 31 19:07:07 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand clear Aug 31 19:07:07 volumio volumio[1113]: info: CoreCommandRouter::volumioGetQueue Aug 31 19:07:07 volumio volumio[1113]: info: CoreStateMachine::getQueue Aug 31 19:07:07 volumio volumio[1113]: info: CorePlayQueue::getQueue Aug 31 19:07:07 volumio volumio[1113]: info: Aug 31 19:07:07 volumio volumio[1113]: ---------------------------- MPD announces system playlist update Aug 31 19:07:07 volumio volumio[1113]: info: Ignoring MPD Status Update Aug 31 19:07:07 volumio volumio[1113]: info: sendMpdCommand clear took 37 milliseconds Aug 31 19:07:07 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.178.88:9790/minimserver/*/music/Michael*20Jackson*20-*20Invincible*20(2001)*20*5b24bit*20Hi-Res*5d/12*20-*20Don*27t*20Walk*20Away.flac" Aug 31 19:07:07 volumio volumio[1113]: error: updateQueue error: null Aug 31 19:07:07 volumio volumio[1113]: info: ------------------------------ 3ms Aug 31 19:07:08 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:07:08 volumio volumio[1113]: info: CorePlayQueue::getTrack 11 Aug 31 19:07:08 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.178.88:9790/minimserver/*/music/Michael*20Jackson*20-*20Invincible*20(2001)*20*5b24bit*20Hi-Res*5d/12*20-*20Don*27t*20Walk*20Away.flac" Aug 31 19:07:08 volumio volumio[1113]: info: Aug 31 19:07:08 volumio volumio[1113]: ---------------------------- MPD announces system playlist update Aug 31 19:07:08 volumio volumio[1113]: info: Ignoring MPD Status Update Aug 31 19:07:08 volumio volumio[1113]: info: sendMpdCommand add "http://192.168.178.88:9790/minimserver/*/music/Michael*20Jackson*20-*20Invincible*20(2001)*20*5b24bit*20Hi-Res*5d/12*20-*20Don*27t*20Walk*20Away.flac" took 1 milliseconds Aug 31 19:07:08 volumio volumio[1113]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 31 19:07:08 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand play Aug 31 19:07:08 volumio volumio[1113]: info: ------------------------------ 2ms Aug 31 19:07:08 volumio volumio[1113]: info: sendMpdCommand play took 2 milliseconds Aug 31 19:07:08 volumio volumio[1113]: info: Aug 31 19:07:08 volumio volumio[1113]: ---------------------------- MPD announces state update: player Aug 31 19:07:08 volumio volumio[1113]: info: ControllerMpd::getState Aug 31 19:07:08 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Aug 31 19:07:08 volumio volumio[1113]: info: Aug 31 19:07:08 volumio volumio[1113]: ---------------------------- MPD announces state update: player Aug 31 19:07:08 volumio volumio[1113]: info: sendMpdCommand status took 3 milliseconds Aug 31 19:07:08 volumio volumio[1113]: info: ControllerMpd::getState Aug 31 19:07:08 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Aug 31 19:07:08 volumio volumio[1113]: verbose: ControllerMpd::parseState Aug 31 19:07:08 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 19:07:08 volumio volumio[1113]: info: sendMpdCommand status took 4 milliseconds Aug 31 19:07:08 volumio volumio[1113]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 31 19:07:08 volumio volumio[1113]: verbose: ControllerMpd::parseState Aug 31 19:07:08 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 19:07:08 volumio volumio[1113]: verbose: ControllerMpd::parseTrackInfo Aug 31 19:07:08 volumio volumio[1113]: info: ControllerMpd::pushState Aug 31 19:07:08 volumio volumio[1113]: info: CoreCommandRouter::servicePushState Aug 31 19:07:08 volumio volumio[1113]: info: CorePlayQueue::getTrack 11 Aug 31 19:07:08 volumio volumio[1113]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":265,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"12*20-*20Don*27t*20Walk*20Away.flac","artist":null,"album":null,"uri":"http://192.168.178.88:9790/minimserver/*/music/Michael*20Jackson*20-*20Invincible*20(2001)*20*5b24bit*20Hi-Res*5d/12*20-*20Don*27t*20Walk*20Away.flac","trackType":"flac"} Aug 31 19:07:08 volumio volumio[1113]: verbose: CURRENT POSITION 11 Aug 31 19:07:08 volumio volumio[1113]: info: CoreStateMachine::syncState stateService play Aug 31 19:07:08 volumio volumio[1113]: info: CoreStateMachine::syncState currentStatus stop Aug 31 19:07:08 volumio volumio[1113]: info: ------------------------------ 15ms Aug 31 19:07:08 volumio volumio[1113]: info: sendMpdCommand playlistinfo took 5 milliseconds Aug 31 19:07:08 volumio volumio[1113]: verbose: ControllerMpd::parseTrackInfo Aug 31 19:07:08 volumio volumio[1113]: info: ControllerMpd::pushState Aug 31 19:07:08 volumio volumio[1113]: info: CoreCommandRouter::servicePushState Aug 31 19:07:08 volumio volumio[1113]: info: CorePlayQueue::getTrack 11 Aug 31 19:07:08 volumio volumio[1113]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":265,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"12*20-*20Don*27t*20Walk*20Away.flac","artist":null,"album":null,"uri":"http://192.168.178.88:9790/minimserver/*/music/Michael*20Jackson*20-*20Invincible*20(2001)*20*5b24bit*20Hi-Res*5d/12*20-*20Don*27t*20Walk*20Away.flac","trackType":"flac"} Aug 31 19:07:08 volumio volumio[1113]: verbose: CURRENT POSITION 11 Aug 31 19:07:08 volumio volumio[1113]: info: CoreStateMachine::syncState stateService play Aug 31 19:07:08 volumio volumio[1113]: info: CoreStateMachine::syncState currentStatus play Aug 31 19:07:08 volumio volumio[1113]: info: Received an update from plugin. extracting info from payload Aug 31 19:07:08 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:07:08 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 19:07:08 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:07:08 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:07:08 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:07:08 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:07:08 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:07:08 volumio volumio[1113]: info: ------------------------------ 64ms Aug 31 19:07:09 volumio volumio[1113]: info: Aug 31 19:07:09 volumio volumio[1113]: ---------------------------- MPD announces system playlist update Aug 31 19:07:09 volumio volumio[1113]: info: Ignoring MPD Status Update Aug 31 19:07:09 volumio volumio[1113]: info: Aug 31 19:07:09 volumio volumio[1113]: ---------------------------- MPD announces state update: player Aug 31 19:07:09 volumio volumio[1113]: info: ControllerMpd::getState Aug 31 19:07:09 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Aug 31 19:07:09 volumio volumio[1113]: info: ------------------------------ 6ms Aug 31 19:07:09 volumio volumio[1113]: info: sendMpdCommand status took 5 milliseconds Aug 31 19:07:09 volumio volumio[1113]: verbose: ControllerMpd::parseState Aug 31 19:07:09 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 19:07:09 volumio volumio[1113]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 31 19:07:09 volumio volumio[1113]: verbose: ControllerMpd::parseTrackInfo Aug 31 19:07:09 volumio volumio[1113]: info: ControllerMpd::pushState Aug 31 19:07:09 volumio volumio[1113]: info: CoreCommandRouter::servicePushState Aug 31 19:07:09 volumio volumio[1113]: info: CorePlayQueue::getTrack 11 Aug 31 19:07:09 volumio volumio[1113]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":265,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1449 Kbps","isStreaming":false,"title":"Don't Walk Away","artist":"Michael Jackson","album":"Invincible","uri":"http://192.168.178.88:9790/minimserver/*/music/Michael*20Jackson*20-*20Invincible*20(2001)*20*5b24bit*20Hi-Res*5d/12*20-*20Don*27t*20Walk*20Away.flac","trackType":"flac"} Aug 31 19:07:09 volumio volumio[1113]: verbose: CURRENT POSITION 11 Aug 31 19:07:09 volumio volumio[1113]: info: CoreStateMachine::syncState stateService play Aug 31 19:07:09 volumio volumio[1113]: info: CoreStateMachine::syncState currentStatus play Aug 31 19:07:09 volumio volumio[1113]: info: Received an update from plugin. extracting info from payload Aug 31 19:07:09 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:07:09 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 19:07:09 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:07:09 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:07:09 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:07:09 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:07:09 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:07:09 volumio volumio[1113]: info: ------------------------------ 71ms Aug 31 19:07:09 volumio volumio[1113]: info: CoreCommandRouter::volumioGetQueue Aug 31 19:07:09 volumio volumio[1113]: info: CoreStateMachine::getQueue Aug 31 19:07:09 volumio volumio[1113]: info: CorePlayQueue::getQueue Aug 31 19:07:09 volumio volumio[1113]: info: CoreCommandRouter::volumioGetQueue Aug 31 19:07:09 volumio volumio[1113]: info: CoreStateMachine::getQueue Aug 31 19:07:09 volumio volumio[1113]: info: CorePlayQueue::getQueue Aug 31 19:07:11 volumio volumio[1113]: info: Executing endpoint metavolumio Aug 31 19:07:11 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 31 19:07:11 volumio volumio[1113]: info: Executing endpoint metavolumio Aug 31 19:07:11 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 31 19:07:11 volumio volumio[1113]: info: Executing endpoint metavolumio Aug 31 19:07:11 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 31 19:07:13 volumio volumio[1113]: error: Failed request for metavolumio API Aug 31 19:07:13 volumio volumio[1113]: error: Failed request for metavolumio API Aug 31 19:07:15 volumio volumio[1113]: info: CoreCommandRouter::volumioNext Aug 31 19:07:15 volumio volumio[1113]: info: CoreStateMachine::next Aug 31 19:07:15 volumio volumio[1113]: info: ControllerMpd::next Aug 31 19:07:15 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand next Aug 31 19:07:15 volumio volumio[1113]: info: Aug 31 19:07:15 volumio volumio[1113]: ---------------------------- MPD announces system playlist update Aug 31 19:07:15 volumio volumio[1113]: info: Ignoring MPD Status Update Aug 31 19:07:15 volumio volumio[1113]: info: Aug 31 19:07:15 volumio volumio[1113]: ---------------------------- MPD announces state update: player Aug 31 19:07:15 volumio volumio[1113]: info: sendMpdCommand next took 25 milliseconds Aug 31 19:07:15 volumio volumio[1113]: info: ControllerMpd::getState Aug 31 19:07:15 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Aug 31 19:07:15 volumio volumio[1113]: error: updateQueue error: null Aug 31 19:07:15 volumio volumio[1113]: info: ------------------------------ 21ms Aug 31 19:07:15 volumio volumio[1113]: info: sendMpdCommand status took 2 milliseconds Aug 31 19:07:15 volumio volumio[1113]: verbose: ControllerMpd::parseState Aug 31 19:07:15 volumio volumio[1113]: info: ControllerMpd::pushState Aug 31 19:07:15 volumio volumio[1113]: info: CoreCommandRouter::servicePushState Aug 31 19:07:15 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:07:15 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 19:07:15 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:07:15 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:07:15 volumio volumio[1113]: info: CorePlayQueue::getTrack 11 Aug 31 19:07:15 volumio volumio[1113]: 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} Aug 31 19:07:15 volumio volumio[1113]: verbose: CURRENT POSITION 11 Aug 31 19:07:15 volumio volumio[1113]: info: CoreStateMachine::syncState stateService stop Aug 31 19:07:15 volumio volumio[1113]: info: CoreStateMachine::syncState currentStatus play Aug 31 19:07:15 volumio volumio[1113]: info: CoreStateMachine::play index undefined Aug 31 19:07:15 volumio volumio[1113]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 19:07:15 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:07:15 volumio volumio[1113]: info: CorePlayQueue::getTrack 12 Aug 31 19:07:15 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 19:07:15 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:07:15 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:07:15 volumio volumio[1113]: info: CorePlayQueue::getTrack 12 Aug 31 19:07:15 volumio volumio[1113]: info: CorePlayQueue::getTrack 12 Aug 31 19:07:15 volumio volumio[1113]: info: CoreStateMachine::startPlaybackTimer Aug 31 19:07:15 volumio volumio[1113]: info: CorePlayQueue::getTrack 12 Aug 31 19:07:15 volumio volumio[1113]: info: [1788196035414] ControllerUPNPBrowser::clearAddPlayTrack Aug 31 19:07:15 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 19:07:15 volumio volumio[1113]: info: ------------------------------ 54ms Aug 31 19:07:15 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:07:15 volumio volumio[1113]: info: CorePlayQueue::getTrack 12 Aug 31 19:07:15 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 19:07:15 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:07:15 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:07:15 volumio volumio[1113]: info: CorePlayQueue::getTrack 12 Aug 31 19:07:15 volumio volumio[1113]: info: sendMpdCommand stop took 18 milliseconds Aug 31 19:07:15 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand clear Aug 31 19:07:15 volumio volumio[1113]: info: CoreCommandRouter::volumioGetQueue Aug 31 19:07:15 volumio volumio[1113]: info: CoreStateMachine::getQueue Aug 31 19:07:15 volumio volumio[1113]: info: CorePlayQueue::getQueue Aug 31 19:07:15 volumio volumio[1113]: info: Aug 31 19:07:15 volumio volumio[1113]: ---------------------------- MPD announces system playlist update Aug 31 19:07:15 volumio volumio[1113]: info: Ignoring MPD Status Update Aug 31 19:07:15 volumio volumio[1113]: info: sendMpdCommand clear took 18 milliseconds Aug 31 19:07:15 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.178.88:9790/minimserver/*/music/Michael*20Jackson*20-*20Invincible*20(2001)*20*5b24bit*20Hi-Res*5d/13*20-*20Cry.flac" Aug 31 19:07:15 volumio volumio[1113]: error: updateQueue error: null Aug 31 19:07:15 volumio volumio[1113]: info: ------------------------------ 4ms Aug 31 19:07:16 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.178.88:9790/minimserver/*/music/Michael*20Jackson*20-*20Invincible*20(2001)*20*5b24bit*20Hi-Res*5d/13*20-*20Cry.flac" Aug 31 19:07:16 volumio volumio[1113]: info: Aug 31 19:07:16 volumio volumio[1113]: ---------------------------- MPD announces system playlist update Aug 31 19:07:16 volumio volumio[1113]: info: Ignoring MPD Status Update Aug 31 19:07:16 volumio volumio[1113]: info: sendMpdCommand add "http://192.168.178.88:9790/minimserver/*/music/Michael*20Jackson*20-*20Invincible*20(2001)*20*5b24bit*20Hi-Res*5d/13*20-*20Cry.flac" took 1 milliseconds Aug 31 19:07:16 volumio volumio[1113]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 31 19:07:16 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand play Aug 31 19:07:16 volumio volumio[1113]: info: ------------------------------ 2ms Aug 31 19:07:16 volumio volumio[1113]: info: sendMpdCommand play took 1 milliseconds Aug 31 19:07:16 volumio volumio[1113]: info: Aug 31 19:07:16 volumio volumio[1113]: ---------------------------- MPD announces state update: player Aug 31 19:07:16 volumio volumio[1113]: info: ControllerMpd::getState Aug 31 19:07:16 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Aug 31 19:07:16 volumio volumio[1113]: info: Aug 31 19:07:16 volumio volumio[1113]: ---------------------------- MPD announces state update: player Aug 31 19:07:16 volumio volumio[1113]: info: sendMpdCommand status took 2 milliseconds Aug 31 19:07:16 volumio volumio[1113]: info: ControllerMpd::getState Aug 31 19:07:16 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Aug 31 19:07:16 volumio volumio[1113]: verbose: ControllerMpd::parseState Aug 31 19:07:16 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 19:07:16 volumio volumio[1113]: info: sendMpdCommand status took 1 milliseconds Aug 31 19:07:16 volumio volumio[1113]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 31 19:07:16 volumio volumio[1113]: verbose: ControllerMpd::parseState Aug 31 19:07:16 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 19:07:16 volumio volumio[1113]: verbose: ControllerMpd::parseTrackInfo Aug 31 19:07:16 volumio volumio[1113]: info: ControllerMpd::pushState Aug 31 19:07:16 volumio volumio[1113]: info: CoreCommandRouter::servicePushState Aug 31 19:07:16 volumio volumio[1113]: info: CorePlayQueue::getTrack 12 Aug 31 19:07:16 volumio volumio[1113]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":301,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"13*20-*20Cry.flac","artist":null,"album":null,"uri":"http://192.168.178.88:9790/minimserver/*/music/Michael*20Jackson*20-*20Invincible*20(2001)*20*5b24bit*20Hi-Res*5d/13*20-*20Cry.flac","trackType":"flac"} Aug 31 19:07:16 volumio volumio[1113]: verbose: CURRENT POSITION 12 Aug 31 19:07:16 volumio volumio[1113]: info: CoreStateMachine::syncState stateService play Aug 31 19:07:16 volumio volumio[1113]: info: CoreStateMachine::syncState currentStatus stop Aug 31 19:07:16 volumio volumio[1113]: info: ------------------------------ 7ms Aug 31 19:07:16 volumio volumio[1113]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 31 19:07:16 volumio volumio[1113]: verbose: ControllerMpd::parseTrackInfo Aug 31 19:07:16 volumio volumio[1113]: info: ControllerMpd::pushState Aug 31 19:07:16 volumio volumio[1113]: info: CoreCommandRouter::servicePushState Aug 31 19:07:16 volumio volumio[1113]: info: CorePlayQueue::getTrack 12 Aug 31 19:07:16 volumio volumio[1113]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":301,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"13*20-*20Cry.flac","artist":null,"album":null,"uri":"http://192.168.178.88:9790/minimserver/*/music/Michael*20Jackson*20-*20Invincible*20(2001)*20*5b24bit*20Hi-Res*5d/13*20-*20Cry.flac","trackType":"flac"} Aug 31 19:07:16 volumio volumio[1113]: verbose: CURRENT POSITION 12 Aug 31 19:07:16 volumio volumio[1113]: info: CoreStateMachine::syncState stateService play Aug 31 19:07:16 volumio volumio[1113]: info: CoreStateMachine::syncState currentStatus play Aug 31 19:07:16 volumio volumio[1113]: info: Received an update from plugin. extracting info from payload Aug 31 19:07:16 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:07:16 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 19:07:16 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:07:16 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:07:16 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:07:16 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:07:16 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:07:16 volumio volumio[1113]: info: ------------------------------ 17ms Aug 31 19:07:17 volumio volumio[1113]: info: Aug 31 19:07:17 volumio volumio[1113]: ---------------------------- MPD announces system playlist update Aug 31 19:07:17 volumio volumio[1113]: info: Ignoring MPD Status Update Aug 31 19:07:17 volumio volumio[1113]: info: Aug 31 19:07:17 volumio volumio[1113]: ---------------------------- MPD announces state update: player Aug 31 19:07:17 volumio volumio[1113]: info: ControllerMpd::getState Aug 31 19:07:17 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Aug 31 19:07:17 volumio volumio[1113]: info: ------------------------------ 3ms Aug 31 19:07:17 volumio volumio[1113]: info: sendMpdCommand status took 7 milliseconds Aug 31 19:07:17 volumio volumio[1113]: verbose: ControllerMpd::parseState Aug 31 19:07:17 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 19:07:17 volumio volumio[1113]: info: sendMpdCommand playlistinfo took 0 milliseconds Aug 31 19:07:17 volumio volumio[1113]: verbose: ControllerMpd::parseTrackInfo Aug 31 19:07:17 volumio volumio[1113]: info: ControllerMpd::pushState Aug 31 19:07:17 volumio volumio[1113]: info: CoreCommandRouter::servicePushState Aug 31 19:07:17 volumio volumio[1113]: info: CorePlayQueue::getTrack 12 Aug 31 19:07:17 volumio volumio[1113]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":301,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1080 Kbps","isStreaming":false,"title":"Cry","artist":"Michael Jackson","album":"Invincible","uri":"http://192.168.178.88:9790/minimserver/*/music/Michael*20Jackson*20-*20Invincible*20(2001)*20*5b24bit*20Hi-Res*5d/13*20-*20Cry.flac","trackType":"flac"} Aug 31 19:07:17 volumio volumio[1113]: verbose: CURRENT POSITION 12 Aug 31 19:07:17 volumio volumio[1113]: info: CoreStateMachine::syncState stateService play Aug 31 19:07:17 volumio volumio[1113]: info: CoreStateMachine::syncState currentStatus play Aug 31 19:07:17 volumio volumio[1113]: info: Received an update from plugin. extracting info from payload Aug 31 19:07:17 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:07:17 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 19:07:17 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:07:17 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:07:17 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:07:17 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:07:17 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:07:17 volumio volumio[1113]: info: ------------------------------ 60ms Aug 31 19:07:17 volumio volumio[1113]: info: CoreCommandRouter::volumioGetQueue Aug 31 19:07:17 volumio volumio[1113]: info: CoreStateMachine::getQueue Aug 31 19:07:17 volumio volumio[1113]: info: CorePlayQueue::getQueue Aug 31 19:07:17 volumio volumio[1113]: info: CoreCommandRouter::volumioGetQueue Aug 31 19:07:17 volumio volumio[1113]: info: CoreStateMachine::getQueue Aug 31 19:07:17 volumio volumio[1113]: info: CorePlayQueue::getQueue Aug 31 19:07:18 volumio volumio[1113]: error: Failed request for metavolumio API Aug 31 19:07:18 volumio volumio[1113]: error: Failed request for metavolumio API Aug 31 19:07:19 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:07:19 volumio volumio[1113]: info: Listing playlists Aug 31 19:07:19 volumio volumio[1113]: info: Listing playlists Aug 31 19:07:19 volumio volumio[1113]: info: Executing endpoint metavolumio Aug 31 19:07:19 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 31 19:07:19 volumio volumio[1113]: info: Executing endpoint metavolumio Aug 31 19:07:19 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 31 19:07:19 volumio volumio[1113]: info: Executing endpoint metavolumio Aug 31 19:07:19 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 31 19:07:21 volumio volumio[1113]: info: CoreCommandRouter::volumioNext Aug 31 19:07:21 volumio volumio[1113]: info: CoreStateMachine::next Aug 31 19:07:21 volumio volumio[1113]: info: ControllerMpd::next Aug 31 19:07:21 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand next Aug 31 19:07:21 volumio volumio[1113]: info: Aug 31 19:07:21 volumio volumio[1113]: ---------------------------- MPD announces system playlist update Aug 31 19:07:21 volumio volumio[1113]: info: Ignoring MPD Status Update Aug 31 19:07:21 volumio volumio[1113]: info: Aug 31 19:07:21 volumio volumio[1113]: ---------------------------- MPD announces state update: player Aug 31 19:07:21 volumio volumio[1113]: info: sendMpdCommand next took 6 milliseconds Aug 31 19:07:21 volumio volumio[1113]: info: ControllerMpd::getState Aug 31 19:07:21 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Aug 31 19:07:21 volumio volumio[1113]: error: updateQueue error: null Aug 31 19:07:21 volumio volumio[1113]: info: ------------------------------ 3ms Aug 31 19:07:21 volumio volumio[1113]: info: sendMpdCommand status took 2 milliseconds Aug 31 19:07:21 volumio volumio[1113]: verbose: ControllerMpd::parseState Aug 31 19:07:21 volumio volumio[1113]: info: ControllerMpd::pushState Aug 31 19:07:21 volumio volumio[1113]: info: CoreCommandRouter::servicePushState Aug 31 19:07:21 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:07:21 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 19:07:21 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:07:21 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:07:21 volumio volumio[1113]: info: CorePlayQueue::getTrack 12 Aug 31 19:07:21 volumio volumio[1113]: 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} Aug 31 19:07:21 volumio volumio[1113]: verbose: CURRENT POSITION 12 Aug 31 19:07:21 volumio volumio[1113]: info: CoreStateMachine::syncState stateService stop Aug 31 19:07:21 volumio volumio[1113]: info: CoreStateMachine::syncState currentStatus play Aug 31 19:07:21 volumio volumio[1113]: info: CoreStateMachine::play index undefined Aug 31 19:07:21 volumio volumio[1113]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 19:07:21 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:07:21 volumio volumio[1113]: info: CorePlayQueue::getTrack 13 Aug 31 19:07:21 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 19:07:21 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:07:21 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:07:21 volumio volumio[1113]: info: CorePlayQueue::getTrack 13 Aug 31 19:07:21 volumio volumio[1113]: info: CorePlayQueue::getTrack 13 Aug 31 19:07:21 volumio volumio[1113]: info: CoreStateMachine::startPlaybackTimer Aug 31 19:07:21 volumio volumio[1113]: info: CorePlayQueue::getTrack 13 Aug 31 19:07:21 volumio volumio[1113]: info: [1788196041133] ControllerUPNPBrowser::clearAddPlayTrack Aug 31 19:07:21 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 19:07:21 volumio volumio[1113]: info: ------------------------------ 62ms Aug 31 19:07:21 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:07:21 volumio volumio[1113]: info: CorePlayQueue::getTrack 13 Aug 31 19:07:21 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 19:07:21 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:07:21 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:07:21 volumio volumio[1113]: info: CorePlayQueue::getTrack 13 Aug 31 19:07:21 volumio volumio[1113]: info: sendMpdCommand stop took 15 milliseconds Aug 31 19:07:21 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand clear Aug 31 19:07:21 volumio volumio[1113]: info: CoreCommandRouter::volumioGetQueue Aug 31 19:07:21 volumio volumio[1113]: info: CoreStateMachine::getQueue Aug 31 19:07:21 volumio volumio[1113]: info: CorePlayQueue::getQueue Aug 31 19:07:21 volumio volumio[1113]: info: Aug 31 19:07:21 volumio volumio[1113]: ---------------------------- MPD announces system playlist update Aug 31 19:07:21 volumio volumio[1113]: info: Ignoring MPD Status Update Aug 31 19:07:21 volumio volumio[1113]: info: sendMpdCommand clear took 26 milliseconds Aug 31 19:07:21 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.178.88:9790/minimserver/*/music/Michael*20Jackson*20-*20Invincible*20(2001)*20*5b24bit*20Hi-Res*5d/14*20-*20The*20Lost*20Children.flac" Aug 31 19:07:21 volumio volumio[1113]: error: updateQueue error: null Aug 31 19:07:21 volumio volumio[1113]: info: ------------------------------ 10ms Aug 31 19:07:21 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.178.88:9790/minimserver/*/music/Michael*20Jackson*20-*20Invincible*20(2001)*20*5b24bit*20Hi-Res*5d/14*20-*20The*20Lost*20Children.flac" Aug 31 19:07:21 volumio volumio[1113]: info: Aug 31 19:07:21 volumio volumio[1113]: ---------------------------- MPD announces system playlist update Aug 31 19:07:21 volumio volumio[1113]: info: Ignoring MPD Status Update Aug 31 19:07:21 volumio volumio[1113]: info: sendMpdCommand add "http://192.168.178.88:9790/minimserver/*/music/Michael*20Jackson*20-*20Invincible*20(2001)*20*5b24bit*20Hi-Res*5d/14*20-*20The*20Lost*20Children.flac" took 1 milliseconds Aug 31 19:07:21 volumio volumio[1113]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 31 19:07:21 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand play Aug 31 19:07:21 volumio volumio[1113]: info: ------------------------------ 5ms Aug 31 19:07:21 volumio volumio[1113]: info: sendMpdCommand play took 1 milliseconds Aug 31 19:07:21 volumio volumio[1113]: info: Aug 31 19:07:21 volumio volumio[1113]: ---------------------------- MPD announces state update: player Aug 31 19:07:21 volumio volumio[1113]: info: ControllerMpd::getState Aug 31 19:07:21 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Aug 31 19:07:21 volumio volumio[1113]: info: Aug 31 19:07:21 volumio volumio[1113]: ---------------------------- MPD announces state update: player Aug 31 19:07:21 volumio volumio[1113]: info: ControllerMpd::getState Aug 31 19:07:21 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Aug 31 19:07:21 volumio volumio[1113]: info: sendMpdCommand status took 2 milliseconds Aug 31 19:07:21 volumio volumio[1113]: verbose: ControllerMpd::parseState Aug 31 19:07:21 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 19:07:21 volumio volumio[1113]: info: sendMpdCommand status took 10 milliseconds Aug 31 19:07:21 volumio volumio[1113]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 31 19:07:21 volumio volumio[1113]: verbose: ControllerMpd::parseState Aug 31 19:07:21 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 19:07:21 volumio volumio[1113]: verbose: ControllerMpd::parseTrackInfo Aug 31 19:07:21 volumio volumio[1113]: info: ControllerMpd::pushState Aug 31 19:07:21 volumio volumio[1113]: info: CoreCommandRouter::servicePushState Aug 31 19:07:21 volumio volumio[1113]: info: CorePlayQueue::getTrack 13 Aug 31 19:07:21 volumio volumio[1113]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":241,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"14*20-*20The*20Lost*20Children.flac","artist":null,"album":null,"uri":"http://192.168.178.88:9790/minimserver/*/music/Michael*20Jackson*20-*20Invincible*20(2001)*20*5b24bit*20Hi-Res*5d/14*20-*20The*20Lost*20Children.flac","trackType":"flac"} Aug 31 19:07:21 volumio volumio[1113]: verbose: CURRENT POSITION 13 Aug 31 19:07:21 volumio volumio[1113]: info: CoreStateMachine::syncState stateService play Aug 31 19:07:21 volumio volumio[1113]: info: CoreStateMachine::syncState currentStatus stop Aug 31 19:07:21 volumio volumio[1113]: info: ------------------------------ 15ms Aug 31 19:07:21 volumio volumio[1113]: info: sendMpdCommand playlistinfo took 11 milliseconds Aug 31 19:07:21 volumio volumio[1113]: verbose: ControllerMpd::parseTrackInfo Aug 31 19:07:21 volumio volumio[1113]: info: ControllerMpd::pushState Aug 31 19:07:21 volumio volumio[1113]: info: CoreCommandRouter::servicePushState Aug 31 19:07:21 volumio volumio[1113]: info: CorePlayQueue::getTrack 13 Aug 31 19:07:21 volumio volumio[1113]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":241,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"14*20-*20The*20Lost*20Children.flac","artist":null,"album":null,"uri":"http://192.168.178.88:9790/minimserver/*/music/Michael*20Jackson*20-*20Invincible*20(2001)*20*5b24bit*20Hi-Res*5d/14*20-*20The*20Lost*20Children.flac","trackType":"flac"} Aug 31 19:07:21 volumio volumio[1113]: verbose: CURRENT POSITION 13 Aug 31 19:07:21 volumio volumio[1113]: info: CoreStateMachine::syncState stateService play Aug 31 19:07:21 volumio volumio[1113]: info: CoreStateMachine::syncState currentStatus play Aug 31 19:07:21 volumio volumio[1113]: info: Received an update from plugin. extracting info from payload Aug 31 19:07:21 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:07:21 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 19:07:21 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:07:21 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:07:21 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:07:21 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:07:21 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:07:21 volumio volumio[1113]: info: ------------------------------ 68ms Aug 31 19:07:22 volumio volumio[1113]: info: Aug 31 19:07:22 volumio volumio[1113]: ---------------------------- MPD announces system playlist update Aug 31 19:07:22 volumio volumio[1113]: info: Ignoring MPD Status Update Aug 31 19:07:22 volumio volumio[1113]: info: Aug 31 19:07:22 volumio volumio[1113]: ---------------------------- MPD announces state update: player Aug 31 19:07:22 volumio volumio[1113]: info: ControllerMpd::getState Aug 31 19:07:22 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Aug 31 19:07:22 volumio volumio[1113]: info: ------------------------------ 4ms Aug 31 19:07:22 volumio volumio[1113]: info: sendMpdCommand status took 1 milliseconds Aug 31 19:07:22 volumio volumio[1113]: verbose: ControllerMpd::parseState Aug 31 19:07:22 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 19:07:22 volumio volumio[1113]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 31 19:07:22 volumio volumio[1113]: verbose: ControllerMpd::parseTrackInfo Aug 31 19:07:22 volumio volumio[1113]: info: ControllerMpd::pushState Aug 31 19:07:22 volumio volumio[1113]: info: CoreCommandRouter::servicePushState Aug 31 19:07:22 volumio volumio[1113]: info: CorePlayQueue::getTrack 13 Aug 31 19:07:22 volumio volumio[1113]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":241,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"900 Kbps","isStreaming":false,"title":"The Lost Children","artist":"Michael Jackson","album":"Invincible","uri":"http://192.168.178.88:9790/minimserver/*/music/Michael*20Jackson*20-*20Invincible*20(2001)*20*5b24bit*20Hi-Res*5d/14*20-*20The*20Lost*20Children.flac","trackType":"flac"} Aug 31 19:07:22 volumio volumio[1113]: verbose: CURRENT POSITION 13 Aug 31 19:07:22 volumio volumio[1113]: info: CoreStateMachine::syncState stateService play Aug 31 19:07:22 volumio volumio[1113]: info: CoreStateMachine::syncState currentStatus play Aug 31 19:07:22 volumio volumio[1113]: info: Received an update from plugin. extracting info from payload Aug 31 19:07:22 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:07:22 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 19:07:22 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:07:22 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:07:22 volumio volumio[1113]: info: CoreStateMachine::pushState Aug 31 19:07:22 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Aug 31 19:07:22 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:07:22 volumio volumio[1113]: info: ------------------------------ 30ms Aug 31 19:07:22 volumio volumio[1113]: info: CoreCommandRouter::volumioGetQueue Aug 31 19:07:22 volumio volumio[1113]: info: CoreStateMachine::getQueue Aug 31 19:07:22 volumio volumio[1113]: info: CorePlayQueue::getQueue Aug 31 19:07:22 volumio volumio[1113]: info: CoreCommandRouter::volumioGetQueue Aug 31 19:07:22 volumio volumio[1113]: info: CoreStateMachine::getQueue Aug 31 19:07:22 volumio volumio[1113]: info: CorePlayQueue::getQueue Aug 31 19:07:24 volumio volumio[1113]: info: Executing endpoint metavolumio Aug 31 19:07:24 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 31 19:07:24 volumio volumio[1113]: info: Executing endpoint metavolumio Aug 31 19:07:24 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 31 19:07:24 volumio volumio[1113]: info: Executing endpoint metavolumio Aug 31 19:07:24 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 31 19:07:26 volumio volumio[1113]: error: Failed request for metavolumio API Aug 31 19:07:26 volumio volumio[1113]: error: Failed request for metavolumio API Aug 31 19:07:28 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Aug 31 19:07:32 volumio volumio[1113]: error: Failed request for metavolumio API Aug 31 19:07:32 volumio volumio[1113]: error: Failed request for metavolumio API Aug 31 19:07:35 volumio volumio[1113]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 19:07:35 volumio volumio[1113]: TypeError: Cannot read property 'slice' of null Aug 31 19:07:35 volumio volumio[1113]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Aug 31 19:07:35 volumio volumio[1113]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Aug 31 19:07:35 volumio volumio[1113]: at Object.onceWrapper (events.js:520:26) Aug 31 19:07:35 volumio volumio[1113]: at ClientRequest.emit (events.js:400:28) Aug 31 19:07:35 volumio volumio[1113]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:647:27) Aug 31 19:07:35 volumio volumio[1113]: at HTTPParser.parserOnHeadersComplete (_http_common.js:127:17) Aug 31 19:07:35 volumio volumio[1113]: at TLSSocket.socketOnData (_http_client.js:515:22) Aug 31 19:07:35 volumio volumio[1113]: at TLSSocket.emit (events.js:400:28) Aug 31 19:07:35 volumio volumio[1113]: at addChunk (internal/streams/readable.js:293:12) Aug 31 19:07:35 volumio volumio[1113]: at readableAddChunk (internal/streams/readable.js:267:9) Aug 31 19:07:35 volumio volumio[1113]: at TLSSocket.Readable.push (internal/streams/readable.js:206:10) Aug 31 19:07:35 volumio volumio[1113]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:188:23) Aug 31 19:07:35 volumio volumio[1113]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 19:07:36 volumio sudo[31324]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-08-31 19:06 Aug 31 19:07:36 volumio sudo[31324]: 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="3dada8b1e619a5feb94867e0865ace17474d7bce" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="30045b259d1a704a832ca7c1460c1fdfa4b723f9" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 05 Mar 2026 12:25:22 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_DEVICENAME="Asus Tinkerboard" VOLUMIO_HASH="cf5455596c5328b0cbc691c04ff0184a"