[Bug 106353] New: [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
Wed May 2 12:19:12 UTC 2018
https://bugs.freedesktop.org/show_bug.cgi?id=106353
Bug ID: 106353
Summary: [CI] igt at gem_ctx_create@basic-files - dmesg-warn -
ODEBUG: free active (active state 0) object type:
hrtimer hint: hrtimer_wakeup
Product: DRI
Version: XOrg git
Hardware: Other
OS: All
Status: NEW
Severity: normal
Priority: medium
Component: DRM/Intel
Assignee: intel-gfx-bugs at lists.freedesktop.org
Reporter: martin.peres at free.fr
QA Contact: intel-gfx-bugs at lists.freedesktop.org
CC: intel-gfx-bugs at lists.freedesktop.org
https://intel-gfx-ci.01.org/tree/drm-tip/CI_DRM_4120/fi-skl-guc/igt@gem_ctx_create@basic-files.html
[ 30.094826] Setting dangerous option reset - tainting kernel
[ 37.237787] ------------[ cut here ]------------
[ 37.238162] ODEBUG: free active (active state 0) object type: hrtimer hint:
hrtimer_wakeup+0x0/0x20
[ 37.238185] WARNING: CPU: 1 PID: 154 at lib/debugobjects.c:339
debug_print_object+0xd7/0x100
[ 37.238191] Modules linked in: snd_hda_codec_hdmi snd_hda_codec_realtek
snd_hda_codec_generic i915 x86_pkg_temp_thermal intel_powerclamp coretemp
crct10dif_pclmul crc32_pclmul snd_hda_intel ghash_clmulni_intel snd_hda_codec
snd_hwdep snd_hda_core e1000e snd_pcm mei_me mei prime_numbers
[ 37.238218] CPU: 1 PID: 154 Comm: kworker/u16:4 Tainted: G U
4.17.0-rc3-CI-CI_DRM_4120+ #1
[ 37.238224] Hardware name: System manufacturer System Product Name/Z170 PRO
GAMING, BIOS 3402 04/26/2017
[ 37.238247] Workqueue: i915 __i915_gem_free_work [i915]
[ 37.238252] RIP: 0010:debug_print_object+0xd7/0x100
[ 37.238256] RSP: 0018:ffffc90001373be8 EFLAGS: 00010086
[ 37.238261] RAX: 0000000000000000 RBX: ffff8801375b3388 RCX:
0000000000000002
[ 37.238266] RDX: 0000000080000002 RSI: ffffffff820c3025 RDI:
00000000ffffffff
[ 37.238271] RBP: ffffffff82248480 R08: 0000000000000000 R09:
0000000000000000
[ 37.238276] R10: ffffc90001373ca8 R11: ffffffff82243e18 R12:
ffffffff820bf29f
[ 37.238281] R13: ffffffff81114d00 R14: ffffc9003b1f9000 R15:
dead000000000100
[ 37.238286] FS: 0000000000000000(0000) GS:ffff880236c40000(0000)
knlGS:0000000000000000
[ 37.238293] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 37.238297] CR2: 00007f6ff34aa000 CR3: 0000000005210002 CR4:
00000000003606e0
[ 37.238303] DR0: 0000000000000000 DR1: 0000000000000000 DR2:
0000000000000000
[ 37.238308] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7:
0000000000000400
[ 37.238313] Call Trace:
[ 37.238317] ? mark_held_locks+0x50/0x80
[ 37.238321] ? _raw_spin_unlock_irqrestore+0x4c/0x60
[ 37.238326] ? trace_hardirqs_on_caller+0xe0/0x1b0
[ 37.238331] ? lock_acquire+0xa6/0x210
[ 37.238335] ? debug_check_no_obj_freed+0xa4/0x210
[ 37.238341] debug_check_no_obj_freed+0x194/0x210
[ 37.238346] __vunmap+0xab/0x100
[ 37.238367] __i915_gem_object_put_pages+0x1ec/0x200 [i915]
[ 37.238389] __i915_gem_free_objects+0x275/0x780 [i915]
[ 37.238410] __i915_gem_free_work+0x5d/0x90 [i915]
[ 37.238416] process_one_work+0x229/0x6a0
[ 37.238421] worker_thread+0x35/0x380
[ 37.238426] ? process_one_work+0x6a0/0x6a0
[ 37.238430] kthread+0x119/0x130
[ 37.238434] ? _kthread_create_on_node+0x60/0x60
[ 37.238438] ret_from_fork+0x3a/0x50
[ 37.238443] Code: 02 01 e8 3d 38 05 00 8b 43 10 4d 89 e9 4c 89 e6 8b 4b 14
4c 8b 45 00 48 c7 c7 88 16 0d 82 48 8b 14 c5 00 c9 e6 81 e8 19 0b bf ff <0f> 0b
48 c7 c7 c8 16 0d 82 e8 74 62 c6 ff be 02 00 00 00 48 89
[ 37.238493] irq event stamp: 6922722
[ 37.238499] hardirqs last enabled at (6922721): [<ffffffff8192f97c>]
_raw_spin_unlock_irqrestore+0x4c/0x60
[ 37.238509] hardirqs last disabled at (6922722): [<ffffffff8192f7ed>]
_raw_spin_lock_irqsave+0xd/0x50
[ 37.238519] softirqs last enabled at (6919998): [<ffffffff81c0032b>]
__do_softirq+0x32b/0x4e1
[ 37.238528] softirqs last disabled at (6919977): [<ffffffff8108b904>]
irq_exit+0xa4/0xb0
[ 37.238537] WARNING: CPU: 1 PID: 154 at lib/debugobjects.c:339
debug_print_object+0xd7/0x100
[ 37.238545] ---[ end trace a269db5d13fd1fbc ]---
[ 37.238550] ODEBUG: ODEBUG: debug object originally initialized at:
[ 37.238558] futex_wait+0x77/0x240
[ 37.238563] do_futex+0x486/0xb10
[ 37.238569] __se_sys_futex+0x128/0x170
[ 37.238574] do_syscall_64+0x55/0x190
[ 37.238579] entry_SYSCALL_64_after_hwframe+0x49/0xbe
[ 37.238586] ======================================================
[ 37.238587] WARNING: possible circular locking dependency detected
[ 37.238587] 4.17.0-rc3-CI-CI_DRM_4120+ #1 Tainted: G U
[ 37.238588] ------------------------------------------------------
[ 37.238588] kworker/u16:4/154 is trying to acquire lock:
[ 37.238588] 000000005c99e3ce ((console_sem).lock){-...}, at:
down_trylock+0xa/0x30
[ 37.238590] but task is already holding lock:
[ 37.238590] 000000005a835f50 (&obj_hash[i].lock){-.-.}, at:
debug_check_no_obj_freed+0xa4/0x210
[ 37.238592] which lock already depends on the new lock.
[ 37.238593] the existing dependency chain (in reverse order) is:
[ 37.238594] -> #3 (&obj_hash[i].lock){-.-.}:
[ 37.238595] __debug_object_init+0x74/0x590
[ 37.238596] hrtimer_init+0x1b/0x160
[ 37.238596] init_dl_task_timer+0x17/0x30
[ 37.238597] __sched_fork.isra.16+0xa4/0xf0
[ 37.238597] init_idle+0x53/0x270
[ 37.238597] sched_init+0x42c/0x49f
[ 37.238598] start_kernel+0x269/0x4bb
[ 37.238598] secondary_startup_64+0xa5/0xb0
[ 37.238598] -> #2 (&rq->lock){-.-.}:
[ 37.238600] task_fork_fair+0x36/0x160
[ 37.238600] sched_fork+0x123/0x280
[ 37.238601] copy_process.part.7+0x5f4/0x1d30
[ 37.238601] _do_fork+0xe2/0x700
[ 37.238601] kernel_thread+0x20/0x30
[ 37.238602] rest_init+0x1d/0x220
[ 37.238602] start_kernel+0x49b/0x4bb
[ 37.238602] secondary_startup_64+0xa5/0xb0
[ 37.238603] -> #1 (&p->pi_lock){-.-.}:
[ 37.238604] try_to_wake_up+0x37/0x650
[ 37.238605] up+0x3b/0x50
[ 37.238605] __up_console_sem+0x2e/0x50
[ 37.238606] console_unlock+0x32f/0x640
[ 37.238606] vprintk_emit+0x23b/0x4d0
[ 37.238606] printk+0x4d/0x69
[ 37.238607] drm_dbg+0x7f/0x90
[ 37.238607] do_gmbus_xfer+0x35c/0x440 [i915]
[ 37.238608] gmbus_xfer+0x7a/0x90 [i915]
[ 37.238608] __i2c_transfer+0x117/0x7d0
[ 37.238608] i2c_transfer+0x54/0xe0
[ 37.238609] drm_dp_dual_mode_read+0x5f/0xa0
[ 37.238609] drm_dp_dual_mode_detect+0x40/0x170
[ 37.238609] intel_hdmi_set_edid+0x7e/0x280 [i915]
[ 37.238610] intel_hdmi_detect+0x7e/0xa0 [i915]
[ 37.238610] drm_helper_probe_single_connector_modes+0xd2/0x6d0
[ 37.238611] drm_setup_crtcs+0x159/0xc90
[ 37.238611] __drm_fb_helper_initial_config_and_unlock+0x34/0x4a0
[ 37.238612] intel_fbdev_initial_config+0xf/0x20 [i915]
[ 37.238612] async_run_entry_fn+0x34/0x160
[ 37.238612] process_one_work+0x229/0x6a0
[ 37.238613] worker_thread+0x35/0x380
[ 37.238613] kthread+0x119/0x130
[ 37.238614] ret_from_fork+0x3a/0x50
[ 37.238614] -> #0 ((console_sem).lock){-...}:
[ 37.238615] _raw_spin_lock_irqsave+0x33/0x50
[ 37.238616] down_trylock+0xa/0x30
[ 37.238616] __down_trylock_console_sem+0x20/0x80
[ 37.238617] console_trylock+0xe/0x60
[ 37.238617] vprintk_emit+0x22e/0x4d0
[ 37.238617] printk+0x4d/0x69
[ 37.238618] __warn_printk+0x46/0x90
[ 37.238618] debug_print_object+0xd7/0x100
[ 37.238618] debug_check_no_obj_freed+0x194/0x210
[ 37.238619] __vunmap+0xab/0x100
[ 37.238619] __i915_gem_object_put_pages+0x1ec/0x200 [i915]
[ 37.238619] __i915_gem_free_objects+0x275/0x780 [i915]
[ 37.238620] __i915_gem_free_work+0x5d/0x90 [i915]
[ 37.238620] process_one_work+0x229/0x6a0
[ 37.238620] worker_thread+0x35/0x380
[ 37.238621] kthread+0x119/0x130
[ 37.238621] ret_from_fork+0x3a/0x50
[ 37.238622] other info that might help us debug this:
[ 37.238623] Chain exists of:
[ 37.238623] (console_sem).lock --> &rq->lock --> &obj_hash[i].lock
[ 37.238625] Possible unsafe locking scenario:
[ 37.238626] CPU0 CPU1
[ 37.238626] ---- ----
[ 37.238626] lock(&obj_hash[i].lock);
[ 37.238627] lock(&rq->lock);
[ 37.238628] lock(&obj_hash[i].lock);
[ 37.238629] lock((console_sem).lock);
[ 37.238631] *** DEADLOCK ***
[ 37.238631] 4 locks held by kworker/u16:4/154:
[ 37.238631] #0: 00000000187b427a ((wq_completion)"i915"){+.+.}, at:
process_one_work+0x1a3/0x6a0
[ 37.238633] #1: 0000000042f30b9a
((work_completion)(&i915->mm.free_work)){+.+.}, at:
process_one_work+0x1a3/0x6a0
[ 37.238635] #2: 000000009d7ef09a (&obj->mm.lock){+.+.}, at:
__i915_gem_object_put_pages+0x4f/0x200 [i915]
[ 37.238637] #3: 000000005a835f50 (&obj_hash[i].lock){-.-.}, at:
debug_check_no_obj_freed+0xa4/0x210
[ 37.238639] stack backtrace:
[ 37.238639] CPU: 1 PID: 154 Comm: kworker/u16:4 Tainted: G U
4.17.0-rc3-CI-CI_DRM_4120+ #1
[ 37.238640] Hardware name: System manufacturer System Product Name/Z170 PRO
GAMING, BIOS 3402 04/26/2017
[ 37.238640] Workqueue: i915 __i915_gem_free_work [i915]
[ 37.238641] Call Trace:
[ 37.238641] dump_stack+0x67/0x9b
[ 37.238642] print_circular_bug.isra.18+0x1c8/0x2b0
[ 37.238642] __lock_acquire+0x1897/0x1b50
[ 37.238642] ? lock_acquire+0xa6/0x210
[ 37.238643] lock_acquire+0xa6/0x210
[ 37.238643] ? down_trylock+0xa/0x30
[ 37.238643] ? sched_clock_cpu+0x10/0xe0
[ 37.238644] ? vprintk_emit+0x22e/0x4d0
[ 37.238644] _raw_spin_lock_irqsave+0x33/0x50
[ 37.238645] ? down_trylock+0xa/0x30
[ 37.238645] down_trylock+0xa/0x30
[ 37.238646] __down_trylock_console_sem+0x20/0x80
[ 37.238646] console_trylock+0xe/0x60
[ 37.238646] vprintk_emit+0x22e/0x4d0
[ 37.238647] ? clock_was_set_work+0x20/0x20
[ 37.238647] printk+0x4d/0x69
[ 37.238648] ? clock_was_set_work+0x20/0x20
[ 37.238648] __warn_printk+0x46/0x90
[ 37.238648] ? clock_was_set_work+0x20/0x20
[ 37.238649] debug_print_object+0xd7/0x100
[ 37.238649] ? mark_held_locks+0x50/0x80
[ 37.238649] ? _raw_spin_unlock_irqrestore+0x4c/0x60
[ 37.238650] ? trace_hardirqs_on_caller+0xe0/0x1b0
[ 37.238650] ? lock_acquire+0xa6/0x210
[ 37.238651] ? debug_check_no_obj_freed+0xa4/0x210
[ 37.238651] debug_check_no_obj_freed+0x194/0x210
[ 37.238651] __vunmap+0xab/0x100
[ 37.238652] __i915_gem_object_put_pages+0x1ec/0x200 [i915]
[ 37.238652] __i915_gem_free_objects+0x275/0x780 [i915]
[ 37.238652] __i915_gem_free_work+0x5d/0x90 [i915]
[ 37.238653] process_one_work+0x229/0x6a0
[ 37.238653] worker_thread+0x35/0x380
[ 37.238654] ? process_one_work+0x6a0/0x6a0
[ 37.238654] kthread+0x119/0x130
[ 37.238655] ? _kthread_create_on_node+0x60/0x60
[ 37.238655] 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/20180502/c5d9d4a9/attachment-0001.html>
More information about the intel-gfx-bugs
mailing list