Hello,I've been seeing soft lockups related to ath5k on my EEEPC 1000 HG for some time. The one in the attached log happended directly after executing
rmmod ath5k modprobe ath5k(The access point is known to have hiccups from time to time, so when the first connection attempt failed, I tried to reload the ath5k module.)
Generally, I'm getting system freezes sporadically, maybe every couple of days. Since I've dug up one other ath5k-related soft lockup from my logs (2.6.32-rc8), to my mind the simplest explanation would be that all the other freezes were also due to ath5k. I've never had any freezes with kernels from the 2.6.31 line, therefore I think the described behaviour constitutes a regression. So far, I've seen freezes with 2.6.32-rc4, 2.6.32-rc6, 2.6.32-rc8, 2.6.32 and 2.6.32.1. I've been running some time on 2.6.32-rc3 without freeze, but since the frequency of the freezes is so low, I wouldn't consider that conclusive, yet.
Hope that helps! Write me, if you need more information. (And please CC: me for replies as I'm not subscribed to the list.)
Cheers! Thiemo Nagel
[86928.475836] wlan0: direct probe to AP 00:30:f1:ac:77:ec (try 1) [86928.478363] wlan0: direct probe responded [86928.478379] wlan0: authenticate with AP 00:30:f1:ac:77:ec (try 1) [86928.494476] wlan0: authenticated [86928.494671] wlan0: associate with AP 00:30:f1:ac:77:ec (try 1) [86928.697209] wlan0: associate with AP 00:30:f1:ac:77:ec (try 2) [86928.897190] wlan0: associate with AP 00:30:f1:ac:77:ec (try 3) [86929.097369] wlan0: association with AP 00:30:f1:ac:77:ec timed out [86930.034739] ath5k 0000:01:00.0: PCI INT A disabled [86936.392681] ath5k 0000:01:00.0: PCI INT A -> GSI 19 (level, low) -> IRQ 19 [86936.392753] ath5k 0000:01:00.0: setting latency timer to 64 [86936.392999] ath5k 0000:01:00.0: registered as 'phy1' [86936.488690] ath: EEPROM regdomain: 0x60 [86936.488699] ath: EEPROM indicates we should expect a direct regpair map [86936.488709] ath: Country alpha2 being used: 00 [86936.488715] ath: Regpair used: 0x60 [86936.492620] phy1: Selected rate control algorithm 'minstrel' [86936.502413] Registered led device: ath5k-phy1::rx [86936.505465] Registered led device: ath5k-phy1::tx [86936.505483] ath5k phy1: Atheros AR2425 chip found (MAC: 0xe2, PHY: 0x70) [87006.374036] BUG: soft lockup - CPU#0 stuck for 61s! [NetworkManager:28713] [87006.374036] Modules linked in: ath5k nls_iso8859_1 nls_cp437 vfat fat nfs lockd nfs_acl auth_rpcgss sunrpc mac80211 ath i915 drm_kms_helper drm i2c_algo_bit sco bnep rfcomm l2cap crc16 bluetooth acpi_cpufreq cpufreq_conservative cpufreq_powersave cpufreq_userspace cpufreq_stats binfmt_misc fuse loop snd_hda_codec_realtek snd_hda_intel snd_hda_codec snd_pcm_oss snd_mixer_oss option uvcvideo snd_pcm snd_seq_dummy snd_seq_oss usbserial videodev snd_seq_midi_event v4l1_compat snd_seq snd_timer snd_seq_device usb_storage eeepc_laptop snd rtc_cmos rtc_core uhci_hcd psmouse soundcore rtc_lib evdev pci_hotplug atl1e intel_agp ehci_hcd battery ac button rng_core processor snd_page_alloc agpgart sha256_generic dm_crypt dm_mod sd_mod thermal fan ata_generic ata_piix libata [last unloaded: ath5k] [87006.374036] irq event stamp: 6704963 [87006.374036] hardirqs last enabled at (6704962): [<c10030b8>] restore_all_notrace+0x0/0x18 [87006.374036] hardirqs last disabled at (6704963): [<c1003994>] apic_timer_interrupt+0x28/0x34 [87006.374036] softirqs last enabled at (6690098): [<c1035d38>] __do_softirq+0x17b/0x183 [87006.374036] softirqs last disabled at (6690101): [<c1035d6b>] do_softirq+0x2b/0x43 [87006.374036] [87006.374036] Pid: 28713, comm: NetworkManager Not tainted (2.6.32.1 #3) 1000HG [87006.374036] EIP: 0060:[<c11506f9>] EFLAGS: 00000292 CPU: 0 [87006.374036] EIP is at ioread32+0xc/0x2a [87006.374036] EAX: 00009d12 EBX: 00004d6e ECX: 00fba000 EDX: f656e000 [87006.374036] ESI: 00000002 EDI: 00009860 EBP: f6afb960 ESP: f6afb960 [87006.374036] DS: 007b ES: 007b FS: 00d8 GS: 0033 SS: 0068 [87006.374036] CR0: 8005003b CR2: b76e7000 CR3: 2f5c9000 CR4: 000006d0 [87006.374036] DR0: 00000000 DR1: 00000000 DR2: 00000000 DR3: 00000000 [87006.374036] DR6: ffff0ff0 DR7: 00000400 [87006.374036] Call Trace: [87006.374036] [<f85d09f1>] ath5k_hw_register_timeout+0x29/0x57 [ath5k] [87006.374036] [<f85d0a61>] ath5k_hw_noise_floor_calibration+0x42/0x162 [ath5k] [87006.374036] [<f85d168f>] ath5k_hw_phy_calibrate+0x40b/0x457 [ath5k] [87006.374036] [<f82be4cd>] ? ieee80211_stop_queues_by_reason+0x3e/0x45 [mac80211] [87006.374036] [<f85d65a6>] ath5k_tasklet_calibrate+0xea/0x14c [ath5k] [87006.374036] [<c10356f1>] tasklet_action+0x8d/0xe0 [87006.374036] [<c1035c83>] __do_softirq+0xc6/0x183 [87006.374036] [<c1035d6b>] do_softirq+0x2b/0x43 [87006.374036] [<c1035ef8>] irq_exit+0x38/0x7a [87006.374036] [<c1004533>] do_IRQ+0x84/0x9a [87006.374036] [<c100368e>] common_interrupt+0x2e/0x34 [87006.374036] [<c115007b>] ? sg_miter_next+0xba/0xef [87006.374036] [<c1282919>] ? schedule+0x10d/0x871 [87006.374036] [<c1053abf>] ? mark_held_locks+0x47/0x5f [87006.374036] [<c10030b8>] ? restore_all_notrace+0x0/0x18 [87006.374036] [<c10030b8>] ? restore_all_notrace+0x0/0x18 [87006.374036] [<c10500d8>] ? tick_resume_broadcast+0x1d/0x7a [87006.374036] [<c12831cc>] preempt_schedule_irq+0x30/0x4b [87006.374036] [<c1002f32>] need_resched+0x1f/0x21 [87006.374036] [<c11506f9>] ? ioread32+0xc/0x2a [87006.374036] [<f85cd772>] ? ath5k_hw_reset_key+0x6c/0x16a [ath5k] [87006.374036] [<f85d7437>] ? ath5k_rfkill_hw_start+0xd5/0xdd [ath5k] [87006.374036] [<f85d60e8>] ? ath5k_start+0xc2/0xf5 [ath5k] [87006.374036] [<f82b624b>] ? ieee80211_open+0x22f/0x5a5 [mac80211] [87006.374036] [<c1286bc7>] ? notifier_call_chain+0x49/0x71 [87006.374036] [<c1204e32>] ? dev_open+0x93/0xd3 [87006.374036] [<c120469a>] ? dev_change_flags+0xa4/0x157 [87006.374036] [<c120be7c>] ? do_setlink+0x247/0x2ff [87006.374036] [<c128488b>] ? _read_unlock+0x38/0x44 [87006.374036] [<c120c012>] ? rtnl_setlink+0xde/0xe4 [87006.374036] [<c120bf34>] ? rtnl_setlink+0x0/0xe4 [87006.374036] [<c120cd61>] ? rtnetlink_rcv_msg+0x199/0x1b3 [87006.374036] [<c120cbc8>] ? rtnetlink_rcv_msg+0x0/0x1b3 [87006.374036] [<c1219a63>] ? netlink_rcv_skb+0x30/0x76 [87006.374036] [<c120cbc0>] ? rtnetlink_rcv+0x1e/0x26 [87006.374036] [<c121988d>] ? netlink_unicast+0xc4/0x11a [87006.374036] [<c1219f62>] ? netlink_sendmsg+0x22e/0x23b [87006.374036] [<c11f6266>] ? __sock_sendmsg+0x45/0x4e [87006.374036] [<c11f6a32>] ? sock_sendmsg+0xb8/0xce [87006.374036] [<c10553bc>] ? lock_release_non_nested+0x84/0x1db [87006.374036] [<c104505f>] ? autoremove_wake_function+0x0/0x33 [87006.374036] [<c109e8d8>] ? might_fault+0x6e/0x88 [87006.374036] [<c109e8ed>] ? might_fault+0x83/0x88 [87006.374036] [<c114deb9>] ? copy_from_user+0x26/0x6a [87006.374036] [<c11fe22d>] ? verify_iovec+0x40/0x6f [87006.374036] [<c11f6b87>] ? sys_sendmsg+0x13f/0x192 [87006.374036] [<c105518c>] ? __lock_acquire+0xbdd/0xbec [87006.374036] [<c10b3277>] ? kmem_cache_free+0x99/0xd7 [87006.374036] [<c1053d24>] ? trace_hardirqs_on_caller+0x107/0x12f [87006.374036] [<c10553bc>] ? lock_release_non_nested+0x84/0x1db [87006.374036] [<c1053d57>] ? trace_hardirqs_on+0xb/0xd [87006.374036] [<c109e8d8>] ? might_fault+0x6e/0x88 [87006.374036] [<c11f7bf1>] ? sys_socketcall+0x157/0x18e [87006.374036] [<c1002fa4>] ? sysenter_do_call+0x12/0x32
[ 4480.374194] ath5k 0000:01:00.0: PCI INT A disabled [ 4491.383755] ath5k 0000:01:00.0: PCI INT A -> GSI 19 (level, low) -> IRQ 19 [ 4491.383797] ath5k 0000:01:00.0: setting latency timer to 64 [ 4491.383980] ath5k 0000:01:00.0: registered as 'phy3' [ 4491.479895] ath: EEPROM regdomain: 0x60 [ 4491.479904] ath: EEPROM indicates we should expect a direct regpair map [ 4491.479914] ath: Country alpha2 being used: 00 [ 4491.479920] ath: Regpair used: 0x60 [ 4491.484285] phy3: Selected rate control algorithm 'minstrel' [ 4491.491702] Registered led device: ath5k-phy3::rx [ 4491.491908] Registered led device: ath5k-phy3::tx [ 4491.496307] ath5k phy3: Atheros AR2425 chip found (MAC: 0xe2, PHY: 0x70) [ 4502.188810] ATL1E 0000:03:00.0: irq 27 for MSI/MSI-X [ 4505.443134] CE: hpet increasing min_delta_ns to 15000 nsec [ 4566.624783] BUG: soft lockup - CPU#0 stuck for 61s! [NetworkManager:7027] [ 4566.624783] Modules linked in: ath5k nfs lockd nfs_acl auth_rpcgss sunrpc i915 drm_kms_helper drm i2c_algo_bit sco bnep rfcomm l2cap crc16 bluetooth acpi_cpufreq cpufreq_conservative cpufreq_powersave cpufreq_userspace cpufreq_stats binfmt_misc loop snd_hda_codec_realtek snd_hda_intel snd_hda_codec snd_pcm_oss snd_mixer_oss snd_pcm snd_seq_dummy uvcvideo snd_seq_oss videodev option snd_seq_midi_event v4l1_compat usbserial snd_seq snd_timer snd_seq_device usb_storage mac80211 snd eeepc_laptop uhci_hcd atl1e ath soundcore rtc_cmos ehci_hcd psmouse rtc_core intel_agp rng_core rtc_lib snd_page_alloc agpgart evdev battery pci_hotplug ac processor button sha256_generic dm_crypt dm_mod sd_mod thermal fan ata_generic ata_piix libata [last unloaded: ath5k] [ 4566.624783] irq event stamp: 6671091 [ 4566.624783] hardirqs last enabled at (6671090): [<c1003078>] restore_all_notrace+0x0/0x18 [ 4566.624783] hardirqs last disabled at (6671091): [<c1003954>] apic_timer_interrupt+0x28/0x34 [ 4566.624783] softirqs last enabled at (6665256): [<c1035cf0>] __do_softirq+0x17b/0x183 [ 4566.624783] softirqs last disabled at (6665259): [<c1035d23>] do_softirq+0x2b/0x43 [ 4566.624783] [ 4566.624783] Pid: 7027, comm: NetworkManager Not tainted (2.6.32-rc8 #8) 1000HG [ 4566.624783] EIP: 0060:[<c1282a9f>] EFLAGS: 00200202 CPU: 0 [ 4566.624783] EIP is at preempt_schedule+0x0/0x3b [ 4566.624783] EAX: f6b14000 EBX: e66f3958 ECX: 00fbb000 EDX: 00000001 [ 4566.624783] ESI: 00005dc1 EDI: e66f397c EBP: f6b15958 ESP: f6b15944 [ 4566.624783] DS: 007b ES: 007b FS: 00d8 GS: 0033 SS: 0068 [ 4566.624783] CR0: 8005003b CR2: b76a3d60 CR3: 32aa3000 CR4: 000006d0 [ 4566.624783] DR0: 00000000 DR1: 00000000 DR2: 00000000 DR3: 00000000 [ 4566.624783] DR6: ffff0ff0 DR7: 00000400 [ 4566.624783] Call Trace: [ 4566.624783] [<c114d2b1>] ? delay_tsc+0x53/0x92 [ 4566.624783] [<c114d23f>] __const_udelay+0x2f/0x31 [ 4566.624783] [<f86ada0c>] ath5k_hw_register_timeout+0x44/0x57 [ath5k] [ 4566.624783] [<f86ada61>] ath5k_hw_noise_floor_calibration+0x42/0x162 [ath5k] [ 4566.624783] [<f86ae68f>] ath5k_hw_phy_calibrate+0x40b/0x457 [ath5k] [ 4566.624783] [<f82d451d>] ? ieee80211_stop_queues_by_reason+0x3e/0x45 [mac80211] [ 4566.624783] [<f86b35a6>] ath5k_tasklet_calibrate+0xea/0x14c [ath5k] [ 4566.624783] [<c10356a9>] tasklet_action+0x8d/0xe0 [ 4566.624783] [<c1035c3b>] __do_softirq+0xc6/0x183 [ 4566.624783] [<c1035d23>] do_softirq+0x2b/0x43 [ 4566.624783] [<c1035eb0>] irq_exit+0x38/0x7a [ 4566.624783] [<c10044f3>] do_IRQ+0x84/0x9a [ 4566.624783] [<c100364e>] common_interrupt+0x2e/0x34 [ 4566.624783] [<c1282128>] ? schedule+0x64/0x871 [ 4566.624783] [<c1053a57>] ? mark_held_locks+0x47/0x5f [ 4566.624783] [<c1003078>] ? restore_all_notrace+0x0/0x18 [ 4566.624783] [<c1003078>] ? restore_all_notrace+0x0/0x18 [ 4566.624783] [<c10500d8>] ? tick_device_uses_broadcast+0xb/0x7b [ 4566.624783] [<c1282a84>] preempt_schedule_irq+0x30/0x4b [ 4566.624783] [<c1002ef2>] need_resched+0x1f/0x21 [ 4566.624783] [<c114fff9>] ? ioread32+0xc/0x2a [ 4566.624783] [<f86aa772>] ? ath5k_hw_reset_key+0x6c/0x16a [ath5k] [ 4566.624783] [<f86b4437>] ? ath5k_rfkill_hw_start+0xd5/0xdd [ath5k] [ 4566.624783] [<f86b30e8>] ? ath5k_start+0xc2/0xf5 [ath5k] [ 4566.624783] [<f82cc29b>] ? ieee80211_open+0x22f/0x5a5 [mac80211] [ 4566.624783] [<c128647f>] ? notifier_call_chain+0x49/0x71 [ 4566.624783] [<c12046b2>] ? dev_open+0x93/0xd3 [ 4566.624783] [<c1203f1a>] ? dev_change_flags+0xa4/0x157 [ 4566.624783] [<c120b6fc>] ? do_setlink+0x247/0x2ff [ 4566.624783] [<c1284143>] ? _read_unlock+0x38/0x44 [ 4566.624783] [<c120b892>] ? rtnl_setlink+0xde/0xe4 [ 4566.624783] [<c120b7b4>] ? rtnl_setlink+0x0/0xe4 [ 4566.624783] [<c120c5e1>] ? rtnetlink_rcv_msg+0x199/0x1b3 [ 4566.624783] [<c120c448>] ? rtnetlink_rcv_msg+0x0/0x1b3 [ 4566.624783] [<c12192e3>] ? netlink_rcv_skb+0x30/0x76 [ 4566.624783] [<c120c440>] ? rtnetlink_rcv+0x1e/0x26 [ 4566.624783] [<c121910d>] ? netlink_unicast+0xc4/0x11a [ 4566.624783] [<c12197e2>] ? netlink_sendmsg+0x22e/0x23b [ 4566.624783] [<c11f5ae6>] ? __sock_sendmsg+0x45/0x4e [ 4566.624783] [<c11f62b2>] ? sock_sendmsg+0xb8/0xce [ 4566.624783] [<c1055354>] ? lock_release_non_nested+0x84/0x1db [ 4566.624783] [<c1044ff7>] ? autoremove_wake_function+0x0/0x33 [ 4566.624783] [<c109e2c8>] ? might_fault+0x6e/0x88 [ 4566.624783] [<c109e2dd>] ? might_fault+0x83/0x88 [ 4566.624783] [<c114d7b9>] ? copy_from_user+0x26/0x6a [ 4566.624783] [<c11fdaad>] ? verify_iovec+0x40/0x6f [ 4566.624783] [<c11f6407>] ? sys_sendmsg+0x13f/0x192 [ 4566.624783] [<c1055124>] ? __lock_acquire+0xbdd/0xbec [ 4566.624783] [<c10b2c5f>] ? kmem_cache_free+0x99/0xd7 [ 4566.624783] [<c1053cbc>] ? trace_hardirqs_on_caller+0x107/0x12f [ 4566.624783] [<c1055354>] ? lock_release_non_nested+0x84/0x1db [ 4566.624783] [<c1053cef>] ? trace_hardirqs_on+0xb/0xd [ 4566.624783] [<c109e2c8>] ? might_fault+0x6e/0x88 [ 4566.624783] [<c11f7471>] ? sys_socketcall+0x157/0x18e [ 4566.624783] [<c1002f64>] ? sysenter_do_call+0x12/0x32
config-2.6.32.1.bz2
Description: application/bzip
_______________________________________________ ath5k-devel mailing list ath5k-devel@lists.ath5k.org https://lists.ath5k.org/mailman/listinfo/ath5k-devel
