linux icon indicating copy to clipboard operation
linux copied to clipboard
verified publisher icon thesofproject

[BUG] "rmmod snd_sof_pci_intel_apl" hangs on jf-glk-bob-da7219-1

Open marc-hb opened this issue 1 year ago • 3 comments

Describe the bug

rmmod snd_sof_pci_intel_apl hangs on jf-glk-bob-da7219-1

Generally nothing in the logs, except once - see below.

To Reproduce

Test check-kmod-load-unload-25

Reproduction Rate

Happened 3 times in daily tests this week.

#/result/planresultdetail/39529?model=GLK_BOB_DA7219-ipc3&testcase=check-sof-logger (this test loads-unloads)

#/result/planresultdetail/39637?model=GLK_BOB_DA7219-ipc3&testcase=check-kmod-load-unload-25

/#/result/planresultdetail/39760?model=GLK_BOB_DA7219-ipc3&testcase=check-sof-logger

2024-04-10 16:02:39 UTC [REMOTE_INFO] ===== Starting iteration 21 of 25 =====
2024-04-10 16:02:39 UTC [REMOTE_INFO] wait dsp power status to become suspended
2024-04-10 16:02:40 UTC [REMOTE_INFO] run kmod/sof-kmod-remove.sh
RMMOD	snd_usb_audio
SKIP	snd_hda_intel  	not loaded
RMMOD	snd_sof_pci_intel_tng
RMMOD	snd_sof_pci_intel_skl
RMMOD	snd_sof_pci_intel_apl

hangs, rebooted by CI after 2 minutes

Environment

This seems like a recent regression. It looks very different from https://github.com/thesofproject/linux/issues/2676

First spotted in daily run 39529 : Linux Branch: topic/sof-dev Linux Commit: 45fa8789fdea KConfig Branch: master KConfig Commit: d885ec26b1a9

SOF Branch: stable-v2.2 SOF Commit: 60ef38e7f5c6

Screenshots or console output

There's generally no message in the logs, except that time:

#/result/planresultdetail/39760?model=GLK_BOB_DA7219-ipc3&testcase=check-sof-logger

cc:

  • #3042

marc-hb avatar Apr 12 '24 20:04 marc-hb

journalctl at the time of #/result/planresultdetail/39760?model=GLK_BOB_DA7219-ipc3&testcase=check-sof-logger

