[BUG][SDW] Aplay+ arecord DMIC32 will cause IO error and dmesg shows ipc timed out for 0x60050000 size 12

[YvonneYang2]

Describe the bug
Aplay + arecord DMIC32 will cause IO error and dmesg shows ipc timed out for 0x60050000 size 12
Terminate it then areocrd or aplay can work.

To Reproduce
1.Aplay -Dhw:0,0 -r48000 -c2 -fs32_le 2.wav -vv -i
2.Arecord -Dhw:0,2 -r48000 -c4 -fs32_le 1.wav -vv -i

frequency
100%

Expected result
Both arecord normally

Actual result
Arecord stop and shows IO error after trigger arecord -Dhw:0,2

Environment
Firmware: 0223d94 https://github.com/thesofproject/sof/commits/sdw-001-drop-stable
+PR thesofproject/sof#1781
Kernel: a8f3408 https://github.com/thesofproject/linux/commits/integration/soundwire-alpha
Topology: file: same with firmware branch, sof-icl-rt700.m4
platform: ICL RVP with onboard ALC700 in SDW mode

dmesg

[   48.458137] sof-audio-pci 0000:00:1f.3: ipc tx: 0x60040000: GLB_STREAM_MSG: TRIG_START
[   48.458643] sof-audio-pci 0000:00:1f.3: ipc tx succeeded: 0x60040000: GLB_STREAM_MSG: TRIG_START
[   48.458645] plb: soc_pcm_trigger component name snd-soc-dummy
[   48.458647] plb: soc_pcm_trigger cpu_dai name DMIC01 2
[   48.458648] plb: soc_pcm_trigger codec_dai name dmic-hifi
[   48.458649] plb: soc_pcm_trigger component name 0000:00:1f.3
[   48.458650] plb: soc_pcm_trigger component name dmic-codec
[   48.458651] plb: soc_pcm_trigger cpu_dai name DMIC01 Pin
[   48.963067] plb: soc_pcm_trigger codec_dai name snd-soc-dummy-dai
[   48.963082] plb: soc_pcm_trigger component name 0000:00:1f.3
[   48.963100] sof-audio-pci 0000:00:1f.3: pcm: trigger stream 2 dir 1 cmd 0
[   48.963138] sof-audio-pci 0000:00:1f.3: FW Poll Status: reg=0x20340000 successful
[   48.963154] sof-audio-pci 0000:00:1f.3: ipc tx: 0x60050000: GLB_STREAM_MSG: TRIG_STOP
[   49.466973] sof-audio-pci 0000:00:1f.3: error: ipc timed out for 0x60050000 size 12
[   49.467003] sof-audio-pci 0000:00:1f.3: status: fw entered - code 00000005
[   49.467192] sof-audio-pci 0000:00:1f.3: error: unexpected fault 0x00000000 trace 0x00004000
[   49.467221] sof-audio-pci 0000:00:1f.3: error: hda irq intsts 0x00000000 intlctl 0xc0000083 rirb 00
[   49.467226] sof-audio-pci 0000:00:1f.3: error: dsp irq ppsts 0x00000000 adspis 0x00000000
[   49.467248] sof-audio-pci 0000:00:1f.3: error: host status 0x00000000 dsp status 0x00000000 mask 0x00000003
[   49.467252] sof-audio-pci 0000:00:1f.3: error: waking up any trace sleepers
[   49.467262]  DMIC01: ASoC: trigger FE failed -110
[   49.467319] sof-audio-pci 0000:00:1f.3: pcm: free stream 2 dir 1
[   49.467328] sof-audio-pci 0000:00:1f.3: ipc tx: 0x60030000: GLB_STREAM_MSG: PCM_FREE
[   49.970699] sof-audio-pci 0000:00:1f.3: error: ipc timed out for 0x60030000 size 12
[   49.970728] sof-audio-pci 0000:00:1f.3: status: fw entered - code 00000005
[   49.970919] sof-audio-pci 0000:00:1f.3: error: unexpected fault 0x00000000 trace 0x00004000
[   49.970948] sof-audio-pci 0000:00:1f.3: error: hda irq intsts 0x00000000 intlctl 0xc0000083 rirb 00
[   49.970953] sof-audio-pci 0000:00:1f.3: error: dsp irq ppsts 0x00000000 adspis 0x00000000
[   49.970975] sof-audio-pci 0000:00:1f.3: error: host status 0x00000000 dsp status 0x00000000 mask 0x00000003
[   49.970979] sof-audio-pci 0000:00:1f.3: error: waking up any trace sleepers
[   49.971149] sof-audio-pci 0000:00:1f.3: pcm: close stream 2 dir 1
[   50.123090] plb: soc_pcm_trigger codec_dai name snd-soc-dummy-dai
[   50.123105] plb: soc_pcm_trigger component name 0000:00:1f.3
[   50.123123] sof-audio-pci 0000:00:1f.3: pcm: trigger stream 0 dir 0 cmd 0
[   50.123158] sof-audio-pci 0000:00:1f.3: FW Poll Status: reg=0x20140000 successful
[   50.123174] sof-audio-pci 0000:00:1f.3: ipc tx: 0x60050000: GLB_STREAM_MSG: TRIG_STOP
[   50.138692] plb: soc_pcm_trigger codec_dai name snd-soc-dummy-dai
[   50.138704] plb: soc_pcm_trigger component name 0000:00:1f.3
[   50.138716] sof-audio-pci 0000:00:1f.3: pcm: trigger stream 1 dir 1 cmd 0
[   50.138772] sof-audio-pci 0000:00:1f.3: FW Poll Status: reg=0x20240000 successful
[   50.626986] sof-audio-pci 0000:00:1f.3: error: ipc timed out for 0x60050000 size 12
[   50.627015] sof-audio-pci 0000:00:1f.3: status: fw entered - code 00000005
[   50.627208] sof-audio-pci 0000:00:1f.3: error: unexpected fault 0x00000000 trace 0x00004000
[   50.627237] sof-audio-pci 0000:00:1f.3: error: hda irq intsts 0x00000000 intlctl 0xc0000001 rirb 00
[   50.627241] sof-audio-pci 0000:00:1f.3: error: dsp irq ppsts 0x00000000 adspis 0x00000000
[   50.627263] sof-audio-pci 0000:00:1f.3: error: host status 0x00000000 dsp status 0x00000000 mask 0x00000003
[   50.627268] sof-audio-pci 0000:00:1f.3: error: waking up any trace sleepers
[   50.627284]  SDW0-speakers: ASoC: trigger FE failed -110
[   50.627344] sof-audio-pci 0000:00:1f.3: pcm: free stream 0 dir 0
[   50.627353] sof-audio-pci 0000:00:1f.3: ipc tx: 0x60030000: GLB_STREAM_MSG: PCM_FREE
[   51.130883] sof-audio-pci 0000:00:1f.3: error: ipc timed out for 0x60030000 size 12
[   51.130912] sof-audio-pci 0000:00:1f.3: status: fw entered - code 00000005
[   51.131103] sof-audio-pci 0000:00:1f.3: error: unexpected fault 0x00000000 trace 0x00004000
[   51.131133] sof-audio-pci 0000:00:1f.3: error: hda irq intsts 0x00000000 intlctl 0xc0000001 rirb 00
[   51.131137] sof-audio-pci 0000:00:1f.3: error: dsp irq ppsts 0x00000000 adspis 0x00000000
[   51.131160] sof-audio-pci 0000:00:1f.3: error: host status 0x00000000 dsp status 0x00000000 mask 0x00000003
[   51.131164] sof-audio-pci 0000:00:1f.3: error: waking up any trace sleepers
[   51.131488] sof-audio-pci 0000:00:1f.3: ipc tx: 0x60050000: GLB_STREAM_MSG: TRIG_STOP
[   51.634475] sof-audio-pci 0000:00:1f.3: error: ipc timed out for 0x60050000 size 12
[   51.634506] sof-audio-pci 0000:00:1f.3: status: fw entered - code 00000005
[   51.634838] sof-audio-pci 0000:00:1f.3: error: unexpected fault 0x00000000 trace 0x00004000
[   51.634876] sof-audio-pci 0000:00:1f.3: error: hda irq intsts 0x00000000 intlctl 0xc0000001 rirb 00
[   51.634880] sof-audio-pci 0000:00:1f.3: error: dsp irq ppsts 0x00000000 adspis 0x00000000
[   51.634908] sof-audio-pci 0000:00:1f.3: error: host status 0x00000000 dsp status 0x00000000 mask 0x00000003
[   51.634912] sof-audio-pci 0000:00:1f.3: error: waking up any trace sleepers
[   51.634929]  SDW0-mics: ASoC: trigger FE failed -110
[   51.634993] sof-audio-pci 0000:00:1f.3: ipc tx: 0x80010000: GLB_DAI_MSG: CONFIG
[   52.138496] sof-audio-pci 0000:00:1f.3: error: ipc timed out for 0x80010000 size 120
[   52.138517] sof-audio-pci 0000:00:1f.3: status: fw entered - code 00000005
[   52.138967] sof-audio-pci 0000:00:1f.3: error: unexpected fault 0x00000000 trace 0x00004000
[   52.139001] sof-audio-pci 0000:00:1f.3: error: hda irq intsts 0x00000000 intlctl 0xc0000001 rirb 00
[   52.139005] sof-audio-pci 0000:00:1f.3: error: dsp irq ppsts 0x00000000 adspis 0x00000000
[   52.139039] sof-audio-pci 0000:00:1f.3: error: host status 0x00000000 dsp status 0x00000000 mask 0x00000003
[   52.139043] sof-audio-pci 0000:00:1f.3: error: waking up any trace sleepers
[   52.139048] sof-audio-pci 0000:00:1f.3: error: failed to free stream for link 0 dai->id 2

