103293 – [CI] igt@kms_* - dmesg-warn - *ERROR* Timed out waiting for DP idle patterns - vblank not available on crtc 0, ret=-22

Bug 103293 - [CI] igt@kms_* - dmesg-warn - *ERROR* Timed out waiting for DP idle patterns - vblank not available on crtc 0, ret=-22

Summary: [CI] igt@kms_* - dmesg-warn - *ERROR* Timed out waiting for DP idle patterns ...

Status:	CLOSED WORKSFORME

Alias:	None

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

Importance:	medium normal
Assignee:	Intel GFX Bugs mailing list
QA Contact:	Intel GFX Bugs mailing list

URL:
Whiteboard:	ReadyForDev
Keywords:

Depends on:
Blocks:

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

See Also:
i915 platform:	BXT, GLK, KBL
i915 features:	display/DP

Attachments

Description Marta Löfstedt 2017-10-16 12:52:31 UTC

CI_DRM_3239 KBL-shards dmesg-warn igt@kms_cursor_legacy@long-nonblocking-modeset-vs-cursor-atomic:
	
[  182.494228] [drm:intel_dp_set_idle_link_train [i915]] *ERROR* Timed out waiting for DP idle patterns
[  182.597127] [drm:intel_dp_set_idle_link_train [i915]] *ERROR* Timed out waiting for DP idle patterns
[  182.706771] [drm:intel_dp_set_idle_link_train [i915]] *ERROR* Timed out waiting for DP idle patterns
[  182.816977] [drm:intel_dp_set_idle_link_train [i915]] *ERROR* Timed out waiting for DP idle patterns
[  183.535438] vblank not available on crtc 0, ret=-22
[  183.535469] ------------[ cut here ]------------
[  183.535485] WARNING: CPU: 1 PID: 5 at drivers/gpu/drm/drm_vblank.c:1042 drm_wait_one_vblank+0x157/0x1a0
[  183.535494] Modules linked in: snd_hda_codec_hdmi snd_hda_codec_realtek snd_hda_codec_generic i915 snd_hda_intel snd_hda_codec snd_hwdep snd_hda_core x86_pkg_temp_thermal intel_powerclamp coretemp crct10dif_pclmul crc32_pclmul snd_pcm ghash_clmulni_intel e1000e ptp pps_core mei_me mei prime_numbers pinctrl_sunrisepoint i2c_hid pinctrl_intel
[  183.535718] CPU: 1 PID: 5 Comm: kworker/u8:0 Tainted: G     U          4.14.0-rc4-CI-CI_DRM_3239+ #1
[  183.535728] Hardware name:                  /NUC7i5BNB, BIOS BNKBL357.86A.0048.2017.0704.1415 07/04/2017
[  183.535800] Workqueue: i915-dp i915_digport_work_func [i915]
[  183.535819] task: ffff8802752dd440 task.stack: ffffc9000006c000
[  183.535832] RIP: 0010:drm_wait_one_vblank+0x157/0x1a0
[  183.535842] RSP: 0018:ffffc9000006fd10 EFLAGS: 00010282
[  183.535860] RAX: 0000000000000027 RBX: ffff880268c50000 RCX: 0000000000000001
[  183.535869] RDX: 0000000080000001 RSI: ffffffff81d18fe7 RDI: 00000000ffffffff
[  183.535879] RBP: ffffc9000006fd68 R08: ffff8802752ddd78 R09: 0000000000000000
[  183.535888] R10: 0000000000000000 R11: 0000000000000000 R12: 0000000000000000
[  183.535897] R13: 0000000000000000 R14: ffff88026961a548 R15: ffff8802696a4470
[  183.535907] FS:  0000000000000000(0000) GS:ffff88027ec80000(0000) knlGS:0000000000000000
[  183.535917] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[  183.535926] CR2: 00007efc95e30000 CR3: 000000027282d005 CR4: 00000000003606e0
[  183.537813] Call Trace:
[  183.537839]  ? drm_dp_dpcd_write+0x1b/0x20
[  183.537941]  ? intel_dp_set_link_train+0x49/0xf0 [i915]
[  183.538029]  intel_dp_check_link_status+0x125/0x1a0 [i915]
[  183.538201]  intel_dp_hpd_pulse+0x166/0x2f0 [i915]
[  183.538307]  i915_digport_work_func+0x8f/0x100 [i915]
[  183.538337]  process_one_work+0x233/0x660
[  183.538371]  worker_thread+0x4e/0x3b0
[  183.538401]  kthread+0x152/0x190
[  183.538421]  ? process_one_work+0x660/0x660
[  183.538549]  ? kthread_create_on_node+0x40/0x40
[  183.538569]  ? kthread_create_on_node+0x40/0x40
[  183.538590]  ret_from_fork+0x27/0x40
[  183.538627] Code: c6 eb 9e 0f ff 31 c0 eb c3 0f ff 48 83 c4 30 5b 41 5c 41 5d 41 5e 41 5f 5d c3 89 c2 44 89 e6 48 c7 c7 88 74 ce 81 e8 ea 5e ae ff <0f> ff e9 46 ff ff ff 0f ff 45 31 ff e9 fc fe ff ff 0f ff 31 c0 
[  183.539776] ---[ end trace 095b222339f007d6 ]---

