================================= [ INFO: inconsistent lock state ] 3.0.1 #1 --------------------------------- inconsistent {SOFTIRQ-ON-W} -> {IN-SOFTIRQ-W} usage. kworker/0:1/11 [HC0[0]:SC1[5]:HE1:SE0] takes: (rose_callsign_lock){+.?...}, at: [] rose_get_neigh_callsign+0x28/0x80 [rose] {SOFTIRQ-ON-W} state was registered at: [] __lock_acquire+0x5f4/0x1620 [] lock_acquire+0xa2/0x120 [] _raw_spin_lock+0x31/0x40 [] 0xffffffffa021d068 [] do_one_initcall+0x43/0x190 [] sys_init_module+0x90/0x1f0 [] system_call_fastpath+0x16/0x1b irq event stamp: 17758 hardirqs last enabled at (17758): [] _raw_spin_unlock_irqrestore+0x40/0x70 hardirqs last disabled at (17757): [] _raw_spin_lock_irqsave+0x2e/0x70 softirqs last enabled at (17720): [] mkiss_receive_buf+0x2e2/0x3dc [mkiss] softirqs last disabled at (17721): [] call_softirq+0x1c/0x30 other info that might help us debug this: Possible unsafe locking scenario: CPU0 ---- lock(rose_callsign_lock); lock(rose_callsign_lock); *** DEADLOCK *** 5 locks held by kworker/0:1/11: #0: (events){.+.+.+}, at: [] process_one_work+0x137/0x520 #1: ((&tty->buf.work)){+.+...}, at: [] process_one_work+0x137/0x520 #2: (rcu_read_lock){.+.+..}, at: [] __netif_receive_skb+0xeb/0x6a0 #3: (rose_neigh_list_lock){+.-...}, at: [] rose_route_frame+0x79/0x6c0 [rose] #4: (rose_route_list_lock){+.-...}, at: [] rose_route_frame+0x85/0x6c0 [rose] stack backtrace: Pid: 11, comm: kworker/0:1 Not tainted 3.0.1 #1 Call Trace: [] print_usage_bug+0x225/0x270 [] mark_lock+0x323/0x3f0 [] __lock_acquire+0x57e/0x1620 [] ? __lock_acquire+0xb13/0x1620 [] lock_acquire+0xa2/0x120 [] ? rose_get_neigh_callsign+0x28/0x80 [rose] [] _raw_spin_lock+0x31/0x40 [] ? rose_get_neigh_callsign+0x28/0x80 [rose] [] ? trace_hardirqs_on_caller+0x65/0x180 [] rose_get_neigh_callsign+0x28/0x80 [rose] [] rose_send_frame+0x33/0xb0 [rose] [] ? skb_dequeue+0x66/0x90 [] rose_link_rx_restart+0x6b/0x170 [rose] [] rose_route_frame+0x171/0x6c0 [rose] [] ? lock_timer_base+0x3c/0x70 [] ? ax25_protocol_function+0x1c/0x70 [ax25] [] ? _raw_spin_unlock_irqrestore+0x40/0x70 [] ? rose_get_neigh+0x1a0/0x1a0 [rose] [] ax25_rx_iframe+0x77/0x350 [ax25] [] ax25_std_frame_in+0x8be/0x920 [ax25] [] ? ax25_find_cb+0xcc/0x120 [ax25] [] ax25_rcv+0x3aa/0x9a0 [ax25] [] ? mark_held_locks+0x6b/0xa0 [] ? _raw_spin_unlock_irqrestore+0x40/0x70 [] ? sock_def_readable+0x8a/0xb0 [] ? sock_get_timestamp+0xc0/0xc0 [] ax25_kiss_rcv+0x9f/0xb0 [ax25] [] __netif_receive_skb+0x20d/0x6a0 [] ? __netif_receive_skb+0xeb/0x6a0 [] process_backlog+0xd4/0x1e0 [] net_rx_action+0x125/0x280 [] __do_softirq+0xc6/0x210 [] call_softirq+0x1c/0x30 [] ? do_softirq+0x9d/0xd0 [] ? mkiss_receive_buf+0x2e2/0x3dc [mkiss] [] local_bh_enable_ip+0xeb/0xf0 [] _raw_spin_unlock_bh+0x34/0x40 [] mkiss_receive_buf+0x2e2/0x3dc [mkiss] [] flush_to_ldisc+0x18a/0x1a0 [] process_one_work+0x1a6/0x520 [] ? process_one_work+0x137/0x520 [] ? tty_schedule_flip+0x60/0x60 [] worker_thread+0x173/0x400 [] ? manage_workers+0x210/0x210 [] kthread+0xb6/0xc0 [] ? trace_hardirqs_on_caller+0x13d/0x180 [] kernel_thread_helper+0x4/0x10 [] ? retint_restore_args+0x13/0x13 [] ? __init_kthread_worker+0x70/0x70 [] ? gs_change+0x13/0x13 mkiss: ax1: Trying crc-flexnet mkiss: ax0: Trying crc-smack type=1305 audit(1312815573.499:96604): auid=4294967295 ses=4294967295 op="remove rule" key=(null) list=4 res=1 type=1305 audit(1312815573.499:96605): audit_enabled=0 old=1 auid=4294967295 ses=4294967295 res=1 mkiss: ax0: Trying crc-flexnet [root@f6bvp-8 bernard]#