LNL HDA: multiple-pipeline-playback-50 failure

[plbossart]

Intel daily test

#/result/planresultdetail/37931?model=LNLM_RVP_HDA&testcase=multiple-pipeline-playback-50

This happened multiple days in a row, unfortunately there are no dmesg or mtrace logs available.

@marc-hb @fredoh9 @keqiaozhang can you try to find the logs for us please?

[plbossart]

This could be related to #4822 as well

[marc-hb]

@marc-hb @fredoh9 @keqiaozhang can you try to find the logs for us please?

I can but you can too with just ssh ; journalctl --list-boots; journalctl -b -8 -p 5

I found no error in the logs, they just stop like this:

```` Feb 14 21:43:08 sh-lnlm-rvp-hda-01 kernel: snd_sof:sof_ipc4_log_header: sof-audio-pci-intel-lnl 0000:00:1f.3: ipc tx done : 0x13000004|0x0: GLB_SET_PIPELINE_STATE Feb 14 21:43:09 sh-lnlm-rvp-hda-01 kernel: snd_sof_intel_hda_common:hda_dai_trigger: sof-audio-pci-intel-lnl 0000:00:1f.3: cmd=0 dai DMIC01 Pin direction 1 Feb 14 21:43:09 sh-lnlm-rvp-hda-01 kernel: snd_sof:sof_ipc4_set_pipeline_state: sof-audio-pci-intel-lnl 0000:00:1f.3: ipc4 set pipeline instance 0 state 3 Feb 14 21:43:09 sh-lnlm-rvp-hda-01 kernel: snd_sof:sof_ipc4_log_header: sof-audio-pci-intel-lnl 0000:00:1f.3: ipc tx : 0x13000003|0x0: GLB_SET_PIPELINE_STATE Feb 14 21:43:09 sh-lnlm-rvp-hda-01 kernel: snd_sof:sof_ipc4_log_header: sof-audio-pci-intel-lnl 0000:00:1f.3: ipc tx reply: 0x33000000|0x0: GLB_SET_PIPELINE_STATE Feb 14 21:43:09 sh-lnlm-rvp-hda-01 kernel: snd_sof:sof_ipc4_log_header: sof-audio-pci-intel-lnl 0000:00:1f.3: ipc tx done : 0x13000003|0x0: GLB_SET_PIPELINE_STATE Feb 14 21:43:09 sh-lnlm-rvp-hda-01 kernel: snd_sof:sof_pcm_trigger: sof-audio-pci-intel-lnl 0000:00:1f.3: pcm: trigger stream 6 dir 1 cmd 0 Feb 14 21:43:09 sh-lnlm-rvp-hda-01 kernel: snd_sof:sof_ipc4_trigger_pipelines: sof-audio-pci-intel-lnl 0000:00:1f.3: trigger cmd: 0 state: 3 Feb 14 21:43:09 sh-lnlm-rvp-hda-01 kernel: snd_sof:sof_ipc4_set_pipeline_state: sof-audio-pci-intel-lnl 0000:00:1f.3: ipc4 set pipeline instance 1 state 3 Feb 14 21:43:09 sh-lnlm-rvp-hda-01 kernel: snd_sof:sof_ipc4_log_header: sof-audio-pci-intel-lnl 0000:00:1f.3: ipc tx : 0x13010003|0x0: GLB_SET_PIPELINE_STATE Feb 14 21:43:09 sh-lnlm-rvp-hda-01 kernel: snd_sof:sof_ipc4_log_header: sof-audio-pci-intel-lnl 0000:00:1f.3: ipc tx reply: 0x33000000|0x0: GLB_SET_PIPELINE_STATE Feb 14 21:43:09 sh-lnlm-rvp-hda-01 kernel: snd_sof:sof_ipc4_log_header: sof-audio-pci-intel-lnl 0000:00:1f.3: ipc tx done : 0x13010003|0x0: GLB_SET_PIPELINE_STATE Feb 14 21:43:09 sh-lnlm-rvp-hda-01 kernel: snd_sof:sof_pcm_trigger: sof-audio-pci-intel-lnl 0000:00:1f.3: pcm: trigger stream 6 dir 1 cmd 1 Feb 14 21:43:09 sh-lnlm-rvp-hda-01 kernel: snd_sof:sof_ipc4_trigger_pipelines: sof-audio-pci-intel-lnl 0000:00:1f.3: trigger cmd: 1 state: 4 Feb 14 21:43:09 sh-lnlm-rvp-hda-01 kernel: snd_sof:sof_ipc4_set_pipeline_state: sof-audio-pci-intel-lnl 0000:00:1f.3: ipc4 set pipeline instance 1 state 3 Feb 14 21:43:09 sh-lnlm-rvp-hda-01 kernel: snd_sof:sof_ipc4_log_header: sof-audio-pci-intel-lnl 0000:00:1f.3: ipc tx : 0x13010003|0x0: GLB_SET_PIPELINE_STATE Feb 14 21:43:09 sh-lnlm-rvp-hda-01 kernel: snd_sof:sof_ipc4_log_header: sof-audio-pci-intel-lnl 0000:00:1f.3: ipc tx reply: 0x33000000|0x0: GLB_SET_PIPELINE_STATE Feb 14 21:43:09 sh-lnlm-rvp-hda-01 kernel: snd_sof:sof_ipc4_log_header: sof-audio-pci-intel-lnl 0000:00:1f.3: ipc tx done : 0x13010003|0x0: GLB_SET_PIPELINE_STATE Feb 14 21:43:09 sh-lnlm-rvp-hda-01 kernel: snd_sof:sof_ipc4_set_pipeline_state: sof-audio-pci-intel-lnl 0000:00:1f.3: ipc4 set pipeline instance 1 state 4 Feb 14 21:43:09 sh-lnlm-rvp-hda-01 kernel: snd_sof:sof_ipc4_log_header: sof-audio-pci-intel-lnl 0000:00:1f.3: ipc tx : 0x13010004|0x0: GLB_SET_PIPELINE_STATE Feb 14 21:43:09 sh-lnlm-rvp-hda-01 kernel: snd_sof:sof_ipc4_log_header: sof-audio-pci-intel-lnl 0000:00:1f.3: ipc tx reply: 0x33000000|0x0: GLB_SET_PIPELINE_STATE Feb 14 21:43:09 sh-lnlm-rvp-hda-01 kernel: snd_sof:sof_ipc4_log_header: sof-audio-pci-intel-lnl 0000:00:1f.3: ipc tx done : 0x13010004|0x0: GLB_SET_PIPELINE_STATE Feb 14 21:43:09 sh-lnlm-rvp-hda-01 kernel: snd_sof_intel_hda_common:hda_dai_trigger: sof-audio-pci-intel-lnl 0000:00:1f.3: cmd=1 dai DMIC01 Pin direction 1 Feb 14 21:43:09 sh-lnlm-rvp-hda-01 kernel: snd_sof:sof_ipc4_set_pipeline_state: sof-audio-pci-intel-lnl 0000:00:1f.3: ipc4 set pipeline instance 0 state 4 Feb 14 21:43:09 sh-lnlm-rvp-hda-01 kernel: snd_sof:sof_ipc4_log_header: sof-audio-pci-intel-lnl 0000:00:1f.3: ipc tx : 0x13000004|0x0: GLB_SET_PIPELINE_STATE Feb 14 21:43:09 sh-lnlm-rvp-hda-01 kernel: snd_sof:sof_ipc4_log_header: sof-audio-pci-intel-lnl 0000:00:1f.3: ipc tx reply: 0x33000000|0x0: GLB_SET_PIPELINE_STATE Feb 14 21:43:09 sh-lnlm-rvp-hda-01 kernel: snd_sof:sof_ipc4_log_header: sof-audio-pci-intel-lnl 0000:00:1f.3: ipc tx done : 0x13000004|0x0: GLB_SET_PIPELINE_STATE Feb 14 21:43:10 sh-lnlm-rvp-hda-01 kernel: snd_sof_intel_hda_common:hda_dai_trigger: sof-audio-pci-intel-lnl 0000:00:1f.3: cmd=0 dai DMIC01 Pin direction 1 Feb 14 21:43:10 sh-lnlm-rvp-hda-01 kernel: snd_sof:sof_ipc4_set_pipeline_state: sof-audio-pci-intel-lnl 0000:00:1f.3: ipc4 set pipeline instance 0 state 3 Feb 14 21:43:10 sh-lnlm-rvp-hda-01 kernel: snd_sof:sof_ipc4_log_header: sof-audio-pci-intel-lnl 0000:00:1f.3: ipc tx : 0x13000003|0x0: GLB_SET_PIPELINE_STATE Feb 14 21:43:10 sh-lnlm-rvp-hda-01 kernel: snd_sof:sof_ipc4_log_header: sof-audio-pci-intel-lnl 0000:00:1f.3: ipc tx reply: 0x33000000|0x0: GLB_SET_PIPELINE_STATE Feb 14 21:43:10 sh-lnlm-rvp-hda-01 kernel: snd_sof:sof_ipc4_log_header: sof-audio-pci-intel-lnl 0000:00:1f.3: ipc tx done : 0x13000003|0x0: GLB_SET_PIPELINE_STATE Feb 14 21:43:10 sh-lnlm-rvp-hda-01 kernel: snd_sof:sof_pcm_trigger: sof-audio-pci-intel-lnl 0000:00:1f.3: pcm: trigger stream 6 dir 1 cmd 0 Feb 14 21:43:10 sh-lnlm-rvp-hda-01 kernel: snd_sof:sof_ipc4_trigger_pipelines: sof-audio-pci-intel-lnl 0000:00:1f.3: trigger cmd: 0 state: 3 Feb 14 21:43:10 sh-lnlm-rvp-hda-01 kernel: snd_sof:sof_ipc4_set_pipeline_state: sof-audio-pci-intel-lnl 0000:00:1f.3: ipc4 set pipeline instance 1 state 3 Feb 14 21:43:10 sh-lnlm-rvp-hda-01 kernel: snd_sof:sof_ipc4_log_header: sof-audio-pci-intel-lnl 0000:00:1f.3: ipc tx : 0x13010003|0x0: GLB_SET_PIPELINE_STATE Feb 14 21:43:10 sh-lnlm-rvp-hda-01 kernel: snd_sof:sof_ipc4_log_header: sof-audio-pci-intel-lnl 0000:00:1f.3: ipc tx reply: 0x33000000|0x0: GLB_SET_PIPELINE_STATE Feb 14 21:43:10 sh-lnlm-rvp-hda-01 kernel: snd_sof:sof_ipc4_log_header: sof-audio-pci-intel-lnl 0000:00:1f.3: ipc tx done : 0x13010003|0x0: GLB_SET_PIPELINE_STATE Feb 14 21:43:10 sh-lnlm-rvp-hda-01 kernel: snd_sof:sof_pcm_hw_free: sof-audio-pci-intel-lnl 0000:00:1f.3: pcm: free stream 6 dir 1 Feb 14 21:43:10 sh-lnlm-rvp-hda-01 kernel: snd_sof_intel_hda_common:hda_dsp_stream_trigger: sof-audio-pci-intel-lnl 0000:00:1f.3: FW Poll Status: reg[0x80]=0x20140000 successful Feb 14 21:43:10 sh-lnlm-rvp-hda-01 kernel: snd_sof:sof_ipc4_trigger_pipelines: sof-audio-pci-intel-lnl 0000:00:1f.3: trigger cmd: 0 state: 2 Feb 14 21:43:10 sh-lnlm-rvp-hda-01 kernel: snd_sof:sof_ipc4_log_header: sof-audio-pci-intel-lnl 0000:00:1f.3: ipc tx : 0x13000002|0x1: GLB_SET_PIPELINE_STATE [data size: 12] Feb 14 21:43:10 sh-lnlm-rvp-hda-01 kernel: snd_sof:sof_ipc4_log_header: sof-audio-pci-intel-lnl 0000:00:1f.3: ipc tx reply: 0x33000000|0x1: GLB_SET_PIPELINE_STATE Feb 14 21:43:10 sh-lnlm-rvp-hda-01 kernel: snd_sof:sof_ipc4_log_header: sof-audio-pci-intel-lnl 0000:00:1f.3: ipc tx done : 0x13000002|0x1: GLB_SET_PIPELINE_STATE [data size: 12] Feb 14 21:43:10 sh-lnlm-rvp-hda-01 kernel: snd_sof:sof_ipc4_route_free: sof-audio-pci-intel-lnl 0000:00:1f.3: unbind modules dai-copier.DMIC.dmic01.capture:0 -> eqiir.12.1:0 Feb 14 21:43:10 sh-lnlm-rvp-hda-01 kernel: snd_sof:sof_widget_free_unlocked: sof-audio-pci-intel-lnl 0000:00:1f.3: widget dai-copier.DMIC.dmic01.capture freed Feb 14 21:43:10 sh-lnlm-rvp-hda-01 kernel: snd_sof:sof_ipc4_route_free: sof-audio-pci-intel-lnl 0000:00:1f.3: unbind modules eqiir.12.1:0 -> module-copier.12.2:0 Feb 14 21:43:10 sh-lnlm-rvp-hda-01 kernel: snd_sof:sof_widget_free_unlocked: sof-audio-pci-intel-lnl 0000:00:1f.3: widget eqiir.12.1 freed Feb 14 21:43:10 sh-lnlm-rvp-hda-01 kernel: snd_sof:sof_ipc4_route_free: sof-audio-pci-intel-lnl 0000:00:1f.3: unbind modules module-copier.12.2:0 -> gain.11.1:0 Feb 14 21:43:10 sh-lnlm-rvp-hda-01 kernel: snd_sof:sof_ipc4_log_header: sof-audio-pci-intel-lnl 0000:00:1f.3: ipc tx : 0x46010004|0x6: MOD_UNBIND Feb 14 21:43:10 sh-lnlm-rvp-hda-01 kernel: snd_sof:sof_ipc4_log_header: sof-audio-pci-intel-lnl 0000:00:1f.3: ipc tx reply: 0x66000000|0x6: MOD_UNBIND Feb 14 21:43:10 sh-lnlm-rvp-hda-01 kernel: snd_sof:sof_ipc4_log_header: sof-audio-pci-intel-lnl 0000:00:1f.3: ipc tx done : 0x46010004|0x6: MOD_UNBIND Feb 14 21:43:10 sh-lnlm-rvp-hda-01 kernel: snd_sof:sof_ipc4_log_header: sof-audio-pci-intel-lnl 0000:00:1f.3: ipc tx : 0x12000000|0x0: GLB_DELETE_PIPELINE Feb 14 21:43:10 sh-lnlm-rvp-hda-01 kernel: snd_sof:sof_ipc4_log_header: sof-audio-pci-intel-lnl 0000:00:1f.3: ipc tx reply: 0x32000000|0x0: GLB_DELETE_PIPELINE Feb 14 21:43:10 sh-lnlm-rvp-hda-01 kernel: snd_sof:sof_ipc4_log_header: sof-audio-pci-intel-lnl 0000:00:1f.3: ipc tx done : 0x12000000|0x0: GLB_DELETE_PIPELINE Feb 14 21:43:10 sh-lnlm-rvp-hda-01 kernel: snd_sof:sof_widget_free_unlocked: sof-audio-pci-intel-lnl 0000:00:1f.3: widget pipeline.12 freed Feb 14 21:43:10 sh-lnlm-rvp-hda-01 kernel: snd_sof:sof_widget_free_unlocked: sof-audio-pci-intel-lnl 0000:00:1f.3: widget module-copier.12.2 freed Feb 14 21:43:10 sh-lnlm-rvp-hda-01 kernel: snd_sof:sof_ipc4_route_free: sof-audio-pci-intel-lnl 0000:00:1f.3: unbind modules gain.11.1:0 -> host-copier.6.capture:0 Feb 14 21:43:10 sh-lnlm-rvp-hda-01 kernel: snd_sof:sof_widget_free_unlocked: sof-audio-pci-intel-lnl 0000:00:1f.3: widget gain.11.1 freed Feb 14 21:43:10 sh-lnlm-rvp-hda-01 kernel: snd_sof:sof_ipc4_log_header: sof-audio-pci-intel-lnl 0000:00:1f.3: ipc tx : 0x12010000|0x0: GLB_DELETE_PIPELINE Feb 14 21:43:10 sh-lnlm-rvp-hda-01 kernel: snd_sof:sof_ipc4_log_header: sof-audio-pci-intel-lnl 0000:00:1f.3: ipc tx reply: 0x32000000|0x0: GLB_DELETE_PIPELINE Feb 14 21:43:10 sh-lnlm-rvp-hda-01 kernel: snd_sof:sof_ipc4_log_header: sof-audio-pci-intel-lnl 0000:00:1f.3: ipc tx done : 0x12010000|0x0: GLB_DELETE_PIPELINE Feb 14 21:43:10 sh-lnlm-rvp-hda-01 kernel: snd_sof:sof_widget_free_unlocked: sof-audio-pci-intel-lnl 0000:00:1f.3: widget pipeline.11 freed Feb 14 21:43:10 sh-lnlm-rvp-hda-01 kernel: snd_sof:sof_widget_free_unlocked: sof-audio-pci-intel-lnl 0000:00:1f.3: widget host-copier.6.capture freed Feb 14 21:43:10 sh-lnlm-rvp-hda-01 kernel: snd_sof:sof_pcm_close: sof-audio-pci-intel-lnl 0000:00:1f.3: pcm: close stream 6 dir 1 Feb 14 21:43:10 sh-lnlm-rvp-hda-01 sudo[12719]: ubuntu : PWD=/home/ubuntu ; USER=root ; COMMAND=/usr/bin/lspci -D Feb 14 21:43:10 sh-lnlm-rvp-hda-01 sudo[12719]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 14 21:43:10 sh-lnlm-rvp-hda-01 sudo[12719]: pam_unix(sudo:session): session closed for user root Feb 14 21:43:10 sh-lnlm-rvp-hda-01 sudo[12724]: ubuntu : PWD=/home/ubuntu ; USER=root ; COMMAND=/usr/bin/lspci -s 0000:00:1f.3 -kx Feb 14 21:43:10 sh-lnlm-rvp-hda-01 sudo[12724]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 14 21:43:10 sh-lnlm-rvp-hda-01 sudo[12724]: pam_unix(sudo:session): session closed for user root Feb 14 21:43:10 sh-lnlm-rvp-hda-01 sudo[12754]: ubuntu : PWD=/home/ubuntu ; USER=root ; COMMAND=/usr/bin/true Feb 14 21:43:10 sh-lnlm-rvp-hda-01 sudo[12754]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 14 21:43:10 sh-lnlm-rvp-hda-01 sudo[12754]: pam_unix(sudo:session): session closed for user root ````

[keqiaozhang]

A duplicate of https://github.com/thesofproject/linux/issues/4678.

[marc-hb]

#4678 has a kernel call trace, this one has none.

[plbossart]

yeah it's doesn't look like a duplicate to me either...

[marc-hb]

"Unknown fix", close?