arecord_02_cause_IO_error_logger.log
arecord_02_cause_IOerror_dmesg.log

[plbossart]

please retest with firmware master and kernel integration/soundwire-latest branch

[plbossart]

also please check on both CML and ICL, this looks like a duplicate of #1198

[ClarexZhou]

Error still seen on ICL with firmware master and kernel integration/soundwire-latest branch.
Behavior and Dmesg is different for CML ALC700 and ICL ALC700

CML ALC700 is track on #1198
And current issue seems same with thesofproject/sof#1839
@plbossart , will this issue be track in kernel or firmware?

[ClarexZhou]

thesofproject/sof#1839 to check first

[plbossart]

Is this issue still valid @ClarexZhou @mengdonglin ?

[ClarexZhou]

IPC timed out for 0x60050000: GLB_STREAM_MSG: TRIG_STOP occurred during multipipline stress test.
Failed at 54th time, use below stress test scripts and run stress test via "./multiplex_pipeline-test.sh pcm0p pcm4p pcm1c pcm2c pcm3c"

multiplex_pipeline-test.sh.txt

Environment
Firmware: e755494 Master
Kernel: d2b55e0 on integration/soundwire-latest
Topology: file: same with firmware branch, sof-cml-rt700.m4
platform: CML RVP with onboard ALC700 in SDW mode
Other: Runtime PM status is enabled (without debug=1 options)

