Search Linux Wireless

Re: Busy looping iwl3945 driver

[Date Prev][Date Next][Thread Prev][Thread Next][Date Index][Thread Index]

 



Ahh

Seems to be related to this thread:

https://lkml.org/lkml/2011/11/29/102



2011/12/1 Zdenek Kabelac <zdenek.kabelac@xxxxxxxxx>:
> Hi
>
> Today I've noticed  100% CPU occupied by kworker thread - which
> appeared to be the result of some  iwl3945 driver interaction.
>
> Here are some trace of some tasks from that moment.
> It looked like when I've then even tried to run 'mc' of  'ifconfig' -
> they could not start since some device lock was kept busy.
>
> The problem was most probably caused by doing suspend when attached to
> the wireless network  and resuming in wired docking station,
> without having the previous wireless AP available.
>
> My hw -  T61, 4G, C2D, iwl3945 wifi,   X86_64 kernel 3.2.0-rc3
> Only reboot fixed this problem.
>
>
>  NetworkManager  D 0000000000000000     0   648      1 0x00000004
>  ffff8801301157e8 0000000000000046 ffffffff814d9fe4 0000000000000000
>  ffff8801301157f8 ffff88012c968000 0000000000000000 ffff880130115fd8
>  ffff880130115fd8 ffff880130115fd8 ffffffff81a0d020 ffff88012c968000
>  Call Trace:
>  [<ffffffff814d9fe4>] ? __schedule+0x994/0xb50
>  [<ffffffff814da23f>] schedule+0x3f/0x60
>  [<ffffffff814db1c2>] mutex_lock_nested+0x1b2/0x3f0
>  [<ffffffffa033984f>] ? ieee80211_ifa_changed+0x9f/0x150 [mac80211]
>  [<ffffffffa033984f>] ? ieee80211_ifa_changed+0x9f/0x150 [mac80211]
>  [<ffffffffa033984f>] ieee80211_ifa_changed+0x9f/0x150 [mac80211]
>  [<ffffffff814e17e3>] notifier_call_chain+0x93/0x100
>  [<ffffffff81082cf8>] __blocking_notifier_call_chain+0x78/0xb0
>  [<ffffffff81082d46>] blocking_notifier_call_chain+0x16/0x20
>  [<ffffffff814856b6>] __inet_del_ifa+0xb6/0x290
>  [<ffffffff8148633e>] inet_rtm_deladdr+0x10e/0x160
>  [<ffffffff81436544>] rtnetlink_rcv_msg+0x124/0x2e0
>  [<ffffffff81436420>] ? __rtnl_unlock+0x20/0x20
>  [<ffffffff81446c49>] netlink_rcv_skb+0xa9/0xd0
>  [<ffffffff81434465>] rtnetlink_rcv+0x25/0x40
>  [<ffffffff81446580>] netlink_unicast+0x2c0/0x310
>  [<ffffffff81446886>] netlink_sendmsg+0x2b6/0x310
>  [<ffffffff81410458>] ? sock_update_classid+0xb8/0x1f0
>  [<ffffffff81409cee>] sock_sendmsg+0x10e/0x1e0
>  [<ffffffff8140babd>] ? sock_recvmsg+0x18d/0x210
>  [<ffffffff8112dce3>] ? might_fault+0x53/0xb0
>  [<ffffffff8112dd2c>] ? might_fault+0x9c/0xb0
>  [<ffffffff8112dce3>] ? might_fault+0x53/0xb0
>  [<ffffffff814191c6>] ? verify_iovec+0x56/0xd0
>  [<ffffffff8140c116>] __sys_sendmsg+0x376/0x380
>  [<ffffffff81161458>] ? fget_light+0x48/0x3b0
>  [<ffffffff811614f1>] ? fget_light+0xe1/0x3b0
>  [<ffffffff81161458>] ? fget_light+0x48/0x3b0
>  [<ffffffff8118149f>] ? mntput+0x1f/0x30
>  [<ffffffff8140db19>] sys_sendmsg+0x49/0x90
>  [<ffffffff814e562b>] system_call_fastpath+0x16/0x1b
>
>
>
> avahi-daemon    D 0000000000000246     0   667      1 0x00000000
>  ffff880127df5ba8 0000000000000046 0000000000000000 0000000000000000
>  ffff880127df5bb8 ffff880128be4600 0000000000000000 ffff880127df5fd8
>  ffff880127df5fd8 ffff880127df5fd8 ffff88012ca72300 ffff880128be4600
> Call Trace:
>  [<ffffffff814da23f>] schedule+0x3f/0x60
>  [<ffffffff814db1c2>] mutex_lock_nested+0x1b2/0x3f0
>  [<ffffffff81434437>] ? rtnl_lock+0x17/0x20
>  [<ffffffff81434437>] ? rtnl_lock+0x17/0x20
>  [<ffffffff81434437>] rtnl_lock+0x17/0x20
>  [<ffffffff8148ff6f>] ip_mc_leave_group+0x2f/0x180
>  [<ffffffff8145b2f4>] do_ip_setsockopt+0x6d4/0xdb0
>  [<ffffffff81409cee>] ? sock_sendmsg+0x10e/0x1e0
>  [<ffffffff8116a1fc>] ? pipe_write+0x2ec/0x5b0
>  [<ffffffff811a1b43>] ? fsnotify+0x93/0x600
>  [<ffffffff8140d6f1>] ? sys_sendto+0x101/0x130
>  [<ffffffff8145bad6>] ip_setsockopt+0x36/0xb0
>  [<ffffffff8147dcab>] udp_setsockopt+0x1b/0x40
>  [<ffffffff8140e574>] sock_common_setsockopt+0x14/0x20
>  [<ffffffff8140d8ff>] sys_setsockopt+0x7f/0xe0
>  [<ffffffff814e562b>] system_call_fastpath+0x16/0x1b
>
>
> wpa_supplicant  D 0000000000000246     0   770      1 0x00000000
>  ffff880127c17908 0000000000000046 0000000000000000 0000000000000000
>  ffff880127c17918 ffff880127e02300 0000000000000000 ffff880127c17fd8
>  ffff880127c17fd8 ffff880127c17fd8 ffff88012ca72300 ffff880127e02300
> Call Trace:
>  [<ffffffff814da23f>] schedule+0x3f/0x60
>  [<ffffffff814db1c2>] mutex_lock_nested+0x1b2/0x3f0
>  [<ffffffff81434437>] ? rtnl_lock+0x17/0x20
>  [<ffffffff81434437>] ? rtnl_lock+0x17/0x20
>  [<ffffffff81434437>] rtnl_lock+0x17/0x20
>  [<ffffffffa02ec1d5>] nl80211_pre_doit+0xf5/0x190 [cfg80211]
>  [<ffffffff81447263>] genl_rcv_msg+0x1c3/0x250
>  [<ffffffff814470a0>] ? genl_rcv+0x40/0x40
>  [<ffffffff81446c49>] netlink_rcv_skb+0xa9/0xd0
>  [<ffffffff81447085>] genl_rcv+0x25/0x40
>  [<ffffffff81446580>] netlink_unicast+0x2c0/0x310
>  [<ffffffff81446886>] netlink_sendmsg+0x2b6/0x310
>  [<ffffffff81410458>] ? sock_update_classid+0xb8/0x1f0
>  [<ffffffff81409cee>] sock_sendmsg+0x10e/0x1e0
>  [<ffffffff8140babd>] ? sock_recvmsg+0x18d/0x210
>  [<ffffffff8112dce3>] ? might_fault+0x53/0xb0
>  [<ffffffff8112dd2c>] ? might_fault+0x9c/0xb0
>  [<ffffffff8112dce3>] ? might_fault+0x53/0xb0
>  [<ffffffff814191c6>] ? verify_iovec+0x56/0xd0
>  [<ffffffff8140c116>] __sys_sendmsg+0x376/0x380
>  [<ffffffff8106a904>] ? set_current_blocked+0x34/0x60
>  [<ffffffff8100bee1>] ? fpu_finit+0x21/0x40
>  [<ffffffff8100bf4a>] ? init_fpu+0x4a/0x180
>  [<ffffffff8100d198>] ? restore_i387_xstate+0xc8/0x1d0
>  [<ffffffff811615bf>] ? fget_light+0x1af/0x3b0
>  [<ffffffff814ddedb>] ? _raw_spin_unlock_irq+0x3b/0x60
>  [<ffffffff8140db19>] sys_sendmsg+0x49/0x90
>  [<ffffffff814e562b>] system_call_fastpath+0x16/0x1b
>
>
>
> ntpd            D 0000000000000246     0 21324      1 0x00000000
>  ffff880127adfca8 0000000000000046 0000000000000000 0000000000000000
>  ffff880127adfcb8 ffff880127f5c600 0000000000000000 ffff880127adffd8
>  ffff880127adffd8 ffff880127adffd8 ffff8800379e4600 ffff880127f5c600
> Call Trace:
>  [<ffffffff814da23f>] schedule+0x3f/0x60
>  [<ffffffff814db1c2>] mutex_lock_nested+0x1b2/0x3f0
>  [<ffffffff81434437>] ? rtnl_lock+0x17/0x20
>  [<ffffffff81434437>] ? rtnl_lock+0x17/0x20
>  [<ffffffff81434437>] rtnl_lock+0x17/0x20
>  [<ffffffff81428165>] dev_ioctl+0x3c5/0x660
>  [<ffffffff81090b80>] ? lockdep_init_map+0xf0/0x590
>  [<ffffffff814092ad>] sock_do_ioctl+0x5d/0x70
>  [<ffffffff81409339>] sock_ioctl+0x79/0x2f0
>  [<ffffffff811736f8>] do_vfs_ioctl+0x98/0x570
>  [<ffffffff814dddf5>] ? _raw_spin_unlock+0x35/0x60
>  [<ffffffff8115d7cd>] ? fd_install+0x7d/0x100
>  [<ffffffff811615bf>] ? fget_light+0x1af/0x3b0
>  [<ffffffff81173c61>] sys_ioctl+0x91/0xa0
>  [<ffffffff814e562b>] system_call_fastpath+0x16/0x1b
>
>
>
> Here I've tried  'mc'  and rmmod of iwl3945 (which had in fact open count == 0)
>
>
>
> mc              D 0000000000000000     0 12325  12174 0x00000000
>  ffff880007a77ca8 0000000000000046 ffffffff814d9fe4 0000000000000000
>  ffff880007a77cb8 ffff880037918000 0000000000000000 ffff880007a77fd8
>  ffff880007a77fd8 ffff880007a77fd8 ffffffff81a0d020 ffff880037918000
> Call Trace:
>  [<ffffffff814d9fe4>] ? __schedule+0x994/0xb50
>  [<ffffffff814da23f>] schedule+0x3f/0x60
>  [<ffffffff814db1c2>] mutex_lock_nested+0x1b2/0x3f0
>  [<ffffffff81434437>] ? rtnl_lock+0x17/0x20
>  [<ffffffff81434437>] ? rtnl_lock+0x17/0x20
>  [<ffffffff81434437>] rtnl_lock+0x17/0x20
>  [<ffffffff81428165>] dev_ioctl+0x3c5/0x660
>  [<ffffffff81090b80>] ? lockdep_init_map+0xf0/0x590
>  [<ffffffff814092ad>] sock_do_ioctl+0x5d/0x70
>  [<ffffffff81409339>] sock_ioctl+0x79/0x2f0
>  [<ffffffff811736f8>] do_vfs_ioctl+0x98/0x570
>  [<ffffffff814dddf5>] ? _raw_spin_unlock+0x35/0x60
>  [<ffffffff8115d7cd>] ? fd_install+0x7d/0x100
>  [<ffffffff811615bf>] ? fget_light+0x1af/0x3b0
>  [<ffffffff81173c61>] sys_ioctl+0x91/0xa0
>  [<ffffffff814e562b>] system_call_fastpath+0x16/0x1b
> rmmod           D 0000000000000000     0 12340  22112 0x00000000
>  ffff880051811bf8 0000000000000046 ffffffff814d9fe4 ffffffff814d5fd1
>  0000000000000001 ffff8800b0cd8000 0000000000000046 ffff880051811fd8
>  ffff880051811fd8 ffff880051811fd8 ffff880136d44600 ffff8800b0cd8000
> Call Trace:
>  [<ffffffff814d9fe4>] ? __schedule+0x994/0xb50
>  [<ffffffff814d5fd1>] ? __slab_free+0x233/0x3aa
>  [<ffffffff814da23f>] schedule+0x3f/0x60
>  [<ffffffff814dd265>] rwsem_down_failed_common+0xc5/0x160
>  [<ffffffff814dd313>] rwsem_down_write_failed+0x13/0x20
>  [<ffffffff812d2733>] call_rwsem_down_write_failed+0x13/0x20
>  [<ffffffff81082a07>] ? blocking_notifier_chain_unregister+0x27/0xe0
>  [<ffffffff814dc629>] ? down_write+0x89/0xb0
>  [<ffffffff81082a07>] ? blocking_notifier_chain_unregister+0x27/0xe0
>  [<ffffffff81082a07>] blocking_notifier_chain_unregister+0x27/0xe0
>  [<ffffffff81485cd8>] unregister_inetaddr_notifier+0x18/0x20
>  [<ffffffffa03380b6>] ieee80211_unregister_hw+0x46/0x120 [mac80211]
>  [<ffffffffa03b4779>] iwl3945_pci_remove+0x57/0x300 [iwl3945]
>  [<ffffffff814dde62>] ? _raw_spin_unlock_irqrestore+0x42/0x80
>  [<ffffffff812ee156>] pci_device_remove+0x46/0x110
>  [<ffffffff813926bc>] __device_release_driver+0x7c/0xe0
>  [<ffffffff81392fc8>] driver_detach+0xd8/0xe0
>  [<ffffffff813924f1>] bus_remove_driver+0x91/0x110
>  [<ffffffff813937b2>] driver_unregister+0x62/0xa0
>  [<ffffffff812ecf94>] pci_unregister_driver+0x44/0xc0
>  [<ffffffffa03b4a32>] iwl3945_exit+0x10/0x17 [iwl3945]
>  [<ffffffff810a169e>] sys_delete_module+0x16e/0x250
>  [<ffffffff8109647f>] ? trace_hardirqs_on_caller+0x1f/0x1b0
>  [<ffffffff814e562b>] system_call_fastpath+0x16/0x1b
>
>
>
> Here is  'pert record -a -g'
>
> # Overhead          Command               Shared Object
>                       Symbol
> # ........  ...............  ..........................
> ...........................................
> #
>    97.57%      kworker/u:3  [kernel.kallsyms]           [k] lock_timer_base
>                |
>                --- lock_timer_base
>                    try_to_del_timer_sync
>                    del_timer_sync
>                    iwl3945_send_tx_power
>                    ieee80211_bss_info_change_notify
>                    ieee80211_sta_eosp_irqsafe
>                    ieee80211_sta_eosp_irqsafe
>                    ieee80211_stop_tx_ba_cb
>                    ieee80211_stop_tx_ba_cb
>                    ieee80211_stop_tx_ba_cb
>                    ieee80211_stop_tx_ba_cb
>                    process_one_work
>                    worker_thread
>                    kthread
>                    kernel_thread_helper
>
>     0.38%      kworker/u:3  [kernel.kallsyms]           [k] ioread32
>                |
>                --- ioread32
>                   |
>                   |--37.76%-- iwl_legacy_send_bt_config
>                   |          iwl3945_rx_queue_restock
>                   |          iwl3945_irq_tasklet
>                   |          tasklet_action
>                   |          __do_softirq
>                   |          call_softirq
>                   |          do_softirq
>                   |          irq_exit
>                   |          __irqentry_text_start
>                   |          ret_from_intr
>                   |          try_to_del_timer_sync
>                   |          del_timer_sync
>                   |          iwl3945_send_tx_power
>                   |          ieee80211_bss_info_change_notify
>                   |          ieee80211_sta_eosp_irqsafe
>                   |          ieee80211_sta_eosp_irqsafe
>                   |          ieee80211_stop_tx_ba_cb
>                   |          ieee80211_stop_tx_ba_cb
>                   |          ieee80211_stop_tx_ba_cb
>                   |          ieee80211_stop_tx_ba_cb
>                   |          process_one_work
>                   |          worker_thread
>                   |          kthread
>                   |          kernel_thread_helper
>                   |
>                   |--31.13%-- iwl3945_irq_tasklet
>                   |          tasklet_action
>                   |          __do_softirq
>                   |          call_softirq
>                   |          do_softirq
>                   |          irq_exit
>                   |          |
>                   |          |--92.86%-- __irqentry_text_start
>                   |          |          ret_from_intr
>                   |          |          try_to_del_timer_sync
>                   |          |          del_timer_sync
>                   |          |          iwl3945_send_tx_power
>                   |          |          ieee80211_bss_info_change_notify
>                   |          |          ieee80211_sta_eosp_irqsafe
>                   |          |          ieee80211_sta_eosp_irqsafe
>                   |          |          ieee80211_stop_tx_ba_cb
>                   |          |          ieee80211_stop_tx_ba_cb
>                   |          |          ieee80211_stop_tx_ba_cb
>                   |          |          ieee80211_stop_tx_ba_cb
>                   |          |          process_one_work
>                   |          |          worker_thread
>                   |          |          kthread
>                   |          |          kernel_thread_helper
>                   |          |
>                   |           --7.14%-- smp_apic_timer_interrupt
>                   |                     apic_timer_interrupt
>                   |                     try_to_del_timer_sync
>                   |                     del_timer_sync
>                   |                     iwl3945_send_tx_power
>                   |                     ieee80211_bss_info_change_notify
>                   |                     ieee80211_sta_eosp_irqsafe
>                   |                     ieee80211_sta_eosp_irqsafe
>                   |                     ieee80211_stop_tx_ba_cb
>                   |                     ieee80211_stop_tx_ba_cb
>                   |                     ieee80211_stop_tx_ba_cb
>                   |                     ieee80211_stop_tx_ba_cb
>                   |                     process_one_work
>                   |                     worker_thread
>                   |                     kthread
>                   |                     kernel_thread_helper
>                   |
>                    --31.12%-- iwl_legacy_add_beacon_time
>                              handle_irq_event_percpu
>                              handle_irq_event
>                              handle_edge_irq
>                              handle_irq
>                              __irqentry_text_start
>                              ret_from_intr
>                              try_to_del_timer_sync
>                              del_timer_sync
>                              iwl3945_send_tx_power
>                              ieee80211_bss_info_change_notify
>                              ieee80211_sta_eosp_irqsafe
>                              ieee80211_sta_eosp_irqsafe
>                              ieee80211_stop_tx_ba_cb
>                              ieee80211_stop_tx_ba_cb
>                              ieee80211_stop_tx_ba_cb
>                              ieee80211_stop_tx_ba_cb
>                              process_one_work
>                              worker_thread
>                              kthread
>                              kernel_thread_helper
>
>     0.27%      kworker/u:3  [kernel.kallsyms]           [k] read_hpet
> ....
>
> Zdenek
--
To unsubscribe from this list: send the line "unsubscribe linux-wireless" in
the body of a message to majordomo@xxxxxxxxxxxxxxx
More majordomo info at  http://vger.kernel.org/majordomo-info.html


[Index of Archives]     [Linux Host AP]     [ATH6KL]     [Linux Bluetooth]     [Linux Netdev]     [Kernel Newbies]     [Linux Kernel]     [IDE]     [Security]     [Git]     [Netfilter]     [Bugtraq]     [Yosemite News]     [MIPS Linux]     [ARM Linux]     [Linux Security]     [Linux RAID]     [Linux ATA RAID]     [Samba]     [Device Mapper]
  Powered by Linux