Skip to content

[S3] Failed to aplay source after suspend/resume without playback on APL-nocodec #1073

New issue
New issue
Closed
Closed
[S3] Failed to aplay source after suspend/resume without playback on APL-nocodec#1073
Assignees
zhuyingjiang
Labels
APLApplies to Apollolake platformbugSomething isn't working as expected

Description

@Jiangxinx
Jiangxinx
opened on Mar 4, 2019

Summary:
Fail to check aplay function after suspend/resume 50 times without plaback on APL-nocodec.
sof-logger is empty. sof-logger -t log is attached below.

Step：
1.sudo ./stress-s3.sh 50 1 0

Dmesg:

[  349.430069] r8169 0000:03:00.0 enp3s0: Link is Up - 100Mbps/Full - flow control rx/tx
[  350.582642] PM: suspend entry (deep)
[  350.582646] PM: Syncing filesystems ... done.
[  350.583335] Freezing user space processes ... (elapsed 0.001 seconds) done.
[  350.585322] OOM killer disabled.
[  350.585323] Freezing remaining freezable tasks ... (elapsed 0.001 seconds) done.
[  350.587053] printk: Suspending console(s) (use no_console_suspend to debug)
[  350.588174] sof-audio-pci 0000:00:0e.0: ipc tx: 0x40010000: GLB_PM_MSG: CTX_SAVE
[  350.588198] r8169 0000:03:00.0 enp3s0: Link is Down
[  350.895170] sof-audio-pci 0000:00:0e.0: error: ipc timed out for 0x40010000 size 0x4c
[  350.895182] sof-audio-pci 0000:00:0e.0: unknown ROM status value 01006701
[  350.895187] sof-audio-pci 0000:00:0e.0: error: status = 0x001d003c panic = 0x00000000
[  350.895192] sof-audio-pci 0000:00:0e.0: unknown ROM status value 01006701
[  350.895212] sof-audio-pci 0000:00:0e.0: error: ctx_save ipc error during suspend -110
[  350.946112] sof-audio-pci 0000:00:0e.0: FW Poll Status: reg=0x1d003c timedout
[  350.946122] sof-audio-pci 0000:00:0e.0: error: reset enter failed: core_mask 3 adspcs 0x1d003c
[  350.946125] sof-audio-pci 0000:00:0e.0: error: dsp core reset failed: core_mask 3
[  350.946128] sof-audio-pci 0000:00:0e.0: error: failed to power down core during suspend
[  350.946131] sof-audio-pci 0000:00:0e.0: error: suspending dsp
[  350.946135] sof-audio-pci 0000:00:0e.0: error: failed to power down DSP during suspend -5
[  350.946149] pci_pm_suspend(): snd_sof_suspend+0x0/0x10 [snd_sof] returns -5
[  350.946158] dpm_run_callback(): pci_pm_suspend+0x0/0x120 returns -5
[  350.946165] PM: Device 0000:00:0e.0 failed to suspend: error -5
[  350.946170] PM: Some devices failed to suspend, or early wake event detected
[  351.057779] r8169 0000:02:00.0 enp2s0: Link is Down
[  351.058014] r8169 0000:03:00.0 enp3s0: Link is Down
[  351.058424] OOM killer enabled.
[  351.058425] Restarting tasks ... done.
[  351.060332] video LNXVIDEO:00: Restoring backlight state
[  351.060345] PM: suspend exit
[  351.257025] ata2: SATA link down (SStatus 4 SControl 300)
[  351.264780] ata1: SATA link down (SStatus 4 SControl 300)
[  353.162866] r8169 0000:03:00.0 enp3s0: Link is Up - 100Mbps/Full - flow control rx/tx
[  353.305383] sof-audio-pci 0000:00:0e.0: pcm: open stream 0 dir 0
[  353.305388] sof-audio-pci 0000:00:0e.0: error: pcm open failed to resume -22
[  353.305395] sof-audio-pci 0000:00:0e.0: ASoC: can't open component 0000:00:0e.0: -22
[  353.305398]  Port0: ASoC: failed to start FE -22

Env:
sof master: 24e241b
kernel sof-dev: cf5978a
tplg: sof-apl-nocodec.tplg

Log:
dmesg.log
logger.log

Activity

  1. Jiangxinx
    added
    bugSomething isn't working as expected
    APLApplies to Apollolake platform
    on Mar 4, 2019
  2. Jiangxinx
    changed the title [-][PM] Failed to aplay source after suspend/resume without playback on APL-nocodec[/-] [+][S3] Failed to aplay source after suspend/resume without playback on APL-nocodec[/+] on Mar 4, 2019

  3. lgirdwood commented on Mar 5, 2019

    @lgirdwood
    lgirdwood
    on Mar 5, 2019
    Member

    @Jiangxinx which board ?

  4. Jiangxinx commented on Mar 6, 2019

    @Jiangxinx
    Jiangxinx
    on Mar 6, 2019
    ContributorAuthor

    @lgirdwood APL-UP2-NOCODEC

  5. plbossart commented on Mar 6, 2019

    @plbossart
    plbossart
    on Mar 6, 2019
    Member

    Can you clarify the reference to 'aplay'?

    are you

    • running the test and you see a ROM error before running aplay, in which case aplay is really irrelevant?
    • or are you done with the test and resuming when aplay starts?

  6. Jiangxinx commented on Mar 6, 2019

    @Jiangxinx
    Jiangxinx
    on Mar 6, 2019
    ContributorAuthor

    @plbossart Attach some details:

    1. aplay -Dhw:0,0 -fS16_LE -c2 -r48000 /dev/zero
      result: passed.
    2. sudo ./stress-s3.sh 50 1 0
      result:
    == start time: 2019-03-06 18:25:52 ==
---- test 1 of 1 ----
System will suspend after 1 seconds ...
system will resume after 2 seconds ...
rtcwake: wakeup from "mem" using /dev/rtc0 at Wed Mar  6 10:25:56 2019
Home directory not accessible: Permission denied
Home directory not accessible: Permission denied
aplay: main:788: audio open error: Invalid argument
Failed to aplay source!
== end time: 2019-03-06 18:26:00 ==

  7. lgirdwood commented on Mar 11, 2019

    @lgirdwood
    lgirdwood
    on Mar 11, 2019
    Member

    @Jiangxinx can you try with this thesofproject/linux#704

  8. mengdonglin
    assigned
    zhuyingjiang
    on Mar 13, 2019

  9. ranj063 commented on Mar 18, 2019

    @ranj063
    ranj063
    on Mar 18, 2019
    Collaborator

    @zhuyingjiang im starting to suspect if this is a regression at all? Are you sure rtcwake -s 2 -m mem ever worked with nocodec before? It looks like the DSP cannot be powered back on after entering S3.

    But if I do "rtcwake -s 2 -m freeze", the system enters S2Idle and resumes fine.
    @plbossart is this expected?

  10. stevyan commented on Mar 18, 2019

    @stevyan
    stevyan
    on Mar 18, 2019

    I've rolled back the build version 20190227 to check this issue, it works well. However, this issue is always exist since issue #1054 occurred (Fixed by thesofproject/linux#675 and #1047) and never disappear. Anyway, the S3 operation is working (suspend/resume), but, the aplay will be failed till now.

  11. lgirdwood commented on Mar 18, 2019

    @lgirdwood
    lgirdwood
    on Mar 18, 2019
    Member

    @stevyan, @Jiangxinx the IPC PM context save is failing, so this looks more like a FW issue.

    [  350.588174] sof-audio-pci 0000:00:0e.0: ipc tx: 0x40010000: GLB_PM_MSG: CTX_SAVE