https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_3239/shard-kbl5/igt@kms_cursor_legacy@long-nonblocking-modeset-vs-cursor-atomic.html

Comment 1 Marta Löfstedt 2017-10-16 12:54:06 UTC

This time without the WARN:

<3>[   20.584067] [drm:intel_dp_set_idle_link_train [i915]] *ERROR* Timed out waiting for DP idle patterns

https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_3239/shard-kbl4/igt@kms_atomic_transition@1x-modeset-transitions-nonblocking.html

Comment 2 Marta Löfstedt 2017-10-16 12:54:54 UTC

Here also without vbalnk WARN:

https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_3239/shard-kbl6/igt@kms_busy@extended-modeset-hang-oldfb-render-B.html

Comment 3 Marta Löfstedt 2017-10-17 07:10:38 UTC

https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_3241/shard-kbl7/igt@kms_cursor_legacy@nonblocking-modeset-vs-cursor-atomic.html

Comment 4 Marta Löfstedt 2017-10-17 07:11:28 UTC

https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_3241/shard-kbl5/igt@kms_busy@extended-modeset-hang-oldfb-render-A.html

Comment 5 Marta Löfstedt 2017-10-30 07:54:24 UTC

Also on APL-shards CI_DRM_3292 igt@kms_busy@extended-modeset-hang-oldfb-render-B


<4>[  744.804104] vblank not available on crtc 1, ret=-22
<4>[  744.804135] ------------[ cut here ]------------
<4>[  744.804147] WARNING: CPU: 0 PID: 74 at drivers/gpu/drm/drm_vblank.c:1063 drm_wait_one_vblank+0x160/0x1b0
<4>[  744.804152] Modules linked in: vgem 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 lpc_ich snd_hwdep snd_hda_core r8169 snd_pcm mii mei_me mei prime_numbers pinctrl_broxton pinctrl_intel
<4>[  744.804266] CPU: 0 PID: 74 Comm: kworker/u8:1 Tainted: G     U          4.14.0-rc6-CI-CI_DRM_3292+ #1
<4>[  744.804271] Hardware name:                  /NUC6CAYB, BIOS AYAPLCEL.86A.0040.2017.0619.1722 06/19/2017
<4>[  744.804334] Workqueue: i915-dp i915_digport_work_func [i915]
<4>[  744.804345] task: ffff880274100040 task.stack: ffffc90000478000
<4>[  744.804353] RIP: 0010:drm_wait_one_vblank+0x160/0x1b0
<4>[  744.804358] RSP: 0018:ffffc9000047bd18 EFLAGS: 00010296
<4>[  744.804368] RAX: 0000000000000027 RBX: ffff880266e90000 RCX: 0000000000000001
<4>[  744.804373] RDX: 0000000080000001 RSI: ffffffff81cb4c2b RDI: 00000000ffffffff
<4>[  744.804378] RBP: ffffc9000047bd70 R08: ffff880274100950 R09: 0000000000000000
<4>[  744.804383] R10: 0000000000000000 R11: 0000000000000000 R12: ffff88026506b7e8
<4>[  744.804388] R13: 0000000000000001 R14: 0000000000000210 R15: ffff88026b10a320
<4>[  744.804394] FS:  0000000000000000(0000) GS:ffff88027fc00000(0000) knlGS:0000000000000000
<4>[  744.804399] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
<4>[  744.804404] CR2: 00007ff23de52010 CR3: 000000026e8f2000 CR4: 00000000003406f0
<4>[  744.804409] Call Trace:
<4>[  744.804419]  ? drm_dp_dpcd_write+0x1b/0x20
<4>[  744.804476]  ? intel_dp_set_link_train+0x49/0xf0 [i915]
<4>[  744.804535]  intel_dp_check_link_status+0x125/0x1a0 [i915]
<4>[  744.804593]  intel_dp_hpd_pulse+0x166/0x2f0 [i915]
<4>[  744.804712]  i915_digport_work_func+0x8f/0x100 [i915]
<4>[  744.804729]  process_one_work+0x221/0x650
<4>[  744.804746]  worker_thread+0x4e/0x3b0
<4>[  744.804762]  kthread+0x114/0x150
<4>[  744.804769]  ? process_one_work+0x650/0x650
<4>[  744.804778]  ? kthread_create_on_node+0x40/0x40
<4>[  744.804790]  ret_from_fork+0x27/0x40
<4>[  744.804806] Code: c4 eb 9e 0f ff 31 c0 eb c3 0f ff 48 83 c4 30 5b 41 5c 41 5d 41 5e 41 5f 5d c3 89 c2 44 89 ee 48 c7 c7 80 79 ce 81 e8 11 6f ae ff <0f> ff e9 46 ff ff ff 0f ff 45 31 ff e9 fc fe ff ff 0f ff 31 c0 
<4>[  744.805111] ---[ end trace f3becc8703561c3d ]---
<3>[  744.805718] [drm:intel_dp_set_idle_link_train [i915]] *ERROR* Timed out waiting for DP idle patterns

