TIMESTAMP (us) DELTA C# COMPONENT LOCATION CONTENT ktime=222.812s @ 2021-09-28 21:09:01 +0800 CST Found valid LDC address after skipping 52 bytes (one line uses 20 + 0 to 16 bytes) [ 223696204.444445] ( 0.000000) c0 unknown 0.0 ....../intel/dmic/dmic.c:1397 dmic_start(), dmic_active_fifos_mask = 0x3 [ 167168397.992741] ( 167168400.000000) c1 ll-schedule ./schedule/ll_schedule.c:413 task add 0xbe2d0300 pipe-task [ 167168406.221908] ( 8.229166) c1 ll-schedule ./schedule/ll_schedule.c:417 task params pri 0 flags 0 start 0 period 16000 [ 167168421.951074] ( 15.729166) c1 ll-schedule ./schedule/ll_schedule.c:329 new added task->start 2518834088 at 2518804100 [ 167168429.971907] ( 8.020833) c1 ll-schedule ./schedule/ll_schedule.c:332 num_tasks 2 total_num_tasks 6 [ 167170101.794757] ( 1671.822876) c1 host 4.19 src/audio/host.c:377 no bytes to copy, 0 avail in buffer, 24576 free in DMA [ 167350349.964678] ( 180248.171875) c0 google-hotword-detect 10.57 /google_hotword_detect.c:216 Model: data=0xbe1cf040, size=66580 [ 167350359.964677] ( 10.000000) c0 google-hotword-detect 10.57 /google_hotword_detect.c:219 GoogleHotwordVersion 306749607 [ 167351391.266720] ( 1031.302002) c1 idc ......./intel/cavs/idc.c:58 idc_irq_handler(), IPC_IDCTFC_BUSY [ 167351410.745886] ( 19.479166) c1 idc ......./intel/cavs/idc.c:165 idc_do_cmd() [ 167351498.454216] ( 87.708328) c1 pipe 4.24 ....../pipeline-stream.c:184 pipe trigger cmd 0 [ 167351524.443798] ( 25.989582) c1 igo-nr 4.20 ../audio/igo_nr/igo_nr.c:798 igo_nr_trigger(), command = 0 [ 167351609.756294] ( 85.312500) c1 dmic-dai 2.0 ....../intel/dmic/dmic.c:1431 dmic_stop(), dmic_active_fifos_mask = 0x3 [ 167351621.527127] ( 11.770833) c1 ll-schedule ./schedule/ll_schedule.c:539 task cancel 0x9e265808 dmic-work <59c87728-d8f9-42f6-b89d-5870a87b0e1e> [ 167351631.631294] ( 10.104166) c1 ll-schedule ./schedule/ll_schedule.c:359 num_tasks 1 total_num_tasks 5 [ 167351641.995877] ( 10.364583) c1 dw-dma src/drivers/dw/dma.c:407 dw_dma_stop(): dma 1 channel 0 stop [ 167351715.433374] ( 73.437500) c1 ll-schedule ./schedule/ll_schedule.c:539 task cancel 0xbe2d0300 pipe-task [ 167351728.714623] ( 13.281249) c1 ll-schedule /schedule/timer_domain.c:210 timer_domain_unregister domain->type 1 domain->clk 4 [ 167351754.183372] ( 25.468748) c1 ll-schedule ./schedule/ll_schedule.c:359 num_tasks 0 total_num_tasks 4 [ 167353670.589546] ( 1916.406128) c1 idc ......./intel/cavs/idc.c:58 idc_irq_handler(), IPC_IDCTFC_BUSY [ 167353684.912462] ( 14.322916) c1 idc ......./intel/cavs/idc.c:165 idc_do_cmd() [ 167353701.839545] ( 16.927082) c1 pipe 4.24 ......./pipeline-graph.c:383 pipe reset [ 167353794.547874] ( 92.708328) c1 igo-nr 4.20 ../audio/igo_nr/igo_nr.c:786 igo_nr_reset() [ 167353808.349957] ( 13.802083) c1 dai 4.23 src/audio/dai.c:870 dai_reset() [ 167353818.141623] ( 9.791666) c1 dw-dma src/drivers/dw/dma.c:254 dw_dma_channel_put(): dma 1 channel 0 put [ 167354937.672829] ( 1119.531250) c1 idc ......./intel/cavs/idc.c:58 idc_irq_handler(), IPC_IDCTFC_BUSY [ 167354951.787412] ( 14.114583) c1 idc ......./intel/cavs/idc.c:165 idc_do_cmd() [ 167354970.016578] ( 18.229166) c1 igo-nr 4.20 ../audio/igo_nr/igo_nr.c:594 igo_nr_cmd() [ 167354978.922827] ( 8.906250) c1 igo-nr 4.20 ../audio/igo_nr/igo_nr.c:527 igo_nr_cmd_set_data(), SOF_CTRL_CMD_BINARY [ 167356093.558200] ( 1114.635376) c1 idc ......./intel/cavs/idc.c:58 idc_irq_handler(), IPC_IDCTFC_BUSY [ 167356107.620699] ( 14.062499) c1 idc ......./intel/cavs/idc.c:165 idc_do_cmd() [ 167356129.026948] ( 21.406250) c1 pipe 4.24 ....../pipeline-params.c:217 pipe params dir 1 frame_fmt 2 buffer_fmt 0 rate 48000 [ 167356137.985281] ( 8.958333) c1 pipe 4.24 ....../pipeline-params.c:221 pipe params stream_tag 3 channels 4 sample_valid_bytes 4 sample_container_bytes 4 [ 167356287.933192] ( 149.947906) c1 igo-nr 4.20 ../audio/igo_nr/igo_nr.c:381 igo_nr_params() [ 167356305.641525] ( 17.708332) c1 igo-nr 4.20 ../audio/igo_nr/igo_nr.c:423 igo_nr_params(), sample rate = 48000 [ 167356324.443607] ( 18.802082) c1 dai 4.23 src/audio/dai.c:357 dai_data_config() dai type = 2 index = 0 dd 0x9e275300 [ 167356333.922773] ( 9.479166) c1 dai 4.23 src/audio/dai.c:376 config->dmic.fifo_bits = 32 config->dmic.num_pdm_active = 2 [ 167356726.995674] ( 393.072906) c1 dai 4.23 src/audio/dai.c:509 dai_capture_params() src_dev = 0 stream_id = 0 src_width = 4 dest_width = 4 [ 167356736.579007] ( 9.583333) c1 dai 4.23 src/audio/dai.c:515 dai_capture_params() fifo 0x10008 [ 167356756.839423] ( 20.260416) c1 pipe 4.24 ....../pipeline-params.c:303 pipe prepare [ 167357072.308161] ( 315.468750) c1 igo-nr 4.20 ../audio/igo_nr/igo_nr.c:679 igo_nr_set_igo_params() [ 167357081.422744] ( 9.114583) c1 igo-nr 4.20 ../audio/igo_nr/igo_nr.c:683 New config detected. [ 167357812.828965] ( 731.406250) c1 igo-nr 4.20 ../audio/igo_nr/igo_nr.c:262 set_capture_func(), SOF_IPC_FRAME_S32_LE [ 167358151.318535] ( 338.489563) c1 dai 4.23 src/audio/dai.c:821 dai_prepare() [ 167358161.110201] ( 9.791666) c1 dai 4.23 src/audio/dai.c:769 dai_config_prepare(), channel = 0 [ 167358170.276867] ( 9.166666) c1 dw-dma src/drivers/dw/dma.c:191 dw_dma_channel_get(): dma 1 request channel 0 [ 167358180.589367] ( 10.312500) c1 dai 4.23 src/audio/dai.c:786 dai_config(): new configured dma channel index 0 [ 167360280.693450] ( 2100.104004) c1 idc ......./intel/cavs/idc.c:58 idc_irq_handler(), IPC_IDCTFC_BUSY [ 167360296.162199] ( 15.468749) c1 idc ......./intel/cavs/idc.c:165 idc_do_cmd() [ 167360313.662199] ( 17.500000) c1 pipe 4.24 ....../pipeline-stream.c:184 pipe trigger cmd 1 [ 167360345.589281] ( 31.927082) c1 igo-nr 4.20 ../audio/igo_nr/igo_nr.c:798 igo_nr_trigger(), command = 1 [ 167360380.172613] ( 34.583332) c1 dmic-dai 2.0 ....../intel/dmic/dmic.c:1288 dmic_start(), dmic->fifo_a [ 167360390.328862] ( 10.156250) c1 dmic-dai 2.0 ....../intel/dmic/dmic.c:1323 dmic_start(), mic_a = 1, mic_b = 1, fir_a = 1, fir_b = 1 [ 167360401.214278] ( 10.885416) c1 dmic-dai 2.0 ....../intel/dmic/dmic.c:1323 dmic_start(), mic_a = 1, mic_b = 1, fir_a = 1, fir_b = 1 [ 167360413.610111] ( 12.395833) c1 ll-schedule ./schedule/ll_schedule.c:413 task add 0x9e265808 dmic-work <59c87728-d8f9-42f6-b89d-5870a87b0e1e> [ 167360422.308028] ( 8.697916) c1 ll-schedule ./schedule/ll_schedule.c:417 task params pri 4 flags 0 start 1000 period 1000 [ 167360452.776776] ( 30.468748) c1 ll-schedule /schedule/timer_domain.c:191 timer_domain_register domain->type 1 domain->clk 4 domain->ticks_per_ms 38400 period 1000 [ 167360472.151776] ( 19.375000) c1 ll-schedule ./schedule/ll_schedule.c:329 new added task->start 2522520488 at 2522491463 [ 167360480.172609] ( 8.020833) c1 ll-schedule ./schedule/ll_schedule.c:332 num_tasks 1 total_num_tasks 5 [ 167360488.922608] ( 8.750000) c1 dmic-dai 2.0 ....../intel/dmic/dmic.c:1397 dmic_start(), dmic_active_fifos_mask = 0x3 [ 167360504.599691] ( 15.677083) c1 ll-schedule ./schedule/ll_schedule.c:413 task add 0xbe2d0300 pipe-task [ 167360512.828857] ( 8.229166) c1 ll-schedule ./schedule/ll_schedule.c:417 task params pri 0 flags 0 start 0 period 16000 [ 167360528.558023] ( 15.729166) c1 ll-schedule ./schedule/ll_schedule.c:329 new added task->start 2522520488 at 2522492547 [ 167360536.578856] ( 8.020833) c1 ll-schedule ./schedule/ll_schedule.c:332 num_tasks 2 total_num_tasks 6 [ 167362101.995461] ( 1565.416626) c1 host 4.19 src/audio/host.c:377 no bytes to copy, 0 avail in buffer, 24576 free in DMA [ 167550394.800479] ( 188292.812500) c0 google-hotword-detect 10.57 /google_hotword_detect.c:216 Model: data=0xbe1ba740, size=66580