[Bug 109513] [CI][BAT] igt at pm_rpm@module-reload - dmesg-warn - Use count on domain AUDIO is already zero

Tue Apr 9 13:30:08 UTC 2019

https://bugs.freedesktop.org/show_bug.cgi?id=109513

--- Comment #15 from Amadeusz Sławiński <amadeuszx.slawinski at linux.intel.com> ---
Hi,

First of all, as I understand CI is not testing audio per se, but can you
consider adding
snd_hda_core.dyndbg="+pfl" snd_soc_hdac_hdmi.dyndbg="+pfl"
for debugging HDMI Audio functionality to kernel parameters?
It will give more useful logs from audio side.

Can you tell me what exactly is being tested in this test?

One of the things I see, that in all logs, moments before warning device goes
to sleep and then is being woken up.

I've took a look at where calls are performed from audio side and I don't see
anything obviously wrong with them. But considering that we just finished
resume it looks to me like something tries to call __intel_display_power_put()
on device that is still down.

Another thing is the "enabling always-on" & "disabling always-on" sequence, in
failing case it seems suspiciously short to me (in comparison with working
cases), but I'm not well versed in graphics code/states, so can't tell if it's
really correct.

Suspend:

<7>[  244.855833] [drm:intel_runtime_suspend [i915]] Suspending device
<7>[  244.856535] [drm:gen9_set_dc_state [i915]] Setting DC state from 01 to 00
<7>[  244.856695] [drm:intel_power_well_disable [i915]] disabling power well 1
<7>[  244.856863] [drm:hsw_wait_for_power_well_disable.isra.7 [i915]] power
well 1 forced on (bios:1 driver:0 kvmr:0 debug:0)
<7>[  244.857358] [drm:bxt_enable_dc9 [i915]] Enabling DC9
<7>[  244.857415] [drm:gen9_set_dc_state [i915]] Setting DC state from 00 to 08
<7>[  244.857580] [drm:intel_runtime_suspend [i915]] Device suspended

...

<7>[  244.858101] [drm:intel_atomic_check [i915]] New cdclk calculated to be
logical 79200 kHz, actual 79200 kHz
<7>[  244.858208] [drm:intel_atomic_check [i915]] New voltage level calculated
to be logical 4, actual 4
<7>[  244.858903] [drm:drm_mode_setcrtc] [CRTC:125:pipe C]

Resume:

<7>[  244.881249] [drm:intel_runtime_resume [i915]] Resuming device
<7>[  244.881317] [drm:bxt_disable_dc9 [i915]] Disabling DC9
<7>[  244.881376] [drm:gen9_set_dc_state [i915]] Setting DC state from 08 to 00
<7>[  244.881458] [drm:gen9_set_dc_state [i915]] Setting DC state from 00 to 00
<7>[  244.881577] [drm:intel_power_well_enable [i915]] enabling power well 1
<7>[  244.881726] [drm:intel_dump_cdclk_state [i915]] Current CDCLK 19200 kHz,
VCO 0 kHz, ref 19200 kHz, bypass 19200 kHz, voltage level 1
<7>[  244.881790] [drm:bxt_init_cdclk [i915]] Sanitizing cdclk programmed by
pre-os
<7>[  244.882371] [drm:gen9_enable_dc5 [i915]] Enabling DC5
<7>[  244.882425] [drm:gen9_set_dc_state [i915]] Setting DC state from 00 to 01
<7>[  244.886970] [drm:__bxt_hpd_detection_setup [i915]] Invert bit setting:
hp_ctl:10001818 hp_port:38
<7>[  244.892311] [drm:__bxt_hpd_detection_setup [i915]] Invert bit setting:
hp_ctl:10001818 hp_port:30
<7>[  244.895516] [drm:intel_runtime_resume [i915]] Device resumed

Sequence between "enabling always-on" & "disabling always-on":

<7>[  244.895677] [drm:intel_power_well_enable [i915]] enabling always-on
<7>[  244.895732] [drm:intel_power_well_enable [i915]] enabling DC off
<7>[  244.896797] [drm:gen9_set_dc_state [i915]] Setting DC state from 01 to 00
<7>[  244.897856] [drm:intel_atomic_commit_tail [i915]] [ENCODER:126:DDI B]
<7>[  244.897920] [drm:intel_atomic_commit_tail [i915]] [ENCODER:135:DDI C]
<7>[  244.897981] [drm:verify_single_dpll_state.isra.108 [i915]] PORT PLL A
<7>[  244.898053] [drm:verify_single_dpll_state.isra.108 [i915]] PORT PLL B
<7>[  244.898283] [drm:verify_single_dpll_state.isra.108 [i915]] PORT PLL C
<7>[  244.898372] [drm:intel_power_well_disable [i915]] disabling DC off
<7>[  244.898434] [drm:gen9_enable_dc5 [i915]] Enabling DC5
<7>[  244.898488] [drm:gen9_set_dc_state [i915]] Setting DC state from 00 to 01
<7>[  244.902735] [drm:intel_power_well_disable [i915]] disabling always-on

Warning:

<4>[  244.904316] ------------[ cut here ]------------
<4>[  244.904321] Use count on domain AUDIO is already zero
<4>[  244.904403] WARNING: CPU: 0 PID: 274 at
drivers/gpu/drm/i915/intel_runtime_pm.c:1928
__intel_display_power_put+0xe9/0x100 [i915]

Summing up, I see few suspicious things, but without additional logs enabled
it's bit hard to tell anything concrete.

-- 
You are receiving this mail because:
You are on the CC list for the bug.
You are the assignee for the bug.
You are the QA Contact for the bug.
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <https://lists.freedesktop.org/archives/intel-gfx-bugs/attachments/20190409/1a3ec59f/attachment.html>