TIMESTAMP (us) DELTA C# COMPONENT LOCATION CONTENT ktime=888.623s @ 2021-07-28 15:05:06 -0700 PDT [ 1631674174.173510] ( 0.000000) c0 dma-trace src/trace/dma-trace.c:335 ERROR FW ABI 0x3012001 DBG ABI 0x5003000 tag v1.8-rc2-57-gfd7f61a7ba31 src hash 0xf30c278d (ldc hash 0xf30c278d) [ 242.239574] ( 242.239578) c0 ll-schedule ./schedule/ll_schedule.c:413 task add 0x9e1c6480 dma-trace-task <2b972272-c5b1-4b7e-926f-0fc5cb4c4690> [ 259.791656] ( 17.552082) c0 ll-schedule ./schedule/ll_schedule.c:417 task params pri 4 flags 0 start 500000 period 500000 [ 285.572905] ( 25.781248) c0 ll-schedule ./schedule/ll_schedule.c:329 new added task->start 4735548 at 4728714 [ 302.552071] ( 16.979166) c0 ll-schedule ./schedule/ll_schedule.c:332 num_tasks 2 total_num_tasks 2 [ 1409.635361] ( 1107.083252) c0 component src/ipc/helper-ipc3.c:264 comp new host <8b9d100c-6d78-418f-90a3-e0e805d0852b> type 1 id 1.0 [ 1453.020776] ( 43.385414) c0 hda-dma ..../intel/hda/hda-dma.c:794 hda-dmac :5 -> probe [ 1553.697855] ( 100.677078) c0 dma src/lib/dma.c:121 dma_get() ID 5 sref = 1 busy channels 0 [ 1818.229094] ( 264.531250) c0 component src/ipc/helper-ipc3.c:264 comp new Maxim DSM <0cd84e80-ebd3-11ea-adc1-0242ac120002> type 21 id 1.1 [ 3442.187363] ( 1623.958252) c0 buffer src/ipc/helper-ipc3.c:802 buffer new size 0x300 id 1.2 flags 0x0 [ 3677.812354] ( 235.624985) c0 buffer src/ipc/helper-ipc3.c:802 buffer new size 0x600 id 1.3 flags 0x0 [ 3923.906094] ( 246.093735) c0 buffer src/ipc/helper-ipc3.c:802 buffer new size 0x600 id 1.4 flags 0x0 [ 4171.718584] ( 247.812485) c0 component src/ipc/helper-ipc3.c:264 comp new dai type 2 id 1.5 [ 4237.395665] ( 65.677078) c0 power ../cavs/lib/pm_runtime.c:170 en_ssp_power index 1 [ 4255.937331] ( 18.541666) c0 power ../cavs/lib/pm_runtime.c:179 en_ssp_power I2SLCTL 00000202 [ 4275.520663] ( 19.583332) c0 dai src/lib/dai.c:164 dai_get type 1 index 1 new sref 1 [ 4385.416492] ( 109.895828) c0 dma src/lib/dma.c:121 dma_get() ID 0 sref = 1 busy channels 0 [ 4581.562318] ( 196.145828) c0 pipe ......./pipeline-graph.c:113 pipeline new pipe_id 1 priority 0 [ 4779.895643] ( 198.333328) c0 component src/ipc/helper-ipc3.c:264 comp new host <8b9d100c-6d78-418f-90a3-e0e805d0852b> type 1 id 2.7 [ 4823.124808] ( 43.229164) c0 dma src/lib/dma.c:121 dma_get() ID 5 sref = 2 busy channels 0 [ 5082.447715] ( 259.322906) c0 component src/ipc/helper-ipc3.c:264 comp new pga type 5 id 2.8 [ 5122.656046] ( 40.208332) c0 pga 2.8 ../audio/volume/volume.c:378 vol->initial_ramp = 20, vol->ramp = 2, vol->min_value = 0, vol->max_value = 65536 [ 5324.270622] ( 201.614578) c0 buffer src/ipc/helper-ipc3.c:802 buffer new size 0x300 id 2.9 flags 0x0 [ 5555.364363] ( 231.093735) c0 buffer src/ipc/helper-ipc3.c:802 buffer new size 0x480 id 2.10 flags 0x0 [ 5813.072686] ( 257.708313) c0 component src/ipc/helper-ipc3.c:264 comp new dai type 2 id 2.11 [ 5877.708100] ( 64.635414) c0 power ../cavs/lib/pm_runtime.c:170 en_ssp_power index 0 [ 5896.353932] ( 18.645832) c0 power ../cavs/lib/pm_runtime.c:179 en_ssp_power I2SLCTL 00000303 [ 5915.468515] ( 19.114582) c0 dai src/lib/dai.c:164 dai_get type 1 index 0 new sref 1 [ 6024.166427] ( 108.697914) c0 dma src/lib/dma.c:121 dma_get() ID 1 sref = 1 busy channels 0 [ 6201.926837] ( 177.760406) c0 pipe ......./pipeline-graph.c:113 pipeline new pipe_id 2 priority 0 [ 6463.489326] ( 261.562500) c0 component src/ipc/helper-ipc3.c:264 comp new host <8b9d100c-6d78-418f-90a3-e0e805d0852b> type 1 id 3.13 [ 6506.041408] ( 42.552082) c0 dma src/lib/dma.c:121 dma_get() ID 4 sref = 2 busy channels 1 [ 6755.416398] ( 249.374985) c0 component src/ipc/helper-ipc3.c:264 comp new pga type 5 id 3.14 [ 6795.520563] ( 40.104164) c0 pga 3.14 ../audio/volume/volume.c:378 vol->initial_ramp = 250, vol->ramp = 0, vol->min_value = 0, vol->max_value = 2072775 [ 6975.728889] ( 180.208328) c0 buffer src/ipc/helper-ipc3.c:802 buffer new size 0x300 id 3.15 flags 0x0 [ 7225.885130] ( 250.156235) c0 buffer src/ipc/helper-ipc3.c:802 buffer new size 0x480 id 3.16 flags 0x0 [ 7453.853870] ( 227.968735) c0 component src/ipc/helper-ipc3.c:264 comp new dai type 2 id 3.17 [ 7497.812202] ( 43.958332) c0 dai src/lib/dai.c:164 dai_get type 1 index 0 new sref 2 [ 7519.114285] ( 21.302082) c0 dma src/lib/dma.c:121 dma_get() ID 0 sref = 2 busy channels 0 [ 7744.687192] ( 225.572906) c0 pipe ......./pipeline-graph.c:113 pipeline new pipe_id 3 priority 0 [ 7958.541350] ( 213.854156) c0 ipc src/ipc/handler-ipc3.c:625 ipc: pm core mask 0x3 -> enable [ 8252.447589] ( 293.906250) c1 edf-schedule /schedule/edf_schedule.c:260 edf_scheduler_init() [ 8385.624667] ( 133.177078) c1 idc src/idc/idc.c:318 idc_init() [ 8744.478819] ( 358.854156) c1 idc ......./intel/cavs/idc.c:58 idc_irq_handler(), IPC_IDCTFC_BUSY [ 8767.551735] ( 23.072916) c1 idc ......./intel/cavs/idc.c:165 idc_do_cmd() [ 8807.030900] ( 39.479164) c1 component src/ipc/helper-ipc3.c:264 comp new host <8b9d100c-6d78-418f-90a3-e0e805d0852b> type 1 id 4.19 [ 8852.343398] ( 45.312500) c1 dma src/lib/dma.c:121 dma_get() ID 4 sref = 3 busy channels 1 [ 9104.895472] ( 252.552078) c1 idc ......./intel/cavs/idc.c:58 idc_irq_handler(), IPC_IDCTFC_BUSY [ 9127.082971] ( 22.187500) c1 idc ......./intel/cavs/idc.c:165 idc_do_cmd() [ 9156.041303] ( 28.958332) c1 component src/ipc/helper-ipc3.c:264 comp new igo-nr <696ae2bc-2877-11eb-adc1-0242ac120002> type 0 id 4.20 [ 9173.280885] ( 17.239582) c1 igo-nr ../audio/igo_nr/igo_nr.c:283 igo_nr_new() [ 9364.791295] ( 191.510406) c1 igo-nr ../audio/igo_nr/igo_nr.c:333 igo_nr created [ 9613.072535] ( 248.281235) c0 buffer src/ipc/helper-ipc3.c:802 buffer new size 0x6000 id 4.21 flags 0x0 [ 9916.353773] ( 303.281250) c0 buffer src/ipc/helper-ipc3.c:802 buffer new size 0x6000 id 4.22 flags 0x0 [ 10198.489178] ( 282.135406) c1 idc ......./intel/cavs/idc.c:58 idc_irq_handler(), IPC_IDCTFC_BUSY [ 10221.041261] ( 22.552082) c1 idc ......./intel/cavs/idc.c:165 idc_do_cmd() [ 10254.270426] ( 33.229164) c1 component src/ipc/helper-ipc3.c:264 comp new dai type 2 id 4.23 [ 10299.947507] ( 45.677082) c1 dmic-dai 2.0 src/drivers/intel/dmic.c:1583 dmic_probe() [ 10357.916255] ( 57.968746) c1 dai src/lib/dai.c:164 dai_get type 2 index 0 new sref 1 [ 10380.260004] ( 22.343750) c1 dma src/lib/dma.c:121 dma_get() ID 1 sref = 2 busy channels 0 [ 10611.197495] ( 230.937485) c1 idc ......./intel/cavs/idc.c:58 idc_irq_handler(), IPC_IDCTFC_BUSY [ 10633.749577] ( 22.552082) c1 idc ......./intel/cavs/idc.c:165 idc_do_cmd() [ 10671.562076] ( 37.812500) c1 pipe ......./pipeline-graph.c:113 pipeline new pipe_id 4 priority 0 [ 10947.551648] ( 275.989563) c0 component src/ipc/helper-ipc3.c:264 comp new host <8b9d100c-6d78-418f-90a3-e0e805d0852b> type 1 id 5.25 [ 10990.520397] ( 42.968750) c0 dma src/lib/dma.c:121 dma_get() ID 5 sref = 3 busy channels 0 [ 11214.374554] ( 223.854156) c0 component src/ipc/helper-ipc3.c:264 comp new pga type 5 id 5.26 [ 11254.218303] ( 39.843750) c0 pga 5.26 ../audio/volume/volume.c:378 vol->initial_ramp = 20, vol->ramp = 2, vol->min_value = 0, vol->max_value = 65536 [ 11481.822460] ( 227.604156) c0 buffer src/ipc/helper-ipc3.c:802 buffer new size 0x300 id 5.27 flags 0x0 [ 11734.687034] ( 252.864578) c0 buffer src/ipc/helper-ipc3.c:802 buffer new size 0x480 id 5.28 flags 0x0 [ 11986.614107] ( 251.927078) c0 component src/ipc/helper-ipc3.c:264 comp new dai type 2 id 5.29 [ 12032.187022] ( 45.572914) c0 hda-dai 3.0 /drivers/intel/hda/hda.c:63 hda_probe() [ 12055.364104] ( 23.177082) c0 dai src/lib/dai.c:164 dai_get type 3 index 0 new sref 1 [ 12077.759937] ( 22.395832) c0 hda-dma ..../intel/hda/hda-dma.c:794 hda-dmac :7 -> probe [ 12163.437017] ( 85.677078) c0 dma src/lib/dma.c:121 dma_get() ID 7 sref = 1 busy channels 0 [ 12399.791174] ( 236.354156) c0 pipe ......./pipeline-graph.c:113 pipeline new pipe_id 5 priority 0 [ 12706.509912] ( 306.718750) c0 component src/ipc/helper-ipc3.c:264 comp new host <8b9d100c-6d78-418f-90a3-e0e805d0852b> type 1 id 6.31 [ 12748.749493] ( 42.239582) c0 dma src/lib/dma.c:121 dma_get() ID 5 sref = 4 busy channels 0 [ 13006.145317] ( 257.395813) c0 component src/ipc/helper-ipc3.c:264 comp new pga type 5 id 6.32 [ 13046.093232] ( 39.947914) c0 pga 6.32 ../audio/volume/volume.c:378 vol->initial_ramp = 20, vol->ramp = 2, vol->min_value = 0, vol->max_value = 65536 [ 13265.780723] ( 219.687485) c0 buffer src/ipc/helper-ipc3.c:802 buffer new size 0x300 id 6.33 flags 0x0 [ 13534.999462] ( 269.218750) c0 buffer src/ipc/helper-ipc3.c:802 buffer new size 0x480 id 6.34 flags 0x0 [ 13793.020285] ( 258.020813) c0 component src/ipc/helper-ipc3.c:264 comp new dai type 2 id 6.35 [ 13838.228617] ( 45.208332) c0 hda-dai 3.1 /drivers/intel/hda/hda.c:63 hda_probe() [ 13861.197366] ( 22.968750) c0 dai src/lib/dai.c:164 dai_get type 3 index 1 new sref 1 [ 13883.384865] ( 22.187500) c0 dma src/lib/dma.c:121 dma_get() ID 7 sref = 2 busy channels 0 [ 14134.634855] ( 251.249985) c0 pipe ......./pipeline-graph.c:113 pipeline new pipe_id 6 priority 0 [ 14386.145262] ( 251.510406) c0 component src/ipc/helper-ipc3.c:264 comp new host <8b9d100c-6d78-418f-90a3-e0e805d0852b> type 1 id 7.37 [ 14428.280677] ( 42.135414) c0 dma src/lib/dma.c:121 dma_get() ID 5 sref = 5 busy channels 0 [ 14726.926498] ( 298.645813) c0 component src/ipc/helper-ipc3.c:264 comp new pga type 5 id 7.38 [ 14766.718163] ( 39.791664) c0 pga 7.38 ../audio/volume/volume.c:378 vol->initial_ramp = 20, vol->ramp = 2, vol->min_value = 0, vol->max_value = 65536 [ 14990.468154] ( 223.749985) c0 buffer src/ipc/helper-ipc3.c:802 buffer new size 0x300 id 7.39 flags 0x0 [ 15211.770229] ( 221.302078) c0 buffer src/ipc/helper-ipc3.c:802 buffer new size 0x480 id 7.40 flags 0x0 [ 15483.280635] ( 271.510406) c0 component src/ipc/helper-ipc3.c:264 comp new dai type 2 id 7.41 [ 15528.436883] ( 45.156250) c0 hda-dai 3.2 /drivers/intel/hda/hda.c:63 hda_probe() [ 15551.718132] ( 23.281250) c0 dai src/lib/dai.c:164 dai_get type 3 index 2 new sref 1 [ 15573.801464] ( 22.083332) c0 dma src/lib/dma.c:121 dma_get() ID 7 sref = 3 busy channels 0 [ 15823.645205] ( 249.843735) c0 pipe ......./pipeline-graph.c:113 pipeline new pipe_id 7 priority 0 [ 16075.936861] ( 252.291656) c0 component src/ipc/helper-ipc3.c:264 comp new host <8b9d100c-6d78-418f-90a3-e0e805d0852b> type 1 id 8.43 [ 16118.436860] ( 42.500000) c0 dma src/lib/dma.c:121 dma_get() ID 5 sref = 6 busy channels 0 [ 16372.759766] ( 254.322906) c0 component src/ipc/helper-ipc3.c:264 comp new pga type 5 id 8.44 [ 16412.916014] ( 40.156250) c0 pga 8.44 ../audio/volume/volume.c:378 vol->initial_ramp = 20, vol->ramp = 2, vol->min_value = 0, vol->max_value = 65536 [ 16623.541006] ( 210.624985) c0 buffer src/ipc/helper-ipc3.c:802 buffer new size 0x300 id 8.45 flags 0x0 [ 16929.374327] ( 305.833313) c0 buffer src/ipc/helper-ipc3.c:802 buffer new size 0x480 id 8.46 flags 0x0 [ 17205.728483] ( 276.354156) c0 component src/ipc/helper-ipc3.c:264 comp new dai type 2 id 8.47 [ 17251.145148] ( 45.416664) c0 hda-dai 3.3 /drivers/intel/hda/hda.c:63 hda_probe() [ 17274.165980] ( 23.020832) c0 dai src/lib/dai.c:164 dai_get type 3 index 3 new sref 1 [ 17296.353479] ( 22.187500) c0 dma src/lib/dma.c:121 dma_get() ID 7 sref = 4 busy channels 0 [ 17551.926386] ( 255.572906) c0 pipe ......./pipeline-graph.c:113 pipeline new pipe_id 8 priority 0 [ 17841.145124] ( 289.218750) c0 component src/ipc/helper-ipc3.c:264 comp new host <8b9d100c-6d78-418f-90a3-e0e805d0852b> type 1 id 9.49 [ 17883.280539] ( 42.135414) c0 dma src/lib/dma.c:121 dma_get() ID 4 sref = 4 busy channels 1 [ 18147.290946] ( 264.010406) c0 component src/ipc/helper-ipc3.c:264 comp new pga type 5 id 9.50 [ 18187.030527] ( 39.739582) c0 pga 9.50 ../audio/volume/volume.c:378 vol->initial_ramp = 250, vol->ramp = 0, vol->min_value = 0, vol->max_value = 2072775 [ 18428.020101] ( 240.989578) c0 component src/ipc/helper-ipc3.c:264 comp new kpb type 16 id 9.51 [ 18445.311767] ( 17.291666) c0 kpb src/audio/kpb.c:136 kpb_new() [ 18758.280505] ( 312.968750) c0 buffer src/ipc/helper-ipc3.c:802 buffer new size 0x1e00 id 9.52 flags 0x0 [ 19024.790911] ( 266.510406) c0 buffer src/ipc/helper-ipc3.c:802 buffer new size 0x1400 id 9.53 flags 0x0 [ 19269.009651] ( 244.218735) c0 buffer src/ipc/helper-ipc3.c:802 buffer new size 0x1400 id 9.54 flags 0x0 [ 19572.811722] ( 303.802063) c0 component src/ipc/helper-ipc3.c:264 comp new dai type 2 id 9.55 [ 19618.228387] ( 45.416664) c0 dmic-dai 2.1 src/drivers/intel/dmic.c:1583 dmic_probe() [ 19677.238801] ( 59.010414) c0 dai src/lib/dai.c:164 dai_get type 2 index 1 new sref 1 [ 19700.103384] ( 22.864582) c0 dma src/lib/dma.c:121 dma_get() ID 0 sref = 3 busy channels 0 [ 19975.624206] ( 275.520813) c0 pipe ......./pipeline-graph.c:113 pipeline new pipe_id 9 priority 0 [ 20206.770030] ( 231.145828) c0 component src/ipc/helper-ipc3.c:264 comp new google-hotword-detect type 15 id 10.57 [ 20223.853363] ( 17.083332) c0 google-hotword-detect /google_hotword_detect.c:94 ghd_create() [ 20515.780435] ( 291.927063) c0 component src/ipc/helper-ipc3.c:264 comp new selector <55a88ed5-3d18-46ca-88f1-0ee6eae9930f> type 17 id 10.58 [ 20533.332517] ( 17.552082) c0 selector ..../selector/selector.c:61 selector_new() [ 20760.884592] ( 227.552078) c0 buffer src/ipc/helper-ipc3.c:802 buffer new size 0x1400 id 10.59 flags 0x0 [ 21002.707499] ( 241.822906) c0 buffer src/ipc/helper-ipc3.c:802 buffer new size 0x1400 id 10.60 flags 0x0 [ 21289.530404] ( 286.822906) c0 pipe ......./pipeline-graph.c:113 pipeline new pipe_id 10 priority 1 [ 21571.249143] ( 281.718750) c0 component src/ipc/helper-ipc3.c:264 comp new host <8b9d100c-6d78-418f-90a3-e0e805d0852b> type 1 id 11.63 [ 21613.592891] ( 42.343750) c0 dma src/lib/dma.c:121 dma_get() ID 4 sref = 5 busy channels 1 [ 21901.561630] ( 287.968750) c0 component src/ipc/helper-ipc3.c:264 comp new demux type 18 id 11.64 [ 21919.061629] ( 17.500000) c0 mux src/audio/mux/mux.c:168 mux_new() [ 21973.124127] ( 54.062496) c0 demux 11.64 src/audio/mux/mux.c:100 mux_set_values() [ 22215.311617] ( 242.187485) c0 buffer src/ipc/helper-ipc3.c:802 buffer new size 0x300 id 11.65 flags 0x0 [ 22461.509524] ( 246.197906) c0 buffer src/ipc/helper-ipc3.c:802 buffer new size 0x600 id 11.66 flags 0x0 [ 22759.374096] ( 297.864563) c0 component src/ipc/helper-ipc3.c:264 comp new dai type 2 id 11.67 [ 22804.426177] ( 45.052082) c0 dai src/lib/dai.c:164 dai_get type 1 index 1 new sref 2 [ 22825.519926] ( 21.093750) c0 dma src/lib/dma.c:121 dma_get() ID 1 sref = 3 busy channels 0 [ 23070.676167] ( 245.156235) c0 pipe ......./pipeline-graph.c:113 pipeline new pipe_id 11 priority 0 [ 23284.426158] ( 213.749985) c0 demux 11.64 ......./pipeline-graph.c:158 connect buffer 4 as sink [ 23599.165729] ( 314.739563) c0 kpb 9.51 ......./pipeline-graph.c:158 connect buffer 59 as sink [ 23739.269890] ( 140.104156) c0 host 1.0 ......./pipeline-graph.c:158 connect buffer 2 as sink [ 23923.384466] ( 184.114578) c0 Maxim DSM 1.1 ......./pipeline-graph.c:160 connect buffer 2 as source [ 24085.051126] ( 161.666656) c0 Maxim DSM 1.1 ......./pipeline-graph.c:160 connect buffer 4 as source [ 24246.196953] ( 161.145828) c0 Maxim DSM 1.1 ......./pipeline-graph.c:158 connect buffer 3 as sink [ 24426.561529] ( 180.364578) c0 dai 1.5 ......./pipeline-graph.c:160 connect buffer 3 as source [ 24604.842772] ( 178.281250) c0 host 2.7 ......./pipeline-graph.c:158 connect buffer 9 as sink [ 24747.551100] ( 142.708328) c0 pga 2.8 ......./pipeline-graph.c:160 connect buffer 9 as source [ 24891.144844] ( 143.593750) c0 pga 2.8 ......./pipeline-graph.c:158 connect buffer 10 as sink [ 25048.071921] ( 156.927078) c0 dai 2.11 ......./pipeline-graph.c:160 connect buffer 10 as source [ 25216.978165] ( 168.906250) c0 host 3.13 ......./pipeline-graph.c:160 connect buffer 15 as source [ 25401.509407] ( 184.531250) c0 pga 3.14 ......./pipeline-graph.c:158 connect buffer 15 as sink [ 25548.332318] ( 146.822906) c0 pga 3.14 ......./pipeline-graph.c:160 connect buffer 16 as source [ 25740.103144] ( 191.770828) c0 dai 3.17 ......./pipeline-graph.c:158 connect buffer 16 as sink [ 25907.238554] ( 167.135406) c1 idc ......./intel/cavs/idc.c:58 idc_irq_handler(), IPC_IDCTFC_BUSY [ 25929.321886] ( 22.083332) c1 idc ......./intel/cavs/idc.c:165 idc_do_cmd() [ 25965.155218] ( 35.833332) c1 host 4.19 ......./pipeline-graph.c:160 connect buffer 21 as source [ 26134.009378] ( 168.854156) c1 idc ......./intel/cavs/idc.c:58 idc_irq_handler(), IPC_IDCTFC_BUSY [ 26156.040627] ( 22.031250) c1 idc ......./intel/cavs/idc.c:165 idc_do_cmd() [ 26191.353126] ( 35.312500) c1 igo-nr 4.20 ......./pipeline-graph.c:158 connect buffer 21 as sink [ 26373.540619] ( 182.187500) c1 idc ......./intel/cavs/idc.c:58 idc_irq_handler(), IPC_IDCTFC_BUSY [ 26395.676034] ( 22.135416) c1 idc ......./intel/cavs/idc.c:165 idc_do_cmd() [ 26429.946866] ( 34.270832) c1 igo-nr 4.20 ......./pipeline-graph.c:160 connect buffer 22 as source [ 26613.592692] ( 183.645828) c1 idc ......./intel/cavs/idc.c:58 idc_irq_handler(), IPC_IDCTFC_BUSY [ 26635.832275] ( 22.239582) c1 idc ......./intel/cavs/idc.c:165 idc_do_cmd() [ 26672.394773] ( 36.562500) c1 dai 4.23 ......./pipeline-graph.c:158 connect buffer 22 as sink [ 26890.884348] ( 218.489578) c0 host 5.25 ......./pipeline-graph.c:158 connect buffer 27 as sink [ 27060.936425] ( 170.052078) c0 pga 5.26 ......./pipeline-graph.c:160 connect buffer 27 as source [ 27258.801000] ( 197.864578) c0 pga 5.26 ......./pipeline-graph.c:158 connect buffer 28 as sink [ 27431.405160] ( 172.604156) c0 dai 5.29 ......./pipeline-graph.c:160 connect buffer 28 as source [ 27613.436403] ( 182.031250) c0 host 6.31 ......./pipeline-graph.c:158 connect buffer 33 as sink [ 27784.165563] ( 170.729156) c0 pga 6.32 ......./pipeline-graph.c:160 connect buffer 33 as source [ 27947.030139] ( 162.864578) c0 pga 6.32 ......./pipeline-graph.c:158 connect buffer 34 as sink [ 28110.363466] ( 163.333328) c0 dai 6.35 ......./pipeline-graph.c:160 connect buffer 34 as source [ 28292.394709] ( 182.031250) c0 host 7.37 ......./pipeline-graph.c:158 connect buffer 39 as sink [ 28451.665536] ( 159.270828) c0 pga 7.38 ......./pipeline-graph.c:160 connect buffer 39 as source [ 28676.144694] ( 224.479156) c0 pga 7.38 ......./pipeline-graph.c:158 connect buffer 40 as sink [ 28847.186354] ( 171.041656) c0 dai 7.41 ......./pipeline-graph.c:160 connect buffer 40 as source [ 29033.436346] ( 186.250000) c0 host 8.43 ......./pipeline-graph.c:158 connect buffer 45 as sink [ 29219.894672] ( 186.458328) c0 pga 8.44 ......./pipeline-graph.c:160 connect buffer 45 as source [ 29391.769665] ( 171.875000) c0 pga 8.44 ......./pipeline-graph.c:158 connect buffer 46 as sink [ 29609.686323] ( 217.916656) c0 dai 8.47 ......./pipeline-graph.c:160 connect buffer 46 as source [ 29799.425899] ( 189.739578) c0 host 9.49 ......./pipeline-graph.c:160 connect buffer 54 as source [ 29993.696725] ( 194.270828) c0 kpb 9.51 ......./pipeline-graph.c:158 connect buffer 54 as sink [ 30166.613385] ( 172.916656) c0 kpb 9.51 ......./pipeline-graph.c:160 connect buffer 53 as source [ 30339.165461] ( 172.552078) c0 pga 9.50 ......./pipeline-graph.c:158 connect buffer 53 as sink [ 30528.540454] ( 189.375000) c0 pga 9.50 ......./pipeline-graph.c:160 connect buffer 52 as source [ 30746.405028] ( 217.864578) c0 dai 9.55 ......./pipeline-graph.c:158 connect buffer 52 as sink [ 30931.873771] ( 185.468750) c0 google-hotword-detect 10.57 ......./pipeline-graph.c:160 connect buffer 60 as source [ 31126.248763] ( 194.374985) c0 selector 10.58 ......./pipeline-graph.c:158 connect buffer 60 as sink [ 31306.457089] ( 180.208328) c0 selector 10.58 ......./pipeline-graph.c:160 connect buffer 59 as source [ 31524.373747] ( 217.916656) c0 host 11.63 ......./pipeline-graph.c:160 connect buffer 65 as source [ 31735.363322] ( 210.989578) c0 demux 11.64 ......./pipeline-graph.c:158 connect buffer 65 as sink [ 31933.123731] ( 197.760406) c0 demux 11.64 ......./pipeline-graph.c:160 connect buffer 66 as source [ 32131.821640] ( 198.697906) c0 dai 11.67 ......./pipeline-graph.c:158 connect buffer 66 as sink [ 32377.238297] ( 245.416656) c0 dai src/lib/dai.c:164 dai_get type 1 index 1 new sref 3 [ 32396.561213] ( 19.322916) c0 ssp-dai 1.1 /drivers/intel/ssp/ssp.c:172 ssp_set_config(), config->format = 0x4005 [ 32432.134128] ( 35.572914) c0 ssp-dai 1.1 /drivers/intel/ssp/ssp.c:599 ssp_set_config(), sscr0 = 0xc7d0003f, sscr1 = 0xd0700000, ssto = 0x00000000, sspsp = 0x10004 [ 32449.217461] ( 17.083332) c0 ssp-dai 1.1 /drivers/intel/ssp/ssp.c:601 ssp_set_config(), sscr2 = 0x00004002, sspsp2 = 0x00000000, sscr3 = 0x07070000, ssioc = 0x00000020 [ 32467.915377] ( 18.697916) c0 ssp-dai 1.1 /drivers/intel/ssp/ssp.c:603 ssp_set_config(), ssrsa = 0x000000ff, sstsa = 0x0000000f [ 32487.394542] ( 19.479166) c0 dai src/lib/dai.c:188 dai_put type 1 index 1 new sref 2 [ 32509.269542] ( 21.875000) c0 dai 1.5 src/audio/dai.c:706 dai_config() dai type = 1 index = 1 dd 0x9e1c2000 [ 32590.884122] ( 81.614578) c0 dai 11.67 src/audio/dai.c:706 dai_config() dai type = 1 index = 1 dd 0x9e1c3c00 [ 32645.884119] ( 54.999996) c1 idc ......./intel/cavs/idc.c:58 idc_irq_handler(), IPC_IDCTFC_BUSY [ 32668.279952] ( 22.395832) c1 idc ......./intel/cavs/idc.c:165 idc_do_cmd() [ 32994.842439] ( 326.562500) c0 dai src/lib/dai.c:164 dai_get type 1 index 0 new sref 3 [ 33014.373688] ( 19.531250) c0 ssp-dai 1.0 /drivers/intel/ssp/ssp.c:172 ssp_set_config(), config->format = 0x4001 [ 33047.759103] ( 33.385414) c0 ssp-dai 1.0 /drivers/intel/ssp/ssp.c:599 ssp_set_config(), sscr0 = 0xc1d0003f, sscr1 = 0xd0700000, ssto = 0x00000000, sspsp = 0x2200000 [ 33064.946603] ( 17.187500) c0 ssp-dai 1.0 /drivers/intel/ssp/ssp.c:601 ssp_set_config(), sscr2 = 0x00004002, sspsp2 = 0x00000000, sscr3 = 0x07070000, ssioc = 0x00000020 [ 33083.488269] ( 18.541666) c0 ssp-dai 1.0 /drivers/intel/ssp/ssp.c:603 ssp_set_config(), ssrsa = 0x00000003, sstsa = 0x00000003 [ 33103.019518] ( 19.531250) c0 dai src/lib/dai.c:188 dai_put type 1 index 0 new sref 2 [ 33128.540350] ( 25.520832) c0 dai 2.11 src/audio/dai.c:706 dai_config() dai type = 1 index = 0 dd 0x9e1c2100 [ 33176.977848] ( 48.437496) c0 dai 3.17 src/audio/dai.c:706 dai_config() dai type = 1 index = 0 dd 0x9e1c2200 [ 33253.696595] ( 76.718750) c1 idc ......./intel/cavs/idc.c:58 idc_irq_handler(), IPC_IDCTFC_BUSY [ 33275.571594] ( 21.875000) c1 idc ......./intel/cavs/idc.c:165 idc_do_cmd() [ 33625.727831] ( 350.156250) c0 dai src/lib/dai.c:164 dai_get type 1 index 0 new sref 3 [ 33645.154913] ( 19.427082) c0 ssp-dai 1.0 /drivers/intel/ssp/ssp.c:172 ssp_set_config(), config->format = 0x4001 [ 33678.384078] ( 33.229164) c0 ssp-dai 1.0 /drivers/intel/ssp/ssp.c:599 ssp_set_config(), sscr0 = 0xc1d0003f, sscr1 = 0xd0700000, ssto = 0x00000000, sspsp = 0x2200000 [ 33695.519494] ( 17.135416) c0 ssp-dai 1.0 /drivers/intel/ssp/ssp.c:601 ssp_set_config(), sscr2 = 0x00004002, sspsp2 = 0x00000000, sscr3 = 0x07070000, ssioc = 0x00000020 [ 33714.009077] ( 18.489582) c0 ssp-dai 1.0 /drivers/intel/ssp/ssp.c:603 ssp_set_config(), ssrsa = 0x00000003, sstsa = 0x00000003 [ 33733.488243] ( 19.479166) c0 dai src/lib/dai.c:188 dai_put type 1 index 0 new sref 2 [ 33759.113242] ( 25.624998) c0 dai 2.11 src/audio/dai.c:706 dai_config() dai type = 1 index = 0 dd 0x9e1c2100 [ 33790.311157] ( 31.197916) c0 dai 3.17 src/audio/dai.c:706 dai_config() dai type = 1 index = 0 dd 0x9e1c2200 [ 33850.519488] ( 60.208332) c1 idc ......./intel/cavs/idc.c:58 idc_irq_handler(), IPC_IDCTFC_BUSY [ 33872.186154] ( 21.666666) c1 idc ......./intel/cavs/idc.c:165 idc_do_cmd() [ 34200.102808] ( 327.916656) c0 dai src/lib/dai.c:164 dai_get type 2 index 0 new sref 2 [ 34217.967390] ( 17.864582) c0 dmic-dai 2.0 src/drivers/intel/dmic.c:1121 dmic_set_config() [ 34243.227806] ( 25.260416) c0 dmic-dai 2.0 src/drivers/intel/dmic.c:1161 dmic_set_config(): unmute_ramp_time_ms = 200 [ 34293.696554] ( 50.468746) c0 dmic-dai 2.0 src/drivers/intel/dmic.c:1212 dmic_set_config(), prm config->dmic.num_pdm_active = 2 [ 34312.081970] ( 18.385416) c0 dmic-dai 2.0 src/drivers/intel/dmic.c:1214 dmic_set_config(), prm pdmclk_min = 2400000, pdmclk_max = 4800000 [ 34330.936136] ( 18.854166) c0 dmic-dai 2.0 src/drivers/intel/dmic.c:1216 dmic_set_config(), prm duty_min = 40, duty_max = 60 [ 34348.019468] ( 17.083332) c0 dmic-dai 2.0 src/drivers/intel/dmic.c:1218 dmic_set_config(), prm fifo_fs = 48000, fifo_bits = 32 [ 34404.009050] ( 55.989582) c0 dmic-dai 2.0 src/drivers/intel/dmic.c:1260 dmic_set_config(), cfg clkdiv = 16, mcic = 25 [ 34422.394466] ( 18.385416) c0 dmic-dai 2.0 src/drivers/intel/dmic.c:1262 dmic_set_config(), cfg mfir_a = 2, mfir_b = 0 [ 34440.727798] ( 18.333332) c0 dmic-dai 2.0 src/drivers/intel/dmic.c:1264 dmic_set_config(), cfg cic_shift = 3 [ 34457.602797] ( 16.875000) c0 dmic-dai 2.0 src/drivers/intel/dmic.c:1266 dmic_set_config(), cfg fir_a_shift = 0, cfg.fir_b_shift = 0 [ 34474.373630] ( 16.770832) c0 dmic-dai 2.0 src/drivers/intel/dmic.c:1268 dmic_set_config(), cfg fir_a_length = 101, fir_b_length = 0 [ 34493.436129] ( 19.062500) c0 dmic-dai 2.0 src/drivers/intel/dmic.c:826 configuring registers [ 34583.748626] ( 90.312500) c0 dai src/lib/dai.c:188 dai_put type 2 index 0 new sref 1 [ 34644.946540] ( 61.197914) c1 idc ......./intel/cavs/idc.c:58 idc_irq_handler(), IPC_IDCTFC_BUSY [ 34667.342372] ( 22.395832) c1 idc ......./intel/cavs/idc.c:165 idc_do_cmd() [ 34705.779871] ( 38.437500) c1 dai 4.23 src/audio/dai.c:706 dai_config() dai type = 2 index = 0 dd 0x9e1c2300 [ 35048.540274] ( 342.760406) c0 dai src/lib/dai.c:164 dai_get type 3 index 0 new sref 2 [ 35068.384023] ( 19.843750) c0 dai src/lib/dai.c:188 dai_put type 3 index 0 new sref 1 [ 35101.300689] ( 32.916664) c0 dai 5.29 src/audio/dai.c:706 dai_config() dai type = 3 index = 0 dd 0x9e1c3000 [ 35148.956937] ( 47.656250) c1 idc ......./intel/cavs/idc.c:58 idc_irq_handler(), IPC_IDCTFC_BUSY [ 35170.831936] ( 21.875000) c1 idc ......./intel/cavs/idc.c:165 idc_do_cmd() [ 35477.394424] ( 306.562500) c0 dai src/lib/dai.c:164 dai_get type 3 index 1 new sref 2 [ 35496.092340] ( 18.697916) c0 dai src/lib/dai.c:188 dai_put type 3 index 1 new sref 1 [ 35531.821505] ( 35.729164) c0 dai 6.35 src/audio/dai.c:706 dai_config() dai type = 3 index = 1 dd 0x9e1c3400 [ 35574.269420] ( 42.447914) c1 idc ......./intel/cavs/idc.c:58 idc_irq_handler(), IPC_IDCTFC_BUSY [ 35596.092336] ( 21.822916) c1 idc ......./intel/cavs/idc.c:165 idc_do_cmd() [ 35960.883988] ( 364.791656) c0 dai src/lib/dai.c:164 dai_get type 3 index 2 new sref 2 [ 35979.529820] ( 18.645832) c0 dai src/lib/dai.c:188 dai_put type 3 index 2 new sref 1 [ 36018.331902] ( 38.802082) c0 dai 7.41 src/audio/dai.c:706 dai_config() dai type = 3 index = 2 dd 0x9e1c3600 [ 36057.706901] ( 39.375000) c1 idc ......./intel/cavs/idc.c:58 idc_irq_handler(), IPC_IDCTFC_BUSY [ 36079.581900] ( 21.875000) c1 idc ......./intel/cavs/idc.c:165 idc_do_cmd() [ 36390.519387] ( 310.937500) c0 dai src/lib/dai.c:164 dai_get type 3 index 3 new sref 2 [ 36409.217303] ( 18.697916) c0 dai src/lib/dai.c:188 dai_put type 3 index 3 new sref 1 [ 36450.727718] ( 41.510414) c0 dai 8.47 src/audio/dai.c:706 dai_config() dai type = 3 index = 3 dd 0x9e1c3800 [ 36487.081883] ( 36.354164) c1 idc ......./intel/cavs/idc.c:58 idc_irq_handler(), IPC_IDCTFC_BUSY [ 36508.800633] ( 21.718750) c1 idc ......./intel/cavs/idc.c:165 idc_do_cmd() [ 36749.008956] ( 240.208328) c0 dai src/lib/dai.c:164 dai_get type 2 index 1 new sref 2 [ 36767.186039] ( 18.177082) c0 dmic-dai 2.1 src/drivers/intel/dmic.c:1121 dmic_set_config() [ 36791.404788] ( 24.218748) c0 dmic-dai 2.1 src/drivers/intel/dmic.c:1161 dmic_set_config(): unmute_ramp_time_ms = 400 [ 36815.571454] ( 24.166666) c0 dmic-dai 2.1 src/drivers/intel/dmic.c:1212 dmic_set_config(), prm config->dmic.num_pdm_active = 1 [ 36834.321453] ( 18.750000) c0 dmic-dai 2.1 src/drivers/intel/dmic.c:1214 dmic_set_config(), prm pdmclk_min = 2400000, pdmclk_max = 4800000 [ 36853.019369] ( 18.697916) c0 dmic-dai 2.1 src/drivers/intel/dmic.c:1216 dmic_set_config(), prm duty_min = 40, duty_max = 60 [ 36871.873535] ( 18.854166) c0 dmic-dai 2.1 src/drivers/intel/dmic.c:1218 dmic_set_config(), prm fifo_fs = 16000, fifo_bits = 32 [ 36980.831864] ( 108.958328) c0 dmic-dai 2.1 src/drivers/intel/dmic.c:1260 dmic_set_config(), cfg clkdiv = 16, mcic = 25 [ 36997.602697] ( 16.770832) c0 dmic-dai 2.1 src/drivers/intel/dmic.c:1262 dmic_set_config(), cfg mfir_a = 2, mfir_b = 6 [ 37014.477696] ( 16.875000) c0 dmic-dai 2.1 src/drivers/intel/dmic.c:1264 dmic_set_config(), cfg cic_shift = 3 [ 37031.040195] ( 16.562500) c0 dmic-dai 2.1 src/drivers/intel/dmic.c:1266 dmic_set_config(), cfg fir_a_shift = 0, cfg.fir_b_shift = 1 [ 37048.019361] ( 16.979166) c0 dmic-dai 2.1 src/drivers/intel/dmic.c:1268 dmic_set_config(), cfg fir_a_length = 101, fir_b_length = 247 [ 37066.665194] ( 18.645832) c0 dmic-dai 2.1 src/drivers/intel/dmic.c:826 configuring registers [ 37229.061021] ( 162.395828) c0 dai src/lib/dai.c:188 dai_put type 2 index 1 new sref 1 [ 37275.102685] ( 46.041664) c0 dai 9.55 src/audio/dai.c:706 dai_config() dai type = 2 index = 1 dd 0x9e1c3a00 [ 37334.113100] ( 59.010414) c1 idc ......./intel/cavs/idc.c:58 idc_irq_handler(), IPC_IDCTFC_BUSY [ 37355.936016] ( 21.822916) c1 idc ......./intel/cavs/idc.c:165 idc_do_cmd() [ 37658.383920] ( 302.447906) c0 dai src/lib/dai.c:164 dai_get type 1 index 1 new sref 3 [ 37678.592253] ( 20.208332) c0 ssp-dai 1.1 /drivers/intel/ssp/ssp.c:172 ssp_set_config(), config->format = 0x4005 [ 37713.956835] ( 35.364582) c0 ssp-dai 1.1 /drivers/intel/ssp/ssp.c:599 ssp_set_config(), sscr0 = 0xc7d0003f, sscr1 = 0xd0700000, ssto = 0x00000000, sspsp = 0x10004 [ 37731.040167] ( 17.083332) c0 ssp-dai 1.1 /drivers/intel/ssp/ssp.c:601 ssp_set_config(), sscr2 = 0x00004002, sspsp2 = 0x00000000, sscr3 = 0x07070000, ssioc = 0x00000020 [ 37749.529750] ( 18.489582) c0 ssp-dai 1.1 /drivers/intel/ssp/ssp.c:603 ssp_set_config(), ssrsa = 0x000000ff, sstsa = 0x0000000f [ 37769.008916] ( 19.479166) c0 dai src/lib/dai.c:188 dai_put type 1 index 1 new sref 2 [ 37790.310998] ( 21.302082) c0 dai 1.5 src/audio/dai.c:706 dai_config() dai type = 1 index = 1 dd 0x9e1c2000 [ 37855.988079] ( 65.677078) c0 dai 11.67 src/audio/dai.c:706 dai_config() dai type = 1 index = 1 dd 0x9e1c3c00 [ 37892.238078] ( 36.250000) c1 idc ......./intel/cavs/idc.c:58 idc_irq_handler(), IPC_IDCTFC_BUSY [ 37913.748493] ( 21.510416) c1 idc ......./intel/cavs/idc.c:165 idc_do_cmd() [ 38231.717231] ( 317.968750) c0 pipe 11.68 ......./pipeline-graph.c:300 pipeline complete, clock freq 400000000Hz [ 38251.977647] ( 20.260416) c0 memory src/lib/alloc.c:1097 heap: buffer status [ 38268.956813] ( 16.979166) c0 memory src/lib/alloc.c:1071 heap: 0xbe12f800 size 524288 blocks 1 caps 0x71 [ 38285.988062] ( 17.031250) c0 memory src/lib/alloc.c:1073 used 214528 free 309760 [ 38302.342228] ( 16.354166) c0 memory src/lib/alloc.c:1071 heap: 0xbe801800 size 59392 blocks 1 caps 0x69 [ 38320.519311] ( 18.177082) c0 memory src/lib/alloc.c:1073 used 0 free 59392 [ 38338.435977] ( 17.916666) c0 memory src/lib/alloc.c:1099 heap: runtime status [ 38358.071392] ( 19.635416) c0 memory src/lib/alloc.c:1071 heap: 0xbe120000 size 63488 blocks 7 caps 0x45 [ 38374.477642] ( 16.406250) c0 memory src/lib/alloc.c:1073 used 24064 free 39424 [ 38391.092224] ( 16.614582) c0 memory src/lib/alloc.c:1081 block 1 base 0xbe122000 size 128 [ 38407.342224] ( 16.250000) c0 memory src/lib/alloc.c:1084 count 64 free 28 [ 38424.008890] ( 16.666666) c0 memory src/lib/alloc.c:1081 block 2 base 0xbe124000 size 256 [ 38442.342222] ( 18.333332) c0 memory src/lib/alloc.c:1084 count 128 free 77 [ 38458.644305] ( 16.302082) c0 memory src/lib/alloc.c:1081 block 3 base 0xbe12c000 size 512 [ 38475.310971] ( 16.666666) c0 memory src/lib/alloc.c:1084 count 8 free 8 [ 38490.050554] ( 14.739583) c0 memory src/lib/alloc.c:1081 block 4 base 0xbe12d000 size 1024 [ 38506.977637] ( 16.927082) c0 memory src/lib/alloc.c:1084 count 4 free 4 [ 38556.613051] ( 49.635414) c0 memory src/lib/alloc.c:1102 heap: runtime shared status [ 38573.071384] ( 16.458332) c0 memory src/lib/alloc.c:1071 heap: 0x9e1bd000 size 37888 blocks 5 caps 0x45 [ 38589.581800] ( 16.510416) c0 memory src/lib/alloc.c:1073 used 27904 free 9984 [ 38981.821368] ( 392.239563) c0 dma-trace src/trace/trace.c:133 Suppressed 6 similar messages: block %d base 0x%x size %d [ 38993.383867] ( 11.562500) c0 dma-trace src/trace/trace.c:133 Suppressed 6 similar messages: count %d free %d [ 39006.092200] ( 12.708333) c0 pipe 10.62 ......./pipeline-graph.c:300 pipeline complete, clock freq 400000000Hz [ 39259.738023] ( 253.645828) c0 pipe 9.56 ......./pipeline-graph.c:300 pipeline complete, clock freq 400000000Hz [ 39594.269260] ( 334.531250) c0 pipe 8.48 ......./pipeline-graph.c:300 pipeline complete, clock freq 400000000Hz [ 39900.050498] ( 305.781250) c0 pipe 7.42 ......./pipeline-graph.c:300 pipeline complete, clock freq 400000000Hz [ 40123.540072] ( 223.489578) c0 pipe 6.36 ......./pipeline-graph.c:300 pipeline complete, clock freq 400000000Hz [ 40350.310897] ( 226.770828) c0 pipe 5.30 ......./pipeline-graph.c:300 pipeline complete, clock freq 400000000Hz [ 40517.498390] ( 167.187500) c1 idc ......./intel/cavs/idc.c:58 idc_irq_handler(), IPC_IDCTFC_BUSY [ 40539.217139] ( 21.718750) c1 idc ......./intel/cavs/idc.c:165 idc_do_cmd() [ 40597.550470] ( 58.333332) c1 pipe 4.24 ......./pipeline-graph.c:300 pipeline complete, clock freq 400000000Hz [ 40872.550459] ( 275.000000) c0 pipe 3.18 ......./pipeline-graph.c:300 pipeline complete, clock freq 400000000Hz [ 41075.519201] ( 202.968735) c0 pipe 2.12 ......./pipeline-graph.c:300 pipeline complete, clock freq 400000000Hz [ 41245.623361] ( 170.104156) c0 pipe 1.6 ......./pipeline-graph.c:300 pipeline complete, clock freq 400000000Hz [ 41491.665018] ( 246.041656) c0 demux 11.64 src/audio/mux/mux.c:343 mux_cmd() cmd = 0x00000066 [ 41508.644184] ( 16.979166) c0 demux 11.64 src/audio/mux/mux.c:284 mux_ctrl_set_cmd(), cdata->cmd = 0x00000003 [ 41525.675433] ( 17.031250) c0 demux 11.64 src/audio/mux/mux.c:100 mux_set_values() [ 42161.664991] ( 635.989563) c0 selector 10.58 ..../selector/selector.c:322 selector_cmd() [ 42178.539991] ( 16.875000) c0 selector 10.58 ..../selector/selector.c:251 selector_ctrl_set_data(), SOF_CTRL_CMD_BINARY [ 312260.404259] ( 270081.875000) c0 pga 9.50 ../audio/volume/volume.c:560 volume_ctrl_set_cmd(), channel = 0, value = 65536 [ 312278.008425] ( 17.604166) c0 pga 9.50 ../audio/volume/volume.c:560 volume_ctrl_set_cmd(), channel = 1, value = 65536 [ 312750.977156] ( 472.968719) c0 pga 8.44 ../audio/volume/volume.c:560 volume_ctrl_set_cmd(), channel = 0, value = 65536 [ 312767.435488] ( 16.458332) c0 pga 8.44 ../audio/volume/volume.c:560 volume_ctrl_set_cmd(), channel = 1, value = 65536 [ 313226.029220] ( 458.593719) c0 pga 7.38 ../audio/volume/volume.c:560 volume_ctrl_set_cmd(), channel = 0, value = 65536 [ 313242.747970] ( 16.718750) c0 pga 7.38 ../audio/volume/volume.c:560 volume_ctrl_set_cmd(), channel = 1, value = 65536 [ 313698.581285] ( 455.833313) c0 pga 6.32 ../audio/volume/volume.c:560 volume_ctrl_set_cmd(), channel = 0, value = 65536 [ 313715.300034] ( 16.718750) c0 pga 6.32 ../audio/volume/volume.c:560 volume_ctrl_set_cmd(), channel = 1, value = 65536 [ 314127.956268] ( 412.656219) c0 pga 5.26 ../audio/volume/volume.c:560 volume_ctrl_set_cmd(), channel = 0, value = 65536 [ 314144.883350] ( 16.927082) c0 pga 5.26 ../audio/volume/volume.c:560 volume_ctrl_set_cmd(), channel = 1, value = 65536 [ 314553.268751] ( 408.385406) c1 idc ......./intel/cavs/idc.c:58 idc_irq_handler(), IPC_IDCTFC_BUSY [ 314575.508333] ( 22.239582) c1 idc ......./intel/cavs/idc.c:165 idc_do_cmd() [ 314602.122916] ( 26.614582) c1 igo-nr 4.20 ../audio/igo_nr/igo_nr.c:594 igo_nr_cmd() [ 314619.362498] ( 17.239582) c1 igo-nr 4.20 ../audio/igo_nr/igo_nr.c:527 igo_nr_cmd_set_data(), SOF_CTRL_CMD_BINARY [ 315311.862471] ( 692.500000) c0 pga 3.14 ../audio/volume/volume.c:560 volume_ctrl_set_cmd(), channel = 0, value = 65536 [ 315328.581220] ( 16.718750) c0 pga 3.14 ../audio/volume/volume.c:560 volume_ctrl_set_cmd(), channel = 1, value = 65536 [ 315708.997872] ( 380.416656) c0 pga 2.8 ../audio/volume/volume.c:560 volume_ctrl_set_cmd(), channel = 0, value = 65536 [ 315725.768704] ( 16.770832) c0 pga 2.8 ../audio/volume/volume.c:560 volume_ctrl_set_cmd(), channel = 1, value = 65536 [ 338718.111541] ( 22992.343750) c0 ipc src/ipc/handler-ipc3.c:605 ipc: pm -> restore [ 339360.611515] ( 642.500000) c0 pipe 9.56 ....../pipeline-params.c:215 pipe params dir 1 frame_fmt 0 buffer_fmt 0 rate 16000 [ 339378.319848] ( 17.708332) c0 pipe 9.56 ....../pipeline-params.c:219 pipe params stream_tag 2 channels 2 sample_valid_bytes 2 sample_container_bytes 2 [ 339696.861502] ( 318.541656) c0 dai 9.55 src/audio/dai.c:357 dai_data_config() dai type = 2 index = 1 dd 0x9e1c3a00 [ 339715.299001] ( 18.437500) c0 dai 9.55 src/audio/dai.c:376 config->dmic.fifo_bits = 32 config->dmic.num_pdm_active = 1 [ 339992.851073] ( 277.552063) c0 dai 9.55 src/audio/dai.c:509 dai_capture_params() src_dev = 1 stream_id = 0 src_width = 4 dest_width = 4 [ 340010.403156] ( 17.552082) c0 dai 9.55 src/audio/dai.c:515 dai_capture_params() fifo 0x10108 [ 340036.496905] ( 26.093748) c0 pipe 9.56 ....../pipeline-params.c:297 pipe prepare [ 340142.642734] ( 106.145828) c0 kpb 9.51 src/audio/kpb.c:442 kpb_prepare() [ 340160.663567] ( 18.020832) c0 kpb src/audio/kpb.c:226 kpb_allocate_history_buffer() [ 341560.142678] ( 1399.479126) c0 kpb src/audio/kpb.c:253 kpb new memory block: 58880 [ 342111.757239] ( 551.614563) c0 kpb src/audio/kpb.c:253 kpb new memory block: 133120 [ 342127.903072] ( 16.145832) c0 kpb src/audio/kpb.c:302 kpb_allocate_history_buffer(): allocated 192000 bytes [ 342144.517654] ( 16.614582) c0 kpb src/audio/kpb.c:1532 kpb_reset_history_buffer() [ 342161.340570] ( 16.822916) c0 kpb src/audio/kpb.c:1427 kpb_clear_history_buffer() [ 344706.705053] ( 2545.364502) c0 dai 9.55 src/audio/dai.c:821 dai_prepare() [ 344724.934219] ( 18.229166) c0 dai 9.55 src/audio/dai.c:769 dai_config_prepare(), channel = 0 [ 344742.642551] ( 17.708332) c0 dw-dma src/drivers/dw/dma.c:191 dw_dma_channel_get(): dma 0 request channel 0 [ 344761.080050] ( 18.437500) c0 dai 9.55 src/audio/dai.c:786 dai_config(): new configured dma channel index 0 [ 345805.663342] ( 1044.583252) c0 pipe 10.62 ....../pipeline-params.c:215 pipe params dir 1 frame_fmt 0 buffer_fmt 0 rate 16000 [ 345823.684175] ( 18.020832) c0 pipe 10.62 ....../pipeline-params.c:219 pipe params stream_tag 0 channels 2 sample_valid_bytes 2 sample_container_bytes 0 [ 345857.798757] ( 34.114582) c0 selector 10.58 ..../selector/selector.c:225 selector_params() [ 345884.152922] ( 26.354166) c0 kpb 9.51 src/audio/kpb.c:408 ERROR kpb_params(): kpb has been already configured. [ 345904.569588] ( 20.416666) c0 pipe 10.62 ....../pipeline-params.c:297 pipe prepare [ 345939.986254] ( 35.416664) c0 google-hotword-detect 10.57 /google_hotword_detect.c:216 Model: data=0xbe163e00, size=66580 [ 345956.861253] ( 16.875000) c0 google-hotword-detect 10.57 /google_hotword_detect.c:219 GoogleHotwordVersion 306749607 [ 346287.382073] ( 330.520813) c0 selector 10.58 ..../selector/selector.c:435 selector_prepare() [ 346305.663322] ( 18.281250) c0 selector 10.58 ..../selector/selector.c:465 selector_prepare(): sourceb->schannels = 2 [ 346321.913322] ( 16.250000) c0 selector 10.58 ..../selector/selector.c:467 selector_prepare(): sinkb->channels = 1 [ 346402.798735] ( 80.885414) c0 kpb 9.51 src/audio/kpb.c:442 kpb_prepare() [ 346421.600818] ( 18.802082) c0 kpb 9.51 src/audio/component.c:64 comp_set_state(), state already set to 3 [ 346691.236224] ( 269.635406) c0 pipe 10.62 ....../pipeline-stream.c:184 pipe trigger cmd 1 [ 346892.746632] ( 201.510406) c0 selector 10.58 ..../selector/selector.c:356 selector_trigger() [ 346913.215382] ( 20.468750) c0 ll-schedule ./schedule/ll_schedule.c:413 task add 0xbe120240 pipe-task [ 346930.507048] ( 17.291666) c0 ll-schedule ./schedule/ll_schedule.c:417 task params pri 1 flags 0 start 0 period 20000 [ 346956.913297] ( 26.406248) c0 ll-schedule ./schedule/ll_schedule.c:329 new added task->start 11417148 at 11384803 [ 346975.350796] ( 18.437500) c0 ll-schedule ./schedule/ll_schedule.c:332 num_tasks 3 total_num_tasks 3 [ 348533.111151] ( 1557.760376) c0 pipe 9.56 ....../pipeline-stream.c:184 pipe trigger cmd 1 [ 348626.548647] ( 93.437500) c0 kpb 9.51 src/audio/kpb.c:374 kpb_trigger() [ 348718.006977] ( 91.458328) c0 dmic-dai 2.1 src/drivers/intel/dmic.c:1320 dmic_start(), dmic->fifo_b [ 348737.277809] ( 19.270832) c0 dmic-dai 2.1 src/drivers/intel/dmic.c:1346 dmic_start(), mic_a = 1, mic_b = 1, fir_a = 1, fir_b = 1 [ 348755.402808] ( 18.125000) c0 dmic-dai 2.1 src/drivers/intel/dmic.c:1346 dmic_start(), mic_a = 0, mic_b = 0, fir_a = 0, fir_b = 0 [ 348775.923641] ( 20.520832) c0 ll-schedule ./schedule/ll_schedule.c:413 task add 0x9e1c0908 dmic-work <59c87728-d8f9-42f6-b89d-5870a87b0e1e> [ 348793.267390] ( 17.343750) c0 ll-schedule ./schedule/ll_schedule.c:417 task params pri 4 flags 0 start 1000 period 1000 [ 348821.079889] ( 27.812498) c0 ll-schedule ./schedule/ll_schedule.c:329 new added task->start 11455548 at 11420561 [ 348839.413222] ( 18.333332) c0 ll-schedule ./schedule/ll_schedule.c:332 num_tasks 4 total_num_tasks 4 [ 348858.527804] ( 19.114582) c0 dmic-dai 2.1 src/drivers/intel/dmic.c:1420 dmic_start(), done active_fifos = 1 [ 348882.017387] ( 23.489582) c0 ll-schedule ./schedule/ll_schedule.c:413 task add 0xbe120180 pipe-task [ 348900.506969] ( 18.489582) c0 ll-schedule ./schedule/ll_schedule.c:417 task params pri 0 flags 0 start 0 period 20000 [ 348926.444468] ( 25.937498) c0 ll-schedule ./schedule/ll_schedule.c:329 new added task->start 11455548 at 11422590 [ 348944.881968] ( 18.437500) c0 ll-schedule ./schedule/ll_schedule.c:332 num_tasks 5 total_num_tasks 5 [ 1151173.443840] ( 802228.562500) c0 ll-schedule ./schedule/ll_schedule.c:125 task complete 0x9e1c0908 dmic-work <59c87728-d8f9-42f6-b89d-5870a87b0e1e> [ 1151191.568839] ( 18.125000) c0 ll-schedule ./schedule/ll_schedule.c:128 num_tasks 4 total_num_tasks 4 [ 2892257.593405] ( 1741066.000000) c0 dai src/lib/dai.c:164 dai_get type 1 index 0 new sref 3 [ 2892278.478821] ( 20.885416) c0 ssp-dai 1.0 /drivers/intel/ssp/ssp.c:172 ssp_set_config(), config->format = 0x4001 [ 2892313.478820] ( 35.000000) c0 ssp-dai 1.0 /drivers/intel/ssp/ssp.c:599 ssp_set_config(), sscr0 = 0xc1d0003f, sscr1 = 0xd0700000, ssto = 0x00000000, sspsp = 0x2200000 [ 2892330.405902] ( 16.927082) c0 ssp-dai 1.0 /drivers/intel/ssp/ssp.c:601 ssp_set_config(), sscr2 = 0x00004002, sspsp2 = 0x00000000, sscr3 = 0x07070000, ssioc = 0x00000020 [ 2892349.155902] ( 18.750000) c0 ssp-dai 1.0 /drivers/intel/ssp/ssp.c:603 ssp_set_config(), ssrsa = 0x00000003, sstsa = 0x00000003 [ 2892368.843401] ( 19.687500) c0 dai src/lib/dai.c:188 dai_put type 1 index 0 new sref 2 [ 2892395.926733] ( 27.083332) c0 dai 2.11 src/audio/dai.c:706 dai_config() dai type = 1 index = 0 dd 0x9e1c2100 [ 2892429.051732] ( 33.125000) c0 dai 3.17 src/audio/dai.c:706 dai_config() dai type = 1 index = 0 dd 0x9e1c2200 [ 2892496.239229] ( 67.187500) c1 idc ......./intel/cavs/idc.c:58 idc_irq_handler(), IPC_IDCTFC_BUSY [ 2892518.478812] ( 22.239582) c1 idc ......./intel/cavs/idc.c:165 idc_do_cmd() [ 2892814.207967] ( 295.729156) c0 pipe 2.12 ....../pipeline-params.c:215 pipe params dir 0 frame_fmt 0 buffer_fmt 0 rate 48000 [ 2892832.072549] ( 17.864582) c0 pipe 2.12 ....../pipeline-params.c:219 pipe params stream_tag 1 channels 2 sample_valid_bytes 2 sample_container_bytes 2 [ 2892930.041295] ( 97.968742) c0 dai 2.11 src/audio/dai.c:357 dai_data_config() dai type = 1 index = 0 dd 0x9e1c2100 [ 2892979.937127] ( 49.895832) c0 dai 2.11 src/audio/dai.c:442 dai_playback_params() dest_dev = 2 stream_id = 0 src_width = 4 dest_width = 4 [ 2892998.478793] ( 18.541666) c0 dai 2.11 src/audio/dai.c:448 dai_playback_params() fifo 0x77010 [ 2893025.874625] ( 27.395832) c0 pipe 2.12 ....../pipeline-params.c:297 pipe prepare [ 2893093.166289] ( 67.291664) c0 dai 2.11 src/audio/dai.c:821 dai_prepare() [ 2893113.270455] ( 20.104166) c0 dai 2.11 src/audio/dai.c:769 dai_config_prepare(), channel = 0 [ 2893130.405871] ( 17.135416) c0 dw-dma src/drivers/dw/dma.c:191 dw_dma_channel_get(): dma 1 request channel 0 [ 2893148.791287] ( 18.385416) c0 dai 2.11 src/audio/dai.c:786 dai_config(): new configured dma channel index 0 [ 2944722.695487] ( 51573.902344) c0 pipe 2.12 ....../pipeline-stream.c:184 pipe trigger cmd 1 [ 2944819.830900] ( 97.135414) c0 ssp-dai 1.0 /drivers/intel/ssp/ssp.c:841 ssp_trigger() cmd 1 [ 2944838.841316] ( 19.010416) c0 ssp-dai 1.0 /drivers/intel/ssp/ssp.c:630 ssp_pre_start() [ 2944859.257982] ( 20.416666) c0 mn ./drivers/intel/ssp/mn.c:258 mclk_rate 24576000, mclk_source_clock 0 [ 2944876.132981] ( 16.875000) c0 mn ./drivers/intel/ssp/mn.c:220 mclk_id 0 mdivr_val 1 [ 2944896.289230] ( 20.156250) c0 mn ./drivers/intel/ssp/mn.c:320 find_mn for freq 24576000 bclk 3072000 [ 2944916.185063] ( 19.895832) c0 mn ./drivers/intel/ssp/mn.c:617 bclk_rate 3072000, *out_scr_div 8, m 1, n 1 [ 2944935.716312] ( 19.531250) c0 ssp-dai 1.0 /drivers/intel/ssp/ssp.c:686 ssp_set_config(), sscr0 = 0xc1d0077f [ 2944955.195478] ( 19.479166) c0 ssp-dai 1.0 /drivers/intel/ssp/ssp.c:758 ssp_start() [ 2945071.289223] ( 116.093742) c0 ll-schedule ./schedule/ll_schedule.c:413 task add 0xbe120300 pipe-task [ 2945088.737139] ( 17.447916) c0 ll-schedule ./schedule/ll_schedule.c:417 task params pri 0 flags 0 start 0 period 1000 [ 2945114.518388] ( 25.781248) c0 ll-schedule ./schedule/ll_schedule.c:329 new added task->start 61298748 at 61269434 [ 2945131.757971] ( 17.239582) c0 ll-schedule ./schedule/ll_schedule.c:332 num_tasks 5 total_num_tasks 5 [ 10269385.008598] ( 7324253.500000) c0 google-hotword-detect 10.57 /google_hotword_detect.c:375 Hotword detected 2000ms [ 10269412.977347] ( 27.968748) c0 kpb 9.51 src/audio/kpb.c:935 kpb_event_handler(): received event with ID: 2 [ 10269431.466929] ( 18.489582) c0 kpb 9.51 src/audio/kpb.c:1029 kpb_init_draining(): requested draining of 2000 [ms] from history buffer [ 10269449.800262] ( 18.333332) c0 kpb 9.51 src/audio/kpb.c:1126 kpb_init_draining: unlimited draining speed selected. [ 10269467.873178] ( 18.072916) c0 kpb 9.51 src/audio/kpb.c:1129 kpb_init_draining(), schedule draining task [ 10269498.966927] ( 31.093748) c1 idc ......./intel/cavs/idc.c:58 idc_irq_handler(), IPC_IDCTFC_BUSY [ 10269521.206509] ( 22.239582) c1 idc ......./intel/cavs/idc.c:165 idc_do_cmd() [ 10269536.883592] ( 15.677083) c0 kpb src/audio/kpb.c:1193 kpb_draining_task(), start. [ 10277213.393704] ( 7676.510254) c0 kpb src/audio/kpb.c:1280 kpb: update drain_req by 1264 [ 10277314.383283] ( 100.989578) c0 kpb src/audio/kpb.c:1280 kpb: update drain_req by 0 [ 10277334.904115] ( 20.520832) c0 kpb src/audio/kpb.c:1308 KPB: kpb_draining_task(), done. 129264 drained in 3 ms [ 32638833.025966] ( 22361498.000000) c0 pipe 9.56 ....../pipeline-stream.c:184 pipe trigger cmd 0 [ 32638857.973882] ( 24.947916) c0 kpb 9.51 src/audio/kpb.c:374 kpb_trigger() [ 32638883.025964] ( 25.052082) c0 dmic-dai 2.1 src/drivers/intel/dmic.c:1450 dmic_stop(), dmic_active_fifos = 1 [ 32638904.223880] ( 21.197916) c0 ll-schedule ./schedule/ll_schedule.c:539 task cancel 0x9e1c0908 dmic-work <59c87728-d8f9-42f6-b89d-5870a87b0e1e> [ 32638926.046796] ( 21.822916) c0 dw-dma src/drivers/dw/dma.c:407 dw_dma_stop(): dma 0 channel 0 stop [ 32638950.473878] ( 24.427082) c0 ll-schedule ./schedule/ll_schedule.c:539 task cancel 0xbe120180 pipe-task [ 32638971.307210] ( 20.833332) c0 ll-schedule ./schedule/ll_schedule.c:359 num_tasks 4 total_num_tasks 4 [ 32639280.005115] ( 308.697906) c0 pipe 9.56 ......./pipeline-graph.c:383 pipe reset [ 32639349.119695] ( 69.114578) c0 kpb src/audio/kpb.c:568 kpb_reset(): resetting from state 8, state log 45467578 [ 32639368.078028] ( 18.958332) c0 kpb src/audio/kpb.c:1532 kpb_reset_history_buffer() [ 32639384.848861] ( 16.770832) c0 kpb src/audio/kpb.c:1427 kpb_clear_history_buffer() [ 32641797.088348] ( 2412.239502) c0 dai 9.55 src/audio/dai.c:870 dai_reset() [ 32641817.400847] ( 20.312500) c0 dw-dma src/drivers/dw/dma.c:254 dw_dma_channel_put(): dma 0 channel 0 put [ 32642800.369558] ( 982.968689) c0 pipe 10.62 ....../pipeline-stream.c:184 pipe trigger cmd 0 [ 32642822.244557] ( 21.875000) c0 selector 10.58 ..../selector/selector.c:356 selector_trigger() [ 32642842.088307] ( 19.843750) c0 ll-schedule ./schedule/ll_schedule.c:539 task cancel 0xbe120240 pipe-task [ 32642861.307056] ( 19.218750) c0 ll-schedule ./schedule/ll_schedule.c:359 num_tasks 3 total_num_tasks 3 [ 32643151.671628] ( 290.364563) c0 pipe 10.62 ......./pipeline-graph.c:383 pipe reset [ 32643386.254952] ( 234.583328) c0 selector 10.58 ..../selector/selector.c:516 selector_reset() [ 32643405.369534] ( 19.114582) c0 kpb src/audio/kpb.c:568 kpb_reset(): resetting from state 3, state log 54675783 [ 32643422.661200] ( 17.291666) c0 kpb src/audio/kpb.c:1532 kpb_reset_history_buffer() [ 32643439.015366] ( 16.354166) c0 kpb src/audio/kpb.c:1427 kpb_clear_history_buffer() [ 32645845.473604] ( 2406.458252) c0 kpb 9.51 src/audio/component.c:64 comp_set_state(), state already set to 1 [ 32924212.650043] ( 278367.187500) c0 pipe 9.56 ....../pipeline-params.c:215 pipe params dir 1 frame_fmt 0 buffer_fmt 0 rate 16000 [ 32924231.347959] ( 18.697916) c0 pipe 9.56 ....../pipeline-params.c:219 pipe params stream_tag 2 channels 2 sample_valid_bytes 2 sample_container_bytes 2 [ 32924466.712533] ( 235.364578) c0 dai 9.55 src/audio/dai.c:357 dai_data_config() dai type = 2 index = 1 dd 0x9e1c3a00 [ 32924485.045865] ( 18.333332) c0 dai 9.55 src/audio/dai.c:376 config->dmic.fifo_bits = 32 config->dmic.num_pdm_active = 1 [ 32924805.670852] ( 320.625000) c0 dai 9.55 src/audio/dai.c:509 dai_capture_params() src_dev = 1 stream_id = 0 src_width = 4 dest_width = 4 [ 32924824.160435] ( 18.489582) c0 dai 9.55 src/audio/dai.c:515 dai_capture_params() fifo 0x10108 [ 32924853.639601] ( 29.479166) c0 pipe 9.56 ....../pipeline-params.c:297 pipe prepare [ 32924945.566680] ( 91.927078) c0 kpb 9.51 src/audio/kpb.c:442 kpb_prepare() [ 32924963.275013] ( 17.708332) c0 kpb src/audio/kpb.c:1532 kpb_reset_history_buffer() [ 32924980.045846] ( 16.770832) c0 kpb src/audio/kpb.c:1427 kpb_clear_history_buffer() [ 32927567.076993] ( 2587.031250) c0 dai 9.55 src/audio/dai.c:821 dai_prepare() [ 32927586.347825] ( 19.270832) c0 dai 9.55 src/audio/dai.c:769 dai_config_prepare(), channel = 0 [ 32927604.160325] ( 17.812500) c0 dw-dma src/drivers/dw/dma.c:191 dw_dma_channel_get(): dma 0 request channel 0 [ 32927622.597824] ( 18.437500) c0 dai 9.55 src/audio/dai.c:786 dai_config(): new configured dma channel index 0 [ 32928448.431124] ( 825.833313) c0 pipe 10.62 ....../pipeline-params.c:215 pipe params dir 1 frame_fmt 0 buffer_fmt 0 rate 16000 [ 32928466.295707] ( 17.864582) c0 pipe 10.62 ....../pipeline-params.c:219 pipe params stream_tag 0 channels 2 sample_valid_bytes 2 sample_container_bytes 0 [ 32928501.035289] ( 34.739582) c0 selector 10.58 ..../selector/selector.c:225 selector_params() [ 32928527.545705] ( 26.510416) c0 kpb 9.51 src/audio/kpb.c:408 ERROR kpb_params(): kpb has been already configured. [ 32928548.118620] ( 20.572916) c0 pipe 10.62 ....../pipeline-params.c:297 pipe prepare [ 32928566.035286] ( 17.916666) c0 google-hotword-detect 10.57 /google_hotword_detect.c:216 Model: data=0xbe198b00, size=66580 [ 32928584.576952] ( 18.541666) c0 google-hotword-detect 10.57 /google_hotword_detect.c:219 GoogleHotwordVersion 306749607 [ 32929048.483184] ( 463.906219) c0 selector 10.58 ..../selector/selector.c:435 selector_prepare() [ 32929067.181100] ( 18.697916) c0 selector 10.58 ..../selector/selector.c:465 selector_prepare(): sourceb->schannels = 2 [ 32929083.379016] ( 16.197916) c0 selector 10.58 ..../selector/selector.c:467 selector_prepare(): sinkb->channels = 1 [ 32929164.785263] ( 81.406250) c0 kpb 9.51 src/audio/kpb.c:442 kpb_prepare() [ 32929183.587345] ( 18.802082) c0 kpb 9.51 src/audio/component.c:64 comp_set_state(), state already set to 3 [ 32929478.274833] ( 294.687500) c0 pipe 10.62 ....../pipeline-stream.c:184 pipe trigger cmd 1 [ 32929679.628992] ( 201.354156) c0 selector 10.58 ..../selector/selector.c:356 selector_trigger() [ 32929700.826908] ( 21.197916) c0 ll-schedule ./schedule/ll_schedule.c:413 task add 0xbe120240 pipe-task [ 32929717.858157] ( 17.031250) c0 ll-schedule ./schedule/ll_schedule.c:417 task params pri 1 flags 0 start 0 period 20000 [ 32929744.472740] ( 26.614582) c0 ll-schedule ./schedule/ll_schedule.c:329 new added task->start 636991548 at 636974351 [ 32929763.274822] ( 18.802082) c0 ll-schedule ./schedule/ll_schedule.c:332 num_tasks 4 total_num_tasks 4 [ 32931144.003934] ( 1380.729126) c0 pipe 9.56 ....../pipeline-stream.c:184 pipe trigger cmd 1 [ 32931173.951849] ( 29.947916) c0 kpb 9.51 src/audio/kpb.c:374 kpb_trigger() [ 32931213.691431] ( 39.739582) c0 dmic-dai 2.1 src/drivers/intel/dmic.c:1320 dmic_start(), dmic->fifo_b [ 32931234.160180] ( 20.468750) c0 dmic-dai 2.1 src/drivers/intel/dmic.c:1346 dmic_start(), mic_a = 1, mic_b = 1, fir_a = 1, fir_b = 1 [ 32931253.222680] ( 19.062500) c0 dmic-dai 2.1 src/drivers/intel/dmic.c:1346 dmic_start(), mic_a = 0, mic_b = 0, fir_a = 0, fir_b = 0 [ 32931274.212262] ( 20.989582) c0 ll-schedule ./schedule/ll_schedule.c:413 task add 0x9e1c0908 dmic-work <59c87728-d8f9-42f6-b89d-5870a87b0e1e> [ 32931291.868511] ( 17.656250) c0 ll-schedule ./schedule/ll_schedule.c:417 task params pri 4 flags 0 start 1000 period 1000 [ 32931319.889344] ( 28.020832) c0 ll-schedule ./schedule/ll_schedule.c:329 new added task->start 637029948 at 637004565 [ 32931338.639343] ( 18.750000) c0 ll-schedule ./schedule/ll_schedule.c:332 num_tasks 5 total_num_tasks 5 [ 32931357.858092] ( 19.218750) c0 dmic-dai 2.1 src/drivers/intel/dmic.c:1420 dmic_start(), done active_fifos = 1 [ 32931381.816424] ( 23.958332) c0 ll-schedule ./schedule/ll_schedule.c:413 task add 0xbe120180 pipe-task [ 32931400.410174] ( 18.593750) c0 ll-schedule ./schedule/ll_schedule.c:417 task params pri 0 flags 0 start 0 period 20000 [ 32931426.399756] ( 25.989582) c0 ll-schedule ./schedule/ll_schedule.c:329 new added task->start 637029948 at 637006615 [ 32931444.681005] ( 18.281250) c0 ll-schedule ./schedule/ll_schedule.c:332 num_tasks 6 total_num_tasks 6 [ 33733256.003311] ( 801811.312500) c0 ll-schedule ./schedule/ll_schedule.c:125 task complete 0x9e1c0908 dmic-work <59c87728-d8f9-42f6-b89d-5870a87b0e1e> [ 33733274.076227] ( 18.072916) c0 ll-schedule ./schedule/ll_schedule.c:128 num_tasks 5 total_num_tasks 5 [ 37923766.045128] ( 4190492.000000) c1 idc ......./intel/cavs/idc.c:58 idc_irq_handler(), IPC_IDCTFC_BUSY [ 37923787.815961] ( 21.770832) c1 idc ......./intel/cavs/idc.c:165 idc_do_cmd() [ 37923817.763876] ( 29.947916) c1 pipe 4.24 ....../pipeline-params.c:215 pipe params dir 1 frame_fmt 2 buffer_fmt 0 rate 48000 [ 37923835.211792] ( 17.447916) c1 pipe 4.24 ....../pipeline-params.c:219 pipe params stream_tag 3 channels 4 sample_valid_bytes 4 sample_container_bytes 4 [ 37923993.857619] ( 158.645828) c1 igo-nr 4.20 ../audio/igo_nr/igo_nr.c:381 igo_nr_params() [ 37924019.534702] ( 25.677082) c1 igo-nr 4.20 ../audio/igo_nr/igo_nr.c:423 igo_nr_params(), sample rate = 48000 [ 37924045.263867] ( 25.729166) c1 dai 4.23 src/audio/dai.c:357 dai_data_config() dai type = 2 index = 0 dd 0x9e1c2300 [ 37924063.440950] ( 18.177082) c1 dai 4.23 src/audio/dai.c:376 config->dmic.fifo_bits = 32 config->dmic.num_pdm_active = 2 [ 37924455.315934] ( 391.874969) c1 memory src/lib/alloc.c:302 ERROR failed to allocate 49216 [ 37924481.461767] ( 26.145832) c1 buffer src/audio/buffer.c:60 ERROR buffer_alloc(): could not alloc size = 49152 bytes of type = 32 [ 37924498.961766] ( 17.500000) c1 dai 4.23 src/audio/dai.c:638 ERROR dai_params(): failed to alloc dma buffer [ 37924519.378432] ( 20.416666) c1 pipe 4.24 ....../pipeline-params.c:235 ERROR pipeline_params(): ret = -12, host->comp.id = 19 [ 37924538.440931] ( 19.062500) c1 ipc src/ipc/handler-ipc3.c:284 ERROR ipc: pipe 4 comp 19 params failed -12 [ 37924556.045097] ( 17.604166) c1 pipe 4.24 ......./pipeline-graph.c:383 pipe reset [ 37924662.659676] ( 106.614578) c1 igo-nr 4.20 ../audio/igo_nr/igo_nr.c:786 igo_nr_reset() [ 37924680.003425] ( 17.343750) c1 igo-nr 4.20 src/audio/component.c:64 comp_set_state(), state already set to 1 [ 37924701.357591] ( 21.354166) c1 dai 4.23 src/audio/dai.c:870 dai_reset()