From mboxrd@z Thu Jan 1 00:00:00 1970 From: Ivo van Doorn Subject: Re: lockdep rt2500usb report Date: Fri, 11 Aug 2006 19:43:22 +0200 Message-ID: <200608111943.22886.IvDoorn@gmail.com> References: <44DC4110.6080300@sipsolutions.net> Mime-Version: 1.0 Content-Type: text/plain; charset="iso-8859-1" Content-Transfer-Encoding: 7bit Cc: netdev , Jiri Benc Return-path: Received: from nf-out-0910.google.com ([64.233.182.186]:35097 "EHLO nf-out-0910.google.com") by vger.kernel.org with ESMTP id S932379AbWHKRoE (ORCPT ); Fri, 11 Aug 2006 13:44:04 -0400 Received: by nf-out-0910.google.com with SMTP id a4so980328nfc for ; Fri, 11 Aug 2006 10:43:58 -0700 (PDT) To: Johannes Berg In-Reply-To: <44DC4110.6080300@sipsolutions.net> Content-Disposition: inline Sender: netdev-owner@vger.kernel.org List-Id: netdev.vger.kernel.org On Friday 11 August 2006 10:34, Johannes Berg wrote: > 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? Mhh, looking through the trace this might be a dscape bug. The problem seem to come from the TX handler, and nothing has come out of rt2500usb yet. Jiri, do you have any ideas about this one? Ivo > [ 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: [] > dev_queue_xmit+0x52/0 x24f > [ 1807.658603] > [ 1807.658604] but task is already holding lock: > [ 1807.658606] (&dev->_xmit_lock){-+..}, at: [] > 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] [] lock_acquire+0x5c/0x79 > [ 1807.658631] [] _spin_lock_bh+0x3b/0x48 > [ 1807.658639] [] dev_activate+0x5e/0x10f > [ 1807.658646] [] dev_open+0x5c/0x73 > [ 1807.658652] [] dev_change_flags+0x51/0x107 > [ 1807.658659] [] do_setlink+0x182/0x378 > [ 1807.658665] [] rtnetlink_rcv_msg+0x163/0x214 > [ 1807.658671] [] netlink_run_queue+0x83/0x114 > [ 1807.658678] [] rtnetlink_rcv+0x2c/0x49 > [ 1807.658684] [] netlink_data_ready+0x15/0x59 > [ 1807.658691] [] netlink_sendskb+0x1f/0x36 > [ 1807.658697] [] netlink_unicast+0x190/0x1f2 > [ 1807.658703] [] netlink_sendmsg+0x1ba/0x29d > [ 1807.658709] [] sock_sendmsg+0xcf/0xf3 > [ 1807.658717] [] sys_sendmsg+0xf6/0x1fb > [ 1807.658723] [] sys_socketcall+0x232/0x253 > [ 1807.658729] [] sysenter_past_esp+0x56/0x8d > [ 1807.658737] > [ 1807.658737] -> #0 (&dev->queue_lock){-+..}: > [ 1807.658740] [] lock_acquire+0x5c/0x79 > [ 1807.658748] [] _spin_lock+0x36/0x43 > [ 1807.658754] [] dev_queue_xmit+0x52/0x24f > [ 1807.658760] [] > ieee80211_subif_start_xmit+0x299/0x49f [80211 ] > [ 1807.658777] [] dev_hard_start_xmit+0x15f/0x24c > [ 1807.658783] [] dev_queue_xmit+0x207/0x24f > [ 1807.658789] [] mld_sendpack+0x228/0x29f [ipv6] > [ 1807.658812] [] mld_ifc_timer_expire+0x217/0x260 [ipv6] > [ 1807.658829] [] run_timer_softirq+0xbf/0x1ae > [ 1807.658836] [] __do_softirq+0x50/0xc1 > [ 1807.658844] [] do_softirq+0x49/0x4b > [ 1807.658849] [] irq_exit+0x42/0x44 > [ 1807.658855] [] do_IRQ+0x3c/0x78 > [ 1807.658861] [] common_interrupt+0x25/0x2c > [ 1807.658867] [] cpu_idle+0x41/0x69 > [ 1807.658873] [] rest_init+0x39/0x3b > [ 1807.658878] [] start_kernel+0x2a6/0x31e > [ 1807.658885] [] 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: [] > dev_queue_xmit+0 x1ee/0x24f > [ 1807.658905] > [ 1807.658906] stack backtrace: > [ 1807.658908] [] show_trace+0x12/0x14 > [ 1807.658911] [] dump_stack+0x19/0x1e > [ 1807.658914] [] print_circular_bug_tail+0x5d/0x66 > [ 1807.658918] [] __lock_acquire+0xb89/0xd8a > [ 1807.658921] [] lock_acquire+0x5c/0x79 > [ 1807.658925] [] _spin_lock+0x36/0x43 > [ 1807.658928] [] dev_queue_xmit+0x52/0x24f > [ 1807.658931] [] ieee80211_subif_start_xmit+0x299/0x49f [80211] > [ 1807.658942] [] dev_hard_start_xmit+0x15f/0x24c > [ 1807.658946] [] dev_queue_xmit+0x207/0x24f > [ 1807.658949] [] mld_sendpack+0x228/0x29f [ipv6] > [ 1807.658964] [] mld_ifc_timer_expire+0x217/0x260 [ipv6] > [ 1807.658979] [] run_timer_softirq+0xbf/0x1ae > [ 1807.658982] [] __do_softirq+0x50/0xc1 > [ 1807.658986] [] do_softirq+0x49/0x4b > [ 1807.658989] [] irq_exit+0x42/0x44 > [ 1807.658992] [] do_IRQ+0x3c/0x78 > [ 1807.658995] [] common_interrupt+0x25/0x2c > [ 1807.658998] [] cpu_idle+0x41/0x69 > [ 1807.659001] [] rest_init+0x39/0x3b > [ 1807.659004] [] start_kernel+0x2a6/0x31e > [ 1807.659007] [] 0xc0100199 > >