2009-02-05 14:59:40

by Jan Schneider

[permalink] [raw]
Subject: iwlagn crashing

Hi,

since there doesn't seem to be a bug tracker, I'm gonna report it
here. Exactly every 10 minutes, I get this dump in the logs:

Feb 5 14:38:27 tank kernel: [ 6267.996208] no space for a new
keyModules linked
in: aes_x86_64 aes_generic af_packet i915 drm rfcomm bnep sco l2cap
ipt_MASQUER
ADE iptable_nat nf_nat nf_conntrack_ipv4 xt_state nf_conntrack
ipt_REJECT xt_tcp
udp bridge stp kvm_intel kvm ppdev vmnet vmblock vmci vmmon ipv6
acpi_cpufreq cp
ufreq_userspace cpufreq_stats cpufreq_powersave cpufreq_conservative
cpufreq_ond
emand freq_table container sbs sbshc pci_slot iptable_filter ip_tables
x_tables
uinput sbp2 lp btusb bluetooth pcmcia snd_hda_intel arc4 snd_pcm_oss
ecb snd_mix
er_oss crypto_blkcipher snd_pcm iwlagn iwlcore snd_seq_dummy dcdbas
snd_seq_oss
rfkill dm_multipath psmouse sdhci_pci scsi_dh sdhci led_class pcspkr
ricoh_mmc s
erio_raw yenta_socket rsrc_nonstatic joydev mmc_core mac80211
snd_seq_midi pcmci
a_core snd_rawmidi snd_seq_midi_event snd_seq cfg80211 snd_timer
snd_seq_device
snd uvcvideo soundcore compat_ioctl32 shpchp pci_hotplug
snd_page_alloc videodev
v4l1_compat evdev video output parport_pc parport wmi button ac
Feb 5 14:38:27 tank kernel: battery intel_agp usbhid hid ext3 jbd
mbcache sr_mo
d cdrom sd_mod crc_t10dif sg pata_acpi ohci1394 ahci ata_generic
ieee1394 ehci_h
cd uhci_hcd libata scsi_mod dock usbcore e1000e dm_mirror dm_log
dm_snapshot dm_
mod thermal processor fan fbcon tileblit font bitblit softcursor fuse
Feb 5 14:38:27 tank kernel: [ 6267.996419] Pid: 9, comm: events/0 Tainted: G
W 2.6.27-11-generic #1
Feb 5 14:38:27 tank kernel: [ 6267.996424]
Feb 5 14:38:27 tank kernel: [ 6267.996426] Call Trace:
Feb 5 14:38:27 tank kernel: [ 6267.996442] [<ffffffff8024e92c>]
warn_slowpath+
0xbc/0xf0
Feb 5 14:38:27 tank kernel: [ 6267.996452] [<ffffffff802ddc69>] ?
constant_tes
t_bit+0x9/0x40
Feb 5 14:38:27 tank kernel: [ 6267.996461] [<ffffffff8050367e>] ?
_spin_lock+0xe/0x20
Feb 5 14:38:27 tank kernel: [ 6267.996468] [<ffffffff802de75e>] ?
get_partial_node+0xae/0xc0
Feb 5 14:38:27 tank kernel: [ 6267.996474] [<ffffffff802e1f10>] ?
__slab_alloc+0xd0/0x260
Feb 5 14:38:27 tank kernel: [ 6267.996482] [<ffffffff80301b21>] ?
alloc_inode+0x1f1/0x220
Feb 5 14:38:27 tank kernel: [ 6267.996489] [<ffffffff802ff9d6>] ?
__d_lookup+0x16/0x150
Feb 5 14:38:27 tank kernel: [ 6267.996496] [<ffffffff80363499>] ?
cap_d_instantiate+0x9/0x10
Feb 5 14:38:27 tank kernel: [ 6267.996505] [<ffffffff80362e29>] ?
cap_inode_alloc_security+0x9/0x10
Feb 5 14:38:27 tank kernel: [ 6267.996523] [<ffffffffa0402930>] ?
iwl_mac_set_key+0x0/0x160 [iwlagn]
Feb 5 14:38:27 tank kernel: [ 6267.996531] [<ffffffff8036268b>] ?
security_d_instantiate+0x1b/0x30
Feb 5 14:38:27 tank kernel: [ 6267.996538] [<ffffffff802ffe55>] ?
d_instantiate+0x75/0x90
Feb 5 14:38:27 tank kernel: [ 6267.996545] [<ffffffff8031fff6>] ?
inotify_inode_queue_event+0x16/0x100
Feb 5 14:38:27 tank kernel: [ 6267.996554] [<ffffffff80350e1a>] ?
debugfs_mknod+0xda/0x140
Feb 5 14:38:27 tank kernel: [ 6267.996560] [<ffffffff80300549>] ?
dput+0xb9/0x190
Feb 5 14:38:27 tank kernel: [ 6267.996566] [<ffffffff80502179>] ?
mutex_unlock+0x9/0x20
Feb 5 14:38:27 tank kernel: [ 6267.996586] [<ffffffffa03ed412>]
iwl_set_dynamic_key+0x492/0x4e0 [iwlcore]
Feb 5 14:38:27 tank kernel: [ 6267.996599] [<ffffffff80351122>] ?
debugfs_create_file+0x82/0xe0
Feb 5 14:38:27 tank kernel: [ 6267.996620] [<ffffffffa03efc9e>] ?
iwl_scan_cancel+0xe/0x80 [iwlcore]
Feb 5 14:38:27 tank kernel: [ 6267.996635] [<ffffffffa0402a5c>]
iwl_mac_set_key+0x12c/0x160 [iwlagn]
Feb 5 14:38:27 tank kernel: [ 6267.996673] [<ffffffffa033b067>]
__ieee80211_key_todo+0x167/0x330 [mac80211]
Feb 5 14:38:27 tank kernel: [ 6267.996706] [<ffffffffa033b3b0>] ?
key_todo+0x0/0x10 [mac80211]
Feb 5 14:38:27 tank kernel: [ 6267.996734] [<ffffffffa033b39a>]
ieee80211_key_todo+0x1a/0x30 [mac80211]
Feb 5 14:38:27 tank kernel: [ 6267.996762] [<ffffffffa033b3be>]
key_todo+0xe/0x10 [mac80211]
Feb 5 14:38:27 tank kernel: [ 6267.996771] [<ffffffff80262812>]
run_workqueue+0xc2/0x1a0
Feb 5 14:38:27 tank kernel: [ 6267.996783] [<ffffffff80262b1f>]
worker_thread+0xaf/0x130
Feb 5 14:38:27 tank kernel: [ 6267.996791] [<ffffffff80267060>] ?
autoremove_wake_function+0x0/0x40
Feb 5 14:38:27 tank kernel: [ 6267.996798] [<ffffffff80262a70>] ?
worker_thread+0x0/0x130
Feb 5 14:38:27 tank kernel: [ 6267.996803] [<ffffffff80266c2e>]
kthread+0x4e/0x90
Feb 5 14:38:27 tank kernel: [ 6267.996810] [<ffffffff80213c99>]
child_rip+0xa/0x11
Feb 5 14:38:27 tank kernel: [ 6267.996816] [<ffffffff80266be0>] ?
kthread+0x0/0x90
Feb 5 14:38:27 tank kernel: [ 6267.996821] [<ffffffff80213c8f>] ?
child_rip+0x0/0x11
Feb 5 14:38:27 tank kernel: [ 6267.996825]
Feb 5 14:38:27 tank kernel: [ 6267.996828] ---[ end trace
ec5f349ce4f550a2 ]---

