-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Thu 2025-06-19 19:56:52 CEST. -- Jun 19 19:55:59 volumio volumio[857]: info: CoreCommandRouter::volumioNext Jun 19 19:55:59 volumio volumio[857]: info: CoreStateMachine::next Jun 19 19:55:59 volumio volumio[857]: info: CoreStateMachine::stop Jun 19 19:55:59 volumio volumio[857]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 19 19:55:59 volumio volumio[857]: info: CoreStateMachine::stPlaybackTimer Jun 19 19:55:59 volumio volumio[857]: info: CoreStateMachine::updateTrackBlock Jun 19 19:55:59 volumio volumio[857]: info: CorePlayQueue::getTrackBlock Jun 19 19:55:59 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:55:59 volumio volumio[857]: info: CorePlayQueue::getTrack 49 Jun 19 19:55:59 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 19 19:55:59 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:55:59 volumio volumio[857]: info: CoreStateMachine::serviceStop Jun 19 19:55:59 volumio volumio[857]: info: CorePlayQueue::getTrack 49 Jun 19 19:55:59 volumio volumio[857]: info: CoreCommandRouter::serviceStop Jun 19 19:55:59 volumio volumio[857]: info: ControllerMpd::stop Jun 19 19:55:59 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand stop Jun 19 19:55:59 volumio volumio[857]: info: Jun 19 19:55:59 volumio volumio[857]: ---------------------------- MPD announces state update: player Jun 19 19:55:59 volumio volumio[857]: info: sendMpdCommand stop took 4 milliseconds Jun 19 19:55:59 volumio volumio[857]: info: ControllerMpd::getState Jun 19 19:55:59 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand status Jun 19 19:55:59 volumio volumio[857]: info: CoreStateMachine::play index undefined Jun 19 19:55:59 volumio volumio[857]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 19 19:55:59 volumio volumio[857]: info: CorePlayQueue::getTrack 50 Jun 19 19:55:59 volumio volumio[857]: info: CoreStateMachine::startPlaybackTimer Jun 19 19:55:59 volumio volumio[857]: info: CorePlayQueue::getTrack 50 Jun 19 19:55:59 volumio volumio[857]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Zbigniew Preisner/Fairy Tale A True Story/01 Zbigniew Preisner - Houdini.flac Jun 19 19:55:59 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand stop Jun 19 19:55:59 volumio volumio[857]: info: CoreStateMachine::updateTrackBlock Jun 19 19:55:59 volumio volumio[857]: info: CorePlayQueue::getTrackBlock Jun 19 19:55:59 volumio volumio[857]: info: sendMpdCommand status took 5 milliseconds Jun 19 19:55:59 volumio volumio[857]: info: sendMpdCommand stop took 1 milliseconds Jun 19 19:55:59 volumio volumio[857]: verbose: ControllerMpd::parseState Jun 19 19:55:59 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 19:55:59 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jun 19 19:55:59 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand clear Jun 19 19:55:59 volumio volumio[857]: info: Jun 19 19:55:59 volumio volumio[857]: ---------------------------- MPD announces state update: player Jun 19 19:55:59 volumio volumio[857]: info: ControllerMpd::getState Jun 19 19:55:59 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand status Jun 19 19:55:59 volumio volumio[857]: info: Jun 19 19:55:59 volumio volumio[857]: ---------------------------- MPD announces state update: player Jun 19 19:55:59 volumio volumio[857]: info: ControllerMpd::getState Jun 19 19:55:59 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand status Jun 19 19:55:59 volumio volumio[857]: info: Jun 19 19:55:59 volumio volumio[857]: ---------------------------- MPD announces system playlist update Jun 19 19:55:59 volumio volumio[857]: info: Ignoring MPD Status Update Jun 19 19:55:59 volumio volumio[857]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 19 19:55:59 volumio volumio[857]: info: sendMpdCommand clear took 9 milliseconds Jun 19 19:55:59 volumio volumio[857]: info: sendMpdCommand status took 8 milliseconds Jun 19 19:55:59 volumio volumio[857]: verbose: ControllerMpd::parseTrackInfo Jun 19 19:55:59 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Zbigniew Preisner/Fairy Tale A True Story/01 Zbigniew Preisner - Houdini.flac" Jun 19 19:55:59 volumio volumio[857]: verbose: ControllerMpd::parseState Jun 19 19:55:59 volumio volumio[857]: info: ControllerMpd::pushState Jun 19 19:55:59 volumio volumio[857]: info: CoreCommandRouter::servicePushState Jun 19 19:55:59 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:55:59 volumio volumio[857]: info: CorePlayQueue::getTrack 50 Jun 19 19:55:59 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 19 19:55:59 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:55:59 volumio volumio[857]: info: CorePlayQueue::getTrack 50 Jun 19 19:55:59 volumio volumio[857]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Beauty: Song For Africa","artist":"Serah & Friedemann","album":"Flight Of The Stork","uri":"INTERNAL/Serah & Friedemann/Flight Of The Stork/03 Serah & Friedemann - Beauty; Song For Africa.flac","trackType":"flac"} Jun 19 19:55:59 volumio volumio[857]: verbose: CURRENT POSITION 50 Jun 19 19:55:59 volumio volumio[857]: info: CoreStateMachine::syncState stateService stop Jun 19 19:55:59 volumio volumio[857]: info: CoreStateMachine::syncState currentStatus stop Jun 19 19:55:59 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:55:59 volumio volumio[857]: info: CorePlayQueue::getTrack 50 Jun 19 19:55:59 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:55:59 volumio volumio[857]: info: No code Jun 19 19:55:59 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:55:59 volumio volumio[857]: info: CorePlayQueue::getTrack 50 Jun 19 19:55:59 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:55:59 volumio volumio[857]: info: ControllerMpd::pushState Jun 19 19:55:59 volumio volumio[857]: info: CoreCommandRouter::servicePushState Jun 19 19:55:59 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:55:59 volumio volumio[857]: info: CorePlayQueue::getTrack 50 Jun 19 19:55:59 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:55:59 volumio volumio[857]: info: CorePlayQueue::getTrack 50 Jun 19 19:55:59 volumio volumio[857]: 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} Jun 19 19:55:59 volumio volumio[857]: verbose: CURRENT POSITION 50 Jun 19 19:55:59 volumio volumio[857]: info: CoreStateMachine::syncState stateService stop Jun 19 19:55:59 volumio volumio[857]: info: CoreStateMachine::syncState currentStatus stop Jun 19 19:55:59 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:55:59 volumio volumio[857]: info: CorePlayQueue::getTrack 50 Jun 19 19:55:59 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:55:59 volumio volumio[857]: info: No code Jun 19 19:55:59 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:55:59 volumio volumio[857]: info: CorePlayQueue::getTrack 50 Jun 19 19:55:59 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:55:59 volumio volumio[857]: info: ------------------------------ 36ms Jun 19 19:55:59 volumio volumio[857]: info: ------------------------------ 28ms Jun 19 19:55:59 volumio volumio[857]: info: Jun 19 19:55:59 volumio volumio[857]: ---------------------------- MPD announces system playlist update Jun 19 19:55:59 volumio volumio[857]: info: Ignoring MPD Status Update Jun 19 19:55:59 volumio volumio[857]: error: updateQueue error: null Jun 19 19:55:59 volumio volumio[857]: info: Jun 19 19:55:59 volumio volumio[857]: ---------------------------- MPD announces system playlist update Jun 19 19:55:59 volumio volumio[857]: info: Ignoring MPD Status Update Jun 19 19:55:59 volumio volumio[857]: info: sendMpdCommand status took 30 milliseconds Jun 19 19:55:59 volumio volumio[857]: info: ------------------------------ 30ms Jun 19 19:55:59 volumio volumio[857]: info: sendMpdCommand add "INTERNAL/Zbigniew Preisner/Fairy Tale A True Story/01 Zbigniew Preisner - Houdini.flac" took 22 milliseconds Jun 19 19:55:59 volumio volumio[857]: info: ------------------------------ 4ms Jun 19 19:55:59 volumio volumio[857]: verbose: ControllerMpd::parseState Jun 19 19:55:59 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand play Jun 19 19:55:59 volumio volumio[857]: info: ControllerMpd::pushState Jun 19 19:55:59 volumio volumio[857]: info: CoreCommandRouter::servicePushState Jun 19 19:55:59 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:55:59 volumio volumio[857]: info: CorePlayQueue::getTrack 50 Jun 19 19:55:59 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:55:59 volumio volumio[857]: info: CorePlayQueue::getTrack 50 Jun 19 19:55:59 volumio volumio[857]: 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} Jun 19 19:55:59 volumio volumio[857]: verbose: CURRENT POSITION 50 Jun 19 19:55:59 volumio volumio[857]: info: CoreStateMachine::syncState stateService stop Jun 19 19:55:59 volumio volumio[857]: info: CoreStateMachine::syncState currentStatus stop Jun 19 19:55:59 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:55:59 volumio volumio[857]: info: CorePlayQueue::getTrack 50 Jun 19 19:55:59 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:55:59 volumio volumio[857]: info: No code Jun 19 19:55:59 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:55:59 volumio volumio[857]: info: CorePlayQueue::getTrack 50 Jun 19 19:55:59 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:55:59 volumio volumio[857]: info: ------------------------------ 41ms Jun 19 19:55:59 volumio volumio[857]: info: Jun 19 19:55:59 volumio volumio[857]: ---------------------------- MPD announces system playlist update Jun 19 19:55:59 volumio volumio[857]: info: Ignoring MPD Status Update Jun 19 19:55:59 volumio volumio[857]: info: Jun 19 19:55:59 volumio volumio[857]: ---------------------------- MPD announces system playlist update Jun 19 19:55:59 volumio volumio[857]: info: Ignoring MPD Status Update Jun 19 19:55:59 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jun 19 19:55:59 volumio volumio[857]: info: Jun 19 19:55:59 volumio volumio[857]: ---------------------------- MPD announces state update: player Jun 19 19:55:59 volumio volumio[857]: info: Jun 19 19:55:59 volumio volumio[857]: ---------------------------- MPD announces state update: player Jun 19 19:55:59 volumio volumio[857]: info: ------------------------------ 28ms Jun 19 19:55:59 volumio volumio[857]: info: sendMpdCommand play took 27 milliseconds Jun 19 19:55:59 volumio volumio[857]: info: ControllerMpd::getState Jun 19 19:55:59 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand status Jun 19 19:55:59 volumio volumio[857]: info: ------------------------------ 18ms Jun 19 19:55:59 volumio volumio[857]: info: ------------------------------ 17ms Jun 19 19:55:59 volumio volumio[857]: info: ControllerMpd::getState Jun 19 19:55:59 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand status Jun 19 19:55:59 volumio volumio[857]: info: Jun 19 19:55:59 volumio volumio[857]: ---------------------------- MPD announces state update: player Jun 19 19:55:59 volumio volumio[857]: info: ControllerMpd::getState Jun 19 19:55:59 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand status Jun 19 19:55:59 volumio volumio[857]: info: Jun 19 19:55:59 volumio volumio[857]: ---------------------------- MPD announces system playlist update Jun 19 19:55:59 volumio volumio[857]: info: Ignoring MPD Status Update Jun 19 19:55:59 volumio volumio[857]: info: Jun 19 19:55:59 volumio volumio[857]: ---------------------------- MPD announces state update: player Jun 19 19:55:59 volumio volumio[857]: info: ControllerMpd::getState Jun 19 19:55:59 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand status Jun 19 19:55:59 volumio volumio[857]: info: sendMpdCommand status took 8 milliseconds Jun 19 19:55:59 volumio volumio[857]: info: sendMpdCommand status took 7 milliseconds Jun 19 19:55:59 volumio volumio[857]: info: sendMpdCommand status took 5 milliseconds Jun 19 19:55:59 volumio volumio[857]: verbose: ControllerMpd::parseState Jun 19 19:55:59 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 19:55:59 volumio volumio[857]: verbose: ControllerMpd::parseState Jun 19 19:55:59 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 19:55:59 volumio volumio[857]: verbose: ControllerMpd::parseState Jun 19 19:55:59 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 19:55:59 volumio volumio[857]: info: Jun 19 19:55:59 volumio volumio[857]: ---------------------------- MPD announces state update: player Jun 19 19:55:59 volumio volumio[857]: info: ControllerMpd::getState Jun 19 19:55:59 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand status Jun 19 19:55:59 volumio volumio[857]: info: ------------------------------ 12ms Jun 19 19:55:59 volumio volumio[857]: info: sendMpdCommand status took 10 milliseconds Jun 19 19:55:59 volumio volumio[857]: info: sendMpdCommand playlistinfo took 7 milliseconds Jun 19 19:55:59 volumio volumio[857]: info: sendMpdCommand playlistinfo took 7 milliseconds Jun 19 19:55:59 volumio volumio[857]: info: sendMpdCommand playlistinfo took 6 milliseconds Jun 19 19:55:59 volumio volumio[857]: info: sendMpdCommand status took 4 milliseconds Jun 19 19:55:59 volumio volumio[857]: verbose: ControllerMpd::parseState Jun 19 19:55:59 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 19:55:59 volumio volumio[857]: verbose: ControllerMpd::parseTrackInfo Jun 19 19:55:59 volumio volumio[857]: verbose: ControllerMpd::parseTrackInfo Jun 19 19:55:59 volumio volumio[857]: verbose: ControllerMpd::parseTrackInfo Jun 19 19:55:59 volumio volumio[857]: verbose: ControllerMpd::parseState Jun 19 19:55:59 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 19:55:59 volumio volumio[857]: info: ControllerMpd::pushState Jun 19 19:55:59 volumio volumio[857]: info: CoreCommandRouter::servicePushState Jun 19 19:55:59 volumio volumio[857]: info: CorePlayQueue::getTrack 50 Jun 19 19:55:59 volumio volumio[857]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":83,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"385 Kbps","isStreaming":false,"title":"Houdini","artist":"Zbigniew Preisner","album":"Fairy Tale A True Story","uri":"INTERNAL/Zbigniew Preisner/Fairy Tale A True Story/01 Zbigniew Preisner - Houdini.flac","trackType":"flac"} Jun 19 19:55:59 volumio volumio[857]: verbose: CURRENT POSITION 50 Jun 19 19:55:59 volumio volumio[857]: info: CoreStateMachine::syncState stateService play Jun 19 19:55:59 volumio volumio[857]: info: CoreStateMachine::syncState currentStatus stop Jun 19 19:55:59 volumio volumio[857]: info: ControllerMpd::pushState Jun 19 19:55:59 volumio volumio[857]: info: CoreCommandRouter::servicePushState Jun 19 19:55:59 volumio volumio[857]: info: CorePlayQueue::getTrack 50 Jun 19 19:55:59 volumio volumio[857]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":83,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"380 Kbps","isStreaming":false,"title":"Houdini","artist":"Zbigniew Preisner","album":"Fairy Tale A True Story","uri":"INTERNAL/Zbigniew Preisner/Fairy Tale A True Story/01 Zbigniew Preisner - Houdini.flac","trackType":"flac"} Jun 19 19:55:59 volumio volumio[857]: verbose: CURRENT POSITION 50 Jun 19 19:55:59 volumio volumio[857]: info: CoreStateMachine::syncState stateService play Jun 19 19:55:59 volumio volumio[857]: info: CoreStateMachine::syncState currentStatus play Jun 19 19:55:59 volumio volumio[857]: info: Received an update from plugin. extracting info from payload Jun 19 19:55:59 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:55:59 volumio volumio[857]: info: CorePlayQueue::getTrack 50 Jun 19 19:55:59 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 19 19:55:59 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:55:59 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:55:59 volumio volumio[857]: info: CorePlayQueue::getTrack 50 Jun 19 19:55:59 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:55:59 volumio volumio[857]: info: ControllerMpd::pushState Jun 19 19:55:59 volumio volumio[857]: info: CoreCommandRouter::servicePushState Jun 19 19:55:59 volumio volumio[857]: info: CorePlayQueue::getTrack 50 Jun 19 19:55:59 volumio volumio[857]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":83,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"380 Kbps","isStreaming":false,"title":"Houdini","artist":"Zbigniew Preisner","album":"Fairy Tale A True Story","uri":"INTERNAL/Zbigniew Preisner/Fairy Tale A True Story/01 Zbigniew Preisner - Houdini.flac","trackType":"flac"} Jun 19 19:55:59 volumio volumio[857]: verbose: CURRENT POSITION 50 Jun 19 19:55:59 volumio volumio[857]: info: CoreStateMachine::syncState stateService play Jun 19 19:55:59 volumio volumio[857]: info: CoreStateMachine::syncState currentStatus play Jun 19 19:55:59 volumio volumio[857]: info: Received an update from plugin. extracting info from payload Jun 19 19:55:59 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:55:59 volumio volumio[857]: info: CorePlayQueue::getTrack 50 Jun 19 19:55:59 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:55:59 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:55:59 volumio volumio[857]: info: CorePlayQueue::getTrack 50 Jun 19 19:55:59 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:55:59 volumio volumio[857]: info: ------------------------------ 38ms Jun 19 19:55:59 volumio volumio[857]: info: ------------------------------ 42ms Jun 19 19:55:59 volumio volumio[857]: info: ------------------------------ 40ms Jun 19 19:55:59 volumio volumio[857]: info: Jun 19 19:55:59 volumio volumio[857]: ---------------------------- MPD announces state update: player Jun 19 19:55:59 volumio volumio[857]: info: ControllerMpd::getState Jun 19 19:55:59 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand status Jun 19 19:55:59 volumio volumio[857]: info: sendMpdCommand playlistinfo took 27 milliseconds Jun 19 19:55:59 volumio volumio[857]: info: sendMpdCommand playlistinfo took 25 milliseconds Jun 19 19:55:59 volumio volumio[857]: info: sendMpdCommand status took 2 milliseconds Jun 19 19:55:59 volumio volumio[857]: verbose: ControllerMpd::parseTrackInfo Jun 19 19:55:59 volumio volumio[857]: verbose: ControllerMpd::parseTrackInfo Jun 19 19:55:59 volumio volumio[857]: verbose: ControllerMpd::parseState Jun 19 19:55:59 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 19:55:59 volumio volumio[857]: info: ControllerMpd::pushState Jun 19 19:55:59 volumio volumio[857]: info: CoreCommandRouter::servicePushState Jun 19 19:55:59 volumio volumio[857]: info: CorePlayQueue::getTrack 50 Jun 19 19:55:59 volumio volumio[857]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":83,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"380 Kbps","isStreaming":false,"title":"Houdini","artist":"Zbigniew Preisner","album":"Fairy Tale A True Story","uri":"INTERNAL/Zbigniew Preisner/Fairy Tale A True Story/01 Zbigniew Preisner - Houdini.flac","trackType":"flac"} Jun 19 19:55:59 volumio volumio[857]: verbose: CURRENT POSITION 50 Jun 19 19:55:59 volumio volumio[857]: info: CoreStateMachine::syncState stateService play Jun 19 19:55:59 volumio volumio[857]: info: CoreStateMachine::syncState currentStatus play Jun 19 19:55:59 volumio volumio[857]: info: Received an update from plugin. extracting info from payload Jun 19 19:55:59 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:55:59 volumio volumio[857]: info: CorePlayQueue::getTrack 50 Jun 19 19:55:59 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:55:59 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:55:59 volumio volumio[857]: info: CorePlayQueue::getTrack 50 Jun 19 19:55:59 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:55:59 volumio volumio[857]: info: ControllerMpd::pushState Jun 19 19:55:59 volumio volumio[857]: info: CoreCommandRouter::servicePushState Jun 19 19:55:59 volumio volumio[857]: info: CorePlayQueue::getTrack 50 Jun 19 19:55:59 volumio volumio[857]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":83,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"380 Kbps","isStreaming":false,"title":"Houdini","artist":"Zbigniew Preisner","album":"Fairy Tale A True Story","uri":"INTERNAL/Zbigniew Preisner/Fairy Tale A True Story/01 Zbigniew Preisner - Houdini.flac","trackType":"flac"} Jun 19 19:55:59 volumio volumio[857]: verbose: CURRENT POSITION 50 Jun 19 19:55:59 volumio volumio[857]: info: CoreStateMachine::syncState stateService play Jun 19 19:55:59 volumio volumio[857]: info: CoreStateMachine::syncState currentStatus play Jun 19 19:55:59 volumio volumio[857]: info: Received an update from plugin. extracting info from payload Jun 19 19:55:59 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:55:59 volumio volumio[857]: info: CorePlayQueue::getTrack 50 Jun 19 19:55:59 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:55:59 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:55:59 volumio volumio[857]: info: CorePlayQueue::getTrack 50 Jun 19 19:55:59 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:55:59 volumio volumio[857]: info: ------------------------------ 63ms Jun 19 19:55:59 volumio volumio[857]: info: ------------------------------ 56ms Jun 19 19:55:59 volumio volumio[857]: info: sendMpdCommand playlistinfo took 33 milliseconds Jun 19 19:55:59 volumio volumio[857]: verbose: ControllerMpd::parseTrackInfo Jun 19 19:55:59 volumio volumio[857]: info: ControllerMpd::pushState Jun 19 19:55:59 volumio volumio[857]: info: CoreCommandRouter::servicePushState Jun 19 19:55:59 volumio volumio[857]: info: CorePlayQueue::getTrack 50 Jun 19 19:55:59 volumio volumio[857]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":83,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"380 Kbps","isStreaming":false,"title":"Houdini","artist":"Zbigniew Preisner","album":"Fairy Tale A True Story","uri":"INTERNAL/Zbigniew Preisner/Fairy Tale A True Story/01 Zbigniew Preisner - Houdini.flac","trackType":"flac"} Jun 19 19:55:59 volumio volumio[857]: verbose: CURRENT POSITION 50 Jun 19 19:55:59 volumio volumio[857]: info: CoreStateMachine::syncState stateService play Jun 19 19:55:59 volumio volumio[857]: info: CoreStateMachine::syncState currentStatus play Jun 19 19:55:59 volumio volumio[857]: info: Received an update from plugin. extracting info from payload Jun 19 19:55:59 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:55:59 volumio volumio[857]: info: CorePlayQueue::getTrack 50 Jun 19 19:55:59 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:55:59 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:55:59 volumio volumio[857]: info: CorePlayQueue::getTrack 50 Jun 19 19:55:59 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:55:59 volumio volumio[857]: info: ------------------------------ 46ms Jun 19 19:56:02 volumio volumio[857]: info: CoreCommandRouter::volumioNext Jun 19 19:56:02 volumio volumio[857]: info: CoreStateMachine::next Jun 19 19:56:02 volumio volumio[857]: info: CoreStateMachine::stop Jun 19 19:56:02 volumio volumio[857]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 19 19:56:02 volumio volumio[857]: info: CoreStateMachine::stPlaybackTimer Jun 19 19:56:02 volumio volumio[857]: info: CoreStateMachine::updateTrackBlock Jun 19 19:56:02 volumio volumio[857]: info: CorePlayQueue::getTrackBlock Jun 19 19:56:02 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:02 volumio volumio[857]: info: CorePlayQueue::getTrack 50 Jun 19 19:56:02 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 19 19:56:02 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:02 volumio volumio[857]: info: CoreStateMachine::serviceStop Jun 19 19:56:02 volumio volumio[857]: info: CorePlayQueue::getTrack 50 Jun 19 19:56:02 volumio volumio[857]: info: CoreCommandRouter::serviceStop Jun 19 19:56:02 volumio volumio[857]: info: ControllerMpd::stop Jun 19 19:56:02 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand stop Jun 19 19:56:02 volumio volumio[857]: info: Jun 19 19:56:02 volumio volumio[857]: ---------------------------- MPD announces state update: player Jun 19 19:56:02 volumio volumio[857]: info: sendMpdCommand stop took 7 milliseconds Jun 19 19:56:02 volumio volumio[857]: info: ControllerMpd::getState Jun 19 19:56:02 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand status Jun 19 19:56:02 volumio volumio[857]: info: CoreStateMachine::play index undefined Jun 19 19:56:02 volumio volumio[857]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 19 19:56:02 volumio volumio[857]: info: CorePlayQueue::getTrack 51 Jun 19 19:56:02 volumio volumio[857]: info: CoreStateMachine::startPlaybackTimer Jun 19 19:56:02 volumio volumio[857]: info: CorePlayQueue::getTrack 51 Jun 19 19:56:02 volumio volumio[857]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Zbigniew Preisner/Fairy Tale A True Story/02 Zbigniew Preisner - Fairytale.flac Jun 19 19:56:02 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand stop Jun 19 19:56:02 volumio volumio[857]: info: CoreStateMachine::updateTrackBlock Jun 19 19:56:02 volumio volumio[857]: info: CorePlayQueue::getTrackBlock Jun 19 19:56:02 volumio volumio[857]: info: Jun 19 19:56:02 volumio volumio[857]: ---------------------------- MPD announces state update: player Jun 19 19:56:02 volumio volumio[857]: info: ControllerMpd::getState Jun 19 19:56:02 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand status Jun 19 19:56:02 volumio volumio[857]: info: Jun 19 19:56:02 volumio volumio[857]: ---------------------------- MPD announces state update: player Jun 19 19:56:02 volumio volumio[857]: info: ControllerMpd::getState Jun 19 19:56:02 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand status Jun 19 19:56:02 volumio volumio[857]: info: sendMpdCommand status took 6 milliseconds Jun 19 19:56:02 volumio volumio[857]: info: sendMpdCommand stop took 5 milliseconds Jun 19 19:56:02 volumio volumio[857]: info: sendMpdCommand status took 4 milliseconds Jun 19 19:56:02 volumio volumio[857]: info: sendMpdCommand status took 2 milliseconds Jun 19 19:56:02 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jun 19 19:56:02 volumio volumio[857]: verbose: ControllerMpd::parseState Jun 19 19:56:02 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 19:56:02 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand clear Jun 19 19:56:02 volumio volumio[857]: verbose: ControllerMpd::parseState Jun 19 19:56:02 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 19:56:02 volumio volumio[857]: verbose: ControllerMpd::parseState Jun 19 19:56:02 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 19:56:02 volumio volumio[857]: info: Jun 19 19:56:02 volumio volumio[857]: ---------------------------- MPD announces system playlist update Jun 19 19:56:02 volumio volumio[857]: info: Ignoring MPD Status Update Jun 19 19:56:02 volumio volumio[857]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 19 19:56:02 volumio volumio[857]: info: sendMpdCommand clear took 4 milliseconds Jun 19 19:56:02 volumio volumio[857]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 19 19:56:02 volumio volumio[857]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 19 19:56:02 volumio volumio[857]: verbose: ControllerMpd::parseTrackInfo Jun 19 19:56:02 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Zbigniew Preisner/Fairy Tale A True Story/02 Zbigniew Preisner - Fairytale.flac" Jun 19 19:56:02 volumio volumio[857]: verbose: ControllerMpd::parseTrackInfo Jun 19 19:56:02 volumio volumio[857]: verbose: ControllerMpd::parseTrackInfo Jun 19 19:56:02 volumio volumio[857]: info: ControllerMpd::pushState Jun 19 19:56:02 volumio volumio[857]: info: CoreCommandRouter::servicePushState Jun 19 19:56:02 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:02 volumio volumio[857]: info: CorePlayQueue::getTrack 51 Jun 19 19:56:02 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 19 19:56:02 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:02 volumio volumio[857]: info: CorePlayQueue::getTrack 51 Jun 19 19:56:02 volumio volumio[857]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Houdini","artist":"Zbigniew Preisner","album":"Fairy Tale A True Story","uri":"INTERNAL/Zbigniew Preisner/Fairy Tale A True Story/01 Zbigniew Preisner - Houdini.flac","trackType":"flac"} Jun 19 19:56:02 volumio volumio[857]: verbose: CURRENT POSITION 51 Jun 19 19:56:02 volumio volumio[857]: info: CoreStateMachine::syncState stateService stop Jun 19 19:56:02 volumio volumio[857]: info: CoreStateMachine::syncState currentStatus stop Jun 19 19:56:02 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:02 volumio volumio[857]: info: CorePlayQueue::getTrack 51 Jun 19 19:56:02 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:02 volumio volumio[857]: info: No code Jun 19 19:56:02 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:02 volumio volumio[857]: info: CorePlayQueue::getTrack 51 Jun 19 19:56:02 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:02 volumio volumio[857]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 19 19:56:02 volumio volumio[857]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 19 19:56:02 volumio volumio[857]: info: ------------------------------ 27ms Jun 19 19:56:02 volumio volumio[857]: info: ------------------------------ 23ms Jun 19 19:56:02 volumio volumio[857]: info: ------------------------------ 22ms Jun 19 19:56:02 volumio volumio[857]: info: Jun 19 19:56:02 volumio volumio[857]: ---------------------------- MPD announces system playlist update Jun 19 19:56:02 volumio volumio[857]: info: Ignoring MPD Status Update Jun 19 19:56:02 volumio volumio[857]: info: Jun 19 19:56:02 volumio volumio[857]: ---------------------------- MPD announces system playlist update Jun 19 19:56:02 volumio volumio[857]: info: Ignoring MPD Status Update Jun 19 19:56:02 volumio volumio[857]: error: updateQueue error: null Jun 19 19:56:02 volumio volumio[857]: info: Jun 19 19:56:02 volumio volumio[857]: ---------------------------- MPD announces system playlist update Jun 19 19:56:02 volumio volumio[857]: info: Ignoring MPD Status Update Jun 19 19:56:02 volumio volumio[857]: info: ------------------------------ 20ms Jun 19 19:56:02 volumio volumio[857]: info: sendMpdCommand add "INTERNAL/Zbigniew Preisner/Fairy Tale A True Story/02 Zbigniew Preisner - Fairytale.flac" took 19 milliseconds Jun 19 19:56:02 volumio volumio[857]: info: ------------------------------ 4ms Jun 19 19:56:02 volumio volumio[857]: info: ------------------------------ 3ms Jun 19 19:56:02 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand play Jun 19 19:56:02 volumio volumio[857]: info: Jun 19 19:56:02 volumio volumio[857]: ---------------------------- MPD announces system playlist update Jun 19 19:56:02 volumio volumio[857]: info: Ignoring MPD Status Update Jun 19 19:56:02 volumio volumio[857]: info: Jun 19 19:56:02 volumio volumio[857]: ---------------------------- MPD announces system playlist update Jun 19 19:56:02 volumio volumio[857]: info: Ignoring MPD Status Update Jun 19 19:56:02 volumio volumio[857]: info: Jun 19 19:56:02 volumio volumio[857]: ---------------------------- MPD announces state update: player Jun 19 19:56:02 volumio volumio[857]: info: ------------------------------ 17ms Jun 19 19:56:02 volumio volumio[857]: info: sendMpdCommand play took 15 milliseconds Jun 19 19:56:02 volumio volumio[857]: info: ------------------------------ 14ms Jun 19 19:56:02 volumio volumio[857]: info: ------------------------------ 12ms Jun 19 19:56:02 volumio volumio[857]: info: ControllerMpd::getState Jun 19 19:56:02 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jun 19 19:56:02 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand status Jun 19 19:56:02 volumio volumio[857]: info: Jun 19 19:56:02 volumio volumio[857]: ---------------------------- MPD announces state update: player Jun 19 19:56:02 volumio volumio[857]: info: ControllerMpd::getState Jun 19 19:56:02 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand status Jun 19 19:56:02 volumio volumio[857]: info: Jun 19 19:56:02 volumio volumio[857]: ---------------------------- MPD announces state update: player Jun 19 19:56:02 volumio volumio[857]: info: ControllerMpd::getState Jun 19 19:56:02 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand status Jun 19 19:56:02 volumio volumio[857]: info: Jun 19 19:56:02 volumio volumio[857]: ---------------------------- MPD announces state update: player Jun 19 19:56:02 volumio volumio[857]: info: ControllerMpd::getState Jun 19 19:56:02 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand status Jun 19 19:56:02 volumio volumio[857]: info: sendMpdCommand status took 9 milliseconds Jun 19 19:56:02 volumio volumio[857]: info: sendMpdCommand status took 7 milliseconds Jun 19 19:56:02 volumio volumio[857]: info: sendMpdCommand status took 6 milliseconds Jun 19 19:56:02 volumio volumio[857]: verbose: ControllerMpd::parseState Jun 19 19:56:02 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 19:56:02 volumio volumio[857]: verbose: ControllerMpd::parseState Jun 19 19:56:02 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 19:56:02 volumio volumio[857]: verbose: ControllerMpd::parseState Jun 19 19:56:02 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 19:56:02 volumio volumio[857]: info: Jun 19 19:56:02 volumio volumio[857]: ---------------------------- MPD announces state update: player Jun 19 19:56:02 volumio volumio[857]: info: ControllerMpd::getState Jun 19 19:56:02 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand status Jun 19 19:56:02 volumio volumio[857]: info: Jun 19 19:56:02 volumio volumio[857]: ---------------------------- MPD announces state update: player Jun 19 19:56:02 volumio volumio[857]: info: ControllerMpd::getState Jun 19 19:56:02 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand status Jun 19 19:56:02 volumio volumio[857]: info: sendMpdCommand status took 10 milliseconds Jun 19 19:56:02 volumio volumio[857]: info: sendMpdCommand playlistinfo took 8 milliseconds Jun 19 19:56:02 volumio volumio[857]: info: sendMpdCommand playlistinfo took 7 milliseconds Jun 19 19:56:02 volumio volumio[857]: info: sendMpdCommand playlistinfo took 7 milliseconds Jun 19 19:56:02 volumio volumio[857]: info: sendMpdCommand status took 6 milliseconds Jun 19 19:56:02 volumio volumio[857]: info: sendMpdCommand status took 4 milliseconds Jun 19 19:56:02 volumio volumio[857]: verbose: ControllerMpd::parseState Jun 19 19:56:02 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 19:56:02 volumio volumio[857]: verbose: ControllerMpd::parseTrackInfo Jun 19 19:56:02 volumio volumio[857]: verbose: ControllerMpd::parseTrackInfo Jun 19 19:56:02 volumio volumio[857]: verbose: ControllerMpd::parseTrackInfo Jun 19 19:56:02 volumio volumio[857]: verbose: ControllerMpd::parseState Jun 19 19:56:02 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 19:56:02 volumio volumio[857]: verbose: ControllerMpd::parseState Jun 19 19:56:02 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 19:56:02 volumio volumio[857]: info: ControllerMpd::pushState Jun 19 19:56:02 volumio volumio[857]: info: CoreCommandRouter::servicePushState Jun 19 19:56:02 volumio volumio[857]: info: CorePlayQueue::getTrack 51 Jun 19 19:56:02 volumio volumio[857]: verbose: STATE SERVICE {"status":"play","position":0,"seek":457,"duration":173,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"429 Kbps","isStreaming":false,"title":"Fairytale","artist":"Zbigniew Preisner","album":"Fairy Tale A True Story","uri":"INTERNAL/Zbigniew Preisner/Fairy Tale A True Story/02 Zbigniew Preisner - Fairytale.flac","trackType":"flac"} Jun 19 19:56:02 volumio volumio[857]: verbose: CURRENT POSITION 51 Jun 19 19:56:02 volumio volumio[857]: info: CoreStateMachine::syncState stateService play Jun 19 19:56:02 volumio volumio[857]: info: CoreStateMachine::syncState currentStatus stop Jun 19 19:56:02 volumio volumio[857]: info: ControllerMpd::pushState Jun 19 19:56:02 volumio volumio[857]: info: CoreCommandRouter::servicePushState Jun 19 19:56:02 volumio volumio[857]: info: CorePlayQueue::getTrack 51 Jun 19 19:56:02 volumio volumio[857]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":173,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"415 Kbps","isStreaming":false,"title":"Fairytale","artist":"Zbigniew Preisner","album":"Fairy Tale A True Story","uri":"INTERNAL/Zbigniew Preisner/Fairy Tale A True Story/02 Zbigniew Preisner - Fairytale.flac","trackType":"flac"} Jun 19 19:56:02 volumio volumio[857]: verbose: CURRENT POSITION 51 Jun 19 19:56:02 volumio volumio[857]: info: CoreStateMachine::syncState stateService play Jun 19 19:56:02 volumio volumio[857]: info: CoreStateMachine::syncState currentStatus play Jun 19 19:56:02 volumio volumio[857]: info: Received an update from plugin. extracting info from payload Jun 19 19:56:02 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:02 volumio volumio[857]: info: CorePlayQueue::getTrack 51 Jun 19 19:56:02 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 19 19:56:02 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:02 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:02 volumio volumio[857]: info: CorePlayQueue::getTrack 51 Jun 19 19:56:02 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:02 volumio volumio[857]: info: ControllerMpd::pushState Jun 19 19:56:02 volumio volumio[857]: info: CoreCommandRouter::servicePushState Jun 19 19:56:02 volumio volumio[857]: info: CorePlayQueue::getTrack 51 Jun 19 19:56:02 volumio volumio[857]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":173,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"448 Kbps","isStreaming":false,"title":"Fairytale","artist":"Zbigniew Preisner","album":"Fairy Tale A True Story","uri":"INTERNAL/Zbigniew Preisner/Fairy Tale A True Story/02 Zbigniew Preisner - Fairytale.flac","trackType":"flac"} Jun 19 19:56:02 volumio volumio[857]: verbose: CURRENT POSITION 51 Jun 19 19:56:02 volumio volumio[857]: info: CoreStateMachine::syncState stateService play Jun 19 19:56:02 volumio volumio[857]: info: CoreStateMachine::syncState currentStatus play Jun 19 19:56:02 volumio volumio[857]: info: Received an update from plugin. extracting info from payload Jun 19 19:56:02 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:02 volumio volumio[857]: info: CorePlayQueue::getTrack 51 Jun 19 19:56:02 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:02 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:02 volumio volumio[857]: info: CorePlayQueue::getTrack 51 Jun 19 19:56:02 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:02 volumio volumio[857]: info: ------------------------------ 37ms Jun 19 19:56:02 volumio volumio[857]: info: ------------------------------ 41ms Jun 19 19:56:02 volumio volumio[857]: info: ------------------------------ 40ms Jun 19 19:56:02 volumio volumio[857]: info: sendMpdCommand playlistinfo took 24 milliseconds Jun 19 19:56:02 volumio volumio[857]: info: sendMpdCommand playlistinfo took 25 milliseconds Jun 19 19:56:02 volumio volumio[857]: info: sendMpdCommand playlistinfo took 24 milliseconds Jun 19 19:56:02 volumio volumio[857]: verbose: ControllerMpd::parseTrackInfo Jun 19 19:56:02 volumio volumio[857]: verbose: ControllerMpd::parseTrackInfo Jun 19 19:56:02 volumio volumio[857]: verbose: ControllerMpd::parseTrackInfo Jun 19 19:56:02 volumio volumio[857]: info: ControllerMpd::pushState Jun 19 19:56:02 volumio volumio[857]: info: CoreCommandRouter::servicePushState Jun 19 19:56:02 volumio volumio[857]: info: CorePlayQueue::getTrack 51 Jun 19 19:56:02 volumio volumio[857]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":173,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"405 Kbps","isStreaming":false,"title":"Fairytale","artist":"Zbigniew Preisner","album":"Fairy Tale A True Story","uri":"INTERNAL/Zbigniew Preisner/Fairy Tale A True Story/02 Zbigniew Preisner - Fairytale.flac","trackType":"flac"} Jun 19 19:56:02 volumio volumio[857]: verbose: CURRENT POSITION 51 Jun 19 19:56:02 volumio volumio[857]: info: CoreStateMachine::syncState stateService play Jun 19 19:56:02 volumio volumio[857]: info: CoreStateMachine::syncState currentStatus play Jun 19 19:56:02 volumio volumio[857]: info: Received an update from plugin. extracting info from payload Jun 19 19:56:02 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:02 volumio volumio[857]: info: CorePlayQueue::getTrack 51 Jun 19 19:56:02 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:02 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:02 volumio volumio[857]: info: CorePlayQueue::getTrack 51 Jun 19 19:56:02 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:02 volumio volumio[857]: info: ControllerMpd::pushState Jun 19 19:56:02 volumio volumio[857]: info: CoreCommandRouter::servicePushState Jun 19 19:56:02 volumio volumio[857]: info: CorePlayQueue::getTrack 51 Jun 19 19:56:02 volumio volumio[857]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":173,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"405 Kbps","isStreaming":false,"title":"Fairytale","artist":"Zbigniew Preisner","album":"Fairy Tale A True Story","uri":"INTERNAL/Zbigniew Preisner/Fairy Tale A True Story/02 Zbigniew Preisner - Fairytale.flac","trackType":"flac"} Jun 19 19:56:02 volumio volumio[857]: verbose: CURRENT POSITION 51 Jun 19 19:56:02 volumio volumio[857]: info: CoreStateMachine::syncState stateService play Jun 19 19:56:02 volumio volumio[857]: info: CoreStateMachine::syncState currentStatus play Jun 19 19:56:02 volumio volumio[857]: info: Received an update from plugin. extracting info from payload Jun 19 19:56:02 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:02 volumio volumio[857]: info: CorePlayQueue::getTrack 51 Jun 19 19:56:02 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:02 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:02 volumio volumio[857]: info: CorePlayQueue::getTrack 51 Jun 19 19:56:02 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:02 volumio volumio[857]: info: ControllerMpd::pushState Jun 19 19:56:02 volumio volumio[857]: info: CoreCommandRouter::servicePushState Jun 19 19:56:02 volumio volumio[857]: info: CorePlayQueue::getTrack 51 Jun 19 19:56:02 volumio volumio[857]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":173,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"405 Kbps","isStreaming":false,"title":"Fairytale","artist":"Zbigniew Preisner","album":"Fairy Tale A True Story","uri":"INTERNAL/Zbigniew Preisner/Fairy Tale A True Story/02 Zbigniew Preisner - Fairytale.flac","trackType":"flac"} Jun 19 19:56:02 volumio volumio[857]: verbose: CURRENT POSITION 51 Jun 19 19:56:02 volumio volumio[857]: info: CoreStateMachine::syncState stateService play Jun 19 19:56:02 volumio volumio[857]: info: CoreStateMachine::syncState currentStatus play Jun 19 19:56:02 volumio volumio[857]: info: Received an update from plugin. extracting info from payload Jun 19 19:56:02 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:02 volumio volumio[857]: info: CorePlayQueue::getTrack 51 Jun 19 19:56:02 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:02 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:02 volumio volumio[857]: info: CorePlayQueue::getTrack 51 Jun 19 19:56:02 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:02 volumio volumio[857]: info: ------------------------------ 66ms Jun 19 19:56:02 volumio volumio[857]: info: ------------------------------ 61ms Jun 19 19:56:02 volumio volumio[857]: info: ------------------------------ 60ms Jun 19 19:56:04 volumio volumio[857]: info: CoreCommandRouter::volumioNext Jun 19 19:56:04 volumio volumio[857]: info: CoreStateMachine::next Jun 19 19:56:04 volumio volumio[857]: info: CoreStateMachine::stop Jun 19 19:56:04 volumio volumio[857]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 19 19:56:04 volumio volumio[857]: info: CoreStateMachine::stPlaybackTimer Jun 19 19:56:04 volumio volumio[857]: info: CoreStateMachine::updateTrackBlock Jun 19 19:56:04 volumio volumio[857]: info: CorePlayQueue::getTrackBlock Jun 19 19:56:04 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:04 volumio volumio[857]: info: CorePlayQueue::getTrack 51 Jun 19 19:56:04 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 19 19:56:04 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:04 volumio volumio[857]: info: CoreStateMachine::serviceStop Jun 19 19:56:04 volumio volumio[857]: info: CorePlayQueue::getTrack 51 Jun 19 19:56:04 volumio volumio[857]: info: CoreCommandRouter::serviceStop Jun 19 19:56:04 volumio volumio[857]: info: ControllerMpd::stop Jun 19 19:56:04 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand stop Jun 19 19:56:04 volumio volumio[857]: info: Jun 19 19:56:04 volumio volumio[857]: ---------------------------- MPD announces state update: player Jun 19 19:56:04 volumio volumio[857]: info: sendMpdCommand stop took 6 milliseconds Jun 19 19:56:04 volumio volumio[857]: info: ControllerMpd::getState Jun 19 19:56:04 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand status Jun 19 19:56:04 volumio volumio[857]: info: CoreStateMachine::play index undefined Jun 19 19:56:04 volumio volumio[857]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 19 19:56:04 volumio volumio[857]: info: CorePlayQueue::getTrack 52 Jun 19 19:56:04 volumio volumio[857]: info: CoreStateMachine::startPlaybackTimer Jun 19 19:56:04 volumio volumio[857]: info: CorePlayQueue::getTrack 52 Jun 19 19:56:04 volumio volumio[857]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Zbigniew Preisner/Fairy Tale A True Story/03 Zbigniew Preisner - Alone in the Attic.flac Jun 19 19:56:04 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand stop Jun 19 19:56:04 volumio volumio[857]: info: CoreStateMachine::updateTrackBlock Jun 19 19:56:04 volumio volumio[857]: info: CorePlayQueue::getTrackBlock Jun 19 19:56:04 volumio volumio[857]: info: Jun 19 19:56:04 volumio volumio[857]: ---------------------------- MPD announces state update: player Jun 19 19:56:04 volumio volumio[857]: info: ControllerMpd::getState Jun 19 19:56:04 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand status Jun 19 19:56:04 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jun 19 19:56:04 volumio volumio[857]: info: Jun 19 19:56:04 volumio volumio[857]: ---------------------------- MPD announces state update: player Jun 19 19:56:04 volumio volumio[857]: info: ControllerMpd::getState Jun 19 19:56:04 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand status Jun 19 19:56:04 volumio volumio[857]: info: sendMpdCommand status took 6 milliseconds Jun 19 19:56:04 volumio volumio[857]: info: sendMpdCommand stop took 5 milliseconds Jun 19 19:56:04 volumio volumio[857]: info: sendMpdCommand status took 4 milliseconds Jun 19 19:56:04 volumio volumio[857]: info: sendMpdCommand status took 2 milliseconds Jun 19 19:56:04 volumio volumio[857]: verbose: ControllerMpd::parseState Jun 19 19:56:04 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 19:56:04 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand clear Jun 19 19:56:04 volumio volumio[857]: verbose: ControllerMpd::parseState Jun 19 19:56:04 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 19:56:04 volumio volumio[857]: verbose: ControllerMpd::parseState Jun 19 19:56:04 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 19:56:04 volumio volumio[857]: info: Jun 19 19:56:04 volumio volumio[857]: ---------------------------- MPD announces system playlist update Jun 19 19:56:04 volumio volumio[857]: info: Ignoring MPD Status Update Jun 19 19:56:04 volumio volumio[857]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 19 19:56:04 volumio volumio[857]: info: sendMpdCommand clear took 3 milliseconds Jun 19 19:56:04 volumio volumio[857]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 19 19:56:04 volumio volumio[857]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 19 19:56:04 volumio volumio[857]: verbose: ControllerMpd::parseTrackInfo Jun 19 19:56:04 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Zbigniew Preisner/Fairy Tale A True Story/03 Zbigniew Preisner - Alone in the Attic.flac" Jun 19 19:56:04 volumio volumio[857]: verbose: ControllerMpd::parseTrackInfo Jun 19 19:56:04 volumio volumio[857]: verbose: ControllerMpd::parseTrackInfo Jun 19 19:56:04 volumio volumio[857]: info: ControllerMpd::pushState Jun 19 19:56:04 volumio volumio[857]: info: CoreCommandRouter::servicePushState Jun 19 19:56:04 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:04 volumio volumio[857]: info: CorePlayQueue::getTrack 52 Jun 19 19:56:04 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 19 19:56:04 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:04 volumio volumio[857]: info: CorePlayQueue::getTrack 52 Jun 19 19:56:04 volumio volumio[857]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Fairytale","artist":"Zbigniew Preisner","album":"Fairy Tale A True Story","uri":"INTERNAL/Zbigniew Preisner/Fairy Tale A True Story/02 Zbigniew Preisner - Fairytale.flac","trackType":"flac"} Jun 19 19:56:04 volumio volumio[857]: verbose: CURRENT POSITION 52 Jun 19 19:56:04 volumio volumio[857]: info: CoreStateMachine::syncState stateService stop Jun 19 19:56:04 volumio volumio[857]: info: CoreStateMachine::syncState currentStatus stop Jun 19 19:56:04 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:04 volumio volumio[857]: info: CorePlayQueue::getTrack 52 Jun 19 19:56:04 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:04 volumio volumio[857]: info: No code Jun 19 19:56:04 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:04 volumio volumio[857]: info: CorePlayQueue::getTrack 52 Jun 19 19:56:04 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:04 volumio volumio[857]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 19 19:56:04 volumio volumio[857]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 19 19:56:04 volumio volumio[857]: info: ------------------------------ 25ms Jun 19 19:56:04 volumio volumio[857]: info: ------------------------------ 21ms Jun 19 19:56:04 volumio volumio[857]: info: ------------------------------ 20ms Jun 19 19:56:04 volumio volumio[857]: info: Jun 19 19:56:04 volumio volumio[857]: ---------------------------- MPD announces system playlist update Jun 19 19:56:04 volumio volumio[857]: info: Ignoring MPD Status Update Jun 19 19:56:04 volumio volumio[857]: info: Jun 19 19:56:04 volumio volumio[857]: ---------------------------- MPD announces system playlist update Jun 19 19:56:04 volumio volumio[857]: info: Ignoring MPD Status Update Jun 19 19:56:04 volumio volumio[857]: error: updateQueue error: null Jun 19 19:56:04 volumio volumio[857]: info: Jun 19 19:56:04 volumio volumio[857]: ---------------------------- MPD announces system playlist update Jun 19 19:56:04 volumio volumio[857]: info: Ignoring MPD Status Update Jun 19 19:56:04 volumio volumio[857]: info: ------------------------------ 19ms Jun 19 19:56:04 volumio volumio[857]: info: sendMpdCommand add "INTERNAL/Zbigniew Preisner/Fairy Tale A True Story/03 Zbigniew Preisner - Alone in the Attic.flac" took 17 milliseconds Jun 19 19:56:04 volumio volumio[857]: info: ------------------------------ 4ms Jun 19 19:56:04 volumio volumio[857]: info: ------------------------------ 3ms Jun 19 19:56:04 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand play Jun 19 19:56:04 volumio volumio[857]: info: Jun 19 19:56:04 volumio volumio[857]: ---------------------------- MPD announces system playlist update Jun 19 19:56:04 volumio volumio[857]: info: Ignoring MPD Status Update Jun 19 19:56:04 volumio volumio[857]: info: Jun 19 19:56:04 volumio volumio[857]: ---------------------------- MPD announces system playlist update Jun 19 19:56:04 volumio volumio[857]: info: Ignoring MPD Status Update Jun 19 19:56:04 volumio volumio[857]: info: Jun 19 19:56:04 volumio volumio[857]: ---------------------------- MPD announces state update: player Jun 19 19:56:04 volumio volumio[857]: info: ------------------------------ 8ms Jun 19 19:56:04 volumio volumio[857]: info: sendMpdCommand play took 7 milliseconds Jun 19 19:56:04 volumio volumio[857]: info: ------------------------------ 6ms Jun 19 19:56:04 volumio volumio[857]: info: ------------------------------ 5ms Jun 19 19:56:04 volumio volumio[857]: info: ControllerMpd::getState Jun 19 19:56:04 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand status Jun 19 19:56:04 volumio volumio[857]: info: Jun 19 19:56:04 volumio volumio[857]: ---------------------------- MPD announces state update: player Jun 19 19:56:04 volumio volumio[857]: info: ControllerMpd::getState Jun 19 19:56:04 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand status Jun 19 19:56:04 volumio volumio[857]: info: Jun 19 19:56:04 volumio volumio[857]: ---------------------------- MPD announces state update: player Jun 19 19:56:04 volumio volumio[857]: info: ControllerMpd::getState Jun 19 19:56:04 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand status Jun 19 19:56:04 volumio volumio[857]: info: Jun 19 19:56:04 volumio volumio[857]: ---------------------------- MPD announces state update: player Jun 19 19:56:04 volumio volumio[857]: info: sendMpdCommand status took 4 milliseconds Jun 19 19:56:04 volumio volumio[857]: info: ControllerMpd::getState Jun 19 19:56:04 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand status Jun 19 19:56:04 volumio volumio[857]: info: sendMpdCommand status took 3 milliseconds Jun 19 19:56:04 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jun 19 19:56:04 volumio volumio[857]: info: sendMpdCommand status took 6 milliseconds Jun 19 19:56:04 volumio volumio[857]: verbose: ControllerMpd::parseState Jun 19 19:56:04 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 19:56:04 volumio volumio[857]: verbose: ControllerMpd::parseState Jun 19 19:56:04 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 19:56:04 volumio volumio[857]: verbose: ControllerMpd::parseState Jun 19 19:56:04 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 19:56:04 volumio volumio[857]: info: Jun 19 19:56:04 volumio volumio[857]: ---------------------------- MPD announces state update: player Jun 19 19:56:04 volumio volumio[857]: info: ControllerMpd::getState Jun 19 19:56:04 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand status Jun 19 19:56:04 volumio volumio[857]: info: Jun 19 19:56:04 volumio volumio[857]: ---------------------------- MPD announces state update: player Jun 19 19:56:04 volumio volumio[857]: info: ControllerMpd::getState Jun 19 19:56:04 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand status Jun 19 19:56:04 volumio volumio[857]: info: sendMpdCommand status took 11 milliseconds Jun 19 19:56:04 volumio volumio[857]: info: sendMpdCommand playlistinfo took 7 milliseconds Jun 19 19:56:04 volumio volumio[857]: info: sendMpdCommand playlistinfo took 7 milliseconds Jun 19 19:56:04 volumio volumio[857]: info: sendMpdCommand playlistinfo took 7 milliseconds Jun 19 19:56:04 volumio volumio[857]: info: sendMpdCommand status took 5 milliseconds Jun 19 19:56:04 volumio volumio[857]: info: sendMpdCommand status took 3 milliseconds Jun 19 19:56:04 volumio volumio[857]: verbose: ControllerMpd::parseState Jun 19 19:56:04 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 19:56:04 volumio volumio[857]: verbose: ControllerMpd::parseTrackInfo Jun 19 19:56:04 volumio volumio[857]: verbose: ControllerMpd::parseTrackInfo Jun 19 19:56:04 volumio volumio[857]: verbose: ControllerMpd::parseTrackInfo Jun 19 19:56:04 volumio volumio[857]: verbose: ControllerMpd::parseState Jun 19 19:56:04 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 19:56:04 volumio volumio[857]: verbose: ControllerMpd::parseState Jun 19 19:56:04 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 19:56:04 volumio volumio[857]: info: ControllerMpd::pushState Jun 19 19:56:04 volumio volumio[857]: info: CoreCommandRouter::servicePushState Jun 19 19:56:04 volumio volumio[857]: info: CorePlayQueue::getTrack 52 Jun 19 19:56:04 volumio volumio[857]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":38,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Alone in the Attic","artist":"Zbigniew Preisner","album":"Fairy Tale A True Story","uri":"INTERNAL/Zbigniew Preisner/Fairy Tale A True Story/03 Zbigniew Preisner - Alone in the Attic.flac","trackType":"flac"} Jun 19 19:56:04 volumio volumio[857]: verbose: CURRENT POSITION 52 Jun 19 19:56:04 volumio volumio[857]: info: CoreStateMachine::syncState stateService play Jun 19 19:56:04 volumio volumio[857]: info: CoreStateMachine::syncState currentStatus stop Jun 19 19:56:04 volumio volumio[857]: info: ControllerMpd::pushState Jun 19 19:56:04 volumio volumio[857]: info: CoreCommandRouter::servicePushState Jun 19 19:56:04 volumio volumio[857]: info: CorePlayQueue::getTrack 52 Jun 19 19:56:04 volumio volumio[857]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":38,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Alone in the Attic","artist":"Zbigniew Preisner","album":"Fairy Tale A True Story","uri":"INTERNAL/Zbigniew Preisner/Fairy Tale A True Story/03 Zbigniew Preisner - Alone in the Attic.flac","trackType":"flac"} Jun 19 19:56:04 volumio volumio[857]: verbose: CURRENT POSITION 52 Jun 19 19:56:04 volumio volumio[857]: info: CoreStateMachine::syncState stateService play Jun 19 19:56:04 volumio volumio[857]: info: CoreStateMachine::syncState currentStatus play Jun 19 19:56:04 volumio volumio[857]: info: Received an update from plugin. extracting info from payload Jun 19 19:56:04 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:04 volumio volumio[857]: info: CorePlayQueue::getTrack 52 Jun 19 19:56:04 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 19 19:56:04 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:04 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:04 volumio volumio[857]: info: CorePlayQueue::getTrack 52 Jun 19 19:56:04 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:04 volumio volumio[857]: info: ControllerMpd::pushState Jun 19 19:56:04 volumio volumio[857]: info: CoreCommandRouter::servicePushState Jun 19 19:56:04 volumio volumio[857]: info: CorePlayQueue::getTrack 52 Jun 19 19:56:04 volumio volumio[857]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":38,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Alone in the Attic","artist":"Zbigniew Preisner","album":"Fairy Tale A True Story","uri":"INTERNAL/Zbigniew Preisner/Fairy Tale A True Story/03 Zbigniew Preisner - Alone in the Attic.flac","trackType":"flac"} Jun 19 19:56:04 volumio volumio[857]: verbose: CURRENT POSITION 52 Jun 19 19:56:04 volumio volumio[857]: info: CoreStateMachine::syncState stateService play Jun 19 19:56:04 volumio volumio[857]: info: CoreStateMachine::syncState currentStatus play Jun 19 19:56:04 volumio volumio[857]: info: Received an update from plugin. extracting info from payload Jun 19 19:56:04 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:04 volumio volumio[857]: info: CorePlayQueue::getTrack 52 Jun 19 19:56:04 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:04 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:04 volumio volumio[857]: info: CorePlayQueue::getTrack 52 Jun 19 19:56:04 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:04 volumio volumio[857]: info: ------------------------------ 33ms Jun 19 19:56:04 volumio volumio[857]: info: ------------------------------ 37ms Jun 19 19:56:04 volumio volumio[857]: info: ------------------------------ 36ms Jun 19 19:56:04 volumio volumio[857]: info: sendMpdCommand playlistinfo took 21 milliseconds Jun 19 19:56:04 volumio volumio[857]: info: sendMpdCommand playlistinfo took 21 milliseconds Jun 19 19:56:04 volumio volumio[857]: info: sendMpdCommand playlistinfo took 20 milliseconds Jun 19 19:56:04 volumio volumio[857]: verbose: ControllerMpd::parseTrackInfo Jun 19 19:56:04 volumio volumio[857]: verbose: ControllerMpd::parseTrackInfo Jun 19 19:56:04 volumio volumio[857]: verbose: ControllerMpd::parseTrackInfo Jun 19 19:56:04 volumio volumio[857]: info: ControllerMpd::pushState Jun 19 19:56:04 volumio volumio[857]: info: CoreCommandRouter::servicePushState Jun 19 19:56:04 volumio volumio[857]: info: CorePlayQueue::getTrack 52 Jun 19 19:56:04 volumio volumio[857]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":38,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Alone in the Attic","artist":"Zbigniew Preisner","album":"Fairy Tale A True Story","uri":"INTERNAL/Zbigniew Preisner/Fairy Tale A True Story/03 Zbigniew Preisner - Alone in the Attic.flac","trackType":"flac"} Jun 19 19:56:04 volumio volumio[857]: verbose: CURRENT POSITION 52 Jun 19 19:56:04 volumio volumio[857]: info: CoreStateMachine::syncState stateService play Jun 19 19:56:04 volumio volumio[857]: info: CoreStateMachine::syncState currentStatus play Jun 19 19:56:04 volumio volumio[857]: info: Received an update from plugin. extracting info from payload Jun 19 19:56:04 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:04 volumio volumio[857]: info: CorePlayQueue::getTrack 52 Jun 19 19:56:04 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:04 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:04 volumio volumio[857]: info: CorePlayQueue::getTrack 52 Jun 19 19:56:04 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:04 volumio volumio[857]: info: ControllerMpd::pushState Jun 19 19:56:04 volumio volumio[857]: info: CoreCommandRouter::servicePushState Jun 19 19:56:04 volumio volumio[857]: info: CorePlayQueue::getTrack 52 Jun 19 19:56:04 volumio volumio[857]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":38,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Alone in the Attic","artist":"Zbigniew Preisner","album":"Fairy Tale A True Story","uri":"INTERNAL/Zbigniew Preisner/Fairy Tale A True Story/03 Zbigniew Preisner - Alone in the Attic.flac","trackType":"flac"} Jun 19 19:56:04 volumio volumio[857]: verbose: CURRENT POSITION 52 Jun 19 19:56:04 volumio volumio[857]: info: CoreStateMachine::syncState stateService play Jun 19 19:56:04 volumio volumio[857]: info: CoreStateMachine::syncState currentStatus play Jun 19 19:56:04 volumio volumio[857]: info: Received an update from plugin. extracting info from payload Jun 19 19:56:04 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:04 volumio volumio[857]: info: CorePlayQueue::getTrack 52 Jun 19 19:56:04 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:04 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:04 volumio volumio[857]: info: CorePlayQueue::getTrack 52 Jun 19 19:56:04 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:04 volumio volumio[857]: info: ControllerMpd::pushState Jun 19 19:56:04 volumio volumio[857]: info: CoreCommandRouter::servicePushState Jun 19 19:56:04 volumio volumio[857]: info: CorePlayQueue::getTrack 52 Jun 19 19:56:04 volumio volumio[857]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":38,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"522 Kbps","isStreaming":false,"title":"Alone in the Attic","artist":"Zbigniew Preisner","album":"Fairy Tale A True Story","uri":"INTERNAL/Zbigniew Preisner/Fairy Tale A True Story/03 Zbigniew Preisner - Alone in the Attic.flac","trackType":"flac"} Jun 19 19:56:04 volumio volumio[857]: verbose: CURRENT POSITION 52 Jun 19 19:56:04 volumio volumio[857]: info: CoreStateMachine::syncState stateService play Jun 19 19:56:04 volumio volumio[857]: info: CoreStateMachine::syncState currentStatus play Jun 19 19:56:04 volumio volumio[857]: info: Received an update from plugin. extracting info from payload Jun 19 19:56:04 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:04 volumio volumio[857]: info: CorePlayQueue::getTrack 52 Jun 19 19:56:04 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 19 19:56:04 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:04 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:04 volumio volumio[857]: info: CorePlayQueue::getTrack 52 Jun 19 19:56:04 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:04 volumio volumio[857]: info: ------------------------------ 68ms Jun 19 19:56:04 volumio volumio[857]: info: ------------------------------ 61ms Jun 19 19:56:04 volumio volumio[857]: info: ------------------------------ 59ms Jun 19 19:56:08 volumio volumio[857]: info: CoreCommandRouter::volumioNext Jun 19 19:56:08 volumio volumio[857]: info: CoreStateMachine::next Jun 19 19:56:08 volumio volumio[857]: info: CoreStateMachine::stop Jun 19 19:56:08 volumio volumio[857]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 19 19:56:08 volumio volumio[857]: info: CoreStateMachine::stPlaybackTimer Jun 19 19:56:08 volumio volumio[857]: info: CoreStateMachine::updateTrackBlock Jun 19 19:56:08 volumio volumio[857]: info: CorePlayQueue::getTrackBlock Jun 19 19:56:08 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:08 volumio volumio[857]: info: CorePlayQueue::getTrack 52 Jun 19 19:56:08 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 19 19:56:08 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:08 volumio volumio[857]: info: CoreStateMachine::serviceStop Jun 19 19:56:08 volumio volumio[857]: info: CorePlayQueue::getTrack 52 Jun 19 19:56:08 volumio volumio[857]: info: CoreCommandRouter::serviceStop Jun 19 19:56:08 volumio volumio[857]: info: ControllerMpd::stop Jun 19 19:56:08 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand stop Jun 19 19:56:08 volumio volumio[857]: info: sendMpdCommand stop took 6 milliseconds Jun 19 19:56:08 volumio volumio[857]: info: CoreStateMachine::play index undefined Jun 19 19:56:08 volumio volumio[857]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 19 19:56:08 volumio volumio[857]: info: CorePlayQueue::getTrack 53 Jun 19 19:56:08 volumio volumio[857]: info: CoreStateMachine::startPlaybackTimer Jun 19 19:56:08 volumio volumio[857]: info: CorePlayQueue::getTrack 53 Jun 19 19:56:08 volumio volumio[857]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Zbigniew Preisner/Fairy Tale A True Story/04 Zbigniew Preisner - Frances.flac Jun 19 19:56:08 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand stop Jun 19 19:56:08 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jun 19 19:56:08 volumio volumio[857]: info: CoreStateMachine::updateTrackBlock Jun 19 19:56:08 volumio volumio[857]: info: CorePlayQueue::getTrackBlock Jun 19 19:56:08 volumio volumio[857]: info: Jun 19 19:56:08 volumio volumio[857]: ---------------------------- MPD announces state update: player Jun 19 19:56:08 volumio volumio[857]: info: ControllerMpd::getState Jun 19 19:56:08 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand status Jun 19 19:56:08 volumio volumio[857]: info: sendMpdCommand stop took 2 milliseconds Jun 19 19:56:08 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand clear Jun 19 19:56:08 volumio volumio[857]: info: Jun 19 19:56:08 volumio volumio[857]: ---------------------------- MPD announces state update: player Jun 19 19:56:08 volumio volumio[857]: info: ControllerMpd::getState Jun 19 19:56:08 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand status Jun 19 19:56:08 volumio volumio[857]: info: Jun 19 19:56:08 volumio volumio[857]: ---------------------------- MPD announces state update: player Jun 19 19:56:08 volumio volumio[857]: info: ControllerMpd::getState Jun 19 19:56:08 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand status Jun 19 19:56:08 volumio volumio[857]: info: Jun 19 19:56:08 volumio volumio[857]: ---------------------------- MPD announces system playlist update Jun 19 19:56:08 volumio volumio[857]: info: Ignoring MPD Status Update Jun 19 19:56:08 volumio volumio[857]: info: sendMpdCommand status took 4 milliseconds Jun 19 19:56:08 volumio volumio[857]: info: sendMpdCommand clear took 4 milliseconds Jun 19 19:56:08 volumio volumio[857]: info: sendMpdCommand status took 3 milliseconds Jun 19 19:56:08 volumio volumio[857]: info: sendMpdCommand status took 2 milliseconds Jun 19 19:56:08 volumio volumio[857]: verbose: ControllerMpd::parseState Jun 19 19:56:08 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 19:56:08 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Zbigniew Preisner/Fairy Tale A True Story/04 Zbigniew Preisner - Frances.flac" Jun 19 19:56:08 volumio volumio[857]: verbose: ControllerMpd::parseState Jun 19 19:56:08 volumio volumio[857]: verbose: ControllerMpd::parseState Jun 19 19:56:08 volumio volumio[857]: info: ControllerMpd::pushState Jun 19 19:56:08 volumio volumio[857]: info: CoreCommandRouter::servicePushState Jun 19 19:56:08 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:08 volumio volumio[857]: info: CorePlayQueue::getTrack 53 Jun 19 19:56:08 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 19 19:56:08 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:08 volumio volumio[857]: info: CorePlayQueue::getTrack 53 Jun 19 19:56:08 volumio volumio[857]: 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} Jun 19 19:56:08 volumio volumio[857]: verbose: CURRENT POSITION 53 Jun 19 19:56:08 volumio volumio[857]: info: CoreStateMachine::syncState stateService stop Jun 19 19:56:08 volumio volumio[857]: info: CoreStateMachine::syncState currentStatus stop Jun 19 19:56:08 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:08 volumio volumio[857]: info: CorePlayQueue::getTrack 53 Jun 19 19:56:08 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:08 volumio volumio[857]: info: No code Jun 19 19:56:08 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:08 volumio volumio[857]: info: CorePlayQueue::getTrack 53 Jun 19 19:56:08 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:08 volumio volumio[857]: info: ControllerMpd::pushState Jun 19 19:56:08 volumio volumio[857]: info: CoreCommandRouter::servicePushState Jun 19 19:56:08 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:08 volumio volumio[857]: info: CorePlayQueue::getTrack 53 Jun 19 19:56:08 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:08 volumio volumio[857]: info: CorePlayQueue::getTrack 53 Jun 19 19:56:08 volumio volumio[857]: 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} Jun 19 19:56:08 volumio volumio[857]: verbose: CURRENT POSITION 53 Jun 19 19:56:08 volumio volumio[857]: info: CoreStateMachine::syncState stateService stop Jun 19 19:56:08 volumio volumio[857]: info: CoreStateMachine::syncState currentStatus stop Jun 19 19:56:08 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:08 volumio volumio[857]: info: CorePlayQueue::getTrack 53 Jun 19 19:56:08 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:08 volumio volumio[857]: info: No code Jun 19 19:56:08 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:08 volumio volumio[857]: info: CorePlayQueue::getTrack 53 Jun 19 19:56:08 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:08 volumio volumio[857]: info: ------------------------------ 22ms Jun 19 19:56:08 volumio volumio[857]: info: ------------------------------ 22ms Jun 19 19:56:08 volumio volumio[857]: info: Jun 19 19:56:08 volumio volumio[857]: ---------------------------- MPD announces system playlist update Jun 19 19:56:08 volumio volumio[857]: info: Ignoring MPD Status Update Jun 19 19:56:08 volumio volumio[857]: info: Jun 19 19:56:08 volumio volumio[857]: ---------------------------- MPD announces system playlist update Jun 19 19:56:08 volumio volumio[857]: info: Ignoring MPD Status Update Jun 19 19:56:08 volumio volumio[857]: error: updateQueue error: null Jun 19 19:56:08 volumio volumio[857]: info: Jun 19 19:56:08 volumio volumio[857]: ---------------------------- MPD announces system playlist update Jun 19 19:56:08 volumio volumio[857]: info: Ignoring MPD Status Update Jun 19 19:56:08 volumio volumio[857]: info: ------------------------------ 25ms Jun 19 19:56:08 volumio volumio[857]: info: sendMpdCommand playlistinfo took 24 milliseconds Jun 19 19:56:08 volumio volumio[857]: info: sendMpdCommand add "INTERNAL/Zbigniew Preisner/Fairy Tale A True Story/04 Zbigniew Preisner - Frances.flac" took 23 milliseconds Jun 19 19:56:08 volumio volumio[857]: info: ------------------------------ 5ms Jun 19 19:56:08 volumio volumio[857]: info: ------------------------------ 5ms Jun 19 19:56:08 volumio volumio[857]: verbose: ControllerMpd::parseTrackInfo Jun 19 19:56:08 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand play Jun 19 19:56:08 volumio volumio[857]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 19 19:56:08 volumio volumio[857]: info: ------------------------------ 33ms Jun 19 19:56:08 volumio volumio[857]: info: Jun 19 19:56:08 volumio volumio[857]: ---------------------------- MPD announces system playlist update Jun 19 19:56:08 volumio volumio[857]: info: Ignoring MPD Status Update Jun 19 19:56:08 volumio volumio[857]: info: Jun 19 19:56:08 volumio volumio[857]: ---------------------------- MPD announces system playlist update Jun 19 19:56:08 volumio volumio[857]: info: Ignoring MPD Status Update Jun 19 19:56:08 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jun 19 19:56:08 volumio volumio[857]: info: Jun 19 19:56:08 volumio volumio[857]: ---------------------------- MPD announces state update: player Jun 19 19:56:08 volumio volumio[857]: info: ------------------------------ 16ms Jun 19 19:56:08 volumio volumio[857]: info: sendMpdCommand play took 12 milliseconds Jun 19 19:56:08 volumio volumio[857]: info: ------------------------------ 11ms Jun 19 19:56:08 volumio volumio[857]: info: ------------------------------ 11ms Jun 19 19:56:08 volumio volumio[857]: info: ControllerMpd::getState Jun 19 19:56:08 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand status Jun 19 19:56:08 volumio volumio[857]: info: Jun 19 19:56:08 volumio volumio[857]: ---------------------------- MPD announces state update: player Jun 19 19:56:08 volumio volumio[857]: info: ControllerMpd::getState Jun 19 19:56:08 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand status Jun 19 19:56:08 volumio volumio[857]: info: Jun 19 19:56:08 volumio volumio[857]: ---------------------------- MPD announces state update: player Jun 19 19:56:08 volumio volumio[857]: info: ControllerMpd::getState Jun 19 19:56:08 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand status Jun 19 19:56:08 volumio volumio[857]: info: Jun 19 19:56:08 volumio volumio[857]: ---------------------------- MPD announces state update: player Jun 19 19:56:08 volumio volumio[857]: info: ControllerMpd::getState Jun 19 19:56:08 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand status Jun 19 19:56:08 volumio volumio[857]: info: sendMpdCommand status took 6 milliseconds Jun 19 19:56:08 volumio volumio[857]: info: sendMpdCommand status took 4 milliseconds Jun 19 19:56:08 volumio volumio[857]: info: sendMpdCommand status took 3 milliseconds Jun 19 19:56:08 volumio volumio[857]: verbose: ControllerMpd::parseState Jun 19 19:56:08 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 19:56:08 volumio volumio[857]: verbose: ControllerMpd::parseState Jun 19 19:56:08 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 19:56:08 volumio volumio[857]: verbose: ControllerMpd::parseState Jun 19 19:56:08 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 19:56:08 volumio volumio[857]: info: Jun 19 19:56:08 volumio volumio[857]: ---------------------------- MPD announces state update: player Jun 19 19:56:08 volumio volumio[857]: info: ControllerMpd::getState Jun 19 19:56:08 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand status Jun 19 19:56:08 volumio volumio[857]: info: Jun 19 19:56:08 volumio volumio[857]: ---------------------------- MPD announces state update: player Jun 19 19:56:08 volumio volumio[857]: info: ControllerMpd::getState Jun 19 19:56:08 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand status Jun 19 19:56:08 volumio volumio[857]: info: sendMpdCommand status took 12 milliseconds Jun 19 19:56:08 volumio volumio[857]: info: sendMpdCommand playlistinfo took 11 milliseconds Jun 19 19:56:08 volumio volumio[857]: info: sendMpdCommand playlistinfo took 7 milliseconds Jun 19 19:56:08 volumio volumio[857]: info: sendMpdCommand playlistinfo took 6 milliseconds Jun 19 19:56:08 volumio volumio[857]: info: sendMpdCommand status took 5 milliseconds Jun 19 19:56:08 volumio volumio[857]: info: sendMpdCommand status took 3 milliseconds Jun 19 19:56:08 volumio volumio[857]: verbose: ControllerMpd::parseState Jun 19 19:56:08 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 19:56:08 volumio volumio[857]: verbose: ControllerMpd::parseTrackInfo Jun 19 19:56:08 volumio volumio[857]: verbose: ControllerMpd::parseTrackInfo Jun 19 19:56:08 volumio volumio[857]: verbose: ControllerMpd::parseTrackInfo Jun 19 19:56:08 volumio volumio[857]: verbose: ControllerMpd::parseState Jun 19 19:56:08 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 19:56:08 volumio volumio[857]: verbose: ControllerMpd::parseState Jun 19 19:56:08 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 19:56:08 volumio volumio[857]: info: ControllerMpd::pushState Jun 19 19:56:08 volumio volumio[857]: info: CoreCommandRouter::servicePushState Jun 19 19:56:08 volumio volumio[857]: info: CorePlayQueue::getTrack 53 Jun 19 19:56:08 volumio volumio[857]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":48,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"296 Kbps","isStreaming":false,"title":"Frances","artist":"Zbigniew Preisner","album":"Fairy Tale A True Story","uri":"INTERNAL/Zbigniew Preisner/Fairy Tale A True Story/04 Zbigniew Preisner - Frances.flac","trackType":"flac"} Jun 19 19:56:08 volumio volumio[857]: verbose: CURRENT POSITION 53 Jun 19 19:56:08 volumio volumio[857]: info: CoreStateMachine::syncState stateService play Jun 19 19:56:08 volumio volumio[857]: info: CoreStateMachine::syncState currentStatus stop Jun 19 19:56:08 volumio volumio[857]: info: ControllerMpd::pushState Jun 19 19:56:08 volumio volumio[857]: info: CoreCommandRouter::servicePushState Jun 19 19:56:08 volumio volumio[857]: info: CorePlayQueue::getTrack 53 Jun 19 19:56:08 volumio volumio[857]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":48,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"439 Kbps","isStreaming":false,"title":"Frances","artist":"Zbigniew Preisner","album":"Fairy Tale A True Story","uri":"INTERNAL/Zbigniew Preisner/Fairy Tale A True Story/04 Zbigniew Preisner - Frances.flac","trackType":"flac"} Jun 19 19:56:08 volumio volumio[857]: verbose: CURRENT POSITION 53 Jun 19 19:56:08 volumio volumio[857]: info: CoreStateMachine::syncState stateService play Jun 19 19:56:08 volumio volumio[857]: info: CoreStateMachine::syncState currentStatus play Jun 19 19:56:08 volumio volumio[857]: info: Received an update from plugin. extracting info from payload Jun 19 19:56:08 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:08 volumio volumio[857]: info: CorePlayQueue::getTrack 53 Jun 19 19:56:08 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 19 19:56:08 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:08 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:08 volumio volumio[857]: info: CorePlayQueue::getTrack 53 Jun 19 19:56:08 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:08 volumio volumio[857]: info: ControllerMpd::pushState Jun 19 19:56:08 volumio volumio[857]: info: CoreCommandRouter::servicePushState Jun 19 19:56:08 volumio volumio[857]: info: CorePlayQueue::getTrack 53 Jun 19 19:56:08 volumio volumio[857]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":48,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"458 Kbps","isStreaming":false,"title":"Frances","artist":"Zbigniew Preisner","album":"Fairy Tale A True Story","uri":"INTERNAL/Zbigniew Preisner/Fairy Tale A True Story/04 Zbigniew Preisner - Frances.flac","trackType":"flac"} Jun 19 19:56:08 volumio volumio[857]: verbose: CURRENT POSITION 53 Jun 19 19:56:08 volumio volumio[857]: info: CoreStateMachine::syncState stateService play Jun 19 19:56:08 volumio volumio[857]: info: CoreStateMachine::syncState currentStatus play Jun 19 19:56:08 volumio volumio[857]: info: Received an update from plugin. extracting info from payload Jun 19 19:56:08 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:08 volumio volumio[857]: info: CorePlayQueue::getTrack 53 Jun 19 19:56:08 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:08 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:08 volumio volumio[857]: info: CorePlayQueue::getTrack 53 Jun 19 19:56:08 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:08 volumio volumio[857]: info: ------------------------------ 37ms Jun 19 19:56:08 volumio volumio[857]: info: ------------------------------ 39ms Jun 19 19:56:08 volumio volumio[857]: info: ------------------------------ 38ms Jun 19 19:56:08 volumio volumio[857]: info: sendMpdCommand playlistinfo took 21 milliseconds Jun 19 19:56:08 volumio volumio[857]: info: sendMpdCommand playlistinfo took 21 milliseconds Jun 19 19:56:08 volumio volumio[857]: info: sendMpdCommand playlistinfo took 20 milliseconds Jun 19 19:56:08 volumio volumio[857]: verbose: ControllerMpd::parseTrackInfo Jun 19 19:56:08 volumio volumio[857]: verbose: ControllerMpd::parseTrackInfo Jun 19 19:56:08 volumio volumio[857]: verbose: ControllerMpd::parseTrackInfo Jun 19 19:56:08 volumio volumio[857]: info: ControllerMpd::pushState Jun 19 19:56:08 volumio volumio[857]: info: CoreCommandRouter::servicePushState Jun 19 19:56:08 volumio volumio[857]: info: CorePlayQueue::getTrack 53 Jun 19 19:56:08 volumio volumio[857]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":48,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"462 Kbps","isStreaming":false,"title":"Frances","artist":"Zbigniew Preisner","album":"Fairy Tale A True Story","uri":"INTERNAL/Zbigniew Preisner/Fairy Tale A True Story/04 Zbigniew Preisner - Frances.flac","trackType":"flac"} Jun 19 19:56:08 volumio volumio[857]: verbose: CURRENT POSITION 53 Jun 19 19:56:08 volumio volumio[857]: info: CoreStateMachine::syncState stateService play Jun 19 19:56:08 volumio volumio[857]: info: CoreStateMachine::syncState currentStatus play Jun 19 19:56:08 volumio volumio[857]: info: Received an update from plugin. extracting info from payload Jun 19 19:56:08 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:08 volumio volumio[857]: info: CorePlayQueue::getTrack 53 Jun 19 19:56:08 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:08 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:08 volumio volumio[857]: info: CorePlayQueue::getTrack 53 Jun 19 19:56:08 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:08 volumio volumio[857]: info: ControllerMpd::pushState Jun 19 19:56:08 volumio volumio[857]: info: CoreCommandRouter::servicePushState Jun 19 19:56:08 volumio volumio[857]: info: CorePlayQueue::getTrack 53 Jun 19 19:56:08 volumio volumio[857]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":48,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"462 Kbps","isStreaming":false,"title":"Frances","artist":"Zbigniew Preisner","album":"Fairy Tale A True Story","uri":"INTERNAL/Zbigniew Preisner/Fairy Tale A True Story/04 Zbigniew Preisner - Frances.flac","trackType":"flac"} Jun 19 19:56:08 volumio volumio[857]: verbose: CURRENT POSITION 53 Jun 19 19:56:08 volumio volumio[857]: info: CoreStateMachine::syncState stateService play Jun 19 19:56:08 volumio volumio[857]: info: CoreStateMachine::syncState currentStatus play Jun 19 19:56:08 volumio volumio[857]: info: Received an update from plugin. extracting info from payload Jun 19 19:56:08 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:08 volumio volumio[857]: info: CorePlayQueue::getTrack 53 Jun 19 19:56:08 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:08 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:08 volumio volumio[857]: info: CorePlayQueue::getTrack 53 Jun 19 19:56:08 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:08 volumio volumio[857]: info: ControllerMpd::pushState Jun 19 19:56:08 volumio volumio[857]: info: CoreCommandRouter::servicePushState Jun 19 19:56:08 volumio volumio[857]: info: CorePlayQueue::getTrack 53 Jun 19 19:56:08 volumio volumio[857]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":48,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"462 Kbps","isStreaming":false,"title":"Frances","artist":"Zbigniew Preisner","album":"Fairy Tale A True Story","uri":"INTERNAL/Zbigniew Preisner/Fairy Tale A True Story/04 Zbigniew Preisner - Frances.flac","trackType":"flac"} Jun 19 19:56:08 volumio volumio[857]: verbose: CURRENT POSITION 53 Jun 19 19:56:08 volumio volumio[857]: info: CoreStateMachine::syncState stateService play Jun 19 19:56:08 volumio volumio[857]: info: CoreStateMachine::syncState currentStatus play Jun 19 19:56:08 volumio volumio[857]: info: Received an update from plugin. extracting info from payload Jun 19 19:56:08 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:08 volumio volumio[857]: info: CorePlayQueue::getTrack 53 Jun 19 19:56:08 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:08 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:08 volumio volumio[857]: info: CorePlayQueue::getTrack 53 Jun 19 19:56:08 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:08 volumio volumio[857]: info: ------------------------------ 64ms Jun 19 19:56:08 volumio volumio[857]: info: ------------------------------ 55ms Jun 19 19:56:08 volumio volumio[857]: info: ------------------------------ 54ms Jun 19 19:56:12 volumio volumio[857]: info: CoreCommandRouter::volumioNext Jun 19 19:56:12 volumio volumio[857]: info: CoreStateMachine::next Jun 19 19:56:12 volumio volumio[857]: info: CoreStateMachine::stop Jun 19 19:56:12 volumio volumio[857]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 19 19:56:12 volumio volumio[857]: info: CoreStateMachine::stPlaybackTimer Jun 19 19:56:12 volumio volumio[857]: info: CoreStateMachine::updateTrackBlock Jun 19 19:56:12 volumio volumio[857]: info: CorePlayQueue::getTrackBlock Jun 19 19:56:12 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:12 volumio volumio[857]: info: CorePlayQueue::getTrack 53 Jun 19 19:56:12 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 19 19:56:12 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:12 volumio volumio[857]: info: CoreStateMachine::serviceStop Jun 19 19:56:12 volumio volumio[857]: info: CorePlayQueue::getTrack 53 Jun 19 19:56:12 volumio volumio[857]: info: CoreCommandRouter::serviceStop Jun 19 19:56:12 volumio volumio[857]: info: ControllerMpd::stop Jun 19 19:56:12 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand stop Jun 19 19:56:12 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jun 19 19:56:12 volumio volumio[857]: info: Jun 19 19:56:12 volumio volumio[857]: ---------------------------- MPD announces state update: player Jun 19 19:56:12 volumio volumio[857]: info: sendMpdCommand stop took 8 milliseconds Jun 19 19:56:12 volumio volumio[857]: info: ControllerMpd::getState Jun 19 19:56:12 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand status Jun 19 19:56:12 volumio volumio[857]: info: CoreStateMachine::play index undefined Jun 19 19:56:12 volumio volumio[857]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 19 19:56:12 volumio volumio[857]: info: CorePlayQueue::getTrack 54 Jun 19 19:56:12 volumio volumio[857]: info: CoreStateMachine::startPlaybackTimer Jun 19 19:56:12 volumio volumio[857]: info: CorePlayQueue::getTrack 54 Jun 19 19:56:12 volumio volumio[857]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Zbigniew Preisner/Fairy Tale A True Story/05 Zbigniew Preisner - Strange Meeting.flac Jun 19 19:56:12 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand stop Jun 19 19:56:12 volumio volumio[857]: info: CoreStateMachine::updateTrackBlock Jun 19 19:56:12 volumio volumio[857]: info: CorePlayQueue::getTrackBlock Jun 19 19:56:12 volumio volumio[857]: info: sendMpdCommand status took 3 milliseconds Jun 19 19:56:12 volumio volumio[857]: info: sendMpdCommand stop took 2 milliseconds Jun 19 19:56:12 volumio volumio[857]: verbose: ControllerMpd::parseState Jun 19 19:56:12 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 19:56:12 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand clear Jun 19 19:56:12 volumio volumio[857]: info: Jun 19 19:56:12 volumio volumio[857]: ---------------------------- MPD announces state update: player Jun 19 19:56:12 volumio volumio[857]: info: ControllerMpd::getState Jun 19 19:56:12 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand status Jun 19 19:56:12 volumio volumio[857]: info: Jun 19 19:56:12 volumio volumio[857]: ---------------------------- MPD announces state update: player Jun 19 19:56:12 volumio volumio[857]: info: ControllerMpd::getState Jun 19 19:56:12 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand status Jun 19 19:56:12 volumio volumio[857]: info: Jun 19 19:56:12 volumio volumio[857]: ---------------------------- MPD announces system playlist update Jun 19 19:56:12 volumio volumio[857]: info: Ignoring MPD Status Update Jun 19 19:56:12 volumio volumio[857]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 19 19:56:12 volumio volumio[857]: info: sendMpdCommand clear took 4 milliseconds Jun 19 19:56:12 volumio volumio[857]: info: sendMpdCommand status took 3 milliseconds Jun 19 19:56:12 volumio volumio[857]: info: sendMpdCommand status took 2 milliseconds Jun 19 19:56:12 volumio volumio[857]: verbose: ControllerMpd::parseTrackInfo Jun 19 19:56:12 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Zbigniew Preisner/Fairy Tale A True Story/05 Zbigniew Preisner - Strange Meeting.flac" Jun 19 19:56:12 volumio volumio[857]: verbose: ControllerMpd::parseState Jun 19 19:56:12 volumio volumio[857]: verbose: ControllerMpd::parseState Jun 19 19:56:12 volumio volumio[857]: info: ControllerMpd::pushState Jun 19 19:56:12 volumio volumio[857]: info: CoreCommandRouter::servicePushState Jun 19 19:56:12 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:12 volumio volumio[857]: info: CorePlayQueue::getTrack 54 Jun 19 19:56:12 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 19 19:56:12 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:12 volumio volumio[857]: info: CorePlayQueue::getTrack 54 Jun 19 19:56:12 volumio volumio[857]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Frances","artist":"Zbigniew Preisner","album":"Fairy Tale A True Story","uri":"INTERNAL/Zbigniew Preisner/Fairy Tale A True Story/04 Zbigniew Preisner - Frances.flac","trackType":"flac"} Jun 19 19:56:12 volumio volumio[857]: verbose: CURRENT POSITION 54 Jun 19 19:56:12 volumio volumio[857]: info: CoreStateMachine::syncState stateService stop Jun 19 19:56:12 volumio volumio[857]: info: CoreStateMachine::syncState currentStatus stop Jun 19 19:56:12 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:12 volumio volumio[857]: info: CorePlayQueue::getTrack 54 Jun 19 19:56:12 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:12 volumio volumio[857]: info: No code Jun 19 19:56:12 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:12 volumio volumio[857]: info: CorePlayQueue::getTrack 54 Jun 19 19:56:12 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:12 volumio volumio[857]: info: ControllerMpd::pushState Jun 19 19:56:12 volumio volumio[857]: info: CoreCommandRouter::servicePushState Jun 19 19:56:12 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:12 volumio volumio[857]: info: CorePlayQueue::getTrack 54 Jun 19 19:56:12 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:12 volumio volumio[857]: info: CorePlayQueue::getTrack 54 Jun 19 19:56:12 volumio volumio[857]: 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} Jun 19 19:56:12 volumio volumio[857]: verbose: CURRENT POSITION 54 Jun 19 19:56:12 volumio volumio[857]: info: CoreStateMachine::syncState stateService stop Jun 19 19:56:12 volumio volumio[857]: info: CoreStateMachine::syncState currentStatus stop Jun 19 19:56:12 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:12 volumio volumio[857]: info: CorePlayQueue::getTrack 54 Jun 19 19:56:12 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:12 volumio volumio[857]: info: No code Jun 19 19:56:12 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:12 volumio volumio[857]: info: CorePlayQueue::getTrack 54 Jun 19 19:56:12 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:12 volumio volumio[857]: info: ControllerMpd::pushState Jun 19 19:56:12 volumio volumio[857]: info: CoreCommandRouter::servicePushState Jun 19 19:56:12 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:12 volumio volumio[857]: info: CorePlayQueue::getTrack 54 Jun 19 19:56:12 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:12 volumio volumio[857]: info: CorePlayQueue::getTrack 54 Jun 19 19:56:12 volumio volumio[857]: 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} Jun 19 19:56:12 volumio volumio[857]: verbose: CURRENT POSITION 54 Jun 19 19:56:12 volumio volumio[857]: info: CoreStateMachine::syncState stateService stop Jun 19 19:56:12 volumio volumio[857]: info: CoreStateMachine::syncState currentStatus stop Jun 19 19:56:12 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:12 volumio volumio[857]: info: CorePlayQueue::getTrack 54 Jun 19 19:56:12 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:12 volumio volumio[857]: info: No code Jun 19 19:56:12 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:12 volumio volumio[857]: info: CorePlayQueue::getTrack 54 Jun 19 19:56:12 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:12 volumio volumio[857]: info: ------------------------------ 34ms Jun 19 19:56:12 volumio volumio[857]: info: ------------------------------ 29ms Jun 19 19:56:12 volumio volumio[857]: info: ------------------------------ 29ms Jun 19 19:56:12 volumio volumio[857]: info: Jun 19 19:56:12 volumio volumio[857]: ---------------------------- MPD announces system playlist update Jun 19 19:56:12 volumio volumio[857]: info: Ignoring MPD Status Update Jun 19 19:56:12 volumio volumio[857]: info: Jun 19 19:56:12 volumio volumio[857]: ---------------------------- MPD announces system playlist update Jun 19 19:56:12 volumio volumio[857]: info: Ignoring MPD Status Update Jun 19 19:56:12 volumio volumio[857]: error: updateQueue error: null Jun 19 19:56:12 volumio volumio[857]: info: Jun 19 19:56:12 volumio volumio[857]: ---------------------------- MPD announces system playlist update Jun 19 19:56:12 volumio volumio[857]: info: Ignoring MPD Status Update Jun 19 19:56:12 volumio volumio[857]: info: ------------------------------ 32ms Jun 19 19:56:12 volumio volumio[857]: info: sendMpdCommand add "INTERNAL/Zbigniew Preisner/Fairy Tale A True Story/05 Zbigniew Preisner - Strange Meeting.flac" took 30 milliseconds Jun 19 19:56:12 volumio volumio[857]: info: ------------------------------ 3ms Jun 19 19:56:12 volumio volumio[857]: info: ------------------------------ 3ms Jun 19 19:56:12 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand play Jun 19 19:56:12 volumio volumio[857]: info: Jun 19 19:56:12 volumio volumio[857]: ---------------------------- MPD announces system playlist update Jun 19 19:56:12 volumio volumio[857]: info: Ignoring MPD Status Update Jun 19 19:56:12 volumio volumio[857]: info: Jun 19 19:56:12 volumio volumio[857]: ---------------------------- MPD announces system playlist update Jun 19 19:56:12 volumio volumio[857]: info: Ignoring MPD Status Update Jun 19 19:56:12 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jun 19 19:56:12 volumio volumio[857]: info: Jun 19 19:56:12 volumio volumio[857]: ---------------------------- MPD announces state update: player Jun 19 19:56:12 volumio volumio[857]: info: ------------------------------ 22ms Jun 19 19:56:12 volumio volumio[857]: info: sendMpdCommand play took 21 milliseconds Jun 19 19:56:12 volumio volumio[857]: info: ------------------------------ 21ms Jun 19 19:56:12 volumio volumio[857]: info: ------------------------------ 20ms Jun 19 19:56:12 volumio volumio[857]: info: ControllerMpd::getState Jun 19 19:56:12 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand status Jun 19 19:56:12 volumio volumio[857]: info: Jun 19 19:56:12 volumio volumio[857]: ---------------------------- MPD announces state update: player Jun 19 19:56:12 volumio volumio[857]: info: ControllerMpd::getState Jun 19 19:56:12 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand status Jun 19 19:56:12 volumio volumio[857]: info: Jun 19 19:56:12 volumio volumio[857]: ---------------------------- MPD announces state update: player Jun 19 19:56:12 volumio volumio[857]: info: ControllerMpd::getState Jun 19 19:56:12 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand status Jun 19 19:56:12 volumio volumio[857]: info: Jun 19 19:56:12 volumio volumio[857]: ---------------------------- MPD announces state update: player Jun 19 19:56:12 volumio volumio[857]: info: ControllerMpd::getState Jun 19 19:56:12 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand status Jun 19 19:56:12 volumio volumio[857]: info: sendMpdCommand status took 18 milliseconds Jun 19 19:56:12 volumio volumio[857]: info: sendMpdCommand status took 16 milliseconds Jun 19 19:56:12 volumio volumio[857]: info: sendMpdCommand status took 4 milliseconds Jun 19 19:56:12 volumio volumio[857]: verbose: ControllerMpd::parseState Jun 19 19:56:12 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 19:56:12 volumio volumio[857]: verbose: ControllerMpd::parseState Jun 19 19:56:12 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 19:56:12 volumio volumio[857]: verbose: ControllerMpd::parseState Jun 19 19:56:12 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 19:56:12 volumio volumio[857]: info: Jun 19 19:56:12 volumio volumio[857]: ---------------------------- MPD announces state update: player Jun 19 19:56:12 volumio volumio[857]: info: ControllerMpd::getState Jun 19 19:56:12 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand status Jun 19 19:56:12 volumio volumio[857]: info: Jun 19 19:56:12 volumio volumio[857]: ---------------------------- MPD announces state update: player Jun 19 19:56:12 volumio volumio[857]: info: ControllerMpd::getState Jun 19 19:56:12 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand status Jun 19 19:56:12 volumio volumio[857]: info: sendMpdCommand status took 9 milliseconds Jun 19 19:56:12 volumio volumio[857]: verbose: ControllerMpd::parseState Jun 19 19:56:12 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 19:56:12 volumio volumio[857]: info: sendMpdCommand playlistinfo took 10 milliseconds Jun 19 19:56:12 volumio volumio[857]: info: sendMpdCommand playlistinfo took 9 milliseconds Jun 19 19:56:12 volumio volumio[857]: info: sendMpdCommand playlistinfo took 10 milliseconds Jun 19 19:56:12 volumio volumio[857]: info: sendMpdCommand status took 9 milliseconds Jun 19 19:56:12 volumio volumio[857]: info: sendMpdCommand status took 7 milliseconds Jun 19 19:56:12 volumio volumio[857]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 19 19:56:12 volumio volumio[857]: verbose: ControllerMpd::parseTrackInfo Jun 19 19:56:12 volumio volumio[857]: verbose: ControllerMpd::parseTrackInfo Jun 19 19:56:12 volumio volumio[857]: verbose: ControllerMpd::parseTrackInfo Jun 19 19:56:12 volumio volumio[857]: verbose: ControllerMpd::parseState Jun 19 19:56:12 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 19:56:12 volumio volumio[857]: verbose: ControllerMpd::parseState Jun 19 19:56:12 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 19:56:12 volumio volumio[857]: verbose: ControllerMpd::parseTrackInfo Jun 19 19:56:12 volumio volumio[857]: info: ControllerMpd::pushState Jun 19 19:56:12 volumio volumio[857]: info: CoreCommandRouter::servicePushState Jun 19 19:56:12 volumio volumio[857]: info: CorePlayQueue::getTrack 54 Jun 19 19:56:12 volumio volumio[857]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":106,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"555 Kbps","isStreaming":false,"title":"Strange Meeting","artist":"Zbigniew Preisner","album":"Fairy Tale A True Story","uri":"INTERNAL/Zbigniew Preisner/Fairy Tale A True Story/05 Zbigniew Preisner - Strange Meeting.flac","trackType":"flac"} Jun 19 19:56:12 volumio volumio[857]: verbose: CURRENT POSITION 54 Jun 19 19:56:12 volumio volumio[857]: info: CoreStateMachine::syncState stateService play Jun 19 19:56:12 volumio volumio[857]: info: CoreStateMachine::syncState currentStatus stop Jun 19 19:56:12 volumio volumio[857]: info: ControllerMpd::pushState Jun 19 19:56:12 volumio volumio[857]: info: CoreCommandRouter::servicePushState Jun 19 19:56:12 volumio volumio[857]: info: CorePlayQueue::getTrack 54 Jun 19 19:56:12 volumio volumio[857]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":106,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"555 Kbps","isStreaming":false,"title":"Strange Meeting","artist":"Zbigniew Preisner","album":"Fairy Tale A True Story","uri":"INTERNAL/Zbigniew Preisner/Fairy Tale A True Story/05 Zbigniew Preisner - Strange Meeting.flac","trackType":"flac"} Jun 19 19:56:12 volumio volumio[857]: verbose: CURRENT POSITION 54 Jun 19 19:56:12 volumio volumio[857]: info: CoreStateMachine::syncState stateService play Jun 19 19:56:12 volumio volumio[857]: info: CoreStateMachine::syncState currentStatus play Jun 19 19:56:12 volumio volumio[857]: info: Received an update from plugin. extracting info from payload Jun 19 19:56:12 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:12 volumio volumio[857]: info: CorePlayQueue::getTrack 54 Jun 19 19:56:12 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 19 19:56:12 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:12 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:12 volumio volumio[857]: info: CorePlayQueue::getTrack 54 Jun 19 19:56:12 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:12 volumio volumio[857]: info: ControllerMpd::pushState Jun 19 19:56:12 volumio volumio[857]: info: CoreCommandRouter::servicePushState Jun 19 19:56:12 volumio volumio[857]: info: CorePlayQueue::getTrack 54 Jun 19 19:56:12 volumio volumio[857]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":106,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"555 Kbps","isStreaming":false,"title":"Strange Meeting","artist":"Zbigniew Preisner","album":"Fairy Tale A True Story","uri":"INTERNAL/Zbigniew Preisner/Fairy Tale A True Story/05 Zbigniew Preisner - Strange Meeting.flac","trackType":"flac"} Jun 19 19:56:12 volumio volumio[857]: verbose: CURRENT POSITION 54 Jun 19 19:56:12 volumio volumio[857]: info: CoreStateMachine::syncState stateService play Jun 19 19:56:12 volumio volumio[857]: info: CoreStateMachine::syncState currentStatus play Jun 19 19:56:12 volumio volumio[857]: info: Received an update from plugin. extracting info from payload Jun 19 19:56:12 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:12 volumio volumio[857]: info: CorePlayQueue::getTrack 54 Jun 19 19:56:12 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:12 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:12 volumio volumio[857]: info: CorePlayQueue::getTrack 54 Jun 19 19:56:12 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:12 volumio volumio[857]: info: ControllerMpd::pushState Jun 19 19:56:12 volumio volumio[857]: info: CoreCommandRouter::servicePushState Jun 19 19:56:12 volumio volumio[857]: info: CorePlayQueue::getTrack 54 Jun 19 19:56:12 volumio volumio[857]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":106,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"555 Kbps","isStreaming":false,"title":"Strange Meeting","artist":"Zbigniew Preisner","album":"Fairy Tale A True Story","uri":"INTERNAL/Zbigniew Preisner/Fairy Tale A True Story/05 Zbigniew Preisner - Strange Meeting.flac","trackType":"flac"} Jun 19 19:56:12 volumio volumio[857]: verbose: CURRENT POSITION 54 Jun 19 19:56:12 volumio volumio[857]: info: CoreStateMachine::syncState stateService play Jun 19 19:56:12 volumio volumio[857]: info: CoreStateMachine::syncState currentStatus play Jun 19 19:56:12 volumio volumio[857]: info: Received an update from plugin. extracting info from payload Jun 19 19:56:12 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:12 volumio volumio[857]: info: CorePlayQueue::getTrack 54 Jun 19 19:56:12 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:12 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:12 volumio volumio[857]: info: CorePlayQueue::getTrack 54 Jun 19 19:56:12 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:12 volumio volumio[857]: info: ------------------------------ 62ms Jun 19 19:56:12 volumio volumio[857]: info: ------------------------------ 64ms Jun 19 19:56:12 volumio volumio[857]: info: ------------------------------ 51ms Jun 19 19:56:12 volumio volumio[857]: info: ------------------------------ 50ms Jun 19 19:56:12 volumio volumio[857]: info: sendMpdCommand playlistinfo took 33 milliseconds Jun 19 19:56:12 volumio volumio[857]: info: sendMpdCommand playlistinfo took 33 milliseconds Jun 19 19:56:12 volumio volumio[857]: verbose: ControllerMpd::parseTrackInfo Jun 19 19:56:12 volumio volumio[857]: verbose: ControllerMpd::parseTrackInfo Jun 19 19:56:12 volumio volumio[857]: info: ControllerMpd::pushState Jun 19 19:56:12 volumio volumio[857]: info: CoreCommandRouter::servicePushState Jun 19 19:56:12 volumio volumio[857]: info: CorePlayQueue::getTrack 54 Jun 19 19:56:12 volumio volumio[857]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":106,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"555 Kbps","isStreaming":false,"title":"Strange Meeting","artist":"Zbigniew Preisner","album":"Fairy Tale A True Story","uri":"INTERNAL/Zbigniew Preisner/Fairy Tale A True Story/05 Zbigniew Preisner - Strange Meeting.flac","trackType":"flac"} Jun 19 19:56:12 volumio volumio[857]: verbose: CURRENT POSITION 54 Jun 19 19:56:12 volumio volumio[857]: info: CoreStateMachine::syncState stateService play Jun 19 19:56:12 volumio volumio[857]: info: CoreStateMachine::syncState currentStatus play Jun 19 19:56:12 volumio volumio[857]: info: Received an update from plugin. extracting info from payload Jun 19 19:56:12 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:12 volumio volumio[857]: info: CorePlayQueue::getTrack 54 Jun 19 19:56:12 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:12 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:12 volumio volumio[857]: info: CorePlayQueue::getTrack 54 Jun 19 19:56:12 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:12 volumio volumio[857]: info: ControllerMpd::pushState Jun 19 19:56:12 volumio volumio[857]: info: CoreCommandRouter::servicePushState Jun 19 19:56:12 volumio volumio[857]: info: CorePlayQueue::getTrack 54 Jun 19 19:56:12 volumio volumio[857]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":106,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"555 Kbps","isStreaming":false,"title":"Strange Meeting","artist":"Zbigniew Preisner","album":"Fairy Tale A True Story","uri":"INTERNAL/Zbigniew Preisner/Fairy Tale A True Story/05 Zbigniew Preisner - Strange Meeting.flac","trackType":"flac"} Jun 19 19:56:12 volumio volumio[857]: verbose: CURRENT POSITION 54 Jun 19 19:56:12 volumio volumio[857]: info: CoreStateMachine::syncState stateService play Jun 19 19:56:12 volumio volumio[857]: info: CoreStateMachine::syncState currentStatus play Jun 19 19:56:12 volumio volumio[857]: info: Received an update from plugin. extracting info from payload Jun 19 19:56:12 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:12 volumio volumio[857]: info: CorePlayQueue::getTrack 54 Jun 19 19:56:12 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:12 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:12 volumio volumio[857]: info: CorePlayQueue::getTrack 54 Jun 19 19:56:12 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:12 volumio volumio[857]: info: ------------------------------ 63ms Jun 19 19:56:12 volumio volumio[857]: info: ------------------------------ 62ms Jun 19 19:56:16 volumio volumio[857]: info: CoreCommandRouter::volumioNext Jun 19 19:56:16 volumio volumio[857]: info: CoreStateMachine::next Jun 19 19:56:16 volumio volumio[857]: info: CoreStateMachine::stop Jun 19 19:56:16 volumio volumio[857]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 19 19:56:16 volumio volumio[857]: info: CoreStateMachine::stPlaybackTimer Jun 19 19:56:16 volumio volumio[857]: info: CoreStateMachine::updateTrackBlock Jun 19 19:56:16 volumio volumio[857]: info: CorePlayQueue::getTrackBlock Jun 19 19:56:16 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:16 volumio volumio[857]: info: CorePlayQueue::getTrack 54 Jun 19 19:56:16 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 19 19:56:16 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:16 volumio volumio[857]: info: CoreStateMachine::serviceStop Jun 19 19:56:16 volumio volumio[857]: info: CorePlayQueue::getTrack 54 Jun 19 19:56:16 volumio volumio[857]: info: CoreCommandRouter::serviceStop Jun 19 19:56:16 volumio volumio[857]: info: ControllerMpd::stop Jun 19 19:56:16 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand stop Jun 19 19:56:16 volumio volumio[857]: info: sendMpdCommand stop took 4 milliseconds Jun 19 19:56:16 volumio volumio[857]: info: CoreStateMachine::play index undefined Jun 19 19:56:16 volumio volumio[857]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 19 19:56:16 volumio volumio[857]: info: CorePlayQueue::getTrack 55 Jun 19 19:56:16 volumio volumio[857]: info: CoreStateMachine::startPlaybackTimer Jun 19 19:56:16 volumio volumio[857]: info: CorePlayQueue::getTrack 55 Jun 19 19:56:16 volumio volumio[857]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Zbigniew Preisner/Fairy Tale A True Story/06 Zbigniew Preisner - Wing of an Angel.flac Jun 19 19:56:16 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand stop Jun 19 19:56:16 volumio volumio[857]: info: CoreStateMachine::updateTrackBlock Jun 19 19:56:16 volumio volumio[857]: info: CorePlayQueue::getTrackBlock Jun 19 19:56:16 volumio volumio[857]: info: Jun 19 19:56:16 volumio volumio[857]: ---------------------------- MPD announces state update: player Jun 19 19:56:16 volumio volumio[857]: info: ControllerMpd::getState Jun 19 19:56:16 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand status Jun 19 19:56:16 volumio volumio[857]: info: sendMpdCommand stop took 2 milliseconds Jun 19 19:56:16 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand clear Jun 19 19:56:16 volumio volumio[857]: info: Jun 19 19:56:16 volumio volumio[857]: ---------------------------- MPD announces state update: player Jun 19 19:56:16 volumio volumio[857]: info: ControllerMpd::getState Jun 19 19:56:16 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand status Jun 19 19:56:16 volumio volumio[857]: info: Jun 19 19:56:16 volumio volumio[857]: ---------------------------- MPD announces state update: player Jun 19 19:56:16 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jun 19 19:56:16 volumio volumio[857]: info: ControllerMpd::getState Jun 19 19:56:16 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand status Jun 19 19:56:16 volumio volumio[857]: info: Jun 19 19:56:16 volumio volumio[857]: ---------------------------- MPD announces system playlist update Jun 19 19:56:16 volumio volumio[857]: info: Ignoring MPD Status Update Jun 19 19:56:16 volumio volumio[857]: info: sendMpdCommand status took 5 milliseconds Jun 19 19:56:16 volumio volumio[857]: info: sendMpdCommand clear took 5 milliseconds Jun 19 19:56:16 volumio volumio[857]: info: sendMpdCommand status took 3 milliseconds Jun 19 19:56:16 volumio volumio[857]: info: sendMpdCommand status took 2 milliseconds Jun 19 19:56:16 volumio volumio[857]: verbose: ControllerMpd::parseState Jun 19 19:56:16 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 19:56:16 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Zbigniew Preisner/Fairy Tale A True Story/06 Zbigniew Preisner - Wing of an Angel.flac" Jun 19 19:56:16 volumio volumio[857]: verbose: ControllerMpd::parseState Jun 19 19:56:16 volumio volumio[857]: verbose: ControllerMpd::parseState Jun 19 19:56:16 volumio volumio[857]: info: ControllerMpd::pushState Jun 19 19:56:16 volumio volumio[857]: info: CoreCommandRouter::servicePushState Jun 19 19:56:16 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:16 volumio volumio[857]: info: CorePlayQueue::getTrack 55 Jun 19 19:56:16 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 19 19:56:16 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:16 volumio volumio[857]: info: CorePlayQueue::getTrack 55 Jun 19 19:56:16 volumio volumio[857]: 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} Jun 19 19:56:16 volumio volumio[857]: verbose: CURRENT POSITION 55 Jun 19 19:56:16 volumio volumio[857]: info: CoreStateMachine::syncState stateService stop Jun 19 19:56:16 volumio volumio[857]: info: CoreStateMachine::syncState currentStatus stop Jun 19 19:56:16 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:16 volumio volumio[857]: info: CorePlayQueue::getTrack 55 Jun 19 19:56:16 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:16 volumio volumio[857]: info: No code Jun 19 19:56:16 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:16 volumio volumio[857]: info: CorePlayQueue::getTrack 55 Jun 19 19:56:16 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:16 volumio volumio[857]: info: ControllerMpd::pushState Jun 19 19:56:16 volumio volumio[857]: info: CoreCommandRouter::servicePushState Jun 19 19:56:16 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:16 volumio volumio[857]: info: CorePlayQueue::getTrack 55 Jun 19 19:56:16 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:16 volumio volumio[857]: info: CorePlayQueue::getTrack 55 Jun 19 19:56:16 volumio volumio[857]: 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} Jun 19 19:56:16 volumio volumio[857]: verbose: CURRENT POSITION 55 Jun 19 19:56:16 volumio volumio[857]: info: CoreStateMachine::syncState stateService stop Jun 19 19:56:16 volumio volumio[857]: info: CoreStateMachine::syncState currentStatus stop Jun 19 19:56:16 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:16 volumio volumio[857]: info: CorePlayQueue::getTrack 55 Jun 19 19:56:16 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:16 volumio volumio[857]: info: No code Jun 19 19:56:16 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:16 volumio volumio[857]: info: CorePlayQueue::getTrack 55 Jun 19 19:56:16 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:16 volumio volumio[857]: info: ------------------------------ 26ms Jun 19 19:56:16 volumio volumio[857]: info: ------------------------------ 25ms Jun 19 19:56:16 volumio volumio[857]: info: Jun 19 19:56:16 volumio volumio[857]: ---------------------------- MPD announces system playlist update Jun 19 19:56:16 volumio volumio[857]: info: Ignoring MPD Status Update Jun 19 19:56:16 volumio volumio[857]: info: Jun 19 19:56:16 volumio volumio[857]: ---------------------------- MPD announces system playlist update Jun 19 19:56:16 volumio volumio[857]: info: Ignoring MPD Status Update Jun 19 19:56:16 volumio volumio[857]: error: updateQueue error: null Jun 19 19:56:16 volumio volumio[857]: info: Jun 19 19:56:16 volumio volumio[857]: ---------------------------- MPD announces system playlist update Jun 19 19:56:16 volumio volumio[857]: info: Ignoring MPD Status Update Jun 19 19:56:16 volumio volumio[857]: info: ------------------------------ 27ms Jun 19 19:56:16 volumio volumio[857]: info: sendMpdCommand playlistinfo took 26 milliseconds Jun 19 19:56:16 volumio volumio[857]: info: sendMpdCommand add "INTERNAL/Zbigniew Preisner/Fairy Tale A True Story/06 Zbigniew Preisner - Wing of an Angel.flac" took 26 milliseconds Jun 19 19:56:16 volumio volumio[857]: info: ------------------------------ 4ms Jun 19 19:56:16 volumio volumio[857]: info: ------------------------------ 3ms Jun 19 19:56:16 volumio volumio[857]: verbose: ControllerMpd::parseTrackInfo Jun 19 19:56:16 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand play Jun 19 19:56:16 volumio volumio[857]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 19 19:56:16 volumio volumio[857]: info: ------------------------------ 35ms Jun 19 19:56:16 volumio volumio[857]: info: Jun 19 19:56:16 volumio volumio[857]: ---------------------------- MPD announces system playlist update Jun 19 19:56:16 volumio volumio[857]: info: Ignoring MPD Status Update Jun 19 19:56:16 volumio volumio[857]: info: Jun 19 19:56:16 volumio volumio[857]: ---------------------------- MPD announces system playlist update Jun 19 19:56:16 volumio volumio[857]: info: Ignoring MPD Status Update Jun 19 19:56:16 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jun 19 19:56:16 volumio volumio[857]: info: Jun 19 19:56:16 volumio volumio[857]: ---------------------------- MPD announces state update: player Jun 19 19:56:16 volumio volumio[857]: info: ------------------------------ 12ms Jun 19 19:56:16 volumio volumio[857]: info: sendMpdCommand play took 11 milliseconds Jun 19 19:56:16 volumio volumio[857]: info: ------------------------------ 10ms Jun 19 19:56:16 volumio volumio[857]: info: ------------------------------ 9ms Jun 19 19:56:16 volumio volumio[857]: info: ControllerMpd::getState Jun 19 19:56:16 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand status Jun 19 19:56:16 volumio volumio[857]: info: Jun 19 19:56:16 volumio volumio[857]: ---------------------------- MPD announces state update: player Jun 19 19:56:16 volumio volumio[857]: info: ControllerMpd::getState Jun 19 19:56:16 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand status Jun 19 19:56:16 volumio volumio[857]: info: Jun 19 19:56:16 volumio volumio[857]: ---------------------------- MPD announces state update: player Jun 19 19:56:16 volumio volumio[857]: info: ControllerMpd::getState Jun 19 19:56:16 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand status Jun 19 19:56:16 volumio volumio[857]: info: Jun 19 19:56:16 volumio volumio[857]: ---------------------------- MPD announces state update: player Jun 19 19:56:16 volumio volumio[857]: info: ControllerMpd::getState Jun 19 19:56:16 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand status Jun 19 19:56:16 volumio volumio[857]: info: sendMpdCommand status took 7 milliseconds Jun 19 19:56:16 volumio volumio[857]: verbose: ControllerMpd::parseState Jun 19 19:56:16 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 19:56:16 volumio volumio[857]: info: Jun 19 19:56:16 volumio volumio[857]: ---------------------------- MPD announces state update: player Jun 19 19:56:16 volumio volumio[857]: info: ControllerMpd::getState Jun 19 19:56:16 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand status Jun 19 19:56:16 volumio volumio[857]: info: sendMpdCommand status took 10 milliseconds Jun 19 19:56:16 volumio volumio[857]: info: sendMpdCommand status took 9 milliseconds Jun 19 19:56:16 volumio volumio[857]: info: sendMpdCommand status took 6 milliseconds Jun 19 19:56:16 volumio volumio[857]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 19 19:56:16 volumio volumio[857]: verbose: ControllerMpd::parseState Jun 19 19:56:16 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 19:56:16 volumio volumio[857]: verbose: ControllerMpd::parseState Jun 19 19:56:16 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 19:56:16 volumio volumio[857]: verbose: ControllerMpd::parseState Jun 19 19:56:16 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 19:56:16 volumio volumio[857]: verbose: ControllerMpd::parseTrackInfo Jun 19 19:56:16 volumio volumio[857]: info: ControllerMpd::pushState Jun 19 19:56:16 volumio volumio[857]: info: CoreCommandRouter::servicePushState Jun 19 19:56:16 volumio volumio[857]: info: CorePlayQueue::getTrack 55 Jun 19 19:56:16 volumio volumio[857]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":77,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Wing of an Angel","artist":"Zbigniew Preisner","album":"Fairy Tale A True Story","uri":"INTERNAL/Zbigniew Preisner/Fairy Tale A True Story/06 Zbigniew Preisner - Wing of an Angel.flac","trackType":"flac"} Jun 19 19:56:16 volumio volumio[857]: verbose: CURRENT POSITION 55 Jun 19 19:56:16 volumio volumio[857]: info: CoreStateMachine::syncState stateService play Jun 19 19:56:16 volumio volumio[857]: info: CoreStateMachine::syncState currentStatus stop Jun 19 19:56:16 volumio volumio[857]: info: ------------------------------ 20ms Jun 19 19:56:16 volumio volumio[857]: info: Jun 19 19:56:16 volumio volumio[857]: ---------------------------- MPD announces state update: player Jun 19 19:56:16 volumio volumio[857]: info: ControllerMpd::getState Jun 19 19:56:16 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand status Jun 19 19:56:16 volumio volumio[857]: info: sendMpdCommand status took 10 milliseconds Jun 19 19:56:16 volumio volumio[857]: info: sendMpdCommand playlistinfo took 6 milliseconds Jun 19 19:56:16 volumio volumio[857]: info: sendMpdCommand playlistinfo took 6 milliseconds Jun 19 19:56:16 volumio volumio[857]: info: sendMpdCommand playlistinfo took 6 milliseconds Jun 19 19:56:16 volumio volumio[857]: info: sendMpdCommand status took 3 milliseconds Jun 19 19:56:16 volumio volumio[857]: verbose: ControllerMpd::parseState Jun 19 19:56:16 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 19:56:16 volumio volumio[857]: verbose: ControllerMpd::parseTrackInfo Jun 19 19:56:16 volumio volumio[857]: verbose: ControllerMpd::parseTrackInfo Jun 19 19:56:16 volumio volumio[857]: verbose: ControllerMpd::parseTrackInfo Jun 19 19:56:16 volumio volumio[857]: verbose: ControllerMpd::parseState Jun 19 19:56:16 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 19:56:16 volumio volumio[857]: info: ControllerMpd::pushState Jun 19 19:56:16 volumio volumio[857]: info: CoreCommandRouter::servicePushState Jun 19 19:56:16 volumio volumio[857]: info: CorePlayQueue::getTrack 55 Jun 19 19:56:16 volumio volumio[857]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":77,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"388 Kbps","isStreaming":false,"title":"Wing of an Angel","artist":"Zbigniew Preisner","album":"Fairy Tale A True Story","uri":"INTERNAL/Zbigniew Preisner/Fairy Tale A True Story/06 Zbigniew Preisner - Wing of an Angel.flac","trackType":"flac"} Jun 19 19:56:16 volumio volumio[857]: verbose: CURRENT POSITION 55 Jun 19 19:56:16 volumio volumio[857]: info: CoreStateMachine::syncState stateService play Jun 19 19:56:16 volumio volumio[857]: info: CoreStateMachine::syncState currentStatus play Jun 19 19:56:16 volumio volumio[857]: info: Received an update from plugin. extracting info from payload Jun 19 19:56:16 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:16 volumio volumio[857]: info: CorePlayQueue::getTrack 55 Jun 19 19:56:16 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 19 19:56:16 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:16 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:16 volumio volumio[857]: info: CorePlayQueue::getTrack 55 Jun 19 19:56:16 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:16 volumio volumio[857]: info: ControllerMpd::pushState Jun 19 19:56:16 volumio volumio[857]: info: CoreCommandRouter::servicePushState Jun 19 19:56:16 volumio volumio[857]: info: CorePlayQueue::getTrack 55 Jun 19 19:56:16 volumio volumio[857]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":77,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"388 Kbps","isStreaming":false,"title":"Wing of an Angel","artist":"Zbigniew Preisner","album":"Fairy Tale A True Story","uri":"INTERNAL/Zbigniew Preisner/Fairy Tale A True Story/06 Zbigniew Preisner - Wing of an Angel.flac","trackType":"flac"} Jun 19 19:56:16 volumio volumio[857]: verbose: CURRENT POSITION 55 Jun 19 19:56:16 volumio volumio[857]: info: CoreStateMachine::syncState stateService play Jun 19 19:56:16 volumio volumio[857]: info: CoreStateMachine::syncState currentStatus play Jun 19 19:56:16 volumio volumio[857]: info: Received an update from plugin. extracting info from payload Jun 19 19:56:16 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:16 volumio volumio[857]: info: CorePlayQueue::getTrack 55 Jun 19 19:56:16 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:16 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:16 volumio volumio[857]: info: CorePlayQueue::getTrack 55 Jun 19 19:56:16 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:16 volumio volumio[857]: info: ControllerMpd::pushState Jun 19 19:56:16 volumio volumio[857]: info: CoreCommandRouter::servicePushState Jun 19 19:56:16 volumio volumio[857]: info: CorePlayQueue::getTrack 55 Jun 19 19:56:16 volumio volumio[857]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":77,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"374 Kbps","isStreaming":false,"title":"Wing of an Angel","artist":"Zbigniew Preisner","album":"Fairy Tale A True Story","uri":"INTERNAL/Zbigniew Preisner/Fairy Tale A True Story/06 Zbigniew Preisner - Wing of an Angel.flac","trackType":"flac"} Jun 19 19:56:16 volumio volumio[857]: verbose: CURRENT POSITION 55 Jun 19 19:56:16 volumio volumio[857]: info: CoreStateMachine::syncState stateService play Jun 19 19:56:16 volumio volumio[857]: info: CoreStateMachine::syncState currentStatus play Jun 19 19:56:16 volumio volumio[857]: info: Received an update from plugin. extracting info from payload Jun 19 19:56:16 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:16 volumio volumio[857]: info: CorePlayQueue::getTrack 55 Jun 19 19:56:16 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:16 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:16 volumio volumio[857]: info: CorePlayQueue::getTrack 55 Jun 19 19:56:16 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:16 volumio volumio[857]: info: ------------------------------ 51ms Jun 19 19:56:16 volumio volumio[857]: info: ------------------------------ 50ms Jun 19 19:56:16 volumio volumio[857]: info: ------------------------------ 49ms Jun 19 19:56:16 volumio volumio[857]: info: sendMpdCommand playlistinfo took 33 milliseconds Jun 19 19:56:16 volumio volumio[857]: info: sendMpdCommand playlistinfo took 31 milliseconds Jun 19 19:56:16 volumio volumio[857]: verbose: ControllerMpd::parseTrackInfo Jun 19 19:56:16 volumio volumio[857]: verbose: ControllerMpd::parseTrackInfo Jun 19 19:56:16 volumio volumio[857]: info: ControllerMpd::pushState Jun 19 19:56:16 volumio volumio[857]: info: CoreCommandRouter::servicePushState Jun 19 19:56:16 volumio volumio[857]: info: CorePlayQueue::getTrack 55 Jun 19 19:56:16 volumio volumio[857]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":77,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"357 Kbps","isStreaming":false,"title":"Wing of an Angel","artist":"Zbigniew Preisner","album":"Fairy Tale A True Story","uri":"INTERNAL/Zbigniew Preisner/Fairy Tale A True Story/06 Zbigniew Preisner - Wing of an Angel.flac","trackType":"flac"} Jun 19 19:56:16 volumio volumio[857]: verbose: CURRENT POSITION 55 Jun 19 19:56:16 volumio volumio[857]: info: CoreStateMachine::syncState stateService play Jun 19 19:56:16 volumio volumio[857]: info: CoreStateMachine::syncState currentStatus play Jun 19 19:56:16 volumio volumio[857]: info: Received an update from plugin. extracting info from payload Jun 19 19:56:16 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:16 volumio volumio[857]: info: CorePlayQueue::getTrack 55 Jun 19 19:56:16 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:16 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:16 volumio volumio[857]: info: CorePlayQueue::getTrack 55 Jun 19 19:56:16 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:16 volumio volumio[857]: info: ControllerMpd::pushState Jun 19 19:56:16 volumio volumio[857]: info: CoreCommandRouter::servicePushState Jun 19 19:56:16 volumio volumio[857]: info: CorePlayQueue::getTrack 55 Jun 19 19:56:16 volumio volumio[857]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":77,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"357 Kbps","isStreaming":false,"title":"Wing of an Angel","artist":"Zbigniew Preisner","album":"Fairy Tale A True Story","uri":"INTERNAL/Zbigniew Preisner/Fairy Tale A True Story/06 Zbigniew Preisner - Wing of an Angel.flac","trackType":"flac"} Jun 19 19:56:16 volumio volumio[857]: verbose: CURRENT POSITION 55 Jun 19 19:56:16 volumio volumio[857]: info: CoreStateMachine::syncState stateService play Jun 19 19:56:16 volumio volumio[857]: info: CoreStateMachine::syncState currentStatus play Jun 19 19:56:16 volumio volumio[857]: info: Received an update from plugin. extracting info from payload Jun 19 19:56:16 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:16 volumio volumio[857]: info: CorePlayQueue::getTrack 55 Jun 19 19:56:16 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:16 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:16 volumio volumio[857]: info: CorePlayQueue::getTrack 55 Jun 19 19:56:16 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:16 volumio volumio[857]: info: ------------------------------ 63ms Jun 19 19:56:16 volumio volumio[857]: info: ------------------------------ 55ms Jun 19 19:56:19 volumio volumio[857]: info: CoreCommandRouter::volumioNext Jun 19 19:56:19 volumio volumio[857]: info: CoreStateMachine::next Jun 19 19:56:19 volumio volumio[857]: info: CoreStateMachine::stop Jun 19 19:56:19 volumio volumio[857]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 19 19:56:19 volumio volumio[857]: info: CoreStateMachine::stPlaybackTimer Jun 19 19:56:19 volumio volumio[857]: info: CoreStateMachine::updateTrackBlock Jun 19 19:56:19 volumio volumio[857]: info: CorePlayQueue::getTrackBlock Jun 19 19:56:19 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:19 volumio volumio[857]: info: CorePlayQueue::getTrack 55 Jun 19 19:56:19 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 19 19:56:19 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:19 volumio volumio[857]: info: CoreStateMachine::serviceStop Jun 19 19:56:19 volumio volumio[857]: info: CorePlayQueue::getTrack 55 Jun 19 19:56:19 volumio volumio[857]: info: CoreCommandRouter::serviceStop Jun 19 19:56:19 volumio volumio[857]: info: ControllerMpd::stop Jun 19 19:56:19 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand stop Jun 19 19:56:19 volumio volumio[857]: info: sendMpdCommand stop took 4 milliseconds Jun 19 19:56:19 volumio volumio[857]: info: CoreStateMachine::play index undefined Jun 19 19:56:19 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jun 19 19:56:19 volumio volumio[857]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 19 19:56:19 volumio volumio[857]: info: CorePlayQueue::getTrack 56 Jun 19 19:56:19 volumio volumio[857]: info: CoreStateMachine::startPlaybackTimer Jun 19 19:56:19 volumio volumio[857]: info: CorePlayQueue::getTrack 56 Jun 19 19:56:19 volumio volumio[857]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Zbigniew Preisner/Fairy Tale A True Story/07 Zbigniew Preisner - The Beck.flac Jun 19 19:56:19 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand stop Jun 19 19:56:19 volumio volumio[857]: info: CoreStateMachine::updateTrackBlock Jun 19 19:56:19 volumio volumio[857]: info: CorePlayQueue::getTrackBlock Jun 19 19:56:19 volumio volumio[857]: info: Jun 19 19:56:19 volumio volumio[857]: ---------------------------- MPD announces state update: player Jun 19 19:56:19 volumio volumio[857]: info: ControllerMpd::getState Jun 19 19:56:19 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand status Jun 19 19:56:19 volumio volumio[857]: info: sendMpdCommand stop took 1 milliseconds Jun 19 19:56:19 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand clear Jun 19 19:56:19 volumio volumio[857]: info: Jun 19 19:56:19 volumio volumio[857]: ---------------------------- MPD announces state update: player Jun 19 19:56:19 volumio volumio[857]: info: ControllerMpd::getState Jun 19 19:56:19 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand status Jun 19 19:56:19 volumio volumio[857]: info: Jun 19 19:56:19 volumio volumio[857]: ---------------------------- MPD announces state update: player Jun 19 19:56:19 volumio volumio[857]: info: ControllerMpd::getState Jun 19 19:56:19 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand status Jun 19 19:56:19 volumio volumio[857]: info: Jun 19 19:56:19 volumio volumio[857]: ---------------------------- MPD announces system playlist update Jun 19 19:56:19 volumio volumio[857]: info: Ignoring MPD Status Update Jun 19 19:56:19 volumio volumio[857]: info: sendMpdCommand status took 5 milliseconds Jun 19 19:56:19 volumio volumio[857]: info: sendMpdCommand clear took 4 milliseconds Jun 19 19:56:19 volumio volumio[857]: info: sendMpdCommand status took 3 milliseconds Jun 19 19:56:19 volumio volumio[857]: info: sendMpdCommand status took 2 milliseconds Jun 19 19:56:19 volumio volumio[857]: verbose: ControllerMpd::parseState Jun 19 19:56:19 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 19:56:19 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Zbigniew Preisner/Fairy Tale A True Story/07 Zbigniew Preisner - The Beck.flac" Jun 19 19:56:19 volumio volumio[857]: verbose: ControllerMpd::parseState Jun 19 19:56:19 volumio volumio[857]: verbose: ControllerMpd::parseState Jun 19 19:56:19 volumio volumio[857]: info: ControllerMpd::pushState Jun 19 19:56:19 volumio volumio[857]: info: CoreCommandRouter::servicePushState Jun 19 19:56:19 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:19 volumio volumio[857]: info: CorePlayQueue::getTrack 56 Jun 19 19:56:19 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 19 19:56:19 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:19 volumio volumio[857]: info: CorePlayQueue::getTrack 56 Jun 19 19:56:19 volumio volumio[857]: 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} Jun 19 19:56:19 volumio volumio[857]: verbose: CURRENT POSITION 56 Jun 19 19:56:19 volumio volumio[857]: info: CoreStateMachine::syncState stateService stop Jun 19 19:56:19 volumio volumio[857]: info: CoreStateMachine::syncState currentStatus stop Jun 19 19:56:19 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:19 volumio volumio[857]: info: CorePlayQueue::getTrack 56 Jun 19 19:56:19 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:19 volumio volumio[857]: info: No code Jun 19 19:56:19 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:19 volumio volumio[857]: info: CorePlayQueue::getTrack 56 Jun 19 19:56:19 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:19 volumio volumio[857]: info: ControllerMpd::pushState Jun 19 19:56:19 volumio volumio[857]: info: CoreCommandRouter::servicePushState Jun 19 19:56:19 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:19 volumio volumio[857]: info: CorePlayQueue::getTrack 56 Jun 19 19:56:19 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:19 volumio volumio[857]: info: CorePlayQueue::getTrack 56 Jun 19 19:56:19 volumio volumio[857]: 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} Jun 19 19:56:19 volumio volumio[857]: verbose: CURRENT POSITION 56 Jun 19 19:56:19 volumio volumio[857]: info: CoreStateMachine::syncState stateService stop Jun 19 19:56:19 volumio volumio[857]: info: CoreStateMachine::syncState currentStatus stop Jun 19 19:56:19 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:19 volumio volumio[857]: info: CorePlayQueue::getTrack 56 Jun 19 19:56:19 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:19 volumio volumio[857]: info: No code Jun 19 19:56:19 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:19 volumio volumio[857]: info: CorePlayQueue::getTrack 56 Jun 19 19:56:19 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:19 volumio volumio[857]: info: ------------------------------ 24ms Jun 19 19:56:19 volumio volumio[857]: info: ------------------------------ 24ms Jun 19 19:56:19 volumio volumio[857]: info: Jun 19 19:56:19 volumio volumio[857]: ---------------------------- MPD announces system playlist update Jun 19 19:56:19 volumio volumio[857]: info: Ignoring MPD Status Update Jun 19 19:56:19 volumio volumio[857]: info: Jun 19 19:56:19 volumio volumio[857]: ---------------------------- MPD announces system playlist update Jun 19 19:56:19 volumio volumio[857]: info: Ignoring MPD Status Update Jun 19 19:56:19 volumio volumio[857]: error: updateQueue error: null Jun 19 19:56:19 volumio volumio[857]: info: Jun 19 19:56:19 volumio volumio[857]: ---------------------------- MPD announces system playlist update Jun 19 19:56:19 volumio volumio[857]: info: Ignoring MPD Status Update Jun 19 19:56:19 volumio volumio[857]: info: ------------------------------ 27ms Jun 19 19:56:19 volumio volumio[857]: info: sendMpdCommand playlistinfo took 25 milliseconds Jun 19 19:56:19 volumio volumio[857]: info: sendMpdCommand add "INTERNAL/Zbigniew Preisner/Fairy Tale A True Story/07 Zbigniew Preisner - The Beck.flac" took 26 milliseconds Jun 19 19:56:19 volumio volumio[857]: info: ------------------------------ 4ms Jun 19 19:56:19 volumio volumio[857]: info: ------------------------------ 3ms Jun 19 19:56:19 volumio volumio[857]: verbose: ControllerMpd::parseTrackInfo Jun 19 19:56:19 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand play Jun 19 19:56:19 volumio volumio[857]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 19 19:56:19 volumio volumio[857]: info: ------------------------------ 33ms Jun 19 19:56:19 volumio volumio[857]: info: Jun 19 19:56:19 volumio volumio[857]: ---------------------------- MPD announces system playlist update Jun 19 19:56:19 volumio volumio[857]: info: Ignoring MPD Status Update Jun 19 19:56:19 volumio volumio[857]: info: Jun 19 19:56:19 volumio volumio[857]: ---------------------------- MPD announces system playlist update Jun 19 19:56:19 volumio volumio[857]: info: Ignoring MPD Status Update Jun 19 19:56:19 volumio volumio[857]: info: Jun 19 19:56:19 volumio volumio[857]: ---------------------------- MPD announces state update: player Jun 19 19:56:19 volumio volumio[857]: info: ------------------------------ 12ms Jun 19 19:56:19 volumio volumio[857]: info: sendMpdCommand play took 10 milliseconds Jun 19 19:56:19 volumio volumio[857]: info: ------------------------------ 9ms Jun 19 19:56:19 volumio volumio[857]: info: ------------------------------ 9ms Jun 19 19:56:19 volumio volumio[857]: info: ControllerMpd::getState Jun 19 19:56:19 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand status Jun 19 19:56:19 volumio volumio[857]: info: Jun 19 19:56:19 volumio volumio[857]: ---------------------------- MPD announces state update: player Jun 19 19:56:19 volumio volumio[857]: info: ControllerMpd::getState Jun 19 19:56:19 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand status Jun 19 19:56:19 volumio volumio[857]: info: Jun 19 19:56:19 volumio volumio[857]: ---------------------------- MPD announces state update: player Jun 19 19:56:19 volumio volumio[857]: info: ControllerMpd::getState Jun 19 19:56:19 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jun 19 19:56:19 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand status Jun 19 19:56:19 volumio volumio[857]: info: Jun 19 19:56:19 volumio volumio[857]: ---------------------------- MPD announces state update: player Jun 19 19:56:19 volumio volumio[857]: info: ControllerMpd::getState Jun 19 19:56:19 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand status Jun 19 19:56:19 volumio volumio[857]: info: sendMpdCommand status took 7 milliseconds Jun 19 19:56:19 volumio volumio[857]: info: sendMpdCommand status took 5 milliseconds Jun 19 19:56:19 volumio volumio[857]: info: sendMpdCommand status took 4 milliseconds Jun 19 19:56:19 volumio volumio[857]: verbose: ControllerMpd::parseState Jun 19 19:56:19 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 19:56:19 volumio volumio[857]: verbose: ControllerMpd::parseState Jun 19 19:56:19 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 19:56:19 volumio volumio[857]: verbose: ControllerMpd::parseState Jun 19 19:56:19 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 19:56:19 volumio volumio[857]: info: Jun 19 19:56:19 volumio volumio[857]: ---------------------------- MPD announces state update: player Jun 19 19:56:19 volumio volumio[857]: info: ControllerMpd::getState Jun 19 19:56:19 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand status Jun 19 19:56:19 volumio volumio[857]: info: Jun 19 19:56:19 volumio volumio[857]: ---------------------------- MPD announces state update: player Jun 19 19:56:19 volumio volumio[857]: info: ControllerMpd::getState Jun 19 19:56:19 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand status Jun 19 19:56:19 volumio volumio[857]: info: sendMpdCommand status took 12 milliseconds Jun 19 19:56:19 volumio volumio[857]: info: sendMpdCommand playlistinfo took 8 milliseconds Jun 19 19:56:19 volumio volumio[857]: info: sendMpdCommand playlistinfo took 8 milliseconds Jun 19 19:56:19 volumio volumio[857]: info: sendMpdCommand status took 5 milliseconds Jun 19 19:56:19 volumio volumio[857]: info: sendMpdCommand status took 4 milliseconds Jun 19 19:56:19 volumio volumio[857]: verbose: ControllerMpd::parseState Jun 19 19:56:19 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 19:56:19 volumio volumio[857]: verbose: ControllerMpd::parseTrackInfo Jun 19 19:56:19 volumio volumio[857]: verbose: ControllerMpd::parseTrackInfo Jun 19 19:56:19 volumio volumio[857]: verbose: ControllerMpd::parseTrackInfo Jun 19 19:56:19 volumio volumio[857]: verbose: ControllerMpd::parseState Jun 19 19:56:19 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 19:56:19 volumio volumio[857]: verbose: ControllerMpd::parseState Jun 19 19:56:19 volumio volumio[857]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 19 19:56:19 volumio volumio[857]: info: ControllerMpd::pushState Jun 19 19:56:19 volumio volumio[857]: info: CoreCommandRouter::servicePushState Jun 19 19:56:19 volumio volumio[857]: info: CorePlayQueue::getTrack 56 Jun 19 19:56:19 volumio volumio[857]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":194,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Beck","artist":"Zbigniew Preisner","album":"Fairy Tale A True Story","uri":"INTERNAL/Zbigniew Preisner/Fairy Tale A True Story/07 Zbigniew Preisner - The Beck.flac","trackType":"flac"} Jun 19 19:56:19 volumio volumio[857]: verbose: CURRENT POSITION 56 Jun 19 19:56:19 volumio volumio[857]: info: CoreStateMachine::syncState stateService play Jun 19 19:56:19 volumio volumio[857]: info: CoreStateMachine::syncState currentStatus stop Jun 19 19:56:19 volumio volumio[857]: info: ControllerMpd::pushState Jun 19 19:56:19 volumio volumio[857]: info: CoreCommandRouter::servicePushState Jun 19 19:56:19 volumio volumio[857]: info: CorePlayQueue::getTrack 56 Jun 19 19:56:19 volumio volumio[857]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":194,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Beck","artist":"Zbigniew Preisner","album":"Fairy Tale A True Story","uri":"INTERNAL/Zbigniew Preisner/Fairy Tale A True Story/07 Zbigniew Preisner - The Beck.flac","trackType":"flac"} Jun 19 19:56:19 volumio volumio[857]: verbose: CURRENT POSITION 56 Jun 19 19:56:19 volumio volumio[857]: info: CoreStateMachine::syncState stateService play Jun 19 19:56:19 volumio volumio[857]: info: CoreStateMachine::syncState currentStatus play Jun 19 19:56:19 volumio volumio[857]: info: Received an update from plugin. extracting info from payload Jun 19 19:56:19 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:19 volumio volumio[857]: info: CorePlayQueue::getTrack 56 Jun 19 19:56:19 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 19 19:56:19 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:19 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:19 volumio volumio[857]: info: CorePlayQueue::getTrack 56 Jun 19 19:56:19 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:19 volumio volumio[857]: info: ControllerMpd::pushState Jun 19 19:56:19 volumio volumio[857]: info: CoreCommandRouter::servicePushState Jun 19 19:56:19 volumio volumio[857]: info: CorePlayQueue::getTrack 56 Jun 19 19:56:19 volumio volumio[857]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":194,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Beck","artist":"Zbigniew Preisner","album":"Fairy Tale A True Story","uri":"INTERNAL/Zbigniew Preisner/Fairy Tale A True Story/07 Zbigniew Preisner - The Beck.flac","trackType":"flac"} Jun 19 19:56:19 volumio volumio[857]: verbose: CURRENT POSITION 56 Jun 19 19:56:19 volumio volumio[857]: info: CoreStateMachine::syncState stateService play Jun 19 19:56:19 volumio volumio[857]: info: CoreStateMachine::syncState currentStatus play Jun 19 19:56:19 volumio volumio[857]: info: Received an update from plugin. extracting info from payload Jun 19 19:56:19 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:19 volumio volumio[857]: info: CorePlayQueue::getTrack 56 Jun 19 19:56:19 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:19 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:19 volumio volumio[857]: info: CorePlayQueue::getTrack 56 Jun 19 19:56:19 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:19 volumio volumio[857]: info: ------------------------------ 40ms Jun 19 19:56:19 volumio volumio[857]: info: ------------------------------ 42ms Jun 19 19:56:19 volumio volumio[857]: info: ------------------------------ 40ms Jun 19 19:56:19 volumio volumio[857]: info: sendMpdCommand playlistinfo took 25 milliseconds Jun 19 19:56:19 volumio volumio[857]: info: sendMpdCommand playlistinfo took 23 milliseconds Jun 19 19:56:19 volumio volumio[857]: info: sendMpdCommand playlistinfo took 24 milliseconds Jun 19 19:56:19 volumio volumio[857]: verbose: ControllerMpd::parseTrackInfo Jun 19 19:56:19 volumio volumio[857]: verbose: ControllerMpd::parseTrackInfo Jun 19 19:56:19 volumio volumio[857]: verbose: ControllerMpd::parseTrackInfo Jun 19 19:56:19 volumio volumio[857]: info: ControllerMpd::pushState Jun 19 19:56:19 volumio volumio[857]: info: CoreCommandRouter::servicePushState Jun 19 19:56:19 volumio volumio[857]: info: CorePlayQueue::getTrack 56 Jun 19 19:56:19 volumio volumio[857]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":194,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"663 Kbps","isStreaming":false,"title":"The Beck","artist":"Zbigniew Preisner","album":"Fairy Tale A True Story","uri":"INTERNAL/Zbigniew Preisner/Fairy Tale A True Story/07 Zbigniew Preisner - The Beck.flac","trackType":"flac"} Jun 19 19:56:19 volumio volumio[857]: verbose: CURRENT POSITION 56 Jun 19 19:56:19 volumio volumio[857]: info: CoreStateMachine::syncState stateService play Jun 19 19:56:19 volumio volumio[857]: info: CoreStateMachine::syncState currentStatus play Jun 19 19:56:19 volumio volumio[857]: info: Received an update from plugin. extracting info from payload Jun 19 19:56:19 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:19 volumio volumio[857]: info: CorePlayQueue::getTrack 56 Jun 19 19:56:19 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 19 19:56:19 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:19 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:19 volumio volumio[857]: info: CorePlayQueue::getTrack 56 Jun 19 19:56:19 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:19 volumio volumio[857]: info: ControllerMpd::pushState Jun 19 19:56:19 volumio volumio[857]: info: CoreCommandRouter::servicePushState Jun 19 19:56:19 volumio volumio[857]: info: CorePlayQueue::getTrack 56 Jun 19 19:56:19 volumio volumio[857]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":194,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"694 Kbps","isStreaming":false,"title":"The Beck","artist":"Zbigniew Preisner","album":"Fairy Tale A True Story","uri":"INTERNAL/Zbigniew Preisner/Fairy Tale A True Story/07 Zbigniew Preisner - The Beck.flac","trackType":"flac"} Jun 19 19:56:19 volumio volumio[857]: verbose: CURRENT POSITION 56 Jun 19 19:56:19 volumio volumio[857]: info: CoreStateMachine::syncState stateService play Jun 19 19:56:19 volumio volumio[857]: info: CoreStateMachine::syncState currentStatus play Jun 19 19:56:19 volumio volumio[857]: info: Received an update from plugin. extracting info from payload Jun 19 19:56:19 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:19 volumio volumio[857]: info: CorePlayQueue::getTrack 56 Jun 19 19:56:19 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:19 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:19 volumio volumio[857]: info: CorePlayQueue::getTrack 56 Jun 19 19:56:19 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:19 volumio volumio[857]: info: ControllerMpd::pushState Jun 19 19:56:19 volumio volumio[857]: info: CoreCommandRouter::servicePushState Jun 19 19:56:19 volumio volumio[857]: info: CorePlayQueue::getTrack 56 Jun 19 19:56:19 volumio volumio[857]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":194,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"712 Kbps","isStreaming":false,"title":"The Beck","artist":"Zbigniew Preisner","album":"Fairy Tale A True Story","uri":"INTERNAL/Zbigniew Preisner/Fairy Tale A True Story/07 Zbigniew Preisner - The Beck.flac","trackType":"flac"} Jun 19 19:56:19 volumio volumio[857]: verbose: CURRENT POSITION 56 Jun 19 19:56:19 volumio volumio[857]: info: CoreStateMachine::syncState stateService play Jun 19 19:56:19 volumio volumio[857]: info: CoreStateMachine::syncState currentStatus play Jun 19 19:56:19 volumio volumio[857]: info: Received an update from plugin. extracting info from payload Jun 19 19:56:19 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:19 volumio volumio[857]: info: CorePlayQueue::getTrack 56 Jun 19 19:56:19 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:19 volumio volumio[857]: info: CoreStateMachine::pushState Jun 19 19:56:19 volumio volumio[857]: info: CorePlayQueue::getTrack 56 Jun 19 19:56:19 volumio volumio[857]: info: CoreCommandRouter::volumioPushState Jun 19 19:56:19 volumio volumio[857]: info: ------------------------------ 62ms Jun 19 19:56:19 volumio volumio[857]: info: ------------------------------ 54ms Jun 19 19:56:19 volumio volumio[857]: info: ------------------------------ 53ms Jun 19 19:56:52 volumio volumio[857]: info: ___________ PLUGINS: Run Shutdown Tasks ___________ Jun 19 19:56:52 volumio volumio[857]: info: PLUGIN onShutdown : networkfs Jun 19 19:56:52 volumio volumio[857]: info: PLUGIN onShutdown : audiophonicsonoff Jun 19 19:56:52 volumio volumio[857]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 19 19:56:52 volumio volumio[857]: TypeError: Cannot read property 'writeSync' of undefined Jun 19 19:56:52 volumio volumio[857]: at ControllerAudiophonicsOnOff.onVolumioShutdown (/data/plugins/system_hardware/audiophonicsonoff/index.js:48:25) Jun 19 19:56:52 volumio volumio[857]: at PluginManager.onVolumioShutdownPlugin (/volumio/app/pluginmanager.js:638:30) Jun 19 19:56:52 volumio volumio[857]: at HashMap. (/volumio/app/pluginmanager.js:621:31) Jun 19 19:56:52 volumio volumio[857]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jun 19 19:56:52 volumio volumio[857]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jun 19 19:56:52 volumio volumio[857]: at PluginManager.onVolumioShutdown (/volumio/app/pluginmanager.js:619:20) Jun 19 19:56:52 volumio volumio[857]: at CoreCommandRouter.shutdown (/volumio/app/index.js:1328:22) Jun 19 19:56:52 volumio volumio[857]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:859:33) Jun 19 19:56:52 volumio volumio[857]: at Socket.emit (events.js:315:20) Jun 19 19:56:52 volumio volumio[857]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Jun 19 19:56:52 volumio volumio[857]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Jun 19 19:56:52 volumio volumio[857]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 19 19:56:52 volumio sudo[8401]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-19 19:55 Jun 19 19:56:52 volumio sudo[8401]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 04:52:53 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="a72866a0de4045751d03a035de6290e1"