[Bug 106353] [CI] igt at gem_* - dmesg-warn - ODEBUG: free active (active state 0) object type: hrtimer hint: hrtimer_wakeup
bugzilla-daemon at freedesktop.org
bugzilla-daemon at freedesktop.org
Tue Jun 5 12:13:09 UTC 2018
https://bugs.freedesktop.org/show_bug.cgi?id=106353
--- Comment #6 from Martin Peres <martin.peres at free.fr> ---
https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_4269_38/fi-cnl-y3/igt@gem_ctx_create@basic-files.html
[ 58.312435] Setting dangerous option reset - tainting kernel
[ 64.416419] ------------[ cut here ]------------
[ 64.416797] ODEBUG: free active (active state 0) object type: hrtimer hint:
hrtimer_wakeup+0x0/0x20
[ 64.416814] WARNING: CPU: 0 PID: 129 at lib/debugobjects.c:339
debug_print_object+0xd7/0x100
[ 64.416822] Modules linked in: snd_hda_codec_hdmi snd_hda_codec_realtek
snd_hda_codec_generic i915 snd_hda_intel snd_hda_codec x86_pkg_temp_thermal
intel_powerclamp coretemp snd_hwdep crct10dif_pclmul snd_hda_core crc32_pclmul
ghash_clmulni_intel e1000e snd_pcm mei_me mei prime_numbers
[ 64.416859] CPU: 0 PID: 129 Comm: kworker/u8:2 Tainted: G U
4.17.0-rc7-g25dda01a94cb-repeat_1+ #1
[ 64.416868] Hardware name: Intel Corporation CannonLake Client
Platform/CannonLake Y LPDDR4 RVP, BIOS CNLSFWR1.R00.X122.B01.1801151045
01/15/2018
[ 64.416901] Workqueue: i915 __i915_gem_free_work [i915]
[ 64.416908] RIP: 0010:debug_print_object+0xd7/0x100
[ 64.416914] RSP: 0018:ffffb8ce404c3be8 EFLAGS: 00010086
[ 64.416920] RAX: 0000000000000000 RBX: ffffa3c412834a88 RCX:
0000000000000002
[ 64.416927] RDX: 0000000080000002 RSI: ffffffff84086cfe RDI:
00000000ffffffff
[ 64.416934] RBP: ffffffff84248c00 R08: 0000000000000000 R09:
0000000000000000
[ 64.416941] R10: ffffb8ce404c3ca8 R11: ffffffff84244598 R12:
ffffffff840a24bf
[ 64.416948] R13: ffffffff831196c0 R14: ffffb8ce6f8cb000 R15:
dead000000000100
[ 64.416955] FS: 0000000000000000(0000) GS:ffffa3c4ef800000(0000)
knlGS:0000000000000000
[ 64.416964] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 64.416970] CR2: 00007f3581620f80 CR3: 0000000212210006 CR4:
0000000000760ef0
[ 64.416977] DR0: 0000000000000000 DR1: 0000000000000000 DR2:
0000000000000000
[ 64.416984] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7:
0000000000000400
[ 64.416991] PKRU: 55555554
[ 64.416995] Call Trace:
[ 64.417000] ? mark_held_locks+0x50/0x80
[ 64.417007] ? _raw_spin_unlock_irqrestore+0x4c/0x60
[ 64.417013] ? trace_hardirqs_on_caller+0xe0/0x1b0
[ 64.417020] ? lock_acquire+0xa6/0x210
[ 64.417026] ? debug_check_no_obj_freed+0xa4/0x210
[ 64.417034] debug_check_no_obj_freed+0x194/0x210
[ 64.417042] __vunmap+0xab/0x100
[ 64.417068] __i915_gem_object_put_pages+0x1a7/0x200 [i915]
[ 64.417096] __i915_gem_free_objects+0x275/0x780 [i915]
[ 64.417125] __i915_gem_free_work+0x5d/0x90 [i915]
[ 64.417132] process_one_work+0x229/0x6a0
[ 64.417139] worker_thread+0x35/0x380
[ 64.417144] ? process_one_work+0x6a0/0x6a0
[ 64.417150] kthread+0x119/0x130
[ 64.417155] ? kthread_flush_work_fn+0x10/0x10
[ 64.417162] ret_from_fork+0x3a/0x50
[ 64.417169] 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 30 91 0a 84 48 8b 14 c5 20 48 e4 83 e8 49 67 bf ff <0f> 0b
48 c7 c7 70 91 0a 84 e8 44 c0 c6 ff be 02 00 00 00 48 89
[ 64.417230] irq event stamp: 786892
[ 64.417236] hardirqs last enabled at (786891): [<ffffffff8394993c>]
_raw_spin_unlock_irqrestore+0x4c/0x60
[ 64.417246] hardirqs last disabled at (786892): [<ffffffff839497ad>]
_raw_spin_lock_irqsave+0xd/0x50
[ 64.417256] softirqs last enabled at (786418): [<ffffffff83c0032b>]
__do_softirq+0x32b/0x4e1
[ 64.417265] softirqs last disabled at (786411): [<ffffffff83090104>]
irq_exit+0xa4/0xb0
[ 64.417274] WARNING: CPU: 0 PID: 129 at lib/debugobjects.c:339
debug_print_object+0xd7/0x100
[ 64.417283] ---[ end trace b30c46be45d46de9 ]---
[ 64.417288] ODEBUG: ODEBUG: debug object originally initialized at:
[ 64.417295] futex_wait+0x77/0x240
[ 64.417300] do_futex+0x486/0xb10
[ 64.417305] __se_sys_futex+0x128/0x170
[ 64.417311] do_syscall_64+0x55/0x190
[ 64.417316] entry_SYSCALL_64_after_hwframe+0x49/0xbe
[ 64.417323] ======================================================
[ 64.417324] WARNING: possible circular locking dependency detected
[ 64.417324] 4.17.0-rc7-g25dda01a94cb-repeat_1+ #1 Tainted: G U
[ 64.417325] ------------------------------------------------------
[ 64.417325] kworker/u8:2/129 is trying to acquire lock:
[ 64.417325] 0000000013453dd1 ((console_sem).lock){-...}, at:
down_trylock+0xa/0x30
[ 64.417327] but task is already holding lock:
[ 64.417327] 0000000026752c6c (&obj_hash[i].lock){-.-.}, at:
debug_check_no_obj_freed+0xa4/0x210
[ 64.417329] which lock already depends on the new lock.
[ 64.417330] the existing dependency chain (in reverse order) is:
[ 64.417331] -> #3 (&obj_hash[i].lock){-.-.}:
[ 64.417332] __debug_object_init+0x74/0x590
[ 64.417333] hrtimer_init+0x1b/0x160
[ 64.417333] init_dl_task_timer+0x17/0x30
[ 64.417333] __sched_fork.isra.16+0xa4/0xf0
[ 64.417334] init_idle+0x53/0x270
[ 64.417334] sched_init+0x42c/0x49f
[ 64.417334] start_kernel+0x269/0x4c2
[ 64.417335] secondary_startup_64+0xa5/0xb0
[ 64.417335] -> #2 (&rq->lock){-.-.}:
[ 64.417337] task_fork_fair+0x36/0x160
[ 64.417337] sched_fork+0x123/0x280
[ 64.417337] copy_process.part.7+0x5f4/0x1d30
[ 64.417338] _do_fork+0xe2/0x700
[ 64.417338] kernel_thread+0x20/0x30
[ 64.417338] rest_init+0x1d/0x220
[ 64.417339] start_kernel+0x4a2/0x4c2
[ 64.417339] secondary_startup_64+0xa5/0xb0
[ 64.417339] -> #1 (&p->pi_lock){-.-.}:
[ 64.417341] try_to_wake_up+0x37/0x650
[ 64.417341] up+0x3b/0x50
[ 64.417341] __up_console_sem+0x2e/0x50
[ 64.417342] console_unlock+0x32f/0x640
[ 64.417342] register_framebuffer+0x24c/0x350
[ 64.417343] __drm_fb_helper_initial_config_and_unlock+0x22a/0x4a0
[ 64.417343] intel_fbdev_initial_config+0xf/0x20 [i915]
[ 64.417343] async_run_entry_fn+0x34/0x160
[ 64.417344] process_one_work+0x229/0x6a0
[ 64.417344] worker_thread+0x35/0x380
[ 64.417344] kthread+0x119/0x130
[ 64.417345] ret_from_fork+0x3a/0x50
[ 64.417345] -> #0 ((console_sem).lock){-...}:
[ 64.417347] _raw_spin_lock_irqsave+0x33/0x50
[ 64.417347] down_trylock+0xa/0x30
[ 64.417347] __down_trylock_console_sem+0x20/0x80
[ 64.417348] console_trylock+0xe/0x60
[ 64.417348] vprintk_emit+0x22e/0x4d0
[ 64.417348] printk+0x4d/0x69
[ 64.417349] __warn_printk+0x46/0x90
[ 64.417349] debug_print_object+0xd7/0x100
[ 64.417349] debug_check_no_obj_freed+0x194/0x210
[ 64.417350] __vunmap+0xab/0x100
[ 64.417350] __i915_gem_object_put_pages+0x1a7/0x200 [i915]
[ 64.417350] __i915_gem_free_objects+0x275/0x780 [i915]
[ 64.417351] __i915_gem_free_work+0x5d/0x90 [i915]
[ 64.417351] process_one_work+0x229/0x6a0
[ 64.417351] worker_thread+0x35/0x380
[ 64.417352] kthread+0x119/0x130
[ 64.417352] ret_from_fork+0x3a/0x50
[ 64.417353] other info that might help us debug this:
[ 64.417353] Chain exists of:
[ 64.417354] (console_sem).lock --> &rq->lock --> &obj_hash[i].lock
[ 64.417356] Possible unsafe locking scenario:
[ 64.417356] CPU0 CPU1
[ 64.417357] ---- ----
[ 64.417357] lock(&obj_hash[i].lock);
[ 64.417358] lock(&rq->lock);
[ 64.417359] lock(&obj_hash[i].lock);
[ 64.417360] lock((console_sem).lock);
[ 64.417361] *** DEADLOCK ***
[ 64.417361] 4 locks held by kworker/u8:2/129:
[ 64.417362] #0: 000000005ff4f972 ((wq_completion)"i915"){+.+.}, at:
process_one_work+0x1a3/0x6a0
[ 64.417363] #1: 00000000ab4ebc16
((work_completion)(&i915->mm.free_work)){+.+.}, at:
process_one_work+0x1a3/0x6a0
[ 64.417365] #2: 00000000f093260c (&obj->mm.lock){+.+.}, at:
__i915_gem_object_put_pages+0x4f/0x200 [i915]
[ 64.417366] #3: 0000000026752c6c (&obj_hash[i].lock){-.-.}, at:
debug_check_no_obj_freed+0xa4/0x210
[ 64.417368] stack backtrace:
[ 64.417369] CPU: 0 PID: 129 Comm: kworker/u8:2 Tainted: G U
4.17.0-rc7-g25dda01a94cb-repeat_1+ #1
[ 64.417369] Hardware name: Intel Corporation CannonLake Client
Platform/CannonLake Y LPDDR4 RVP, BIOS CNLSFWR1.R00.X122.B01.1801151045
01/15/2018
[ 64.417370] Workqueue: i915 __i915_gem_free_work [i915]
[ 64.417370] Call Trace:
[ 64.417371] dump_stack+0x67/0x9b
[ 64.417371] print_circular_bug.isra.18+0x1c8/0x2b0
[ 64.417371] __lock_acquire+0x1897/0x1b50
[ 64.417372] ? lock_acquire+0xa6/0x210
[ 64.417372] lock_acquire+0xa6/0x210
[ 64.417372] ? down_trylock+0xa/0x30
[ 64.417373] ? sched_clock_cpu+0x10/0xe0
[ 64.417373] ? vprintk_emit+0x22e/0x4d0
[ 64.417373] _raw_spin_lock_irqsave+0x33/0x50
[ 64.417374] ? down_trylock+0xa/0x30
[ 64.417374] down_trylock+0xa/0x30
[ 64.417375] __down_trylock_console_sem+0x20/0x80
[ 64.417375] console_trylock+0xe/0x60
[ 64.417375] vprintk_emit+0x22e/0x4d0
[ 64.417375] ? clock_was_set_work+0x20/0x20
[ 64.417376] printk+0x4d/0x69
[ 64.417376] ? clock_was_set_work+0x20/0x20
[ 64.417376] __warn_printk+0x46/0x90
[ 64.417377] ? clock_was_set_work+0x20/0x20
[ 64.417377] debug_print_object+0xd7/0x100
[ 64.417377] ? mark_held_locks+0x50/0x80
[ 64.417378] ? _raw_spin_unlock_irqrestore+0x4c/0x60
[ 64.417378] ? trace_hardirqs_on_caller+0xe0/0x1b0
[ 64.417379] ? lock_acquire+0xa6/0x210
[ 64.417379] ? debug_check_no_obj_freed+0xa4/0x210
[ 64.417379] debug_check_no_obj_freed+0x194/0x210
[ 64.417380] __vunmap+0xab/0x100
[ 64.417380] __i915_gem_object_put_pages+0x1a7/0x200 [i915]
[ 64.417380] __i915_gem_free_objects+0x275/0x780 [i915]
[ 64.417381] __i915_gem_free_work+0x5d/0x90 [i915]
[ 64.417381] process_one_work+0x229/0x6a0
[ 64.417381] worker_thread+0x35/0x380
[ 64.417382] ? process_one_work+0x6a0/0x6a0
[ 64.417382] kthread+0x119/0x130
[ 64.417382] ? kthread_flush_work_fn+0x10/0x10
[ 64.417383] ret_from_fork+0x3a/0x50
--
You are receiving this mail because:
You are the QA Contact for the bug.
You are the assignee for the bug.
You are on the CC list for the bug.
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <https://lists.freedesktop.org/archives/intel-gfx-bugs/attachments/20180605/e4c83cea/attachment.html>
More information about the intel-gfx-bugs
mailing list