Skip to content

[BUG][CML]" ipc error for 0x60060000 size 12"when do Capture-pause-release test using headset(0,0) #2404

Closed

Description

@Liviali155

Describe the bug
I/O error when do Capture-pause-release test using headset (0,0)
After error occurred arecord stop,but if arecord again,it can work normally

To Reproduce
1.sudo reboot
2. ./sof-test-pause-release.sh -t pcm0c (script as attachment)

Capture

Reproduction Rate
10/10

Expected behavior
There is no I/O error,after error occurred arecord stop,but if arecord again,it can work normally

Actual result
I/O error
error

dmesgerror.log
[ 7739.251053] sof-audio-pci 0000:00:1f.3: ipc rx: 0x90020000: GLB_TRACE_MSG
[ 7739.251076] sof-audio-pci 0000:00:1f.3: ipc rx done: 0x90020000: GLB_TRACE_MSG
[ 7739.732448] sof-audio-pci 0000:00:1f.3: pcm: trigger stream 0 dir 1 cmd 3
[ 7739.732453] sof-audio-pci 0000:00:1f.3: ipc tx: 0x60060000: GLB_STREAM_MSG: TRIG_PAUSE
[ 7739.732726] sof-audio-pci 0000:00:1f.3: error: ipc error for 0x60060000 size 12
[ 7739.733241] sof-audio-pci 0000:00:1f.3: FW Poll Status: reg=0x20240000 successful
[ 7739.733245]  HDA Analog: ASoC: trigger FE cmd: 3 failed: -22
[ 7739.733251] sof-audio-pci 0000:00:1f.3: pcm: trigger stream 0 dir 1 cmd 4
[ 7739.733755] sof-audio-pci 0000:00:1f.3: FW Poll Status: reg=0x2024001e successful
[ 7739.733760] sof-audio-pci 0000:00:1f.3: ipc tx: 0x60070000: GLB_STREAM_MSG: TRIG_RELEASE
[ 7739.733975] sof-audio-pci 0000:00:1f.3: error: ipc error for 0x60070000 size 12
[ 7739.733977]  HDA Analog: ASoC: trigger FE cmd: 4 failed: -22
[ 7739.750774] sof-audio-pci 0000:00:1f.3: ipc rx: 0x90020000: GLB_TRACE_MSG
[ 7739.750796] sof-audio-pci 0000:00:1f.3: ipc rx done: 0x90020000: GLB_TRACE_MSG
[ 7740.240197] sof-audio-pci 0000:00:1f.3: pcm: trigger stream 0 dir 1 cmd 0
[ 7740.240209] sof-audio-pci 0000:00:1f.3: ipc tx: 0x60050000: GLB_STREAM_MSG: TRIG_STOP
[ 7740.240448] sof-audio-pci 0000:00:1f.3: ipc tx succeeded: 0x60050000: GLB_STREAM_MSG: TRIG_STOP
[ 7740.240462] sof-audio-pci 0000:00:1f.3: FW Poll Status: reg=0x20240000 successful
[ 7740.240471] sof-audio-pci 0000:00:1f.3: ipc tx: 0x60030000: GLB_STREAM_MSG: PCM_FREE
[ 7740.240837] sof-audio-pci 0000:00:1f.3: ipc tx succeeded: 0x60030000: GLB_STREAM_MSG: PCM_FREE
[ 7740.240856] sof-audio-pci 0000:00:1f.3: In hda_link_pcm_trigger cmd=0
[ 7740.240876] sof-audio-pci 0000:00:1f.3: ipc tx: 0x80010000: GLB_DAI_MSG: CONFIG    

Environment
Platform: CML laptop with onboard codec ALC3204 in HDA mode
Firmware: 53d35e4 https://github.com/thesofproject/sof/commits/master
Kernel: b2d9823 https://github.com/thesofproject/linux/commits/topic/sof-dev
Topology: file: tools/topology/sof-hda-generic-2ch.tplg same as firmware

dmesg0218.log
sof_trace0218.log
sof-test-pause-release.txt

