Firmware crash when attempting playback on UP^2 #7

Activity

1. lgirdwood

   added

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

   APLApplies to Apollolake platformApplies to Apollolake platform

   on Jun 22, 2018
2. lgirdwood

   added this to the 1.2 milestone on Jun 22, 2018
3. lgirdwood

   assigned

   lbetlej

   and

   mmaka1

   on Jun 22, 2018
4. lgirdwood commented on Jun 22, 2018

   

   lgirdwood

   on Jun 22, 2018

   MemberAuthor

   More actions

   Bisected to commit 14ca1fa
5. keqiaozhang commented on Jun 22, 2018

   

   keqiaozhang

   on Jun 22, 2018

   Collaborator

   More actions

   This regression also happens on CNL-RVP.
6. xiulipan commented on Jun 22, 2018

   

   xiulipan

   on Jun 22, 2018

   Contributor

   More actions

   Got different dmesg message
   [ 58.942186] sof-audio sof-audio: ipc: 0x60010000 succeeded
   [ 58.944289] sof-audio sof-audio: pcm: trigger stream 0 dir 0 cmd 1
   [ 58.944309] sof-audio sof-audio: ipc: send 0x60040000
   [ 58.944477] sof-audio sof-audio: ipc: 0x60040000 succeeded
   [ 58.947580] sof-audio sof-audio: posn mailbox: posn offset is 0xc1000
   [ 58.947584] sof-audio sof-audio: posn XRUN: host 180 comp 4 size -192
   [ 58.947586] sof-audio sof-audio: ipc rx: 0x60090000 done
   [ 59.556828] sof-audio sof-audio: PCM: stream 0 dir 0 DMA position 0 DAI position 0
   [ 60.064495] sof-audio sof-audio: pcm: trigger stream 0 dir 0 cmd 0
   [ 60.064522] sof-audio sof-audio: ipc: send 0x60050000
   [ 60.384298] sof-audio sof-audio: error: ipc timed out for 0x60050000 size 0xc
   [ 60.384317] sof-audio sof-audio: status: fw entered - code 00000005
   [ 60.384342] sof-audio sof-audio: error: runtime exception
   [ 60.384348] sof-audio sof-audio: error: trace point 00004000
   [ 60.384355] sof-audio sof-audio: error: DSP Firmware Oops
   [ 60.384361] sof-audio sof-audio: error: Exception Cause: LoadStorePIFDataErrorCause, Synchronous PIF data error during LoadStore access
   [ 60.384369] sof-audio sof-audio: EXCCAUSE 0x0000000d EXCVADDR 0x00000040 PS 0x00070025 SAR 0x00000010
   [ 60.384377] sof-audio sof-audio: EPC1 0xbe01b1ac EPC2 0xbe01b1a9 EPC3 0xbe010b56 EPC4 0x00000000
   [ 60.384384] sof-audio sof-audio: EPC5 0xbe01b1a9 EPC6 0x00000000 EPC7 0x00000000 DEPC 0x00000000
   [ 60.384392] sof-audio sof-audio: EPS2 0x00060120 EPS3 0x00060520 EPS4 0x00000000 EPS5 0x00060120
   [ 60.384399] sof-audio sof-audio: EPS6 0x00000000 EPS7 0x00000000 INTENABL 0x000101d8 INTERRU 0x00000222
   [ 60.384405] sof-audio sof-audio: stack dump from 0xbe07ff40
   [ 60.384413] sof-audio sof-audio: 0xbe07ff40: 0xbe01b1ac 0x00000000 0x00000010 0x00000000
   [ 60.384421] sof-audio sof-audio: 0xbe07ff44: 0xbe044024 0x00000000 0x00000008 0x00000000
   [ 60.384428] sof-audio sof-audio: 0xbe07ff48: 0x0000000d 0x00000000 0xbe020db9 0xbe020dbf
   [ 60.384435] sof-audio sof-audio: 0xbe07ff4c: 0xbe010b53 0x00000000 0xbe046aa4 0x00000000
   [ 60.384443] sof-audio sof-audio: 0xbe07ff50: 0x00000001 0x00000000 0xbe046928 0x00000000
   [ 60.384467] sof-audio sof-audio: 0xbe07ff54: 0x7e0104c6 0x00000000 0x00004000 0x00000000
   [ 60.384470] sof-audio sof-audio: 0xbe07ff58: 0x00000008 0x00000000 0x00000005 0xbe041d64
   [ 60.384473] sof-audio sof-audio: 0xbe07ff5c: 0x00000000 0x00000000 0x01000000 0x00000000
   [ 60.384475] sof-audio sof-audio: error: waking up any trace sleepers
   [ 60.384487] Passthrough: ASoC: trigger FE failed -110
   [ 60.384511] sof-audio sof-audio: pcm: free stream 0 dir 0
   [ 60.384526] sof-audio sof-audio: ipc: send 0x60030000
