Hi,
> This is running wireless-dev from yesterday. All I did was plug in a
> rt2500usb device into a usb port on a freshly booted system. I have a
> feeling that this is could be one of the problems reported earlier with
> the d80211 stack, but I haven't mastered the art of picking through
> these traces yet... What's swapper doing in there?
Just received a similar bug from another user that has been using rt61pci.
My guess is still a bug in d80211, but I have no idea on how to debug this one.
Jiri, do you have any ideas?
Aug 26 00:27:57 Arjuna kernel: [ 1219.846213] rt61pci->rt61pci_config_channel:
Info - Switching channel. RF1: 0x00002ccc, RF2: 0x00004796, RF3: 0x0006b655,
RF4: 0x000ffa0b.
Aug 26 00:27:57 Arjuna kernel: [ 1219.849434] rt61pci->rt61pci_config_channel:
Info - Switching channel. RF1: 0x00002ccc, RF2: 0x00004786, RF3: 0x0006b655,
RF4: 0x000ffa0b.
Aug 26 00:27:57 Arjuna kernel: [ 1219.851772]
rt61pci->rt61pci_init_channel_time: Notice - Channel change time has been set
to 3000.
Aug 26 00:27:57 Arjuna kernel: [ 1219.858982] rt61pci->rt61pci_conf_tx: Notice
- Ignoring configuration for queue 5.
Aug 26 00:27:57 Arjuna kernel: [ 1219.879794]
Aug 26 00:27:57 Arjuna kernel: [ 1219.879797]
=======================================================
Aug 26 00:27:57 Arjuna kernel: [ 1219.879802] [ INFO: possible circular locking
dependency detected ]
Aug 26 00:27:57 Arjuna kernel: [ 1219.879805]
-------------------------------------------------------
Aug 26 00:27:57 Arjuna kernel: [ 1219.879807] ifup-eth/4014 is trying to
acquire lock:
Aug 26 00:27:57 Arjuna kernel: [ 1219.879810] (&dev->queue_lock#2){-+..}, at:
[<ffffffff8119c3e3>] dev_queue_xmit+0x10c/0x251
Aug 26 00:27:57 Arjuna kernel: [ 1219.879820]
Aug 26 00:27:57 Arjuna kernel: [ 1219.879821] but task is already holding lock:
Aug 26 00:27:57 Arjuna kernel: [ 1219.879823] (&dev->_xmit_lock){-+..}, at:
[<ffffffff8119c451>] dev_queue_xmit+0x17a/0x251
Aug 26 00:27:57 Arjuna kernel: [ 1219.879829]
Aug 26 00:27:57 Arjuna kernel: [ 1219.879830] which lock already depends on the
new lock.
Aug 26 00:27:57 Arjuna kernel: [ 1219.879831]
Aug 26 00:27:57 Arjuna kernel: [ 1219.879833]
Aug 26 00:27:57 Arjuna kernel: [ 1219.879834] the existing dependency chain (in
reverse order) is:
Aug 26 00:27:57 Arjuna kernel: [ 1219.879836]
Aug 26 00:27:57 Arjuna kernel: [ 1219.879837] -> #1 (&dev->_xmit_lock){-+..}:
Aug 26 00:27:57 Arjuna kernel: [ 1219.879840] [<ffffffff8103fb8c>]
lock_acquire+0x7a/0xa1
Aug 26 00:27:57 Arjuna kernel: [ 1219.879848] [<ffffffff811f7053>]
_spin_lock_bh+0x29/0x36
Aug 26 00:27:57 Arjuna kernel: [ 1219.879855] [<ffffffff811ab935>]
dev_activate+0x108/0x142
Aug 26 00:27:57 Arjuna kernel: [ 1219.879862] [<ffffffff8119badb>]
dev_open+0x59/0x77
Aug 26 00:27:57 Arjuna kernel: [ 1219.879867] [<ffffffff8119986c>]
dev_change_flags+0x5c/0x122
Aug 26 00:27:57 Arjuna kernel: [ 1219.879873] [<ffffffff811ddc34>]
devinet_ioctl+0x259/0x5f1
Aug 26 00:27:57 Arjuna kernel: [ 1219.879881] [<ffffffff811de287>]
inet_ioctl+0x70/0x8f
Aug 26 00:27:57 Arjuna kernel: [ 1219.879887] [<ffffffff8118fd6c>]
sock_ioctl+0x1ea/0x20c
Aug 26 00:27:57 Arjuna kernel: [ 1219.879893] [<ffffffff8108d6d6>]
do_ioctl+0x1a/0x50
Aug 26 00:27:57 Arjuna kernel: [ 1219.879900] [<ffffffff8108d939>]
vfs_ioctl+0x22d/0x240
Aug 26 00:27:57 Arjuna kernel: [ 1219.879905] [<ffffffff8108d9aa>]
sys_ioctl+0x5e/0x84
Aug 26 00:27:57 Arjuna kernel: [ 1219.879910] [<ffffffff81009641>]
system_call+0x7d/0x83
Aug 26 00:27:57 Arjuna kernel: [ 1219.879917]
Aug 26 00:27:57 Arjuna kernel: [ 1219.879918] -> #0 (&dev->queue_lock#2){-+..}:
Aug 26 00:27:57 Arjuna kernel: [ 1219.879921] [<ffffffff8103fb8c>]
lock_acquire+0x7a/0xa1
Aug 26 00:27:57 Arjuna kernel: [ 1219.879927] [<ffffffff811f701d>]
_spin_lock+0x24/0x31
Aug 26 00:27:57 Arjuna kernel: [ 1219.879933] [<ffffffff8119c3e2>]
dev_queue_xmit+0x10b/0x251
Aug 26 00:27:57 Arjuna kernel: [ 1219.879938] [<ffffffff8816771b>]
ieee80211_subif_start_xmit+0x4a3/0x4c2 [80211]
Aug 26 00:27:57 Arjuna kernel: [ 1219.879975] [<ffffffff8119a43f>]
dev_hard_start_xmit+0x1ad/0x223
Aug 26 00:27:57 Arjuna kernel: [ 1219.879981] [<ffffffff8119c46c>]
dev_queue_xmit+0x195/0x251
Aug 26 00:27:57 Arjuna kernel: [ 1219.879987] [<ffffffff8840c965>]
mld_sendpack+0x1a2/0x27c [ipv6]
Aug 26 00:27:57 Arjuna kernel: [ 1219.880018] [<ffffffff8840d711>]
mld_ifc_timer_expire+0x1e6/0x21f [ipv6]
Aug 26 00:27:57 Arjuna kernel: [ 1219.880041] [<ffffffff8102fa1c>]
run_timer_softirq+0x13a/0x1bb
Aug 26 00:27:57 Arjuna kernel: [ 1219.880049] [<ffffffff8102c75e>]
__do_softirq+0x60/0xcc
Aug 26 00:27:57 Arjuna kernel: [ 1219.880055] [<ffffffff8100a48b>]
call_softirq+0x1b/0x28
Aug 26 00:27:57 Arjuna kernel: [ 1219.880061] [<ffffffff8100b8b1>]
do_softirq+0x38/0x9f
Aug 26 00:27:57 Arjuna kernel: [ 1219.880066] [<ffffffff8102c66a>]
irq_exit+0x56/0x59
Aug 26 00:27:57 Arjuna kernel: [ 1219.880072] [<ffffffff8100ba14>]
do_IRQ+0xfc/0x108
Aug 26 00:27:57 Arjuna kernel: [ 1219.880077] [<ffffffff81009b79>]
common_interrupt+0x65/0x66
Aug 26 00:27:57 Arjuna kernel: [ 1219.880083]
Aug 26 00:27:57 Arjuna kernel: [ 1219.880084] other info that might help us
debug this:
Aug 26 00:27:57 Arjuna kernel: [ 1219.880086]
Aug 26 00:27:57 Arjuna kernel: [ 1219.880088] 3 locks held by ifup-eth/4014:
Aug 26 00:27:57 Arjuna kernel: [ 1219.880090] #0: (dcache_lock){--..}, at:
[<ffffffff810ea6b1>] _atomic_dec_and_lock+0x15/0x34
Aug 26 00:27:57 Arjuna kernel: [ 1219.880098] #1: (&dentry->d_lock){--..},
at: [<ffffffff81092198>] dput+0x4d/0x18e
Aug 26 00:27:57 Arjuna kernel: [ 1219.880104] #2: (&dev->_xmit_lock){-+..},
at: [<ffffffff8119c451>] dev_queue_xmit+0x17a/0x251
Aug 26 00:27:57 Arjuna kernel: [ 1219.880110]
Aug 26 00:27:57 Arjuna kernel: [ 1219.880110] stack backtrace:
Aug 26 00:27:57 Arjuna kernel: [ 1219.880112]
Aug 26 00:27:57 Arjuna kernel: [ 1219.880113] Call Trace:
Aug 26 00:27:57 Arjuna kernel: [ 1219.880214] [<ffffffff8100a7a4>]
show_trace+0xa0/0x2d6
Aug 26 00:27:57 Arjuna kernel: [ 1219.880227] [<ffffffff8100a9ef>]
dump_stack+0x15/0x17
Aug 26 00:27:57 Arjuna kernel: [ 1219.880239] [<ffffffff8103e310>]
print_circular_bug_tail+0x6c/0x77
Aug 26 00:27:57 Arjuna kernel: [ 1219.880316] [<ffffffff8103f943>]
__lock_acquire+0x866/0xa35
Aug 26 00:27:57 Arjuna kernel: [ 1219.880393] [<ffffffff8103fb8d>]
lock_acquire+0x7b/0xa1
Aug 26 00:27:57 Arjuna kernel: [ 1219.880471] [<ffffffff811f701e>]
_spin_lock+0x25/0x31
Aug 26 00:27:57 Arjuna kernel: [ 1219.880552] [<ffffffff8119c3e3>]
dev_queue_xmit+0x10c/0x251
Aug 26 00:27:57 Arjuna kernel: [ 1219.880977] [<ffffffff8816771c>]
:80211:ieee80211_subif_start_xmit+0x4a4/0x4c2
Aug 26 00:27:57 Arjuna kernel: [ 1219.880989] [<ffffffff8119a440>]
dev_hard_start_xmit+0x1ae/0x223
Aug 26 00:27:57 Arjuna kernel: [ 1219.881382] [<ffffffff8119c46d>]
dev_queue_xmit+0x196/0x251
Aug 26 00:27:57 Arjuna kernel: [ 1219.881794] [<ffffffff8840c966>]
:ipv6:mld_sendpack+0x1a3/0x27c
Aug 26 00:27:57 Arjuna kernel: [ 1219.881838] [<ffffffff8840d712>]
:ipv6:mld_ifc_timer_expire+0x1e7/0x21f
Aug 26 00:27:57 Arjuna kernel: [ 1219.881860] [<ffffffff8102fa1d>]
run_timer_softirq+0x13b/0x1bb
Aug 26 00:27:57 Arjuna kernel: [ 1219.881921] [<ffffffff8102c75f>]
__do_softirq+0x61/0xcc
Aug 26 00:27:57 Arjuna kernel: [ 1219.881974] [<ffffffff8100a48c>]
call_softirq+0x1c/0x28
Aug 26 00:27:57 Arjuna kernel: [ 1219.881985] DWARF2 unwinder stuck at
call_softirq+0x1c/0x28
Aug 26 00:27:57 Arjuna kernel: [ 1219.881987] Leftover inexact backtrace:
Aug 26 00:27:58 Arjuna kernel: [ 1219.881989] <IRQ> [<ffffffff8100b8b2>]
do_softirq+0x39/0x9f
Aug 26 00:27:58 Arjuna kernel: [ 1219.882007] [<ffffffff8102c66b>]
irq_exit+0x57/0x59
Aug 26 00:27:58 Arjuna kernel: [ 1219.882011] [<ffffffff8100ba15>]
do_IRQ+0xfd/0x108
Aug 26 00:27:58 Arjuna kernel: [ 1219.882016] [<ffffffff81092198>]
dput+0x4d/0x18e
Aug 26 00:27:58 Arjuna kernel: [ 1219.882020] [<ffffffff81009b7a>]
ret_from_intr+0x0/0xf
Aug 26 00:27:58 Arjuna kernel: [ 1219.882022] <EOI> [<ffffffff8103fba2>]
lock_acquire+0x90/0xa1
Aug 26 00:27:58 Arjuna kernel: [ 1219.882040] [<ffffffff81092198>]
dput+0x4d/0x18e
Aug 26 00:27:58 Arjuna kernel: [ 1219.882051] [<ffffffff811f701e>]
_spin_lock+0x25/0x31
Aug 26 00:27:58 Arjuna kernel: [ 1219.882056] [<ffffffff81092198>]
dput+0x4d/0x18e
Aug 26 00:27:58 Arjuna kernel: [ 1219.882062] [<ffffffff8108aee5>]
__link_path_walk+0xda2/0xf67
Aug 26 00:27:58 Arjuna kernel: [ 1219.882075] [<ffffffff8108b112>]
link_path_walk+0x68/0xfd
Aug 26 00:27:58 Arjuna kernel: [ 1219.882087] [<ffffffff8107be1a>]
vfs_read+0x13d/0x172
Aug 26 00:27:58 Arjuna kernel: [ 1219.882099] [<ffffffff8108b595>]
do_path_lookup+0x275/0x2f2
Aug 26 00:27:58 Arjuna kernel: [ 1219.882109] [<ffffffff8108c102>]
__path_lookup_intent_open+0x5c/0x9f
Aug 26 00:27:58 Arjuna kernel: [ 1219.882117] [<ffffffff810ab1dc>]
load_elf_binary+0x0/0x1a66
Aug 26 00:27:58 Arjuna kernel: [ 1219.882122] [<ffffffff8108c1b2>]
path_lookup_open+0xc/0xe
Aug 26 00:27:58 Arjuna kernel: [ 1219.882127] [<ffffffff810852f3>]
open_exec+0x32/0xe9
Aug 26 00:27:58 Arjuna kernel: [ 1219.882138] [<ffffffff8107be1a>]
vfs_read+0x13d/0x172
Aug 26 00:27:58 Arjuna kernel: [ 1219.882145] [<ffffffff810851aa>]
kernel_read+0x43/0x5a
Aug 26 00:27:58 Arjuna kernel: [ 1219.882152] [<ffffffff810ab46d>]
load_elf_binary+0x291/0x1a66
Aug 26 00:27:58 Arjuna kernel: [ 1219.882163] [<ffffffff8835f989>]
:binfmt_misc:load_misc_binary+0x360/0x375
Aug 26 00:27:58 Arjuna kernel: [ 1219.882170] [<ffffffff8105eff1>]
__alloc_pages+0x74/0x2ba
Aug 26 00:27:58 Arjuna kernel: [ 1219.882176] [<ffffffff81084fe6>]
search_binary_handler+0x149/0x280
Aug 26 00:27:58 Arjuna kernel: [ 1219.882182] [<ffffffff81084f4d>]
search_binary_handler+0xb0/0x280
Aug 26 00:27:58 Arjuna kernel: [ 1219.882191] [<ffffffff810ab1dc>]
load_elf_binary+0x0/0x1a66
Aug 26 00:27:58 Arjuna kernel: [ 1219.882197] [<ffffffff810ab1dc>]
load_elf_binary+0x0/0x1a66
Aug 26 00:27:58 Arjuna kernel: [ 1219.882202] [<ffffffff81084f57>]
search_binary_handler+0xba/0x280
Aug 26 00:27:58 Arjuna kernel: [ 1219.882211] [<ffffffff81086b76>]
do_execve+0x187/0x23d
Aug 26 00:27:58 Arjuna kernel: [ 1219.882219] [<ffffffff81008377>]
sys_execve+0x36/0x8b
Aug 26 00:27:58 Arjuna kernel: [ 1219.882226] [<ffffffff81009a33>]
stub_execve+0x67/0xb0
Aug 26 00:27:58 Arjuna kernel: [ 1219.882242]
Aug 26 00:29:27 Arjuna kernel: [ 1309.371392] rt61pci->rt61pci_config_channel:
Info - Switching channel. RF1: 0x00002ccc, RF2: 0x00004796, RF3: 0x0006b655,
RF4: 0x000ffa0b.
Aug 26 00:29:27 Arjuna kernel: [ 1309.376699] wlan0: cannot create symlink to
default key
Aug 26 00:29:27 Arjuna kernel: [ 1309.378033] rt61pci->rt61pci_conf_tx: Notice
- Ignoring configuration for queue 5.
Aug 26 00:40:25 Arjuna kernel: 0060/serio1/input0, Type: 2, Code: 0, Value: 8
> [ 1806.889513] usb 5-2: new high speed USB device using ehci_hcd and
> address 2
> [ 1807.164838] usb 5-2: configuration #1 chosen from 1 choice
> [ 1807.252880] Loading module: rt2500usb - CVS (N/A) by
> http://rt2x00.serialmonkey.com.
> [ 1807.338966] wmaster0: Selected rate control algorithm 'simple'
> [ 1807.364971] usbcore: registered new driver rt2500usb
> [ 1807.658580]
> [ 1807.658582] =======================================================
> [ 1807.658586] [ INFO: possible circular locking dependency detected ]
> [ 1807.658588] -------------------------------------------------------
> [ 1807.658591] swapper/0 is trying to acquire lock:
> [ 1807.658593] (&dev->queue_lock){-+..}, at: [<c0297518>]
> dev_queue_xmit+0x52/0 x24f
> [ 1807.658603]
> [ 1807.658604] but task is already holding lock:
> [ 1807.658606] (&dev->_xmit_lock){-+..}, at: [<c02976b4>]
> dev_queue_xmit+0x1ee/ 0x24f
> [ 1807.658611]
> [ 1807.658612] which lock already depends on the new lock.
> [ 1807.658613]
> [ 1807.658615]
> [ 1807.658616] the existing dependency chain (in reverse order) is:
> [ 1807.658618]
> [ 1807.658619] -> #1 (&dev->_xmit_lock){-+..}:
> [ 1807.658622] [<c01322b7>] lock_acquire+0x5c/0x79
> [ 1807.658631] [<c02f5560>] _spin_lock_bh+0x3b/0x48
> [ 1807.658639] [<c02a3231>] dev_activate+0x5e/0x10f
> [ 1807.658646] [<c0295c89>] dev_open+0x5c/0x73
> [ 1807.658652] [<c029538f>] dev_change_flags+0x51/0x107
> [ 1807.658659] [<c029e60a>] do_setlink+0x182/0x378
> [ 1807.658665] [<c029dac2>] rtnetlink_rcv_msg+0x163/0x214
> [ 1807.658671] [<c02a5b9e>] netlink_run_queue+0x83/0x114
> [ 1807.658678] [<c029d833>] rtnetlink_rcv+0x2c/0x49
> [ 1807.658684] [<c02a5c44>] netlink_data_ready+0x15/0x59
> [ 1807.658691] [<c02a3893>] netlink_sendskb+0x1f/0x36
> [ 1807.658697] [<c02a5673>] netlink_unicast+0x190/0x1f2
> [ 1807.658703] [<c02a588f>] netlink_sendmsg+0x1ba/0x29d
> [ 1807.658709] [<c028cb46>] sock_sendmsg+0xcf/0xf3
> [ 1807.658717] [<c028cc60>] sys_sendmsg+0xf6/0x1fb
> [ 1807.658723] [<c028d4af>] sys_socketcall+0x232/0x253
> [ 1807.658729] [<c0102ead>] sysenter_past_esp+0x56/0x8d
> [ 1807.658737]
> [ 1807.658737] -> #0 (&dev->queue_lock){-+..}:
> [ 1807.658740] [<c01322b7>] lock_acquire+0x5c/0x79
> [ 1807.658748] [<c02f5763>] _spin_lock+0x36/0x43
> [ 1807.658754] [<c0297518>] dev_queue_xmit+0x52/0x24f
> [ 1807.658760] [<e2cb2aff>]
> ieee80211_subif_start_xmit+0x299/0x49f [80211 ]
> [ 1807.658777] [<c02973d9>] dev_hard_start_xmit+0x15f/0x24c
> [ 1807.658783] [<c02976cd>] dev_queue_xmit+0x207/0x24f
> [ 1807.658789] [<e351b8a4>] mld_sendpack+0x228/0x29f [ipv6]
> [ 1807.658812] [<e351bf49>] mld_ifc_timer_expire+0x217/0x260 [ipv6]
> [ 1807.658829] [<c0121a6d>] run_timer_softirq+0xbf/0x1ae
> [ 1807.658836] [<c011e30c>] __do_softirq+0x50/0xc1
> [ 1807.658844] [<c011e3c6>] do_softirq+0x49/0x4b
> [ 1807.658849] [<c011e50f>] irq_exit+0x42/0x44
> [ 1807.658855] [<c01055d4>] do_IRQ+0x3c/0x78
> [ 1807.658861] [<c01039cd>] common_interrupt+0x25/0x2c
> [ 1807.658867] [<c0101c27>] cpu_idle+0x41/0x69
> [ 1807.658873] [<c0100295>] rest_init+0x39/0x3b
> [ 1807.658878] [<c03ee718>] start_kernel+0x2a6/0x31e
> [ 1807.658885] [<c0100199>] 0xc0100199
> [ 1807.658894]
> [ 1807.658894] other info that might help us debug this:
> [ 1807.658895]
> [ 1807.658898] 1 lock held by swapper/0:
> [ 1807.658899] #0: (&dev->_xmit_lock){-+..}, at: [<c02976b4>]
> dev_queue_xmit+0 x1ee/0x24f
> [ 1807.658905]
> [ 1807.658906] stack backtrace:
> [ 1807.658908] [<c01053a0>] show_trace+0x12/0x14
> [ 1807.658911] [<c01053bb>] dump_stack+0x19/0x1e
> [ 1807.658914] [<c01300f4>] print_circular_bug_tail+0x5d/0x66
> [ 1807.658918] [<c0131d9c>] __lock_acquire+0xb89/0xd8a
> [ 1807.658921] [<c01322b7>] lock_acquire+0x5c/0x79
> [ 1807.658925] [<c02f5763>] _spin_lock+0x36/0x43
> [ 1807.658928] [<c0297518>] dev_queue_xmit+0x52/0x24f
> [ 1807.658931] [<e2cb2aff>] ieee80211_subif_start_xmit+0x299/0x49f [80211]
> [ 1807.658942] [<c02973d9>] dev_hard_start_xmit+0x15f/0x24c
> [ 1807.658946] [<c02976cd>] dev_queue_xmit+0x207/0x24f
> [ 1807.658949] [<e351b8a4>] mld_sendpack+0x228/0x29f [ipv6]
> [ 1807.658964] [<e351bf49>] mld_ifc_timer_expire+0x217/0x260 [ipv6]
> [ 1807.658979] [<c0121a6d>] run_timer_softirq+0xbf/0x1ae
> [ 1807.658982] [<c011e30c>] __do_softirq+0x50/0xc1
> [ 1807.658986] [<c011e3c6>] do_softirq+0x49/0x4b
> [ 1807.658989] [<c011e50f>] irq_exit+0x42/0x44
> [ 1807.658992] [<c01055d4>] do_IRQ+0x3c/0x78
> [ 1807.658995] [<c01039cd>] common_interrupt+0x25/0x2c
> [ 1807.658998] [<c0101c27>] cpu_idle+0x41/0x69
> [ 1807.659001] [<c0100295>] rest_init+0x39/0x3b
> [ 1807.659004] [<c03ee718>] start_kernel+0x2a6/0x31e
> [ 1807.659007] [<c0100199>] 0xc0100199
>
>
-
To unsubscribe from this list: send the line "unsubscribe netdev" in
the body of a message to [EMAIL PROTECTED]
More majordomo info at http://vger.kernel.org/majordomo-info.html
