inconsistent lock state (tty? input?)

[Marcin Slusarz]

On current git (a447c0932445f92ce6f4c1bd020f62c5097a7842):
[   12.288256] Adding 1020112k swap on /dev/sda2.  Priority:-1 extents:1 across:1020112k
[   33.469646] Marking TSC unstable due to cpufreq changes
[   37.320027] Clocksource tsc unstable (delta = -122876252 ns)
[   46.696880]
[   46.696883] =================================
[   46.696899] [ INFO: inconsistent lock state ]
[   46.696904] 2.6.27-03556-gbc1d26d #15
[   46.696908] ---------------------------------
[   46.696912] inconsistent {hardirq-on-W} -> {in-hardirq-R} usage.
[   46.696917] swapper/0 [HC1[1]:SC0[0]:HE0:SE1] takes:
[   46.696921]  (tasklist_lock){--?-}, at: [<ffffffff802a6991>] send_sigio+0x3f/0xa1
[   46.696940] {hardirq-on-W} state was registered at:
[   46.696946]   [<ffffffff80254f32>] mark_held_locks+0x52/0x72
[   46.696955]   [<ffffffff802550de>] trace_hardirqs_on_caller+0xf9/0x13a
[   46.696963]   [<ffffffff8025512c>] trace_hardirqs_on+0xd/0xf
[   46.696970]   [<ffffffff8051e065>] _spin_unlock_irq+0x30/0x57
[   46.696980]   [<ffffffff803b5ac7>] proc_clear_tty+0x4e/0x5b
[   46.696988]   [<ffffffff80241cbb>] sys_setsid+0x86/0xa2
[   46.696998]   [<ffffffff8020b4fb>] system_call_fastpath+0x16/0x1b
[   46.697006]   [<ffffffffffffffff>] 0xffffffffffffffff
[   46.697015] irq event stamp: 406110
[   46.697019] hardirqs last  enabled at (406109): [<ffffffff8025512c>] trace_hardirqs_on+0xd/0xf
[   46.697027] hardirqs last disabled at (406110): [<ffffffff8051d61d>] trace_hardirqs_off_thunk+0x3a/0x3c
[   46.697036] softirqs last  enabled at (406098): [<ffffffff8023892c>] __do_softirq+0xbc/0xc4
[   46.697045] softirqs last disabled at (406065): [<ffffffff8020c3ac>] call_softirq+0x1c/0x28
[   46.697053]
[   46.697054] other info that might help us debug this:
[   46.697059] 6 locks held by swapper/0:
[   46.697064]  #0:  (&serio->lock){++..}, at: [<ffffffff8043edc5>] serio_interrupt+0x2b/0x83
[   46.697080]  #1:  (&dev->event_lock){++..}, at: [<ffffffff80442b21>] input_event+0x40/0x7a
[   46.697096]  #2:  (rcu_read_lock){..--}, at: [<ffffffff80441566>] input_pass_event+0x0/0xfa
[   46.697111]  #3:  (rcu_read_lock){..--}, at: [<ffffffff804445b3>] mousedev_notify_readers+0x0/0x237
[   46.697127]  #4:  (fasync_lock){..+.}, at: [<ffffffff802a6a84>] kill_fasync+0x2c/0x4e
[   46.697142]  #5:  (&f->f_owner.lock){..+.}, at: [<ffffffff802a6976>] send_sigio+0x24/0xa1
[   46.697155]
[   46.697156] stack backtrace:
[   46.697165] Pid: 0, comm: swapper Not tainted 2.6.27-03556-gbc1d26d #15
[   46.697171] Call Trace:
[   46.697175]  <IRQ>  [<ffffffff802535d7>] print_usage_bug+0x1ad/0x1be
[   46.697188]  [<ffffffff80254737>] mark_lock_irq+0x29b/0x681
[   46.697193]  [<ffffffff80254d16>] mark_lock+0x1f9/0x2d1
[   46.697198]  [<ffffffff80254e1a>] mark_irqflags+0x2c/0xf2
[   46.697204]  [<ffffffff80256336>] __lock_acquire+0x4e8/0x634
[   46.697210]  [<ffffffff80256507>] lock_acquire+0x85/0xa9
[   46.697216]  [<ffffffff802a6991>] ? send_sigio+0x3f/0xa1
[   46.697222]  [<ffffffff8051dba7>] _read_lock+0x3e/0x4a
[   46.697227]  [<ffffffff802a6991>] ? send_sigio+0x3f/0xa1
[   46.697233]  [<ffffffff802a6991>] send_sigio+0x3f/0xa1
[   46.697238]  [<ffffffff802a6a47>] __kill_fasync+0x54/0x65
[   46.697244]  [<ffffffff802a6a92>] kill_fasync+0x3a/0x4e
[   46.697249]  [<ffffffff80444769>] mousedev_notify_readers+0x1b6/0x237
[   46.697256]  [<ffffffff804445b3>] ? mousedev_notify_readers+0x0/0x237
[   46.697261]  [<ffffffff80444cd1>] mousedev_event+0x119/0x14e
[   46.697267]  [<ffffffff804415fa>] input_pass_event+0x94/0xfa
[   46.697274]  [<ffffffff80441566>] ? input_pass_event+0x0/0xfa
[   46.697279]  [<ffffffff80441fda>] input_handle_event+0x25c/0x281
[   46.697286]  [<ffffffff80442b43>] input_event+0x62/0x7a
[   46.697293]  [<ffffffff80449eac>] psmouse_process_byte+0x257/0x267
[   46.697298]  [<ffffffff80449082>] psmouse_handle_byte+0x17/0x10d
[   46.697305]  [<ffffffff80449b30>] psmouse_interrupt+0x277/0x285
[   46.697311]  [<ffffffff8043edc5>] ? serio_interrupt+0x2b/0x83
[   46.697316]  [<ffffffff8043ede1>] serio_interrupt+0x47/0x83
[   46.697322]  [<ffffffff8043fdaa>] i8042_interrupt+0x20b/0x223
[   46.697328]  [<ffffffff80264031>] handle_IRQ_event+0x25/0x5a
[   46.697334]  [<ffffffff80265480>] handle_edge_irq+0xdd/0x123
[   46.697340]  [<ffffffff8020d1e0>] do_IRQ+0xe4/0x14c
[   46.697345]  [<ffffffff8020ba8b>] ret_from_intr+0x0/0xf
[   46.697350]  <EOI>  [<ffffffff80520909>] ? __atomic_notifier_call_chain+0x0/0xb7
[   46.697363]  [<ffffffff8021148c>] ? default_idle+0x32/0x4c
[   46.697369]  [<ffffffff8021148a>] ? default_idle+0x30/0x4c
[   46.697374]  [<ffffffff8020a1dc>] ? cpu_idle+0x4b/0x87
[   46.697494]  [<ffffffff80513771>] ? rest_init+0x75/0x77

Didn't happen on 4480f15b3306f43bbb0310d461142b4e897ca45b (Oct 12)
and fd048088306656824958e7783ffcee27e241b361 (Oct 11).
--
To unsubscribe from this list: send the line "unsubscribe linux-kernel" in
the body of a message to majordomo@xxxxxxxxxxxxxxx
More majordomo info at  http://vger.kernel.org/majordomo-info.html
Please read the FAQ at  http://www.tux.org/lkml/