[  350.588198] r8169 0000:03:00.0 enp3s0: Link is Down
[  350.895170] sof-audio-pci 0000:00:0e.0: error: ipc timed out for 0x40010000 size 0x4c
[  350.895182] sof-audio-pci 0000:00:0e.0: unknown ROM status value 01006701
[  350.895187] sof-audio-pci 0000:00:0e.0: error: status = 0x001d003c panic = 0x00000000
[  350.895192] sof-audio-pci 0000:00:0e.0: unknown ROM status value 01006701
[  350.895212] sof-audio-pci 0000:00:0e.0: error: ctx_save ipc error during suspend -110
[  350.946112] sof-audio-pci 0000:00:0e.0: FW Poll Status: reg=0x1d003c timedout
[  350.946122] sof-audio-pci 0000:00:0e.0: error: reset enter failed: core_mask 3 adspcs 0x1d003c
[  350.946125] sof-audio-pci 0000:00:0e.0: error: dsp core reset failed: core_mask 3
[  350.946128] sof-audio-pci 0000:00:0e.0: error: failed to power down core during suspend
[  350.946131] sof-audio-pci 0000:00:0e.0: error: suspending dsp
[  350.946135] sof-audio-pci 0000:00:0e.0: error: failed to power down DSP during suspend -5

    The driver expectation is that this and subsequent commands will succeed, which they usually do. This first command that fails is the PM IPC and that then cascades into driver side PM failures.

    @ranj063 @plbossart I think it's worthwhile here to see if forcing the device into D3 at the PCI level helps at all since it looks like the SHIM level PM gets stuck at this point. Fwiw, I'm not sure if the PM core will set D3 for this device if we return errors like above in the driver.

  12. ranj063 commented on Mar 18, 2019

    @ranj063
    ranj063
    on Mar 18, 2019
    Collaborator

    @lgirdwood the log from above is a bit old. We have address the CTX_SAVE failure that happens prior to entering suspend by not reading the mem windo for this IPC.
    So now the log looks like this:
    PM: suspend entry (deep)
    [40556.846651] PM: Syncing filesystems ... done.
    [40556.849149] Freezing user space processes ... (elapsed 0.000 seconds) done.
    [40556.850008] OOM killer disabled.
    [40556.850010] Freezing remaining freezable tasks ... (elapsed 0.001 seconds) done.
    [40556.851313] printk: Suspending console(s) (use no_console_suspend to debug)
    [40556.854808] r8169 0000:02:00.0 enp2s0: Link is Down
    [40556.865326] sof-audio-pci 0000:00:0e.0: Debug PCIR: 00000000 at 00000044
    [40556.865943] sof-audio-pci 0000:00:0e.0: loading firmware
    [40556.865959] sof-audio-pci 0000:00:0e.0: Debug PCIR: 00000002 at 00000048
    [40556.865964] sof-audio-pci 0000:00:0e.0: Debug PCIW: 00000000 at 00000048
    [40556.865968] sof-audio-pci 0000:00:0e.0: Debug PCIR: 00000000 at 00000044
    [40556.865973] sof-audio-pci 0000:00:0e.0: Debug PCIW: 00000004 at 00000044
    [40556.865975] sof-audio-pci 0000:00:0e.0: booting DSP firmware
    [40556.866080] sof-audio-pci 0000:00:0e.0: period_bytes:0x0
    [40556.866083] sof-audio-pci 0000:00:0e.0: periods:1
    [40556.866129] sof-audio-pci 0000:00:0e.0: FW Poll Status: reg=0x3030303 successful
    [40556.866134] sof-audio-pci 0000:00:0e.0: FW Poll Status: reg=0x3030302 successful
    [40556.866138] sof-audio-pci 0000:00:0e.0: unstall/run core: core_mask = 1
    [40556.866143] sof-audio-pci 0000:00:0e.0: DSP core(s) enabled? 1 : core_mask 1
    [40556.867278] sof-audio-pci 0000:00:0e.0: FW Poll Status: reg=0x40000000 successful
    [40556.867284] sof-audio-pci 0000:00:0e.0: FW Poll Status: reg=0x1010202 successful
    [40556.884155] sof-audio-pci 0000:00:0e.0: FW Poll Status: reg=0x5000001 successful
    [40556.904344] sof-audio-pci 0000:00:0e.0: FW Poll Status: reg=0x5 successful
    [40556.904375] sof-audio-pci 0000:00:0e.0: Firmware download successful, booting...
    [40556.904430] sof-audio-pci 0000:00:0e.0: ipc rx: 0x70000000: FW_READY
    [40556.904436] sof-audio-pci 0000:00:0e.0: ipc: DSP is ready 0x70000000 offset 0x81000
    [40556.904441] sof-audio-pci 0000:00:0e.0: ipc rx done: 0x70000000: FW_READY
    [40556.904476] sof-audio-pci 0000:00:0e.0: firmware boot complete
    [40556.904487] sof-audio-pci 0000:00:0e.0: Debug PCIR: 00000000 at 00000048
    [40556.904494] sof-audio-pci 0000:00:0e.0: Debug PCIW: 00000002 at 00000048
    [40556.904501] sof-audio-pci 0000:00:0e.0: Debug PCIR: 00000004 at 00000044
    [40556.904508] sof-audio-pci 0000:00:0e.0: Debug PCIW: 00000000 at 00000044
    [40556.904530] sof-audio-pci 0000:00:0e.0: period_bytes:0x0
    [40556.904535] sof-audio-pci 0000:00:0e.0: periods:1
    [40556.904545] sof-audio-pci 0000:00:0e.0: stream_tag: 1
    [40556.904575] sof-audio-pci 0000:00:0e.0: ipc tx: 0x90010000: GLB_TRACE_MSG
    [40556.905232] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x90010000: GLB_TRACE_MSG
    [40556.905286] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30010000: GLB_TPLG_MSG: COMP_NEW
    [40556.905452] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30010000: GLB_TPLG_MSG: COMP_NEW
    [40556.905490] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30010000: GLB_TPLG_MSG: COMP_NEW
    [40556.905627] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30010000: GLB_TPLG_MSG: COMP_NEW
    [40556.905660] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30200000: GLB_TPLG_MSG: BUFFER_NEW
    [40556.905802] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30200000: GLB_TPLG_MSG: BUFFER_NEW
    [40556.905834] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30200000: GLB_TPLG_MSG: BUFFER_NEW
    [40556.905930] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30200000: GLB_TPLG_MSG: BUFFER_NEW
    [40556.905963] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30010000: GLB_TPLG_MSG: COMP_NEW
    [40556.906179] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30010000: GLB_TPLG_MSG: COMP_NEW
    [40556.906224] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30100000: GLB_TPLG_MSG: PIPE_NEW
    [40556.906315] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30100000: GLB_TPLG_MSG: PIPE_NEW
    [40556.906329] sof-audio-pci 0000:00:0e.0: DSP core(s) enabled? 1 : core_mask 1
    [40556.906347] sof-audio-pci 0000:00:0e.0: ipc tx: 0x40070000: GLB_PM_MSG: CORE_ENABLE
    [40556.906402] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x40070000: GLB_PM_MSG: CORE_ENABLE
    [40556.906432] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30010000: GLB_TPLG_MSG: COMP_NEW
    [40556.906517] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30010000: GLB_TPLG_MSG: COMP_NEW
    [40556.906548] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30010000: GLB_TPLG_MSG: COMP_NEW
    [40556.906630] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30010000: GLB_TPLG_MSG: COMP_NEW
    [40556.906655] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30200000: GLB_TPLG_MSG: BUFFER_NEW
    [40556.906741] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30200000: GLB_TPLG_MSG: BUFFER_NEW
    [40556.906766] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30200000: GLB_TPLG_MSG: BUFFER_NEW
    [40556.906847] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30200000: GLB_TPLG_MSG: BUFFER_NEW
    [40556.906907] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30010000: GLB_TPLG_MSG: COMP_NEW
    [40556.906996] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30010000: GLB_TPLG_MSG: COMP_NEW
    [40556.907023] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30100000: GLB_TPLG_MSG: PIPE_NEW
    [40556.907099] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30100000: GLB_TPLG_MSG: PIPE_NEW
    [40556.907111] sof-audio-pci 0000:00:0e.0: DSP core(s) enabled? 1 : core_mask 1
    [40556.907128] sof-audio-pci 0000:00:0e.0: ipc tx: 0x40070000: GLB_PM_MSG: CORE_ENABLE
    [40556.907182] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x40070000: GLB_PM_MSG: CORE_ENABLE
    [40556.907212] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30010000: GLB_TPLG_MSG: COMP_NEW
    [40556.907297] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30010000: GLB_TPLG_MSG: COMP_NEW
    [40556.907327] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30010000: GLB_TPLG_MSG: COMP_NEW
    [40556.907412] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30010000: GLB_TPLG_MSG: COMP_NEW
    [40556.907436] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30200000: GLB_TPLG_MSG: BUFFER_NEW
    [40556.907519] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30200000: GLB_TPLG_MSG: BUFFER_NEW
    [40556.907544] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30200000: GLB_TPLG_MSG: BUFFER_NEW
    [40556.907626] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30200000: GLB_TPLG_MSG: BUFFER_NEW
    [40556.907656] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30010000: GLB_TPLG_MSG: COMP_NEW
    [40556.907845] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30010000: GLB_TPLG_MSG: COMP_NEW
    [40556.907883] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30100000: GLB_TPLG_MSG: PIPE_NEW
    [40556.907966] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30100000: GLB_TPLG_MSG: PIPE_NEW
    [40556.907978] sof-audio-pci 0000:00:0e.0: DSP core(s) enabled? 1 : core_mask 1
    [40556.907997] sof-audio-pci 0000:00:0e.0: ipc tx: 0x40070000: GLB_PM_MSG: CORE_ENABLE
    [40556.908233] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x40070000: GLB_PM_MSG: CORE_ENABLE
    [40556.908265] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30010000: GLB_TPLG_MSG: COMP_NEW
    [40556.908354] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30010000: GLB_TPLG_MSG: COMP_NEW
    [40556.908384] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30010000: GLB_TPLG_MSG: COMP_NEW
    [40556.908469] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30010000: GLB_TPLG_MSG: COMP_NEW
    [40556.908494] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30200000: GLB_TPLG_MSG: BUFFER_NEW
    [40556.908580] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30200000: GLB_TPLG_MSG: BUFFER_NEW
    [40556.908605] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30200000: GLB_TPLG_MSG: BUFFER_NEW
    [40556.908689] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30200000: GLB_TPLG_MSG: BUFFER_NEW
    [40556.908719] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30010000: GLB_TPLG_MSG: COMP_NEW
    [40556.908811] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30010000: GLB_TPLG_MSG: COMP_NEW
    [40556.908838] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30100000: GLB_TPLG_MSG: PIPE_NEW
    [40556.908925] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30100000: GLB_TPLG_MSG: PIPE_NEW
    [40556.908938] sof-audio-pci 0000:00:0e.0: DSP core(s) enabled? 1 : core_mask 1
    [40556.908954] sof-audio-pci 0000:00:0e.0: ipc tx: 0x40070000: GLB_PM_MSG: CORE_ENABLE
    [40556.909012] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x40070000: GLB_PM_MSG: CORE_ENABLE
    [40556.909040] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30010000: GLB_TPLG_MSG: COMP_NEW
    [40556.909129] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30010000: GLB_TPLG_MSG: COMP_NEW
    [40556.909157] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30010000: GLB_TPLG_MSG: COMP_NEW
    [40556.909245] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30010000: GLB_TPLG_MSG: COMP_NEW
    [40556.909268] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30200000: GLB_TPLG_MSG: BUFFER_NEW
    [40556.909353] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30200000: GLB_TPLG_MSG: BUFFER_NEW
    [40556.909376] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30200000: GLB_TPLG_MSG: BUFFER_NEW
    [40556.909462] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30200000: GLB_TPLG_MSG: BUFFER_NEW
    [40556.909490] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30010000: GLB_TPLG_MSG: COMP_NEW
    [40556.909605] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30010000: GLB_TPLG_MSG: COMP_NEW
    [40556.909629] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30100000: GLB_TPLG_MSG: PIPE_NEW
    [40556.909717] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30100000: GLB_TPLG_MSG: PIPE_NEW
    [40556.909729] sof-audio-pci 0000:00:0e.0: DSP core(s) enabled? 1 : core_mask 1
    [40556.909743] sof-audio-pci 0000:00:0e.0: ipc tx: 0x40070000: GLB_PM_MSG: CORE_ENABLE
    [40556.909798] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x40070000: GLB_PM_MSG: CORE_ENABLE
    [40556.909826] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30010000: GLB_TPLG_MSG: COMP_NEW
    [40556.909917] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30010000: GLB_TPLG_MSG: COMP_NEW
    [40556.909947] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30010000: GLB_TPLG_MSG: COMP_NEW
    [40556.910036] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30010000: GLB_TPLG_MSG: COMP_NEW
    [40556.910059] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30200000: GLB_TPLG_MSG: BUFFER_NEW
    [40556.910147] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30200000: GLB_TPLG_MSG: BUFFER_NEW
    [40556.910170] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30200000: GLB_TPLG_MSG: BUFFER_NEW
    [40556.910257] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30200000: GLB_TPLG_MSG: BUFFER_NEW
    [40556.910285] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30010000: GLB_TPLG_MSG: COMP_NEW
    [40556.910380] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30010000: GLB_TPLG_MSG: COMP_NEW
    [40556.910406] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30100000: GLB_TPLG_MSG: PIPE_NEW
    [40556.910496] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30100000: GLB_TPLG_MSG: PIPE_NEW
    [40556.910508] sof-audio-pci 0000:00:0e.0: DSP core(s) enabled? 1 : core_mask 1
    [40556.910523] sof-audio-pci 0000:00:0e.0: ipc tx: 0x40070000: GLB_PM_MSG: CORE_ENABLE
    [40556.910578] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x40070000: GLB_PM_MSG: CORE_ENABLE
    [40556.910605] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30010000: GLB_TPLG_MSG: COMP_NEW
    [40556.910698] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30010000: GLB_TPLG_MSG: COMP_NEW
    [40556.910727] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30010000: GLB_TPLG_MSG: COMP_NEW
    [40556.910818] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30010000: GLB_TPLG_MSG: COMP_NEW
    [40556.910841] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30200000: GLB_TPLG_MSG: BUFFER_NEW
    [40556.910933] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30200000: GLB_TPLG_MSG: BUFFER_NEW
    [40556.910956] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30200000: GLB_TPLG_MSG: BUFFER_NEW
    [40556.911046] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30200000: GLB_TPLG_MSG: BUFFER_NEW
    [40556.911074] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30010000: GLB_TPLG_MSG: COMP_NEW
    [40556.911192] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30010000: GLB_TPLG_MSG: COMP_NEW
    [40556.911216] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30100000: GLB_TPLG_MSG: PIPE_NEW
    [40556.911311] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30100000: GLB_TPLG_MSG: PIPE_NEW
    [40556.911323] sof-audio-pci 0000:00:0e.0: DSP core(s) enabled? 1 : core_mask 1
    [40556.911338] sof-audio-pci 0000:00:0e.0: ipc tx: 0x40070000: GLB_PM_MSG: CORE_ENABLE
    [40556.911393] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x40070000: GLB_PM_MSG: CORE_ENABLE
    [40556.911420] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30010000: GLB_TPLG_MSG: COMP_NEW
    [40556.911515] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30010000: GLB_TPLG_MSG: COMP_NEW
    [40556.911543] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30010000: GLB_TPLG_MSG: COMP_NEW
    [40556.911636] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30010000: GLB_TPLG_MSG: COMP_NEW
    [40556.911659] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30200000: GLB_TPLG_MSG: BUFFER_NEW
    [40556.911757] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30200000: GLB_TPLG_MSG: BUFFER_NEW
    [40556.911780] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30200000: GLB_TPLG_MSG: BUFFER_NEW
    [40556.911872] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30200000: GLB_TPLG_MSG: BUFFER_NEW
    [40556.911915] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30010000: GLB_TPLG_MSG: COMP_NEW
    [40556.912015] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30010000: GLB_TPLG_MSG: COMP_NEW
    [40556.912039] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30100000: GLB_TPLG_MSG: PIPE_NEW
    [40556.912137] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30100000: GLB_TPLG_MSG: PIPE_NEW
    [40556.912149] sof-audio-pci 0000:00:0e.0: DSP core(s) enabled? 1 : core_mask 1
    [40556.912163] sof-audio-pci 0000:00:0e.0: ipc tx: 0x40070000: GLB_PM_MSG: CORE_ENABLE
    [40556.912219] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x40070000: GLB_PM_MSG: CORE_ENABLE
    [40556.912246] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30010000: GLB_TPLG_MSG: COMP_NEW
    [40556.912342] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30010000: GLB_TPLG_MSG: COMP_NEW
    [40556.912365] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30200000: GLB_TPLG_MSG: BUFFER_NEW
    [40556.912468] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30200000: GLB_TPLG_MSG: BUFFER_NEW
    [40556.912496] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30010000: GLB_TPLG_MSG: COMP_NEW
    [40556.912643] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30010000: GLB_TPLG_MSG: COMP_NEW
    [40556.912668] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30100000: GLB_TPLG_MSG: PIPE_NEW
    [40556.912778] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30100000: GLB_TPLG_MSG: PIPE_NEW
    [40556.912790] sof-audio-pci 0000:00:0e.0: DSP core(s) enabled? 1 : core_mask 1
    [40556.912804] sof-audio-pci 0000:00:0e.0: ipc tx: 0x40070000: GLB_PM_MSG: CORE_ENABLE
    [40556.912860] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x40070000: GLB_PM_MSG: CORE_ENABLE
    [40556.912897] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
    [40556.912956] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
    [40556.912976] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
    [40556.913036] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
    [40556.913056] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
    [40556.913116] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
    [40556.913136] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
    [40556.913197] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
    [40556.913218] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
    [40556.913285] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
    [40556.913306] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
    [40556.913369] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
    [40556.913389] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
    [40556.913455] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
    [40556.913475] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
    [40556.913540] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
    [40556.913561] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
    [40556.913627] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
    [40556.913647] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
    [40556.913715] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
    [40556.913735] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
    [40556.913809] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
    [40556.913829] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
    [40556.913916] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
    [40556.913938] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
    [40556.914012] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
    [40556.914032] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
    [40556.914105] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
    [40556.914126] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
    [40556.914201] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
    [40556.914221] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
    [40556.914298] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
    [40556.914318] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
    [40556.914421] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
    [40556.914440] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
    [40556.914515] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
    [40556.914534] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
    [40556.914612] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
    [40556.914631] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
    [40556.914697] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
    [40556.914708] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
    [40556.914778] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
    [40556.914789] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
    [40556.914862] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
    [40556.914880] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
    [40556.914953] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
    [40556.914964] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
    [40556.915037] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
    [40556.915048] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
    [40556.915124] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
    [40556.915135] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
    [40556.915184] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
    [40556.915196] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
    [40556.915248] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
    [40556.915260] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
    [40556.915316] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
    [40556.915327] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
    [40556.915387] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
    [40556.915398] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
    [40556.915461] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
    [40556.915471] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
    [40556.915539] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
    [40556.915549] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
    [40556.915619] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
    [40556.915631] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
    [40556.915704] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
    [40556.915716] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
    [40556.915793] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
    [40556.915815] sof-audio-pci 0000:00:0e.0: ipc tx: 0x80010000: GLB_DAI_MSG: CONFIG
    [40556.915914] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x80010000: GLB_DAI_MSG: CONFIG
    [40556.915935] sof-audio-pci 0000:00:0e.0: ipc tx: 0x80010000: GLB_DAI_MSG: CONFIG
    [40556.916023] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x80010000: GLB_DAI_MSG: CONFIG
    [40556.916044] sof-audio-pci 0000:00:0e.0: ipc tx: 0x80010000: GLB_DAI_MSG: CONFIG
    [40556.916138] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x80010000: GLB_DAI_MSG: CONFIG
    [40556.916159] sof-audio-pci 0000:00:0e.0: ipc tx: 0x80010000: GLB_DAI_MSG: CONFIG
    [40556.916246] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x80010000: GLB_DAI_MSG: CONFIG
    [40556.916265] sof-audio-pci 0000:00:0e.0: ipc tx: 0x80010000: GLB_DAI_MSG: CONFIG
    [40556.916361] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x80010000: GLB_DAI_MSG: CONFIG
    [40556.916381] sof-audio-pci 0000:00:0e.0: ipc tx: 0x80010000: GLB_DAI_MSG: CONFIG
    [40556.916468] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x80010000: GLB_DAI_MSG: CONFIG
    [40556.916488] sof-audio-pci 0000:00:0e.0: ipc tx: 0x80010000: GLB_DAI_MSG: CONFIG
    [40556.916584] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x80010000: GLB_DAI_MSG: CONFIG
    [40556.916604] sof-audio-pci 0000:00:0e.0: ipc tx: 0x80010000: GLB_DAI_MSG: CONFIG
    [40556.916690] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x80010000: GLB_DAI_MSG: CONFIG
    [40556.916714] sof-audio-pci 0000:00:0e.0: ipc tx: 0x80010000: GLB_DAI_MSG: CONFIG
    [40556.917001] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x80010000: GLB_DAI_MSG: CONFIG
    [40556.917008] sof-audio-pci 0000:00:0e.0: tplg: complete pipeline PIPELINE.13.DMIC0.IN id 51
    [40556.917016] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30130000: GLB_TPLG_MSG: PIPE_COMPLETE
    [40556.917133] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30130000: GLB_TPLG_MSG: PIPE_COMPLETE
    [40556.917139] sof-audio-pci 0000:00:0e.0: tplg: complete pipeline PIPELINE.12.SSP5.IN id 47
    [40556.917147] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30130000: GLB_TPLG_MSG: PIPE_COMPLETE
    [40556.917219] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30130000: GLB_TPLG_MSG: PIPE_COMPLETE
    [40556.917225] sof-audio-pci 0000:00:0e.0: tplg: complete pipeline PIPELINE.11.SSP5.OUT id 41
    [40556.917233] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30130000: GLB_TPLG_MSG: PIPE_COMPLETE
    [40556.917300] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30130000: GLB_TPLG_MSG: PIPE_COMPLETE
    [40556.917305] sof-audio-pci 0000:00:0e.0: tplg: complete pipeline PIPELINE.8.SSP3.IN id 35
    [40556.917313] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30130000: GLB_TPLG_MSG: PIPE_COMPLETE
    [40556.917378] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30130000: GLB_TPLG_MSG: PIPE_COMPLETE
    [40556.917383] sof-audio-pci 0000:00:0e.0: tplg: complete pipeline PIPELINE.7.SSP3.OUT id 29
    [40556.917391] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30130000: GLB_TPLG_MSG: PIPE_COMPLETE
    [40556.917452] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30130000: GLB_TPLG_MSG: PIPE_COMPLETE
    [40556.917458] sof-audio-pci 0000:00:0e.0: tplg: complete pipeline PIPELINE.4.SSP1.IN id 23
    [40556.917466] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30130000: GLB_TPLG_MSG: PIPE_COMPLETE
    [40556.917525] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30130000: GLB_TPLG_MSG: PIPE_COMPLETE
    [40556.917530] sof-audio-pci 0000:00:0e.0: tplg: complete pipeline PIPELINE.3.SSP1.OUT id 17
    [40556.917539] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30130000: GLB_TPLG_MSG: PIPE_COMPLETE
    [40556.917593] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30130000: GLB_TPLG_MSG: PIPE_COMPLETE
    [40556.917599] sof-audio-pci 0000:00:0e.0: tplg: complete pipeline PIPELINE.2.SSP0.IN id 11
    [40556.917607] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30130000: GLB_TPLG_MSG: PIPE_COMPLETE
    [40556.917659] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30130000: GLB_TPLG_MSG: PIPE_COMPLETE
    [40556.917665] sof-audio-pci 0000:00:0e.0: tplg: complete pipeline PIPELINE.1.SSP0.OUT id 5
    [40556.917673] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30130000: GLB_TPLG_MSG: PIPE_COMPLETE
    [40556.917727] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30130000: GLB_TPLG_MSG: PIPE_COMPLETE
    [40556.917747] sof-audio-pci 0000:00:0e.0: ipc tx: 0x50010000: GLB_COMP_MSG: SET_VALUE
    [40556.917870] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x50010000: GLB_COMP_MSG: SET_VALUE
    [40556.917896] sof-audio-pci 0000:00:0e.0: ipc tx: 0x50010000: GLB_COMP_MSG: SET_VALUE
    [40556.918016] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x50010000: GLB_COMP_MSG: SET_VALUE
    [40556.918035] sof-audio-pci 0000:00:0e.0: ipc tx: 0x50010000: GLB_COMP_MSG: SET_VALUE
    [40556.918154] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x50010000: GLB_COMP_MSG: SET_VALUE
    [40556.918174] sof-audio-pci 0000:00:0e.0: ipc tx: 0x50010000: GLB_COMP_MSG: SET_VALUE
    [40556.918290] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x50010000: GLB_COMP_MSG: SET_VALUE
    [40556.918309] sof-audio-pci 0000:00:0e.0: ipc tx: 0x50010000: GLB_COMP_MSG: SET_VALUE
    [40556.918422] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x50010000: GLB_COMP_MSG: SET_VALUE
    [40556.918442] sof-audio-pci 0000:00:0e.0: ipc tx: 0x50010000: GLB_COMP_MSG: SET_VALUE
    [40556.918555] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x50010000: GLB_COMP_MSG: SET_VALUE
    [40556.918574] sof-audio-pci 0000:00:0e.0: ipc tx: 0x50010000: GLB_COMP_MSG: SET_VALUE
    [40556.918685] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x50010000: GLB_COMP_MSG: SET_VALUE
    [40556.918704] sof-audio-pci 0000:00:0e.0: ipc tx: 0x50010000: GLB_COMP_MSG: SET_VALUE
    [40556.918792] sof-audio-pci 0000:00:0e.0: ipc rx: 0x90020000: GLB_TRACE_MSG
    [40556.918808] sof-audio-pci 0000:00:0e.0: ipc rx done: 0x90020000: GLB_TRACE_MSG
    [40556.918876] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x50010000: GLB_COMP_MSG: SET_VALUE
    [40556.918899] sof-audio-pci 0000:00:0e.0: ipc tx: 0x40020000: GLB_PM_MSG: CTX_RESTORE
    [40556.918943] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x40020000: GLB_PM_MSG: CTX_RESTORE
    [40556.918969] sof-audio-pci 0000:00:0e.0: ipc tx: 0x40010000: GLB_PM_MSG: CTX_SAVE
    [40556.919031] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x40010000: GLB_PM_MSG: CTX_SAVE
    [40556.919041] sof-audio-pci 0000:00:0e.0: FW Poll Status: reg=0x1010303 successful
    [40556.919871] sof-audio-pci 0000:00:0e.0: FW Poll Status: reg=0x303 successful
    [40556.919874] sof-audio-pci 0000:00:0e.0: DSP core(s) enabled? 0 : core_mask 3
    [40556.919879] sof-audio-pci 0000:00:0e.0: Debug PCIR: 00000010 at 00000044
    [40557.024373] ACPI: Preparing to enter system sleep state S3
    [40557.025011] PM: Saving platform NVS memory
    [40557.025018] Disabling non-boot CPUs ...
    [40557.031571] IRQ 8: no longer affine to CPU1
    [40557.031650] IRQ 121: no longer affine to CPU1
    [40557.031656] IRQ 123: no longer affine to CPU1
    [40557.031660] IRQ 124: no longer affine to CPU1
    [40557.031666] IRQ 125: no longer affine to CPU1
    [40557.032720] smpboot: CPU 1 is now offline
    [40557.035588] ACPI: Low-level resume complete
    [40557.035750] PM: Restoring platform NVS memory
    [40557.036679] Enabling non-boot CPUs ...
    [40557.036742] x86: Booting SMP configuration:
    [40557.036744] smpboot: Booting Node 0 Processor 1 APIC 0x4
    [40557.038024] cache: parent cpu1 should not be sleeping
    [40557.038470] CPU1 is up
    [40557.040145] ACPI: Waking up from system sleep state S3
    [40557.071128] sof-audio-pci 0000:00:0e.0: Debug PCIR: 00000000 at 00000044
    [40557.072535] sof-audio-pci 0000:00:0e.0: loading firmware
    [40557.072552] sof-audio-pci 0000:00:0e.0: Debug PCIR: 00000002 at 00000048
    [40557.072558] sof-audio-pci 0000:00:0e.0: Debug PCIW: 00000000 at 00000048
    [40557.072564] sof-audio-pci 0000:00:0e.0: Debug PCIR: 00000000 at 00000044
    [40557.072569] sof-audio-pci 0000:00:0e.0: Debug PCIW: 00000004 at 00000044
    [40557.072572] sof-audio-pci 0000:00:0e.0: booting DSP firmware
    [40557.072738] sof-audio-pci 0000:00:0e.0: period_bytes:0x0
    [40557.072742] sof-audio-pci 0000:00:0e.0: periods:1
    [40557.134197] sof-audio-pci 0000:00:0e.0: FW Poll Status: reg=0x1d003c timedout
    [40557.134200] sof-audio-pci 0000:00:0e.0: error: timeout on core powerup
    [40557.134205] sof-audio-pci 0000:00:0e.0: error: power up core failed core_mask 3adspcs 0x1d003c
    [40557.134207] sof-audio-pci 0000:00:0e.0: error: dsp core 0/1 power up failed
    [40557.134211] sof-audio-pci 0000:00:0e.0: unknown ROM status value 01006701
    [40557.134215] sof-audio-pci 0000:00:0e.0: error: status = 0x001d003c panic = 0x00000001
    [40557.134218] sof-audio-pci 0000:00:0e.0: unknown ROM status value 01006701
    [40557.184688] sof-audio-pci 0000:00:0e.0: FW Poll Status: reg=0x1d003c timedout
    [40557.184699] sof-audio-pci 0000:00:0e.0: error: reset enter failed: core_mask 3 adspcs 0x1d003c
    [40557.184704] sof-audio-pci 0000:00:0e.0: error: dsp core reset failed: core_mask 3
    [40557.184711] sof-audio-pci 0000:00:0e.0: error: Error code=0x1d003c: FW status=0x1006701
    [40557.184715] sof-audio-pci 0000:00:0e.0: error: iteration 0 of Core En/ROM load failed: -5
    [40557.243583] sof-audio-pci 0000:00:0e.0: FW Poll Status: reg=0x1d003c timedout
    [40557.243590] sof-audio-pci 0000:00:0e.0: error: timeout on core powerup
    [40557.243598] sof-audio-pci 0000:00:0e.0: error: power up core failed core_mask 3adspcs 0x1d003c
    [40557.243602] sof-audio-pci 0000:00:0e.0: error: dsp core 0/1 power up failed
    [40557.243609] sof-audio-pci 0000:00:0e.0: unknown ROM status value 01006701
    [40557.243616] sof-audio-pci 0000:00:0e.0: error: status = 0x001d003c panic = 0x00000001
    [40557.243621] sof-audio-pci 0000:00:0e.0: unknown ROM status value 01006701
    [40557.296305] sof-audio-pci 0000:00:0e.0: FW Poll Status: reg=0x1d003c timedout
    [40557.296317] sof-audio-pci 0000:00:0e.0: error: reset enter failed: core_mask 3 adspcs 0x1d003c
    [40557.296322] sof-audio-pci 0000:00:0e.0: error: dsp core reset failed: core_mask 3
    [40557.296329] sof-audio-pci 0000:00:0e.0: error: Error code=0x1d003c: FW status=0x1006701
    [40557.296334] sof-audio-pci 0000:00:0e.0: error: iteration 1 of Core En/ROM load failed: -5
    [40557.348922] sof-audio-pci 0000:00:0e.0: FW Poll Status: reg=0x1d003c timedout
    [40557.348930] sof-audio-pci 0000:00:0e.0: error: timeout on core powerup
    [40557.348938] sof-audio-pci 0000:00:0e.0: error: power up core failed core_mask 3adspcs 0x1d003c
    [40557.348943] sof-audio-pci 0000:00:0e.0: error: dsp core 0/1 power up failed
    [40557.348951] sof-audio-pci 0000:00:0e.0: unknown ROM status value 01006701
    [40557.348958] sof-audio-pci 0000:00:0e.0: error: status = 0x001d003c panic = 0x00000001
    [40557.348964] sof-audio-pci 0000:00:0e.0: unknown ROM status value 01006701
    [40557.380444] ata2: SATA link down (SStatus 4 SControl 300)
    [40557.380499] ata1: SATA link down (SStatus 4 SControl 300)
    [40557.401001] sof-audio-pci 0000:00:0e.0: FW Poll Status: reg=0x1d003c timedout
    [40557.401013] sof-audio-pci 0000:00:0e.0: error: reset enter failed: core_mask 3 adspcs 0x1d003c
    [40557.401018] sof-audio-pci 0000:00:0e.0: error: dsp core reset failed: core_mask 3
    [40557.401026] sof-audio-pci 0000:00:0e.0: error: Error code=0x1d003c: FW status=0x1006701
    [40557.401031] sof-audio-pci 0000:00:0e.0: error: iteration 2 of Core En/ROM load failed: -5
    [40557.401036] sof-audio-pci 0000:00:0e.0: error: dsp init failed after 3 attempts with err: -5
    [40557.401108] sof-audio-pci 0000:00:0e.0: unknown ROM status value 01006701
    [40557.401115] sof-audio-pci 0000:00:0e.0: error: status = 0x001d003c panic = 0x00000001
    [40557.401122] sof-audio-pci 0000:00:0e.0: unknown ROM status value 01006701
    [40557.401128] sof-audio-pci 0000:00:0e.0: error: failed to reset DSP
    [40557.401133] sof-audio-pci 0000:00:0e.0: error: failed to boot DSP firmware after resume -5
    [40557.401148] dpm_run_callback(): pci_pm_resume+0x0/0xa0 returns -5
    [40557.401155] PM: Device 0000:00:0e.0 failed to resume: error -5
    [40557.402578] OOM killer enabled.
    [40557.402581] Restarting tasks ... done.
    [40557.413684] video LNXVIDEO:00: Restoring backlight state
    [40557.413686] PM: suspend exit
    [40557.414529] r8169 0000:02:00.0 enp2s0: Link is Down
    [40557.414702] r8169 0000:03:00.0 enp3s0: Link is Down
    [40558.800611] r8169 0000:02:00.0 enp2s0: Link is Up - 100Mbps/Full - flow control rx/tx
    [40559.731783] sof-audio-pci 0000:00:0e.0: ipc tx: 0x40010000: GLB_PM_MSG: CTX_SAVE
    [40560.035514] sof-audio-pci 0000:00:0e.0: error: ipc timed out for 0x40010000 size 0x4c
    [40560.035536] sof-audio-pci 0000:00:0e.0: unknown ROM status value 01006701
    [40560.035544] sof-audio-pci 0000:00:0e.0: error: status = 0x001d003c panic = 0x00000000
    [40560.035553] sof-audio-pci 0000:00:0e.0: unknown ROM status value 01006701
    [40560.035566] sof-audio-pci 0000:00:0e.0: error: ctx_save ipc error during suspend -110
    [40560.088434] sof-audio-pci 0000:00:0e.0: FW Poll Status: reg=0x1d003c timedout
    [40560.088447] sof-audio-pci 0000:00:0e.0: error: reset enter failed: core_mask 3 adspcs 0x1d003c
    [40560.088461] sof-audio-pci 0000:00:0e.0: error: dsp core reset failed: core_mask 3
    [40560.088468] sof-audio-pci 0000:00:0e.0: error: failed to power down core during suspend
    [40560.088476] sof-audio-pci 0000:00:0e.0: error: failed to power down DSP during suspend -5
    [40560.088496] sof-audio-pci 0000:00:0e.0: can't suspend (snd_sof_runtime_suspend [snd_sof] returned -5)

  13. lgirdwood commented on Mar 19, 2019

    @lgirdwood
    lgirdwood
    on Mar 19, 2019
    Member

    @ranj063 thanks for the update, did we try and extend the ROM timeout ?

  14. ranj063 commented on Mar 19, 2019

    @ranj063
    ranj063
    on Mar 19, 2019
    Collaborator

    @lgirdwood yes, for APL it has been increased from 70 to 150ms. And for CML it has been increased to 300 ms

  15. ranj063 commented on Mar 20, 2019

    @ranj063
    ranj063
    on Mar 20, 2019
    Collaborator

    I've rolled back the build version 20190227 to check this issue, it works well. However, this issue is always exist since issue #1054 occurred (Fixed by thesofproject/linux#675 and #1047) and never disappear. Anyway, the S3 operation is working (suspend/resume), but, the aplay will be failed till now.

    @stevyan could you please check something for me with the build version 20190227?
    Was the DSP getting powered off and on during the S3 test? If you could send me the dmesg logs that would be even better. Thanks a bunch!

  16. Jiangxinx commented on Mar 20, 2019

    @Jiangxinx
    Jiangxinx
    on Mar 20, 2019
    ContributorAuthor

    @ranj063 I have checked the DSP status with version 20190227 and daily version.
    step:
    1.sudo ./stress-s3.sh 50 1 0
    2.sudo cat /sys/bus/pci/devices/0000:00:0e.0/power/runtime_status

    Output(20190227):
    active

    Dmesg(20190227):

    [  158.785845] sof-audio-pci 0000:00:0e.0: Debug PCIR: 00000000 at  00000044
