[34276079360.729168] (335839904.000000) c0 dma-trace src/trace/dma-trace.c:339 ERROR FW ABI 0x3012001 DBG ABI 0x5002001 tag v1.5-rc1-919-g445d65f89a9b src hash 0x77242610 (ldc hash 0x77242610) [34276079605.052086] ( 244.322922) c0 ll-schedule ./schedule/ll_schedule.c:345 task add 0x9e136480 dma-trace-task <2b972272-c5b1-4b7e-926f-0fc5cb4c4690> [34276079613.281250] ( 8.229167) c0 ll-schedule ./schedule/ll_schedule.c:349 task params pri 4 flags 0 start 500000 period 500000 [34276079644.322918] ( 31.041666) c0 ll-schedule /schedule/timer_domain.c:176 timer_domain_register domain->type 1 domain->clk 4 domain->ticks_per_ms 38400 period 500000 [34276079662.135418] ( 17.812500) c0 ll-schedule ./schedule/ll_schedule.c:252 num_tasks 1 total_num_tasks 1 [34276080138.593750] ( 476.458344) c0 component src/audio/component.c:130 comp new host <8b9d100c-6d78-418f-90a3-e0e805d0852b> type 1 id 1.0 [34276080172.916668] ( 34.322918) c0 hda-dma ..../intel/hda/hda-dma.c:794 hda-dmac :5 -> probe [34276080254.114586] ( 81.197914) c0 dma src/lib/dma.c:126 dma_get() ID 5 sref = 1 busy channels 0 [34276080469.166668] ( 215.052078) c0 component src/audio/component.c:130 comp new pga type 5 id 1.1 [34276080501.197918] ( 32.031250) c0 pga 1.1 ../audio/volume/volume.c:379 vol->initial_ramp = 20, vol->ramp = 2, vol->min_value = 0, vol->max_value = 65536 [34276081048.437500] ( 547.239563) c0 buffer src/audio/buffer.c:79 buffer new size 0x300 id 1.2 flags 0x0 [34276081413.593750] ( 365.156250) c0 buffer src/audio/buffer.c:79 buffer new size 0x300 id 1.3 flags 0x0 [34276081642.135418] ( 228.541672) c0 component src/audio/component.c:130 comp new dai type 2 id 1.4 [34276081676.718750] ( 34.583332) c0 ll-schedule ./schedule/ll_schedule.c:202 perf ll_work peak plat 128 cpu 1265 [34276081714.791668] ( 38.072918) c0 alh-dai 4.2 src/drivers/intel/alh.c:90 alh_probe() [34276081730.885418] ( 16.093750) c0 dai src/lib/dai.c:164 dai_get type 4 index 2 new sref 1 [34276081817.760418] ( 86.875000) c0 dma src/lib/dma.c:126 dma_get() ID 0 sref = 1 busy channels 0 [34276082008.645836] ( 190.885422) c0 pipe src/audio/pipeline.c:78 pipeline new pipe_id 1 period 1000 priority 0 [34276082215.833336] ( 207.187500) c0 component src/audio/component.c:130 comp new host <8b9d100c-6d78-418f-90a3-e0e805d0852b> type 1 id 2.6 [34276082248.906250] ( 33.072918) c0 dma src/lib/dma.c:126 dma_get() ID 4 sref = 2 busy channels 1 [34276082573.854168] ( 324.947906) c0 component src/audio/component.c:130 comp new pga type 5 id 2.7 [34276082605.052086] ( 31.197916) c0 pga 2.7 ../audio/volume/volume.c:379 vol->initial_ramp = 250, vol->ramp = 0, vol->min_value = 0, vol->max_value = 2072775 [34276082847.968750] ( 242.916672) c0 buffer src/audio/buffer.c:79 buffer new size 0x300 id 2.8 flags 0x0 [34276083052.760418] ( 204.791672) c0 buffer src/audio/buffer.c:79 buffer new size 0x300 id 2.9 flags 0x0 [34276083273.385418] ( 220.625000) c0 component src/audio/component.c:130 comp new dai type 2 id 2.10 [34276083304.791668] ( 31.406250) c0 alh-dai 4.3 src/drivers/intel/alh.c:90 alh_probe() [34276083321.250000] ( 16.458334) c0 dai src/lib/dai.c:164 dai_get type 4 index 3 new sref 1 [34276083407.500000] ( 86.250000) c0 dma src/lib/dma.c:126 dma_get() ID 1 sref = 1 busy channels 0 [34276083699.427086] ( 291.927094) c0 ll-schedule ./schedule/ll_schedule.c:202 perf ll_work peak plat 130 cpu 1289 [34276083750.052086] ( 50.625000) c0 pipe src/audio/pipeline.c:78 pipeline new pipe_id 2 period 1000 priority 0 [34276083959.947918] ( 209.895828) c0 component src/audio/component.c:130 comp new host <8b9d100c-6d78-418f-90a3-e0e805d0852b> type 1 id 3.12 [34276083993.020836] ( 33.072918) c0 dma src/lib/dma.c:126 dma_get() ID 5 sref = 2 busy channels 0 [34276084208.697918] ( 215.677078) c0 component src/audio/component.c:130 comp new smart_amp-test <167a961e-8ae4-11ea-89f1-000c29ce1635> type 21 id 3.13 [34276084401.927086] ( 193.229172) c0 buffer src/audio/buffer.c:79 buffer new size 0x300 id 3.14 flags 0x0 [34276084806.197918] ( 404.270844) c0 buffer src/audio/buffer.c:79 buffer new size 0x300 id 3.15 flags 0x0 [34276085062.500000] ( 256.302094) c0 buffer src/audio/buffer.c:79 buffer new size 0x600 id 3.16 flags 0x0 [34276085308.437500] ( 245.937500) c0 component src/audio/component.c:130 comp new dai type 2 id 3.17 [34276085340.468750] ( 32.031250) c0 alh-dai 4.258 src/drivers/intel/alh.c:90 alh_probe() [34276085356.770836] ( 16.302084) c0 dai src/lib/dai.c:164 dai_get type 4 index 258 new sref 1 [34276085368.125000] ( 11.354167) c0 dma src/lib/dma.c:126 dma_get() ID 0 sref = 2 busy channels 0 [34276085566.718750] ( 198.593750) c0 pipe src/audio/pipeline.c:78 pipeline new pipe_id 3 period 1000 priority 0 [34276085919.791668] ( 353.072906) c0 component src/audio/component.c:130 comp new host <8b9d100c-6d78-418f-90a3-e0e805d0852b> type 1 id 4.19 [34276085953.385418] ( 33.593750) c0 dma src/lib/dma.c:126 dma_get() ID 4 sref = 3 busy channels 1 [34276086153.541668] ( 200.156250) c0 component src/audio/component.c:130 comp new demux type 18 id 4.20 [34276086163.020836] ( 9.479167) c0 mux src/audio/mux/mux.c:133 mux_new() [34276086207.239586] ( 44.218750) c0 demux 4.20 src/audio/mux/mux.c:54 mux_set_values() [34276086383.281250] ( 176.041672) c0 buffer src/audio/buffer.c:79 buffer new size 0x300 id 4.21 flags 0x0 [34276086573.541668] ( 190.260422) c0 buffer src/audio/buffer.c:79 buffer new size 0x600 id 4.22 flags 0x0 [34276087132.031250] ( 558.489563) c0 component src/audio/component.c:130 comp new dai type 2 id 4.23 [34276087164.218750] ( 32.187500) c0 alh-dai 4.259 src/drivers/intel/alh.c:90 alh_probe() [34276087180.885418] ( 16.666666) c0 dai src/lib/dai.c:164 dai_get type 4 index 259 new sref 1 [34276087192.447918] ( 11.562500) c0 dma src/lib/dma.c:126 dma_get() ID 1 sref = 2 busy channels 0 [34276087408.437500] ( 215.989578) c0 pipe src/audio/pipeline.c:78 pipeline new pipe_id 4 period 1000 priority 0 [34276087637.343750] ( 228.906250) c0 component src/audio/component.c:130 comp new host <8b9d100c-6d78-418f-90a3-e0e805d0852b> type 1 id 7.25 [34276087703.437500] ( 66.093750) c0 dma src/lib/dma.c:126 dma_get() ID 5 sref = 3 busy channels 0 [34276088000.416668] ( 296.979156) c0 component src/audio/component.c:130 comp new pga type 5 id 7.26 [34276088031.875000] ( 31.458334) c0 pga 7.26 ../audio/volume/volume.c:379 vol->initial_ramp = 20, vol->ramp = 2, vol->min_value = 0, vol->max_value = 65536 [34276088205.364586] ( 173.489578) c0 buffer src/audio/buffer.c:79 buffer new size 0x300 id 7.27 flags 0x0 [34276088400.260418] ( 194.895828) c0 buffer src/audio/buffer.c:79 buffer new size 0x300 id 7.28 flags 0x0 [34276088608.802086] ( 208.541672) c0 component src/audio/component.c:130 comp new dai type 2 id 7.29 [34276088640.416668] ( 31.614584) c0 hda-dai 3.0 /drivers/intel/hda/hda.c:65 hda_probe() [34276088654.427086] ( 14.010417) c0 dai src/lib/dai.c:164 dai_get type 3 index 0 new sref 1 [34276088666.041668] ( 11.614583) c0 hda-dma ..../intel/hda/hda-dma.c:794 hda-dmac :7 -> probe [34276088732.552086] ( 66.510414) c0 dma src/lib/dma.c:126 dma_get() ID 7 sref = 1 busy channels 0 [34276089059.635418] ( 327.083344) c0 pipe src/audio/pipeline.c:78 pipeline new pipe_id 7 period 1000 priority 0 [34276089307.552086] ( 247.916672) c0 component src/audio/component.c:130 comp new host <8b9d100c-6d78-418f-90a3-e0e805d0852b> type 1 id 8.31 [34276089340.625000] ( 33.072918) c0 dma src/lib/dma.c:126 dma_get() ID 5 sref = 4 busy channels 0 [34276089543.333336] ( 202.708328) c0 component src/audio/component.c:130 comp new pga type 5 id 8.32 [34276089574.427086] ( 31.093750) c0 pga 8.32 ../audio/volume/volume.c:379 vol->initial_ramp = 20, vol->ramp = 2, vol->min_value = 0, vol->max_value = 65536 [34276089737.708336] ( 163.281250) c0 buffer src/audio/buffer.c:79 buffer new size 0x300 id 8.33 flags 0x0 [34276090039.270836] ( 301.562500) c0 buffer src/audio/buffer.c:79 buffer new size 0x300 id 8.34 flags 0x0 [34276090230.468750] ( 191.197922) c0 component src/audio/component.c:130 comp new dai type 2 id 8.35 [34276090261.822918] ( 31.354166) c0 hda-dai 3.1 /drivers/intel/hda/hda.c:65 hda_probe() [34276090275.781250] ( 13.958333) c0 dai src/lib/dai.c:164 dai_get type 3 index 1 new sref 1 [34276090286.875000] ( 11.093750) c0 dma src/lib/dma.c:126 dma_get() ID 7 sref = 2 busy channels 0 [34276090494.322918] ( 207.447922) c0 pipe src/audio/pipeline.c:78 pipeline new pipe_id 8 period 1000 priority 0 [34276090761.458336] ( 267.135406) c0 component src/audio/component.c:130 comp new host <8b9d100c-6d78-418f-90a3-e0e805d0852b> type 1 id 9.37 [34276090794.375000] ( 32.916668) c0 dma src/lib/dma.c:126 dma_get() ID 5 sref = 5 busy channels 0 [34276091110.208336] ( 315.833344) c0 component src/audio/component.c:130 comp new pga type 5 id 9.38 [34276091141.145836] ( 30.937500) c0 pga 9.38 ../audio/volume/volume.c:379 vol->initial_ramp = 20, vol->ramp = 2, vol->min_value = 0, vol->max_value = 65536 [34276091318.489586] ( 177.343750) c0 buffer src/audio/buffer.c:79 buffer new size 0x300 id 9.39 flags 0x0 [34276091513.385418] ( 194.895828) c0 buffer src/audio/buffer.c:79 buffer new size 0x300 id 9.40 flags 0x0 [34276091745.677086] ( 232.291672) c0 component src/audio/component.c:130 comp new dai type 2 id 9.41 [34276091777.083336] ( 31.406250) c0 hda-dai 3.2 /drivers/intel/hda/hda.c:65 hda_probe() [34276091791.041668] ( 13.958333) c0 dai src/lib/dai.c:164 dai_get type 3 index 2 new sref 1 [34276091801.927086] ( 10.885417) c0 dma src/lib/dma.c:126 dma_get() ID 7 sref = 3 busy channels 0 [34276092120.937500] ( 319.010406) c0 pipe src/audio/pipeline.c:78 pipeline new pipe_id 9 period 1000 priority 0 [34276092346.041668] ( 225.104172) c0 component src/audio/component.c:130 comp new host <8b9d100c-6d78-418f-90a3-e0e805d0852b> type 1 id 10.43 [34276092379.010418] ( 32.968750) c0 dma src/lib/dma.c:126 dma_get() ID 5 sref = 6 busy channels 0 [34276092602.239586] ( 223.229172) c0 component src/audio/component.c:130 comp new pga type 5 id 10.44 [34276092633.281250] ( 31.041666) c0 pga 10.44 ../audio/volume/volume.c:379 vol->initial_ramp = 20, vol->ramp = 2, vol->min_value = 0, vol->max_value = 65536 [34276092836.614586] ( 203.333328) c0 buffer src/audio/buffer.c:79 buffer new size 0x300 id 10.45 flags 0x0 [34276093124.166668] ( 287.552094) c0 buffer src/audio/buffer.c:79 buffer new size 0x300 id 10.46 flags 0x0 [34276093348.750000] ( 224.583328) c0 component src/audio/component.c:130 comp new dai type 2 id 10.47 [34276093380.000000] ( 31.250000) c0 hda-dai 3.3 /drivers/intel/hda/hda.c:65 hda_probe() [34276093394.062500] ( 14.062500) c0 dai src/lib/dai.c:164 dai_get type 3 index 3 new sref 1 [34276093404.947918] ( 10.885417) c0 dma src/lib/dma.c:126 dma_get() ID 7 sref = 4 busy channels 0 [34276093619.322918] ( 214.375000) c0 pipe src/audio/pipeline.c:78 pipeline new pipe_id 10 period 1000 priority 0 [34276093875.260418] ( 255.937500) c0 component src/audio/component.c:130 comp new host <8b9d100c-6d78-418f-90a3-e0e805d0852b> type 1 id 11.49 [34276093908.645836] ( 33.385418) c0 dma src/lib/dma.c:126 dma_get() ID 4 sref = 4 busy channels 1 [34276094134.947918] ( 226.302078) c0 buffer src/audio/buffer.c:79 buffer new size 0x600 id 11.50 flags 0x0 [34276094353.489586] ( 218.541672) c0 component src/audio/component.c:130 comp new dai type 2 id 11.51 [34276094385.000000] ( 31.510416) c0 dmic-dai 2.0 src/drivers/intel/dmic.c:1555 dmic_probe() [34276094431.093750] ( 46.093750) c0 dai src/lib/dai.c:164 dai_get type 2 index 0 new sref 1 [34276094442.552086] ( 11.458333) c0 dma src/lib/dma.c:126 dma_get() ID 0 sref = 3 busy channels 0 [34276094659.114586] ( 216.562500) c0 pipe src/audio/pipeline.c:78 pipeline new pipe_id 11 period 1000 priority 0 [34276094894.531250] ( 235.416672) c0 component src/audio/component.c:130 comp new host <8b9d100c-6d78-418f-90a3-e0e805d0852b> type 1 id 12.53 [34276094927.552086] ( 33.020832) c0 dma src/lib/dma.c:126 dma_get() ID 4 sref = 5 busy channels 1 [34276095216.250000] ( 288.697906) c0 component src/audio/component.c:130 comp new pga type 5 id 12.54 [34276095247.447918] ( 31.197916) c0 pga 12.54 ../audio/volume/volume.c:379 vol->initial_ramp = 250, vol->ramp = 0, vol->min_value = 0, vol->max_value = 2072775 [34276095452.291668] ( 204.843750) c0 component src/audio/component.c:130 comp new kpb type 16 id 12.55 [34276095461.562500] ( 9.270833) c0 kpb src/audio/kpb.c:135 kpb_new() [34276095746.197918] ( 284.635406) c0 buffer src/audio/buffer.c:79 buffer new size 0x780 id 12.56 flags 0x0 [34276095955.833336] ( 209.635422) c0 buffer src/audio/buffer.c:79 buffer new size 0x500 id 12.57 flags 0x0 [34276096207.031250] ( 251.197922) c0 buffer src/audio/buffer.c:79 buffer new size 0x500 id 12.58 flags 0x0 [34276096426.666668] ( 219.635422) c0 component src/audio/component.c:130 comp new dai type 2 id 12.59 [34276096457.968750] ( 31.302084) c0 dmic-dai 2.1 src/drivers/intel/dmic.c:1555 dmic_probe() [34276096499.062500] ( 41.093750) c0 dai src/lib/dai.c:164 dai_get type 2 index 1 new sref 1 [34276096510.625000] ( 11.562500) c0 dma src/lib/dma.c:126 dma_get() ID 1 sref = 3 busy channels 0 [34276096812.291668] ( 301.666656) c0 pipe src/audio/pipeline.c:78 pipeline new pipe_id 12 period 5000 priority 0 [34276097053.750000] ( 241.458328) c0 component src/audio/component.c:130 comp new kd-test type 15 id 13.61 [34276097062.395836] ( 8.645833) c0 kd-test ..../audio/detect_test.c:256 test_keyword_new() [34276097324.010418] ( 261.614594) c0 component src/audio/component.c:130 comp new selector <55a88ed5-3d18-46ca-88f1-0ee6eae9930f> type 17 id 13.62 [34276097333.333336] ( 9.322917) c0 selector ..../selector/selector.c:61 selector_new() [34276097529.010418] ( 195.677078) c0 buffer src/audio/buffer.c:79 buffer new size 0x500 id 13.63 flags 0x0 [34276097747.916668] ( 218.906250) c0 buffer src/audio/buffer.c:79 buffer new size 0x500 id 13.64 flags 0x0 [34276097990.572918] ( 242.656250) c0 pipe src/audio/pipeline.c:78 pipeline new pipe_id 13 period 5000 priority 1 [34276098292.812500] ( 302.239594) c0 demux 4.20 src/audio/pipeline.c:125 connect buffer 16 as sink [34276098497.447918] ( 204.635422) c0 kpb 12.55 src/audio/pipeline.c:125 connect buffer 63 as sink [34276098680.416668] ( 182.968750) c0 host 1.0 src/audio/pipeline.c:125 connect buffer 2 as sink [34276098835.260418] ( 154.843750) c0 pga 1.1 src/audio/pipeline.c:127 connect buffer 2 as source [34276098983.489586] ( 148.229172) c0 pga 1.1 src/audio/pipeline.c:125 connect buffer 3 as sink [34276099135.729168] ( 152.239578) c0 dai 1.4 src/audio/pipeline.c:127 connect buffer 3 as source [34276099380.052086] ( 244.322922) c0 host 2.6 src/audio/pipeline.c:127 connect buffer 8 as source [34276099562.291668] ( 182.239578) c0 pga 2.7 src/audio/pipeline.c:125 connect buffer 8 as sink [34276099731.510418] ( 169.218750) c0 pga 2.7 src/audio/pipeline.c:127 connect buffer 9 as source [34276099877.447918] ( 145.937500) c0 dai 2.10 src/audio/pipeline.c:125 connect buffer 9 as sink [34276100027.864586] ( 150.416672) c0 host 3.12 src/audio/pipeline.c:125 connect buffer 14 as sink [34276100180.572918] ( 152.708328) c0 smart_amp-test 3.13 src/audio/pipeline.c:127 connect buffer 14 as source [34276100330.729168] ( 150.156250) c0 smart_amp-test 3.13 src/audio/pipeline.c:127 connect buffer 16 as source [34276100601.875000] ( 271.145844) c0 smart_amp-test 3.13 src/audio/pipeline.c:125 connect buffer 15 as sink [34276100873.177086] ( 271.302094) c0 dai 3.17 src/audio/pipeline.c:127 connect buffer 15 as source [34276101052.083336] ( 178.906250) c0 host 4.19 src/audio/pipeline.c:127 connect buffer 21 as source [34276101208.854168] ( 156.770828) c0 demux 4.20 src/audio/pipeline.c:125 connect buffer 21 as sink [34276101369.218750] ( 160.364578) c0 demux 4.20 src/audio/pipeline.c:127 connect buffer 22 as source [34276101537.083336] ( 167.864578) c0 dai 4.23 src/audio/pipeline.c:125 connect buffer 22 as sink [34276101765.729168] ( 228.645828) c0 ll-schedule ./schedule/ll_schedule.c:202 perf ll_work peak plat 1412 cpu 14672 [34276101816.041668] ( 50.312500) c0 host 7.25 src/audio/pipeline.c:125 connect buffer 27 as sink [34276101983.958336] ( 167.916672) c0 pga 7.26 src/audio/pipeline.c:127 connect buffer 27 as source [34276102154.322918] ( 170.364578) c0 pga 7.26 src/audio/pipeline.c:125 connect buffer 28 as sink [34276102310.625000] ( 156.302078) c0 dai 7.29 src/audio/pipeline.c:127 connect buffer 28 as source [34276102471.822918] ( 161.197922) c0 host 8.31 src/audio/pipeline.c:125 connect buffer 33 as sink [34276102854.479168] ( 382.656250) c0 pga 8.32 src/audio/pipeline.c:127 connect buffer 33 as source [34276103086.406250] ( 231.927078) c0 pga 8.32 src/audio/pipeline.c:125 connect buffer 34 as sink [34276103263.958336] ( 177.552078) c0 dai 8.35 src/audio/pipeline.c:127 connect buffer 34 as source [34276103435.625000] ( 171.666672) c0 host 9.37 src/audio/pipeline.c:125 connect buffer 39 as sink [34276103601.406250] ( 165.781250) c0 pga 9.38 src/audio/pipeline.c:127 connect buffer 39 as source [34276103747.656250] ( 146.250000) c0 pga 9.38 src/audio/pipeline.c:125 connect buffer 40 as sink [34276104130.989586] ( 383.333344) c0 dai 9.41 src/audio/pipeline.c:127 connect buffer 40 as source [34276104296.875000] ( 165.885422) c0 host 10.43 src/audio/pipeline.c:125 connect buffer 45 as sink [34276104473.802086] ( 176.927078) c0 pga 10.44 src/audio/pipeline.c:127 connect buffer 45 as source [34276104660.520836] ( 186.718750) c0 pga 10.44 src/audio/pipeline.c:125 connect buffer 46 as sink [34276105205.625000] ( 545.104187) c0 dai 10.47 src/audio/pipeline.c:127 connect buffer 46 as source [34276105383.385418] ( 177.760422) c0 host 11.49 src/audio/pipeline.c:127 connect buffer 50 as source [34276105564.010418] ( 180.625000) c0 dai 11.51 src/audio/pipeline.c:125 connect buffer 50 as sink [34276105756.666668] ( 192.656250) c0 host 12.53 src/audio/pipeline.c:127 connect buffer 58 as source [34276106075.781250] ( 319.114594) c0 kpb 12.55 src/audio/pipeline.c:125 connect buffer 58 as sink [34276106258.802086] ( 183.020828) c0 kpb 12.55 src/audio/pipeline.c:127 connect buffer 57 as source [34276106465.729168] ( 206.927078) c0 pga 12.54 src/audio/pipeline.c:125 connect buffer 57 as sink [34276106662.291668] ( 196.562500) c0 pga 12.54 src/audio/pipeline.c:127 connect buffer 56 as source [34276106836.562500] ( 174.270828) c0 dai 12.59 src/audio/pipeline.c:125 connect buffer 56 as sink [34276107094.791668] ( 258.229156) c0 kd-test 13.61 src/audio/pipeline.c:127 connect buffer 64 as source [34276107272.291668] ( 177.500000) c0 selector 13.62 src/audio/pipeline.c:125 connect buffer 64 as sink [34276107440.104168] ( 167.812500) c0 selector 13.62 src/audio/pipeline.c:127 connect buffer 63 as source [34276107598.125000] ( 158.020828) c0 dai src/lib/dai.c:164 dai_get type 4 index 2 new sref 2 [34276107607.708336] ( 9.583333) c0 alh-dai 4.2 src/drivers/intel/alh.c:38 alh_set_config() config->format = 0x 0 [34276107618.177086] ( 10.468750) c0 dai src/lib/dai.c:189 dai_put type 4 index 2 new sref 1 [34276107631.770836] ( 13.593750) c0 dai 1.4 src/audio/dai.c:889 dai_config() dai type = 4 index = 2 [34276107641.406250] ( 9.635417) c0 dai 1.4 src/audio/dai.c:950 dai_config(), channel = 0 [34276107649.843750] ( 8.437500) c0 dw-dma src/drivers/dw/dma.c:190 dw_dma_channel_get(): dma 0 request channel 0 [34276107943.385418] ( 293.541656) c0 dai src/lib/dai.c:164 dai_get type 4 index 3 new sref 2 [34276107952.656250] ( 9.270833) c0 alh-dai 4.3 src/drivers/intel/alh.c:38 alh_set_config() config->format = 0x 0 [34276107963.125000] ( 10.468750) c0 dai src/lib/dai.c:189 dai_put type 4 index 3 new sref 1 [34276107979.479168] ( 16.354166) c0 dai 2.10 src/audio/dai.c:889 dai_config() dai type = 4 index = 3 [34276107988.906250] ( 9.427083) c0 dai 2.10 src/audio/dai.c:950 dai_config(), channel = 0 [34276107996.666668] ( 7.760417) c0 dw-dma src/drivers/dw/dma.c:190 dw_dma_channel_get(): dma 1 request channel 0 [34276108200.677086] ( 204.010422) c0 dai src/lib/dai.c:164 dai_get type 4 index 258 new sref 2 [34276108209.895836] ( 9.218750) c0 alh-dai 4.258 src/drivers/intel/alh.c:38 alh_set_config() config->format = 0x 0 [34276108220.156250] ( 10.260417) c0 dai src/lib/dai.c:189 dai_put type 4 index 258 new sref 1 [34276108240.156250] ( 20.000000) c0 dai 3.17 src/audio/dai.c:889 dai_config() dai type = 4 index = 258 [34276108249.375000] ( 9.218750) c0 dai 3.17 src/audio/dai.c:950 dai_config(), channel = 0 [34276108257.291668] ( 7.916667) c0 dw-dma src/drivers/dw/dma.c:190 dw_dma_channel_get(): dma 0 request channel 0 [34276108452.031250] ( 194.739578) c0 dai src/lib/dai.c:164 dai_get type 4 index 259 new sref 2 [34276108461.458336] ( 9.427083) c0 alh-dai 4.259 src/drivers/intel/alh.c:38 alh_set_config() config->format = 0x 0 [34276108471.875000] ( 10.416667) c0 dai src/lib/dai.c:189 dai_put type 4 index 259 new sref 1 [34276108495.468750] ( 23.593750) c0 dai 4.23 src/audio/dai.c:889 dai_config() dai type = 4 index = 259 [34276108504.739586] ( 9.270833) c0 dai 4.23 src/audio/dai.c:950 dai_config(), channel = 0 [34276108512.500000] ( 7.760417) c0 dw-dma src/drivers/dw/dma.c:190 dw_dma_channel_get(): dma 1 request channel 0 [34276108855.625000] ( 343.125000) c0 dai src/lib/dai.c:164 dai_get type 3 index 0 new sref 2 [34276108866.354168] ( 10.729167) c0 dai src/lib/dai.c:189 dai_put type 3 index 0 new sref 1 [34276108893.489586] ( 27.135416) c0 dai 7.29 src/audio/dai.c:889 dai_config() dai type = 3 index = 0 [34276108902.500000] ( 9.010417) c0 dai 7.29 src/audio/dai.c:921 dai_config(), channel = -1 [34276109114.895836] ( 212.395828) c0 dai src/lib/dai.c:164 dai_get type 3 index 1 new sref 2 [34276109125.416668] ( 10.520833) c0 dai src/lib/dai.c:189 dai_put type 3 index 1 new sref 1 [34276109155.833336] ( 30.416666) c0 dai 8.35 src/audio/dai.c:889 dai_config() dai type = 3 index = 1 [34276109164.687500] ( 8.854167) c0 dai 8.35 src/audio/dai.c:921 dai_config(), channel = -1 [34276109357.031250] ( 192.343750) c0 dai src/lib/dai.c:164 dai_get type 3 index 2 new sref 2 [34276109367.552086] ( 10.520833) c0 dai src/lib/dai.c:189 dai_put type 3 index 2 new sref 1 [34276109401.562500] ( 34.010418) c0 dai 9.41 src/audio/dai.c:889 dai_config() dai type = 3 index = 2 [34276109410.364586] ( 8.802083) c0 dai 9.41 src/audio/dai.c:921 dai_config(), channel = -1 [34276109592.239586] ( 181.875000) c0 dai src/lib/dai.c:164 dai_get type 3 index 3 new sref 2 [34276109603.020836] ( 10.781250) c0 dai src/lib/dai.c:189 dai_put type 3 index 3 new sref 1 [34276109640.364586] ( 37.343750) c0 dai 10.47 src/audio/dai.c:889 dai_config() dai type = 3 index = 3 [34276109649.375000] ( 9.010417) c0 dai 10.47 src/audio/dai.c:921 dai_config(), channel = -1 [34276109949.739586] ( 300.364594) c0 dai src/lib/dai.c:164 dai_get type 2 index 0 new sref 2 [34276109958.958336] ( 9.218750) c0 dmic-dai 2.0 src/drivers/intel/dmic.c:1112 dmic_set_config() [34276109976.041668] ( 17.083334) c0 dmic-dai 2.0 src/drivers/intel/dmic.c:1147 dmic_set_config(): unmute_ramp_time_ms = 200 [34276110003.593750] ( 27.552084) c0 dmic-dai 2.0 src/drivers/intel/dmic.c:1194 dmic_set_config(), prm config->dmic.num_pdm_active = 2 [34276110011.510418] ( 7.916667) c0 dmic-dai 2.0 src/drivers/intel/dmic.c:1196 dmic_set_config(), prm pdmclk_min = 500000, pdmclk_max = 4800000 [34276110019.322918] ( 7.812500) c0 dmic-dai 2.0 src/drivers/intel/dmic.c:1198 dmic_set_config(), prm duty_min = 40, duty_max = 60 [34276110027.187500] ( 7.864583) c0 dmic-dai 2.0 src/drivers/intel/dmic.c:1200 dmic_set_config(), prm fifo_fs = 48000, fifo_bits = 32 [34276110073.020836] ( 45.833332) c0 dmic-dai 2.0 src/drivers/intel/dmic.c:1238 dmic_set_config(), cfg clkdiv = 16, mcic = 25 [34276110081.041668] ( 8.020833) c0 dmic-dai 2.0 src/drivers/intel/dmic.c:1240 dmic_set_config(), cfg mfir_a = 2, mfir_b = 0 [34276110088.958336] ( 7.916667) c0 dmic-dai 2.0 src/drivers/intel/dmic.c:1242 dmic_set_config(), cfg cic_shift = 3 [34276110096.406250] ( 7.447917) c0 dmic-dai 2.0 src/drivers/intel/dmic.c:1244 dmic_set_config(), cfg fir_a_shift = 0, cfg.fir_b_shift = 0 [34276110104.270836] ( 7.864583) c0 dmic-dai 2.0 src/drivers/intel/dmic.c:1246 dmic_set_config(), cfg fir_a_length = 101, fir_b_length = 0 [34276110112.291668] ( 8.020833) c0 dmic-dai 2.0 src/drivers/intel/dmic.c:836 configuring registers [34276110183.020836] ( 70.729164) c0 dai src/lib/dai.c:189 dai_put type 2 index 0 new sref 1 [34276110224.010418] ( 40.989582) c0 dai 11.51 src/audio/dai.c:889 dai_config() dai type = 2 index = 0 [34276110233.125000] ( 9.114583) c0 dai 11.51 src/audio/dai.c:917 dai_config(), config->dmic.fifo_bits = 32 config->dmic.num_pdm_active = 2 [34276110241.354168] ( 8.229167) c0 dw-dma src/drivers/dw/dma.c:190 dw_dma_channel_get(): dma 0 request channel 0 [34276110436.718750] ( 195.364578) c0 dai src/lib/dai.c:164 dai_get type 2 index 1 new sref 2 [34276110445.833336] ( 9.114583) c0 dmic-dai 2.1 src/drivers/intel/dmic.c:1112 dmic_set_config() [34276110461.510418] ( 15.677083) c0 dmic-dai 2.1 src/drivers/intel/dmic.c:1147 dmic_set_config(): unmute_ramp_time_ms = 400 [34276110475.625000] ( 14.114583) c0 dmic-dai 2.1 src/drivers/intel/dmic.c:1194 dmic_set_config(), prm config->dmic.num_pdm_active = 1 [34276110483.541668] ( 7.916667) c0 dmic-dai 2.1 src/drivers/intel/dmic.c:1196 dmic_set_config(), prm pdmclk_min = 500000, pdmclk_max = 4800000 [34276110491.302086] ( 7.760417) c0 dmic-dai 2.1 src/drivers/intel/dmic.c:1198 dmic_set_config(), prm duty_min = 40, duty_max = 60 [34276110499.270836] ( 7.968750) c0 dmic-dai 2.1 src/drivers/intel/dmic.c:1200 dmic_set_config(), prm fifo_fs = 16000, fifo_bits = 32 [34276110642.500000] ( 143.229172) c0 dmic-dai 2.1 src/drivers/intel/dmic.c:1238 dmic_set_config(), cfg clkdiv = 16, mcic = 25 [34276110650.572918] ( 8.072917) c0 dmic-dai 2.1 src/drivers/intel/dmic.c:1240 dmic_set_config(), cfg mfir_a = 2, mfir_b = 6 [34276110658.281250] ( 7.708333) c0 dmic-dai 2.1 src/drivers/intel/dmic.c:1242 dmic_set_config(), cfg cic_shift = 3 [34276110665.781250] ( 7.500000) c0 dmic-dai 2.1 src/drivers/intel/dmic.c:1244 dmic_set_config(), cfg fir_a_shift = 0, cfg.fir_b_shift = 1 [34276110673.697918] ( 7.916667) c0 dmic-dai 2.1 src/drivers/intel/dmic.c:1246 dmic_set_config(), cfg fir_a_length = 101, fir_b_length = 249 [34276110681.406250] ( 7.708333) c0 dmic-dai 2.1 src/drivers/intel/dmic.c:836 configuring registers [34276110880.885418] ( 199.479172) c0 dai src/lib/dai.c:189 dai_put type 2 index 1 new sref 1 [34276110925.625000] ( 44.739582) c0 dai 12.59 src/audio/dai.c:889 dai_config() dai type = 2 index = 1 [34276110935.000000] ( 9.375000) c0 dai 12.59 src/audio/dai.c:917 dai_config(), config->dmic.fifo_bits = 32 config->dmic.num_pdm_active = 1 [34276110942.812500] ( 7.812500) c0 dw-dma src/drivers/dw/dma.c:190 dw_dma_channel_get(): dma 1 request channel 0 [34276111385.364586] ( 442.552094) c0 pipe 13.66 src/audio/pipeline.c:246 pipeline complete, clock freq 400000000Hz [34276111395.677086] ( 10.312500) c0 memory src/lib/alloc.c:1063 heap: buffer status [34276111403.750000] ( 8.072917) c0 memory src/lib/alloc.c:1032 heap: 0xbe09f800 size 524288 blocks 1 caps 0x71 [34276111411.875000] ( 8.125000) c0 memory src/lib/alloc.c:1034 used 55040 free 469248 [34276111419.947918] ( 8.072917) c0 memory src/lib/alloc.c:1032 heap: 0xbe801800 size 59392 blocks 1 caps 0x69 [34276111427.916668] ( 7.968750) c0 memory src/lib/alloc.c:1034 used 0 free 59392 [34276111435.520836] ( 7.604167) c0 memory src/lib/alloc.c:1065 heap: runtime status [34276111443.229168] ( 7.708333) c0 memory src/lib/alloc.c:1032 heap: 0xbe090000 size 63488 blocks 7 caps 0x45 [34276111451.354168] ( 8.125000) c0 memory src/lib/alloc.c:1034 used 22272 free 41216 [34276111459.218750] ( 7.864583) c0 memory src/lib/alloc.c:1042 block 1 base 0xbe092000 size 128 [34276111467.239586] ( 8.020833) c0 memory src/lib/alloc.c:1045 count 64 free 30 [34276111475.104168] ( 7.864583) c0 memory src/lib/alloc.c:1042 block 2 base 0xbe094000 size 256 [34276111483.072918] ( 7.968750) c0 memory src/lib/alloc.c:1045 count 128 free 67 [34276111490.937500] ( 7.864583) c0 memory src/lib/alloc.c:1042 block 3 base 0xbe09c000 size 512 [34276111498.906250] ( 7.968750) c0 memory src/lib/alloc.c:1045 count 8 free 8 [34276111506.770836] ( 7.864583) c0 memory src/lib/alloc.c:1042 block 4 base 0xbe09d000 size 1024 [34276111514.739586] ( 7.968750) c0 memory src/lib/alloc.c:1045 count 4 free 4 [34276111522.656250] ( 7.916667) c0 memory src/lib/alloc.c:1042 block 5 base 0xbe09e000 size 2048 [34276111530.572918] ( 7.916667) c0 memory src/lib/alloc.c:1045 count 1 free 0 [34276111538.437500] ( 7.864583) c0 memory src/lib/alloc.c:1042 block 6 base 0xbe09e800 size 4096 [34276111546.354168] ( 7.916667) c0 memory src/lib/alloc.c:1045 count 1 free 1 [34276111554.010418] ( 7.656250) c0 memory src/lib/alloc.c:1068 heap: runtime shared status [34276111561.718750] ( 7.708333) c0 memory src/lib/alloc.c:1032 heap: 0x9e12d000 size 37888 blocks 5 caps 0x45 [34276111569.791668] ( 8.072917) c0 memory src/lib/alloc.c:1034 used 13888 free 24000 [34276111577.708336] ( 7.916667) c0 memory src/lib/alloc.c:1042 block 1 base 0x9e130000 size 128 [34276111585.677086] ( 7.968750) c0 memory src/lib/alloc.c:1045 count 64 free 37 [34276111593.593750] ( 7.916667) c0 memory src/lib/alloc.c:1042 block 2 base 0x9e132000 size 256 [34276111601.562500] ( 7.968750) c0 memory src/lib/alloc.c:1045 count 4 free 4 [34276111609.427086] ( 7.864583) c0 memory src/lib/alloc.c:1042 block 3 base 0x9e132400 size 512 [34276111617.395836] ( 7.968750) c0 memory src/lib/alloc.c:1045 count 16 free 11 [34276111625.260418] ( 7.864583) c0 memory src/lib/alloc.c:1042 block 4 base 0x9e134400 size 1024 [34276111634.114586] ( 8.854167) c0 memory src/lib/alloc.c:1045 count 8 free 8 [34276111844.739586] ( 210.625000) c0 pipe 12.60 src/audio/pipeline.c:246 pipeline complete, clock freq 400000000Hz [34276112072.031250] ( 227.291672) c0 pipe 11.52 src/audio/pipeline.c:246 pipeline complete, clock freq 400000000Hz [34276112253.958336] ( 181.927078) c0 pipe 10.48 src/audio/pipeline.c:246 pipeline complete, clock freq 400000000Hz [34276112470.885418] ( 216.927078) c0 pipe 9.42 src/audio/pipeline.c:246 pipeline complete, clock freq 400000000Hz [34276112637.656250] ( 166.770828) c0 pipe 8.36 src/audio/pipeline.c:246 pipeline complete, clock freq 400000000Hz [34276112930.989586] ( 293.333344) c0 pipe 7.30 src/audio/pipeline.c:246 pipeline complete, clock freq 400000000Hz [34276113080.885418] ( 149.895828) c0 pipe 4.24 src/audio/pipeline.c:246 pipeline complete, clock freq 400000000Hz [34276113379.583336] ( 298.697906) c0 pipe 3.18 src/audio/pipeline.c:246 pipeline complete, clock freq 400000000Hz [34276113516.979168] ( 137.395828) c0 pipe 2.11 src/audio/pipeline.c:246 pipeline complete, clock freq 400000000Hz [34276113824.427086] ( 307.447906) c0 pipe 1.5 src/audio/pipeline.c:246 pipeline complete, clock freq 400000000Hz [34276113980.833336] ( 156.406250) c0 selector 13.62 ..../selector/selector.c:322 selector_cmd() [34276113989.479168] ( 8.645833) c0 selector 13.62 ..../selector/selector.c:251 selector_ctrl_set_data(), SOF_CTRL_CMD_BINARY [34276114293.593750] ( 304.114594) c0 kd-test 13.61 ..../audio/detect_test.c:556 test_keyword_cmd() [34276114302.968750] ( 9.375000) c0 kd-test 13.61 ..../audio/detect_test.c:470 test_keyword_cmd_set_data(), SOF_CTRL_CMD_BINARY [34276114692.395836] ( 389.427094) c0 kd-test 13.61 ..../audio/detect_test.c:556 test_keyword_cmd() [34276114701.250000] ( 8.854167) c0 kd-test 13.61 ..../audio/detect_test.c:470 test_keyword_cmd_set_data(), SOF_CTRL_CMD_BINARY [34276114710.104168] ( 8.854167) c0 kd-test 13.61 ..../audio/detect_test.c:410 test_keyword_set_config(), blob size = 32 [34276115235.000000] ( 524.895813) c0 pga 12.54 ../audio/volume/volume.c:561 volume_ctrl_set_cmd(), channel = 0, value = 2072775 [34276115247.343750] ( 12.343750) c0 pga 12.54 ../audio/volume/volume.c:561 volume_ctrl_set_cmd(), channel = 1, value = 2072775 [34276115457.447918] ( 210.104172) c0 pga 10.44 ../audio/volume/volume.c:561 volume_ctrl_set_cmd(), channel = 0, value = 65536 [34276115466.770836] ( 9.322917) c0 pga 10.44 ../audio/volume/volume.c:561 volume_ctrl_set_cmd(), channel = 1, value = 65536 [34276115706.197918] ( 239.427078) c0 pga 9.38 ../audio/volume/volume.c:561 volume_ctrl_set_cmd(), channel = 0, value = 65536 [34276115716.250000] ( 10.052083) c0 pga 9.38 ../audio/volume/volume.c:561 volume_ctrl_set_cmd(), channel = 1, value = 65536 [34276115922.968750] ( 206.718750) c0 pga 8.32 ../audio/volume/volume.c:561 volume_ctrl_set_cmd(), channel = 0, value = 65536 [34276115932.447918] ( 9.479167) c0 pga 8.32 ../audio/volume/volume.c:561 volume_ctrl_set_cmd(), channel = 1, value = 65536 [34276116146.093750] ( 213.645828) c0 pga 7.26 ../audio/volume/volume.c:561 volume_ctrl_set_cmd(), channel = 0, value = 65536 [34276116155.468750] ( 9.375000) c0 pga 7.26 ../audio/volume/volume.c:561 volume_ctrl_set_cmd(), channel = 1, value = 65536 [34276116368.802086] ( 213.333328) c0 demux 4.20 src/audio/mux/mux.c:308 mux_cmd() cmd = 0x00000066 [34276116377.343750] ( 8.541667) c0 demux 4.20 src/audio/mux/mux.c:249 mux_ctrl_set_cmd(), cdata->cmd = 0x00000003 [34276116385.104168] ( 7.760417) c0 demux 4.20 src/audio/mux/mux.c:54 mux_set_values() [34276116648.281250] ( 263.177094) c0 smart_amp-test 3.13 ./audio/smart_amp_test.c:247 smart_amp_cmd(): cmd: 102 [34276116657.187500] ( 8.906250) c0 smart_amp-test 3.13 ./audio/smart_amp_test.c:229 smart_amp_ctrl_set_data(), SOF_CTRL_CMD_BINARY [34276116923.125000] ( 265.937500) c0 smart_amp-test 3.13 ./audio/smart_amp_test.c:247 smart_amp_cmd(): cmd: 102 [34276116931.822918] ( 8.697917) c0 smart_amp-test 3.13 ./audio/smart_amp_test.c:229 smart_amp_ctrl_set_data(), SOF_CTRL_CMD_BINARY [34276117157.760418] ( 225.937500) c0 smart_amp-test 3.13 ./audio/smart_amp_test.c:247 smart_amp_cmd(): cmd: 102 [34276117166.093750] ( 8.333333) c0 smart_amp-test 3.13 ./audio/smart_amp_test.c:229 smart_amp_ctrl_set_data(), SOF_CTRL_CMD_BINARY [34276117445.520836] ( 279.427094) c0 pga 2.7 ../audio/volume/volume.c:561 volume_ctrl_set_cmd(), channel = 0, value = 2072775 [34276117454.947918] ( 9.427083) c0 pga 2.7 ../audio/volume/volume.c:561 volume_ctrl_set_cmd(), channel = 1, value = 2072775 [34276117744.218750] ( 289.270844) c0 ll-schedule ./schedule/ll_schedule.c:202 perf ll_work peak plat 1484 cpu 15456 [34276117767.760418] ( 23.541666) c0 pga 1.1 ../audio/volume/volume.c:561 volume_ctrl_set_cmd(), channel = 0, value = 65536 [34276117777.916668] ( 10.156250) c0 pga 1.1 ../audio/volume/volume.c:561 volume_ctrl_set_cmd(), channel = 1, value = 65536 [34276117976.562500] ( 198.645828) c0 ipc src/ipc/handler.c:606 ipc: pm -> restore [34276118847.187500] ( 870.625000) c0 pipe 12.60 src/audio/pipeline.c:538 pipe params dir 1 frame_fmt 1 buffer_fmt 0 rate 16000 [34276118856.562500] ( 9.375000) c0 pipe 12.60 src/audio/pipeline.c:542 pipe params stream_tag 2 channels 2 sample_valid_bytes 3 sample_container_bytes 4 [34276119005.156250] ( 148.593750) c0 dai 12.59 src/audio/dai.c:411 dai_capture_params() src_dev = 1 stream_id = 0 src_width = 4 dest_width = 4 [34276119013.854168] ( 8.697917) c0 dai 12.59 src/audio/dai.c:417 dai_capture_params() fifo 0x10108 [34276119030.312500] ( 16.458334) c0 pipe 12.60 src/audio/pipeline.c:669 pipe prepare [34276119071.145836] ( 40.833332) c0 kpb 12.55 src/audio/kpb.c:435 kpb_prepare() [34276119080.572918] ( 9.427083) c0 kpb src/audio/kpb.c:219 kpb_allocate_history_buffer() [34276122535.156250] ( 3454.583252) c0 memory src/lib/alloc.c:275 ERROR 155 blocks needed for allocation but only 154 blocks are remaining [34276122643.437500] ( 108.281250) c0 kpb src/audio/kpb.c:246 kpb new memory block: 58880 [34276123407.500000] ( 764.062500) c0 kpb src/audio/kpb.c:246 kpb new memory block: 325120 [34276123414.947918] ( 7.447917) c0 kpb src/audio/kpb.c:295 kpb_allocate_history_buffer(): allocated 384000 bytes [34276123422.760418] ( 7.812500) c0 kpb src/audio/kpb.c:1520 kpb_reset_history_buffer() [34276123430.208336] ( 7.447917) c0 kpb src/audio/kpb.c:1415 kpb_clear_history_buffer() [34276128629.947918] ( 5199.739746) c0 pipe 13.66 src/audio/pipeline.c:538 pipe params dir 1 frame_fmt 1 buffer_fmt 0 rate 16000 [34276128639.947918] ( 10.000000) c0 pipe 13.66 src/audio/pipeline.c:542 pipe params stream_tag 0 channels 2 sample_valid_bytes 3 sample_container_bytes 0 [34276128669.010418] ( 29.062500) c0 selector 13.62 ..../selector/selector.c:225 selector_params() [34276128688.437500] ( 19.427084) c0 kpb 12.55 src/audio/kpb.c:401 ERROR kpb_params(): kpb has been already configured. [34276128701.718750] ( 13.281250) c0 pipe 13.66 src/audio/pipeline.c:669 pipe prepare [34276128724.375000] ( 22.656250) c0 kd-test 13.61 ..../audio/detect_test.c:639 test_keyword_prepare() [34276128774.635418] ( 50.260418) c0 selector 13.62 ..../selector/selector.c:435 selector_prepare() [34276128782.708336] ( 8.072917) c0 selector 13.62 ..../selector/selector.c:465 selector_prepare(): sourceb->schannels = 2 [34276128789.947918] ( 7.239583) c0 selector 13.62 ..../selector/selector.c:467 selector_prepare(): sinkb->channels = 1 [34276128815.260418] ( 25.312500) c0 kpb 12.55 src/audio/kpb.c:435 kpb_prepare() [34276128823.177086] ( 7.916667) c0 kpb 12.55 src/audio/component.c:180 comp_set_state(), state already set to 3 [34276128979.427086] ( 156.250000) c0 pipe 13.66 src/audio/pipeline.c:863 pipe trigger cmd 1 [34276128988.437500] ( 9.010417) c0 kd-test 13.61 ..../audio/detect_test.c:573 test_keyword_trigger() [34276128997.656250] ( 9.218750) c0 selector 13.62 ..../selector/selector.c:356 selector_trigger() [34276129009.010418] ( 11.354167) c0 ll-schedule ./schedule/ll_schedule.c:345 task add 0xbe090240 pipe-task [34276129017.187500] ( 8.177083) c0 ll-schedule ./schedule/ll_schedule.c:349 task params pri 1 flags 0 start 0 period 5000 [34276129028.072918] ( 10.885417) c0 ll-schedule ./schedule/ll_schedule.c:252 num_tasks 2 total_num_tasks 2 [34276129704.427086] ( 676.354187) c0 selector 13.62 src/audio/pipeline.c:206 perf comp_copy peak plat 43 cpu 466 [34276130693.802086] ( 989.375000) c0 pipe 12.60 src/audio/pipeline.c:863 pipe trigger cmd 1 [34276130768.177086] ( 74.375000) c0 kpb 12.55 src/audio/kpb.c:367 kpb_trigger() [34276130790.885418] ( 22.708334) c0 dmic-dai 2.1 src/drivers/intel/dmic.c:1293 dmic_start(), dmic->fifo_b [34276130800.104168] ( 9.218750) c0 dmic-dai 2.1 src/drivers/intel/dmic.c:1319 dmic_start(), mic_a = 1, mic_b = 1, fir_a = 1, fir_b = 1 [34276130810.416668] ( 10.312500) c0 dmic-dai 2.1 src/drivers/intel/dmic.c:1319 dmic_start(), mic_a = 0, mic_b = 0, fir_a = 0, fir_b = 0 [34276130820.781250] ( 10.364583) c0 ll-schedule ./schedule/ll_schedule.c:345 task add 0x9e130c08 dmic-work <59c87728-d8f9-42f6-b89d-5870a87b0e1e> [34276130829.114586] ( 8.333333) c0 ll-schedule ./schedule/ll_schedule.c:349 task params pri 4 flags 0 start 1000 period 1000 [34276130840.000000] ( 10.885417) c0 ll-schedule ./schedule/ll_schedule.c:252 num_tasks 3 total_num_tasks 3 [34276130850.052086] ( 10.052083) c0 dmic-dai 2.1 src/drivers/intel/dmic.c:1391 dmic_start(), done active_fifos = 1 [34276130868.177086] ( 18.125000) c0 ll-schedule ./schedule/ll_schedule.c:345 task add 0xbe090180 pipe-task [34276130876.510418] ( 8.333333) c0 ll-schedule ./schedule/ll_schedule.c:349 task params pri 0 flags 0 start 0 period 5000 [34276130886.666668] ( 10.156250) c0 ll-schedule ./schedule/ll_schedule.c:252 num_tasks 4 total_num_tasks 4 [34276131723.906250] ( 837.239563) c0 dai 12.59 src/audio/pipeline.c:206 perf comp_copy peak plat 373 cpu 3930 [34276131745.989586] ( 22.083334) c0 pga 12.54 src/audio/pipeline.c:206 perf comp_copy peak plat 224 cpu 2336 [34276131772.552086] ( 26.562500) c0 kpb 12.55 src/audio/pipeline.c:206 perf comp_copy peak plat 303 cpu 3180 [34276131787.083336] ( 14.531250) c0 ll-schedule ./schedule/ll_schedule.c:202 perf ll_work peak plat 1788 cpu 18583 [34276139709.270836] ( 7922.187500) c0 selector 13.62 src/audio/pipeline.c:206 perf comp_copy peak plat 143 cpu 1488 [34276139726.614586] ( 17.343750) c0 kd-test 13.61 src/audio/pipeline.c:206 perf comp_copy peak plat 122 cpu 1272 [34276141739.739586] ( 2013.125000) c0 dai 12.59 src/audio/pipeline.c:206 perf comp_copy peak plat 703 cpu 7340 [34276141810.520836] ( 70.781250) c0 pga 12.54 src/audio/pipeline.c:206 perf comp_copy peak plat 1155 cpu 12028 [34276141874.010418] ( 63.489582) c0 kpb 12.55 src/audio/pipeline.c:206 perf comp_copy peak plat 1021 cpu 10628 [34276141892.395836] ( 18.385416) c0 ll-schedule ./schedule/ll_schedule.c:202 perf ll_work peak plat 3822 cpu 39780 [34276149716.666668] ( 7824.270996) c0 selector 13.62 src/audio/pipeline.c:206 perf comp_copy peak plat 275 cpu 2864 [34276149747.291668] ( 30.625000) c0 kd-test 13.61 src/audio/pipeline.c:206 perf comp_copy peak plat 384 cpu 4000 [34276151740.885418] ( 1993.593750) c0 dai 12.59 src/audio/pipeline.c:206 perf comp_copy peak plat 728 cpu 7612 [34276151813.437500] ( 72.552086) c0 pga 12.54 src/audio/pipeline.c:206 perf comp_copy peak plat 1194 cpu 12452 [34276151879.322918] ( 65.885414) c0 kpb 12.55 src/audio/pipeline.c:206 perf comp_copy peak plat 1062 cpu 11056 [34276151897.187500] ( 17.864584) c0 ll-schedule ./schedule/ll_schedule.c:202 perf ll_work peak plat 3918 cpu 40812 [34276159717.135418] ( 7819.947754) c0 selector 13.62 src/audio/pipeline.c:206 perf comp_copy peak plat 284 cpu 2956 [34276159748.697918] ( 31.562500) c0 kd-test 13.61 src/audio/pipeline.c:206 perf comp_copy peak plat 399 cpu 4160 [34276161863.489586] ( 2114.791748) c0 kpb 12.55 src/audio/pipeline.c:206 perf comp_copy peak plat 1066 cpu 11104 [34276169717.135418] ( 7853.645996) c0 selector 13.62 src/audio/pipeline.c:206 perf comp_copy peak plat 285 cpu 2964 [34276169748.645836] ( 31.510416) c0 kd-test 13.61 src/audio/pipeline.c:206 perf comp_copy peak plat 400 cpu 4172 [34276221863.020836] ( 52114.375000) c0 kpb 12.55 src/audio/pipeline.c:206 perf comp_copy peak plat 1066 cpu 11112 [34276239740.781250] ( 17877.759766) c0 kd-test 13.61 src/audio/pipeline.c:206 perf comp_copy peak plat 403 cpu 4196 [34276261864.687500] ( 22123.906250) c0 kpb 12.55 src/audio/pipeline.c:206 perf comp_copy peak plat 1077 cpu 11208 [34276269716.718750] ( 7852.031250) c0 selector 13.62 src/audio/pipeline.c:206 perf comp_copy peak plat 285 cpu 2968 [34276281805.937500] ( 12089.218750) c0 pga 12.54 src/audio/pipeline.c:206 perf comp_copy peak plat 1196 cpu 12456 [34276559717.343750] ( 277911.406250) c0 selector 13.62 src/audio/pipeline.c:206 perf comp_copy peak plat 286 cpu 2984 [34276579717.500000] ( 20000.156250) c0 selector 13.62 src/audio/pipeline.c:206 perf comp_copy peak plat 289 cpu 3008 [34276799741.562500] ( 220024.062500) c0 kd-test 13.61 src/audio/pipeline.c:206 perf comp_copy peak plat 407 cpu 4248 [34276869717.447918] ( 69975.882812) c0 selector 13.62 src/audio/pipeline.c:206 perf comp_copy peak plat 291 cpu 3028 [34276889717.552086] ( 20000.103516) c0 selector 13.62 src/audio/pipeline.c:206 perf comp_copy peak plat 291 cpu 3032 [34276933706.562500] ( 43989.011719) c0 ll-schedule ./schedule/ll_schedule.c:124 task complete 0x9e130c08 dmic-work <59c87728-d8f9-42f6-b89d-5870a87b0e1e> [34276933715.468750] ( 8.906250) c0 ll-schedule ./schedule/ll_schedule.c:127 num_tasks 3 total_num_tasks 3 [34277011740.260418] ( 78024.789062) c0 dai 12.59 src/audio/pipeline.c:206 perf comp_copy peak plat 730 cpu 7636 [34277051849.479168] ( 40109.218750) c0 kpb 12.55 src/audio/pipeline.c:206 perf comp_copy peak plat 1085 cpu 11300 [34278931740.468750] ( 1879891.000000) c0 dai 12.59 src/audio/pipeline.c:206 perf comp_copy peak plat 735 cpu 7684 [34280459733.177086] ( 1527992.750000) c0 kd-test 13.61 ..../audio/detect_test.c:113 notify_host() [34280459748.958336] ( 15.781250) c0 kd-test 13.61 ..../audio/detect_test.c:122 notify_kpb(), preamble: 33600 [34280459757.395836] ( 8.437500) c0 kpb 12.55 src/audio/kpb.c:926 kpb_event_handler(): received event with ID: 2 [34280459766.510418] ( 9.114583) c0 kpb 12.55 src/audio/kpb.c:1021 kpb_init_draining(): requested draining of 2000 [ms] from history buffer [34280459776.406250] ( 9.895833) c0 kpb 12.55 src/audio/kpb.c:1118 kpb_init_draining: unlimited draining speed selected. [34280459783.593750] ( 7.187500) c0 kpb 12.55 src/audio/kpb.c:1121 kpb_init_draining(), schedule draining task [34280459799.531250] ( 15.937500) c0 kd-test 13.61 src/audio/pipeline.c:206 perf comp_copy peak plat 1566 cpu 16312 [34280459830.000000] ( 30.468750) c0 kpb src/audio/kpb.c:1182 kpb_draining_task(), start. [34280459903.437500] ( 73.437500) c0 host 12.53 src/audio/kpb.c:206 perf comp_copy peak plat 521 cpu 5432 [34280461719.791668] ( 1816.354126) c0 dai 12.59 src/audio/pipeline.c:206 perf comp_copy peak plat 788 cpu 8246 [34280461817.760418] ( 97.968750) c0 host 12.53 src/audio/host.c:376 no bytes to copy, 0 avail in buffer, 1280 free in DMA [34280465704.739586] ( 3886.979248) c0 host 12.53 src/audio/kpb.c:206 perf comp_copy peak plat 1052 cpu 10952 [34280467699.843750] ( 1995.104126) c0 host 12.53 src/audio/kpb.c:206 perf comp_copy peak plat 1080 cpu 11240 [34280469695.729168] ( 1995.885376) c0 host 12.53 src/audio/kpb.c:206 perf comp_copy peak plat 1095 cpu 11412 [34280471346.718750] ( 1650.989624) c0 kpb src/audio/kpb.c:1269 kpb: update drain_req by 640 [34280471392.135418] ( 45.416668) c0 kpb src/audio/kpb.c:1269 kpb: update drain_req by 0 [34280471403.020836] ( 10.885417) c0 kpb src/audio/kpb.c:1296 KPB: kpb_draining_task(), done. 256640 drained in 5 ms