103181 – [CI] igt@[kms_cursor_legacy|kms_plane_lowres]@* - dmesg-warn - WARNING: CPU: 1 PID: 0 at drivers/gpu/drm/drm_vblank.c:625 drm_calc_vbltimestamp_from_scanoutpos+0x139/0x2e0

Bug 103181 - [CI] igt@[kms_cursor_legacy|kms_plane_lowres]@* - dmesg-warn - WARNING: CPU: 1 PID: 0 at drivers/gpu/drm/drm_vblank.c:625 drm_calc_vbltimestamp_from_scanoutpos+0x139/0x2e0

Summary: [CI] igt@[kms_cursor_legacy|kms_plane_lowres]@* - dmesg-warn - WARNING: CPU: ...

Status:	CLOSED WORKSFORME

Alias:	None

Product:	DRI
Classification:	Unclassified
Component:	DRM/Intel (show other bugs)
Version:	DRI git
Hardware:	Other All

Importance:	high critical
Assignee:	Mika Kahola
QA Contact:	Intel GFX Bugs mailing list

URL:
Whiteboard:	ReadyForDev
Keywords:

Depends on:
Blocks:

Reported:	2017-10-10 07:52 UTC by Marta Löfstedt
Modified:	2018-01-03 11:21 UTC (History)
CC List:	1 user (show)

See Also:
i915 platform:	BXT, GLK, HSW
i915 features:	display/Other

Attachments

Description Marta Löfstedt 2017-10-10 07:52:07 UTC

CI_DRM_3200 APL-shards igt@kms_cursor_legacy@flip-vs-cursor-busy-crc-legacy


[  213.250729] ------------[ cut here ]------------
[  213.250736] WARNING: CPU: 1 PID: 0 at drivers/gpu/drm/drm_vblank.c:625 drm_calc_vbltimestamp_from_scanoutpos+0x139/0x2e0
[  213.250738] Modules linked in: vgem snd_hda_codec_hdmi snd_hda_codec_realtek snd_hda_codec_generic x86_pkg_temp_thermal coretemp crct10dif_pclmul crc32_pclmul ghash_clmulni_intel lpc_ich r8169 mii mei_me mei i915 snd_hda_intel snd_hda_codec snd_hwdep snd_hda_core snd_pcm pinctrl_broxton pinctrl_intel prime_numbers
[  213.250765] CPU: 1 PID: 0 Comm: swapper/1 Tainted: G     U          4.14.0-rc4-CI-CI_DRM_3200+ #1
[  213.250766] Hardware name:                  /NUC6CAYB, BIOS AYAPLCEL.86A.0040.2017.0619.1722 06/19/2017
[  213.250767] task: ffff880276128040 task.stack: ffffc90000084000
[  213.250770] RIP: 0010:drm_calc_vbltimestamp_from_scanoutpos+0x139/0x2e0
[  213.250771] RSP: 0018:ffff88027fc83cd0 EFLAGS: 00010082
[  213.250773] RAX: ffffffffa01ba7e0 RBX: 0000000000000000 RCX: 0000000000000000
[  213.250774] RDX: ffffffff81ce7220 RSI: 0000000000000001 RDI: ffffffff81c65f62
[  213.250776] RBP: ffff88027fc83d30 R08: 0000000000000000 R09: ffffffff8160afb0
[  213.250777] R10: 0000000000000000 R11: ffffffffa0097510 R12: 0000000000000000
[  213.250778] R13: ffff88026d4c1d48 R14: ffff88026d4c1bf8 R15: ffff88026b080000
[  213.250779] FS:  0000000000000000(0000) GS:ffff88027fc80000(0000) knlGS:0000000000000000
[  213.250781] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[  213.250782] CR2: 00007f3531bec000 CR3: 000000026dbf1000 CR4: 00000000003406e0
[  213.250783] Call Trace:
[  213.250785]  <IRQ>
[  213.250789]  ? _raw_spin_unlock_irqrestore+0x42/0x70
[  213.250838]  ? fwtable_read32+0xc2/0x2c0 [i915]
[  213.250841]  drm_get_last_vbltimestamp+0x3f/0x70
[  213.250844]  drm_update_vblank_count+0x67/0x270
[  213.250849]  drm_crtc_accurate_vblank_count+0x44/0x90
[  213.250883]  display_pipe_crc_irq_handler+0x167/0x210 [i915]
[  213.250919]  hsw_pipe_crc_irq_handler+0x51/0x60 [i915]
[  213.250953]  gen8_irq_handler+0x3b1/0x670 [i915]
[  213.250959]  __handle_irq_event_percpu+0x49/0x350
[  213.250963]  handle_irq_event_percpu+0x23/0x60
[  213.250965]  handle_irq_event+0x39/0x60
[  213.250968]  handle_edge_irq+0xf4/0x1c0
[  213.250972]  handle_irq+0x1a/0x30
[  213.250974]  do_IRQ+0x68/0x130
[  213.250977]  common_interrupt+0x9a/0x9a
[  213.250978]  </IRQ>
[  213.250982] RIP: 0010:cpuidle_enter_state+0x136/0x370
[  213.250983] RSP: 0018:ffffc90000087e80 EFLAGS: 00000212 ORIG_RAX: ffffffffffffff7d
[  213.250985] RAX: 0000000000000000 RBX: 000000000020b1e6 RCX: 0000000000000000
[  213.250986] RDX: 00000031a6bb5e10 RSI: ffffffff81cb4786 RDI: ffffffff8175fbf2
[  213.250987] RBP: ffffc90000087eb8 R08: 0000000000000000 R09: 0000000000000002
[  213.250988] R10: 00000000000049ae R11: 00000000000018ad R12: 0000000000000003
[  213.250989] R13: 0000000000000003 R14: ffff880274bd2e68 R15: 00000031a69aac2a
[  213.250993]  ? cpuidle_enter_state+0x132/0x370
[  213.250997]  cpuidle_enter+0x17/0x20
[  213.251000]  call_cpuidle+0x23/0x40
[  213.251002]  do_idle+0x192/0x1e0
[  213.251006]  cpu_startup_entry+0x1d/0x20
[  213.251008]  start_secondary+0x11c/0x140
[  213.251012]  secondary_startup_64+0xa5/0xa5
[  213.251016] Code: d9 48 c7 c2 20 72 ce 81 be 01 00 00 00 48 c7 c7 62 5f c6 81 e8 49 56 fe ff 49 8b 87 28 08 00 00 48 83 78 20 00 0f 84 ec fe ff ff <0f> ff e9 e5 fe ff ff 41 b9 d3 4d 62 10 41 89 c4 44 89 d8 41 f7 
[  213.251060] ---[ end trace d8f53414de57f124 ]---

