From mboxrd@z Thu Jan 1 00:00:00 1970 Return-path: Received: from mail.deathmatch.net ([72.66.92.28]:3623 "EHLO mail.deathmatch.net" rhost-flags-OK-OK-OK-OK) by vger.kernel.org with ESMTP id S1751243AbZFSLbK (ORCPT ); Fri, 19 Jun 2009 07:31:10 -0400 Date: Fri, 19 Jun 2009 07:30:49 -0400 From: Bob Copeland To: linux-wireless@vger.kernel.org Cc: ath5k-devel@lists.ath5k.org Subject: lockdep spew Message-ID: <20090619113049.GB18956@hash.localnet> MIME-Version: 1.0 Content-Type: text/plain; charset=us-ascii Sender: linux-wireless-owner@vger.kernel.org List-ID: I get the following in latest wireless-testing, by starting hostapd, then turning on network manager which then tries to act like a station. Quite possibly a driver bug but I haven't had a chance to look at it in depth yet. [ 959.515213] phy0: device now idle [ 960.122130] phy0: device no longer idle - in use [ 960.161448] phy0: device now idle [ 960.163728] PM: Adding info for No Bus:mon.wlan0 [ 960.199042] phy0: device no longer idle - in use [ 960.551906] phy0: Allocated STA 00:18:41:c8:58:43 [ 960.552865] phy0: Inserted STA 00:18:41:c8:58:43 [ 966.937833] [ 966.937836] ====================================================== [ 966.937855] [ INFO: SOFTIRQ-safe -> SOFTIRQ-unsafe lock order detected ] [ 966.937863] 2.6.30-wl #136 [ 966.937869] ------------------------------------------------------ [ 966.937877] wpa_supplicant/2889 [HC0[0]:SC0[0]:HE0:SE1] is trying to acquire: [ 966.937884] (&local->key_lock){......}, at: [] ieee80211_key_free+0x41/0x60 [mac80211] [ 966.937935] [ 966.937936] and this task is already holding: [ 966.937954] (&local->sta_lock){..-...}, at: [] sta_info_flush+0x39/0xbc [mac80211] [ 966.938016] which would create a new lock dependency: [ 966.938032] (&local->sta_lock){..-...} -> (&local->key_lock){......} [ 966.938108] [ 966.938109] but this new dependency connects a SOFTIRQ-irq-safe lock: [ 966.938129] (&local->sta_lock){..-...} [ 966.938151] ... which became SOFTIRQ-irq-safe at: [ 966.938171] [] __lock_acquire+0x548/0x12ab [ 966.938199] [] lock_acquire+0xac/0xc9 [ 966.938221] [] _spin_lock_irqsave+0x36/0x69 [ 966.938256] [] ieee80211_beacon_get+0xfe/0x1f4 [mac80211] [ 966.938259] [] ath5k_beacon_update+0x32/0x307 [ath5k] [ 966.938259] [] ath5k_beacon_send+0x22c/0x2b8 [ath5k] [ 966.938259] [] ath5k_tasklet_beacon+0x94/0xa3 [ath5k] [ 966.938259] [] tasklet_hi_action+0x93/0xe6 [ 966.938259] [] __do_softirq+0xb4/0x184 [ 966.938259] [] do_softirq+0x30/0x48 [ 966.938259] [] irq_exit+0x3d/0x74 [ 966.938259] [] do_IRQ+0x75/0x89 [ 966.938259] [] common_interrupt+0x2e/0x34 [ 966.938259] [] cpuidle_idle_call+0x6a/0xa0 [ 966.938259] [] cpu_idle+0x53/0x85 [ 966.938259] [] rest_init+0x6c/0x6e [ 966.938259] [] start_kernel+0x281/0x286 [ 966.938259] [] __init_begin+0x38/0x40 [ 966.938259] [] 0xffffffff [ 966.938259] [ 966.938259] to a SOFTIRQ-irq-unsafe lock: [ 966.938259] (todo_lock){+.+...} [ 966.938259] ... which became SOFTIRQ-irq-unsafe at: [ 966.938259] ... [] __lock_acquire+0x5bd/0x12ab [ 966.938259] [] lock_acquire+0xac/0xc9 [ 966.938259] [] _spin_lock+0x2d/0x5d [ 966.938259] [] __ieee80211_key_todo+0x1c5/0x1e9 [mac80211] [ 966.938259] [] ieee80211_key_todo+0x19/0x25 [mac80211] [ 966.938259] [] ieee80211_todo_for_each_key+0x72/0x79 [mac80211] [ 966.938259] [] ieee80211_enable_keys+0x56/0x5b [mac80211] [ 966.938259] [] ieee80211_open+0x44e/0x5a3 [mac80211] [ 966.938259] [] dev_open+0x8b/0xc0 [ 966.938259] [] dev_change_flags+0x9b/0x14c [ 966.938259] [] do_setlink+0x2a2/0x348 [ 966.938259] [] rtnl_setlink+0xe5/0xe9 [ 966.938259] [] rtnetlink_rcv_msg+0x18a/0x1a0 [ 966.938259] [] netlink_rcv_skb+0x35/0x7b [ 966.938259] [] rtnetlink_rcv+0x20/0x27 [ 966.938259] [] netlink_unicast+0x1a9/0x20a [ 966.938259] [] netlink_sendmsg+0x223/0x230 [ 966.938259] [] sock_sendmsg+0xcc/0xe2 [ 966.938259] [] sys_sendmsg+0x14e/0x19b [ 966.938259] [] sys_socketcall+0x13f/0x168 [ 966.938259] [] sysenter_do_call+0x12/0x36 [ 966.938259] [] 0xffffffff [ 966.938259] [ 966.938259] other info that might help us debug this: [ 966.938259] [ 966.938259] 2 locks held by wpa_supplicant/2889: [ 966.938259] #0: (rtnl_mutex){+.+.+.}, at: [] rtnl_lock+0x14/0x16 [ 966.938259] #1: (&local->sta_lock){..-...}, at: [] sta_info_flush+0x39/0xbc [mac80211] [ 966.938259] [ 966.938259] the SOFTIRQ-irq-safe lock's dependencies: [ 966.938259] -> (&local->sta_lock){..-...} ops: 0 { [ 966.938259] IN-SOFTIRQ-W at: [ 966.938259] [] __lock_acquire+0x548/0x12ab [ 966.938259] [] lock_acquire+0xac/0xc9 [ 966.938259] [] _spin_lock_irqsave+0x36/0x69 [ 966.938259] [] ieee80211_beacon_get+0xfe/0x1f4 [mac80211] [ 966.938259] [] ath5k_beacon_update+0x32/0x307 [ath5k] [ 966.938259] [] ath5k_beacon_send+0x22c/0x2b8 [ath5k] [ 966.938259] [] ath5k_tasklet_beacon+0x94/0xa3 [ath5k] [ 966.938259] [] tasklet_hi_action+0x93/0xe6 [ 966.938259] [] __do_softirq+0xb4/0x184 [ 966.938259] [] do_softirq+0x30/0x48 [ 966.938259] [] irq_exit+0x3d/0x74 [ 966.938259] [] do_IRQ+0x75/0x89 [ 966.938259] [] common_interrupt+0x2e/0x34 [ 966.938259] [] cpuidle_idle_call+0x6a/0xa0 [ 966.938259] [] cpu_idle+0x53/0x85 [ 966.938259] [] rest_init+0x6c/0x6e [ 966.938259] [] start_kernel+0x281/0x286 [ 966.938259] [] __init_begin+0x38/0x40 [ 966.938259] [] 0xffffffff [ 966.938259] INITIAL USE at: [ 966.938259] [] __lock_acquire+0x60e/0x12ab [ 966.938259] [] lock_acquire+0xac/0xc9 [ 966.938259] [] sta_info_flush+0x39/0xbc [mac80211] [ 966.938259] [] ieee80211_stop+0xe3/0x460 [mac80211] [ 966.938259] [] dev_close+0x72/0x8e [ 966.938259] [] dev_change_flags+0x9b/0x14c [ 966.938259] [] devinet_ioctl+0x214/0x4f3 [ 966.938259] [] inet_ioctl+0x93/0xac [ 966.938259] [] sock_ioctl+0x1ce/0x1f2 [ 966.938259] [] vfs_ioctl+0x1d/0x76 [ 966.938259] [] do_vfs_ioctl+0x479/0x4b3 [ 966.938259] [] sys_ioctl+0x33/0x4d [ 966.938259] [] sysenter_do_call+0x12/0x36 [ 966.938259] [] 0xffffffff [ 966.938259] } [] __key.37782+0x0/0xffff1039 [mac80211] [ 966.938259] -> (&sta->flaglock){..-...} ops: 0 { [ 966.938259] IN-SOFTIRQ-W at: [ 966.938259] [] __lock_acquire+0x548/0x12ab [ 966.938259] [] _spin_lock_irqsave+0x36/0x69 [] ieee80211_invoke_rx_handlers+0x111/0x11cb [mac80211] [ 966.938259] [] __ieee80211_rx_handle_packet+0x549/0x576 [mac80211] [ 966.938259] [] __ieee80211_rx+0x4d5/0x556 [mac80211] [ 966.938259] [] ath5k_tasklet_rx+0x50a/0x55d [ath5k] [ 966.938259] [] tasklet_action+0x93/0xe6 [ 966.938259] [] __do_softirq+0xb4/0x184 [ 966.938259] [] do_softirq+0x30/0x48 [ 966.938259] [] irq_exit+0x3d/0x74 [ 966.938259] [] do_IRQ+0x75/0x89 [ 966.938259] [] common_interrupt+0x2e/0x34 [ 966.938259] [] cpuidle_idle_call+0x6a/0xa0 [ 966.938259] [] cpu_idle+0x53/0x85 [ 966.938259] [] rest_init+0x6c/0x6e [ 966.938259] [] start_kernel+0x281/0x286 [ 966.938259] [] __init_begin+0x38/0x40 [ 966.938259] [] 0xffffffff [ 966.938259] [] __lock_acquire+0x60e/0x12ab [ 966.938259] [] lock_acquire+0xac/0xc9 [ 966.938259] [] _spin_lock_irqsave+0x36/0x69 [ 966.938259] [] ieee80211_tx_prepare+0x238/0x29d [mac80211] [ 966.938259] [] ieee80211_tx+0x60/0x248 [mac80211] [ 966.938259] [] ieee80211_xmit+0x281/0x293 [mac80211] [ 966.938259] [] ieee80211_monitor_start_xmit+0x82/0x91 [mac80211] [ 966.938259] [] dev_hard_start_xmit+0x256/0x2b5 [ 966.938259] [] __qdisc_run+0xc5/0x1b0 [ 966.938259] [] dev_queue_xmit+0x245/0x367 [ 966.938259] [] packet_sendmsg+0x1c2/0x207 [af_packet] [ 966.938259] [] sock_sendmsg+0xcc/0xe2 [ 966.938259] [] sys_sendmsg+0x14e/0x19b [ 966.938259] [] sys_socketcall+0x13f/0x168 [ 966.938259] [] sysenter_do_call+0x12/0x36 [ 966.938259] [] 0xffffffff [ 966.938259] } [ 966.938259] ... key at: [] __key.37322+0x0/0xffff1031 [mac80211] [ 966.938259] ... acquired at: [ 966.938259] [] __lock_acquire+0xfc8/0x12ab [ 966.938259] [] lock_acquire+0xac/0xc9 [ 966.938259] [] _spin_lock_irqsave+0x36/0x69 [ 966.938259] [] __sta_info_unlink+0x97/0x162 [mac80211] [ 966.938259] [] sta_info_unlink+0x28/0x36 [mac80211] [ 966.938259] [] ieee80211_del_station+0xa0/0xf0 [mac80211] [ 966.938259] [] nl80211_del_station+0x71/0x93 [cfg80211] [ 966.938259] [] genl_rcv_msg+0x18c/0x1a6 [ 966.938259] [] netlink_rcv_skb+0x35/0x7b [ 966.938259] [] genl_rcv+0x23/0x30 [ 966.938259] [] netlink_unicast+0x1a9/0x20a [ 966.938259] [] netlink_sendmsg+0x223/0x230 [ 966.938259] [] sock_sendmsg+0xcc/0xe2 [ 966.938259] [] sys_sendmsg+0x14e/0x19b [ 966.938259] [] sys_socketcall+0x13f/0x168 [ 966.938259] [] sysenter_do_call+0x12/0x36 [ 966.938259] [] 0xffffffff [ 966.938259] [ 966.938259] [ 966.938259] the SOFTIRQ-irq-unsafe lock's dependencies: [ 966.938259] -> (todo_lock){+.+...} ops: 0 { [ 966.938259] HARDIRQ-ON-W at: [ 966.938259] [] __lock_acquire+0x59e/0x12ab [ 966.938259] [ 966.938259] [] _spin_lock+0x2d/0x5d [] __ieee80211_key_todo+0x1c5/0x1e9 [mac80211] [ 966.938259] [] ieee80211_key_todo+0x19/0x25 [mac80211] [ 966.938259] [] ieee80211_todo_for_each_key+0x72/0x79 [mac80211] [ 966.938259] [] ieee80211_enable_keys+0x56/0x5b [mac80211] [ 966.938259] [] ieee80211_open+0x44e/0x5a3 [mac80211] [ 966.938259] [] dev_open+0x8b/0xc0 [ 966.938259] [] dev_change_flags+0x9b/0x14c [ 966.938259] [] do_setlink+0x2a2/0x348 [ 966.938259] [] rtnl_setlink+0xe5/0xe9 [ 966.938259] [] rtnetlink_rcv_msg+0x18a/0x1a0 [ 966.938259] [] netlink_rcv_skb+0x35/0x7b [ 966.938259] [] rtnetlink_rcv+0x20/0x27 [ 966.938259] [] netlink_unicast+0x1a9/0x20a [ 966.938259] [] netlink_sendmsg+0x223/0x230 [ 966.938259] [] sock_sendmsg+0xcc/0xe2 [ 966.938259] [] sys_sendmsg+0x14e/0x19b [ 966.938259] [] sys_socketcall+0x13f/0x168 [ 966.938259] [] sysenter_do_call+0x12/0x36 [ 966.938259] [] 0xffffffff [ 966.938259] SOFTIRQ-ON-W at: [ 966.938259] [] __lock_acquire+0x5bd/0x12ab [ 966.938259] [] lock_acquire+0xac/0xc9 [ 966.938259] [] __ieee80211_key_todo+0x1c5/0x1e9 [mac80211] [ 966.938259] [] ieee80211_key_todo+0x19/0x25 [mac80211] [ 966.938259] [] ieee80211_todo_for_each_key+0x72/0x79 [mac80211] [ 966.938259] [] ieee80211_enable_keys+0x56/0x5b [mac80211] [ 966.938259] [] ieee80211_open+0x44e/0x5a3 [mac80211] [ 966.938259] [] dev_open+0x8b/0xc0 [ 966.938259] [] dev_change_flags+0x9b/0x14c [ 966.938259] [] do_setlink+0x2a2/0x348 [ 966.938259] [] rtnl_setlink+0xe5/0xe9 [ 966.938259] [] rtnetlink_rcv_msg+0x18a/0x1a0 [ 966.938259] [] netlink_rcv_skb+0x35/0x7b [ 966.938259] [] rtnetlink_rcv+0x20/0x27 [ 966.938259] [] netlink_unicast+0x1a9/0x20a [ 966.938259] [] netlink_sendmsg+0x223/0x230 [ 966.938259] [] sock_sendmsg+0xcc/0xe2 [ 966.938259] [] sys_sendmsg+0x14e/0x19b [ 966.938259] [] sys_socketcall+0x13f/0x168 [ 966.938259] [] sysenter_do_call+0x12/0x36 [ 966.938259] [] 0xffffffff [ 966.938259] INITIAL USE at: [ 966.938259] [] __lock_acquire+0x60e/0x12ab [ 966.938259] [ 966.938259] [] _spin_lock+0x2d/0x5d [] __ieee80211_key_todo+0x1c5/0x1e9 [mac80211] [ 966.938259] [] ieee80211_key_todo+0x19/0x25 [mac80211] [ 966.938259] [] ieee80211_todo_for_each_key+0x72/0x79 [mac80211] [ 966.938259] [] ieee80211_enable_keys+0x56/0x5b [mac80211] [ 966.938259] [] ieee80211_open+0x44e/0x5a3 [mac80211] [ 966.938259] [] dev_open+0x8b/0xc0 [ 966.938259] [] dev_change_flags+0x9b/0x14c [ 966.938259] [] do_setlink+0x2a2/0x348 [ 966.938259] [] rtnl_setlink+0xe5/0xe9 [ 966.938259] [] rtnetlink_rcv_msg+0x18a/0x1a0 [ 966.938259] [] netlink_rcv_skb+0x35/0x7b [ 966.938259] [] rtnetlink_rcv+0x20/0x27 [ 966.938259] [] netlink_unicast+0x1a9/0x20a [ 966.938259] [] netlink_sendmsg+0x223/0x230 [ 966.938259] [] sock_sendmsg+0xcc/0xe2 [ 966.938259] [] sys_sendmsg+0x14e/0x19b [ 966.938259] [] sys_socketcall+0x13f/0x168 [ 966.938259] [] sysenter_do_call+0x12/0x36 [] 0xffffffff [ 966.938259] } [ 966.938259] ... key at: [] todo_lock+0x10/0xffff12fd [mac80211] [ 966.938259] -> (&cwq->lock){-.-...} ops: 0 { [ 966.938259] IN-HARDIRQ-W at: [ 966.938259] [] __lock_acquire+0x529/0x12ab [ 966.938259] [] lock_acquire+0xac/0xc9 [ 966.938259] [] _spin_lock_irqsave+0x36/0x69 [ 966.938259] [] __queue_work+0x17/0x34 [ 966.938259] [] queue_work_on+0x40/0x49 [ 966.938259] [] queue_work+0x26/0x4a [ 966.938259] [] __acpi_os_execute+0xe0/0x137 [ 966.938259] [] acpi_os_execute+0xf/0x11 [ 966.938259] [] acpi_ec_gpe_handler+0x6f/0xa3 [ 966.938259] [] acpi_ev_gpe_dispatch+0x51/0x10f [ 966.938259] [] acpi_ev_gpe_detect+0x8c/0xce [ 966.938259] [] acpi_ev_sci_xrupt_handler+0x1a/0x20 [ 966.938259] [] acpi_irq+0x13/0x2e [ 966.938259] [] handle_IRQ_event+0x6a/0x12f [ 966.938259] [] handle_fasteoi_irq+0x7e/0xb5 [ 966.938259] [] handle_irq+0x40/0x4b [ 966.938259] [] do_IRQ+0x49/0x89 [ 966.938259] [] common_interrupt+0x2e/0x34 [ 966.938259] [] cpu_idle+0x53/0x85 [ 966.938259] [] rest_init+0x6c/0x6e [ 966.938259] [] start_kernel+0x281/0x286 [ 966.938259] [] __init_begin+0x38/0x40 [ 966.938259] [] 0xffffffff [ 966.938259] IN-SOFTIRQ-W at: [ 966.938259] [] __lock_acquire+0x548/0x12ab [ 966.938259] [] lock_acquire+0xac/0xc9 [ 966.938259] [] _spin_lock_irqsave+0x36/0x69 [ 966.938259] [] __queue_work+0x17/0x34 [ 966.938259] [] queue_work_on+0x40/0x49 [ 966.938259] [] queue_work+0x26/0x4a [ 966.938259] [] kblockd_schedule_work+0x12/0x14 [ 966.938259] [] as_antic_timeout+0x58/0xa4 [ 966.938259] [] run_timer_softirq+0x177/0x1ec [ 966.938259] [] irq_exit+0x3d/0x74 [] apic_timer_interrupt+0x2f/0x34 [ 966.938259] [ 966.938259] [] __lock_acquire+0x60e/0x12ab [ 966.938259] [] _spin_lock_irqsave+0x36/0x69 [ 966.938259] [] queue_work+0x26/0x4a [ 966.938259] [ 966.938259] [ 966.938259] [] bus_register+0xe4/0x230 [] driver_init+0x1c/0x28 [ 966.938259] [ 966.938259] [] 0xffffffff ops: 0[ 966.938259] [] lock_acquire+0xac/0xc9 [ 966.938259] [ 966.938259] [ 966.938259] [ 966.938259] [] acpi_ev_gpe_detect+0x8c/0xce [ 966.938259] [] acpi_irq+0x13/0x2e [] handle_IRQ_event+0x6a/0x12f [] handle_fasteoi_irq+0x7e/0xb5 [ 966.938259] [ 966.938259] [] acpi_ec_space_handler+0x82/0x195 [ 966.938259] [] acpi_ex_access_region+0x19f/0x1a9 [ 966.938259] [] acpi_ex_resolve_node_to_value+0x166/0x1dc [ 966.938259] [] acpi_ds_evaluate_name_path+0x71/0xdc [ 966.938259] [] acpi_ps_parse_aml+0x8e/0x288 [ 966.938259] [] acpi_ns_evaluate+0xcc/0x180 [ 966.938259] [ 966.938259] [] acpi_ns_walk_namespace+0x9a/0x10b [ 966.938259] [] acpi_install_address_space_handler+0x65/0x7b [ 966.938259] [] acpi_ec_ecdt_probe+0x1d9/0x215 [ 966.938259] [ 966.938259] [] kernel_thread_helper+0x7/0x10 [] 0xffffffff [] __lock_acquire+0x548/0x12ab [ 966.938259] [ 966.938259] [] __rcu_process_callbacks+0x17e/0x1ea [ 966.938259] [ 966.938259] [] do_softirq+0x30/0x48 [ 966.938259] [] smp_apic_timer_interrupt+0x73/0x81 [ 966.938259] [ 966.938259] [] start_kernel+0x281/0x286 [] __init_begin+0x38/0x40 [ 966.938259] [ 966.938259] [] __lock_acquire+0x5f8/0x12ab [ 966.938259] [] _spin_lock_irqsave+0x36/0x69 [] prepare_to_wait+0x1c/0x4c [ 966.938259] [] kthread+0x4b/0x6f [ 966.938259] [ 966.938259] [] lock_acquire+0xac/0xc9 [ 966.938259] [] wait_for_common+0x2f/0xf7 [] wait_for_completion+0x17/0x19 [ 966.938259] [] migration_init+0x1d/0x4b [ 966.938259] [] kernel_init+0x4d/0x156 [] 0xffffffff (&rq->lock[ 966.938259] IN-HARDIRQ-W[ 966.938259] [] lock_acquire+0xac/0xc9 [ 966.938259] [] scheduler_tick+0x36/0x1a5 [ 966.938259] [ 966.938259] [ 966.938259] [] handle_IRQ_event+0x6a/0x12f [ 966.938259] [ 966.938259] [] common_interrupt+0x2e/0x34 [ 966.938259] [ 966.938259] [] sget+0x39/0x3b2 [] sysfs_get_sb+0x18/0x1a [ 966.938259] [] kern_mount_data+0x15/0x17 [ 966.938259] [] vfs_caches_init+0xd9/0xeb [ 966.938259] [ 966.938259] [ 966.938259] [ 966.938259] IN-SOFTIRQ-W[ 966.938259] [] __lock_acquire+0x548/0x12ab [ 966.938259] [ 966.938259] [] _spin_lock+0x2d/0x5d [ 966.938259] [ 966.938259] [ 966.938259] [] process_timeout+0xd/0xf [] run_timer_softirq+0x177/0x1ec [ 966.938259] [ 966.938259] [ 966.938259] [ 966.938259] [ 966.938259] [ 966.938259] [] cpu_idle+0x53/0x85 [ 966.938259] [] start_kernel+0x281/0x286 [ 966.938259] [ 966.938259] [ 966.938259] IN-RECLAIM_FS-W[ 966.938259] [] __lock_acquire+0x5f8/0x12ab [ 966.938259] [ 966.938259] [] _spin_lock+0x2d/0x5d [ 966.938259] [] task_rq_lock+0x3b/0x65 [] set_cpus_allowed_ptr+0x1a/0xdc [] kswapd+0x5e/0x58a [ 966.938259] [] kthread+0x4b/0x6f [ 966.938259] [] 0xffffffff [ 966.938259] [] __lock_acquire+0x60e/0x12ab [ 966.938259] [ 966.938259] [ 966.938259] [ 966.938259] [] start_kernel+0xee/0x286 [ 966.938259] [] 0xffffffff [ 966.938259] } [ 966.938259] ... key at: (&vec->lock){..-...} { at: [ 966.938259] [ 966.938259] [ 966.938259] [] _spin_lock_irqsave+0x36/0x69 [ 966.938259] [ 966.938259] [] enqueue_task_rt+0xe5/0x23c [ 966.938259] [] try_to_wake_up+0xcb/0x208 [ 966.938259] [ 966.938259] [] rebalance_domains+0x2d6/0x3b5 [] run_rebalance_domains+0x34/0xa7 [] __do_softirq+0xb4/0x184 [ 966.938259] [ 966.938259] [] do_IRQ+0x75/0x89 [ 966.938259] [] cpuidle_idle_call+0x6a/0xa0 [ 966.938259] [] cpu_idle+0x53/0x85 [ 966.938259] [ 966.938259] [ 966.938259] [] __init_begin+0x38/0x40 [] 0xffffffff [ 966.938259] INITIAL USE[ 966.938259] [ 966.938259] [ 966.938259] [] _spin_lock_irqsave+0x36/0x69 [] cpupri_set+0xa9/0xf0 [ 966.938259] [ 966.938259] [] rq_attach_root+0x86/0xa9 [ 966.938259] [] sched_init+0x262/0x364 [ 966.938259] [] start_kernel+0xee/0x286 [ 966.938259] [] __init_begin+0x38/0x40 [ 966.938259] [] 0xffffffff [ 966.938259] } [ 966.938259] ... key at: [] __key.14459+0x0/0x30 [ 966.938259] ... acquired at: [ 966.938259] [] __lock_acquire+0xfc8/0x12ab [ 966.938259] [] lock_acquire+0xac/0xc9 [ 966.938259] [] _spin_lock_irqsave+0x36/0x69 [ 966.938259] [] cpupri_set+0xa9/0xf0 [ 966.938259] [] rq_online_rt+0x5e/0x61 [ 966.938259] [] set_rq_online+0x40/0x4a [ 966.938259] [] rq_attach_root+0x86/0xa9 [ 966.938259] [] sched_init+0x262/0x364 [ 966.938259] [] start_kernel+0xee/0x286 [ 966.938259] [] __init_begin+0x38/0x40 [ 966.938259] [] 0xffffffff [ 966.938259] [ 966.938259] -> (&rt_b->rt_runtime_lock){..-...} ops: 0 { [ 966.938259] IN-SOFTIRQ-W at: [ 966.938259] [] __lock_acquire+0x548/0x12ab [ 966.938259] [] lock_acquire+0xac/0xc9 [ 966.938259] [] _spin_lock+0x2d/0x5d [ 966.938259] [] enqueue_task_rt+0x167/0x23c [ 966.938259] [] enqueue_task+0x2c/0x38 [ 966.938259] [] activate_task+0x24/0x2a [ 966.938259] [] try_to_wake_up+0xcb/0x208 [ 966.938259] [] wake_up_process+0x14/0x16 [ 966.938259] [] rebalance_domains+0x2d6/0x3b5 [ 966.938259] [] run_rebalance_domains+0x34/0xa7 [ 966.938259] [] __do_softirq+0xb4/0x184 [ 966.938259] [] do_softirq+0x30/0x48 [ 966.938259] [] irq_exit+0x3d/0x74 [ 966.938259] [] do_IRQ+0x75/0x89 [ 966.938259] [] common_interrupt+0x2e/0x34 [ 966.938259] [] cpuidle_idle_call+0x6a/0xa0 [ 966.938259] [] cpu_idle+0x53/0x85 [ 966.938259] [ 966.938259] [] __init_begin+0x38/0x40 [ 966.938259] [ 966.938259] [] _spin_lock+0x2d/0x5d [ 966.938259] [ 966.938259] [] enqueue_task+0x2c/0x38 [] try_to_wake_up+0xcb/0x208 [] wake_up_process+0x14/0x16 [] migration_call+0xad/0x410 [] kernel_thread_helper+0x7/0x10 [ 966.938259] -> at: [ 966.938259] [ 966.938259] [ 966.938259] [ 966.938259] [] run_local_timers+0xd/0x1e [ 966.938259] [ 966.938259] [] smp_apic_timer_interrupt+0x6e/0x81 [ 966.938259] [ 966.938259] [ 966.938259] [] notifier_call_chain+0x51/0x7e [ 966.938259] [] _cpu_up+0x62/0x105 [ 966.938259] [] kernel_init+0x99/0x156 [ 966.938259] [ 966.938259] IN-SOFTIRQ-W[ 966.938259] [ 966.938259] [] _spin_lock_irqsave+0x36/0x69 [ 966.938259] [] tick_setup_sched_timer+0x10b/0x133 [ 966.938259] [] run_timer_softirq+0x27/0x1ec [ 966.938259] [ 966.938259] [] irq_exit+0x3d/0x74 [ 966.938259] [ 966.938259] [] start_secondary+0x1ad/0x1b4 [ 966.938259] [] lock_acquire+0xac/0xc9 [ 966.938259] [ 966.938259] [] __hrtimer_start_range_ns+0x34/0x288 [ 966.938259] [ 966.938259] [] activate_task+0x24/0x2a [ 966.938259] [ 966.938259] [] migration_call+0xad/0x410 [ 966.938259] [ 966.938259] [ 966.938259] [ 966.938259] ... acquired at: [ 966.938259] [] lock_acquire+0xac/0xc9 [] enqueue_task_rt+0x1b8/0x23c [ 966.938259] [] wake_up_process+0x14/0x16 [] migration_init+0x3b/0x4b [ 966.938259] [] kernel_thread_helper+0x7/0x10 ){-.....}[ 966.938259] [] __lock_acquire+0x529/0x12ab [ 966.938259] [ 966.938259] [ 966.938259] [ 966.938259] [] update_process_times+0x47/0x53 [] tick_sched_timer+0x71/0x9f [ 966.938259] [] hrtimer_interrupt+0x106/0x165 [] apic_timer_interrupt+0x2f/0x34 [ 966.938259] [ 966.938259] [ 966.938259] INITIAL USE[ 966.938259] [] __lock_acquire+0x60e/0x12ab [ 966.938259] [ 966.938259] [ 966.938259] [ 966.938259] [ 966.938259] [ 966.938259] [ 966.938259] [ 966.938259] [] kernel_thread_helper+0x7/0x10 [ 966.938259] } [] __lock_acquire+0xfc8/0x12ab [ 966.938259] [] lock_acquire+0xac/0xc9 [ 966.938259] [] __enable_runtime+0x34/0x7f [ 966.938259] [] rq_online_rt+0x44/0x61 [] set_rq_online+0x40/0x4a [ 966.938259] [ 966.938259] [ 966.938259] [] raw_notifier_call_chain+0x11/0x13 [] _cpu_up+0xc6/0x105 [ 966.938259] [] kernel_thread_helper+0x7/0x10 [ 966.938259] [] 0xffffffff [ 966.938259] ... acquired at: [] __lock_acquire+0xfc8/0x12ab [] _spin_lock+0x2d/0x5d [] enqueue_task_rt+0x167/0x23c [] enqueue_task+0x2c/0x38 [] activate_task+0x24/0x2a [ 966.938259] [] wake_up_process+0x14/0x16 [ 966.938259] [] migration_call+0xad/0x410 [ 966.938259] [] migration_init+0x3b/0x4b [] do_one_initcall+0x4f/0x116 [ 966.938259] [] kernel_init+0x4d/0x156 [ 966.938259] [] kernel_thread_helper+0x7/0x10 [] 0xffffffff [ 966.938259] [] __lock_acquire+0xfc8/0x12ab [ 966.938259] [] lock_acquire+0xac/0xc9 [] _spin_lock+0x2d/0x5d [] update_curr_rt+0x123/0x1b0 [ 966.938259] [] dequeue_task_rt+0x22/0x62 [] dequeue_task+0x98/0xa6 [] __schedule+0x133/0x7fa [ 966.938259] [] schedule+0x17/0x30 [ 966.938259] [ 966.938259] [] kernel_thread_helper+0x7/0x10 [] 0xffffffff [ 966.938259] (&rq->lock/1 ops: 0[ 966.938259] IN-SOFTIRQ-W at: [] __lock_acquire+0x548/0x12ab [ 966.938259] [] _spin_lock_nested+0x34/0x64 [ 966.938259] [ 966.938259] [] run_rebalance_domains+0x34/0xa7 [ 966.938259] [ 966.938259] [] do_softirq+0x30/0x48 [ 966.938259] [ 966.938259] [ 966.938259] [ 966.938259] [ 966.938259] [] start_kernel+0x281/0x286 [ 966.938259] [] __init_begin+0x38/0x40 [ 966.938259] [] 0xffffffff [ 966.938259] [] __lock_acquire+0x60e/0x12ab [ 966.938259] [ 966.938259] [] double_rq_lock+0x4c/0x7f [ 966.938259] [ 966.938259] [] run_rebalance_domains+0x34/0xa7 [ 966.938259] [] do_softirq+0x30/0x48 [ 966.938259] [ 966.938259] [] smp_apic_timer_interrupt+0x73/0x81 [ 966.938259] [] apic_timer_interrupt+0x2f/0x34 [ 966.938259] [] cpu_idle+0x53/0x85 [ 966.938259] [] rest_init+0x6c/0x6e [ 966.938259] [] start_kernel+0x281/0x286 [ 966.938259] [] __init_begin+0x38/0x40 [ 966.938259] [] 0xffffffff [ 966.938259] } [ 966.938259] ... key at: [] __key.48522+0x1/0x8 [ 966.938259] -> (&sig->cputimer.lock){-.-...} ops: 0 { [ 966.938259] IN-HARDIRQ-W at: [ 966.938259] [] __lock_acquire+0x529/0x12ab [ 966.938259] [] lock_acquire+0xac/0xc9 [ 966.938259] [] _spin_lock+0x2d/0x5d [ 966.938259] [] account_system_time+0x105/0x157 [ 966.938259] [] account_process_tick+0x69/0x7e [ 966.938259] [] update_process_times+0x24/0x53 [ 966.938259] [] tick_sched_timer+0x71/0x9f [ 966.938259] [] __run_hrtimer+0x63/0x98 [ 966.938259] [] hrtimer_interrupt+0x106/0x165 [ 966.938259] [] smp_apic_timer_interrupt+0x6e/0x81 [ 966.938259] [] apic_timer_interrupt+0x2f/0x34 [ 966.938259] [] do_sync_read+0xb0/0xeb [ 966.938259] [] vfs_read+0x8d/0x106 [ 966.938259] [] sys_read+0x48/0x75 [ 966.938259] [] sysenter_do_call+0x12/0x36 [ 966.938259] [] 0xffffffff [ 966.938259] IN-SOFTIRQ-W at: [ 966.938259] [] __lock_acquire+0x548/0x12ab [ 966.938259] [] lock_acquire+0xac/0xc9 [ 966.938259] [] _spin_lock+0x2d/0x5d [ 966.938259] [] update_curr+0x128/0x149 [ 966.938259] [] enqueue_task_fair+0x40/0xd6 [ 966.938259] [] enqueue_task+0x2c/0x38 [ 966.938259] [] activate_task+0x24/0x2a [ 966.938259] [] try_to_wake_up+0xcb/0x208 [ 966.938259] [] default_wake_function+0x10/0x12 [ 966.938259] [] autoremove_wake_function+0x14/0x34 [ 966.938259] [] wake_bit_function+0x2f/0x3c [ 966.938259] [] __wake_up_common+0x39/0x61 [ 966.938259] [] __wake_up+0x2e/0x40 [ 966.938259] [] __wake_up_bit+0x2c/0x2f [] unlock_page+0x4c/0x4f [ 966.938259] [ 966.938259] [] req_bio_endio+0x89/0xa6 [] end_that_request_data+0x19/0x48 [ 966.938259] [ 966.938259] [] scsi_io_completion+0x1c9/0x434 [ 966.938259] [ 966.938259] [] blk_done_softirq+0x63/0x70 [ 966.938259] [ 966.938259] [] irq_exit+0x3d/0x74 [] common_interrupt+0x2e/0x34 [ 966.938259] [ 966.938259] [] do_path_lookup+0xe9/0x103 [ 966.938259] [ 966.938259] [ 966.938259] [] 0xffffffff [ 966.938259] [ 966.938259] [] lock_acquire+0xac/0xc9 [ 966.938259] [] posix_cpu_timers_exit_group+0x16/0x39 [ 966.938259] [ 966.938259] [ 966.938259] [] kernel_thread_helper+0x7/0x10 [ 966.938259] ... key at: [ 966.938259] [] _spin_lock+0x2d/0x5d [] dequeue_task_fair+0x40/0x185 [] pull_task+0x16/0x42 [] rebalance_domains+0x1c0/0x3b5 [] run_rebalance_domains+0x34/0xa7 [] do_softirq+0x30/0x48 [] common_interrupt+0x2e/0x34 [] __lock_acquire+0xfc8/0x12ab [ 966.938259] [ 966.938259] [] run_rebalance_domains+0x34/0xa7 [] do_softirq+0x30/0x48 [ 966.938259] [] cpu_idle+0x53/0x85 [ 966.938259] [ 966.938259] ... acquired at: [ 966.938259] [ 966.938259] [ 966.938259] [ 966.938259] [ 966.938259] [] __lock_page_killable+0x74/0x7b [] generic_file_aio_read+0x390/0x54f [] do_sync_read+0xb0/0xeb [] sys_read+0x48/0x75 [ 966.938259] [] __lock_acquire+0xfc8/0x12ab [] task_rq_lock+0x3b/0x65 [ 966.938259] [] __wake_up_common+0x39/0x61 [] complete+0x30/0x43 [] kthreadd+0xc1/0xe8 [] kernel_thread_helper+0x7/0x10 [] 0xffffffff [ 966.938259] -> { at: [] __lock_acquire+0x60e/0x12ab [ 966.938259] [] _spin_lock_irqsave+0x36/0x69 [] sys_epoll_ctl+0x241/0x41e [ 966.938259] [] 0xffffffff [ 966.938259] ... key at: [] __key.22648+0x0/0x8 [ 966.938259] ... acquired at: [ 966.938259] [] __lock_acquire+0xfc8/0x12ab [] lock_acquire+0xac/0xc9 [] _spin_lock+0x2d/0x5d [] task_rq_lock+0x3b/0x65 [] try_to_wake_up+0x31/0x208 [] default_wake_function+0x10/0x12 [] __wake_up_common+0x39/0x61 [ 966.938259] [] __wake_up_locked+0x16/0x1a [] ep_poll_callback+0x81/0xb8 [ 966.938259] [] __wake_up_common+0x39/0x61 [] __wake_up_sync_key+0x38/0x4a [ 966.938259] [] unix_stream_connect+0x317/0x387 [unix] [ 966.938259] [] sys_connect+0x59/0x76 [ 966.938259] [] sys_socketcall+0x76/0x168 [ 966.938259] [] 0xffffffff [ 966.938259] [] __lock_acquire+0xfc8/0x12ab [ 966.938259] [] _spin_lock_irqsave+0x36/0x69 [ 966.938259] [] ep_poll_callback+0x18/0xb8 [ 966.938259] [] __wake_up_sync_key+0x38/0x4a [] sock_def_readable+0x3f/0x6d [] unix_stream_connect+0x317/0x387 [unix] [ 966.938259] [] sys_connect+0x59/0x76 [ 966.938259] [] sys_socketcall+0x76/0x168 [ 966.938259] [] sysenter_do_call+0x12/0x36 [] 0xffffffff [ 966.938259] [ 966.938259] ... acquired at: [] __lock_acquire+0xfc8/0x12ab [] lock_acquire+0xac/0xc9 [ 966.938259] [] _spin_lock_irqsave+0x36/0x69 [ 966.938259] [] __wake_up+0x1a/0x40 [] insert_work+0x9a/0xa3 [] __queue_work+0x26/0x34 [ 966.938259] [] queue_work_on+0x40/0x49 [ 966.938259] [] call_usermodehelper_exec+0x8a/0xdc [] kobject_uevent_env+0x333/0x368 [] kobject_uevent+0xa/0xc [] kset_register+0x2e/0x34 [] bus_register+0xe4/0x230 [] driver_init+0x1c/0x28 [] kernel_init+0xf2/0x156 [] kernel_thread_helper+0x7/0x10 [] 0xffffffff [ 966.938259] ... acquired at: [ 966.938259] [] __lock_acquire+0xfc8/0x12ab [] lock_acquire+0xac/0xc9 [] _spin_lock_irqsave+0x36/0x69 [ 966.938259] [ 966.938259] [] queue_work_on+0x40/0x49 [] schedule_work+0x14/0x16 [] add_todo+0x4a/0x58 [mac80211] [ 966.938259] [] ieee80211_key_link+0x11a/0x138 [mac80211] [ 966.938259] [] ieee80211_add_key+0xca/0x104 [mac80211] [ 966.938259] [] nl80211_new_key+0xfe/0x12a [cfg80211] [ 966.938259] [] genl_rcv_msg+0x18c/0x1a6 [ 966.938259] [] netlink_rcv_skb+0x35/0x7b [ 966.938259] [] genl_rcv+0x23/0x30 [ 966.938259] [] netlink_unicast+0x1a9/0x20a [ 966.938259] [] netlink_sendmsg+0x223/0x230 [ 966.938259] [] sock_sendmsg+0xcc/0xe2 [ 966.938259] [] sys_sendmsg+0x14e/0x19b [ 966.938259] [] sys_socketcall+0x13f/0x168 [ 966.938259] [] sysenter_do_call+0x12/0x36 [ 966.938259] [] 0xffffffff [ 966.938259] [ 966.938259] [ 966.938259] stack backtrace: [ 966.938259] Pid: 2889, comm: wpa_supplicant Not tainted 2.6.30-wl #136 [ 966.938259] Call Trace: [ 966.938259] [] ? printk+0x14/0x16 [ 966.938259] [] check_usage+0x2fe/0x30f [ 966.938259] [] check_irq_usage+0x50/0xac [ 966.938259] [] __lock_acquire+0xd51/0x12ab [ 966.938259] [] ? ieee80211_key_free+0x41/0x60 [mac80211] [ 966.938259] [] ? ieee80211_key_free+0x41/0x60 [mac80211] [ 966.938259] [] lock_acquire+0xac/0xc9 [ 966.938259] [] ? ieee80211_key_free+0x41/0x60 [mac80211] [ 966.938259] [] _spin_lock_irqsave+0x36/0x69 [ 966.938259] [] ? ieee80211_key_free+0x41/0x60 [mac80211] [ 966.938259] [] ieee80211_key_free+0x41/0x60 [mac80211] [ 966.938259] [] __sta_info_unlink+0x69/0x162 [mac80211] [ 966.938259] [] ? _spin_lock_irqsave+0x5f/0x69 [ 966.938259] [] sta_info_flush+0x6a/0xbc [mac80211] [ 966.938259] [] ieee80211_stop+0xe3/0x460 [mac80211] [ 966.938259] [] ? _spin_unlock_bh+0x34/0x37 [ 966.938259] [] dev_close+0x72/0x8e [ 966.938259] [] dev_change_flags+0x9b/0x14c [ 966.938259] [] devinet_ioctl+0x214/0x4f3 [ 966.938259] [] inet_ioctl+0x93/0xac [ 966.938259] [] sock_ioctl+0x1ce/0x1f2 [ 966.938259] [] ? sock_ioctl+0x0/0x1f2 [ 966.938259] [] vfs_ioctl+0x1d/0x76 [ 966.938259] [] ? lock_release_holdtime+0xf5/0xfa [ 966.938259] [] do_vfs_ioctl+0x479/0x4b3 [ 966.938259] [] ? might_fault+0x50/0x8d [ 966.938259] [] ? might_fault+0x50/0x8d [ 966.938259] [] ? trace_hardirqs_on_thunk+0xc/0x10 [ 966.938259] [] sys_ioctl+0x33/0x4d [ 966.938259] [] sysenter_do_call+0x12/0x36 [ 966.938259] phy0: Removed STA 00:18:41:c8:58:43 [ 967.027386] phy0: Destroyed STA 00:18:41:c8:58:43 [ 979.976463] wlan0: authenticate with AP 00:1a:70:da:a9:cd [ 979.978033] wlan0: authenticated [ 979.978049] wlan0: associate with AP 00:1a:70:da:a9:cd [ 979.980300] wlan0: RX AssocResp from 00:1a:70:da:a9:cd (capab=0x411 status=0 aid=3) [ 979.980316] wlan0: associated [ 979.980343] phy0: Allocated STA 00:1a:70:da:a9:cd [ 979.981246] phy0: Inserted STA 00:1a:70:da:a9:cd [ 979.981792] wlan0: switched to short slot time (BSSID=00:1a:70:da:a9:cd) -- Bob Copeland %% www.bobcopeland.com