-- 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"