Return-path: Received: from mx2.redhat.com ([66.187.237.31]:39235 "EHLO mx2.redhat.com" rhost-flags-OK-OK-OK-OK) by vger.kernel.org with ESMTP id S1752921AbZBFAHo (ORCPT ); Thu, 5 Feb 2009 19:07:44 -0500 Subject: Re: iwlagn crashing From: Dan Williams To: Jan Schneider Cc: reinette chatre , "linux-wireless@vger.kernel.org" In-Reply-To: <20090206002925.52604jwemh5j7t6o@neo.wg.de> References: <20090205152550.10936yvgbf2eipcs@neo.wg.de> <1233870102.558.6.camel@rc-desk> <20090206001718.15786wrk24r178g0@neo.wg.de> <20090206002925.52604jwemh5j7t6o@neo.wg.de> Content-Type: text/plain Date: Thu, 05 Feb 2009 19:06:14 -0500 Message-Id: <1233878774.27709.2.camel@localhost> (sfid-20090206_010748_567496_F77D3973) Mime-Version: 1.0 Sender: linux-wireless-owner@vger.kernel.org List-ID: On Fri, 2009-02-06 at 00:29 +0100, Jan Schneider wrote: > Zitat von Jan Schneider : > > > Zitat von reinette chatre : > > > >> 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] [] > >>> warn_slowpath+ > >>> 0xbc/0xf0 > >>> Feb 5 14:38:27 tank kernel: [ 6267.996452] [] ? > >>> constant_tes > >>> t_bit+0x9/0x40 > >>> Feb 5 14:38:27 tank kernel: [ 6267.996461] [] ? > >>> _spin_lock+0xe/0x20 > >>> Feb 5 14:38:27 tank kernel: [ 6267.996468] [] ? > >>> get_partial_node+0xae/0xc0 > >>> Feb 5 14:38:27 tank kernel: [ 6267.996474] [] ? > >>> __slab_alloc+0xd0/0x260 > >>> Feb 5 14:38:27 tank kernel: [ 6267.996482] [] ? > >>> alloc_inode+0x1f1/0x220 > >>> Feb 5 14:38:27 tank kernel: [ 6267.996489] [] ? > >>> __d_lookup+0x16/0x150 > >>> Feb 5 14:38:27 tank kernel: [ 6267.996496] [] ? > >>> cap_d_instantiate+0x9/0x10 > >>> Feb 5 14:38:27 tank kernel: [ 6267.996505] [] ? > >>> cap_inode_alloc_security+0x9/0x10 > >>> Feb 5 14:38:27 tank kernel: [ 6267.996523] [] ? > >>> iwl_mac_set_key+0x0/0x160 [iwlagn] > >>> Feb 5 14:38:27 tank kernel: [ 6267.996531] [] ? > >>> security_d_instantiate+0x1b/0x30 > >>> Feb 5 14:38:27 tank kernel: [ 6267.996538] [] ? > >>> d_instantiate+0x75/0x90 > >>> Feb 5 14:38:27 tank kernel: [ 6267.996545] [] ? > >>> inotify_inode_queue_event+0x16/0x100 > >>> Feb 5 14:38:27 tank kernel: [ 6267.996554] [] ? > >>> debugfs_mknod+0xda/0x140 > >>> Feb 5 14:38:27 tank kernel: [ 6267.996560] [] ? > >>> dput+0xb9/0x190 > >>> Feb 5 14:38:27 tank kernel: [ 6267.996566] [] ? > >>> mutex_unlock+0x9/0x20 > >>> Feb 5 14:38:27 tank kernel: [ 6267.996586] [] > >>> iwl_set_dynamic_key+0x492/0x4e0 [iwlcore] > >>> Feb 5 14:38:27 tank kernel: [ 6267.996599] [] ? > >>> debugfs_create_file+0x82/0xe0 > >>> Feb 5 14:38:27 tank kernel: [ 6267.996620] [] ? > >>> iwl_scan_cancel+0xe/0x80 [iwlcore] > >>> Feb 5 14:38:27 tank kernel: [ 6267.996635] [] > >>> iwl_mac_set_key+0x12c/0x160 [iwlagn] > >>> Feb 5 14:38:27 tank kernel: [ 6267.996673] [] > >>> __ieee80211_key_todo+0x167/0x330 [mac80211] > >>> Feb 5 14:38:27 tank kernel: [ 6267.996706] [] ? > >>> key_todo+0x0/0x10 [mac80211] > >>> Feb 5 14:38:27 tank kernel: [ 6267.996734] [] > >>> ieee80211_key_todo+0x1a/0x30 [mac80211] > >>> Feb 5 14:38:27 tank kernel: [ 6267.996762] [] > >>> key_todo+0xe/0x10 [mac80211] > >>> Feb 5 14:38:27 tank kernel: [ 6267.996771] [] > >>> run_workqueue+0xc2/0x1a0 > >>> Feb 5 14:38:27 tank kernel: [ 6267.996783] [] > >>> worker_thread+0xaf/0x130 > >>> Feb 5 14:38:27 tank kernel: [ 6267.996791] [] ? > >>> autoremove_wake_function+0x0/0x40 > >>> Feb 5 14:38:27 tank kernel: [ 6267.996798] [] ? > >>> worker_thread+0x0/0x130 > >>> Feb 5 14:38:27 tank kernel: [ 6267.996803] [] > >>> kthread+0x4e/0x90 > >>> Feb 5 14:38:27 tank kernel: [ 6267.996810] [] > >>> child_rip+0xa/0x11 > >>> Feb 5 14:38:27 tank kernel: [ 6267.996816] [] ? > >>> kthread+0x0/0x90 > >>> Feb 5 14:38:27 tank kernel: [ 6267.996821] [] ? > >>> 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: (wlan0): supplicant > connection state change: 7 -> 6 > Feb 6 00:18:28 tank NetworkManager: (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. >