I'm not *entirely* sure but this began happening at about the time I installed the latest kernel updates for CentOS 5.1 (kernel-2.6.18-53.1.6.el5.i686.rpm from January 24). Shortly before that I changed the encryption on my wireless router and switched from ndiswrapper back to the standard bcm43xx driver, but I'm pretty sure the network was OK before that update for the simple reason that I was able to download the update itself.
In any case, for the past week or so wireless network connectivity from my CentOS 5 laptop has been terrible. The wireless router is set up as my DNS server, and connectivity is as expected for wired-connected computers and wirelessly from my Mac, from the game consoles, from my work laptop (currently running ubuntu), and from the CentOS laptop when re-booted into Windows XP. So the problem is restricted to CentOS 5.
It takes minutes to do DNS lookups, and to establish a connection in the first place takes so long that "stateless" stuff like web surfing ranges from impossible (timeout) to merely unbearable. The automatic software updater hasn't succeeded in connecting ("Details: None") since the 24th. However, SSH connections seem to work OK (if perhaps a little bursty) once I manage to get connected (often by ssh'ng to the destination by IP address rather than by name).
I can ping computers on the wired LAN on the other side of the router:
PING 192.168.0.100 (192.168.0.100) 56(84) bytes of data. 64 bytes from 192.168.0.100: icmp_seq=1 ttl=64 time=0.094 ms 64 bytes from 192.168.0.100: icmp_seq=2 ttl=64 time=0.084 ms 64 bytes from 192.168.0.100: icmp_seq=3 ttl=64 time=0.082 ms 64 bytes from 192.168.0.100: icmp_seq=4 ttl=64 time=0.084 ms 64 bytes from 192.168.0.100: icmp_seq=5 ttl=64 time=0.085 ms 64 bytes from 192.168.0.100: icmp_seq=6 ttl=64 time=0.088 ms 64 bytes from 192.168.0.100: icmp_seq=7 ttl=64 time=0.085 ms 64 bytes from 192.168.0.100: icmp_seq=8 ttl=64 time=0.085 ms 64 bytes from 192.168.0.100: icmp_seq=9 ttl=64 time=0.086 ms 64 bytes from 192.168.0.100: icmp_seq=10 ttl=64 time=0.087 ms 64 bytes from 192.168.0.100: icmp_seq=11 ttl=64 time=0.087 ms 64 bytes from 192.168.0.100: icmp_seq=12 ttl=64 time=0.085 ms
--- 192.168.0.100 ping statistics --- 12 packets transmitted, 12 received, 0% packet loss, time 10997ms rtt min/avg/max/mdev = 0.082/0.086/0.094/0.003 ms
But when I ping the router itself:
PING 192.168.0.1 (192.168.0.1) 56(84) bytes of data. 64 bytes from 192.168.0.1: icmp_seq=3 ttl=64 time=32.1 ms 64 bytes from 192.168.0.1: icmp_seq=4 ttl=64 time=2.36 ms 64 bytes from 192.168.0.1: icmp_seq=5 ttl=64 time=1.89 ms 64 bytes from 192.168.0.1: icmp_seq=7 ttl=64 time=1.76 ms 64 bytes from 192.168.0.1: icmp_seq=8 ttl=64 time=1.80 ms 64 bytes from 192.168.0.1: icmp_seq=9 ttl=64 time=1.78 ms 64 bytes from 192.168.0.1: icmp_seq=10 ttl=64 time=1.84 ms 64 bytes from 192.168.0.1: icmp_seq=12 ttl=64 time=1.94 ms 64 bytes from 192.168.0.1: icmp_seq=12 ttl=64 time=3.58 ms (DUP!) 64 bytes from 192.168.0.1: icmp_seq=13 ttl=64 time=1.88 ms 64 bytes from 192.168.0.1: icmp_seq=14 ttl=64 time=1.99 ms 64 bytes from 192.168.0.1: icmp_seq=16 ttl=64 time=1.76 ms
--- 192.168.0.1 ping statistics --- 17 packets transmitted, 11 received, +1 duplicates, 35% packet loss, time 15997ms rtt min/avg/max/mdev = 1.765/4.562/32.120/8.323 ms
At one point I had 15 consecutive DUP packets during a ping run before things finally settled down.
Any idea what could cause this, or how to diagnose it further? I've run "arping -D" and there is only one responder on 192.168.0.1.
On Sat, 2 Feb 2008, Bart Schaefer wrote:
I'm not *entirely* sure but this began happening at about the time I installed the latest kernel updates for CentOS 5.1 (kernel-2.6.18-53.1.6.el5.i686.rpm from January 24). Shortly before that I changed the encryption on my wireless router and switched from ndiswrapper back to the standard bcm43xx driver, but I'm pretty sure the network was OK before that update for the simple reason that I was able to download the update itself.
<snip>
What do you see in dmesg? Does the last bcm43xx message show that you are associated or not?
On Feb 2, 2008 12:12 PM, Barry Brimer lists@brimer.org wrote:
What do you see in dmesg? Does the last bcm43xx message show that you are associated or not?
Well, clearly I'm associated as I can ping the router and also ping addresses on the far side of the router. However, dmesg output from bringing up the interface is appended below.
Something else nasty is going on because for the last three reboots the machine has only remained up for about 5 minutes at a time before it becomes unable to execute any new programs and has to be power-cycled to restart it. I got one console message about a failed journal write before the second of those forced reboots. Sometimes I can ctrl-alt-F2 over to a virtual console but then mingetty can't fork so I can't log in; init then shuts down the tty because it's respawning too fast, and I end up pressing the power button. I'm going to have to give up for now.
------------ SoftMAC: empty ratesinfo? SoftMAC: empty ratesinfo? ieee80211_crypt: registered algorithm 'WEP' bcm43xx: set security called, .active_key = 0, .level = 1, .enabled = 1, .encrypt = 1 SoftMAC: Associate: Scanning for networks first. SoftMAC: Associate: failed to initiate scan. Is device up? bcm43xx: PHY connected bcm43xx: PHY disconnected bcm43xx: PHY connected bcm43xx: Microcode rev 0x118, pl 0x17 (2004-05-06 21:34:00) bcm43xx: Radio turned on bcm43xx: Radio enabled by hardware bcm43xx: Chip initialized bcm43xx: 30-bit DMA initialized bcm43xx: TODO: Incomplete code in keymac_write() at drivers/net/wireless/bcm43xx/bcm43xx_main.c:1112 bcm43xx: TODO: Incomplete code in keymac_write() at drivers/net/wireless/bcm43xx/bcm43xx_main.c:1112 bcm43xx: TODO: Incomplete code in keymac_write() at drivers/net/wireless/bcm43xx/bcm43xx_main.c:1112 bcm43xx: TODO: Incomplete code in keymac_write() at drivers/net/wireless/bcm43xx/bcm43xx_main.c:1112 bcm43xx: TODO: Incomplete code in keymac_write() at drivers/net/wireless/bcm43xx/bcm43xx_main.c:1112 bcm43xx: TODO: Incomplete code in keymac_write() at drivers/net/wireless/bcm43xx/bcm43xx_main.c:1112 bcm43xx: TODO: Incomplete code in keymac_write() at drivers/net/wireless/bcm43xx/bcm43xx_main.c:1112 bcm43xx: TODO: Incomplete code in keymac_write() at drivers/net/wireless/bcm43xx/bcm43xx_main.c:1112 bcm43xx: TODO: Incomplete code in keymac_write() at drivers/net/wireless/bcm43xx/bcm43xx_main.c:1114 bcm43xx: TODO: Incomplete code in keymac_write() at drivers/net/wireless/bcm43xx/bcm43xx_main.c:1114 bcm43xx: TODO: Incomplete code in keymac_write() at drivers/net/wireless/bcm43xx/bcm43xx_main.c:1114 bcm43xx: TODO: Incomplete code in keymac_write() at drivers/net/wireless/bcm43xx/bcm43xx_main.c:1114 bcm43xx: Keys cleared bcm43xx: Selected 802.11 core (phytype 2) ADDRCONF(NETDEV_UP): eth1: link is not ready SoftMAC: Associate: Scanning for networks first. SoftMAC: Scanning finished: scanned 11 channels starting with channel 1 SoftMAC: Queueing Authentication Request to 00:13:46:f1:3b:7c SoftMAC: Cannot associate without being authenticated, requested authentication SoftMAC: Already requested authentication, waiting... SoftMAC: Sent Authentication Request to 00:13:46:f1:3b:7c. SoftMAC: Open Authentication completed with 00:13:46:f1:3b:7c SoftMAC: sent association request! SoftMAC: associated! ADDRCONF(NETDEV_CHANGE): eth1: link becomes ready audit(1201986381.450:4): avc: denied { read } for pid=2072 comm="dhclient" name="services" dev=sda5 ino=9418832 scontext=system_u:system_r:dhcpc_t:s0 tcontext=root:object_r:rpm_script_tmp_t:s0 tclass=file audit(1201986381.450:5): avc: denied { getattr } for pid=2072 comm="dhclient" path="/etc/services" dev=sda5 ino=9418832 scontext=system_u:system_r:dhcpc_t:s0 tcontext=root:object_r:rpm_script_tmp_t:s0 tclass=file bcm43xx: Radio turned off bcm43xx: DMA-32 0x0260 (RX) max used slots: 1/64 bcm43xx: DMA-32 0x0200 (RX) max used slots: 1/64 bcm43xx: DMA-32 0x02A0 (TX) max used slots: 0/512 bcm43xx: DMA-32 0x0280 (TX) max used slots: 0/512 bcm43xx: DMA-32 0x0260 (TX) max used slots: 0/512 bcm43xx: DMA-32 0x0240 (TX) max used slots: 0/512 bcm43xx: DMA-32 0x0220 (TX) max used slots: 2/512 bcm43xx: DMA-32 0x0200 (TX) max used slots: 0/512 bcm43xx: PHY disconnected bcm43xx: PHY connected bcm43xx: Microcode rev 0x118, pl 0x17 (2004-05-06 21:34:00) bcm43xx: Radio turned on bcm43xx: Radio enabled by hardware bcm43xx: Chip initialized bcm43xx: 30-bit DMA initialized bcm43xx: TODO: Incomplete code in keymac_write() at drivers/net/wireless/bcm43xx/bcm43xx_main.c:1112 bcm43xx: TODO: Incomplete code in keymac_write() at drivers/net/wireless/bcm43xx/bcm43xx_main.c:1112 bcm43xx: TODO: Incomplete code in keymac_write() at drivers/net/wireless/bcm43xx/bcm43xx_main.c:1112 bcm43xx: TODO: Incomplete code in keymac_write() at drivers/net/wireless/bcm43xx/bcm43xx_main.c:1112 bcm43xx: TODO: Incomplete code in keymac_write() at drivers/net/wireless/bcm43xx/bcm43xx_main.c:1112 bcm43xx: TODO: Incomplete code in keymac_write() at drivers/net/wireless/bcm43xx/bcm43xx_main.c:1112 bcm43xx: TODO: Incomplete code in keymac_write() at drivers/net/wireless/bcm43xx/bcm43xx_main.c:1112 bcm43xx: TODO: Incomplete code in keymac_write() at drivers/net/wireless/bcm43xx/bcm43xx_main.c:1112 bcm43xx: TODO: Incomplete code in keymac_write() at drivers/net/wireless/bcm43xx/bcm43xx_main.c:1114 bcm43xx: TODO: Incomplete code in keymac_write() at drivers/net/wireless/bcm43xx/bcm43xx_main.c:1114 bcm43xx: TODO: Incomplete code in keymac_write() at drivers/net/wireless/bcm43xx/bcm43xx_main.c:1114 bcm43xx: TODO: Incomplete code in keymac_write() at drivers/net/wireless/bcm43xx/bcm43xx_main.c:1114 bcm43xx: Keys cleared bcm43xx: Selected 802.11 core (phytype 2) SoftMAC: Associate: Scanning for networks first. SoftMAC: Scanning finished: scanned 11 channels starting with channel 1 SoftMAC: Queueing Authentication Request to 00:13:46:f1:3b:7c SoftMAC: Cannot associate without being authenticated, requested authentication SoftMAC: Sent Authentication Request to 00:13:46:f1:3b:7c. SoftMAC: Open Authentication completed with 00:13:46:f1:3b:7c SoftMAC: sent association request! SoftMAC: associated! SoftMAC: Authentication response received from 00:17:ab:dd:07:ea but no queue item exists. SoftMAC: Authentication response received from 00:13:46:f1:3b:7c but no queue item exists. SoftMAC: Authentication response received from 00:17:ab:dd:07:ea but no queue item exists. SoftMAC: Authentication response received from 00:13:46:f1:3b:7c but no queue item exists. SoftMAC: Authentication response received from 00:17:ab:dd:07:ea but no queue item exists. SoftMAC: Authentication response received from 00:13:46:f1:3b:7c but no queue item exists. eth1: no IPv6 routers present SoftMAC: Authentication response received from 00:17:ab:dd:07:ea but no queue item exists. SoftMAC: Authentication response received from 00:13:46:f1:3b:7c but no queue item exists. SoftMAC: Authentication response received from 00:17:ab:dd:07:ea but no queue item exists. SoftMAC: Authentication response received from 00:13:46:f1:3b:7c but no queue item exists. SoftMAC: Authentication response received from 00:17:ab:dd:07:ea but no queue item exists. SoftMAC: Authentication response received from 00:13:46:f1:3b:7c but no queue item exists.
On Sat, 2 Feb 2008, Bart Schaefer wrote:
On Feb 2, 2008 12:12 PM, Barry Brimer lists@brimer.org wrote:
What do you see in dmesg? Does the last bcm43xx message show that you are associated or not?
Well, clearly I'm associated as I can ping the router and also ping addresses on the far side of the router. However, dmesg output from bringing up the interface is appended below.
I realize that you were associated. The question was more geared towards whether you were associated once and it stayed that way, or whether you were associated and disassociated several times. I found it more difficult to stay connected to wireless networks that had multiple access points with the bcm43xx driver and less with a network with one access point. I also have had my fair share of "SoftMAC: Authentication response received from <MAC ADDRESS> but no queue item exists." as well.
Barry