Re: Call trace on 4.12.0-rc1

["Ville Syrjälä" <ville.syrjala@linux.intel.com>]

On Tue, May 16, 2017 at 10:43:39PM +0200, Hans de Goede wrote:
> Hi,
> 
> On 05/16/2017 09:55 PM, FKr wrote:
> > Hi,
> > I'm using 4.12.0-rc1 from https://github.com/jwrdegoede/linux-sunxi and got
> > the following weird trace yesterday. Previously I've been getting output
> > similar to https://www.spinics.net/lists/intel-gfx/msg127638.html, some boots
> > on 4.12.0-rc1  I don't get any trace at all.
> 
> This is really weird, we are getting the error while we are trying to
> acquire the wakelock ... ? Or do we need some other lock before we can
> take the wakelock ?

You would also need the runtime pm reference. IIRC we unregister the
notifier in runtime suspend, so I think intel_runtime_pm_get_noresume()
should be OK in this case. Imre?

> 
> Any input from one of the Intel developers would be appreciated.
> 
> Regards,
> 
> Hans
> 
> 
> >
> > [ 2383.844192] perf: interrupt took too long (2522 > 2500), lowering
> > kernel.perf_event_max_sample_rate to 79200
> > [ 2634.863978] [drm:intel_pipe_update_end] *ERROR* Atomic update failure on
> > pipe A (start=157909 end=157910) time 322 us, min 1073, max 1079, scanline
> > start 1063, end 1084
> > [ 2647.881794] perf: interrupt took too long (3193 > 3152), lowering
> > kernel.perf_event_max_sample_rate to 62400
> > [ 3297.857921] perf: interrupt took too long (4020 > 3991), lowering
> > kernel.perf_event_max_sample_rate to 49500
> > [ 4670.977136] mmc0: Tuning timeout, falling back to fixed sampling clock
> > [ 4671.436604] mmc0: Tuning timeout, falling back to fixed sampling clock
> > [ 4680.756302] mmc0: Tuning timeout, falling back to fixed sampling clock
> > [ 4707.846872] perf: interrupt took too long (5046 > 5025), lowering
> > kernel.perf_event_max_sample_rate to 39600
> > [ 4846.672969] RPM wakelock ref not held during HW access
> > [ 4846.673050] ------------[ cut here ]------------
> > [ 4846.673084] WARNING: CPU: 0 PID: 5227 at drivers/gpu/drm/i915/intel_drv.h:
> > 1780 intel_uncore_forcewake_get+0xa0/0xb0
> > [ 4846.673088] Modules linked in: snd_soc_sst_cht_bsw_nau8824 btusb btintel
> > bluetooth axp288_fuel_gauge ecdh_generic axp288_charger extcon_axp288
> > axp288_adc snd_hdmi_lpe_audio snd_intel_sst_acpi extcon_intel_int3496
> > snd_intel_sst_core extcon_core snd_soc_nau8824 snd_soc_sst_atom_hifi2_platform
> > snd_soc_core snd_compress snd_soc_sst_match snd_pcm snd_timer kxcjk_1013
> > industrialio_triggered_buffer intel_cht_int33fe snd soundcore intel_int0002
> > [ 4846.673201] CPU: 0 PID: 5227 Comm: kworker/0:1 Not tainted 4.12.0-rc1+ #3
> > [ 4846.673206] Hardware name: MEDION E2228T MD60250/NT16H, BIOS 5.11
> > 02/27/2017
> > [ 4846.673224] Workqueue: events fuel_gauge_status_monitor [axp288_fuel_gauge]
> > [ 4846.673235] task: ffff8800a85be800 task.stack: ffffc90002610000
> > [ 4846.673248] RIP: 0010:intel_uncore_forcewake_get+0xa0/0xb0
> > [ 4846.673255] RSP: 0018:ffffc90002613aa0 EFLAGS: 00010286
> > [ 4846.673265] RAX: 000000000000002a RBX: ffff880136cc8000 RCX:
> > ffffffff82063e88
> > [ 4846.673272] RDX: 0000000000000000 RSI: 0000000000000082 RDI:
> > 0000000000000247
> > [ 4846.673278] RBP: ffffc90002613ac0 R08: 000000000000002a R09:
> > 00000000000002ac
> > [ 4846.673284] R10: 0000000000000001 R11: 0000000000000000 R12:
> > 0000000000000007
> > [ 4846.673289] R13: 0000000000000001 R14: 0000000000000000 R15:
> > 0000000000000000
> > [ 4846.673298] FS:  0000000000000000(0000) GS:ffff88013fc00000(0000) knlGS:
> > 0000000000000000
> > [ 4846.673305] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
> > [ 4846.673311] CR2: 00000758c709c000 CR3: 0000000002009000 CR4:
> > 00000000001006f0
> > [ 4846.673317] Call Trace:
> > [ 4846.673340]  i915_pmic_bus_access_notifier+0x37/0x40
> > [ 4846.673354]  notifier_call_chain+0x4a/0x70
> > [ 4846.673368]  __blocking_notifier_call_chain+0x47/0x60
> > [ 4846.673380]  blocking_notifier_call_chain+0x16/0x20
> > [ 4846.673393]  iosf_mbi_call_pmic_bus_access_notifier_chain+0x1b/0x20
> > [ 4846.673406]  baytrail_i2c_acquire+0x64/0x220
> > [ 4846.673420]  i2c_dw_acquire_lock+0x21/0x50
> > [ 4846.673431]  i2c_dw_xfer+0xa3/0x4a0
> > [ 4846.673444]  __i2c_transfer+0x115/0x430
> > [ 4846.673456]  i2c_transfer+0x5c/0xc0
> > [ 4846.673469]  regmap_i2c_read+0x6d/0xa0
> > [ 4846.673482]  _regmap_raw_read+0xda/0x210
> > [ 4846.673491]  ? _regmap_raw_read+0xda/0x210
> > [ 4846.673503]  ? __update_load_avg_se.isra.5+0x15b/0x180
> > [ 4846.673514]  _regmap_bus_read+0x2a/0x60
> > [ 4846.673524]  _regmap_read+0x6c/0x130
> > [ 4846.673535]  regmap_read+0x3f/0x60
> > [ 4846.673549]  fuel_gauge_reg_readb.isra.5+0x40/0x90 [axp288_fuel_gauge]
> > [ 4846.673564]  fuel_gauge_get_status+0x2d/0x100 [axp288_fuel_gauge]
> > [ 4846.673577]  ? __schedule+0x2e3/0x840
> > [ 4846.673590]  fuel_gauge_status_monitor+0x16/0x40 [axp288_fuel_gauge]
> > [ 4846.673603]  process_one_work+0x1e0/0x420
> > [ 4846.673616]  worker_thread+0x48/0x3f0
> > [ 4846.673629]  kthread+0x108/0x140
> > [ 4846.673640]  ? process_one_work+0x420/0x420
> > [ 4846.673650]  ? kthread_create_on_node+0x70/0x70
> > [ 4846.673662]  ret_from_fork+0x2c/0x40
> > [ 4846.673673] Code: 05 51 79 a1 00 01 e8 84 3c ae ff 0f ff eb a5 80 3d 40 79
> > a1 00 00 75 a6 48 c7 c7 98 fe e9 81 c6 05 30 79 a1 00 01 e8 64 3c ae ff <0f>
> > ff eb 8f 66 90 66 2e 0f 1f 84 00 00 00 00 00 0f 1f 44 00 00
> > [ 4846.673896] ---[ end trace 4f0b6934a8fd8068 ]---
> >
> > Regards,
> > FKr
> >
> _______________________________________________
> Intel-gfx mailing list
> Intel-gfx@lists.freedesktop.org
> https://lists.freedesktop.org/mailman/listinfo/intel-gfx

-- 
Ville Syrjälä
Intel OTC
_______________________________________________
Intel-gfx mailing list
Intel-gfx@lists.freedesktop.org
https://lists.freedesktop.org/mailman/listinfo/intel-gfx