This is an Intel 5300 inside a Dell Latitude. I *used* to have all
kind of other issues that I can't reliably reproduce at the moment, so
I thought I would start with this one, and see if it fixes other
issues too.
This is compat-wireless-2009-01-30.

Jan.

--
Do you need professional PHP or Horde consulting?
http://horde.org/consulting/



2009-02-05 23:17:49

by Jan Schneider

[permalink] [raw]
Subject: Re: iwlagn crashing

Zitat von reinette chatre <[email protected]>:

> On Thu, 2009-02-05 at 06:25 -0800, Jan Schneider wrote:
>> Hi,
>>
>> since there doesn't seem to be a bug tracker,
>
> http://www.intellinuxwireless.org/bugzilla/

That's a bit misleading then, because a) it doesn't list iwlagn in
bugzilla, and b) the bold red first paragraph on the homepage tells
that this is no longer the place I am looking for.

>> I'm gonna report it
>> here. Exactly every 10 minutes, I get this dump in the logs:
>>
>> Feb 5 14:38:27 tank kernel: [ 6267.996208] no space for a new
>> keyModules linked
>> in: aes_x86_64 aes_generic af_packet i915 drm rfcomm bnep sco l2cap
>> ipt_MASQUER
>> ADE iptable_nat nf_nat nf_conntrack_ipv4 xt_state nf_conntrack
>> ipt_REJECT xt_tcp
>> udp bridge stp kvm_intel kvm ppdev vmnet vmblock vmci vmmon ipv6
>> acpi_cpufreq cp
>> ufreq_userspace cpufreq_stats cpufreq_powersave cpufreq_conservative
>> cpufreq_ond
>> emand freq_table container sbs sbshc pci_slot iptable_filter ip_tables
>> x_tables
>> uinput sbp2 lp btusb bluetooth pcmcia snd_hda_intel arc4 snd_pcm_oss
>> ecb snd_mix
>> er_oss crypto_blkcipher snd_pcm iwlagn iwlcore snd_seq_dummy dcdbas
>> snd_seq_oss
>> rfkill dm_multipath psmouse sdhci_pci scsi_dh sdhci led_class pcspkr
>> ricoh_mmc s
>> erio_raw yenta_socket rsrc_nonstatic joydev mmc_core mac80211
>> snd_seq_midi pcmci
>> a_core snd_rawmidi snd_seq_midi_event snd_seq cfg80211 snd_timer
>> snd_seq_device
>> snd uvcvideo soundcore compat_ioctl32 shpchp pci_hotplug
>> snd_page_alloc videodev
>> v4l1_compat evdev video output parport_pc parport wmi button ac
>> Feb 5 14:38:27 tank kernel: battery intel_agp usbhid hid ext3 jbd
>> mbcache sr_mo
>> d cdrom sd_mod crc_t10dif sg pata_acpi ohci1394 ahci ata_generic
>> ieee1394 ehci_h
>> cd uhci_hcd libata scsi_mod dock usbcore e1000e dm_mirror dm_log
>> dm_snapshot dm_
>> mod thermal processor fan fbcon tileblit font bitblit softcursor fuse
>> Feb 5 14:38:27 tank kernel: [ 6267.996419] Pid: 9, comm: events/0
>> Tainted: G
>> W 2.6.27-11-generic #1
>> Feb 5 14:38:27 tank kernel: [ 6267.996424]
>> Feb 5 14:38:27 tank kernel: [ 6267.996426] Call Trace:
>> Feb 5 14:38:27 tank kernel: [ 6267.996442] [<ffffffff8024e92c>]
>> warn_slowpath+
>> 0xbc/0xf0
>> Feb 5 14:38:27 tank kernel: [ 6267.996452] [<ffffffff802ddc69>] ?
>> constant_tes
>> t_bit+0x9/0x40
>> Feb 5 14:38:27 tank kernel: [ 6267.996461] [<ffffffff8050367e>] ?
>> _spin_lock+0xe/0x20
>> Feb 5 14:38:27 tank kernel: [ 6267.996468] [<ffffffff802de75e>] ?
>> get_partial_node+0xae/0xc0
>> Feb 5 14:38:27 tank kernel: [ 6267.996474] [<ffffffff802e1f10>] ?
>> __slab_alloc+0xd0/0x260
>> Feb 5 14:38:27 tank kernel: [ 6267.996482] [<ffffffff80301b21>] ?
>> alloc_inode+0x1f1/0x220
>> Feb 5 14:38:27 tank kernel: [ 6267.996489] [<ffffffff802ff9d6>] ?
>> __d_lookup+0x16/0x150
>> Feb 5 14:38:27 tank kernel: [ 6267.996496] [<ffffffff80363499>] ?
>> cap_d_instantiate+0x9/0x10
>> Feb 5 14:38:27 tank kernel: [ 6267.996505] [<ffffffff80362e29>] ?
>> cap_inode_alloc_security+0x9/0x10
>> Feb 5 14:38:27 tank kernel: [ 6267.996523] [<ffffffffa0402930>] ?
>> iwl_mac_set_key+0x0/0x160 [iwlagn]
>> Feb 5 14:38:27 tank kernel: [ 6267.996531] [<ffffffff8036268b>] ?
>> security_d_instantiate+0x1b/0x30
>> Feb 5 14:38:27 tank kernel: [ 6267.996538] [<ffffffff802ffe55>] ?
>> d_instantiate+0x75/0x90
>> Feb 5 14:38:27 tank kernel: [ 6267.996545] [<ffffffff8031fff6>] ?
>> inotify_inode_queue_event+0x16/0x100
>> Feb 5 14:38:27 tank kernel: [ 6267.996554] [<ffffffff80350e1a>] ?
>> debugfs_mknod+0xda/0x140
>> Feb 5 14:38:27 tank kernel: [ 6267.996560] [<ffffffff80300549>] ?
>> dput+0xb9/0x190
>> Feb 5 14:38:27 tank kernel: [ 6267.996566] [<ffffffff80502179>] ?
>> mutex_unlock+0x9/0x20
>> Feb 5 14:38:27 tank kernel: [ 6267.996586] [<ffffffffa03ed412>]
>> iwl_set_dynamic_key+0x492/0x4e0 [iwlcore]
>> Feb 5 14:38:27 tank kernel: [ 6267.996599] [<ffffffff80351122>] ?
>> debugfs_create_file+0x82/0xe0
>> Feb 5 14:38:27 tank kernel: [ 6267.996620] [<ffffffffa03efc9e>] ?
>> iwl_scan_cancel+0xe/0x80 [iwlcore]
>> Feb 5 14:38:27 tank kernel: [ 6267.996635] [<ffffffffa0402a5c>]
>> iwl_mac_set_key+0x12c/0x160 [iwlagn]
>> Feb 5 14:38:27 tank kernel: [ 6267.996673] [<ffffffffa033b067>]
>> __ieee80211_key_todo+0x167/0x330 [mac80211]
>> Feb 5 14:38:27 tank kernel: [ 6267.996706] [<ffffffffa033b3b0>] ?
>> key_todo+0x0/0x10 [mac80211]
>> Feb 5 14:38:27 tank kernel: [ 6267.996734] [<ffffffffa033b39a>]
>> ieee80211_key_todo+0x1a/0x30 [mac80211]
>> Feb 5 14:38:27 tank kernel: [ 6267.996762] [<ffffffffa033b3be>]
>> key_todo+0xe/0x10 [mac80211]
>> Feb 5 14:38:27 tank kernel: [ 6267.996771] [<ffffffff80262812>]
>> run_workqueue+0xc2/0x1a0
>> Feb 5 14:38:27 tank kernel: [ 6267.996783] [<ffffffff80262b1f>]
>> worker_thread+0xaf/0x130
>> Feb 5 14:38:27 tank kernel: [ 6267.996791] [<ffffffff80267060>] ?
>> autoremove_wake_function+0x0/0x40
>> Feb 5 14:38:27 tank kernel: [ 6267.996798] [<ffffffff80262a70>] ?
>> worker_thread+0x0/0x130
>> Feb 5 14:38:27 tank kernel: [ 6267.996803] [<ffffffff80266c2e>]
>> kthread+0x4e/0x90
>> Feb 5 14:38:27 tank kernel: [ 6267.996810] [<ffffffff80213c99>]
>> child_rip+0xa/0x11
>> Feb 5 14:38:27 tank kernel: [ 6267.996816] [<ffffffff80266be0>] ?
>> kthread+0x0/0x90
>> Feb 5 14:38:27 tank kernel: [ 6267.996821] [<ffffffff80213c8f>] ?
>> child_rip+0x0/0x11
>> Feb 5 14:38:27 tank kernel: [ 6267.996825]
>> Feb 5 14:38:27 tank kernel: [ 6267.996828] ---[ end trace
>> ec5f349ce4f550a2 ]---
>>
>> This is an Intel 5300 inside a Dell Latitude. I *used* to have all
>> kind of other issues that I can't reliably reproduce at the moment, so
>> I thought I would start with this one, and see if it fixes other
>> issues too.
>> This is compat-wireless-2009-01-30.
>
> Around this time we added a patch that may help with this. Could you
> please check if you are running with
> c15040bdab2181a55893438eabdd402a363c0338 ("iwlwifi: clean key table in
> iwl_clear_stations_table")?

No, I didn't, but I updated to latest HEAD snapshot and that fixed it
indeed. Great! Now let's see if that also improves overall stability.

Thanks, Jan.

--
Do you need professional PHP or Horde consulting?
http://horde.org/consulting/


2009-02-06 00:07:44

by Dan Williams

[permalink] [raw]
Subject: Re: iwlagn crashing

On Fri, 2009-02-06 at 00:29 +0100, Jan Schneider wrote:
> Zitat von Jan Schneider <[email protected]>:
>
> > Zitat von reinette chatre <[email protected]>:
> >
> >> On Thu, 2009-02-05 at 06:25 -0800, Jan Schneider wrote:
> >>> I'm gonna report it
> >>> here. Exactly every 10 minutes, I get this dump in the logs:
> >>>
> >>> Feb 5 14:38:27 tank kernel: [ 6267.996208] no space for a new
> >>> keyModules linked
> >>> in: aes_x86_64 aes_generic af_packet i915 drm rfcomm bnep sco l2cap
> >>> ipt_MASQUER
> >>> ADE iptable_nat nf_nat nf_conntrack_ipv4 xt_state nf_conntrack
> >>> ipt_REJECT xt_tcp
> >>> udp bridge stp kvm_intel kvm ppdev vmnet vmblock vmci vmmon ipv6
> >>> acpi_cpufreq cp
> >>> ufreq_userspace cpufreq_stats cpufreq_powersave cpufreq_conservative
> >>> cpufreq_ond
> >>> emand freq_table container sbs sbshc pci_slot iptable_filter ip_tables
> >>> x_tables
> >>> uinput sbp2 lp btusb bluetooth pcmcia snd_hda_intel arc4 snd_pcm_oss
> >>> ecb snd_mix
> >>> er_oss crypto_blkcipher snd_pcm iwlagn iwlcore snd_seq_dummy dcdbas
> >>> snd_seq_oss
> >>> rfkill dm_multipath psmouse sdhci_pci scsi_dh sdhci led_class pcspkr
> >>> ricoh_mmc s
> >>> erio_raw yenta_socket rsrc_nonstatic joydev mmc_core mac80211
> >>> snd_seq_midi pcmci
> >>> a_core snd_rawmidi snd_seq_midi_event snd_seq cfg80211 snd_timer
> >>> snd_seq_device
> >>> snd uvcvideo soundcore compat_ioctl32 shpchp pci_hotplug
> >>> snd_page_alloc videodev
> >>> v4l1_compat evdev video output parport_pc parport wmi button ac
> >>> Feb 5 14:38:27 tank kernel: battery intel_agp usbhid hid ext3 jbd
> >>> mbcache sr_mo
> >>> d cdrom sd_mod crc_t10dif sg pata_acpi ohci1394 ahci ata_generic
> >>> ieee1394 ehci_h
> >>> cd uhci_hcd libata scsi_mod dock usbcore e1000e dm_mirror dm_log
> >>> dm_snapshot dm_
> >>> mod thermal processor fan fbcon tileblit font bitblit softcursor fuse
> >>> Feb 5 14:38:27 tank kernel: [ 6267.996419] Pid: 9, comm: events/0
> >>> Tainted: G
> >>> W 2.6.27-11-generic #1
> >>> Feb 5 14:38:27 tank kernel: [ 6267.996424]
> >>> Feb 5 14:38:27 tank kernel: [ 6267.996426] Call Trace:
> >>> Feb 5 14:38:27 tank kernel: [ 6267.996442] [<ffffffff8024e92c>]
> >>> warn_slowpath+
> >>> 0xbc/0xf0
> >>> Feb 5 14:38:27 tank kernel: [ 6267.996452] [<ffffffff802ddc69>] ?
> >>> constant_tes
> >>> t_bit+0x9/0x40
> >>> Feb 5 14:38:27 tank kernel: [ 6267.996461] [<ffffffff8050367e>] ?
> >>> _spin_lock+0xe/0x20
> >>> Feb 5 14:38:27 tank kernel: [ 6267.996468] [<ffffffff802de75e>] ?
> >>> get_partial_node+0xae/0xc0
> >>> Feb 5 14:38:27 tank kernel: [ 6267.996474] [<ffffffff802e1f10>] ?
> >>> __slab_alloc+0xd0/0x260
> >>> Feb 5 14:38:27 tank kernel: [ 6267.996482] [<ffffffff80301b21>] ?
> >>> alloc_inode+0x1f1/0x220
> >>> Feb 5 14:38:27 tank kernel: [ 6267.996489] [<ffffffff802ff9d6>] ?
> >>> __d_lookup+0x16/0x150
> >>> Feb 5 14:38:27 tank kernel: [ 6267.996496] [<ffffffff80363499>] ?
> >>> cap_d_instantiate+0x9/0x10
> >>> Feb 5 14:38:27 tank kernel: [ 6267.996505] [<ffffffff80362e29>] ?
> >>> cap_inode_alloc_security+0x9/0x10
> >>> Feb 5 14:38:27 tank kernel: [ 6267.996523] [<ffffffffa0402930>] ?
> >>> iwl_mac_set_key+0x0/0x160 [iwlagn]
> >>> Feb 5 14:38:27 tank kernel: [ 6267.996531] [<ffffffff8036268b>] ?
> >>> security_d_instantiate+0x1b/0x30
> >>> Feb 5 14:38:27 tank kernel: [ 6267.996538] [<ffffffff802ffe55>] ?
> >>> d_instantiate+0x75/0x90
> >>> Feb 5 14:38:27 tank kernel: [ 6267.996545] [<ffffffff8031fff6>] ?
> >>> inotify_inode_queue_event+0x16/0x100
> >>> Feb 5 14:38:27 tank kernel: [ 6267.996554] [<ffffffff80350e1a>] ?
> >>> debugfs_mknod+0xda/0x140
> >>> Feb 5 14:38:27 tank kernel: [ 6267.996560] [<ffffffff80300549>] ?
> >>> dput+0xb9/0x190
> >>> Feb 5 14:38:27 tank kernel: [ 6267.996566] [<ffffffff80502179>] ?
> >>> mutex_unlock+0x9/0x20
> >>> Feb 5 14:38:27 tank kernel: [ 6267.996586] [<ffffffffa03ed412>]
> >>> iwl_set_dynamic_key+0x492/0x4e0 [iwlcore]
> >>> Feb 5 14:38:27 tank kernel: [ 6267.996599] [<ffffffff80351122>] ?
> >>> debugfs_create_file+0x82/0xe0
> >>> Feb 5 14:38:27 tank kernel: [ 6267.996620] [<ffffffffa03efc9e>] ?
> >>> iwl_scan_cancel+0xe/0x80 [iwlcore]
> >>> Feb 5 14:38:27 tank kernel: [ 6267.996635] [<ffffffffa0402a5c>]
> >>> iwl_mac_set_key+0x12c/0x160 [iwlagn]
> >>> Feb 5 14:38:27 tank kernel: [ 6267.996673] [<ffffffffa033b067>]
> >>> __ieee80211_key_todo+0x167/0x330 [mac80211]
> >>> Feb 5 14:38:27 tank kernel: [ 6267.996706] [<ffffffffa033b3b0>] ?
> >>> key_todo+0x0/0x10 [mac80211]
> >>> Feb 5 14:38:27 tank kernel: [ 6267.996734] [<ffffffffa033b39a>]
> >>> ieee80211_key_todo+0x1a/0x30 [mac80211]
> >>> Feb 5 14:38:27 tank kernel: [ 6267.996762] [<ffffffffa033b3be>]
> >>> key_todo+0xe/0x10 [mac80211]
> >>> Feb 5 14:38:27 tank kernel: [ 6267.996771] [<ffffffff80262812>]
> >>> run_workqueue+0xc2/0x1a0
> >>> Feb 5 14:38:27 tank kernel: [ 6267.996783] [<ffffffff80262b1f>]
> >>> worker_thread+0xaf/0x130
> >>> Feb 5 14:38:27 tank kernel: [ 6267.996791] [<ffffffff80267060>] ?
> >>> autoremove_wake_function+0x0/0x40
> >>> Feb 5 14:38:27 tank kernel: [ 6267.996798] [<ffffffff80262a70>] ?
> >>> worker_thread+0x0/0x130
> >>> Feb 5 14:38:27 tank kernel: [ 6267.996803] [<ffffffff80266c2e>]
> >>> kthread+0x4e/0x90
> >>> Feb 5 14:38:27 tank kernel: [ 6267.996810] [<ffffffff80213c99>]
> >>> child_rip+0xa/0x11
> >>> Feb 5 14:38:27 tank kernel: [ 6267.996816] [<ffffffff80266be0>] ?
> >>> kthread+0x0/0x90
> >>> Feb 5 14:38:27 tank kernel: [ 6267.996821] [<ffffffff80213c8f>] ?
> >>> child_rip+0x0/0x11
> >>> Feb 5 14:38:27 tank kernel: [ 6267.996825]
> >>> Feb 5 14:38:27 tank kernel: [ 6267.996828] ---[ end trace
> >>> ec5f349ce4f550a2 ]---
> >>>
> >>> This is an Intel 5300 inside a Dell Latitude. I *used* to have all
> >>> kind of other issues that I can't reliably reproduce at the moment, so
> >>> I thought I would start with this one, and see if it fixes other
> >>> issues too.
> >>> This is compat-wireless-2009-01-30.
> >>
> >> Around this time we added a patch that may help with this. Could you
> >> please check if you are running with
> >> c15040bdab2181a55893438eabdd402a363c0338 ("iwlwifi: clean key table in
> >> iwl_clear_stations_table")?
> >
> > No, I didn't, but I updated to latest HEAD snapshot and that fixed
> > it indeed. Great! Now let's see if that also improves overall
> > stability.
>
> As a sidenote, I still see the following every 10 Minutes:
>
> Feb 6 00:18:28 tank NetworkManager: <info> (wlan0): supplicant
> connection state change: 7 -> 6
> Feb 6 00:18:28 tank NetworkManager: <info> (wlan0): supplicant
> connection state change: 6 -> 7

(you seem to be running a 0.7 pre-release; might want to kick you distro
to update that...)

10 minutes is likely your WPA multicast re-key interval. NM is just
printing out supplicant state changes; 6 = group handshake, 7 =
completed.

Yes, NM needs finer configurable log levels.

Dan

> I have no idea what that means, but I thought I'd check here before
> asking on the wpa_supplicant/NetworkManager lists, because it happens
> at the same rate as my original problem. And looking at my backlogs I
> see that this always used to happen right before the dump from above.
>
> Jan.
>


2009-02-05 21:38:17

by Reinette Chatre

[permalink] [raw]
Subject: Re: iwlagn crashing

On Thu, 2009-02-05 at 06:25 -0800, Jan Schneider wrote:
> Hi,
>
> since there doesn't seem to be a bug tracker,

http://www.intellinuxwireless.org/bugzilla/

> I'm gonna report it
> here. Exactly every 10 minutes, I get this dump in the logs:
>
> Feb 5 14:38:27 tank kernel: [ 6267.996208] no space for a new
> keyModules linked
> in: aes_x86_64 aes_generic af_packet i915 drm rfcomm bnep sco l2cap
> ipt_MASQUER
> ADE iptable_nat nf_nat nf_conntrack_ipv4 xt_state nf_conntrack
> ipt_REJECT xt_tcp
> udp bridge stp kvm_intel kvm ppdev vmnet vmblock vmci vmmon ipv6
> acpi_cpufreq cp
> ufreq_userspace cpufreq_stats cpufreq_powersave cpufreq_conservative
> cpufreq_ond
> emand freq_table container sbs sbshc pci_slot iptable_filter ip_tables
> x_tables
> uinput sbp2 lp btusb bluetooth pcmcia snd_hda_intel arc4 snd_pcm_oss
> ecb snd_mix
> er_oss crypto_blkcipher snd_pcm iwlagn iwlcore snd_seq_dummy dcdbas
> snd_seq_oss
> rfkill dm_multipath psmouse sdhci_pci scsi_dh sdhci led_class pcspkr
> ricoh_mmc s
> erio_raw yenta_socket rsrc_nonstatic joydev mmc_core mac80211
> snd_seq_midi pcmci
> a_core snd_rawmidi snd_seq_midi_event snd_seq cfg80211 snd_timer
> snd_seq_device
> snd uvcvideo soundcore compat_ioctl32 shpchp pci_hotplug
> snd_page_alloc videodev
> v4l1_compat evdev video output parport_pc parport wmi button ac
> Feb 5 14:38:27 tank kernel: battery intel_agp usbhid hid ext3 jbd
> mbcache sr_mo
> d cdrom sd_mod crc_t10dif sg pata_acpi ohci1394 ahci ata_generic
> ieee1394 ehci_h
> cd uhci_hcd libata scsi_mod dock usbcore e1000e dm_mirror dm_log
> dm_snapshot dm_
> mod thermal processor fan fbcon tileblit font bitblit softcursor fuse
> Feb 5 14:38:27 tank kernel: [ 6267.996419] Pid: 9, comm: events/0 Tainted: G
> W 2.6.27-11-generic #1
> Feb 5 14:38:27 tank kernel: [ 6267.996424]
> Feb 5 14:38:27 tank kernel: [ 6267.996426] Call Trace:
> Feb 5 14:38:27 tank kernel: [ 6267.996442] [<ffffffff8024e92c>]
> warn_slowpath+
> 0xbc/0xf0
> Feb 5 14:38:27 tank kernel: [ 6267.996452] [<ffffffff802ddc69>] ?
> constant_tes
> t_bit+0x9/0x40
> Feb 5 14:38:27 tank kernel: [ 6267.996461] [<ffffffff8050367e>] ?
> _spin_lock+0xe/0x20
> Feb 5 14:38:27 tank kernel: [ 6267.996468] [<ffffffff802de75e>] ?
> get_partial_node+0xae/0xc0
> Feb 5 14:38:27 tank kernel: [ 6267.996474] [<ffffffff802e1f10>] ?
> __slab_alloc+0xd0/0x260
> Feb 5 14:38:27 tank kernel: [ 6267.996482] [<ffffffff80301b21>] ?
> alloc_inode+0x1f1/0x220
> Feb 5 14:38:27 tank kernel: [ 6267.996489] [<ffffffff802ff9d6>] ?
> __d_lookup+0x16/0x150
> Feb 5 14:38:27 tank kernel: [ 6267.996496] [<ffffffff80363499>] ?
> cap_d_instantiate+0x9/0x10
> Feb 5 14:38:27 tank kernel: [ 6267.996505] [<ffffffff80362e29>] ?
> cap_inode_alloc_security+0x9/0x10
> Feb 5 14:38:27 tank kernel: [ 6267.996523] [<ffffffffa0402930>] ?
> iwl_mac_set_key+0x0/0x160 [iwlagn]
> Feb 5 14:38:27 tank kernel: [ 6267.996531] [<ffffffff8036268b>] ?
> security_d_instantiate+0x1b/0x30
> Feb 5 14:38:27 tank kernel: [ 6267.996538] [<ffffffff802ffe55>] ?
> d_instantiate+0x75/0x90
> Feb 5 14:38:27 tank kernel: [ 6267.996545] [<ffffffff8031fff6>] ?
> inotify_inode_queue_event+0x16/0x100
> Feb 5 14:38:27 tank kernel: [ 6267.996554] [<ffffffff80350e1a>] ?
> debugfs_mknod+0xda/0x140
> Feb 5 14:38:27 tank kernel: [ 6267.996560] [<ffffffff80300549>] ?
> dput+0xb9/0x190
> Feb 5 14:38:27 tank kernel: [ 6267.996566] [<ffffffff80502179>] ?
> mutex_unlock+0x9/0x20
> Feb 5 14:38:27 tank kernel: [ 6267.996586] [<ffffffffa03ed412>]
> iwl_set_dynamic_key+0x492/0x4e0 [iwlcore]
> Feb 5 14:38:27 tank kernel: [ 6267.996599] [<ffffffff80351122>] ?
> debugfs_create_file+0x82/0xe0
> Feb 5 14:38:27 tank kernel: [ 6267.996620] [<ffffffffa03efc9e>] ?
> iwl_scan_cancel+0xe/0x80 [iwlcore]
> Feb 5 14:38:27 tank kernel: [ 6267.996635] [<ffffffffa0402a5c>]
> iwl_mac_set_key+0x12c/0x160 [iwlagn]
> Feb 5 14:38:27 tank kernel: [ 6267.996673] [<ffffffffa033b067>]
> __ieee80211_key_todo+0x167/0x330 [mac80211]
> Feb 5 14:38:27 tank kernel: [ 6267.996706] [<ffffffffa033b3b0>] ?
> key_todo+0x0/0x10 [mac80211]
> Feb 5 14:38:27 tank kernel: [ 6267.996734] [<ffffffffa033b39a>]
> ieee80211_key_todo+0x1a/0x30 [mac80211]
> Feb 5 14:38:27 tank kernel: [ 6267.996762] [<ffffffffa033b3be>]
> key_todo+0xe/0x10 [mac80211]
> Feb 5 14:38:27 tank kernel: [ 6267.996771] [<ffffffff80262812>]
> run_workqueue+0xc2/0x1a0
> Feb 5 14:38:27 tank kernel: [ 6267.996783] [<ffffffff80262b1f>]
> worker_thread+0xaf/0x130
> Feb 5 14:38:27 tank kernel: [ 6267.996791] [<ffffffff80267060>] ?
> autoremove_wake_function+0x0/0x40
> Feb 5 14:38:27 tank kernel: [ 6267.996798] [<ffffffff80262a70>] ?
> worker_thread+0x0/0x130
> Feb 5 14:38:27 tank kernel: [ 6267.996803] [<ffffffff80266c2e>]
> kthread+0x4e/0x90
> Feb 5 14:38:27 tank kernel: [ 6267.996810] [<ffffffff80213c99>]
> child_rip+0xa/0x11
> Feb 5 14:38:27 tank kernel: [ 6267.996816] [<ffffffff80266be0>] ?
> kthread+0x0/0x90
> Feb 5 14:38:27 tank kernel: [ 6267.996821] [<ffffffff80213c8f>] ?
> child_rip+0x0/0x11
> Feb 5 14:38:27 tank kernel: [ 6267.996825]
> Feb 5 14:38:27 tank kernel: [ 6267.996828] ---[ end trace
> ec5f349ce4f550a2 ]---
>
> This is an Intel 5300 inside a Dell Latitude. I *used* to have all
> kind of other issues that I can't reliably reproduce at the moment, so
> I thought I would start with this one, and see if it fixes other
> issues too.
> This is compat-wireless-2009-01-30.

Around this time we added a patch that may help with this. Could you
please check if you are running with
c15040bdab2181a55893438eabdd402a363c0338 ("iwlwifi: clean key table in
iwl_clear_stations_table")?

Reinette


2009-02-05 23:29:34

by Jan Schneider

[permalink] [raw]
Subject: Re: iwlagn crashing

Zitat von Jan Schneider <[email protected]>:

> Zitat von reinette chatre <[email protected]>:
>
>> On Thu, 2009-02-05 at 06:25 -0800, Jan Schneider wrote:
>>> I'm gonna report it
>>> here. Exactly every 10 minutes, I get this dump in the logs:
>>>
>>> Feb 5 14:38:27 tank kernel: [ 6267.996208] no space for a new
>>> keyModules linked
>>> in: aes_x86_64 aes_generic af_packet i915 drm rfcomm bnep sco l2cap
>>> ipt_MASQUER
>>> ADE iptable_nat nf_nat nf_conntrack_ipv4 xt_state nf_conntrack
>>> ipt_REJECT xt_tcp
>>> udp bridge stp kvm_intel kvm ppdev vmnet vmblock vmci vmmon ipv6
>>> acpi_cpufreq cp
>>> ufreq_userspace cpufreq_stats cpufreq_powersave cpufreq_conservative
>>> cpufreq_ond
>>> emand freq_table container sbs sbshc pci_slot iptable_filter ip_tables
>>> x_tables
>>> uinput sbp2 lp btusb bluetooth pcmcia snd_hda_intel arc4 snd_pcm_oss
>>> ecb snd_mix
>>> er_oss crypto_blkcipher snd_pcm iwlagn iwlcore snd_seq_dummy dcdbas
>>> snd_seq_oss
>>> rfkill dm_multipath psmouse sdhci_pci scsi_dh sdhci led_class pcspkr
>>> ricoh_mmc s
>>> erio_raw yenta_socket rsrc_nonstatic joydev mmc_core mac80211
>>> snd_seq_midi pcmci
>>> a_core snd_rawmidi snd_seq_midi_event snd_seq cfg80211 snd_timer
>>> snd_seq_device
>>> snd uvcvideo soundcore compat_ioctl32 shpchp pci_hotplug
>>> snd_page_alloc videodev
>>> v4l1_compat evdev video output parport_pc parport wmi button ac
>>> Feb 5 14:38:27 tank kernel: battery intel_agp usbhid hid ext3 jbd
>>> mbcache sr_mo
>>> d cdrom sd_mod crc_t10dif sg pata_acpi ohci1394 ahci ata_generic
>>> ieee1394 ehci_h
>>> cd uhci_hcd libata scsi_mod dock usbcore e1000e dm_mirror dm_log
>>> dm_snapshot dm_
>>> mod thermal processor fan fbcon tileblit font bitblit softcursor fuse
>>> Feb 5 14:38:27 tank kernel: [ 6267.996419] Pid: 9, comm: events/0
>>> Tainted: G
>>> W 2.6.27-11-generic #1
>>> Feb 5 14:38:27 tank kernel: [ 6267.996424]
>>> Feb 5 14:38:27 tank kernel: [ 6267.996426] Call Trace:
>>> Feb 5 14:38:27 tank kernel: [ 6267.996442] [<ffffffff8024e92c>]
>>> warn_slowpath+
>>> 0xbc/0xf0
>>> Feb 5 14:38:27 tank kernel: [ 6267.996452] [<ffffffff802ddc69>] ?
>>> constant_tes
>>> t_bit+0x9/0x40
>>> Feb 5 14:38:27 tank kernel: [ 6267.996461] [<ffffffff8050367e>] ?
>>> _spin_lock+0xe/0x20
>>> Feb 5 14:38:27 tank kernel: [ 6267.996468] [<ffffffff802de75e>] ?
>>> get_partial_node+0xae/0xc0
>>> Feb 5 14:38:27 tank kernel: [ 6267.996474] [<ffffffff802e1f10>] ?
>>> __slab_alloc+0xd0/0x260
>>> Feb 5 14:38:27 tank kernel: [ 6267.996482] [<ffffffff80301b21>] ?
>>> alloc_inode+0x1f1/0x220
>>> Feb 5 14:38:27 tank kernel: [ 6267.996489] [<ffffffff802ff9d6>] ?
>>> __d_lookup+0x16/0x150
>>> Feb 5 14:38:27 tank kernel: [ 6267.996496] [<ffffffff80363499>] ?
>>> cap_d_instantiate+0x9/0x10
>>> Feb 5 14:38:27 tank kernel: [ 6267.996505] [<ffffffff80362e29>] ?
>>> cap_inode_alloc_security+0x9/0x10
>>> Feb 5 14:38:27 tank kernel: [ 6267.996523] [<ffffffffa0402930>] ?
>>> iwl_mac_set_key+0x0/0x160 [iwlagn]
>>> Feb 5 14:38:27 tank kernel: [ 6267.996531] [<ffffffff8036268b>] ?
>>> security_d_instantiate+0x1b/0x30
>>> Feb 5 14:38:27 tank kernel: [ 6267.996538] [<ffffffff802ffe55>] ?
>>> d_instantiate+0x75/0x90
>>> Feb 5 14:38:27 tank kernel: [ 6267.996545] [<ffffffff8031fff6>] ?
>>> inotify_inode_queue_event+0x16/0x100
>>> Feb 5 14:38:27 tank kernel: [ 6267.996554] [<ffffffff80350e1a>] ?
>>> debugfs_mknod+0xda/0x140
>>> Feb 5 14:38:27 tank kernel: [ 6267.996560] [<ffffffff80300549>] ?
>>> dput+0xb9/0x190
>>> Feb 5 14:38:27 tank kernel: [ 6267.996566] [<ffffffff80502179>] ?
>>> mutex_unlock+0x9/0x20
>>> Feb 5 14:38:27 tank kernel: [ 6267.996586] [<ffffffffa03ed412>]
>>> iwl_set_dynamic_key+0x492/0x4e0 [iwlcore]
>>> Feb 5 14:38:27 tank kernel: [ 6267.996599] [<ffffffff80351122>] ?
>>> debugfs_create_file+0x82/0xe0
>>> Feb 5 14:38:27 tank kernel: [ 6267.996620] [<ffffffffa03efc9e>] ?
>>> iwl_scan_cancel+0xe/0x80 [iwlcore]
>>> Feb 5 14:38:27 tank kernel: [ 6267.996635] [<ffffffffa0402a5c>]
>>> iwl_mac_set_key+0x12c/0x160 [iwlagn]
>>> Feb 5 14:38:27 tank kernel: [ 6267.996673] [<ffffffffa033b067>]
>>> __ieee80211_key_todo+0x167/0x330 [mac80211]
>>> Feb 5 14:38:27 tank kernel: [ 6267.996706] [<ffffffffa033b3b0>] ?
>>> key_todo+0x0/0x10 [mac80211]
>>> Feb 5 14:38:27 tank kernel: [ 6267.996734] [<ffffffffa033b39a>]
>>> ieee80211_key_todo+0x1a/0x30 [mac80211]
>>> Feb 5 14:38:27 tank kernel: [ 6267.996762] [<ffffffffa033b3be>]
>>> key_todo+0xe/0x10 [mac80211]
>>> Feb 5 14:38:27 tank kernel: [ 6267.996771] [<ffffffff80262812>]
>>> run_workqueue+0xc2/0x1a0
>>> Feb 5 14:38:27 tank kernel: [ 6267.996783] [<ffffffff80262b1f>]
>>> worker_thread+0xaf/0x130
>>> Feb 5 14:38:27 tank kernel: [ 6267.996791] [<ffffffff80267060>] ?
>>> autoremove_wake_function+0x0/0x40
>>> Feb 5 14:38:27 tank kernel: [ 6267.996798] [<ffffffff80262a70>] ?
>>> worker_thread+0x0/0x130
>>> Feb 5 14:38:27 tank kernel: [ 6267.996803] [<ffffffff80266c2e>]
>>> kthread+0x4e/0x90
>>> Feb 5 14:38:27 tank kernel: [ 6267.996810] [<ffffffff80213c99>]
>>> child_rip+0xa/0x11
>>> Feb 5 14:38:27 tank kernel: [ 6267.996816] [<ffffffff80266be0>] ?
>>> kthread+0x0/0x90
>>> Feb 5 14:38:27 tank kernel: [ 6267.996821] [<ffffffff80213c8f>] ?
>>> child_rip+0x0/0x11
>>> Feb 5 14:38:27 tank kernel: [ 6267.996825]
>>> Feb 5 14:38:27 tank kernel: [ 6267.996828] ---[ end trace
>>> ec5f349ce4f550a2 ]---
>>>
>>> This is an Intel 5300 inside a Dell Latitude. I *used* to have all
>>> kind of other issues that I can't reliably reproduce at the moment, so
>>> I thought I would start with this one, and see if it fixes other
>>> issues too.
>>> This is compat-wireless-2009-01-30.
>>
>> Around this time we added a patch that may help with this. Could you
>> please check if you are running with
>> c15040bdab2181a55893438eabdd402a363c0338 ("iwlwifi: clean key table in
>> iwl_clear_stations_table")?
>
> No, I didn't, but I updated to latest HEAD snapshot and that fixed
> it indeed. Great! Now let's see if that also improves overall
> stability.

As a sidenote, I still see the following every 10 Minutes:

Feb 6 00:18:28 tank NetworkManager: <info> (wlan0): supplicant
connection state change: 7 -> 6
Feb 6 00:18:28 tank NetworkManager: <info> (wlan0): supplicant
connection state change: 6 -> 7

I have no idea what that means, but I thought I'd check here before
asking on the wpa_supplicant/NetworkManager lists, because it happens
at the same rate as my original problem. And looking at my backlogs I
see that this always used to happen right before the dump from above.

Jan.

--
Do you need professional PHP or Horde consulting?
http://horde.org/consulting/