Activity

  1. added
    bugSomething isn't working as expected
    CMLApplies to Comet Lake platform
    P2Critical bugs or normal features
    on Feb 18, 2020
  2. Liviali155 commented on Feb 18, 2020

    @Liviali155
    Author

    Seems a Firmware regression issue
    I changed firmware to 20200110(commit:c6baa979),error not seen

  3. Liviali155 commented on Feb 18, 2020

    @Liviali155
    Author

    Bi-sect result:
    caused by Firmware commit 91fb594

  4. Liviali155 commented on Feb 18, 2020

    @Liviali155
    Author

    Related #2280

  5. lgirdwood commented on Feb 18, 2020

    @lgirdwood
    Member

    @Liviali155 is this fatal ? Does audio continue or do you need to restart aplay ?
    @slawblauciak This looks like a race that's easy to reproduce with slower clock ?

  6. Liviali155 commented on Feb 19, 2020

    @Liviali155
    Author

    is this fatal ? Does audio continue or do you need to restart aplay

    After error occurred arecord stop,but if arecord again,it can work normally

  7. lgirdwood commented on Feb 21, 2020

    @lgirdwood
    Member

    @slawblauciak @mmaka1 can we make the 400M clock the default for CML etc, the low power clock can then be optionally selected by user who need it until the switching is dynamic at runtime.

  8. added this to the v1.5 milestone on Feb 21, 2020
  9. added
    P1Blocker bugs or important features
    and removed
    P2Critical bugs or normal features
    on Feb 24, 2020
  10. changed the title [-][BUG][CML Mantis]I/O error when do Capture-pause-release test using (0,0)[/-] [+][BUG][CML Mantis]I/O error when do Capture-pause-release test using headset(0,0)[/+] on Feb 24, 2020
  11. slawblauciak commented on Feb 25, 2020

    @slawblauciak
    Collaborator

    Hmm, the FW log doesn't appear complete. I can see many pause/release operations being done from the dmesg, but only one in the FW trace. Is the trace you provided cut in any way? Does it always end up like that?

  12. 4 remaining items

  13. juimonen commented on Feb 26, 2020

    @juimonen

    @Liviali155 ok thanks, at least the error trace seems to be consistent so xrun (no data), recover fail, and pipeline stop.

  14. changed the title [-][BUG][CML Mantis]I/O error when do Capture-pause-release test using headset(0,0)[/-] [+][BUG][CML]I/O error when do Capture-pause-release test using headset(0,0)[/+] on Mar 3, 2020
  15. tlauda commented on Mar 3, 2020

    @tlauda
    Contributor

    @lgirdwood @Liviali155 Basically the same problem as with #2467. If L1SEN is disabled, then it looks like some kind of pause/release sequence problem with this HDA codec. In the FW log we can clearly see, that the new data hasn't arrived on HDA link after releasing the stream. I'm suggesting codec issue, since we haven't seen this kind of error on CI for a long long time (since the HDA DAI sequence has been fixed in kernel).

  16. lgirdwood commented on Mar 4, 2020

    @lgirdwood
    Member
    • @kv2019i @ranj063 @plbossart fyi - what are we doing here on the host side for HDA pause/release. My understanding is that the HDA link may power OFF (stream STOP only ?) after a timeout period to save power (i.e. codec then needs to be brought back up etc).
  17. changed the title [-][BUG][CML]I/O error when do Capture-pause-release test using headset(0,0)[/-] [+][BUG][CML]" ipc error for 0x60060000 size 12"when do Capture-pause-release test using headset(0,0)[/+] on Mar 5, 2020
  18. Liviali155 commented on Mar 5, 2020

    @Liviali155
    Author

    On CFL-S RVP also can reproduce this issue
    1.cd sof-test(as attachment)
    2.cd test-case
    3.export TPLG=/lib/firmware/intel/sof-tplg/sof-hda-generic-4ch.tplg
    4.check-pause-resume.sh -c 100 -m capture

    I/O error when pause/release arecord(0,0)(headset) ,dmesg show "ipc error for 0x60060000 size "
    Platform: CFL-S RVP with onboard codec ALC700 in HDA mode

    dmesg_error.log
        da-4 kernel: [ 3202.720799] sof-audio-pci 0000:00:1f.3: ipc rx: 0x90020000: GLB_TRACE_MSG
    jf-cfl-rvp-hda-4 kernel: [ 3202.720841] sof-audio-pci 0000:00:1f.3: ipc rx done: 0x90020000: GLB_TRACE_MSG
    jf-cfl-rvp-hda-4 kernel: [ 3203.050245] sof-audio-pci 0000:00:1f.3: pcm: trigger stream 0 dir 1 cmd 3
    jf-cfl-rvp-hda-4 kernel: [ 3203.050256] sof-audio-pci 0000:00:1f.3: ipc tx: 0x60060000: GLB_STREAM_MSG: TRIG_PAUSE
    jf-cfl-rvp-hda-4 kernel: [ 3203.050647] sof-audio-pci 0000:00:1f.3: error: ipc error for 0x60060000 size 12
    jf-cfl-rvp-hda-4 kernel: [ 3203.051241] sof-audio-pci 0000:00:1f.3: FW Poll Status: reg=0x20240000 successful
    jf-cfl-rvp-hda-4 kernel: [ 3203.051259]  HDA Analog: ASoC: trigger FE cmd: 3 failed: -22
    jf-cfl-rvp-hda-4 kernel: [ 3203.051269] sof-audio-pci 0000:00:1f.3: pcm: trigger stream 0 dir 1 cmd 4
    jf-cfl-rvp-hda-4 kernel: [ 3203.051843] sof-audio-pci 0000:00:1f.3: FW Poll Status: reg=0x2024001e successful
    jf-cfl-rvp-hda-4 kernel: [ 3203.051852] sof-audio-pci 0000:00:1f.3: ipc tx: 0x60070000: GLB_STREAM_MSG: TRIG_RELEASE
    jf-cfl-rvp-hda-4 kernel: [ 3203.052167] sof-audio-pci 0000:00:1f.3: error: ipc error for 0x60070000 size 12
    jf-cfl-rvp-hda-4 kernel: [ 3203.052176]  HDA Analog: ASoC: trigger FE cmd: 4 failed: -22
    jf-cfl-rvp-hda-4 kernel: [ 3203.052314] sof-audio-pci 0000:00:1f.3: pcm: trigger stream 0 dir 1 cmd 3
    jf-cfl-rvp-hda-4 kernel: [ 3203.052322] sof-audio-pci 0000:00:1f.3: ipc tx: 0x60060000: GLB_STREAM_MSG: TRIG_PAUSE
    jf-cfl-rvp-hda-4 kernel: [ 3203.052650] sof-audio-pci 0000:00:1f.3: error: ipc error for 0x60060000 size 12
    jf-cfl-rvp-hda-4 kernel: [ 3203.053245] sof-audio-pci 0000:00:1f.3: FW Poll Status: reg=0x20240000 successful
    jf-cfl-rvp-hda-4 kernel: [ 3203.053255]  HDA Analog: ASoC: trigger FE cmd: 3 failed: -22
    jf-cfl-rvp-hda-4 kernel: [ 3203.053265] sof-audio-pci 0000:00:1f.3: pcm: trigger stream 0 dir 1 cmd 4
    jf-cfl-rvp-hda-4 kernel: [ 3203.053845] sof-audio-pci 0000:00:1f.3: FW Poll Status: reg=0x2024001e successful
    jf-cfl-rvp-hda-4 kernel: [ 3203.053854] sof-audio-pci 0000:00:1f.3: ipc tx: 0x60070000: GLB_STREAM_MSG: TRIG_RELEASE
    jf-cfl-rvp-hda-4 kernel: [ 3203.054127] sof-audio-pci 0000:00:1f.3: error: ipc error for 0x60070000 size 12
    jf-cfl-rvp-hda-4 kernel: [ 3203.054135]  HDA Analog: ASoC: trigger FE cmd: 4 failed: -22
    jf-cfl-rvp-hda-4 kernel: [ 3203.220797] sof-audio-pci 0000:00:1f.3: ipc rx: 0x90020000: GLB_TRACE_MSG
    jf-cfl-rvp-hda-4 kernel: [ 3203.220838] sof-audio-pci 0000:00:1f.3: ipc rx done: 0x90020000: GLB_TRACE_MSG
    jf-cfl-rvp-hda-4 kernel: [ 3203.658219] sof-audio-pci 0000:00:1f.3: pcm: trigger stream 0 dir 1 cmd 3
    jf-cfl-rvp-hda-4 kernel: [ 3203.658230] sof-audio-pci 0000:00:1f.3: ipc tx: 0x60060000: GLB_STREAM_MSG: TRIG_PAUSE
    jf-cfl-rvp-hda-4 kernel: [ 3203.658598] sof-audio-pci 0000:00:1f.3: error: ipc error for 0x60060000 size 12
    jf-cfl-rvp-hda-4 kernel: [ 3203.659198] sof-audio-pci 0000:00:1f.3: FW Poll Status: reg=0x20240000 successful
    jf-cfl-rvp-hda-4 kernel: [ 3203.659209]  HDA Analog: ASoC: trigger FE cmd: 3 failed: -22
    jf-cfl-rvp-hda-4 kernel: [ 3203.659220] sof-audio-pci 0000:00:1f.3: pcm: trigger stream 0 dir 1 cmd 4
    jf-cfl-rvp-hda-4 kernel: [ 3203.659751] sof-audio-pci 0000:00:1f.3: FW Poll Status: reg=0x2024001e successful
    jf-cfl-rvp-hda-4 kernel: [ 3203.659758] sof-audio-pci 0000:00:1f.3: ipc tx: 0x60070000: GLB_STREAM_MSG: TRIG_RELEASE
    jf-cfl-rvp-hda-4 kernel: [ 3203.660080] sof-audio-pci 0000:00:1f.3: error: ipc error for 0x60070000 size 12
    jf-cfl-rvp-hda-4 kernel: [ 3203.660088]  HDA Analog: ASoC: trigger FE cmd: 4 failed: -22
    jf-cfl-rvp-hda-4 kernel: [ 3203.660215] sof-audio-pci 0000:00:1f.3: pcm: trigger stream 0 dir 1 cmd 3
    jf-cfl-rvp-hda-4 kernel: [ 3203.660222] sof-audio-pci 0000:00:1f.3: ipc tx: 0x60060000: GLB_STREAM_MSG: TRIG_PAUSE
    jf-cfl-rvp-hda-4 kernel: [ 3203.660586] sof-audio-pci 0000:00:1f.3: error: ipc error for 0x60060000 size 
    
    jf-cfl-rvp-hda-4 kernel: [ 3203.661173] sof-audio-pci 0000:00:1f.3: FW Poll Status: reg
    
    sof_errortrace.log
    CORE  LEVEL      COMP_ID                TIMESTAMP            DELTA                FILE_NAME	CONTENT
        0      1          DMA         4003043952.083333              nan intel/cavs/hda-dma.c:789 	hda_dma_link_check_xrun() error: xrun detected
        0      1         COMP 2.15    4003043972.760417        20.677084      src/audio/dai.c:642 	dai_report_xrun() error: overrun due to no data available
        0      1         COMP 2.15    4003043989.114583        16.354166      src/audio/dai.c:919 	comp_overrun() error: sink->free = 768, copy_bytes = 0
        0      1         PIPE         4003044112.760417       123.645836 src/audio/pipeline.c:789 	pipeline_copy() error: ret = -61, start->comp.id = 15, dir = 0
        0      1         PIPE 2.16    4003044133.072917        20.312500 src/audio/pipeline.c:961 	pipeline_task(): xrun recover failed! pipeline will be stopped!
        0      1         COMP 2.15    4003802801.510417    758668.437500 rc/audio/component.c:160 	comp_set_state() error: wrong state = 1, COMP_TRIGGER_PAUSE
        0      1         PIPE         4003802821.718750        20.208334 src/audio/pipeline.c:661 	pipeline_trigger() error: ret = -22, host->comp.id = 9, cmd = 2
        0      1          IPC         4003802842.031250        20.312500    src/ipc/handler.c:468 	ipc: comp 9 trigger 0x60000 failed -22
        0      1         COMP 2.15    4003804743.020833      1900.989624 rc/audio/component.c:136 	comp_set_state() error: wrong state = 1, COMP_TRIGGER_RELEASE
        0      1         PIPE         4003804763.437500        20.416666 src/audio/pipeline.c:661 	pipeline_trigger() error: ret = -22, host->comp.id = 9, cmd = 3
        0      1          IPC         4003804783.333333        19.895834    src/ipc/handler.c:468 	ipc: comp 9 trigger 0x70000 failed -22
        0      1         COMP 2.15    4003805325.937500       542.604187 rc/audio/component.c:160 	comp_set_state() error: wrong state = 1, COMP_TRIGGER_PAUSE
        0      1         PIPE         4003805346.822917        20.885416 src/audio/pipeline.c:661 	pipeline_trigger() error: ret = -22, host->comp.id = 9, cmd = 2
        0      1          IPC         4003805366.718750        19.895834    src/ipc/handler.c:468 	ipc: comp 9 trigger 0x60000 failed -22
        0      1         COMP 2.15    4003807243.385417      1876.666626 rc/audio/component.c:136 	comp_set_state() error: wrong state = 1, COMP_TRIGGER_RELEASE
        0      1         PIPE         4003807263.802083        20.416666 src/audio/pipeline.c:661 	pipeline_trigger() error: ret = -22, host->comp.id = 9, cmd = 3
        0      1          IPC         4003807283.697917        19.895834    src/ipc/handler.c:468 	ipc: comp 9 trigger 0x70000 failed -22
        0      1         COMP 2.15    4004562772.916667    755489.250000 rc/audio/component.c:160 	comp_set_state() error: wrong state = 1, COMP_TRIGGER_PAUSE
        0      1         PIPE         4004562792.916667        20.000000 src/audio/pipeline.c:661 	pipeline_trigger() error: ret = -22, host->comp.id = 9, cmd = 2
        0      1          IPC         4004562813.281250        20.364584    src/ipc/handler.c:468 	ipc: comp 9 trigger 0x60000 failed -22
        0      1         COMP 2.15    4004564635.520833      1822.239624 rc/audio/component.c:136 	comp_set_state() error: wrong state = 1, COMP_TRIGGER_RELEASE
        0      1         PIPE         4004564655.468750        19.947916 src/audio/pipeline.c:661 	pipeline_trigger() error: ret = -22, host->comp.id = 9, cmd = 3
        0      1          IPC         4004564676.354167        20.885416    src/ipc/handler.c:468 	ipc: comp 9 trigger 0x70000 failed -22
        0      1         COMP 2.15    4004565262.656250       586.302063 rc/audio/component.c:160 	comp_set_state() error: wrong state = 1, COMP_TRIGGER_PAUSE
        0      1         PIPE         4004565282.604167        19.947916 src/audio/pipeline.c:661 	pipeline_trigger() error: ret = -22, host->comp.id = 9, cmd = 2
        0      1          IPC         4004565303.229167        20.625000    src/ipc/handler.c:468 	ipc: comp 9 trigger 0x60000 failed -22
        0      1         COMP 2.15    4004567112.291667      1809.062500 rc/audio/component.c:136 	comp_set_state() error: wrong state = 1, COMP_TRIGGER_RELEASE
        0      1         PIPE         4004567132.708333        20.416666 src/audio/pipeline.c:661 	pipeline_trigger() error: ret = -22, host->comp.id = 9, cmd = 3
        0      1          IPC         4004567153.125000        20.416666    src/ipc/handler.c:468 	ipc: comp 9 trigger 0x70000 failed -22
        0      1         COMP 2.10    4005197918.645833    630765.500000 rc/audio/component.c:169 	comp_set_state() error: wrong state = 5, COMP_TRIGGER_RESET
        0      1         COMP 2.11    4005197960.468750        41.822918 rc/audio/component.c:169 	comp_set_state() error: wrong state = 5, COMP_TRIGGER_RESET
    

    dmesg.txt
    etrace.txt
    slogger.txt
    sof-test.zip

  19. tlauda commented on Mar 10, 2020

    @tlauda
    Contributor

    @Liviali155 Please verify with #2512.

  20. Liviali155 commented on Mar 10, 2020

    @Liviali155
    Author

    @Liviali155 Please verify with #2512.

    Tried with PR #2512 ,error not seen
    Platform: CML laptop with onboard codec ALC3204 in HDA mode
    TGL-U RVP with onboard codec ALC711 in HDA mode

  21. tlauda commented on Mar 11, 2020

    @tlauda
    Contributor

    Fixed with #2512.

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

Metadata

Metadata

Assignees

Labels

CFLApplies to Coffee Lake platformCMLApplies to Comet Lake platformP1Blocker bugs or important featuresbugSomething isn't working as expected

Type

No type

Projects

No projects

    Milestone

    Relationships

    None yet

    Development

    No branches or pull requests

    Issue actions