[BUG] [multi-core] FW panic when stream is tested on core 1 on MTL RVP #7108
Description
When Core 1 is enabled by topology for stream 1, test the following cmd: aplay works for the first time but is failed at the second time
root@sh-mtlp-rvp-nocodec-04:~# aplay 48K_Let_It_Go.wav -Dhw:0,1 -d 3;sleep 1;aplay 48K_Let_It_Go.wav -Dhw:0,1 -d 3
Playing WAVE '48K_Let_It_Go.wav' : Signed 16 bit Little Endian, Rate 48000 Hz, Stereo
Playing WAVE '48K_Let_It_Go.wav' : Signed 16 bit Little Endian, Rate 48000 Hz, Stereo
aplay: pcm_write:2127: write error: Input/output error
No issue on TGL RVP
FW: branch sof main and mtl-003-stable
Kernel: branch sof-dev + thesofproject/linux#4198
kernel_log.txt
trace.txt
Kernel log:
[ 98.517121] snd_sof:sof_ipc4_log_header: sof-audio-pci-intel-mtl 0000:00:1f.3: ipc tx : 0x47000000|0x0: MOD_SET_DX [data size: 8]
[ 98.517996] snd_sof:sof_ipc4_log_header: sof-audio-pci-intel-mtl 0000:00:1f.3: ipc tx reply: 0x67000000|0x0: MOD_SET_DX
[ 98.518220] snd_sof:sof_ipc4_log_header: sof-audio-pci-intel-mtl 0000:00:1f.3: ipc tx done : 0x47000000|0x0: MOD_SET_DX [data size: 8]
[ 98.518229] Message payload: 00000000: 00000002 00000002
[ 98.518233] snd_sof:snd_sof_dsp_core_get: sof-audio-pci-intel-mtl 0000:00:1f.3: Core 1 powered up
[ 98.518237] snd_sof:snd_sof_dsp_core_get: sof-audio-pci-intel-mtl 0000:00:1f.3: Core ref 1
[ 98.518243] snd_sof:sof_ipc4_log_header: sof-audio-pci-intel-mtl 0000:00:1f.3: ipc tx : 0x11000005|0x100000: GLB_CREATE_PIPELINE
[ 99.025987] sof-audio-pci-intel-mtl 0000:00:1f.3: ipc timed out for 0x11000005|0x100000
[ 99.026000] sof-audio-pci-intel-mtl 0000:00:1f.3: ------------[ IPC dump start ]------------
[ 99.026015] sof-audio-pci-intel-mtl 0000:00:1f.3: Host IPC initiator: 0x91000005|0x100000|0x0, target: 0x1b060000|0x0|0x0, ctl: 0x3
[ 99.026021] sof-audio-pci-intel-mtl 0000:00:1f.3: ------------[ IPC dump end ]------------
[ 99.026024] sof-audio-pci-intel-mtl 0000:00:1f.3: ------------[ DSP dump start ]------------
[ 99.026027] sof-audio-pci-intel-mtl 0000:00:1f.3: IPC timeout
[ 99.026030] sof-audio-pci-intel-mtl 0000:00:1f.3: fw_state: SOF_FW_BOOT_COMPLETE (7)
[ 99.026041] sof-audio-pci-intel-mtl 0000:00:1f.3: ROM status: 0x5, ROM error: 0x0
[ 99.026044] sof-audio-pci-intel-mtl 0000:00:1f.3: ROM debug status: 0x50000005, ROM debug error: 0x0
[ 99.026052] sof-audio-pci-intel-mtl 0000:00:1f.3: ROM feature bit enabled
[ 99.026054] sof-audio-pci-intel-mtl 0000:00:1f.3: ------------[ DSP dump end ]------------
[ 99.026059] sof-audio-pci-intel-mtl 0000:00:1f.3: failed to create module pipeline.3
[ 99.026069] snd_sof:sof_ipc4_log_header: sof-audio-pci-intel-mtl 0000:00:1f.3: ipc tx : 0x47000000|0x0: MOD_SET_DX [data size: 8]
[ 99.528976] sof-audio-pci-intel-mtl 0000:00:1f.3: ipc timed out for 0x47000000|0x0
[ 99.528990] sof-audio-pci-intel-mtl 0000:00:1f.3: IPC timeout
[ 99.528998] Message payload: 00000000: 00000002 00000000
[ 99.529004] sof-audio-pci-intel-mtl 0000:00:1f.3: Failed to set up connected widgets
FW log:
[00:00:03.565,590] <inf> dai_intel_ssp: dai_ssp_pm_runtime_dis_ssp_power: dai_ssp_pm_runtime_dis_ssp_power index 1
[00:00:03.565,595] <inf> dai_intel_ssp: dai_ssp_pm_runtime_dis_ssp_power: dai_ssp_pm_runtime_dis_ssp_power I2SLCTL
[00:00:03.566,001] <inf> ipc: ipc_cmd: rx : 0x47000000|0x0
[00:00:04.583,588] <inf> ipc: ipc_cmd: rx : 0x47000000|0x0
[00:00:04.584,705] <inf> ipc: ipc_cmd: rx : 0x11000005|0x100000
[00:00:04.584,728] <err> os: ** FATAL EXCEPTION
[00:00:04.584,735] <err> os: ** CPU 1 EXCCAUSE 9 (load/store alignment)
[00:00:04.584,740] <err> os: ** PC 0xa006c3ac VADDR 0x60313
[00:00:04.584,745] <err> os: ** PS 0x50f20
[00:00:04.584,760] <err> os: ** (INTLEVEL:0 EXCM: 0 UM:1 RING:0 WOE:1 OWB:15 CALLINC:1)
[00:00:04.584,768] <err> os: ** A0 0xa006c3d8 SP 0xa00e7820 A2 0x60313 A3 0x60323
[00:00:04.584,775] <err> os: ** A4 0x6006c4fe A5 0xa00ec540 A6 0xa00e781c A7 0x400f14fc
[00:00:04.584,781] <err> os: ** A8 0x6006c510 A9 0xa00ec520 A10 0xa00e781c A11 0x10
[00:00:04.584,788] <err> os: ** A12 (nil) A13 0x400de6a0 A14 0xfffffffe A15 0x1
[00:00:04.584,793] <err> os: ** LBEG 0xa0037126 LEND 0xa0037137 LCOUNT 0xa00683e8
[00:00:04.584,798] <err> os: ** SAR 0x1
Backtrace:0xa006c3a9:0xa00e7820 0xa006c3d5:0xa00e7850 0xa006790c:0xa00e7880 0xa0067ced:0xa00e78a0 0xa0045594:0xa00e78d0 0xa0034e16:0xa00e7900 0xa003311b:0xa00e7930
[00:00:04.584,853] <err> os: >>> ZEPHYR FATAL ERROR 0: CPU exception on CPU 1
[00:00:04.584,858] <err> os: Current thread: 0x400f0b88 (unknown)