Full data:
https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_3200/shard-apl4/igt@kms_cursor_legacy@flip-vs-cursor-busy-crc-legacy.html

Comment 1 Marta Löfstedt 2017-10-23 07:42:11 UTC

https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_3273/shard-glkb5/igt@kms_plane_lowres@pipe-A-tiling-x.html

	

[ 1894.128191] hpet1: lost 7161 rtc interrupts
[ 1895.023976] hpet1: lost 7161 rtc interrupts
[ 1895.575560] ------------[ cut here ]------------
[ 1895.575591] WARNING: CPU: 1 PID: 5972 at drivers/gpu/drm/drm_vblank.c:613 drm_calc_vbltimestamp_from_scanoutpos+0x140/0x300
[ 1895.575594] Modules linked in: vgem snd_hda_codec_hdmi snd_hda_codec_realtek snd_hda_codec_generic x86_pkg_temp_thermal intel_powerclamp i915 coretemp crct10dif_pclmul crc32_pclmul ghash_clmulni_intel snd_hda_intel snd_hda_codec snd_hwdep snd_hda_core snd_pcm r8169 mei_me prime_numbers mii mei i2c_hid pinctrl_geminilake pinctrl_intel
[ 1895.575666] CPU: 1 PID: 5972 Comm: kms_plane_lowre Tainted: G     U          4.14.0-rc5-CI-CI_DRM_3273+ #1
[ 1895.575669] Hardware name: Intel Corp. Geminilake/GLK RVP1 DDR4 (05), BIOS GELKRVPA.X64.0062.B30.1708222146 08/22/2017
[ 1895.575672] task: ffff8801785a8040 task.stack: ffffc90003b3c000
[ 1895.575676] RIP: 0010:drm_calc_vbltimestamp_from_scanoutpos+0x140/0x300
[ 1895.575678] RSP: 0018:ffff88017fc83b88 EFLAGS: 00010082
[ 1895.575683] RAX: ffffffffa0228800 RBX: 0000000000000000 RCX: 0000000000000000
[ 1895.575686] RDX: ffffffff81ce69c0 RSI: 0000000000000001 RDI: ffffffff81c65b4a
[ 1895.575689] RBP: ffff88017fc83be8 R08: 0000000000000000 R09: ffffffff81608cf0
[ 1895.575691] R10: 000000000e332634 R11: ffffffffa0104530 R12: 0000000000000000
[ 1895.575694] R13: ffff880167b84260 R14: ffff880167b84138 R15: ffff88016dfd0000
[ 1895.575697] FS:  00007f0d0f1c2a40(0000) GS:ffff88017fc80000(0000) knlGS:0000000000000000
[ 1895.575701] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 1895.575703] CR2: 00007f2a5b8a7028 CR3: 000000017064c000 CR4: 00000000003406e0
[ 1895.575706] Call Trace:
[ 1895.575710]  <IRQ>
[ 1895.575747]  ? fwtable_read32+0x251/0x2c0 [i915]
[ 1895.575753]  drm_get_last_vbltimestamp+0x3d/0x60
[ 1895.575758]  drm_update_vblank_count+0x6d/0x250
[ 1895.575764]  drm_crtc_accurate_vblank_count+0x42/0xa0
[ 1895.575795]  display_pipe_crc_irq_handler+0x172/0x220 [i915]
[ 1895.575829]  hsw_pipe_crc_irq_handler+0x51/0x60 [i915]
[ 1895.575860]  gen8_irq_handler+0x3b1/0x670 [i915]
[ 1895.575869]  __handle_irq_event_percpu+0x49/0x350
[ 1895.575875]  handle_irq_event_percpu+0x23/0x60
[ 1895.575879]  handle_irq_event+0x39/0x60
[ 1895.575883]  handle_edge_irq+0xf4/0x1c0
[ 1895.575889]  handle_irq+0x1a/0x30
[ 1895.575894]  do_IRQ+0x68/0x130
[ 1895.575900]  common_interrupt+0x9a/0x9a
[ 1895.575904] RIP: 0010:__do_softirq+0xa0/0x4ae
[ 1895.575907] RSP: 0018:ffff88017fc83f58 EFLAGS: 00000206 ORIG_RAX: ffffffffffffffbc
[ 1895.575912] RAX: 00000000ffffffff RBX: ffff8801785a8040 RCX: 0000000000000000
[ 1895.575914] RDX: 00000000000013ce RSI: ffffffff81d0d97c RDI: ffffffff81cc18d6
[ 1895.575917] RBP: ffff88017fc83fb8 R08: 0000000000000000 R09: 0000000000000000
[ 1895.575919] R10: 0000000000000000 R11: 0000000000000000 R12: 0000000000000000
[ 1895.575922] R13: 0000000000000001 R14: 00000000c0000000 R15: ffff88016dfd0bb0
[ 1895.575932]  ? __do_softirq+0x9a/0x4ae
[ 1895.575942]  irq_exit+0xae/0xc0
[ 1895.575946]  smp_apic_timer_interrupt+0x9e/0x2e0
[ 1895.575950]  apic_timer_interrupt+0x9a/0xa0
[ 1895.575953]  </IRQ>
[ 1895.575956] RIP: 0010:_raw_spin_unlock_irqrestore+0x59/0x70
[ 1895.575959] RSP: 0018:ffffc90003b3fa70 EFLAGS: 00000202 ORIG_RAX: ffffffffffffff10
[ 1895.575964] RAX: ffff8801785a8040 RBX: 0000000000000202 RCX: 0000000000000006
[ 1895.575966] RDX: 00000000000013ce RSI: ffffffff81d0d97c RDI: ffffffff81cc18d6
[ 1895.575969] RBP: ffffc90003b3fa80 R08: ffff8801785a8928 R09: 0000000000000000
[ 1895.575972] R10: 0000000000000000 R11: 0000000000000000 R12: ffff88016dfd0bb0
[ 1895.575975] R13: 0000000000000001 R14: 00000000c0000000 R15: ffff88016dfd0bb0
[ 1895.576015]  fwtable_read32+0x8d/0x2c0 [i915]
[ 1895.576051]  intel_disable_pipe+0x9a/0x220 [i915]
[ 1895.576086]  haswell_crtc_disable+0x74/0x140 [i915]
[ 1895.576121]  intel_atomic_commit_tail+0x10c/0xbf0 [i915]
[ 1895.576160]  intel_atomic_commit+0x21f/0x2b0 [i915]
[ 1895.576167]  drm_atomic_commit+0x4b/0x50
[ 1895.576171]  drm_mode_atomic_ioctl+0x92c/0xb10
[ 1895.576183]  ? drm_atomic_set_property+0x4a0/0x4a0
[ 1895.576188]  drm_ioctl_kernel+0x69/0xb0
[ 1895.576193]  drm_ioctl+0x2f9/0x3d0
[ 1895.576196]  ? drm_atomic_set_property+0x4a0/0x4a0
[ 1895.576205]  ? trace_hardirqs_on_caller+0xe3/0x1b0
[ 1895.576210]  ? trace_hardirqs_on_thunk+0x1a/0x1c
[ 1895.576217]  do_vfs_ioctl+0x94/0x670
[ 1895.576221]  ? entry_SYSCALL_64_fastpath+0x5/0xb1
[ 1895.576226]  ? __this_cpu_preempt_check+0x13/0x20
[ 1895.576230]  ? trace_hardirqs_on_caller+0xe3/0x1b0
[ 1895.576235]  SyS_ioctl+0x41/0x70
[ 1895.576240]  entry_SYSCALL_64_fastpath+0x1c/0xb1
[ 1895.576243] RIP: 0033:0x7f0d0d3c3587
[ 1895.576246] RSP: 002b:00007fff7c614768 EFLAGS: 00000246 ORIG_RAX: 0000000000000010
[ 1895.576252] RAX: ffffffffffffffda RBX: ffffffff81491f73 RCX: 00007f0d0d3c3587
[ 1895.576255] RDX: 00007fff7c6147c0 RSI: 00000000c03864bc RDI: 0000000000000003
[ 1895.576258] RBP: ffffc90003b3ff88 R08: 000055bc91af1810 R09: 000055bc91aea410
[ 1895.576261] R10: 0000000000000001 R11: 0000000000000246 R12: 000055bc91aea520
[ 1895.576263] R13: 0000000000000003 R14: 00000000c03864bc R15: 000055bc91af09d0
[ 1895.576268]  ? __this_cpu_preempt_check+0x13/0x20
[ 1895.576274] Code: d9 48 c7 c2 c0 69 ce 81 be 01 00 00 00 48 c7 c7 4a 5b c6 81 e8 82 ec ff ff 49 8b 87 80 07 00 00 48 83 78 20 00 0f 84 e5 fe ff ff <0f> ff e9 de fe ff ff 41 b9 d3 4d 62 10 41 89 c4 44 89 d8 41 f7 
[ 1895.576423] ---[ end trace 67ce465911c36c1a ]---
[ 1895.927440] hpet1: lost 7160 rtc interrupts
[ 1896.826122] hpet1: lost 7161 rtc interrupts
[ 1897.723810] hpet1: lost 7161 rtc interrupts

