-- Logs begin at Thu 2019-02-14 13:11:58 MSK, end at Fri 2026-04-10 20:05:52 MSK. -- Apr 10 20:04:00 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::volumioGetQueue Apr 10 20:04:00 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::getQueue Apr 10 20:04:00 deqx-gen-4.local volumio[5344]: info: CorePlayQueue::getQueue Apr 10 20:04:03 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Apr 10 20:04:03 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 10 20:04:06 deqx-gen-4.local volumio[5344]: info: Preload queue cleared Apr 10 20:04:08 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Apr 10 20:04:10 deqx-gen-4.local volumio[5344]: info: Preload queue cleared Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: Preload queue cleared Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::ClearQueue Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::stop Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::stPlaybackTimer Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::updateTrackBlock Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: CorePlayQueue::getTrackBlock Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::pushState Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: CorePlayQueue::getTrack 2 Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::volumioPushState Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::serviceStop Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: CorePlayQueue::getTrack 2 Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::serviceStop Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: [1775840669177] ControllerQobuz::stop Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: ControllerMpd::stop Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: verbose: ControllerMpd::sendMpdCommand stop Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: CorePlayQueue::clearPlayQueue Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: CorePlayQueue::saveQueue Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::volumioPushQueue Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::addQueueItems Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: CorePlayQueue::addQueueItems Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: Preload queue cleared Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99625224 Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::volumioPushQueue Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: CorePlayQueue::saveQueue Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::updateTrackBlock Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: CorePlayQueue::getTrackBlock Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::volumioPlay Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::play index 0 Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::stop Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::play index undefined Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: CorePlayQueue::getTrack 0 Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::startPlaybackTimer Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: CorePlayQueue::getTrack 0 Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::volumioGetVisibleSources Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: [1775840669210] ControllerWebradio::clearAddPlayTrack Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: verbose: ControllerMpd::sendMpdCommand stop Apr 10 20:04:29 deqx-gen-4.local kernel: asoc-aml-card auge_sound: tdm playback stop Apr 10 20:04:29 deqx-gen-4.local kernel: spdif_a is set to disable Apr 10 20:04:29 deqx-gen-4.local kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Apr 10 20:04:29 deqx-gen-4.local kernel: aml_dai_tdm_hw_free(), disable mclk for TDM-B Apr 10 20:04:29 deqx-gen-4.local kernel: tdm playback mute: 1, lane_cnt = 8 Apr 10 20:04:29 deqx-gen-4.local kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: ---------------------------- MPD announces state update: player Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: sendMpdCommand stop took 47 milliseconds Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: ControllerMpd::getState Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: verbose: ControllerMpd::sendMpdCommand status Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: sendMpdCommand stop took 15 milliseconds Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: verbose: ControllerMpd::sendMpdCommand clear Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: ---------------------------- MPD announces state update: player Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: ControllerMpd::getState Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: verbose: ControllerMpd::sendMpdCommand status Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: ---------------------------- MPD announces state update: player Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: ControllerMpd::getState Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: verbose: ControllerMpd::sendMpdCommand status Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: ---------------------------- MPD announces system playlist update Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: Ignoring MPD Status Update Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: sendMpdCommand status took 19 milliseconds Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: sendMpdCommand clear took 18 milliseconds Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: sendMpdCommand status took 7 milliseconds Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: sendMpdCommand status took 5 milliseconds Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: verbose: ControllerMpd::parseState Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99625224" Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: verbose: ControllerMpd::parseState Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: verbose: ControllerMpd::parseState Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: ControllerMpd::pushState Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::servicePushState Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::pushState Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: CorePlayQueue::getTrack 0 Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::volumioPushState Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: CorePlayQueue::getTrack 0 Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: ControllerMpd::pushState Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::servicePushState Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::pushState Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: CorePlayQueue::getTrack 0 Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::volumioPushState Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: CorePlayQueue::getTrack 0 Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: ------------------------------ 62ms Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: ------------------------------ 52ms Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: ---------------------------- MPD announces system playlist update Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: Ignoring MPD Status Update Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: ---------------------------- MPD announces system playlist update Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: Ignoring MPD Status Update Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: error: updateQueue error: null Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: ------------------------------ 58ms Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: sendMpdCommand playlistinfo took 54 milliseconds Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: verbose: ControllerMpd::parseTrackInfo Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: ------------------------------ 81ms Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99625224" took 695 milliseconds Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: verbose: ControllerMpd::sendMpdCommand play Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: ---------------------------- MPD announces system playlist update Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: Ignoring MPD Status Update Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: ------------------------------ 653ms Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: ------------------------------ 652ms Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: ---------------------------- MPD announces system playlist update Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: Ignoring MPD Status Update Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: ---------------------------- MPD announces system playlist update Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: Ignoring MPD Status Update Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: sendMpdCommand play took 11 milliseconds Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: ------------------------------ 11ms Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: ------------------------------ 6ms Apr 10 20:04:29 deqx-gen-4.local volumio[5344]: info: ------------------------------ 3ms Apr 10 20:04:31 deqx-gen-4.local kernel: aml_tdm_open Apr 10 20:04:31 deqx-gen-4.local kernel: Not init audio effects Apr 10 20:04:31 deqx-gen-4.local kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: ---------------------------- MPD announces state update: player Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: ControllerMpd::getState Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: verbose: ControllerMpd::sendMpdCommand status Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: ---------------------------- MPD announces state update: player Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: ControllerMpd::getState Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: verbose: ControllerMpd::sendMpdCommand status Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: ---------------------------- MPD announces state update: player Apr 10 20:04:31 deqx-gen-4.local kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Apr 10 20:04:31 deqx-gen-4.local kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Apr 10 20:04:31 deqx-gen-4.local kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Apr 10 20:04:31 deqx-gen-4.local kernel: asoc aml_dai_set_tdm_fmt, 0x4001, ffffffc0509a6818, id(1), clksel(1) Apr 10 20:04:31 deqx-gen-4.local kernel: aml_dai_set_tdm_fmt(), fmt not change Apr 10 20:04:31 deqx-gen-4.local kernel: dump_pcm_setting(ffffffc0509a6818) Apr 10 20:04:31 deqx-gen-4.local kernel: pcm_mode(1) Apr 10 20:04:31 deqx-gen-4.local kernel: sysclk(11289600) Apr 10 20:04:31 deqx-gen-4.local kernel: sysclk_bclk_ratio(4) Apr 10 20:04:31 deqx-gen-4.local kernel: bclk(2822400) Apr 10 20:04:31 deqx-gen-4.local kernel: bclk_lrclk_ratio(64) Apr 10 20:04:31 deqx-gen-4.local kernel: lrclk(44100) Apr 10 20:04:31 deqx-gen-4.local kernel: tx_mask(0x3) Apr 10 20:04:31 deqx-gen-4.local kernel: rx_mask(0x3) Apr 10 20:04:31 deqx-gen-4.local kernel: slots(2) Apr 10 20:04:31 deqx-gen-4.local kernel: slot_width(32) Apr 10 20:04:31 deqx-gen-4.local kernel: lane_mask_in(0x2) Apr 10 20:04:31 deqx-gen-4.local kernel: lane_mask_out(0x1) Apr 10 20:04:31 deqx-gen-4.local kernel: lane_oe_mask_in(0x0) Apr 10 20:04:31 deqx-gen-4.local kernel: lane_oe_mask_out(0x0) Apr 10 20:04:31 deqx-gen-4.local kernel: lane_lb_mask_in(0x0) Apr 10 20:04:31 deqx-gen-4.local kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Apr 10 20:04:31 deqx-gen-4.local kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Apr 10 20:04:31 deqx-gen-4.local kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Apr 10 20:04:31 deqx-gen-4.local kernel: aml_dai_set_clkdiv, div 4, clksel(1) Apr 10 20:04:31 deqx-gen-4.local kernel: aml_dai_set_bclk_ratio, select I2S mode Apr 10 20:04:31 deqx-gen-4.local kernel: aml_dai_tdm_hw_params(), enable mclk for TDM-B Apr 10 20:04:31 deqx-gen-4.local kernel: aml_tdm_prepare(), reset fddr Apr 10 20:04:31 deqx-gen-4.local kernel: spdif_a fifo ctrl, frddr:0 type:4, 24 bits, chmask 0x3, swap 0x10 Apr 10 20:04:31 deqx-gen-4.local kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Apr 10 20:04:31 deqx-gen-4.local kernel: hdmitx: hw: set audio Apr 10 20:04:31 deqx-gen-4.local kernel: hdmitx: hw: hdmitx tx_aud_src = 0 Apr 10 20:04:31 deqx-gen-4.local kernel: hdmitx: fs = 2, cd = 4, tmds_clk = 148352 Apr 10 20:04:31 deqx-gen-4.local kernel: hdmitx: hw: aud_n_para = 8918 Apr 10 20:04:31 deqx-gen-4.local kernel: hdmitx: hw: set channel status Apr 10 20:04:31 deqx-gen-4.local kernel: hdmitx: audio: Audio Type: PCM Apr 10 20:04:31 deqx-gen-4.local kernel: hdmitx: audio: set audio param Apr 10 20:04:31 deqx-gen-4.local kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Apr 10 20:04:31 deqx-gen-4.local kernel: tdm playback mute: 0, lane_cnt = 8 Apr 10 20:04:31 deqx-gen-4.local kernel: aml_tdm_prepare(), reset fddr Apr 10 20:04:31 deqx-gen-4.local kernel: spdif_a fifo ctrl, frddr:0 type:4, 24 bits, chmask 0x3, swap 0x10 Apr 10 20:04:31 deqx-gen-4.local kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Apr 10 20:04:31 deqx-gen-4.local kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Apr 10 20:04:31 deqx-gen-4.local kernel: tdm playback mute: 0, lane_cnt = 8 Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: ControllerMpd::getState Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: verbose: ControllerMpd::sendMpdCommand status Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: ---------------------------- MPD announces state update: player Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: sendMpdCommand status took 19 milliseconds Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: sendMpdCommand status took 17 milliseconds Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: ControllerMpd::getState Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: verbose: ControllerMpd::sendMpdCommand status Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: sendMpdCommand status took 14 milliseconds Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: verbose: ControllerMpd::parseState Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: verbose: ControllerMpd::parseState Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: verbose: ControllerMpd::parseState Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: ---------------------------- MPD announces state update: player Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: ControllerMpd::getState Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: verbose: ControllerMpd::sendMpdCommand status Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: ---------------------------- MPD announces state update: player Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: ControllerMpd::getState Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: verbose: ControllerMpd::sendMpdCommand status Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: sendMpdCommand status took 18 milliseconds Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: sendMpdCommand playlistinfo took 9 milliseconds Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: sendMpdCommand playlistinfo took 8 milliseconds Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: sendMpdCommand status took 6 milliseconds Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: sendMpdCommand status took 4 milliseconds Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: verbose: ControllerMpd::parseState Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: verbose: ControllerMpd::parseTrackInfo Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: verbose: ControllerMpd::parseState Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: verbose: ControllerMpd::parseState Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: ControllerMpd::pushState Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::servicePushState Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: CorePlayQueue::getTrack 0 Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 1013/9999) Hot 109 Reggae","album":null,"uri":"https://hot109.com:8162/stream","trackType":""} Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: verbose: CURRENT POSITION 0 Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::syncState stateService play Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::syncState currentStatus stop Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: ControllerMpd::pushState Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::servicePushState Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: CorePlayQueue::getTrack 0 Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 1013/9999) Hot 109 Reggae","album":null,"uri":"https://hot109.com:8162/stream","trackType":""} Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: verbose: CURRENT POSITION 0 Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::syncState stateService play Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::syncState currentStatus play Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: Received an update from plugin. extracting info from payload Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::pushState Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: CorePlayQueue::getTrack 0 Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::volumioPushState Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::pushState Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::volumioPushState Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: ControllerMpd::pushState Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::servicePushState Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: CorePlayQueue::getTrack 0 Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 1013/9999) Hot 109 Reggae","album":null,"uri":"https://hot109.com:8162/stream","trackType":""} Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: verbose: CURRENT POSITION 0 Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::syncState stateService play Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::syncState currentStatus play Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: Received an update from plugin. extracting info from payload Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::pushState Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: CorePlayQueue::getTrack 0 Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::volumioPushState Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::pushState Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::volumioPushState Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: ------------------------------ 91ms Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: ------------------------------ 101ms Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: ------------------------------ 100ms Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: sendMpdCommand playlistinfo took 61 milliseconds Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: sendMpdCommand playlistinfo took 69 milliseconds Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: sendMpdCommand playlistinfo took 69 milliseconds Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: verbose: ControllerMpd::parseTrackInfo Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: verbose: ControllerMpd::parseTrackInfo Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: verbose: ControllerMpd::parseTrackInfo Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: ControllerMpd::pushState Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::servicePushState Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: CorePlayQueue::getTrack 0 Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 1013/9999) Hot 109 Reggae","album":null,"uri":"https://hot109.com:8162/stream","trackType":""} Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: verbose: CURRENT POSITION 0 Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::syncState stateService play Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::syncState currentStatus play Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: Received an update from plugin. extracting info from payload Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::pushState Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: CorePlayQueue::getTrack 0 Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::volumioPushState Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::pushState Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::volumioPushState Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: ControllerMpd::pushState Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::servicePushState Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: CorePlayQueue::getTrack 0 Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 1013/9999) Hot 109 Reggae","album":null,"uri":"https://hot109.com:8162/stream","trackType":""} Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: verbose: CURRENT POSITION 0 Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::syncState stateService play Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::syncState currentStatus play Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: Received an update from plugin. extracting info from payload Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::pushState Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: CorePlayQueue::getTrack 0 Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::volumioPushState Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::pushState Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::volumioPushState Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: ControllerMpd::pushState Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::servicePushState Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: CorePlayQueue::getTrack 0 Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 1013/9999) Hot 109 Reggae","album":null,"uri":"https://hot109.com:8162/stream","trackType":""} Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: verbose: CURRENT POSITION 0 Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::syncState stateService play Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::syncState currentStatus play Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: Received an update from plugin. extracting info from payload Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::pushState Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: CorePlayQueue::getTrack 0 Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::volumioPushState Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::pushState Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::volumioPushState Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: ------------------------------ 172ms Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: ------------------------------ 154ms Apr 10 20:04:31 deqx-gen-4.local volumio[5344]: info: ------------------------------ 153ms Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: Preload queue cleared Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::ClearQueue Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::stop Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::stPlaybackTimer Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::updateTrackBlock Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: CorePlayQueue::getTrackBlock Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::pushState Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: CorePlayQueue::getTrack 0 Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::volumioPushState Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::serviceStop Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: CorePlayQueue::getTrack 0 Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::serviceStop Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: [1775840680640] ControllerWebradio::stop Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: verbose: ControllerMpd::sendMpdCommand stop Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: CorePlayQueue::clearPlayQueue Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: CorePlayQueue::saveQueue Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::volumioPushQueue Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::addQueueItems Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: CorePlayQueue::addQueueItems Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: Preload queue cleared Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99441647 Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::volumioPushQueue Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: CorePlayQueue::saveQueue Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::updateTrackBlock Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: CorePlayQueue::getTrackBlock Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::volumioPlay Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::play index 0 Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::stop Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::play index undefined Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: CorePlayQueue::getTrack 0 Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::startPlaybackTimer Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: CorePlayQueue::getTrack 0 Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::volumioGetVisibleSources Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: [1775840680666] ControllerWebradio::clearAddPlayTrack Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: verbose: ControllerMpd::sendMpdCommand stop Apr 10 20:04:40 deqx-gen-4.local kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Apr 10 20:04:40 deqx-gen-4.local kernel: aml_dai_tdm_hw_free(), disable mclk for TDM-B Apr 10 20:04:40 deqx-gen-4.local kernel: tdm playback mute: 1, lane_cnt = 8 Apr 10 20:04:40 deqx-gen-4.local kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: ---------------------------- MPD announces state update: player Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: sendMpdCommand stop took 42 milliseconds Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: ControllerMpd::getState Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: verbose: ControllerMpd::sendMpdCommand status Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: sendMpdCommand stop took 16 milliseconds Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: verbose: ControllerMpd::sendMpdCommand clear Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: ---------------------------- MPD announces system playlist update Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: Ignoring MPD Status Update Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: sendMpdCommand status took 5 milliseconds Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: sendMpdCommand clear took 4 milliseconds Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: verbose: ControllerMpd::parseState Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99441647" Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: ---------------------------- MPD announces state update: player Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: ControllerMpd::getState Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: verbose: ControllerMpd::sendMpdCommand status Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: ---------------------------- MPD announces state update: player Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: ControllerMpd::getState Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: verbose: ControllerMpd::sendMpdCommand status Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: error: updateQueue error: null Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: ---------------------------- MPD announces system playlist update Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: Ignoring MPD Status Update Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: ------------------------------ 167ms Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: sendMpdCommand playlistinfo took 164 milliseconds Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99441647" took 163 milliseconds Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: verbose: ControllerMpd::parseTrackInfo Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: verbose: ControllerMpd::sendMpdCommand play Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: ------------------------------ 177ms Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: sendMpdCommand status took 166 milliseconds Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: sendMpdCommand status took 165 milliseconds Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: ------------------------------ 10ms Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: verbose: ControllerMpd::parseState Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: verbose: ControllerMpd::parseState Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: ControllerMpd::pushState Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::servicePushState Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::pushState Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::volumioPushState Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: CorePlayQueue::getTrack 0 Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: 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} Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: verbose: CURRENT POSITION 0 Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::syncState stateService stop Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::syncState currentStatus stop Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::pushState Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::volumioPushState Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: No code Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::pushState Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::volumioPushState Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: ControllerMpd::pushState Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::servicePushState Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::pushState Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::volumioPushState Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: CorePlayQueue::getTrack 0 Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: 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} Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: verbose: CURRENT POSITION 0 Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::syncState stateService stop Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::syncState currentStatus stop Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::pushState Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::volumioPushState Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: No code Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::pushState Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::volumioPushState Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: ------------------------------ 218ms Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: ------------------------------ 217ms Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: ---------------------------- MPD announces system playlist update Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: Ignoring MPD Status Update Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: ---------------------------- MPD announces system playlist update Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: Ignoring MPD Status Update Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: sendMpdCommand play took 72 milliseconds Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: ------------------------------ 11ms Apr 10 20:04:40 deqx-gen-4.local volumio[5344]: info: ------------------------------ 5ms Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: ---------------------------- MPD announces state update: player Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: ControllerMpd::getState Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: verbose: ControllerMpd::sendMpdCommand status Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: ---------------------------- MPD announces state update: player Apr 10 20:04:42 deqx-gen-4.local kernel: aml_tdm_open Apr 10 20:04:42 deqx-gen-4.local kernel: Not init audio effects Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: ControllerMpd::getState Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: verbose: ControllerMpd::sendMpdCommand status Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: ---------------------------- MPD announces state update: player Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: ControllerMpd::getState Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: verbose: ControllerMpd::sendMpdCommand status Apr 10 20:04:42 deqx-gen-4.local kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Apr 10 20:04:42 deqx-gen-4.local kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Apr 10 20:04:42 deqx-gen-4.local kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Apr 10 20:04:42 deqx-gen-4.local kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Apr 10 20:04:42 deqx-gen-4.local kernel: asoc aml_dai_set_tdm_fmt, 0x4001, ffffffc0509a6818, id(1), clksel(1) Apr 10 20:04:42 deqx-gen-4.local kernel: aml_dai_set_tdm_fmt(), fmt not change Apr 10 20:04:42 deqx-gen-4.local kernel: dump_pcm_setting(ffffffc0509a6818) Apr 10 20:04:42 deqx-gen-4.local kernel: pcm_mode(1) Apr 10 20:04:42 deqx-gen-4.local kernel: sysclk(11289600) Apr 10 20:04:42 deqx-gen-4.local kernel: sysclk_bclk_ratio(4) Apr 10 20:04:42 deqx-gen-4.local kernel: bclk(2822400) Apr 10 20:04:42 deqx-gen-4.local kernel: bclk_lrclk_ratio(64) Apr 10 20:04:42 deqx-gen-4.local kernel: lrclk(44100) Apr 10 20:04:42 deqx-gen-4.local kernel: tx_mask(0x3) Apr 10 20:04:42 deqx-gen-4.local kernel: rx_mask(0x3) Apr 10 20:04:42 deqx-gen-4.local kernel: slots(2) Apr 10 20:04:42 deqx-gen-4.local kernel: slot_width(32) Apr 10 20:04:42 deqx-gen-4.local kernel: lane_mask_in(0x2) Apr 10 20:04:42 deqx-gen-4.local kernel: lane_mask_out(0x1) Apr 10 20:04:42 deqx-gen-4.local kernel: lane_oe_mask_in(0x0) Apr 10 20:04:42 deqx-gen-4.local kernel: lane_oe_mask_out(0x0) Apr 10 20:04:42 deqx-gen-4.local kernel: lane_lb_mask_in(0x0) Apr 10 20:04:42 deqx-gen-4.local kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Apr 10 20:04:42 deqx-gen-4.local kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Apr 10 20:04:42 deqx-gen-4.local kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Apr 10 20:04:42 deqx-gen-4.local kernel: aml_dai_set_clkdiv, div 4, clksel(1) Apr 10 20:04:42 deqx-gen-4.local kernel: aml_dai_set_bclk_ratio, select I2S mode Apr 10 20:04:42 deqx-gen-4.local kernel: aml_dai_tdm_hw_params(), enable mclk for TDM-B Apr 10 20:04:42 deqx-gen-4.local kernel: aml_tdm_prepare(), reset fddr Apr 10 20:04:42 deqx-gen-4.local kernel: spdif_a fifo ctrl, frddr:0 type:4, 24 bits, chmask 0x3, swap 0x10 Apr 10 20:04:42 deqx-gen-4.local kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Apr 10 20:04:42 deqx-gen-4.local kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Apr 10 20:04:42 deqx-gen-4.local kernel: tdm playback mute: 0, lane_cnt = 8 Apr 10 20:04:42 deqx-gen-4.local kernel: aml_tdm_prepare(), reset fddr Apr 10 20:04:42 deqx-gen-4.local kernel: spdif_a fifo ctrl, frddr:0 type:4, 24 bits, chmask 0x3, swap 0x10 Apr 10 20:04:42 deqx-gen-4.local kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Apr 10 20:04:42 deqx-gen-4.local kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Apr 10 20:04:42 deqx-gen-4.local kernel: tdm playback mute: 0, lane_cnt = 8 Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: ---------------------------- MPD announces state update: player Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: sendMpdCommand status took 19 milliseconds Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: ControllerMpd::getState Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: verbose: ControllerMpd::sendMpdCommand status Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: sendMpdCommand status took 18 milliseconds Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: sendMpdCommand status took 17 milliseconds Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: verbose: ControllerMpd::parseState Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: verbose: ControllerMpd::parseState Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: verbose: ControllerMpd::parseState Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: ---------------------------- MPD announces state update: player Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: ControllerMpd::getState Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: verbose: ControllerMpd::sendMpdCommand status Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: ---------------------------- MPD announces state update: player Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: ControllerMpd::getState Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: verbose: ControllerMpd::sendMpdCommand status Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: sendMpdCommand status took 20 milliseconds Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: sendMpdCommand playlistinfo took 18 milliseconds Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: sendMpdCommand status took 5 milliseconds Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: sendMpdCommand status took 4 milliseconds Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: verbose: ControllerMpd::parseState Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: verbose: ControllerMpd::parseTrackInfo Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: verbose: ControllerMpd::parseState Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: verbose: ControllerMpd::parseState Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: ControllerMpd::pushState Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::servicePushState Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: CorePlayQueue::getTrack 0 Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 648/2000) Blues Radio","album":null,"uri":"https://i4.streams.ovh:8352/stream","trackType":""} Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: verbose: CURRENT POSITION 0 Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::syncState stateService play Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::syncState currentStatus stop Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: ControllerMpd::pushState Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::servicePushState Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: CorePlayQueue::getTrack 0 Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 648/2000) Blues Radio","album":null,"uri":"https://i4.streams.ovh:8352/stream","trackType":""} Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: verbose: CURRENT POSITION 0 Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::syncState stateService play Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::syncState currentStatus play Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: Received an update from plugin. extracting info from payload Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::pushState Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: CorePlayQueue::getTrack 0 Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::volumioPushState Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::pushState Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::volumioPushState Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: ControllerMpd::pushState Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::servicePushState Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: CorePlayQueue::getTrack 0 Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 648/2000) Blues Radio","album":null,"uri":"https://i4.streams.ovh:8352/stream","trackType":""} Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: verbose: CURRENT POSITION 0 Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::syncState stateService play Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::syncState currentStatus play Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: Received an update from plugin. extracting info from payload Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::pushState Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: CorePlayQueue::getTrack 0 Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::volumioPushState Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::pushState Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::volumioPushState Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: ------------------------------ 73ms Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: ------------------------------ 81ms Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: ------------------------------ 80ms Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: sendMpdCommand playlistinfo took 45 milliseconds Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: sendMpdCommand playlistinfo took 43 milliseconds Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: sendMpdCommand playlistinfo took 39 milliseconds Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: verbose: ControllerMpd::parseTrackInfo Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: verbose: ControllerMpd::parseTrackInfo Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: verbose: ControllerMpd::parseTrackInfo Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: ControllerMpd::pushState Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::servicePushState Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: CorePlayQueue::getTrack 0 Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 648/2000) Blues Radio","album":null,"uri":"https://i4.streams.ovh:8352/stream","trackType":""} Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: verbose: CURRENT POSITION 0 Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::syncState stateService play Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::syncState currentStatus play Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: Received an update from plugin. extracting info from payload Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::pushState Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: CorePlayQueue::getTrack 0 Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::volumioPushState Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::pushState Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::volumioPushState Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: ControllerMpd::pushState Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::servicePushState Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: CorePlayQueue::getTrack 0 Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 648/2000) Blues Radio","album":null,"uri":"https://i4.streams.ovh:8352/stream","trackType":""} Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: verbose: CURRENT POSITION 0 Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::syncState stateService play Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::syncState currentStatus play Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: Received an update from plugin. extracting info from payload Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::pushState Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: CorePlayQueue::getTrack 0 Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::volumioPushState Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::pushState Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::volumioPushState Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: ControllerMpd::pushState Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::servicePushState Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: CorePlayQueue::getTrack 0 Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 648/2000) Blues Radio","album":null,"uri":"https://i4.streams.ovh:8352/stream","trackType":""} Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: verbose: CURRENT POSITION 0 Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::syncState stateService play Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::syncState currentStatus play Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: Received an update from plugin. extracting info from payload Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::pushState Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: CorePlayQueue::getTrack 0 Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::volumioPushState Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::pushState Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::volumioPushState Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: ------------------------------ 122ms Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: ------------------------------ 116ms Apr 10 20:04:42 deqx-gen-4.local volumio[5344]: info: ------------------------------ 105ms Apr 10 20:04:48 deqx-gen-4.local volumio[5344]: info: Preload queue cleared Apr 10 20:04:48 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 10 20:04:48 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::ClearQueue Apr 10 20:04:48 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::stop Apr 10 20:04:48 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 10 20:04:48 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::stPlaybackTimer Apr 10 20:04:48 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::updateTrackBlock Apr 10 20:04:48 deqx-gen-4.local volumio[5344]: info: CorePlayQueue::getTrackBlock Apr 10 20:04:48 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::pushState Apr 10 20:04:48 deqx-gen-4.local volumio[5344]: info: CorePlayQueue::getTrack 0 Apr 10 20:04:48 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 10 20:04:48 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::volumioPushState Apr 10 20:04:48 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::serviceStop Apr 10 20:04:48 deqx-gen-4.local volumio[5344]: info: CorePlayQueue::getTrack 0 Apr 10 20:04:48 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::serviceStop Apr 10 20:04:48 deqx-gen-4.local volumio[5344]: info: [1775840688423] ControllerWebradio::stop Apr 10 20:04:48 deqx-gen-4.local volumio[5344]: verbose: ControllerMpd::sendMpdCommand stop Apr 10 20:04:48 deqx-gen-4.local volumio[5344]: info: CorePlayQueue::clearPlayQueue Apr 10 20:04:48 deqx-gen-4.local volumio[5344]: info: CorePlayQueue::saveQueue Apr 10 20:04:48 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::volumioPushQueue Apr 10 20:04:48 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::addQueueItems Apr 10 20:04:48 deqx-gen-4.local volumio[5344]: info: CorePlayQueue::addQueueItems Apr 10 20:04:48 deqx-gen-4.local volumio[5344]: info: Preload queue cleared Apr 10 20:04:48 deqx-gen-4.local volumio[5344]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99441647 Apr 10 20:04:48 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 10 20:04:48 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::volumioPushQueue Apr 10 20:04:48 deqx-gen-4.local volumio[5344]: info: CorePlayQueue::saveQueue Apr 10 20:04:48 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::updateTrackBlock Apr 10 20:04:48 deqx-gen-4.local volumio[5344]: info: CorePlayQueue::getTrackBlock Apr 10 20:04:48 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::volumioPlay Apr 10 20:04:48 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::play index 0 Apr 10 20:04:48 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 10 20:04:48 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::stop Apr 10 20:04:48 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 10 20:04:48 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::play index undefined Apr 10 20:04:48 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 10 20:04:48 deqx-gen-4.local volumio[5344]: info: CorePlayQueue::getTrack 0 Apr 10 20:04:48 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::startPlaybackTimer Apr 10 20:04:48 deqx-gen-4.local volumio[5344]: info: CorePlayQueue::getTrack 0 Apr 10 20:04:48 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::volumioGetVisibleSources Apr 10 20:04:48 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 10 20:04:48 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 10 20:04:48 deqx-gen-4.local volumio[5344]: info: [1775840688447] ControllerWebradio::clearAddPlayTrack Apr 10 20:04:48 deqx-gen-4.local volumio[5344]: verbose: ControllerMpd::sendMpdCommand stop Apr 10 20:04:51 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::volumioPlay Apr 10 20:04:51 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::play index undefined Apr 10 20:04:51 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 10 20:04:51 deqx-gen-4.local volumio[5344]: info: CorePlayQueue::getTrack 0 Apr 10 20:04:51 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::startPlaybackTimer Apr 10 20:04:51 deqx-gen-4.local volumio[5344]: info: CorePlayQueue::getTrack 0 Apr 10 20:04:51 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::volumioGetVisibleSources Apr 10 20:04:51 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 10 20:04:51 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 10 20:04:51 deqx-gen-4.local volumio[5344]: info: [1775840691477] ControllerWebradio::clearAddPlayTrack Apr 10 20:04:51 deqx-gen-4.local volumio[5344]: verbose: ControllerMpd::sendMpdCommand stop Apr 10 20:05:24 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::volumioPlay Apr 10 20:05:24 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::play index undefined Apr 10 20:05:24 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 10 20:05:24 deqx-gen-4.local volumio[5344]: info: CorePlayQueue::getTrack 0 Apr 10 20:05:24 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::startPlaybackTimer Apr 10 20:05:24 deqx-gen-4.local volumio[5344]: info: CorePlayQueue::getTrack 0 Apr 10 20:05:24 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::volumioGetVisibleSources Apr 10 20:05:24 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 10 20:05:24 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 10 20:05:24 deqx-gen-4.local volumio[5344]: info: [1775840724650] ControllerWebradio::clearAddPlayTrack Apr 10 20:05:24 deqx-gen-4.local volumio[5344]: verbose: ControllerMpd::sendMpdCommand stop Apr 10 20:05:39 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 10 20:05:39 deqx-gen-4.local volumio[5344]: info: Preload queue cleared Apr 10 20:05:41 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 10 20:05:41 deqx-gen-4.local volumio[5344]: info: Preload queue cleared Apr 10 20:05:45 deqx-gen-4.local volumio[5344]: info: Preload queue cleared Apr 10 20:05:45 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 10 20:05:45 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::ClearQueue Apr 10 20:05:45 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::stop Apr 10 20:05:45 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 10 20:05:45 deqx-gen-4.local volumio[5344]: info: CorePlayQueue::clearPlayQueue Apr 10 20:05:45 deqx-gen-4.local volumio[5344]: info: CorePlayQueue::saveQueue Apr 10 20:05:45 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::volumioPushQueue Apr 10 20:05:45 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::addQueueItems Apr 10 20:05:45 deqx-gen-4.local volumio[5344]: info: CorePlayQueue::addQueueItems Apr 10 20:05:45 deqx-gen-4.local volumio[5344]: info: Preload queue cleared Apr 10 20:05:45 deqx-gen-4.local volumio[5344]: info: Adding Item to queue: upnp/folder/http://192.168.50.1:8200/ctl/ContentDir@1 Apr 10 20:05:45 deqx-gen-4.local volumio[5344]: info: Exploding uri upnp/folder/http://192.168.50.1:8200/ctl/ContentDir@1 in service upnp_browser Apr 10 20:05:46 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::volumioPushQueue Apr 10 20:05:46 deqx-gen-4.local volumio[5344]: info: CorePlayQueue::saveQueue Apr 10 20:05:46 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::updateTrackBlock Apr 10 20:05:46 deqx-gen-4.local volumio[5344]: info: CorePlayQueue::getTrackBlock Apr 10 20:05:46 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::volumioPlay Apr 10 20:05:46 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::play index 0 Apr 10 20:05:46 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 10 20:05:46 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::stop Apr 10 20:05:46 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 10 20:05:46 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::play index undefined Apr 10 20:05:46 deqx-gen-4.local volumio[5344]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 10 20:05:46 deqx-gen-4.local volumio[5344]: info: CorePlayQueue::getTrack 0 Apr 10 20:05:47 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 10 20:05:47 deqx-gen-4.local volumio[5344]: info: Preload queue cleared Apr 10 20:05:51 deqx-gen-4.local volumio[5344]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 10 20:05:51 deqx-gen-4.local volumio[5344]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 10 20:05:51 deqx-gen-4.local volumio[5344]: TypeError: Cannot read property 'length' of undefined Apr 10 20:05:51 deqx-gen-4.local volumio[5344]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Apr 10 20:05:51 deqx-gen-4.local volumio[5344]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Apr 10 20:05:51 deqx-gen-4.local volumio[5344]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Apr 10 20:05:51 deqx-gen-4.local volumio[5344]: at Parser.emit (events.js:400:28) Apr 10 20:05:51 deqx-gen-4.local volumio[5344]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Apr 10 20:05:51 deqx-gen-4.local volumio[5344]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Apr 10 20:05:51 deqx-gen-4.local volumio[5344]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Apr 10 20:05:51 deqx-gen-4.local volumio[5344]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Apr 10 20:05:51 deqx-gen-4.local volumio[5344]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Apr 10 20:05:51 deqx-gen-4.local volumio[5344]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Apr 10 20:05:51 deqx-gen-4.local volumio[5344]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Apr 10 20:05:51 deqx-gen-4.local volumio[5344]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Apr 10 20:05:51 deqx-gen-4.local volumio[5344]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Apr 10 20:05:51 deqx-gen-4.local volumio[5344]: at IncomingMessage.emit (events.js:412:35) Apr 10 20:05:51 deqx-gen-4.local volumio[5344]: at endReadableNT (internal/streams/readable.js:1333:12) Apr 10 20:05:51 deqx-gen-4.local volumio[5344]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Apr 10 20:05:51 deqx-gen-4.local volumio[5344]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 10 20:05:52 deqx-gen-4.local sudo[15279]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-04-10 20:04 Apr 10 20:05:52 deqx-gen-4.local sudo[15279]: 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="900c37ee0cafd620e4a37a1118cc5316b54b45b3" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="ca3c0885e14bcba58c57ef609685ae76316b61d6" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="deqx" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 16 Apr 2024 02:17:33 PM CEST" VOLUMIO_VERSION="3.624" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_HASH="ca1050ae12f3dc501a57f6b4c284f5d2"