Bug 105777

Summary:	[BAT] igt@gvt_basic@invalid-placeholder-test - incomplete - Softdog after - DEBUG_LOCKS_WARN_ON(mutex_is_locked(lock)) - drm_fb_helper_fini.part.13+0xb7/0x100
Product:	DRI	Reporter:	Marta Löfstedt <marta.lofstedt>
Component:	DRM/Intel	Assignee:	Jani Saarinen <jani.saarinen>
Status:	CLOSED WORKSFORME	QA Contact:	Intel GFX Bugs mailing list <intel-gfx-bugs>
Severity:	normal
Priority:	high	CC:	intel-gfx-bugs
Version:	DRI git
Hardware:	Other
OS:	All
Whiteboard:	ReadyForDev
i915 platform:	CNL	i915 features:	display/Other

Description Marta Löfstedt 2018-03-28 06:16:19 UTC

https://intel-gfx-ci.01.org/tree/drm-tip/IGT_4386/fi-cnl-y3/igt@gvt_basic@invalid-placeholder-test.html

run.log say owatch timeout:
running: igt/gvt_basic/invalid-placeholder-test

[284/285] skip: 25, pass: 259 |                
owatch: TIMEOUT!
owatch: timeout for /dev/watchdog0 set to 10 (requested 10)

However, From dmesg and pstore there is another backtrace:

<7>[  536.051747] [IGT] gvt_basic: executing
...