Comment 2 Marta Löfstedt 2017-11-07 07:09:09 UTC

https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_3316/shard-hsw8/igt@kms_plane_lowres@pipe-b-tiling-none.html

<7>[  667.936976] [drm:intel_reference_shared_dpll [i915]] using SPLL for pipe B
<7>[  667.944054] [drm:intel_disable_pipe [i915]] disabling pipe B
<4>[  667.949773] ------------[ cut here ]------------
<4>[  667.949775] WARNING: CPU: 0 PID: 6629 at drivers/gpu/drm/drm_vblank.c:613 drm_calc_vbltimestamp_from_scanoutpos+0x140/0x300
<4>[  667.949776] Modules linked in: vgem snd_hda_codec_hdmi i915 x86_pkg_temp_thermal intel_powerclamp snd_hda_codec_realtek snd_hda_codec_generic coretemp crct10dif_pclmul crc32_pclmul snd_hda_intel snd_hda_codec ghash_clmulni_intel snd_hwdep snd_hda_core snd_pcm mei_me r8169 mei mii lpc_ich prime_numbers
<4>[  667.949787] CPU: 0 PID: 6629 Comm: kms_plane_lowre Tainted: G     U          4.14.0-rc8-CI-CI_DRM_3316+ #1
<4>[  667.949788] Hardware name: MSI MS-7924/Z97M-G43(MS-7924), BIOS V1.12 02/15/2016
<4>[  667.949788] task: ffff8803a6c80040 task.stack: ffffc90000528000
<4>[  667.949789] RIP: 0010:drm_calc_vbltimestamp_from_scanoutpos+0x140/0x300
<4>[  667.949791] RSP: 0018:ffff88041fa03b88 EFLAGS: 00010082
<4>[  667.949792] RAX: ffffffffa029d840 RBX: 0000000000000001 RCX: 0000000000000001
<4>[  667.949793] RDX: ffffffff81ce7b10 RSI: 0000000000000001 RDI: ffffffff81c66ad2
<4>[  667.949794] RBP: ffff88041fa03be8 R08: 0000000000000000 R09: ffffffff81609030
<4>[  667.949794] R10: 0000000000000000 R11: ffffffffa01771d0 R12: 0000000000000000
<4>[  667.949795] R13: ffff8803f97d4470 R14: ffff8803f97d4138 R15: ffff8803f9bc0000
<4>[  667.949796] FS:  00007f7f7bb91a40(0000) GS:ffff88041fa00000(0000) knlGS:0000000000000000
<4>[  667.949797] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
<4>[  667.949798] CR2: 000056284cd4a048 CR3: 00000004018d2003 CR4: 00000000001606f0
<4>[  667.949798] Call Trace:
<4>[  667.949799]  <IRQ>
<4>[  667.949815]  ? gen6_read32+0xd4/0x2b0 [i915]
<4>[  667.949818]  drm_get_last_vbltimestamp+0x3d/0x60
<4>[  667.949819]  drm_update_vblank_count+0x6d/0x250
<4>[  667.949821]  drm_crtc_accurate_vblank_count+0x42/0xa0
<4>[  667.949832]  display_pipe_crc_irq_handler+0x172/0x220 [i915]
<4>[  667.949843]  hsw_pipe_crc_irq_handler+0x51/0x60 [i915]
<4>[  667.949862]  ironlake_irq_handler+0x914/0xaa0 [i915]
<4>[  667.949865]  __handle_irq_event_percpu+0x49/0x350
<4>[  667.949866]  handle_irq_event_percpu+0x23/0x60
<4>[  667.949867]  handle_irq_event+0x39/0x60
<4>[  667.949868]  handle_edge_irq+0xf4/0x1c0
<4>[  667.949870]  handle_irq+0x1a/0x30
<4>[  667.949871]  do_IRQ+0x68/0x130
<4>[  667.949872]  common_interrupt+0x9a/0x9a
<4>[  667.949873] RIP: 0010:__do_softirq+0xa0/0x4ae
<4>[  667.949873] RSP: 0018:ffff88041fa03f58 EFLAGS: 00000246 ORIG_RAX: ffffffffffffff8d
<4>[  667.949874] RAX: 00000000ffffffff RBX: ffff8803a6c80040 RCX: 0000000000000000
<4>[  667.949874] RDX: 0000000080010000 RSI: 0000000000000100 RDI: ffffffff8191634a
<4>[  667.949874] RBP: ffff88041fa03fb8 R08: 0000000000000001 R09: 0000000000000000
<4>[  667.949875] R10: 0000000000000000 R11: 0000000000000000 R12: 0000000000000000
<4>[  667.949875] R13: 0000000000000000 R14: ffffffff81f608b0 R15: ffffffff82ca4ee0
<4>[  667.949877]  ? __do_softirq+0x9a/0x4ae
<4>[  667.949878]  ? __do_softirq+0x9a/0x4ae
<4>[  667.949880]  ? hrtimer_interrupt+0x124/0x200
<4>[  667.949881]  irq_exit+0xae/0xc0
<4>[  667.949892]  smp_apic_timer_interrupt+0x9e/0x2e0
<4>[  667.949893]  apic_timer_interrupt+0x9a/0xa0
<4>[  667.949893]  </IRQ>
<4>[  667.949895] RIP: 0010:console_unlock+0x41f/0x570
<4>[  667.949896] RSP: 0018:ffffc9000052b910 EFLAGS: 00000247 ORIG_RAX: ffffffffffffff10
<4>[  667.949896] RAX: 0000000080000000 RBX: 0000000000000000 RCX: 0000000000000000
<4>[  667.949897] RDX: 0000000080000001 RSI: 0000000000000001 RDI: ffffffff810efe4b
<4>[  667.949897] RBP: ffffc9000052b948 R08: 0000000000000001 R09: 0000000000000000
<4>[  667.949898] R10: 0000000000000000 R11: 0000000000000000 R12: 0000000000000040
<4>[  667.949898] R13: 0000000000000000 R14: ffffffff81f608b0 R15: ffffffff82ca4ee0
<4>[  667.949900]  ? console_unlock+0x41b/0x570
<4>[  667.949901]  vprintk_emit+0x254/0x3b0
<4>[  667.949903]  vprintk_default+0x1f/0x30
<4>[  667.949904]  vprintk_func+0x2c/0x90
<4>[  667.949905]  printk+0x43/0x4b
<4>[  667.949931]  ? intel_disable_pipe+0x4c/0x220 [i915]
<4>[  667.949932]  drm_printk+0x8e/0x90
<4>[  667.949933]  ? _raw_spin_unlock_irqrestore+0x42/0x70
<4>[  667.949949]  intel_disable_pipe+0x4c/0x220 [i915]
<4>[  667.949963]  haswell_crtc_disable+0x74/0x140 [i915]
<4>[  667.949978]  intel_atomic_commit_tail+0x10c/0xbe0 [i915]
<4>[  667.949993]  intel_atomic_commit+0x223/0x2d0 [i915]
<4>[  667.949994]  drm_atomic_commit+0x4b/0x50
<4>[  667.949995]  drm_mode_atomic_ioctl+0x936/0xb10
<4>[  667.949997]  ? drm_atomic_set_property+0x4a0/0x4a0
<4>[  667.949998]  drm_ioctl_kernel+0x69/0xb0
<4>[  667.950000]  drm_ioctl+0x2f9/0x3d0
<4>[  667.950000]  ? drm_atomic_set_property+0x4a0/0x4a0
<4>[  667.950003]  do_vfs_ioctl+0x94/0x670
<4>[  667.950005]  SyS_ioctl+0x41/0x70
<4>[  667.950006]  entry_SYSCALL_64_fastpath+0x1c/0xb1
<4>[  667.950007] RIP: 0033:0x7f7f79d90587
<4>[  667.950007] RSP: 002b:00007ffff7ed2888 EFLAGS: 00000246 ORIG_RAX: 0000000000000010
<4>[  667.950008] RAX: ffffffffffffffda RBX: 000056284cd48da0 RCX: 00007f7f79d90587
<4>[  667.950008] RDX: 00007ffff7ed28e0 RSI: 00000000c03864bc RDI: 0000000000000003
<4>[  667.950009] RBP: 00007f7f7a050400 R08: 000056284cd49360 R09: 000056284cd49230
<4>[  667.950009] R10: 0000000000000001 R11: 0000000000000246 R12: 0000000000000000
<4>[  667.950009] R13: 00007ffff7ed28b0 R14: 00000000000000bb R15: 0000000000000003
<4>[  667.950011] Code: d9 48 c7 c2 10 7b ce 81 be 01 00 00 00 48 c7 c7 d2 6a c6 81 e8 52 ec ff ff 49 8b 87 80 07 00 00 48 83 78 20 00 0f 84 e5 fe ff ff <0f> ff e9 de fe ff ff 41 b9 d3 4d 62 10 41 89 c4 44 89 d8 41 f7 
<4>[  667.950026] ---[ end trace 979ffc1c3cc899ec ]---
<4>[  667.993243] hpet1: lost 1 rtc interrupts
<7>[  668.465536] [drm:intel_disable_shared_dpll [i915]] disable SPLL (active 2, on? 1) for crtc 46