7. xiulipan commented on Jun 22, 2018

   

   xiulipan

   on Jun 22, 2018

   Contributor

   More actions

   The rmbox log is here:
   0x0 [53.891054] delta [53.891054] dai xru
   0x10 [53.891885] delta [0.000831] dai xru
   0x20 [53.891895] delta [0.000011] pipe pxr
   0x30 [53.889751] delta [********] value 0x17707574
   0x40 [53.890408] delta [0.000657] value 0x17707574
   0x50 [53.891054] delta [0.000646] dai xru
   0x60 [53.891056] delta [0.000002] comp Xun
   0x70 [53.891058] delta [0.000002] value 0x0000000000040000
   0x80 [53.891059] delta [0.000001] value 0x00000000000000c0
   0x90 [53.891066] delta [0.000007] pipe tsk
   0xa0 [53.891885] delta [0.000819] dai xru
   0xb0 [53.891886] delta [0.000002] comp Xun
   0xc0 [53.891887] delta [0.000001] value 0x0000000000040000
   0xd0 [53.891889] delta [0.000001] value 0x00000000000000c0
   0xe0 [53.891895] delta [0.000006] pipe pxr
   0xf0 [53.891897] delta [0.000001] pipe cmd
   0x100 [53.891898] delta [0.000002] host trg
   0x110 [53.891901] delta [0.000002] host DDi
   0x120 [53.891903] delta [0.000002] volume trg
   0x130 [53.891905] delta [0.000002] dai trg
   0x140 [53.892884] delta [0.000979] ssp tri
   0x150 [53.892887] delta [0.000003] ssp Ss1
   0x160 [53.892888] delta [0.000002] ssp Ss2
   0x170 [53.892899] delta [0.000010] dma LSo
   0x180 [53.892912] delta [0.000013] pipe pre
   0x190 [53.892914] delta [0.000002] host pre
   0x1a0 [53.892916] delta [0.000002] volume pre
   0x1b0 [53.892918] delta [0.000003] dai pre
   0x1c0 [53.892938] delta [0.000020] pipe cmd
   0x1d0 [53.892940] delta [0.000002] host trg
   0x1e0 [53.892942] delta [0.000002] host DEn
   0x1f0 [53.892945] delta [0.000003] volume trg
   0x200 [53.892947] delta [0.000002] dai trg
   0x210 [53.892950] delta [0.000004] ssp tri
   0x220 [53.892952] delta [0.000002] ssp sta
   0x230 [53.892956] delta [0.000003] pipe tsk
8. xiulipan commented on Jun 22, 2018

   

   xiulipan

   on Jun 22, 2018

   Contributor

   More actions

   After adding some debug trace patch the panic disappear.
   It seems we have some potential memory issues.
   I remember we have such a regression mouths later and disappear with some new add patches. Need to root cause it now.
9. lgirdwood

   assigned

   tlauda

   on Jun 22, 2018
