[Bug 106353] [CI] igt at gem_ctx_create@basic-files - dmesg-warn - ODEBUG: free active (active state 0) object type: hrtimer hint: hrtimer_wakeup
bugzilla-daemon at freedesktop.org
bugzilla-daemon at freedesktop.org
Mon May 28 17:08:43 UTC 2018
https://bugs.freedesktop.org/show_bug.cgi?id=106353
Martin Peres <martin.peres at free.fr> changed:
What |Removed |Added
----------------------------------------------------------------------------
i915 platform| |BXT
--- Comment #4 from Martin Peres <martin.peres at free.fr> ---
Also found in a run 6 days ago on BXT:
https://intel-gfx-ci.01.org/tree/drm-tip/drmtip_46/fi-bxt-j4205/igt@gem_exec_schedule@preempt-queue-contexts-bsd.html
[ 309.079881] ------------[ cut here ]------------
[ 309.080535] ODEBUG: free active (active state 0) object type: hrtimer hint:
hrtimer_wakeup+0x0/0x20
[ 309.080569] WARNING: CPU: 3 PID: 1546 at lib/debugobjects.c:339
debug_print_object+0xd7/0x100
[ 309.080584] Modules linked in: vgem snd_hda_codec_hdmi snd_hda_codec_realtek
snd_hda_codec_generic i915 snd_hda_intel x86_pkg_temp_thermal intel_powerclamp
snd_hda_codec coretemp crct10dif_pclmul snd_hwdep crc32_pclmul
ghash_clmulni_intel snd_hda_core snd_pcm r8169 mii lpc_ich mei_me mei
prime_numbers pinctrl_broxton pinctrl_intel
[ 309.080670] CPU: 3 PID: 1546 Comm: kworker/u8:33 Tainted: G U
4.17.0-rc5-gda383fb02111-drmtip_46+ #1
[ 309.080688] Hardware name: To Be Filled By O.E.M. To Be Filled By
O.E.M./J4205-ITX, BIOS P1.10 09/29/2016
[ 309.080756] Workqueue: i915 __i915_gem_free_work [i915]
[ 309.080769] RIP: 0010:debug_print_object+0xd7/0x100
[ 309.080779] RSP: 0018:ffffb7a8c0957be8 EFLAGS: 00010086
[ 309.080792] RAX: 0000000000000000 RBX: ffff99fc98427e08 RCX:
0000000000000002
[ 309.080806] RDX: 0000000080000002 RSI: ffffffffbc086656 RDI:
00000000ffffffff
[ 309.080820] RBP: ffffffffbc248c00 R08: 0000000000000000 R09:
0000000000000000
[ 309.080833] R10: ffffb7a8c0957ca8 R11: ffffffffbc244598 R12:
ffffffffbc0a1d9f
[ 309.080847] R13: ffffffffbb118c50 R14: ffffb7a8c3e84000 R15:
dead000000000100
[ 309.080861] FS: 0000000000000000(0000) GS:ffff99fcbfd80000(0000)
knlGS:0000000000000000
[ 309.080876] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 309.080888] CR2: 00007fdc3650c9a8 CR3: 000000026443e000 CR4:
00000000003406e0
[ 309.080901] Call Trace:
[ 309.080911] ? mark_held_locks+0x50/0x80
[ 309.080922] ? _raw_spin_unlock_irqrestore+0x4c/0x60
[ 309.080934] ? trace_hardirqs_on_caller+0xe0/0x1b0
[ 309.080947] ? lock_acquire+0xa6/0x210
[ 309.080957] ? debug_check_no_obj_freed+0xa4/0x210
[ 309.080971] debug_check_no_obj_freed+0x194/0x210
[ 309.080986] __vunmap+0xab/0x100
[ 309.081045] __i915_gem_object_put_pages+0x1a7/0x200 [i915]
[ 309.081109] __i915_gem_free_objects+0x275/0x780 [i915]
[ 309.081173] __i915_gem_free_work+0x5d/0x90 [i915]
[ 309.081185] process_one_work+0x229/0x6a0
[ 309.081198] worker_thread+0x35/0x380
[ 309.081208] ? process_one_work+0x6a0/0x6a0
[ 309.081218] kthread+0x119/0x130
[ 309.081228] ? _kthread_create_on_node+0x60/0x60
[ 309.081240] ret_from_fork+0x3a/0x50
[ 309.081253] Code: 02 01 e8 2d 38 05 00 8b 43 10 4d 89 e9 4c 89 e6 8b 4b 14
4c 8b 45 00 48 c7 c7 10 8a 0a bc 48 8b 14 c5 e0 45 e4 bb e8 49 6e bf ff <0f> 0b
48 c7 c7 50 8a 0a bc e8 c4 c6 c6 ff be 02 00 00 00 48 89
[ 309.081365] irq event stamp: 711844
[ 309.081375] hardirqs last enabled at (711843): [<ffffffffbb9487fc>]
_raw_spin_unlock_irqrestore+0x4c/0x60
[ 309.081394] hardirqs last disabled at (711844): [<ffffffffbb94866d>]
_raw_spin_lock_irqsave+0xd/0x50
[ 309.081412] softirqs last enabled at (711076): [<ffffffffbbc0032b>]
__do_softirq+0x32b/0x4e1
[ 309.081430] softirqs last disabled at (711071): [<ffffffffbb08f714>]
irq_exit+0xa4/0xb0
[ 309.081446] WARNING: CPU: 3 PID: 1546 at lib/debugobjects.c:339
debug_print_object+0xd7/0x100
[ 309.081462] ---[ end trace 8c792c819b7f1a70 ]---
[ 309.081472] ODEBUG: ODEBUG: debug object originally initialized at:
[ 309.081486] futex_wait+0x77/0x240
[ 309.081495] do_futex+0x486/0xb10
[ 309.081504] __se_sys_futex+0x128/0x170
[ 309.081513] do_syscall_64+0x55/0x190
[ 309.081523] entry_SYSCALL_64_after_hwframe+0x49/0xbe
[ 309.081537] ======================================================
[ 309.081538] WARNING: possible circular locking dependency detected
[ 309.081539] 4.17.0-rc5-gda383fb02111-drmtip_46+ #1 Tainted: G U
[ 309.081540] ------------------------------------------------------
[ 309.081540] kworker/u8:33/1546 is trying to acquire lock:
[ 309.081541] 0000000094eae43d ((console_sem).lock){-...}, at:
down_trylock+0xa/0x30
[ 309.081544] but task is already holding lock:
[ 309.081545] 000000004b79b31d (&obj_hash[i].lock){-.-.}, at:
debug_check_no_obj_freed+0xa4/0x210
[ 309.081548] which lock already depends on the new lock.
[ 309.081550] the existing dependency chain (in reverse order) is:
[ 309.081551] -> #3 (&obj_hash[i].lock){-.-.}:
[ 309.081553] __debug_object_init+0x74/0x590
[ 309.081554] hrtimer_init+0x1b/0x160
[ 309.081555] init_dl_task_timer+0x17/0x30
[ 309.081556] __sched_fork.isra.16+0xa4/0xf0
[ 309.081556] init_idle+0x53/0x270
[ 309.081557] sched_init+0x42c/0x49f
[ 309.081558] start_kernel+0x269/0x4c2
[ 309.081559] secondary_startup_64+0xa5/0xb0
[ 309.081560] -> #2 (&rq->lock){-.-.}:
[ 309.081562] task_fork_fair+0x36/0x160
[ 309.081563] sched_fork+0x123/0x280
[ 309.081564] copy_process.part.7+0x5f4/0x1d30
[ 309.081564] _do_fork+0xe2/0x700
[ 309.081565] kernel_thread+0x20/0x30
[ 309.081566] rest_init+0x1d/0x220
[ 309.081566] start_kernel+0x4a2/0x4c2
[ 309.081567] secondary_startup_64+0xa5/0xb0
[ 309.081568] -> #1 (&p->pi_lock){-.-.}:
[ 309.081571] try_to_wake_up+0x37/0x650
[ 309.081571] up+0x3b/0x50
[ 309.081572] __up_console_sem+0x2e/0x50
[ 309.081573] console_unlock+0x32f/0x640
[ 309.081573] con_install+0xc0/0xd0
[ 309.081574] tty_init_dev+0x6c/0x1e0
[ 309.081575] tty_open+0x2e6/0x3e0
[ 309.081576] chrdev_open+0xa2/0x1c0
[ 309.081576] do_dentry_open.isra.1+0x19c/0x2f0
[ 309.081577] path_openat+0x4fe/0xb00
[ 309.081578] do_filp_open+0x96/0x110
[ 309.081579] do_sys_open+0x1b8/0x240
[ 309.081579] do_syscall_64+0x55/0x190
[ 309.081580] entry_SYSCALL_64_after_hwframe+0x49/0xbe
[ 309.081581] -> #0 ((console_sem).lock){-...}:
[ 309.081584] _raw_spin_lock_irqsave+0x33/0x50
[ 309.081584] down_trylock+0xa/0x30
[ 309.081585] __down_trylock_console_sem+0x20/0x80
[ 309.081586] console_trylock+0xe/0x60
[ 309.081587] vprintk_emit+0x22e/0x4d0
[ 309.081587] printk+0x4d/0x69
[ 309.081588] __warn_printk+0x46/0x90
[ 309.081589] debug_print_object+0xd7/0x100
[ 309.081590] debug_check_no_obj_freed+0x194/0x210
[ 309.081590] __vunmap+0xab/0x100
[ 309.081591] __i915_gem_object_put_pages+0x1a7/0x200 [i915]
[ 309.081592] __i915_gem_free_objects+0x275/0x780 [i915]
[ 309.081593] __i915_gem_free_work+0x5d/0x90 [i915]
[ 309.081594] process_one_work+0x229/0x6a0
[ 309.081595] worker_thread+0x35/0x380
[ 309.081595] kthread+0x119/0x130
[ 309.081596] ret_from_fork+0x3a/0x50
[ 309.081597] other info that might help us debug this:
[ 309.081598] Chain exists of:
[ 309.081599] (console_sem).lock --> &rq->lock --> &obj_hash[i].lock
[ 309.081603] Possible unsafe locking scenario:
[ 309.081604] CPU0 CPU1
[ 309.081605] ---- ----
[ 309.081605] lock(&obj_hash[i].lock);
[ 309.081607] lock(&rq->lock);
[ 309.081609] lock(&obj_hash[i].lock);
[ 309.081610] lock((console_sem).lock);
[ 309.081612] *** DEADLOCK ***
[ 309.081613] 4 locks held by kworker/u8:33/1546:
[ 309.081614] #0: 00000000440e11f9 ((wq_completion)"i915"){+.+.}, at:
process_one_work+0x1a3/0x6a0
[ 309.081617] #1: 00000000e349877b
((work_completion)(&i915->mm.free_work)){+.+.}, at:
process_one_work+0x1a3/0x6a0
[ 309.081620] #2: 00000000de0e7429 (&obj->mm.lock){+.+.}, at:
__i915_gem_object_put_pages+0x4f/0x200 [i915]
[ 309.081623] #3: 000000004b79b31d (&obj_hash[i].lock){-.-.}, at:
debug_check_no_obj_freed+0xa4/0x210
[ 309.081627] stack backtrace:
[ 309.081628] CPU: 3 PID: 1546 Comm: kworker/u8:33 Tainted: G U
4.17.0-rc5-gda383fb02111-drmtip_46+ #1
[ 309.081629] Hardware name: To Be Filled By O.E.M. To Be Filled By
O.E.M./J4205-ITX, BIOS P1.10 09/29/2016
[ 309.081630] Workqueue: i915 __i915_gem_free_work [i915]
[ 309.081631] Call Trace:
[ 309.081631] dump_stack+0x67/0x9b
[ 309.081632] print_circular_bug.isra.18+0x1c8/0x2b0
[ 309.081633] __lock_acquire+0x1897/0x1b50
[ 309.081633] ? lock_acquire+0xa6/0x210
[ 309.081634] lock_acquire+0xa6/0x210
[ 309.081634] ? down_trylock+0xa/0x30
[ 309.081635] ? sched_clock_cpu+0x10/0xe0
[ 309.081635] ? vprintk_emit+0x22e/0x4d0
[ 309.081636] _raw_spin_lock_irqsave+0x33/0x50
[ 309.081636] ? down_trylock+0xa/0x30
[ 309.081637] down_trylock+0xa/0x30
[ 309.081638] __down_trylock_console_sem+0x20/0x80
[ 309.081638] console_trylock+0xe/0x60
[ 309.081639] vprintk_emit+0x22e/0x4d0
[ 309.081639] ? clock_was_set_work+0x20/0x20
[ 309.081640] printk+0x4d/0x69
[ 309.081640] ? clock_was_set_work+0x20/0x20
[ 309.081641] __warn_printk+0x46/0x90
[ 309.081641] ? clock_was_set_work+0x20/0x20
[ 309.081642] debug_print_object+0xd7/0x100
[ 309.081643] ? mark_held_locks+0x50/0x80
[ 309.081643] ? _raw_spin_unlock_irqrestore+0x4c/0x60
[ 309.081644] ? trace_hardirqs_on_caller+0xe0/0x1b0
[ 309.081644] ? lock_acquire+0xa6/0x210
[ 309.081645] ? debug_check_no_obj_freed+0xa4/0x210
[ 309.081646] debug_check_no_obj_freed+0x194/0x210
[ 309.081646] __vunmap+0xab/0x100
[ 309.081647] __i915_gem_object_put_pages+0x1a7/0x200 [i915]
[ 309.081647] __i915_gem_free_objects+0x275/0x780 [i915]
[ 309.081648] __i915_gem_free_work+0x5d/0x90 [i915]
[ 309.081648] process_one_work+0x229/0x6a0
[ 309.081649] worker_thread+0x35/0x380
[ 309.081650] ? process_one_work+0x6a0/0x6a0
[ 309.081650] kthread+0x119/0x130
[ 309.081651] ? _kthread_create_on_node+0x60/0x60
[ 309.081651] ret_from_fork+0x3a/0x50
[ 309.082769] ------------[ cut here ]------------
[ 309.082791] ODEBUG: free active (active state 0) object type: hrtimer hint:
hrtimer_wakeup+0x0/0x20
[ 309.082829] WARNING: CPU: 3 PID: 1546 at lib/debugobjects.c:339
debug_print_object+0xd7/0x100
[ 309.082849] Modules linked in: vgem snd_hda_codec_hdmi snd_hda_codec_realtek
snd_hda_codec_generic i915 snd_hda_intel x86_pkg_temp_thermal intel_powerclamp
snd_hda_codec coretemp crct10dif_pclmul snd_hwdep crc32_pclmul
ghash_clmulni_intel snd_hda_core snd_pcm r8169 mii lpc_ich mei_me mei
prime_numbers pinctrl_broxton pinctrl_intel
[ 309.082993] CPU: 3 PID: 1546 Comm: kworker/u8:33 Tainted: G U W
4.17.0-rc5-gda383fb02111-drmtip_46+ #1
[ 309.083012] Hardware name: To Be Filled By O.E.M. To Be Filled By
O.E.M./J4205-ITX, BIOS P1.10 09/29/2016
[ 309.083104] Workqueue: i915 __i915_gem_free_work [i915]
[ 309.083117] RIP: 0010:debug_print_object+0xd7/0x100
[ 309.083132] RSP: 0018:ffffb7a8c0957be8 EFLAGS: 00010086
[ 309.083150] RAX: 0000000000000000 RBX: ffff99fcb18c1988 RCX:
0000000000000002
[ 309.083169] RDX: 0000000080000002 RSI: ffffffffbc086656 RDI:
00000000ffffffff
[ 309.083186] RBP: ffffffffbc248c00 R08: 0000000000000000 R09:
0000000000000000
[ 309.083203] R10: ffffb7a8c0957ca8 R11: ffffffffbc244598 R12:
ffffffffbc0a1d9f
[ 309.083219] R13: ffffffffbb118c50 R14: ffffb7a8c3e84000 R15:
dead000000000100
[ 309.083233] FS: 0000000000000000(0000) GS:ffff99fcbfd80000(0000)
knlGS:0000000000000000
[ 309.083248] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 309.083260] CR2: 00007fdc3650c9a8 CR3: 000000026443e000 CR4:
00000000003406e0
[ 309.083274] Call Trace:
[ 309.083287] ? lock_acquire+0xa6/0x210
[ 309.083302] ? lock_acquire+0xa6/0x210
[ 309.083312] ? debug_check_no_obj_freed+0xa4/0x210
[ 309.083324] ? __free_object+0xa9/0xb0
[ 309.083336] debug_check_no_obj_freed+0x194/0x210
[ 309.083352] __vunmap+0xab/0x100
[ 309.083413] __i915_gem_object_put_pages+0x1a7/0x200 [i915]
[ 309.083479] __i915_gem_free_objects+0x275/0x780 [i915]
[ 309.083542] __i915_gem_free_work+0x5d/0x90 [i915]
[ 309.083556] process_one_work+0x229/0x6a0
[ 309.083571] worker_thread+0x35/0x380
[ 309.083582] ? process_one_work+0x6a0/0x6a0
[ 309.083592] kthread+0x119/0x130
[ 309.083601] ? _kthread_create_on_node+0x60/0x60
[ 309.083615] ret_from_fork+0x3a/0x50
[ 309.083628] Code: 02 01 e8 2d 38 05 00 8b 43 10 4d 89 e9 4c 89 e6 8b 4b 14
4c 8b 45 00 48 c7 c7 10 8a 0a bc 48 8b 14 c5 e0 45 e4 bb e8 49 6e bf ff <0f> 0b
48 c7 c7 50 8a 0a bc e8 c4 c6 c6 ff be 02 00 00 00 48 89
[ 309.083743] irq event stamp: 711844
[ 309.083753] hardirqs last enabled at (711843): [<ffffffffbb9487fc>]
_raw_spin_unlock_irqrestore+0x4c/0x60
[ 309.083772] hardirqs last disabled at (711844): [<ffffffffbb94866d>]
_raw_spin_lock_irqsave+0xd/0x50
[ 309.083791] softirqs last enabled at (711076): [<ffffffffbbc0032b>]
__do_softirq+0x32b/0x4e1
[ 309.083809] softirqs last disabled at (711071): [<ffffffffbb08f714>]
irq_exit+0xa4/0xb0
[ 309.083825] WARNING: CPU: 3 PID: 1546 at lib/debugobjects.c:339
debug_print_object+0xd7/0x100
[ 309.083841] ---[ end trace 8c792c819b7f1a71 ]---
[ 309.083852] ODEBUG: ODEBUG: debug object originally initialized at:
[ 309.083867] futex_wait+0x77/0x240
[ 309.083877] do_futex+0x486/0xb10
[ 309.083886] __se_sys_futex+0x128/0x170
[ 309.083896] do_syscall_64+0x55/0x190
[ 309.083906] entry_SYSCALL_64_after_hwframe+0x49/0xbe
[ 309.083953] ------------[ cut here ]------------
[ 309.083975] ODEBUG: free active (active state 0) object type: hrtimer hint:
hrtimer_wakeup+0x0/0x20
[ 309.084010] WARNING: CPU: 3 PID: 1546 at lib/debugobjects.c:339
debug_print_object+0xd7/0x100
[ 309.084029] Modules linked in: vgem snd_hda_codec_hdmi snd_hda_codec_realtek
snd_hda_codec_generic i915 snd_hda_intel x86_pkg_temp_thermal intel_powerclamp
snd_hda_codec coretemp crct10dif_pclmul snd_hwdep crc32_pclmul
ghash_clmulni_intel snd_hda_core snd_pcm r8169 mii lpc_ich mei_me mei
prime_numbers pinctrl_broxton pinctrl_intel
[ 309.084117] CPU: 3 PID: 1546 Comm: kworker/u8:33 Tainted: G U W
4.17.0-rc5-gda383fb02111-drmtip_46+ #1
[ 309.084135] Hardware name: To Be Filled By O.E.M. To Be Filled By
O.E.M./J4205-ITX, BIOS P1.10 09/29/2016
[ 309.084222] Workqueue: i915 __i915_gem_free_work [i915]
[ 309.084238] RIP: 0010:debug_print_object+0xd7/0x100
[ 309.084248] RSP: 0018:ffffb7a8c0957be8 EFLAGS: 00010086
[ 309.084260] RAX: 0000000000000000 RBX: ffff99fca94d6608 RCX:
0000000000000002
[ 309.084273] RDX: 0000000080000002 RSI: ffffffffbc086656 RDI:
00000000ffffffff
[ 309.084287] RBP: ffffffffbc248c00 R08: 0000000000000000 R09:
0000000000000000
[ 309.084301] R10: ffffb7a8c0957ca8 R11: ffffffffbc244598 R12:
ffffffffbc0a1d9f
[ 309.084314] R13: ffffffffbb118c50 R14: ffffb7a8c3e84000 R15:
dead000000000100
[ 309.084329] FS: 0000000000000000(0000) GS:ffff99fcbfd80000(0000)
knlGS:0000000000000000
[ 309.084344] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 309.084355] CR2: 00007fdc3650c9a8 CR3: 000000026443e000 CR4:
00000000003406e0
[ 309.084369] Call Trace:
[ 309.084381] ? lock_acquire+0xa6/0x210
[ 309.084393] ? lock_acquire+0xa6/0x210
[ 309.084404] ? debug_check_no_obj_freed+0xa4/0x210
[ 309.084415] ? __free_object+0xa9/0xb0
[ 309.084427] debug_check_no_obj_freed+0x194/0x210
[ 309.084443] __vunmap+0xab/0x100
[ 309.084514] __i915_gem_object_put_pages+0x1a7/0x200 [i915]
[ 309.084582] __i915_gem_free_objects+0x275/0x780 [i915]
[ 309.084647] __i915_gem_free_work+0x5d/0x90 [i915]
[ 309.084662] process_one_work+0x229/0x6a0
[ 309.084677] worker_thread+0x35/0x380
[ 309.084688] ? process_one_work+0x6a0/0x6a0
[ 309.084698] kthread+0x119/0x130
[ 309.084709] ? _kthread_create_on_node+0x60/0x60
[ 309.084722] ret_from_fork+0x3a/0x50
[ 309.084736] Code: 02 01 e8 2d 38 05 00 8b 43 10 4d 89 e9 4c 89 e6 8b 4b 14
4c 8b 45 00 48 c7 c7 10 8a 0a bc 48 8b 14 c5 e0 45 e4 bb e8 49 6e bf ff <0f> 0b
48 c7 c7 50 8a 0a bc e8 c4 c6 c6 ff be 02 00 00 00 48 89
[ 309.084855] irq event stamp: 711844
[ 309.084867] hardirqs last enabled at (711843): [<ffffffffbb9487fc>]
_raw_spin_unlock_irqrestore+0x4c/0x60
[ 309.084886] hardirqs last disabled at (711844): [<ffffffffbb94866d>]
_raw_spin_lock_irqsave+0xd/0x50
[ 309.084905] softirqs last enabled at (711076): [<ffffffffbbc0032b>]
__do_softirq+0x32b/0x4e1
[ 309.084923] softirqs last disabled at (711071): [<ffffffffbb08f714>]
irq_exit+0xa4/0xb0
[ 309.084942] WARNING: CPU: 3 PID: 1546 at lib/debugobjects.c:339
debug_print_object+0xd7/0x100
[ 309.084958] ---[ end trace 8c792c819b7f1a72 ]---
--
You are receiving this mail because:
You are the QA Contact for the bug.
You are on the CC list for the bug.
You are the assignee for the bug.
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <https://lists.freedesktop.org/archives/intel-gfx-bugs/attachments/20180528/a9b3f824/attachment-0001.html>
More information about the intel-gfx-bugs
mailing list