Dmesg

[ 1791.900263] sof-audio-pci 0000:00:1f.3: ipc tx succeeded: 0x60040000: GLB_STREAM_MSG: TRIG_START
[ 1791.900266] sof-audio-pci 0000:00:1f.3: In hda_link_pcm_trigger cmd=1
[ 1791.902220] rt700 sdw:1:25d:700:0:0: rt700_clock_config complete, clk_freq=6000000
[ 1791.904254] rt700 sdw:1:25d:700:0:0: rt700_clock_config complete, clk_freq=6000000
[ 1791.906705] sof-audio-pci 0000:00:1f.3: pcm: trigger stream 0 dir 0 cmd 1
[ 1791.906729] sof-audio-pci 0000:00:1f.3: FW Poll Status: reg=0x14001e successful
[ 1791.906730] sof-audio-pci 0000:00:1f.3: pcm: trigger stream 2 dir 1 cmd 1
[ 1791.906731] sof-audio-pci 0000:00:1f.3: ipc tx: 0x60040000: GLB_STREAM_MSG: TRIG_START
[ 1791.906754] sof-audio-pci 0000:00:1f.3: pcm: trigger stream 3 dir 1 cmd 1
[ 1791.906761] sof-audio-pci 0000:00:1f.3: FW Poll Status: reg=0x2034001e successful
[ 1791.906950] sof-audio-pci 0000:00:1f.3: ipc tx succeeded: 0x60040000: GLB_STREAM_MSG: TRIG_START
[ 1791.906953] sof-audio-pci 0000:00:1f.3: ipc tx: 0x60040000: GLB_STREAM_MSG: TRIG_START
[ 1791.907148] sof-audio-pci 0000:00:1f.3: ipc tx succeeded: 0x60040000: GLB_STREAM_MSG: TRIG_START
[ 1791.907312] sof-audio-pci 0000:00:1f.3: FW Poll Status: reg=0x2044001e successful
[ 1791.907314] sof-audio-pci 0000:00:1f.3: ipc tx: 0x60040000: GLB_STREAM_MSG: TRIG_START
[ 1791.907553] rt700 sdw:1:25d:700:0:0: rt700_clock_config complete, clk_freq=6000000
[ 1791.907577] sof-audio-pci 0000:00:1f.3: ipc tx succeeded: 0x60040000: GLB_STREAM_MSG: TRIG_START
[ 1791.909164] rt700 sdw:1:25d:700:0:0: rt700_clock_config complete, clk_freq=6000000
[ 1791.912206] rt700 sdw:1:25d:700:0:0: rt700_clock_config complete, clk_freq=6000000
[ 1791.914182] rt700 sdw:1:25d:700:0:0: rt700_clock_config complete, clk_freq=6000000
[ 1792.411109] sof-audio-pci 0000:00:1f.3: pcm: trigger stream 2 dir 1 cmd 0
[ 1792.411116] sof-audio-pci 0000:00:1f.3: ipc tx: 0x60050000: GLB_STREAM_MSG: TRIG_STOP
[ 1792.914890] sof-audio-pci 0000:00:1f.3: error: ipc timed out for 0x60050000 size 12
[ 1792.914893] sof-audio-pci 0000:00:1f.3: info: preventing DSP entering D3 state to preserve context
[ 1792.914899] sof-audio-pci 0000:00:1f.3: status: fw entered - code 00000005
[ 1792.914970] sof-audio-pci 0000:00:1f.3: error: unexpected fault 0x00000000 trace 0x00004000
[ 1792.914976] sof-audio-pci 0000:00:1f.3: error: hda irq intsts 0x00000000 intlctl 0xc000018f rirb 00
[ 1792.914977] sof-audio-pci 0000:00:1f.3: error: dsp irq ppsts 0x00000000 adspis 0x00000000
[ 1792.914982] sof-audio-pci 0000:00:1f.3: error: host status 0x00000000 dsp status 0x00000000 mask 0x00000003
[ 1792.914983] sof-audio-pci 0000:00:1f.3: error: waking up any trace sleepers
[ 1792.915069] sof-audio-pci 0000:00:1f.3: error: trace IO error
[ 1792.915070] sof-audio-pci 0000:00:1f.3: error: trace IO error
[ 1792.915070] sof-audio-pci 0000:00:1f.3: error: trace IO error
[ 1792.915071] sof-audio-pci 0000:00:1f.3: error: trace IO error
[ 1792.915072] sof-audio-pci 0000:00:1f.3: error: trace IO error
[ 1792.915073] sof-audio-pci 0000:00:1f.3: error: trace IO error
[ 1792.915073] sof-audio-pci 0000:00:1f.3: error: trace IO error
[ 1792.915074] sof-audio-pci 0000:00:1f.3: error: trace IO error
[ 1792.915075] sof-audio-pci 0000:00:1f.3: error: trace IO error
[ 1792.915076] sof-audio-pci 0000:00:1f.3: error: trace IO error
[ 1792.915076] sof-audio-pci 0000:00:1f.3: error: trace IO error
[ 1792.915078] sof-audio-pci 0000:00:1f.3: FW Poll Status: reg=0x20340000 successful
[ 1792.915084]  DMIC01: ASoC: trigger FE failed -110
[ 1792.915100] sof-audio-pci 0000:00:1f.3: pcm: free stream 2 dir 1
[ 1792.915102] sof-audio-pci 0000:00:1f.3: ipc tx: 0x60030000: GLB_STREAM_MSG: PCM_FREE
[ 1792.915106] sof-audio-pci 0000:00:1f.3: error: trace IO error
[ 1792.915113] sof-audio-pci 0000:00:1f.3: error: trace IO error
[ 1792.915195] sof-audio-pci 0000:00:1f.3: error: trace IO error
[ 1792.915197] sof-audio-pci 0000:00:1f.3: error: trace IO error
[ 1792.915212] sof-audio-pci 0000:00:1f.3: error: trace IO error
[ 1792.915213] sof-audio-pci 0000:00:1f.3: error: trace IO error
[ 1792.915214] sof-audio-pci 0000:00:1f.3: error: trace IO error
[ 1792.915215] sof-audio-pci 0000:00:1f.3: error: trace IO error
[ 1792.915215] sof-audio-pci 0000:00:1f.3: error: trace IO error
[ 1792.915224] sof-audio-pci 0000:00:1f.3: error: trace IO error
[ 1792.915225] sof-audio-pci 0000:00:1f.3: error: trace IO error
[ 1792.915226] sof-audio-pci 0000:00:1f.3: error: trace IO error
[ 1792.915226] sof-audio-pci 0000:00:1f.3: error: trace IO error
[ 1792.915228] sof-audio-pci 0000:00:1f.3: error: trace IO error
[ 1792.915309] sof-audio-pci 0000:00:1f.3: error: trace IO error
[ 1792.915318] sof-audio-pci 0000:00:1f.3: error: trace IO error
[ 1792.915321] sof-audio-pci 0000:00:1f.3: error: trace IO error
[ 1792.915323] sof-audio-pci 0000:00:1f.3: error: trace IO error
[ 1792.915323] sof-audio-pci 0000:00:1f.3: error: trace IO error
[ 1792.915325] sof-audio-pci 0000:00:1f.3: error: trace IO error
[ 1792.915326] sof-audio-pci 0000:00:1f.3: error: trace IO error
[ 1792.915343] sof-audio-pci 0000:00:1f.3: error: trace IO error
[ 1792.915343] sof-audio-pci 0000:00:1f.3: error: trace IO error
[ 1792.915351] sof-audio-pci 0000:00:1f.3: error: trace IO error
[ 1792.915353] sof-audio-pci 0000:00:1f.3: error: trace IO error
[ 1792.915353] sof-audio-pci 0000:00:1f.3: error: trace IO error
[ 1792.915397] sof-audio-pci 0000:00:1f.3: error: trace IO error
[ 1792.915413] sof-audio-pci 0000:00:1f.3: error: trace IO error
[ 1792.915414] sof-audio-pci 0000:00:1f.3: error: trace IO error
[ 1792.915420] sof-audio-pci 0000:00:1f.3: error: trace IO error
[ 1792.915424] sof-audio-pci 0000:00:1f.3: error: trace IO error
[ 1792.915426] sof-audio-pci 0000:00:1f.3: error: trace IO error
[ 1792.915429] sof-audio-pci 0000:00:1f.3: error: trace IO error
[ 1792.915435] sof-audio-pci 0000:00:1f.3: error: trace IO error
[ 1792.915455] sof-audio-pci 0000:00:1f.3: error: trace IO error
[ 1792.915455] sof-audio-pci 0000:00:1f.3: error: trace IO error
[ 1792.915463] sof-audio-pci 0000:00:1f.3: error: trace IO error
[ 1792.915508] sof-audio-pci 0000:00:1f.3: error: trace IO error
[ 1792.915537] sof-audio-pci 0000:00:1f.3: error: trace IO error
[ 1792.915543] sof-audio-pci 0000:00:1f.3: error: trace IO error
[ 1792.915543] sof-audio-pci 0000:00:1f.3: error: trace IO error
[ 1792.915547] sof-audio-pci 0000:00:1f.3: error: trace IO error
[ 1792.915550] sof-audio-pci 0000:00:1f.3: error: trace IO error
[ 1792.915584] sof-audio-pci 0000:00:1f.3: error: trace IO error
[ 1792.915585] sof-audio-pci 0000:00:1f.3: error: trace IO error
[ 1793.418674] sof-audio-pci 0000:00:1f.3: error: ipc timed out for 0x60030000 size 12
[ 1793.418678] sof-audio-pci 0000:00:1f.3: info: preventing DSP entering D3 state to preserve context
[ 1793.418690] sof-audio-pci 0000:00:1f.3: status: fw entered - code 00000005
[ 1793.418765] sof-audio-pci 0000:00:1f.3: error: unexpected fault 0x00000000 trace 0x00004000
[ 1793.418771] sof-audio-pci 0000:00:1f.3: error: hda irq intsts 0x00000000 intlctl 0xc000018b rirb 00
[ 1793.418772] sof-audio-pci 0000:00:1f.3: error: dsp irq ppsts 0x00000000 adspis 0x00000000
[ 1793.418779] sof-audio-pci 0000:00:1f.3: error: host status 0x00000000 dsp status 0x00000000 mask 0x00000003
[ 1793.418780] sof-audio-pci 0000:00:1f.3: error: waking up any trace sleepers
[ 1793.418837] sof-audio-pci 0000:00:1f.3: pcm: close stream 2 dir 1
[ 1793.619229] sof-audio-pci 0000:00:1f.3: pcm: trigger stream 4 dir 0 cmd 0
[ 1793.619239] sof-audio-pci 0000:00:1f.3: ipc tx: 0x60050000: GLB_STREAM_MSG: TRIG_STOP
[ 1793.635016] sof-audio-pci 0000:00:1f.3: pcm: trigger stream 0 dir 0 cmd 0
[ 1794.122924] sof-audio-pci 0000:00:1f.3: error: ipc timed out for 0x60050000 size 12
[ 1794.122932] sof-audio-pci 0000:00:1f.3: info: preventing DSP entering D3 state to preserve context
[ 1794.122946] sof-audio-pci 0000:00:1f.3: status: fw entered - code 00000005
[ 1794.123036] sof-audio-pci 0000:00:1f.3: error: unexpected fault 0x00000000 trace 0x00004000
[ 1794.123047] sof-audio-pci 0000:00:1f.3: error: hda irq intsts 0x00000000 intlctl 0xc000018b rirb 00
[ 1794.123050] sof-audio-pci 0000:00:1f.3: error: dsp irq ppsts 0x00000000 adspis 0x00000000
[ 1794.123063] sof-audio-pci 0000:00:1f.3: error: host status 0x00000000 dsp status 0x00000000 mask 0x00000003
[ 1794.123066] sof-audio-pci 0000:00:1f.3: error: waking up any trace sleepers
[ 1794.123088] sof-audio-pci 0000:00:1f.3: FW Poll Status: reg=0x20240000 successful
[ 1794.123095]  HDMI1: ASoC: trigger FE failed -110
[ 1794.123119] sof-audio-pci 0000:00:1f.3: pcm: free stream 4 dir 0
[ 1794.123125] sof-audio-pci 0000:00:1f.3: ipc tx: 0x60030000: GLB_STREAM_MSG: PCM_FREE
[ 1794.626671] sof-audio-pci 0000:00:1f.3: error: ipc timed out for 0x60030000 size 12
[ 1794.626674] sof-audio-pci 0000:00:1f.3: info: preventing DSP entering D3 state to preserve context
[ 1794.626682] sof-audio-pci 0000:00:1f.3: status: fw entered - code 00000005
[ 1794.626756] sof-audio-pci 0000:00:1f.3: error: unexpected fault 0x00000000 trace 0x00004000
[ 1794.626762] sof-audio-pci 0000:00:1f.3: error: hda irq intsts 0x00000000 intlctl 0xc000008b rirb 00
[ 1794.626763] sof-audio-pci 0000:00:1f.3: error: dsp irq ppsts 0x00000000 adspis 0x00000000
[ 1794.626769] sof-audio-pci 0000:00:1f.3: error: host status 0x00000000 dsp status 0x00000000 mask 0x00000003
[ 1794.626770] sof-audio-pci 0000:00:1f.3: error: waking up any trace sleepers
[ 1794.626811] sof-audio-pci 0000:00:1f.3: ipc tx: 0x60050000: GLB_STREAM_MSG: TRIG_STOP
[ 1794.735138] sof-audio-pci 0000:00:1f.3: pcm: trigger stream 1 dir 1 cmd 0
[ 1794.735160] sof-audio-pci 0000:00:1f.3: pcm: trigger stream 3 dir 1 cmd 0
[ 1795.130990] sof-audio-pci 0000:00:1f.3: error: ipc timed out for 0x60050000 size 12
[ 1795.130999] sof-audio-pci 0000:00:1f.3: info: preventing DSP entering D3 state to preserve context
[ 1795.131015] sof-audio-pci 0000:00:1f.3: status: fw entered - code 00000005
[ 1795.131109] sof-audio-pci 0000:00:1f.3: error: unexpected fault 0x00000000 trace 0x00004000
[ 1795.131119] sof-audio-pci 0000:00:1f.3: error: hda irq intsts 0x00000000 intlctl 0xc000008b rirb 00
[ 1795.131123] sof-audio-pci 0000:00:1f.3: error: dsp irq ppsts 0x00000000 adspis 0x00000000
[ 1795.131139] sof-audio-pci 0000:00:1f.3: error: host status 0x00000000 dsp status 0x00000000 mask 0x00000003
[ 1795.131142] sof-audio-pci 0000:00:1f.3: error: waking up any trace sleepers
[ 1795.131165] sof-audio-pci 0000:00:1f.3: FW Poll Status: reg=0x140000 successful
[ 1795.131174]  SDW1-speakers: ASoC: trigger FE failed -110
[ 1795.131224] sof-audio-pci 0000:00:1f.3: ipc tx: 0x80010000: GLB_DAI_MSG: CONFIG
[ 1795.634974] sof-audio-pci 0000:00:1f.3: error: ipc timed out for 0x80010000 size 120
[ 1795.634982] sof-audio-pci 0000:00:1f.3: info: preventing DSP entering D3 state to preserve context
[ 1795.634994] sof-audio-pci 0000:00:1f.3: status: fw entered - code 00000005
[ 1795.635088] sof-audio-pci 0000:00:1f.3: error: unexpected fault 0x00000000 trace 0x00004000
[ 1795.635100] sof-audio-pci 0000:00:1f.3: error: hda irq intsts 0x00000000 intlctl 0xc000000b rirb 00
[ 1795.635103] sof-audio-pci 0000:00:1f.3: error: dsp irq ppsts 0x00000000 adspis 0x00000000
[ 1795.635119] sof-audio-pci 0000:00:1f.3: error: host status 0x00000000 dsp status 0x00000000 mask 0x00000003
[ 1795.635123] sof-audio-pci 0000:00:1f.3: error: waking up any trace sleepers
[ 1795.635132] sof-audio-pci 0000:00:1f.3: error: failed to set dai config for iDisp1
[ 1795.635151] sof-audio-pci 0000:00:1f.3: pcm: close stream 4 dir 0
[ 1795.635203] sof-audio-pci 0000:00:1f.3: ipc tx: 0x60050000: GLB_STREAM_MSG: TRIG_STOP

