-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Sun 2025-12-14 22:58:36 CET. -- Dec 14 22:57:01 primo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: inputs , alsavolume Dec 14 22:57:01 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:57:01 primo volumio[3185]: info: CorePlayQueue::getTrack 234 Dec 14 22:57:01 primo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 22:57:01 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:57:01 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:57:01 primo volumio[3185]: info: CorePlayQueue::getTrack 234 Dec 14 22:57:01 primo volumio[3185]: info: Signalling Playback active due to playback status change Dec 14 22:57:01 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:57:01 primo volumio[3185]: info: CorePlayQueue::getTrack 234 Dec 14 22:57:01 primo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 22:57:01 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:57:01 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:57:01 primo volumio[3185]: info: CorePlayQueue::getTrack 234 Dec 14 22:57:01 primo volumio[3185]: info: Signalling Playback active due to playback status change Dec 14 22:57:11 primo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 14 22:57:11 primo volumio[3185]: info: CURURI: artists://Al%20Stewart Dec 14 22:57:11 primo volumio[3185]: info: Preload queue cleared Dec 14 22:57:11 primo volumio[3185]: info: Preload queue cleared Dec 14 22:57:11 primo volumio[3185]: info: Preloading song: music-library/USB/MUSIKARKIV/Music/iTunes/iTunes Music/Music/Al Stewart/Karagarga Goes Psychedelic CD2/12 Turn Into Earth.mp3 Dec 14 22:57:11 primo volumio[3185]: info: Exploding uri music-library/USB/MUSIKARKIV/Music/iTunes/iTunes Music/Music/Al Stewart/Karagarga Goes Psychedelic CD2/12 Turn Into Earth.mp3 in service mpd Dec 14 22:57:11 primo volumio[3185]: info: ALBUMART /albumart?cacheid=596&web=Al%20Stewart/Karagarga%20Goes%20Psychedelic%20CD2/extralarge&path=%2Fmnt%2FUSB%2FMUSIKARKIV%2FMusic%2FiTunes%2FiTunes%20Music%2FMusic%2FAl%20Stewart%2FKaragarga%20Goes%20Psychedelic%20CD2%2F12%20Turn%20Into%20Earth.mp3&metadata=false Dec 14 22:57:11 primo volumio[3185]: info: URI /mnt/USB/MUSIKARKIV/Music/iTunes/iTunes Music/Music/Al Stewart/Karagarga Goes Psychedelic CD2/12 Turn Into Earth.mp3 Dec 14 22:57:12 primo volumio[3185]: info: Executing endpoint metavolumio Dec 14 22:57:12 primo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 14 22:57:13 primo volumio[3185]: info: Preload queue cleared Dec 14 22:57:13 primo volumio[3185]: info: CoreCommandRouter::volumioAddQueueItems Dec 14 22:57:13 primo volumio[3185]: info: CoreStateMachine::addQueueItems Dec 14 22:57:13 primo volumio[3185]: info: CorePlayQueue::addQueueItems Dec 14 22:57:13 primo volumio[3185]: info: Preload queue cleared Dec 14 22:57:13 primo volumio[3185]: info: Adding Item to queue: artists://Al%20Stewart Dec 14 22:57:13 primo volumio[3185]: info: Exploding uri artists://Al%20Stewart in service mpd Dec 14 22:57:13 primo volumio[3185]: info: CoreCommandRouter::volumioPushQueue Dec 14 22:57:13 primo volumio[3185]: info: CorePlayQueue::saveQueue Dec 14 22:57:13 primo volumio[3185]: info: CoreStateMachine::updateTrackBlock Dec 14 22:57:13 primo volumio[3185]: info: CorePlayQueue::getTrackBlock Dec 14 22:57:13 primo volumio[3185]: info: CoreCommandRouter::volumioPlay Dec 14 22:57:13 primo volumio[3185]: info: CoreStateMachine::play index 235 Dec 14 22:57:13 primo volumio[3185]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 14 22:57:13 primo volumio[3185]: info: CoreStateMachine::stop Dec 14 22:57:13 primo volumio[3185]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 14 22:57:13 primo volumio[3185]: info: CoreStateMachine::stPlaybackTimer Dec 14 22:57:13 primo volumio[3185]: info: CoreStateMachine::updateTrackBlock Dec 14 22:57:13 primo volumio[3185]: info: CorePlayQueue::getTrackBlock Dec 14 22:57:13 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:57:13 primo volumio[3185]: info: CorePlayQueue::getTrack 234 Dec 14 22:57:13 primo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 22:57:13 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:57:13 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:57:13 primo volumio[3185]: info: CorePlayQueue::getTrack 234 Dec 14 22:57:13 primo volumio[3185]: info: CoreStateMachine::serviceStop Dec 14 22:57:13 primo volumio[3185]: info: CorePlayQueue::getTrack 234 Dec 14 22:57:13 primo volumio[3185]: info: CoreCommandRouter::serviceStop Dec 14 22:57:13 primo volumio[3185]: info: ControllerMpd::stop Dec 14 22:57:13 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand stop Dec 14 22:57:13 primo kernel: asoc-aml-card auge_sound: tdm playback stop Dec 14 22:57:13 primo kernel: spdif_a is set to disable Dec 14 22:57:13 primo kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Dec 14 22:57:13 primo kernel: aml_dai_tdm_hw_free(), disable mclk for TDM-B Dec 14 22:57:13 primo kernel: tdm playback mute: 1, lane_cnt = 8 Dec 14 22:57:13 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Dec 14 22:57:13 primo volumio[3185]: info: Dec 14 22:57:13 primo volumio[3185]: ---------------------------- MPD announces state update: player Dec 14 22:57:13 primo volumio[3185]: info: sendMpdCommand stop took 36 milliseconds Dec 14 22:57:13 primo volumio[3185]: info: ControllerMpd::getState Dec 14 22:57:13 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand status Dec 14 22:57:13 primo volumio[3185]: info: CoreStateMachine::play index undefined Dec 14 22:57:13 primo volumio[3185]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 14 22:57:13 primo volumio[3185]: info: CorePlayQueue::getTrack 235 Dec 14 22:57:13 primo volumio[3185]: info: CoreStateMachine::startPlaybackTimer Dec 14 22:57:13 primo volumio[3185]: info: CorePlayQueue::getTrack 235 Dec 14 22:57:13 primo volumio[3185]: info: CoreCommandRouter::volumioGetVisibleSources Dec 14 22:57:13 primo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 14 22:57:13 primo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 14 22:57:13 primo volumio[3185]: verbose: ControllerMpd::clearAddPlayTracks USB/MUSIKARKIV/Music/iTunes/iTunes Music/Music/Al Stewart/Karagarga Goes Psychedelic CD2/12 Turn Into Earth.mp3 Dec 14 22:57:13 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand stop Dec 14 22:57:13 primo volumio[3185]: info: Dec 14 22:57:13 primo volumio[3185]: ---------------------------- MPD announces state update: player Dec 14 22:57:13 primo volumio[3185]: info: ControllerMpd::getState Dec 14 22:57:13 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand status Dec 14 22:57:13 primo volumio[3185]: info: Dec 14 22:57:13 primo volumio[3185]: ---------------------------- MPD announces state update: player Dec 14 22:57:13 primo volumio[3185]: info: ControllerMpd::getState Dec 14 22:57:13 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand status Dec 14 22:57:13 primo volumio[3185]: info: sendMpdCommand status took 16 milliseconds Dec 14 22:57:13 primo volumio[3185]: info: sendMpdCommand stop took 14 milliseconds Dec 14 22:57:13 primo volumio[3185]: info: sendMpdCommand status took 11 milliseconds Dec 14 22:57:13 primo volumio[3185]: info: sendMpdCommand status took 9 milliseconds Dec 14 22:57:13 primo volumio[3185]: verbose: ControllerMpd::parseState Dec 14 22:57:13 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 14 22:57:13 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand clear Dec 14 22:57:13 primo volumio[3185]: verbose: ControllerMpd::parseState Dec 14 22:57:13 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 14 22:57:13 primo volumio[3185]: verbose: ControllerMpd::parseState Dec 14 22:57:13 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 14 22:57:13 primo volumio[3185]: info: Dec 14 22:57:13 primo volumio[3185]: ---------------------------- MPD announces system playlist update Dec 14 22:57:13 primo volumio[3185]: info: Ignoring MPD Status Update Dec 14 22:57:13 primo volumio[3185]: info: sendMpdCommand playlistinfo took 7 milliseconds Dec 14 22:57:13 primo volumio[3185]: info: sendMpdCommand clear took 7 milliseconds Dec 14 22:57:13 primo volumio[3185]: info: sendMpdCommand playlistinfo took 8 milliseconds Dec 14 22:57:13 primo volumio[3185]: info: sendMpdCommand playlistinfo took 8 milliseconds Dec 14 22:57:13 primo volumio[3185]: verbose: ControllerMpd::parseTrackInfo Dec 14 22:57:13 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand add "USB/MUSIKARKIV/Music/iTunes/iTunes Music/Music/Al Stewart/Karagarga Goes Psychedelic CD2/12 Turn Into Earth.mp3" Dec 14 22:57:13 primo volumio[3185]: verbose: ControllerMpd::parseTrackInfo Dec 14 22:57:13 primo volumio[3185]: verbose: ControllerMpd::parseTrackInfo Dec 14 22:57:13 primo volumio[3185]: info: ControllerMpd::pushState Dec 14 22:57:13 primo volumio[3185]: info: CoreCommandRouter::servicePushState Dec 14 22:57:13 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:57:13 primo volumio[3185]: info: CorePlayQueue::getTrack 235 Dec 14 22:57:13 primo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 22:57:13 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:57:13 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:57:13 primo volumio[3185]: info: CorePlayQueue::getTrack 235 Dec 14 22:57:13 primo volumio[3185]: info: CorePlayQueue::getTrack 235 Dec 14 22:57:13 primo volumio[3185]: 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":"Hot Mud","artist":"Afrique","album":"Soul Makossa","uri":"USB/MUSIKARKIV/Music/iTunes/iTunes Music/Music/Afrique/Soul Makossa/06 Hot Mud.mp3","trackType":"mp3"} Dec 14 22:57:13 primo volumio[3185]: verbose: CURRENT POSITION 235 Dec 14 22:57:13 primo volumio[3185]: info: CoreStateMachine::syncState stateService stop Dec 14 22:57:13 primo volumio[3185]: info: CoreStateMachine::syncState currentStatus stop Dec 14 22:57:13 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:57:13 primo volumio[3185]: info: CorePlayQueue::getTrack 235 Dec 14 22:57:13 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:57:13 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:57:13 primo volumio[3185]: info: CorePlayQueue::getTrack 235 Dec 14 22:57:13 primo volumio[3185]: info: No code Dec 14 22:57:13 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:57:13 primo volumio[3185]: info: CorePlayQueue::getTrack 235 Dec 14 22:57:13 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:57:13 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:57:13 primo volumio[3185]: info: CorePlayQueue::getTrack 235 Dec 14 22:57:13 primo volumio[3185]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Dec 14 22:57:13 primo volumio[3185]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Dec 14 22:57:13 primo volumio[3185]: info: ------------------------------ 49ms Dec 14 22:57:13 primo volumio[3185]: info: ------------------------------ 43ms Dec 14 22:57:13 primo volumio[3185]: info: ------------------------------ 41ms Dec 14 22:57:13 primo volumio[3185]: info: Dec 14 22:57:13 primo volumio[3185]: ---------------------------- MPD announces system playlist update Dec 14 22:57:13 primo volumio[3185]: info: Ignoring MPD Status Update Dec 14 22:57:13 primo volumio[3185]: info: Dec 14 22:57:13 primo volumio[3185]: ---------------------------- MPD announces system playlist update Dec 14 22:57:13 primo volumio[3185]: info: Ignoring MPD Status Update Dec 14 22:57:13 primo volumio[3185]: error: updateQueue error: null Dec 14 22:57:13 primo volumio[3185]: info: Dec 14 22:57:13 primo volumio[3185]: ---------------------------- MPD announces system playlist update Dec 14 22:57:13 primo volumio[3185]: info: Ignoring MPD Status Update Dec 14 22:57:13 primo volumio[3185]: info: ------------------------------ 33ms Dec 14 22:57:13 primo volumio[3185]: info: sendMpdCommand add "USB/MUSIKARKIV/Music/iTunes/iTunes Music/Music/Al Stewart/Karagarga Goes Psychedelic CD2/12 Turn Into Earth.mp3" took 30 milliseconds Dec 14 22:57:13 primo volumio[3185]: info: ------------------------------ 6ms Dec 14 22:57:13 primo volumio[3185]: info: ------------------------------ 5ms Dec 14 22:57:13 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand play Dec 14 22:57:13 primo volumio[3185]: info: Dec 14 22:57:13 primo volumio[3185]: ---------------------------- MPD announces system playlist update Dec 14 22:57:13 primo volumio[3185]: info: Ignoring MPD Status Update Dec 14 22:57:13 primo volumio[3185]: info: Dec 14 22:57:13 primo volumio[3185]: ---------------------------- MPD announces system playlist update Dec 14 22:57:13 primo volumio[3185]: info: Ignoring MPD Status Update Dec 14 22:57:13 primo kernel: aml_tdm_open Dec 14 22:57:13 primo kernel: Not init audio effects Dec 14 22:57:13 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Dec 14 22:57:13 primo kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Dec 14 22:57:13 primo kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Dec 14 22:57:13 primo kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Dec 14 22:57:13 primo kernel: asoc aml_dai_set_tdm_fmt, 0x4001, ffffffc03d2b8e18, id(1), clksel(1) Dec 14 22:57:13 primo kernel: aml_dai_set_tdm_fmt(), fmt not change Dec 14 22:57:13 primo kernel: dump_pcm_setting(ffffffc03d2b8e18) Dec 14 22:57:13 primo kernel: pcm_mode(1) Dec 14 22:57:13 primo kernel: sysclk(11289600) Dec 14 22:57:13 primo kernel: sysclk_bclk_ratio(4) Dec 14 22:57:13 primo kernel: bclk(2822400) Dec 14 22:57:13 primo kernel: bclk_lrclk_ratio(64) Dec 14 22:57:13 primo kernel: lrclk(44100) Dec 14 22:57:13 primo kernel: tx_mask(0x3) Dec 14 22:57:13 primo kernel: rx_mask(0x3) Dec 14 22:57:13 primo kernel: slots(2) Dec 14 22:57:13 primo kernel: slot_width(32) Dec 14 22:57:13 primo kernel: lane_mask_in(0x2) Dec 14 22:57:13 primo kernel: lane_mask_out(0x1) Dec 14 22:57:13 primo kernel: lane_oe_mask_in(0x0) Dec 14 22:57:13 primo kernel: lane_oe_mask_out(0x0) Dec 14 22:57:13 primo kernel: lane_lb_mask_in(0x0) Dec 14 22:57:13 primo kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Dec 14 22:57:13 primo kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Dec 14 22:57:13 primo kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Dec 14 22:57:13 primo kernel: aml_dai_set_clkdiv, div 4, clksel(1) Dec 14 22:57:13 primo kernel: aml_dai_set_bclk_ratio, select I2S mode Dec 14 22:57:13 primo kernel: aml_dai_tdm_hw_params(), enable mclk for TDM-B Dec 14 22:57:13 primo kernel: aml_tdm_prepare(), reset fddr Dec 14 22:57:13 primo kernel: spdif_a fifo ctrl, frddr:0 type:4, 24 bits, chmask 0x3, swap 0x10 Dec 14 22:57:13 primo kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Dec 14 22:57:13 primo kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Dec 14 22:57:13 primo kernel: tdm playback mute: 0, lane_cnt = 8 Dec 14 22:57:13 primo volumio[3185]: info: Dec 14 22:57:13 primo volumio[3185]: ---------------------------- MPD announces state update: player Dec 14 22:57:13 primo volumio[3185]: info: Dec 14 22:57:13 primo volumio[3185]: ---------------------------- MPD announces state update: player Dec 14 22:57:13 primo volumio[3185]: info: ------------------------------ 20ms Dec 14 22:57:13 primo volumio[3185]: info: sendMpdCommand play took 19 milliseconds Dec 14 22:57:13 primo volumio[3185]: info: ------------------------------ 17ms Dec 14 22:57:13 primo volumio[3185]: info: ControllerMpd::getState Dec 14 22:57:13 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand status Dec 14 22:57:13 primo volumio[3185]: info: ------------------------------ 16ms Dec 14 22:57:13 primo volumio[3185]: info: ControllerMpd::getState Dec 14 22:57:13 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand status Dec 14 22:57:13 primo volumio[3185]: info: Dec 14 22:57:13 primo volumio[3185]: ---------------------------- MPD announces state update: player Dec 14 22:57:13 primo volumio[3185]: info: ControllerMpd::getState Dec 14 22:57:13 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand status Dec 14 22:57:13 primo volumio[3185]: info: Dec 14 22:57:13 primo volumio[3185]: ---------------------------- MPD announces state update: player Dec 14 22:57:13 primo volumio[3185]: info: ControllerMpd::getState Dec 14 22:57:13 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand status Dec 14 22:57:13 primo volumio[3185]: info: sendMpdCommand status took 8 milliseconds Dec 14 22:57:13 primo volumio[3185]: info: sendMpdCommand status took 7 milliseconds Dec 14 22:57:13 primo volumio[3185]: info: sendMpdCommand status took 6 milliseconds Dec 14 22:57:13 primo volumio[3185]: info: sendMpdCommand status took 4 milliseconds Dec 14 22:57:13 primo volumio[3185]: verbose: ControllerMpd::parseState Dec 14 22:57:13 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 14 22:57:13 primo volumio[3185]: verbose: ControllerMpd::parseState Dec 14 22:57:13 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 14 22:57:13 primo volumio[3185]: verbose: ControllerMpd::parseState Dec 14 22:57:13 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 14 22:57:13 primo volumio[3185]: verbose: ControllerMpd::parseState Dec 14 22:57:13 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 14 22:57:13 primo kernel: asoc-aml-card auge_sound: tdm playback enable Dec 14 22:57:13 primo kernel: spdif_a is set to enable Dec 14 22:57:13 primo volumio[3185]: info: MCU Signalled Playback Inactive Dec 14 22:57:13 primo volumio[3185]: info: Dec 14 22:57:13 primo volumio[3185]: ---------------------------- MPD announces state update: player Dec 14 22:57:13 primo volumio[3185]: info: ControllerMpd::getState Dec 14 22:57:13 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand status Dec 14 22:57:13 primo volumio[3185]: info: Dec 14 22:57:13 primo volumio[3185]: ---------------------------- MPD announces state update: player Dec 14 22:57:13 primo volumio[3185]: info: ControllerMpd::getState Dec 14 22:57:13 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand status Dec 14 22:57:13 primo volumio[3185]: info: sendMpdCommand playlistinfo took 21 milliseconds Dec 14 22:57:13 primo volumio[3185]: info: sendMpdCommand playlistinfo took 22 milliseconds Dec 14 22:57:13 primo volumio[3185]: info: sendMpdCommand playlistinfo took 22 milliseconds Dec 14 22:57:13 primo volumio[3185]: info: sendMpdCommand playlistinfo took 21 milliseconds Dec 14 22:57:13 primo volumio[3185]: info: sendMpdCommand status took 13 milliseconds Dec 14 22:57:13 primo volumio[3185]: info: sendMpdCommand status took 11 milliseconds Dec 14 22:57:13 primo volumio[3185]: verbose: ControllerMpd::parseTrackInfo Dec 14 22:57:13 primo volumio[3185]: verbose: ControllerMpd::parseTrackInfo Dec 14 22:57:13 primo volumio[3185]: verbose: ControllerMpd::parseTrackInfo Dec 14 22:57:13 primo volumio[3185]: verbose: ControllerMpd::parseTrackInfo Dec 14 22:57:13 primo volumio[3185]: verbose: ControllerMpd::parseState Dec 14 22:57:13 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 14 22:57:13 primo volumio[3185]: verbose: ControllerMpd::parseState Dec 14 22:57:13 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 14 22:57:13 primo volumio[3185]: info: ControllerMpd::pushState Dec 14 22:57:13 primo volumio[3185]: info: CoreCommandRouter::servicePushState Dec 14 22:57:13 primo volumio[3185]: info: CorePlayQueue::getTrack 235 Dec 14 22:57:13 primo volumio[3185]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":175,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Turn Into Earth","artist":"Al Stewart","album":"Karagarga Goes Psychedelic CD2","uri":"USB/MUSIKARKIV/Music/iTunes/iTunes Music/Music/Al Stewart/Karagarga Goes Psychedelic CD2/12 Turn Into Earth.mp3","trackType":"mp3"} Dec 14 22:57:13 primo volumio[3185]: verbose: CURRENT POSITION 235 Dec 14 22:57:13 primo volumio[3185]: info: CoreStateMachine::syncState stateService play Dec 14 22:57:13 primo volumio[3185]: info: CoreStateMachine::syncState currentStatus stop Dec 14 22:57:13 primo volumio[3185]: info: ControllerMpd::pushState Dec 14 22:57:13 primo volumio[3185]: info: CoreCommandRouter::servicePushState Dec 14 22:57:13 primo volumio[3185]: info: CorePlayQueue::getTrack 235 Dec 14 22:57:13 primo volumio[3185]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":175,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Turn Into Earth","artist":"Al Stewart","album":"Karagarga Goes Psychedelic CD2","uri":"USB/MUSIKARKIV/Music/iTunes/iTunes Music/Music/Al Stewart/Karagarga Goes Psychedelic CD2/12 Turn Into Earth.mp3","trackType":"mp3"} Dec 14 22:57:13 primo volumio[3185]: verbose: CURRENT POSITION 235 Dec 14 22:57:13 primo volumio[3185]: info: CoreStateMachine::syncState stateService play Dec 14 22:57:13 primo volumio[3185]: info: CoreStateMachine::syncState currentStatus play Dec 14 22:57:13 primo volumio[3185]: info: Received an update from plugin. extracting info from payload Dec 14 22:57:13 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:57:13 primo volumio[3185]: info: CorePlayQueue::getTrack 235 Dec 14 22:57:13 primo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 22:57:13 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:57:13 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:57:13 primo volumio[3185]: info: CorePlayQueue::getTrack 235 Dec 14 22:57:13 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:57:13 primo volumio[3185]: info: CorePlayQueue::getTrack 235 Dec 14 22:57:13 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:57:13 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:57:13 primo volumio[3185]: info: CorePlayQueue::getTrack 235 Dec 14 22:57:13 primo volumio[3185]: info: ControllerMpd::pushState Dec 14 22:57:13 primo volumio[3185]: info: CoreCommandRouter::servicePushState Dec 14 22:57:13 primo volumio[3185]: info: CorePlayQueue::getTrack 235 Dec 14 22:57:13 primo volumio[3185]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":175,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Turn Into Earth","artist":"Al Stewart","album":"Karagarga Goes Psychedelic CD2","uri":"USB/MUSIKARKIV/Music/iTunes/iTunes Music/Music/Al Stewart/Karagarga Goes Psychedelic CD2/12 Turn Into Earth.mp3","trackType":"mp3"} Dec 14 22:57:13 primo volumio[3185]: verbose: CURRENT POSITION 235 Dec 14 22:57:13 primo volumio[3185]: info: CoreStateMachine::syncState stateService play Dec 14 22:57:13 primo volumio[3185]: info: CoreStateMachine::syncState currentStatus play Dec 14 22:57:13 primo volumio[3185]: info: Received an update from plugin. extracting info from payload Dec 14 22:57:13 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:57:13 primo volumio[3185]: info: CorePlayQueue::getTrack 235 Dec 14 22:57:13 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:57:13 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:57:13 primo volumio[3185]: info: CorePlayQueue::getTrack 235 Dec 14 22:57:13 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:57:13 primo volumio[3185]: info: CorePlayQueue::getTrack 235 Dec 14 22:57:13 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:57:13 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:57:13 primo volumio[3185]: info: CorePlayQueue::getTrack 235 Dec 14 22:57:13 primo volumio[3185]: info: ControllerMpd::pushState Dec 14 22:57:13 primo volumio[3185]: info: CoreCommandRouter::servicePushState Dec 14 22:57:13 primo volumio[3185]: info: CorePlayQueue::getTrack 235 Dec 14 22:57:13 primo volumio[3185]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":175,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Turn Into Earth","artist":"Al Stewart","album":"Karagarga Goes Psychedelic CD2","uri":"USB/MUSIKARKIV/Music/iTunes/iTunes Music/Music/Al Stewart/Karagarga Goes Psychedelic CD2/12 Turn Into Earth.mp3","trackType":"mp3"} Dec 14 22:57:13 primo volumio[3185]: verbose: CURRENT POSITION 235 Dec 14 22:57:13 primo volumio[3185]: info: CoreStateMachine::syncState stateService play Dec 14 22:57:13 primo volumio[3185]: info: CoreStateMachine::syncState currentStatus play Dec 14 22:57:13 primo volumio[3185]: info: Received an update from plugin. extracting info from payload Dec 14 22:57:13 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:57:13 primo volumio[3185]: info: CorePlayQueue::getTrack 235 Dec 14 22:57:13 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:57:13 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:57:13 primo volumio[3185]: info: CorePlayQueue::getTrack 235 Dec 14 22:57:13 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:57:13 primo volumio[3185]: info: CorePlayQueue::getTrack 235 Dec 14 22:57:13 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:57:13 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:57:13 primo volumio[3185]: info: CorePlayQueue::getTrack 235 Dec 14 22:57:13 primo volumio[3185]: info: ------------------------------ 59ms Dec 14 22:57:13 primo volumio[3185]: info: ------------------------------ 72ms Dec 14 22:57:13 primo volumio[3185]: info: ------------------------------ 70ms Dec 14 22:57:13 primo volumio[3185]: info: ------------------------------ 69ms Dec 14 22:57:13 primo volumio[3185]: info: sendMpdCommand playlistinfo took 61 milliseconds Dec 14 22:57:13 primo volumio[3185]: info: sendMpdCommand playlistinfo took 61 milliseconds Dec 14 22:57:13 primo volumio[3185]: verbose: ControllerMpd::parseTrackInfo Dec 14 22:57:13 primo volumio[3185]: verbose: ControllerMpd::parseTrackInfo Dec 14 22:57:13 primo volumio[3185]: info: ControllerMpd::pushState Dec 14 22:57:13 primo volumio[3185]: info: CoreCommandRouter::servicePushState Dec 14 22:57:13 primo volumio[3185]: info: CorePlayQueue::getTrack 235 Dec 14 22:57:13 primo volumio[3185]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":175,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Turn Into Earth","artist":"Al Stewart","album":"Karagarga Goes Psychedelic CD2","uri":"USB/MUSIKARKIV/Music/iTunes/iTunes Music/Music/Al Stewart/Karagarga Goes Psychedelic CD2/12 Turn Into Earth.mp3","trackType":"mp3"} Dec 14 22:57:13 primo volumio[3185]: verbose: CURRENT POSITION 235 Dec 14 22:57:13 primo volumio[3185]: info: CoreStateMachine::syncState stateService play Dec 14 22:57:13 primo volumio[3185]: info: CoreStateMachine::syncState currentStatus play Dec 14 22:57:13 primo volumio[3185]: info: Received an update from plugin. extracting info from payload Dec 14 22:57:13 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:57:13 primo volumio[3185]: info: CorePlayQueue::getTrack 235 Dec 14 22:57:13 primo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 22:57:13 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:57:13 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:57:13 primo volumio[3185]: info: CorePlayQueue::getTrack 235 Dec 14 22:57:13 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:57:13 primo volumio[3185]: info: CorePlayQueue::getTrack 235 Dec 14 22:57:13 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:57:13 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:57:13 primo volumio[3185]: info: CorePlayQueue::getTrack 235 Dec 14 22:57:13 primo volumio[3185]: info: ControllerMpd::pushState Dec 14 22:57:13 primo volumio[3185]: info: CoreCommandRouter::servicePushState Dec 14 22:57:13 primo volumio[3185]: info: CorePlayQueue::getTrack 235 Dec 14 22:57:13 primo volumio[3185]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":175,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Turn Into Earth","artist":"Al Stewart","album":"Karagarga Goes Psychedelic CD2","uri":"USB/MUSIKARKIV/Music/iTunes/iTunes Music/Music/Al Stewart/Karagarga Goes Psychedelic CD2/12 Turn Into Earth.mp3","trackType":"mp3"} Dec 14 22:57:13 primo volumio[3185]: verbose: CURRENT POSITION 235 Dec 14 22:57:13 primo volumio[3185]: info: CoreStateMachine::syncState stateService play Dec 14 22:57:13 primo volumio[3185]: info: CoreStateMachine::syncState currentStatus play Dec 14 22:57:13 primo volumio[3185]: info: Received an update from plugin. extracting info from payload Dec 14 22:57:13 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:57:13 primo volumio[3185]: info: CorePlayQueue::getTrack 235 Dec 14 22:57:13 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:57:13 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:57:13 primo volumio[3185]: info: CorePlayQueue::getTrack 235 Dec 14 22:57:13 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:57:13 primo volumio[3185]: info: CorePlayQueue::getTrack 235 Dec 14 22:57:13 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:57:13 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:57:13 primo volumio[3185]: info: CorePlayQueue::getTrack 235 Dec 14 22:57:13 primo volumio[3185]: info: ------------------------------ 98ms Dec 14 22:57:13 primo volumio[3185]: info: ------------------------------ 97ms Dec 14 22:57:13 primo volumio[3185]: info: Signalling Playback active due to playback status change Dec 14 22:57:13 primo volumio[3185]: info: Signalling Playback active due to playback status change Dec 14 22:57:13 primo volumio[3185]: info: Signalling Playback active due to playback status change Dec 14 22:57:13 primo volumio[3185]: info: Signalling Playback active due to playback status change Dec 14 22:57:13 primo volumio[3185]: info: Signalling Playback active due to playback status change Dec 14 22:57:13 primo volumio[3185]: info: Signalling Playback active due to playback status change Dec 14 22:57:13 primo volumio[3185]: info: Signalling Playback active due to playback status change Dec 14 22:57:13 primo volumio[3185]: info: Signalling Playback active due to playback status change Dec 14 22:57:13 primo volumio[3185]: info: Signalling Playback active due to playback status change Dec 14 22:57:13 primo volumio[3185]: info: Signalling Playback active due to playback status change Dec 14 22:57:13 primo volumio[3185]: info: MCU Signalled Playback Active Dec 14 22:58:02 primo volumio[3185]: info: CoreCommandRouter::volumioNext Dec 14 22:58:02 primo volumio[3185]: info: CoreStateMachine::next Dec 14 22:58:02 primo volumio[3185]: info: CoreStateMachine::stop Dec 14 22:58:02 primo volumio[3185]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 14 22:58:02 primo volumio[3185]: info: CoreStateMachine::stPlaybackTimer Dec 14 22:58:02 primo volumio[3185]: info: CoreStateMachine::updateTrackBlock Dec 14 22:58:02 primo volumio[3185]: info: CorePlayQueue::getTrackBlock Dec 14 22:58:02 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:02 primo volumio[3185]: info: CorePlayQueue::getTrack 235 Dec 14 22:58:02 primo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 22:58:02 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:02 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:02 primo volumio[3185]: info: CorePlayQueue::getTrack 235 Dec 14 22:58:02 primo volumio[3185]: info: CoreStateMachine::serviceStop Dec 14 22:58:02 primo volumio[3185]: info: CorePlayQueue::getTrack 235 Dec 14 22:58:02 primo volumio[3185]: info: CoreCommandRouter::serviceStop Dec 14 22:58:02 primo volumio[3185]: info: ControllerMpd::stop Dec 14 22:58:02 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand stop Dec 14 22:58:02 primo kernel: asoc-aml-card auge_sound: tdm playback stop Dec 14 22:58:02 primo kernel: spdif_a is set to disable Dec 14 22:58:02 primo kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Dec 14 22:58:02 primo kernel: aml_dai_tdm_hw_free(), disable mclk for TDM-B Dec 14 22:58:02 primo kernel: tdm playback mute: 1, lane_cnt = 8 Dec 14 22:58:02 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Dec 14 22:58:02 primo volumio[3185]: info: Dec 14 22:58:02 primo volumio[3185]: ---------------------------- MPD announces state update: player Dec 14 22:58:02 primo volumio[3185]: info: sendMpdCommand stop took 10 milliseconds Dec 14 22:58:02 primo volumio[3185]: info: ControllerMpd::getState Dec 14 22:58:02 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand status Dec 14 22:58:02 primo volumio[3185]: info: CoreStateMachine::play index undefined Dec 14 22:58:02 primo volumio[3185]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 14 22:58:02 primo volumio[3185]: info: CorePlayQueue::getTrack 236 Dec 14 22:58:02 primo volumio[3185]: info: sendMpdCommand status took 4 milliseconds Dec 14 22:58:02 primo volumio[3185]: verbose: ControllerMpd::parseState Dec 14 22:58:02 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 14 22:58:02 primo volumio[3185]: info: Dec 14 22:58:02 primo volumio[3185]: ---------------------------- MPD announces state update: player Dec 14 22:58:02 primo volumio[3185]: info: ControllerMpd::getState Dec 14 22:58:02 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand status Dec 14 22:58:02 primo volumio[3185]: info: Dec 14 22:58:02 primo volumio[3185]: ---------------------------- MPD announces state update: player Dec 14 22:58:02 primo volumio[3185]: info: ControllerMpd::getState Dec 14 22:58:02 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand status Dec 14 22:58:02 primo volumio[3185]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 14 22:58:02 primo volumio[3185]: info: sendMpdCommand status took 4 milliseconds Dec 14 22:58:02 primo volumio[3185]: info: sendMpdCommand status took 3 milliseconds Dec 14 22:58:02 primo volumio[3185]: verbose: ControllerMpd::parseTrackInfo Dec 14 22:58:02 primo volumio[3185]: verbose: ControllerMpd::parseState Dec 14 22:58:02 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 14 22:58:02 primo volumio[3185]: verbose: ControllerMpd::parseState Dec 14 22:58:02 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 14 22:58:02 primo volumio[3185]: info: ControllerMpd::pushState Dec 14 22:58:02 primo volumio[3185]: info: CoreCommandRouter::servicePushState Dec 14 22:58:02 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:02 primo volumio[3185]: info: CorePlayQueue::getTrack 0 Dec 14 22:58:02 primo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 22:58:02 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:02 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:02 primo volumio[3185]: info: CorePlayQueue::getTrack 0 Dec 14 22:58:02 primo volumio[3185]: info: CorePlayQueue::getTrack 0 Dec 14 22:58:02 primo volumio[3185]: 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":"Turn Into Earth","artist":"Al Stewart","album":"Karagarga Goes Psychedelic CD2","uri":"USB/MUSIKARKIV/Music/iTunes/iTunes Music/Music/Al Stewart/Karagarga Goes Psychedelic CD2/12 Turn Into Earth.mp3","trackType":"mp3"} Dec 14 22:58:02 primo volumio[3185]: verbose: CURRENT POSITION 0 Dec 14 22:58:02 primo volumio[3185]: info: CoreStateMachine::syncState stateService stop Dec 14 22:58:02 primo volumio[3185]: info: CoreStateMachine::syncState currentStatus stop Dec 14 22:58:02 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:02 primo volumio[3185]: info: CorePlayQueue::getTrack 0 Dec 14 22:58:02 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:02 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:02 primo volumio[3185]: info: CorePlayQueue::getTrack 0 Dec 14 22:58:02 primo volumio[3185]: info: No code Dec 14 22:58:02 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:02 primo volumio[3185]: info: CorePlayQueue::getTrack 0 Dec 14 22:58:02 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:02 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:02 primo volumio[3185]: info: CorePlayQueue::getTrack 0 Dec 14 22:58:02 primo volumio[3185]: info: ------------------------------ 30ms Dec 14 22:58:02 primo volumio[3185]: info: sendMpdCommand playlistinfo took 20 milliseconds Dec 14 22:58:02 primo volumio[3185]: info: sendMpdCommand playlistinfo took 20 milliseconds Dec 14 22:58:02 primo volumio[3185]: verbose: ControllerMpd::parseTrackInfo Dec 14 22:58:02 primo volumio[3185]: verbose: ControllerMpd::parseTrackInfo Dec 14 22:58:02 primo volumio[3185]: info: ControllerMpd::pushState Dec 14 22:58:02 primo volumio[3185]: info: CoreCommandRouter::servicePushState Dec 14 22:58:02 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:02 primo volumio[3185]: info: CorePlayQueue::getTrack 0 Dec 14 22:58:02 primo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 22:58:02 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:02 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:02 primo volumio[3185]: info: CorePlayQueue::getTrack 0 Dec 14 22:58:02 primo volumio[3185]: info: CorePlayQueue::getTrack 0 Dec 14 22:58:02 primo volumio[3185]: 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":"Turn Into Earth","artist":"Al Stewart","album":"Karagarga Goes Psychedelic CD2","uri":"USB/MUSIKARKIV/Music/iTunes/iTunes Music/Music/Al Stewart/Karagarga Goes Psychedelic CD2/12 Turn Into Earth.mp3","trackType":"mp3"} Dec 14 22:58:02 primo volumio[3185]: verbose: CURRENT POSITION 0 Dec 14 22:58:02 primo volumio[3185]: info: CoreStateMachine::syncState stateService stop Dec 14 22:58:02 primo volumio[3185]: info: CoreStateMachine::syncState currentStatus stop Dec 14 22:58:02 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:02 primo volumio[3185]: info: CorePlayQueue::getTrack 0 Dec 14 22:58:02 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:02 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:02 primo volumio[3185]: info: CorePlayQueue::getTrack 0 Dec 14 22:58:02 primo volumio[3185]: info: No code Dec 14 22:58:02 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:02 primo volumio[3185]: info: CorePlayQueue::getTrack 0 Dec 14 22:58:02 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:02 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:02 primo volumio[3185]: info: CorePlayQueue::getTrack 0 Dec 14 22:58:02 primo volumio[3185]: info: ControllerMpd::pushState Dec 14 22:58:02 primo volumio[3185]: info: CoreCommandRouter::servicePushState Dec 14 22:58:02 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:02 primo volumio[3185]: info: CorePlayQueue::getTrack 0 Dec 14 22:58:02 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:02 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:02 primo volumio[3185]: info: CorePlayQueue::getTrack 0 Dec 14 22:58:02 primo volumio[3185]: info: CorePlayQueue::getTrack 0 Dec 14 22:58:02 primo volumio[3185]: 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":"Turn Into Earth","artist":"Al Stewart","album":"Karagarga Goes Psychedelic CD2","uri":"USB/MUSIKARKIV/Music/iTunes/iTunes Music/Music/Al Stewart/Karagarga Goes Psychedelic CD2/12 Turn Into Earth.mp3","trackType":"mp3"} Dec 14 22:58:02 primo volumio[3185]: verbose: CURRENT POSITION 0 Dec 14 22:58:02 primo volumio[3185]: info: CoreStateMachine::syncState stateService stop Dec 14 22:58:02 primo volumio[3185]: info: CoreStateMachine::syncState currentStatus stop Dec 14 22:58:02 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:02 primo volumio[3185]: info: CorePlayQueue::getTrack 0 Dec 14 22:58:02 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:03 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:03 primo volumio[3185]: info: CorePlayQueue::getTrack 0 Dec 14 22:58:03 primo volumio[3185]: info: No code Dec 14 22:58:03 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:03 primo volumio[3185]: info: CorePlayQueue::getTrack 0 Dec 14 22:58:03 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:03 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:03 primo volumio[3185]: info: CorePlayQueue::getTrack 0 Dec 14 22:58:03 primo volumio[3185]: info: ------------------------------ 61ms Dec 14 22:58:03 primo volumio[3185]: info: ------------------------------ 61ms Dec 14 22:58:03 primo volumio[3185]: info: MCU Signalled Playback Inactive Dec 14 22:58:09 primo volumio[3185]: info: CoreCommandRouter::volumioPlay Dec 14 22:58:09 primo volumio[3185]: info: CoreStateMachine::play index undefined Dec 14 22:58:09 primo volumio[3185]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 14 22:58:09 primo volumio[3185]: info: CorePlayQueue::getTrack 0 Dec 14 22:58:09 primo volumio[3185]: info: CoreStateMachine::startPlaybackTimer Dec 14 22:58:09 primo volumio[3185]: info: CorePlayQueue::getTrack 0 Dec 14 22:58:09 primo volumio[3185]: verbose: ControllerMpd::clearAddPlayTracks USB/MUSIKARKIV/Music/iTunes/iTunes Music/Music/Compilations/MUSICSYSTEM _ ELECTRIC/15 Foreigners, Please Don't Leave Us Alone With The Danes.mp3 Dec 14 22:58:09 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand stop Dec 14 22:58:09 primo volumio[3185]: info: sendMpdCommand stop took 3 milliseconds Dec 14 22:58:09 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand clear Dec 14 22:58:09 primo volumio[3185]: info: sendMpdCommand clear took 1 milliseconds Dec 14 22:58:09 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand add "USB/MUSIKARKIV/Music/iTunes/iTunes Music/Music/Compilations/MUSICSYSTEM _ ELECTRIC/15 Foreigners, Please Don't Leave Us Alone With The Danes.mp3" Dec 14 22:58:09 primo volumio[3185]: info: Dec 14 22:58:09 primo volumio[3185]: ---------------------------- MPD announces system playlist update Dec 14 22:58:09 primo volumio[3185]: info: Ignoring MPD Status Update Dec 14 22:58:09 primo volumio[3185]: info: Dec 14 22:58:09 primo volumio[3185]: ---------------------------- MPD announces system playlist update Dec 14 22:58:09 primo volumio[3185]: info: Ignoring MPD Status Update Dec 14 22:58:09 primo volumio[3185]: info: Dec 14 22:58:09 primo volumio[3185]: ---------------------------- MPD announces system playlist update Dec 14 22:58:09 primo volumio[3185]: info: Ignoring MPD Status Update Dec 14 22:58:09 primo volumio[3185]: info: Dec 14 22:58:09 primo volumio[3185]: ---------------------------- MPD announces system playlist update Dec 14 22:58:09 primo volumio[3185]: info: Ignoring MPD Status Update Dec 14 22:58:09 primo volumio[3185]: info: sendMpdCommand add "USB/MUSIKARKIV/Music/iTunes/iTunes Music/Music/Compilations/MUSICSYSTEM _ ELECTRIC/15 Foreigners, Please Don't Leave Us Alone With The Danes.mp3" took 7 milliseconds Dec 14 22:58:09 primo volumio[3185]: info: ------------------------------ 6ms Dec 14 22:58:09 primo volumio[3185]: info: ------------------------------ 5ms Dec 14 22:58:09 primo volumio[3185]: info: ------------------------------ 3ms Dec 14 22:58:09 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand play Dec 14 22:58:09 primo volumio[3185]: info: Dec 14 22:58:09 primo volumio[3185]: ---------------------------- MPD announces system playlist update Dec 14 22:58:09 primo volumio[3185]: info: Ignoring MPD Status Update Dec 14 22:58:09 primo volumio[3185]: info: Dec 14 22:58:09 primo volumio[3185]: ---------------------------- MPD announces system playlist update Dec 14 22:58:09 primo volumio[3185]: info: Ignoring MPD Status Update Dec 14 22:58:09 primo volumio[3185]: info: ------------------------------ 7ms Dec 14 22:58:09 primo volumio[3185]: info: sendMpdCommand play took 5 milliseconds Dec 14 22:58:09 primo volumio[3185]: info: ------------------------------ 5ms Dec 14 22:58:09 primo volumio[3185]: info: ------------------------------ 3ms Dec 14 22:58:09 primo kernel: aml_tdm_open Dec 14 22:58:09 primo kernel: Not init audio effects Dec 14 22:58:09 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Dec 14 22:58:09 primo kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Dec 14 22:58:09 primo kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Dec 14 22:58:09 primo kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Dec 14 22:58:09 primo kernel: asoc aml_dai_set_tdm_fmt, 0x4001, ffffffc03d2b8e18, id(1), clksel(1) Dec 14 22:58:09 primo kernel: aml_dai_set_tdm_fmt(), fmt not change Dec 14 22:58:09 primo kernel: dump_pcm_setting(ffffffc03d2b8e18) Dec 14 22:58:09 primo kernel: pcm_mode(1) Dec 14 22:58:09 primo kernel: sysclk(11289600) Dec 14 22:58:09 primo kernel: sysclk_bclk_ratio(4) Dec 14 22:58:09 primo kernel: bclk(2822400) Dec 14 22:58:09 primo kernel: bclk_lrclk_ratio(64) Dec 14 22:58:09 primo kernel: lrclk(44100) Dec 14 22:58:09 primo kernel: tx_mask(0x3) Dec 14 22:58:09 primo kernel: rx_mask(0x3) Dec 14 22:58:09 primo kernel: slots(2) Dec 14 22:58:09 primo kernel: slot_width(32) Dec 14 22:58:09 primo kernel: lane_mask_in(0x2) Dec 14 22:58:09 primo kernel: lane_mask_out(0x1) Dec 14 22:58:09 primo kernel: lane_oe_mask_in(0x0) Dec 14 22:58:09 primo kernel: lane_oe_mask_out(0x0) Dec 14 22:58:09 primo kernel: lane_lb_mask_in(0x0) Dec 14 22:58:09 primo kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Dec 14 22:58:09 primo kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Dec 14 22:58:09 primo kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Dec 14 22:58:09 primo kernel: aml_dai_set_clkdiv, div 4, clksel(1) Dec 14 22:58:09 primo kernel: aml_dai_set_bclk_ratio, select I2S mode Dec 14 22:58:09 primo kernel: aml_dai_tdm_hw_params(), enable mclk for TDM-B Dec 14 22:58:09 primo kernel: aml_tdm_prepare(), reset fddr Dec 14 22:58:09 primo kernel: spdif_a fifo ctrl, frddr:0 type:4, 24 bits, chmask 0x3, swap 0x10 Dec 14 22:58:09 primo kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Dec 14 22:58:09 primo kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Dec 14 22:58:09 primo kernel: tdm playback mute: 0, lane_cnt = 8 Dec 14 22:58:09 primo volumio[3185]: info: Dec 14 22:58:09 primo volumio[3185]: ---------------------------- MPD announces state update: player Dec 14 22:58:09 primo volumio[3185]: info: ControllerMpd::getState Dec 14 22:58:09 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand status Dec 14 22:58:09 primo volumio[3185]: info: Dec 14 22:58:09 primo volumio[3185]: ---------------------------- MPD announces state update: player Dec 14 22:58:09 primo volumio[3185]: info: ControllerMpd::getState Dec 14 22:58:09 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand status Dec 14 22:58:09 primo volumio[3185]: info: Dec 14 22:58:09 primo volumio[3185]: ---------------------------- MPD announces state update: player Dec 14 22:58:09 primo volumio[3185]: info: ControllerMpd::getState Dec 14 22:58:09 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand status Dec 14 22:58:09 primo volumio[3185]: info: Dec 14 22:58:09 primo volumio[3185]: ---------------------------- MPD announces state update: player Dec 14 22:58:09 primo kernel: asoc-aml-card auge_sound: tdm playback enable Dec 14 22:58:09 primo kernel: spdif_a is set to enable Dec 14 22:58:09 primo volumio[3185]: info: ControllerMpd::getState Dec 14 22:58:09 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand status Dec 14 22:58:09 primo volumio[3185]: info: sendMpdCommand status took 19 milliseconds Dec 14 22:58:09 primo volumio[3185]: info: sendMpdCommand status took 14 milliseconds Dec 14 22:58:09 primo volumio[3185]: info: sendMpdCommand status took 13 milliseconds Dec 14 22:58:09 primo volumio[3185]: verbose: ControllerMpd::parseState Dec 14 22:58:09 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 14 22:58:09 primo volumio[3185]: verbose: ControllerMpd::parseState Dec 14 22:58:09 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 14 22:58:09 primo volumio[3185]: verbose: ControllerMpd::parseState Dec 14 22:58:09 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 14 22:58:09 primo volumio[3185]: info: Dec 14 22:58:09 primo volumio[3185]: ---------------------------- MPD announces state update: player Dec 14 22:58:09 primo volumio[3185]: info: ControllerMpd::getState Dec 14 22:58:09 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand status Dec 14 22:58:09 primo volumio[3185]: info: Dec 14 22:58:09 primo volumio[3185]: ---------------------------- MPD announces state update: player Dec 14 22:58:09 primo volumio[3185]: info: ControllerMpd::getState Dec 14 22:58:09 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand status Dec 14 22:58:09 primo volumio[3185]: info: sendMpdCommand status took 10 milliseconds Dec 14 22:58:09 primo volumio[3185]: info: sendMpdCommand playlistinfo took 12 milliseconds Dec 14 22:58:09 primo volumio[3185]: info: sendMpdCommand playlistinfo took 12 milliseconds Dec 14 22:58:09 primo volumio[3185]: info: sendMpdCommand playlistinfo took 12 milliseconds Dec 14 22:58:09 primo volumio[3185]: info: sendMpdCommand status took 10 milliseconds Dec 14 22:58:09 primo volumio[3185]: info: sendMpdCommand status took 9 milliseconds Dec 14 22:58:09 primo volumio[3185]: verbose: ControllerMpd::parseState Dec 14 22:58:09 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 14 22:58:09 primo volumio[3185]: verbose: ControllerMpd::parseTrackInfo Dec 14 22:58:09 primo volumio[3185]: verbose: ControllerMpd::parseTrackInfo Dec 14 22:58:09 primo volumio[3185]: verbose: ControllerMpd::parseTrackInfo Dec 14 22:58:09 primo volumio[3185]: verbose: ControllerMpd::parseState Dec 14 22:58:09 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 14 22:58:09 primo volumio[3185]: verbose: ControllerMpd::parseState Dec 14 22:58:09 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 14 22:58:09 primo volumio[3185]: info: ControllerMpd::pushState Dec 14 22:58:09 primo volumio[3185]: info: CoreCommandRouter::servicePushState Dec 14 22:58:09 primo volumio[3185]: info: CorePlayQueue::getTrack 0 Dec 14 22:58:09 primo volumio[3185]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":7,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Foreigners, Please Don't Leave Us Alone With The Danes","artist":"Superflex Music","album":"MUSICSYSTEM / ELECTRIC","uri":"USB/MUSIKARKIV/Music/iTunes/iTunes Music/Music/Compilations/MUSICSYSTEM _ ELECTRIC/15 Foreigners, Please Don't Leave Us Alone With The Danes.mp3","trackType":"mp3"} Dec 14 22:58:09 primo volumio[3185]: verbose: CURRENT POSITION 0 Dec 14 22:58:09 primo volumio[3185]: info: CoreStateMachine::syncState stateService play Dec 14 22:58:09 primo volumio[3185]: info: CoreStateMachine::syncState currentStatus stop Dec 14 22:58:09 primo volumio[3185]: info: ControllerMpd::pushState Dec 14 22:58:09 primo volumio[3185]: info: CoreCommandRouter::servicePushState Dec 14 22:58:09 primo volumio[3185]: info: CorePlayQueue::getTrack 0 Dec 14 22:58:09 primo volumio[3185]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":7,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Foreigners, Please Don't Leave Us Alone With The Danes","artist":"Superflex Music","album":"MUSICSYSTEM / ELECTRIC","uri":"USB/MUSIKARKIV/Music/iTunes/iTunes Music/Music/Compilations/MUSICSYSTEM _ ELECTRIC/15 Foreigners, Please Don't Leave Us Alone With The Danes.mp3","trackType":"mp3"} Dec 14 22:58:09 primo volumio[3185]: verbose: CURRENT POSITION 0 Dec 14 22:58:09 primo volumio[3185]: info: CoreStateMachine::syncState stateService play Dec 14 22:58:09 primo volumio[3185]: info: CoreStateMachine::syncState currentStatus play Dec 14 22:58:09 primo volumio[3185]: info: Received an update from plugin. extracting info from payload Dec 14 22:58:09 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:09 primo volumio[3185]: info: CorePlayQueue::getTrack 0 Dec 14 22:58:09 primo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 22:58:09 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:09 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:09 primo volumio[3185]: info: CorePlayQueue::getTrack 0 Dec 14 22:58:09 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:09 primo volumio[3185]: info: CorePlayQueue::getTrack 0 Dec 14 22:58:09 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:09 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:09 primo volumio[3185]: info: CorePlayQueue::getTrack 0 Dec 14 22:58:09 primo volumio[3185]: info: ControllerMpd::pushState Dec 14 22:58:09 primo volumio[3185]: info: CoreCommandRouter::servicePushState Dec 14 22:58:09 primo volumio[3185]: info: CorePlayQueue::getTrack 0 Dec 14 22:58:09 primo volumio[3185]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":7,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Foreigners, Please Don't Leave Us Alone With The Danes","artist":"Superflex Music","album":"MUSICSYSTEM / ELECTRIC","uri":"USB/MUSIKARKIV/Music/iTunes/iTunes Music/Music/Compilations/MUSICSYSTEM _ ELECTRIC/15 Foreigners, Please Don't Leave Us Alone With The Danes.mp3","trackType":"mp3"} Dec 14 22:58:09 primo volumio[3185]: verbose: CURRENT POSITION 0 Dec 14 22:58:09 primo volumio[3185]: info: CoreStateMachine::syncState stateService play Dec 14 22:58:09 primo volumio[3185]: info: CoreStateMachine::syncState currentStatus play Dec 14 22:58:09 primo volumio[3185]: info: Received an update from plugin. extracting info from payload Dec 14 22:58:09 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:09 primo volumio[3185]: info: CorePlayQueue::getTrack 0 Dec 14 22:58:09 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:09 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:09 primo volumio[3185]: info: CorePlayQueue::getTrack 0 Dec 14 22:58:09 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:09 primo volumio[3185]: info: CorePlayQueue::getTrack 0 Dec 14 22:58:09 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:09 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:09 primo volumio[3185]: info: CorePlayQueue::getTrack 0 Dec 14 22:58:09 primo volumio[3185]: info: ------------------------------ 57ms Dec 14 22:58:09 primo volumio[3185]: info: ------------------------------ 60ms Dec 14 22:58:09 primo volumio[3185]: info: ------------------------------ 59ms Dec 14 22:58:09 primo volumio[3185]: info: sendMpdCommand playlistinfo took 31 milliseconds Dec 14 22:58:09 primo volumio[3185]: info: sendMpdCommand playlistinfo took 30 milliseconds Dec 14 22:58:09 primo volumio[3185]: info: sendMpdCommand playlistinfo took 30 milliseconds Dec 14 22:58:09 primo volumio[3185]: verbose: ControllerMpd::parseTrackInfo Dec 14 22:58:09 primo volumio[3185]: verbose: ControllerMpd::parseTrackInfo Dec 14 22:58:09 primo volumio[3185]: verbose: ControllerMpd::parseTrackInfo Dec 14 22:58:09 primo volumio[3185]: info: ControllerMpd::pushState Dec 14 22:58:09 primo volumio[3185]: info: CoreCommandRouter::servicePushState Dec 14 22:58:09 primo volumio[3185]: info: CorePlayQueue::getTrack 0 Dec 14 22:58:09 primo volumio[3185]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":7,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Foreigners, Please Don't Leave Us Alone With The Danes","artist":"Superflex Music","album":"MUSICSYSTEM / ELECTRIC","uri":"USB/MUSIKARKIV/Music/iTunes/iTunes Music/Music/Compilations/MUSICSYSTEM _ ELECTRIC/15 Foreigners, Please Don't Leave Us Alone With The Danes.mp3","trackType":"mp3"} Dec 14 22:58:09 primo volumio[3185]: verbose: CURRENT POSITION 0 Dec 14 22:58:09 primo volumio[3185]: info: CoreStateMachine::syncState stateService play Dec 14 22:58:09 primo volumio[3185]: info: CoreStateMachine::syncState currentStatus play Dec 14 22:58:09 primo volumio[3185]: info: Received an update from plugin. extracting info from payload Dec 14 22:58:09 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:09 primo volumio[3185]: info: CorePlayQueue::getTrack 0 Dec 14 22:58:09 primo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 22:58:09 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:09 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:09 primo volumio[3185]: info: CorePlayQueue::getTrack 0 Dec 14 22:58:09 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:09 primo volumio[3185]: info: CorePlayQueue::getTrack 0 Dec 14 22:58:09 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:09 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:09 primo volumio[3185]: info: CorePlayQueue::getTrack 0 Dec 14 22:58:09 primo volumio[3185]: info: ControllerMpd::pushState Dec 14 22:58:09 primo volumio[3185]: info: CoreCommandRouter::servicePushState Dec 14 22:58:09 primo volumio[3185]: info: CorePlayQueue::getTrack 0 Dec 14 22:58:09 primo volumio[3185]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":7,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Foreigners, Please Don't Leave Us Alone With The Danes","artist":"Superflex Music","album":"MUSICSYSTEM / ELECTRIC","uri":"USB/MUSIKARKIV/Music/iTunes/iTunes Music/Music/Compilations/MUSICSYSTEM _ ELECTRIC/15 Foreigners, Please Don't Leave Us Alone With The Danes.mp3","trackType":"mp3"} Dec 14 22:58:09 primo volumio[3185]: verbose: CURRENT POSITION 0 Dec 14 22:58:09 primo volumio[3185]: info: CoreStateMachine::syncState stateService play Dec 14 22:58:09 primo volumio[3185]: info: CoreStateMachine::syncState currentStatus play Dec 14 22:58:09 primo volumio[3185]: info: Received an update from plugin. extracting info from payload Dec 14 22:58:09 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:09 primo volumio[3185]: info: CorePlayQueue::getTrack 0 Dec 14 22:58:09 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:09 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:09 primo volumio[3185]: info: CorePlayQueue::getTrack 0 Dec 14 22:58:09 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:09 primo volumio[3185]: info: CorePlayQueue::getTrack 0 Dec 14 22:58:09 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:09 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:09 primo volumio[3185]: info: CorePlayQueue::getTrack 0 Dec 14 22:58:09 primo volumio[3185]: info: ControllerMpd::pushState Dec 14 22:58:09 primo volumio[3185]: info: CoreCommandRouter::servicePushState Dec 14 22:58:09 primo volumio[3185]: info: CorePlayQueue::getTrack 0 Dec 14 22:58:09 primo volumio[3185]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":7,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Foreigners, Please Don't Leave Us Alone With The Danes","artist":"Superflex Music","album":"MUSICSYSTEM / ELECTRIC","uri":"USB/MUSIKARKIV/Music/iTunes/iTunes Music/Music/Compilations/MUSICSYSTEM _ ELECTRIC/15 Foreigners, Please Don't Leave Us Alone With The Danes.mp3","trackType":"mp3"} Dec 14 22:58:09 primo volumio[3185]: verbose: CURRENT POSITION 0 Dec 14 22:58:09 primo volumio[3185]: info: CoreStateMachine::syncState stateService play Dec 14 22:58:09 primo volumio[3185]: info: CoreStateMachine::syncState currentStatus play Dec 14 22:58:09 primo volumio[3185]: info: Received an update from plugin. extracting info from payload Dec 14 22:58:09 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:09 primo volumio[3185]: info: CorePlayQueue::getTrack 0 Dec 14 22:58:09 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:09 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:09 primo volumio[3185]: info: CorePlayQueue::getTrack 0 Dec 14 22:58:09 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:09 primo volumio[3185]: info: CorePlayQueue::getTrack 0 Dec 14 22:58:09 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:09 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:09 primo volumio[3185]: info: CorePlayQueue::getTrack 0 Dec 14 22:58:09 primo volumio[3185]: info: ------------------------------ 97ms Dec 14 22:58:09 primo volumio[3185]: info: ------------------------------ 82ms Dec 14 22:58:09 primo volumio[3185]: info: ------------------------------ 81ms Dec 14 22:58:09 primo volumio[3185]: info: Signalling Playback active due to playback status change Dec 14 22:58:09 primo volumio[3185]: info: Signalling Playback active due to playback status change Dec 14 22:58:09 primo volumio[3185]: info: Signalling Playback active due to playback status change Dec 14 22:58:09 primo volumio[3185]: info: Signalling Playback active due to playback status change Dec 14 22:58:09 primo volumio[3185]: info: Signalling Playback active due to playback status change Dec 14 22:58:09 primo volumio[3185]: info: Signalling Playback active due to playback status change Dec 14 22:58:09 primo volumio[3185]: info: Signalling Playback active due to playback status change Dec 14 22:58:09 primo volumio[3185]: info: Signalling Playback active due to playback status change Dec 14 22:58:09 primo volumio[3185]: info: Signalling Playback active due to playback status change Dec 14 22:58:09 primo volumio[3185]: info: Signalling Playback active due to playback status change Dec 14 22:58:09 primo volumio[3185]: info: MCU Signalled Playback Active Dec 14 22:58:10 primo volumio[3185]: info: CorePlayQueue::getTrack 0 Dec 14 22:58:10 primo volumio[3185]: info: CorePlayQueue::getTrack 1 Dec 14 22:58:10 primo volumio[3185]: info: Prefetching next song Dec 14 22:58:10 primo volumio[3185]: info: DOING PREFETCH IN MPD Dec 14 22:58:10 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand add "USB/MUSIKARKIV/Music/iTunes/iTunes Music/Music/Superflex + August Engkilde/Chicken Farm/08 Chicken Farm.mp3" Dec 14 22:58:10 primo volumio[3185]: info: sendMpdCommand add "USB/MUSIKARKIV/Music/iTunes/iTunes Music/Music/Superflex + August Engkilde/Chicken Farm/08 Chicken Farm.mp3" took 2 milliseconds Dec 14 22:58:10 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand consume 1 Dec 14 22:58:10 primo volumio[3185]: info: Dec 14 22:58:10 primo volumio[3185]: ---------------------------- MPD announces system playlist update Dec 14 22:58:10 primo volumio[3185]: info: Ignoring MPD Status Update Dec 14 22:58:10 primo volumio[3185]: info: sendMpdCommand consume 1 took 2 milliseconds Dec 14 22:58:10 primo volumio[3185]: info: Dec 14 22:58:10 primo volumio[3185]: ---------------------------- MPD announces system playlist update Dec 14 22:58:10 primo volumio[3185]: info: Ignoring MPD Status Update Dec 14 22:58:10 primo volumio[3185]: info: Dec 14 22:58:10 primo volumio[3185]: ---------------------------- MPD announces system playlist update Dec 14 22:58:10 primo volumio[3185]: info: Ignoring MPD Status Update Dec 14 22:58:10 primo volumio[3185]: info: ------------------------------ 5ms Dec 14 22:58:10 primo volumio[3185]: info: ------------------------------ 4ms Dec 14 22:58:10 primo volumio[3185]: info: ------------------------------ 3ms Dec 14 22:58:11 primo volumio[3185]: info: CoreCommandRouter::volumioNext Dec 14 22:58:11 primo volumio[3185]: info: CoreStateMachine::next Dec 14 22:58:11 primo volumio[3185]: info: CoreStateMachine::stop Dec 14 22:58:11 primo volumio[3185]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 14 22:58:11 primo volumio[3185]: info: CoreStateMachine::stPlaybackTimer Dec 14 22:58:11 primo volumio[3185]: info: CoreStateMachine::updateTrackBlock Dec 14 22:58:11 primo volumio[3185]: info: CorePlayQueue::getTrackBlock Dec 14 22:58:11 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:11 primo volumio[3185]: info: CorePlayQueue::getTrack 0 Dec 14 22:58:11 primo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 22:58:11 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:11 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:11 primo volumio[3185]: info: CorePlayQueue::getTrack 0 Dec 14 22:58:11 primo volumio[3185]: info: CoreStateMachine::serviceStop Dec 14 22:58:11 primo volumio[3185]: info: CorePlayQueue::getTrack 0 Dec 14 22:58:11 primo volumio[3185]: info: CoreCommandRouter::serviceStop Dec 14 22:58:11 primo volumio[3185]: info: ControllerMpd::stop Dec 14 22:58:11 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand stop Dec 14 22:58:11 primo kernel: asoc-aml-card auge_sound: tdm playback stop Dec 14 22:58:11 primo kernel: spdif_a is set to disable Dec 14 22:58:11 primo kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Dec 14 22:58:11 primo kernel: aml_dai_tdm_hw_free(), disable mclk for TDM-B Dec 14 22:58:11 primo kernel: tdm playback mute: 1, lane_cnt = 8 Dec 14 22:58:11 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Dec 14 22:58:11 primo volumio[3185]: info: Dec 14 22:58:11 primo volumio[3185]: ---------------------------- MPD announces state update: player Dec 14 22:58:11 primo volumio[3185]: info: sendMpdCommand stop took 9 milliseconds Dec 14 22:58:11 primo volumio[3185]: info: ControllerMpd::getState Dec 14 22:58:11 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand status Dec 14 22:58:11 primo volumio[3185]: info: CoreStateMachine::play index undefined Dec 14 22:58:11 primo volumio[3185]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 14 22:58:11 primo volumio[3185]: info: CorePlayQueue::getTrack 1 Dec 14 22:58:11 primo volumio[3185]: info: CoreStateMachine::startPlaybackTimer Dec 14 22:58:11 primo volumio[3185]: info: CorePlayQueue::getTrack 1 Dec 14 22:58:11 primo volumio[3185]: verbose: ControllerMpd::clearAddPlayTracks USB/MUSIKARKIV/Music/iTunes/iTunes Music/Music/Superflex + August Engkilde/Chicken Farm/08 Chicken Farm.mp3 Dec 14 22:58:11 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand stop Dec 14 22:58:11 primo volumio[3185]: info: CoreStateMachine::updateTrackBlock Dec 14 22:58:11 primo volumio[3185]: info: CorePlayQueue::getTrackBlock Dec 14 22:58:11 primo volumio[3185]: info: sendMpdCommand status took 4 milliseconds Dec 14 22:58:11 primo volumio[3185]: info: sendMpdCommand stop took 2 milliseconds Dec 14 22:58:11 primo volumio[3185]: verbose: ControllerMpd::parseState Dec 14 22:58:11 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 14 22:58:11 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand clear Dec 14 22:58:11 primo volumio[3185]: info: Dec 14 22:58:11 primo volumio[3185]: ---------------------------- MPD announces state update: player Dec 14 22:58:11 primo volumio[3185]: info: ControllerMpd::getState Dec 14 22:58:11 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand status Dec 14 22:58:11 primo volumio[3185]: info: Dec 14 22:58:11 primo volumio[3185]: ---------------------------- MPD announces state update: player Dec 14 22:58:11 primo volumio[3185]: info: ControllerMpd::getState Dec 14 22:58:11 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand status Dec 14 22:58:11 primo volumio[3185]: info: Dec 14 22:58:11 primo volumio[3185]: ---------------------------- MPD announces system playlist update Dec 14 22:58:11 primo volumio[3185]: info: Ignoring MPD Status Update Dec 14 22:58:11 primo volumio[3185]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 14 22:58:11 primo volumio[3185]: info: sendMpdCommand clear took 5 milliseconds Dec 14 22:58:11 primo volumio[3185]: info: sendMpdCommand status took 4 milliseconds Dec 14 22:58:11 primo volumio[3185]: info: sendMpdCommand status took 3 milliseconds Dec 14 22:58:11 primo volumio[3185]: verbose: ControllerMpd::parseTrackInfo Dec 14 22:58:11 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand add "USB/MUSIKARKIV/Music/iTunes/iTunes Music/Music/Superflex + August Engkilde/Chicken Farm/08 Chicken Farm.mp3" Dec 14 22:58:11 primo volumio[3185]: verbose: ControllerMpd::parseState Dec 14 22:58:11 primo volumio[3185]: verbose: ControllerMpd::parseState Dec 14 22:58:11 primo volumio[3185]: info: ControllerMpd::pushState Dec 14 22:58:11 primo volumio[3185]: info: CoreCommandRouter::servicePushState Dec 14 22:58:11 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:11 primo volumio[3185]: info: CorePlayQueue::getTrack 1 Dec 14 22:58:11 primo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 22:58:11 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:11 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:11 primo volumio[3185]: info: CorePlayQueue::getTrack 1 Dec 14 22:58:11 primo volumio[3185]: info: CorePlayQueue::getTrack 1 Dec 14 22:58:11 primo volumio[3185]: 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":"Foreigners, Please Don't Leave Us Alone With The Danes","artist":"Superflex Music","album":"MUSICSYSTEM / ELECTRIC","uri":"USB/MUSIKARKIV/Music/iTunes/iTunes Music/Music/Compilations/MUSICSYSTEM _ ELECTRIC/15 Foreigners, Please Don't Leave Us Alone With The Danes.mp3","trackType":"mp3"} Dec 14 22:58:11 primo volumio[3185]: verbose: CURRENT POSITION 1 Dec 14 22:58:11 primo volumio[3185]: info: CoreStateMachine::syncState stateService stop Dec 14 22:58:11 primo volumio[3185]: info: CoreStateMachine::syncState currentStatus stop Dec 14 22:58:11 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:11 primo volumio[3185]: info: CorePlayQueue::getTrack 1 Dec 14 22:58:11 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:11 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:11 primo volumio[3185]: info: CorePlayQueue::getTrack 1 Dec 14 22:58:11 primo volumio[3185]: info: No code Dec 14 22:58:11 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:11 primo volumio[3185]: info: CorePlayQueue::getTrack 1 Dec 14 22:58:11 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:11 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:11 primo volumio[3185]: info: CorePlayQueue::getTrack 1 Dec 14 22:58:11 primo volumio[3185]: info: ControllerMpd::pushState Dec 14 22:58:11 primo volumio[3185]: info: CoreCommandRouter::servicePushState Dec 14 22:58:11 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:11 primo volumio[3185]: info: CorePlayQueue::getTrack 1 Dec 14 22:58:11 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:11 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:11 primo volumio[3185]: info: CorePlayQueue::getTrack 1 Dec 14 22:58:11 primo volumio[3185]: info: CorePlayQueue::getTrack 1 Dec 14 22:58:11 primo volumio[3185]: 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} Dec 14 22:58:11 primo volumio[3185]: verbose: CURRENT POSITION 1 Dec 14 22:58:11 primo volumio[3185]: info: CoreStateMachine::syncState stateService stop Dec 14 22:58:11 primo volumio[3185]: info: CoreStateMachine::syncState currentStatus stop Dec 14 22:58:11 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:11 primo volumio[3185]: info: CorePlayQueue::getTrack 1 Dec 14 22:58:11 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:11 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:11 primo volumio[3185]: info: CorePlayQueue::getTrack 1 Dec 14 22:58:11 primo volumio[3185]: info: No code Dec 14 22:58:11 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:11 primo volumio[3185]: info: CorePlayQueue::getTrack 1 Dec 14 22:58:11 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:11 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:11 primo volumio[3185]: info: CorePlayQueue::getTrack 1 Dec 14 22:58:11 primo volumio[3185]: info: ControllerMpd::pushState Dec 14 22:58:11 primo volumio[3185]: info: CoreCommandRouter::servicePushState Dec 14 22:58:11 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:11 primo volumio[3185]: info: CorePlayQueue::getTrack 1 Dec 14 22:58:11 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:11 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:11 primo volumio[3185]: info: CorePlayQueue::getTrack 1 Dec 14 22:58:11 primo volumio[3185]: info: CorePlayQueue::getTrack 1 Dec 14 22:58:11 primo volumio[3185]: 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} Dec 14 22:58:11 primo volumio[3185]: verbose: CURRENT POSITION 1 Dec 14 22:58:11 primo volumio[3185]: info: CoreStateMachine::syncState stateService stop Dec 14 22:58:11 primo volumio[3185]: info: CoreStateMachine::syncState currentStatus stop Dec 14 22:58:11 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:11 primo volumio[3185]: info: CorePlayQueue::getTrack 1 Dec 14 22:58:11 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:11 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:11 primo volumio[3185]: info: CorePlayQueue::getTrack 1 Dec 14 22:58:11 primo volumio[3185]: info: No code Dec 14 22:58:11 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:11 primo volumio[3185]: info: CorePlayQueue::getTrack 1 Dec 14 22:58:11 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:11 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:11 primo volumio[3185]: info: CorePlayQueue::getTrack 1 Dec 14 22:58:11 primo volumio[3185]: info: ------------------------------ 60ms Dec 14 22:58:11 primo volumio[3185]: info: ------------------------------ 54ms Dec 14 22:58:11 primo volumio[3185]: info: ------------------------------ 55ms Dec 14 22:58:11 primo volumio[3185]: info: Dec 14 22:58:11 primo volumio[3185]: ---------------------------- MPD announces system playlist update Dec 14 22:58:11 primo volumio[3185]: info: Ignoring MPD Status Update Dec 14 22:58:11 primo volumio[3185]: info: Dec 14 22:58:11 primo volumio[3185]: ---------------------------- MPD announces system playlist update Dec 14 22:58:11 primo volumio[3185]: info: Ignoring MPD Status Update Dec 14 22:58:11 primo volumio[3185]: error: updateQueue error: null Dec 14 22:58:11 primo volumio[3185]: info: Dec 14 22:58:11 primo volumio[3185]: ---------------------------- MPD announces system playlist update Dec 14 22:58:11 primo volumio[3185]: info: Ignoring MPD Status Update Dec 14 22:58:11 primo volumio[3185]: info: ------------------------------ 62ms Dec 14 22:58:11 primo volumio[3185]: info: sendMpdCommand add "USB/MUSIKARKIV/Music/iTunes/iTunes Music/Music/Superflex + August Engkilde/Chicken Farm/08 Chicken Farm.mp3" took 60 milliseconds Dec 14 22:58:11 primo volumio[3185]: info: ------------------------------ 9ms Dec 14 22:58:11 primo volumio[3185]: info: ------------------------------ 8ms Dec 14 22:58:11 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand play Dec 14 22:58:11 primo volumio[3185]: info: Dec 14 22:58:11 primo volumio[3185]: ---------------------------- MPD announces system playlist update Dec 14 22:58:11 primo volumio[3185]: info: Ignoring MPD Status Update Dec 14 22:58:11 primo volumio[3185]: info: Dec 14 22:58:11 primo volumio[3185]: ---------------------------- MPD announces system playlist update Dec 14 22:58:11 primo volumio[3185]: info: Ignoring MPD Status Update Dec 14 22:58:11 primo kernel: aml_tdm_open Dec 14 22:58:11 primo kernel: Not init audio effects Dec 14 22:58:11 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Dec 14 22:58:11 primo kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Dec 14 22:58:11 primo kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Dec 14 22:58:11 primo kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Dec 14 22:58:11 primo kernel: asoc aml_dai_set_tdm_fmt, 0x4001, ffffffc03d2b8e18, id(1), clksel(1) Dec 14 22:58:11 primo kernel: aml_dai_set_tdm_fmt(), fmt not change Dec 14 22:58:11 primo kernel: dump_pcm_setting(ffffffc03d2b8e18) Dec 14 22:58:11 primo kernel: pcm_mode(1) Dec 14 22:58:11 primo kernel: sysclk(11289600) Dec 14 22:58:11 primo kernel: sysclk_bclk_ratio(4) Dec 14 22:58:11 primo kernel: bclk(2822400) Dec 14 22:58:11 primo kernel: bclk_lrclk_ratio(64) Dec 14 22:58:11 primo kernel: lrclk(44100) Dec 14 22:58:11 primo kernel: tx_mask(0x3) Dec 14 22:58:11 primo kernel: rx_mask(0x3) Dec 14 22:58:11 primo kernel: slots(2) Dec 14 22:58:11 primo kernel: slot_width(32) Dec 14 22:58:11 primo kernel: lane_mask_in(0x2) Dec 14 22:58:11 primo kernel: lane_mask_out(0x1) Dec 14 22:58:11 primo kernel: lane_oe_mask_in(0x0) Dec 14 22:58:11 primo kernel: lane_oe_mask_out(0x0) Dec 14 22:58:11 primo kernel: lane_lb_mask_in(0x0) Dec 14 22:58:11 primo kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Dec 14 22:58:11 primo kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Dec 14 22:58:11 primo kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Dec 14 22:58:11 primo kernel: aml_dai_set_clkdiv, div 4, clksel(1) Dec 14 22:58:11 primo kernel: aml_dai_set_bclk_ratio, select I2S mode Dec 14 22:58:11 primo kernel: aml_dai_tdm_hw_params(), enable mclk for TDM-B Dec 14 22:58:11 primo kernel: aml_tdm_prepare(), reset fddr Dec 14 22:58:11 primo kernel: spdif_a fifo ctrl, frddr:0 type:4, 24 bits, chmask 0x3, swap 0x10 Dec 14 22:58:11 primo kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Dec 14 22:58:11 primo kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Dec 14 22:58:11 primo kernel: tdm playback mute: 0, lane_cnt = 8 Dec 14 22:58:11 primo volumio[3185]: info: Dec 14 22:58:11 primo volumio[3185]: ---------------------------- MPD announces state update: player Dec 14 22:58:11 primo volumio[3185]: info: ------------------------------ 27ms Dec 14 22:58:11 primo volumio[3185]: info: sendMpdCommand play took 25 milliseconds Dec 14 22:58:11 primo volumio[3185]: info: ------------------------------ 25ms Dec 14 22:58:11 primo volumio[3185]: info: ------------------------------ 23ms Dec 14 22:58:11 primo volumio[3185]: info: ControllerMpd::getState Dec 14 22:58:11 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand status Dec 14 22:58:11 primo volumio[3185]: info: Dec 14 22:58:11 primo volumio[3185]: ---------------------------- MPD announces state update: player Dec 14 22:58:11 primo volumio[3185]: info: ControllerMpd::getState Dec 14 22:58:11 primo kernel: asoc-aml-card auge_sound: tdm playback enable Dec 14 22:58:11 primo kernel: spdif_a is set to enable Dec 14 22:58:11 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand status Dec 14 22:58:11 primo volumio[3185]: info: Dec 14 22:58:11 primo volumio[3185]: ---------------------------- MPD announces state update: player Dec 14 22:58:11 primo volumio[3185]: info: ControllerMpd::getState Dec 14 22:58:11 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand status Dec 14 22:58:11 primo volumio[3185]: info: Dec 14 22:58:11 primo volumio[3185]: ---------------------------- MPD announces state update: player Dec 14 22:58:11 primo volumio[3185]: info: ControllerMpd::getState Dec 14 22:58:11 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand status Dec 14 22:58:11 primo volumio[3185]: info: sendMpdCommand status took 8 milliseconds Dec 14 22:58:11 primo volumio[3185]: info: sendMpdCommand status took 5 milliseconds Dec 14 22:58:11 primo volumio[3185]: info: sendMpdCommand status took 5 milliseconds Dec 14 22:58:11 primo volumio[3185]: verbose: ControllerMpd::parseState Dec 14 22:58:11 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 14 22:58:11 primo volumio[3185]: verbose: ControllerMpd::parseState Dec 14 22:58:11 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 14 22:58:11 primo volumio[3185]: verbose: ControllerMpd::parseState Dec 14 22:58:11 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 14 22:58:11 primo volumio[3185]: info: Dec 14 22:58:11 primo volumio[3185]: ---------------------------- MPD announces state update: player Dec 14 22:58:11 primo volumio[3185]: info: ControllerMpd::getState Dec 14 22:58:11 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand status Dec 14 22:58:11 primo volumio[3185]: info: Dec 14 22:58:11 primo volumio[3185]: ---------------------------- MPD announces state update: player Dec 14 22:58:11 primo volumio[3185]: info: ControllerMpd::getState Dec 14 22:58:11 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand status Dec 14 22:58:11 primo volumio[3185]: info: MCU Signalled Playback Inactive Dec 14 22:58:11 primo volumio[3185]: info: sendMpdCommand status took 11 milliseconds Dec 14 22:58:11 primo volumio[3185]: info: sendMpdCommand playlistinfo took 9 milliseconds Dec 14 22:58:11 primo volumio[3185]: info: sendMpdCommand playlistinfo took 10 milliseconds Dec 14 22:58:11 primo volumio[3185]: info: sendMpdCommand playlistinfo took 9 milliseconds Dec 14 22:58:11 primo volumio[3185]: info: sendMpdCommand status took 8 milliseconds Dec 14 22:58:11 primo volumio[3185]: info: sendMpdCommand status took 7 milliseconds Dec 14 22:58:11 primo volumio[3185]: verbose: ControllerMpd::parseState Dec 14 22:58:11 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 14 22:58:11 primo volumio[3185]: verbose: ControllerMpd::parseTrackInfo Dec 14 22:58:11 primo volumio[3185]: verbose: ControllerMpd::parseTrackInfo Dec 14 22:58:11 primo volumio[3185]: verbose: ControllerMpd::parseTrackInfo Dec 14 22:58:11 primo volumio[3185]: verbose: ControllerMpd::parseState Dec 14 22:58:11 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 14 22:58:11 primo volumio[3185]: verbose: ControllerMpd::parseState Dec 14 22:58:11 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 14 22:58:11 primo volumio[3185]: info: ControllerMpd::pushState Dec 14 22:58:11 primo volumio[3185]: info: CoreCommandRouter::servicePushState Dec 14 22:58:11 primo volumio[3185]: info: CorePlayQueue::getTrack 1 Dec 14 22:58:11 primo volumio[3185]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":675,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Chicken Farm","artist":"Superflex + August Engkilde","album":"Chicken Farm","uri":"USB/MUSIKARKIV/Music/iTunes/iTunes Music/Music/Superflex + August Engkilde/Chicken Farm/08 Chicken Farm.mp3","trackType":"mp3"} Dec 14 22:58:11 primo volumio[3185]: verbose: CURRENT POSITION 1 Dec 14 22:58:11 primo volumio[3185]: info: CoreStateMachine::syncState stateService play Dec 14 22:58:11 primo volumio[3185]: info: CoreStateMachine::syncState currentStatus stop Dec 14 22:58:11 primo volumio[3185]: info: ControllerMpd::pushState Dec 14 22:58:11 primo volumio[3185]: info: CoreCommandRouter::servicePushState Dec 14 22:58:11 primo volumio[3185]: info: CorePlayQueue::getTrack 1 Dec 14 22:58:11 primo volumio[3185]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":675,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Chicken Farm","artist":"Superflex + August Engkilde","album":"Chicken Farm","uri":"USB/MUSIKARKIV/Music/iTunes/iTunes Music/Music/Superflex + August Engkilde/Chicken Farm/08 Chicken Farm.mp3","trackType":"mp3"} Dec 14 22:58:11 primo volumio[3185]: verbose: CURRENT POSITION 1 Dec 14 22:58:11 primo volumio[3185]: info: CoreStateMachine::syncState stateService play Dec 14 22:58:11 primo volumio[3185]: info: CoreStateMachine::syncState currentStatus play Dec 14 22:58:11 primo volumio[3185]: info: Received an update from plugin. extracting info from payload Dec 14 22:58:11 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:11 primo volumio[3185]: info: CorePlayQueue::getTrack 1 Dec 14 22:58:11 primo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 22:58:11 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:11 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:11 primo volumio[3185]: info: CorePlayQueue::getTrack 1 Dec 14 22:58:11 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:11 primo volumio[3185]: info: CorePlayQueue::getTrack 1 Dec 14 22:58:11 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:11 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:11 primo volumio[3185]: info: CorePlayQueue::getTrack 1 Dec 14 22:58:11 primo volumio[3185]: info: ControllerMpd::pushState Dec 14 22:58:11 primo volumio[3185]: info: CoreCommandRouter::servicePushState Dec 14 22:58:11 primo volumio[3185]: info: CorePlayQueue::getTrack 1 Dec 14 22:58:11 primo volumio[3185]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":675,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Chicken Farm","artist":"Superflex + August Engkilde","album":"Chicken Farm","uri":"USB/MUSIKARKIV/Music/iTunes/iTunes Music/Music/Superflex + August Engkilde/Chicken Farm/08 Chicken Farm.mp3","trackType":"mp3"} Dec 14 22:58:11 primo volumio[3185]: verbose: CURRENT POSITION 1 Dec 14 22:58:11 primo volumio[3185]: info: CoreStateMachine::syncState stateService play Dec 14 22:58:11 primo volumio[3185]: info: CoreStateMachine::syncState currentStatus play Dec 14 22:58:11 primo volumio[3185]: info: Received an update from plugin. extracting info from payload Dec 14 22:58:11 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:11 primo volumio[3185]: info: CorePlayQueue::getTrack 1 Dec 14 22:58:11 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:11 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:11 primo volumio[3185]: info: CorePlayQueue::getTrack 1 Dec 14 22:58:11 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:11 primo volumio[3185]: info: CorePlayQueue::getTrack 1 Dec 14 22:58:11 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:11 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:11 primo volumio[3185]: info: CorePlayQueue::getTrack 1 Dec 14 22:58:11 primo volumio[3185]: info: ------------------------------ 45ms Dec 14 22:58:11 primo volumio[3185]: info: ------------------------------ 49ms Dec 14 22:58:11 primo volumio[3185]: info: ------------------------------ 47ms Dec 14 22:58:11 primo volumio[3185]: info: sendMpdCommand playlistinfo took 39 milliseconds Dec 14 22:58:11 primo volumio[3185]: info: sendMpdCommand playlistinfo took 38 milliseconds Dec 14 22:58:11 primo volumio[3185]: info: sendMpdCommand playlistinfo took 37 milliseconds Dec 14 22:58:11 primo volumio[3185]: verbose: ControllerMpd::parseTrackInfo Dec 14 22:58:11 primo volumio[3185]: verbose: ControllerMpd::parseTrackInfo Dec 14 22:58:11 primo volumio[3185]: verbose: ControllerMpd::parseTrackInfo Dec 14 22:58:11 primo volumio[3185]: info: ControllerMpd::pushState Dec 14 22:58:11 primo volumio[3185]: info: CoreCommandRouter::servicePushState Dec 14 22:58:11 primo volumio[3185]: info: CorePlayQueue::getTrack 1 Dec 14 22:58:11 primo volumio[3185]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":675,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Chicken Farm","artist":"Superflex + August Engkilde","album":"Chicken Farm","uri":"USB/MUSIKARKIV/Music/iTunes/iTunes Music/Music/Superflex + August Engkilde/Chicken Farm/08 Chicken Farm.mp3","trackType":"mp3"} Dec 14 22:58:11 primo volumio[3185]: verbose: CURRENT POSITION 1 Dec 14 22:58:11 primo volumio[3185]: info: CoreStateMachine::syncState stateService play Dec 14 22:58:11 primo volumio[3185]: info: CoreStateMachine::syncState currentStatus play Dec 14 22:58:11 primo volumio[3185]: info: Received an update from plugin. extracting info from payload Dec 14 22:58:11 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:11 primo volumio[3185]: info: CorePlayQueue::getTrack 1 Dec 14 22:58:11 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:11 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:11 primo volumio[3185]: info: CorePlayQueue::getTrack 1 Dec 14 22:58:11 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:11 primo volumio[3185]: info: CorePlayQueue::getTrack 1 Dec 14 22:58:11 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:11 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:11 primo volumio[3185]: info: CorePlayQueue::getTrack 1 Dec 14 22:58:11 primo volumio[3185]: info: ControllerMpd::pushState Dec 14 22:58:11 primo volumio[3185]: info: CoreCommandRouter::servicePushState Dec 14 22:58:11 primo volumio[3185]: info: CorePlayQueue::getTrack 1 Dec 14 22:58:11 primo volumio[3185]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":675,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Chicken Farm","artist":"Superflex + August Engkilde","album":"Chicken Farm","uri":"USB/MUSIKARKIV/Music/iTunes/iTunes Music/Music/Superflex + August Engkilde/Chicken Farm/08 Chicken Farm.mp3","trackType":"mp3"} Dec 14 22:58:11 primo volumio[3185]: verbose: CURRENT POSITION 1 Dec 14 22:58:11 primo volumio[3185]: info: CoreStateMachine::syncState stateService play Dec 14 22:58:11 primo volumio[3185]: info: CoreStateMachine::syncState currentStatus play Dec 14 22:58:11 primo volumio[3185]: info: Received an update from plugin. extracting info from payload Dec 14 22:58:11 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:11 primo volumio[3185]: info: CorePlayQueue::getTrack 1 Dec 14 22:58:11 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:11 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:11 primo volumio[3185]: info: CorePlayQueue::getTrack 1 Dec 14 22:58:11 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:11 primo volumio[3185]: info: CorePlayQueue::getTrack 1 Dec 14 22:58:11 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:11 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:11 primo volumio[3185]: info: CorePlayQueue::getTrack 1 Dec 14 22:58:11 primo volumio[3185]: info: ControllerMpd::pushState Dec 14 22:58:11 primo volumio[3185]: info: CoreCommandRouter::servicePushState Dec 14 22:58:11 primo volumio[3185]: info: CorePlayQueue::getTrack 1 Dec 14 22:58:11 primo volumio[3185]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":675,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Chicken Farm","artist":"Superflex + August Engkilde","album":"Chicken Farm","uri":"USB/MUSIKARKIV/Music/iTunes/iTunes Music/Music/Superflex + August Engkilde/Chicken Farm/08 Chicken Farm.mp3","trackType":"mp3"} Dec 14 22:58:11 primo volumio[3185]: verbose: CURRENT POSITION 1 Dec 14 22:58:11 primo volumio[3185]: info: CoreStateMachine::syncState stateService play Dec 14 22:58:11 primo volumio[3185]: info: CoreStateMachine::syncState currentStatus play Dec 14 22:58:11 primo volumio[3185]: info: Received an update from plugin. extracting info from payload Dec 14 22:58:11 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:11 primo volumio[3185]: info: CorePlayQueue::getTrack 1 Dec 14 22:58:11 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:11 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:11 primo volumio[3185]: info: CorePlayQueue::getTrack 1 Dec 14 22:58:11 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:11 primo volumio[3185]: info: CorePlayQueue::getTrack 1 Dec 14 22:58:11 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:11 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:11 primo volumio[3185]: info: CorePlayQueue::getTrack 1 Dec 14 22:58:11 primo volumio[3185]: info: ------------------------------ 90ms Dec 14 22:58:11 primo volumio[3185]: info: ------------------------------ 85ms Dec 14 22:58:11 primo volumio[3185]: info: ------------------------------ 84ms Dec 14 22:58:11 primo volumio[3185]: info: Signalling Playback active due to playback status change Dec 14 22:58:11 primo volumio[3185]: info: Signalling Playback active due to playback status change Dec 14 22:58:11 primo volumio[3185]: info: Signalling Playback active due to playback status change Dec 14 22:58:11 primo volumio[3185]: info: Signalling Playback active due to playback status change Dec 14 22:58:11 primo volumio[3185]: info: Signalling Playback active due to playback status change Dec 14 22:58:11 primo volumio[3185]: info: Signalling Playback active due to playback status change Dec 14 22:58:11 primo volumio[3185]: info: Signalling Playback active due to playback status change Dec 14 22:58:11 primo volumio[3185]: info: Signalling Playback active due to playback status change Dec 14 22:58:11 primo volumio[3185]: info: Signalling Playback active due to playback status change Dec 14 22:58:11 primo volumio[3185]: info: Signalling Playback active due to playback status change Dec 14 22:58:11 primo volumio[3185]: info: MCU Signalled Playback Active Dec 14 22:58:12 primo volumio[3185]: info: CoreCommandRouter::volumioNext Dec 14 22:58:12 primo volumio[3185]: info: CoreStateMachine::next Dec 14 22:58:12 primo volumio[3185]: info: CoreStateMachine::stop Dec 14 22:58:12 primo volumio[3185]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 14 22:58:12 primo volumio[3185]: info: CoreStateMachine::stPlaybackTimer Dec 14 22:58:12 primo volumio[3185]: info: CoreStateMachine::updateTrackBlock Dec 14 22:58:12 primo volumio[3185]: info: CorePlayQueue::getTrackBlock Dec 14 22:58:12 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:12 primo volumio[3185]: info: CorePlayQueue::getTrack 1 Dec 14 22:58:12 primo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 22:58:12 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:12 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:12 primo volumio[3185]: info: CorePlayQueue::getTrack 1 Dec 14 22:58:12 primo volumio[3185]: info: CoreStateMachine::serviceStop Dec 14 22:58:12 primo volumio[3185]: info: CorePlayQueue::getTrack 1 Dec 14 22:58:12 primo volumio[3185]: info: CoreCommandRouter::serviceStop Dec 14 22:58:12 primo volumio[3185]: info: ControllerMpd::stop Dec 14 22:58:12 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand stop Dec 14 22:58:12 primo kernel: asoc-aml-card auge_sound: tdm playback stop Dec 14 22:58:12 primo kernel: spdif_a is set to disable Dec 14 22:58:12 primo kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Dec 14 22:58:12 primo kernel: aml_dai_tdm_hw_free(), disable mclk for TDM-B Dec 14 22:58:12 primo kernel: tdm playback mute: 1, lane_cnt = 8 Dec 14 22:58:12 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Dec 14 22:58:12 primo volumio[3185]: info: Dec 14 22:58:12 primo volumio[3185]: ---------------------------- MPD announces state update: player Dec 14 22:58:12 primo volumio[3185]: info: sendMpdCommand stop took 18 milliseconds Dec 14 22:58:12 primo volumio[3185]: info: ControllerMpd::getState Dec 14 22:58:12 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand status Dec 14 22:58:12 primo volumio[3185]: info: CoreStateMachine::play index undefined Dec 14 22:58:12 primo volumio[3185]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 14 22:58:12 primo volumio[3185]: info: CorePlayQueue::getTrack 2 Dec 14 22:58:12 primo volumio[3185]: info: CoreStateMachine::startPlaybackTimer Dec 14 22:58:12 primo volumio[3185]: info: CorePlayQueue::getTrack 2 Dec 14 22:58:12 primo volumio[3185]: verbose: ControllerMpd::clearAddPlayTracks USB/MUSIKARKIV/Music/iTunes/iTunes Music/Music/Superflex Music/Frogmusic for sauna/frogmusic 01.mp3 Dec 14 22:58:12 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand stop Dec 14 22:58:12 primo volumio[3185]: info: CoreStateMachine::updateTrackBlock Dec 14 22:58:12 primo volumio[3185]: info: CorePlayQueue::getTrackBlock Dec 14 22:58:12 primo volumio[3185]: info: Dec 14 22:58:12 primo volumio[3185]: ---------------------------- MPD announces state update: player Dec 14 22:58:12 primo volumio[3185]: info: ControllerMpd::getState Dec 14 22:58:12 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand status Dec 14 22:58:12 primo volumio[3185]: info: Dec 14 22:58:12 primo volumio[3185]: ---------------------------- MPD announces state update: player Dec 14 22:58:12 primo volumio[3185]: info: ControllerMpd::getState Dec 14 22:58:12 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand status Dec 14 22:58:12 primo volumio[3185]: info: sendMpdCommand status took 10 milliseconds Dec 14 22:58:12 primo volumio[3185]: info: sendMpdCommand stop took 9 milliseconds Dec 14 22:58:12 primo volumio[3185]: info: sendMpdCommand status took 4 milliseconds Dec 14 22:58:12 primo volumio[3185]: info: sendMpdCommand status took 2 milliseconds Dec 14 22:58:12 primo volumio[3185]: verbose: ControllerMpd::parseState Dec 14 22:58:12 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 14 22:58:12 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand clear Dec 14 22:58:12 primo volumio[3185]: verbose: ControllerMpd::parseState Dec 14 22:58:12 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 14 22:58:12 primo volumio[3185]: verbose: ControllerMpd::parseState Dec 14 22:58:12 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 14 22:58:12 primo volumio[3185]: info: Dec 14 22:58:12 primo volumio[3185]: ---------------------------- MPD announces system playlist update Dec 14 22:58:12 primo volumio[3185]: info: Ignoring MPD Status Update Dec 14 22:58:12 primo volumio[3185]: info: sendMpdCommand playlistinfo took 9 milliseconds Dec 14 22:58:12 primo volumio[3185]: info: sendMpdCommand clear took 9 milliseconds Dec 14 22:58:12 primo volumio[3185]: info: sendMpdCommand playlistinfo took 10 milliseconds Dec 14 22:58:12 primo volumio[3185]: info: sendMpdCommand playlistinfo took 9 milliseconds Dec 14 22:58:12 primo volumio[3185]: verbose: ControllerMpd::parseTrackInfo Dec 14 22:58:12 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand add "USB/MUSIKARKIV/Music/iTunes/iTunes Music/Music/Superflex Music/Frogmusic for sauna/frogmusic 01.mp3" Dec 14 22:58:12 primo volumio[3185]: verbose: ControllerMpd::parseTrackInfo Dec 14 22:58:12 primo volumio[3185]: verbose: ControllerMpd::parseTrackInfo Dec 14 22:58:12 primo volumio[3185]: info: ControllerMpd::pushState Dec 14 22:58:12 primo volumio[3185]: info: CoreCommandRouter::servicePushState Dec 14 22:58:12 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:12 primo volumio[3185]: info: CorePlayQueue::getTrack 2 Dec 14 22:58:12 primo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 22:58:12 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:12 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:12 primo volumio[3185]: info: CorePlayQueue::getTrack 2 Dec 14 22:58:12 primo volumio[3185]: info: CorePlayQueue::getTrack 2 Dec 14 22:58:12 primo volumio[3185]: 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":"Chicken Farm","artist":"Superflex + August Engkilde","album":"Chicken Farm","uri":"USB/MUSIKARKIV/Music/iTunes/iTunes Music/Music/Superflex + August Engkilde/Chicken Farm/08 Chicken Farm.mp3","trackType":"mp3"} Dec 14 22:58:12 primo volumio[3185]: verbose: CURRENT POSITION 2 Dec 14 22:58:12 primo volumio[3185]: info: CoreStateMachine::syncState stateService stop Dec 14 22:58:12 primo volumio[3185]: info: CoreStateMachine::syncState currentStatus stop Dec 14 22:58:12 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:12 primo volumio[3185]: info: CorePlayQueue::getTrack 2 Dec 14 22:58:12 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:12 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:12 primo volumio[3185]: info: CorePlayQueue::getTrack 2 Dec 14 22:58:12 primo volumio[3185]: info: No code Dec 14 22:58:12 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:12 primo volumio[3185]: info: CorePlayQueue::getTrack 2 Dec 14 22:58:12 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:12 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:12 primo volumio[3185]: info: CorePlayQueue::getTrack 2 Dec 14 22:58:12 primo volumio[3185]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Dec 14 22:58:12 primo volumio[3185]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Dec 14 22:58:12 primo volumio[3185]: info: ------------------------------ 42ms Dec 14 22:58:12 primo volumio[3185]: info: ------------------------------ 35ms Dec 14 22:58:12 primo volumio[3185]: info: ------------------------------ 34ms Dec 14 22:58:12 primo volumio[3185]: info: Dec 14 22:58:12 primo volumio[3185]: ---------------------------- MPD announces system playlist update Dec 14 22:58:12 primo volumio[3185]: info: Ignoring MPD Status Update Dec 14 22:58:12 primo volumio[3185]: info: Dec 14 22:58:12 primo volumio[3185]: ---------------------------- MPD announces system playlist update Dec 14 22:58:12 primo volumio[3185]: info: Ignoring MPD Status Update Dec 14 22:58:12 primo volumio[3185]: error: updateQueue error: null Dec 14 22:58:12 primo volumio[3185]: info: Dec 14 22:58:12 primo volumio[3185]: ---------------------------- MPD announces system playlist update Dec 14 22:58:12 primo volumio[3185]: info: Ignoring MPD Status Update Dec 14 22:58:12 primo volumio[3185]: info: ------------------------------ 27ms Dec 14 22:58:12 primo volumio[3185]: info: sendMpdCommand add "USB/MUSIKARKIV/Music/iTunes/iTunes Music/Music/Superflex Music/Frogmusic for sauna/frogmusic 01.mp3" took 25 milliseconds Dec 14 22:58:12 primo volumio[3185]: info: ------------------------------ 5ms Dec 14 22:58:12 primo volumio[3185]: info: ------------------------------ 4ms Dec 14 22:58:12 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand play Dec 14 22:58:12 primo volumio[3185]: info: Dec 14 22:58:12 primo volumio[3185]: ---------------------------- MPD announces system playlist update Dec 14 22:58:12 primo volumio[3185]: info: Ignoring MPD Status Update Dec 14 22:58:12 primo volumio[3185]: info: Dec 14 22:58:12 primo volumio[3185]: ---------------------------- MPD announces system playlist update Dec 14 22:58:12 primo volumio[3185]: info: Ignoring MPD Status Update Dec 14 22:58:12 primo kernel: aml_tdm_open Dec 14 22:58:12 primo kernel: Not init audio effects Dec 14 22:58:12 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Dec 14 22:58:12 primo kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Dec 14 22:58:12 primo kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Dec 14 22:58:12 primo kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Dec 14 22:58:12 primo kernel: asoc aml_dai_set_tdm_fmt, 0x4001, ffffffc03d2b8e18, id(1), clksel(1) Dec 14 22:58:12 primo kernel: aml_dai_set_tdm_fmt(), fmt not change Dec 14 22:58:12 primo kernel: dump_pcm_setting(ffffffc03d2b8e18) Dec 14 22:58:12 primo kernel: pcm_mode(1) Dec 14 22:58:12 primo kernel: sysclk(11289600) Dec 14 22:58:12 primo kernel: sysclk_bclk_ratio(4) Dec 14 22:58:12 primo kernel: bclk(2822400) Dec 14 22:58:12 primo kernel: bclk_lrclk_ratio(64) Dec 14 22:58:12 primo kernel: lrclk(44100) Dec 14 22:58:12 primo kernel: tx_mask(0x3) Dec 14 22:58:12 primo kernel: rx_mask(0x3) Dec 14 22:58:12 primo kernel: slots(2) Dec 14 22:58:12 primo kernel: slot_width(32) Dec 14 22:58:12 primo kernel: lane_mask_in(0x2) Dec 14 22:58:12 primo kernel: lane_mask_out(0x1) Dec 14 22:58:12 primo kernel: lane_oe_mask_in(0x0) Dec 14 22:58:12 primo kernel: lane_oe_mask_out(0x0) Dec 14 22:58:12 primo kernel: lane_lb_mask_in(0x0) Dec 14 22:58:12 primo kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Dec 14 22:58:12 primo kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Dec 14 22:58:12 primo kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Dec 14 22:58:12 primo kernel: aml_dai_set_clkdiv, div 4, clksel(1) Dec 14 22:58:12 primo kernel: aml_dai_set_bclk_ratio, select I2S mode Dec 14 22:58:12 primo kernel: aml_dai_tdm_hw_params(), enable mclk for TDM-B Dec 14 22:58:12 primo kernel: aml_tdm_prepare(), reset fddr Dec 14 22:58:12 primo kernel: spdif_a fifo ctrl, frddr:0 type:4, 24 bits, chmask 0x3, swap 0x10 Dec 14 22:58:12 primo kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Dec 14 22:58:12 primo kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Dec 14 22:58:12 primo kernel: tdm playback mute: 0, lane_cnt = 8 Dec 14 22:58:12 primo volumio[3185]: info: Dec 14 22:58:12 primo volumio[3185]: ---------------------------- MPD announces state update: player Dec 14 22:58:12 primo volumio[3185]: info: Dec 14 22:58:12 primo volumio[3185]: ---------------------------- MPD announces state update: player Dec 14 22:58:12 primo volumio[3185]: info: ------------------------------ 16ms Dec 14 22:58:12 primo volumio[3185]: info: sendMpdCommand play took 14 milliseconds Dec 14 22:58:12 primo volumio[3185]: info: ControllerMpd::getState Dec 14 22:58:12 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand status Dec 14 22:58:12 primo volumio[3185]: info: ------------------------------ 14ms Dec 14 22:58:12 primo volumio[3185]: info: ControllerMpd::getState Dec 14 22:58:12 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand status Dec 14 22:58:12 primo volumio[3185]: info: ------------------------------ 13ms Dec 14 22:58:12 primo volumio[3185]: info: Dec 14 22:58:12 primo volumio[3185]: ---------------------------- MPD announces state update: player Dec 14 22:58:12 primo volumio[3185]: info: ControllerMpd::getState Dec 14 22:58:12 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand status Dec 14 22:58:12 primo volumio[3185]: info: Dec 14 22:58:12 primo volumio[3185]: ---------------------------- MPD announces state update: player Dec 14 22:58:12 primo volumio[3185]: info: ControllerMpd::getState Dec 14 22:58:12 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand status Dec 14 22:58:12 primo kernel: asoc-aml-card auge_sound: tdm playback enable Dec 14 22:58:12 primo kernel: spdif_a is set to enable Dec 14 22:58:12 primo volumio[3185]: info: sendMpdCommand status took 18 milliseconds Dec 14 22:58:12 primo volumio[3185]: info: sendMpdCommand status took 19 milliseconds Dec 14 22:58:12 primo volumio[3185]: info: sendMpdCommand status took 17 milliseconds Dec 14 22:58:12 primo volumio[3185]: info: sendMpdCommand status took 14 milliseconds Dec 14 22:58:12 primo volumio[3185]: verbose: ControllerMpd::parseState Dec 14 22:58:12 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 14 22:58:12 primo volumio[3185]: verbose: ControllerMpd::parseState Dec 14 22:58:12 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 14 22:58:12 primo volumio[3185]: verbose: ControllerMpd::parseState Dec 14 22:58:12 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 14 22:58:12 primo volumio[3185]: verbose: ControllerMpd::parseState Dec 14 22:58:12 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 14 22:58:12 primo volumio[3185]: info: Dec 14 22:58:12 primo volumio[3185]: ---------------------------- MPD announces state update: player Dec 14 22:58:12 primo volumio[3185]: info: ControllerMpd::getState Dec 14 22:58:12 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand status Dec 14 22:58:12 primo volumio[3185]: info: sendMpdCommand playlistinfo took 8 milliseconds Dec 14 22:58:12 primo volumio[3185]: info: sendMpdCommand playlistinfo took 9 milliseconds Dec 14 22:58:12 primo volumio[3185]: info: sendMpdCommand playlistinfo took 8 milliseconds Dec 14 22:58:12 primo volumio[3185]: info: sendMpdCommand playlistinfo took 8 milliseconds Dec 14 22:58:12 primo volumio[3185]: info: sendMpdCommand status took 6 milliseconds Dec 14 22:58:12 primo volumio[3185]: verbose: ControllerMpd::parseTrackInfo Dec 14 22:58:12 primo volumio[3185]: verbose: ControllerMpd::parseTrackInfo Dec 14 22:58:12 primo volumio[3185]: verbose: ControllerMpd::parseTrackInfo Dec 14 22:58:12 primo volumio[3185]: verbose: ControllerMpd::parseTrackInfo Dec 14 22:58:12 primo volumio[3185]: verbose: ControllerMpd::parseState Dec 14 22:58:12 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 14 22:58:12 primo volumio[3185]: info: ControllerMpd::pushState Dec 14 22:58:12 primo volumio[3185]: info: CoreCommandRouter::servicePushState Dec 14 22:58:12 primo volumio[3185]: info: CorePlayQueue::getTrack 2 Dec 14 22:58:12 primo volumio[3185]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":990,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"frogmusic 01","artist":"Superflex Music","album":"Frogmusic for sauna","uri":"USB/MUSIKARKIV/Music/iTunes/iTunes Music/Music/Superflex Music/Frogmusic for sauna/frogmusic 01.mp3","trackType":"mp3"} Dec 14 22:58:12 primo volumio[3185]: verbose: CURRENT POSITION 2 Dec 14 22:58:12 primo volumio[3185]: info: CoreStateMachine::syncState stateService play Dec 14 22:58:12 primo volumio[3185]: info: CoreStateMachine::syncState currentStatus stop Dec 14 22:58:12 primo volumio[3185]: info: ControllerMpd::pushState Dec 14 22:58:12 primo volumio[3185]: info: CoreCommandRouter::servicePushState Dec 14 22:58:12 primo volumio[3185]: info: CorePlayQueue::getTrack 2 Dec 14 22:58:12 primo volumio[3185]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":990,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"frogmusic 01","artist":"Superflex Music","album":"Frogmusic for sauna","uri":"USB/MUSIKARKIV/Music/iTunes/iTunes Music/Music/Superflex Music/Frogmusic for sauna/frogmusic 01.mp3","trackType":"mp3"} Dec 14 22:58:12 primo volumio[3185]: verbose: CURRENT POSITION 2 Dec 14 22:58:12 primo volumio[3185]: info: CoreStateMachine::syncState stateService play Dec 14 22:58:12 primo volumio[3185]: info: CoreStateMachine::syncState currentStatus play Dec 14 22:58:12 primo volumio[3185]: info: Received an update from plugin. extracting info from payload Dec 14 22:58:12 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:12 primo volumio[3185]: info: CorePlayQueue::getTrack 2 Dec 14 22:58:12 primo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 22:58:12 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:12 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:12 primo volumio[3185]: info: CorePlayQueue::getTrack 2 Dec 14 22:58:12 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:12 primo volumio[3185]: info: CorePlayQueue::getTrack 2 Dec 14 22:58:12 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:12 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:12 primo volumio[3185]: info: CorePlayQueue::getTrack 2 Dec 14 22:58:12 primo volumio[3185]: info: ControllerMpd::pushState Dec 14 22:58:12 primo volumio[3185]: info: CoreCommandRouter::servicePushState Dec 14 22:58:12 primo volumio[3185]: info: CorePlayQueue::getTrack 2 Dec 14 22:58:12 primo volumio[3185]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":990,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"frogmusic 01","artist":"Superflex Music","album":"Frogmusic for sauna","uri":"USB/MUSIKARKIV/Music/iTunes/iTunes Music/Music/Superflex Music/Frogmusic for sauna/frogmusic 01.mp3","trackType":"mp3"} Dec 14 22:58:12 primo volumio[3185]: verbose: CURRENT POSITION 2 Dec 14 22:58:12 primo volumio[3185]: info: CoreStateMachine::syncState stateService play Dec 14 22:58:12 primo volumio[3185]: info: CoreStateMachine::syncState currentStatus play Dec 14 22:58:12 primo volumio[3185]: info: Received an update from plugin. extracting info from payload Dec 14 22:58:12 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:12 primo volumio[3185]: info: CorePlayQueue::getTrack 2 Dec 14 22:58:12 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:12 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:12 primo volumio[3185]: info: CorePlayQueue::getTrack 2 Dec 14 22:58:12 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:12 primo volumio[3185]: info: CorePlayQueue::getTrack 2 Dec 14 22:58:12 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:12 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:12 primo volumio[3185]: info: CorePlayQueue::getTrack 2 Dec 14 22:58:12 primo volumio[3185]: info: ControllerMpd::pushState Dec 14 22:58:12 primo volumio[3185]: info: CoreCommandRouter::servicePushState Dec 14 22:58:12 primo volumio[3185]: info: CorePlayQueue::getTrack 2 Dec 14 22:58:12 primo volumio[3185]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":990,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"frogmusic 01","artist":"Superflex Music","album":"Frogmusic for sauna","uri":"USB/MUSIKARKIV/Music/iTunes/iTunes Music/Music/Superflex Music/Frogmusic for sauna/frogmusic 01.mp3","trackType":"mp3"} Dec 14 22:58:12 primo volumio[3185]: verbose: CURRENT POSITION 2 Dec 14 22:58:12 primo volumio[3185]: info: CoreStateMachine::syncState stateService play Dec 14 22:58:12 primo volumio[3185]: info: CoreStateMachine::syncState currentStatus play Dec 14 22:58:12 primo volumio[3185]: info: Received an update from plugin. extracting info from payload Dec 14 22:58:12 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:12 primo volumio[3185]: info: CorePlayQueue::getTrack 2 Dec 14 22:58:12 primo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 22:58:12 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:12 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:12 primo volumio[3185]: info: CorePlayQueue::getTrack 2 Dec 14 22:58:12 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:12 primo volumio[3185]: info: CorePlayQueue::getTrack 2 Dec 14 22:58:12 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:12 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:12 primo volumio[3185]: info: CorePlayQueue::getTrack 2 Dec 14 22:58:12 primo volumio[3185]: info: ------------------------------ 56ms Dec 14 22:58:12 primo volumio[3185]: info: ------------------------------ 67ms Dec 14 22:58:12 primo volumio[3185]: info: ------------------------------ 65ms Dec 14 22:58:12 primo volumio[3185]: info: ------------------------------ 64ms Dec 14 22:58:12 primo volumio[3185]: info: sendMpdCommand playlistinfo took 37 milliseconds Dec 14 22:58:12 primo volumio[3185]: verbose: ControllerMpd::parseTrackInfo Dec 14 22:58:12 primo volumio[3185]: info: ControllerMpd::pushState Dec 14 22:58:12 primo volumio[3185]: info: CoreCommandRouter::servicePushState Dec 14 22:58:12 primo volumio[3185]: info: CorePlayQueue::getTrack 2 Dec 14 22:58:12 primo volumio[3185]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":990,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"frogmusic 01","artist":"Superflex Music","album":"Frogmusic for sauna","uri":"USB/MUSIKARKIV/Music/iTunes/iTunes Music/Music/Superflex Music/Frogmusic for sauna/frogmusic 01.mp3","trackType":"mp3"} Dec 14 22:58:12 primo volumio[3185]: verbose: CURRENT POSITION 2 Dec 14 22:58:12 primo volumio[3185]: info: CoreStateMachine::syncState stateService play Dec 14 22:58:12 primo volumio[3185]: info: CoreStateMachine::syncState currentStatus play Dec 14 22:58:12 primo volumio[3185]: info: Received an update from plugin. extracting info from payload Dec 14 22:58:12 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:12 primo volumio[3185]: info: CorePlayQueue::getTrack 2 Dec 14 22:58:12 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:12 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:12 primo volumio[3185]: info: CorePlayQueue::getTrack 2 Dec 14 22:58:12 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:12 primo volumio[3185]: info: CorePlayQueue::getTrack 2 Dec 14 22:58:12 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:12 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:12 primo volumio[3185]: info: CorePlayQueue::getTrack 2 Dec 14 22:58:12 primo volumio[3185]: info: ------------------------------ 59ms Dec 14 22:58:12 primo volumio[3185]: info: Signalling Playback active due to playback status change Dec 14 22:58:12 primo volumio[3185]: info: Signalling Playback active due to playback status change Dec 14 22:58:12 primo volumio[3185]: info: Signalling Playback active due to playback status change Dec 14 22:58:12 primo volumio[3185]: info: Signalling Playback active due to playback status change Dec 14 22:58:12 primo volumio[3185]: info: Signalling Playback active due to playback status change Dec 14 22:58:12 primo volumio[3185]: info: Signalling Playback active due to playback status change Dec 14 22:58:12 primo volumio[3185]: info: Signalling Playback active due to playback status change Dec 14 22:58:12 primo volumio[3185]: info: Signalling Playback active due to playback status change Dec 14 22:58:12 primo volumio[3185]: info: MCU Signalled Playback Inactive Dec 14 22:58:13 primo volumio[3185]: info: MCU Signalled Playback Active Dec 14 22:58:14 primo volumio[3185]: info: CoreCommandRouter::volumioNext Dec 14 22:58:14 primo volumio[3185]: info: CoreStateMachine::next Dec 14 22:58:14 primo volumio[3185]: info: CoreStateMachine::stop Dec 14 22:58:14 primo volumio[3185]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 14 22:58:14 primo volumio[3185]: info: CoreStateMachine::stPlaybackTimer Dec 14 22:58:14 primo volumio[3185]: info: CoreStateMachine::updateTrackBlock Dec 14 22:58:14 primo volumio[3185]: info: CorePlayQueue::getTrackBlock Dec 14 22:58:14 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:14 primo volumio[3185]: info: CorePlayQueue::getTrack 2 Dec 14 22:58:14 primo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 22:58:14 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:14 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:14 primo volumio[3185]: info: CorePlayQueue::getTrack 2 Dec 14 22:58:14 primo volumio[3185]: info: CoreStateMachine::serviceStop Dec 14 22:58:14 primo volumio[3185]: info: CorePlayQueue::getTrack 2 Dec 14 22:58:14 primo volumio[3185]: info: CoreCommandRouter::serviceStop Dec 14 22:58:14 primo volumio[3185]: info: ControllerMpd::stop Dec 14 22:58:14 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand stop Dec 14 22:58:14 primo kernel: asoc-aml-card auge_sound: tdm playback stop Dec 14 22:58:14 primo kernel: spdif_a is set to disable Dec 14 22:58:14 primo kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Dec 14 22:58:14 primo kernel: aml_dai_tdm_hw_free(), disable mclk for TDM-B Dec 14 22:58:14 primo kernel: tdm playback mute: 1, lane_cnt = 8 Dec 14 22:58:14 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Dec 14 22:58:14 primo volumio[3185]: info: Dec 14 22:58:14 primo volumio[3185]: ---------------------------- MPD announces state update: player Dec 14 22:58:14 primo volumio[3185]: info: sendMpdCommand stop took 15 milliseconds Dec 14 22:58:14 primo volumio[3185]: info: ControllerMpd::getState Dec 14 22:58:14 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand status Dec 14 22:58:14 primo volumio[3185]: info: CoreStateMachine::play index undefined Dec 14 22:58:14 primo volumio[3185]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 14 22:58:14 primo volumio[3185]: info: CorePlayQueue::getTrack 3 Dec 14 22:58:14 primo volumio[3185]: info: CoreStateMachine::startPlaybackTimer Dec 14 22:58:14 primo volumio[3185]: info: CorePlayQueue::getTrack 3 Dec 14 22:58:14 primo volumio[3185]: verbose: ControllerMpd::clearAddPlayTracks USB/MUSIKARKIV/Music/iTunes/iTunes Music/Music/Superflex Music/Hello DJ/01 Hello DJ 01.mp3 Dec 14 22:58:14 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand stop Dec 14 22:58:14 primo volumio[3185]: info: CoreStateMachine::updateTrackBlock Dec 14 22:58:14 primo volumio[3185]: info: CorePlayQueue::getTrackBlock Dec 14 22:58:14 primo volumio[3185]: info: sendMpdCommand status took 3 milliseconds Dec 14 22:58:14 primo volumio[3185]: info: sendMpdCommand stop took 2 milliseconds Dec 14 22:58:14 primo volumio[3185]: verbose: ControllerMpd::parseState Dec 14 22:58:14 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 14 22:58:14 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand clear Dec 14 22:58:14 primo volumio[3185]: info: Dec 14 22:58:14 primo volumio[3185]: ---------------------------- MPD announces state update: player Dec 14 22:58:14 primo volumio[3185]: info: ControllerMpd::getState Dec 14 22:58:14 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand status Dec 14 22:58:14 primo volumio[3185]: info: Dec 14 22:58:14 primo volumio[3185]: ---------------------------- MPD announces state update: player Dec 14 22:58:14 primo volumio[3185]: info: ControllerMpd::getState Dec 14 22:58:14 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand status Dec 14 22:58:14 primo volumio[3185]: info: Dec 14 22:58:14 primo volumio[3185]: ---------------------------- MPD announces system playlist update Dec 14 22:58:14 primo volumio[3185]: info: Ignoring MPD Status Update Dec 14 22:58:14 primo volumio[3185]: info: sendMpdCommand playlistinfo took 7 milliseconds Dec 14 22:58:14 primo volumio[3185]: info: sendMpdCommand clear took 6 milliseconds Dec 14 22:58:14 primo volumio[3185]: info: sendMpdCommand status took 4 milliseconds Dec 14 22:58:14 primo volumio[3185]: info: sendMpdCommand status took 4 milliseconds Dec 14 22:58:14 primo volumio[3185]: verbose: ControllerMpd::parseTrackInfo Dec 14 22:58:14 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand add "USB/MUSIKARKIV/Music/iTunes/iTunes Music/Music/Superflex Music/Hello DJ/01 Hello DJ 01.mp3" Dec 14 22:58:14 primo volumio[3185]: verbose: ControllerMpd::parseState Dec 14 22:58:14 primo volumio[3185]: verbose: ControllerMpd::parseState Dec 14 22:58:14 primo volumio[3185]: info: ControllerMpd::pushState Dec 14 22:58:14 primo volumio[3185]: info: CoreCommandRouter::servicePushState Dec 14 22:58:14 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:14 primo volumio[3185]: info: CorePlayQueue::getTrack 3 Dec 14 22:58:14 primo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 22:58:14 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:14 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:14 primo volumio[3185]: info: CorePlayQueue::getTrack 3 Dec 14 22:58:14 primo volumio[3185]: info: CorePlayQueue::getTrack 3 Dec 14 22:58:14 primo volumio[3185]: 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":"frogmusic 01","artist":"Superflex Music","album":"Frogmusic for sauna","uri":"USB/MUSIKARKIV/Music/iTunes/iTunes Music/Music/Superflex Music/Frogmusic for sauna/frogmusic 01.mp3","trackType":"mp3"} Dec 14 22:58:14 primo volumio[3185]: verbose: CURRENT POSITION 3 Dec 14 22:58:14 primo volumio[3185]: info: CoreStateMachine::syncState stateService stop Dec 14 22:58:14 primo volumio[3185]: info: CoreStateMachine::syncState currentStatus stop Dec 14 22:58:14 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:14 primo volumio[3185]: info: CorePlayQueue::getTrack 3 Dec 14 22:58:14 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:14 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:14 primo volumio[3185]: info: CorePlayQueue::getTrack 3 Dec 14 22:58:14 primo volumio[3185]: info: No code Dec 14 22:58:14 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:14 primo volumio[3185]: info: CorePlayQueue::getTrack 3 Dec 14 22:58:14 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:14 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:14 primo volumio[3185]: info: CorePlayQueue::getTrack 3 Dec 14 22:58:14 primo volumio[3185]: info: ControllerMpd::pushState Dec 14 22:58:14 primo volumio[3185]: info: CoreCommandRouter::servicePushState Dec 14 22:58:14 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:14 primo volumio[3185]: info: CorePlayQueue::getTrack 3 Dec 14 22:58:14 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:14 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:14 primo volumio[3185]: info: CorePlayQueue::getTrack 3 Dec 14 22:58:14 primo volumio[3185]: info: CorePlayQueue::getTrack 3 Dec 14 22:58:14 primo volumio[3185]: 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} Dec 14 22:58:14 primo volumio[3185]: verbose: CURRENT POSITION 3 Dec 14 22:58:14 primo volumio[3185]: info: CoreStateMachine::syncState stateService stop Dec 14 22:58:14 primo volumio[3185]: info: CoreStateMachine::syncState currentStatus stop Dec 14 22:58:14 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:14 primo volumio[3185]: info: CorePlayQueue::getTrack 3 Dec 14 22:58:14 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:14 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:14 primo volumio[3185]: info: CorePlayQueue::getTrack 3 Dec 14 22:58:14 primo volumio[3185]: info: No code Dec 14 22:58:14 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:14 primo volumio[3185]: info: CorePlayQueue::getTrack 3 Dec 14 22:58:14 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:14 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:14 primo volumio[3185]: info: CorePlayQueue::getTrack 3 Dec 14 22:58:14 primo volumio[3185]: info: ControllerMpd::pushState Dec 14 22:58:14 primo volumio[3185]: info: CoreCommandRouter::servicePushState Dec 14 22:58:14 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:14 primo volumio[3185]: info: CorePlayQueue::getTrack 3 Dec 14 22:58:14 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:14 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:14 primo volumio[3185]: info: CorePlayQueue::getTrack 3 Dec 14 22:58:14 primo volumio[3185]: info: CorePlayQueue::getTrack 3 Dec 14 22:58:14 primo volumio[3185]: 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} Dec 14 22:58:14 primo volumio[3185]: verbose: CURRENT POSITION 3 Dec 14 22:58:14 primo volumio[3185]: info: CoreStateMachine::syncState stateService stop Dec 14 22:58:14 primo volumio[3185]: info: CoreStateMachine::syncState currentStatus stop Dec 14 22:58:14 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:14 primo volumio[3185]: info: CorePlayQueue::getTrack 3 Dec 14 22:58:14 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:14 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:14 primo volumio[3185]: info: CorePlayQueue::getTrack 3 Dec 14 22:58:14 primo volumio[3185]: info: No code Dec 14 22:58:14 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:14 primo volumio[3185]: info: CorePlayQueue::getTrack 3 Dec 14 22:58:14 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:14 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:14 primo volumio[3185]: info: CorePlayQueue::getTrack 3 Dec 14 22:58:14 primo volumio[3185]: info: ------------------------------ 64ms Dec 14 22:58:14 primo volumio[3185]: info: ------------------------------ 58ms Dec 14 22:58:14 primo volumio[3185]: info: ------------------------------ 57ms Dec 14 22:58:14 primo volumio[3185]: info: Dec 14 22:58:14 primo volumio[3185]: ---------------------------- MPD announces system playlist update Dec 14 22:58:14 primo volumio[3185]: info: Ignoring MPD Status Update Dec 14 22:58:14 primo volumio[3185]: info: Dec 14 22:58:14 primo volumio[3185]: ---------------------------- MPD announces system playlist update Dec 14 22:58:14 primo volumio[3185]: info: Ignoring MPD Status Update Dec 14 22:58:14 primo volumio[3185]: error: updateQueue error: null Dec 14 22:58:14 primo volumio[3185]: info: Dec 14 22:58:14 primo volumio[3185]: ---------------------------- MPD announces system playlist update Dec 14 22:58:14 primo volumio[3185]: info: Ignoring MPD Status Update Dec 14 22:58:14 primo volumio[3185]: info: ------------------------------ 61ms Dec 14 22:58:14 primo volumio[3185]: info: sendMpdCommand add "USB/MUSIKARKIV/Music/iTunes/iTunes Music/Music/Superflex Music/Hello DJ/01 Hello DJ 01.mp3" took 58 milliseconds Dec 14 22:58:14 primo volumio[3185]: info: ------------------------------ 6ms Dec 14 22:58:14 primo volumio[3185]: info: ------------------------------ 4ms Dec 14 22:58:14 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand play Dec 14 22:58:14 primo volumio[3185]: info: Dec 14 22:58:14 primo volumio[3185]: ---------------------------- MPD announces system playlist update Dec 14 22:58:14 primo volumio[3185]: info: Ignoring MPD Status Update Dec 14 22:58:14 primo kernel: aml_tdm_open Dec 14 22:58:14 primo kernel: Not init audio effects Dec 14 22:58:14 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Dec 14 22:58:14 primo kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Dec 14 22:58:14 primo kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Dec 14 22:58:14 primo kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Dec 14 22:58:14 primo kernel: asoc aml_dai_set_tdm_fmt, 0x4001, ffffffc03d2b8e18, id(1), clksel(1) Dec 14 22:58:14 primo kernel: aml_dai_set_tdm_fmt(), fmt not change Dec 14 22:58:14 primo kernel: dump_pcm_setting(ffffffc03d2b8e18) Dec 14 22:58:14 primo kernel: pcm_mode(1) Dec 14 22:58:14 primo kernel: sysclk(11289600) Dec 14 22:58:14 primo kernel: sysclk_bclk_ratio(4) Dec 14 22:58:14 primo kernel: bclk(2822400) Dec 14 22:58:14 primo kernel: bclk_lrclk_ratio(64) Dec 14 22:58:14 primo kernel: lrclk(44100) Dec 14 22:58:14 primo kernel: tx_mask(0x3) Dec 14 22:58:14 primo kernel: rx_mask(0x3) Dec 14 22:58:14 primo kernel: slots(2) Dec 14 22:58:14 primo kernel: slot_width(32) Dec 14 22:58:14 primo kernel: lane_mask_in(0x2) Dec 14 22:58:14 primo kernel: lane_mask_out(0x1) Dec 14 22:58:14 primo kernel: lane_oe_mask_in(0x0) Dec 14 22:58:14 primo kernel: lane_oe_mask_out(0x0) Dec 14 22:58:14 primo kernel: lane_lb_mask_in(0x0) Dec 14 22:58:14 primo kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Dec 14 22:58:14 primo kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Dec 14 22:58:14 primo kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Dec 14 22:58:14 primo kernel: aml_dai_set_clkdiv, div 4, clksel(1) Dec 14 22:58:14 primo kernel: aml_dai_set_bclk_ratio, select I2S mode Dec 14 22:58:14 primo kernel: aml_dai_tdm_hw_params(), enable mclk for TDM-B Dec 14 22:58:14 primo kernel: aml_tdm_prepare(), reset fddr Dec 14 22:58:14 primo kernel: spdif_a fifo ctrl, frddr:0 type:4, 24 bits, chmask 0x3, swap 0x10 Dec 14 22:58:14 primo kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Dec 14 22:58:14 primo kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Dec 14 22:58:14 primo kernel: tdm playback mute: 0, lane_cnt = 8 Dec 14 22:58:14 primo volumio[3185]: info: Dec 14 22:58:14 primo volumio[3185]: ---------------------------- MPD announces system playlist update Dec 14 22:58:14 primo volumio[3185]: info: Ignoring MPD Status Update Dec 14 22:58:14 primo volumio[3185]: info: Dec 14 22:58:14 primo volumio[3185]: ---------------------------- MPD announces state update: player Dec 14 22:58:14 primo volumio[3185]: info: Dec 14 22:58:14 primo volumio[3185]: ---------------------------- MPD announces state update: player Dec 14 22:58:14 primo volumio[3185]: info: ------------------------------ 24ms Dec 14 22:58:14 primo volumio[3185]: info: sendMpdCommand play took 23 milliseconds Dec 14 22:58:14 primo volumio[3185]: info: ControllerMpd::getState Dec 14 22:58:14 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand status Dec 14 22:58:14 primo volumio[3185]: info: ------------------------------ 22ms Dec 14 22:58:14 primo volumio[3185]: info: ------------------------------ 18ms Dec 14 22:58:14 primo volumio[3185]: info: ControllerMpd::getState Dec 14 22:58:14 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand status Dec 14 22:58:14 primo volumio[3185]: info: Dec 14 22:58:14 primo volumio[3185]: ---------------------------- MPD announces state update: player Dec 14 22:58:14 primo volumio[3185]: info: ControllerMpd::getState Dec 14 22:58:14 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand status Dec 14 22:58:14 primo volumio[3185]: info: Dec 14 22:58:14 primo volumio[3185]: ---------------------------- MPD announces state update: player Dec 14 22:58:14 primo volumio[3185]: info: ControllerMpd::getState Dec 14 22:58:14 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand status Dec 14 22:58:14 primo kernel: asoc-aml-card auge_sound: tdm playback enable Dec 14 22:58:14 primo kernel: spdif_a is set to enable Dec 14 22:58:14 primo volumio[3185]: info: sendMpdCommand status took 15 milliseconds Dec 14 22:58:14 primo volumio[3185]: info: sendMpdCommand status took 15 milliseconds Dec 14 22:58:14 primo volumio[3185]: info: sendMpdCommand status took 13 milliseconds Dec 14 22:58:14 primo volumio[3185]: info: sendMpdCommand status took 12 milliseconds Dec 14 22:58:14 primo volumio[3185]: verbose: ControllerMpd::parseState Dec 14 22:58:14 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 14 22:58:14 primo volumio[3185]: verbose: ControllerMpd::parseState Dec 14 22:58:14 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 14 22:58:14 primo volumio[3185]: verbose: ControllerMpd::parseState Dec 14 22:58:14 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 14 22:58:14 primo volumio[3185]: verbose: ControllerMpd::parseState Dec 14 22:58:14 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 14 22:58:14 primo volumio[3185]: info: Dec 14 22:58:14 primo volumio[3185]: ---------------------------- MPD announces state update: player Dec 14 22:58:14 primo volumio[3185]: info: ControllerMpd::getState Dec 14 22:58:14 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand status Dec 14 22:58:14 primo volumio[3185]: info: Dec 14 22:58:14 primo volumio[3185]: ---------------------------- MPD announces state update: player Dec 14 22:58:14 primo volumio[3185]: info: ControllerMpd::getState Dec 14 22:58:14 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand status Dec 14 22:58:14 primo volumio[3185]: info: MCU Signalled Playback Inactive Dec 14 22:58:14 primo volumio[3185]: info: sendMpdCommand playlistinfo took 11 milliseconds Dec 14 22:58:14 primo volumio[3185]: info: sendMpdCommand playlistinfo took 11 milliseconds Dec 14 22:58:14 primo volumio[3185]: info: sendMpdCommand playlistinfo took 11 milliseconds Dec 14 22:58:14 primo volumio[3185]: info: sendMpdCommand playlistinfo took 11 milliseconds Dec 14 22:58:14 primo volumio[3185]: verbose: ControllerMpd::parseTrackInfo Dec 14 22:58:14 primo volumio[3185]: verbose: ControllerMpd::parseTrackInfo Dec 14 22:58:14 primo volumio[3185]: verbose: ControllerMpd::parseTrackInfo Dec 14 22:58:14 primo volumio[3185]: verbose: ControllerMpd::parseTrackInfo Dec 14 22:58:14 primo volumio[3185]: info: ControllerMpd::pushState Dec 14 22:58:14 primo volumio[3185]: info: CoreCommandRouter::servicePushState Dec 14 22:58:14 primo volumio[3185]: info: CorePlayQueue::getTrack 3 Dec 14 22:58:14 primo volumio[3185]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":322,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Hello DJ 01","artist":"Superflex Music","album":"Hello DJ","uri":"USB/MUSIKARKIV/Music/iTunes/iTunes Music/Music/Superflex Music/Hello DJ/01 Hello DJ 01.mp3","trackType":"mp3"} Dec 14 22:58:14 primo volumio[3185]: verbose: CURRENT POSITION 3 Dec 14 22:58:14 primo volumio[3185]: info: CoreStateMachine::syncState stateService play Dec 14 22:58:14 primo volumio[3185]: info: CoreStateMachine::syncState currentStatus stop Dec 14 22:58:14 primo volumio[3185]: info: ControllerMpd::pushState Dec 14 22:58:14 primo volumio[3185]: info: CoreCommandRouter::servicePushState Dec 14 22:58:14 primo volumio[3185]: info: CorePlayQueue::getTrack 3 Dec 14 22:58:14 primo volumio[3185]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":322,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Hello DJ 01","artist":"Superflex Music","album":"Hello DJ","uri":"USB/MUSIKARKIV/Music/iTunes/iTunes Music/Music/Superflex Music/Hello DJ/01 Hello DJ 01.mp3","trackType":"mp3"} Dec 14 22:58:14 primo volumio[3185]: verbose: CURRENT POSITION 3 Dec 14 22:58:14 primo volumio[3185]: info: CoreStateMachine::syncState stateService play Dec 14 22:58:14 primo volumio[3185]: info: CoreStateMachine::syncState currentStatus play Dec 14 22:58:14 primo volumio[3185]: info: Received an update from plugin. extracting info from payload Dec 14 22:58:14 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:14 primo volumio[3185]: info: CorePlayQueue::getTrack 3 Dec 14 22:58:14 primo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 22:58:14 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:14 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:14 primo volumio[3185]: info: CorePlayQueue::getTrack 3 Dec 14 22:58:14 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:14 primo volumio[3185]: info: CorePlayQueue::getTrack 3 Dec 14 22:58:14 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:14 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:14 primo volumio[3185]: info: CorePlayQueue::getTrack 3 Dec 14 22:58:14 primo volumio[3185]: info: ControllerMpd::pushState Dec 14 22:58:14 primo volumio[3185]: info: CoreCommandRouter::servicePushState Dec 14 22:58:14 primo volumio[3185]: info: CorePlayQueue::getTrack 3 Dec 14 22:58:14 primo volumio[3185]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":322,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Hello DJ 01","artist":"Superflex Music","album":"Hello DJ","uri":"USB/MUSIKARKIV/Music/iTunes/iTunes Music/Music/Superflex Music/Hello DJ/01 Hello DJ 01.mp3","trackType":"mp3"} Dec 14 22:58:14 primo volumio[3185]: verbose: CURRENT POSITION 3 Dec 14 22:58:14 primo volumio[3185]: info: CoreStateMachine::syncState stateService play Dec 14 22:58:14 primo volumio[3185]: info: CoreStateMachine::syncState currentStatus play Dec 14 22:58:14 primo volumio[3185]: info: Received an update from plugin. extracting info from payload Dec 14 22:58:14 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:14 primo volumio[3185]: info: CorePlayQueue::getTrack 3 Dec 14 22:58:14 primo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 22:58:14 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:14 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:14 primo volumio[3185]: info: CorePlayQueue::getTrack 3 Dec 14 22:58:14 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:14 primo volumio[3185]: info: CorePlayQueue::getTrack 3 Dec 14 22:58:14 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:14 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:14 primo volumio[3185]: info: CorePlayQueue::getTrack 3 Dec 14 22:58:14 primo volumio[3185]: info: ControllerMpd::pushState Dec 14 22:58:14 primo volumio[3185]: info: CoreCommandRouter::servicePushState Dec 14 22:58:14 primo volumio[3185]: info: CorePlayQueue::getTrack 3 Dec 14 22:58:14 primo volumio[3185]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":322,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Hello DJ 01","artist":"Superflex Music","album":"Hello DJ","uri":"USB/MUSIKARKIV/Music/iTunes/iTunes Music/Music/Superflex Music/Hello DJ/01 Hello DJ 01.mp3","trackType":"mp3"} Dec 14 22:58:14 primo volumio[3185]: verbose: CURRENT POSITION 3 Dec 14 22:58:14 primo volumio[3185]: info: CoreStateMachine::syncState stateService play Dec 14 22:58:14 primo volumio[3185]: info: CoreStateMachine::syncState currentStatus play Dec 14 22:58:14 primo volumio[3185]: info: Received an update from plugin. extracting info from payload Dec 14 22:58:14 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:14 primo volumio[3185]: info: CorePlayQueue::getTrack 3 Dec 14 22:58:14 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:14 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:14 primo volumio[3185]: info: CorePlayQueue::getTrack 3 Dec 14 22:58:14 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:14 primo volumio[3185]: info: CorePlayQueue::getTrack 3 Dec 14 22:58:14 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:14 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:14 primo volumio[3185]: info: CorePlayQueue::getTrack 3 Dec 14 22:58:14 primo volumio[3185]: info: ------------------------------ 59ms Dec 14 22:58:14 primo volumio[3185]: info: ------------------------------ 69ms Dec 14 22:58:14 primo volumio[3185]: info: ------------------------------ 67ms Dec 14 22:58:14 primo volumio[3185]: info: ------------------------------ 66ms Dec 14 22:58:14 primo volumio[3185]: info: sendMpdCommand status took 49 milliseconds Dec 14 22:58:14 primo volumio[3185]: info: sendMpdCommand status took 48 milliseconds Dec 14 22:58:14 primo volumio[3185]: verbose: ControllerMpd::parseState Dec 14 22:58:14 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 14 22:58:14 primo volumio[3185]: verbose: ControllerMpd::parseState Dec 14 22:58:14 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 14 22:58:14 primo volumio[3185]: info: Signalling Playback active due to playback status change Dec 14 22:58:14 primo volumio[3185]: info: Signalling Playback active due to playback status change Dec 14 22:58:14 primo volumio[3185]: info: Signalling Playback active due to playback status change Dec 14 22:58:14 primo volumio[3185]: info: Signalling Playback active due to playback status change Dec 14 22:58:14 primo volumio[3185]: info: Signalling Playback active due to playback status change Dec 14 22:58:14 primo volumio[3185]: info: Signalling Playback active due to playback status change Dec 14 22:58:14 primo volumio[3185]: info: sendMpdCommand playlistinfo took 19 milliseconds Dec 14 22:58:14 primo volumio[3185]: info: sendMpdCommand playlistinfo took 20 milliseconds Dec 14 22:58:14 primo volumio[3185]: verbose: ControllerMpd::parseTrackInfo Dec 14 22:58:14 primo volumio[3185]: verbose: ControllerMpd::parseTrackInfo Dec 14 22:58:14 primo volumio[3185]: info: ControllerMpd::pushState Dec 14 22:58:14 primo volumio[3185]: info: CoreCommandRouter::servicePushState Dec 14 22:58:14 primo volumio[3185]: info: CorePlayQueue::getTrack 3 Dec 14 22:58:14 primo volumio[3185]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":322,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Hello DJ 01","artist":"Superflex Music","album":"Hello DJ","uri":"USB/MUSIKARKIV/Music/iTunes/iTunes Music/Music/Superflex Music/Hello DJ/01 Hello DJ 01.mp3","trackType":"mp3"} Dec 14 22:58:14 primo volumio[3185]: verbose: CURRENT POSITION 3 Dec 14 22:58:14 primo volumio[3185]: info: CoreStateMachine::syncState stateService play Dec 14 22:58:14 primo volumio[3185]: info: CoreStateMachine::syncState currentStatus play Dec 14 22:58:14 primo volumio[3185]: info: Received an update from plugin. extracting info from payload Dec 14 22:58:14 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:14 primo volumio[3185]: info: CorePlayQueue::getTrack 3 Dec 14 22:58:14 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:14 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:14 primo volumio[3185]: info: CorePlayQueue::getTrack 3 Dec 14 22:58:14 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:14 primo volumio[3185]: info: CorePlayQueue::getTrack 3 Dec 14 22:58:14 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:14 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:14 primo volumio[3185]: info: CorePlayQueue::getTrack 3 Dec 14 22:58:14 primo volumio[3185]: info: ControllerMpd::pushState Dec 14 22:58:14 primo volumio[3185]: info: CoreCommandRouter::servicePushState Dec 14 22:58:14 primo volumio[3185]: info: CorePlayQueue::getTrack 3 Dec 14 22:58:14 primo volumio[3185]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":322,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Hello DJ 01","artist":"Superflex Music","album":"Hello DJ","uri":"USB/MUSIKARKIV/Music/iTunes/iTunes Music/Music/Superflex Music/Hello DJ/01 Hello DJ 01.mp3","trackType":"mp3"} Dec 14 22:58:14 primo volumio[3185]: verbose: CURRENT POSITION 3 Dec 14 22:58:14 primo volumio[3185]: info: CoreStateMachine::syncState stateService play Dec 14 22:58:14 primo volumio[3185]: info: CoreStateMachine::syncState currentStatus play Dec 14 22:58:14 primo volumio[3185]: info: Received an update from plugin. extracting info from payload Dec 14 22:58:14 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:14 primo volumio[3185]: info: CorePlayQueue::getTrack 3 Dec 14 22:58:14 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:14 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:14 primo volumio[3185]: info: CorePlayQueue::getTrack 3 Dec 14 22:58:14 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:14 primo volumio[3185]: info: CorePlayQueue::getTrack 3 Dec 14 22:58:14 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:14 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:14 primo volumio[3185]: info: CorePlayQueue::getTrack 3 Dec 14 22:58:14 primo volumio[3185]: info: ------------------------------ 92ms Dec 14 22:58:14 primo volumio[3185]: info: ------------------------------ 92ms Dec 14 22:58:14 primo volumio[3185]: info: Signalling Playback active due to playback status change Dec 14 22:58:14 primo volumio[3185]: info: Signalling Playback active due to playback status change Dec 14 22:58:14 primo volumio[3185]: info: Signalling Playback active due to playback status change Dec 14 22:58:14 primo volumio[3185]: info: Signalling Playback active due to playback status change Dec 14 22:58:14 primo volumio[3185]: info: MCU Signalled Playback Active Dec 14 22:58:15 primo volumio[3185]: info: CoreCommandRouter::volumioNext Dec 14 22:58:15 primo volumio[3185]: info: CoreStateMachine::next Dec 14 22:58:15 primo volumio[3185]: info: CoreStateMachine::stop Dec 14 22:58:15 primo volumio[3185]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 14 22:58:15 primo volumio[3185]: info: CoreStateMachine::stPlaybackTimer Dec 14 22:58:15 primo volumio[3185]: info: CoreStateMachine::updateTrackBlock Dec 14 22:58:15 primo volumio[3185]: info: CorePlayQueue::getTrackBlock Dec 14 22:58:15 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:15 primo volumio[3185]: info: CorePlayQueue::getTrack 3 Dec 14 22:58:15 primo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 22:58:15 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:15 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:15 primo volumio[3185]: info: CorePlayQueue::getTrack 3 Dec 14 22:58:15 primo volumio[3185]: info: CoreStateMachine::serviceStop Dec 14 22:58:15 primo volumio[3185]: info: CorePlayQueue::getTrack 3 Dec 14 22:58:15 primo volumio[3185]: info: CoreCommandRouter::serviceStop Dec 14 22:58:15 primo volumio[3185]: info: ControllerMpd::stop Dec 14 22:58:15 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand stop Dec 14 22:58:15 primo kernel: asoc-aml-card auge_sound: tdm playback stop Dec 14 22:58:15 primo kernel: spdif_a is set to disable Dec 14 22:58:15 primo kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Dec 14 22:58:15 primo kernel: aml_dai_tdm_hw_free(), disable mclk for TDM-B Dec 14 22:58:15 primo kernel: tdm playback mute: 1, lane_cnt = 8 Dec 14 22:58:15 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Dec 14 22:58:15 primo volumio[3185]: info: Dec 14 22:58:15 primo volumio[3185]: ---------------------------- MPD announces state update: player Dec 14 22:58:15 primo volumio[3185]: info: sendMpdCommand stop took 16 milliseconds Dec 14 22:58:15 primo volumio[3185]: info: ControllerMpd::getState Dec 14 22:58:15 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand status Dec 14 22:58:15 primo volumio[3185]: info: CoreStateMachine::play index undefined Dec 14 22:58:15 primo volumio[3185]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 14 22:58:15 primo volumio[3185]: info: CorePlayQueue::getTrack 4 Dec 14 22:58:15 primo volumio[3185]: info: CoreStateMachine::startPlaybackTimer Dec 14 22:58:15 primo volumio[3185]: info: CorePlayQueue::getTrack 4 Dec 14 22:58:15 primo volumio[3185]: verbose: ControllerMpd::clearAddPlayTracks USB/MUSIKARKIV/Music/iTunes/iTunes Music/Music/Superflex Music/Hello DJ/02 Hello DJ 02.mp3 Dec 14 22:58:15 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand stop Dec 14 22:58:15 primo volumio[3185]: info: CoreStateMachine::updateTrackBlock Dec 14 22:58:15 primo volumio[3185]: info: CorePlayQueue::getTrackBlock Dec 14 22:58:15 primo volumio[3185]: info: sendMpdCommand status took 18 milliseconds Dec 14 22:58:15 primo volumio[3185]: info: sendMpdCommand stop took 14 milliseconds Dec 14 22:58:15 primo volumio[3185]: verbose: ControllerMpd::parseState Dec 14 22:58:15 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 14 22:58:15 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand clear Dec 14 22:58:15 primo volumio[3185]: info: Dec 14 22:58:15 primo volumio[3185]: ---------------------------- MPD announces state update: player Dec 14 22:58:15 primo volumio[3185]: info: ControllerMpd::getState Dec 14 22:58:15 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand status Dec 14 22:58:15 primo volumio[3185]: info: Dec 14 22:58:15 primo volumio[3185]: ---------------------------- MPD announces state update: player Dec 14 22:58:15 primo volumio[3185]: info: ControllerMpd::getState Dec 14 22:58:15 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand status Dec 14 22:58:15 primo volumio[3185]: info: Dec 14 22:58:15 primo volumio[3185]: ---------------------------- MPD announces system playlist update Dec 14 22:58:15 primo volumio[3185]: info: Ignoring MPD Status Update Dec 14 22:58:15 primo volumio[3185]: info: sendMpdCommand playlistinfo took 11 milliseconds Dec 14 22:58:15 primo volumio[3185]: info: sendMpdCommand clear took 12 milliseconds Dec 14 22:58:15 primo volumio[3185]: info: sendMpdCommand status took 8 milliseconds Dec 14 22:58:15 primo volumio[3185]: info: sendMpdCommand status took 6 milliseconds Dec 14 22:58:15 primo volumio[3185]: verbose: ControllerMpd::parseTrackInfo Dec 14 22:58:15 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand add "USB/MUSIKARKIV/Music/iTunes/iTunes Music/Music/Superflex Music/Hello DJ/02 Hello DJ 02.mp3" Dec 14 22:58:15 primo volumio[3185]: verbose: ControllerMpd::parseState Dec 14 22:58:15 primo volumio[3185]: verbose: ControllerMpd::parseState Dec 14 22:58:15 primo volumio[3185]: info: ControllerMpd::pushState Dec 14 22:58:15 primo volumio[3185]: info: CoreCommandRouter::servicePushState Dec 14 22:58:15 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:15 primo volumio[3185]: info: CorePlayQueue::getTrack 4 Dec 14 22:58:15 primo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 22:58:15 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:15 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:15 primo volumio[3185]: info: CorePlayQueue::getTrack 4 Dec 14 22:58:15 primo volumio[3185]: info: CorePlayQueue::getTrack 4 Dec 14 22:58:15 primo volumio[3185]: 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":"Hello DJ 01","artist":"Superflex Music","album":"Hello DJ","uri":"USB/MUSIKARKIV/Music/iTunes/iTunes Music/Music/Superflex Music/Hello DJ/01 Hello DJ 01.mp3","trackType":"mp3"} Dec 14 22:58:15 primo volumio[3185]: verbose: CURRENT POSITION 4 Dec 14 22:58:15 primo volumio[3185]: info: CoreStateMachine::syncState stateService stop Dec 14 22:58:15 primo volumio[3185]: info: CoreStateMachine::syncState currentStatus stop Dec 14 22:58:15 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:15 primo volumio[3185]: info: CorePlayQueue::getTrack 4 Dec 14 22:58:15 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:15 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:15 primo volumio[3185]: info: CorePlayQueue::getTrack 4 Dec 14 22:58:15 primo volumio[3185]: info: No code Dec 14 22:58:15 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:15 primo volumio[3185]: info: CorePlayQueue::getTrack 4 Dec 14 22:58:15 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:15 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:15 primo volumio[3185]: info: CorePlayQueue::getTrack 4 Dec 14 22:58:15 primo volumio[3185]: info: ControllerMpd::pushState Dec 14 22:58:15 primo volumio[3185]: info: CoreCommandRouter::servicePushState Dec 14 22:58:15 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:15 primo volumio[3185]: info: CorePlayQueue::getTrack 4 Dec 14 22:58:15 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:15 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:15 primo volumio[3185]: info: CorePlayQueue::getTrack 4 Dec 14 22:58:15 primo volumio[3185]: info: CorePlayQueue::getTrack 4 Dec 14 22:58:15 primo volumio[3185]: 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} Dec 14 22:58:15 primo volumio[3185]: verbose: CURRENT POSITION 4 Dec 14 22:58:15 primo volumio[3185]: info: CoreStateMachine::syncState stateService stop Dec 14 22:58:15 primo volumio[3185]: info: CoreStateMachine::syncState currentStatus stop Dec 14 22:58:15 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:15 primo volumio[3185]: info: CorePlayQueue::getTrack 4 Dec 14 22:58:15 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:15 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:15 primo volumio[3185]: info: CorePlayQueue::getTrack 4 Dec 14 22:58:15 primo volumio[3185]: info: No code Dec 14 22:58:15 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:15 primo volumio[3185]: info: CorePlayQueue::getTrack 4 Dec 14 22:58:15 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:15 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:15 primo volumio[3185]: info: CorePlayQueue::getTrack 4 Dec 14 22:58:15 primo volumio[3185]: info: ControllerMpd::pushState Dec 14 22:58:15 primo volumio[3185]: info: CoreCommandRouter::servicePushState Dec 14 22:58:15 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:15 primo volumio[3185]: info: CorePlayQueue::getTrack 4 Dec 14 22:58:15 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:15 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:15 primo volumio[3185]: info: CorePlayQueue::getTrack 4 Dec 14 22:58:15 primo volumio[3185]: info: CorePlayQueue::getTrack 4 Dec 14 22:58:15 primo volumio[3185]: 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} Dec 14 22:58:15 primo volumio[3185]: verbose: CURRENT POSITION 4 Dec 14 22:58:15 primo volumio[3185]: info: CoreStateMachine::syncState stateService stop Dec 14 22:58:15 primo volumio[3185]: info: CoreStateMachine::syncState currentStatus stop Dec 14 22:58:15 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:15 primo volumio[3185]: info: CorePlayQueue::getTrack 4 Dec 14 22:58:15 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:15 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:15 primo volumio[3185]: info: CorePlayQueue::getTrack 4 Dec 14 22:58:15 primo volumio[3185]: info: No code Dec 14 22:58:15 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:15 primo volumio[3185]: info: CorePlayQueue::getTrack 4 Dec 14 22:58:15 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:15 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:15 primo volumio[3185]: info: CorePlayQueue::getTrack 4 Dec 14 22:58:15 primo volumio[3185]: info: ------------------------------ 119ms Dec 14 22:58:15 primo volumio[3185]: info: ------------------------------ 98ms Dec 14 22:58:15 primo volumio[3185]: info: ------------------------------ 97ms Dec 14 22:58:15 primo volumio[3185]: info: Dec 14 22:58:15 primo volumio[3185]: ---------------------------- MPD announces system playlist update Dec 14 22:58:15 primo volumio[3185]: info: Ignoring MPD Status Update Dec 14 22:58:15 primo volumio[3185]: info: Dec 14 22:58:15 primo volumio[3185]: ---------------------------- MPD announces system playlist update Dec 14 22:58:15 primo volumio[3185]: info: Ignoring MPD Status Update Dec 14 22:58:15 primo volumio[3185]: error: updateQueue error: null Dec 14 22:58:15 primo kernel: aml_tdm_open Dec 14 22:58:15 primo kernel: Not init audio effects Dec 14 22:58:15 primo volumio[3185]: info: Dec 14 22:58:15 primo volumio[3185]: ---------------------------- MPD announces system playlist update Dec 14 22:58:15 primo volumio[3185]: info: Ignoring MPD Status Update Dec 14 22:58:15 primo volumio[3185]: info: ------------------------------ 99ms Dec 14 22:58:15 primo volumio[3185]: info: sendMpdCommand add "USB/MUSIKARKIV/Music/iTunes/iTunes Music/Music/Superflex Music/Hello DJ/02 Hello DJ 02.mp3" took 97 milliseconds Dec 14 22:58:15 primo volumio[3185]: info: ------------------------------ 6ms Dec 14 22:58:15 primo volumio[3185]: info: ------------------------------ 5ms Dec 14 22:58:15 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand play Dec 14 22:58:15 primo volumio[3185]: info: Dec 14 22:58:15 primo volumio[3185]: ---------------------------- MPD announces system playlist update Dec 14 22:58:15 primo volumio[3185]: info: Ignoring MPD Status Update Dec 14 22:58:15 primo volumio[3185]: info: Dec 14 22:58:15 primo volumio[3185]: ---------------------------- MPD announces system playlist update Dec 14 22:58:15 primo volumio[3185]: info: Ignoring MPD Status Update Dec 14 22:58:15 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Dec 14 22:58:15 primo kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Dec 14 22:58:15 primo kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Dec 14 22:58:15 primo kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Dec 14 22:58:15 primo kernel: asoc aml_dai_set_tdm_fmt, 0x4001, ffffffc03d2b8e18, id(1), clksel(1) Dec 14 22:58:15 primo kernel: aml_dai_set_tdm_fmt(), fmt not change Dec 14 22:58:15 primo kernel: dump_pcm_setting(ffffffc03d2b8e18) Dec 14 22:58:15 primo kernel: pcm_mode(1) Dec 14 22:58:15 primo kernel: sysclk(11289600) Dec 14 22:58:15 primo kernel: sysclk_bclk_ratio(4) Dec 14 22:58:15 primo kernel: bclk(2822400) Dec 14 22:58:15 primo kernel: bclk_lrclk_ratio(64) Dec 14 22:58:15 primo kernel: lrclk(44100) Dec 14 22:58:15 primo kernel: tx_mask(0x3) Dec 14 22:58:15 primo kernel: rx_mask(0x3) Dec 14 22:58:15 primo kernel: slots(2) Dec 14 22:58:15 primo kernel: slot_width(32) Dec 14 22:58:15 primo kernel: lane_mask_in(0x2) Dec 14 22:58:15 primo kernel: lane_mask_out(0x1) Dec 14 22:58:15 primo kernel: lane_oe_mask_in(0x0) Dec 14 22:58:15 primo kernel: lane_oe_mask_out(0x0) Dec 14 22:58:15 primo kernel: lane_lb_mask_in(0x0) Dec 14 22:58:15 primo kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Dec 14 22:58:15 primo kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Dec 14 22:58:15 primo kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Dec 14 22:58:15 primo kernel: aml_dai_set_clkdiv, div 4, clksel(1) Dec 14 22:58:15 primo kernel: aml_dai_set_bclk_ratio, select I2S mode Dec 14 22:58:15 primo kernel: aml_dai_tdm_hw_params(), enable mclk for TDM-B Dec 14 22:58:15 primo kernel: aml_tdm_prepare(), reset fddr Dec 14 22:58:15 primo kernel: spdif_a fifo ctrl, frddr:0 type:4, 24 bits, chmask 0x3, swap 0x10 Dec 14 22:58:15 primo kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Dec 14 22:58:15 primo kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Dec 14 22:58:15 primo kernel: tdm playback mute: 0, lane_cnt = 8 Dec 14 22:58:15 primo kernel: asoc-aml-card auge_sound: tdm playback enable Dec 14 22:58:15 primo kernel: spdif_a is set to enable Dec 14 22:58:15 primo volumio[3185]: info: MCU Signalled Playback Inactive Dec 14 22:58:15 primo volumio[3185]: info: Dec 14 22:58:15 primo volumio[3185]: ---------------------------- MPD announces state update: player Dec 14 22:58:15 primo volumio[3185]: info: Dec 14 22:58:15 primo volumio[3185]: ---------------------------- MPD announces state update: player Dec 14 22:58:15 primo volumio[3185]: info: ------------------------------ 46ms Dec 14 22:58:15 primo volumio[3185]: info: sendMpdCommand play took 43 milliseconds Dec 14 22:58:15 primo volumio[3185]: info: ControllerMpd::getState Dec 14 22:58:15 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand status Dec 14 22:58:15 primo volumio[3185]: info: ------------------------------ 43ms Dec 14 22:58:15 primo volumio[3185]: info: ------------------------------ 41ms Dec 14 22:58:15 primo volumio[3185]: info: ControllerMpd::getState Dec 14 22:58:15 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand status Dec 14 22:58:15 primo volumio[3185]: info: Dec 14 22:58:15 primo volumio[3185]: ---------------------------- MPD announces state update: player Dec 14 22:58:15 primo volumio[3185]: info: ControllerMpd::getState Dec 14 22:58:15 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand status Dec 14 22:58:15 primo volumio[3185]: info: Dec 14 22:58:15 primo volumio[3185]: ---------------------------- MPD announces state update: player Dec 14 22:58:15 primo volumio[3185]: info: ControllerMpd::getState Dec 14 22:58:15 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand status Dec 14 22:58:15 primo volumio[3185]: info: sendMpdCommand status took 6 milliseconds Dec 14 22:58:15 primo volumio[3185]: info: sendMpdCommand status took 6 milliseconds Dec 14 22:58:15 primo volumio[3185]: info: sendMpdCommand status took 5 milliseconds Dec 14 22:58:15 primo volumio[3185]: info: sendMpdCommand status took 3 milliseconds Dec 14 22:58:15 primo volumio[3185]: verbose: ControllerMpd::parseState Dec 14 22:58:15 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 14 22:58:15 primo volumio[3185]: verbose: ControllerMpd::parseState Dec 14 22:58:15 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 14 22:58:15 primo volumio[3185]: verbose: ControllerMpd::parseState Dec 14 22:58:15 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 14 22:58:15 primo volumio[3185]: verbose: ControllerMpd::parseState Dec 14 22:58:15 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 14 22:58:15 primo volumio[3185]: info: Dec 14 22:58:15 primo volumio[3185]: ---------------------------- MPD announces state update: player Dec 14 22:58:15 primo volumio[3185]: info: ControllerMpd::getState Dec 14 22:58:15 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand status Dec 14 22:58:15 primo volumio[3185]: info: Dec 14 22:58:15 primo volumio[3185]: ---------------------------- MPD announces state update: player Dec 14 22:58:15 primo volumio[3185]: info: ControllerMpd::getState Dec 14 22:58:15 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand status Dec 14 22:58:15 primo volumio[3185]: info: sendMpdCommand playlistinfo took 10 milliseconds Dec 14 22:58:15 primo volumio[3185]: info: sendMpdCommand playlistinfo took 10 milliseconds Dec 14 22:58:15 primo volumio[3185]: info: sendMpdCommand playlistinfo took 10 milliseconds Dec 14 22:58:15 primo volumio[3185]: info: sendMpdCommand playlistinfo took 10 milliseconds Dec 14 22:58:15 primo volumio[3185]: info: sendMpdCommand status took 8 milliseconds Dec 14 22:58:15 primo volumio[3185]: info: sendMpdCommand status took 7 milliseconds Dec 14 22:58:15 primo volumio[3185]: verbose: ControllerMpd::parseTrackInfo Dec 14 22:58:15 primo volumio[3185]: verbose: ControllerMpd::parseTrackInfo Dec 14 22:58:15 primo volumio[3185]: verbose: ControllerMpd::parseTrackInfo Dec 14 22:58:15 primo volumio[3185]: verbose: ControllerMpd::parseTrackInfo Dec 14 22:58:15 primo volumio[3185]: verbose: ControllerMpd::parseState Dec 14 22:58:15 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 14 22:58:15 primo volumio[3185]: verbose: ControllerMpd::parseState Dec 14 22:58:15 primo volumio[3185]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 14 22:58:15 primo volumio[3185]: info: ControllerMpd::pushState Dec 14 22:58:15 primo volumio[3185]: info: CoreCommandRouter::servicePushState Dec 14 22:58:15 primo volumio[3185]: info: CorePlayQueue::getTrack 4 Dec 14 22:58:15 primo volumio[3185]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":220,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Hello DJ 02","artist":"Superflex Music","album":"Hello DJ","uri":"USB/MUSIKARKIV/Music/iTunes/iTunes Music/Music/Superflex Music/Hello DJ/02 Hello DJ 02.mp3","trackType":"mp3"} Dec 14 22:58:15 primo volumio[3185]: verbose: CURRENT POSITION 4 Dec 14 22:58:15 primo volumio[3185]: info: CoreStateMachine::syncState stateService play Dec 14 22:58:15 primo volumio[3185]: info: CoreStateMachine::syncState currentStatus stop Dec 14 22:58:15 primo volumio[3185]: info: ControllerMpd::pushState Dec 14 22:58:15 primo volumio[3185]: info: CoreCommandRouter::servicePushState Dec 14 22:58:15 primo volumio[3185]: info: CorePlayQueue::getTrack 4 Dec 14 22:58:15 primo volumio[3185]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":220,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Hello DJ 02","artist":"Superflex Music","album":"Hello DJ","uri":"USB/MUSIKARKIV/Music/iTunes/iTunes Music/Music/Superflex Music/Hello DJ/02 Hello DJ 02.mp3","trackType":"mp3"} Dec 14 22:58:15 primo volumio[3185]: verbose: CURRENT POSITION 4 Dec 14 22:58:15 primo volumio[3185]: info: CoreStateMachine::syncState stateService play Dec 14 22:58:15 primo volumio[3185]: info: CoreStateMachine::syncState currentStatus play Dec 14 22:58:15 primo volumio[3185]: info: Received an update from plugin. extracting info from payload Dec 14 22:58:15 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:15 primo volumio[3185]: info: CorePlayQueue::getTrack 4 Dec 14 22:58:15 primo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 22:58:15 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:15 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:15 primo volumio[3185]: info: CorePlayQueue::getTrack 4 Dec 14 22:58:15 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:15 primo volumio[3185]: info: CorePlayQueue::getTrack 4 Dec 14 22:58:15 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:15 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:15 primo volumio[3185]: info: CorePlayQueue::getTrack 4 Dec 14 22:58:15 primo volumio[3185]: info: ControllerMpd::pushState Dec 14 22:58:15 primo volumio[3185]: info: CoreCommandRouter::servicePushState Dec 14 22:58:15 primo volumio[3185]: info: CorePlayQueue::getTrack 4 Dec 14 22:58:15 primo volumio[3185]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":220,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Hello DJ 02","artist":"Superflex Music","album":"Hello DJ","uri":"USB/MUSIKARKIV/Music/iTunes/iTunes Music/Music/Superflex Music/Hello DJ/02 Hello DJ 02.mp3","trackType":"mp3"} Dec 14 22:58:15 primo volumio[3185]: verbose: CURRENT POSITION 4 Dec 14 22:58:15 primo volumio[3185]: info: CoreStateMachine::syncState stateService play Dec 14 22:58:15 primo volumio[3185]: info: CoreStateMachine::syncState currentStatus play Dec 14 22:58:15 primo volumio[3185]: info: Received an update from plugin. extracting info from payload Dec 14 22:58:15 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:15 primo volumio[3185]: info: CorePlayQueue::getTrack 4 Dec 14 22:58:15 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:15 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:15 primo volumio[3185]: info: CorePlayQueue::getTrack 4 Dec 14 22:58:15 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:15 primo volumio[3185]: info: CorePlayQueue::getTrack 4 Dec 14 22:58:15 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:15 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:15 primo volumio[3185]: info: CorePlayQueue::getTrack 4 Dec 14 22:58:15 primo volumio[3185]: info: ControllerMpd::pushState Dec 14 22:58:15 primo volumio[3185]: info: CoreCommandRouter::servicePushState Dec 14 22:58:15 primo volumio[3185]: info: CorePlayQueue::getTrack 4 Dec 14 22:58:15 primo volumio[3185]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":220,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Hello DJ 02","artist":"Superflex Music","album":"Hello DJ","uri":"USB/MUSIKARKIV/Music/iTunes/iTunes Music/Music/Superflex Music/Hello DJ/02 Hello DJ 02.mp3","trackType":"mp3"} Dec 14 22:58:15 primo volumio[3185]: verbose: CURRENT POSITION 4 Dec 14 22:58:15 primo volumio[3185]: info: CoreStateMachine::syncState stateService play Dec 14 22:58:15 primo volumio[3185]: info: CoreStateMachine::syncState currentStatus play Dec 14 22:58:15 primo volumio[3185]: info: Received an update from plugin. extracting info from payload Dec 14 22:58:15 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:15 primo volumio[3185]: info: CorePlayQueue::getTrack 4 Dec 14 22:58:15 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:15 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:15 primo volumio[3185]: info: CorePlayQueue::getTrack 4 Dec 14 22:58:15 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:15 primo volumio[3185]: info: CorePlayQueue::getTrack 4 Dec 14 22:58:15 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:15 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:15 primo volumio[3185]: info: CorePlayQueue::getTrack 4 Dec 14 22:58:15 primo volumio[3185]: info: ------------------------------ 48ms Dec 14 22:58:15 primo volumio[3185]: info: ------------------------------ 56ms Dec 14 22:58:15 primo volumio[3185]: info: ------------------------------ 54ms Dec 14 22:58:15 primo volumio[3185]: info: ------------------------------ 53ms Dec 14 22:58:15 primo volumio[3185]: info: sendMpdCommand playlistinfo took 37 milliseconds Dec 14 22:58:15 primo volumio[3185]: info: sendMpdCommand playlistinfo took 37 milliseconds Dec 14 22:58:15 primo volumio[3185]: verbose: ControllerMpd::parseTrackInfo Dec 14 22:58:15 primo volumio[3185]: verbose: ControllerMpd::parseTrackInfo Dec 14 22:58:15 primo volumio[3185]: info: ControllerMpd::pushState Dec 14 22:58:15 primo volumio[3185]: info: CoreCommandRouter::servicePushState Dec 14 22:58:15 primo volumio[3185]: info: CorePlayQueue::getTrack 4 Dec 14 22:58:15 primo volumio[3185]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":220,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Hello DJ 02","artist":"Superflex Music","album":"Hello DJ","uri":"USB/MUSIKARKIV/Music/iTunes/iTunes Music/Music/Superflex Music/Hello DJ/02 Hello DJ 02.mp3","trackType":"mp3"} Dec 14 22:58:15 primo volumio[3185]: verbose: CURRENT POSITION 4 Dec 14 22:58:15 primo volumio[3185]: info: CoreStateMachine::syncState stateService play Dec 14 22:58:15 primo volumio[3185]: info: CoreStateMachine::syncState currentStatus play Dec 14 22:58:15 primo volumio[3185]: info: Received an update from plugin. extracting info from payload Dec 14 22:58:15 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:15 primo volumio[3185]: info: CorePlayQueue::getTrack 4 Dec 14 22:58:15 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:15 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:15 primo volumio[3185]: info: CorePlayQueue::getTrack 4 Dec 14 22:58:15 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:15 primo volumio[3185]: info: CorePlayQueue::getTrack 4 Dec 14 22:58:15 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:15 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:15 primo volumio[3185]: info: CorePlayQueue::getTrack 4 Dec 14 22:58:15 primo volumio[3185]: info: ControllerMpd::pushState Dec 14 22:58:15 primo volumio[3185]: info: CoreCommandRouter::servicePushState Dec 14 22:58:15 primo volumio[3185]: info: CorePlayQueue::getTrack 4 Dec 14 22:58:15 primo volumio[3185]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":220,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Hello DJ 02","artist":"Superflex Music","album":"Hello DJ","uri":"USB/MUSIKARKIV/Music/iTunes/iTunes Music/Music/Superflex Music/Hello DJ/02 Hello DJ 02.mp3","trackType":"mp3"} Dec 14 22:58:15 primo volumio[3185]: verbose: CURRENT POSITION 4 Dec 14 22:58:15 primo volumio[3185]: info: CoreStateMachine::syncState stateService play Dec 14 22:58:15 primo volumio[3185]: info: CoreStateMachine::syncState currentStatus play Dec 14 22:58:15 primo volumio[3185]: info: Received an update from plugin. extracting info from payload Dec 14 22:58:15 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:15 primo volumio[3185]: info: CorePlayQueue::getTrack 4 Dec 14 22:58:15 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:15 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:15 primo volumio[3185]: info: CorePlayQueue::getTrack 4 Dec 14 22:58:15 primo volumio[3185]: info: CoreStateMachine::pushState Dec 14 22:58:15 primo volumio[3185]: info: CorePlayQueue::getTrack 4 Dec 14 22:58:15 primo volumio[3185]: info: CoreCommandRouter::volumioPushState Dec 14 22:58:15 primo volumio[3185]: info: CoreCommandRouter::volumioGetState Dec 14 22:58:15 primo volumio[3185]: info: CorePlayQueue::getTrack 4 Dec 14 22:58:15 primo volumio[3185]: info: ------------------------------ 72ms Dec 14 22:58:15 primo volumio[3185]: info: ------------------------------ 72ms Dec 14 22:58:15 primo volumio[3185]: info: Signalling Playback active due to playback status change Dec 14 22:58:15 primo volumio[3185]: info: Signalling Playback active due to playback status change Dec 14 22:58:15 primo volumio[3185]: info: Signalling Playback active due to playback status change Dec 14 22:58:15 primo volumio[3185]: info: Signalling Playback active due to playback status change Dec 14 22:58:15 primo volumio[3185]: info: Signalling Playback active due to playback status change Dec 14 22:58:15 primo volumio[3185]: info: Signalling Playback active due to playback status change Dec 14 22:58:15 primo volumio[3185]: info: Signalling Playback active due to playback status change Dec 14 22:58:15 primo volumio[3185]: info: Signalling Playback active due to playback status change Dec 14 22:58:15 primo volumio[3185]: info: Signalling Playback active due to playback status change Dec 14 22:58:15 primo volumio[3185]: info: Signalling Playback active due to playback status change Dec 14 22:58:15 primo volumio[3185]: info: MCU Signalled Playback Active Dec 14 22:58:21 primo volumio[3185]: info: Executing endpoint metavolumio Dec 14 22:58:21 primo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 14 22:58:35 primo volumio[3185]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 14 22:58:35 primo volumio[3185]: TypeError: Cannot read property 'slice' of null Dec 14 22:58:35 primo volumio[3185]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Dec 14 22:58:35 primo volumio[3185]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Dec 14 22:58:35 primo volumio[3185]: at Object.onceWrapper (events.js:520:26) Dec 14 22:58:35 primo volumio[3185]: at ClientRequest.emit (events.js:400:28) Dec 14 22:58:35 primo volumio[3185]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:647:27) Dec 14 22:58:35 primo volumio[3185]: at HTTPParser.parserOnHeadersComplete (_http_common.js:127:17) Dec 14 22:58:35 primo volumio[3185]: at HTTPParser.execute () Dec 14 22:58:35 primo volumio[3185]: at TLSSocket.socketOnData (_http_client.js:515:22) Dec 14 22:58:35 primo volumio[3185]: at TLSSocket.emit (events.js:400:28) Dec 14 22:58:35 primo volumio[3185]: at addChunk (internal/streams/readable.js:293:12) Dec 14 22:58:35 primo volumio[3185]: at readableAddChunk (internal/streams/readable.js:267:9) Dec 14 22:58:35 primo volumio[3185]: at TLSSocket.Readable.push (internal/streams/readable.js:206:10) Dec 14 22:58:35 primo volumio[3185]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:188:23) Dec 14 22:58:35 primo volumio[3185]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 14 22:58:36 primo sudo[18777]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-14 22:57 Dec 14 22:58:36 primo sudo[18777]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="cc50ad4a2058d01de272214eb33827883bd2b7d8" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="502750e5d54ecf269d25125b622bfabc030fb553" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="primo2rev2" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 12 Nov 2025 08:41:01 PM CET" VOLUMIO_VERSION="3.874" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Primo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Primo" VOLUMIO_HASH="4dc90e4f98d685221d79f0e0b580095e"