* 2.6.26.4 hard-unsafe lock order detected (drm-related?)
@ 2008-09-10 16:48 Marcus Furlong
2008-09-10 18:15 ` Peter Zijlstra
0 siblings, 1 reply; 4+ messages in thread
From: Marcus Furlong @ 2008-09-10 16:48 UTC (permalink / raw)
To: linux-kernel
Hi,
Just found this in dmesg, if any more info is needed let me know.
Marcus.
[18255.940781] ======================================================
[18255.940789] [ INFO: hard-safe -> hard-unsafe lock order detected ]
[18255.940792] 2.6.26.4 #1
[18255.940794] ------------------------------------------------------
[18255.940797] swapper/0 [HC0[0]:SC1[2]:HE0:SE0] is trying to acquire:
[18255.940800] (&dev->lock.spinlock){-+..}, at: [<f9072ecc>]
drm_lock_take+0x1c/0xc0 [drm]
[18255.940815]
[18255.940816] and this task is already holding:
[18255.940818] (&dev->tasklet_lock){++..}, at: [<f907254e>]
drm_locked_tasklet_func+0x1e/0x90 [drm]
[18255.940831] which would create a new lock dependency:
[18255.940833] (&dev->tasklet_lock){++..} -> (&dev->lock.spinlock){-+..}
[18255.940840]
[18255.940841] but this new dependency connects a hard-irq-safe lock:
[18255.940843] (&dev->tasklet_lock){++..}
[18255.940846] ... which became hard-irq-safe at:
[18255.940848] [<c016495d>] __lock_acquire+0x82d/0xfb0
[18255.940856] [<c0165141>] lock_acquire+0x61/0x80
[18255.940862] [<c0496de3>] _spin_lock_irqsave+0x43/0x60
[18255.940869] [<f907260d>] drm_locked_tasklet+0x4d/0xa0 [drm]
[18255.940880] [<f9048bf3>] i915_driver_irq_handler+0x1c3/0x1f0 [i915]
[18255.940888] [<c016f048>] handle_IRQ_event+0x28/0x60
[18255.940892] [<c01704f8>] handle_fasteoi_irq+0x78/0xf0
[18255.940892] [<c011d719>] do_IRQ+0x79/0xc0
[18255.940892] [<ffffffff>] 0xffffffff
[18255.940892]
[18255.940892] to a hard-irq-unsafe lock:
[18255.940892] (&dev->lock.spinlock){-+..}
[18255.940892] ... which became hard-irq-unsafe at:
[18255.940892] ... [<c0164736>] __lock_acquire+0x606/0xfb0
[18255.940892] [<c0165141>] lock_acquire+0x61/0x80
[18255.940892] [<c0496e8b>] _spin_lock_bh+0x3b/0x50
[18255.940892] [<f907319e>] drm_lock+0x8e/0x310 [drm]
[18255.940892] [<f90713e9>] drm_ioctl+0x1b9/0x2f0 [drm]
[18255.940892] [<c01a30eb>] vfs_ioctl+0x6b/0x80
[18255.940892] [<c01a3157>] do_vfs_ioctl+0x57/0x2b0
[18255.940892] [<c01a33e9>] sys_ioctl+0x39/0x60
[18255.940892] [<c011a505>] sysenter_past_esp+0x6a/0xb1
[18255.940892] [<ffffffff>] 0xffffffff
[18255.940892]
[18255.940892] other info that might help us debug this:
[18255.940892]
[18255.940892] 1 lock held by swapper/0:
[18255.940892] #0: (&dev->tasklet_lock){++..}, at: [<f907254e>]
drm_locked_tasklet_func+0x1e/0x90 [drm]
[18255.940892]
[18255.940892] the hard-irq-safe lock's dependencies:
[18255.940892] -> (&dev->tasklet_lock){++..} ops: 0 {
[18255.940892] initial-use at:
[18255.940892] [<c016424d>]
__lock_acquire+0x11d/0xfb0
[18255.940892] [<c0165141>]
lock_acquire+0x61/0x80
[18255.940892] [<c0496de3>]
_spin_lock_irqsave+0x43/0x60
[18255.940892] [<f907260d>]
drm_locked_tasklet+0x4d/0xa0 [drm]
[18255.940892] [<f9048bf3>]
i915_driver_irq_handler+0x1c3/0x1f0 [i915]
[18255.940892] [<c016f048>]
handle_IRQ_event+0x28/0x60
[18255.940892] [<c01704f8>]
handle_fasteoi_irq+0x78/0xf0
[18255.940892] [<c011d719>]
do_IRQ+0x79/0xc0
[18255.940892] [<ffffffff>] 0xffffffff
[18255.940892] in-hardirq-W at:
[18255.940892] [<c016495d>]
__lock_acquire+0x82d/0xfb0
[18255.940892] [<c0165141>]
lock_acquire+0x61/0x80
[18255.940892] [<c0496de3>]
_spin_lock_irqsave+0x43/0x60
[18255.940892] [<f907260d>]
drm_locked_tasklet+0x4d/0xa0 [drm]
[18255.940892] [<f9048bf3>]
i915_driver_irq_handler+0x1c3/0x1f0 [i915]
[18255.940892] [<c016f048>]
handle_IRQ_event+0x28/0x60
[18255.940892] [<c01704f8>]
handle_fasteoi_irq+0x78/0xf0
[18255.940892] [<c011d719>]
do_IRQ+0x79/0xc0
[18255.940892] [<ffffffff>] 0xffffffff
[18255.940892] in-softirq-W at:
[18255.940892] [<c01645ef>]
__lock_acquire+0x4bf/0xfb0
[18255.940892] [<c0165141>]
lock_acquire+0x61/0x80
[18255.940892] [<c0496de3>]
_spin_lock_irqsave+0x43/0x60
[18255.940892] [<f907254e>]
drm_locked_tasklet_func+0x1e/0x90 [drm]
[18255.940892] [<c01476eb>]
tasklet_hi_action+0x5b/0xd0
[18255.940892] [<c01474e4>]
__do_softirq+0x74/0xe0
[18255.940892] [<c011d665>]
do_softirq+0x95/0xd0
[18255.940892] [<ffffffff>] 0xffffffff
[18255.940892] }
[18255.940892] ... key at: [<f907e444>] __key.23164+0x0/0xffff90cb [drm]
[18255.940892]
[18255.940892] the hard-irq-unsafe lock's dependencies:
[18255.940892] -> (&dev->lock.spinlock){-+..} ops: 0 {
[18255.940892] initial-use at:
[18255.940892] [<c016424d>]
__lock_acquire+0x11d/0xfb0
[18255.940892] [<c0165141>]
lock_acquire+0x61/0x80
[18255.940892] [<c0496e8b>]
_spin_lock_bh+0x3b/0x50
[18255.940892] [<f907319e>]
drm_lock+0x8e/0x310 [drm]
[18255.940892] [<f90713e9>]
drm_ioctl+0x1b9/0x2f0 [drm]
[18255.940892] [<c01a30eb>]
vfs_ioctl+0x6b/0x80
[18255.940892] [<c01a3157>]
do_vfs_ioctl+0x57/0x2b0
[18255.940892] [<c01a33e9>]
sys_ioctl+0x39/0x60
[18255.940892] [<c011a505>]
sysenter_past_esp+0x6a/0xb1
[18255.940892] [<ffffffff>] 0xffffffff
[18255.940892] in-softirq-W at:
[18255.940892] [<c01645ef>]
__lock_acquire+0x4bf/0xfb0
[18255.940892] [<c0165141>]
lock_acquire+0x61/0x80
[18255.940892] [<c0496e8b>]
_spin_lock_bh+0x3b/0x50
[18255.940892] [<f9072ecc>]
drm_lock_take+0x1c/0xc0 [drm]
[18255.940892] [<f907256a>]
drm_locked_tasklet_func+0x3a/0x90 [drm]
[18255.940892] [<c01476eb>]
tasklet_hi_action+0x5b/0xd0
[18255.940892] [<c01474e4>]
__do_softirq+0x74/0xe0
[18255.940892] [<c011d665>]
do_softirq+0x95/0xd0
[18255.940892] [<ffffffff>] 0xffffffff
[18255.940892] hardirq-on-W at:
[18255.940892] [<c0164736>]
__lock_acquire+0x606/0xfb0
[18255.940892] [<c0165141>]
lock_acquire+0x61/0x80
[18255.940892] [<c0496e8b>]
_spin_lock_bh+0x3b/0x50
[18255.940892] [<f907319e>]
drm_lock+0x8e/0x310 [drm]
[18255.940892] [<f90713e9>]
drm_ioctl+0x1b9/0x2f0 [drm]
[18255.940892] [<c01a30eb>]
vfs_ioctl+0x6b/0x80
[18255.940892] [<c01a3157>]
do_vfs_ioctl+0x57/0x2b0
[18255.940892] [<c01a33e9>]
sys_ioctl+0x39/0x60
[18255.940892] [<c011a505>]
sysenter_past_esp+0x6a/0xb1
[18255.940892] [<ffffffff>] 0xffffffff
[18255.940892] }
[18255.940892] ... key at: [<f907e43c>] __key.23165+0x0/0xffff90d3 [drm]
[18255.940892]
[18255.940892] stack backtrace:
[18255.940892] Pid: 0, comm: swapper Not tainted 2.6.26.4 #1
[18255.940892] [<c0164072>] check_usage+0x252/0x260
[18255.940892] [<c0164c18>] __lock_acquire+0xae8/0xfb0
[18255.940892] [<c0165141>] lock_acquire+0x61/0x80
[18255.940892] [<f9072ecc>] ? drm_lock_take+0x1c/0xc0 [drm]
[18255.940892] [<c0496e8b>] _spin_lock_bh+0x3b/0x50
[18255.940892] [<f9072ecc>] ? drm_lock_take+0x1c/0xc0 [drm]
[18255.940892] [<f9072ecc>] drm_lock_take+0x1c/0xc0 [drm]
[18255.940892] [<f907256a>] drm_locked_tasklet_func+0x3a/0x90 [drm]
[18255.940892] [<c01476eb>] tasklet_hi_action+0x5b/0xd0
[18255.940892] [<c01474e4>] __do_softirq+0x74/0xe0
[18255.940892] [<c011d665>] do_softirq+0x95/0xd0
[18255.940892] [<c0170480>] ? handle_fasteoi_irq+0x0/0xf0
[18255.940892] [<c0147386>] irq_exit+0x86/0xa0
[18255.940892] [<c011d720>] do_IRQ+0x80/0xc0
[18255.940892] [<c011af82>] common_interrupt+0x2e/0x34
[18255.940892] [<c01600d8>] ? tick_setup_oneshot+0x28/0x40
[18255.940892] [<c030fa9f>] ? acpi_idle_enter_bm+0x297/0x306
[18255.940892] [<c03cfd0b>] cpuidle_idle_call+0x6b/0xc0
[18255.940892] [<c03cfca0>] ? cpuidle_idle_call+0x0/0xc0
[18255.940892] [<c0118f70>] cpu_idle+0x60/0xf0
[18255.940892] [<c04806f2>] rest_init+0x62/0x70
[18255.940892] =======================
^ permalink raw reply [flat|nested] 4+ messages in thread
* Re: 2.6.26.4 hard-unsafe lock order detected (drm-related?)
2008-09-10 16:48 2.6.26.4 hard-unsafe lock order detected (drm-related?) Marcus Furlong
@ 2008-09-10 18:15 ` Peter Zijlstra
2008-09-11 11:36 ` Marcus Furlong
0 siblings, 1 reply; 4+ messages in thread
From: Peter Zijlstra @ 2008-09-10 18:15 UTC (permalink / raw)
To: Marcus Furlong; +Cc: linux-kernel, Dave Airlie
On Wed, 2008-09-10 at 17:48 +0100, Marcus Furlong wrote:
> Hi,
>
> Just found this in dmesg, if any more info is needed let me know.
Dave, have you ever seen this one before?
Marcus, can you reproduce? - if so, could you try .27-rc6 to see if it
is still valid?
> [18255.940781] ======================================================
> [18255.940789] [ INFO: hard-safe -> hard-unsafe lock order detected ]
> [18255.940792] 2.6.26.4 #1
> [18255.940794] ------------------------------------------------------
> [18255.940797] swapper/0 [HC0[0]:SC1[2]:HE0:SE0] is trying to acquire:
> [18255.940800] (&dev->lock.spinlock){-+..}, at: [<f9072ecc>]
> drm_lock_take+0x1c/0xc0 [drm]
> [18255.940815]
> [18255.940816] and this task is already holding:
> [18255.940818] (&dev->tasklet_lock){++..}, at: [<f907254e>]
> drm_locked_tasklet_func+0x1e/0x90 [drm]
> [18255.940831] which would create a new lock dependency:
> [18255.940833] (&dev->tasklet_lock){++..} -> (&dev->lock.spinlock){-+..}
> [18255.940840]
> [18255.940841] but this new dependency connects a hard-irq-safe lock:
> [18255.940843] (&dev->tasklet_lock){++..}
> [18255.940846] ... which became hard-irq-safe at:
> [18255.940848] [<c016495d>] __lock_acquire+0x82d/0xfb0
> [18255.940856] [<c0165141>] lock_acquire+0x61/0x80
> [18255.940862] [<c0496de3>] _spin_lock_irqsave+0x43/0x60
> [18255.940869] [<f907260d>] drm_locked_tasklet+0x4d/0xa0 [drm]
> [18255.940880] [<f9048bf3>] i915_driver_irq_handler+0x1c3/0x1f0 [i915]
> [18255.940888] [<c016f048>] handle_IRQ_event+0x28/0x60
> [18255.940892] [<c01704f8>] handle_fasteoi_irq+0x78/0xf0
> [18255.940892] [<c011d719>] do_IRQ+0x79/0xc0
> [18255.940892] [<ffffffff>] 0xffffffff
> [18255.940892]
> [18255.940892] to a hard-irq-unsafe lock:
> [18255.940892] (&dev->lock.spinlock){-+..}
> [18255.940892] ... which became hard-irq-unsafe at:
> [18255.940892] ... [<c0164736>] __lock_acquire+0x606/0xfb0
> [18255.940892] [<c0165141>] lock_acquire+0x61/0x80
> [18255.940892] [<c0496e8b>] _spin_lock_bh+0x3b/0x50
> [18255.940892] [<f907319e>] drm_lock+0x8e/0x310 [drm]
> [18255.940892] [<f90713e9>] drm_ioctl+0x1b9/0x2f0 [drm]
> [18255.940892] [<c01a30eb>] vfs_ioctl+0x6b/0x80
> [18255.940892] [<c01a3157>] do_vfs_ioctl+0x57/0x2b0
> [18255.940892] [<c01a33e9>] sys_ioctl+0x39/0x60
> [18255.940892] [<c011a505>] sysenter_past_esp+0x6a/0xb1
> [18255.940892] [<ffffffff>] 0xffffffff
> [18255.940892]
> [18255.940892] other info that might help us debug this:
> [18255.940892]
> [18255.940892] 1 lock held by swapper/0:
> [18255.940892] #0: (&dev->tasklet_lock){++..}, at: [<f907254e>]
> drm_locked_tasklet_func+0x1e/0x90 [drm]
> [18255.940892]
> [18255.940892] the hard-irq-safe lock's dependencies:
> [18255.940892] -> (&dev->tasklet_lock){++..} ops: 0 {
> [18255.940892] initial-use at:
> [18255.940892] [<c016424d>]
> __lock_acquire+0x11d/0xfb0
> [18255.940892] [<c0165141>]
> lock_acquire+0x61/0x80
> [18255.940892] [<c0496de3>]
> _spin_lock_irqsave+0x43/0x60
> [18255.940892] [<f907260d>]
> drm_locked_tasklet+0x4d/0xa0 [drm]
> [18255.940892] [<f9048bf3>]
> i915_driver_irq_handler+0x1c3/0x1f0 [i915]
> [18255.940892] [<c016f048>]
> handle_IRQ_event+0x28/0x60
> [18255.940892] [<c01704f8>]
> handle_fasteoi_irq+0x78/0xf0
> [18255.940892] [<c011d719>]
> do_IRQ+0x79/0xc0
> [18255.940892] [<ffffffff>] 0xffffffff
> [18255.940892] in-hardirq-W at:
> [18255.940892] [<c016495d>]
> __lock_acquire+0x82d/0xfb0
> [18255.940892] [<c0165141>]
> lock_acquire+0x61/0x80
> [18255.940892] [<c0496de3>]
> _spin_lock_irqsave+0x43/0x60
> [18255.940892] [<f907260d>]
> drm_locked_tasklet+0x4d/0xa0 [drm]
> [18255.940892] [<f9048bf3>]
> i915_driver_irq_handler+0x1c3/0x1f0 [i915]
> [18255.940892] [<c016f048>]
> handle_IRQ_event+0x28/0x60
> [18255.940892] [<c01704f8>]
> handle_fasteoi_irq+0x78/0xf0
> [18255.940892] [<c011d719>]
> do_IRQ+0x79/0xc0
> [18255.940892] [<ffffffff>] 0xffffffff
> [18255.940892] in-softirq-W at:
> [18255.940892] [<c01645ef>]
> __lock_acquire+0x4bf/0xfb0
> [18255.940892] [<c0165141>]
> lock_acquire+0x61/0x80
> [18255.940892] [<c0496de3>]
> _spin_lock_irqsave+0x43/0x60
> [18255.940892] [<f907254e>]
> drm_locked_tasklet_func+0x1e/0x90 [drm]
> [18255.940892] [<c01476eb>]
> tasklet_hi_action+0x5b/0xd0
> [18255.940892] [<c01474e4>]
> __do_softirq+0x74/0xe0
> [18255.940892] [<c011d665>]
> do_softirq+0x95/0xd0
> [18255.940892] [<ffffffff>] 0xffffffff
> [18255.940892] }
> [18255.940892] ... key at: [<f907e444>] __key.23164+0x0/0xffff90cb [drm]
> [18255.940892]
> [18255.940892] the hard-irq-unsafe lock's dependencies:
> [18255.940892] -> (&dev->lock.spinlock){-+..} ops: 0 {
> [18255.940892] initial-use at:
> [18255.940892] [<c016424d>]
> __lock_acquire+0x11d/0xfb0
> [18255.940892] [<c0165141>]
> lock_acquire+0x61/0x80
> [18255.940892] [<c0496e8b>]
> _spin_lock_bh+0x3b/0x50
> [18255.940892] [<f907319e>]
> drm_lock+0x8e/0x310 [drm]
> [18255.940892] [<f90713e9>]
> drm_ioctl+0x1b9/0x2f0 [drm]
> [18255.940892] [<c01a30eb>]
> vfs_ioctl+0x6b/0x80
> [18255.940892] [<c01a3157>]
> do_vfs_ioctl+0x57/0x2b0
> [18255.940892] [<c01a33e9>]
> sys_ioctl+0x39/0x60
> [18255.940892] [<c011a505>]
> sysenter_past_esp+0x6a/0xb1
> [18255.940892] [<ffffffff>] 0xffffffff
> [18255.940892] in-softirq-W at:
> [18255.940892] [<c01645ef>]
> __lock_acquire+0x4bf/0xfb0
> [18255.940892] [<c0165141>]
> lock_acquire+0x61/0x80
> [18255.940892] [<c0496e8b>]
> _spin_lock_bh+0x3b/0x50
> [18255.940892] [<f9072ecc>]
> drm_lock_take+0x1c/0xc0 [drm]
> [18255.940892] [<f907256a>]
> drm_locked_tasklet_func+0x3a/0x90 [drm]
> [18255.940892] [<c01476eb>]
> tasklet_hi_action+0x5b/0xd0
> [18255.940892] [<c01474e4>]
> __do_softirq+0x74/0xe0
> [18255.940892] [<c011d665>]
> do_softirq+0x95/0xd0
> [18255.940892] [<ffffffff>] 0xffffffff
> [18255.940892] hardirq-on-W at:
> [18255.940892] [<c0164736>]
> __lock_acquire+0x606/0xfb0
> [18255.940892] [<c0165141>]
> lock_acquire+0x61/0x80
> [18255.940892] [<c0496e8b>]
> _spin_lock_bh+0x3b/0x50
> [18255.940892] [<f907319e>]
> drm_lock+0x8e/0x310 [drm]
> [18255.940892] [<f90713e9>]
> drm_ioctl+0x1b9/0x2f0 [drm]
> [18255.940892] [<c01a30eb>]
> vfs_ioctl+0x6b/0x80
> [18255.940892] [<c01a3157>]
> do_vfs_ioctl+0x57/0x2b0
> [18255.940892] [<c01a33e9>]
> sys_ioctl+0x39/0x60
> [18255.940892] [<c011a505>]
> sysenter_past_esp+0x6a/0xb1
> [18255.940892] [<ffffffff>] 0xffffffff
> [18255.940892] }
> [18255.940892] ... key at: [<f907e43c>] __key.23165+0x0/0xffff90d3 [drm]
> [18255.940892]
> [18255.940892] stack backtrace:
> [18255.940892] Pid: 0, comm: swapper Not tainted 2.6.26.4 #1
> [18255.940892] [<c0164072>] check_usage+0x252/0x260
> [18255.940892] [<c0164c18>] __lock_acquire+0xae8/0xfb0
> [18255.940892] [<c0165141>] lock_acquire+0x61/0x80
> [18255.940892] [<f9072ecc>] ? drm_lock_take+0x1c/0xc0 [drm]
> [18255.940892] [<c0496e8b>] _spin_lock_bh+0x3b/0x50
> [18255.940892] [<f9072ecc>] ? drm_lock_take+0x1c/0xc0 [drm]
> [18255.940892] [<f9072ecc>] drm_lock_take+0x1c/0xc0 [drm]
> [18255.940892] [<f907256a>] drm_locked_tasklet_func+0x3a/0x90 [drm]
> [18255.940892] [<c01476eb>] tasklet_hi_action+0x5b/0xd0
> [18255.940892] [<c01474e4>] __do_softirq+0x74/0xe0
> [18255.940892] [<c011d665>] do_softirq+0x95/0xd0
> [18255.940892] [<c0170480>] ? handle_fasteoi_irq+0x0/0xf0
> [18255.940892] [<c0147386>] irq_exit+0x86/0xa0
> [18255.940892] [<c011d720>] do_IRQ+0x80/0xc0
> [18255.940892] [<c011af82>] common_interrupt+0x2e/0x34
> [18255.940892] [<c01600d8>] ? tick_setup_oneshot+0x28/0x40
> [18255.940892] [<c030fa9f>] ? acpi_idle_enter_bm+0x297/0x306
> [18255.940892] [<c03cfd0b>] cpuidle_idle_call+0x6b/0xc0
> [18255.940892] [<c03cfca0>] ? cpuidle_idle_call+0x0/0xc0
> [18255.940892] [<c0118f70>] cpu_idle+0x60/0xf0
> [18255.940892] [<c04806f2>] rest_init+0x62/0x70
> [18255.940892] =======================
>
>
> --
> To unsubscribe from this list: send the line "unsubscribe linux-kernel" in
> the body of a message to majordomo@vger.kernel.org
> More majordomo info at http://vger.kernel.org/majordomo-info.html
> Please read the FAQ at http://www.tux.org/lkml/
^ permalink raw reply [flat|nested] 4+ messages in thread
* Re: 2.6.26.4 hard-unsafe lock order detected (drm-related?)
2008-09-10 18:15 ` Peter Zijlstra
@ 2008-09-11 11:36 ` Marcus Furlong
2008-09-11 18:21 ` Marcus Furlong
0 siblings, 1 reply; 4+ messages in thread
From: Marcus Furlong @ 2008-09-11 11:36 UTC (permalink / raw)
To: linux-kernel
On Wednesday 10 September 2008 19:15 in
<1221070510.4415.207.camel@twins.programming.kicks-ass.net>, Peter Zijlstra
wrote:
> On Wed, 2008-09-10 at 17:48 +0100, Marcus Furlong wrote:
>> Hi,
>>
>> Just found this in dmesg, if any more info is needed let me know.
>
> Dave, have you ever seen this one before?
>
> Marcus, can you reproduce? - if so, could you try .27-rc6 to see if it
> is still valid?
Can't reproduce so far.
>
>> [18255.940781] ======================================================
>> [18255.940789] [ INFO: hard-safe -> hard-unsafe lock order detected ]
>> [18255.940792] 2.6.26.4 #1
>> [18255.940794] ------------------------------------------------------
>> [18255.940797] swapper/0 [HC0[0]:SC1[2]:HE0:SE0] is trying to acquire:
>> [18255.940800] (&dev->lock.spinlock){-+..}, at: [<f9072ecc>]
>> drm_lock_take+0x1c/0xc0 [drm]
>> [18255.940815]
>> [18255.940816] and this task is already holding:
>> [18255.940818] (&dev->tasklet_lock){++..}, at: [<f907254e>]
>> drm_locked_tasklet_func+0x1e/0x90 [drm]
>> [18255.940831] which would create a new lock dependency:
>> [18255.940833] (&dev->tasklet_lock){++..} -> (&dev->lock.spinlock){-+..}
>> [18255.940840]
>> [18255.940841] but this new dependency connects a hard-irq-safe lock:
>> [18255.940843] (&dev->tasklet_lock){++..}
>> [18255.940846] ... which became hard-irq-safe at:
>> [18255.940848] [<c016495d>] __lock_acquire+0x82d/0xfb0
>> [18255.940856] [<c0165141>] lock_acquire+0x61/0x80
>> [18255.940862] [<c0496de3>] _spin_lock_irqsave+0x43/0x60
>> [18255.940869] [<f907260d>] drm_locked_tasklet+0x4d/0xa0 [drm]
>> [18255.940880] [<f9048bf3>] i915_driver_irq_handler+0x1c3/0x1f0 [i915]
>> [18255.940888] [<c016f048>] handle_IRQ_event+0x28/0x60
>> [18255.940892] [<c01704f8>] handle_fasteoi_irq+0x78/0xf0
>> [18255.940892] [<c011d719>] do_IRQ+0x79/0xc0
>> [18255.940892] [<ffffffff>] 0xffffffff
>> [18255.940892]
>> [18255.940892] to a hard-irq-unsafe lock:
>> [18255.940892] (&dev->lock.spinlock){-+..}
>> [18255.940892] ... which became hard-irq-unsafe at:
>> [18255.940892] ... [<c0164736>] __lock_acquire+0x606/0xfb0
>> [18255.940892] [<c0165141>] lock_acquire+0x61/0x80
>> [18255.940892] [<c0496e8b>] _spin_lock_bh+0x3b/0x50
>> [18255.940892] [<f907319e>] drm_lock+0x8e/0x310 [drm]
>> [18255.940892] [<f90713e9>] drm_ioctl+0x1b9/0x2f0 [drm]
>> [18255.940892] [<c01a30eb>] vfs_ioctl+0x6b/0x80
>> [18255.940892] [<c01a3157>] do_vfs_ioctl+0x57/0x2b0
>> [18255.940892] [<c01a33e9>] sys_ioctl+0x39/0x60
>> [18255.940892] [<c011a505>] sysenter_past_esp+0x6a/0xb1
>> [18255.940892] [<ffffffff>] 0xffffffff
>> [18255.940892]
>> [18255.940892] other info that might help us debug this:
>> [18255.940892]
>> [18255.940892] 1 lock held by swapper/0:
>> [18255.940892] #0: (&dev->tasklet_lock){++..}, at: [<f907254e>]
>> drm_locked_tasklet_func+0x1e/0x90 [drm]
>> [18255.940892]
>> [18255.940892] the hard-irq-safe lock's dependencies:
>> [18255.940892] -> (&dev->tasklet_lock){++..} ops: 0 {
>> [18255.940892] initial-use at:
>> [18255.940892] [<c016424d>]
>> __lock_acquire+0x11d/0xfb0
>> [18255.940892] [<c0165141>]
>> lock_acquire+0x61/0x80
>> [18255.940892] [<c0496de3>]
>> _spin_lock_irqsave+0x43/0x60
>> [18255.940892] [<f907260d>]
>> drm_locked_tasklet+0x4d/0xa0 [drm]
>> [18255.940892] [<f9048bf3>]
>> i915_driver_irq_handler+0x1c3/0x1f0 [i915]
>> [18255.940892] [<c016f048>]
>> handle_IRQ_event+0x28/0x60
>> [18255.940892] [<c01704f8>]
>> handle_fasteoi_irq+0x78/0xf0
>> [18255.940892] [<c011d719>]
>> do_IRQ+0x79/0xc0
>> [18255.940892] [<ffffffff>]
>> [0xffffffff
>> [18255.940892] in-hardirq-W at:
>> [18255.940892] [<c016495d>]
>> __lock_acquire+0x82d/0xfb0
>> [18255.940892] [<c0165141>]
>> lock_acquire+0x61/0x80
>> [18255.940892] [<c0496de3>]
>> _spin_lock_irqsave+0x43/0x60
>> [18255.940892] [<f907260d>]
>> drm_locked_tasklet+0x4d/0xa0 [drm]
>> [18255.940892] [<f9048bf3>]
>> i915_driver_irq_handler+0x1c3/0x1f0 [i915]
>> [18255.940892] [<c016f048>]
>> handle_IRQ_event+0x28/0x60
>> [18255.940892] [<c01704f8>]
>> handle_fasteoi_irq+0x78/0xf0
>> [18255.940892] [<c011d719>]
>> do_IRQ+0x79/0xc0
>> [18255.940892] [<ffffffff>]
>> [0xffffffff
>> [18255.940892] in-softirq-W at:
>> [18255.940892] [<c01645ef>]
>> __lock_acquire+0x4bf/0xfb0
>> [18255.940892] [<c0165141>]
>> lock_acquire+0x61/0x80
>> [18255.940892] [<c0496de3>]
>> _spin_lock_irqsave+0x43/0x60
>> [18255.940892] [<f907254e>]
>> drm_locked_tasklet_func+0x1e/0x90 [drm]
>> [18255.940892] [<c01476eb>]
>> tasklet_hi_action+0x5b/0xd0
>> [18255.940892] [<c01474e4>]
>> __do_softirq+0x74/0xe0
>> [18255.940892] [<c011d665>]
>> do_softirq+0x95/0xd0
>> [18255.940892] [<ffffffff>]
>> [0xffffffff
>> [18255.940892] }
>> [18255.940892] ... key at: [<f907e444>] __key.23164+0x0/0xffff90cb
>> [[drm] 18255.940892]
>> [18255.940892] the hard-irq-unsafe lock's dependencies:
>> [18255.940892] -> (&dev->lock.spinlock){-+..} ops: 0 {
>> [18255.940892] initial-use at:
>> [18255.940892] [<c016424d>]
>> __lock_acquire+0x11d/0xfb0
>> [18255.940892] [<c0165141>]
>> lock_acquire+0x61/0x80
>> [18255.940892] [<c0496e8b>]
>> _spin_lock_bh+0x3b/0x50
>> [18255.940892] [<f907319e>]
>> drm_lock+0x8e/0x310 [drm]
>> [18255.940892] [<f90713e9>]
>> drm_ioctl+0x1b9/0x2f0 [drm]
>> [18255.940892] [<c01a30eb>]
>> vfs_ioctl+0x6b/0x80
>> [18255.940892] [<c01a3157>]
>> do_vfs_ioctl+0x57/0x2b0
>> [18255.940892] [<c01a33e9>]
>> sys_ioctl+0x39/0x60
>> [18255.940892] [<c011a505>]
>> sysenter_past_esp+0x6a/0xb1
>> [18255.940892] [<ffffffff>]
>> [0xffffffff
>> [18255.940892] in-softirq-W at:
>> [18255.940892] [<c01645ef>]
>> __lock_acquire+0x4bf/0xfb0
>> [18255.940892] [<c0165141>]
>> lock_acquire+0x61/0x80
>> [18255.940892] [<c0496e8b>]
>> _spin_lock_bh+0x3b/0x50
>> [18255.940892] [<f9072ecc>]
>> drm_lock_take+0x1c/0xc0 [drm]
>> [18255.940892] [<f907256a>]
>> drm_locked_tasklet_func+0x3a/0x90 [drm]
>> [18255.940892] [<c01476eb>]
>> tasklet_hi_action+0x5b/0xd0
>> [18255.940892] [<c01474e4>]
>> __do_softirq+0x74/0xe0
>> [18255.940892] [<c011d665>]
>> do_softirq+0x95/0xd0
>> [18255.940892] [<ffffffff>]
>> [0xffffffff
>> [18255.940892] hardirq-on-W at:
>> [18255.940892] [<c0164736>]
>> __lock_acquire+0x606/0xfb0
>> [18255.940892] [<c0165141>]
>> lock_acquire+0x61/0x80
>> [18255.940892] [<c0496e8b>]
>> _spin_lock_bh+0x3b/0x50
>> [18255.940892] [<f907319e>]
>> drm_lock+0x8e/0x310 [drm]
>> [18255.940892] [<f90713e9>]
>> drm_ioctl+0x1b9/0x2f0 [drm]
>> [18255.940892] [<c01a30eb>]
>> vfs_ioctl+0x6b/0x80
>> [18255.940892] [<c01a3157>]
>> do_vfs_ioctl+0x57/0x2b0
>> [18255.940892] [<c01a33e9>]
>> sys_ioctl+0x39/0x60
>> [18255.940892] [<c011a505>]
>> sysenter_past_esp+0x6a/0xb1
>> [18255.940892] [<ffffffff>]
>> [0xffffffff
>> [18255.940892] }
>> [18255.940892] ... key at: [<f907e43c>] __key.23165+0x0/0xffff90d3
>> [[drm] 18255.940892]
>> [18255.940892] stack backtrace:
>> [18255.940892] Pid: 0, comm: swapper Not tainted 2.6.26.4 #1
>> [18255.940892] [<c0164072>] check_usage+0x252/0x260
>> [18255.940892] [<c0164c18>] __lock_acquire+0xae8/0xfb0
>> [18255.940892] [<c0165141>] lock_acquire+0x61/0x80
>> [18255.940892] [<f9072ecc>] ? drm_lock_take+0x1c/0xc0 [drm]
>> [18255.940892] [<c0496e8b>] _spin_lock_bh+0x3b/0x50
>> [18255.940892] [<f9072ecc>] ? drm_lock_take+0x1c/0xc0 [drm]
>> [18255.940892] [<f9072ecc>] drm_lock_take+0x1c/0xc0 [drm]
>> [18255.940892] [<f907256a>] drm_locked_tasklet_func+0x3a/0x90 [drm]
>> [18255.940892] [<c01476eb>] tasklet_hi_action+0x5b/0xd0
>> [18255.940892] [<c01474e4>] __do_softirq+0x74/0xe0
>> [18255.940892] [<c011d665>] do_softirq+0x95/0xd0
>> [18255.940892] [<c0170480>] ? handle_fasteoi_irq+0x0/0xf0
>> [18255.940892] [<c0147386>] irq_exit+0x86/0xa0
>> [18255.940892] [<c011d720>] do_IRQ+0x80/0xc0
>> [18255.940892] [<c011af82>] common_interrupt+0x2e/0x34
>> [18255.940892] [<c01600d8>] ? tick_setup_oneshot+0x28/0x40
>> [18255.940892] [<c030fa9f>] ? acpi_idle_enter_bm+0x297/0x306
>> [18255.940892] [<c03cfd0b>] cpuidle_idle_call+0x6b/0xc0
>> [18255.940892] [<c03cfca0>] ? cpuidle_idle_call+0x0/0xc0
>> [18255.940892] [<c0118f70>] cpu_idle+0x60/0xf0
>> [18255.940892] [<c04806f2>] rest_init+0x62/0x70
>> [18255.940892] =======================
>>
>>
>> --
>> To unsubscribe from this list: send the line "unsubscribe linux-kernel" in
>> the body of a message to majordomo@vger.kernel.org
>> More majordomo info at http://vger.kernel.org/majordomo-info.html
>> Please read the FAQ at http://www.tux.org/lkml/
^ permalink raw reply [flat|nested] 4+ messages in thread
* Re: 2.6.26.4 hard-unsafe lock order detected (drm-related?)
2008-09-11 11:36 ` Marcus Furlong
@ 2008-09-11 18:21 ` Marcus Furlong
0 siblings, 0 replies; 4+ messages in thread
From: Marcus Furlong @ 2008-09-11 18:21 UTC (permalink / raw)
To: linux-kernel
On Thursday 11 September 2008 12:36 in <gaavrs$of3$1@ger.gmane.org>, Marcus
Furlong wrote:
> On Wednesday 10 September 2008 19:15 in
> <1221070510.4415.207.camel@twins.programming.kicks-ass.net>, Peter Zijlstra
> wrote:
>
>> On Wed, 2008-09-10 at 17:48 +0100, Marcus Furlong wrote:
>>> Hi,
>>>
>>> Just found this in dmesg, if any more info is needed let me know.
>>
>> Dave, have you ever seen this one before?
>>
>> Marcus, can you reproduce? - if so, could you try .27-rc6 to see if it
>> is still valid?
>
> Can't reproduce so far.
Actually just found another one on 2.6.26.4. Not sure what I was doing at the
time though. Tried 27-rc6 for a few hours last night and it didn't recoccur.
I'll see if I can pinpoint what causes it.
[16869.930402] ======================================================
[16869.930413] [ INFO: hard-safe -> hard-unsafe lock order detected ]
[16869.930418] 2.6.26.4 #1
[16869.930422] ------------------------------------------------------
[16869.930427] swapper/0 [HC0[0]:SC1[2]:HE0:SE0] is trying to acquire:
[16869.930432] (&dev->lock.spinlock){-+..}, at: [<f9072ecc>]
drm_lock_take+0x1c/0xc0 [drm]
[16869.930460]
[16869.930461] and this task is already holding:
[16869.930464] (&dev->tasklet_lock){++..}, at: [<f907254e>]
drm_locked_tasklet_func+0x1e/0x90 [drm]
[16869.930485] which would create a new lock dependency:
[16869.930490] (&dev->tasklet_lock){++..} -> (&dev->lock.spinlock){-+..}
[16869.930510]
[16869.930511] but this new dependency connects a hard-irq-safe lock:
[16869.930517] (&dev->tasklet_lock){++..}
[16869.930524] ... which became hard-irq-safe at:
[16869.930529] [<c016495d>] __lock_acquire+0x82d/0xfb0
[16869.930544] [<c0165141>] lock_acquire+0x61/0x80
[16869.930556] [<c0496de3>] _spin_lock_irqsave+0x43/0x60
[16869.930571] [<f907260d>] drm_locked_tasklet+0x4d/0xa0 [drm]
[16869.930592] [<f9048bf3>] i915_driver_irq_handler+0x1c3/0x1f0 [i915]
[16869.930611] [<c016f048>] handle_IRQ_event+0x28/0x60
[16869.930624] [<c01704f8>] handle_fasteoi_irq+0x78/0xf0
[16869.930636] [<c011d719>] do_IRQ+0x79/0xc0
[16869.930649] [<ffffffff>] 0xffffffff
[16869.930661]
[16869.930662] to a hard-irq-unsafe lock:
[16869.930668] (&dev->lock.spinlock){-+..}
[16869.930674] ... which became hard-irq-unsafe at:
[16869.930679] ... [<c0164736>] __lock_acquire+0x606/0xfb0
[16869.930696] [<c0165141>] lock_acquire+0x61/0x80
[16869.930708] [<c0496e8b>] _spin_lock_bh+0x3b/0x50
[16869.930724] [<f907319e>] drm_lock+0x8e/0x310 [drm]
[16869.930747] [<f90713e9>] drm_ioctl+0x1b9/0x2f0 [drm]
[16869.930773] [<c01a30eb>] vfs_ioctl+0x6b/0x80
[16869.930785] [<c01a3157>] do_vfs_ioctl+0x57/0x2b0
[16869.930798] [<c01a33e9>] sys_ioctl+0x39/0x60
[16869.930809] [<c011a505>] sysenter_past_esp+0x6a/0xb1
[16869.930821] [<ffffffff>] 0xffffffff
[16869.930837]
[16869.930838] other info that might help us debug this:
[16869.930839]
[16869.930842] 1 lock held by swapper/0:
[16869.930844] #0: (&dev->tasklet_lock){++..}, at: [<f907254e>]
drm_locked_tasklet_func+0x1e/0x90 [drm]
[16869.930858]
[16869.930859] the hard-irq-safe lock's dependencies:
[16869.930862] -> (&dev->tasklet_lock){++..} ops: 0 {
[16869.930870] initial-use at:
[16869.930873] [<c016424d>]
__lock_acquire+0x11d/0xfb0
[16869.930932] [<c0165141>]
lock_acquire+0x61/0x80
[16869.930987] [<c0496de3>]
_spin_lock_irqsave+0x43/0x60
[16869.930987] [<f9073007>]
drm_unlock+0x27/0xc0 [drm]
[16869.930987] [<f90713e9>]
drm_ioctl+0x1b9/0x2f0 [drm]
[16869.930987] [<c01a30eb>]
vfs_ioctl+0x6b/0x80
[16869.930987] [<c01a3157>]
do_vfs_ioctl+0x57/0x2b0
[16869.930987] [<c01a33e9>]
sys_ioctl+0x39/0x60
[16869.930987] [<c011a505>]
sysenter_past_esp+0x6a/0xb1
[16869.930987] [<ffffffff>] 0xffffffff
[16869.930987] in-hardirq-W at:
[16869.930987] [<c016495d>]
__lock_acquire+0x82d/0xfb0
[16869.930987] [<c0165141>]
lock_acquire+0x61/0x80
[16869.930987] [<c0496de3>]
_spin_lock_irqsave+0x43/0x60
[16869.930987] [<f907260d>]
drm_locked_tasklet+0x4d/0xa0 [drm]
[16869.930987] [<f9048bf3>]
i915_driver_irq_handler+0x1c3/0x1f0 [i915]
[16869.930987] [<c016f048>]
handle_IRQ_event+0x28/0x60
[16869.930987] [<c01704f8>]
handle_fasteoi_irq+0x78/0xf0
[16869.930987] [<c011d719>]
do_IRQ+0x79/0xc0
[16869.930987] [<ffffffff>] 0xffffffff
[16869.930987] in-softirq-W at:
[16869.930987] [<c01645ef>]
__lock_acquire+0x4bf/0xfb0
[16869.930987] [<c0165141>]
lock_acquire+0x61/0x80
[16869.930987] [<c0496de3>]
_spin_lock_irqsave+0x43/0x60
[16869.930987] [<f907254e>]
drm_locked_tasklet_func+0x1e/0x90 [drm]
[16869.930987] [<c01476eb>]
tasklet_hi_action+0x5b/0xd0
[16869.930987] [<c01474e4>]
__do_softirq+0x74/0xe0
[16869.930987] [<c011d665>]
do_softirq+0x95/0xd0
[16869.930987] [<ffffffff>] 0xffffffff
[16869.930987] }
[16869.930987] ... key at: [<f907e444>] __key.23164+0x0/0xffff90cb [drm]
[16869.930987]
[16869.930987] the hard-irq-unsafe lock's dependencies:
[16869.930987] -> (&dev->lock.spinlock){-+..} ops: 0 {
[16869.930987] initial-use at:
[16869.930987] [<c016424d>]
__lock_acquire+0x11d/0xfb0
[16869.930987] [<c0165141>]
lock_acquire+0x61/0x80
[16869.930987] [<c0496e8b>]
_spin_lock_bh+0x3b/0x50
[16869.930987] [<f907319e>]
drm_lock+0x8e/0x310 [drm]
[16869.930987] [<f90713e9>]
drm_ioctl+0x1b9/0x2f0 [drm]
[16869.930987] [<c01a30eb>]
vfs_ioctl+0x6b/0x80
[16869.930987] [<c01a3157>]
do_vfs_ioctl+0x57/0x2b0
[16869.930987] [<c01a33e9>]
sys_ioctl+0x39/0x60
[16869.930987] [<c011a505>]
sysenter_past_esp+0x6a/0xb1
[16869.930987] [<ffffffff>] 0xffffffff
[16869.930987] in-softirq-W at:
[16869.930987] [<c01645ef>]
__lock_acquire+0x4bf/0xfb0
[16869.930987] [<c0165141>]
lock_acquire+0x61/0x80
[16869.930987] [<c0496e8b>]
_spin_lock_bh+0x3b/0x50
[16869.930987] [<f9072ecc>]
drm_lock_take+0x1c/0xc0 [drm]
[16869.930987] [<f907256a>]
drm_locked_tasklet_func+0x3a/0x90 [drm]
[16869.930987] [<c01476eb>]
tasklet_hi_action+0x5b/0xd0
[16869.930987] [<c01474e4>]
__do_softirq+0x74/0xe0
[16869.930987] [<c011d665>]
do_softirq+0x95/0xd0
[16869.930987] [<ffffffff>] 0xffffffff
[16869.930987] hardirq-on-W at:
[16869.930987] [<c0164736>]
__lock_acquire+0x606/0xfb0
[16869.930987] [<c0165141>]
lock_acquire+0x61/0x80
[16869.930987] [<c0496e8b>]
_spin_lock_bh+0x3b/0x50
[16869.930987] [<f907319e>]
drm_lock+0x8e/0x310 [drm]
[16869.930987] [<f90713e9>]
drm_ioctl+0x1b9/0x2f0 [drm]
[16869.930987] [<c01a30eb>]
vfs_ioctl+0x6b/0x80
[16869.930987] [<c01a3157>]
do_vfs_ioctl+0x57/0x2b0
[16869.930987] [<c01a33e9>]
sys_ioctl+0x39/0x60
[16869.930987] [<c011a505>]
sysenter_past_esp+0x6a/0xb1
[16869.930987] [<ffffffff>] 0xffffffff
[16869.930987] }
[16869.930987] ... key at: [<f907e43c>] __key.23165+0x0/0xffff90d3 [drm]
[16869.930987]
[16869.930987] stack backtrace:
[16869.930987] Pid: 0, comm: swapper Not tainted 2.6.26.4 #1
[16869.930987] [<c0164072>] check_usage+0x252/0x260
[16869.930987] [<c0164c18>] __lock_acquire+0xae8/0xfb0
[16869.930987] [<c0135534>] ? hrtick_start_fair+0x114/0x170
[16869.930987] [<c0165141>] lock_acquire+0x61/0x80
[16869.930987] [<f9072ecc>] ? drm_lock_take+0x1c/0xc0 [drm]
[16869.930987] [<c0496e8b>] _spin_lock_bh+0x3b/0x50
[16869.930987] [<f9072ecc>] ? drm_lock_take+0x1c/0xc0 [drm]
[16869.930987] [<f9072ecc>] drm_lock_take+0x1c/0xc0 [drm]
[16869.930987] [<f907256a>] drm_locked_tasklet_func+0x3a/0x90 [drm]
[16869.930987] [<c01476eb>] tasklet_hi_action+0x5b/0xd0
[16869.930987] [<c01474e4>] __do_softirq+0x74/0xe0
[16869.930987] [<c011d665>] do_softirq+0x95/0xd0
[16869.930987] [<c0170480>] ? handle_fasteoi_irq+0x0/0xf0
[16869.930987] [<c0147386>] irq_exit+0x86/0xa0
[16869.930987] [<c011d720>] do_IRQ+0x80/0xc0
[16869.930987] [<c011af82>] common_interrupt+0x2e/0x34
[16869.930987] [<c01600d8>] ? tick_setup_oneshot+0x28/0x40
[16869.930987] [<c030fa9f>] ? acpi_idle_enter_bm+0x297/0x306
[16869.930987] [<c03cfd0b>] cpuidle_idle_call+0x6b/0xc0
[16869.930987] [<c03cfca0>] ? cpuidle_idle_call+0x0/0xc0
[16869.930987] [<c0118f70>] cpu_idle+0x60/0xf0
[16869.930987] [<c04806f2>] rest_init+0x62/0x70
[16869.930987] =======================
^ permalink raw reply [flat|nested] 4+ messages in thread
end of thread, other threads:[~2008-09-11 18:21 UTC | newest]
Thread overview: 4+ messages (download: mbox.gz follow: Atom feed
-- links below jump to the message on this page --
2008-09-10 16:48 2.6.26.4 hard-unsafe lock order detected (drm-related?) Marcus Furlong
2008-09-10 18:15 ` Peter Zijlstra
2008-09-11 11:36 ` Marcus Furlong
2008-09-11 18:21 ` Marcus Furlong
This is a public inbox, see mirroring instructions
for how to clone and mirror all data and code used for this inbox