error sof logger

   0      1         DMIC         2245801105.885417        12.083333 rs/intel/cavs/dmic.c:1503	dmic_irq_handler() error: full fifo A or PDM overrrun
    0      1         DMIC         2245801117.343750        11.458333 rs/intel/cavs/dmic.c:1503	dmic_irq_handler() error: full fifo A or PDM overrrun
    0      1         DMIC         2245801129.479167        12.135417 rs/intel/cavs/dmic.c:1503	dmic_irq_handler() error: full fifo A or PDM overrrun
    0      1         DMIC         2245801140.937500        11.458333 rs/intel/cavs/dmic.c:1503	dmic_irq_handler() error: full fifo A or PDM overrrun
    0      1         DMIC         2245801153.020833        12.083333 rs/intel/cavs/dmic.c:1503	dmic_irq_handler() error: full fifo A or PDM overrrun
    0      1         DMIC         2245801164.479167        11.458333 rs/intel/cavs/dmic.c:1503	dmic_irq_handler() error: full fifo A or PDM overrrun
    0      1         DMIC         2245801176.562500        12.083333 rs/intel/cavs/dmic.c:1503	dmic_irq_handler() error: full fifo A or PDM overrrun
    0      1         DMIC         2245801188.020833        11.458333 rs/intel/cavs/dmic.c:1503	dmic_irq_handler() error: full fifo A or PDM overrrun
    0      1         DMIC         2245801200.104167        12.083333 rs/intel/cavs/dmic.c:1503	dmic_irq_handler() error: full fifo A or PDM overrrun
    0      1         DMIC         2245801211.614583        11.510417 rs/intel/cavs/dmic.c:1503	dmic_irq_handler() error: full fifo A or PDM overrrun
    0      1         DMIC         2245801223.750000        12.135417 rs/intel/cavs/dmic.c:1503	dmic_irq_handler() error: full fifo A or PDM overrrun
    0      1         DMIC         2245801235.260417        11.510417 rs/intel/cavs/dmic.c:1503	dmic_irq_handler() error: full fifo A or PDM overrrun
    0      1         DMIC         2245801247.395833        12.135417 rs/intel/cavs/dmic.c:1503	dmic_irq_handler() error: full fifo A or PDM overrrun
    0      1         DMIC         2245801258.906250        11.510417 rs/intel/cavs/dmic.c:1503	dmic_irq_handler() error: full fifo A or PDM overrrun
    0      1         DMIC         2245801271.041667        12.135417 rs/intel/cavs/dmic.c:1503	dmic_irq_handler() error: full fifo A or PDM overrrun
    0      1         DMIC         2245801282.552083        11.510417 rs/intel/cavs/dmic.c:1503	dmic_irq_handler() error: full fifo A or PDM overrrun
    0      1         DMIC         2245801294.635417        12.083333 rs/intel/cavs/dmic.c:1503	dmic_irq_handler() error: full fifo A or PDM overrrun
    0      1         DMIC         2245801306.093750        11.458333 rs/intel/cavs/dmic.c:1503	dmic_irq_handler() error: full fifo A or PDM overrrun
    0      1          DAI 4.18    2245801331.770833        25.677084      src/audio/dai.c:591 	dai_check_for_xrun() error: overrun due to no data available
    0      1         COMP         2245801336.614583         4.843750 of/audio/component.h:759 	comp_overrun() error: dev->comp.id = 18, sink->free = 0, copy_bytes = 0
    0      1         PIPE         2245801388.906250        52.291668 src/audio/pipeline.c:847 	pipeline_copy() error: ret = -61, start->comp.id = 18, dir = 0
    0      1         PIPE 4.19    2245801395.052083         6.145833 src/audio/pipeline.c:1030	pipeline_task(): xrun recover failed! pipeline will be stopped!
    0      1         DMIC         2245794820.625000              nan rs/intel/cavs/dmic.c:1503	dmic_irq_handler() error: full fifo A or PDM overrrun
    0      1         DMIC         2245794832.135417        11.510417 rs/intel/cavs/dmic.c:1503	dmic_irq_handler() error: full fifo A or PDM overrrun
    0      1         DMIC         2245794844.218750        12.083333 rs/intel/cavs/dmic.c:1503	dmic_irq_handler() error: full fifo A or PDM overrrun
    0      1         DMIC         2245794855.677083        11.458333 rs/intel/cavs/dmic.c:1503	dmic_irq_handler() error: full fifo A or PDM overrrun
    0      1         DMIC         2245794867.760417        12.083333 rs/intel/cavs/dmic.c:1503	dmic_irq_handler() error: full fifo A or PDM overrrun
    0      1         DMIC         2245794879.218750        11.458333 rs/intel/cavs/dmic.c:1503	dmic_irq_handler() error: full fifo A or PDM overrrun
    0      1         DMIC         2245794891.354167        12.135417 rs/intel/cavs/dmic.c:1503	dmic_irq_handler() error: full fifo A or PDM overrrun
    0      1         DMIC         2245794902.864583        11.510417 rs/intel/cavs/dmic.c:1503	dmic_irq_handler() error: full fifo A or PDM overrrun
    0      1         DMIC         2245794914.947917        12.083333 rs/intel/cavs/dmic.c:1503	dmic_irq_handler() error: full fifo A or PDM overrrun
    0      1         DMIC         2245794926.406250        11.458333 rs/intel/cavs/dmic.c:1503	dmic_irq_handler() error: full fifo A or PDM overrrun
    0      1         DMIC         2245794938.541667        12.135417 rs/intel/cavs/dmic.c:1503	dmic_irq_handler() error: full fifo A or PDM overrrun
    0      1         DMIC         2245794950.052083        11.510417 rs/intel/cavs/dmic.c:1503	dmic_irq_handler() error: full fifo A or PDM overrrun
    0      1         DMIC         2245794962.187500        12.135417 rs/intel/cavs/dmic.c:1503	dmic_irq_handler() error: full fifo A or PDM overrrun
    0      1         DMIC         2245794973.697917        11.510417 rs/intel/cavs/dmic.c:1503	dmic_irq_handler() error: full fifo A or PDM overrrun
    0      1         DMIC         2245794985.833333        12.135417 rs/intel/cavs/dmic.c:1503	dmic_irq_handler() error: full fifo A or PDM overrrun
    0      1         DMIC         2245794997.343750        11.510417 rs/intel/cavs/dmic.c:1503	dmic_irq_handler() error: full fifo A or PDM overrrun
    0      1         DMIC         2245795009.479167        12.135417 rs/intel/cavs/dmic.c:1503	dmic_irq_handler() error: full fifo A or PDM overrrun
    0      1         DMIC         2245795020.989583        11.510417 rs/intel/cavs/dmic.c:1503	dmic_irq_handler() error: full fifo A or PDM overrrun
    0      1         DMIC         2245795033.125000        12.135417 rs/intel/cavs/dmic.c:1503	dmic_irq_handler() error: full fifo A or PDM overrrun
    0      1         DMIC         2245795044.635417        11.510417 rs/intel/cavs/dmic.c:1503	dmic_irq_handler() error: fu

