Skip to content

[BUG] [IPC4] IPC timed out for ssp loopback in multi stream capture #6330

Closed

Description

@bardliao

Describe the bug

IPC timed out when I tested #6329 with SSP loopback mode.

To Reproduce
sudo ./sof/tools/mtrace/mtrace-reader.py
aplay -Dhw:0,0 -f s32_le -r 48000 -c 2 -i -vvv ~/California_Dreaming_48k_16bit.wav
arecord -Dhw:0,0 -f s32_le -c 2 -r 48000 /tmp/port0.wav -vvv
arecord -Dhw:0,12 -f s32_le -c 2 -r 48000 /tmp/port12.wav -vvv
stop aplay
stop arecord

Reproduction Rate
100%

Expected behavior
No error

Impact
What impact does this issue have on your progress (e.g., annoyance, showstopper)

Environment

  1. Branch name and commit hash of the 2 repositories: sof (firmware/topology) and linux (kernel driver).
    • Kernel: daily test kernel version
    • SOF: daily test zephyr IPC4 version
  2. Name of the topology file
    • Topology: sof-tgl-nocodec.tplg
  3. Name of the platform(s) on which the bug is observed.
    • Platform: TGLU

Screenshots or console output

[  203.246522] snd_sof:sof_ipc4_route_free: sof-audio-pci-intel-tgl 0000:00:1f.3: unbind modules copier.SSP.8.1:0 -> gain.7.1:0
[  203.246528] snd_sof:sof_ipc4_log_header: sof-audio-pci-intel-tgl 0000:00:1f.3: ipc tx      : 0x46020004|0x20006: MOD_UNBIND
[  203.246694] snd_sof:sof_ipc4_log_header: sof-audio-pci-intel-tgl 0000:00:1f.3: ipc tx reply: 0x66000000|0x20006: MOD_UNBIND
[  203.246738] snd_sof:sof_ipc4_log_header: sof-audio-pci-intel-tgl 0000:00:1f.3: ipc tx done : 0x46020004|0x20006: MOD_UNBIND
[  203.246747] snd_sof:sof_ipc4_log_header: sof-audio-pci-intel-tgl 0000:00:1f.3: ipc tx      : 0x12080000|0x0: GLB_DELETE_PIPELINE
[  203.753044] sof-audio-pci-intel-tgl 0000:00:1f.3: ipc timed out for 0x12080000|0x0
[  203.753080] sof-audio-pci-intel-tgl 0000:00:1f.3: preventing DSP entering D3 state to preserve context
[  203.753085] sof-audio-pci-intel-tgl 0000:00:1f.3: ------------[ IPC dump start ]------------
[  203.753121] sof-audio-pci-intel-tgl 0000:00:1f.3: hda irq intsts 0x00000000 intlctl 0xc0000000 rirb 00
[  203.753131] sof-audio-pci-intel-tgl 0000:00:1f.3: dsp irq ppsts 0x00000000 adspis 0x00000000
[  203.753165] sof-audio-pci-intel-tgl 0000:00:1f.3: Host IPC initiator: 0x92080000|0x0|0x0, target: 0x0|0x0|0x0, ctl: 0x3
[  203.753173] sof-audio-pci-intel-tgl 0000:00:1f.3: ------------[ IPC dump end ]------------
[  203.753178] sof-audio-pci-intel-tgl 0000:00:1f.3: ------------[ DSP dump start ]------------
[  203.753182] sof-audio-pci-intel-tgl 0000:00:1f.3: IPC timeout
[  203.753187] sof-audio-pci-intel-tgl 0000:00:1f.3: fw_state: SOF_FW_BOOT_COMPLETE (6)
[  203.753211] sof-audio-pci-intel-tgl 0000:00:1f.3: 0x00000005: module: ROM, state: FW_ENTERED, running
[  203.753273] sof-audio-pci-intel-tgl 0000:00:1f.3: extended rom status:  0x5 0x0 0x4000 0x0 0x0 0x0 0x0 0x4000
[  203.753278] sof-audio-pci-intel-tgl 0000:00:1f.3: ------------[ DSP dump end ]------------
[  203.753284] sof-audio-pci-intel-tgl 0000:00:1f.3: failed to free pipeline widget pipeline.8
[  203.753294] snd_sof:sof_ipc4_route_free: sof-audio-pci-intel-tgl 0000:00:1f.3: unbind modules gain.7.1:0 -> copier.host.7.1:0
[  203.753302] snd_sof:sof_ipc4_log_header: sof-audio-pci-intel-tgl 0000:00:1f.3: ipc tx      : 0x46020006|0x30004: MOD_UNBIND
[  204.256974] sof-audio-pci-intel-tgl 0000:00:1f.3: ipc timed out for 0x46020006|0x30004
[  204.256989] sof-audio-pci-intel-tgl 0000:00:1f.3: preventing DSP entering D3 state to preserve context
[  204.256994] sof-audio-pci-intel-tgl 0000:00:1f.3: ------------[ IPC dump start ]------------
[  204.257035] sof-audio-pci-intel-tgl 0000:00:1f.3: hda irq intsts 0x00000000 intlctl 0xc0000000 rirb 00
[  204.257041] sof-audio-pci-intel-tgl 0000:00:1f.3: dsp irq ppsts 0x00000000 adspis 0x00000000
[  204.257088] sof-audio-pci-intel-tgl 0000:00:1f.3: Host IPC initiator: 0x92080000|0x0|0x0, target: 0x0|0x0|0x0, ctl: 0x3
[  204.257095] sof-audio-pci-intel-tgl 0000:00:1f.3: ------------[ IPC dump end ]------------
[  204.257099] sof-audio-pci-intel-tgl 0000:00:1f.3: ------------[ DSP dump start ]------------
[  204.257103] sof-audio-pci-intel-tgl 0000:00:1f.3: IPC timeout
[  204.257108] sof-audio-pci-intel-tgl 0000:00:1f.3: fw_state: SOF_FW_BOOT_COMPLETE (6)
[  204.257135] sof-audio-pci-intel-tgl 0000:00:1f.3: 0x00000005: module: ROM, state: FW_ENTERED, running
[  204.257171] sof-audio-pci-intel-tgl 0000:00:1f.3: extended rom status:  0x5 0x0 0x4000 0x0 0x0 0x0 0x0 0x4000
[  204.257176] sof-audio-pci-intel-tgl 0000:00:1f.3: ------------[ DSP dump end ]------------
[  204.257182] sof-audio-pci-intel-tgl 0000:00:1f.3: failed to unbind modules gain.7.1 -> copier.host.7.1
[  204.257194] snd_sof:sof_widget_free: sof-audio-pci-intel-tgl 0000:00:1f.3: widget gain.7.1 freed
[  204.257203] snd_sof:sof_ipc4_log_header: sof-audio-pci-intel-tgl 0000:00:1f.3: ipc tx      : 0x12070000|0x0: GLB_DELETE_PIPELINE
[  204.760982] sof-audio-pci-intel-tgl 0000:00:1f.3: ipc timed out for 0x12070000|0x0
[  204.760998] sof-audio-pci-intel-tgl 0000:00:1f.3: preventing DSP entering D3 state to preserve context
[  204.761003] sof-audio-pci-intel-tgl 0000:00:1f.3: ------------[ IPC dump start ]------------
[  204.761043] sof-audio-pci-intel-tgl 0000:00:1f.3: hda irq intsts 0x00000000 intlctl 0xc0000000 rirb 00
[  204.761050] sof-audio-pci-intel-tgl 0000:00:1f.3: dsp irq ppsts 0x00000000 adspis 0x00000000
[  204.761097] sof-audio-pci-intel-tgl 0000:00:1f.3: Host IPC initiator: 0x92080000|0x0|0x0, target: 0x0|0x0|0x0, ctl: 0x3
[  204.761105] sof-audio-pci-intel-tgl 0000:00:1f.3: ------------[ IPC dump end ]------------
[  204.761109] sof-audio-pci-intel-tgl 0000:00:1f.3: ------------[ DSP dump start ]------------
[  204.761114] sof-audio-pci-intel-tgl 0000:00:1f.3: IPC timeout
[  204.761119] sof-audio-pci-intel-tgl 0000:00:1f.3: fw_state: SOF_FW_BOOT_COMPLETE (6)
[  204.761146] sof-audio-pci-intel-tgl 0000:00:1f.3: 0x00000005: module: ROM, state: FW_ENTERED, running
[  204.761183] sof-audio-pci-intel-tgl 0000:00:1f.3: extended rom status:  0x5 0x0 0x4000 0x0 0x0 0x0 0x0 0x4000
[  204.761187] sof-audio-pci-intel-tgl 0000:00:1f.3: ------------[ DSP dump end ]------------
[  204.761194] sof-audio-pci-intel-tgl 0000:00:1f.3: failed to free pipeline widget pipeline.7
[  204.761203] sof-audio-pci-intel-tgl 0000:00:1f.3: Failed to free connected widgets
[  204.761216] sof-audio-pci-intel-tgl 0000:00:1f.3: ASoC: error at snd_soc_pcm_component_hw_free on 0000:00:1f.3: -110

