Attachment 297200 Details for Bug 436496 – steps to reproduce the problem and info from log files

steps to reproduce the problem and info from log files

2.6.24.3-18.fc8-wireless.txt (text/plain), 18.71 KB, created by Clive Malcolm on 2008-03-07 16:15:04 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Clive Malcolm

Created: 2008-03-07 16:15:04 UTC

Size: 18.71 KB

patch

obsolete

>2.6.24.3-13.fc8
>
>Inserted wireless card.
>
>dmesg output
>============
>
>pccard: CardBus card inserted into slot 0
>PCI: Enabling device 0000:02:00.0 (0000 -> 0002)
>ACPI: PCI Interrupt 0000:02:00.0[A] -> Link [LNKD] -> GSI 11 (level, low) -> IRQ 11
>PCI: Setting latency timer of device 0000:02:00.0 to 64
>phy0: Selected rate control algorithm 'pid'
>Registered led device: rt61pci-phy0:radio
>Registered led device: rt61pci-phy0:assoc
>ADDRCONF(NETDEV_UP): wlan0: link is not ready
>
>
>/var/log/messages
>=================
>
>Mar  7 15:09:43 localhost dhclient: wmaster0: unknown hardware address type 801
>Mar  7 15:09:44 localhost dhclient:last message repeated 2 times
>Mar  7 15:09:44 localhost dhclient: DHCPDISCOVER on wlan0 to 255.255.255.255 port 67 interval 7
>Mar  7 15:09:44 localhost dhclient: wmaster0: unknown hardware address type 801
>Mar  7 15:09:44 localhost dhclient: DHCPDISCOVER on wlan0 to 255.255.255.255 port 67 interval 7
>Mar  7 15:09:48 localhost ntpd[1927]: sendto(193.40.133.142) (fd=21): Invalid argument
>Mar  7 15:09:50 localhost dhclient: DHCPDISCOVER on wlan0 to 255.255.255.255 port 67 interval 14
>Mar  7 15:09:50 localhost dhclient: DHCPDISCOVER on wlan0 to 255.255.255.255 port 67 interval 14
>Mar  7 15:10:04 localhost dhclient: DHCPDISCOVER on wlan0 to 255.255.255.255 port 67 interval 9
>Mar  7 15:10:04 localhost dhclient: DHCPDISCOVER on wlan0 to 255.255.255.255 port 67 interval 9
>Mar  7 15:10:10 localhost ntpd[1927]: sendto(193.0.71.133) (fd=21): Invalid argument
>Mar  7 15:10:13 localhost dhclient: DHCPDISCOVER on wlan0 to 255.255.255.255 port 67 interval 14
>Mar  7 15:10:13 localhost dhclient: DHCPDISCOVER on wlan0 to 255.255.255.255 port 67 interval 14
>Mar  7 15:10:27 localhost dhclient: DHCPDISCOVER on wlan0 to 255.255.255.255 port 67 interval 17
>Mar  7 15:10:27 localhost dhclient: DHCPDISCOVER on wlan0 to 255.255.255.255 port 67 interval 17
>Mar  7 15:10:32 localhost ntpd[1927]: sendto(193.228.143.12) (fd=21): Invalid argument
>Mar  7 15:10:44 localhost dhclient: No DHCPOFFERS received.
>Mar  7 15:10:44 localhost dhclient: No DHCPOFFERS received.
>Mar  7 15:10:45 localhost NetworkManager: <info>  wlan0: Device is fully-supported using driver 'rt61pci'.
>Mar  7 15:10:45 localhost NetworkManager: <info>  wlan0: driver supports SSID scans (scan_capa 0x01).
>Mar  7 15:10:45 localhost NetworkManager: <info>  Now managing wireless (802.11) device 'wlan0'.
>Mar  7 15:10:45 localhost NetworkManager: <info>  Bringing down device wlan0
>Mar  7 15:10:47 localhost NetworkManager: <info>  Bringing up device wlan0
>Mar  7 15:10:47 localhost kernel: ADDRCONF(NETDEV_UP): wlan0: link is not ready
>Mar  7 15:10:47 localhost NetworkManager: <info>  Deactivating device wlan0.
>Mar  7 15:10:47 localhost NetworkManager: <WARN>  nm_device_802_11_wireless_disable_encryption(): error setting key for device wlan0: No such file or directory
>Mar  7 15:10:47 localhost NetworkManager: <info>  (wlan0): exporting device as /org/freedesktop/Hal/devices/net_00_0e_2e_c7_fe_49_0
>Mar  7 15:10:47 localhost NetworkManager: <info>  (wlan0) supplicant interface is now in state 2 (from 1).
>
>
>modinfo rt61pci
>===============
>
>$ modinfo rt61pci
>filename:       /lib/modules/2.6.24.3-18.fc8/kernel/drivers/net/wireless/rt2x00/rt61pci.ko
>license:        GPL
>firmware:       rt2661.bin
>firmware:       rt2561s.bin
>firmware:       rt2561.bin
>description:    Ralink RT61 PCI & PCMCIA Wireless LAN driver.
>version:        2.1.3
>author:         http://rt2x00.serialmonkey.com
>srcversion:     A64BB58D74E204853EA1671
>alias:          pci:v00001814d00000401sv*sd*bc*sc*i*
>alias:          pci:v00001814d00000302sv*sd*bc*sc*i*
>alias:          pci:v00001814d00000301sv*sd*bc*sc*i*
>depends:        rt2x00lib,rt2x00pci,eeprom_93cx6
>vermagic:       2.6.24.3-18.fc8 SMP mod_unload 686 4KSTACKS 
>
>route
>=====
>
>$ route
>Kernel IP routing table
>Destination     Gateway         Genmask         Flags Metric Ref    Use Iface
>192.168.2.0     *               255.255.255.0   U     0      0        0 eth1
>default         192.168.2.1     0.0.0.0         UG    0      0        0 eth1
>
>NetworkManager select access point.
>
>/var/log/messages
>=================
>
>Mar  7 15:13:45 localhost NetworkManager: <info>  (wlan0) Supplicant interface state change: 0 -> 2
>Mar  7 15:14:10 localhost NetworkManager: <info>  Activation (wlan0/wireless): association took too long, asking for new key.
>Mar  7 15:14:10 localhost NetworkManager: <info>  (wlan0) Supplicant interface state change: 2 -> 0
>Mar  7 15:14:21 localhost NetworkManager: <WARN>  get_secrets_cb(): Couldn't get connection secrets: applet.c.3300 (get_secrets_dialog_response_cb): canceled.
>Mar  7 15:14:21 localhost NetworkManager: <info>  Activation (wlan0) failed for access point (default)
>Mar  7 15:14:21 localhost NetworkManager: <info>  Marking connection 'Auto default' invalid.
>Mar  7 15:14:21 localhost NetworkManager: <info>  Activation (wlan0) failed.
>Mar  7 15:14:21 localhost NetworkManager: <info>  Deactivating device wlan0.
>Mar  7 15:14:21 localhost NetworkManager: <WARN>  nm_device_802_11_wireless_disable_encryption(): error setting key for device wlan0: No such file or directory
>
>moved closer to access point - connected :)
>
>Mar  7 15:30:42 localhost NetworkManager: <info>  User request for activation of wlan0.
>Mar  7 15:30:42 localhost NetworkManager: <info>  Activating device wlan0
>Mar  7 15:30:42 localhost NetworkManager: <info>  Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled...
>Mar  7 15:30:42 localhost NetworkManager: <info>  Activation (wlan0) Stage 1 of 5 (Device Prepare) started...
>Mar  7 15:30:42 localhost NetworkManager: <info>  Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled...
>Mar  7 15:30:42 localhost NetworkManager: <info>  Activation (wlan0) Stage 1 of 5 (Device Prepare) complete.
>Mar  7 15:30:42 localhost NetworkManager: <info>  Activation (wlan0) Stage 2 of 5 (Device Configure) starting...
>Mar  7 15:30:42 localhost NetworkManager: <info>  Activation (wlan0/wireless): access point 'Auto default' has security, but secrets are required.
>Mar  7 15:30:42 localhost NetworkManager: <info>  Activation (wlan0) Stage 2 of 5 (Device Configure) complete.
>Mar  7 15:30:42 localhost NetworkManager: Missing or invalid key management
>Mar  7 15:30:42 localhost NetworkManager: <info>  Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled...
>Mar  7 15:30:42 localhost NetworkManager: <info>  Activation (wlan0) Stage 1 of 5 (Device Prepare) started...
>Mar  7 15:30:42 localhost NetworkManager: <info>  Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled...
>Mar  7 15:30:42 localhost NetworkManager: <info>  Activation (wlan0) Stage 1 of 5 (Device Prepare) complete.
>Mar  7 15:30:42 localhost NetworkManager: <info>  Activation (wlan0) Stage 2 of 5 (Device Configure) starting...
>Mar  7 15:30:42 localhost NetworkManager: <info>  Activation (wlan0/wireless): connection 'Auto default' has security, and secrets exist.  No new secrets needed.
>Mar  7 15:30:42 localhost NetworkManager: <info>  Config: added 'ssid' value 'default'
>Mar  7 15:30:42 localhost NetworkManager: <info>  Config: added 'key_mgmt' value 'WPA-PSK'
>Mar  7 15:30:42 localhost NetworkManager: <info>  Config: added 'psk' value '<omitted>'
>Mar  7 15:30:42 localhost NetworkManager: <info>  Config: added 'proto' value 'WPA RSN'
>Mar  7 15:30:42 localhost NetworkManager: <info>  Config: added 'pairwise' value 'TKIP CCMP'
>Mar  7 15:30:42 localhost NetworkManager: <info>  Config: added 'group' value 'WEP40 WEP104 TKIP CCMP'
>Mar  7 15:30:42 localhost NetworkManager: <info>  Activation (wlan0) Stage 2 of 5 (Device Configure) complete.
>Mar  7 15:30:42 localhost NetworkManager: <info>  Config: set interface ap_scan to 1
>Mar  7 15:30:42 localhost NetworkManager: <info>  (wlan0) Supplicant interface state change: 0 -> 2
>Mar  7 15:30:42 localhost NetworkManager: <info>  (wlan0) Supplicant interface state change: 2 -> 3
>Mar  7 15:30:42 localhost kernel: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
>Mar  7 15:30:42 localhost NetworkManager: <info>  (wlan0) Supplicant interface state change: 3 -> 4
>Mar  7 15:30:43 localhost NetworkManager: <info>  (wlan0) Supplicant interface state change: 4 -> 5
>Mar  7 15:30:43 localhost NetworkManager: <info>  (wlan0) Supplicant interface state change: 5 -> 6
>Mar  7 15:30:43 localhost NetworkManager: <info>  (wlan0) Supplicant interface state change: 6 -> 7
>Mar  7 15:30:43 localhost NetworkManager: <info>  Activation (wlan0/wireless) Stage 2 of 5 (Device Configure) successful.  Connected to wireless network 'default'.
>Mar  7 15:30:43 localhost NetworkManager: <info>  Activation (wlan0) Stage 3 of 5 (IP Configure Start) scheduled.
>Mar  7 15:30:43 localhost NetworkManager: <info>  Activation (wlan0) Stage 3 of 5 (IP Configure Start) started...
>Mar  7 15:30:43 localhost NetworkManager: <info>  Activation (wlan0) Beginning DHCP transaction.
>Mar  7 15:30:43 localhost dhclient: Internet Systems Consortium DHCP Client V3.0.6-Fedora
>Mar  7 15:30:43 localhost dhclient: Copyright 2004-2007 Internet Systems Consortium.
>Mar  7 15:30:43 localhost dhclient: All rights reserved.
>Mar  7 15:30:43 localhost dhclient: For info, please visit http://www.isc.org/sw/dhcp/
>Mar  7 15:30:43 localhost dhclient: 
>Mar  7 15:30:43 localhost dhclient: wmaster0: unknown hardware address type 801
>Mar  7 15:30:43 localhost NetworkManager: <info>  dhclient started with pid 3138
>Mar  7 15:30:43 localhost NetworkManager: <info>  Activation (wlan0) Stage 3 of 5 (IP Configure Start) complete.
>Mar  7 15:30:43 localhost NetworkManager: <info>  DHCP: device wlan0 state changed (null) -> preinit
>Mar  7 15:30:43 localhost dhclient: wmaster0: unknown hardware address type 801
>Mar  7 15:30:43 localhost dhclient: Listening on LPF/wlan0/00:0e:2e:c7:fe:49
>Mar  7 15:30:43 localhost dhclient: Sending on   LPF/wlan0/00:0e:2e:c7:fe:49
>Mar  7 15:30:43 localhost dhclient: Sending on   Socket/fallback
>Mar  7 15:30:43 localhost dhclient: DHCPDISCOVER on wlan0 to 255.255.255.255 port 67 interval 6
>Mar  7 15:30:44 localhost avahi-daemon[2064]: Registering new address record for fe80::20e:2eff:fec7:fe49 on wlan0.*.
>Mar  7 15:30:45 localhost dhclient: DHCPOFFER from 192.168.2.1
>Mar  7 15:30:45 localhost dhclient: DHCPREQUEST on wlan0 to 255.255.255.255 port 67
>Mar  7 15:30:45 localhost dhclient: DHCPACK from 192.168.2.1
>Mar  7 15:30:45 localhost NetworkManager: <info>  DHCP: device wlan0 state changed preinit -> bound
>Mar  7 15:30:45 localhost NetworkManager: <info>  Activation (wlan0) Stage 4 of 5 (IP Configure Get) scheduled...
>Mar  7 15:30:45 localhost NetworkManager: <info>  Activation (wlan0) Stage 4 of 5 (IP Configure Get) started...
>Mar  7 15:30:45 localhost NetworkManager: <info>    address 192.168.2.101
>Mar  7 15:30:45 localhost NetworkManager: <info>    netmask 255.255.255.0
>Mar  7 15:30:45 localhost NetworkManager: <info>    broadcast 192.168.2.255
>Mar  7 15:30:45 localhost NetworkManager: <info>    gateway 192.168.2.1
>Mar  7 15:30:45 localhost NetworkManager: <info>    nameserver '192.168.2.1'
>Mar  7 15:30:45 localhost NetworkManager: <info>  Activation (wlan0) Stage 5 of 5 (IP Configure Commit) scheduled...
>Mar  7 15:30:45 localhost NetworkManager: <info>  Activation (wlan0) Stage 4 of 5 (IP Configure Get) complete.
>Mar  7 15:30:45 localhost NetworkManager: <info>  Activation (wlan0) Stage 5 of 5 (IP Configure Commit) started...
>Mar  7 15:30:45 localhost avahi-daemon[2064]: Withdrawing address record for fe80::20e:2eff:fec7:fe49 on wlan0.
>Mar  7 15:30:45 localhost dhclient: bound to 192.168.2.101 -- renewal in 418105932 seconds.
>Mar  7 15:30:45 localhost avahi-daemon[2064]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.2.101.
>Mar  7 15:30:45 localhost avahi-daemon[2064]: New relevant interface wlan0.IPv4 for mDNS.
>Mar  7 15:30:45 localhost avahi-daemon[2064]: Registering new address record for 192.168.2.101 on wlan0.IPv4.
>Mar  7 15:30:46 localhost NetworkManager: <info>  Activation (wlan0) successful, device activated.
>Mar  7 15:30:46 localhost NetworkManager: <info>  Activation (wlan0) Stage 5 of 5 (IP Configure Commit) complete.
>Mar  7 15:30:48 localhost avahi-daemon[2064]: Registering new address record for fe80::20e:2eff:fec7:fe49 on wlan0.*.
>
>test connectivity - it works :)
>
>$ route
>Kernel IP routing table
>Destination     Gateway         Genmask         Flags Metric Ref    Use Iface
>192.168.2.0     *               255.255.255.0   U     0      0        0 wlan0
>default         192.168.2.1     0.0.0.0         UG    0      0        0 wlan0
>[mrhappy@localhost ~]$ ping www.bbc.co.uk
>PING www.bbc.net.uk (212.58.253.75) 56(84) bytes of data.
>64 bytes from www6.cwwtf.bbc.co.uk (212.58.253.75): icmp_seq=1 ttl=246 time=16.9 ms
>64 bytes from www6.cwwtf.bbc.co.uk (212.58.253.75): icmp_seq=2 ttl=246 time=16.4 ms
>64 bytes from www6.cwwtf.bbc.co.uk (212.58.253.75): icmp_seq=3 ttl=246 time=17.3 ms
>
>--- www.bbc.net.uk ping statistics ---
>3 packets transmitted, 3 received, 0% packet loss, time 1999ms
>rtt min/avg/max/mdev = 16.439/16.912/17.317/0.376 ms
>
>ftp a file
>==========
>
>$ wget ftp://ftp.virginmedia.com/mirrors/fedoraproject.org/8/Live/i686/Fedora-8-Live-i686.iso
>--15:34:05--  ftp://ftp.virginmedia.com/mirrors/fedoraproject.org/8/Live/i686/Fedora-8-Live-i686.iso
>           => `Fedora-8-Live-i686.iso'
>Resolving ftp.virginmedia.com... 194.117.143.71, 194.117.143.72
>Connecting to ftp.virginmedia.com|194.117.143.71|:21... connected.
>Logging in as anonymous ... Logged in!
>==> SYST ... done.    ==> PWD ... done.
>==> TYPE I ... done.  ==> CWD /mirrors/fedoraproject.org/8/Live/i686 ... done.
>==> PASV ... done.    ==> RETR Fedora-8-Live-i686.iso ... done.
>Length: 731,086,848 (697M) (unauthoritative)
>
> 0% [                                     ] 357,656       --.--K/s ETA
>
>network connectivity dies.
>==========================
>
>$ route
>Kernel IP routing table
>Destination     Gateway         Genmask         Flags Metric Ref    Use Iface
>192.168.2.0     *               255.255.255.0   U     0      0        0 wlan0
>default         192.168.2.1     0.0.0.0         UG    0      0        0 wlan0
>[mrhappy@localhost ~]$ ping www.bbc.co.uk
>
>[mrhappy@localhost ~]$ ping 192.168.2.1
>PING 192.168.2.1 (192.168.2.1) 56(84) bytes of data.
>
>--- 192.168.2.1 ping statistics ---
>14 packets transmitted, 0 received, 100% packet loss, time 15000ms
>
>=====
>
>This is reproduceable every time I try and download a large file!.
>
>with this kernel 2.6.24.3-18.fc8 the machine continues to respond, previously and when I added the following to /etc/modprobe.conf?
>
>   options mac80211 ieee80211_default_rc_algo="simple"
>
>the machine would lock up and I would have to press the power button for 5 seconds to turn it off, then turn it back on again.
>
>test network connectivity again since I have been typing this.
>==============================================================
>
>$ ping 192.168.2.1
>PING 192.168.2.1 (192.168.2.1) 56(84) bytes of data.
>From 192.168.2.101 icmp_seq=3 Destination Host Unreachable
>From 192.168.2.101 icmp_seq=4 Destination Host Unreachable
>From 192.168.2.101 icmp_seq=6 Destination Host Unreachable
>From 192.168.2.101 icmp_seq=7 Destination Host Unreachable
>From 192.168.2.101 icmp_seq=8 Destination Host Unreachable
>
>--- 192.168.2.1 ping statistics ---
>9 packets transmitted, 0 received, +5 errors, 100% packet loss, time 7999ms
>, pipe 3
>[mrhappy@localhost ~]$ route
>Kernel IP routing table
>Destination     Gateway         Genmask         Flags Metric Ref    Use Iface
>192.168.2.0     *               255.255.255.0   U     0      0        0 wlan0
>default         192.168.2.1     0.0.0.0         UG    0      0        0 wlan0
>
>So you can see I have a wireless card that has lost network connectivity since trying to ftp a large file.
>
>ejected card
>
>/var/log/messages
>=================
>
>Mar  7 15:45:46 localhost kernel: pccard: card ejected from slot 0
>Mar  7 15:45:47 localhost kernel: phy0 -> rt61pci_mcu_request: Error - mcu request error. Request 0x50 failed for token 0xff.
>Mar  7 15:45:47 localhost kernel:last message repeated 3 times
>Mar  7 15:45:47 localhost avahi-daemon[2064]: Interface wlan0.IPv4 no longer relevant for mDNS.
>Mar  7 15:45:47 localhost avahi-daemon[2064]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.2.101.
>Mar  7 15:45:47 localhost dhclient: receive_packet failed on wlan0: Network is down
>Mar  7 15:45:47 localhost NetworkManager: <info>  (wlan0) Supplicant interface state change: 7 -> 0
>Mar  7 15:45:47 localhost avahi-daemon[2064]: Withdrawing address record for fe80::20e:2eff:fec7:fe49 on wlan0.
>Mar  7 15:45:47 localhost avahi-daemon[2064]: Withdrawing address record for 192.168.2.101 on wlan0.
>Mar  7 15:45:47 localhost kernel: ACPI: PCI interrupt for device 0000:02:00.0 disabled
>Mar  7 15:45:48 localhost NetworkManager: <info>  Bringing down device wlan0
>Mar  7 15:45:48 localhost NetworkManager: <info>  Deactivating device wlan0.
>Mar  7 15:45:48 localhost NetworkManager: <info>  wlan0: canceled DHCP transaction, dhclient pid 3138
>Mar  7 15:45:49 localhost NetworkManager: <info>  Deactivating device wlan0.
>
>
>Can someone investigate.
>
>plugged in my wired card without rebooting and logged the bug
>
>https://bugzilla.redhat.com/show_bug.cgi?id=436496
>
>modinfo 8139too
>filename:       /lib/modules/2.6.24.3-18.fc8/kernel/drivers/net/8139too.ko
>version:        0.9.28
>license:        GPL
>description:    RealTek RTL-8139 Fast Ethernet driver
>author:         Jeff Garzik <jgarzik@pobox.com>
>srcversion:     69C244F6E1FEDCB14385855
>alias:          pci:v*d00008139sv000013D1sd0000AB06bc*sc*i*
>alias:          pci:v*d00008139sv00001186sd00001300bc*sc*i*
>alias:          pci:v*d00008139sv000010ECsd00008139bc*sc*i*
>alias:          pci:v000010ECd00008129sv*sd*bc*sc*i*
>alias:          pci:v0000021Bd00008139sv*sd*bc*sc*i*
>alias:          pci:v00001743d00008139sv*sd*bc*sc*i*
>alias:          pci:v0000126Cd00001211sv*sd*bc*sc*i*
>alias:          pci:v0000018Ad00000106sv*sd*bc*sc*i*
>alias:          pci:v000002ACd00001012sv*sd*bc*sc*i*
>alias:          pci:v00001432d00009130sv*sd*bc*sc*i*
>alias:          pci:v000011DBd00001234sv*sd*bc*sc*i*
>alias:          pci:v000014EAd0000AB07sv*sd*bc*sc*i*
>alias:          pci:v000014EAd0000AB06sv*sd*bc*sc*i*
>alias:          pci:v00001259d0000A11Esv*sd*bc*sc*i*
>alias:          pci:v00001259d0000A117sv*sd*bc*sc*i*
>alias:          pci:v000013D1d0000AB06sv*sd*bc*sc*i*
>alias:          pci:v00001186d00001340sv*sd*bc*sc*i*
>alias:          pci:v00001186d00001300sv*sd*bc*sc*i*
>alias:          pci:v00004033d00001360sv*sd*bc*sc*i*
>alias:          pci:v00001500d00001360sv*sd*bc*sc*i*
>alias:          pci:v00001113d00001211sv*sd*bc*sc*i*
>alias:          pci:v000010ECd00008138sv*sd*bc*sc*i*
>alias:          pci:v000010ECd00008139sv*sd*bc*sc*i*
>depends:        mii
>vermagic:       2.6.24.3-18.fc8 SMP mod_unload 686 4KSTACKS 
>parm:           debug:8139too bitmapped message enable number (int)
>parm:           multicast_filter_limit:8139too maximum number of filtered multicast addresses (int)
>parm:           media:8139too: Bits 4+9: force full duplex, bit 5: 100Mbps (array of int)
>parm:           full_duplex:8139too: Force full duplex for board(s) (1) (array of int)
>

Actions: View

Attachments on bug 436496: 297200 | 297288