multipipeline_test_54_logger_error.txt
multipipeline_test_54_logger.txt
multipipeline_test_54_dmesg.txt

[YvonneYang2]

Same issue occurred when run "./multiplex_pipeline-test.sh pcm0p pcm4p pcm1c pcm3c" 32th times on icl rt700 rvp.

Environment
Firmware: e755494 Master
Kernel: d2b55e0 on integration/soundwire-latest
Topology: file: same with firmware branch, sof-icl-rt700.m4
platform: ICL RVP with onboard ALC700 in SDW mode
Other: Runtime PM status is enabled (without debug=1 options)

Dmesg

[  845.554136] rt700 sdw:0:25d:700:0:0: rt700_clock_config complete, clk_freq=4800000
[  846.053764] sof-audio-pci 0000:00:1f.3: pcm: trigger stream 3 dir 1 cmd 0
[  846.053773] sof-audio-pci 0000:00:1f.3: ipc tx: 0x60050000: GLB_STREAM_MSG: TRIG_STOP
[  846.557732] sof-audio-pci 0000:00:1f.3: error: ipc timed out for 0x60050000 size 12
[  846.557741] sof-audio-pci 0000:00:1f.3: info: preventing DSP entering D3 state to preserve context
[  846.557754] sof-audio-pci 0000:00:1f.3: status: fw entered - code 00000005
[  846.557877] sof-audio-pci 0000:00:1f.3: error: unexpected fault 0x00000000 trace 0x00004000
[  846.557905] sof-audio-pci 0000:00:1f.3: error: hda irq intsts 0x00000000 intlctl 0xc0000187 rirb 00
[  846.557908] sof-audio-pci 0000:00:1f.3: error: dsp irq ppsts 0x00000000 adspis 0x00000000
[  846.557923] sof-audio-pci 0000:00:1f.3: error: host status 0x00000000 dsp status 0x00000000 mask 0x00000003
[  846.557927] sof-audio-pci 0000:00:1f.3: error: waking up any trace sleepers
[  846.558064] sof-audio-pci 0000:00:1f.3: error: trace IO error
[  846.558071] sof-audio-pci 0000:00:1f.3: error: trace IO error