https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_3292/shard-apl2/igt@kms_busy@extended-modeset-hang-oldfb-render-B.html

Comment 6 Marta Löfstedt 2017-10-30 14:20:54 UTC

from CI_DRM_3269 and so far up to CI_DRM_3293

The error prints:

[   20.925108] [drm:intel_dp_set_idle_link_train [i915]] *ERROR* Timed out waiting for DP idle patterns

happend 100% of the runs on KBL-shards.

https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_3269/shard-kbl3/igt@kms_atomic_transition@1x-modeset-transitions-nonblocking.html

https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_3293/shard-kbl7/igt@kms_atomic_transition@1x-modeset-transitions-nonblocking.html

Comment 7 Marta Löfstedt 2017-11-07 13:56:30 UTC

https://intel-gfx-ci.01.org/tree/drm-tip/Patchwork_6979/shard-kbl2/igt@kms_busy@extended-modeset-hang-oldfb-render-c.html

Comment 8 Marta Löfstedt 2017-11-07 13:57:56 UTC

https://intel-gfx-ci.01.org/tree/drm-tip/Patchwork_6979/shard-apl3/igt@kms_busy@extended-modeset-hang-oldfb-with-reset-render-a.html

Comment 9 Marta Löfstedt 2017-11-21 11:35:16 UTC

Also,
https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_3365/shard-glkb1/igt@kms_cursor_legacy@nonblocking-modeset-vs-cursor-atomic.html

	

[ 1657.162283] vblank not available on crtc 0, ret=-22
[ 1657.162305] ------------[ cut here ]------------
[ 1657.162313] WARNING: CPU: 0 PID: 2442 at drivers/gpu/drm/drm_vblank.c:1063 drm_wait_one_vblank+0x14b/0x190
[ 1657.162316] Modules linked in: i2c_dev 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 r8169 snd_hwdep snd_hda_core mii snd_pcm mei_me mei prime_numbers i2c_hid pinctrl_geminilake pinctrl_intel [last unloaded: i915]
[ 1657.162403] CPU: 0 PID: 2442 Comm: kworker/0:1 Tainted: G     U          4.14.0-CI-CI_DRM_3365+ #1
[ 1657.162406] Hardware name: Intel Corp. Geminilake/GLK RVP2 LP4SD (07), BIOS GELKRVPA.X64.0062.B30.1708222146 08/22/2017
[ 1657.162443] Workqueue: events i915_hpd_poll_init_work [i915]
[ 1657.162449] task: ffff880176bc4ec0 task.stack: ffffc90000c4c000
[ 1657.162453] RIP: 0010:drm_wait_one_vblank+0x14b/0x190
[ 1657.162456] RSP: 0018:ffffc90000c4fc60 EFLAGS: 00010286
[ 1657.162462] RAX: 0000000000000027 RBX: ffff8801648a0000 RCX: 0000000000000001
[ 1657.162465] RDX: 0000000080000001 RSI: ffffffff81cb96d3 RDI: 00000000ffffffff
[ 1657.162468] RBP: 0000000000000000 R08: ffff880176bc5848 R09: 0000000000000000
[ 1657.162471] R10: 0000000000000000 R11: 0000000000000000 R12: ffff88016e8f2548
[ 1657.162474] R13: 0000000000000000 R14: ffff88016f42c478 R15: 0000000000000002
[ 1657.162477] FS:  0000000000000000(0000) GS:ffff88017fc00000(0000) knlGS:0000000000000000
[ 1657.162480] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 1657.162483] CR2: 00007f27574ea218 CR3: 0000000003e0f000 CR4: 00000000003406f0
[ 1657.162487] Call Trace:
[ 1657.162527]  intel_dp_check_link_status+0x117/0x190 [i915]
[ 1657.162566]  intel_dp_detect+0x24d/0x4c0 [i915]
[ 1657.162576]  drm_helper_probe_detect_ctx+0x31/0xb0
[ 1657.162586]  drm_helper_hpd_irq_event+0x86/0x100
[ 1657.162625]  i915_hpd_poll_init_work+0xbd/0xf0 [i915]
[ 1657.162634]  process_one_work+0x233/0x670
[ 1657.162643]  worker_thread+0x4d/0x3b0
[ 1657.162650]  kthread+0x107/0x140
[ 1657.162655]  ? process_one_work+0x670/0x670
[ 1657.162658]  ? kthread_create_on_node+0x40/0x40
[ 1657.162665]  ret_from_fork+0x27/0x40
[ 1657.162676] Code: 89 c4 eb a6 0f ff 31 c0 eb c8 0f ff 48 83 c4 28 5b 5d 41 5c 41 5d 41 5e 41 5f c3 89 c2 89 ee 48 c7 c7 58 e3 ce 81 e8 00 36 b0 ff <0f> ff e9 54 ff ff ff 0f ff 45 31 f6 e9 0c ff ff ff 0f ff 31 c0 
[ 1657.162863] ---[ end trace 5d799f5f6b59c540 ]---

Comment 10 Marta Löfstedt 2018-01-11 07:36:31 UTC

This issue hasn't been reproduced for over 300 runs. I will close

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.