Return-Path: Received: (majordomo@vger.kernel.org) by vger.kernel.org via listexpand id S1756638AbZD0H70 (ORCPT ); Mon, 27 Apr 2009 03:59:26 -0400 Received: (majordomo@vger.kernel.org) by vger.kernel.org id S1753146AbZD0H7O (ORCPT ); Mon, 27 Apr 2009 03:59:14 -0400 Received: from mail-bw0-f163.google.com ([209.85.218.163]:52758 "EHLO mail-bw0-f163.google.com" rhost-flags-OK-OK-OK-OK) by vger.kernel.org with ESMTP id S1750898AbZD0H7J convert rfc822-to-8bit (ORCPT ); Mon, 27 Apr 2009 03:59:09 -0400 DomainKey-Signature: a=rsa-sha1; c=nofws; d=gmail.com; s=gamma; h=mime-version:in-reply-to:references:date:message-id:subject:from:to :cc:content-type:content-transfer-encoding; b=cS/BUIHlcBtsJ5sshbUSh4S4E2uZACYo9b9EYkwMO0CH4gmSGber4bEL3RaFz+AllE g37SPJRACWFJLZmpriAVG2qE/OBv0TnZ8N3OkvN4Dgf/PnVxZlERE436msdSdTrr5uL7 6OfUdN7AD1Iwqlj1hYgB6rwKVo3fjCkenxQbc= MIME-Version: 1.0 In-Reply-To: References: <20090422140107.GD3288@tuxdriver.com> Date: Mon, 27 Apr 2009 09:59:04 +0200 Message-ID: Subject: Re: regression IWl3945 - doesn't work with recent 2.6.30-rcX From: Zdenek Kabelac To: "John W. Linville" Cc: Linux Kernel Mailing List , linux-wireless@vger.kernel.org Content-Type: text/plain; charset=ISO-8859-1 Content-Transfer-Encoding: 8BIT Sender: linux-kernel-owner@vger.kernel.org List-ID: X-Mailing-List: linux-kernel@vger.kernel.org Content-Length: 62381 Lines: 1424 2009/4/24 Zdenek Kabelac : > 2009/4/22 John W. Linville : >> On Wed, Apr 22, 2009 at 12:33:03PM +0200, Zdenek Kabelac wrote: >>> Hi >>> >>> I'm checking whether -rcX kernel could be usable on my >>> T61/4GB/C2D/x86_64 - but wireless seems to be still out of >>> functionality: >>> I'm getting lots of weird trace-back messages and it looks like >>> iwl3945 is not working at all. >>> (attached messages from fresh build of -rc3 - but it never worked even in -rc1) >> >> Looks like this one did _not_ make -rc3: >> >> commit df833b1d73680f9f9dc72cbc3215edbbc6ab740d >> Author: Reinette Chatre >> Date: ? Tue Apr 21 10:55:48 2009 -0700 >> >> ? ?iwlwifi: DMA fixes >> >> ? ?A few issues wrt DMA were uncovered when using the driver with swiotlb. >> ... >> >> It is in wireless-2.6 and should be in net-2.6 -- please try one of those kernels. > > > I can confirm that current upstream linux commit > 9f5a691253924fd033a58c6b1fed57bb0a4eccf4 works again with iwlwifi. > and it already contains the patch you suggested to check. > While Wifi seems to be working well - I've noticed once attached long INFO message during suspend. Zdenek ----------- Linux version 2.6.30-rc3-00324-g8087eae (gcc version 4.4.0 20090414 (Red Hat 4.4.0-0.34) (GCC) ) #55 SMP Fri Apr 24 20:22:26 CEST 2009 Command line: ro root=/dev/sda5 selinux=0 no_console_suspend ... ADDRCONF(NETDEV_UP): wlan0: link is not ready virbr0: no IPv6 routers present wlan0: authenticate with AP 00:11:d8:da:65:40 wlan0: authenticated wlan0: associate with AP 00:11:d8:da:65:40 wlan0: RX AssocResp from 00:11:d8:da:65:40 (capab=0x401 status=0 aid=4) wlan0: associated ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready wlan0: disassociating by local choice (reason=3) audit(1240599276.009:18266): audit_enabled=0 old=1 auid=4294967295 ses=4294967295 res=1 wlan0: no IPv6 routers present fuse init (API version 7.11) wlan0: authenticate with AP 00:11:d8:da:65:40 wlan0: authenticated wlan0: associate with AP 00:11:d8:da:65:40 wlan0: RX AssocResp from 00:11:d8:da:65:40 (capab=0x401 status=0 aid=4) wlan0: associated wlan0: no probe response from AP 00:11:d8:da:65:40 - disassociating wlan0: deauthenticated (Reason: 7) wlan0: authenticate with AP 00:11:d8:da:65:40 wlan0: authenticate with AP 00:11:d8:da:65:40 wlan0: authenticate with AP 00:11:d8:da:65:40 wlan0: authenticated wlan0: associate with AP 00:11:d8:da:65:40 wlan0: RX ReassocResp from 00:11:d8:da:65:40 (capab=0x401 status=0 aid=4) wlan0: associated .......... ========================================================= [ INFO: possible irq lock inversion dependency detected ] 2.6.30-rc3-00324-g8087eae #55 --------------------------------------------------------- swapper/0 just changed the state of lock: (&(&priv->scan_check)->timer){+.-...}, at: [] run_timer_softirq+0x120/0x2e0 but this lock was taken by another, HARDIRQ-safe lock in the past: (&priv->lock){-.-...} and interrupts could create inverse lock ordering between them. other info that might help us debug this: 1 lock held by swapper/0: #0: (rcu_read_lock){.+.+..}, at: [] __atomic_notifier_call_chain+0x0/0xa0 the first lock's dependencies: -> (&(&priv->scan_check)->timer){+.-...} ops: 0 { HARDIRQ-ON-W at: [] 0xffffffffffffffff IN-SOFTIRQ-W at: [] 0xffffffffffffffff INITIAL USE at: [] __lock_acquire+0x19e/0x1100 [] lock_acquire+0x98/0x140 [] del_timer_sync+0x41/0xa0 [] iwl3945_mac_add_interface+0x14d/0x420 [iwl3945] [] ieee80211_open+0x408/0x920 [mac80211] [] dev_open+0x9a/0x100 [] dev_change_flags+0x96/0x1c0 [] do_setlink+0x212/0x4a0 [] rtnl_setlink+0x122/0x160 [] rtnetlink_rcv_msg+0x1df/0x220 [] netlink_rcv_skb+0xa9/0xd0 [] rtnetlink_rcv+0x25/0x40 [] netlink_unicast+0x29a/0x2f0 [] netlink_sendmsg+0x222/0x320 [] sock_sendmsg+0x10b/0x140 [] sys_sendmsg+0x1d0/0x330 [] system_call_fastpath+0x16/0x1b [] 0xffffffffffffffff } ... key at: [] __key.46148+0x0/0xffffffffffff27ff [iwl3945] the second lock's dependencies: -> (&priv->lock){-.-...} ops: 0 { IN-HARDIRQ-W at: [] 0xffffffffffffffff IN-SOFTIRQ-W at: [] 0xffffffffffffffff INITIAL USE at: [] __lock_acquire+0x19e/0x1100 [] lock_acquire+0x98/0x140 [] _spin_lock_irqsave+0x48/0x90 [] iwl_reset_qos+0x24/0x2c0 [iwlcore] [] iwl3945_pci_probe+0x348/0x1080 [iwl3945] [] local_pci_probe+0x17/0x20 [] pci_device_probe+0x88/0xb0 [] driver_probe_device+0x89/0x180 [] __driver_attach+0x9b/0xb0 [] bus_for_each_dev+0x73/0xa0 [] driver_attach+0x1e/0x20 [] bus_add_driver+0xd5/0x290 [] driver_register+0x86/0x140 [] __pci_register_driver+0x66/0xe0 [] 0xffffffffa00b305c [] do_one_initcall+0x3f/0x1c0 [] sys_init_module+0xb1/0x200 [] system_call_fastpath+0x16/0x1b [] 0xffffffffffffffff } ... key at: [] __key.46201+0x0/0xffffffffffff2807 [iwl3945] -> (pci_lock){-.....} ops: 0 { IN-HARDIRQ-W at: [] 0xffffffffffffffff INITIAL USE at: [] __lock_acquire+0x19e/0x1100 [] lock_acquire+0x98/0x140 [] _spin_lock_irqsave+0x48/0x90 [] pci_bus_read_config_dword+0x42/0x90 [] pci_scan_single_device+0x6c/0x170 [] pci_scan_slot+0x1e/0xc0 [] pci_scan_child_bus+0x29/0x9d [] pci_scan_bus_parented+0x23/0x31 [] pci_acpi_scan_root+0x10c/0x2c6 [] acpi_pci_root_add+0x2c2/0x3f7 [] acpi_device_probe+0x50/0x1c4 [] driver_probe_device+0x89/0x180 [] __driver_attach+0x9b/0xb0 [] bus_for_each_dev+0x73/0xa0 [] driver_attach+0x1e/0x20 [] bus_add_driver+0xd5/0x290 [] driver_register+0x86/0x140 [] acpi_bus_register_driver+0x43/0x48 [] acpi_pci_root_init+0x19/0x28 [] do_one_initcall+0x3f/0x1c0 [] kernel_init+0x13a/0x190 [] child_rip+0xa/0x20 [] 0xffffffffffffffff } ... key at: [] pci_lock+0x18/0x40 -> (pci_config_lock){-.....} ops: 0 { IN-HARDIRQ-W at: [] 0xffffffffffffffff INITIAL USE at: [] __lock_acquire+0x19e/0x1100 [] lock_acquire+0x98/0x140 [] _spin_lock_irqsave+0x48/0x90 [] pci_conf1_read+0x80/0x120 [] __pci_mmcfg_init+0x92/0x308 [] pci_mmcfg_early_init+0xe/0x10 [] pci_arch_init+0x1e/0x58 [] do_one_initcall+0x3f/0x1c0 [] kernel_init+0x13a/0x190 [] child_rip+0xa/0x20 [] 0xffffffffffffffff } ... key at: [] pci_config_lock+0x18/0x60 ... acquired at: [] __lock_acquire+0xce0/0x1100 [] lock_acquire+0x98/0x140 [] _spin_lock_irqsave+0x48/0x90 [] pci_conf1_read+0x80/0x120 [] raw_pci_read+0x52/0x60 [] pci_read+0x2c/0x30 [] pci_bus_read_config_dword+0x60/0x90 [] pci_scan_single_device+0x6c/0x170 [] pci_scan_slot+0x1e/0xc0 [] pci_scan_child_bus+0x29/0x9d [] pci_scan_bus_parented+0x23/0x31 [] pci_acpi_scan_root+0x10c/0x2c6 [] acpi_pci_root_add+0x2c2/0x3f7 [] acpi_device_probe+0x50/0x1c4 [] driver_probe_device+0x89/0x180 [] __driver_attach+0x9b/0xb0 [] bus_for_each_dev+0x73/0xa0 [] driver_attach+0x1e/0x20 [] bus_add_driver+0xd5/0x290 [] driver_register+0x86/0x140 [] acpi_bus_register_driver+0x43/0x48 [] acpi_pci_root_init+0x19/0x28 [] do_one_initcall+0x3f/0x1c0 [] kernel_init+0x13a/0x190 [] child_rip+0xa/0x20 [] 0xffffffffffffffff ... acquired at: [] __lock_acquire+0xce0/0x1100 [] lock_acquire+0x98/0x140 [] _spin_lock_irqsave+0x48/0x90 [] pci_bus_read_config_word+0x42/0x90 [] __pci_bus_find_cap_start+0x1e/0x50 [] pci_find_capability+0x2a/0x60 [] iwl_power_initialize+0xb4/0x210 [iwlcore] [] iwl3945_apm_init+0x1a/0x670 [iwl3945] [] iwl3945_hw_nic_init+0x3f/0xd60 [iwl3945] [] __iwl3945_up+0xb5/0x750 [iwl3945] [] iwl3945_mac_start+0x438/0x1440 [iwl3945] [] ieee80211_open+0x289/0x920 [mac80211] [] dev_open+0x9a/0x100 [] dev_change_flags+0x96/0x1c0 [] do_setlink+0x212/0x4a0 [] rtnl_setlink+0x122/0x160 [] rtnetlink_rcv_msg+0x1df/0x220 [] netlink_rcv_skb+0xa9/0xd0 [] rtnetlink_rcv+0x25/0x40 [] netlink_unicast+0x29a/0x2f0 [] netlink_sendmsg+0x222/0x320 [] sock_sendmsg+0x10b/0x140 [] sys_sendmsg+0x1d0/0x330 [] system_call_fastpath+0x16/0x1b [] 0xffffffffffffffff -> (&rxq->lock){..-...} ops: 0 { IN-SOFTIRQ-W at: [] 0xffffffffffffffff INITIAL USE at: [] __lock_acquire+0x19e/0x1100 [] lock_acquire+0x98/0x140 [] _spin_lock_irqsave+0x48/0x90 [] iwl3945_rx_allocate+0x2c/0x1e0 [iwl3945] [] iwl3945_rx_replenish+0x21/0x60 [iwl3945] [] iwl3945_hw_nic_init+0xe9/0xd60 [iwl3945] [] __iwl3945_up+0xb5/0x750 [iwl3945] [] iwl3945_mac_start+0x438/0x1440 [iwl3945] [] ieee80211_open+0x289/0x920 [mac80211] [] dev_open+0x9a/0x100 [] dev_change_flags+0x96/0x1c0 [] do_setlink+0x212/0x4a0 [] rtnl_setlink+0x122/0x160 [] rtnetlink_rcv_msg+0x1df/0x220 [] netlink_rcv_skb+0xa9/0xd0 [] rtnetlink_rcv+0x25/0x40 [] netlink_unicast+0x29a/0x2f0 [] netlink_sendmsg+0x222/0x320 [] sock_sendmsg+0x10b/0x140 [] sys_sendmsg+0x1d0/0x330 [] system_call_fastpath+0x16/0x1b [] 0xffffffffffffffff } ... key at: [] __key.39781+0x0/0xfffffffffffea608 [iwlcore] -> (io_tlb_lock){-.-...} ops: 0 { IN-HARDIRQ-W at: [] 0xffffffffffffffff IN-SOFTIRQ-W at: [] 0xffffffffffffffff INITIAL USE at: [] __lock_acquire+0x19e/0x1100 [] lock_acquire+0x98/0x140 [] _spin_lock_irqsave+0x48/0x90 [] map_single+0xa4/0x260 [] swiotlb_map_sg_attrs+0x7d/0x1a0 [] ata_qc_issue+0x10f/0x300 [] ata_scsi_translate+0xa6/0x180 [] ata_scsi_queuecmd+0xbf/0x2d0 [] scsi_dispatch_cmd+0x197/0x2c0 [] scsi_request_fn+0x3c4/0x520 [] __generic_unplug_device+0x35/0x40 [] blk_execute_rq_nowait+0x70/0xc0 [] blk_execute_rq+0x7a/0xe0 [] scsi_execute+0xfc/0x160 [] scsi_execute_req+0xaa/0x180 [] scsi_probe_and_add_lun+0x24a/0xe90 [] __scsi_add_device+0x10a/0x110 [] ata_scsi_scan_host+0x13b/0x1d0 [] async_port_probe+0xac/0xe0 [] async_thread+0x108/0x260 [] kthread+0x5a/0xa0 [] child_rip+0xa/0x20 [] 0xffffffffffffffff } ... key at: [] io_tlb_lock+0x18/0x40 ... acquired at: [] __lock_acquire+0xce0/0x1100 [] lock_acquire+0x98/0x140 [] _spin_lock_irqsave+0x48/0x90 [] map_single+0xa4/0x260 [] swiotlb_map_page+0xd2/0x1a0 [] iwl3945_rx_allocate+0xb3/0x1e0 [iwl3945] [] iwl3945_rx_replenish+0x21/0x60 [iwl3945] [] iwl3945_hw_nic_init+0xe9/0xd60 [iwl3945] [] __iwl3945_up+0xb5/0x750 [iwl3945] [] iwl3945_mac_start+0x438/0x1440 [iwl3945] [] ieee80211_open+0x289/0x920 [mac80211] [] dev_open+0x9a/0x100 [] dev_change_flags+0x96/0x1c0 [] do_setlink+0x212/0x4a0 [] rtnl_setlink+0x122/0x160 [] rtnetlink_rcv_msg+0x1df/0x220 [] netlink_rcv_skb+0xa9/0xd0 [] rtnetlink_rcv+0x25/0x40 [] netlink_unicast+0x29a/0x2f0 [] netlink_sendmsg+0x222/0x320 [] sock_sendmsg+0x10b/0x140 [] sys_sendmsg+0x1d0/0x330 [] system_call_fastpath+0x16/0x1b [] 0xffffffffffffffff -> (&zone->lock){..-...} ops: 0 { IN-SOFTIRQ-W at: [] 0xffffffffffffffff INITIAL USE at: [] __lock_acquire+0x19e/0x1100 [] lock_acquire+0x98/0x140 [] _spin_lock+0x36/0x70 [] free_pages_bulk.clone.1+0x32/0x270 [] free_hot_cold_page+0x219/0x2a0 [] free_hot_page+0x10/0x20 [] __free_pages+0x35/0x40 [] __free_pages_bootmem+0x24/0x69 [] free_all_bootmem_core+0xf7/0x1c5 [] free_all_bootmem+0x10/0x12 [] mem_init+0x1e/0x161 [] start_kernel+0x2cd/0x37b [] x86_64_start_reservations+0x125/0x129 [] x86_64_start_kernel+0xe4/0xeb [] 0xffffffffffffffff } ... key at: [] __key.31011+0x0/0x8 ... acquired at: [] __lock_acquire+0xce0/0x1100 [] lock_acquire+0x98/0x140 [] _spin_lock_irqsave+0x48/0x90 [] get_page_from_freelist+0x561/0x5e0 [] __alloc_pages_internal+0xed/0x4f0 [] __slab_alloc+0x138/0x4a0 [] __kmalloc_track_caller+0x15d/0x1c0 [] __alloc_skb+0x74/0x170 [] iwl3945_rx_allocate+0x118/0x1e0 [iwl3945] [] iwl3945_rx_replenish+0x21/0x60 [iwl3945] [] iwl3945_hw_nic_init+0xe9/0xd60 [iwl3945] [] __iwl3945_up+0xb5/0x750 [iwl3945] [] iwl3945_mac_start+0x438/0x1440 [iwl3945] [] ieee80211_open+0x289/0x920 [mac80211] [] dev_open+0x9a/0x100 [] dev_change_flags+0x96/0x1c0 [] do_setlink+0x212/0x4a0 [] rtnl_setlink+0x122/0x160 [] rtnetlink_rcv_msg+0x1df/0x220 [] netlink_rcv_skb+0xa9/0xd0 [] rtnetlink_rcv+0x25/0x40 [] netlink_unicast+0x29a/0x2f0 [] netlink_sendmsg+0x222/0x320 [] sock_sendmsg+0x10b/0x140 [] sys_sendmsg+0x1d0/0x330 [] system_call_fastpath+0x16/0x1b [] 0xffffffffffffffff -> (&n->list_lock){-.-...} ops: 0 { IN-HARDIRQ-W at: [] 0xffffffffffffffff IN-SOFTIRQ-W at: [] 0xffffffffffffffff INITIAL USE at: [] __lock_acquire+0x19e/0x1100 [] lock_acquire+0x98/0x140 [] _spin_lock+0x36/0x70 [] add_partial+0x27/0x90 [] __slab_free+0x8c/0xf0 [] kmem_cache_free+0xf7/0x130 [] acpi_os_release_object+0xe/0x12 [] acpi_ps_free_op+0x69/0x70 [] acpi_ps_delete_parse_tree+0x43/0x60 [] acpi_ps_complete_this_op+0x183/0x194 [] acpi_ps_complete_op+0x2e/0x2b7 [] acpi_ps_parse_loop+0x776/0x901 [] acpi_ps_parse_aml+0x10f/0x3e6 [] acpi_ns_one_complete_parse+0x13a/0x157 [] acpi_ns_parse_table+0x4e/0x91 [] acpi_ns_load_table+0x7d/0x118 [] acpi_load_tables+0x72/0x15f [] acpi_early_init+0x60/0xf5 [] start_kernel+0x365/0x37b [] x86_64_start_reservations+0x125/0x129 [] x86_64_start_kernel+0xe4/0xeb [] 0xffffffffffffffff } ... key at: [] __key.25156+0x0/0x8 ... acquired at: [] __lock_acquire+0xce0/0x1100 [] lock_acquire+0x98/0x140 [] _spin_lock+0x36/0x70 [] __slab_alloc+0x2e4/0x4a0 [] kmem_cache_alloc+0x167/0x170 [] __alloc_skb+0x4a/0x170 [] iwl3945_rx_allocate+0x118/0x1e0 [iwl3945] [] iwl3945_rx_replenish+0x21/0x60 [iwl3945] [] iwl3945_hw_nic_init+0xe9/0xd60 [iwl3945] [] __iwl3945_up+0xb5/0x750 [iwl3945] [] iwl3945_mac_start+0x438/0x1440 [iwl3945] [] ieee80211_open+0x289/0x920 [mac80211] [] dev_open+0x9a/0x100 [] dev_change_flags+0x96/0x1c0 [] do_setlink+0x212/0x4a0 [] rtnl_setlink+0x122/0x160 [] rtnetlink_rcv_msg+0x1df/0x220 [] netlink_rcv_skb+0xa9/0xd0 [] rtnetlink_rcv+0x25/0x40 [] netlink_unicast+0x29a/0x2f0 [] netlink_sendmsg+0x222/0x320 [] sock_sendmsg+0x10b/0x140 [] sys_sendmsg+0x1d0/0x330 [] system_call_fastpath+0x16/0x1b [] 0xffffffffffffffff ... acquired at: [] __lock_acquire+0xce0/0x1100 [] lock_acquire+0x98/0x140 [] _spin_lock_irqsave+0x48/0x90 [] iwl3945_rx_queue_restock+0x2f/0x170 [iwl3945] [] iwl3945_rx_replenish+0x3c/0x60 [iwl3945] [] iwl3945_hw_nic_init+0xe9/0xd60 [iwl3945] [] __iwl3945_up+0xb5/0x750 [iwl3945] [] iwl3945_mac_start+0x438/0x1440 [iwl3945] [] ieee80211_open+0x289/0x920 [mac80211] [] dev_open+0x9a/0x100 [] dev_change_flags+0x96/0x1c0 [] do_setlink+0x212/0x4a0 [] rtnl_setlink+0x122/0x160 [] rtnetlink_rcv_msg+0x1df/0x220 [] netlink_rcv_skb+0xa9/0xd0 [] rtnetlink_rcv+0x25/0x40 [] netlink_unicast+0x29a/0x2f0 [] netlink_sendmsg+0x222/0x320 [] sock_sendmsg+0x10b/0x140 [] sys_sendmsg+0x1d0/0x330 [] system_call_fastpath+0x16/0x1b [] 0xffffffffffffffff ... acquired at: [] 0xffffffffffffffff -> (&base->lock){-.-...} ops: 0 { IN-HARDIRQ-W at: [] 0xffffffffffffffff IN-SOFTIRQ-W at: [] 0xffffffffffffffff INITIAL USE at: [] __lock_acquire+0x19e/0x1100 [] lock_acquire+0x98/0x140 [] _spin_lock_irqsave+0x48/0x90 [] lock_timer_base+0x3c/0x70 [] mod_timer+0x57/0x120 [] con_init+0xc3/0x24c [] console_init+0x19/0x2a [] start_kernel+0x227/0x37b [] x86_64_start_reservations+0x125/0x129 [] x86_64_start_kernel+0xe4/0xeb [] 0xffffffffffffffff } ... key at: [] __key.23674+0x0/0x8 ... acquired at: [] 0xffffffffffffffff -> (&q->lock){-.-.-.} ops: 0 { IN-HARDIRQ-W at: [] 0xffffffffffffffff IN-SOFTIRQ-W at: [] 0xffffffffffffffff IN-RECLAIM_FS-W at: [] __lock_acquire+0x39a/0x1100 [] lock_acquire+0x98/0x140 [] _spin_lock_irqsave+0x48/0x90 [] prepare_to_wait+0x2a/0x80 [] kswapd+0x100/0x750 [] kthread+0x5a/0xa0 [] child_rip+0xa/0x20 [] 0xffffffffffffffff INITIAL USE at: [] __lock_acquire+0x19e/0x1100 [] lock_acquire+0x98/0x140 [] _spin_lock_irq+0x3c/0x80 [] wait_for_common+0x4d/0x190 [] wait_for_completion+0x1d/0x20 [] kthread_create+0xb5/0x150 [] migration_call+0x4a/0x4de [] migration_init+0x27/0x5b [] do_one_initcall+0x3f/0x1c0 [] kernel_init+0x64/0x190 [] child_rip+0xa/0x20 [] 0xffffffffffffffff } ... key at: [] __key.18195+0x0/0x18 -> (&rq->lock){-.-.-.} ops: 0 { IN-HARDIRQ-W at: [] 0xffffffffffffffff IN-SOFTIRQ-W at: [] 0xffffffffffffffff IN-RECLAIM_FS-W at: [] __lock_acquire+0x39a/0x1100 [] lock_acquire+0x98/0x140 [] _spin_lock+0x36/0x70 [] task_rq_lock+0x52/0x90 [] set_cpus_allowed_ptr+0x2f/0x150 [] kswapd+0x79/0x750 [] kthread+0x5a/0xa0 [] child_rip+0xa/0x20 [] 0xffffffffffffffff INITIAL USE at: [] __lock_acquire+0x19e/0x1100 [] lock_acquire+0x98/0x140 [] _spin_lock_irqsave+0x48/0x90 [] rq_attach_root+0x2b/0x110 [] sched_init+0x293/0x3c5 [] start_kernel+0x145/0x37b [] x86_64_start_reservations+0x125/0x129 [] x86_64_start_kernel+0xe4/0xeb [] 0xffffffffffffffff } ... key at: [] __key.49600+0x0/0x8 -> (&vec->lock){..-...} ops: 0 { IN-SOFTIRQ-W at: [] 0xffffffffffffffff INITIAL USE at: [] __lock_acquire+0x19e/0x1100 [] lock_acquire+0x98/0x140 [] _spin_lock_irqsave+0x48/0x90 [] cpupri_set+0xec/0x190 [] rq_online_rt+0x73/0x80 [] set_rq_online+0x4e/0x70 [] rq_attach_root+0xe8/0x110 [] sched_init+0x293/0x3c5 [] start_kernel+0x145/0x37b [] x86_64_start_reservations+0x125/0x129 [] x86_64_start_kernel+0xe4/0xeb [] 0xffffffffffffffff } ... key at: [] __key.15002+0x0/0x28 ... acquired at: [] __lock_acquire+0xce0/0x1100 [] lock_acquire+0x98/0x140 [] _spin_lock_irqsave+0x48/0x90 [] cpupri_set+0xec/0x190 [] rq_online_rt+0x73/0x80 [] set_rq_online+0x4e/0x70 [] rq_attach_root+0xe8/0x110 [] sched_init+0x293/0x3c5 [] start_kernel+0x145/0x37b [] x86_64_start_reservations+0x125/0x129 [] x86_64_start_kernel+0xe4/0xeb [] 0xffffffffffffffff -> (&rt_b->rt_runtime_lock){..-...} ops: 0 { IN-SOFTIRQ-W at: [] 0xffffffffffffffff INITIAL USE at: [] __lock_acquire+0x19e/0x1100 [] lock_acquire+0x98/0x140 [] _spin_lock+0x36/0x70 [] enqueue_task_rt+0x1f4/0x290 [] enqueue_task+0x5c/0x70 [] activate_task+0x2d/0x40 [] try_to_wake_up+0x204/0x2f0 [] wake_up_process+0x15/0x20 [] migration_call+0xdc/0x4de [] migration_init+0x48/0x5b [] do_one_initcall+0x3f/0x1c0 [] kernel_init+0x64/0x190 [] child_rip+0xa/0x20 [] 0xffffffffffffffff } ... key at: [] __key.38840+0x0/0x8 -> (&cpu_base->lock){-.-...} ops: 0 { IN-HARDIRQ-W at: [] 0xffffffffffffffff IN-SOFTIRQ-W at: [] 0xffffffffffffffff INITIAL USE at: [] __lock_acquire+0x19e/0x1100 [] lock_acquire+0x98/0x140 [] _spin_lock_irqsave+0x48/0x90 [] lock_hrtimer_base+0x31/0x60 [] __hrtimer_start_range_ns+0x42/0x2b0 [] enqueue_task_rt+0x242/0x290 [] enqueue_task+0x5c/0x70 [] activate_task+0x2d/0x40 [] try_to_wake_up+0x204/0x2f0 [] wake_up_process+0x15/0x20 [] migration_call+0xdc/0x4de [] migration_init+0x48/0x5b [] do_one_initcall+0x3f/0x1c0 [] kernel_init+0x64/0x190 [] child_rip+0xa/0x20 [] 0xffffffffffffffff } ... key at: [] __key.20525+0x0/0x8 ... acquired at: [] __lock_acquire+0xce0/0x1100 [] lock_acquire+0x98/0x140 [] _spin_lock_irqsave+0x48/0x90 [] lock_hrtimer_base+0x31/0x60 [] __hrtimer_start_range_ns+0x42/0x2b0 [] enqueue_task_rt+0x242/0x290 [] enqueue_task+0x5c/0x70 [] activate_task+0x2d/0x40 [] try_to_wake_up+0x204/0x2f0 [] wake_up_process+0x15/0x20 [] migration_call+0xdc/0x4de [] migration_init+0x48/0x5b [] do_one_initcall+0x3f/0x1c0 [] kernel_init+0x64/0x190 [] child_rip+0xa/0x20 [] 0xffffffffffffffff -> (&rt_rq->rt_runtime_lock){-.-...} ops: 0 { IN-HARDIRQ-W at: [] 0xffffffffffffffff IN-SOFTIRQ-W at: [] 0xffffffffffffffff INITIAL USE at: [] __lock_acquire+0x19e/0x1100 [] lock_acquire+0x98/0x140 [] _spin_lock+0x36/0x70 [] update_curr_rt+0x104/0x180 [] dequeue_task_rt+0x24/0x80 [] dequeue_task+0xb5/0xf0 [] deactivate_task+0x2d/0x40 [] thread_return+0xe7/0x722 [] schedule+0xe/0x30 [] migration_thread+0x1d8/0x2d0 [] kthread+0x5a/0xa0 [] child_rip+0xa/0x20 [] 0xffffffffffffffff } ... key at: [] __key.49580+0x0/0x8 ... acquired at: [] __lock_acquire+0xce0/0x1100 [] lock_acquire+0x98/0x140 [] _spin_lock+0x36/0x70 [] __enable_runtime+0x3d/0x80 [] rq_online_rt+0x57/0x80 [] set_rq_online+0x4e/0x70 [] migration_call+0x117/0x4de [] notifier_call_chain+0x68/0xa0 [] raw_notifier_call_chain+0x16/0x20 [] _cpu_up+0xe5/0x134 [] cpu_up+0x5f/0x6f [] kernel_init+0xbd/0x190 [] child_rip+0xa/0x20 [] 0xffffffffffffffff ... acquired at: [] __lock_acquire+0xce0/0x1100 [] lock_acquire+0x98/0x140 [] _spin_lock+0x36/0x70 [] enqueue_task_rt+0x1f4/0x290 [] enqueue_task+0x5c/0x70 [] activate_task+0x2d/0x40 [] try_to_wake_up+0x204/0x2f0 [] wake_up_process+0x15/0x20 [] migration_call+0xdc/0x4de [] migration_init+0x48/0x5b [] do_one_initcall+0x3f/0x1c0 [] kernel_init+0x64/0x190 [] child_rip+0xa/0x20 [] 0xffffffffffffffff ... acquired at: [] __lock_acquire+0xce0/0x1100 [] lock_acquire+0x98/0x140 [] _spin_lock+0x36/0x70 [] update_curr_rt+0x104/0x180 [] dequeue_task_rt+0x24/0x80 [] dequeue_task+0xb5/0xf0 [] deactivate_task+0x2d/0x40 [] thread_return+0xe7/0x722 [] schedule+0xe/0x30 [] migration_thread+0x1d8/0x2d0 [] kthread+0x5a/0xa0 [] child_rip+0xa/0x20 [] 0xffffffffffffffff -> (&rq->lock/1){..-...} ops: 0 { IN-SOFTIRQ-W at: [] 0xffffffffffffffff INITIAL USE at: [] __lock_acquire+0x19e/0x1100 [] lock_acquire+0x98/0x140 [] _spin_lock_nested+0x34/0x70 [] double_rq_lock+0x3a/0xa0 [] __migrate_task+0x64/0x120 [] migration_thread+0xa5/0x2d0 [] kthread+0x5a/0xa0 [] child_rip+0xa/0x20 [] 0xffffffffffffffff } ... key at: [] __key.49600+0x1/0x8 -> (&sig->cputimer.lock){-.-...} ops: 0 { IN-HARDIRQ-W at: [] 0xffffffffffffffff IN-SOFTIRQ-W at: [] 0xffffffffffffffff INITIAL USE at: [] __lock_acquire+0x19e/0x1100 [] lock_acquire+0x98/0x140 [] _spin_lock_irqsave+0x48/0x90 [] thread_group_cputimer+0x3d/0x100 [] posix_cpu_timers_exit_group+0x1a/0x40 [] release_task+0x368/0x480 [] do_exit+0x5b5/0x7c0 [] ____call_usermodehelper+0x155/0x160 [] child_rip+0xa/0x20 [] 0xffffffffffffffff } ... key at: [] __key.15894+0x0/0x8 ... acquired at: [] __lock_acquire+0xce0/0x1100 [] lock_acquire+0x98/0x140 [] _spin_lock+0x36/0x70 [] update_curr+0x103/0x140 [] enqueue_task_fair+0x5f/0x2f0 [] enqueue_task+0x5c/0x70 [] activate_task+0x2d/0x40 [] __migrate_task+0xf4/0x120 [] migration_thread+0xa5/0x2d0 [] kthread+0x5a/0xa0 [] child_rip+0xa/0x20 [] 0xffffffffffffffff ... acquired at: [] __lock_acquire+0xce0/0x1100 [] lock_acquire+0x98/0x140 [] _spin_lock_nested+0x34/0x70 [] double_rq_lock+0x3a/0xa0 [] __migrate_task+0x64/0x120 [] migration_thread+0xa5/0x2d0 [] kthread+0x5a/0xa0 [] child_rip+0xa/0x20 [] 0xffffffffffffffff ... acquired at: [] __lock_acquire+0xce0/0x1100 [] lock_acquire+0x98/0x140 [] _spin_lock+0x36/0x70 [] update_curr+0x103/0x140 [] enqueue_task_fair+0x5f/0x2f0 [] enqueue_task+0x5c/0x70 [] activate_task+0x2d/0x40 [] try_to_wake_up+0x204/0x2f0 [] default_wake_function+0x12/0x20 [] __wake_up_common+0x59/0x90 [] complete+0x46/0x60 [] kthreadd+0xba/0x150 [] child_rip+0xa/0x20 [] 0xffffffffffffffff -> (latency_lock){-.-...} ops: 0 { IN-HARDIRQ-W at: [] 0xffffffffffffffff IN-SOFTIRQ-W at: [] 0xffffffffffffffff INITIAL USE at: [] __lock_acquire+0x19e/0x1100 [] lock_acquire+0x98/0x140 [] _spin_lock_irqsave+0x48/0x90 [] __account_scheduler_latency+0x93/0x310 [] enqueue_task_fair+0x2e5/0x2f0 [] enqueue_task+0x5c/0x70 [] activate_task+0x2d/0x40 [] try_to_wake_up+0x204/0x2f0 [] default_wake_function+0x12/0x20 [] __wake_up_common+0x59/0x90 [] __wake_up+0x48/0x70 [] audit_log_end+0x120/0x170 [] audit_log_exit+0x2ad/0x1110 [] audit_syscall_exit+0x254/0x260 [] sysret_audit+0x14/0x1e [] 0xffffffffffffffff } ... key at: [] latency_lock+0x18/0x40 ... acquired at: [] __lock_acquire+0xce0/0x1100 [] lock_acquire+0x98/0x140 [] _spin_lock_irqsave+0x48/0x90 [] __account_scheduler_latency+0x93/0x310 [] enqueue_task_fair+0x2e5/0x2f0 [] enqueue_task+0x5c/0x70 [] activate_task+0x2d/0x40 [] try_to_wake_up+0x204/0x2f0 [] default_wake_function+0x12/0x20 [] __wake_up_common+0x59/0x90 [] __wake_up+0x48/0x70 [] audit_log_end+0x120/0x170 [] audit_log_exit+0x2ad/0x1110 [] audit_syscall_exit+0x254/0x260 [] sysret_audit+0x14/0x1e [] 0xffffffffffffffff ... acquired at: [] __lock_acquire+0xce0/0x1100 [] lock_acquire+0x98/0x140 [] _spin_lock+0x36/0x70 [] task_rq_lock+0x52/0x90 [] try_to_wake_up+0x49/0x2f0 [] default_wake_function+0x12/0x20 [] __wake_up_common+0x59/0x90 [] complete+0x46/0x60 [] kthread+0x39/0xa0 [] child_rip+0xa/0x20 [] 0xffffffffffffffff -> (&ep->lock){..-...} ops: 0 { IN-SOFTIRQ-W at: [] 0xffffffffffffffff INITIAL USE at: [] __lock_acquire+0x19e/0x1100 [] lock_acquire+0x98/0x140 [] _spin_lock_irqsave+0x48/0x90 [] sys_epoll_ctl+0x3a7/0x550 [] system_call_fastpath+0x16/0x1b [] 0xffffffffffffffff } ... key at: [] __key.23029+0x0/0x10 ... acquired at: [] __lock_acquire+0xce0/0x1100 [] lock_acquire+0x98/0x140 [] _spin_lock+0x36/0x70 [] task_rq_lock+0x52/0x90 [] try_to_wake_up+0x49/0x2f0 [] default_wake_function+0x12/0x20 [] __wake_up_common+0x59/0x90 [] __wake_up_locked+0x18/0x20 [] ep_poll_callback+0x90/0x130 [] __wake_up_common+0x59/0x90 [] __wake_up_sync_key+0x53/0x80 [] sock_def_readable+0x49/0x80 [] unix_stream_connect+0x467/0x480 [] sys_connect+0xa7/0xc0 [] system_call_fastpath+0x16/0x1b [] 0xffffffffffffffff ... acquired at: [] __lock_acquire+0xce0/0x1100 [] lock_acquire+0x98/0x140 [] _spin_lock_irqsave+0x48/0x90 [] ep_poll_callback+0x2d/0x130 [] __wake_up_common+0x59/0x90 [] __wake_up_sync_key+0x53/0x80 [] sock_def_readable+0x49/0x80 [] unix_stream_connect+0x467/0x480 [] sys_connect+0xa7/0xc0 [] system_call_fastpath+0x16/0x1b [] 0xffffffffffffffff ... acquired at: [] 0xffffffffffffffff -> (&cwq->lock){-.-...} ops: 0 { IN-HARDIRQ-W at: [] 0xffffffffffffffff IN-SOFTIRQ-W at: [] 0xffffffffffffffff INITIAL USE at: [] __lock_acquire+0x19e/0x1100 [] lock_acquire+0x98/0x140 [] _spin_lock_irqsave+0x48/0x90 [] __queue_work+0x24/0x50 [] queue_work_on+0x42/0x60 [] queue_work+0x1f/0x30 [] call_usermodehelper_exec+0xd2/0xf0 [] kobject_uevent_env+0x3a5/0x430 [] kobject_uevent+0xb/0x10 [] kset_register+0x52/0x60 [] bus_register+0xe2/0x2f0 [] platform_bus_init+0x29/0x41 [] driver_init+0x1d/0x29 [] kernel_init+0x127/0x190 [] child_rip+0xa/0x20 [] 0xffffffffffffffff } ... key at: [] __key.24067+0x0/0x8 ... acquired at: [] __lock_acquire+0xce0/0x1100 [] lock_acquire+0x98/0x140 [] _spin_lock_irqsave+0x48/0x90 [] __wake_up+0x32/0x70 [] insert_work+0x7a/0xc0 [] __queue_work+0x36/0x50 [] queue_work_on+0x42/0x60 [] queue_work+0x1f/0x30 [] call_usermodehelper_exec+0xd2/0xf0 [] kobject_uevent_env+0x3a5/0x430 [] kobject_uevent+0xb/0x10 [] kset_register+0x52/0x60 [] bus_register+0xe2/0x2f0 [] platform_bus_init+0x29/0x41 [] driver_init+0x1d/0x29 [] kernel_init+0x127/0x190 [] child_rip+0xa/0x20 [] 0xffffffffffffffff ... acquired at: [] __lock_acquire+0xce0/0x1100 [] lock_acquire+0x98/0x140 [] _spin_lock_irqsave+0x48/0x90 [] __queue_work+0x24/0x50 [] queue_work_on+0x42/0x60 [] queue_work+0x1f/0x30 [] iwl_scan_initiate+0xcd/0x2a0 [iwlcore] [] iwl_mac_hw_scan+0x10b/0x390 [iwlcore] [] ieee80211_start_scan+0x9c/0x310 [mac80211] [] ieee80211_sta_work+0x222/0xa70 [mac80211] [] worker_thread+0x1e8/0x3a0 [] kthread+0x5a/0xa0 [] child_rip+0xa/0x20 [] 0xffffffffffffffff -> (&priv->hcmd_lock){..-...} ops: 0 { IN-SOFTIRQ-W at: [] 0xffffffffffffffff INITIAL USE at: [] __lock_acquire+0x19e/0x1100 [] lock_acquire+0x98/0x140 [] _spin_lock_irqsave+0x48/0x90 [] iwl_enqueue_hcmd+0xb9/0x540 [iwlcore] [] iwl_send_cmd_sync+0x71/0x4d0 [iwlcore] [] iwl_send_cmd_pdu+0x39/0x40 [iwlcore] [] iwl_send_bt_config+0x3b/0x40 [iwlcore] [] iwl3945_bg_alive_start+0x2e9/0x830 [iwl3945] [] worker_thread+0x1e8/0x3a0 [] kthread+0x5a/0xa0 [] child_rip+0xa/0x20 [] 0xffffffffffffffff } ... key at: [] __key.46204+0x0/0xffffffffffff281f [iwl3945] ... acquired at: [] __lock_acquire+0xce0/0x1100 [] lock_acquire+0x98/0x140 [] _spin_lock_irqsave+0x48/0x90 [] map_single+0xa4/0x260 [] swiotlb_map_page+0xd2/0x1a0 [] iwl_enqueue_hcmd+0x222/0x540 [iwlcore] [] iwl_send_cmd_sync+0x71/0x4d0 [iwlcore] [] iwl_send_cmd_pdu+0x39/0x40 [iwlcore] [] iwl_send_bt_config+0x3b/0x40 [iwlcore] [] iwl3945_bg_alive_start+0x2e9/0x830 [iwl3945] [] worker_thread+0x1e8/0x3a0 [] kthread+0x5a/0xa0 [] child_rip+0xa/0x20 [] 0xffffffffffffffff ... acquired at: [] 0xffffffffffffffff -> (&list->lock#7){..-...} ops: 0 { IN-SOFTIRQ-W at: [] 0xffffffffffffffff INITIAL USE at: [] 0xffffffffffffffff } ... key at: [] __key.19075+0x0/0xffffffffffff25c1 [mac80211] ... acquired at: [] 0xffffffffffffffff -> (&(&priv->scan_check)->timer){+.-...} ops: 0 { HARDIRQ-ON-W at: [] 0xffffffffffffffff IN-SOFTIRQ-W at: [] 0xffffffffffffffff INITIAL USE at: [] __lock_acquire+0x19e/0x1100 [] lock_acquire+0x98/0x140 [] del_timer_sync+0x41/0xa0 [] iwl3945_mac_add_interface+0x14d/0x420 [iwl3945] [] ieee80211_open+0x408/0x920 [mac80211] [] dev_open+0x9a/0x100 [] dev_change_flags+0x96/0x1c0 [] do_setlink+0x212/0x4a0 [] rtnl_setlink+0x122/0x160 [] rtnetlink_rcv_msg+0x1df/0x220 [] netlink_rcv_skb+0xa9/0xd0 [] rtnetlink_rcv+0x25/0x40 [] netlink_unicast+0x29a/0x2f0 [] netlink_sendmsg+0x222/0x320 [] sock_sendmsg+0x10b/0x140 [] sys_sendmsg+0x1d0/0x330 [] system_call_fastpath+0x16/0x1b [] 0xffffffffffffffff } ... key at: [] __key.46148+0x0/0xffffffffffff27ff [iwl3945] ... acquired at: [] 0xffffffffffffffff stack backtrace: Pid: 0, comm: swapper Not tainted 2.6.30-rc3-00324-g8087eae #55 Call Trace: [] print_irq_inversion_bug+0x13f/0x160 [] ? check_usage_backwards+0x0/0xc0 [] check_usage_backwards+0x65/0xc0 [] mark_lock+0x1b8/0x430 [] __lock_acquire+0x4c1/0x1100 [] lock_acquire+0x98/0x140 [] ? run_timer_softirq+0x120/0x2e0 [] ? delayed_work_timer_fn+0x0/0x50 [] run_timer_softirq+0x1df/0x2e0 [] ? run_timer_softirq+0x120/0x2e0 [] ? __do_softirq+0x61/0x1f0 [] __do_softirq+0xa2/0x1f0 [] call_softirq+0x1c/0x50 [] do_softirq+0x75/0xc0 [] irq_exit+0x95/0xa0 [] smp_apic_timer_interrupt+0x71/0x9c [] apic_timer_interrupt+0x13/0x20 [] ? lock_acquire+0xb2/0x140 [] ? __atomic_notifier_call_chain+0x0/0xa0 [] ? clockevents_notify+0x42/0xb0 [] ? __atomic_notifier_call_chain+0x51/0xa0 [] ? __atomic_notifier_call_chain+0x0/0xa0 [] ? atomic_notifier_call_chain+0x11/0x20 [] ? cpu_idle+0x98/0xc0 [] ? start_secondary+0x189/0x18e ....... CE: hpet increasing min_delta_ns to 15000 nsec usb 3-1: USB disconnect, address 2 btusb_intr_complete: hci0 urb ffff880138eb4600 failed to resubmit (19) btusb_bulk_complete: hci0 urb ffff880138eb4180 failed to resubmit (19) btusb_bulk_complete: hci0 urb ffff880138eb4000 failed to resubmit (19) btusb_send_frame: hci0 urb ffff880092d6ea80 submission failed wlan0: direct probe to AP 00:11:d8:da:65:40 try 1 wlan0 direct probe responded wlan0: authenticate with AP 00:11:d8:da:65:40 wlan0: authenticated wlan0: associate with AP 00:11:d8:da:65:40 wlan0: RX AssocResp from 00:11:d8:da:65:40 (capab=0x401 status=0 aid=1) wlan0: associated wlan0: disassociating by local choice (reason=3) [drm:i915_get_vblank_counter] *ERROR* trying to get vblank count for disabled pipe 0 PM: Syncing filesystems ... done. Freezing user space processes ... (elapsed 0.00 seconds) done. Freezing remaining freezable tasks ... (elapsed 0.00 seconds) done. -- To unsubscribe from this list: send the line "unsubscribe linux-kernel" in the body of a message to majordomo@vger.kernel.org More majordomo info at http://vger.kernel.org/majordomo-info.html Please read the FAQ at http://www.tux.org/lkml/