error logger

 CORE  LEVEL      COMP_ID                TIMESTAMP            DELTA                FILE_NAME    CONTENT
    0      1         DMIC         1690237457.968750              nan rs/intel/cavs/dmic.c:1506  dmic_irq_handler() error: full fifo B or PDM overrrun
    0      1         DMIC         1690237473.697917        15.729167 rs/intel/cavs/dmic.c:1506  dmic_irq_handler() error: full fifo B or PDM overrrun
    0      1         DMIC         1690237492.708333        19.010416 rs/intel/cavs/dmic.c:1506  dmic_irq_handler() error: full fifo B or PDM overrrun
    0      1         DMIC         1690237510.625000        17.916666 rs/intel/cavs/dmic.c:1506  dmic_irq_handler() error: full fifo B or PDM overrrun
    0      1         DMIC         1690237529.479167        18.854166 rs/intel/cavs/dmic.c:1506  dmic_irq_handler() error: full fifo B or PDM overrrun
    0      1         DMIC         1690237547.343750        17.864584 rs/intel/cavs/dmic.c:1506  dmic_irq_handler() error: full fifo B or PDM overrrun
    0      1         DMIC         1690237566.197917        18.854166 rs/intel/cavs/dmic.c:1506  dmic_irq_handler() error: full fifo B or PDM overrrun
    0      1         DMIC         1690237584.114583        17.916666 rs/intel/cavs/dmic.c:1506  dmic_irq_handler() error: full fifo B or PDM overrrun
    0      1         DMIC         1690237603.072917        18.958334 rs/intel/cavs/dmic.c:1506  dmic_irq_handler() error: full fifo B or PDM overrrun
    0      1         DMIC         1690237620.989583        17.916666 rs/intel/cavs/dmic.c:1506  dmic_irq_handler() error: full fifo B or PDM overrrun
    0      1         DMIC         1690237639.843750        18.854166 rs/intel/cavs/dmic.c:1506  dmic_irq_handler() error: full fifo B or PDM overrrun
    0      1         DMIC         1690237657.708333        17.864584 rs/intel/cavs/dmic.c:1506  dmic_irq_handler() error: full fifo B or PDM 

multipipeline_test_32_logger_error.log
multipipeline_test_32logger.log
multipipeline_test_32_dmesg.log

[bardliao]

Same issue occurred when run "./multiplex_pipeline-test.sh pcm0p pcm4p pcm1c pcm3c" 32th times on icl rt700 rvp.

@YvonneYang2 Does it mean it works at the first 31 times?

[ClarexZhou]

Same issue occurred when run "./multiplex_pipeline-test.sh pcm0p pcm4p pcm1c pcm3c" 32th times on icl rt700 rvp.

@YvonneYang2 Does it mean it works at the first 31 times?

Yes. Works at first 31 times. Failed from 32th, then always failed.

[YvonneYang2]

Replaced by https://github.com/thesofproject/sof/issues/1928