TIMESTAMP DELTA C# COMPONENT LOCATION CONTENT [ 7434473.229167] ( 7434473.000000) c0 dma-trace src/trace/dma-trace.c:339 ERROR FW ABI 0x3012001 DBG ABI 0x5003000 tag v1.7-rc1-50-gb0facccfc8dd src hash 0xc59fdd96 (ldc hash 0xc59fdd96) [ 7434719.479167] ( 246.250000) c0 ll-schedule ./schedule/ll_schedule.c:348 task add 0x9e136480 dma-trace-task <2b972272-c5b1-4b7e-926f-0fc5cb4c4690> [ 7434736.927083] ( 17.447916) c0 ll-schedule ./schedule/ll_schedule.c:352 task params pri 4 flags 0 start 500000 period 500000 [ 7434757.031250] ( 20.104166) c0 ll-schedule ./schedule/ll_schedule.c:252 num_tasks 2 total_num_tasks 2 [ 7435044.687500] ( 287.656250) c0 sa src/lib/agent.c:79 WARN validate(), ll drift detected, delta = 41748 [ 8215564.479167] ( 780519.812500) c0 component src/audio/component.c:130 comp new host <8b9d100c-6d78-418f-90a3-e0e805d0852b> type 1 id 1.0 [ 8215608.489583] ( 44.010418) c0 hda-dma ..../intel/hda/hda-dma.c:794 hda-dmac :5 -> probe [ 8215707.187500] ( 98.697914) c0 dma src/lib/dma.c:127 dma_get() ID 5 sref = 1 busy channels 0 [ 8216169.218750] ( 462.031250) c0 component src/audio/component.c:130 comp new pga type 5 id 1.1 [ 8216210.625000] ( 41.406250) c0 pga 1.1 ../audio/volume/volume.c:379 vol->initial_ramp = 20, vol->ramp = 2, vol->min_value = 0, vol->max_value = 65536 [ 8216654.062500] ( 443.437500) c0 component src/audio/component.c:130 comp new demux type 18 id 1.2 [ 8216672.031250] ( 17.968750) c0 mux src/audio/mux/mux.c:168 mux_new() [ 8216726.302083] ( 54.270832) c0 demux 1.2 src/audio/mux/mux.c:100 mux_set_values() [ 8217105.885417] ( 379.583344) c0 buffer src/audio/buffer.c:79 buffer new size 0x300 id 1.3 flags 0x0 [ 8217562.552083] ( 456.666656) c0 buffer src/audio/buffer.c:79 buffer new size 0x300 id 1.4 flags 0x0 [ 8217989.427083] ( 426.875000) c0 buffer src/audio/buffer.c:79 buffer new size 0x300 id 1.5 flags 0x0 [ 8218422.708333] ( 433.281250) c0 component src/audio/component.c:130 comp new dai type 2 id 1.6 [ 8218485.052083] ( 62.343750) c0 power ../cavs/lib/pm_runtime.c:183 en_ssp_power index 1 [ 8218503.541667] ( 18.489584) c0 power ../cavs/lib/pm_runtime.c:192 en_ssp_power I2SLCTL 00000202 [ 8218523.229167] ( 19.687500) c0 dai src/lib/dai.c:165 dai_get type 1 index 1 new sref 1 [ 8218628.385417] ( 105.156250) c0 dma src/lib/dma.c:127 dma_get() ID 0 sref = 1 busy channels 0 [ 8219064.583333] ( 436.197906) c0 pipe src/audio/pipeline.c:78 pipeline new pipe_id 1 period 1000 priority 0 [ 8219521.822917] ( 457.239594) c0 component src/audio/component.c:130 comp new dai type 2 id 1.8 [ 8219562.916667] ( 41.093750) c0 dai src/lib/dai.c:165 dai_get type 1 index 1 new sref 2 [ 8219666.458333] ( 103.541664) c0 dma src/lib/dma.c:127 dma_get() ID 1 sref = 1 busy channels 0 [ 8220138.958333] ( 472.500000) c0 component src/audio/component.c:130 comp new host <8b9d100c-6d78-418f-90a3-e0e805d0852b> type 1 id 2.9 [ 8220182.187500] ( 43.229168) c0 dma src/lib/dma.c:127 dma_get() ID 5 sref = 2 busy channels 0 [ 8220689.322917] ( 507.135406) c0 component src/audio/component.c:130 comp new pga type 5 id 2.10 [ 8220729.739583] ( 40.416668) c0 pga 2.10 ../audio/volume/volume.c:379 vol->initial_ramp = 20, vol->ramp = 2, vol->min_value = 0, vol->max_value = 65536 [ 8221074.114583] ( 344.375000) c0 buffer src/audio/buffer.c:79 buffer new size 0x300 id 2.11 flags 0x0 [ 8221776.302083] ( 702.187500) c0 buffer src/audio/buffer.c:79 buffer new size 0x300 id 2.12 flags 0x0 [ 8222270.104167] ( 493.802094) c0 component src/audio/component.c:130 comp new dai type 2 id 2.13 [ 8222331.718750] ( 61.614582) c0 power ../cavs/lib/pm_runtime.c:183 en_ssp_power index 0 [ 8222350.156250] ( 18.437500) c0 power ../cavs/lib/pm_runtime.c:192 en_ssp_power I2SLCTL 00000303 [ 8222369.322917] ( 19.166666) c0 dai src/lib/dai.c:165 dai_get type 1 index 0 new sref 1 [ 8222390.468750] ( 21.145834) c0 dma src/lib/dma.c:127 dma_get() ID 0 sref = 2 busy channels 0 [ 8222829.583333] ( 439.114594) c0 pipe src/audio/pipeline.c:78 pipeline new pipe_id 2 period 1000 priority 0 [ 8223681.510417] ( 851.927063) c0 component src/audio/component.c:130 comp new host <8b9d100c-6d78-418f-90a3-e0e805d0852b> type 1 id 3.15 [ 8223724.687500] ( 43.177082) c0 dma src/lib/dma.c:127 dma_get() ID 4 sref = 2 busy channels 1 [ 8224169.791667] ( 445.104156) c0 component src/audio/component.c:130 comp new pga type 5 id 3.16 [ 8224210.468750] ( 40.677082) c0 pga 3.16 ../audio/volume/volume.c:379 vol->initial_ramp = 250, vol->ramp = 0, vol->min_value = 0, vol->max_value = 2072775 [ 8224586.354167] ( 375.885406) c0 buffer src/audio/buffer.c:79 buffer new size 0x300 id 3.17 flags 0x0 [ 8225007.291667] ( 420.937500) c0 sa src/lib/agent.c:79 WARN validate(), ll drift detected, delta = 41201 [ 8225058.958333] ( 51.666668) c0 buffer src/audio/buffer.c:79 buffer new size 0x300 id 3.18 flags 0x0 [ 8225692.083333] ( 633.125000) c0 component src/audio/component.c:130 comp new dai type 2 id 3.19 [ 8225732.812500] ( 40.729168) c0 dai src/lib/dai.c:165 dai_get type 1 index 0 new sref 2 [ 8225753.593750] ( 20.781250) c0 dma src/lib/dma.c:127 dma_get() ID 1 sref = 2 busy channels 0 [ 8226190.416667] ( 436.822906) c0 pipe src/audio/pipeline.c:78 pipeline new pipe_id 3 period 1000 priority 0 [ 8226672.395833] ( 481.979156) c0 component src/audio/component.c:130 comp new host <8b9d100c-6d78-418f-90a3-e0e805d0852b> type 1 id 5.21 [ 8226715.729167] ( 43.333332) c0 dma src/lib/dma.c:127 dma_get() ID 5 sref = 3 busy channels 0 [ 8227131.718750] ( 415.989594) c0 component src/audio/component.c:130 comp new pga type 5 id 5.22 [ 8227172.500000] ( 40.781250) c0 pga 5.22 ../audio/volume/volume.c:379 vol->initial_ramp = 20, vol->ramp = 2, vol->min_value = 0, vol->max_value = 65536 [ 8227762.291667] ( 589.791687) c0 buffer src/audio/buffer.c:79 buffer new size 0x300 id 5.23 flags 0x0 [ 8228187.812500] ( 425.520844) c0 buffer src/audio/buffer.c:79 buffer new size 0x300 id 5.24 flags 0x0 [ 8228620.364583] ( 432.552094) c0 component src/audio/component.c:130 comp new dai type 2 id 5.25 [ 8228661.510417] ( 41.145832) c0 hda-dai 3.0 /drivers/intel/hda/hda.c:65 hda_probe() [ 8228684.947917] ( 23.437500) c0 dai src/lib/dai.c:165 dai_get type 3 index 0 new sref 1 [ 8228706.718750] ( 21.770834) c0 hda-dma ..../intel/hda/hda-dma.c:794 hda-dmac :7 -> probe [ 8228791.041667] ( 84.322914) c0 dma src/lib/dma.c:127 dma_get() ID 7 sref = 1 busy channels 0 [ 8228962.968750] ( 171.927078) c0 pipe src/audio/pipeline.c:78 pipeline new pipe_id 5 period 1000 priority 0 [ 8229783.645833] ( 820.677063) c0 component src/audio/component.c:130 comp new host <8b9d100c-6d78-418f-90a3-e0e805d0852b> type 1 id 6.27 [ 8229826.562500] ( 42.916668) c0 dma src/lib/dma.c:127 dma_get() ID 5 sref = 4 busy channels 0 [ 8230276.822917] ( 450.260406) c0 component src/audio/component.c:130 comp new pga type 5 id 6.28 [ 8230317.656250] ( 40.833332) c0 pga 6.28 ../audio/volume/volume.c:379 vol->initial_ramp = 20, vol->ramp = 2, vol->min_value = 0, vol->max_value = 65536 [ 8230692.656250] ( 375.000000) c0 buffer src/audio/buffer.c:79 buffer new size 0x300 id 6.29 flags 0x0 [ 8231050.052083] ( 357.395844) c0 sa src/lib/agent.c:79 WARN validate(), ll drift detected, delta = 41238 [ 8231105.364583] ( 55.312500) c0 buffer src/audio/buffer.c:79 buffer new size 0x300 id 6.30 flags 0x0 [ 8231554.531250] ( 449.166656) c0 component src/audio/component.c:130 comp new dai type 2 id 6.31 [ 8231595.781250] ( 41.250000) c0 hda-dai 3.1 /drivers/intel/hda/hda.c:65 hda_probe() [ 8231618.854167] ( 23.072916) c0 dai src/lib/dai.c:165 dai_get type 3 index 1 new sref 1 [ 8231640.937500] ( 22.083334) c0 dma src/lib/dma.c:127 dma_get() ID 7 sref = 2 busy channels 0 [ 8232061.354167] ( 420.416656) c0 pipe src/audio/pipeline.c:78 pipeline new pipe_id 6 period 1000 priority 0 [ 8232535.260417] ( 473.906250) c0 component src/audio/component.c:130 comp new host <8b9d100c-6d78-418f-90a3-e0e805d0852b> type 1 id 7.33 [ 8232578.489583] ( 43.229168) c0 dma src/lib/dma.c:127 dma_get() ID 5 sref = 5 busy channels 0 [ 8233054.895833] ( 476.406250) c0 component src/audio/component.c:130 comp new pga type 5 id 7.34 [ 8233095.520833] ( 40.625000) c0 pga 7.34 ../audio/volume/volume.c:379 vol->initial_ramp = 20, vol->ramp = 2, vol->min_value = 0, vol->max_value = 65536 [ 8233991.354167] ( 895.833313) c0 buffer src/audio/buffer.c:79 buffer new size 0x300 id 7.35 flags 0x0 [ 8234417.031250] ( 425.677094) c0 buffer src/audio/buffer.c:79 buffer new size 0x300 id 7.36 flags 0x0 [ 8234940.364583] ( 523.333313) c0 component src/audio/component.c:130 comp new dai type 2 id 7.37 [ 8234981.562500] ( 41.197918) c0 hda-dai 3.2 /drivers/intel/hda/hda.c:65 hda_probe() [ 8235004.739583] ( 23.177084) c0 dai src/lib/dai.c:165 dai_get type 3 index 2 new sref 1 [ 8235026.666667] ( 21.927084) c0 dma src/lib/dma.c:127 dma_get() ID 7 sref = 3 busy channels 0 [ 8235668.854167] ( 642.187500) c0 pipe src/audio/pipeline.c:78 pipeline new pipe_id 7 period 1000 priority 0 [ 8236137.552083] ( 468.697906) c0 component src/audio/component.c:130 comp new host <8b9d100c-6d78-418f-90a3-e0e805d0852b> type 1 id 8.39 [ 8236181.145833] ( 43.593750) c0 dma src/lib/dma.c:127 dma_get() ID 5 sref = 6 busy channels 0 [ 8236688.697917] ( 507.552094) c0 component src/audio/component.c:130 comp new pga type 5 id 8.40 [ 8236729.479167] ( 40.781250) c0 pga 8.40 ../audio/volume/volume.c:379 vol->initial_ramp = 20, vol->ramp = 2, vol->min_value = 0, vol->max_value = 65536 [ 8237032.708333] ( 303.229156) c0 sa src/lib/agent.c:79 WARN validate(), ll drift detected, delta = 41012 [ 8237091.197917] ( 58.489582) c0 buffer src/audio/buffer.c:79 buffer new size 0x300 id 8.41 flags 0x0 [ 8238143.489583] ( 1052.291626) c0 buffer src/audio/buffer.c:79 buffer new size 0x300 id 8.42 flags 0x0 [ 8238575.052083] ( 431.562500) c0 component src/audio/component.c:130 comp new dai type 2 id 8.43 [ 8238616.770833] ( 41.718750) c0 hda-dai 3.3 /drivers/intel/hda/hda.c:65 hda_probe() [ 8238639.843750] ( 23.072916) c0 dai src/lib/dai.c:165 dai_get type 3 index 3 new sref 1 [ 8238661.614583] ( 21.770834) c0 dma src/lib/dma.c:127 dma_get() ID 7 sref = 4 busy channels 0 [ 8239121.250000] ( 459.635406) c0 pipe src/audio/pipeline.c:78 pipeline new pipe_id 8 period 1000 priority 0 [ 8239741.614583] ( 620.364563) c0 component src/audio/component.c:130 comp new host <8b9d100c-6d78-418f-90a3-e0e805d0852b> type 1 id 9.45 [ 8239785.208333] ( 43.593750) c0 dma src/lib/dma.c:127 dma_get() ID 4 sref = 3 busy channels 1 [ 8240219.427083] ( 434.218750) c0 buffer src/audio/buffer.c:79 buffer new size 0x300 id 9.46 flags 0x0 [ 8240739.218750] ( 519.791687) c0 pipe src/audio/pipeline.c:78 pipeline new pipe_id 9 period 1000 priority 1 [ 8241115.260417] ( 376.041656) c0 component src/audio/component.c:130 comp new host <8b9d100c-6d78-418f-90a3-e0e805d0852b> type 1 id 10.49 [ 8241158.333333] ( 43.072918) c0 dma src/lib/dma.c:127 dma_get() ID 4 sref = 4 busy channels 1 [ 8241929.947917] ( 771.614563) c0 buffer src/audio/buffer.c:79 buffer new size 0x600 id 10.50 flags 0x0 [ 8242360.520833] ( 430.572906) c0 component src/audio/component.c:130 comp new dai type 2 id 10.51 [ 8242402.343750] ( 41.822918) c0 dmic-dai 2.0 src/drivers/intel/dmic.c:1551 dmic_probe() [ 8242460.364583] ( 58.020832) c0 dai src/lib/dai.c:165 dai_get type 2 index 0 new sref 1 [ 8242482.656250] ( 22.291666) c0 dma src/lib/dma.c:127 dma_get() ID 0 sref = 3 busy channels 0 [ 8243675.260417] ( 1192.604126) c0 pipe src/audio/pipeline.c:78 pipeline new pipe_id 10 period 1000 priority 0 [ 8244138.489583] ( 463.229156) c0 component src/audio/component.c:130 comp new host <8b9d100c-6d78-418f-90a3-e0e805d0852b> type 1 id 11.53 [ 8244181.927083] ( 43.437500) c0 dma src/lib/dma.c:127 dma_get() ID 4 sref = 5 busy channels 1 [ 8244627.135417] ( 445.208344) c0 component src/audio/component.c:130 comp new pga type 5 id 11.54 [ 8244668.020833] ( 40.885418) c0 pga 11.54 ../audio/volume/volume.c:379 vol->initial_ramp = 250, vol->ramp = 0, vol->min_value = 0, vol->max_value = 2072775 [ 8245078.750000] ( 410.729156) c0 component src/audio/component.c:130 comp new kpb type 16 id 11.55 [ 8245096.822917] ( 18.072916) c0 kpb src/audio/kpb.c:135 kpb_new() [ 8245594.531250] ( 497.708344) c0 buffer src/audio/buffer.c:79 buffer new size 0x780 id 11.56 flags 0x0 [ 8246019.166667] ( 424.635406) c0 buffer src/audio/buffer.c:79 buffer new size 0x500 id 11.57 flags 0x0 [ 8246446.302083] ( 427.135406) c0 buffer src/audio/buffer.c:79 buffer new size 0x500 id 11.58 flags 0x0 [ 8246970.312500] ( 524.010437) c0 component src/audio/component.c:130 comp new dai type 2 id 11.59 [ 8247011.666667] ( 41.354168) c0 dmic-dai 2.1 src/drivers/intel/dmic.c:1551 dmic_probe() [ 8247065.364583] ( 53.697918) c0 dai src/lib/dai.c:165 dai_get type 2 index 1 new sref 1 [ 8247086.770833] ( 21.406250) c0 dma src/lib/dma.c:127 dma_get() ID 1 sref = 3 busy channels 0 [ 8247530.260417] ( 443.489594) c0 pipe src/audio/pipeline.c:78 pipeline new pipe_id 11 period 5000 priority 0 [ 8248097.708333] ( 567.447937) c0 component src/audio/component.c:130 comp new kd-test type 15 id 12.61 [ 8248114.739583] ( 17.031250) c0 kd-test ..../audio/detect_test.c:256 test_keyword_new() [ 8248651.562500] ( 536.822937) c0 component src/audio/component.c:130 comp new selector <55a88ed5-3d18-46ca-88f1-0ee6eae9930f> type 17 id 12.62 [ 8248669.687500] ( 18.125000) c0 selector ..../selector/selector.c:61 selector_new() [ 8249057.187500] ( 387.500000) c0 sa src/lib/agent.c:79 WARN validate(), ll drift detected, delta = 41029 [ 8249122.031250] ( 64.843750) c0 buffer src/audio/buffer.c:79 buffer new size 0x500 id 12.63 flags 0x0 [ 8249623.437500] ( 501.406250) c0 buffer src/audio/buffer.c:79 buffer new size 0x500 id 12.64 flags 0x0 [ 8250093.072917] ( 469.635406) c0 pipe src/audio/pipeline.c:78 pipeline new pipe_id 12 period 5000 priority 1 [ 8250735.052083] ( 641.979187) c0 kpb 11.55 src/audio/pipeline.c:125 connect buffer 63 as sink [ 8251111.354167] ( 376.302094) c0 demux 1.2 src/audio/pipeline.c:125 connect buffer 46 as sink [ 8251495.052083] ( 383.697906) c0 host 1.0 src/audio/pipeline.c:125 connect buffer 3 as sink [ 8251861.041667] ( 365.989594) c0 pga 1.1 src/audio/pipeline.c:127 connect buffer 3 as source [ 8252227.500000] ( 366.458344) c0 pga 1.1 src/audio/pipeline.c:125 connect buffer 4 as sink [ 8252594.114583] ( 366.614594) c0 demux 1.2 src/audio/pipeline.c:127 connect buffer 4 as source [ 8253055.416667] ( 461.302094) c0 demux 1.2 src/audio/pipeline.c:125 connect buffer 5 as sink [ 8253389.687500] ( 334.270844) c0 dai 1.6 src/audio/pipeline.c:127 connect buffer 5 as source [ 8253757.604167] ( 367.916656) c0 host 2.9 src/audio/pipeline.c:125 connect buffer 11 as sink [ 8254063.281250] ( 305.677094) c0 pga 2.10 src/audio/pipeline.c:127 connect buffer 11 as source [ 8254429.635417] ( 366.354156) c0 pga 2.10 src/audio/pipeline.c:125 connect buffer 12 as sink [ 8254735.729167] ( 306.093750) c0 dai 2.13 src/audio/pipeline.c:127 connect buffer 12 as source [ 8255072.083333] ( 336.354156) c0 host 3.15 src/audio/pipeline.c:127 connect buffer 17 as source [ 8255470.572917] ( 398.489594) c0 pga 3.16 src/audio/pipeline.c:125 connect buffer 17 as sink [ 8255898.125000] ( 427.552094) c0 pga 3.16 src/audio/pipeline.c:127 connect buffer 18 as source [ 8257087.291667] ( 1189.166626) c0 dai 3.19 src/audio/pipeline.c:125 connect buffer 18 as sink [ 8257488.125000] ( 400.833344) c0 host 5.21 src/audio/pipeline.c:125 connect buffer 23 as sink [ 8257854.531250] ( 366.406250) c0 pga 5.22 src/audio/pipeline.c:127 connect buffer 23 as source [ 8258221.093750] ( 366.562500) c0 pga 5.22 src/audio/pipeline.c:125 connect buffer 24 as sink [ 8258587.916667] ( 366.822906) c0 dai 5.25 src/audio/pipeline.c:127 connect buffer 24 as source [ 8258984.947917] ( 397.031250) c0 host 6.27 src/audio/pipeline.c:125 connect buffer 29 as sink [ 8259322.968750] ( 338.020844) c0 pga 6.28 src/audio/pipeline.c:127 connect buffer 29 as source [ 8259689.114583] ( 366.145844) c0 pga 6.28 src/audio/pipeline.c:125 connect buffer 30 as sink [ 8260056.406250] ( 367.291656) c0 dai 6.31 src/audio/pipeline.c:127 connect buffer 30 as source [ 8260424.375000] ( 367.968750) c0 host 7.33 src/audio/pipeline.c:125 connect buffer 35 as sink [ 8260790.989583] ( 366.614594) c0 pga 7.34 src/audio/pipeline.c:127 connect buffer 35 as source [ 8261077.135417] ( 286.145844) c0 sa src/lib/agent.c:79 WARN validate(), ll drift detected, delta = 41013 [ 8261141.822917] ( 64.687500) c0 pga 7.34 src/audio/pipeline.c:125 connect buffer 36 as sink [ 8261648.802083] ( 506.979156) c0 dai 7.37 src/audio/pipeline.c:127 connect buffer 36 as source [ 8262014.895833] ( 366.093750) c0 host 8.39 src/audio/pipeline.c:125 connect buffer 41 as sink [ 8262380.937500] ( 366.041656) c0 pga 8.40 src/audio/pipeline.c:127 connect buffer 41 as source [ 8262747.447917] ( 366.510406) c0 pga 8.40 src/audio/pipeline.c:125 connect buffer 42 as sink [ 8263082.552083] ( 335.104156) c0 dai 8.43 src/audio/pipeline.c:127 connect buffer 42 as source [ 8263483.229167] ( 400.677094) c0 host 9.45 src/audio/pipeline.c:127 connect buffer 46 as source [ 8263851.041667] ( 367.812500) c0 host 10.49 src/audio/pipeline.c:127 connect buffer 50 as source [ 8264278.593750] ( 427.552094) c0 dai 10.51 src/audio/pipeline.c:125 connect buffer 50 as sink [ 8264647.916667] ( 369.322906) c0 host 11.53 src/audio/pipeline.c:127 connect buffer 58 as source [ 8265043.177083] ( 395.260406) c0 kpb 11.55 src/audio/pipeline.c:125 connect buffer 58 as sink [ 8265441.510417] ( 398.333344) c0 kpb 11.55 src/audio/pipeline.c:127 connect buffer 57 as source [ 8265807.187500] ( 365.677094) c0 pga 11.54 src/audio/pipeline.c:125 connect buffer 57 as sink [ 8266173.177083] ( 365.989594) c0 pga 11.54 src/audio/pipeline.c:127 connect buffer 56 as source [ 8266540.625000] ( 367.447906) c0 dai 11.59 src/audio/pipeline.c:125 connect buffer 56 as sink [ 8267115.520833] ( 574.895813) c0 sa src/lib/agent.c:79 WARN validate(), ll drift detected, delta = 40939 [ 8267521.145833] ( 405.625000) c0 kd-test 12.61 src/audio/pipeline.c:127 connect buffer 64 as source [ 8267886.875000] ( 365.729156) c0 selector 12.62 src/audio/pipeline.c:125 connect buffer 64 as sink [ 8268896.822917] ( 1009.947937) c0 selector 12.62 src/audio/pipeline.c:127 connect buffer 63 as source [ 8270179.479167] ( 1282.656250) c0 dai src/lib/dai.c:165 dai_get type 2 index 0 new sref 2 [ 8270198.020833] ( 18.541666) c0 dmic-dai 2.0 src/drivers/intel/dmic.c:1094 dmic_set_config() [ 8270224.427083] ( 26.406250) c0 dmic-dai 2.0 src/drivers/intel/dmic.c:1134 dmic_set_config(): unmute_ramp_time_ms = 200 [ 8270263.489583] ( 39.062500) c0 dmic-dai 2.0 src/drivers/intel/dmic.c:1182 dmic_set_config(), prm config->dmic.num_pdm_active = 2 [ 8270281.614583] ( 18.125000) c0 dmic-dai 2.0 src/drivers/intel/dmic.c:1184 dmic_set_config(), prm pdmclk_min = 500000, pdmclk_max = 4800000 [ 8270298.645833] ( 17.031250) c0 dmic-dai 2.0 src/drivers/intel/dmic.c:1186 dmic_set_config(), prm duty_min = 40, duty_max = 60 [ 8270317.031250] ( 18.385416) c0 dmic-dai 2.0 src/drivers/intel/dmic.c:1188 dmic_set_config(), prm fifo_fs = 48000, fifo_bits = 32 [ 8270375.833333] ( 58.802082) c0 dmic-dai 2.0 src/drivers/intel/dmic.c:1230 dmic_set_config(), cfg clkdiv = 16, mcic = 25 [ 8270394.114583] ( 18.281250) c0 dmic-dai 2.0 src/drivers/intel/dmic.c:1232 dmic_set_config(), cfg mfir_a = 2, mfir_b = 0 [ 8270412.187500] ( 18.072916) c0 dmic-dai 2.0 src/drivers/intel/dmic.c:1234 dmic_set_config(), cfg cic_shift = 3 [ 8270429.062500] ( 16.875000) c0 dmic-dai 2.0 src/drivers/intel/dmic.c:1236 dmic_set_config(), cfg fir_a_shift = 0, cfg.fir_b_shift = 0 [ 8270445.677083] ( 16.614584) c0 dmic-dai 2.0 src/drivers/intel/dmic.c:1238 dmic_set_config(), cfg fir_a_length = 101, fir_b_length = 0 [ 8270464.427083] ( 18.750000) c0 dmic-dai 2.0 src/drivers/intel/dmic.c:818 configuring registers [ 8270551.770833] ( 87.343750) c0 dai src/lib/dai.c:191 dai_put type 2 index 0 new sref 1 [ 8270602.864583] ( 51.093750) c0 dai 10.51 src/audio/dai.c:891 dai_config() dai type = 2 index = 0 [ 8270621.666667] ( 18.802084) c0 dai 10.51 src/audio/dai.c:919 dai_config(), config->dmic.fifo_bits = 32 config->dmic.num_pdm_active = 2 [ 8270640.312500] ( 18.645834) c0 dw-dma src/drivers/dw/dma.c:191 dw_dma_channel_get(): dma 0 request channel 0 [ 8271093.750000] ( 453.437500) c0 sa src/lib/agent.c:79 WARN validate(), ll drift detected, delta = 42824 [ 8271147.812500] ( 54.062500) c0 dai src/lib/dai.c:165 dai_get type 2 index 1 new sref 2 [ 8271165.937500] ( 18.125000) c0 dmic-dai 2.1 src/drivers/intel/dmic.c:1094 dmic_set_config() [ 8271192.656250] ( 26.718750) c0 dmic-dai 2.1 src/drivers/intel/dmic.c:1134 dmic_set_config(): unmute_ramp_time_ms = 400 [ 8271215.937500] ( 23.281250) c0 dmic-dai 2.1 src/drivers/intel/dmic.c:1182 dmic_set_config(), prm config->dmic.num_pdm_active = 1 [ 8271234.479167] ( 18.541666) c0 dmic-dai 2.1 src/drivers/intel/dmic.c:1184 dmic_set_config(), prm pdmclk_min = 500000, pdmclk_max = 4800000 [ 8271252.656250] ( 18.177084) c0 dmic-dai 2.1 src/drivers/intel/dmic.c:1186 dmic_set_config(), prm duty_min = 40, duty_max = 60 [ 8271271.041667] ( 18.385416) c0 dmic-dai 2.1 src/drivers/intel/dmic.c:1188 dmic_set_config(), prm fifo_fs = 16000, fifo_bits = 32 [ 8271430.468750] ( 159.427078) c0 dmic-dai 2.1 src/drivers/intel/dmic.c:1230 dmic_set_config(), cfg clkdiv = 16, mcic = 25 [ 8271446.979167] ( 16.510416) c0 dmic-dai 2.1 src/drivers/intel/dmic.c:1232 dmic_set_config(), cfg mfir_a = 2, mfir_b = 6 [ 8271463.750000] ( 16.770834) c0 dmic-dai 2.1 src/drivers/intel/dmic.c:1234 dmic_set_config(), cfg cic_shift = 3 [ 8271480.312500] ( 16.562500) c0 dmic-dai 2.1 src/drivers/intel/dmic.c:1236 dmic_set_config(), cfg fir_a_shift = 0, cfg.fir_b_shift = 1 [ 8271497.239583] ( 16.927084) c0 dmic-dai 2.1 src/drivers/intel/dmic.c:1238 dmic_set_config(), cfg fir_a_length = 101, fir_b_length = 249 [ 8271515.937500] ( 18.697916) c0 dmic-dai 2.1 src/drivers/intel/dmic.c:818 configuring registers [ 8271731.458333] ( 215.520828) c0 dai src/lib/dai.c:191 dai_put type 2 index 1 new sref 1 [ 8271786.041667] ( 54.583332) c0 dai 11.59 src/audio/dai.c:891 dai_config() dai type = 2 index = 1 [ 8271805.989583] ( 19.947916) c0 dai 11.59 src/audio/dai.c:919 dai_config(), config->dmic.fifo_bits = 32 config->dmic.num_pdm_active = 1 [ 8271823.125000] ( 17.135416) c0 dw-dma src/drivers/dw/dma.c:191 dw_dma_channel_get(): dma 1 request channel 0 [ 8272259.218750] ( 436.093750) c0 dai src/lib/dai.c:165 dai_get type 1 index 1 new sref 3 [ 8272279.114583] ( 19.895834) c0 ssp-dai 1.1 /drivers/intel/ssp/ssp.c:172 ssp_set_config(), config->format = 0x4001 [ 8272317.552083] ( 38.437500) c0 ssp-dai 1.1 /drivers/intel/ssp/ssp.c:599 ssp_set_config(), sscr0 = 0xc1c0003f, sscr1 = 0xd0700000, ssto = 0x00000000, sspsp = 0x2100000 [ 8272336.041667] ( 18.489584) c0 ssp-dai 1.1 /drivers/intel/ssp/ssp.c:601 ssp_set_config(), sscr2 = 0x00004002, sspsp2 = 0x00000000, sscr3 = 0x03030000, ssioc = 0x00000020 [ 8272352.760417] ( 16.718750) c0 ssp-dai 1.1 /drivers/intel/ssp/ssp.c:603 ssp_set_config(), ssrsa = 0x00000003, sstsa = 0x00000003 [ 8272372.187500] ( 19.427084) c0 dai src/lib/dai.c:191 dai_put type 1 index 1 new sref 2 [ 8272395.520833] ( 23.333334) c0 dai 1.6 src/audio/dai.c:891 dai_config() dai type = 1 index = 1 [ 8272417.187500] ( 21.666666) c0 dw-dma src/drivers/dw/dma.c:191 dw_dma_channel_get(): dma 0 request channel 0 [ 8272449.166667] ( 31.979166) c0 dai 1.8 src/audio/dai.c:891 dai_config() dai type = 1 index = 1 [ 8272466.510417] ( 17.343750) c0 dw-dma src/drivers/dw/dma.c:191 dw_dma_channel_get(): dma 1 request channel 0 [ 8272956.875000] ( 490.364594) c0 dai src/lib/dai.c:165 dai_get type 1 index 1 new sref 3 [ 8272976.666667] ( 19.791666) c0 ssp-dai 1.1 /drivers/intel/ssp/ssp.c:172 ssp_set_config(), config->format = 0x4001 [ 8273013.958333] ( 37.291668) c0 ssp-dai 1.1 /drivers/intel/ssp/ssp.c:599 ssp_set_config(), sscr0 = 0xc1c0003f, sscr1 = 0xd0700000, ssto = 0x00000000, sspsp = 0x2100000 [ 8273030.729167] ( 16.770834) c0 ssp-dai 1.1 /drivers/intel/ssp/ssp.c:601 ssp_set_config(), sscr2 = 0x00004002, sspsp2 = 0x00000000, sscr3 = 0x03030000, ssioc = 0x00000020 [ 8273049.322917] ( 18.593750) c0 ssp-dai 1.1 /drivers/intel/ssp/ssp.c:603 ssp_set_config(), ssrsa = 0x00000003, sstsa = 0x00000003 [ 8273068.489583] ( 19.166666) c0 dai src/lib/dai.c:191 dai_put type 1 index 1 new sref 2 [ 8273092.187500] ( 23.697916) c0 dai 1.6 src/audio/dai.c:891 dai_config() dai type = 1 index = 1 [ 8273133.281250] ( 41.093750) c0 dai 1.8 src/audio/dai.c:891 dai_config() dai type = 1 index = 1 [ 8273599.062500] ( 465.781250) c0 dai src/lib/dai.c:165 dai_get type 1 index 0 new sref 3 [ 8273618.541667] ( 19.479166) c0 ssp-dai 1.0 /drivers/intel/ssp/ssp.c:172 ssp_set_config(), config->format = 0x4001 [ 8273656.041667] ( 37.500000) c0 ssp-dai 1.0 /drivers/intel/ssp/ssp.c:599 ssp_set_config(), sscr0 = 0xc1d00037, sscr1 = 0xd0700000, ssto = 0x00000000, sspsp = 0x2990000 [ 8273672.760417] ( 16.718750) c0 ssp-dai 1.0 /drivers/intel/ssp/ssp.c:601 ssp_set_config(), sscr2 = 0x00004002, sspsp2 = 0x00000000, sscr3 = 0x07070000, ssioc = 0x00000020 [ 8273691.302083] ( 18.541666) c0 ssp-dai 1.0 /drivers/intel/ssp/ssp.c:603 ssp_set_config(), ssrsa = 0x00000003, sstsa = 0x00000003 [ 8273710.677083] ( 19.375000) c0 dai src/lib/dai.c:191 dai_put type 1 index 0 new sref 2 [ 8273740.364583] ( 29.687500) c0 dai 2.13 src/audio/dai.c:891 dai_config() dai type = 1 index = 0 [ 8273760.104167] ( 19.739584) c0 dw-dma src/drivers/dw/dma.c:191 dw_dma_channel_get(): dma 0 request channel 0 [ 8273794.427083] ( 34.322918) c0 dai 3.19 src/audio/dai.c:891 dai_config() dai type = 1 index = 0 [ 8273812.343750] ( 17.916666) c0 dw-dma src/drivers/dw/dma.c:191 dw_dma_channel_get(): dma 1 request channel 0 [ 8274270.052083] ( 457.708344) c0 dai src/lib/dai.c:165 dai_get type 1 index 0 new sref 3 [ 8274289.791667] ( 19.739584) c0 ssp-dai 1.0 /drivers/intel/ssp/ssp.c:172 ssp_set_config(), config->format = 0x4001 [ 8274326.770833] ( 36.979168) c0 ssp-dai 1.0 /drivers/intel/ssp/ssp.c:599 ssp_set_config(), sscr0 = 0xc1d00037, sscr1 = 0xd0700000, ssto = 0x00000000, sspsp = 0x2990000 [ 8274343.697917] ( 16.927084) c0 ssp-dai 1.0 /drivers/intel/ssp/ssp.c:601 ssp_set_config(), sscr2 = 0x00004002, sspsp2 = 0x00000000, sscr3 = 0x07070000, ssioc = 0x00000020 [ 8274362.031250] ( 18.333334) c0 ssp-dai 1.0 /drivers/intel/ssp/ssp.c:603 ssp_set_config(), ssrsa = 0x00000003, sstsa = 0x00000003 [ 8274381.458333] ( 19.427084) c0 dai src/lib/dai.c:191 dai_put type 1 index 0 new sref 2 [ 8274410.833333] ( 29.375000) c0 dai 2.13 src/audio/dai.c:891 dai_config() dai type = 1 index = 0 [ 8274450.260417] ( 39.427082) c0 dai 3.19 src/audio/dai.c:891 dai_config() dai type = 1 index = 0 [ 8275030.989583] ( 580.729187) c0 dai src/lib/dai.c:165 dai_get type 3 index 0 new sref 2 [ 8275051.302083] ( 20.312500) c0 dai src/lib/dai.c:191 dai_put type 3 index 0 new sref 1 [ 8275086.822917] ( 35.520832) c0 dai 5.25 src/audio/dai.c:891 dai_config() dai type = 3 index = 0 [ 8275105.052083] ( 18.229166) c0 dai 5.25 src/audio/dai.c:923 dai_config(), channel = -1 [ 8275615.156250] ( 510.104156) c0 dai src/lib/dai.c:165 dai_get type 3 index 1 new sref 2 [ 8275635.000000] ( 19.843750) c0 dai src/lib/dai.c:191 dai_put type 3 index 1 new sref 1 [ 8275673.854167] ( 38.854168) c0 dai 6.31 src/audio/dai.c:891 dai_config() dai type = 3 index = 1 [ 8275693.177083] ( 19.322916) c0 dai 6.31 src/audio/dai.c:923 dai_config(), channel = -1 [ 8276101.822917] ( 408.645844) c0 dai src/lib/dai.c:165 dai_get type 3 index 2 new sref 2 [ 8276121.406250] ( 19.583334) c0 dai src/lib/dai.c:191 dai_put type 3 index 2 new sref 1 [ 8276162.968750] ( 41.562500) c0 dai 7.37 src/audio/dai.c:891 dai_config() dai type = 3 index = 2 [ 8276182.500000] ( 19.531250) c0 dai 7.37 src/audio/dai.c:923 dai_config(), channel = -1 [ 8276651.770833] ( 469.270844) c0 dai src/lib/dai.c:165 dai_get type 3 index 3 new sref 2 [ 8276671.354167] ( 19.583334) c0 dai src/lib/dai.c:191 dai_put type 3 index 3 new sref 1 [ 8276715.989583] ( 44.635418) c0 dai 8.43 src/audio/dai.c:891 dai_config() dai type = 3 index = 3 [ 8276735.364583] ( 19.375000) c0 dai 8.43 src/audio/dai.c:923 dai_config(), channel = -1 [ 8277202.916667] ( 467.552094) c0 pipe 12.66 src/audio/pipeline.c:246 pipeline complete, clock freq 400000000Hz [ 8277221.666667] ( 18.750000) c0 memory src/lib/alloc.c:1151 heap: buffer status [ 8277238.697917] ( 17.031250) c0 memory src/lib/alloc.c:1120 heap: 0xbe09f800 size 524288 blocks 1 caps 0x71 [ 8277255.781250] ( 17.083334) c0 memory src/lib/alloc.c:1122 used 52480 free 471808 [ 8277272.239583] ( 16.458334) c0 memory src/lib/alloc.c:1120 heap: 0xbe801800 size 59392 blocks 1 caps 0x69 [ 8277290.677083] ( 18.437500) c0 memory src/lib/alloc.c:1122 used 0 free 59392 [ 8277308.697917] ( 18.020834) c0 memory src/lib/alloc.c:1153 heap: runtime status [ 8277324.427083] ( 15.729167) c0 memory src/lib/alloc.c:1120 heap: 0xbe090000 size 63488 blocks 7 caps 0x45 [ 8277341.041667] ( 16.614584) c0 memory src/lib/alloc.c:1122 used 22272 free 41216 [ 8277357.916667] ( 16.875000) c0 memory src/lib/alloc.c:1130 block 1 base 0xbe092000 size 128 [ 8277374.687500] ( 16.770834) c0 memory src/lib/alloc.c:1133 count 64 free 31 [ 8277391.510417] ( 16.822916) c0 memory src/lib/alloc.c:1130 block 2 base 0xbe094000 size 256 [ 8277410.468750] ( 18.958334) c0 memory src/lib/alloc.c:1133 count 128 free 66 [ 8277427.135417] ( 16.666666) c0 memory src/lib/alloc.c:1130 block 3 base 0xbe09c000 size 512 [ 8277444.322917] ( 17.187500) c0 memory src/lib/alloc.c:1133 count 8 free 8 [ 8277459.427083] ( 15.104167) c0 memory src/lib/alloc.c:1130 block 4 base 0xbe09d000 size 1024 [ 8277477.708333] ( 18.281250) c0 memory src/lib/alloc.c:1133 count 4 free 4 [ 8277527.447917] ( 49.739582) c0 memory src/lib/alloc.c:1156 heap: runtime shared status [ 8277544.218750] ( 16.770834) c0 memory src/lib/alloc.c:1120 heap: 0x9e12d000 size 37888 blocks 5 caps 0x45 [ 8277560.989583] ( 16.770834) c0 memory src/lib/alloc.c:1122 used 14272 free 23616 [ 8278044.531250] ( 483.541656) c0 dma-trace src/trace/trace.c:142 Suppressed 6 similar messages: block %d base 0x%x size %d [ 8278056.145833] ( 11.614583) c0 dma-trace src/trace/trace.c:142 Suppressed 6 similar messages: count %d free %d [ 8278068.750000] ( 12.604167) c0 pipe 11.60 src/audio/pipeline.c:246 pipeline complete, clock freq 400000000Hz [ 8278470.208333] ( 401.458344) c0 pipe 10.52 src/audio/pipeline.c:246 pipeline complete, clock freq 400000000Hz [ 8278955.833333] ( 485.625000) c0 pipe 9.48 src/audio/pipeline.c:246 pipeline complete, clock freq 400000000Hz [ 8279559.166667] ( 603.333313) c0 pipe 8.44 src/audio/pipeline.c:246 pipeline complete, clock freq 400000000Hz [ 8279917.968750] ( 358.802094) c0 pipe 7.38 src/audio/pipeline.c:246 pipeline complete, clock freq 400000000Hz [ 8280276.718750] ( 358.750000) c0 pipe 6.32 src/audio/pipeline.c:246 pipeline complete, clock freq 400000000Hz [ 8280636.250000] ( 359.531250) c0 pipe 5.26 src/audio/pipeline.c:246 pipeline complete, clock freq 400000000Hz [ 8280974.843750] ( 338.593750) c0 sa src/lib/agent.c:79 WARN validate(), ll drift detected, delta = 40591 [ 8281112.916667] ( 138.072922) c0 pipe 3.20 src/audio/pipeline.c:246 pipeline complete, clock freq 400000000Hz [ 8281478.541667] ( 365.625000) c0 pipe 2.14 src/audio/pipeline.c:246 pipeline complete, clock freq 400000000Hz [ 8281832.604167] ( 354.062500) c0 pipe 1.7 src/audio/pipeline.c:246 pipeline complete, clock freq 400000000Hz [ 8282210.625000] ( 378.020844) c0 selector 12.62 ..../selector/selector.c:322 selector_cmd() [ 8282228.281250] ( 17.656250) c0 selector 12.62 ..../selector/selector.c:287 selector_ctrl_get_data(), SOF_CTRL_CMD_BINARY [ 8282971.197917] ( 742.916687) c0 kd-test 12.61 ..../audio/detect_test.c:556 test_keyword_cmd() [ 8282988.489583] ( 17.291666) c0 kd-test 12.61 ..../audio/detect_test.c:536 test_keyword_ctrl_get_data() size: 384 [ 8283675.260417] ( 686.770813) c0 kd-test 12.61 ..../audio/detect_test.c:556 test_keyword_cmd() [ 8283691.979167] ( 16.718750) c0 kd-test 12.61 ..../audio/detect_test.c:536 test_keyword_ctrl_get_data() size: 384 [ 8283710.052083] ( 18.072916) c0 kd-test 12.61 ..../audio/detect_test.c:489 test_keyword_get_config() [ 8283726.458333] ( 16.406250) c0 kd-test 12.61 ..../audio/detect_test.c:493 value of block size: 32 [ 8285129.270833] ( 1402.812500) c0 sa src/lib/agent.c:79 WARN validate(), ll drift detected, delta = 42180 [ 8285187.760417] ( 58.489582) c0 pga 11.54 ../audio/volume/volume.c:643 volume_ctrl_get_cmd(), channel = 0, value = 65536 [ 8285205.104167] ( 17.343750) c0 pga 11.54 ../audio/volume/volume.c:643 volume_ctrl_get_cmd(), channel = 1, value = 65536 [ 8285805.052083] ( 599.947937) c0 pga 8.40 ../audio/volume/volume.c:643 volume_ctrl_get_cmd(), channel = 0, value = 65536 [ 8285820.052083] ( 15.000000) c0 pga 8.40 ../audio/volume/volume.c:643 volume_ctrl_get_cmd(), channel = 1, value = 65536 [ 8286413.854167] ( 593.802063) c0 pga 7.34 ../audio/volume/volume.c:643 volume_ctrl_get_cmd(), channel = 0, value = 65536 [ 8286429.114583] ( 15.260417) c0 pga 7.34 ../audio/volume/volume.c:643 volume_ctrl_get_cmd(), channel = 1, value = 65536 [ 8287050.677083] ( 621.562500) c0 pga 6.28 ../audio/volume/volume.c:643 volume_ctrl_get_cmd(), channel = 0, value = 65536 [ 8287066.145833] ( 15.468750) c0 pga 6.28 ../audio/volume/volume.c:643 volume_ctrl_get_cmd(), channel = 1, value = 65536 [ 8287691.979167] ( 625.833313) c0 pga 5.22 ../audio/volume/volume.c:643 volume_ctrl_get_cmd(), channel = 0, value = 65536 [ 8287707.239583] ( 15.260417) c0 pga 5.22 ../audio/volume/volume.c:643 volume_ctrl_get_cmd(), channel = 1, value = 65536 [ 8288883.072917] ( 1175.833374) c0 pga 3.16 ../audio/volume/volume.c:643 volume_ctrl_get_cmd(), channel = 0, value = 65536 [ 8288898.333333] ( 15.260417) c0 pga 3.16 ../audio/volume/volume.c:643 volume_ctrl_get_cmd(), channel = 1, value = 65536 [ 8289580.729167] ( 682.395813) c0 pga 2.10 ../audio/volume/volume.c:643 volume_ctrl_get_cmd(), channel = 0, value = 65536 [ 8289595.781250] ( 15.052083) c0 pga 2.10 ../audio/volume/volume.c:643 volume_ctrl_get_cmd(), channel = 1, value = 65536 [ 8290309.843750] ( 714.062500) c0 demux 1.2 src/audio/mux/mux.c:343 mux_cmd() cmd = 0x00000067 [ 8290327.604167] ( 17.760416) c0 mux src/audio/mux/mux.c:312 mux_ctrl_get_cmd(), cdata->cmd = 0x00000003 [ 8291047.031250] ( 719.427063) c0 sa src/lib/agent.c:79 WARN validate(), ll drift detected, delta = 41966 [ 8291089.843750] ( 42.812500) c0 pga 1.1 ../audio/volume/volume.c:643 volume_ctrl_get_cmd(), channel = 0, value = 65536 [ 8291105.625000] ( 15.781250) c0 pga 1.1 ../audio/volume/volume.c:643 volume_ctrl_get_cmd(), channel = 1, value = 65536 [ 11119103.072917] ( 2827997.500000) c0 pipe 10.52 src/audio/pipeline.c:541 pipe params dir 1 frame_fmt 2 buffer_fmt 0 rate 48000 [ 11119121.302083] ( 18.229166) c0 pipe 10.52 src/audio/pipeline.c:545 pipe params stream_tag 2 channels 4 sample_valid_bytes 4 sample_container_bytes 4 [ 11119251.302083] ( 130.000000) c0 dai 10.51 src/audio/dai.c:412 dai_capture_params() src_dev = 0 stream_id = 0 src_width = 4 dest_width = 4 [ 11119268.489583] ( 17.187500) c0 dai 10.51 src/audio/dai.c:418 dai_capture_params() fifo 0x10008 [ 11119293.750000] ( 25.260416) c0 pipe 10.52 src/audio/pipeline.c:668 pipe prepare [ 11121117.968750] ( 1824.218750) c0 pipe 10.52 src/audio/pipeline.c:863 pipe trigger cmd 1 [ 11121222.343750] ( 104.375000) c0 dmic-dai 2.0 src/drivers/intel/dmic.c:1280 dmic_start(), dmic->fifo_a [ 11121240.416667] ( 18.072916) c0 dmic-dai 2.0 src/drivers/intel/dmic.c:1315 dmic_start(), mic_a = 1, mic_b = 1, fir_a = 1, fir_b = 1 [ 11121257.916667] ( 17.500000) c0 dmic-dai 2.0 src/drivers/intel/dmic.c:1315 dmic_start(), mic_a = 1, mic_b = 1, fir_a = 1, fir_b = 1 [ 11121278.906250] ( 20.989584) c0 ll-schedule ./schedule/ll_schedule.c:348 task add 0x9e130888 dmic-work <59c87728-d8f9-42f6-b89d-5870a87b0e1e> [ 11121296.093750] ( 17.187500) c0 ll-schedule ./schedule/ll_schedule.c:352 task params pri 4 flags 0 start 1000 period 1000 [ 11121318.489583] ( 22.395834) c0 ll-schedule ./schedule/ll_schedule.c:252 num_tasks 3 total_num_tasks 3 [ 11121337.552083] ( 19.062500) c0 dmic-dai 2.0 src/drivers/intel/dmic.c:1387 dmic_start(), done active_fifos = 1 [ 11121368.437500] ( 30.885416) c0 ll-schedule ./schedule/ll_schedule.c:348 task add 0xbe090100 pipe-task [ 11121387.395833] ( 18.958334) c0 ll-schedule ./schedule/ll_schedule.c:352 task params pri 0 flags 0 start 0 period 1000 [ 11121408.645833] ( 21.250000) c0 ll-schedule ./schedule/ll_schedule.c:252 num_tasks 4 total_num_tasks 4 [ 11141841.458333] ( 20432.812500) c0 pipe 10.52 src/audio/pipeline.c:863 pipe trigger cmd 0 [ 11141875.208333] ( 33.750000) c0 dw-dma src/drivers/dw/dma.c:407 dw_dma_stop(): dma 0 channel 0 stop [ 11141896.927083] ( 21.718750) c0 dmic-dai 2.0 src/drivers/intel/dmic.c:1423 dmic_stop(), dmic_active_fifos = 1 [ 11141918.125000] ( 21.197916) c0 ll-schedule ./schedule/ll_schedule.c:481 task cancel 0x9e130888 dmic-work <59c87728-d8f9-42f6-b89d-5870a87b0e1e> [ 11141936.822917] ( 18.697916) c0 ll-schedule ./schedule/ll_schedule.c:290 num_tasks 3 total_num_tasks 3 [ 11141957.291667] ( 20.468750) c0 ll-schedule ./schedule/ll_schedule.c:481 task cancel 0xbe090100 pipe-task [ 11141976.979167] ( 19.687500) c0 ll-schedule ./schedule/ll_schedule.c:290 num_tasks 2 total_num_tasks 2 [ 11143086.145833] ( 1109.166626) c0 pipe 10.52 src/audio/pipeline.c:949 pipe reset [ 11144770.989583] ( 1684.843750) c0 pipe 10.52 src/audio/pipeline.c:541 pipe params dir 1 frame_fmt 2 buffer_fmt 0 rate 48000 [ 11144789.791667] ( 18.802084) c0 pipe 10.52 src/audio/pipeline.c:545 pipe params stream_tag 2 channels 4 sample_valid_bytes 4 sample_container_bytes 4 [ 11144957.604167] ( 167.812500) c0 dai 10.51 src/audio/dai.c:412 dai_capture_params() src_dev = 0 stream_id = 0 src_width = 4 dest_width = 4 [ 11144974.843750] ( 17.239584) c0 dai 10.51 src/audio/dai.c:418 dai_capture_params() fifo 0x10008 [ 11145000.156250] ( 25.312500) c0 pipe 10.52 src/audio/pipeline.c:668 pipe prepare [ 11146660.729167] ( 1660.572876) c0 pipe 10.52 src/audio/pipeline.c:863 pipe trigger cmd 1 [ 11146764.531250] ( 103.802086) c0 dmic-dai 2.0 src/drivers/intel/dmic.c:1280 dmic_start(), dmic->fifo_a [ 11146782.031250] ( 17.500000) c0 dmic-dai 2.0 src/drivers/intel/dmic.c:1315 dmic_start(), mic_a = 1, mic_b = 1, fir_a = 1, fir_b = 1 [ 11146799.427083] ( 17.395834) c0 dmic-dai 2.0 src/drivers/intel/dmic.c:1315 dmic_start(), mic_a = 1, mic_b = 1, fir_a = 1, fir_b = 1 [ 11146820.364583] ( 20.937500) c0 ll-schedule ./schedule/ll_schedule.c:348 task add 0x9e130888 dmic-work <59c87728-d8f9-42f6-b89d-5870a87b0e1e> [ 11146837.656250] ( 17.291666) c0 ll-schedule ./schedule/ll_schedule.c:352 task params pri 4 flags 0 start 1000 period 1000 [ 11146859.947917] ( 22.291666) c0 ll-schedule ./schedule/ll_schedule.c:252 num_tasks 3 total_num_tasks 3 [ 11146923.697917] ( 63.750000) c0 dmic-dai 2.0 src/drivers/intel/dmic.c:1387 dmic_start(), done active_fifos = 1 [ 11146952.968750] ( 29.270834) c0 ll-schedule ./schedule/ll_schedule.c:348 task add 0xbe090100 pipe-task [ 11146970.052083] ( 17.083334) c0 ll-schedule ./schedule/ll_schedule.c:352 task params pri 0 flags 0 start 0 period 1000 [ 11146989.479167] ( 19.427084) c0 ll-schedule ./schedule/ll_schedule.c:252 num_tasks 4 total_num_tasks 4 [ 11149030.468750] ( 2040.989624) c0 sa src/lib/agent.c:79 WARN validate(), ll drift detected, delta = 40734 [ 11161550.625000] ( 12520.156250) c0 pipe 10.52 src/audio/pipeline.c:863 pipe trigger cmd 0 [ 11161584.114583] ( 33.489582) c0 dw-dma src/drivers/dw/dma.c:407 dw_dma_stop(): dma 0 channel 0 stop [ 11161606.041667] ( 21.927084) c0 dmic-dai 2.0 src/drivers/intel/dmic.c:1423 dmic_stop(), dmic_active_fifos = 1 [ 11161625.520833] ( 19.479166) c0 ll-schedule ./schedule/ll_schedule.c:481 task cancel 0x9e130888 dmic-work <59c87728-d8f9-42f6-b89d-5870a87b0e1e> [ 11161645.625000] ( 20.104166) c0 ll-schedule ./schedule/ll_schedule.c:290 num_tasks 3 total_num_tasks 3 [ 11161668.072917] ( 22.447916) c0 ll-schedule ./schedule/ll_schedule.c:481 task cancel 0xbe090100 pipe-task [ 11161685.520833] ( 17.447916) c0 ll-schedule ./schedule/ll_schedule.c:290 num_tasks 2 total_num_tasks 2 [ 11163104.895833] ( 1419.375000) c0 pipe 10.52 src/audio/pipeline.c:949 pipe reset [131916709.895833] (120753608.000000) c0 dma-trace src/trace/dma-trace.c:339 ERROR FW ABI 0x3012001 DBG ABI 0x5003000 tag v1.7-rc1-50-gb0facccfc8dd src hash 0xc59fdd96 (ldc hash 0xc59fdd96) [131916957.552083] ( 247.656250) c0 ll-schedule ./schedule/ll_schedule.c:348 task add 0x9e136480 dma-trace-task <2b972272-c5b1-4b7e-926f-0fc5cb4c4690> [131916975.000000] ( 17.447916) c0 ll-schedule ./schedule/ll_schedule.c:352 task params pri 4 flags 0 start 500000 period 500000 [131916995.208333] ( 20.208334) c0 ll-schedule ./schedule/ll_schedule.c:252 num_tasks 2 total_num_tasks 2 [131917584.270833] ( 589.062500) c0 sa src/lib/agent.c:79 WARN validate(), ll drift detected, delta = 41736 [131918454.791667] ( 870.520813) c0 component src/audio/component.c:130 comp new host <8b9d100c-6d78-418f-90a3-e0e805d0852b> type 1 id 1.0 [131918498.750000] ( 43.958332) c0 hda-dma ..../intel/hda/hda-dma.c:794 hda-dmac :5 -> probe [131918597.500000] ( 98.750000) c0 dma src/lib/dma.c:127 dma_get() ID 5 sref = 1 busy channels 0 [131919059.531250] ( 462.031250) c0 component src/audio/component.c:130 comp new pga type 5 id 1.1 [131919100.937500] ( 41.406250) c0 pga 1.1 ../audio/volume/volume.c:379 vol->initial_ramp = 20, vol->ramp = 2, vol->min_value = 0, vol->max_value = 65536 [131919574.114583] ( 473.177094) c0 component src/audio/component.c:130 comp new demux type 18 id 1.2 [131919592.291667] ( 18.177084) c0 mux src/audio/mux/mux.c:168 mux_new() [131919646.458333] ( 54.166668) c0 demux 1.2 src/audio/mux/mux.c:100 mux_set_values() [131920029.583333] ( 383.125000) c0 buffer src/audio/buffer.c:79 buffer new size 0x300 id 1.3 flags 0x0 [131920452.916667] ( 423.333344) c0 buffer src/audio/buffer.c:79 buffer new size 0x300 id 1.4 flags 0x0 [131920879.791667] ( 426.875000) c0 buffer src/audio/buffer.c:79 buffer new size 0x300 id 1.5 flags 0x0 [131921313.541667] ( 433.750000) c0 component src/audio/component.c:130 comp new dai type 2 id 1.6 [131921375.989583] ( 62.447918) c0 power ../cavs/lib/pm_runtime.c:183 en_ssp_power index 1 [131921394.479167] ( 18.489584) c0 power ../cavs/lib/pm_runtime.c:192 en_ssp_power I2SLCTL 00000202 [131921414.218750] ( 19.739584) c0 dai src/lib/dai.c:165 dai_get type 1 index 1 new sref 1 [131921555.416667] ( 141.197922) c0 dma src/lib/dma.c:127 dma_get() ID 0 sref = 1 busy channels 0 [131921925.989583] ( 370.572906) c0 pipe src/audio/pipeline.c:78 pipeline new pipe_id 1 period 1000 priority 0 [131922350.781250] ( 424.791656) c0 component src/audio/component.c:130 comp new dai type 2 id 1.8 [131922391.614583] ( 40.833332) c0 dai src/lib/dai.c:165 dai_get type 1 index 1 new sref 2 [131922495.156250] ( 103.541664) c0 dma src/lib/dma.c:127 dma_get() ID 1 sref = 1 busy channels 0 [131922907.291667] ( 412.135406) c0 component src/audio/component.c:130 comp new host <8b9d100c-6d78-418f-90a3-e0e805d0852b> type 1 id 2.9 [131922950.781250] ( 43.489582) c0 dma src/lib/dma.c:127 dma_get() ID 5 sref = 2 busy channels 0 [131923431.875000] ( 481.093750) c0 component src/audio/component.c:130 comp new pga type 5 id 2.10 [131923472.291667] ( 40.416668) c0 pga 2.10 ../audio/volume/volume.c:379 vol->initial_ramp = 20, vol->ramp = 2, vol->min_value = 0, vol->max_value = 65536 [131923874.947917] ( 402.656250) c0 buffer src/audio/buffer.c:79 buffer new size 0x300 id 2.11 flags 0x0 [131924299.687500] ( 424.739594) c0 buffer src/audio/buffer.c:79 buffer new size 0x300 id 2.12 flags 0x0 [131924732.968750] ( 433.281250) c0 component src/audio/component.c:130 comp new dai type 2 id 2.13 [131924794.687500] ( 61.718750) c0 power ../cavs/lib/pm_runtime.c:183 en_ssp_power index 0 [131924813.177083] ( 18.489584) c0 power ../cavs/lib/pm_runtime.c:192 en_ssp_power I2SLCTL 00000303 [131924832.343750] ( 19.166666) c0 dai src/lib/dai.c:165 dai_get type 1 index 0 new sref 1 [131924853.541667] ( 21.197916) c0 dma src/lib/dma.c:127 dma_get() ID 0 sref = 2 busy channels 0 [131925292.708333] ( 439.166656) c0 pipe src/audio/pipeline.c:78 pipeline new pipe_id 2 period 1000 priority 0 [131925578.385417] ( 285.677094) c0 sa src/lib/agent.c:79 WARN validate(), ll drift detected, delta = 41579 [131925640.312500] ( 61.927082) c0 component src/audio/component.c:130 comp new host <8b9d100c-6d78-418f-90a3-e0e805d0852b> type 1 id 3.15 [131925685.885417] ( 45.572918) c0 dma src/lib/dma.c:127 dma_get() ID 4 sref = 2 busy channels 1 [131926145.000000] ( 459.114594) c0 component src/audio/component.c:130 comp new pga type 5 id 3.16 [131926185.937500] ( 40.937500) c0 pga 3.16 ../audio/volume/volume.c:379 vol->initial_ramp = 250, vol->ramp = 0, vol->min_value = 0, vol->max_value = 2072775 [131926561.041667] ( 375.104156) c0 buffer src/audio/buffer.c:79 buffer new size 0x300 id 3.17 flags 0x0 [131926986.822917] ( 425.781250) c0 buffer src/audio/buffer.c:79 buffer new size 0x300 id 3.18 flags 0x0 [131927449.895833] ( 463.072906) c0 component src/audio/component.c:130 comp new dai type 2 id 3.19 [131927490.833333] ( 40.937500) c0 dai src/lib/dai.c:165 dai_get type 1 index 0 new sref 2 [131927511.666667] ( 20.833334) c0 dma src/lib/dma.c:127 dma_get() ID 1 sref = 2 busy channels 0 [131927921.770833] ( 410.104156) c0 pipe src/audio/pipeline.c:78 pipeline new pipe_id 3 period 1000 priority 0 [131928402.968750] ( 481.197906) c0 component src/audio/component.c:130 comp new host <8b9d100c-6d78-418f-90a3-e0e805d0852b> type 1 id 5.21 [131928446.562500] ( 43.593750) c0 dma src/lib/dma.c:127 dma_get() ID 5 sref = 3 busy channels 0 [131928892.760417] ( 446.197906) c0 component src/audio/component.c:130 comp new pga type 5 id 5.22 [131928933.437500] ( 40.677082) c0 pga 5.22 ../audio/volume/volume.c:379 vol->initial_ramp = 20, vol->ramp = 2, vol->min_value = 0, vol->max_value = 65536 [131929309.791667] ( 376.354156) c0 buffer src/audio/buffer.c:79 buffer new size 0x300 id 5.23 flags 0x0 [131929607.239583] ( 297.447906) c0 sa src/lib/agent.c:79 WARN validate(), ll drift detected, delta = 42198 [131929660.885417] ( 53.645832) c0 buffer src/audio/buffer.c:79 buffer new size 0x300 id 5.24 flags 0x0 [131929897.552083] ( 236.666672) c0 component src/audio/component.c:130 comp new dai type 2 id 5.25 [131929938.489583] ( 40.937500) c0 hda-dai 3.0 /drivers/intel/hda/hda.c:65 hda_probe() [131929961.875000] ( 23.385416) c0 dai src/lib/dai.c:165 dai_get type 3 index 0 new sref 1 [131929983.750000] ( 21.875000) c0 hda-dma ..../intel/hda/hda-dma.c:794 hda-dmac :7 -> probe [131930067.968750] ( 84.218750) c0 dma src/lib/dma.c:127 dma_get() ID 7 sref = 1 busy channels 0 [131930487.812500] ( 419.843750) c0 pipe src/audio/pipeline.c:78 pipeline new pipe_id 5 period 1000 priority 0 [131930907.500000] ( 419.687500) c0 component src/audio/component.c:130 comp new host <8b9d100c-6d78-418f-90a3-e0e805d0852b> type 1 id 6.27 [131930950.625000] ( 43.125000) c0 dma src/lib/dma.c:127 dma_get() ID 5 sref = 4 busy channels 0 [131931432.864583] ( 482.239594) c0 component src/audio/component.c:130 comp new pga type 5 id 6.28 [131931473.593750] ( 40.729168) c0 pga 6.28 ../audio/volume/volume.c:379 vol->initial_ramp = 20, vol->ramp = 2, vol->min_value = 0, vol->max_value = 65536 [131931875.104167] ( 401.510406) c0 buffer src/audio/buffer.c:79 buffer new size 0x300 id 6.29 flags 0x0 [131932300.677083] ( 425.572906) c0 buffer src/audio/buffer.c:79 buffer new size 0x300 id 6.30 flags 0x0 [131932733.541667] ( 432.864594) c0 component src/audio/component.c:130 comp new dai type 2 id 6.31 [131932774.739583] ( 41.197918) c0 hda-dai 3.1 /drivers/intel/hda/hda.c:65 hda_probe() [131932797.812500] ( 23.072916) c0 dai src/lib/dai.c:165 dai_get type 3 index 1 new sref 1 [131932819.947917] ( 22.135416) c0 dma src/lib/dma.c:127 dma_get() ID 7 sref = 2 busy channels 0 [131933243.072917] ( 423.125000) c0 pipe src/audio/pipeline.c:78 pipeline new pipe_id 6 period 1000 priority 0 [131933574.218750] ( 331.145844) c0 sa src/lib/agent.c:79 WARN validate(), ll drift detected, delta = 41479 [131933641.875000] ( 67.656250) c0 component src/audio/component.c:130 comp new host <8b9d100c-6d78-418f-90a3-e0e805d0852b> type 1 id 7.33 [131933687.135417] ( 45.260418) c0 dma src/lib/dma.c:127 dma_get() ID 5 sref = 5 busy channels 0 [131934145.989583] ( 458.854156) c0 component src/audio/component.c:130 comp new pga type 5 id 7.34 [131934186.979167] ( 40.989582) c0 pga 7.34 ../audio/volume/volume.c:379 vol->initial_ramp = 20, vol->ramp = 2, vol->min_value = 0, vol->max_value = 65536 [131934561.822917] ( 374.843750) c0 buffer src/audio/buffer.c:79 buffer new size 0x300 id 7.35 flags 0x0 [131934988.437500] ( 426.614594) c0 buffer src/audio/buffer.c:79 buffer new size 0x300 id 7.36 flags 0x0 [131935450.312500] ( 461.875000) c0 component src/audio/component.c:130 comp new dai type 2 id 7.37 [131935491.927083] ( 41.614582) c0 hda-dai 3.2 /drivers/intel/hda/hda.c:65 hda_probe() [131935515.156250] ( 23.229166) c0 dai src/lib/dai.c:165 dai_get type 3 index 2 new sref 1 [131935537.343750] ( 22.187500) c0 dma src/lib/dma.c:127 dma_get() ID 7 sref = 3 busy channels 0 [131935934.635417] ( 397.291656) c0 pipe src/audio/pipeline.c:78 pipeline new pipe_id 7 period 1000 priority 0 [131936403.906250] ( 469.270844) c0 component src/audio/component.c:130 comp new host <8b9d100c-6d78-418f-90a3-e0e805d0852b> type 1 id 8.39 [131936447.031250] ( 43.125000) c0 dma src/lib/dma.c:127 dma_get() ID 5 sref = 6 busy channels 0 [131936893.645833] ( 446.614594) c0 component src/audio/component.c:130 comp new pga type 5 id 8.40 [131936934.218750] ( 40.572918) c0 pga 8.40 ../audio/volume/volume.c:379 vol->initial_ramp = 20, vol->ramp = 2, vol->min_value = 0, vol->max_value = 65536 [131937405.729167] ( 471.510406) c0 buffer src/audio/buffer.c:79 buffer new size 0x300 id 8.41 flags 0x0 [131937859.114583] ( 453.385406) c0 buffer src/audio/buffer.c:79 buffer new size 0x300 id 8.42 flags 0x0 [131938291.145833] ( 432.031250) c0 component src/audio/component.c:130 comp new dai type 2 id 8.43 [131938332.968750] ( 41.822918) c0 hda-dai 3.3 /drivers/intel/hda/hda.c:65 hda_probe() [131938356.250000] ( 23.281250) c0 dai src/lib/dai.c:165 dai_get type 3 index 3 new sref 1 [131938378.072917] ( 21.822916) c0 dma src/lib/dma.c:127 dma_get() ID 7 sref = 4 busy channels 0 [131938808.437500] ( 430.364594) c0 pipe src/audio/pipeline.c:78 pipeline new pipe_id 8 period 1000 priority 0 [131939273.958333] ( 465.520844) c0 component src/audio/component.c:130 comp new host <8b9d100c-6d78-418f-90a3-e0e805d0852b> type 1 id 9.45 [131939317.500000] ( 43.541668) c0 dma src/lib/dma.c:127 dma_get() ID 4 sref = 3 busy channels 1 [131939618.072917] ( 300.572906) c0 sa src/lib/agent.c:79 WARN validate(), ll drift detected, delta = 42538 [131939678.125000] ( 60.052082) c0 buffer src/audio/buffer.c:79 buffer new size 0x300 id 9.46 flags 0x0 [131939949.947917] ( 271.822906) c0 pipe src/audio/pipeline.c:78 pipeline new pipe_id 9 period 1000 priority 1 [131940435.312500] ( 485.364594) c0 component src/audio/component.c:130 comp new host <8b9d100c-6d78-418f-90a3-e0e805d0852b> type 1 id 10.49 [131940478.385417] ( 43.072918) c0 dma src/lib/dma.c:127 dma_get() ID 4 sref = 4 busy channels 1 [131940853.281250] ( 374.895844) c0 buffer src/audio/buffer.c:79 buffer new size 0x600 id 10.50 flags 0x0 [131941283.750000] ( 430.468750) c0 component src/audio/component.c:130 comp new dai type 2 id 10.51 [131941325.364583] ( 41.614582) c0 dmic-dai 2.0 src/drivers/intel/dmic.c:1551 dmic_probe() [131941383.593750] ( 58.229168) c0 dai src/lib/dai.c:165 dai_get type 2 index 0 new sref 1 [131941441.614583] ( 58.020832) c0 dma src/lib/dma.c:127 dma_get() ID 0 sref = 3 busy channels 0 [131941866.354167] ( 424.739594) c0 pipe src/audio/pipeline.c:78 pipeline new pipe_id 10 period 1000 priority 0 [131942329.270833] ( 462.916656) c0 component src/audio/component.c:130 comp new host <8b9d100c-6d78-418f-90a3-e0e805d0852b> type 1 id 11.53 [131942372.812500] ( 43.541668) c0 dma src/lib/dma.c:127 dma_get() ID 4 sref = 5 busy channels 1 [131942819.062500] ( 446.250000) c0 component src/audio/component.c:130 comp new pga type 5 id 11.54 [131942859.895833] ( 40.833332) c0 pga 11.54 ../audio/volume/volume.c:379 vol->initial_ramp = 250, vol->ramp = 0, vol->min_value = 0, vol->max_value = 2072775 [131943299.895833] ( 440.000000) c0 component src/audio/component.c:130 comp new kpb type 16 id 11.55 [131943318.229167] ( 18.333334) c0 kpb src/audio/kpb.c:135 kpb_new() [131943846.458333] ( 528.229187) c0 buffer src/audio/buffer.c:79 buffer new size 0x780 id 11.56 flags 0x0 [131944271.093750] ( 424.635406) c0 buffer src/audio/buffer.c:79 buffer new size 0x500 id 11.57 flags 0x0 [131944698.437500] ( 427.343750) c0 buffer src/audio/buffer.c:79 buffer new size 0x500 id 11.58 flags 0x0 [131945131.927083] ( 433.489594) c0 component src/audio/component.c:130 comp new dai type 2 id 11.59 [131945173.072917] ( 41.145832) c0 dmic-dai 2.1 src/drivers/intel/dmic.c:1551 dmic_probe() [131945226.458333] ( 53.385418) c0 dai src/lib/dai.c:165 dai_get type 2 index 1 new sref 1 [131945247.604167] ( 21.145834) c0 dma src/lib/dma.c:127 dma_get() ID 1 sref = 3 busy channels 0 [131945601.197917] ( 353.593750) c0 sa src/lib/agent.c:79 WARN validate(), ll drift detected, delta = 42003 [131945708.541667] ( 107.343750) c0 pipe src/audio/pipeline.c:78 pipeline new pipe_id 11 period 5000 priority 0 [131946167.239583] ( 458.697906) c0 component src/audio/component.c:130 comp new kd-test type 15 id 12.61 [131946184.843750] ( 17.604166) c0 kd-test ..../audio/detect_test.c:256 test_keyword_new() [131946658.854167] ( 474.010406) c0 component src/audio/component.c:130 comp new selector <55a88ed5-3d18-46ca-88f1-0ee6eae9930f> type 17 id 12.62 [131946677.239583] ( 18.385416) c0 selector ..../selector/selector.c:61 selector_new() [131947082.343750] ( 405.104156) c0 buffer src/audio/buffer.c:79 buffer new size 0x500 id 12.63 flags 0x0 [131947538.125000] ( 455.781250) c0 buffer src/audio/buffer.c:79 buffer new size 0x500 id 12.64 flags 0x0 [131947978.750000] ( 440.625000) c0 pipe src/audio/pipeline.c:78 pipeline new pipe_id 12 period 5000 priority 1 [131948437.395833] ( 458.645844) c0 kpb 11.55 src/audio/pipeline.c:125 connect buffer 63 as sink [131948844.583333] ( 407.187500) c0 demux 1.2 src/audio/pipeline.c:125 connect buffer 46 as sink [131949196.979167] ( 352.395844) c0 host 1.0 src/audio/pipeline.c:125 connect buffer 3 as sink [131949660.000000] ( 463.020844) c0 pga 1.1 src/audio/pipeline.c:127 connect buffer 3 as source [131949992.447917] ( 332.447906) c0 pga 1.1 src/audio/pipeline.c:125 connect buffer 4 as sink [131950357.760417] ( 365.312500) c0 demux 1.2 src/audio/pipeline.c:127 connect buffer 4 as source [131950724.114583] ( 366.354156) c0 demux 1.2 src/audio/pipeline.c:125 connect buffer 5 as sink [131951091.614583] ( 367.500000) c0 dai 1.6 src/audio/pipeline.c:127 connect buffer 5 as source [131951488.906250] ( 397.291656) c0 host 2.9 src/audio/pipeline.c:125 connect buffer 11 as sink [131951827.760417] ( 338.854156) c0 pga 2.10 src/audio/pipeline.c:127 connect buffer 11 as source [131952193.385417] ( 365.625000) c0 pga 2.10 src/audio/pipeline.c:125 connect buffer 12 as sink [131952561.666667] ( 368.281250) c0 dai 2.13 src/audio/pipeline.c:127 connect buffer 12 as source [131952928.750000] ( 367.083344) c0 host 3.15 src/audio/pipeline.c:127 connect buffer 17 as source [131953294.947917] ( 366.197906) c0 pga 3.16 src/audio/pipeline.c:125 connect buffer 17 as sink [131953591.770833] ( 296.822906) c0 sa src/lib/agent.c:79 WARN validate(), ll drift detected, delta = 41013 [131953646.041667] ( 54.270832) c0 pga 3.16 src/audio/pipeline.c:127 connect buffer 18 as source [131954031.145833] ( 385.104156) c0 dai 3.19 src/audio/pipeline.c:125 connect buffer 18 as sink [131954396.875000] ( 365.729156) c0 host 5.21 src/audio/pipeline.c:125 connect buffer 23 as sink [131954763.750000] ( 366.875000) c0 pga 5.22 src/audio/pipeline.c:127 connect buffer 23 as source [131955130.156250] ( 366.406250) c0 pga 5.22 src/audio/pipeline.c:125 connect buffer 24 as sink [131955525.781250] ( 395.625000) c0 dai 5.25 src/audio/pipeline.c:127 connect buffer 24 as source [131955927.031250] ( 401.250000) c0 host 6.27 src/audio/pipeline.c:125 connect buffer 29 as sink [131956292.968750] ( 365.937500) c0 pga 6.28 src/audio/pipeline.c:127 connect buffer 29 as source [131956659.843750] ( 366.875000) c0 pga 6.28 src/audio/pipeline.c:125 connect buffer 30 as sink [131957026.718750] ( 366.875000) c0 dai 6.31 src/audio/pipeline.c:127 connect buffer 30 as source [131957428.489583] ( 401.770844) c0 host 7.33 src/audio/pipeline.c:125 connect buffer 35 as sink [131957823.281250] ( 394.791656) c0 pga 7.34 src/audio/pipeline.c:127 connect buffer 35 as source [131958188.489583] ( 365.208344) c0 pga 7.34 src/audio/pipeline.c:125 connect buffer 36 as sink [131958556.041667] ( 367.552094) c0 dai 7.37 src/audio/pipeline.c:127 connect buffer 36 as source [131958924.062500] ( 368.020844) c0 host 8.39 src/audio/pipeline.c:125 connect buffer 41 as sink [131959290.364583] ( 366.302094) c0 pga 8.40 src/audio/pipeline.c:127 connect buffer 41 as source [131959573.229167] ( 282.864594) c0 sa src/lib/agent.c:79 WARN validate(), ll drift detected, delta = 41727 [131959641.354167] ( 68.125000) c0 pga 8.40 src/audio/pipeline.c:125 connect buffer 42 as sink [131960026.302083] ( 384.947906) c0 dai 8.43 src/audio/pipeline.c:127 connect buffer 42 as source [131960391.875000] ( 365.572906) c0 host 9.45 src/audio/pipeline.c:127 connect buffer 46 as source [131960759.843750] ( 367.968750) c0 host 10.49 src/audio/pipeline.c:127 connect buffer 50 as source [131961126.718750] ( 366.875000) c0 dai 10.51 src/audio/pipeline.c:125 connect buffer 50 as sink [131961524.375000] ( 397.656250) c0 host 11.53 src/audio/pipeline.c:127 connect buffer 58 as source [131961924.322917] ( 399.947906) c0 kpb 11.55 src/audio/pipeline.c:125 connect buffer 58 as sink [131962289.583333] ( 365.260406) c0 kpb 11.55 src/audio/pipeline.c:127 connect buffer 57 as source [131962655.468750] ( 365.885406) c0 pga 11.54 src/audio/pipeline.c:125 connect buffer 57 as sink [131963021.406250] ( 365.937500) c0 pga 11.54 src/audio/pipeline.c:127 connect buffer 56 as source [131963422.187500] ( 400.781250) c0 dai 11.59 src/audio/pipeline.c:125 connect buffer 56 as sink [131963820.156250] ( 397.968750) c0 kd-test 12.61 src/audio/pipeline.c:127 connect buffer 64 as source [131964246.458333] ( 426.302094) c0 selector 12.62 src/audio/pipeline.c:125 connect buffer 64 as sink [131964612.500000] ( 366.041656) c0 selector 12.62 src/audio/pipeline.c:127 connect buffer 63 as source [131965074.322917] ( 461.822906) c0 dai src/lib/dai.c:165 dai_get type 1 index 1 new sref 3 [131965093.854167] ( 19.531250) c0 ssp-dai 1.1 /drivers/intel/ssp/ssp.c:172 ssp_set_config(), config->format = 0x4001 [131965132.708333] ( 38.854168) c0 ssp-dai 1.1 /drivers/intel/ssp/ssp.c:599 ssp_set_config(), sscr0 = 0xc1c0003f, sscr1 = 0xd0700000, ssto = 0x00000000, sspsp = 0x2100000 [131965149.427083] ( 16.718750) c0 ssp-dai 1.1 /drivers/intel/ssp/ssp.c:601 ssp_set_config(), sscr2 = 0x00004002, sspsp2 = 0x00000000, sscr3 = 0x03030000, ssioc = 0x00000020 [131965168.072917] ( 18.645834) c0 ssp-dai 1.1 /drivers/intel/ssp/ssp.c:603 ssp_set_config(), ssrsa = 0x00000003, sstsa = 0x00000003 [131965187.760417] ( 19.687500) c0 dai src/lib/dai.c:191 dai_put type 1 index 1 new sref 2 [131965213.177083] ( 25.416666) c0 dai 1.6 src/audio/dai.c:891 dai_config() dai type = 1 index = 1 [131965233.437500] ( 20.260416) c0 dw-dma src/drivers/dw/dma.c:191 dw_dma_channel_get(): dma 0 request channel 0 [131965264.062500] ( 30.625000) c0 dai 1.8 src/audio/dai.c:891 dai_config() dai type = 1 index = 1 [131965283.541667] ( 19.479166) c0 dw-dma src/drivers/dw/dma.c:191 dw_dma_channel_get(): dma 1 request channel 0 [131965618.593750] ( 335.052094) c0 sa src/lib/agent.c:79 WARN validate(), ll drift detected, delta = 42558 [131965672.031250] ( 53.437500) c0 dai src/lib/dai.c:165 dai_get type 1 index 1 new sref 3 [131965691.875000] ( 19.843750) c0 ssp-dai 1.1 /drivers/intel/ssp/ssp.c:172 ssp_set_config(), config->format = 0x4001 [131965730.572917] ( 38.697918) c0 ssp-dai 1.1 /drivers/intel/ssp/ssp.c:599 ssp_set_config(), sscr0 = 0xc1c0003f, sscr1 = 0xd0700000, ssto = 0x00000000, sspsp = 0x2100000 [131965747.395833] ( 16.822916) c0 ssp-dai 1.1 /drivers/intel/ssp/ssp.c:601 ssp_set_config(), sscr2 = 0x00004002, sspsp2 = 0x00000000, sscr3 = 0x03030000, ssioc = 0x00000020 [131965765.729167] ( 18.333334) c0 ssp-dai 1.1 /drivers/intel/ssp/ssp.c:603 ssp_set_config(), ssrsa = 0x00000003, sstsa = 0x00000003 [131965784.843750] ( 19.114584) c0 dai src/lib/dai.c:191 dai_put type 1 index 1 new sref 2 [131965809.479167] ( 24.635416) c0 dai 1.6 src/audio/dai.c:891 dai_config() dai type = 1 index = 1 [131965846.458333] ( 36.979168) c0 dai 1.8 src/audio/dai.c:891 dai_config() dai type = 1 index = 1 [131966357.604167] ( 511.145844) c0 dai src/lib/dai.c:165 dai_get type 1 index 0 new sref 3 [131966377.187500] ( 19.583334) c0 ssp-dai 1.0 /drivers/intel/ssp/ssp.c:172 ssp_set_config(), config->format = 0x4001 [131966414.791667] ( 37.604168) c0 ssp-dai 1.0 /drivers/intel/ssp/ssp.c:599 ssp_set_config(), sscr0 = 0xc1d00037, sscr1 = 0xd0700000, ssto = 0x00000000, sspsp = 0x2990000 [131966431.458333] ( 16.666666) c0 ssp-dai 1.0 /drivers/intel/ssp/ssp.c:601 ssp_set_config(), sscr2 = 0x00004002, sspsp2 = 0x00000000, sscr3 = 0x07070000, ssioc = 0x00000020 [131966450.052083] ( 18.593750) c0 ssp-dai 1.0 /drivers/intel/ssp/ssp.c:603 ssp_set_config(), ssrsa = 0x00000003, sstsa = 0x00000003 [131966469.479167] ( 19.427084) c0 dai src/lib/dai.c:191 dai_put type 1 index 0 new sref 2 [131966499.322917] ( 29.843750) c0 dai 2.13 src/audio/dai.c:891 dai_config() dai type = 1 index = 0 [131966519.062500] ( 19.739584) c0 dw-dma src/drivers/dw/dma.c:191 dw_dma_channel_get(): dma 0 request channel 0 [131966552.864583] ( 33.802082) c0 dai 3.19 src/audio/dai.c:891 dai_config() dai type = 1 index = 0 [131966570.781250] ( 17.916666) c0 dw-dma src/drivers/dw/dma.c:191 dw_dma_channel_get(): dma 1 request channel 0 [131967089.531250] ( 518.750000) c0 dai src/lib/dai.c:165 dai_get type 1 index 0 new sref 3 [131967109.062500] ( 19.531250) c0 ssp-dai 1.0 /drivers/intel/ssp/ssp.c:172 ssp_set_config(), config->format = 0x4001 [131967145.989583] ( 36.927082) c0 ssp-dai 1.0 /drivers/intel/ssp/ssp.c:599 ssp_set_config(), sscr0 = 0xc1d00037, sscr1 = 0xd0700000, ssto = 0x00000000, sspsp = 0x2990000 [131967163.072917] ( 17.083334) c0 ssp-dai 1.0 /drivers/intel/ssp/ssp.c:601 ssp_set_config(), sscr2 = 0x00004002, sspsp2 = 0x00000000, sscr3 = 0x07070000, ssioc = 0x00000020 [131967181.666667] ( 18.593750) c0 ssp-dai 1.0 /drivers/intel/ssp/ssp.c:603 ssp_set_config(), ssrsa = 0x00000003, sstsa = 0x00000003 [131967200.833333] ( 19.166666) c0 dai src/lib/dai.c:191 dai_put type 1 index 0 new sref 2 [131967230.208333] ( 29.375000) c0 dai 2.13 src/audio/dai.c:891 dai_config() dai type = 1 index = 0 [131967269.375000] ( 39.166668) c0 dai 3.19 src/audio/dai.c:891 dai_config() dai type = 1 index = 0 [131967666.927083] ( 397.552094) c0 dai src/lib/dai.c:165 dai_get type 3 index 0 new sref 2 [131967687.343750] ( 20.416666) c0 dai src/lib/dai.c:191 dai_put type 3 index 0 new sref 1 [131967723.125000] ( 35.781250) c0 dai 5.25 src/audio/dai.c:891 dai_config() dai type = 3 index = 0 [131967741.458333] ( 18.333334) c0 dai 5.25 src/audio/dai.c:923 dai_config(), channel = -1 [131968191.354167] ( 449.895844) c0 dai src/lib/dai.c:165 dai_get type 3 index 1 new sref 2 [131968211.197917] ( 19.843750) c0 dai src/lib/dai.c:191 dai_put type 3 index 1 new sref 1 [131968250.052083] ( 38.854168) c0 dai 6.31 src/audio/dai.c:891 dai_config() dai type = 3 index = 1 [131968269.791667] ( 19.739584) c0 dai 6.31 src/audio/dai.c:923 dai_config(), channel = -1 [131968738.437500] ( 468.645844) c0 dai src/lib/dai.c:165 dai_get type 3 index 2 new sref 2 [131968758.020833] ( 19.583334) c0 dai src/lib/dai.c:191 dai_put type 3 index 2 new sref 1 [131968799.322917] ( 41.302082) c0 dai 7.37 src/audio/dai.c:891 dai_config() dai type = 3 index = 2 [131968818.958333] ( 19.635416) c0 dai 7.37 src/audio/dai.c:923 dai_config(), channel = -1 [131969288.281250] ( 469.322906) c0 dai src/lib/dai.c:165 dai_get type 3 index 3 new sref 2 [131969307.864583] ( 19.583334) c0 dai src/lib/dai.c:191 dai_put type 3 index 3 new sref 1 [131969352.708333] ( 44.843750) c0 dai 8.43 src/audio/dai.c:891 dai_config() dai type = 3 index = 3 [131969405.885417] ( 53.177082) c0 dai 8.43 src/audio/dai.c:923 dai_config(), channel = -1 [131969837.343750] ( 431.458344) c0 dai src/lib/dai.c:165 dai_get type 2 index 0 new sref 2 [131969855.520833] ( 18.177084) c0 dmic-dai 2.0 src/drivers/intel/dmic.c:1094 dmic_set_config() [131969881.875000] ( 26.354166) c0 dmic-dai 2.0 src/drivers/intel/dmic.c:1134 dmic_set_config(): unmute_ramp_time_ms = 200 [131969920.572917] ( 38.697918) c0 dmic-dai 2.0 src/drivers/intel/dmic.c:1182 dmic_set_config(), prm config->dmic.num_pdm_active = 2 [131969938.645833] ( 18.072916) c0 dmic-dai 2.0 src/drivers/intel/dmic.c:1184 dmic_set_config(), prm pdmclk_min = 500000, pdmclk_max = 4800000 [131969955.729167] ( 17.083334) c0 dmic-dai 2.0 src/drivers/intel/dmic.c:1186 dmic_set_config(), prm duty_min = 40, duty_max = 60 [131969974.062500] ( 18.333334) c0 dmic-dai 2.0 src/drivers/intel/dmic.c:1188 dmic_set_config(), prm fifo_fs = 48000, fifo_bits = 32 [131970032.500000] ( 58.437500) c0 dmic-dai 2.0 src/drivers/intel/dmic.c:1230 dmic_set_config(), cfg clkdiv = 16, mcic = 25 [131970051.145833] ( 18.645834) c0 dmic-dai 2.0 src/drivers/intel/dmic.c:1232 dmic_set_config(), cfg mfir_a = 2, mfir_b = 0 [131970069.218750] ( 18.072916) c0 dmic-dai 2.0 src/drivers/intel/dmic.c:1234 dmic_set_config(), cfg cic_shift = 3 [131970086.145833] ( 16.927084) c0 dmic-dai 2.0 src/drivers/intel/dmic.c:1236 dmic_set_config(), cfg fir_a_shift = 0, cfg.fir_b_shift = 0 [131970102.916667] ( 16.770834) c0 dmic-dai 2.0 src/drivers/intel/dmic.c:1238 dmic_set_config(), cfg fir_a_length = 101, fir_b_length = 0 [131970121.770833] ( 18.854166) c0 dmic-dai 2.0 src/drivers/intel/dmic.c:818 configuring registers [131970209.218750] ( 87.447914) c0 dai src/lib/dai.c:191 dai_put type 2 index 0 new sref 1 [131970259.531250] ( 50.312500) c0 dai 10.51 src/audio/dai.c:891 dai_config() dai type = 2 index = 0 [131970278.333333] ( 18.802084) c0 dai 10.51 src/audio/dai.c:919 dai_config(), config->dmic.fifo_bits = 32 config->dmic.num_pdm_active = 2 [131970296.718750] ( 18.385416) c0 dw-dma src/drivers/dw/dma.c:191 dw_dma_channel_get(): dma 0 request channel 0 [131970755.468750] ( 458.750000) c0 dai src/lib/dai.c:165 dai_get type 2 index 1 new sref 2 [131970773.333333] ( 17.864584) c0 dmic-dai 2.1 src/drivers/intel/dmic.c:1094 dmic_set_config() [131970798.489583] ( 25.156250) c0 dmic-dai 2.1 src/drivers/intel/dmic.c:1134 dmic_set_config(): unmute_ramp_time_ms = 400 [131970821.718750] ( 23.229166) c0 dmic-dai 2.1 src/drivers/intel/dmic.c:1182 dmic_set_config(), prm config->dmic.num_pdm_active = 1 [131970840.000000] ( 18.281250) c0 dmic-dai 2.1 src/drivers/intel/dmic.c:1184 dmic_set_config(), prm pdmclk_min = 500000, pdmclk_max = 4800000 [131970858.489583] ( 18.489584) c0 dmic-dai 2.1 src/drivers/intel/dmic.c:1186 dmic_set_config(), prm duty_min = 40, duty_max = 60 [131970875.260417] ( 16.770834) c0 dmic-dai 2.1 src/drivers/intel/dmic.c:1188 dmic_set_config(), prm fifo_fs = 16000, fifo_bits = 32 [131971034.895833] ( 159.635422) c0 dmic-dai 2.1 src/drivers/intel/dmic.c:1230 dmic_set_config(), cfg clkdiv = 16, mcic = 25 [131971051.562500] ( 16.666666) c0 dmic-dai 2.1 src/drivers/intel/dmic.c:1232 dmic_set_config(), cfg mfir_a = 2, mfir_b = 6 [131971067.916667] ( 16.354166) c0 dmic-dai 2.1 src/drivers/intel/dmic.c:1234 dmic_set_config(), cfg cic_shift = 3 [131971084.375000] ( 16.458334) c0 dmic-dai 2.1 src/drivers/intel/dmic.c:1236 dmic_set_config(), cfg fir_a_shift = 0, cfg.fir_b_shift = 1 [131971101.041667] ( 16.666666) c0 dmic-dai 2.1 src/drivers/intel/dmic.c:1238 dmic_set_config(), cfg fir_a_length = 101, fir_b_length = 249 [131971119.635417] ( 18.593750) c0 dmic-dai 2.1 src/drivers/intel/dmic.c:818 configuring registers [131971335.416667] ( 215.781250) c0 dai src/lib/dai.c:191 dai_put type 2 index 1 new sref 1 [131971428.333333] ( 92.916664) c0 dai 11.59 src/audio/dai.c:891 dai_config() dai type = 2 index = 1 [131971446.562500] ( 18.229166) c0 dai 11.59 src/audio/dai.c:919 dai_config(), config->dmic.fifo_bits = 32 config->dmic.num_pdm_active = 1 [131971463.385417] ( 16.822916) c0 dw-dma src/drivers/dw/dma.c:191 dw_dma_channel_get(): dma 1 request channel 0 [131971948.750000] ( 485.364594) c0 pipe 12.66 src/audio/pipeline.c:246 pipeline complete, clock freq 400000000Hz [131971968.177083] ( 19.427084) c0 memory src/lib/alloc.c:1151 heap: buffer status [131971985.625000] ( 17.447916) c0 memory src/lib/alloc.c:1120 heap: 0xbe09f800 size 524288 blocks 1 caps 0x71 [131972002.500000] ( 16.875000) c0 memory src/lib/alloc.c:1122 used 52480 free 471808 [131972019.010417] ( 16.510416) c0 memory src/lib/alloc.c:1120 heap: 0xbe801800 size 59392 blocks 1 caps 0x69 [131972037.135417] ( 18.125000) c0 memory src/lib/alloc.c:1122 used 0 free 59392 [131972055.416667] ( 18.281250) c0 memory src/lib/alloc.c:1153 heap: runtime status [131972071.145833] ( 15.729167) c0 memory src/lib/alloc.c:1120 heap: 0xbe090000 size 63488 blocks 7 caps 0x45 [131972087.864583] ( 16.718750) c0 memory src/lib/alloc.c:1122 used 22272 free 41216 [131972104.895833] ( 17.031250) c0 memory src/lib/alloc.c:1130 block 1 base 0xbe092000 size 128 [131972121.614583] ( 16.718750) c0 memory src/lib/alloc.c:1133 count 64 free 31 [131972138.802083] ( 17.187500) c0 memory src/lib/alloc.c:1130 block 2 base 0xbe094000 size 256 [131972157.552083] ( 18.750000) c0 memory src/lib/alloc.c:1133 count 128 free 66 [131972174.531250] ( 16.979166) c0 memory src/lib/alloc.c:1130 block 3 base 0xbe09c000 size 512 [131972191.614583] ( 17.083334) c0 memory src/lib/alloc.c:1133 count 8 free 8 [131972208.020833] ( 16.406250) c0 memory src/lib/alloc.c:1130 block 4 base 0xbe09d000 size 1024 [131972225.000000] ( 16.979166) c0 memory src/lib/alloc.c:1133 count 4 free 4 [131972274.791667] ( 49.791668) c0 memory src/lib/alloc.c:1156 heap: runtime shared status [131972291.354167] ( 16.562500) c0 memory src/lib/alloc.c:1120 heap: 0x9e12d000 size 37888 blocks 5 caps 0x45 [131972310.677083] ( 19.322916) c0 memory src/lib/alloc.c:1122 used 14272 free 23616 [131972817.760417] ( 507.083344) c0 dma-trace src/trace/trace.c:142 Suppressed 6 similar messages: block %d base 0x%x size %d [131972829.114583] ( 11.354167) c0 dma-trace src/trace/trace.c:142 Suppressed 6 similar messages: count %d free %d [131972841.666667] ( 12.552083) c0 pipe 11.60 src/audio/pipeline.c:246 pipeline complete, clock freq 400000000Hz [131973303.020833] ( 461.354156) c0 pipe 10.52 src/audio/pipeline.c:246 pipeline complete, clock freq 400000000Hz [131973611.770833] ( 308.750000) c0 sa src/lib/agent.c:79 WARN validate(), ll drift detected, delta = 42415 [131973740.833333] ( 129.062500) c0 pipe 9.48 src/audio/pipeline.c:246 pipeline complete, clock freq 400000000Hz [131974148.958333] ( 408.125000) c0 pipe 8.44 src/audio/pipeline.c:246 pipeline complete, clock freq 400000000Hz [131974568.645833] ( 419.687500) c0 pipe 7.38 src/audio/pipeline.c:246 pipeline complete, clock freq 400000000Hz [131974988.906250] ( 420.260406) c0 pipe 6.32 src/audio/pipeline.c:246 pipeline complete, clock freq 400000000Hz [131975347.656250] ( 358.750000) c0 pipe 5.26 src/audio/pipeline.c:246 pipeline complete, clock freq 400000000Hz [131975523.906250] ( 176.250000) c0 pipe 3.20 src/audio/pipeline.c:246 pipeline complete, clock freq 400000000Hz [131975883.072917] ( 359.166656) c0 pipe 2.14 src/audio/pipeline.c:246 pipeline complete, clock freq 400000000Hz [131976302.708333] ( 419.635406) c0 pipe 1.7 src/audio/pipeline.c:246 pipeline complete, clock freq 400000000Hz [131976740.520833] ( 437.812500) c0 selector 12.62 ..../selector/selector.c:322 selector_cmd() [131976758.020833] ( 17.500000) c0 selector 12.62 ..../selector/selector.c:251 selector_ctrl_set_data(), SOF_CTRL_CMD_BINARY [131977501.302083] ( 743.281250) c0 kd-test 12.61 ..../audio/detect_test.c:556 test_keyword_cmd() [131977519.583333] ( 18.281250) c0 kd-test 12.61 ..../audio/detect_test.c:470 test_keyword_cmd_set_data(), SOF_CTRL_CMD_BINARY [131978267.239583] ( 747.656250) c0 kd-test 12.61 ..../audio/detect_test.c:556 test_keyword_cmd() [131978285.104167] ( 17.864584) c0 kd-test 12.61 ..../audio/detect_test.c:470 test_keyword_cmd_set_data(), SOF_CTRL_CMD_BINARY [131978302.916667] ( 17.812500) c0 kd-test 12.61 ..../audio/detect_test.c:410 test_keyword_set_config(), blob size = 32 [131979656.614583] ( 1353.697876) c0 sa src/lib/agent.c:79 WARN validate(), ll drift detected, delta = 42177 [131979716.250000] ( 59.635418) c0 pga 11.54 ../audio/volume/volume.c:561 volume_ctrl_set_cmd(), channel = 0, value = 65536 [131979735.208333] ( 18.958334) c0 pga 11.54 ../audio/volume/volume.c:561 volume_ctrl_set_cmd(), channel = 1, value = 65536 [131980457.239583] ( 722.031250) c0 pga 8.40 ../audio/volume/volume.c:561 volume_ctrl_set_cmd(), channel = 0, value = 65536 [131980473.854167] ( 16.614584) c0 pga 8.40 ../audio/volume/volume.c:561 volume_ctrl_set_cmd(), channel = 1, value = 65536 [131981126.250000] ( 652.395813) c0 pga 7.34 ../audio/volume/volume.c:561 volume_ctrl_set_cmd(), channel = 0, value = 65536 [131981143.125000] ( 16.875000) c0 pga 7.34 ../audio/volume/volume.c:561 volume_ctrl_set_cmd(), channel = 1, value = 65536 [131981641.406250] ( 498.281250) c0 pga 6.28 ../audio/volume/volume.c:561 volume_ctrl_set_cmd(), channel = 0, value = 65536 [131981658.593750] ( 17.187500) c0 pga 6.28 ../audio/volume/volume.c:561 volume_ctrl_set_cmd(), channel = 1, value = 65536 [131982283.072917] ( 624.479187) c0 pga 5.22 ../audio/volume/volume.c:561 volume_ctrl_set_cmd(), channel = 0, value = 65536 [131982299.322917] ( 16.250000) c0 pga 5.22 ../audio/volume/volume.c:561 volume_ctrl_set_cmd(), channel = 1, value = 65536 [131982952.291667] ( 652.968750) c0 pga 3.16 ../audio/volume/volume.c:561 volume_ctrl_set_cmd(), channel = 0, value = 65536 [131982968.854167] ( 16.562500) c0 pga 3.16 ../audio/volume/volume.c:561 volume_ctrl_set_cmd(), channel = 1, value = 65536 [131983650.416667] ( 681.562500) c0 pga 2.10 ../audio/volume/volume.c:561 volume_ctrl_set_cmd(), channel = 0, value = 65536 [131983667.031250] ( 16.614584) c0 pga 2.10 ../audio/volume/volume.c:561 volume_ctrl_set_cmd(), channel = 1, value = 65536 [131984290.781250] ( 623.750000) c0 demux 1.2 src/audio/mux/mux.c:343 mux_cmd() cmd = 0x00000066 [131984308.385417] ( 17.604166) c0 demux 1.2 src/audio/mux/mux.c:284 mux_ctrl_set_cmd(), cdata->cmd = 0x00000003 [131984325.989583] ( 17.604166) c0 demux 1.2 src/audio/mux/mux.c:100 mux_set_values() [131985087.187500] ( 761.197937) c0 pga 1.1 ../audio/volume/volume.c:561 volume_ctrl_set_cmd(), channel = 0, value = 65536 [131985103.802083] ( 16.614584) c0 pga 1.1 ../audio/volume/volume.c:561 volume_ctrl_set_cmd(), channel = 1, value = 65536 [131985597.187500] ( 493.385406) c0 ipc src/ipc/handler.c:606 ipc: pm -> restore [131986372.083333] ( 774.895813) c0 pipe 1.7 src/audio/pipeline.c:541 pipe params dir 0 frame_fmt 0 buffer_fmt 0 rate 48000 [131986390.208333] ( 18.125000) c0 pipe 1.7 src/audio/pipeline.c:545 pipe params stream_tag 1 channels 2 sample_valid_bytes 2 sample_container_bytes 2 [131986497.916667] ( 107.708336) c0 demux 1.2 src/audio/mux/mux.c:265 mux_params() [131986564.687500] ( 66.770836) c0 dai 1.6 src/audio/dai.c:351 dai_playback_params() dest_dev = 4 stream_id = 0 src_width = 2 dest_width = 2 [131986583.593750] ( 18.906250) c0 dai 1.6 src/audio/dai.c:357 dai_playback_params() fifo 0x77210 [131986611.458333] ( 27.864584) c0 pipe 1.7 src/audio/pipeline.c:668 pipe prepare [131986672.864583] ( 61.406250) c0 demux 1.2 src/audio/mux/mux.c:642 mux_prepare() [131989236.354167] ( 2563.489502) c0 pipe 1.7 src/audio/pipeline.c:863 pipe trigger cmd 1 [131989330.520833] ( 94.166664) c0 demux 1.2 src/audio/mux/mux.c:698 mux_trigger(), command = 1 [131989351.093750] ( 20.572916) c0 ssp-dai 1.1 /drivers/intel/ssp/ssp.c:843 ssp_trigger() cmd 1 [131989369.375000] ( 18.281250) c0 ssp-dai 1.1 /drivers/intel/ssp/ssp.c:631 ssp_pre_start() [131989426.979167] ( 57.604168) c0 mn ./drivers/intel/ssp/mn.c:264 mclk_rate 19200000, mclk_source_clock 1 [131989445.468750] ( 18.489584) c0 mn ./drivers/intel/ssp/mn.c:226 mclk_id 0 mdivr_val 2 [131989467.760417] ( 22.291666) c0 mn ./drivers/intel/ssp/mn.c:328 find_mn for freq 38400000 bclk 1536000 [131989485.729167] ( 17.968750) c0 mn ./drivers/intel/ssp/mn.c:633 bclk_rate 1536000, *out_scr_div 25, m 1, n 1 [131989503.385417] ( 17.656250) c0 ssp-dai 1.1 /drivers/intel/ssp/ssp.c:687 ssp_set_config(), sscr0 = 0xc1c0187f [131989522.812500] ( 19.427084) c0 ssp-dai 1.1 /drivers/intel/ssp/ssp.c:760 ssp_start() [131989645.520833] ( 122.708336) c0 ll-schedule ./schedule/ll_schedule.c:348 task add 0xbe090100 pipe-task [131989662.604167] ( 17.083334) c0 ll-schedule ./schedule/ll_schedule.c:352 task params pri 0 flags 0 start 0 period 1000 [131989683.177083] ( 20.572916) c0 ll-schedule ./schedule/ll_schedule.c:252 num_tasks 3 total_num_tasks 3 [131991619.218750] ( 1936.041626) c0 sa src/lib/agent.c:79 WARN validate(), ll drift detected, delta = 42519 [170507717.031250] ( 38516096.000000) c0 sa src/lib/agent.c:79 WARN validate(), ll drift detected, delta = 41003 [219535741.770833] ( 49028024.000000) c0 sa src/lib/agent.c:79 WARN validate(), ll drift detected, delta = 41007 [230141701.718750] ( 10605960.000000) c0 sa src/lib/agent.c:79 WARN validate(), ll drift detected, delta = 41023 [241587734.375000] ( 11446033.000000) c0 sa src/lib/agent.c:79 WARN validate(), ll drift detected, delta = 41012 [252069733.854167] ( 10481999.000000) c0 sa src/lib/agent.c:79 WARN validate(), ll drift detected, delta = 41025 [253667728.854167] ( 1597995.000000) c0 sa src/lib/agent.c:79 WARN validate(), ll drift detected, delta = 41013 [268751700.364583] ( 15083972.000000) c0 sa src/lib/agent.c:79 WARN validate(), ll drift detected, delta = 41014 [283257740.364583] ( 14506040.000000) c0 sa src/lib/agent.c:79 WARN validate(), ll drift detected, delta = 41024 [284153721.041667] ( 895980.687500) c0 sa src/lib/agent.c:79 WARN validate(), ll drift detected, delta = 41020 [292335710.416667] ( 8181989.500000) c0 sa src/lib/agent.c:79 WARN validate(), ll drift detected, delta = 41013 [293047734.322917] ( 712023.875000) c0 sa src/lib/agent.c:79 WARN validate(), ll drift detected, delta = 41005 [293993702.656250] ( 945968.312500) c0 sa src/lib/agent.c:79 WARN validate(), ll drift detected, delta = 41026 [296813722.708333] ( 2820020.000000) c0 sa src/lib/agent.c:79 WARN validate(), ll drift detected, delta = 41016 [302351714.218750] ( 5537991.500000) c0 sa src/lib/agent.c:79 WARN validate(), ll drift detected, delta = 41012 [306823744.791667] ( 4472030.500000) c0 sa src/lib/agent.c:79 WARN validate(), ll drift detected, delta = 41003 [314911742.135417] ( 8087997.500000) c0 sa src/lib/agent.c:79 WARN validate(), ll drift detected, delta = 41006 [320639733.072917] ( 5727991.000000) c0 sa src/lib/agent.c:79 WARN validate(), ll drift detected, delta = 41015 [326021722.031250] ( 5381989.000000) c0 sa src/lib/agent.c:79 WARN validate(), ll drift detected, delta = 41022 [330905733.020833] ( 4884011.000000) c0 sa src/lib/agent.c:79 WARN validate(), ll drift detected, delta = 41024 [337235703.072917] ( 6329970.000000) c0 sa src/lib/agent.c:79 WARN validate(), ll drift detected, delta = 41013 [350071726.979167] ( 12836024.000000) c0 sa src/lib/agent.c:79 WARN validate(), ll drift detected, delta = 41011 [352821741.562500] ( 2750014.500000) c0 sa src/lib/agent.c:79 WARN validate(), ll drift detected, delta = 41023