[  158.785853] sof-audio-pci 0000:00:0e.0: Debug PCIR: 00000040 at  00000048
[  158.785859] sof-audio-pci 0000:00:0e.0: Debug PCIW: 00000000 at  00000048
[  158.788827] sof-audio-pci 0000:00:0e.0: Debug PCIR: 00000000 at  00000048
[  158.788835] sof-audio-pci 0000:00:0e.0: Debug PCIW: 00000040 at  00000048
[  158.788842] sof-audio-pci 0000:00:0e.0: Debug PCIR: 00000040 at  00000048
[  158.788848] sof-audio-pci 0000:00:0e.0: Debug PCIW: 00000000 at  00000048
[  158.788854] sof-audio-pci 0000:00:0e.0: Debug PCIR: 00000000 at  00000048
[  158.788860] sof-audio-pci 0000:00:0e.0: Debug PCIW: 00000040 at  00000048
[  158.788916] sof-audio-pci 0000:00:0e.0: loading firmware
[  158.788935] sof-audio-pci 0000:00:0e.0: Debug PCIR: 00000002 at  00000048
[  158.788939] sof-audio-pci 0000:00:0e.0: Debug PCIW: 00000000 at  00000048
[  158.788945] sof-audio-pci 0000:00:0e.0: Debug PCIR: 00000000 at  00000044
[  158.788950] sof-audio-pci 0000:00:0e.0: Debug PCIW: 00000004 at  00000044
[  158.788953] sof-audio-pci 0000:00:0e.0: booting DSP firmware
[  158.789125] sof-audio-pci 0000:00:0e.0: period_bytes:0x0
[  158.789129] sof-audio-pci 0000:00:0e.0: periods:1
[  158.790242] sof-audio-pci 0000:00:0e.0: FW Poll Status: reg=0x3030303 successful
[  158.790251] sof-audio-pci 0000:00:0e.0: FW Poll Status: reg=0x3030302 successful
[  158.790256] sof-audio-pci 0000:00:0e.0: unstall/run core: core_mask = 1
[  158.790264] sof-audio-pci 0000:00:0e.0: DSP core(s) enabled? 1 : core_mask 1
[  158.791414] sof-audio-pci 0000:00:0e.0: FW Poll Status: reg=0x40000000 successful
[  158.791420] sof-audio-pci 0000:00:0e.0: FW Poll Status: reg=0x1010202 successful
[  158.815708] sof-audio-pci 0000:00:0e.0: FW Poll Status: reg=0x5000001 successful
[  158.835776] sof-audio-pci 0000:00:0e.0: FW Poll Status: reg=0x5 successful
[  158.835783] sof-audio-pci 0000:00:0e.0: Firmware download successful, booting...
[  158.840532] sof-audio-pci 0000:00:0e.0: ipc rx: 0x70000000: FW_READY
[  158.840535] sof-audio-pci 0000:00:0e.0: ipc: DSP is ready 0x70000000 offset 0x81000
[  158.840540] sof-audio-pci 0000:00:0e.0: ipc rx done: 0x70000000: FW_READY
[  158.840665] sof-audio-pci 0000:00:0e.0: firmware boot complete
[  158.840676] sof-audio-pci 0000:00:0e.0: Debug PCIR: 00000000 at  00000048
[  158.840680] sof-audio-pci 0000:00:0e.0: Debug PCIW: 00000002 at  00000048
[  158.840685] sof-audio-pci 0000:00:0e.0: Debug PCIR: 00000004 at  00000044
[  158.840688] sof-audio-pci 0000:00:0e.0: Debug PCIW: 00000000 at  00000044
[  158.840707] sof-audio-pci 0000:00:0e.0: period_bytes:0x0
[  158.840709] sof-audio-pci 0000:00:0e.0: periods:1
[  158.840716] sof-audio-pci 0000:00:0e.0: stream_tag: 1
[  158.840745] sof-audio-pci 0000:00:0e.0: ipc tx: 0x90010000: GLB_TRACE_MSG
[  158.841429] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x90010000: GLB_TRACE_MSG
[  158.841472] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30010000: GLB_TPLG_MSG: COMP_NEW
[  158.841629] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30010000: GLB_TPLG_MSG: COMP_NEW
[  158.841673] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30010000: GLB_TPLG_MSG: COMP_NEW
[  158.841937] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30010000: GLB_TPLG_MSG: COMP_NEW
[  158.841972] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30200000: GLB_TPLG_MSG: BUFFER_NEW
[  158.842226] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30200000: GLB_TPLG_MSG: BUFFER_NEW
[  158.842261] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30200000: GLB_TPLG_MSG: BUFFER_NEW
[  158.842475] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30200000: GLB_TPLG_MSG: BUFFER_NEW
[  158.842500] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30010000: GLB_TPLG_MSG: COMP_NEW
[  158.842797] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30010000: GLB_TPLG_MSG: COMP_NEW
[  158.842834] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30100000: GLB_TPLG_MSG: PIPE_NEW
[  158.842952] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30100000: GLB_TPLG_MSG: PIPE_NEW
[  158.842969] sof-audio-pci 0000:00:0e.0: DSP core(s) enabled? 1 : core_mask 1
[  158.842984] sof-audio-pci 0000:00:0e.0: ipc tx: 0x40070000: GLB_PM_MSG: CORE_ENABLE
[  158.843215] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x40070000: GLB_PM_MSG: CORE_ENABLE
[  158.843254] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30010000: GLB_TPLG_MSG: COMP_NEW
[  158.843363] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30010000: GLB_TPLG_MSG: COMP_NEW
[  158.843388] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30010000: GLB_TPLG_MSG: COMP_NEW
[  158.843489] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30010000: GLB_TPLG_MSG: COMP_NEW
[  158.843508] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30200000: GLB_TPLG_MSG: BUFFER_NEW
[  158.843718] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30200000: GLB_TPLG_MSG: BUFFER_NEW
[  158.843735] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30200000: GLB_TPLG_MSG: BUFFER_NEW
[  158.843896] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30200000: GLB_TPLG_MSG: BUFFER_NEW
[  158.843936] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30010000: GLB_TPLG_MSG: COMP_NEW
[  158.844025] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30010000: GLB_TPLG_MSG: COMP_NEW
[  158.844043] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30100000: GLB_TPLG_MSG: PIPE_NEW
[  158.844224] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30100000: GLB_TPLG_MSG: PIPE_NEW
[  158.844232] sof-audio-pci 0000:00:0e.0: DSP core(s) enabled? 1 : core_mask 1
[  158.844241] sof-audio-pci 0000:00:0e.0: ipc tx: 0x40070000: GLB_PM_MSG: CORE_ENABLE
[  158.844433] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x40070000: GLB_PM_MSG: CORE_ENABLE
[  158.844456] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30010000: GLB_TPLG_MSG: COMP_NEW
[  158.844641] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30010000: GLB_TPLG_MSG: COMP_NEW
[  158.844690] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30010000: GLB_TPLG_MSG: COMP_NEW
[  158.844956] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30010000: GLB_TPLG_MSG: COMP_NEW
[  158.844991] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30200000: GLB_TPLG_MSG: BUFFER_NEW
[  158.845093] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30200000: GLB_TPLG_MSG: BUFFER_NEW
[  158.845111] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30200000: GLB_TPLG_MSG: BUFFER_NEW
[  158.845214] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30200000: GLB_TPLG_MSG: BUFFER_NEW
[  158.845239] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30010000: GLB_TPLG_MSG: COMP_NEW
[  158.845368] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30010000: GLB_TPLG_MSG: COMP_NEW
[  158.845389] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30100000: GLB_TPLG_MSG: PIPE_NEW
[  158.845561] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30100000: GLB_TPLG_MSG: PIPE_NEW
[  158.845571] sof-audio-pci 0000:00:0e.0: DSP core(s) enabled? 1 : core_mask 1
[  158.845580] sof-audio-pci 0000:00:0e.0: ipc tx: 0x40070000: GLB_PM_MSG: CORE_ENABLE
[  158.845630] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x40070000: GLB_PM_MSG: CORE_ENABLE
[  158.845674] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30010000: GLB_TPLG_MSG: COMP_NEW
[  158.845965] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30010000: GLB_TPLG_MSG: COMP_NEW
[  158.846006] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30010000: GLB_TPLG_MSG: COMP_NEW
[  158.846209] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30010000: GLB_TPLG_MSG: COMP_NEW
[  158.846229] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30200000: GLB_TPLG_MSG: BUFFER_NEW
[  158.846437] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30200000: GLB_TPLG_MSG: BUFFER_NEW
[  158.846455] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30200000: GLB_TPLG_MSG: BUFFER_NEW
[  158.846633] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30200000: GLB_TPLG_MSG: BUFFER_NEW
[  158.846681] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30010000: GLB_TPLG_MSG: COMP_NEW
[  158.846851] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30010000: GLB_TPLG_MSG: COMP_NEW
[  158.846888] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30100000: GLB_TPLG_MSG: PIPE_NEW
[  158.846990] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30100000: GLB_TPLG_MSG: PIPE_NEW
[  158.847001] sof-audio-pci 0000:00:0e.0: DSP core(s) enabled? 1 : core_mask 1
[  158.847012] sof-audio-pci 0000:00:0e.0: ipc tx: 0x40070000: GLB_PM_MSG: CORE_ENABLE
[  158.847183] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x40070000: GLB_PM_MSG: CORE_ENABLE
[  158.847207] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30010000: GLB_TPLG_MSG: COMP_NEW
[  158.847314] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30010000: GLB_TPLG_MSG: COMP_NEW
[  158.847339] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30010000: GLB_TPLG_MSG: COMP_NEW
[  158.847419] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30010000: GLB_TPLG_MSG: COMP_NEW
[  158.847435] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30200000: GLB_TPLG_MSG: BUFFER_NEW
[  158.847514] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30200000: GLB_TPLG_MSG: BUFFER_NEW
[  158.847529] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30200000: GLB_TPLG_MSG: BUFFER_NEW
[  158.847608] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30200000: GLB_TPLG_MSG: BUFFER_NEW
[  158.847629] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30010000: GLB_TPLG_MSG: COMP_NEW
[  158.847748] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30010000: GLB_TPLG_MSG: COMP_NEW
[  158.847767] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30100000: GLB_TPLG_MSG: PIPE_NEW
[  158.847947] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30100000: GLB_TPLG_MSG: PIPE_NEW
[  158.847954] sof-audio-pci 0000:00:0e.0: DSP core(s) enabled? 1 : core_mask 1
[  158.847963] sof-audio-pci 0000:00:0e.0: ipc tx: 0x40070000: GLB_PM_MSG: CORE_ENABLE
[  158.848013] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x40070000: GLB_PM_MSG: CORE_ENABLE
[  158.848033] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30010000: GLB_TPLG_MSG: COMP_NEW
[  158.848118] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30010000: GLB_TPLG_MSG: COMP_NEW
[  158.848140] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30010000: GLB_TPLG_MSG: COMP_NEW
[  158.848224] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30010000: GLB_TPLG_MSG: COMP_NEW
[  158.848240] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30200000: GLB_TPLG_MSG: BUFFER_NEW
[  158.848320] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30200000: GLB_TPLG_MSG: BUFFER_NEW
[  158.848334] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30200000: GLB_TPLG_MSG: BUFFER_NEW
[  158.848416] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30200000: GLB_TPLG_MSG: BUFFER_NEW
[  158.848435] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30010000: GLB_TPLG_MSG: COMP_NEW
[  158.848524] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30010000: GLB_TPLG_MSG: COMP_NEW
[  158.848540] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30100000: GLB_TPLG_MSG: PIPE_NEW
[  158.848623] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30100000: GLB_TPLG_MSG: PIPE_NEW
[  158.848630] sof-audio-pci 0000:00:0e.0: DSP core(s) enabled? 1 : core_mask 1
[  158.848648] sof-audio-pci 0000:00:0e.0: ipc tx: 0x40070000: GLB_PM_MSG: CORE_ENABLE
[  158.848705] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x40070000: GLB_PM_MSG: CORE_ENABLE
[  158.848726] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30010000: GLB_TPLG_MSG: COMP_NEW
[  158.848921] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30010000: GLB_TPLG_MSG: COMP_NEW
[  158.848943] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30010000: GLB_TPLG_MSG: COMP_NEW
[  158.849128] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30010000: GLB_TPLG_MSG: COMP_NEW
[  158.849144] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30200000: GLB_TPLG_MSG: BUFFER_NEW
[  158.849326] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30200000: GLB_TPLG_MSG: BUFFER_NEW
[  158.849342] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30200000: GLB_TPLG_MSG: BUFFER_NEW
[  158.849530] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30200000: GLB_TPLG_MSG: BUFFER_NEW
[  158.849551] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30010000: GLB_TPLG_MSG: COMP_NEW
[  158.849854] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30010000: GLB_TPLG_MSG: COMP_NEW
[  158.849892] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30100000: GLB_TPLG_MSG: PIPE_NEW
[  158.850105] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30100000: GLB_TPLG_MSG: PIPE_NEW
[  158.850115] sof-audio-pci 0000:00:0e.0: DSP core(s) enabled? 1 : core_mask 1
[  158.850126] sof-audio-pci 0000:00:0e.0: ipc tx: 0x40070000: GLB_PM_MSG: CORE_ENABLE
[  158.850300] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x40070000: GLB_PM_MSG: CORE_ENABLE
[  158.850324] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30010000: GLB_TPLG_MSG: COMP_NEW
[  158.850512] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30010000: GLB_TPLG_MSG: COMP_NEW
[  158.850534] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30010000: GLB_TPLG_MSG: COMP_NEW
[  158.850622] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30010000: GLB_TPLG_MSG: COMP_NEW
[  158.850637] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30200000: GLB_TPLG_MSG: BUFFER_NEW
[  158.850832] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30200000: GLB_TPLG_MSG: BUFFER_NEW
[  158.850868] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30200000: GLB_TPLG_MSG: BUFFER_NEW
[  158.850980] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30200000: GLB_TPLG_MSG: BUFFER_NEW
[  158.851004] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30010000: GLB_TPLG_MSG: COMP_NEW
[  158.851199] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30010000: GLB_TPLG_MSG: COMP_NEW
[  158.851218] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30100000: GLB_TPLG_MSG: PIPE_NEW
[  158.851468] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30100000: GLB_TPLG_MSG: PIPE_NEW
[  158.851478] sof-audio-pci 0000:00:0e.0: DSP core(s) enabled? 1 : core_mask 1
[  158.851490] sof-audio-pci 0000:00:0e.0: ipc tx: 0x40070000: GLB_PM_MSG: CORE_ENABLE
[  158.851567] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x40070000: GLB_PM_MSG: CORE_ENABLE
[  158.851588] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30010000: GLB_TPLG_MSG: COMP_NEW
[  158.851703] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30010000: GLB_TPLG_MSG: COMP_NEW
[  158.851719] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30200000: GLB_TPLG_MSG: BUFFER_NEW
[  158.851912] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30200000: GLB_TPLG_MSG: BUFFER_NEW
[  158.851933] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30010000: GLB_TPLG_MSG: COMP_NEW
[  158.852195] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30010000: GLB_TPLG_MSG: COMP_NEW
[  158.852215] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30100000: GLB_TPLG_MSG: PIPE_NEW
[  158.852433] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30100000: GLB_TPLG_MSG: PIPE_NEW
[  158.852442] sof-audio-pci 0000:00:0e.0: DSP core(s) enabled? 1 : core_mask 1
[  158.852453] sof-audio-pci 0000:00:0e.0: ipc tx: 0x40070000: GLB_PM_MSG: CORE_ENABLE
[  158.852541] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x40070000: GLB_PM_MSG: CORE_ENABLE
[  158.852555] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
[  158.852637] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
[  158.852660] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
[  158.852745] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
[  158.852761] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
[  158.852949] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
[  158.852966] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
[  158.853049] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
[  158.853065] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
[  158.853146] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
[  158.853162] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
[  158.853218] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
[  158.853232] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
[  158.853290] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
[  158.853301] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
[  158.853361] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
[  158.853373] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
[  158.853431] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
[  158.853441] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
[  158.853535] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
[  158.853548] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
[  158.853611] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
[  158.853623] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
[  158.853695] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
[  158.853709] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
[  158.853876] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
[  158.853889] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
[  158.853959] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
[  158.853972] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
[  158.854047] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
[  158.854061] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
[  158.854138] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
[  158.854151] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
[  158.854327] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
[  158.854340] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
[  158.854411] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
[  158.854425] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
[  158.854503] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
[  158.854515] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
[  158.854589] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
[  158.854602] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
[  158.854690] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
[  158.854702] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
[  158.854779] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
[  158.854792] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
[  158.854875] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
[  158.854889] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
[  158.854969] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
[  158.854983] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
[  158.855061] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
[  158.855075] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
[  158.855128] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
[  158.855140] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
[  158.855198] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
[  158.855210] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
[  158.855271] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
[  158.855282] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
[  158.855345] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
[  158.855357] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
[  158.855424] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
[  158.855436] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
[  158.855509] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
[  158.855521] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
[  158.855595] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
[  158.855607] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
[  158.855705] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
[  158.855718] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
[  158.855799] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30030000: GLB_TPLG_MSG: COMP_CONNECT
[  158.855825] sof-audio-pci 0000:00:0e.0: ipc tx: 0x80010000: GLB_DAI_MSG: CONFIG
[  158.855926] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x80010000: GLB_DAI_MSG: CONFIG
[  158.855952] sof-audio-pci 0000:00:0e.0: ipc tx: 0x80010000: GLB_DAI_MSG: CONFIG
[  158.856043] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x80010000: GLB_DAI_MSG: CONFIG
[  158.856068] sof-audio-pci 0000:00:0e.0: ipc tx: 0x80010000: GLB_DAI_MSG: CONFIG
[  158.856174] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x80010000: GLB_DAI_MSG: CONFIG
[  158.856200] sof-audio-pci 0000:00:0e.0: ipc tx: 0x80010000: GLB_DAI_MSG: CONFIG
[  158.856390] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x80010000: GLB_DAI_MSG: CONFIG
[  158.856415] sof-audio-pci 0000:00:0e.0: ipc tx: 0x80010000: GLB_DAI_MSG: CONFIG
[  158.856620] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x80010000: GLB_DAI_MSG: CONFIG
[  158.856644] sof-audio-pci 0000:00:0e.0: ipc tx: 0x80010000: GLB_DAI_MSG: CONFIG
[  158.856932] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x80010000: GLB_DAI_MSG: CONFIG
[  158.856978] sof-audio-pci 0000:00:0e.0: ipc tx: 0x80010000: GLB_DAI_MSG: CONFIG
[  158.857080] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x80010000: GLB_DAI_MSG: CONFIG
[  158.857106] sof-audio-pci 0000:00:0e.0: ipc tx: 0x80010000: GLB_DAI_MSG: CONFIG
[  158.857199] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x80010000: GLB_DAI_MSG: CONFIG
[  158.857228] sof-audio-pci 0000:00:0e.0: ipc tx: 0x80010000: GLB_DAI_MSG: CONFIG
[  158.857524] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x80010000: GLB_DAI_MSG: CONFIG
[  158.857532] sof-audio-pci 0000:00:0e.0: tplg: complete pipeline PIPELINE.13.DMIC0.IN id 51
[  158.857541] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30130000: GLB_TPLG_MSG: PIPE_COMPLETE
[  158.857769] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30130000: GLB_TPLG_MSG: PIPE_COMPLETE
[  158.857776] sof-audio-pci 0000:00:0e.0: tplg: complete pipeline PIPELINE.12.SSP5.IN id 47
[  158.857816] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30130000: GLB_TPLG_MSG: PIPE_COMPLETE
[  158.857931] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30130000: GLB_TPLG_MSG: PIPE_COMPLETE
[  158.857942] sof-audio-pci 0000:00:0e.0: tplg: complete pipeline PIPELINE.11.SSP5.OUT id 41
[  158.857954] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30130000: GLB_TPLG_MSG: PIPE_COMPLETE
[  158.858163] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30130000: GLB_TPLG_MSG: PIPE_COMPLETE
[  158.858173] sof-audio-pci 0000:00:0e.0: tplg: complete pipeline PIPELINE.8.SSP3.IN id 35
[  158.858185] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30130000: GLB_TPLG_MSG: PIPE_COMPLETE
[  158.858391] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30130000: GLB_TPLG_MSG: PIPE_COMPLETE
[  158.858401] sof-audio-pci 0000:00:0e.0: tplg: complete pipeline PIPELINE.7.SSP3.OUT id 29
[  158.858414] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30130000: GLB_TPLG_MSG: PIPE_COMPLETE
[  158.858637] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30130000: GLB_TPLG_MSG: PIPE_COMPLETE
[  158.858655] sof-audio-pci 0000:00:0e.0: tplg: complete pipeline PIPELINE.4.SSP1.IN id 23
[  158.858665] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30130000: GLB_TPLG_MSG: PIPE_COMPLETE
[  158.858674] sof-audio-pci 0000:00:0e.0: ipc rx: 0x90020000: GLB_TRACE_MSG
[  158.858696] sof-audio-pci 0000:00:0e.0: ipc rx done: 0x90020000: GLB_TRACE_MSG
[  158.858845] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30130000: GLB_TPLG_MSG: PIPE_COMPLETE
[  158.858868] sof-audio-pci 0000:00:0e.0: tplg: complete pipeline PIPELINE.3.SSP1.OUT id 17
[  158.858886] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30130000: GLB_TPLG_MSG: PIPE_COMPLETE
[  158.859087] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30130000: GLB_TPLG_MSG: PIPE_COMPLETE
[  158.859097] sof-audio-pci 0000:00:0e.0: tplg: complete pipeline PIPELINE.2.SSP0.IN id 11
[  158.859110] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30130000: GLB_TPLG_MSG: PIPE_COMPLETE
[  158.859198] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30130000: GLB_TPLG_MSG: PIPE_COMPLETE
[  158.859208] sof-audio-pci 0000:00:0e.0: tplg: complete pipeline PIPELINE.1.SSP0.OUT id 5
[  158.859221] sof-audio-pci 0000:00:0e.0: ipc tx: 0x30130000: GLB_TPLG_MSG: PIPE_COMPLETE
[  158.859306] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x30130000: GLB_TPLG_MSG: PIPE_COMPLETE
[  158.859339] sof-audio-pci 0000:00:0e.0: ipc tx: 0x50010000: GLB_COMP_MSG: SET_VALUE
[  158.859470] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x50010000: GLB_COMP_MSG: SET_VALUE
[  158.859498] sof-audio-pci 0000:00:0e.0: ipc tx: 0x50010000: GLB_COMP_MSG: SET_VALUE
[  158.859626] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x50010000: GLB_COMP_MSG: SET_VALUE
[  158.859681] sof-audio-pci 0000:00:0e.0: ipc tx: 0x50010000: GLB_COMP_MSG: SET_VALUE
[  158.859836] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x50010000: GLB_COMP_MSG: SET_VALUE
[  158.859867] sof-audio-pci 0000:00:0e.0: ipc tx: 0x50010000: GLB_COMP_MSG: SET_VALUE
[  158.859999] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x50010000: GLB_COMP_MSG: SET_VALUE
[  158.860027] sof-audio-pci 0000:00:0e.0: ipc tx: 0x50010000: GLB_COMP_MSG: SET_VALUE
[  158.860259] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x50010000: GLB_COMP_MSG: SET_VALUE
[  158.860286] sof-audio-pci 0000:00:0e.0: ipc tx: 0x50010000: GLB_COMP_MSG: SET_VALUE
[  158.860516] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x50010000: GLB_COMP_MSG: SET_VALUE
[  158.860543] sof-audio-pci 0000:00:0e.0: ipc tx: 0x50010000: GLB_COMP_MSG: SET_VALUE
[  158.860775] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x50010000: GLB_COMP_MSG: SET_VALUE
[  158.860803] sof-audio-pci 0000:00:0e.0: ipc tx: 0x50010000: GLB_COMP_MSG: SET_VALUE
[  158.861026] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x50010000: GLB_COMP_MSG: SET_VALUE
[  158.861050] sof-audio-pci 0000:00:0e.0: ipc tx: 0x40020000: GLB_PM_MSG: CTX_RESTORE
[  158.861128] sof-audio-pci 0000:00:0e.0: ipc tx succeeded: 0x40020000: GLB_PM_MSG: CTX_RESTORE
[  158.895171] r8169 0000:02:00.0 enp2s0: Link is Down
[  158.896136] r8169 0000:03:00.0 enp3s0: Link is Down
[  159.094936] ata2: SATA link down (SStatus 4 SControl 300)
[  159.094990] ata1: SATA link down (SStatus 4 SControl 300)
[  159.358781] sof-audio-pci 0000:00:0e.0: ipc rx: 0x90020000: GLB_TRACE_MSG
[  159.358800] sof-audio-pci 0000:00:0e.0: ipc rx done: 0x90020000: GLB_TRACE_MSG
[  159.858747] sof-audio-pci 0000:00:0e.0: ipc rx: 0x90020000: GLB_TRACE_MSG
[  159.858766] sof-audio-pci 0000:00:0e.0: ipc rx done: 0x90020000: GLB_TRACE_MSG
[  160.484074] r8169 0000:03:00.0 enp3s0: Link is Up - 100Mbps/Full - flow control rx/tx
[  161.871908] HDMI HDA Codec ehdaudio0D2: hdac_hdmi_present_sense: disconnect for pin:port 5:0
[  161.871915] HDMI HDA Codec ehdaudio0D2: hdac_hdmi_present_sense: disconnect for pin:port 6:0
[  161.871921] HDMI HDA Codec ehdaudio0D2: hdac_hdmi_present_sense: disconnect for pin:port 7:0
[  161.872607] OOM killer enabled.
[  161.872611] Restarting tasks ... done.
[  161.876334] video LNXVIDEO:00: Restoring backlight state
[  161.876341] PM: suspend exit

    Output(20190320):
    error

    Dmesg(20190320):

    [  139.926809] PM: suspend entry (deep)
