Bug 107469

Summary:	[CI] igt@kms_frontbuffer_tracking@fbc-1p-* dmesg-warn - ERROR render: timed out waiting for forcewake ack request.
Product:	DRI	Reporter:	Tomi Sarvela <tomi.p.sarvela>
Component:	DRM/Intel	Assignee:	Intel GFX Bugs mailing list <intel-gfx-bugs>
Status:	RESOLVED MOVED	QA Contact:	Intel GFX Bugs mailing list <intel-gfx-bugs>
Severity:	normal
Priority:	medium	CC:	intel-gfx-bugs, martin.peres
Version:	DRI git
Hardware:	Other
OS:	All
Whiteboard:	ReadyForDev
i915 platform:	SNB	i915 features:	display/Other

Description Tomi Sarvela 2018-08-03 10:37:50 UTC

https://intel-gfx-ci.01.org/tree/drm-tip/IGT_4584/shard-snb4/igt@kms_frontbuffer_tracking@fbc-1p-offscren-pri-shrfb-draw-mmap-wc.html

[   56.839338] Setting dangerous option enable_psr - tainting kernel
[   56.839441] Setting dangerous option enable_fbc - tainting kernel
[   57.746166] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   57.751320] ------------[ cut here ]------------
[   57.751323] GT thread status wait timed out
[   57.751409] WARNING: CPU: 5 PID: 264 at drivers/gpu/drm/i915/intel_uncore.c:303 __gen6_gt_wait_for_thread_c0+0x6e/0xa0 [i915]
[   57.751413] Modules linked in: snd_hda_codec_hdmi snd_hda_codec_realtek snd_hda_codec_generic i915 x86_pkg_temp_thermal coretemp crct10dif_pclmul crc32_pclmul ghash_clmulni_intel snd_hda_intel snd_hda_codec snd_hwdep broadcom bcm_phy_lib snd_hda_core tg3 snd_pcm mei_me mei lpc_ich prime_numbers
[   57.751486] CPU: 5 PID: 264 Comm: kworker/5:2 Tainted: G     U            4.18.0-rc7-CI-CI_DRM_4605+ #1
[   57.751489] Hardware name: Dell Inc. XPS 8300  /0Y2MRG, BIOS A06 10/17/2011
[   57.751551] Workqueue: events_long i915_hangcheck_elapsed [i915]
[   57.751617] RIP: 0010:__gen6_gt_wait_for_thread_c0+0x6e/0xa0 [i915]
[   57.751620] Code: 8b 92 5c 80 13 00 83 e2 07 75 d5 5b 5d c3 80 3d f9 fd 18 00 00 75 f4 48 c7 c7 28 53 2a a0 c6 05 e9 fd 18 00 01 e8 52 b5 ed e0 <0f> 0b eb dd 80 3d d8 fd 18 00 00 75 98 48 c7 c6 f8 52 2a a0 48 c7 
[   57.751809] RSP: 0018:ffffc900016f7d08 EFLAGS: 00010082
[   57.751816] RAX: 0000000000000000 RBX: ffff8802104c0000 RCX: 0000000000000002
[   57.751820] RDX: 0000000080000002 RSI: ffffffff820c701c RDI: 00000000ffffffff
[   57.751824] RBP: 0000000d71f2e65c R08: 0000000000000000 R09: 0000000000000001
[   57.751827] R10: ffffc900016f7ce8 R11: 0000000000000000 R12: ffff8802104c0eb0
[   57.751831] R13: 0000000000000001 R14: 0000000000000000 R15: 0000000000000000
[   57.751836] FS:  0000000000000000(0000) GS:ffff88022fb40000(0000) knlGS:0000000000000000
[   57.751840] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[   57.751844] CR2: 00007ff0abe99000 CR3: 0000000002210005 CR4: 00000000000606e0
[   57.751847] Call Trace:
[   57.751912]  gen6_read32+0x188/0x290 [i915]
[   57.751978]  intel_engine_get_active_head+0xbe/0xd0 [i915]
[   57.752041]  i915_hangcheck_elapsed+0xdc/0x540 [i915]
[   57.752057]  ? lock_acquire+0xa6/0x210
[   57.752074]  process_one_work+0x248/0x6c0
[   57.752089]  worker_thread+0x37/0x380
[   57.752098]  ? process_one_work+0x6c0/0x6c0
[   57.752103]  kthread+0x119/0x130
[   57.752109]  ? kthread_flush_work_fn+0x10/0x10
[   57.752119]  ret_from_fork+0x3a/0x50
[   57.752137] irq event stamp: 136048
[   57.752143] hardirqs last  enabled at (136047): [<ffffffff8193ff04>] _raw_spin_unlock_irq+0x24/0x50
[   57.752149] hardirqs last disabled at (136048): [<ffffffff8193fd3d>] _raw_spin_lock_irqsave+0xd/0x50
[   57.752154] softirqs last  enabled at (132740): [<ffffffff81c0034f>] __do_softirq+0x34f/0x505
[   57.752160] softirqs last disabled at (132733): [<ffffffff8108c835>] irq_exit+0xa5/0xc0
[   57.752222] WARNING: CPU: 5 PID: 264 at drivers/gpu/drm/i915/intel_uncore.c:303 __gen6_gt_wait_for_thread_c0+0x6e/0xa0 [i915]
[   57.752225] ---[ end trace b284483020a019c8 ]---
[   57.802348] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   57.857503] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   57.912641] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   57.967777] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   58.022913] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   58.078049] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   58.133230] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   58.188372] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   58.243511] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   58.298656] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   58.353793] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   58.408940] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   58.464087] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   58.519228] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   58.574369] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   58.629509] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   58.684650] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   58.739789] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   58.794929] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   58.850169] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   58.905307] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   58.960444] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   60.754140] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   60.809503] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   60.864727] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   60.919875] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   60.975044] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   61.030224] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   61.085388] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   61.140586] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   61.195756] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   61.250927] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   61.306122] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   61.361293] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   61.416454] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   61.471624] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   61.526786] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   61.581954] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   61.637123] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   61.692293] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   61.747463] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   61.802632] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   61.857810] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   61.913048] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   61.968214] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   62.023386] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   62.078537] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   62.133681] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   62.188930] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   62.244089] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   62.299234] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   62.354382] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   62.409528] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   62.464677] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   62.519817] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   62.574966] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   62.630114] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   62.685259] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   62.740406] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   62.795551] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   62.850696] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   62.905936] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   62.961097] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   63.016238] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   63.071381] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   63.126524] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   63.181684] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   63.236830] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   63.291974] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   63.347120] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   63.402259] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   63.457408] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   63.512553] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   63.567700] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   63.622844] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   63.677992] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   63.733138] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   63.788282] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   63.843428] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   63.898597] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   63.953740] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   64.008880] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   64.064036] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   64.119181] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   64.174352] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   64.229496] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   64.238101] i915 0000:00:02.0: Resetting chip for hang on rcs0, bcs0, vcs0
[   64.288224] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[   65.300123] [drm:stop_ring [i915]] *ERROR* rcs0 : timed out trying to stop ring
[   65.300257] ------------[ cut here ]------------
[   65.300261] WARN_ON((dev_priv->uncore.funcs.mmio_readl(dev_priv, (((const i915_reg_t){ .reg = (((engine)->mmio_base) + 0x9c) })), true) & (1 << 9)) == 0)
[   65.300348] WARNING: CPU: 3 PID: 1390 at drivers/gpu/drm/i915/intel_ringbuffer.c:401 init_ring_common+0x8b4/0x990 [i915]
[   65.300352] Modules linked in: snd_hda_codec_hdmi snd_hda_codec_realtek snd_hda_codec_generic i915 x86_pkg_temp_thermal coretemp crct10dif_pclmul crc32_pclmul ghash_clmulni_intel snd_hda_intel snd_hda_codec snd_hwdep broadcom bcm_phy_lib snd_hda_core tg3 snd_pcm mei_me mei lpc_ich prime_numbers
[   65.300426] CPU: 3 PID: 1390 Comm: kms_frontbuffer Tainted: G     U  W         4.18.0-rc7-CI-CI_DRM_4605+ #1
[   65.300430] Hardware name: Dell Inc. XPS 8300  /0Y2MRG, BIOS A06 10/17/2011
[   65.300493] RIP: 0010:init_ring_common+0x8b4/0x990 [i915]
[   65.300496] Code: 0b 41 be 80 42 00 00 e9 30 fc ff ff 41 be 80 41 00 00 e9 25 fc ff ff 48 c7 c6 a8 4a 2a a0 48 c7 c7 35 62 28 a0 e8 3c 10 ee e0 <0f> 0b e9 a9 fc ff ff 48 c7 c1 57 62 28 a0 ba cc 00 00 00 48 c7 c6 
[   65.300686] RSP: 0018:ffffc90000353b60 EFLAGS: 00010282
[   65.300693] RAX: 0000000000000000 RBX: ffff8802104c0000 RCX: 0000000000000001
[   65.300697] RDX: 0000000080000001 RSI: ffffffff820c701c RDI: 00000000ffffffff
[   65.300701] RBP: ffff880217b1c2a8 R08: 0000000018d51b99 R09: 0000000000000000
[   65.300705] R10: 0000000000000000 R11: 0000000000000000 R12: ffff8802104c0000
[   65.300708] R13: ffff88020ae417a8 R14: 00000000000020c0 R15: ffff880217b1a158
[   65.300713] FS:  00007ff0b802b980(0000) GS:ffff88022fac0000(0000) knlGS:0000000000000000
[   65.300717] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[   65.300721] CR2: 00007fdc732f20f8 CR3: 000000021cd2e001 CR4: 00000000000606e0
[   65.300724] Call Trace:
[   65.300735]  ? trace_hardirqs_on_caller+0xe0/0x1b0
[   65.300801]  init_render_ring+0xf/0x170 [i915]
[   65.300862]  i915_gem_init_hw+0x26e/0x300 [i915]
[   65.300925]  ? i915_request_wait+0x162/0x8a0 [i915]
[   65.300971]  i915_reset+0x23d/0x290 [i915]
[   65.301032]  __i915_wait_request_check_and_reset.isra.8+0x44/0x50 [i915]
[   65.301090]  i915_request_wait+0x4ee/0x8a0 [i915]
[   65.301105]  ? wake_up_q+0x70/0x70
[   65.301113]  ? wake_up_q+0x70/0x70
[   65.301179]  wait_for_timeline+0x131/0x2c0 [i915]
[   65.301240]  i915_gem_wait_for_idle.part.27+0x5a/0x1b0 [i915]
[   65.301296]  i915_drop_caches_set+0x1cb/0x1e0 [i915]
[   65.301306]  simple_attr_write+0xb0/0xd0
[   65.301319]  full_proxy_write+0x51/0x80
[   65.301329]  __vfs_write+0x31/0x180
[   65.301339]  ? rcu_read_lock_sched_held+0x6f/0x80
[   65.301344]  ? rcu_sync_lockdep_assert+0x29/0x50
[   65.301350]  ? __sb_start_write+0x152/0x1f0
[   65.301355]  ? __sb_start_write+0x168/0x1f0
[   65.301364]  vfs_write+0xbd/0x1a0
[   65.301372]  ksys_write+0x50/0xc0
[   65.301383]  do_syscall_64+0x55/0x190
[   65.301391]  entry_SYSCALL_64_after_hwframe+0x49/0xbe
[   65.301396] RIP: 0033:0x7ff0b7297154
[   65.301399] Code: 89 02 48 c7 c0 ff ff ff ff c3 66 2e 0f 1f 84 00 00 00 00 00 66 90 48 8d 05 b1 07 2e 00 8b 00 85 c0 75 13 b8 01 00 00 00 0f 05 <48> 3d 00 f0 ff ff 77 54 f3 c3 66 90 41 54 55 49 89 d4 53 48 89 f5 
[   65.301589] RSP: 002b:00007ffc7d3621d8 EFLAGS: 00000246 ORIG_RAX: 0000000000000001
[   65.301596] RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 00007ff0b7297154
[   65.301600] RDX: 0000000000000004 RSI: 000055c883f46c70 RDI: 0000000000000006
[   65.301604] RBP: 000055c883f46c70 R08: 00007ff0b802b980 R09: 0000000000000000
[   65.301608] R10: 0000000000000000 R11: 0000000000000246 R12: 000055c883f303f0
[   65.301611] R13: 0000000000000004 R14: 00007ff0b756f2a0 R15: 00007ff0b756e760
[   65.301630] irq event stamp: 222224
[   65.301636] hardirqs last  enabled at (222223): [<ffffffff810f8b9c>] console_unlock+0x3fc/0x600
[   65.301642] hardirqs last disabled at (222224): [<ffffffff81a0111c>] error_entry+0x7c/0x100
[   65.301647] softirqs last  enabled at (206640): [<ffffffff81c0034f>] __do_softirq+0x34f/0x505
[   65.301654] softirqs last disabled at (206631): [<ffffffff8108c835>] irq_exit+0xa5/0xc0
[   65.301715] WARNING: CPU: 3 PID: 1390 at drivers/gpu/drm/i915/intel_ringbuffer.c:401 init_ring_common+0x8b4/0x990 [i915]
[   65.301719] ---[ end trace b284483020a019c9 ]---
[   65.352124] [drm:init_ring_common [i915]] *ERROR* rcs0 initialization failed ctl 00000000 (valid? 0) head 00000000 [0000c140] tail 00000000 [0000c140] start 00000000 [expected 00001000]
[   65.352282] [drm:i915_gem_init_hw [i915]] *ERROR* Failed to restart rcs0 (-5)
[   65.352371] [drm:i915_reset [i915]] *ERROR* Failed to initialise HW following reset (-5)