dmesg_scenario1.txt

<err> copier: failed to find dai comp or sink pipeline not running.

mtrace_scenario1.txt

Activity

  1. added
    bugSomething isn't working as expected
    on Sep 25, 2022
  2. added
    IPC errorIPC error is observed
    IPC4Issues observed with IPC4 (same IPC as Windows)
    on Sep 26, 2022
  3. added this to the v2.4 milestone on Sep 26, 2022
  4. added
    P1Blocker bugs or important features
    on Sep 27, 2022
  5. added
    P2Critical bugs or normal features
    and removed
    P1Blocker bugs or important features
    on Oct 4, 2022
  6. bardliao commented on Oct 31, 2022

    @bardliao
    CollaboratorAuthor

    This will be fixed by thesofproject/linux#3957

  7. keqiaozhang commented on Nov 14, 2022

    @keqiaozhang
    Collaborator

    This issue has been fixed by thesofproject/linux#3957

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment

Metadata

Metadata

Assignees

No one assigned

    Labels

    IPC errorIPC error is observedIPC4Issues observed with IPC4 (same IPC as Windows)P2Critical bugs or normal featuresbugSomething isn't working as expected

    Type

    No type

    Projects

    No projects

      Milestone

      Relationships

      None yet

      Development

      No branches or pull requests

      Issue actions