10. lgirdwood

    added

    regression

    CNLApplies to Cannonlake platformApplies to Cannonlake platform

    and removed

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

    on Jun 22, 2018
11. xiulipan commented on Jun 22, 2018

    

    xiulipan

    on Jun 22, 2018

    Last edited by xiulipan

    Contributor

    More actions

    in etrace (sudo rmbox) and dma trace (sudo rmbox -t)
    value 0x17707574
    shows only with the patch 14ca1fa

    But this patch did not add any trace_value in the commit.
    After revert this patch this strange trace value also disappear.
    There is definitely some error with this patch.
    Hope this info can have some help.

    Thanks
12. 10 remaining items

    Load more

13. ranj063 commented on Jun 26, 2018

    

    ranj063

    on Jun 26, 2018

    Collaborator

    More actions

    @tlauda sorry I didnt time to check this yesterday. I will update today.
14. ranj063 commented on Jun 27, 2018

    

    ranj063

    on Jun 27, 2018

    Collaborator

    More actions

    @tlauda , I've just tested my UP2 and the problem no longer exists and the playback is clean without distortion as well.

    But, I cant play more than a few seconds of audio likely due to #17.
15. xiulipan commented on Jun 27, 2018

    

    xiulipan

    on Jun 27, 2018

    Contributor

    More actions

    @ranj063 Can you check the 0xbe01b018 in you sof-apl.lst
    Where the panic happens?
16. keqiaozhang commented on Jun 27, 2018

    

    keqiaozhang

    on Jun 27, 2018

    Collaborator

    More actions

    I have tested this issue on my UP2 board， this issue is no longer exists with nocodec tplg and codec tplg.
    For nocodec topology, the ssp waveform is normal with different input frequency of sine.
    For codec topology, the sound is clean without errors, but #17 trace buffer overflow issue is still exists.
    Test env:
    sof-dev: cb05347d9f85e18b414098d512c8287c297933d2
    sof-master:599e1800bab31883cf1f9e845279d079d4950c58
    soft-master:
    For nocodec tplg: reset to 308cf3d and use test-ssp5-I2S-volume-s16le-s16le-48k-24576k-nocodec.tplg
    For codec tplg: f756455 and use test-ssp5-mclk-0-I2S-volume-s16le-s16le-48k-24576k-codec.tplg
17. keqiaozhang

    closed this as completedon Jun 27, 2018
18. xiulipan commented on Jul 24, 2018

    

    xiulipan

    on Jul 24, 2018

    Contributor

    More actions

    fixed by #23
19. RDharageswari

    added a commit that references this issue on Jun 6, 2020

    sctp: Don't add the shutdown timer if its already been added

    ...

    20a785a
20. nashif

    added a commit that references this issue on May 7, 2021

    Merge pull request thesofproject#7 from nashif/rimage_remove

    ...

    f97ec1d
21. marc-hb

    added 2 commits that reference this issue on Feb 27, 2024

    zephyr/wrapper.c: dereference NULL in POSIX for better k_panic() trace

    ...

    aafa56b

    zephyr/wrapper.c: dereference NULL in POSIX for better k_panic() trace

    ...

    dcf6b6b
22. lgirdwood

    added a commit that references this issue on Feb 28, 2024

    zephyr/wrapper.c: dereference NULL in POSIX for better k_panic() trace

    ...

    884305b
23. jsarha

    added 3 commits that reference this issue on Oct 16, 2024

    ipc3: handler: Add check for pipeline->source_comp being NULL

    ...

    de50527

    ipc3: handler: Add check for pipeline->source_comp being NULL

    ...

    71afb2f

    pipeline: graph: Check if source_comp is NULL in pipeline_comp_reset()

    ...

    5c1f737
24. lgirdwood

    added a commit that references this issue on Oct 21, 2024

    pipeline: graph: Check if source_comp is NULL in pipeline_comp_reset()

    ...

    73a17ae