Comment 1 Tomi Sarvela 2018-08-03 10:39:48 UTC

https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_4606/shard-snb6/igt@kms_frontbuffer_tracking@fbc-1p-offscren-pri-shrfb-draw-mmap-wc.html

https://intel-gfx-ci.01.org/tree/drm-tip/IGT_4579/shard-snb1/igt@kms_frontbuffer_tracking@fbc-1p-primscrn-cur-indfb-draw-mmap-gtt.html

Comment 2 Martin Peres 2018-09-04 12:25:03 UTC

https://intel-gfx-ci.01.org/tree/drm-tip/IGT_4624/shard-snb2/igt@perf_pmu@busy-start-vcs0.html

[  128.781339] [drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request.
[  128.786503] ------------[ cut here ]------------
[  128.786506] GT thread status wait timed out
[  128.786595] WARNING: CPU: 3 PID: 1606 at drivers/gpu/drm/i915/intel_uncore.c:303 __gen6_gt_wait_for_thread_c0+0x6e/0xa0 [i915]
[  128.786598] Modules linked in: vgem snd_hda_codec_hdmi snd_hda_codec_realtek snd_hda_codec_generic i915 x86_pkg_temp_thermal coretemp crct10dif_pclmul snd_hda_intel crc32_pclmul snd_hda_codec ghash_clmulni_intel snd_hwdep snd_hda_core snd_pcm broadcom bcm_phy_lib tg3 mei_me lpc_ich mei prime_numbers
[  128.786676] CPU: 3 PID: 1606 Comm: perf_pmu Tainted: G     U            4.19.0-rc2-CI-CI_DRM_4763+ #1
[  128.786680] Hardware name: Dell Inc. XPS 8300  /0Y2MRG, BIOS A06 10/17/2011
[  128.786743] RIP: 0010:__gen6_gt_wait_for_thread_c0+0x6e/0xa0 [i915]
[  128.786748] Code: 8b 92 5c 80 13 00 83 e2 07 75 d5 5b 5d c3 80 3d ff 08 19 00 00 75 f4 48 c7 c7 e0 c0 2f a0 c6 05 ef 08 19 00 01 e8 02 4d e8 e0 <0f> 0b eb dd 80 3d de 08 19 00 00 75 98 48 c7 c6 b0 c0 2f a0 48 c7
[  128.786752] RSP: 0018:ffffc900003c39b8 EFLAGS: 00010086
[  128.786759] RAX: 0000000000000000 RBX: ffff88020ebd0000 RCX: 0000000000000202
[  128.786763] RDX: 0000000080000202 RSI: ffffffff820c3076 RDI: 00000000ffffffff
[  128.786767] RBP: 0000001dfbf9c77f R08: 0000000000000000 R09: 0000000000000001
[  128.786771] R10: ffffc900003c3998 R11: 0000000000000000 R12: ffff88020ebd0eb0
[  128.786775] R13: 0000000000000202 R14: ffff88021fd1ebe8 R15: ffff88021fd1ec10
[  128.786779] FS:  00007f7b66b34980(0000) GS:ffff880227ac0000(0000) knlGS:0000000000000000
[  128.786783] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[  128.786787] CR2: 00007f7b66b68040 CR3: 000000017cec2004 CR4: 00000000000606e0
[  128.786791] Call Trace:
[  128.786857]  intel_uncore_forcewake_get+0x44/0xa0 [i915]
[  128.786922]  gen6_bsd_submit_request+0x19/0xa0 [i915]
[  128.786983]  submit_notify+0x8d/0x124 [i915]
[  128.787032]  __i915_sw_fence_complete+0x81/0x250 [i915]
[  128.787099]  i915_request_add+0x30c/0x770 [i915]
[  128.787160]  i915_gem_do_execbuffer+0xe23/0x1800 [i915]
[  128.787186]  ? ___slab_alloc.constprop.34+0x221/0x390
[  128.787239]  ? i915_gem_execbuffer2_ioctl+0xc4/0x3f0 [i915]
[  128.787252]  ? lock_acquire+0xa6/0x1c0
[  128.787317]  ? i915_gem_execbuffer_ioctl+0x300/0x300 [i915]
[  128.787370]  i915_gem_execbuffer2_ioctl+0x21b/0x3f0 [i915]
[  128.787426]  ? i915_gem_execbuffer_ioctl+0x300/0x300 [i915]
[  128.787434]  drm_ioctl_kernel+0x7c/0xf0
[  128.787445]  drm_ioctl+0x2e6/0x3a0
[  128.787499]  ? i915_gem_execbuffer_ioctl+0x300/0x300 [i915]
[  128.787522]  do_vfs_ioctl+0xa0/0x6d0
[  128.787537]  ksys_ioctl+0x35/0x60
[  128.787546]  __x64_sys_ioctl+0x11/0x20
[  128.787552]  do_syscall_64+0x55/0x190
[  128.787560]  entry_SYSCALL_64_after_hwframe+0x49/0xbe
[  128.787565] RIP: 0033:0x7f7b65c2b5d7
[  128.787569] Code: b3 66 90 48 8b 05 b1 48 2d 00 64 c7 00 26 00 00 00 48 c7 c0 ff ff ff ff c3 66 2e 0f 1f 84 00 00 00 00 00 b8 10 00 00 00 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 8b 0d 81 48 2d 00 f7 d8 64 89 01 48
[  128.787573] RSP: 002b:00007ffc152634f8 EFLAGS: 00000246 ORIG_RAX: 0000000000000010
[  128.787580] RAX: ffffffffffffffda RBX: 00005646aaef1ca8 RCX: 00007f7b65c2b5d7
[  128.787584] RDX: 00005646aaef1ce0 RSI: 00000000c0406469 RDI: 0000000000000003
[  128.787588] RBP: 00005646aaef1ce0 R08: 0000000000000002 R09: 0000000000000021
[  128.787591] R10: 0000000000000054 R11: 0000000000000246 R12: 00000000c0406469
[  128.787595] R13: 0000000000000003 R14: 0000000000000000 R15: 0000000000000000
[  128.787613] irq event stamp: 40847
[  128.787619] hardirqs last  enabled at (40846): [<ffffffff8193f66c>] _raw_spin_unlock_irqrestore+0x4c/0x60
[  128.787624] hardirqs last disabled at (40847): [<ffffffff8193f4dd>] _raw_spin_lock_irqsave+0xd/0x50
[  128.787631] softirqs last  enabled at (39780): [<ffffffff81c0031d>] __do_softirq+0x31d/0x483
[  128.787688] softirqs last disabled at (40838): [<ffffffffa01d6bbb>] i915_request_add+0x25b/0x770 [i915]
[  128.787749] WARNING: CPU: 3 PID: 1606 at drivers/gpu/drm/i915/intel_uncore.c:303 __gen6_gt_wait_for_thread_c0+0x6e/0xa0 [i915]
[  128.787753] ---[ end trace 5b104b37b2e2c055 ]---

Comment 3 Chris Wilson 2018-09-21 09:57:20 UTC

*** Bug 107988 has been marked as a duplicate of this bug. ***

Comment 4 Chris Wilson 2018-10-25 11:57:19 UTC

*** Bug 108552 has been marked as a duplicate of this bug. ***

Comment 5 Chris Wilson 2018-11-02 09:52:55 UTC

*** Bug 108633 has been marked as a duplicate of this bug. ***

Comment 6 CI Bug Log 2019-01-02 13:43:58 UTC

A CI Bug Log filter associated to this bug has been updated:

{- SNB: igt@runner@aborted - fail - Previous test: prime_vgem (fence-wait-blt) -}
{+ SNB: igt@runner@aborted - fail - Previous test: prime_vgem (fence-wait-blt) | kms_frontbuffer_tracking (*) +}

New failures caught by the filter:

* https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_5354/shard-snb7/igt@runner@aborted.html

Comment 7 CI Bug Log 2019-01-18 13:15:27 UTC

A CI Bug Log filter associated to this bug has been updated:

{- SNB: igt@runner@aborted - fail - Previous test: prime_vgem (fence-wait-blt) | kms_frontbuffer_tracking (*) -}
{+ SNB: igt@runner@aborted - fail - Previous test: prime_vgem (fence-wait-blt) | kms_frontbuffer_tracking | kms_pipe_crc_basic +}

New failures caught by the filter:

* https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_5440/shard-snb4/igt@runner@aborted.html
* https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_5443/shard-snb6/igt@runner@aborted.html
* https://intel-gfx-ci.01.org/tree/drm-tip/IGT_4777/shard-snb4/igt@runner@aborted.html

Comment 8 CI Bug Log 2019-01-31 08:27:07 UTC

A CI Bug Log filter associated to this bug has been updated:

{- SNB: igt@runner@aborted - fail - Previous test: prime_vgem (fence-wait-blt) | kms_frontbuffer_tracking | kms_pipe_crc_basic -}
{+ SNB HSW: igt@runner@aborted - fail - Previous test: prime_vgem (fence-wait-blt) | kms_frontbuffer_tracking | kms_pipe_crc_basic +}

New failures caught by the filter:

* https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_5505/shard-hsw5/igt@runner@aborted.html
* https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_5513/shard-snb1/igt@runner@aborted.html
* https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_5514/shard-snb1/igt@runner@aborted.html

Comment 9 CI Bug Log 2019-01-31 08:27:55 UTC

A CI Bug Log filter associated to this bug has been updated:

{- SNB HSW: igt@runner@aborted - fail - Previous test: prime_vgem (fence-wait-blt) | kms_frontbuffer_tracking | kms_pipe_crc_basic -}
{+ SNB: igt@runner@aborted - fail - Previous test: prime_vgem (fence-wait-blt) | kms_frontbuffer_tracking | kms_pipe_crc_basic +}

 No new failures caught with the new filter

Comment 10 CI Bug Log 2019-01-31 08:35:02 UTC

A CI Bug Log filter associated to this bug has been updated:

{- SNB: igt@runner@aborted - fail - Previous test: prime_vgem (fence-wait-blt) | kms_frontbuffer_tracking | kms_pipe_crc_basic -}
{+ SNB: igt@runner@aborted - fail - Previous test: prime_vgem (fence-wait-blt) | kms_frontbuffer_tracking | kms_pipe_crc_basic +}

New failures caught by the filter:

* https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_5509/shard-snb2/igt@runner@aborted.html

Comment 11 CI Bug Log 2019-02-21 14:29:06 UTC

A CI Bug Log filter associated to this bug has been updated:

{- SNB: igt@runner@aborted - fail - Previous test: prime_vgem (fence-wait-blt) | kms_frontbuffer_tracking | kms_pipe_crc_basic -}
{+ SNB: igt@runner@aborted - fail - Previous test: prime_vgem (fence-wait-blt) | kms_frontbuffer_tracking | kms_pipe_crc_basic | syncobj_wait +}

New failures caught by the filter:

* https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_5645/shard-snb6/igt@runner@aborted.html

Comment 12 CI Bug Log 2019-08-01 14:31:22 UTC

A CI Bug Log filter associated to this bug has been updated:

{- SNB: igt@runner@aborted - fail - Previous test: prime_vgem (fence-wait-blt) | kms_frontbuffer_tracking | kms_pipe_crc_basic | syncobj_wait -}
{+ SNB: igt@runner@aborted - fail - Previous test: prime_vgem (fence-wait-blt) | kms_frontbuffer_tracking | kms_pipe_crc_basic | syncobj_wait +}

New failures caught by the filter:

  * https://intel-gfx-ci.01.org/tree/drm-tip/IGT_5119/fi-snb-2520m/igt@runner@aborted.html

Comment 13 ashutosh.dixit 2019-11-08 19:31:15 UTC

#assessment

The error/warning "[drm:fw_domains_get [i915]] *ERROR* render: timed out waiting for forcewake ack request" continues to happen sporadically. But considering it is SNB, reducing importance to medium. Please revert if there is disagreement.

Comment 14 Martin Peres 2019-12-02 16:39:45 UTC

-- GitLab Migration Automatic Message --

This bug has been migrated to freedesktop.org's GitLab instance and has been closed from further activity.

You can subscribe and participate further through the new bug through this link to our GitLab instance: https://gitlab.freedesktop.org/drm/intel/issues/698.

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.