-- Logs begin at Mon 2024-07-08 00:24:30 CEST, end at Wed 2024-07-10 15:04:07 CEST. -- Jul 10 15:03:00 tomsprimo54 volumio[3160]: info: Preload queue cleared Jul 10 15:03:00 tomsprimo54 volumio[3160]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 10 15:03:00 tomsprimo54 volumio[3160]: info: CoreStateMachine::ClearQueue Jul 10 15:03:00 tomsprimo54 volumio[3160]: info: CoreStateMachine::stop Jul 10 15:03:00 tomsprimo54 volumio[3160]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 10 15:03:00 tomsprimo54 volumio[3160]: info: CorePlayQueue::clearPlayQueue Jul 10 15:03:00 tomsprimo54 volumio[3160]: info: CorePlayQueue::saveQueue Jul 10 15:03:00 tomsprimo54 volumio[3160]: info: CoreCommandRouter::volumioPushQueue Jul 10 15:03:00 tomsprimo54 volumio[3160]: info: CoreStateMachine::addQueueItems Jul 10 15:03:00 tomsprimo54 volumio[3160]: info: CorePlayQueue::addQueueItems Jul 10 15:03:00 tomsprimo54 volumio[3160]: info: Preload queue cleared Jul 10 15:03:00 tomsprimo54 volumio[3160]: info: Adding Item to queue: webrp/2 Jul 10 15:03:00 tomsprimo54 volumio[3160]: info: Exploding uri webrp/2 in service radio_paradise Jul 10 15:03:00 tomsprimo54 volumio[3160]: info: CoreCommandRouter::volumioPushQueue Jul 10 15:03:00 tomsprimo54 volumio[3160]: info: CorePlayQueue::saveQueue Jul 10 15:03:00 tomsprimo54 volumio[3160]: info: CoreStateMachine::updateTrackBlock Jul 10 15:03:00 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrackBlock Jul 10 15:03:00 tomsprimo54 volumio[3160]: info: CoreCommandRouter::volumioPlay Jul 10 15:03:00 tomsprimo54 volumio[3160]: info: CoreStateMachine::play index 0 Jul 10 15:03:00 tomsprimo54 volumio[3160]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 10 15:03:00 tomsprimo54 volumio[3160]: info: CoreStateMachine::stop Jul 10 15:03:00 tomsprimo54 volumio[3160]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 10 15:03:00 tomsprimo54 volumio[3160]: info: CoreStateMachine::play index undefined Jul 10 15:03:00 tomsprimo54 volumio[3160]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 10 15:03:00 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:03:00 tomsprimo54 volumio[3160]: info: CoreStateMachine::startPlaybackTimer Jul 10 15:03:00 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:03:00 tomsprimo54 volumio[3160]: info: CoreCommandRouter::volumioGetVisibleSources Jul 10 15:03:00 tomsprimo54 volumio[3160]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 15:03:00 tomsprimo54 volumio[3160]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jul 10 15:03:00 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand stop Jul 10 15:03:00 tomsprimo54 volumio[3160]: info: sendMpdCommand stop took 3 milliseconds Jul 10 15:03:00 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand clear Jul 10 15:03:00 tomsprimo54 volumio[3160]: info: Jul 10 15:03:00 tomsprimo54 volumio[3160]: ---------------------------- MPD announces system playlist update Jul 10 15:03:00 tomsprimo54 volumio[3160]: info: Ignoring MPD Status Update Jul 10 15:03:00 tomsprimo54 volumio[3160]: info: sendMpdCommand clear took 2 milliseconds Jul 10 15:03:00 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand consume 1 Jul 10 15:03:00 tomsprimo54 volumio[3160]: error: updateQueue error: null Jul 10 15:03:00 tomsprimo54 volumio[3160]: info: ------------------------------ 3ms Jul 10 15:03:00 tomsprimo54 volumio[3160]: info: sendMpdCommand consume 1 took 2 milliseconds Jul 10 15:03:00 tomsprimo54 volumio[3160]: info: [1720616580092] [RadioParadise] set to consume mode, adding url: https://stream.radioparadise.com/rock-flac Jul 10 15:03:00 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand add "https://stream.radioparadise.com/rock-flac" Jul 10 15:03:00 tomsprimo54 volumio[3160]: info: Jul 10 15:03:00 tomsprimo54 volumio[3160]: ---------------------------- MPD announces system playlist update Jul 10 15:03:00 tomsprimo54 volumio[3160]: info: Ignoring MPD Status Update Jul 10 15:03:00 tomsprimo54 volumio[3160]: info: sendMpdCommand add "https://stream.radioparadise.com/rock-flac" took 1 milliseconds Jul 10 15:03:00 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand play Jul 10 15:03:00 tomsprimo54 volumio[3160]: info: ------------------------------ 6ms Jul 10 15:03:00 tomsprimo54 volumio[3160]: info: sendMpdCommand play took 2 milliseconds Jul 10 15:03:00 tomsprimo54 volumio[3160]: info: [1720616580102] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=2 Jul 10 15:03:00 tomsprimo54 volumio[3160]: info: [1720616580690] [RadioParadise] received new metadata: {"time":149,"artist":"An Emotional Fish","title":"Celebrate","album":"An Emotional Fish","year":"1990","cover":"https://img.radioparadise.com/covers/l/B000008CUZ.jpg","cover_med":"https://img.radioparadise.com/covers/m/B000008CUZ.jpg","cover_small":"https://img.radioparadise.com/covers/s/B000008CUZ.jpg"} Jul 10 15:03:00 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:03:00 tomsprimo54 volumio[3160]: info: CoreCommandRouter::servicePushState Jul 10 15:03:00 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:03:00 tomsprimo54 volumio[3160]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B000008CUZ.jpg","uri":"https://stream.radioparadise.com/rock-flac","name":"Celebrate","title":"Celebrate","artist":"An Emotional Fish","album":"An Emotional Fish","streaming":true,"disableUiControls":true,"duration":154,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jul 10 15:03:00 tomsprimo54 volumio[3160]: verbose: CURRENT POSITION 0 Jul 10 15:03:00 tomsprimo54 volumio[3160]: info: CoreStateMachine::syncState stateService play Jul 10 15:03:00 tomsprimo54 volumio[3160]: info: CoreStateMachine::syncState currentStatus stop Jul 10 15:03:00 tomsprimo54 volumio[3160]: info: [1720616580696] [RadioParadise] setting new timer with duration of 154000 seconds. Jul 10 15:03:00 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:03:00 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 1 Jul 10 15:03:00 tomsprimo54 kernel: aml_tdm_open Jul 10 15:03:00 tomsprimo54 kernel: Not init audio effects Jul 10 15:03:00 tomsprimo54 kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Jul 10 15:03:01 tomsprimo54 volumio[3160]: info: Jul 10 15:03:01 tomsprimo54 volumio[3160]: ---------------------------- MPD announces state update: player Jul 10 15:03:01 tomsprimo54 kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Jul 10 15:03:01 tomsprimo54 kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Jul 10 15:03:01 tomsprimo54 kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Jul 10 15:03:01 tomsprimo54 kernel: asoc aml_dai_set_tdm_fmt, 0x4001, ffffffc03d277618, id(1), clksel(1) Jul 10 15:03:01 tomsprimo54 kernel: aml_dai_set_tdm_fmt(), fmt not change Jul 10 15:03:01 tomsprimo54 kernel: dump_pcm_setting(ffffffc03d277618) Jul 10 15:03:01 tomsprimo54 kernel: pcm_mode(1) Jul 10 15:03:01 tomsprimo54 kernel: sysclk(11289600) Jul 10 15:03:01 tomsprimo54 kernel: sysclk_bclk_ratio(4) Jul 10 15:03:01 tomsprimo54 kernel: bclk(2822400) Jul 10 15:03:01 tomsprimo54 kernel: bclk_lrclk_ratio(64) Jul 10 15:03:01 tomsprimo54 kernel: lrclk(44100) Jul 10 15:03:01 tomsprimo54 kernel: tx_mask(0x3) Jul 10 15:03:01 tomsprimo54 kernel: rx_mask(0x3) Jul 10 15:03:01 tomsprimo54 kernel: slots(2) Jul 10 15:03:01 tomsprimo54 kernel: slot_width(32) Jul 10 15:03:01 tomsprimo54 kernel: lane_mask_in(0x2) Jul 10 15:03:01 tomsprimo54 kernel: lane_mask_out(0x1) Jul 10 15:03:01 tomsprimo54 kernel: lane_oe_mask_in(0x0) Jul 10 15:03:01 tomsprimo54 kernel: lane_oe_mask_out(0x0) Jul 10 15:03:01 tomsprimo54 kernel: lane_lb_mask_in(0x0) Jul 10 15:03:01 tomsprimo54 kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Jul 10 15:03:01 tomsprimo54 kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Jul 10 15:03:01 tomsprimo54 kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Jul 10 15:03:01 tomsprimo54 kernel: aml_dai_set_clkdiv, div 4, clksel(1) Jul 10 15:03:01 tomsprimo54 kernel: aml_dai_set_bclk_ratio, select I2S mode Jul 10 15:03:01 tomsprimo54 kernel: aml_dai_tdm_hw_params(), enable mclk for TDM-B Jul 10 15:03:01 tomsprimo54 kernel: aml_tdm_prepare(), reset fddr Jul 10 15:03:01 tomsprimo54 kernel: spdif_a fifo ctrl, frddr:0 type:1, 16 bits, chmask 0x3, swap 0x10 Jul 10 15:03:01 tomsprimo54 kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Jul 10 15:03:01 tomsprimo54 kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Jul 10 15:03:01 tomsprimo54 kernel: tdm playback mute: 0, lane_cnt = 8 Jul 10 15:03:01 tomsprimo54 kernel: aml_tdm_prepare(), reset fddr Jul 10 15:03:01 tomsprimo54 kernel: spdif_a fifo ctrl, frddr:0 type:1, 16 bits, chmask 0x3, swap 0x10 Jul 10 15:03:01 tomsprimo54 kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Jul 10 15:03:01 tomsprimo54 kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Jul 10 15:03:01 tomsprimo54 kernel: tdm playback mute: 0, lane_cnt = 8 Jul 10 15:03:01 tomsprimo54 volumio[3160]: info: ControllerMpd::getState Jul 10 15:03:01 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand status Jul 10 15:03:01 tomsprimo54 volumio[3160]: info: Jul 10 15:03:01 tomsprimo54 volumio[3160]: ---------------------------- MPD announces state update: player Jul 10 15:03:01 tomsprimo54 volumio[3160]: info: ControllerMpd::getState Jul 10 15:03:01 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand status Jul 10 15:03:01 tomsprimo54 volumio[3160]: info: sendMpdCommand status took 3 milliseconds Jul 10 15:03:01 tomsprimo54 volumio[3160]: verbose: ControllerMpd::parseState Jul 10 15:03:01 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 15:03:01 tomsprimo54 volumio[3160]: info: sendMpdCommand status took 3 milliseconds Jul 10 15:03:01 tomsprimo54 volumio[3160]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 10 15:03:01 tomsprimo54 volumio[3160]: verbose: ControllerMpd::parseState Jul 10 15:03:01 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 15:03:01 tomsprimo54 volumio[3160]: verbose: ControllerMpd::parseTrackInfo Jul 10 15:03:01 tomsprimo54 volumio[3160]: info: ControllerMpd::pushState Jul 10 15:03:01 tomsprimo54 volumio[3160]: info: CoreCommandRouter::servicePushState Jul 10 15:03:01 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:03:01 tomsprimo54 volumio[3160]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Jul 10 15:03:01 tomsprimo54 volumio[3160]: info: ------------------------------ 15ms Jul 10 15:03:01 tomsprimo54 volumio[3160]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 10 15:03:01 tomsprimo54 volumio[3160]: verbose: ControllerMpd::parseTrackInfo Jul 10 15:03:01 tomsprimo54 volumio[3160]: info: ControllerMpd::pushState Jul 10 15:03:01 tomsprimo54 volumio[3160]: info: CoreCommandRouter::servicePushState Jul 10 15:03:01 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:03:01 tomsprimo54 volumio[3160]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Jul 10 15:03:01 tomsprimo54 volumio[3160]: info: ------------------------------ 10ms Jul 10 15:03:01 tomsprimo54 volumio[3160]: info: Jul 10 15:03:01 tomsprimo54 volumio[3160]: ---------------------------- MPD announces system playlist update Jul 10 15:03:01 tomsprimo54 volumio[3160]: info: Ignoring MPD Status Update Jul 10 15:03:01 tomsprimo54 volumio[3160]: info: Jul 10 15:03:01 tomsprimo54 volumio[3160]: ---------------------------- MPD announces state update: player Jul 10 15:03:01 tomsprimo54 volumio[3160]: info: ControllerMpd::getState Jul 10 15:03:01 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand status Jul 10 15:03:01 tomsprimo54 volumio[3160]: info: ------------------------------ 3ms Jul 10 15:03:01 tomsprimo54 volumio[3160]: info: sendMpdCommand status took 2 milliseconds Jul 10 15:03:01 tomsprimo54 volumio[3160]: verbose: ControllerMpd::parseState Jul 10 15:03:01 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 15:03:01 tomsprimo54 volumio[3160]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 10 15:03:01 tomsprimo54 volumio[3160]: verbose: ControllerMpd::parseTrackInfo Jul 10 15:03:01 tomsprimo54 volumio[3160]: info: ControllerMpd::pushState Jul 10 15:03:01 tomsprimo54 volumio[3160]: info: CoreCommandRouter::servicePushState Jul 10 15:03:01 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:03:01 tomsprimo54 volumio[3160]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Jul 10 15:03:01 tomsprimo54 volumio[3160]: info: ------------------------------ 7ms Jul 10 15:03:01 tomsprimo54 kernel: asoc-aml-card auge_sound: tdm playback enable Jul 10 15:03:01 tomsprimo54 kernel: spdif_a is set to enable Jul 10 15:03:07 tomsprimo54 volumio[3160]: info: CoreCommandRouter::volumioPlay Jul 10 15:03:07 tomsprimo54 volumio[3160]: info: CoreStateMachine::play index undefined Jul 10 15:03:07 tomsprimo54 volumio[3160]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 10 15:03:07 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:03:14 tomsprimo54 nmbd[4701]: [2024/07/10 15:03:14.828614, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jul 10 15:03:14 tomsprimo54 nmbd[4701]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.178.100 for name WORKGROUP<1d>. Jul 10 15:03:14 tomsprimo54 nmbd[4701]: This response was from IP 192.168.178.74, reporting an IP address of 192.168.178.74. Jul 10 15:03:14 tomsprimo54 nmbd[4701]: [2024/07/10 15:03:14.829097, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jul 10 15:03:14 tomsprimo54 nmbd[4701]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.178.99 for name WORKGROUP<1d>. Jul 10 15:03:14 tomsprimo54 nmbd[4701]: This response was from IP 192.168.178.74, reporting an IP address of 192.168.178.74. Jul 10 15:03:19 tomsprimo54 nmbd[4701]: [2024/07/10 15:03:19.836109, 0] ../source3/libsmb/nmblib.c:917(send_udp) Jul 10 15:03:19 tomsprimo54 nmbd[4701]: Packet send failed to 192.168.211.255(138) ERRNO=Network is unreachable Jul 10 15:03:21 tomsprimo54 volumio[3160]: info: Preload queue cleared Jul 10 15:03:21 tomsprimo54 volumio[3160]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 10 15:03:21 tomsprimo54 volumio[3160]: info: CoreStateMachine::ClearQueue Jul 10 15:03:21 tomsprimo54 volumio[3160]: info: CoreStateMachine::stop Jul 10 15:03:21 tomsprimo54 volumio[3160]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 10 15:03:21 tomsprimo54 volumio[3160]: info: CoreStateMachine::stPlaybackTimer Jul 10 15:03:21 tomsprimo54 volumio[3160]: info: CoreStateMachine::updateTrackBlock Jul 10 15:03:21 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrackBlock Jul 10 15:03:21 tomsprimo54 volumio[3160]: info: CoreStateMachine::pushState Jul 10 15:03:21 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:03:21 tomsprimo54 volumio[3160]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 15:03:21 tomsprimo54 volumio[3160]: info: CoreCommandRouter::volumioPushState Jul 10 15:03:21 tomsprimo54 volumio[3160]: info: MRS: Pushing multiroomSync output update for this device Jul 10 15:03:21 tomsprimo54 volumio[3160]: info: MRS: Pushing multiroomSync output Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: CoreStateMachine::serviceStop Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: CoreCommandRouter::serviceStop Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: ControllerMpd::stop Jul 10 15:03:22 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand stop Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: CorePlayQueue::clearPlayQueue Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: CorePlayQueue::saveQueue Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: CoreCommandRouter::volumioPushQueue Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: CoreStateMachine::addQueueItems Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: CorePlayQueue::addQueueItems Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: Preload queue cleared Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: Adding Item to queue: webrp/2 Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: Using cached record of: webrp/2 Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: CoreCommandRouter::volumioPushQueue Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: CorePlayQueue::saveQueue Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: CoreStateMachine::updateTrackBlock Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrackBlock Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: CoreCommandRouter::volumioPlay Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: CoreStateMachine::play index 0 Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: CoreStateMachine::stop Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: CoreStateMachine::play index undefined Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: CoreStateMachine::startPlaybackTimer Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: CoreCommandRouter::volumioGetVisibleSources Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jul 10 15:03:22 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand stop Jul 10 15:03:22 tomsprimo54 volumio[3160]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: Updating RAAT Signal Path Jul 10 15:03:22 tomsprimo54 volumio[3160]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jul 10 15:03:22 tomsprimo54 kernel: asoc-aml-card auge_sound: tdm playback stop Jul 10 15:03:22 tomsprimo54 kernel: spdif_a is set to disable Jul 10 15:03:22 tomsprimo54 kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Jul 10 15:03:22 tomsprimo54 kernel: aml_dai_tdm_hw_free(), disable mclk for TDM-B Jul 10 15:03:22 tomsprimo54 kernel: tdm playback mute: 1, lane_cnt = 8 Jul 10 15:03:22 tomsprimo54 kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: Jul 10 15:03:22 tomsprimo54 volumio[3160]: ---------------------------- MPD announces state update: player Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: sendMpdCommand stop took 59 milliseconds Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: ControllerMpd::getState Jul 10 15:03:22 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand status Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: sendMpdCommand stop took 33 milliseconds Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: CoreCommandRouter::servicePushState Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: CoreStateMachine::pushState Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: CoreCommandRouter::volumioPushState Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: MRS: Pushing multiroomSync output update for this device Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: MRS: Pushing multiroomSync output Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:03:22 tomsprimo54 volumio[3160]: verbose: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B000008CUZ.jpg","uri":"https://stream.radioparadise.com/rock-flac","name":"Celebrate","title":"Celebrate","artist":"An Emotional Fish","album":"An Emotional Fish","streaming":true,"disableUiControls":true,"duration":154,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jul 10 15:03:22 tomsprimo54 volumio[3160]: verbose: CURRENT POSITION 0 Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: CoreStateMachine::syncState stateService stop Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: CoreStateMachine::syncState currentStatus stop Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: CoreStateMachine::pushState Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: CoreCommandRouter::volumioPushState Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: MRS: Pushing multiroomSync output update for this device Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: MRS: Pushing multiroomSync output Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: No code Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: CoreStateMachine::pushState Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: CoreCommandRouter::volumioPushState Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: MRS: Pushing multiroomSync output update for this device Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: MRS: Pushing multiroomSync output Jul 10 15:03:22 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand clear Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: Jul 10 15:03:22 tomsprimo54 volumio[3160]: ---------------------------- MPD announces system playlist update Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: Ignoring MPD Status Update Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: sendMpdCommand status took 55 milliseconds Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: sendMpdCommand clear took 17 milliseconds Jul 10 15:03:22 tomsprimo54 volumio[3160]: verbose: ControllerMpd::parseState Jul 10 15:03:22 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 15:03:22 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand consume 1 Jul 10 15:03:22 tomsprimo54 volumio[3160]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: Updating RAAT Signal Path Jul 10 15:03:22 tomsprimo54 volumio[3160]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jul 10 15:03:22 tomsprimo54 volumio[3160]: error: updateQueue error: null Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: ------------------------------ 31ms Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: sendMpdCommand playlistinfo took 28 milliseconds Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: sendMpdCommand consume 1 took 28 milliseconds Jul 10 15:03:22 tomsprimo54 volumio[3160]: verbose: ControllerMpd::parseTrackInfo Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: [1720616602160] [RadioParadise] set to consume mode, adding url: https://stream.radioparadise.com/rock-flac Jul 10 15:03:22 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand add "https://stream.radioparadise.com/rock-flac" Jul 10 15:03:22 tomsprimo54 volumio[3160]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: ------------------------------ 92ms Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: Jul 10 15:03:22 tomsprimo54 volumio[3160]: ---------------------------- MPD announces system playlist update Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: Ignoring MPD Status Update Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: sendMpdCommand add "https://stream.radioparadise.com/rock-flac" took 11 milliseconds Jul 10 15:03:22 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand play Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: ------------------------------ 12ms Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: sendMpdCommand play took 5 milliseconds Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: [1720616602183] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=2 Jul 10 15:03:22 tomsprimo54 volumio[3160]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: Updating RAAT Signal Path Jul 10 15:03:22 tomsprimo54 volumio[3160]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jul 10 15:03:22 tomsprimo54 volumio[3160]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: Updating RAAT Signal Path Jul 10 15:03:22 tomsprimo54 volumio[3160]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: [1720616602756] [RadioParadise] received new metadata: {"time":127,"artist":"An Emotional Fish","title":"Celebrate","album":"An Emotional Fish","year":"1990","cover":"https://img.radioparadise.com/covers/l/B000008CUZ.jpg","cover_med":"https://img.radioparadise.com/covers/m/B000008CUZ.jpg","cover_small":"https://img.radioparadise.com/covers/s/B000008CUZ.jpg"} Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: CoreCommandRouter::servicePushState Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:03:22 tomsprimo54 volumio[3160]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B000008CUZ.jpg","uri":"https://stream.radioparadise.com/rock-flac","name":"Celebrate","title":"Celebrate","artist":"An Emotional Fish","album":"An Emotional Fish","streaming":true,"disableUiControls":true,"duration":132,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jul 10 15:03:22 tomsprimo54 volumio[3160]: verbose: CURRENT POSITION 0 Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: CoreStateMachine::syncState stateService play Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: CoreStateMachine::syncState currentStatus stop Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: [1720616602759] [RadioParadise] setting new timer with duration of 132000 seconds. Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 1 Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: Jul 10 15:03:22 tomsprimo54 volumio[3160]: ---------------------------- MPD announces state update: player Jul 10 15:03:22 tomsprimo54 kernel: aml_tdm_open Jul 10 15:03:22 tomsprimo54 kernel: Not init audio effects Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: ControllerMpd::getState Jul 10 15:03:22 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand status Jul 10 15:03:22 tomsprimo54 kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Jul 10 15:03:22 tomsprimo54 kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Jul 10 15:03:22 tomsprimo54 kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Jul 10 15:03:22 tomsprimo54 kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Jul 10 15:03:22 tomsprimo54 kernel: asoc aml_dai_set_tdm_fmt, 0x4001, ffffffc03d277618, id(1), clksel(1) Jul 10 15:03:22 tomsprimo54 kernel: aml_dai_set_tdm_fmt(), fmt not change Jul 10 15:03:22 tomsprimo54 kernel: dump_pcm_setting(ffffffc03d277618) Jul 10 15:03:22 tomsprimo54 kernel: pcm_mode(1) Jul 10 15:03:22 tomsprimo54 kernel: sysclk(11289600) Jul 10 15:03:22 tomsprimo54 kernel: sysclk_bclk_ratio(4) Jul 10 15:03:22 tomsprimo54 kernel: bclk(2822400) Jul 10 15:03:22 tomsprimo54 kernel: bclk_lrclk_ratio(64) Jul 10 15:03:22 tomsprimo54 kernel: lrclk(44100) Jul 10 15:03:22 tomsprimo54 kernel: tx_mask(0x3) Jul 10 15:03:22 tomsprimo54 kernel: rx_mask(0x3) Jul 10 15:03:22 tomsprimo54 kernel: slots(2) Jul 10 15:03:22 tomsprimo54 kernel: slot_width(32) Jul 10 15:03:22 tomsprimo54 kernel: lane_mask_in(0x2) Jul 10 15:03:22 tomsprimo54 kernel: lane_mask_out(0x1) Jul 10 15:03:22 tomsprimo54 kernel: lane_oe_mask_in(0x0) Jul 10 15:03:22 tomsprimo54 kernel: lane_oe_mask_out(0x0) Jul 10 15:03:22 tomsprimo54 kernel: lane_lb_mask_in(0x0) Jul 10 15:03:22 tomsprimo54 kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Jul 10 15:03:22 tomsprimo54 kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Jul 10 15:03:22 tomsprimo54 kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Jul 10 15:03:22 tomsprimo54 kernel: aml_dai_set_clkdiv, div 4, clksel(1) Jul 10 15:03:22 tomsprimo54 kernel: aml_dai_set_bclk_ratio, select I2S mode Jul 10 15:03:22 tomsprimo54 kernel: aml_dai_tdm_hw_params(), enable mclk for TDM-B Jul 10 15:03:22 tomsprimo54 kernel: aml_tdm_prepare(), reset fddr Jul 10 15:03:22 tomsprimo54 kernel: spdif_a fifo ctrl, frddr:0 type:1, 16 bits, chmask 0x3, swap 0x10 Jul 10 15:03:22 tomsprimo54 kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Jul 10 15:03:22 tomsprimo54 kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Jul 10 15:03:22 tomsprimo54 kernel: tdm playback mute: 0, lane_cnt = 8 Jul 10 15:03:22 tomsprimo54 kernel: aml_tdm_prepare(), reset fddr Jul 10 15:03:22 tomsprimo54 kernel: spdif_a fifo ctrl, frddr:0 type:1, 16 bits, chmask 0x3, swap 0x10 Jul 10 15:03:22 tomsprimo54 kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Jul 10 15:03:22 tomsprimo54 kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Jul 10 15:03:22 tomsprimo54 kernel: tdm playback mute: 0, lane_cnt = 8 Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: Jul 10 15:03:22 tomsprimo54 volumio[3160]: ---------------------------- MPD announces state update: player Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: sendMpdCommand status took 11 milliseconds Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: ControllerMpd::getState Jul 10 15:03:22 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand status Jul 10 15:03:22 tomsprimo54 volumio[3160]: verbose: ControllerMpd::parseState Jul 10 15:03:22 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: sendMpdCommand status took 8 milliseconds Jul 10 15:03:22 tomsprimo54 volumio[3160]: verbose: ControllerMpd::parseState Jul 10 15:03:22 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: sendMpdCommand playlistinfo took 10 milliseconds Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 10 15:03:22 tomsprimo54 volumio[3160]: verbose: ControllerMpd::parseTrackInfo Jul 10 15:03:22 tomsprimo54 volumio[3160]: verbose: ControllerMpd::parseTrackInfo Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: ControllerMpd::pushState Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: CoreCommandRouter::servicePushState Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: ControllerMpd::pushState Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: CoreCommandRouter::servicePushState Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: ------------------------------ 29ms Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: ------------------------------ 17ms Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: Jul 10 15:03:22 tomsprimo54 volumio[3160]: ---------------------------- MPD announces system playlist update Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: Ignoring MPD Status Update Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: Jul 10 15:03:22 tomsprimo54 volumio[3160]: ---------------------------- MPD announces state update: player Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: ControllerMpd::getState Jul 10 15:03:22 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand status Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: ------------------------------ 4ms Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: sendMpdCommand status took 3 milliseconds Jul 10 15:03:22 tomsprimo54 volumio[3160]: verbose: ControllerMpd::parseState Jul 10 15:03:22 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 10 15:03:22 tomsprimo54 volumio[3160]: verbose: ControllerMpd::parseTrackInfo Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: ControllerMpd::pushState Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: CoreCommandRouter::servicePushState Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Jul 10 15:03:22 tomsprimo54 volumio[3160]: info: ------------------------------ 9ms Jul 10 15:03:22 tomsprimo54 kernel: asoc-aml-card auge_sound: tdm playback enable Jul 10 15:03:22 tomsprimo54 kernel: spdif_a is set to enable Jul 10 15:03:24 tomsprimo54 volumio[3160]: info: Executing endpoint metavolumio Jul 10 15:03:24 tomsprimo54 volumio[3160]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 10 15:03:27 tomsprimo54 volumio[3160]: info: CoreCommandRouter::volumioPlay Jul 10 15:03:27 tomsprimo54 volumio[3160]: info: CoreStateMachine::play index undefined Jul 10 15:03:27 tomsprimo54 volumio[3160]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 10 15:03:27 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:03:31 tomsprimo54 volumio[3160]: error: Failed request for metavolumio API Jul 10 15:03:31 tomsprimo54 volumio[3160]: info: CoreCommandRouter::volumioNext Jul 10 15:03:31 tomsprimo54 volumio[3160]: info: CoreStateMachine::next Jul 10 15:03:31 tomsprimo54 volumio[3160]: info: CoreStateMachine::stop Jul 10 15:03:31 tomsprimo54 volumio[3160]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 10 15:03:31 tomsprimo54 volumio[3160]: info: CoreStateMachine::stPlaybackTimer Jul 10 15:03:31 tomsprimo54 volumio[3160]: info: CoreStateMachine::updateTrackBlock Jul 10 15:03:31 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrackBlock Jul 10 15:03:31 tomsprimo54 volumio[3160]: info: CoreStateMachine::pushState Jul 10 15:03:31 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:03:31 tomsprimo54 volumio[3160]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 15:03:31 tomsprimo54 volumio[3160]: info: CoreCommandRouter::volumioPushState Jul 10 15:03:31 tomsprimo54 volumio[3160]: info: MRS: Pushing multiroomSync output update for this device Jul 10 15:03:31 tomsprimo54 volumio[3160]: info: MRS: Pushing multiroomSync output Jul 10 15:03:31 tomsprimo54 volumio[3160]: info: CoreStateMachine::serviceStop Jul 10 15:03:31 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:03:31 tomsprimo54 volumio[3160]: info: CoreCommandRouter::serviceStop Jul 10 15:03:31 tomsprimo54 volumio[3160]: info: ControllerMpd::stop Jul 10 15:03:31 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand stop Jul 10 15:03:32 tomsprimo54 volumio[3160]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jul 10 15:03:32 tomsprimo54 volumio[3160]: info: Updating RAAT Signal Path Jul 10 15:03:32 tomsprimo54 volumio[3160]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jul 10 15:03:32 tomsprimo54 kernel: asoc-aml-card auge_sound: tdm playback stop Jul 10 15:03:32 tomsprimo54 kernel: spdif_a is set to disable Jul 10 15:03:32 tomsprimo54 kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Jul 10 15:03:32 tomsprimo54 kernel: aml_dai_tdm_hw_free(), disable mclk for TDM-B Jul 10 15:03:32 tomsprimo54 kernel: tdm playback mute: 1, lane_cnt = 8 Jul 10 15:03:32 tomsprimo54 kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Jul 10 15:03:32 tomsprimo54 volumio[3160]: info: Jul 10 15:03:32 tomsprimo54 volumio[3160]: ---------------------------- MPD announces state update: player Jul 10 15:03:32 tomsprimo54 volumio[3160]: info: sendMpdCommand stop took 27 milliseconds Jul 10 15:03:32 tomsprimo54 volumio[3160]: info: ControllerMpd::getState Jul 10 15:03:32 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand status Jul 10 15:03:32 tomsprimo54 volumio[3160]: info: CoreCommandRouter::servicePushState Jul 10 15:03:32 tomsprimo54 volumio[3160]: info: CoreStateMachine::pushState Jul 10 15:03:32 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:03:32 tomsprimo54 volumio[3160]: info: CoreCommandRouter::volumioPushState Jul 10 15:03:32 tomsprimo54 volumio[3160]: info: MRS: Pushing multiroomSync output update for this device Jul 10 15:03:32 tomsprimo54 volumio[3160]: info: MRS: Pushing multiroomSync output Jul 10 15:03:32 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:03:32 tomsprimo54 volumio[3160]: verbose: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B000008CUZ.jpg","uri":"https://stream.radioparadise.com/rock-flac","name":"Celebrate","title":"Celebrate","artist":"An Emotional Fish","album":"An Emotional Fish","streaming":true,"disableUiControls":true,"duration":132,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jul 10 15:03:32 tomsprimo54 volumio[3160]: verbose: CURRENT POSITION 0 Jul 10 15:03:32 tomsprimo54 volumio[3160]: info: CoreStateMachine::syncState stateService stop Jul 10 15:03:32 tomsprimo54 volumio[3160]: info: CoreStateMachine::syncState currentStatus stop Jul 10 15:03:32 tomsprimo54 volumio[3160]: info: CoreStateMachine::pushState Jul 10 15:03:32 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:03:32 tomsprimo54 volumio[3160]: info: CoreCommandRouter::volumioPushState Jul 10 15:03:32 tomsprimo54 volumio[3160]: info: MRS: Pushing multiroomSync output update for this device Jul 10 15:03:32 tomsprimo54 volumio[3160]: info: MRS: Pushing multiroomSync output Jul 10 15:03:32 tomsprimo54 volumio[3160]: info: No code Jul 10 15:03:32 tomsprimo54 volumio[3160]: info: CoreStateMachine::pushState Jul 10 15:03:32 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:03:32 tomsprimo54 volumio[3160]: info: CoreCommandRouter::volumioPushState Jul 10 15:03:32 tomsprimo54 volumio[3160]: info: MRS: Pushing multiroomSync output update for this device Jul 10 15:03:32 tomsprimo54 volumio[3160]: info: MRS: Pushing multiroomSync output Jul 10 15:03:32 tomsprimo54 volumio[3160]: info: CoreStateMachine::play index undefined Jul 10 15:03:32 tomsprimo54 volumio[3160]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 10 15:03:32 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 1 Jul 10 15:03:32 tomsprimo54 volumio[3160]: info: sendMpdCommand status took 53 milliseconds Jul 10 15:03:32 tomsprimo54 volumio[3160]: verbose: ControllerMpd::parseState Jul 10 15:03:32 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 15:03:32 tomsprimo54 volumio[3160]: info: sendMpdCommand playlistinfo took 8 milliseconds Jul 10 15:03:32 tomsprimo54 volumio[3160]: verbose: ControllerMpd::parseTrackInfo Jul 10 15:03:32 tomsprimo54 volumio[3160]: info: ControllerMpd::pushState Jul 10 15:03:32 tomsprimo54 volumio[3160]: info: CoreCommandRouter::servicePushState Jul 10 15:03:32 tomsprimo54 volumio[3160]: info: CoreStateMachine::pushState Jul 10 15:03:32 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:03:32 tomsprimo54 volumio[3160]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 15:03:32 tomsprimo54 volumio[3160]: info: CoreCommandRouter::volumioPushState Jul 10 15:03:32 tomsprimo54 volumio[3160]: info: MRS: Pushing multiroomSync output update for this device Jul 10 15:03:32 tomsprimo54 volumio[3160]: info: MRS: Pushing multiroomSync output Jul 10 15:03:32 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:03:32 tomsprimo54 volumio[3160]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Jul 10 15:03:32 tomsprimo54 volumio[3160]: info: ------------------------------ 87ms Jul 10 15:03:32 tomsprimo54 volumio[3160]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jul 10 15:03:32 tomsprimo54 volumio[3160]: info: Updating RAAT Signal Path Jul 10 15:03:32 tomsprimo54 volumio[3160]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jul 10 15:03:32 tomsprimo54 volumio[3160]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jul 10 15:03:32 tomsprimo54 volumio[3160]: info: Updating RAAT Signal Path Jul 10 15:03:32 tomsprimo54 volumio[3160]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jul 10 15:03:32 tomsprimo54 volumio[3160]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jul 10 15:03:32 tomsprimo54 volumio[3160]: info: Updating RAAT Signal Path Jul 10 15:03:32 tomsprimo54 volumio[3160]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jul 10 15:03:32 tomsprimo54 volumio[3160]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jul 10 15:03:32 tomsprimo54 volumio[3160]: info: Updating RAAT Signal Path Jul 10 15:03:32 tomsprimo54 volumio[3160]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jul 10 15:03:34 tomsprimo54 volumio[3160]: info: Executing endpoint metavolumio Jul 10 15:03:34 tomsprimo54 volumio[3160]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 10 15:03:34 tomsprimo54 volumio[3160]: info: Executing endpoint metavolumio Jul 10 15:03:34 tomsprimo54 volumio[3160]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 10 15:03:34 tomsprimo54 volumio[3160]: info: Executing endpoint metavolumio Jul 10 15:03:34 tomsprimo54 volumio[3160]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 10 15:03:36 tomsprimo54 volumio[3160]: info: CoreCommandRouter::volumioNext Jul 10 15:03:36 tomsprimo54 volumio[3160]: info: CoreStateMachine::next Jul 10 15:03:36 tomsprimo54 volumio[3160]: info: CoreStateMachine::stop Jul 10 15:03:36 tomsprimo54 volumio[3160]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 10 15:03:36 tomsprimo54 volumio[3160]: info: CoreStateMachine::play index undefined Jul 10 15:03:36 tomsprimo54 volumio[3160]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 10 15:03:36 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 1 Jul 10 15:03:38 tomsprimo54 volumio[3160]: info: CoreCommandRouter::volumioNext Jul 10 15:03:38 tomsprimo54 volumio[3160]: info: CoreStateMachine::next Jul 10 15:03:38 tomsprimo54 volumio[3160]: info: CoreStateMachine::stop Jul 10 15:03:38 tomsprimo54 volumio[3160]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 10 15:03:38 tomsprimo54 volumio[3160]: info: CoreStateMachine::play index undefined Jul 10 15:03:38 tomsprimo54 volumio[3160]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 10 15:03:38 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 1 Jul 10 15:03:40 tomsprimo54 volumio[3160]: info: CoreCommandRouter::volumioPrevious Jul 10 15:03:40 tomsprimo54 volumio[3160]: info: CoreStateMachine::previous Jul 10 15:03:40 tomsprimo54 volumio[3160]: info: CoreStateMachine::updateTrackBlock Jul 10 15:03:40 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrackBlock Jul 10 15:03:40 tomsprimo54 volumio[3160]: info: CoreStateMachine::serviceClearAddPlay Jul 10 15:03:40 tomsprimo54 volumio[3160]: info: CoreCommandRouter::serviceClearAddPlayTracks Jul 10 15:03:40 tomsprimo54 volumio[3160]: error: WARNING: No clearAddPlayTracks method for service radio_paradise Jul 10 15:03:41 tomsprimo54 volumio[3160]: error: Failed request for metavolumio API Jul 10 15:03:41 tomsprimo54 volumio[3160]: error: Failed request for metavolumio API Jul 10 15:03:41 tomsprimo54 volumio[3160]: error: Failed request for metavolumio API Jul 10 15:03:44 tomsprimo54 volumio[3160]: info: CoreCommandRouter::volumioPlay Jul 10 15:03:44 tomsprimo54 volumio[3160]: info: CoreStateMachine::play index undefined Jul 10 15:03:44 tomsprimo54 volumio[3160]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 10 15:03:44 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 1 Jul 10 15:03:49 tomsprimo54 volumio[3160]: info: CoreCommandRouter::volumioPlay Jul 10 15:03:49 tomsprimo54 volumio[3160]: info: CoreStateMachine::play index undefined Jul 10 15:03:49 tomsprimo54 volumio[3160]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 10 15:03:49 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:03:49 tomsprimo54 volumio[3160]: info: CoreStateMachine::startPlaybackTimer Jul 10 15:03:49 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:03:49 tomsprimo54 volumio[3160]: info: CoreCommandRouter::volumioGetVisibleSources Jul 10 15:03:49 tomsprimo54 volumio[3160]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 15:03:49 tomsprimo54 volumio[3160]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jul 10 15:03:49 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand stop Jul 10 15:03:49 tomsprimo54 volumio[3160]: info: sendMpdCommand stop took 1 milliseconds Jul 10 15:03:49 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand clear Jul 10 15:03:49 tomsprimo54 volumio[3160]: info: Jul 10 15:03:49 tomsprimo54 volumio[3160]: ---------------------------- MPD announces system playlist update Jul 10 15:03:49 tomsprimo54 volumio[3160]: info: Ignoring MPD Status Update Jul 10 15:03:49 tomsprimo54 volumio[3160]: info: sendMpdCommand clear took 1 milliseconds Jul 10 15:03:49 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand consume 1 Jul 10 15:03:49 tomsprimo54 volumio[3160]: error: updateQueue error: null Jul 10 15:03:49 tomsprimo54 volumio[3160]: info: ------------------------------ 3ms Jul 10 15:03:49 tomsprimo54 volumio[3160]: info: sendMpdCommand consume 1 took 2 milliseconds Jul 10 15:03:49 tomsprimo54 volumio[3160]: info: [1720616629453] [RadioParadise] set to consume mode, adding url: https://stream.radioparadise.com/rock-flac Jul 10 15:03:49 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand add "https://stream.radioparadise.com/rock-flac" Jul 10 15:03:49 tomsprimo54 volumio[3160]: info: Jul 10 15:03:49 tomsprimo54 volumio[3160]: ---------------------------- MPD announces system playlist update Jul 10 15:03:49 tomsprimo54 volumio[3160]: info: Ignoring MPD Status Update Jul 10 15:03:49 tomsprimo54 volumio[3160]: info: sendMpdCommand add "https://stream.radioparadise.com/rock-flac" took 2 milliseconds Jul 10 15:03:49 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand play Jul 10 15:03:49 tomsprimo54 volumio[3160]: info: ------------------------------ 7ms Jul 10 15:03:49 tomsprimo54 volumio[3160]: info: sendMpdCommand play took 5 milliseconds Jul 10 15:03:49 tomsprimo54 volumio[3160]: info: [1720616629465] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=2 Jul 10 15:03:50 tomsprimo54 volumio[3160]: info: Jul 10 15:03:50 tomsprimo54 volumio[3160]: ---------------------------- MPD announces state update: player Jul 10 15:03:50 tomsprimo54 kernel: aml_tdm_open Jul 10 15:03:50 tomsprimo54 kernel: Not init audio effects Jul 10 15:03:50 tomsprimo54 volumio[3160]: info: ControllerMpd::getState Jul 10 15:03:50 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand status Jul 10 15:03:50 tomsprimo54 kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Jul 10 15:03:50 tomsprimo54 kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Jul 10 15:03:50 tomsprimo54 kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Jul 10 15:03:50 tomsprimo54 kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Jul 10 15:03:50 tomsprimo54 kernel: asoc aml_dai_set_tdm_fmt, 0x4001, ffffffc03d277618, id(1), clksel(1) Jul 10 15:03:50 tomsprimo54 kernel: aml_dai_set_tdm_fmt(), fmt not change Jul 10 15:03:50 tomsprimo54 kernel: dump_pcm_setting(ffffffc03d277618) Jul 10 15:03:50 tomsprimo54 kernel: pcm_mode(1) Jul 10 15:03:50 tomsprimo54 kernel: sysclk(11289600) Jul 10 15:03:50 tomsprimo54 kernel: sysclk_bclk_ratio(4) Jul 10 15:03:50 tomsprimo54 kernel: bclk(2822400) Jul 10 15:03:50 tomsprimo54 kernel: bclk_lrclk_ratio(64) Jul 10 15:03:50 tomsprimo54 kernel: lrclk(44100) Jul 10 15:03:50 tomsprimo54 kernel: tx_mask(0x3) Jul 10 15:03:50 tomsprimo54 kernel: rx_mask(0x3) Jul 10 15:03:50 tomsprimo54 kernel: slots(2) Jul 10 15:03:50 tomsprimo54 kernel: slot_width(32) Jul 10 15:03:50 tomsprimo54 kernel: lane_mask_in(0x2) Jul 10 15:03:50 tomsprimo54 kernel: lane_mask_out(0x1) Jul 10 15:03:50 tomsprimo54 kernel: lane_oe_mask_in(0x0) Jul 10 15:03:50 tomsprimo54 kernel: lane_oe_mask_out(0x0) Jul 10 15:03:50 tomsprimo54 kernel: lane_lb_mask_in(0x0) Jul 10 15:03:50 tomsprimo54 kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Jul 10 15:03:50 tomsprimo54 kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Jul 10 15:03:50 tomsprimo54 kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Jul 10 15:03:50 tomsprimo54 kernel: aml_dai_set_clkdiv, div 4, clksel(1) Jul 10 15:03:50 tomsprimo54 kernel: aml_dai_set_bclk_ratio, select I2S mode Jul 10 15:03:50 tomsprimo54 kernel: aml_dai_tdm_hw_params(), enable mclk for TDM-B Jul 10 15:03:50 tomsprimo54 kernel: aml_tdm_prepare(), reset fddr Jul 10 15:03:50 tomsprimo54 kernel: spdif_a fifo ctrl, frddr:0 type:1, 16 bits, chmask 0x3, swap 0x10 Jul 10 15:03:50 tomsprimo54 kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Jul 10 15:03:50 tomsprimo54 kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Jul 10 15:03:50 tomsprimo54 kernel: tdm playback mute: 0, lane_cnt = 8 Jul 10 15:03:50 tomsprimo54 kernel: aml_tdm_prepare(), reset fddr Jul 10 15:03:50 tomsprimo54 kernel: spdif_a fifo ctrl, frddr:0 type:1, 16 bits, chmask 0x3, swap 0x10 Jul 10 15:03:50 tomsprimo54 kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Jul 10 15:03:50 tomsprimo54 kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Jul 10 15:03:50 tomsprimo54 kernel: tdm playback mute: 0, lane_cnt = 8 Jul 10 15:03:50 tomsprimo54 volumio[3160]: info: Jul 10 15:03:50 tomsprimo54 volumio[3160]: ---------------------------- MPD announces state update: player Jul 10 15:03:50 tomsprimo54 volumio[3160]: info: sendMpdCommand status took 11 milliseconds Jul 10 15:03:50 tomsprimo54 volumio[3160]: info: ControllerMpd::getState Jul 10 15:03:50 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand status Jul 10 15:03:50 tomsprimo54 volumio[3160]: verbose: ControllerMpd::parseState Jul 10 15:03:50 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 15:03:50 tomsprimo54 volumio[3160]: info: sendMpdCommand status took 2 milliseconds Jul 10 15:03:50 tomsprimo54 volumio[3160]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 10 15:03:50 tomsprimo54 volumio[3160]: verbose: ControllerMpd::parseState Jul 10 15:03:50 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 15:03:50 tomsprimo54 volumio[3160]: verbose: ControllerMpd::parseTrackInfo Jul 10 15:03:50 tomsprimo54 volumio[3160]: info: ControllerMpd::pushState Jul 10 15:03:50 tomsprimo54 volumio[3160]: info: CoreCommandRouter::servicePushState Jul 10 15:03:50 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:03:50 tomsprimo54 volumio[3160]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Jul 10 15:03:50 tomsprimo54 volumio[3160]: info: ------------------------------ 24ms Jul 10 15:03:50 tomsprimo54 volumio[3160]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 10 15:03:50 tomsprimo54 volumio[3160]: verbose: ControllerMpd::parseTrackInfo Jul 10 15:03:50 tomsprimo54 volumio[3160]: info: ControllerMpd::pushState Jul 10 15:03:50 tomsprimo54 volumio[3160]: info: CoreCommandRouter::servicePushState Jul 10 15:03:50 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:03:50 tomsprimo54 volumio[3160]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Jul 10 15:03:50 tomsprimo54 volumio[3160]: info: ------------------------------ 10ms Jul 10 15:03:50 tomsprimo54 volumio[3160]: info: [1720616630028] [RadioParadise] received new metadata: {"time":100,"artist":"An Emotional Fish","title":"Celebrate","album":"An Emotional Fish","year":"1990","cover":"https://img.radioparadise.com/covers/l/B000008CUZ.jpg","cover_med":"https://img.radioparadise.com/covers/m/B000008CUZ.jpg","cover_small":"https://img.radioparadise.com/covers/s/B000008CUZ.jpg"} Jul 10 15:03:50 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:03:50 tomsprimo54 volumio[3160]: info: CoreCommandRouter::servicePushState Jul 10 15:03:50 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:03:50 tomsprimo54 volumio[3160]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B000008CUZ.jpg","uri":"https://stream.radioparadise.com/rock-flac","name":"Celebrate","title":"Celebrate","artist":"An Emotional Fish","album":"An Emotional Fish","streaming":true,"disableUiControls":true,"duration":105,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jul 10 15:03:50 tomsprimo54 volumio[3160]: verbose: CURRENT POSITION 0 Jul 10 15:03:50 tomsprimo54 volumio[3160]: info: CoreStateMachine::syncState stateService play Jul 10 15:03:50 tomsprimo54 volumio[3160]: info: CoreStateMachine::syncState currentStatus stop Jul 10 15:03:50 tomsprimo54 volumio[3160]: info: [1720616630031] [RadioParadise] setting new timer with duration of 105000 seconds. Jul 10 15:03:50 tomsprimo54 volumio[3160]: info: Jul 10 15:03:50 tomsprimo54 volumio[3160]: ---------------------------- MPD announces system playlist update Jul 10 15:03:50 tomsprimo54 volumio[3160]: info: Ignoring MPD Status Update Jul 10 15:03:50 tomsprimo54 volumio[3160]: info: Jul 10 15:03:50 tomsprimo54 volumio[3160]: ---------------------------- MPD announces state update: player Jul 10 15:03:50 tomsprimo54 volumio[3160]: info: ControllerMpd::getState Jul 10 15:03:50 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand status Jul 10 15:03:50 tomsprimo54 volumio[3160]: info: ------------------------------ 3ms Jul 10 15:03:50 tomsprimo54 volumio[3160]: info: sendMpdCommand status took 2 milliseconds Jul 10 15:03:50 tomsprimo54 volumio[3160]: verbose: ControllerMpd::parseState Jul 10 15:03:50 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 15:03:50 tomsprimo54 volumio[3160]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 10 15:03:50 tomsprimo54 volumio[3160]: verbose: ControllerMpd::parseTrackInfo Jul 10 15:03:50 tomsprimo54 volumio[3160]: info: ControllerMpd::pushState Jul 10 15:03:50 tomsprimo54 volumio[3160]: info: CoreCommandRouter::servicePushState Jul 10 15:03:50 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:03:50 tomsprimo54 volumio[3160]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Jul 10 15:03:50 tomsprimo54 volumio[3160]: info: ------------------------------ 6ms Jul 10 15:03:50 tomsprimo54 kernel: asoc-aml-card auge_sound: tdm playback enable Jul 10 15:03:50 tomsprimo54 kernel: spdif_a is set to enable Jul 10 15:03:51 tomsprimo54 volumio[3160]: info: CoreCommandRouter::volumioNext Jul 10 15:03:51 tomsprimo54 volumio[3160]: info: CoreStateMachine::next Jul 10 15:03:51 tomsprimo54 volumio[3160]: info: CoreStateMachine::stop Jul 10 15:03:51 tomsprimo54 volumio[3160]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 10 15:03:51 tomsprimo54 volumio[3160]: info: CoreStateMachine::stPlaybackTimer Jul 10 15:03:51 tomsprimo54 volumio[3160]: info: CoreStateMachine::updateTrackBlock Jul 10 15:03:51 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrackBlock Jul 10 15:03:51 tomsprimo54 volumio[3160]: info: CoreStateMachine::pushState Jul 10 15:03:51 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:03:51 tomsprimo54 volumio[3160]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 15:03:51 tomsprimo54 volumio[3160]: info: CoreCommandRouter::volumioPushState Jul 10 15:03:51 tomsprimo54 volumio[3160]: info: MRS: Pushing multiroomSync output update for this device Jul 10 15:03:51 tomsprimo54 volumio[3160]: info: MRS: Pushing multiroomSync output Jul 10 15:03:51 tomsprimo54 volumio[3160]: info: CoreStateMachine::serviceStop Jul 10 15:03:51 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:03:51 tomsprimo54 volumio[3160]: info: CoreCommandRouter::serviceStop Jul 10 15:03:51 tomsprimo54 volumio[3160]: info: ControllerMpd::stop Jul 10 15:03:51 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand stop Jul 10 15:03:51 tomsprimo54 volumio[3160]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jul 10 15:03:51 tomsprimo54 volumio[3160]: info: Updating RAAT Signal Path Jul 10 15:03:51 tomsprimo54 volumio[3160]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jul 10 15:03:51 tomsprimo54 kernel: asoc-aml-card auge_sound: tdm playback stop Jul 10 15:03:51 tomsprimo54 kernel: spdif_a is set to disable Jul 10 15:03:51 tomsprimo54 kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Jul 10 15:03:51 tomsprimo54 kernel: aml_dai_tdm_hw_free(), disable mclk for TDM-B Jul 10 15:03:51 tomsprimo54 kernel: tdm playback mute: 1, lane_cnt = 8 Jul 10 15:03:51 tomsprimo54 kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Jul 10 15:03:51 tomsprimo54 volumio[3160]: info: Jul 10 15:03:51 tomsprimo54 volumio[3160]: ---------------------------- MPD announces state update: player Jul 10 15:03:51 tomsprimo54 volumio[3160]: info: sendMpdCommand stop took 38 milliseconds Jul 10 15:03:51 tomsprimo54 volumio[3160]: info: ControllerMpd::getState Jul 10 15:03:51 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand status Jul 10 15:03:51 tomsprimo54 volumio[3160]: info: CoreCommandRouter::servicePushState Jul 10 15:03:51 tomsprimo54 volumio[3160]: info: CoreStateMachine::pushState Jul 10 15:03:51 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:03:51 tomsprimo54 volumio[3160]: info: CoreCommandRouter::volumioPushState Jul 10 15:03:51 tomsprimo54 volumio[3160]: info: MRS: Pushing multiroomSync output update for this device Jul 10 15:03:51 tomsprimo54 volumio[3160]: info: MRS: Pushing multiroomSync output Jul 10 15:03:51 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:03:51 tomsprimo54 volumio[3160]: verbose: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B000008CUZ.jpg","uri":"https://stream.radioparadise.com/rock-flac","name":"Celebrate","title":"Celebrate","artist":"An Emotional Fish","album":"An Emotional Fish","streaming":true,"disableUiControls":true,"duration":105,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jul 10 15:03:51 tomsprimo54 volumio[3160]: verbose: CURRENT POSITION 0 Jul 10 15:03:51 tomsprimo54 volumio[3160]: info: CoreStateMachine::syncState stateService stop Jul 10 15:03:51 tomsprimo54 volumio[3160]: info: CoreStateMachine::syncState currentStatus stop Jul 10 15:03:51 tomsprimo54 volumio[3160]: info: CoreStateMachine::pushState Jul 10 15:03:51 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:03:51 tomsprimo54 volumio[3160]: info: CoreCommandRouter::volumioPushState Jul 10 15:03:51 tomsprimo54 volumio[3160]: info: MRS: Pushing multiroomSync output update for this device Jul 10 15:03:51 tomsprimo54 volumio[3160]: info: MRS: Pushing multiroomSync output Jul 10 15:03:51 tomsprimo54 volumio[3160]: info: No code Jul 10 15:03:51 tomsprimo54 volumio[3160]: info: CoreStateMachine::pushState Jul 10 15:03:51 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:03:51 tomsprimo54 volumio[3160]: info: CoreCommandRouter::volumioPushState Jul 10 15:03:51 tomsprimo54 volumio[3160]: info: MRS: Pushing multiroomSync output update for this device Jul 10 15:03:51 tomsprimo54 volumio[3160]: info: MRS: Pushing multiroomSync output Jul 10 15:03:51 tomsprimo54 volumio[3160]: info: CoreStateMachine::play index undefined Jul 10 15:03:51 tomsprimo54 volumio[3160]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 10 15:03:51 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 1 Jul 10 15:03:51 tomsprimo54 volumio[3160]: info: sendMpdCommand status took 58 milliseconds Jul 10 15:03:51 tomsprimo54 volumio[3160]: verbose: ControllerMpd::parseState Jul 10 15:03:51 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 15:03:51 tomsprimo54 volumio[3160]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jul 10 15:03:51 tomsprimo54 volumio[3160]: info: Updating RAAT Signal Path Jul 10 15:03:51 tomsprimo54 volumio[3160]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jul 10 15:03:51 tomsprimo54 volumio[3160]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jul 10 15:03:51 tomsprimo54 volumio[3160]: info: Updating RAAT Signal Path Jul 10 15:03:51 tomsprimo54 volumio[3160]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jul 10 15:03:51 tomsprimo54 volumio[3160]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jul 10 15:03:51 tomsprimo54 volumio[3160]: info: Updating RAAT Signal Path Jul 10 15:03:51 tomsprimo54 volumio[3160]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jul 10 15:03:51 tomsprimo54 volumio[3160]: info: sendMpdCommand playlistinfo took 25 milliseconds Jul 10 15:03:51 tomsprimo54 volumio[3160]: verbose: ControllerMpd::parseTrackInfo Jul 10 15:03:51 tomsprimo54 volumio[3160]: info: ControllerMpd::pushState Jul 10 15:03:51 tomsprimo54 volumio[3160]: info: CoreCommandRouter::servicePushState Jul 10 15:03:51 tomsprimo54 volumio[3160]: info: CoreStateMachine::pushState Jul 10 15:03:51 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:03:51 tomsprimo54 volumio[3160]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 15:03:51 tomsprimo54 volumio[3160]: info: CoreCommandRouter::volumioPushState Jul 10 15:03:51 tomsprimo54 volumio[3160]: info: MRS: Pushing multiroomSync output update for this device Jul 10 15:03:51 tomsprimo54 volumio[3160]: info: MRS: Pushing multiroomSync output Jul 10 15:03:51 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:03:51 tomsprimo54 volumio[3160]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Jul 10 15:03:51 tomsprimo54 volumio[3160]: info: ------------------------------ 108ms Jul 10 15:03:51 tomsprimo54 volumio[3160]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jul 10 15:03:51 tomsprimo54 volumio[3160]: info: Updating RAAT Signal Path Jul 10 15:03:51 tomsprimo54 volumio[3160]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jul 10 15:03:53 tomsprimo54 volumio[3160]: info: CoreCommandRouter::volumioPrevious Jul 10 15:03:53 tomsprimo54 volumio[3160]: info: CoreStateMachine::previous Jul 10 15:03:53 tomsprimo54 volumio[3160]: info: CoreStateMachine::updateTrackBlock Jul 10 15:03:53 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrackBlock Jul 10 15:03:53 tomsprimo54 volumio[3160]: info: CoreStateMachine::serviceClearAddPlay Jul 10 15:03:53 tomsprimo54 volumio[3160]: info: CoreCommandRouter::serviceClearAddPlayTracks Jul 10 15:03:53 tomsprimo54 volumio[3160]: error: WARNING: No clearAddPlayTracks method for service radio_paradise Jul 10 15:03:53 tomsprimo54 volumio[3160]: info: CoreCommandRouter::volumioPlay Jul 10 15:03:53 tomsprimo54 volumio[3160]: info: CoreStateMachine::play index undefined Jul 10 15:03:53 tomsprimo54 volumio[3160]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 10 15:03:53 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 1 Jul 10 15:03:55 tomsprimo54 volumio[3160]: info: CoreCommandRouter::volumioPlay Jul 10 15:03:55 tomsprimo54 volumio[3160]: info: CoreStateMachine::play index undefined Jul 10 15:03:55 tomsprimo54 volumio[3160]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 10 15:03:55 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:03:55 tomsprimo54 volumio[3160]: info: CoreStateMachine::startPlaybackTimer Jul 10 15:03:55 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:03:55 tomsprimo54 volumio[3160]: info: CoreCommandRouter::volumioGetVisibleSources Jul 10 15:03:55 tomsprimo54 volumio[3160]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 15:03:55 tomsprimo54 volumio[3160]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jul 10 15:03:55 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand stop Jul 10 15:03:55 tomsprimo54 volumio[3160]: info: sendMpdCommand stop took 1 milliseconds Jul 10 15:03:55 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand clear Jul 10 15:03:55 tomsprimo54 volumio[3160]: info: Jul 10 15:03:55 tomsprimo54 volumio[3160]: ---------------------------- MPD announces system playlist update Jul 10 15:03:55 tomsprimo54 volumio[3160]: info: Ignoring MPD Status Update Jul 10 15:03:55 tomsprimo54 volumio[3160]: info: sendMpdCommand clear took 2 milliseconds Jul 10 15:03:55 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand consume 1 Jul 10 15:03:55 tomsprimo54 volumio[3160]: error: updateQueue error: null Jul 10 15:03:55 tomsprimo54 volumio[3160]: info: ------------------------------ 3ms Jul 10 15:03:55 tomsprimo54 volumio[3160]: info: sendMpdCommand consume 1 took 1 milliseconds Jul 10 15:03:55 tomsprimo54 volumio[3160]: info: [1720616635761] [RadioParadise] set to consume mode, adding url: https://stream.radioparadise.com/rock-flac Jul 10 15:03:55 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand add "https://stream.radioparadise.com/rock-flac" Jul 10 15:03:55 tomsprimo54 volumio[3160]: info: Jul 10 15:03:55 tomsprimo54 volumio[3160]: ---------------------------- MPD announces system playlist update Jul 10 15:03:55 tomsprimo54 volumio[3160]: info: Ignoring MPD Status Update Jul 10 15:03:55 tomsprimo54 volumio[3160]: info: sendMpdCommand add "https://stream.radioparadise.com/rock-flac" took 2 milliseconds Jul 10 15:03:55 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand play Jul 10 15:03:55 tomsprimo54 volumio[3160]: info: ------------------------------ 6ms Jul 10 15:03:55 tomsprimo54 volumio[3160]: info: sendMpdCommand play took 2 milliseconds Jul 10 15:03:55 tomsprimo54 volumio[3160]: info: [1720616635769] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=2 Jul 10 15:03:56 tomsprimo54 kernel: aml_tdm_open Jul 10 15:03:56 tomsprimo54 kernel: Not init audio effects Jul 10 15:03:56 tomsprimo54 kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Jul 10 15:03:56 tomsprimo54 kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Jul 10 15:03:56 tomsprimo54 kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Jul 10 15:03:56 tomsprimo54 kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Jul 10 15:03:56 tomsprimo54 kernel: asoc aml_dai_set_tdm_fmt, 0x4001, ffffffc03d277618, id(1), clksel(1) Jul 10 15:03:56 tomsprimo54 kernel: aml_dai_set_tdm_fmt(), fmt not change Jul 10 15:03:56 tomsprimo54 kernel: dump_pcm_setting(ffffffc03d277618) Jul 10 15:03:56 tomsprimo54 kernel: pcm_mode(1) Jul 10 15:03:56 tomsprimo54 kernel: sysclk(11289600) Jul 10 15:03:56 tomsprimo54 kernel: sysclk_bclk_ratio(4) Jul 10 15:03:56 tomsprimo54 kernel: bclk(2822400) Jul 10 15:03:56 tomsprimo54 kernel: bclk_lrclk_ratio(64) Jul 10 15:03:56 tomsprimo54 kernel: lrclk(44100) Jul 10 15:03:56 tomsprimo54 kernel: tx_mask(0x3) Jul 10 15:03:56 tomsprimo54 kernel: rx_mask(0x3) Jul 10 15:03:56 tomsprimo54 kernel: slots(2) Jul 10 15:03:56 tomsprimo54 kernel: slot_width(32) Jul 10 15:03:56 tomsprimo54 kernel: lane_mask_in(0x2) Jul 10 15:03:56 tomsprimo54 kernel: lane_mask_out(0x1) Jul 10 15:03:56 tomsprimo54 kernel: lane_oe_mask_in(0x0) Jul 10 15:03:56 tomsprimo54 kernel: lane_oe_mask_out(0x0) Jul 10 15:03:56 tomsprimo54 kernel: lane_lb_mask_in(0x0) Jul 10 15:03:56 tomsprimo54 kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Jul 10 15:03:56 tomsprimo54 kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Jul 10 15:03:56 tomsprimo54 kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Jul 10 15:03:56 tomsprimo54 kernel: aml_dai_set_clkdiv, div 4, clksel(1) Jul 10 15:03:56 tomsprimo54 kernel: aml_dai_set_bclk_ratio, select I2S mode Jul 10 15:03:56 tomsprimo54 kernel: aml_dai_tdm_hw_params(), enable mclk for TDM-B Jul 10 15:03:56 tomsprimo54 kernel: aml_tdm_prepare(), reset fddr Jul 10 15:03:56 tomsprimo54 kernel: spdif_a fifo ctrl, frddr:0 type:1, 16 bits, chmask 0x3, swap 0x10 Jul 10 15:03:56 tomsprimo54 kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Jul 10 15:03:56 tomsprimo54 kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Jul 10 15:03:56 tomsprimo54 kernel: tdm playback mute: 0, lane_cnt = 8 Jul 10 15:03:56 tomsprimo54 kernel: aml_tdm_prepare(), reset fddr Jul 10 15:03:56 tomsprimo54 kernel: spdif_a fifo ctrl, frddr:0 type:1, 16 bits, chmask 0x3, swap 0x10 Jul 10 15:03:56 tomsprimo54 kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Jul 10 15:03:56 tomsprimo54 kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Jul 10 15:03:56 tomsprimo54 kernel: tdm playback mute: 0, lane_cnt = 8 Jul 10 15:03:56 tomsprimo54 volumio[3160]: info: Jul 10 15:03:56 tomsprimo54 volumio[3160]: ---------------------------- MPD announces state update: player Jul 10 15:03:56 tomsprimo54 volumio[3160]: info: ControllerMpd::getState Jul 10 15:03:56 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand status Jul 10 15:03:56 tomsprimo54 volumio[3160]: info: Jul 10 15:03:56 tomsprimo54 volumio[3160]: ---------------------------- MPD announces state update: player Jul 10 15:03:56 tomsprimo54 volumio[3160]: info: ControllerMpd::getState Jul 10 15:03:56 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand status Jul 10 15:03:56 tomsprimo54 volumio[3160]: info: sendMpdCommand status took 5 milliseconds Jul 10 15:03:56 tomsprimo54 volumio[3160]: verbose: ControllerMpd::parseState Jul 10 15:03:56 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 15:03:56 tomsprimo54 volumio[3160]: info: sendMpdCommand status took 8 milliseconds Jul 10 15:03:56 tomsprimo54 volumio[3160]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 10 15:03:56 tomsprimo54 volumio[3160]: verbose: ControllerMpd::parseState Jul 10 15:03:56 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 15:03:56 tomsprimo54 volumio[3160]: verbose: ControllerMpd::parseTrackInfo Jul 10 15:03:56 tomsprimo54 volumio[3160]: info: ControllerMpd::pushState Jul 10 15:03:56 tomsprimo54 volumio[3160]: info: CoreCommandRouter::servicePushState Jul 10 15:03:56 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:03:56 tomsprimo54 volumio[3160]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Jul 10 15:03:56 tomsprimo54 volumio[3160]: info: ------------------------------ 15ms Jul 10 15:03:56 tomsprimo54 volumio[3160]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 10 15:03:56 tomsprimo54 volumio[3160]: verbose: ControllerMpd::parseTrackInfo Jul 10 15:03:56 tomsprimo54 volumio[3160]: info: ControllerMpd::pushState Jul 10 15:03:56 tomsprimo54 volumio[3160]: info: CoreCommandRouter::servicePushState Jul 10 15:03:56 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:03:56 tomsprimo54 volumio[3160]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Jul 10 15:03:56 tomsprimo54 volumio[3160]: info: ------------------------------ 15ms Jul 10 15:03:56 tomsprimo54 volumio[3160]: info: [1720616636317] [RadioParadise] received new metadata: {"time":93,"artist":"An Emotional Fish","title":"Celebrate","album":"An Emotional Fish","year":"1990","cover":"https://img.radioparadise.com/covers/l/B000008CUZ.jpg","cover_med":"https://img.radioparadise.com/covers/m/B000008CUZ.jpg","cover_small":"https://img.radioparadise.com/covers/s/B000008CUZ.jpg"} Jul 10 15:03:56 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:03:56 tomsprimo54 volumio[3160]: info: CoreCommandRouter::servicePushState Jul 10 15:03:56 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:03:56 tomsprimo54 volumio[3160]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B000008CUZ.jpg","uri":"https://stream.radioparadise.com/rock-flac","name":"Celebrate","title":"Celebrate","artist":"An Emotional Fish","album":"An Emotional Fish","streaming":true,"disableUiControls":true,"duration":98,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jul 10 15:03:56 tomsprimo54 volumio[3160]: verbose: CURRENT POSITION 0 Jul 10 15:03:56 tomsprimo54 volumio[3160]: info: CoreStateMachine::syncState stateService play Jul 10 15:03:56 tomsprimo54 volumio[3160]: info: CoreStateMachine::syncState currentStatus stop Jul 10 15:03:56 tomsprimo54 volumio[3160]: info: [1720616636320] [RadioParadise] setting new timer with duration of 98000 seconds. Jul 10 15:03:56 tomsprimo54 volumio[3160]: info: Jul 10 15:03:56 tomsprimo54 volumio[3160]: ---------------------------- MPD announces system playlist update Jul 10 15:03:56 tomsprimo54 volumio[3160]: info: Ignoring MPD Status Update Jul 10 15:03:56 tomsprimo54 volumio[3160]: info: Jul 10 15:03:56 tomsprimo54 volumio[3160]: ---------------------------- MPD announces state update: player Jul 10 15:03:56 tomsprimo54 volumio[3160]: info: ControllerMpd::getState Jul 10 15:03:56 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand status Jul 10 15:03:56 tomsprimo54 volumio[3160]: info: ------------------------------ 4ms Jul 10 15:03:56 tomsprimo54 volumio[3160]: info: sendMpdCommand status took 2 milliseconds Jul 10 15:03:56 tomsprimo54 volumio[3160]: verbose: ControllerMpd::parseState Jul 10 15:03:56 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 15:03:56 tomsprimo54 volumio[3160]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 10 15:03:56 tomsprimo54 volumio[3160]: verbose: ControllerMpd::parseTrackInfo Jul 10 15:03:56 tomsprimo54 volumio[3160]: info: ControllerMpd::pushState Jul 10 15:03:56 tomsprimo54 volumio[3160]: info: CoreCommandRouter::servicePushState Jul 10 15:03:56 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:03:56 tomsprimo54 volumio[3160]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Jul 10 15:03:56 tomsprimo54 volumio[3160]: info: ------------------------------ 9ms Jul 10 15:03:56 tomsprimo54 kernel: asoc-aml-card auge_sound: tdm playback enable Jul 10 15:03:56 tomsprimo54 kernel: spdif_a is set to enable Jul 10 15:03:57 tomsprimo54 volumio[3160]: info: CoreCommandRouter::volumioPrevious Jul 10 15:03:57 tomsprimo54 volumio[3160]: info: CoreStateMachine::previous Jul 10 15:03:57 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:03:57 tomsprimo54 volumio[3160]: info: [1720616637297] [RadioParadise] seek to 0 Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: CoreCommandRouter::volumioPrevious Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: CoreStateMachine::previous Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: CoreStateMachine::stop Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: CoreStateMachine::stPlaybackTimer Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: CoreStateMachine::updateTrackBlock Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrackBlock Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: CoreStateMachine::pushState Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: CoreCommandRouter::volumioPushState Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: MRS: Pushing multiroomSync output update for this device Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: MRS: Pushing multiroomSync output Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: CoreStateMachine::serviceStop Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: CoreCommandRouter::serviceStop Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: ControllerMpd::stop Jul 10 15:03:58 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand stop Jul 10 15:03:58 tomsprimo54 volumio[3160]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: Updating RAAT Signal Path Jul 10 15:03:58 tomsprimo54 volumio[3160]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jul 10 15:03:58 tomsprimo54 kernel: asoc-aml-card auge_sound: tdm playback stop Jul 10 15:03:58 tomsprimo54 kernel: spdif_a is set to disable Jul 10 15:03:58 tomsprimo54 kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Jul 10 15:03:58 tomsprimo54 kernel: aml_dai_tdm_hw_free(), disable mclk for TDM-B Jul 10 15:03:58 tomsprimo54 kernel: tdm playback mute: 1, lane_cnt = 8 Jul 10 15:03:58 tomsprimo54 kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: Jul 10 15:03:58 tomsprimo54 volumio[3160]: ---------------------------- MPD announces state update: player Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: sendMpdCommand stop took 29 milliseconds Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: ControllerMpd::getState Jul 10 15:03:58 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand status Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: CoreCommandRouter::servicePushState Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: CoreStateMachine::pushState Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: CoreCommandRouter::volumioPushState Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: MRS: Pushing multiroomSync output update for this device Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: MRS: Pushing multiroomSync output Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:03:58 tomsprimo54 volumio[3160]: verbose: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B000008CUZ.jpg","uri":"https://stream.radioparadise.com/rock-flac","name":"Celebrate","title":"Celebrate","artist":"An Emotional Fish","album":"An Emotional Fish","streaming":true,"disableUiControls":true,"duration":98,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jul 10 15:03:58 tomsprimo54 volumio[3160]: verbose: CURRENT POSITION 0 Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: CoreStateMachine::syncState stateService stop Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: CoreStateMachine::syncState currentStatus stop Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: CoreStateMachine::pushState Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: CoreCommandRouter::volumioPushState Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: MRS: Pushing multiroomSync output update for this device Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: MRS: Pushing multiroomSync output Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: No code Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: CoreStateMachine::pushState Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: CoreCommandRouter::volumioPushState Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: MRS: Pushing multiroomSync output update for this device Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: MRS: Pushing multiroomSync output Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: sendMpdCommand status took 48 milliseconds Jul 10 15:03:58 tomsprimo54 volumio[3160]: verbose: ControllerMpd::parseState Jul 10 15:03:58 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 10 15:03:58 tomsprimo54 volumio[3160]: verbose: ControllerMpd::parseTrackInfo Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: ControllerMpd::pushState Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: CoreCommandRouter::servicePushState Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: CoreStateMachine::pushState Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: CoreCommandRouter::volumioPushState Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: MRS: Pushing multiroomSync output update for this device Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: MRS: Pushing multiroomSync output Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: ------------------------------ 79ms Jul 10 15:03:58 tomsprimo54 volumio[3160]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: Updating RAAT Signal Path Jul 10 15:03:58 tomsprimo54 volumio[3160]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jul 10 15:03:58 tomsprimo54 volumio[3160]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: Updating RAAT Signal Path Jul 10 15:03:58 tomsprimo54 volumio[3160]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jul 10 15:03:58 tomsprimo54 volumio[3160]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: Updating RAAT Signal Path Jul 10 15:03:58 tomsprimo54 volumio[3160]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jul 10 15:03:58 tomsprimo54 volumio[3160]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: Updating RAAT Signal Path Jul 10 15:03:58 tomsprimo54 volumio[3160]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: CoreStateMachine::play index undefined Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: CoreStateMachine::startPlaybackTimer Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: CoreCommandRouter::volumioGetVisibleSources Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jul 10 15:03:58 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand stop Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: sendMpdCommand stop took 1 milliseconds Jul 10 15:03:58 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand clear Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: Jul 10 15:03:58 tomsprimo54 volumio[3160]: ---------------------------- MPD announces system playlist update Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: Ignoring MPD Status Update Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: sendMpdCommand clear took 2 milliseconds Jul 10 15:03:58 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand consume 1 Jul 10 15:03:58 tomsprimo54 volumio[3160]: error: updateQueue error: null Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: ------------------------------ 3ms Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: sendMpdCommand consume 1 took 2 milliseconds Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: [1720616638958] [RadioParadise] set to consume mode, adding url: https://stream.radioparadise.com/rock-flac Jul 10 15:03:58 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand add "https://stream.radioparadise.com/rock-flac" Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: Jul 10 15:03:58 tomsprimo54 volumio[3160]: ---------------------------- MPD announces system playlist update Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: Ignoring MPD Status Update Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: sendMpdCommand add "https://stream.radioparadise.com/rock-flac" took 1 milliseconds Jul 10 15:03:58 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand play Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: ------------------------------ 5ms Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: sendMpdCommand play took 6 milliseconds Jul 10 15:03:58 tomsprimo54 volumio[3160]: info: [1720616638971] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=2 Jul 10 15:03:59 tomsprimo54 kernel: aml_tdm_open Jul 10 15:03:59 tomsprimo54 kernel: Not init audio effects Jul 10 15:03:59 tomsprimo54 kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Jul 10 15:03:59 tomsprimo54 kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Jul 10 15:03:59 tomsprimo54 kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Jul 10 15:03:59 tomsprimo54 kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Jul 10 15:03:59 tomsprimo54 kernel: asoc aml_dai_set_tdm_fmt, 0x4001, ffffffc03d277618, id(1), clksel(1) Jul 10 15:03:59 tomsprimo54 kernel: aml_dai_set_tdm_fmt(), fmt not change Jul 10 15:03:59 tomsprimo54 kernel: dump_pcm_setting(ffffffc03d277618) Jul 10 15:03:59 tomsprimo54 kernel: pcm_mode(1) Jul 10 15:03:59 tomsprimo54 kernel: sysclk(11289600) Jul 10 15:03:59 tomsprimo54 kernel: sysclk_bclk_ratio(4) Jul 10 15:03:59 tomsprimo54 kernel: bclk(2822400) Jul 10 15:03:59 tomsprimo54 kernel: bclk_lrclk_ratio(64) Jul 10 15:03:59 tomsprimo54 kernel: lrclk(44100) Jul 10 15:03:59 tomsprimo54 kernel: tx_mask(0x3) Jul 10 15:03:59 tomsprimo54 kernel: rx_mask(0x3) Jul 10 15:03:59 tomsprimo54 kernel: slots(2) Jul 10 15:03:59 tomsprimo54 kernel: slot_width(32) Jul 10 15:03:59 tomsprimo54 kernel: lane_mask_in(0x2) Jul 10 15:03:59 tomsprimo54 kernel: lane_mask_out(0x1) Jul 10 15:03:59 tomsprimo54 kernel: lane_oe_mask_in(0x0) Jul 10 15:03:59 tomsprimo54 kernel: lane_oe_mask_out(0x0) Jul 10 15:03:59 tomsprimo54 kernel: lane_lb_mask_in(0x0) Jul 10 15:03:59 tomsprimo54 kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Jul 10 15:03:59 tomsprimo54 kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Jul 10 15:03:59 tomsprimo54 kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Jul 10 15:03:59 tomsprimo54 kernel: aml_dai_set_clkdiv, div 4, clksel(1) Jul 10 15:03:59 tomsprimo54 kernel: aml_dai_set_bclk_ratio, select I2S mode Jul 10 15:03:59 tomsprimo54 kernel: aml_dai_tdm_hw_params(), enable mclk for TDM-B Jul 10 15:03:59 tomsprimo54 kernel: aml_tdm_prepare(), reset fddr Jul 10 15:03:59 tomsprimo54 kernel: spdif_a fifo ctrl, frddr:0 type:1, 16 bits, chmask 0x3, swap 0x10 Jul 10 15:03:59 tomsprimo54 kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Jul 10 15:03:59 tomsprimo54 kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Jul 10 15:03:59 tomsprimo54 kernel: tdm playback mute: 0, lane_cnt = 8 Jul 10 15:03:59 tomsprimo54 kernel: aml_tdm_prepare(), reset fddr Jul 10 15:03:59 tomsprimo54 kernel: spdif_a fifo ctrl, frddr:0 type:1, 16 bits, chmask 0x3, swap 0x10 Jul 10 15:03:59 tomsprimo54 kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Jul 10 15:03:59 tomsprimo54 kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Jul 10 15:03:59 tomsprimo54 kernel: tdm playback mute: 0, lane_cnt = 8 Jul 10 15:03:59 tomsprimo54 volumio[3160]: info: Jul 10 15:03:59 tomsprimo54 volumio[3160]: ---------------------------- MPD announces state update: player Jul 10 15:03:59 tomsprimo54 volumio[3160]: info: ControllerMpd::getState Jul 10 15:03:59 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand status Jul 10 15:03:59 tomsprimo54 volumio[3160]: info: Jul 10 15:03:59 tomsprimo54 volumio[3160]: ---------------------------- MPD announces state update: player Jul 10 15:03:59 tomsprimo54 volumio[3160]: info: sendMpdCommand status took 5 milliseconds Jul 10 15:03:59 tomsprimo54 volumio[3160]: info: ControllerMpd::getState Jul 10 15:03:59 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand status Jul 10 15:03:59 tomsprimo54 volumio[3160]: verbose: ControllerMpd::parseState Jul 10 15:03:59 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 15:03:59 tomsprimo54 volumio[3160]: info: sendMpdCommand status took 3 milliseconds Jul 10 15:03:59 tomsprimo54 volumio[3160]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 10 15:03:59 tomsprimo54 volumio[3160]: verbose: ControllerMpd::parseState Jul 10 15:03:59 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 15:03:59 tomsprimo54 volumio[3160]: verbose: ControllerMpd::parseTrackInfo Jul 10 15:03:59 tomsprimo54 volumio[3160]: info: ControllerMpd::pushState Jul 10 15:03:59 tomsprimo54 volumio[3160]: info: CoreCommandRouter::servicePushState Jul 10 15:03:59 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:03:59 tomsprimo54 volumio[3160]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Jul 10 15:03:59 tomsprimo54 volumio[3160]: info: ------------------------------ 14ms Jul 10 15:03:59 tomsprimo54 volumio[3160]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 10 15:03:59 tomsprimo54 volumio[3160]: verbose: ControllerMpd::parseTrackInfo Jul 10 15:03:59 tomsprimo54 volumio[3160]: info: ControllerMpd::pushState Jul 10 15:03:59 tomsprimo54 volumio[3160]: info: CoreCommandRouter::servicePushState Jul 10 15:03:59 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:03:59 tomsprimo54 volumio[3160]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Jul 10 15:03:59 tomsprimo54 volumio[3160]: info: ------------------------------ 11ms Jul 10 15:03:59 tomsprimo54 volumio[3160]: info: Jul 10 15:03:59 tomsprimo54 volumio[3160]: ---------------------------- MPD announces system playlist update Jul 10 15:03:59 tomsprimo54 volumio[3160]: info: Ignoring MPD Status Update Jul 10 15:03:59 tomsprimo54 volumio[3160]: info: Jul 10 15:03:59 tomsprimo54 volumio[3160]: ---------------------------- MPD announces state update: player Jul 10 15:03:59 tomsprimo54 volumio[3160]: info: ControllerMpd::getState Jul 10 15:03:59 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand status Jul 10 15:03:59 tomsprimo54 volumio[3160]: info: ------------------------------ 3ms Jul 10 15:03:59 tomsprimo54 volumio[3160]: info: sendMpdCommand status took 2 milliseconds Jul 10 15:03:59 tomsprimo54 volumio[3160]: verbose: ControllerMpd::parseState Jul 10 15:03:59 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 15:03:59 tomsprimo54 volumio[3160]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 10 15:03:59 tomsprimo54 volumio[3160]: verbose: ControllerMpd::parseTrackInfo Jul 10 15:03:59 tomsprimo54 volumio[3160]: info: ControllerMpd::pushState Jul 10 15:03:59 tomsprimo54 volumio[3160]: info: CoreCommandRouter::servicePushState Jul 10 15:03:59 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:03:59 tomsprimo54 volumio[3160]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Jul 10 15:03:59 tomsprimo54 volumio[3160]: info: ------------------------------ 8ms Jul 10 15:03:59 tomsprimo54 volumio[3160]: info: [1720616639526] [RadioParadise] received new metadata: {"time":90,"artist":"An Emotional Fish","title":"Celebrate","album":"An Emotional Fish","year":"1990","cover":"https://img.radioparadise.com/covers/l/B000008CUZ.jpg","cover_med":"https://img.radioparadise.com/covers/m/B000008CUZ.jpg","cover_small":"https://img.radioparadise.com/covers/s/B000008CUZ.jpg"} Jul 10 15:03:59 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:03:59 tomsprimo54 volumio[3160]: info: CoreCommandRouter::servicePushState Jul 10 15:03:59 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:03:59 tomsprimo54 volumio[3160]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B000008CUZ.jpg","uri":"https://stream.radioparadise.com/rock-flac","name":"Celebrate","title":"Celebrate","artist":"An Emotional Fish","album":"An Emotional Fish","streaming":true,"disableUiControls":true,"duration":95,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jul 10 15:03:59 tomsprimo54 volumio[3160]: verbose: CURRENT POSITION 0 Jul 10 15:03:59 tomsprimo54 volumio[3160]: info: CoreStateMachine::syncState stateService play Jul 10 15:03:59 tomsprimo54 volumio[3160]: info: CoreStateMachine::syncState currentStatus stop Jul 10 15:03:59 tomsprimo54 volumio[3160]: info: [1720616639528] [RadioParadise] setting new timer with duration of 95000 seconds. Jul 10 15:03:59 tomsprimo54 kernel: asoc-aml-card auge_sound: tdm playback enable Jul 10 15:03:59 tomsprimo54 kernel: spdif_a is set to enable Jul 10 15:04:01 tomsprimo54 volumio[3160]: info: CoreCommandRouter::volumioPrevious Jul 10 15:04:01 tomsprimo54 volumio[3160]: info: CoreStateMachine::previous Jul 10 15:04:01 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:04:01 tomsprimo54 volumio[3160]: info: [1720616641946] [RadioParadise] seek to 0 Jul 10 15:04:02 tomsprimo54 volumio[3160]: info: CoreCommandRouter::volumioPrevious Jul 10 15:04:02 tomsprimo54 volumio[3160]: info: CoreStateMachine::previous Jul 10 15:04:02 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:04:02 tomsprimo54 volumio[3160]: info: CoreStateMachine::stop Jul 10 15:04:02 tomsprimo54 volumio[3160]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 10 15:04:02 tomsprimo54 volumio[3160]: info: CoreStateMachine::stPlaybackTimer Jul 10 15:04:02 tomsprimo54 volumio[3160]: info: CoreStateMachine::updateTrackBlock Jul 10 15:04:02 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrackBlock Jul 10 15:04:02 tomsprimo54 volumio[3160]: info: CoreStateMachine::pushState Jul 10 15:04:02 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:04:02 tomsprimo54 volumio[3160]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 15:04:02 tomsprimo54 volumio[3160]: info: CoreCommandRouter::volumioPushState Jul 10 15:04:02 tomsprimo54 volumio[3160]: info: MRS: Pushing multiroomSync output update for this device Jul 10 15:04:02 tomsprimo54 volumio[3160]: info: MRS: Pushing multiroomSync output Jul 10 15:04:02 tomsprimo54 volumio[3160]: info: CoreStateMachine::serviceStop Jul 10 15:04:02 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:04:02 tomsprimo54 volumio[3160]: info: CoreCommandRouter::serviceStop Jul 10 15:04:02 tomsprimo54 volumio[3160]: info: ControllerMpd::stop Jul 10 15:04:02 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand stop Jul 10 15:04:02 tomsprimo54 volumio[3160]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jul 10 15:04:02 tomsprimo54 volumio[3160]: info: Updating RAAT Signal Path Jul 10 15:04:02 tomsprimo54 volumio[3160]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jul 10 15:04:02 tomsprimo54 kernel: asoc-aml-card auge_sound: tdm playback stop Jul 10 15:04:02 tomsprimo54 kernel: spdif_a is set to disable Jul 10 15:04:02 tomsprimo54 kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Jul 10 15:04:02 tomsprimo54 kernel: aml_dai_tdm_hw_free(), disable mclk for TDM-B Jul 10 15:04:02 tomsprimo54 kernel: tdm playback mute: 1, lane_cnt = 8 Jul 10 15:04:02 tomsprimo54 kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Jul 10 15:04:02 tomsprimo54 volumio[3160]: info: Jul 10 15:04:02 tomsprimo54 volumio[3160]: ---------------------------- MPD announces state update: player Jul 10 15:04:02 tomsprimo54 volumio[3160]: info: sendMpdCommand stop took 28 milliseconds Jul 10 15:04:02 tomsprimo54 volumio[3160]: info: ControllerMpd::getState Jul 10 15:04:02 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand status Jul 10 15:04:02 tomsprimo54 volumio[3160]: info: CoreCommandRouter::servicePushState Jul 10 15:04:02 tomsprimo54 volumio[3160]: info: CoreStateMachine::pushState Jul 10 15:04:02 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:04:02 tomsprimo54 volumio[3160]: info: CoreCommandRouter::volumioPushState Jul 10 15:04:02 tomsprimo54 volumio[3160]: info: MRS: Pushing multiroomSync output update for this device Jul 10 15:04:02 tomsprimo54 volumio[3160]: info: MRS: Pushing multiroomSync output Jul 10 15:04:02 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:04:02 tomsprimo54 volumio[3160]: verbose: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B000008CUZ.jpg","uri":"https://stream.radioparadise.com/rock-flac","name":"Celebrate","title":"Celebrate","artist":"An Emotional Fish","album":"An Emotional Fish","streaming":true,"disableUiControls":true,"duration":95,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jul 10 15:04:02 tomsprimo54 volumio[3160]: verbose: CURRENT POSITION 0 Jul 10 15:04:02 tomsprimo54 volumio[3160]: info: CoreStateMachine::syncState stateService stop Jul 10 15:04:02 tomsprimo54 volumio[3160]: info: CoreStateMachine::syncState currentStatus stop Jul 10 15:04:02 tomsprimo54 volumio[3160]: info: CoreStateMachine::pushState Jul 10 15:04:02 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:04:02 tomsprimo54 volumio[3160]: info: CoreCommandRouter::volumioPushState Jul 10 15:04:02 tomsprimo54 volumio[3160]: info: MRS: Pushing multiroomSync output update for this device Jul 10 15:04:02 tomsprimo54 volumio[3160]: info: MRS: Pushing multiroomSync output Jul 10 15:04:02 tomsprimo54 volumio[3160]: info: No code Jul 10 15:04:02 tomsprimo54 volumio[3160]: info: CoreStateMachine::pushState Jul 10 15:04:02 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:04:02 tomsprimo54 volumio[3160]: info: CoreCommandRouter::volumioPushState Jul 10 15:04:02 tomsprimo54 volumio[3160]: info: MRS: Pushing multiroomSync output update for this device Jul 10 15:04:02 tomsprimo54 volumio[3160]: info: MRS: Pushing multiroomSync output Jul 10 15:04:02 tomsprimo54 volumio[3160]: info: sendMpdCommand status took 46 milliseconds Jul 10 15:04:02 tomsprimo54 volumio[3160]: verbose: ControllerMpd::parseState Jul 10 15:04:02 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 15:04:02 tomsprimo54 volumio[3160]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 10 15:04:02 tomsprimo54 volumio[3160]: verbose: ControllerMpd::parseTrackInfo Jul 10 15:04:02 tomsprimo54 volumio[3160]: info: ControllerMpd::pushState Jul 10 15:04:02 tomsprimo54 volumio[3160]: info: CoreCommandRouter::servicePushState Jul 10 15:04:02 tomsprimo54 volumio[3160]: info: CoreStateMachine::pushState Jul 10 15:04:02 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:04:02 tomsprimo54 volumio[3160]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 15:04:02 tomsprimo54 volumio[3160]: info: CoreCommandRouter::volumioPushState Jul 10 15:04:02 tomsprimo54 volumio[3160]: info: MRS: Pushing multiroomSync output update for this device Jul 10 15:04:02 tomsprimo54 volumio[3160]: info: MRS: Pushing multiroomSync output Jul 10 15:04:02 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:04:02 tomsprimo54 volumio[3160]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Jul 10 15:04:02 tomsprimo54 volumio[3160]: info: ------------------------------ 74ms Jul 10 15:04:02 tomsprimo54 volumio[3160]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jul 10 15:04:02 tomsprimo54 volumio[3160]: info: Updating RAAT Signal Path Jul 10 15:04:02 tomsprimo54 volumio[3160]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jul 10 15:04:02 tomsprimo54 volumio[3160]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jul 10 15:04:02 tomsprimo54 volumio[3160]: info: Updating RAAT Signal Path Jul 10 15:04:02 tomsprimo54 volumio[3160]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jul 10 15:04:02 tomsprimo54 volumio[3160]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jul 10 15:04:02 tomsprimo54 volumio[3160]: info: Updating RAAT Signal Path Jul 10 15:04:02 tomsprimo54 volumio[3160]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jul 10 15:04:02 tomsprimo54 volumio[3160]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jul 10 15:04:02 tomsprimo54 volumio[3160]: info: Updating RAAT Signal Path Jul 10 15:04:02 tomsprimo54 volumio[3160]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jul 10 15:04:03 tomsprimo54 volumio[3160]: info: CoreStateMachine::play index undefined Jul 10 15:04:03 tomsprimo54 volumio[3160]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 10 15:04:03 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:04:03 tomsprimo54 volumio[3160]: info: CoreStateMachine::startPlaybackTimer Jul 10 15:04:03 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 0 Jul 10 15:04:03 tomsprimo54 volumio[3160]: info: CoreCommandRouter::volumioGetVisibleSources Jul 10 15:04:03 tomsprimo54 volumio[3160]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 15:04:03 tomsprimo54 volumio[3160]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jul 10 15:04:03 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand stop Jul 10 15:04:03 tomsprimo54 volumio[3160]: info: sendMpdCommand stop took 1 milliseconds Jul 10 15:04:03 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand clear Jul 10 15:04:03 tomsprimo54 volumio[3160]: info: Jul 10 15:04:03 tomsprimo54 volumio[3160]: ---------------------------- MPD announces system playlist update Jul 10 15:04:03 tomsprimo54 volumio[3160]: info: Ignoring MPD Status Update Jul 10 15:04:03 tomsprimo54 volumio[3160]: info: sendMpdCommand clear took 1 milliseconds Jul 10 15:04:03 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand consume 1 Jul 10 15:04:03 tomsprimo54 volumio[3160]: error: updateQueue error: null Jul 10 15:04:03 tomsprimo54 volumio[3160]: info: ------------------------------ 3ms Jul 10 15:04:03 tomsprimo54 volumio[3160]: info: sendMpdCommand consume 1 took 2 milliseconds Jul 10 15:04:03 tomsprimo54 volumio[3160]: info: [1720616643143] [RadioParadise] set to consume mode, adding url: https://stream.radioparadise.com/rock-flac Jul 10 15:04:03 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand add "https://stream.radioparadise.com/rock-flac" Jul 10 15:04:03 tomsprimo54 volumio[3160]: info: Jul 10 15:04:03 tomsprimo54 volumio[3160]: ---------------------------- MPD announces system playlist update Jul 10 15:04:03 tomsprimo54 volumio[3160]: info: Ignoring MPD Status Update Jul 10 15:04:03 tomsprimo54 volumio[3160]: info: sendMpdCommand add "https://stream.radioparadise.com/rock-flac" took 1 milliseconds Jul 10 15:04:03 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand play Jul 10 15:04:03 tomsprimo54 volumio[3160]: info: ------------------------------ 6ms Jul 10 15:04:03 tomsprimo54 volumio[3160]: info: sendMpdCommand play took 2 milliseconds Jul 10 15:04:03 tomsprimo54 volumio[3160]: info: [1720616643151] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=2 Jul 10 15:04:03 tomsprimo54 volumio[3160]: info: CoreCommandRouter::volumioPrevious Jul 10 15:04:03 tomsprimo54 volumio[3160]: info: CoreStateMachine::previous Jul 10 15:04:03 tomsprimo54 volumio[3160]: info: CoreStateMachine::updateTrackBlock Jul 10 15:04:03 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrackBlock Jul 10 15:04:03 tomsprimo54 volumio[3160]: info: CoreStateMachine::serviceClearAddPlay Jul 10 15:04:03 tomsprimo54 volumio[3160]: info: CoreCommandRouter::serviceClearAddPlayTracks Jul 10 15:04:03 tomsprimo54 volumio[3160]: error: WARNING: No clearAddPlayTracks method for service radio_paradise Jul 10 15:04:03 tomsprimo54 volumio[3160]: info: CoreCommandRouter::volumioPrevious Jul 10 15:04:03 tomsprimo54 volumio[3160]: info: CoreStateMachine::previous Jul 10 15:04:03 tomsprimo54 volumio[3160]: info: CoreStateMachine::updateTrackBlock Jul 10 15:04:03 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrackBlock Jul 10 15:04:03 tomsprimo54 volumio[3160]: info: CoreStateMachine::serviceClearAddPlay Jul 10 15:04:03 tomsprimo54 volumio[3160]: info: CoreCommandRouter::serviceClearAddPlayTracks Jul 10 15:04:03 tomsprimo54 volumio[3160]: error: WARNING: No clearAddPlayTracks method for service radio_paradise Jul 10 15:04:03 tomsprimo54 volumio[3160]: info: CoreCommandRouter::volumioPrevious Jul 10 15:04:03 tomsprimo54 volumio[3160]: info: CoreStateMachine::previous Jul 10 15:04:03 tomsprimo54 volumio[3160]: info: CoreStateMachine::updateTrackBlock Jul 10 15:04:03 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrackBlock Jul 10 15:04:03 tomsprimo54 volumio[3160]: info: CoreStateMachine::serviceClearAddPlay Jul 10 15:04:03 tomsprimo54 volumio[3160]: info: CoreCommandRouter::serviceClearAddPlayTracks Jul 10 15:04:03 tomsprimo54 volumio[3160]: error: WARNING: No clearAddPlayTracks method for service radio_paradise Jul 10 15:04:03 tomsprimo54 volumio[3160]: info: [1720616643702] [RadioParadise] received new metadata: {"time":86,"artist":"An Emotional Fish","title":"Celebrate","album":"An Emotional Fish","year":"1990","cover":"https://img.radioparadise.com/covers/l/B000008CUZ.jpg","cover_med":"https://img.radioparadise.com/covers/m/B000008CUZ.jpg","cover_small":"https://img.radioparadise.com/covers/s/B000008CUZ.jpg"} Jul 10 15:04:03 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 1 Jul 10 15:04:03 tomsprimo54 volumio[3160]: info: CoreCommandRouter::servicePushState Jul 10 15:04:03 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 1 Jul 10 15:04:03 tomsprimo54 volumio[3160]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B000008CUZ.jpg","uri":"https://stream.radioparadise.com/rock-flac","name":"Celebrate","title":"Celebrate","artist":"An Emotional Fish","album":"An Emotional Fish","streaming":true,"disableUiControls":true,"duration":91,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jul 10 15:04:03 tomsprimo54 volumio[3160]: verbose: CURRENT POSITION 1 Jul 10 15:04:03 tomsprimo54 volumio[3160]: info: CoreStateMachine::syncState stateService play Jul 10 15:04:03 tomsprimo54 volumio[3160]: info: CoreStateMachine::syncState currentStatus stop Jul 10 15:04:03 tomsprimo54 volumio[3160]: info: [1720616643704] [RadioParadise] setting new timer with duration of 91000 seconds. Jul 10 15:04:04 tomsprimo54 kernel: aml_tdm_open Jul 10 15:04:04 tomsprimo54 kernel: Not init audio effects Jul 10 15:04:04 tomsprimo54 kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Jul 10 15:04:04 tomsprimo54 kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Jul 10 15:04:04 tomsprimo54 kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Jul 10 15:04:04 tomsprimo54 kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Jul 10 15:04:04 tomsprimo54 kernel: asoc aml_dai_set_tdm_fmt, 0x4001, ffffffc03d277618, id(1), clksel(1) Jul 10 15:04:04 tomsprimo54 kernel: aml_dai_set_tdm_fmt(), fmt not change Jul 10 15:04:04 tomsprimo54 kernel: dump_pcm_setting(ffffffc03d277618) Jul 10 15:04:04 tomsprimo54 kernel: pcm_mode(1) Jul 10 15:04:04 tomsprimo54 kernel: sysclk(11289600) Jul 10 15:04:04 tomsprimo54 kernel: sysclk_bclk_ratio(4) Jul 10 15:04:04 tomsprimo54 kernel: bclk(2822400) Jul 10 15:04:04 tomsprimo54 kernel: bclk_lrclk_ratio(64) Jul 10 15:04:04 tomsprimo54 kernel: lrclk(44100) Jul 10 15:04:04 tomsprimo54 kernel: tx_mask(0x3) Jul 10 15:04:04 tomsprimo54 kernel: rx_mask(0x3) Jul 10 15:04:04 tomsprimo54 kernel: slots(2) Jul 10 15:04:04 tomsprimo54 kernel: slot_width(32) Jul 10 15:04:04 tomsprimo54 kernel: lane_mask_in(0x2) Jul 10 15:04:04 tomsprimo54 kernel: lane_mask_out(0x1) Jul 10 15:04:04 tomsprimo54 kernel: lane_oe_mask_in(0x0) Jul 10 15:04:04 tomsprimo54 kernel: lane_oe_mask_out(0x0) Jul 10 15:04:04 tomsprimo54 kernel: lane_lb_mask_in(0x0) Jul 10 15:04:04 tomsprimo54 kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Jul 10 15:04:04 tomsprimo54 kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Jul 10 15:04:04 tomsprimo54 kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Jul 10 15:04:04 tomsprimo54 kernel: aml_dai_set_clkdiv, div 4, clksel(1) Jul 10 15:04:04 tomsprimo54 kernel: aml_dai_set_bclk_ratio, select I2S mode Jul 10 15:04:04 tomsprimo54 kernel: aml_dai_tdm_hw_params(), enable mclk for TDM-B Jul 10 15:04:04 tomsprimo54 kernel: aml_tdm_prepare(), reset fddr Jul 10 15:04:04 tomsprimo54 kernel: spdif_a fifo ctrl, frddr:0 type:1, 16 bits, chmask 0x3, swap 0x10 Jul 10 15:04:04 tomsprimo54 kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Jul 10 15:04:04 tomsprimo54 kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Jul 10 15:04:04 tomsprimo54 kernel: tdm playback mute: 0, lane_cnt = 8 Jul 10 15:04:04 tomsprimo54 kernel: aml_tdm_prepare(), reset fddr Jul 10 15:04:04 tomsprimo54 kernel: spdif_a fifo ctrl, frddr:0 type:1, 16 bits, chmask 0x3, swap 0x10 Jul 10 15:04:04 tomsprimo54 kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Jul 10 15:04:04 tomsprimo54 kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Jul 10 15:04:04 tomsprimo54 kernel: tdm playback mute: 0, lane_cnt = 8 Jul 10 15:04:04 tomsprimo54 volumio[3160]: info: Jul 10 15:04:04 tomsprimo54 volumio[3160]: ---------------------------- MPD announces state update: player Jul 10 15:04:04 tomsprimo54 volumio[3160]: info: ControllerMpd::getState Jul 10 15:04:04 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand status Jul 10 15:04:04 tomsprimo54 volumio[3160]: info: Jul 10 15:04:04 tomsprimo54 volumio[3160]: ---------------------------- MPD announces state update: player Jul 10 15:04:04 tomsprimo54 volumio[3160]: info: ControllerMpd::getState Jul 10 15:04:04 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand status Jul 10 15:04:04 tomsprimo54 volumio[3160]: info: sendMpdCommand status took 2 milliseconds Jul 10 15:04:04 tomsprimo54 volumio[3160]: verbose: ControllerMpd::parseState Jul 10 15:04:04 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 15:04:04 tomsprimo54 volumio[3160]: info: sendMpdCommand status took 2 milliseconds Jul 10 15:04:04 tomsprimo54 volumio[3160]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 10 15:04:04 tomsprimo54 volumio[3160]: verbose: ControllerMpd::parseState Jul 10 15:04:04 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 15:04:04 tomsprimo54 volumio[3160]: verbose: ControllerMpd::parseTrackInfo Jul 10 15:04:04 tomsprimo54 volumio[3160]: info: ControllerMpd::pushState Jul 10 15:04:04 tomsprimo54 volumio[3160]: info: CoreCommandRouter::servicePushState Jul 10 15:04:04 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 1 Jul 10 15:04:04 tomsprimo54 volumio[3160]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rock-flac","artist":null,"album":null,"uri":"https://stream.radioparadise.com/rock-flac","trackType":""} Jul 10 15:04:04 tomsprimo54 volumio[3160]: verbose: CURRENT POSITION 1 Jul 10 15:04:04 tomsprimo54 volumio[3160]: info: CoreStateMachine::syncState stateService play Jul 10 15:04:04 tomsprimo54 volumio[3160]: info: CoreStateMachine::syncState currentStatus play Jul 10 15:04:04 tomsprimo54 volumio[3160]: info: Received an update from plugin. extracting info from payload Jul 10 15:04:04 tomsprimo54 volumio[3160]: info: CoreStateMachine::pushState Jul 10 15:04:04 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 1 Jul 10 15:04:04 tomsprimo54 volumio[3160]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 15:04:04 tomsprimo54 volumio[3160]: info: CoreCommandRouter::volumioPushState Jul 10 15:04:04 tomsprimo54 volumio[3160]: info: MRS: Pushing multiroomSync output update for this device Jul 10 15:04:04 tomsprimo54 volumio[3160]: info: MRS: Pushing multiroomSync output Jul 10 15:04:04 tomsprimo54 volumio[3160]: info: CoreStateMachine::pushState Jul 10 15:04:04 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 1 Jul 10 15:04:04 tomsprimo54 volumio[3160]: info: CoreCommandRouter::volumioPushState Jul 10 15:04:04 tomsprimo54 volumio[3160]: info: MRS: Pushing multiroomSync output update for this device Jul 10 15:04:04 tomsprimo54 volumio[3160]: info: MRS: Pushing multiroomSync output Jul 10 15:04:04 tomsprimo54 volumio[3160]: info: ------------------------------ 40ms Jul 10 15:04:04 tomsprimo54 volumio[3160]: info: Jul 10 15:04:04 tomsprimo54 volumio[3160]: ---------------------------- MPD announces system playlist update Jul 10 15:04:04 tomsprimo54 volumio[3160]: info: Ignoring MPD Status Update Jul 10 15:04:04 tomsprimo54 volumio[3160]: info: Jul 10 15:04:04 tomsprimo54 volumio[3160]: ---------------------------- MPD announces state update: player Jul 10 15:04:04 tomsprimo54 volumio[3160]: info: sendMpdCommand playlistinfo took 36 milliseconds Jul 10 15:04:04 tomsprimo54 volumio[3160]: info: ControllerMpd::getState Jul 10 15:04:04 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand status Jul 10 15:04:04 tomsprimo54 volumio[3160]: verbose: ControllerMpd::parseTrackInfo Jul 10 15:04:04 tomsprimo54 volumio[3160]: info: ControllerMpd::pushState Jul 10 15:04:04 tomsprimo54 volumio[3160]: info: CoreCommandRouter::servicePushState Jul 10 15:04:04 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 1 Jul 10 15:04:04 tomsprimo54 volumio[3160]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rock-flac","artist":null,"album":null,"uri":"https://stream.radioparadise.com/rock-flac","trackType":""} Jul 10 15:04:04 tomsprimo54 volumio[3160]: verbose: CURRENT POSITION 1 Jul 10 15:04:04 tomsprimo54 volumio[3160]: info: CoreStateMachine::syncState stateService play Jul 10 15:04:04 tomsprimo54 volumio[3160]: info: CoreStateMachine::syncState currentStatus play Jul 10 15:04:04 tomsprimo54 volumio[3160]: info: Received an update from plugin. extracting info from payload Jul 10 15:04:04 tomsprimo54 volumio[3160]: info: CoreStateMachine::pushState Jul 10 15:04:04 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 1 Jul 10 15:04:04 tomsprimo54 volumio[3160]: info: CoreCommandRouter::volumioPushState Jul 10 15:04:04 tomsprimo54 volumio[3160]: info: MRS: Pushing multiroomSync output update for this device Jul 10 15:04:04 tomsprimo54 volumio[3160]: info: MRS: Pushing multiroomSync output Jul 10 15:04:04 tomsprimo54 volumio[3160]: info: CoreStateMachine::pushState Jul 10 15:04:04 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 1 Jul 10 15:04:04 tomsprimo54 volumio[3160]: info: CoreCommandRouter::volumioPushState Jul 10 15:04:04 tomsprimo54 volumio[3160]: info: MRS: Pushing multiroomSync output update for this device Jul 10 15:04:04 tomsprimo54 volumio[3160]: info: MRS: Pushing multiroomSync output Jul 10 15:04:04 tomsprimo54 volumio[3160]: info: ------------------------------ 78ms Jul 10 15:04:04 tomsprimo54 volumio[3160]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jul 10 15:04:04 tomsprimo54 volumio[3160]: info: Updating RAAT Signal Path Jul 10 15:04:04 tomsprimo54 volumio[3160]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jul 10 15:04:04 tomsprimo54 volumio[3160]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jul 10 15:04:04 tomsprimo54 volumio[3160]: info: Updating RAAT Signal Path Jul 10 15:04:04 tomsprimo54 volumio[3160]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jul 10 15:04:04 tomsprimo54 volumio[3160]: info: Updating RAAT Signal Path Jul 10 15:04:04 tomsprimo54 volumio[3160]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jul 10 15:04:04 tomsprimo54 volumio[3160]: info: Updating RAAT Signal Path Jul 10 15:04:04 tomsprimo54 volumio[3160]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jul 10 15:04:04 tomsprimo54 volumio[3160]: info: ------------------------------ 91ms Jul 10 15:04:04 tomsprimo54 volumio[3160]: info: sendMpdCommand status took 88 milliseconds Jul 10 15:04:04 tomsprimo54 volumio[3160]: verbose: ControllerMpd::parseState Jul 10 15:04:04 tomsprimo54 volumio[3160]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 15:04:04 tomsprimo54 volumio[3160]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 10 15:04:04 tomsprimo54 volumio[3160]: verbose: ControllerMpd::parseTrackInfo Jul 10 15:04:04 tomsprimo54 volumio[3160]: info: ControllerMpd::pushState Jul 10 15:04:04 tomsprimo54 volumio[3160]: info: CoreCommandRouter::servicePushState Jul 10 15:04:04 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 1 Jul 10 15:04:04 tomsprimo54 volumio[3160]: verbose: STATE SERVICE {"status":"play","position":0,"seek":983,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rock-flac","artist":"Radio Paradise Rock Mix (flac) - playlist: radioparadise.com","album":null,"uri":"https://stream.radioparadise.com/rock-flac","trackType":""} Jul 10 15:04:04 tomsprimo54 volumio[3160]: verbose: CURRENT POSITION 1 Jul 10 15:04:04 tomsprimo54 volumio[3160]: info: CoreStateMachine::syncState stateService play Jul 10 15:04:04 tomsprimo54 volumio[3160]: info: CoreStateMachine::syncState currentStatus play Jul 10 15:04:04 tomsprimo54 volumio[3160]: info: Received an update from plugin. extracting info from payload Jul 10 15:04:04 tomsprimo54 volumio[3160]: info: CoreStateMachine::pushState Jul 10 15:04:04 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 1 Jul 10 15:04:04 tomsprimo54 volumio[3160]: info: CoreCommandRouter::volumioPushState Jul 10 15:04:04 tomsprimo54 kernel: asoc-aml-card auge_sound: tdm playback enable Jul 10 15:04:04 tomsprimo54 kernel: spdif_a is set to enable Jul 10 15:04:04 tomsprimo54 volumio[3160]: info: MRS: Pushing multiroomSync output update for this device Jul 10 15:04:04 tomsprimo54 volumio[3160]: info: MRS: Pushing multiroomSync output Jul 10 15:04:04 tomsprimo54 volumio[3160]: info: CoreStateMachine::pushState Jul 10 15:04:04 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 1 Jul 10 15:04:04 tomsprimo54 volumio[3160]: info: CoreCommandRouter::volumioPushState Jul 10 15:04:04 tomsprimo54 volumio[3160]: info: MRS: Pushing multiroomSync output update for this device Jul 10 15:04:04 tomsprimo54 volumio[3160]: info: MRS: Pushing multiroomSync output Jul 10 15:04:04 tomsprimo54 volumio[3160]: info: ------------------------------ 141ms Jul 10 15:04:04 tomsprimo54 volumio[3160]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jul 10 15:04:04 tomsprimo54 volumio[3160]: info: Updating RAAT Signal Path Jul 10 15:04:04 tomsprimo54 volumio[3160]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jul 10 15:04:04 tomsprimo54 volumio[3160]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jul 10 15:04:04 tomsprimo54 volumio[3160]: info: Updating RAAT Signal Path Jul 10 15:04:04 tomsprimo54 volumio[3160]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jul 10 15:04:06 tomsprimo54 volumio[3160]: info: CoreCommandRouter::volumioPrevious Jul 10 15:04:06 tomsprimo54 volumio[3160]: info: CoreStateMachine::previous Jul 10 15:04:06 tomsprimo54 volumio[3160]: info: CorePlayQueue::getTrack 1 Jul 10 15:04:06 tomsprimo54 volumio[3160]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 10 15:04:06 tomsprimo54 volumio[3160]: TypeError: Cannot read property 'service' of undefined Jul 10 15:04:06 tomsprimo54 volumio[3160]: at CoreStateMachine.previous (/volumio/app/statemachine.js:1322:97) Jul 10 15:04:06 tomsprimo54 volumio[3160]: at CoreCommandRouter.volumioPrevious (/volumio/app/index.js:95:28) Jul 10 15:04:06 tomsprimo54 volumio[3160]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:267:33) Jul 10 15:04:06 tomsprimo54 volumio[3160]: at Socket.emit (events.js:400:28) Jul 10 15:04:06 tomsprimo54 volumio[3160]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Jul 10 15:04:06 tomsprimo54 volumio[3160]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Jul 10 15:04:06 tomsprimo54 volumio[3160]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 10 15:04:07 tomsprimo54 sudo[14049]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-10 15:03 Jul 10 15:04:07 tomsprimo54 sudo[14049]: 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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="primo2rev2" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 10:05:12 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Primo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Primo" VOLUMIO_HASH="8bc35545f162f3e4d5b68abc51ef9937"