<4>[  558.607125] ------------[ cut here ]------------
<4>[  558.607134] DEBUG_LOCKS_WARN_ON(mutex_is_locked(lock))
<4>[  558.607142] WARNING: CPU: 0 PID: 4636 at kernel/locking/mutex-debug.c:103 mutex_destroy+0x49/0x50
<4>[  558.607161] Modules linked in: 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 snd_hda_codec ghash_clmulni_intel snd_hwdep snd_hda_core snd_pcm e1000e mei_me mei prime_numbers [last unloaded: snd_hda_intel]
<4>[  558.607207] CPU: 0 PID: 4636 Comm: gvt_basic Tainted: G     U           4.16.0-rc7-CI-CI_DRM_3992+ #1
<4>[  558.607217] Hardware name: Intel Corporation CannonLake Client Platform/CannonLake Y LPDDR4 RVP, BIOS CNLSFWR1.R00.X122.B01.1801151045 01/15/2018
<4>[  558.607230] RIP: 0010:mutex_destroy+0x49/0x50
<4>[  558.607236] RSP: 0018:ffffc90000ac7db8 EFLAGS: 00010296
<4>[  558.607243] RAX: 000000000000002a RBX: ffff880258ae6b98 RCX: 0000000000000001
<4>[  558.607250] RDX: 0000000080000001 RSI: 0000000000000001 RDI: 00000000ffffffff
<4>[  558.607257] RBP: ffff88025b350008 R08: 0000000000000000 R09: 0000000000000000
<4>[  558.607265] R10: 0000000000000000 R11: ffffffff810ec1eb R12: ffffffffa032d7e0
<4>[  558.607272] R13: ffffffffa032d850 R14: ffff880264d52ef8 R15: ffffffffa032d8d0
<4>[  558.607280] FS:  00007f1a2988a980(0000) GS:ffff88026f800000(0000) knlGS:0000000000000000
<4>[  558.607289] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
<4>[  558.607295] CR2: 00007f8e46b42e80 CR3: 000000024cc5e004 CR4: 0000000000760ef0
<4>[  558.607302] PKRU: 55555554
<4>[  558.607306] Call Trace:
<4>[  558.607313]  drm_fb_helper_fini.part.13+0xb7/0x100
<4>[  558.607357]  intel_fbdev_fini+0x20/0x80 [i915]
<4>[  558.607399]  intel_modeset_cleanup+0x82/0x110 [i915]
<4>[  558.607429]  i915_driver_unload+0x98/0x110 [i915]
<4>[  558.607459]  i915_pci_remove+0x10/0x20 [i915]
<4>[  558.607466]  pci_device_remove+0x31/0xa0
<4>[  558.607472]  device_release_driver_internal+0x15b/0x230
<4>[  558.607480]  driver_detach+0x35/0x70
<4>[  558.607486]  bus_remove_driver+0x53/0xd0
<4>[  558.607492]  pci_unregister_driver+0x39/0xa0
<4>[  558.607500]  SyS_delete_module+0x180/0x1d0
<4>[  558.607506]  ? do_syscall_64+0x19/0x1a0
<4>[  558.607512]  ? trace_hardirqs_on_caller+0xde/0x1c0
<4>[  558.607518]  do_syscall_64+0x65/0x1a0
<4>[  558.607524]  entry_SYSCALL_64_after_hwframe+0x42/0xb7
<4>[  558.607530] RIP: 0033:0x7f1a28f3f1b7
<4>[  558.607535] RSP: 002b:00007fff1a7a35f8 EFLAGS: 00000206 ORIG_RAX: 00000000000000b0
<4>[  558.607544] RAX: ffffffffffffffda RBX: 0000000000000000 RCX: 00007f1a28f3f1b7
<4>[  558.607551] RDX: 0000000000000000 RSI: 0000000000000800 RDI: 000055f58b01e098
<4>[  558.607559] RBP: 000055f58b01e030 R08: 000055f58b01e09c R09: 00007fff1a7a3638
<4>[  558.607566] R10: 00007fff1a7a25f4 R11: 0000000000000206 R12: 000055f58a51cb00
<4>[  558.607573] R13: 00007fff1a7a38a0 R14: 0000000000000000 R15: 0000000000000000
<4>[  558.607583] Code: 00 00 00 5b c3 e8 78 ec 37 00 85 c0 74 ed 8b 05 2e 77 60 02 85 c0 75 e3 48 c7 c6 0e d6 07 82 48 c7 c7 d3 c4 06 82 e8 07 80 fa ff <0f> 0b eb cc 0f 1f 00 48 b8 11 11 11 11 11 11 11 11 48 89 76 20 
<4>[  558.607646] WARNING: CPU: 0 PID: 4636 at kernel/locking/mutex-debug.c:103 mutex_destroy+0x49/0x50
<4>[  558.607655] ---[ end trace 92fdd645f35b51bd ]---
<7>[  558.622121] [drm:drm_dp_read_desc] DP sink: OUI 38-ec-11 dev-ID  HW-rev 0.0 SW-rev 0.0 quirks 0x0000
<7>[  558.646119] [drm:drm_add_display_info] non_desktop set to 0
<7>[  558.646153] [drm:drm_add_edid_modes] ELD: no CEA Extension found
<7>[  558.646160] [drm:drm_add_display_info] non_desktop set to 0
<7>[  558.646222] [drm:drm_helper_probe_single_connector_modes] [CONNECTOR:101:eDP-1] probed modes :
<7>[  558.646232] [drm:drm_mode_debug_printmodeline] Modeline 102:"3840x2160" 60 533250 3840 3888 3920 4000 2160 2163 2168 2222 0x48 0xa
<7>[  558.646266] [drm:drm_mode_debug_printmodeline] Modeline 103:"3840x2160" 48 426600 3840 3888 3920 4000 2160 2163 2168 2222 0x40 0xa
<4>[  558.646279] general protection fault: 0000 [#1] PREEMPT SMP PTI
<0>[  558.646286] Dumping ftrace buffer:
<0>[  558.646291] ---------------------------------
<0>[  558.646336] CPU:1 [LOST 2227725 EVENTS]
                  prime_vg-3966    1.... 504848333us : reset_all_global_seqno.part.6: rcs0 seqno 0 -> 0
<0>[  558.646411] prime_vg-3966    1.... 504848533us : reset_all_global_seqno.part.6: bcs0 seqno 0 -> 0
...
<0>[  558.659768] gvt_basi-4636    3.... 536295821us : intel_gpu_reset: engine_mask=ffffffff
<0>[  558.659776] ---------------------------------
<4>[  558.659781] Modules linked in: 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 snd_hda_codec ghash_clmulni_intel snd_hwdep snd_hda_core snd_pcm e1000e mei_me mei prime_numbers [last unloaded: snd_hda_intel]
<4>[  558.659819] CPU: 3 PID: 2885 Comm: kworker/3:4 Tainted: G     U  W        4.16.0-rc7-CI-CI_DRM_3992+ #1
<4>[  558.659827] Hardware name: Intel Corporation CannonLake Client Platform/CannonLake Y LPDDR4 RVP, BIOS CNLSFWR1.R00.X122.B01.1801151045 01/15/2018
<4>[  558.659839] Workqueue: events output_poll_execute
<4>[  558.659847] RIP: 0010:drm_setup_crtcs+0x13f/0xc50
<4>[  558.659852] RSP: 0018:ffffc90000847d48 EFLAGS: 00010202
<4>[  558.659858] RAX: 6b6b6b6b6b6b6b6b RBX: ffff880258ae6a48 RCX: 0000000000000001
<4>[  558.659864] RDX: 0000000000000870 RSI: 0000000000000f00 RDI: 00000000ffffffff
<4>[  558.659871] RBP: 0000000000000002 R08: 0000000000000000 R09: 0000000000000000
<4>[  558.659877] R10: 0000000000000f00 R11: ffffffff810ec1eb R12: 0000000000000002
<4>[  558.659884] R13: 0000000000000f00 R14: 0000000000000870 R15: ffff88024d7b6458
<4>[  558.659891] FS:  0000000000000000(0000) GS:ffff88026f980000(0000) knlGS:0000000000000000
<4>[  558.659899] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
<4>[  558.659904] CR2: 00007f8e46b3a050 CR3: 0000000005210003 CR4: 0000000000760ee0
<4>[  558.659911] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
<4>[  558.659918] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
<4>[  558.659924] PKRU: 55555554
<4>[  558.659927] Call Trace:
<4>[  558.659934]  ? drm_fb_helper_is_bound.isra.8+0x6e/0xa0
<4>[  558.659941]  drm_fb_helper_hotplug_event.part.20+0x71/0xa0
<4>[  558.659948]  output_poll_execute+0x8d/0x180
<4>[  558.659955]  process_one_work+0x215/0x620
<4>[  558.659962]  worker_thread+0x48/0x3a0
<4>[  558.659968]  ? _raw_spin_unlock_irqrestore+0x4c/0x60
<4>[  558.659974]  kthread+0xfb/0x130
<4>[  558.659979]  ? process_one_work+0x620/0x620
<4>[  558.659984]  ? _kthread_create_on_node+0x30/0x30
<4>[  558.659990]  ret_from_fork+0x3a/0x50
<4>[  558.659996] Code: 45 31 e4 45 85 c0 0f 8e da 07 00 00 44 8b 6c 24 30 44 8b 74 24 40 48 8b 43 30 49 63 d4 44 89 ee 41 83 c4 01 48 8b 04 d0 44 89 f2 <48> 8b 38 48 8b 87 a8 01 00 00 ff 50 20 01 c5 44 3b 63 20 7c d6 
<1>[  558.660052] RIP: drm_setup_crtcs+0x13f/0xc50 RSP: ffffc90000847d48
<4>[  558.660070] ---[ end trace 92fdd645f35b51be ]---
<7>[  559.620240] [drm:intel_edp_drrs_downclock_work [i915]] eDP Refresh Rate set to : 48Hz
<7>[  561.668336] [drm:edp_panel_vdd_off_sync [i915]] Turning eDP port A VDD off
<7>[  561.668535] [drm:edp_panel_vdd_off_sync [i915]] PP_STATUS: 0x80000008 PP_CONTROL: 0x00000067
<3>[  568.836232] [drm:drm_atomic_helper_wait_for_dependencies] *ERROR* [CRTC:51:pipe A] flip_done timed out
<3>[  579.076225] [drm:drm_atomic_helper_wait_for_dependencies] *ERROR* [CRTC:75:pipe B] flip_done timed out
<3>[  589.316212] [drm:drm_atomic_helper_wait_for_dependencies] *ERROR* [PLANE:28:plane 1A] flip_done timed out
<3>[  599.556230] [drm:drm_atomic_helper_wait_for_dependencies] *ERROR* [PLANE:52:plane 1B] flip_done timed out
<3>[  609.796194] [drm:drm_atomic_helper_wait_for_flip_done] *ERROR* [CRTC:51:pipe A] flip_done timed out
<3>[  620.036211] [drm:drm_atomic_helper_wait_for_flip_done] *ERROR* [CRTC:75:pipe B] flip_done timed out
<7>[  620.036325] [drm:intel_edp_drrs_flush [i915]] eDP Refresh Rate set to : 60Hz
<4>[  620.036447] ------------[ cut here ]------------
<4>[  620.036459] WARN_ON(fbc->crtc->active)
<4>[  620.036556] WARNING: CPU: 0 PID: 4636 at drivers/gpu/drm/i915/intel_fbc.c:1247 intel_fbc_global_disable+0x6b/0x70 [i915]
<4>[  620.036587] Modules linked in: 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 snd_hda_codec ghash_clmulni_intel snd_hwdep snd_hda_core snd_pcm e1000e mei_me mei prime_numbers [last unloaded: snd_hda_intel]
<4>[  620.036673] CPU: 0 PID: 4636 Comm: gvt_basic Tainted: G     UD W        4.16.0-rc7-CI-CI_DRM_3992+ #1
<4>[  620.036692] Hardware name: Intel Corporation CannonLake Client Platform/CannonLake Y LPDDR4 RVP, BIOS CNLSFWR1.R00.X122.B01.1801151045 01/15/2018
<4>[  620.036786] RIP: 0010:intel_fbc_global_disable+0x6b/0x70 [i915]
<4>[  620.036800] RSP: 0018:ffffc90000ac7dd8 EFLAGS: 00010292
<4>[  620.036814] RAX: 000000000000001a RBX: ffff88025a670000 RCX: 0000000000000001
<4>[  620.036830] RDX: 0000000080000001 RSI: 0000000000000001 RDI: 00000000ffffffff
<4>[  620.036845] RBP: ffff88025a6779c0 R08: 0000000000000000 R09: 00

Comment 1 Marta Löfstedt 2018-03-28 07:07:50 UTC

Same thing again:

https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_3993/fi-cnl-y3/igt@drv_module_reload@basic-reload-inject.html

Comment 2 Marta Löfstedt 2018-03-28 07:10:02 UTC

Here is another one:
https://intel-gfx-ci.01.org/tree/drm-tip/IGT_4384/fi-cnl-y3/igt@gvt_basic@invalid-placeholder-test.html

Comment 3 Marta Löfstedt 2018-03-29 07:54:49 UTC

A new one:
https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_4002/fi-cnl-y3/igt@gvt_basic@invalid-placeholder-test.html

Comment 4 Francesco Balestrieri 2018-06-01 07:38:40 UTC

Not seen on new cibuglog, closing.

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.