Comment 3 Marta Löfstedt 2017-12-01 12:42:19 UTC

https://intel-gfx-ci.01.org/tree/drm-tip/IGT_4030/shard-glkb3/igt@kms_plane_lowres@pipe-c-tiling-none.html

	

[ 4472.543698] hpet1: lost 7160 rtc interrupts
[ 4473.417616] hpet1: lost 7161 rtc interrupts
[ 4474.320307] hpet1: lost 7161 rtc interrupts
[ 4475.219700] hpet1: lost 7160 rtc interrupts
[ 4475.357003] WARNING: CPU: 1 PID: 17682 at drivers/gpu/drm/drm_vblank.c:612 drm_calc_vbltimestamp_from_scanoutpos+0x13b/0x2f0
[ 4475.357047] Modules linked in: snd_hda_intel i915 vgem snd_hda_codec_hdmi snd_hda_codec_realtek snd_hda_codec_generic x86_pkg_temp_thermal intel_powerclamp coretemp crct10dif_pclmul crc32_pclmul ghash_clmulni_intel snd_hda_codec snd_hwdep snd_hda_core snd_pcm mei_me r8169 mii mei prime_numbers i2c_hid pinctrl_geminilake pinctrl_intel [last unloaded: i915]
[ 4475.357122] CPU: 1 PID: 17682 Comm: kms_plane_lowre Tainted: G     U           4.15.0-rc1-CI-CI_DRM_3427+ #1
[ 4475.357125] Hardware name: Intel Corp. Geminilake/GLK RVP1 DDR4 (05), BIOS GELKRVPA.X64.0062.B30.1708222146 08/22/2017
[ 4475.357128] task: ffff88017273d1c0 task.stack: ffffc900008a8000
[ 4475.357132] RIP: 0010:drm_calc_vbltimestamp_from_scanoutpos+0x13b/0x2f0
[ 4475.357134] RSP: 0018:ffff88017fc83bf8 EFLAGS: 00010082
[ 4475.357139] RAX: ffffffffa049b8a0 RBX: ffff880173ba0000 RCX: 0000000000000002
[ 4475.357142] RDX: ffffffff81cddd90 RSI: 0000000000000001 RDI: ffffffff81c5feda
[ 4475.357145] RBP: ffff88017fc83c58 R08: 0000000000000000 R09: ffffffff815c7020
[ 4475.357147] R10: 0000000000000001 R11: ffffffffa0376dc0 R12: 0000000000000002
[ 4475.357150] R13: ffff88016423d3d8 R14: ffff88016423d968 R15: 0000000000000000
[ 4475.357153] FS:  00007f1a9cd8ba40(0000) GS:ffff88017fc80000(0000) knlGS:0000000000000000
[ 4475.357156] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 4475.357158] CR2: 00007f15482b1000 CR3: 0000000174fe5000 CR4: 0000000000340ee0
[ 4475.357161] Call Trace:
[ 4475.357165]  <IRQ>
[ 4475.357176]  drm_get_last_vbltimestamp+0x36/0x50
[ 4475.357181]  drm_update_vblank_count+0x60/0x240
[ 4475.357189]  drm_crtc_accurate_vblank_count+0x41/0xa0
[ 4475.357224]  display_pipe_crc_irq_handler+0x16d/0x210 [i915]
[ 4475.357263]  hsw_pipe_crc_irq_handler+0x46/0x50 [i915]
[ 4475.357296]  gen8_irq_handler+0x3a9/0x660 [i915]
[ 4475.357308]  __handle_irq_event_percpu+0x3c/0x340
[ 4475.357316]  handle_irq_event_percpu+0x1b/0x50
[ 4475.357320]  handle_irq_event+0x2f/0x50
[ 4475.357325]  handle_edge_irq+0xe4/0x1b0
[ 4475.357332]  handle_irq+0x11/0x20
[ 4475.357337]  do_IRQ+0x5e/0x120
[ 4475.357343]  common_interrupt+0x93/0x93
[ 4475.357346] RIP: 0010:__do_softirq+0xa1/0x4de
[ 4475.357349] RSP: 0018:ffff88017fc83f70 EFLAGS: 00000206 ORIG_RAX: ffffffffffffffa8
[ 4475.357356] RAX: 00000000ffffffff RBX: ffff88017273d1c0 RCX: 0000000000000000
[ 4475.357358] RDX: 0000000000001423 RSI: ffffffff81d05401 RDI: ffffffff81cb67e6
[ 4475.357361] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000
[ 4475.357364] R10: 0000000000000000 R11: 0000000000000000 R12: ffff88017057d9e8
[ 4475.357366] R13: ffff8801789d4a88 R14: 00000000ffffffff R15: ffff880173279ac0
[ 4475.357388]  irq_exit+0xaa/0xc0
[ 4475.357391]  smp_apic_timer_interrupt+0x94/0x2d0
[ 4475.357395]  apic_timer_interrupt+0x93/0xa0
[ 4475.357398]  </IRQ>
[ 4475.357434] RIP: 0010:assert_planes_disabled.isra.58+0x24/0x60 [i915]
[ 4475.357437] RSP: 0018:ffffc900008abaf8 EFLAGS: 00000297 ORIG_RAX: ffffffffffffff10
[ 4475.357442] RAX: ffff88016e85bfd0 RBX: ffff88016e859a98 RCX: 0000000000000001
[ 4475.357444] RDX: 0000000080000001 RSI: ffff88017057d9e8 RDI: ffff880173ba0000
[ 4475.357447] RBP: ffff880173ba07e0 R08: ffff88017273dab8 R09: 00000000e27e917a
[ 4475.357449] R10: 0000000000000000 R11: 0000000000000000 R12: ffff88017057d9e8
[ 4475.357452] R13: ffff8801789d4a88 R14: 00000000ffffffff R15: ffff880173279ac0
[ 4475.357500]  intel_disable_pipe+0x51/0x210 [i915]
[ 4475.357540]  haswell_crtc_disable+0x69/0x130 [i915]
[ 4475.357579]  intel_atomic_commit_tail+0x1bb/0xd20 [i915]
[ 4475.357614]  ? __i915_sw_fence_complete+0x175/0x230 [i915]
[ 4475.357654]  intel_atomic_commit+0x22a/0x2e0 [i915]
[ 4475.357663]  drm_mode_atomic_ioctl+0x827/0x9d0
[ 4475.357684]  ? drm_atomic_set_property+0x480/0x480
[ 4475.357689]  drm_ioctl_kernel+0x60/0xa0
[ 4475.357695]  drm_ioctl+0x290/0x330
[ 4475.357701]  ? drm_atomic_set_property+0x480/0x480
[ 4475.357717]  do_vfs_ioctl+0x8a/0x680
[ 4475.357721]  ? entry_SYSCALL_64_fastpath+0x5/0x89
[ 4475.357727]  ? trace_hardirqs_on_caller+0xde/0x1c0
[ 4475.357734]  SyS_ioctl+0x36/0x70
[ 4475.357740]  entry_SYSCALL_64_fastpath+0x1c/0x89
[ 4475.357743] RIP: 0033:0x7f1a9af8a587
[ 4475.357746] RSP: 002b:00007ffda3679708 EFLAGS: 00000246 ORIG_RAX: 0000000000000010
[ 4475.357751] RAX: ffffffffffffffda RBX: 000055f90353a1a8 RCX: 00007f1a9af8a587
[ 4475.357753] RDX: 00007ffda3679760 RSI: 00000000c03864bc RDI: 0000000000000003
[ 4475.357756] RBP: 0000000000000004 R08: 000055f90353ede0 R09: 000055f90353a530
[ 4475.357758] R10: 0000000000000001 R11: 0000000000000246 R12: 0000000000000001
[ 4475.357761] R13: 0000000000000001 R14: 0000000000000108 R15: 000055f9017cd448
[ 4475.357774] Code: e1 48 c7 c2 90 dd cd 81 be 01 00 00 00 48 c7 c7 da fe c5 81 e8 37 ed ff ff 48 8b 83 28 08 00 00 48 83 78 20 00 0f 84 e5 fe ff ff <0f> ff 45 31 ed e9 de fe ff ff 41 b8 d3 4d 62 10 89 c8 6a 03 41 
[ 4475.357921] ---[ end trace f9a42ff52cb7da3b ]---
[ 4476.114280] hpet1: lost 7160 rtc interrupts
[ 4477.014937] hpet1: lost 7161 rtc interrupts

Comment 4 Marta Löfstedt 2018-01-03 11:21:22 UTC

The issue has not been reproduced for some time I will close this.

Use of freedesktop.org services, including Bugzilla, is subject to our Code of Conduct. How we collect and use information is described in our Privacy Policy.