[  139.926812] PM: Syncing filesystems ... done.
[  139.930408] Freezing user space processes ... (elapsed 0.001 seconds) done.
[  139.932084] OOM killer disabled.
[  139.932085] Freezing remaining freezable tasks ... (elapsed 0.001 seconds) done.
[  139.934076] printk: Suspending console(s) (use no_console_suspend to debug)
[  139.935231] r8169 0000:03:00.0 enp3s0: Link is Down
[  139.935702] sof-audio-pci 0000:00:0e.0: ipc tx: 0x40010000: GLB_PM_MSG: CTX_SAVE
[  140.241036] sof-audio-pci 0000:00:0e.0: error: ipc timed out for 0x40010000 size 0x4c
[  140.241046] sof-audio-pci 0000:00:0e.0: unknown ROM status value 01006701
[  140.241051] sof-audio-pci 0000:00:0e.0: error: status = 0x001d003c panic = 0x00000000
[  140.241055] sof-audio-pci 0000:00:0e.0: unknown ROM status value 01006701
[  140.241064] sof-audio-pci 0000:00:0e.0: error: ctx_save ipc error during suspend -110
[  140.291938] sof-audio-pci 0000:00:0e.0: FW Poll Status: reg=0x1d003c timedout
[  140.291949] sof-audio-pci 0000:00:0e.0: error: reset enter failed: core_mask 3 adspcs 0x1d003c
[  140.291952] sof-audio-pci 0000:00:0e.0: error: dsp core reset failed: core_mask 3
[  140.291955] sof-audio-pci 0000:00:0e.0: error: failed to power down core during suspend
[  140.291959] sof-audio-pci 0000:00:0e.0: error: suspending dsp
[  140.291963] sof-audio-pci 0000:00:0e.0: error: failed to power down DSP during suspend -5
[  140.291990] pci_pm_suspend(): snd_sof_suspend+0x0/0x10 [snd_sof] returns -5
[  140.291997] dpm_run_callback(): pci_pm_suspend+0x0/0x120 returns -5
[  140.292002] PM: Device 0000:00:0e.0 failed to suspend: error -5
[  140.292007] PM: Some devices failed to suspend, or early wake event detected
[  140.403390] r8169 0000:03:00.0 enp3s0: Link is Down
[  140.404521] OOM killer enabled.
[  140.404523] Restarting tasks ... done.
[  140.407299] video LNXVIDEO:00: Restoring backlight state
[  140.407316] PM: suspend exit
[  140.602822] ata2: SATA link down (SStatus 4 SControl 300)
[  140.602861] ata1: SATA link down (SStatus 4 SControl 300)
[  142.036709] r8169 0000:03:00.0 enp3s0: Link is Up - 100Mbps/Full - flow control rx/tx

  17. zhuyingjiang commented on Mar 20, 2019

    @zhuyingjiang
    zhuyingjiang
    on Mar 20, 2019
    Contributor

    @ranj063 this issue is caused by commit 9a5e2e4ff4 and 7b12f064, revert these two can make the test passed.
    But since 7b12f064 is to make NOCODEC and HDA mutually incompatible. This means NOCODEC mode still need some code that now moved to HDA when do suspend/resume. ---The common part of HDA control is still needed in NOCODEC mode.
    I will go deep with this.

  18. ranj063 commented on Mar 20, 2019

    @ranj063
    ranj063
    on Mar 20, 2019
    Collaborator

    @zhuyingjiang could you please paste commit id with a link so its easier to check?

  19. zhuyingjiang commented on Mar 21, 2019

    @zhuyingjiang
    zhuyingjiang
    on Mar 21, 2019
    Contributor

    @ranj063 Updated, and I made a PR#739 for this issue, could you review it for me?

  20. Jiangxinx commented on Mar 29, 2019

    @Jiangxinx
    Jiangxinx
    on Mar 29, 2019
    ContributorAuthor

    passed on daily test.

  21. Jiangxinx
    closed this as completedon Mar 29, 2019

  22. Jiangxinx commented on Mar 29, 2019

    @Jiangxinx
    Jiangxinx
    on Mar 29, 2019
    ContributorAuthor

    Not reproduce on daily test.
    Step：

    1. sudo ~/test/stress-s3.sh 50 1 0

    Env:
    sof master: 742f83b
    kernel sof-dev: e29122d
    tplg: sof-apl-nocodec.tplg

  23. mengdonglin commented on Apr 1, 2019

    @mengdonglin
    mengdonglin
    on Apr 1, 2019
    Collaborator

    Fixed by thesofproject/linux#739, same as #1072

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

Metadata

Metadata

Assignees

Labels

APLApplies to Apollolake platformbugSomething isn't working as expected

Type

No type

Projects

No projects

    Milestone

    No milestone

    Relationships

    None yet

    Development

    No branches or pull requests

    Issue actions