Apr 12 15:04:08 jf-glk-bob-da7219-1 sudo[2860]:   ubuntu : PWD=/home/ubuntu ; USER=root ; COMMAND=/usr/bin/env PATH=/home/ubuntu/sof-test/tools:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin rmmod snd_sof_pci_intel_apl
Apr 12 15:04:08 jf-glk-bob-da7219-1 sudo[2860]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof_intel_hda:hda_codec_i915_display_power: sof-audio-pci-intel-apl 0000:00:0e.0: Turning i915 HDAC power 1
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:snd_sof_pci_update_bits_unlocked: sof-audio-pci-intel-apl 0000:00:0e.0: Debug PCIR: 00000000 at  00000044
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:snd_sof_pci_update_bits_unlocked: sof-audio-pci-intel-apl 0000:00:0e.0: Debug PCIR: 00000040 at  00000048
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:snd_sof_pci_update_bits_unlocked: sof-audio-pci-intel-apl 0000:00:0e.0: Debug PCIW: 00000000 at  00000048
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:snd_sof_pci_update_bits_unlocked: sof-audio-pci-intel-apl 0000:00:0e.0: Debug PCIR: 00000000 at  00000048
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:snd_sof_pci_update_bits_unlocked: sof-audio-pci-intel-apl 0000:00:0e.0: Debug PCIW: 00000040 at  00000048
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof_intel_hda:hda_codec_i915_display_power: sof-audio-pci-intel-apl 0000:00:0e.0: Turning i915 HDAC power 0
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof_intel_hda_common:hda_dsp_state_log: sof-audio-pci-intel-apl 0000:00:0e.0: Current DSP power state: D0I0
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:sof_set_fw_state: sof-audio-pci-intel-apl 0000:00:0e.0: fw_state change: 0 -> 2
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:snd_sof_load_firmware: sof-audio-pci-intel-apl 0000:00:0e.0: loading firmware
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:sof_set_fw_state: sof-audio-pci-intel-apl 0000:00:0e.0: fw_state change: 2 -> 3
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:snd_sof_pci_update_bits_unlocked: sof-audio-pci-intel-apl 0000:00:0e.0: Debug PCIR: 00000002 at  00000048
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:snd_sof_pci_update_bits_unlocked: sof-audio-pci-intel-apl 0000:00:0e.0: Debug PCIW: 00000000 at  00000048
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:snd_sof_pci_update_bits_unlocked: sof-audio-pci-intel-apl 0000:00:0e.0: Debug PCIR: 00000000 at  00000044
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:snd_sof_pci_update_bits_unlocked: sof-audio-pci-intel-apl 0000:00:0e.0: Debug PCIW: 00000004 at  00000044
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:snd_sof_run_firmware: sof-audio-pci-intel-apl 0000:00:0e.0: booting DSP firmware
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof_intel_hda_common:hda_dsp_stream_hw_params: sof-audio-pci-intel-apl 0000:00:0e.0: FW Poll Status: reg[0x160]=0x40000 successful
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof_intel_hda_common:hda_dsp_stream_hw_params: sof-audio-pci-intel-apl 0000:00:0e.0: FW Poll Status: reg[0x160]=0x40000 successful
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof_intel_hda_common:hda_dsp_stream_setup_bdl: sof-audio-pci-intel-apl 0000:00:0e.0: period_bytes:0x0
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof_intel_hda_common:hda_dsp_stream_setup_bdl: sof-audio-pci-intel-apl 0000:00:0e.0: periods:1
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof_intel_hda_common:hda_dsp_cl_boot_firmware: sof-audio-pci-intel-apl 0000:00:0e.0: Attempting iteration 0 of Core En/ROM load...
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof_intel_hda_common:hda_dsp_core_power_up: sof-audio-pci-intel-apl 0000:00:0e.0: FW Poll Status: reg[0x4]=0x3030303 successful
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof_intel_hda_common:hda_dsp_core_reset_leave: sof-audio-pci-intel-apl 0000:00:0e.0: FW Poll Status: reg[0x4]=0x3030302 successful
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof_intel_hda_common:hda_dsp_core_run: sof-audio-pci-intel-apl 0000:00:0e.0: unstall/run core: core_mask = 1
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof_intel_hda_common:hda_dsp_core_is_enabled: sof-audio-pci-intel-apl 0000:00:0e.0: DSP core(s) enabled? 1 : core_mask 1
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof_intel_hda_common:cl_dsp_init: sof-audio-pci-intel-apl 0000:00:0e.0: FW Poll Status: reg[0x4c]=0x40000000 successful
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof_intel_hda_common:hda_dsp_core_reset_enter: sof-audio-pci-intel-apl 0000:00:0e.0: FW Poll Status: reg[0x4]=0x3030202 successful
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof_intel_hda_common:hda_dsp_core_power_down: sof-audio-pci-intel-apl 0000:00:0e.0: FW Poll Status: reg[0x4]=0x1010202 successful
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof_intel_hda_common:hda_dsp_core_is_enabled: sof-audio-pci-intel-apl 0000:00:0e.0: DSP core(s) enabled? 0 : core_mask 2
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof_intel_hda_common:cl_dsp_init: sof-audio-pci-intel-apl 0000:00:0e.0: FW Poll Status: reg[0x80000]=0x5000001 successful
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof_intel_hda_common:hda_cl_copy_fw: sof-audio-pci-intel-apl 0000:00:0e.0: Code loader DMA starting
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof_intel_hda_common:hda_cl_copy_fw: sof-audio-pci-intel-apl 0000:00:0e.0: Code loader DMA done, waiting for FW_ENTERED status
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof_intel_hda_common:hda_cl_copy_fw: sof-audio-pci-intel-apl 0000:00:0e.0: FW Poll Status: reg[0x80000]=0x5 successful
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof_intel_hda_common:hda_cl_copy_fw: sof-audio-pci-intel-apl 0000:00:0e.0: Code loader FW_ENTERED status
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof_intel_hda_common:hda_dsp_stream_trigger: sof-audio-pci-intel-apl 0000:00:0e.0: FW Poll Status: reg[0x160]=0x140000 successful
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof_intel_hda_common:hda_cl_copy_fw: sof-audio-pci-intel-apl 0000:00:0e.0: Code loader DMA stopped
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof_intel_hda_common:hda_dsp_cl_boot_firmware: sof-audio-pci-intel-apl 0000:00:0e.0: Firmware download successful, booting...
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:ipc3_log_header: sof-audio-pci-intel-apl 0000:00:0e.0: ipc rx: 0x70000000: FW_READY
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:ipc3_fw_ready: sof-audio-pci-intel-apl 0000:00:0e.0: DSP is ready 0x70000000 offset 0x81000
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:sof_set_fw_state: sof-audio-pci-intel-apl 0000:00:0e.0: fw_state change: 3 -> 6
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:ipc3_log_header: sof-audio-pci-intel-apl 0000:00:0e.0: ipc rx done: 0x70000000: FW_READY
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:snd_sof_run_firmware: sof-audio-pci-intel-apl 0000:00:0e.0: firmware boot complete
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:sof_set_fw_state: sof-audio-pci-intel-apl 0000:00:0e.0: fw_state change: 6 -> 7
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:snd_sof_pci_update_bits_unlocked: sof-audio-pci-intel-apl 0000:00:0e.0: Debug PCIR: 00000000 at  00000048
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:snd_sof_pci_update_bits_unlocked: sof-audio-pci-intel-apl 0000:00:0e.0: Debug PCIW: 00000002 at  00000048
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:snd_sof_pci_update_bits_unlocked: sof-audio-pci-intel-apl 0000:00:0e.0: Debug PCIR: 00000004 at  00000044
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:snd_sof_pci_update_bits_unlocked: sof-audio-pci-intel-apl 0000:00:0e.0: Debug PCIW: 00000000 at  00000044
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof_intel_hda_common:hda_dsp_stream_hw_params: sof-audio-pci-intel-apl 0000:00:0e.0: FW Poll Status: reg[0x80]=0x40000 successful
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof_intel_hda_common:hda_dsp_stream_hw_params: sof-audio-pci-intel-apl 0000:00:0e.0: FW Poll Status: reg[0x80]=0x40000 successful
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof_intel_hda_common:hda_dsp_stream_setup_bdl: sof-audio-pci-intel-apl 0000:00:0e.0: period_bytes:0x0
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof_intel_hda_common:hda_dsp_stream_setup_bdl: sof-audio-pci-intel-apl 0000:00:0e.0: periods:1
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:ipc3_dtrace_enable: sof-audio-pci-intel-apl 0000:00:0e.0: stream_tag: 1
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:ipc3_log_header: sof-audio-pci-intel-apl 0000:00:0e.0: ipc tx: 0x90030000: GLB_TRACE_MSG: DMA_PARAMS_EXT
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof_intel_hda_common:hda_dsp_stream_trigger: sof-audio-pci-intel-apl 0000:00:0e.0: FW Poll Status: reg[0x80]=0x2014001e successful
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:ipc3_log_header: sof-audio-pci-intel-apl 0000:00:0e.0: ipc tx: 0x30100000: GLB_TPLG_MSG: PIPE_NEW
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:sof_widget_setup_unlocked: sof-audio-pci-intel-apl 0000:00:0e.0: widget PIPELINE.7.HDA5.OUT setup complete
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:ipc3_log_header: sof-audio-pci-intel-apl 0000:00:0e.0: ipc tx: 0x30010000: GLB_TPLG_MSG: COMP_NEW
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:ipc3_log_header: sof-audio-pci-intel-apl 0000:00:0e.0: ipc tx: 0x80010000: GLB_DAI_MSG: CONFIG
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:sof_widget_setup_unlocked: sof-audio-pci-intel-apl 0000:00:0e.0: widget HDA5.OUT setup complete
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:ipc3_log_header: sof-audio-pci-intel-apl 0000:00:0e.0: ipc tx: 0x30200000: GLB_TPLG_MSG: BUFFER_NEW
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:sof_widget_setup_unlocked: sof-audio-pci-intel-apl 0000:00:0e.0: widget BUF7.1 setup complete
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:ipc3_log_header: sof-audio-pci-intel-apl 0000:00:0e.0: ipc tx: 0x30200000: GLB_TPLG_MSG: BUFFER_NEW
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:sof_widget_setup_unlocked: sof-audio-pci-intel-apl 0000:00:0e.0: widget BUF7.0 setup complete
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:ipc3_log_header: sof-audio-pci-intel-apl 0000:00:0e.0: ipc tx: 0x30010000: GLB_TPLG_MSG: COMP_NEW
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:ipc3_log_header: sof-audio-pci-intel-apl 0000:00:0e.0: ipc tx: 0x50010000: GLB_COMP_MSG: SET_VALUE
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:ipc3_log_header: sof-audio-pci-intel-apl 0000:00:0e.0: ipc tx: 0x50020000: GLB_COMP_MSG: GET_VALUE
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:sof_widget_setup_unlocked: sof-audio-pci-intel-apl 0000:00:0e.0: widget PGA7.0 setup complete
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:ipc3_log_header: sof-audio-pci-intel-apl 0000:00:0e.0: ipc tx: 0x30010000: GLB_TPLG_MSG: COMP_NEW
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:sof_widget_setup_unlocked: sof-audio-pci-intel-apl 0000:00:0e.0: widget PCM7P setup complete
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:ipc3_log_header: sof-audio-pci-intel-apl 0000:00:0e.0: ipc tx: 0x30100000: GLB_TPLG_MSG: PIPE_NEW
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:sof_widget_setup_unlocked: sof-audio-pci-intel-apl 0000:00:0e.0: widget PIPELINE.6.HDA4.OUT setup complete
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:ipc3_log_header: sof-audio-pci-intel-apl 0000:00:0e.0: ipc tx: 0x30010000: GLB_TPLG_MSG: COMP_NEW
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:ipc3_log_header: sof-audio-pci-intel-apl 0000:00:0e.0: ipc tx: 0x80010000: GLB_DAI_MSG: CONFIG
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:sof_widget_setup_unlocked: sof-audio-pci-intel-apl 0000:00:0e.0: widget HDA4.OUT setup complete
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:ipc3_log_header: sof-audio-pci-intel-apl 0000:00:0e.0: ipc tx: 0x30200000: GLB_TPLG_MSG: BUFFER_NEW
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:sof_widget_setup_unlocked: sof-audio-pci-intel-apl 0000:00:0e.0: widget BUF6.1 setup complete
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:ipc3_log_header: sof-audio-pci-intel-apl 0000:00:0e.0: ipc tx: 0x30200000: GLB_TPLG_MSG: BUFFER_NEW
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:sof_widget_setup_unlocked: sof-audio-pci-intel-apl 0000:00:0e.0: widget BUF6.0 setup complete
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:ipc3_log_header: sof-audio-pci-intel-apl 0000:00:0e.0: ipc tx: 0x30010000: GLB_TPLG_MSG: COMP_NEW
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:ipc3_log_header: sof-audio-pci-intel-apl 0000:00:0e.0: ipc tx: 0x50010000: GLB_COMP_MSG: SET_VALUE
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:ipc3_log_header: sof-audio-pci-intel-apl 0000:00:0e.0: ipc tx: 0x50020000: GLB_COMP_MSG: GET_VALUE
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:sof_widget_setup_unlocked: sof-audio-pci-intel-apl 0000:00:0e.0: widget PGA6.0 setup complete
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:ipc3_log_header: sof-audio-pci-intel-apl 0000:00:0e.0: ipc tx: 0x30010000: GLB_TPLG_MSG: COMP_NEW
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:sof_widget_setup_unlocked: sof-audio-pci-intel-apl 0000:00:0e.0: widget PCM6P setup complete
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:ipc3_log_header: sof-audio-pci-intel-apl 0000:00:0e.0: ipc tx: 0x30100000: GLB_TPLG_MSG: PIPE_NEW
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:sof_widget_setup_unlocked: sof-audio-pci-intel-apl 0000:00:0e.0: widget PIPELINE.5.HDA3.OUT setup complete
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:ipc3_log_header: sof-audio-pci-intel-apl 0000:00:0e.0: ipc tx: 0x30010000: GLB_TPLG_MSG: COMP_NEW
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:ipc3_log_header: sof-audio-pci-intel-apl 0000:00:0e.0: ipc tx: 0x80010000: GLB_DAI_MSG: CONFIG
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:sof_widget_setup_unlocked: sof-audio-pci-intel-apl 0000:00:0e.0: widget HDA3.OUT setup complete
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:ipc3_log_header: sof-audio-pci-intel-apl 0000:00:0e.0: ipc tx: 0x30200000: GLB_TPLG_MSG: BUFFER_NEW
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:sof_widget_setup_unlocked: sof-audio-pci-intel-apl 0000:00:0e.0: widget BUF5.1 setup complete
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:ipc3_log_header: sof-audio-pci-intel-apl 0000:00:0e.0: ipc tx: 0x30200000: GLB_TPLG_MSG: BUFFER_NEW
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:sof_widget_setup_unlocked: sof-audio-pci-intel-apl 0000:00:0e.0: widget BUF5.0 setup complete
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:ipc3_log_header: sof-audio-pci-intel-apl 0000:00:0e.0: ipc tx: 0x30010000: GLB_TPLG_MSG: COMP_NEW
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:ipc3_log_header: sof-audio-pci-intel-apl 0000:00:0e.0: ipc tx: 0x50010000: GLB_COMP_MSG: SET_VALUE
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:ipc3_log_header: sof-audio-pci-intel-apl 0000:00:0e.0: ipc tx: 0x50020000: GLB_COMP_MSG: GET_VALUE
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:sof_widget_setup_unlocked: sof-audio-pci-intel-apl 0000:00:0e.0: widget PGA5.0 setup complete
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:ipc3_log_header: sof-audio-pci-intel-apl 0000:00:0e.0: ipc tx: 0x30010000: GLB_TPLG_MSG: COMP_NEW
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:sof_widget_setup_unlocked: sof-audio-pci-intel-apl 0000:00:0e.0: widget PCM5P setup complete
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:ipc3_log_header: sof-audio-pci-intel-apl 0000:00:0e.0: ipc tx: 0x30100000: GLB_TPLG_MSG: PIPE_NEW
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:sof_widget_setup_unlocked: sof-audio-pci-intel-apl 0000:00:0e.0: widget PIPELINE.4.DMIC0.IN setup complete
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:ipc3_log_header: sof-audio-pci-intel-apl 0000:00:0e.0: ipc tx: 0x30010000: GLB_TPLG_MSG: COMP_NEW
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:ipc3_log_header: sof-audio-pci-intel-apl 0000:00:0e.0: ipc tx: 0x80010000: GLB_DAI_MSG: CONFIG
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:sof_widget_setup_unlocked: sof-audio-pci-intel-apl 0000:00:0e.0: widget DMIC0.IN setup complete
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:ipc3_log_header: sof-audio-pci-intel-apl 0000:00:0e.0: ipc tx: 0x30200000: GLB_TPLG_MSG: BUFFER_NEW
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:sof_widget_setup_unlocked: sof-audio-pci-intel-apl 0000:00:0e.0: widget BUF4.1 setup complete
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:ipc3_log_header: sof-audio-pci-intel-apl 0000:00:0e.0: ipc tx: 0x30200000: GLB_TPLG_MSG: BUFFER_NEW
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:sof_widget_setup_unlocked: sof-audio-pci-intel-apl 0000:00:0e.0: widget BUF4.0 setup complete
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:ipc3_log_header: sof-audio-pci-intel-apl 0000:00:0e.0: ipc tx: 0x30010000: GLB_TPLG_MSG: COMP_NEW
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:ipc3_log_header: sof-audio-pci-intel-apl 0000:00:0e.0: ipc tx: 0x50010000: GLB_COMP_MSG: SET_VALUE
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:ipc3_log_header: sof-audio-pci-intel-apl 0000:00:0e.0: ipc tx: 0x50020000: GLB_COMP_MSG: GET_VALUE
Apr 12 15:04:08 jf-glk-bob-da7219-1 kernel: snd_sof:sof_widget_setup_unlocked: sof-audio-pci-intel-apl 0000:00:0e.0: widget PGA4.0 setup complete
Apr 12 15:05:44 jf-glk-bob-da7219-1 sshd[2718]: Received disconnect from 10.54.29.142 port 56910:11: disconnected by user
Apr 12 15:05:44 jf-glk-bob-da7219-1 sshd[2718]: Disconnected from user ubuntu 10.54.29.142 port 56910
Apr 12 15:05:45 jf-glk-bob-da7219-1 sudo[2956]:   ubuntu : PWD=/home/ubuntu ; USER=root ; COMMAND=/usr/bin/echo ping from CI host
Apr 12 15:05:45 jf-glk-bob-da7219-1 sudo[2956]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 12 15:05:45 jf-glk-bob-da7219-1 sudo[2956]: pam_unix(sudo:session): session closed for user root
Apr 12 15:05:45 jf-glk-bob-da7219-1 sshd[2955]: Received disconnect from 10.54.29.142 port 56768:11: disconnected by user
Apr 12 15:05:45 jf-glk-bob-da7219-1 sshd[2955]: Disconnected from user ubuntu 10.54.29.142 port 56768
Apr 12 15:05:45 jf-glk-bob-da7219-1 ubuntu[3002]: test failed, CI session https://sof-ci.ostc.intel.com/api/session/365599 now trying to reboot DUT [email protected] over ssh

marc-hb avatar Apr 12 '24 20:04 marc-hb

The problem is that this is our only APL/GLK device in CI tests. If we had Up2 boards we would be able to see if this was a generic GLK regression or something really related to the Chrome platform.

plbossart avatar Apr 12 '24 20:04 plbossart

Great minds think alike: I just checked the APL UP2 results (which I rescued against all odds) and they have been perfectly fine recently.

marc-hb avatar Apr 12 '24 20:04 marc-hb