Subject: lockdep report from bonding.

From: Dave Jones <[email-masked]>
Date: Wed, 3 Oct 2007 13:05:14 -0400

Reported by a Fedora user this morning.

Ethernet Channel Bonding Driver: v3.1.3 (June 13, 2007)
bonding: MII link monitoring set to 100 ms
ADDRCONF(NETDEV_UP): bond0: link is not ready
bonding: bond0: Adding slave eth0.
e100: eth0: e100_watchdog: link up, 100Mbps, full-duplex
bonding: bond0: making interface eth0 the new active one.
bonding: bond0: enslaving eth0 as an active interface with an up link.

=================================
[ INFO: inconsistent lock state ]
2.6.23-0.214.rc8.git2.fc8 #1
---------------------------------
inconsistent {softirq-on-W} -> {in-softirq-W} usage.
events/1/10 [HC0[0]:SC1[1]:HE1:SE0] takes:
 (&(bond_info->tx_hashtbl_lock)){-+..}, at: [<f8ad154c>] tlb_clear_slave+0x1d/0x9a [bonding]
{softirq-on-W} state was registered at:
  [<c0449fb0>] __lock_acquire+0x4ff/0xc67
  [<c044ab92>] lock_acquire+0x7b/0x9e
  [<c0633050>] _spin_lock+0x2e/0x58
  [<f8ad293a>] bond_alb_initialize+0x64/0x18e [bonding]
  [<f8acf25f>] bond_open+0x33/0x178 [bonding]
  [<c05ceb36>] dev_open+0x31/0x6c
  [<c05ccc8d>] dev_change_flags+0xa3/0x156
  [<c060d579>] devinet_ioctl+0x207/0x50e
  [<c060dc27>] inet_ioctl+0x86/0xa4
  [<c05c2e62>] sock_ioctl+0x1ac/0x1c9
  [<c04942a2>] do_ioctl+0x22/0x68
  [<c0494531>] vfs_ioctl+0x249/0x25c
  [<c049458d>] sys_ioctl+0x49/0x64
  [<c040522e>] syscall_call+0x7/0xb
  [<ffffffff>] 0xffffffff
irq event stamp: 40878
hardirqs last  enabled at (40878): [<c0633474>] _spin_unlock_irq+0x22/0x2f
hardirqs last disabled at (40877): [<c063339d>] _spin_lock_irq+0x19/0x67
softirqs last  enabled at (40872): [<c05e6fcf>] rt_run_flush+0x6e/0x97
softirqs last disabled at (40873): [<c04075d4>] do_softirq+0x74/0xf7

other info that might help us debug this:
3 locks held by events/1/10:
 #0:  (rtnl_mutex){--..}, at: [<c0631c31>] mutex_lock+0x21/0x24
 #1:  (&bond->lock){-.-+}, at: [<f8ad25ed>] bond_alb_monitor+0x16/0x26e [bonding]
 #2:  (&bond->curr_slave_lock){..-+}, at: [<f8ad2680>] bond_alb_monitor+0xa9/0x26e [bonding]

stack backtrace:
 [<c0406463>] show_trace_log_lvl+0x1a/0x2f
 [<c0406e4d>] show_trace+0x12/0x14
 [<c0406e65>] dump_stack+0x16/0x18
 [<c0448856>] print_usage_bug+0x141/0x14b
 [<c04490dc>] mark_lock+0x12f/0x472
 [<c0449f38>] __lock_acquire+0x487/0xc67
 [<c044ab92>] lock_acquire+0x7b/0x9e
 [<c0633050>] _spin_lock+0x2e/0x58
 [<f8ad154c>] tlb_clear_slave+0x1d/0x9a [bonding]
 [<f8ad269a>] bond_alb_monitor+0xc3/0x26e [bonding]
 [<c043541b>] run_timer_softirq+0x127/0x18f
 [<c0432a21>] __do_softirq+0x78/0xff
 [<c04075d4>] do_softirq+0x74/0xf7
 =======================
ADDRCONF(NETDEV_CHANGE): bond0: link becomes ready
bonding: bond0: Adding slave eth1.

-- 
http://www.codemonkey.org.uk