[BUG] multiple-pipeline test fails with DSP EXCEPTION with multicore nocodec topology #8492

Activity

1. fredoh9

   added

   bugSomething isn't working as expectedSomething isn't working as expected

   P1Blocker bugs or important featuresBlocker bugs or important features

   I2SApplies to I2S bus for codec connectionApplies to I2S bus for codec connection

   TGLApplies to Tiger LakeApplies to Tiger Lake

   multicoreIssues observed when not only core#0 is used.Issues observed when not only core#0 is used.

   ADLApplies to Alder Lake platformApplies to Alder Lake platform

   multicore-3coresIssues observed when 3 cores are used.Issues observed when 3 cores are used.

   on Nov 17, 2023
2. marc-hb commented on Nov 18, 2023

   

   marc-hb

   on Nov 18, 2023

   Collaborator

   More actions

   Another multiple-pipeline failure, dunno whether it's related but it's a very recent regression too: https://sof-ci.01.org/sofpr/PR8494/build14998/devicetest/index.html?model=ADLP_RVP_NOCODEC_IPC4ZPH&testcase=multiple-pipeline-all (#8494)
3. fredoh9 commented on Nov 18, 2023

   

   fredoh9

   on Nov 18, 2023

   ContributorAuthor

   More actions

   multiple-pipeline-playback-50 test has similar but a bit different logs.

   dmesg

   [ 4227.456412] kernel: snd_sof:sof_ipc4_log_header: sof-audio-pci-intel-tgl 0000:00:1f.3: ipc tx      : 0x47000000|0x0: MOD_SET_DX [data size: 8]
   [ 4227.456934] kernel: snd_sof:sof_ipc4_log_header: sof-audio-pci-intel-tgl 0000:00:1f.3: ipc tx reply: 0x67000006|0x0: MOD_SET_DX
   [ 4227.456938] kernel: sof-audio-pci-intel-tgl 0000:00:1f.3: FW reported error: 6 - Unknown error while processing the request
   [ 4227.457151] kernel: sof-audio-pci-intel-tgl 0000:00:1f.3: ipc error for msg 0x47000000|0x0
   [ 4227.457156] kernel: sof-audio-pci-intel-tgl 0000:00:1f.3: error: failed to disable target core: 2 for widget pipeline.6
   [ 4227.457158] kernel: sof-audio-pci-intel-tgl 0000:00:1f.3: Failed to free connected widgets
   [ 4227.457163] kernel: sof-audio-pci-intel-tgl 0000:00:1f.3: ASoC: error at snd_soc_pcm_component_hw_free on 0000:00:1f.3: -22
   [ 4227.457187] kernel: snd_sof:sof_pcm_hw_free: sof-audio-pci-intel-tgl 0000:00:1f.3: pcm: free stream 1 dir 0
   [ 4227.457195] kernel: snd_sof_intel_hda_common:hda_dsp_stream_trigger: sof-audio-pci-intel-tgl 0000:00:1f.3: FW Poll Status: reg[0x1c0]=0x20440000 successful
   [ 4227.457204] kernel: snd_sof:sof_ipc4_trigger_pipelines: sof-audio-pci-intel-tgl 0000:00:1f.3: trigger cmd: 0 state: 2
   [ 4227.457208] kernel: snd_sof:sof_ipc4_log_header: sof-audio-pci-intel-tgl 0000:00:1f.3: ipc tx      : 0x13000002|0x1: GLB_SET_PIPELINE_STATE [data size: 12]
   [ 4227.958422] kernel: sof-audio-pci-intel-tgl 0000:00:1f.3: ipc timed out for 0x13000002|0x1
   

   mtrace has ASSERTION FAIL

   [   62.122463] <wrn> copier: copier_comp_trigger: comp:2 0x20004 dai is not ready
   [   62.122585] <inf> src: src_reset: comp:2 0x8 src_reset()
   [   62.123711] <inf> ipc: ipc_cmd: rx	: 0x46010002|0x10003
   [   62.124348] <inf> ipc: ipc_cmd: rx	: 0x12020000|0x0
   [   62.124416] <inf> dma: dma_put: dma_put(), dma = 0x9e09f250, sref = 1
   [   62.124636] <inf> src: src_free: comp:2 0x8 src_free()
   [   62.125286] <inf> ipc: ipc_cmd: rx	: 0x12030000|0x0
   [   62.125596] <inf> dma: dma_put: dma_put(), dma = 0x9e09f1b0, sref = 0
   [   62.125613] <inf> dai_intel_ssp: dai_ssp_pm_runtime_dis_ssp_power: dai_ssp_pm_runtime_dis_ssp_power index 2
   [   62.125755] <err> dai_intel_ssp: dai_ssp_po����������������Çÿÿÿ����	:�¾8—�¾”Î�¾Ø—�¾Ø—�¾¸—�¾����ay poll Ø—�¾¸—�¾��������������������”Î�¾��������è–�¾%U�¾x—�¾”Î�¾Ø—�¾”Î�¾Ø—�¾¸—�¾����Ø—�¾¸—�¾����p_pm¸—�¾����ÇÿÿÿP%
   žX�¾¨—�¾”Î�¾����pm_runtime_dis_ssp_power warning: timeout
   [   62.125778] <inf> dai_intel_ssp: dai_ssp_pm_runtime_dis_ssp_power: dai_ssp_pm_runtime_dis_ssp_power I2SLCTL
   [   62.126203] <inf> ipc: ipc_cmd: rx	: 0x47000000|0x0
   [   62.126355] <err> ipc: ipc4_module_process_dx: failed to disable core 2
   [   62.126370] <err> ipc: ipc_cmd: ipc4: MODULE_MSG failed with err 6
   ASSERTION FAIL [k_sched_current_thread_query()->base.sched_locked != 0U] @ /srv/home/jenkins/workspace/sof_config_build@2/zephyr/kernel/sched.c:1117
   [   62.126713] <err> os: z_xtensa_dump_stack:  **  A0 0xbe0154f8  SP 0xbe0b20f0  A2 0x4  A3 0xbe0b2100
   [   62.126963] <err> os: z_xtensa_dump_stack:  **  A0 0xbe0154f8  SP 0xbe0b3ba0  A2 0x4  A3 0xbe0b3bb0
   [   62.127201] <err> os: z_xtensa_dump_stack:  **  A0 0xbe0154f8  SP 0xbe0b3938  A2 0x4  A3 0xbe0b3948
   [   62.127411] <err> os: z_xtensa_dump_stack:  **  A0 0xbe0154f8  SP 0xbe0b36d0  A2 0x4  A3 0xbe0b36e0
   [   62.127623] <err> os: z_xtensa_dump_stack:  **  A0 0xbe0154f8  SP 0xbe0b3468  A2 0x4  A3 0xbe0b3478
   [   62.127833] <err> os: z_xtensa_dump_stack:  **  A0 0xbe0154f8  SP 0xbe0b3200  A2 0x4  A3 0xbe0b3210
   [   62.128086] <err> os: z_xtensa_dump_stack:  **  A0 0xbe0154f8  SP 0xbe0b2f98  A2 0x4  A3 0xbe0b2fa8
   	Invalid spinlock 0x9e0b8518
   	Invalid spinlock 0x9e0b8518
   	Invalid spinlock 0x9e0b8518
   	Invalid spinlock 0x9e0b8518
   

   Intel Internal Test link:
   planresultdetail/34750?model=ADLP_RVP_NOCODEC-ipc4&testcase=multiple-pipeline-playback-50
4. RanderWang

   added a commit that references this issue on Nov 23, 2023

   west.yml: update Zephyr to efc32081893d

   ...

   1e793e8
5. lgirdwood

   added this to the v2.9 milestone on Nov 23, 2023
6. lgirdwood

   added a commit that references this issue on Nov 24, 2023

   west.yml: update Zephyr to efc32081893d

   ...

   f1ee910
7. fredoh9 commented on Nov 27, 2023

   

   fredoh9

   on Nov 27, 2023

   ContributorAuthor

   More actions

   working fine with the Zephyr fix and west update. Closing now
8. fredoh9

   closed this as completedon Nov 27, 2023